builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0086 starttime: 1491585629.96 results: success (0) buildid: 20170407085322 builduid: b6dd34296d0347cfb2af06886979953e revision: 22df0c5cfbf8a2aa2822bf9c763e6c1c5a3f69de ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-07 10:20:29.960801) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-07 10:20:29.961287) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-07 10:20:29.961581) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-07 10:20:29.988329) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-07 10:20:29.988682) ========= 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.7sjNOCmQIs/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.cWAPegPazC/Listeners TMPDIR=/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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-07 10:20:30-- 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% 14.4M=0.001s 2017-04-07 10:20:30 (14.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.133875 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-07 10:20:30.146888) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-07 10:20:30.147233) ========= 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.7sjNOCmQIs/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.cWAPegPazC/Listeners TMPDIR=/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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.027612 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-07 10:20:30.197047) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-07 10:20:30.197429) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 22df0c5cfbf8a2aa2822bf9c763e6c1c5a3f69de --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 22df0c5cfbf8a2aa2822bf9c763e6c1c5a3f69de --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.7sjNOCmQIs/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.cWAPegPazC/Listeners TMPDIR=/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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-07 10:20:30,308 truncating revision to first 12 chars 2017-04-07 10:20:30,308 Setting DEBUG logging. 2017-04-07 10:20:30,308 attempt 1/10 2017-04-07 10:20:30,308 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/22df0c5cfbf8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-07 10:20:31,351 unpacking tar archive at: mozilla-central-22df0c5cfbf8/testing/mozharness/ program finished with exit code 0 elapsedTime=1.310095 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-07 10:20:31.531281) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-07 10:20:31.531614) ========= script_repo_revision: 22df0c5cfbf8a2aa2822bf9c763e6c1c5a3f69de ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-07 10:20:31.532024) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-07 10:20:31.532319) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-07 10:20:31.552431) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2017-04-07 10:20:31.552836) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.7sjNOCmQIs/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.cWAPegPazC/Listeners TMPDIR=/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 10:20:31 INFO - MultiFileLogger online at 20170407 10:20:31 in /builds/slave/test 10:20:31 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 10:20:31 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:20:31 INFO - {'append_to_log': False, 10:20:31 INFO - 'base_work_dir': '/builds/slave/test', 10:20:31 INFO - 'blob_upload_branch': 'Firefox', 10:20:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:20:31 INFO - 'branch': 'Firefox', 10:20:31 INFO - 'buildbot_json_path': 'buildprops.json', 10:20:31 INFO - 'config_files': ('talos/mac_config.py',), 10:20:31 INFO - 'default_actions': ('clobber', 10:20:31 INFO - 'read-buildbot-config', 10:20:31 INFO - 'download-and-extract', 10:20:31 INFO - 'populate-webroot', 10:20:31 INFO - 'create-virtualenv', 10:20:31 INFO - 'install', 10:20:31 INFO - 'run-tests'), 10:20:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:20:31 INFO - 'download_minidump_stackwalk': True, 10:20:31 INFO - 'download_symbols': 'ondemand', 10:20:31 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:20:31 INFO - 'tooltool.py': '/tools/tooltool.py', 10:20:31 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:20:31 INFO - '/tools/misc-python/virtualenv.py')}, 10:20:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:20:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:20:31 INFO - 'gecko_profile': False, 10:20:31 INFO - 'gecko_profile_interval': 0, 10:20:31 INFO - 'installer_path': 'installer.exe', 10:20:31 INFO - 'log_level': 'info', 10:20:31 INFO - 'log_name': 'talos', 10:20:31 INFO - 'log_to_console': True, 10:20:31 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:20:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:20:31 INFO - 'opt_config_files': (), 10:20:31 INFO - 'pip_index': False, 10:20:31 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:20:31 INFO - 'cmd': ('bash', 10:20:31 INFO - '-c', 10:20:31 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:20:31 INFO - 'enabled': True, 10:20:31 INFO - 'halt_on_failure': False, 10:20:31 INFO - 'name': 'check_screen_resolution'},), 10:20:31 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:20:31 INFO - 'cmd': ('bash', 10:20:31 INFO - '-c', 10:20:31 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:20:31 INFO - 'enabled': True, 10:20:31 INFO - 'halt_on_failure': False, 10:20:31 INFO - 'name': 'check_screen_resolution'},), 10:20:31 INFO - 'run_cmd_checks_enabled': True, 10:20:31 INFO - 'suite': 'dromaeojs-e10s', 10:20:31 INFO - 'system_bits': '32', 10:20:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:20:31 INFO - 'title': 't-yosemite-r7-0086', 10:20:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:20:31 INFO - 'use_talos_json': True, 10:20:31 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:20:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:20:31 INFO - 'work_dir': 'build'} 10:20:31 INFO - [mozharness: 2017-04-07 17:20:31.778880Z] Running clobber step. 10:20:31 INFO - Running pre-action listener: _resource_record_pre_action 10:20:31 INFO - Running main action method: clobber 10:20:31 INFO - rmtree: /builds/slave/test/build 10:20:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:20:34 INFO - Running post-action listener: _resource_record_post_action 10:20:34 INFO - [mozharness: 2017-04-07 17:20:34.281354Z] Finished clobber step (success) 10:20:34 INFO - [mozharness: 2017-04-07 17:20:34.281524Z] Running read-buildbot-config step. 10:20:34 INFO - Running pre-action listener: _resource_record_pre_action 10:20:34 INFO - Running main action method: read_buildbot_config 10:20:34 INFO - Using buildbot properties: 10:20:34 INFO - { 10:20:34 INFO - "project": "", 10:20:34 INFO - "product": "firefox", 10:20:34 INFO - "script_repo_revision": "production", 10:20:34 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 10:20:34 INFO - "repository": "", 10:20:34 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 10:20:34 INFO - "buildid": "20170407085322", 10:20:34 INFO - "pgo_build": "False", 10:20:34 INFO - "basedir": "/builds/slave/test", 10:20:34 INFO - "buildnumber": 116, 10:20:34 INFO - "slavename": "t-yosemite-r7-0086", 10:20:34 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 10:20:34 INFO - "platform": "yosemite_r7", 10:20:34 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 10:20:34 INFO - "branch": "mozilla-central", 10:20:34 INFO - "repo_path": "mozilla-central", 10:20:34 INFO - "revision": "22df0c5cfbf8a2aa2822bf9c763e6c1c5a3f69de", 10:20:34 INFO - "stage_platform": "macosx64", 10:20:34 INFO - "builduid": "b6dd34296d0347cfb2af06886979953e", 10:20:34 INFO - "slavebuilddir": "test" 10:20:34 INFO - } 10:20:34 INFO - Found installer url https://queue.taskcluster.net/v1/task/Yb79z2tSRUCUTb6pnWKZzw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 10:20:34 INFO - Running post-action listener: _resource_record_post_action 10:20:34 INFO - [mozharness: 2017-04-07 17:20:34.284862Z] Finished read-buildbot-config step (success) 10:20:34 INFO - [mozharness: 2017-04-07 17:20:34.284978Z] Running download-and-extract step. 10:20:34 INFO - Running pre-action listener: _resource_record_pre_action 10:20:34 INFO - Running main action method: download_and_extract 10:20:34 INFO - mkdir: /builds/slave/test/build/tests 10:20:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:20:34 INFO - trying https://queue.taskcluster.net/v1/task/Yb79z2tSRUCUTb6pnWKZzw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 10:20:34 INFO - Downloading https://queue.taskcluster.net/v1/task/Yb79z2tSRUCUTb6pnWKZzw/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 10:20:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Yb79z2tSRUCUTb6pnWKZzw/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 10:20:35 INFO - Downloaded 1325 bytes. 10:20:35 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 10:20:35 INFO - Using the following test package requirements: 10:20:35 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:20:35 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 10:20:35 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 10:20:35 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:20:35 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 10:20:35 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:20:35 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 10:20:35 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:20:35 INFO - u'jsshell-mac.zip'], 10:20:35 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:20:35 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 10:20:35 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 10:20:35 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:20:35 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 10:20:35 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:20:35 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 10:20:35 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:20:35 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 10:20:35 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:20:35 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 10:20:35 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 10:20:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Yb79z2tSRUCUTb6pnWKZzw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 10:20:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Yb79z2tSRUCUTb6pnWKZzw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:20:35 INFO - Fetch https://queue.taskcluster.net/v1/task/Yb79z2tSRUCUTb6pnWKZzw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 10:20:37 INFO - Expected file size: 52519772 10:20:37 INFO - Obtained file size: 52519772 10:20:53 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 10:20:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Yb79z2tSRUCUTb6pnWKZzw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 10:20:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Yb79z2tSRUCUTb6pnWKZzw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:20:53 INFO - Fetch https://queue.taskcluster.net/v1/task/Yb79z2tSRUCUTb6pnWKZzw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 10:20:55 INFO - Expected file size: 52519772 10:20:55 INFO - Obtained file size: 52519772 10:21:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Yb79z2tSRUCUTb6pnWKZzw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 10:21:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Yb79z2tSRUCUTb6pnWKZzw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 10:21:11 INFO - Fetch https://queue.taskcluster.net/v1/task/Yb79z2tSRUCUTb6pnWKZzw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 10:21:12 INFO - Expected file size: 14404138 10:21:12 INFO - Obtained file size: 14404138 10:21:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:21:12 INFO - trying https://queue.taskcluster.net/v1/task/Yb79z2tSRUCUTb6pnWKZzw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:21:12 INFO - Downloading https://queue.taskcluster.net/v1/task/Yb79z2tSRUCUTb6pnWKZzw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 10:21:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Yb79z2tSRUCUTb6pnWKZzw/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 10:21:14 INFO - Downloaded 70887646 bytes. 10:21:14 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Yb79z2tSRUCUTb6pnWKZzw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:21:14 INFO - mkdir: /builds/slave/test/properties 10:21:14 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:21:14 INFO - Writing to file /builds/slave/test/properties/build_url 10:21:14 INFO - Contents: 10:21:14 INFO - build_url:https://queue.taskcluster.net/v1/task/Yb79z2tSRUCUTb6pnWKZzw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:21:15 INFO - Running post-action listener: _resource_record_post_action 10:21:15 INFO - Running post-action listener: set_extra_try_arguments 10:21:15 INFO - [mozharness: 2017-04-07 17:21:15.517635Z] Finished download-and-extract step (success) 10:21:15 INFO - [mozharness: 2017-04-07 17:21:15.517758Z] Running populate-webroot step. 10:21:15 INFO - Running pre-action listener: _resource_record_pre_action 10:21:15 INFO - Running main action method: populate_webroot 10:21:15 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:21:15 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:21:15 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:21:15 INFO - '--tppagecycles', 10:21:15 INFO - '7'], 10:21:15 INFO - 'tests': ['tsvgm']}}, 10:21:15 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:21:15 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:21:15 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:21:15 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:21:15 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:21:15 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:21:15 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 10:21:15 INFO - 'talos_options': ['--disable-e10s'], 10:21:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:21:15 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 10:21:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:21:15 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 10:21:15 INFO - 'talos_options': ['--disable-e10s'], 10:21:15 INFO - 'tests': ['damp', 'tps']}, 10:21:15 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 10:21:15 INFO - 'tests': ['damp', 'tps']}, 10:21:15 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:21:15 INFO - 'tests': ['dromaeo_dom']}, 10:21:15 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:21:15 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:21:15 INFO - 'tests': ['basic_compositor_video']}, 10:21:15 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:21:15 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:21:15 INFO - 'tests': ['a11yr', 10:21:15 INFO - 'ts_paint', 10:21:15 INFO - 'tpaint', 10:21:15 INFO - 'sessionrestore', 10:21:15 INFO - 'sessionrestore_no_auto_restore', 10:21:15 INFO - 'tabpaint']}, 10:21:15 INFO - 'other-e10s': {'tests': ['a11yr', 10:21:15 INFO - 'ts_paint', 10:21:15 INFO - 'tpaint', 10:21:15 INFO - 'sessionrestore', 10:21:15 INFO - 'sessionrestore_no_auto_restore', 10:21:15 INFO - 'tabpaint']}, 10:21:15 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:21:15 INFO - 'ts_paint', 10:21:15 INFO - 'tpaint', 10:21:15 INFO - 'sessionrestore', 10:21:15 INFO - 'sessionrestore_no_auto_restore', 10:21:15 INFO - 'tabpaint']}, 10:21:15 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:21:15 INFO - 'ts_paint', 10:21:15 INFO - 'tpaint', 10:21:15 INFO - 'sessionrestore', 10:21:15 INFO - 'sessionrestore_no_auto_restore', 10:21:15 INFO - 'tabpaint']}, 10:21:15 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:21:15 INFO - 'tests': ['a11yr', 10:21:15 INFO - 'ts_paint', 10:21:15 INFO - 'tpaint', 10:21:15 INFO - 'sessionrestore', 10:21:15 INFO - 'sessionrestore_no_auto_restore', 10:21:15 INFO - 'tabpaint']}, 10:21:15 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:21:15 INFO - 'tests': ['a11yr', 10:21:15 INFO - 'ts_paint', 10:21:15 INFO - 'tpaint', 10:21:15 INFO - 'sessionrestore', 10:21:15 INFO - 'sessionrestore_no_auto_restore', 10:21:15 INFO - 'tabpaint']}, 10:21:15 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:21:15 INFO - 'tests': ['tsvgx', 10:21:15 INFO - 'tsvgr_opacity', 10:21:15 INFO - 'tart', 10:21:15 INFO - 'tscrollx', 10:21:15 INFO - 'cart', 10:21:15 INFO - 'tsvg_static']}, 10:21:15 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:21:15 INFO - 'tsvgr_opacity', 10:21:15 INFO - 'tart', 10:21:15 INFO - 'tscrollx', 10:21:15 INFO - 'cart', 10:21:15 INFO - 'tsvg_static']}, 10:21:15 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 10:21:15 INFO - 'talos_options': ['--disable-e10s'], 10:21:15 INFO - 'tests': ['tp5o']}, 10:21:15 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 10:21:15 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 10:21:15 INFO - 'talos_options': ['--disable-e10s', 10:21:15 INFO - '--xperf_path', 10:21:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:21:15 INFO - 'tests': ['tp5n']}, 10:21:15 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 10:21:15 INFO - 'talos_options': ['--xperf_path', 10:21:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:21:15 INFO - 'tests': ['tp5n']}}, 10:21:15 INFO - 'talos.zip': {'path': '', 10:21:15 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:21:15 INFO - Running post-action listener: _resource_record_post_action 10:21:15 INFO - [mozharness: 2017-04-07 17:21:15.536343Z] Finished populate-webroot step (success) 10:21:15 INFO - [mozharness: 2017-04-07 17:21:15.536475Z] Running create-virtualenv step. 10:21:15 INFO - Running pre-action listener: _resource_record_pre_action 10:21:15 INFO - Running main action method: create_virtualenv 10:21:15 INFO - Creating virtualenv /builds/slave/test/build/venv 10:21:15 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 10:21:15 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:21:15 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:21:15 INFO - Using real prefix '/tools/python27' 10:21:15 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:21:16 INFO - Installing distribute.............................................................................................................................................................................................done. 10:21:19 INFO - Installing pip.................done. 10:21:19 INFO - Return code: 0 10:21:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:21:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:21:19 INFO - Reading from file tmpfile_stdout 10:21:19 INFO - Output received: 10:21:19 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) 10:21:19 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:21:19 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')]} 10:21:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:21:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:21:19 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')]} 10:21:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:21:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:21:19 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 0x109094b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109097768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e68d789e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e72d30>, '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 0x108d99db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108e67be8>, '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.7sjNOCmQIs/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.cWAPegPazC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 10:21:19 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 10:21:19 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 10:21:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7sjNOCmQIs/Render', 10:21:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:21:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:21:19 INFO - 'HOME': '/Users/cltbld', 10:21:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:21:19 INFO - 'LOGNAME': 'cltbld', 10:21:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:21:19 INFO - 'MOZ_NO_REMOTE': '1', 10:21:19 INFO - 'NO_EM_RESTART': '1', 10:21:19 INFO - 'PAGER': '/bin/cat', 10:21:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:21:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:21:19 INFO - 'PWD': '/builds/slave/test', 10:21:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:21:19 INFO - 'SHELL': '/bin/bash', 10:21:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cWAPegPazC/Listeners', 10:21:19 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 10:21:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:21:19 INFO - 'USER': 'cltbld', 10:21:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:21:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:21:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:21:19 INFO - 'XPC_FLAGS': '0x0', 10:21:19 INFO - 'XPC_SERVICE_NAME': '0', 10:21:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:21:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:21:19 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 10:21:19 INFO - Cleaning up... 10:21:19 INFO - Return code: 0 10:21:19 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:21:19 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')]} 10:21:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:21:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:21:19 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')]} 10:21:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:21:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:21:19 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 0x109094b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109097768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e68d789e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e72d30>, '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 0x108d99db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108e67be8>, '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.7sjNOCmQIs/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.cWAPegPazC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 10:21:19 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 10:21:19 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 10:21:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7sjNOCmQIs/Render', 10:21:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:21:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:21:19 INFO - 'HOME': '/Users/cltbld', 10:21:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:21:19 INFO - 'LOGNAME': 'cltbld', 10:21:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:21:19 INFO - 'MOZ_NO_REMOTE': '1', 10:21:19 INFO - 'NO_EM_RESTART': '1', 10:21:19 INFO - 'PAGER': '/bin/cat', 10:21:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:21:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:21:19 INFO - 'PWD': '/builds/slave/test', 10:21:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:21:19 INFO - 'SHELL': '/bin/bash', 10:21:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cWAPegPazC/Listeners', 10:21:19 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 10:21:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:21:19 INFO - 'USER': 'cltbld', 10:21:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:21:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:21:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:21:19 INFO - 'XPC_FLAGS': '0x0', 10:21:19 INFO - 'XPC_SERVICE_NAME': '0', 10:21:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:21:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:21:20 INFO - Downloading/unpacking psutil>=3.1.1 10:21:20 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 10:21:20 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 10:21:20 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 10:21:20 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 10:21:20 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 10:21:20 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 10:21:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:21:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:21:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:21:23 INFO - Installing collected packages: psutil 10:21:23 INFO - Running setup.py install for psutil 10:21:23 INFO - building 'psutil._psutil_osx' extension 10:21:23 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 10:21:24 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 10:21:24 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 10:21:24 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 10:21:24 INFO - building 'psutil._psutil_posix' extension 10:21:24 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 10:21:24 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:21:24 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:21:24 INFO - ^ 10:21:24 INFO - 1 warning generated. 10:21:24 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 10:21:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:21:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:21:24 INFO - Successfully installed psutil 10:21:24 INFO - Cleaning up... 10:21:24 INFO - Return code: 0 10:21:24 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:21:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:21:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:21:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:21:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:21:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:21:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:21:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109094b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109097768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e68d789e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e72d30>, '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 0x108d99db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108e67be8>, '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.7sjNOCmQIs/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.cWAPegPazC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 10:21:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 10:21:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 10:21:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7sjNOCmQIs/Render', 10:21:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:21:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:21:24 INFO - 'HOME': '/Users/cltbld', 10:21:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:21:24 INFO - 'LOGNAME': 'cltbld', 10:21:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:21:24 INFO - 'MOZ_NO_REMOTE': '1', 10:21:24 INFO - 'NO_EM_RESTART': '1', 10:21:24 INFO - 'PAGER': '/bin/cat', 10:21:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:21:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:21:24 INFO - 'PWD': '/builds/slave/test', 10:21:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:21:24 INFO - 'SHELL': '/bin/bash', 10:21:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cWAPegPazC/Listeners', 10:21:24 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 10:21:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:21:24 INFO - 'USER': 'cltbld', 10:21:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:21:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:21:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:21:24 INFO - 'XPC_FLAGS': '0x0', 10:21:24 INFO - 'XPC_SERVICE_NAME': '0', 10:21:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:21:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:21:25 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:21:25 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 10:21:25 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 10:21:25 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 10:21:25 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 10:21:25 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 10:21:25 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 10:21:27 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:21:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:21:27 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:21:27 INFO - Installing collected packages: mozsystemmonitor 10:21:27 INFO - Running setup.py install for mozsystemmonitor 10:21:27 INFO - Successfully installed mozsystemmonitor 10:21:27 INFO - Cleaning up... 10:21:28 INFO - Return code: 0 10:21:28 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:21:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:21:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:21:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:21:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:21:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:21:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:21:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x109094b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109097768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e68d789e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e72d30>, '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 0x108d99db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108e67be8>, '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.7sjNOCmQIs/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.cWAPegPazC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 10:21:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 10:21:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 10:21:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7sjNOCmQIs/Render', 10:21:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:21:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:21:28 INFO - 'HOME': '/Users/cltbld', 10:21:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:21:28 INFO - 'LOGNAME': 'cltbld', 10:21:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:21:28 INFO - 'MOZ_NO_REMOTE': '1', 10:21:28 INFO - 'NO_EM_RESTART': '1', 10:21:28 INFO - 'PAGER': '/bin/cat', 10:21:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:21:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:21:28 INFO - 'PWD': '/builds/slave/test', 10:21:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:21:28 INFO - 'SHELL': '/bin/bash', 10:21:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cWAPegPazC/Listeners', 10:21:28 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 10:21:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:21:28 INFO - 'USER': 'cltbld', 10:21:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:21:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:21:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:21:28 INFO - 'XPC_FLAGS': '0x0', 10:21:28 INFO - 'XPC_SERVICE_NAME': '0', 10:21:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:21:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:21:29 INFO - Downloading/unpacking jsonschema==2.5.1 10:21:29 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 10:21:29 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 10:21:29 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 10:21:29 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 10:21:29 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 10:21:29 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 10:21:32 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:21:32 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:21:32 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 10:21:32 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 10:21:32 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 10:21:32 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 10:21:32 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 10:21:32 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 10:21:32 INFO - Downloading functools32-3.2.3-2.tar.gz 10:21:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:21:32 INFO - warning: no files found matching '*.txt' 10:21:32 INFO - no previously-included directories found matching 'build' 10:21:32 INFO - no previously-included directories found matching 'dist' 10:21:32 INFO - no previously-included directories found matching '.git*' 10:21:32 INFO - Installing collected packages: jsonschema, functools32 10:21:32 INFO - Running setup.py install for functools32 10:21:32 INFO - warning: no files found matching '*.txt' 10:21:32 INFO - no previously-included directories found matching 'build' 10:21:32 INFO - no previously-included directories found matching 'dist' 10:21:32 INFO - no previously-included directories found matching '.git*' 10:21:32 INFO - Successfully installed jsonschema functools32 10:21:32 INFO - Cleaning up... 10:21:32 INFO - Return code: 0 10:21:32 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:21:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:21:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:21:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:21:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:21:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:21:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:21:32 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 0x109094b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109097768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e68d789e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e72d30>, '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 0x108d99db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108e67be8>, '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.7sjNOCmQIs/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.cWAPegPazC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 10:21:32 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 10:21:32 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 10:21:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7sjNOCmQIs/Render', 10:21:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:21:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:21:32 INFO - 'HOME': '/Users/cltbld', 10:21:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:21:32 INFO - 'LOGNAME': 'cltbld', 10:21:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:21:32 INFO - 'MOZ_NO_REMOTE': '1', 10:21:32 INFO - 'NO_EM_RESTART': '1', 10:21:32 INFO - 'PAGER': '/bin/cat', 10:21:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:21:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:21:32 INFO - 'PWD': '/builds/slave/test', 10:21:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:21:32 INFO - 'SHELL': '/bin/bash', 10:21:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cWAPegPazC/Listeners', 10:21:32 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 10:21:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:21:32 INFO - 'USER': 'cltbld', 10:21:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:21:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:21:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:21:32 INFO - 'XPC_FLAGS': '0x0', 10:21:32 INFO - 'XPC_SERVICE_NAME': '0', 10:21:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:21:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:21:33 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:21:33 INFO - Cleaning up... 10:21:33 INFO - Return code: 0 10:21:33 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:21:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:21:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:21:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:21:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:21:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:21:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:21:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x109094b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109097768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e68d789e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e72d30>, '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 0x108d99db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108e67be8>, '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.7sjNOCmQIs/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.cWAPegPazC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 10:21:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 10:21:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 10:21:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7sjNOCmQIs/Render', 10:21:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:21:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:21:33 INFO - 'HOME': '/Users/cltbld', 10:21:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:21:33 INFO - 'LOGNAME': 'cltbld', 10:21:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:21:33 INFO - 'MOZ_NO_REMOTE': '1', 10:21:33 INFO - 'NO_EM_RESTART': '1', 10:21:33 INFO - 'PAGER': '/bin/cat', 10:21:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:21:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:21:33 INFO - 'PWD': '/builds/slave/test', 10:21:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:21:33 INFO - 'SHELL': '/bin/bash', 10:21:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cWAPegPazC/Listeners', 10:21:33 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 10:21:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:21:33 INFO - 'USER': 'cltbld', 10:21:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:21:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:21:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:21:33 INFO - 'XPC_FLAGS': '0x0', 10:21:33 INFO - 'XPC_SERVICE_NAME': '0', 10:21:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:21:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:21:33 INFO - Downloading/unpacking blobuploader==1.2.4 10:21:33 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 10:21:33 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 10:21:33 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 10:21:33 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 10:21:33 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 10:21:33 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 10:21:36 INFO - Downloading blobuploader-1.2.4.tar.gz 10:21:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:21:36 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:21:36 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 10:21:36 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 10:21:36 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 10:21:36 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 10:21:36 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 10:21:36 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 10:21:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:21:36 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:21:36 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 10:21:36 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 10:21:36 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 10:21:36 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 10:21:36 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 10:21:36 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 10:21:37 INFO - Downloading docopt-0.6.1.tar.gz 10:21:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:21:37 INFO - Installing collected packages: blobuploader, requests, docopt 10:21:37 INFO - Running setup.py install for blobuploader 10:21:37 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:21:37 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:21:37 INFO - Running setup.py install for requests 10:21:38 INFO - Running setup.py install for docopt 10:21:38 INFO - Successfully installed blobuploader requests docopt 10:21:38 INFO - Cleaning up... 10:21:38 INFO - Return code: 0 10:21:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:21:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:21:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:21:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:21:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:21:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:21:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:21:38 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 0x109094b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109097768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e68d789e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e72d30>, '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 0x108d99db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108e67be8>, '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.7sjNOCmQIs/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.cWAPegPazC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 10:21:38 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 10:21:38 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 10:21:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7sjNOCmQIs/Render', 10:21:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:21:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:21:38 INFO - 'HOME': '/Users/cltbld', 10:21:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:21:38 INFO - 'LOGNAME': 'cltbld', 10:21:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:21:38 INFO - 'MOZ_NO_REMOTE': '1', 10:21:38 INFO - 'NO_EM_RESTART': '1', 10:21:38 INFO - 'PAGER': '/bin/cat', 10:21:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:21:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:21:38 INFO - 'PWD': '/builds/slave/test', 10:21:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:21:38 INFO - 'SHELL': '/bin/bash', 10:21:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cWAPegPazC/Listeners', 10:21:38 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 10:21:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:21:38 INFO - 'USER': 'cltbld', 10:21:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:21:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:21:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:21:38 INFO - 'XPC_FLAGS': '0x0', 10:21:38 INFO - 'XPC_SERVICE_NAME': '0', 10:21:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:21:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:21:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:21:38 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-J80Ce4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:21:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:21:38 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-BRwSZ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:21:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:21:38 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-MsJ3kI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:21:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:21:38 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-inNZfa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:21:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:21:38 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-uTtHPk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:21:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:21:39 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-Sy_XHt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:21:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:21:39 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-XDIFz8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:21:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:21:39 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-XUYQbT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:21:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:21:39 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-NR8UN7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:21:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:21:39 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-mecTs3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:21:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:21:39 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-bNmoLz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:21:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:21:39 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-Hc4P1h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:21:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:21:39 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-JGC9k9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:21:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:21:39 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-06GYLA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:21:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:21:40 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-4vwqgn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:21:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:21:40 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-7LBa9W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:21:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:21:40 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-rS6KQK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:21:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:21:40 INFO - Running setup.py install for manifestparser 10:21:40 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:21:40 INFO - Running setup.py install for mozcrash 10:21:40 INFO - Running setup.py install for mozdebug 10:21:40 INFO - Running setup.py install for mozdevice 10:21:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:21:40 INFO - Running setup.py install for mozfile 10:21:41 INFO - Running setup.py install for mozhttpd 10:21:41 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:21:41 INFO - Running setup.py install for mozinfo 10:21:41 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:21:41 INFO - Running setup.py install for mozInstall 10:21:41 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:21:41 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:21:41 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:21:41 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:21:41 INFO - Running setup.py install for mozleak 10:21:41 INFO - Running setup.py install for mozlog 10:21:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:21:41 INFO - Running setup.py install for moznetwork 10:21:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:21:41 INFO - Running setup.py install for mozprocess 10:21:42 INFO - Running setup.py install for mozprofile 10:21:42 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:21:42 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:21:42 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:21:42 INFO - Running setup.py install for mozrunner 10:21:42 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:21:42 INFO - Running setup.py install for mozscreenshot 10:21:42 INFO - Running setup.py install for moztest 10:21:42 INFO - Running setup.py install for mozversion 10:21:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:21:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:21:42 INFO - Cleaning up... 10:21:42 INFO - Return code: 0 10:21:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:21:42 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')]} 10:21:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:21:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:21:42 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')]} 10:21:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:21:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:21:42 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 0x109094b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109097768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e68d789e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e72d30>, '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 0x108d99db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108e67be8>, '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.7sjNOCmQIs/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.cWAPegPazC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 10:21:42 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 10:21:42 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 10:21:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7sjNOCmQIs/Render', 10:21:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:21:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:21:42 INFO - 'HOME': '/Users/cltbld', 10:21:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:21:42 INFO - 'LOGNAME': 'cltbld', 10:21:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:21:42 INFO - 'MOZ_NO_REMOTE': '1', 10:21:42 INFO - 'NO_EM_RESTART': '1', 10:21:42 INFO - 'PAGER': '/bin/cat', 10:21:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:21:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:21:42 INFO - 'PWD': '/builds/slave/test', 10:21:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:21:42 INFO - 'SHELL': '/bin/bash', 10:21:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cWAPegPazC/Listeners', 10:21:42 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 10:21:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:21:42 INFO - 'USER': 'cltbld', 10:21:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:21:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:21:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:21:42 INFO - 'XPC_FLAGS': '0x0', 10:21:42 INFO - 'XPC_SERVICE_NAME': '0', 10:21:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:21:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:21:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:21:43 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-X0YmBq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:21:43 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)) 10:21:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:21:43 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-aOl8xx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:21:43 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)) 10:21:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:21:43 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-D0yJOz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:21:43 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)) 10:21:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:21:43 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-GAo3tJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:21:43 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)) 10:21:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:21:43 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-BJuJzG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:21:43 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)) 10:21:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:21:43 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-5N0wCM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:21:43 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)) 10:21:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:21:43 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-_xVL1K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:21:43 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)) 10:21:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:21:43 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-rqEILU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:21:44 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)) 10:21:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:21:44 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-Qr1gDQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:21:44 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)) 10:21:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:21:44 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-mnSnqP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:21:44 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)) 10:21:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:21:44 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-IZzOTv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:21:44 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)) 10:21:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:21:44 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-idwP9r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:21:44 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)) 10:21:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:21:44 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-TWqZFx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:21:44 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)) 10:21:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:21:44 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-7p2uAP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:21:44 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)) 10:21:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:21:44 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-JfTQYW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:21:44 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)) 10:21:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:21:44 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-VCfT2t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:21:44 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)) 10:21:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:21:44 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-IZZJFE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:21:44 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)) 10:21:44 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)) 10:21:44 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)) 10:21:44 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)) 10:21:44 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)) 10:21:44 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)) 10:21:44 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:21:44 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 10:21:44 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 10:21:44 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 10:21:44 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 10:21:44 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 10:21:44 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 10:21:47 INFO - Downloading blessings-1.6.tar.gz 10:21:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:21:47 INFO - Installing collected packages: blessings 10:21:47 INFO - Running setup.py install for blessings 10:21:47 INFO - Successfully installed blessings 10:21:47 INFO - Cleaning up... 10:21:47 INFO - Return code: 0 10:21:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:21:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:21:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:21:48 INFO - Reading from file tmpfile_stdout 10:21:48 INFO - Current package versions: 10:21:48 INFO - blessings == 1.6 10:21:48 INFO - blobuploader == 1.2.4 10:21:48 INFO - docopt == 0.6.1 10:21:48 INFO - functools32 == 3.2.3-2 10:21:48 INFO - jsonschema == 2.5.1 10:21:48 INFO - manifestparser == 1.1 10:21:48 INFO - mozInstall == 1.12 10:21:48 INFO - mozcrash == 1.0 10:21:48 INFO - mozdebug == 0.1 10:21:48 INFO - mozdevice == 0.50 10:21:48 INFO - mozfile == 1.2 10:21:48 INFO - mozhttpd == 0.7 10:21:48 INFO - mozinfo == 0.9 10:21:48 INFO - mozleak == 0.1 10:21:48 INFO - mozlog == 3.4 10:21:48 INFO - moznetwork == 0.27 10:21:48 INFO - mozprocess == 0.25 10:21:48 INFO - mozprofile == 0.28 10:21:48 INFO - mozrunner == 6.13 10:21:48 INFO - mozscreenshot == 0.1 10:21:48 INFO - mozsystemmonitor == 0.3 10:21:48 INFO - moztest == 0.8 10:21:48 INFO - mozversion == 1.4 10:21:48 INFO - psutil == 3.1.1 10:21:48 INFO - requests == 1.2.3 10:21:48 INFO - wsgiref == 0.1.2 10:21:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:21:48 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')]} 10:21:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:21:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:21:48 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')]} 10:21:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:21:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:21:48 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 0x109094b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109097768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e68d789e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e72d30>, '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 0x108d99db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108e67be8>, '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.7sjNOCmQIs/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.cWAPegPazC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 10:21:48 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 10:21:48 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 10:21:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7sjNOCmQIs/Render', 10:21:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:21:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:21:48 INFO - 'HOME': '/Users/cltbld', 10:21:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:21:48 INFO - 'LOGNAME': 'cltbld', 10:21:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:21:48 INFO - 'MOZ_NO_REMOTE': '1', 10:21:48 INFO - 'NO_EM_RESTART': '1', 10:21:48 INFO - 'PAGER': '/bin/cat', 10:21:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:21:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:21:48 INFO - 'PWD': '/builds/slave/test', 10:21:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:21:48 INFO - 'SHELL': '/bin/bash', 10:21:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cWAPegPazC/Listeners', 10:21:48 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 10:21:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:21:48 INFO - 'USER': 'cltbld', 10:21:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:21:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:21:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:21:48 INFO - 'XPC_FLAGS': '0x0', 10:21:48 INFO - 'XPC_SERVICE_NAME': '0', 10:21:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:21:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:21:48 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)) 10:21:48 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)) 10:21:48 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)) 10:21:48 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)) 10:21:48 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)) 10:21:48 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)) 10:21:48 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)) 10:21:48 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)) 10:21:48 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)) 10:21:48 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)) 10:21:48 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)) 10:21:48 INFO - Cleaning up... 10:21:48 INFO - Return code: 0 10:21:48 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:21:48 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')]} 10:21:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:21:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:21:48 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')]} 10:21:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:21:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:21:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109094b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109097768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e68d789e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e72d30>, '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 0x108d99db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108e67be8>, '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.7sjNOCmQIs/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.cWAPegPazC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 10:21:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 10:21:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 10:21:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7sjNOCmQIs/Render', 10:21:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:21:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:21:48 INFO - 'HOME': '/Users/cltbld', 10:21:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:21:48 INFO - 'LOGNAME': 'cltbld', 10:21:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:21:48 INFO - 'MOZ_NO_REMOTE': '1', 10:21:48 INFO - 'NO_EM_RESTART': '1', 10:21:48 INFO - 'PAGER': '/bin/cat', 10:21:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:21:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:21:48 INFO - 'PWD': '/builds/slave/test', 10:21:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:21:48 INFO - 'SHELL': '/bin/bash', 10:21:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cWAPegPazC/Listeners', 10:21:48 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 10:21:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:21:48 INFO - 'USER': 'cltbld', 10:21:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:21:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:21:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:21:48 INFO - 'XPC_FLAGS': '0x0', 10:21:48 INFO - 'XPC_SERVICE_NAME': '0', 10:21:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:21:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:21:48 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:21:48 INFO - Cleaning up... 10:21:48 INFO - Return code: 0 10:21:48 INFO - Running post-action listener: _resource_record_post_action 10:21:48 INFO - Running post-action listener: _start_resource_monitoring 10:21:48 INFO - Starting resource monitoring. 10:21:48 INFO - [mozharness: 2017-04-07 17:21:48.792658Z] Finished create-virtualenv step (success) 10:21:48 INFO - [mozharness: 2017-04-07 17:21:48.793598Z] Running install step. 10:21:48 INFO - Running pre-action listener: _resource_record_pre_action 10:21:48 INFO - Running main action method: install 10:21:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:21:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:21:49 INFO - Reading from file tmpfile_stdout 10:21:49 INFO - Detecting whether we're running mozinstall >=1.0... 10:21:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:21:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:21:49 INFO - Reading from file tmpfile_stdout 10:21:49 INFO - Output received: 10:21:49 INFO - Usage: mozinstall [options] installer 10:21:49 INFO - Options: 10:21:49 INFO - -h, --help show this help message and exit 10:21:49 INFO - -d DEST, --destination=DEST 10:21:49 INFO - Directory to install application into. [default: 10:21:49 INFO - "/builds/slave/test"] 10:21:49 INFO - --app=APP Application being installed. [default: firefox] 10:21:49 INFO - mkdir: /builds/slave/test/build/application 10:21:49 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'] 10:21:49 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 10:22:08 INFO - Reading from file tmpfile_stdout 10:22:08 INFO - Output received: 10:22:08 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 10:22:08 INFO - Running post-action listener: _resource_record_post_action 10:22:08 INFO - [mozharness: 2017-04-07 17:22:08.802573Z] Finished install step (success) 10:22:08 INFO - [mozharness: 2017-04-07 17:22:08.802723Z] Running run-tests step. 10:22:08 INFO - Running pre-action listener: _resource_record_pre_action 10:22:08 INFO - Running main action method: run_tests 10:22:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:22:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:22:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:22:08 INFO - 2017-04-07 10:22:08.817 screenresolution[1625:7615] starting screenresolution argv=screenresolution get 10:22:08 INFO - 2017-04-07 10:22:08.819 screenresolution[1625:7615] Display 0: 1600x1200x32@60 10:22:08 INFO - 2017-04-07 10:22:08.825 screenresolution[1626:7617] starting screenresolution argv=screenresolution list 10:22:08 INFO - Available Modes on Display 0 10:22:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:22:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:22:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:22:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:22:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:22:09 INFO - Intel Iris: 10:22:09 INFO - Chipset Model: Intel Iris 10:22:09 INFO - Type: GPU 10:22:09 INFO - Bus: Built-In 10:22:09 INFO - VRAM (Dynamic, Max): 1536 MB 10:22:09 INFO - Vendor: Intel (0x8086) 10:22:09 INFO - Device ID: 0x0a2e 10:22:09 INFO - Revision ID: 0x0009 10:22:09 INFO - Displays: 10:22:09 INFO - Display: 10:22:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:22:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:22:09 INFO - Display Serial Number: 1600x1200 60 10:22:09 INFO - Main Display: Yes 10:22:09 INFO - Mirror: Off 10:22:09 INFO - Online: Yes 10:22:09 INFO - Rotation: Supported 10:22:09 INFO - Return code: 0 10:22:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:22:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:22:09 INFO - Python 2.7.3 10:22:09 INFO - Return code: 0 10:22:09 INFO - grabbing minidump binary from tooltool 10:22:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:22:09 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 0x108e72d30>, '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 0x108d99db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108e67be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:22:09 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 10:22:09 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 10:22:09 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 10:22:09 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:22:09 INFO - Return code: 0 10:22:09 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:22:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:22:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:22:09 INFO - ENV: RUST_BACKTRACE is now 1 10:22:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:22:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:22:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Yb79z2tSRUCUTb6pnWKZzw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0086', '--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 10:22:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Yb79z2tSRUCUTb6pnWKZzw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0086 --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 10:22:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7sjNOCmQIs/Render', 10:22:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:22:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:22:09 INFO - 'HOME': '/Users/cltbld', 10:22:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:22:09 INFO - 'LOGNAME': 'cltbld', 10:22:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:22:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:22:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:22:09 INFO - 'MOZ_NO_REMOTE': '1', 10:22:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:22:09 INFO - 'NO_EM_RESTART': '1', 10:22:09 INFO - 'PAGER': '/bin/cat', 10:22:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:22:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:22:09 INFO - 'PWD': '/builds/slave/test', 10:22:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:22:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:22:09 INFO - 'RUST_BACKTRACE': '1', 10:22:09 INFO - 'SHELL': '/bin/bash', 10:22:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cWAPegPazC/Listeners', 10:22:09 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 10:22:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:22:09 INFO - 'USER': 'cltbld', 10:22:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:22:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:22:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:22:09 INFO - 'XPC_FLAGS': '0x0', 10:22:09 INFO - 'XPC_SERVICE_NAME': '0', 10:22:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:22:09 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Yb79z2tSRUCUTb6pnWKZzw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0086', '--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 10:22:09 INFO - mozversion application_buildid: 20170407085322 10:22:09 INFO - mozversion application_changeset: 22df0c5cfbf8a2aa2822bf9c763e6c1c5a3f69de 10:22:09 INFO - mozversion application_display_name: Nightly 10:22:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:22:09 INFO - mozversion application_name: Firefox 10:22:09 INFO - mozversion application_remotingname: firefox 10:22:09 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 10:22:09 INFO - mozversion application_vendor: Mozilla 10:22:09 INFO - mozversion application_version: 55.0a1 10:22:09 INFO - mozversion platform_buildid: 20170407085322 10:22:09 INFO - mozversion platform_changeset: 22df0c5cfbf8a2aa2822bf9c763e6c1c5a3f69de 10:22:09 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 10:22:09 INFO - mozversion platform_version: 55.0a1 10:22:09 INFO - using testdate: 1491585729 10:22:09 INFO - actual date: 1491585729 10:22:09 INFO - starting webserver on 'localhost:49241' 10:22:09 INFO - SUITE-START | Running 2 tests 10:22:09 INFO - TEST-START | dromaeo_css 10:22:09 INFO - operating with platform_type : mac_ 10:22:09 INFO - Initialising browser for dromaeo_css test... 10:22:09 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/tmpVy8Ot1/profile http://localhost:49241/getInfo.html) 10:22:10 INFO - PID 1636 | 1491585730083 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 10:22:10 INFO - PID 1636 | 1491585730083 addons.xpi WARN Add-on deployment-checker@mozilla.org is missing bootstrap method install 10:22:10 INFO - PID 1636 | 1491585730135 addons.xpi WARN Add-on deployment-checker@mozilla.org is missing bootstrap method startup 10:22:12 INFO - PID 1636 | 2017-04-07 10:22:12.454 plugin-container[1638:7807] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a53, name = 'com.apple.tsm.portname' 10:22:12 INFO - PID 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:22:12 INFO - PID 1636 | 2017-04-07 10:22:12.457 plugin-container[1638:7807] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c17, name = 'com.apple.CFPasteboardClient' 10:22:12 INFO - PID 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:22:12 INFO - PID 1636 | 2017-04-07 10:22:12.457 plugin-container[1638:7807] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:22:12 INFO - PID 1636 | __metrics Screen width/height:1600/1200 10:22:12 INFO - PID 1636 | colorDepth:24 10:22:12 INFO - PID 1636 | Browser inner width/height: 1024/689 10:22:12 INFO - PID 1636 | __metrics 10:22:12 ERROR - PID 1636 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3364: TypeError: this.tabs is undefined 10:22:12 INFO - PID 1636 | 1491585732700 addons.xpi WARN Add-on deployment-checker@mozilla.org is missing bootstrap method shutdown 10:22:13 INFO - TEST-INFO | 1636: exit 0 10:22:13 INFO - Browser initialized. 10:22:13 INFO - Running cycle 1/1 for dromaeo_css test... 10:22:13 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/tmpVy8Ot1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:22:14 INFO - PID 1639 | 1491585734060 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 10:22:14 INFO - PID 1639 | 1491585734061 addons.xpi WARN Add-on deployment-checker@mozilla.org is missing bootstrap method startup 10:22:16 INFO - PID 1639 | 2017-04-07 10:22:16.218 plugin-container[1640:7941] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 10:22:16 INFO - PID 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:22:16 INFO - PID 1639 | 2017-04-07 10:22:16.219 plugin-container[1640:7941] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9103, name = 'com.apple.CFPasteboardClient' 10:22:16 INFO - PID 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:22:16 INFO - PID 1639 | 2017-04-07 10:22:16.219 plugin-container[1640:7941] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:22:25 INFO - PID 1639 | RSS: Main: 234250240 10:22:25 INFO - PID 1639 | 10:25:30 INFO - PID 1639 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 10:25:32 INFO - PID 1639 | RSS: Main: 252416000 10:25:32 INFO - PID 1639 | 10:27:14 INFO - PID 1639 | ************************* 10:27:14 INFO - PID 1639 | A coding exception was thrown and uncaught in a Task. 10:27:14 INFO - PID 1639 | 10:27:14 ERROR - PID 1639 | Full message: TypeError: NetworkError when attempting to fetch resource. 10:27:14 INFO - PID 1639 | Full stack: 10:27:14 INFO - PID 1639 | ************************* 10:27:14 INFO - PID 1639 | ************************* 10:27:14 INFO - PID 1639 | A coding exception was thrown and uncaught in a Task. 10:27:14 INFO - PID 1639 | 10:27:14 ERROR - PID 1639 | Full message: TypeError: NetworkError when attempting to fetch resource. 10:27:14 INFO - PID 1639 | Full stack: 10:27:14 INFO - PID 1639 | ************************* 10:27:14 INFO - PID 1639 | ************************* 10:27:14 INFO - PID 1639 | A coding exception was thrown and uncaught in a Task. 10:27:14 INFO - PID 1639 | 10:27:14 ERROR - PID 1639 | Full message: TypeError: NetworkError when attempting to fetch resource. 10:27:14 INFO - PID 1639 | Full stack: 10:27:14 INFO - PID 1639 | ************************* 10:27:14 INFO - PID 1639 | ************************* 10:27:14 INFO - PID 1639 | A coding exception was thrown and uncaught in a Task. 10:27:14 INFO - PID 1639 | 10:27:14 ERROR - PID 1639 | Full message: TypeError: NetworkError when attempting to fetch resource. 10:27:14 INFO - PID 1639 | Full stack: 10:27:14 INFO - PID 1639 | ************************* 10:28:34 INFO - PID 1639 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 10:28:35 INFO - PID 1639 | RSS: Main: 260972544 10:28:35 INFO - PID 1639 | 10:31:36 INFO - PID 1639 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 10:31:37 INFO - PID 1639 | RSS: Main: 276189184 10:31:37 INFO - PID 1639 | 10:34:39 INFO - PID 1639 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 10:34:40 INFO - PID 1639 | RSS: Main: 268488704 10:34:40 INFO - PID 1639 | 10:37:42 INFO - PID 1639 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 10:37:43 INFO - PID 1639 | RSS: Main: 278806528 10:37:43 INFO - PID 1639 | 10:40:45 INFO - PID 1639 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 10:40:45 INFO - PID 1639 | RSS: Main: 272625664 10:40:45 INFO - PID 1639 | 10:40:45 INFO - PID 1639 | __start_tp_report 10:40:45 INFO - PID 1639 | _x_x_mozilla_page_load 10:40:45 INFO - PID 1639 | _x_x_mozilla_page_load_details 10:40:45 INFO - PID 1639 | |i|pagename|runs| 10:40:45 INFO - PID 1639 | |0;dojo.html;3455.118944669109;3595.694365978893;3628.9657447627433;3643.8724644637437;3645.854165833367;5360.4639536046425;6144.341027922458;6245.219347581552;6307.936920630792;6329.082283068964;6207.286162091361;6252.124702541648;6266.248050233967;6303.968480157593;6339.556231063827;5555.194496797972;5577.498025177733;5605.803796867111;5646.745896434671;5661.943380566204;5513.669179849202;5605.635633683797;5640.576956728241;5741.397153298906;5755.769769209227;5488.91766623497;5537.8061767838335;5593.188987596816;5596.664200147963;5608.635438696512;5523;5532.861678458071;5538.723063846832;5562.7774889004395;5571.860703482445;5028.346314979066;5327.067763141474;5419.187121931703;5470.781168753245;5501.119820828688;6152.969235153841;6200.224971878515;6249.937500624981;6289.339619339995;6312.842178945517;6760.459163266927;6763.357481039294;6788.422984046362;6802.3537763912345;6805.693743781541;6063.787767428163;6827.419669328113;6845.863082738367;6866.416354609864;6873.965630171867;5776.220210271559;5838.737256823453;6182.072689096582;6336.081268216083;6388.808335749982;7042.295770422917;7064.646767661597;7073.292670732885;7095.467839912028;7098.574085554883;6409.294977552466;6492.383223593751;6524.478041756648;6650.73397064112;6694.564853284521;5794.942050579441;6654.833629159213;6780.830479238059;6862.553933994274;6872.484563657746;6139.737467782837;6682.164729408824;6723.831904202434;6727.159105111861;6731.596104233761;5529.225908372831;6656.833579160463;6713.12729345182;6743.52795304324;6790.388865002174;5432.592555558349;5435.619506634576;5436.048691478975;5456.890862182813;5472.480114389126;4725.362076119749;4743.976280118577;4748.216544270226;4797.064572408347;4820.132376172322;5034.244863270465;6207.844803879939;6362.554621176566;6376.1392212051705;6378.968105159444;6813.625250611264;6843.349881761224;6869.553479023947;6882.724691012403;6885.655717214119;6351.110844481776;6395.744170233139;6397;6408.67956602168;6418.101465794793;5128.564072053842;5881.823545293648;5966.134910438049;6009.909851352146;6025.969870150621;5894.793682221101;6315.147455093687;6632.668366581555;7042.119735033121;7087.149542054986;6033.668148251712;6829.965850170717;6884.586924784529;6901.1718593769065;6914.135733033371;6750.223724271772;6832.965835170792;6893.379595836399;6991.405730512964;7063.187733410724;6630.574426498326;6690.498212633975;6693.598384096969;6720.1599800025;6752.594844309161;6585.967070164618;6688;6847.315268473113;6853.965730171317;6866.828329291808;6384.3934826191435;6479.514036447191;6649.667516624246;6651.534392592472;6655.301193374819;6003.789867354796;6368.840778980562;6644.069830223676;6651.966740166268;6676.265610782712;6334.271558770801;6372.171617689671;6393.1688880445245;6424.903626445701;6458.095866578777;6644.036614690746;6660.433863121495;6661.233958094881;6661.4004739573675;6677.465802735866;6460.192475940507;6468;6485.28661847187;6485.416312531706;6600.075989361397 10:40:45 INFO - PID 1639 | |1;ext.html;693.4279219643794;748.876435388161;828.0229329391316;1290.8321918150639;1309.9607011789649;37201.697940572085;38145.04637384067;38145.283462244195;38225.426618600744;38247.61752382475;38221;38225.85322440331;38595.614043859554;38670.613293867056;38689.61310386896;7933.920660793376;8102.635381407821;8390.119037501066;8566.914330856705;8595.656173753074;8513.659453621847;8609.61256743448;8632;8645.95677021614;8646.567671616425;8615.913840861605;8656.177663122025;8657.523836189008;8661.653533858638;8733.257673097794;8569.828603427959;8606.56967151643;8655.783605409852;8661.740147795575;8668.869966950439;8420.989481262226;8458.788530286793;8476.915230847675;8480.939882514685;8491.363147763943;37120.25759484795;37318;37333.06667333311;37432.62567374346;37815.62184378148;3470.132466883279;3501.369753444379;3506.2111025019167;3506.579210494757;3512.7365447591533;29693.554596681068;29753.809847606288;29832.40335193284;29852.701472985427;29885.40229195404;3043.3152540678393;3075;3095.8142511449387;3102.3640153768424;3122.3599162171927;278047;278121;281706;286009;286453;46283;46341.5365846344;46387.76806115948;46394.89656137822;46414.07171856544;48109.27836082462;48246;48552.5144748555;48559.5144048555;48627.513724863005;14237.715245695028;14241.78637320441;14342.784858227134;14352.641183970485;14378.424863005572;11462.48418821155;11628;11664.358460284597;11682.883171168349;11694.824577631342;13269.66825829362;13287.534936277183;13345.933270333586;13366.264855433044;13378.531751388655;4695.201815691341;5854.765809367577;5857.912131318034;5858.648481091063;5905.616134951214;836.2431999040756;872.821071680304;978.6525783346951;983.2478154212033;1039.1271332081087;18177.909110454362;22353.10587576511;23460.882695586413;23649.408764780674;23815.7618423817;942.5617088054088;1023.7747695506977;1029.763154474475;1080.74062225067;1125.5722825326325;10598.364098154136;11248.437578121062;12095.57665481704;12132.57535986254;12216.694582635506;375274;377463;377919;379428;379543;50120.24819627708;50315.993680126194;50541.74729126331;50546;50744.238836417484;1439.9280035998158;1478.098360000399;1508.3589474473524;1515.6665533582593;1522.6574020845442;16587.502374928772;23303.300900972998;23320.883395582914;23416.76583234214;23451.88274058619;36849.81575092107;37273.25453490861;37285.4407183887;37323;37451.43822842714;313998.860011397;317020;318353;318423;319386;25293.861776219746;25990.740092598833;30429.543556846224;31967.840160799045;32236.677633223368;37937;38972.220555589294;39091.80454097825;39832.40251396174;40140.397894031026;22297.665535016662;27946.860265698542;28550.42899142047;28754.424911502072;28948.565771513866;28822;28956.7104328954;28994.710052900045;29101;29226.977055802945;32675.183120422178;33348.99953001414;33380;33391.49912751262;33459.665403346626;27608.585871211548;28920;29018;29203.561946571244;29374;21171.78828211698;21448.713077215416;23097.88451057734;23331.416714582272;23351.649725254476 10:40:45 INFO - PID 1639 | |2;jquery.html;401.4139356539452;422.5246597577725;432.2004292059692;436.5002072626842;457.0653014585173;18285.360012399567;19560.706589401172;21096.789032109675;21962.90185490725;22003.22988695392;21436.03537840797;21739.34781956544;21748.89125554374;21781.891090544566;21797.67303490445;5361.624686271963;5971.193888825007;6218.751249949996;6257;6257.342978987209;5978.820635380924;6111.786087486938;6241.937580624204;6273.3726627337355;6279.37206279373;6203.906941395882;6247.375262473762;6252.249730032402;6255.499560035186;6266.248050233979;5964.19483369746;6135.601185922946;6156.7537298508005;6160.4455598995855;6161.260648722182;5403.540699040586;5422.322209723785;5458.372287186945;5483.643563168382;5548.528375088082;21954.670679939813;26548.734512655014;26921.730782692117;27064.45871082587;27257;2165.361218440548;2211.8672879627275;2233.475133343663;2241.674957131223;2244.102359056374;15919;17253.13734313292;17548.561285967826;17653.470395888144;17749.7337539937;2056.085042156241;2078.8752674839557;2124.6706760452;2127.0853532980814;2129;128604.498842541;135518;136151;138738;139829;11465.254758440675;11889.940550297366;12234.265944043385;12237.93881030607;12289.93855030737;15839.445619403266;15945.282462289224;16009.59976000586;16073.839261607469;16081;8791.912080879238;8931.955340223258;8932.508712020901;9043.547822608974;9055.864162037575;7553.773386798405;8327.66689332432;8423.241908228289;8698.695545655999;8699.43453675509;7462.368891286739;7734.613269336511;7739.7678069658;7744.6902123914415;7754.844903102019;2162.362103179566;2175.8803265820534;2176.5211653436218;2179.466030822459;2205.2722601541454;816.3673153306236;966.2366730283138;974.8342781727123;1004.5730564510054;1016.6441745389054;19378.031098445023;19664.70502942457;20736.274230401865;20802.895985520277;20861.269855555285;948.6110694615174;984.8919965039331;1003.4531180506641;1043.801677681238;1045.283980473363;10060.446675432775;10218.693439196835;10756.408397538054;10791.676249712522;10814.729631759112;656861;670239;684516;709234;712727;136998;139687.6031239695;140593;140770;141541;1168.9181757276908;1220.7924652809045;1269.9682507937375;1271.377025257617;1280.6990357266036;18607.53481162982;19059.42821715351;20245.696314555;21088.789112108683;21325.36023919285;61950.69024654848;63777.681111594145;64992;65602.67198664167;65733.34266657272;88674.55662721646;88944.66081061395;89461.55269223612;89739.55130224307;90026.09973900177;8462.323014158976;8696.130386961253;8719.433236839584;8824.558772061242;8861.91138088611;13351.332433378098;13562.593122205955;13573.79639305431;13680.863191368357;14057.718845622825;10667.093297069836;11368.829467557827;11760.647180584596;11833.644990650295;12084.516619335129;9621.56702948383;10274.486275686335;10289.434081125311;11084.279521831055;11365.374904380338;10593.89406105929;12026.518939242333;12108.455119519467;12206;12301.200421972542;8651.437656552303;8716.564171791511;8751.474911505331;8868.778280543038;9069.818603628022;11657.242279251821;11780.58767943152;11942.164048516868;11979.34113623759;12147.967422769163 10:40:45 INFO - PID 1639 | |3;mootools.html;1399.6290982889534;1448.6161167290677;1459.3287087939548;1464.1946929188944;1498.8276348219954;11051.17116216284;11365.147613928968;11376.943115284423;11512.079033677317;11744.941275293611;11341.752407235197;11392.54429822806;11487;11499.310041397523;11544.422778861066;2457.8648174350405;2496.1762618335997;2505.549001179787;2506.962395564059;2510.083819405922;2414.33605758417;2430.744771798962;2458.4345600511892;2483.3543278747566;2488.029668429314;2434.47658753368;2449.1672831237374;2468.02513007362;2468.950620987579;2471.468634243637;2431.650434009124;2435.476372579904;2455.1529722245796;2470.4564995701007;2476.6532685424054;2377.405648587853;2444.64552639868;2475.851448913053;2477.938051548708;2487.054919130737;11364;11419.31484110956;11441.942790286079;11511.539538418454;11521.654350369503;6406.4554512866;6565.113709649232;6660.766873159416;6675.065490831287;6678.465722742169;3935.980320098381;4169.60388763067;4216.2621541230465;4239.7668128252935;4251.80866860992;1201.7956947318976;1216.8661447240797;1262.9052821038458;1273.178799674205;1273.8789864918813;14707.485238016725;15091.698166036618;15728.921355393264;15832.287547060294;15901.840981590152;5317.813876514381;5456.7544460498575;5640.041192997201;5653.575981801381;5660.830175094754;11776.470058847372;11857.88142118585;11858.584949526898;11872.525098995944;11901.107416943763;6458.2895881453005;6465.191851018622;6468.320826313169;6473.579217350857;6489.772857949949;5428.782848686087;6117.715279791205;6135.018397056449;6166.075088736636;6171.5988460750805;4277.272863613131;4354.586314300136;4367.803448844746;4380.277254253013;4422.3366495026;1093.3658478082693;1282.9166104203198;1295.9222446653232;1297.6172029251393;1314.0210543145417;770.7572114783825;782.5461232485145;797.2784629909941;798.4650284309411;814.9511029338258;5922.555808314393;6002.939970600325;6152.384761523812;6186.81439556805;6253.96873015632;562.1707980728393;564.3256308711051;565.3639655387689;581.1606266167532;590.7223604905773;3740.952533290683;3992.3412636915164;4011.197760447922;4197.874063778091;4300.333448315516;14121.08212966154;14851;14876.214854217564;14878.851211487747;14891.47879824201;14366.712665746836;14417.42330306776;14559.272036398137;14603.269836508132;14778.852211477963;10619.82293629804;10713.625023124154;10778.514966826511;10787.946060269805;10813.243072984815;5454;5788.942110578841;6874.209465911485;6931.584104953719;6950.131233595801;12581.182223155465;12652.114351995271;12657.493700252255;12660.240385576526;12909.806352904527;13224.471021159046;13311.66720831987;13394.93302533481;13505.459781609019;13705.862941370458;7988.960055199686;8160.918390816016;8178.918210817816;8179;8188.959055204924;10376.325538839952;10388.532516036645;10402.895971040496;10464.267501274837;10489.790204196026;8759.60581773809;8828.205461508494;8949.955250223707;9058.637654493747;9072.546372681207;8584.399092063495;8641.265492433213;8694.177614188306;8759.430637008574;8771.91228087711;7876.4092693047105;7960.681572737258;8055;8064.6370913307865;8075.232852878967;5287.391949925808;5991.490723288569;5992.550558708202;6001.06983417571;6116.418940200674;5908.231929849092;5947.881042379215;6119;6156.815295541141;6257.655828929453 10:40:45 INFO - PID 1639 | |4;prototype.html;386.55353067207386;402.59539163141056;404.2198556785404;405.1613160757234;414.2440046914381;4812.855614331567;6235.844103897399;6249.9062514062325;6282.277538083122;6401.847667419868;5209.765560549775;5901.704914754267;6067.87864242716;6090.421409966058;6093;4475.865724028285;4491.438570178728;4507.4140361752925;4510.954890451103;4606.585407313341;4407.338899165119;4421.712588681726;4456.844010459635;4483;4492.550744925514;4012.6557603202964;4255.468066491689;4424.77876106195;4425.269830477967;4436.66724995625;3840.7119466040017;4417.712848664826;4423.447069116361;4435.334699795024;4451.332300154971;3635.72732045095;3699.2046709957217;3735.14091758897;3803.2773772983323;3842.1163132479687;4861.562459378673;5904.7342869570975;5916.875793599203;5943.435373639497;5971.611845230046;4795.544423279783;5308.787648494055;5313.341291524553;5323.494268044568;5339.839804805862;5633.6619802811965;5765.818007308491;5862.794802181903;6387.776427825004;6456.967715161394;3978.189923584762;4321.962728945062;4323.156984388045;4338.43600331958;4349.108432771274;6029.728662210211;6577.901331480031;6657.672512866676;6743.639726095827;6765.188177418692;4405.713628614129;4411.911761764747;4426.004149066434;4429.512753597101;4442.600995462479;4077;4250.3199488081755;4497.527759585262;4500.8649740507835;4513.887152821206;4222.944263934017;4670.696404733681;5044.1929291313945;5148.897022059537;5276.604254680838;5520;5937.346891841894;6145.508359331243;6564.7374105036215;6651.86696266072;1301.7526669932683;1520.6274462756696;1530;1538.5461288919796;1539.61434708763;870.4907629036958;976.7460460280237;1019.1133713669155;1033.8294181459976;1044.3159730376615;6219;6228.688565571703;6246.250449946036;6266.467350275278;6270.968645156745;6624.701888415033;6832.59004459724;6834.760783372558;6844.110265665531;6863.279355667583;5057.620678449131;6139.355367686418;6226.595271307441;6234.441389652587;6243.406876346739;3034.499307614263;3140.0893740815363;3442.6729460701195;3585.7848529088337;3600;5563.443655634485;6680.13158289429;6814.931850681529;6878.243393226741;6964.512484126164;6020.886136064842;6653.538190869409;6894.655267236618;6958.190870373739;7014.824629384204;5778.884422311614;6100.328963813977;6377.8724425512155;6938.01064387989;7034.155901291775;5443.809466668806;5612.915806262828;5931.228940237742;6758.966205168943;6818.897716534157;5553.361363443256;6313.779017734358;6336.683165841781;6439.903401449075;6679.666016699242;5677.914831277617;6338.58799178052;7003.964980175067;7012.158540975116;7022.683979221049;3900.317444447154;4442.844500442469;4449.466064072332;4462.286034234637;4469.865904022885;6204.720787564661;6601.801945941629;6788.287229840796;6838.3161683833205;6892.448604111759;5457.426970168234;6568.638724870178;6609.239937407067;6611.33886611335;6617.636030018395;6323.114763933145;6502.544821862424;6512.804615861532;6559.245686746085;6607.636579988147;5836.678982655994;5939.485431215013;6282.680637066267;6378.1070650108095;6461.676916154268;5196.662216955886;6570;6583.736650533925;6601.24085730128;6601.702923368364;6315.55791094619;6396.360363963752;6424.38968298011;6426.871462570816;6517.837054073686 10:40:45 INFO - PID 1639 | |5;yui.html;787.5707739282091;812.0458461307963;818.1246066708624;853.3173461231014;854.7947394174212;12484.250944943298;14357.425702971896;16098.436554720573;16501.257443415045;16813.411530596433;14912.701745965074;15111;15181.696366072672;15304.311305991228;15424.69150616987;979.5445118020137;993.8111758765848;1020.1838529176666;1028.2596530498029;1028.3058935218698;1009.7778488732467;1022.8670272864517;1025.9487025648725;1027.917766578672;1028.3778314119925;1053.6996955867542;1071.4803320389606;1078.8273876179812;1079.7678499122687;1087.8041952448557;1073.6295977887617;1075.639660713662;1076.2304951959347;1079.0234837472021;1080.8811030786646;776.9611519424006;791.3510921044746;800.7917941335237;801.3028665061375;806.5805780993909;16585.004899705935;16973.15134243283;17142.828571714246;17160.742588861176;17205.311787528484;14785.260736963217;14824.703505929821;14866.479673211494;14880.776788348183;15573;13013.609591712264;13263.602091937257;13447.529336473275;13608.727825443535;13653.795193072112;3355.228297491566;3375.5443015193127;3422.6235114137426;3424.332255210235;3429.4684323929832;115763.4211828944;117353;118037.81962180354;118934;120310.3984480072;8121.8375632488205;11033.117350611932;11049.447527623586;11154.386508741934;11457.968782809587;23858.52282954331;24213.63679544808;24391.512169756505;24505.387365316008;24650.87674561616;7996.120426753053;8095.433319667567;8140.715074972348;8151.470154439942;8304.335653147733;4141.834326626961;4170.291050521419;4176.791160442027;4196.81114349855;4202;4059.8579049733116;4154.4183814266025;4166.854160104443;4183.769892655872;4196.853110141192;864.2783275964514;883.5052370672444;891.5809569502323;895.2793001633747;898.5937008581549;545.8035107361346;608.1850320570456;614.6158650843223;622.8754249150188;652.4030512081392;8887.97788254346;9641.951790241144;9843.950780246196;10175.510143183148;10216.182705383551;589.0281036290155;599.7870755881686;616.2974209401286;635.4312889963456;639.1084437210066;4797.969705447441;5103.106956282636;5208.489238782257;5212.8957420851375;5403.216533602685;100310.4984475073;100353.49823250982;102526.48736756269;102840.97159028464;103908.48045759722;89880.55059724659;89966;90608;90859;92625.073749263;77870;78558.6072069636;79371;79679.60160199161;80386;9370.39092458988;10391.220658450495;10797.838032429678;10852.565897364333;10920.071793897609;40277.597224028556;40501.1899762009;40600.28290797578;41792.791036044626;41932.37101443542;53719.731401342746;54198.10306639249;54834.69401018934;55151.62120946848;55178.72410637921;13277.859259481116;13368.264745438706;13465;13674.247916364695;13839.377228024965;13388.330583470593;13615.748717643826;13694.863051369757;13763;13813.51652692151;12405.161213539655;12623.621291361274;12679.93660031694;12811.551595694105;13167.802482962954;11469;13410.32948352559;13462.86537134616;13528.120672156278;13543.661408464866;13245.801312980506;13364.599062028155;13441.395137219044;13513.189208647513;13534;9499.097585729345;9641.505566637183;9926.851097233692;10052.095311422006;10142.137918276734;10907.127429805438;10967.850797526628;11127.937909115339;11274;11296.548138074713 10:40:45 INFO - PID 1639 | __end_tp_report 10:40:45 INFO - PID 1639 | __start_cc_report 10:40:45 INFO - PID 1639 | _x_x_mozilla_cycle_collect,431 10:40:45 INFO - PID 1639 | __end_cc_report 10:40:45 INFO - PID 1639 | __startTimestamp1491586845880__endTimestamp 10:40:45 INFO - PID 1639 | 10:40:45 INFO - PID 1639 | ------- Summary: start ------- 10:40:45 INFO - PID 1639 | Number of tests: 6 10:40:45 INFO - PID 1639 | 10:40:45 INFO - PID 1639 | [#0] dojo.html Cycles:165 Average:6194.29 Median:6388.81 stddev:737.36 (11.5%) stddev-sans-first:707.62 10:40:45 INFO - PID 1639 | Values: 3455.1 3595.7 3629.0 3643.9 3645.9 5360.5 6144.3 6245.2 6307.9 6329.1 6207.3 6252.1 6266.2 6304.0 6339.6 5555.2 5577.5 5605.8 5646.7 5661.9 5513.7 5605.6 5640.6 5741.4 5755.8 5488.9 5537.8 5593.2 5596.7 5608.6 5523.0 5532.9 5538.7 5562.8 5571.9 5028.3 5327.1 5419.2 5470.8 5501.1 6153.0 6200.2 6249.9 6289.3 6312.8 6760.5 6763.4 6788.4 6802.4 6805.7 6063.8 6827.4 6845.9 6866.4 6874.0 5776.2 5838.7 6182.1 6336.1 6388.8 7042.3 7064.6 7073.3 7095.5 7098.6 6409.3 6492.4 6524.5 6650.7 6694.6 5794.9 6654.8 6780.8 6862.6 6872.5 6139.7 6682.2 6723.8 6727.2 6731.6 5529.2 6656.8 6713.1 6743.5 6790.4 5432.6 5435.6 5436.0 5456.9 5472.5 4725.4 4744.0 4748.2 4797.1 4820.1 5034.2 6207.8 6362.6 6376.1 6379.0 6813.6 6843.3 6869.6 6882.7 6885.7 6351.1 6395.7 6397.0 6408.7 6418.1 5128.6 5881.8 5966.1 6009.9 6026.0 5894.8 6315.1 6632.7 7042.1 7087.1 6033.7 6830.0 6884.6 6901.2 6914.1 6750.2 6833.0 6893.4 6991.4 7063.2 6630.6 6690.5 6693.6 6720.2 6752.6 6586.0 6688.0 6847.3 6854.0 6866.8 6384.4 6479.5 6649.7 6651.5 6655.3 6003.8 6368.8 6644.1 6652.0 6676.3 6334.3 6372.2 6393.2 6424.9 6458.1 6644.0 6660.4 6661.2 6661.4 6677.5 6460.2 6468.0 6485.3 6485.4 6600.1 10:40:45 INFO - PID 1639 | 10:40:45 INFO - PID 1639 | [#1] ext.html Cycles:180 Average:46317.57 Median:33425.58 stddev:86535.86 (258.9%) stddev-sans-first:86710.82 10:40:45 INFO - PID 1639 | Values: 693.4 748.9 828.0 1290.8 1310.0 37201.7 38145.0 38145.3 38225.4 38247.6 38221.0 38225.9 38595.6 38670.6 38689.6 7933.9 8102.6 8390.1 8566.9 8595.7 8513.7 8609.6 8632.0 8646.0 8646.6 8615.9 8656.2 8657.5 8661.7 8733.3 8569.8 8606.6 8655.8 8661.7 8668.9 8421.0 8458.8 8476.9 8480.9 8491.4 37120.3 37318.0 37333.1 37432.6 37815.6 3470.1 3501.4 3506.2 3506.6 3512.7 29693.6 29753.8 29832.4 29852.7 29885.4 3043.3 3075.0 3095.8 3102.4 3122.4 278047.0 278121.0 281706.0 286009.0 286453.0 46283.0 46341.5 46387.8 46394.9 46414.1 48109.3 48246.0 48552.5 48559.5 48627.5 14237.7 14241.8 14342.8 14352.6 14378.4 11462.5 11628.0 11664.4 11682.9 11694.8 13269.7 13287.5 13345.9 13366.3 13378.5 4695.2 5854.8 5857.9 5858.6 5905.6 836.2 872.8 978.7 983.2 1039.1 18177.9 22353.1 23460.9 23649.4 23815.8 942.6 1023.8 1029.8 1080.7 1125.6 10598.4 11248.4 12095.6 12132.6 12216.7 375274.0 377463.0 377919.0 379428.0 379543.0 50120.2 50316.0 50541.7 50546.0 50744.2 1439.9 1478.1 1508.4 1515.7 1522.7 16587.5 23303.3 23320.9 23416.8 23451.9 36849.8 37273.3 37285.4 37323.0 37451.4 313998.9 317020.0 318353.0 318423.0 319386.0 25293.9 25990.7 30429.5 31967.8 32236.7 37937.0 38972.2 39091.8 39832.4 40140.4 22297.7 27946.9 28550.4 28754.4 28948.6 28822.0 28956.7 28994.7 29101.0 29227.0 32675.2 33349.0 33380.0 33391.5 33459.7 27608.6 28920.0 29018.0 29203.6 29374.0 21171.8 21448.7 23097.9 23331.4 23351.6 10:40:45 INFO - PID 1639 | 10:40:45 INFO - PID 1639 | [#2] jquery.html Cycles:180 Average:39518.10 Median:11988.57 stddev:114636.46 (956.2%) stddev-sans-first:114920.42 10:40:45 INFO - PID 1639 | Values: 401.4 422.5 432.2 436.5 457.1 18285.4 19560.7 21096.8 21962.9 22003.2 21436.0 21739.3 21748.9 21781.9 21797.7 5361.6 5971.2 6218.8 6257.0 6257.3 5978.8 6111.8 6241.9 6273.4 6279.4 6203.9 6247.4 6252.2 6255.5 6266.2 5964.2 6135.6 6156.8 6160.4 6161.3 5403.5 5422.3 5458.4 5483.6 5548.5 21954.7 26548.7 26921.7 27064.5 27257.0 2165.4 2211.9 2233.5 2241.7 2244.1 15919.0 17253.1 17548.6 17653.5 17749.7 2056.1 2078.9 2124.7 2127.1 2129.0 128604.5 135518.0 136151.0 138738.0 139829.0 11465.3 11889.9 12234.3 12237.9 12289.9 15839.4 15945.3 16009.6 16073.8 16081.0 8791.9 8932.0 8932.5 9043.5 9055.9 7553.8 8327.7 8423.2 8698.7 8699.4 7462.4 7734.6 7739.8 7744.7 7754.8 2162.4 2175.9 2176.5 2179.5 2205.3 816.4 966.2 974.8 1004.6 1016.6 19378.0 19664.7 20736.3 20802.9 20861.3 948.6 984.9 1003.5 1043.8 1045.3 10060.4 10218.7 10756.4 10791.7 10814.7 656861.0 670239.0 684516.0 709234.0 712727.0 136998.0 139687.6 140593.0 140770.0 141541.0 1168.9 1220.8 1270.0 1271.4 1280.7 18607.5 19059.4 20245.7 21088.8 21325.4 61950.7 63777.7 64992.0 65602.7 65733.3 88674.6 88944.7 89461.6 89739.6 90026.1 8462.3 8696.1 8719.4 8824.6 8861.9 13351.3 13562.6 13573.8 13680.9 14057.7 10667.1 11368.8 11760.6 11833.6 12084.5 9621.6 10274.5 10289.4 11084.3 11365.4 10593.9 12026.5 12108.5 12206.0 12301.2 8651.4 8716.6 8751.5 8868.8 9069.8 11657.2 11780.6 11942.2 11979.3 12148.0 10:40:45 INFO - PID 1639 | 10:40:45 INFO - PID 1639 | [#3] mootools.html Cycles:180 Average:6978.69 Median:2492.10 stddev:4391.41 (176.2%) stddev-sans-first:4383.72 10:40:45 INFO - PID 1639 | Values: 1399.6 1448.6 1459.3 1464.2 1498.8 11051.2 11365.1 11376.9 11512.1 11744.9 11341.8 11392.5 11487.0 11499.3 11544.4 2457.9 2496.2 2505.5 2507.0 2510.1 2414.3 2430.7 2458.4 2483.4 2488.0 2434.5 2449.2 2468.0 2469.0 2471.5 2431.7 2435.5 2455.2 2470.5 2476.7 2377.4 2444.6 2475.9 2477.9 2487.1 11364.0 11419.3 11441.9 11511.5 11521.7 6406.5 6565.1 6660.8 6675.1 6678.5 3936.0 4169.6 4216.3 4239.8 4251.8 1201.8 1216.9 1262.9 1273.2 1273.9 14707.5 15091.7 15728.9 15832.3 15901.8 5317.8 5456.8 5640.0 5653.6 5660.8 11776.5 11857.9 11858.6 11872.5 11901.1 6458.3 6465.2 6468.3 6473.6 6489.8 5428.8 6117.7 6135.0 6166.1 6171.6 4277.3 4354.6 4367.8 4380.3 4422.3 1093.4 1282.9 1295.9 1297.6 1314.0 770.8 782.5 797.3 798.5 815.0 5922.6 6002.9 6152.4 6186.8 6254.0 562.2 564.3 565.4 581.2 590.7 3741.0 3992.3 4011.2 4197.9 4300.3 14121.1 14851.0 14876.2 14878.9 14891.5 14366.7 14417.4 14559.3 14603.3 14778.9 10619.8 10713.6 10778.5 10787.9 10813.2 5454.0 5788.9 6874.2 6931.6 6950.1 12581.2 12652.1 12657.5 12660.2 12909.8 13224.5 13311.7 13394.9 13505.5 13705.9 7989.0 8160.9 8178.9 8179.0 8189.0 10376.3 10388.5 10402.9 10464.3 10489.8 8759.6 8828.2 8950.0 9058.6 9072.5 8584.4 8641.3 8694.2 8759.4 8771.9 7876.4 7960.7 8055.0 8064.6 8075.2 5287.4 5991.5 5992.6 6001.1 6116.4 5908.2 5947.9 6119.0 6156.8 6257.7 10:40:45 INFO - PID 1639 | 10:40:45 INFO - PID 1639 | [#4] prototype.html Cycles:180 Average:5210.78 Median:5903.22 stddev:1646.28 (27.9%) stddev-sans-first:1610.59 10:40:45 INFO - PID 1639 | Values: 386.6 402.6 404.2 405.2 414.2 4812.9 6235.8 6249.9 6282.3 6401.8 5209.8 5901.7 6067.9 6090.4 6093.0 4475.9 4491.4 4507.4 4511.0 4606.6 4407.3 4421.7 4456.8 4483.0 4492.6 4012.7 4255.5 4424.8 4425.3 4436.7 3840.7 4417.7 4423.4 4435.3 4451.3 3635.7 3699.2 3735.1 3803.3 3842.1 4861.6 5904.7 5916.9 5943.4 5971.6 4795.5 5308.8 5313.3 5323.5 5339.8 5633.7 5765.8 5862.8 6387.8 6457.0 3978.2 4322.0 4323.2 4338.4 4349.1 6029.7 6577.9 6657.7 6743.6 6765.2 4405.7 4411.9 4426.0 4429.5 4442.6 4077.0 4250.3 4497.5 4500.9 4513.9 4222.9 4670.7 5044.2 5148.9 5276.6 5520.0 5937.3 6145.5 6564.7 6651.9 1301.8 1520.6 1530.0 1538.5 1539.6 870.5 976.7 1019.1 1033.8 1044.3 6219.0 6228.7 6246.3 6266.5 6271.0 6624.7 6832.6 6834.8 6844.1 6863.3 5057.6 6139.4 6226.6 6234.4 6243.4 3034.5 3140.1 3442.7 3585.8 3600.0 5563.4 6680.1 6814.9 6878.2 6964.5 6020.9 6653.5 6894.7 6958.2 7014.8 5778.9 6100.3 6377.9 6938.0 7034.2 5443.8 5612.9 5931.2 6759.0 6818.9 5553.4 6313.8 6336.7 6439.9 6679.7 5677.9 6338.6 7004.0 7012.2 7022.7 3900.3 4442.8 4449.5 4462.3 4469.9 6204.7 6601.8 6788.3 6838.3 6892.4 5457.4 6568.6 6609.2 6611.3 6617.6 6323.1 6502.5 6512.8 6559.2 6607.6 5836.7 5939.5 6282.7 6378.1 6461.7 5196.7 6570.0 6583.7 6601.2 6601.7 6315.6 6396.4 6424.4 6426.9 6517.8 10:40:45 INFO - PID 1639 | 10:40:45 INFO - PID 1639 | [#5] yui.html Cycles:180 Average:20470.14 Median:16893.28 stddev:29786.52 (176.3%) stddev-sans-first:29833.42 10:40:45 INFO - PID 1639 | Values: 787.6 812.0 818.1 853.3 854.8 12484.3 14357.4 16098.4 16501.3 16813.4 14912.7 15111.0 15181.7 15304.3 15424.7 979.5 993.8 1020.2 1028.3 1028.3 1009.8 1022.9 1025.9 1027.9 1028.4 1053.7 1071.5 1078.8 1079.8 1087.8 1073.6 1075.6 1076.2 1079.0 1080.9 777.0 791.4 800.8 801.3 806.6 16585.0 16973.2 17142.8 17160.7 17205.3 14785.3 14824.7 14866.5 14880.8 15573.0 13013.6 13263.6 13447.5 13608.7 13653.8 3355.2 3375.5 3422.6 3424.3 3429.5 115763.4 117353.0 118037.8 118934.0 120310.4 8121.8 11033.1 11049.4 11154.4 11458.0 23858.5 24213.6 24391.5 24505.4 24650.9 7996.1 8095.4 8140.7 8151.5 8304.3 4141.8 4170.3 4176.8 4196.8 4202.0 4059.9 4154.4 4166.9 4183.8 4196.9 864.3 883.5 891.6 895.3 898.6 545.8 608.2 614.6 622.9 652.4 8888.0 9642.0 9844.0 10175.5 10216.2 589.0 599.8 616.3 635.4 639.1 4798.0 5103.1 5208.5 5212.9 5403.2 100310.5 100353.5 102526.5 102841.0 103908.5 89880.6 89966.0 90608.0 90859.0 92625.1 77870.0 78558.6 79371.0 79679.6 80386.0 9370.4 10391.2 10797.8 10852.6 10920.1 40277.6 40501.2 40600.3 41792.8 41932.4 53719.7 54198.1 54834.7 55151.6 55178.7 13277.9 13368.3 13465.0 13674.2 13839.4 13388.3 13615.7 13694.9 13763.0 13813.5 12405.2 12623.6 12679.9 12811.6 13167.8 11469.0 13410.3 13462.9 13528.1 13543.7 13245.8 13364.6 13441.4 13513.2 13534.0 9499.1 9641.5 9926.9 10052.1 10142.1 10907.1 10967.9 11127.9 11274.0 11296.5 10:40:45 INFO - PID 1639 | -------- Summary: end -------- 10:40:45 INFO - PID 1639 | 10:40:45 INFO - PID 1639 | 1491586845910 addons.xpi WARN Add-on deployment-checker@mozilla.org is missing bootstrap method shutdown 10:40:46 INFO - TEST-INFO | 1639: exit 0 10:40:46 INFO - TEST-OK | dromaeo_css | took 1117111ms 10:40:46 INFO - TEST-START | kraken 10:40:46 INFO - operating with platform_type : mac_ 10:40:46 INFO - Initialising browser for kraken test... 10:40:46 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/tmpNjzV8S/profile http://localhost:49241/getInfo.html) 10:40:47 INFO - PID 1652 | 1491586847128 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 10:40:47 INFO - PID 1652 | 1491586847129 addons.xpi WARN Add-on deployment-checker@mozilla.org is missing bootstrap method install 10:40:47 INFO - PID 1652 | 1491586847180 addons.xpi WARN Add-on deployment-checker@mozilla.org is missing bootstrap method startup 10:40:49 INFO - PID 1652 | 2017-04-07 10:40:49.523 plugin-container[1653:10346] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 10:40:49 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:40:49 INFO - PID 1652 | 2017-04-07 10:40:49.527 plugin-container[1653:10346] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 10:40:49 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:40:49 INFO - PID 1652 | 2017-04-07 10:40:49.527 plugin-container[1653:10346] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:40:49 INFO - PID 1652 | __metrics Screen width/height:1600/1200 10:40:49 INFO - PID 1652 | colorDepth:24 10:40:49 INFO - PID 1652 | Browser inner width/height: 1024/689 10:40:49 INFO - PID 1652 | __metrics 10:40:49 ERROR - PID 1652 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3364: TypeError: this.tabs is undefined 10:40:49 INFO - PID 1652 | 1491586849735 addons.xpi WARN Add-on deployment-checker@mozilla.org is missing bootstrap method shutdown 10:40:50 INFO - TEST-INFO | 1652: exit 0 10:40:50 INFO - Browser initialized. 10:40:50 INFO - Running cycle 1/1 for kraken test... 10:40:50 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/tmpNjzV8S/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:40:51 INFO - PID 1654 | 1491586851106 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 10:40:51 INFO - PID 1654 | 1491586851107 addons.xpi WARN Add-on deployment-checker@mozilla.org is missing bootstrap method startup 10:40:53 INFO - PID 1654 | 2017-04-07 10:40:53.447 plugin-container[1655:10468] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883b, name = 'com.apple.tsm.portname' 10:40:53 INFO - PID 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:40:53 INFO - PID 1654 | 2017-04-07 10:40:53.447 plugin-container[1655:10468] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x430b, name = 'com.apple.CFPasteboardClient' 10:40:53 INFO - PID 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:40:53 INFO - PID 1654 | 2017-04-07 10:40:53.447 plugin-container[1655:10468] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:41:03 INFO - PID 1654 | RSS: Main: 233672704 10:41:03 INFO - PID 1654 | 10:41:05 INFO - PID 1654 | 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) 10:41:06 INFO - PID 1654 | RSS: Main: 248438784 10:41:06 INFO - PID 1654 | 10:41:09 INFO - PID 1654 | 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) 10:41:10 INFO - PID 1654 | RSS: Main: 256995328 10:41:10 INFO - PID 1654 | 10:41:14 INFO - PID 1654 | 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) 10:41:14 INFO - PID 1654 | RSS: Main: 259493888 10:41:14 INFO - PID 1654 | 10:41:16 INFO - PID 1654 | 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) 10:41:17 INFO - PID 1654 | RSS: Main: 260427776 10:41:17 INFO - PID 1654 | 10:41:19 INFO - PID 1654 | 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) 10:41:20 INFO - PID 1654 | RSS: Main: 262045696 10:41:20 INFO - PID 1654 | 10:41:23 INFO - PID 1654 | 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) 10:41:24 INFO - PID 1654 | RSS: Main: 267939840 10:41:24 INFO - PID 1654 | 10:41:27 INFO - PID 1654 | 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) 10:41:28 INFO - PID 1654 | RSS: Main: 267952128 10:41:28 INFO - PID 1654 | 10:41:31 INFO - PID 1654 | 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) 10:41:31 INFO - PID 1654 | RSS: Main: 267812864 10:41:31 INFO - PID 1654 | 10:41:33 INFO - PID 1654 | 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) 10:41:34 INFO - PID 1654 | RSS: Main: 267649024 10:41:34 INFO - PID 1654 | 10:41:35 INFO - PID 1654 | 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) 10:41:36 INFO - PID 1654 | RSS: Main: 267608064 10:41:36 INFO - PID 1654 | 10:41:38 INFO - PID 1654 | 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) 10:41:39 INFO - PID 1654 | RSS: Main: 267706368 10:41:39 INFO - PID 1654 | 10:41:42 INFO - PID 1654 | 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) 10:41:43 INFO - PID 1654 | RSS: Main: 268242944 10:41:43 INFO - PID 1654 | 10:41:46 INFO - PID 1654 | 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) 10:41:47 INFO - PID 1654 | RSS: Main: 265183232 10:41:47 INFO - PID 1654 | 10:41:49 INFO - PID 1654 | 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) 10:41:49 INFO - PID 1654 | RSS: Main: 268525568 10:41:49 INFO - PID 1654 | 10:41:49 INFO - PID 1654 | __start_tp_report 10:41:49 INFO - PID 1654 | _x_x_mozilla_page_load 10:41:49 INFO - PID 1654 | _x_x_mozilla_page_load_details 10:41:49 INFO - PID 1654 | |i|pagename|runs| 10:41:49 INFO - PID 1654 | |0;ai-astar;108;122;122;122;134;172;125;125;121;121 10:41:49 INFO - PID 1654 | |1;audio-beat-detection;164;138;157;188;110;163;155;121;180;155 10:41:49 INFO - PID 1654 | |2;audio-dft;224;227;221;227;228;307;222;261;201;276 10:41:49 INFO - PID 1654 | |3;audio-fft;78;60;66;91;58;71;53;50;68;54 10:41:49 INFO - PID 1654 | |4;audio-oscillator;108;103;124;108;109;101;85;72;72;89 10:41:49 INFO - PID 1654 | |5;imaging-gaussian-blur;84;93;92;92;91;136;92;87;93;91 10:41:49 INFO - PID 1654 | |6;imaging-darkroom;101;128;113;108;100;100;101;113;163;101 10:41:49 INFO - PID 1654 | |7;imaging-desaturate;104;104;104;104;102;103;102;133;107;109 10:41:49 INFO - PID 1654 | |8;json-parse-financial;53;50;51;52;53;53;51;52;52;52 10:41:49 INFO - PID 1654 | |9;json-stringify-tinderbox;61;60;65;69;68;65;60;61;60;77 10:41:49 INFO - PID 1654 | |10;stanford-crypto-aes;90;88;94;83;118;110;109;88;88;90 10:41:49 INFO - PID 1654 | |11;stanford-crypto-ccm;166;171;159;169;179;183;171;160;246;142 10:41:49 INFO - PID 1654 | |12;stanford-crypto-pbkdf2;202;199;198;198;240;201;199;204;249;194 10:41:49 INFO - PID 1654 | |13;stanford-crypto-sha256-iterative;73;72;83;85;70;73;73;73;71;73 10:41:49 INFO - PID 1654 | __end_tp_report 10:41:49 INFO - PID 1654 | __start_cc_report 10:41:49 INFO - PID 1654 | _x_x_mozilla_cycle_collect,1015 10:41:49 INFO - PID 1654 | __end_cc_report 10:41:49 INFO - PID 1654 | __startTimestamp1491586909626__endTimestamp 10:41:49 INFO - PID 1654 | 10:41:49 INFO - PID 1654 | ------- Summary: start ------- 10:41:49 INFO - PID 1654 | Number of tests: 14 10:41:49 INFO - PID 1654 | 10:41:49 INFO - PID 1654 | [#0] ai-astar Cycles:10 Average:127.20 Median:123.50 stddev:16.96 (13.7%) stddev-sans-first:16.51 10:41:49 INFO - PID 1654 | Values: 108.0 122.0 122.0 122.0 134.0 172.0 125.0 125.0 121.0 121.0 10:41:49 INFO - PID 1654 | 10:41:49 INFO - PID 1654 | [#1] audio-beat-detection Cycles:10 Average:153.10 Median:160.00 stddev:24.26 (15.2%) stddev-sans-first:25.41 10:41:49 INFO - PID 1654 | Values: 164.0 138.0 157.0 188.0 110.0 163.0 155.0 121.0 180.0 155.0 10:41:49 INFO - PID 1654 | 10:41:49 INFO - PID 1654 | [#2] audio-dft Cycles:10 Average:239.40 Median:227.50 stddev:31.91 (14.0%) stddev-sans-first:33.36 10:41:49 INFO - PID 1654 | Values: 224.0 227.0 221.0 227.0 228.0 307.0 222.0 261.0 201.0 276.0 10:41:49 INFO - PID 1654 | 10:41:49 INFO - PID 1654 | [#3] audio-fft Cycles:10 Average:64.90 Median:67.00 stddev:12.71 (19.0%) stddev-sans-first:12.57 10:41:49 INFO - PID 1654 | Values: 78.0 60.0 66.0 91.0 58.0 71.0 53.0 50.0 68.0 54.0 10:41:49 INFO - PID 1654 | 10:41:49 INFO - PID 1654 | [#4] audio-oscillator Cycles:10 Average:97.10 Median:98.00 stddev:17.08 (17.4%) stddev-sans-first:17.65 10:41:49 INFO - PID 1654 | Values: 108.0 103.0 124.0 108.0 109.0 101.0 85.0 72.0 72.0 89.0 10:41:49 INFO - PID 1654 | 10:41:49 INFO - PID 1654 | [#5] imaging-gaussian-blur Cycles:10 Average:95.10 Median:92.00 stddev:14.65 (15.9%) stddev-sans-first:14.98 10:41:49 INFO - PID 1654 | Values: 84.0 93.0 92.0 92.0 91.0 136.0 92.0 87.0 93.0 91.0 10:41:49 INFO - PID 1654 | 10:41:49 INFO - PID 1654 | [#6] imaging-darkroom Cycles:10 Average:112.80 Median:110.50 stddev:19.78 (17.9%) stddev-sans-first:20.51 10:41:49 INFO - PID 1654 | Values: 101.0 128.0 113.0 108.0 100.0 100.0 101.0 113.0 163.0 101.0 10:41:49 INFO - PID 1654 | 10:41:49 INFO - PID 1654 | [#7] imaging-desaturate Cycles:10 Average:107.20 Median:104.00 stddev:9.32 (9.0%) stddev-sans-first:9.81 10:41:49 INFO - PID 1654 | Values: 104.0 104.0 104.0 104.0 102.0 103.0 102.0 133.0 107.0 109.0 10:41:49 INFO - PID 1654 | 10:41:49 INFO - PID 1654 | [#8] json-parse-financial Cycles:10 Average:51.90 Median:52.00 stddev:0.99 (1.9%) stddev-sans-first:0.97 10:41:49 INFO - PID 1654 | Values: 53.0 50.0 51.0 52.0 53.0 53.0 51.0 52.0 52.0 52.0 10:41:49 INFO - PID 1654 | 10:41:49 INFO - PID 1654 | [#9] json-stringify-tinderbox Cycles:10 Average:64.60 Median:65.00 stddev:5.52 (8.5%) stddev-sans-first:5.70 10:41:49 INFO - PID 1654 | Values: 61.0 60.0 65.0 69.0 68.0 65.0 60.0 61.0 60.0 77.0 10:41:49 INFO - PID 1654 | 10:41:49 INFO - PID 1654 | [#10] stanford-crypto-aes Cycles:10 Average:95.80 Median:88.00 stddev:11.95 (13.6%) stddev-sans-first:12.49 10:41:49 INFO - PID 1654 | Values: 90.0 88.0 94.0 83.0 118.0 110.0 109.0 88.0 88.0 90.0 10:41:49 INFO - PID 1654 | 10:41:49 INFO - PID 1654 | [#11] stanford-crypto-ccm Cycles:10 Average:174.60 Median:171.00 stddev:27.56 (16.1%) stddev-sans-first:29.06 10:41:49 INFO - PID 1654 | Values: 166.0 171.0 159.0 169.0 179.0 183.0 171.0 160.0 246.0 142.0 10:41:49 INFO - PID 1654 | 10:41:49 INFO - PID 1654 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:208.40 Median:201.50 stddev:19.33 (9.6%) stddev-sans-first:20.36 10:41:49 INFO - PID 1654 | Values: 202.0 199.0 198.0 198.0 240.0 201.0 199.0 204.0 249.0 194.0 10:41:49 INFO - PID 1654 | 10:41:49 INFO - PID 1654 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.60 Median:73.00 stddev:5.08 (7.0%) stddev-sans-first:5.36 10:41:49 INFO - PID 1654 | Values: 73.0 72.0 83.0 85.0 70.0 73.0 73.0 73.0 71.0 73.0 10:41:49 INFO - PID 1654 | -------- Summary: end -------- 10:41:49 INFO - PID 1654 | 10:41:49 INFO - PID 1654 | 1491586909652 addons.xpi WARN Add-on deployment-checker@mozilla.org is missing bootstrap method shutdown 10:41:50 INFO - TEST-INFO | 1654: exit 0 10:41:50 INFO - TEST-OK | kraken | took 64315ms 10:41:50 INFO - SUITE-END | took 1181s 10:41:51 INFO - Completed test suite (00:19:41) 10:41:51 INFO - javascript benchmark 10:41:51 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7711.748377193845, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3455.118944669109, 3595.694365978893, 3628.9657447627433, 3643.8724644637437, 3645.854165833367, 5360.4639536046425, 6144.341027922458, 6245.219347581552, 6307.936920630792, 6329.082283068964, 6207.286162091361, 6252.124702541648, 6266.248050233967, 6303.968480157593, 6339.556231063827, 5555.194496797972, 5577.498025177733, 5605.803796867111, 5646.745896434671, 5661.943380566204, 5513.669179849202, 5605.635633683797, 5640.576956728241, 5741.397153298906, 5755.769769209227, 5488.91766623497, 5537.8061767838335, 5593.188987596816, 5596.664200147963, 5608.635438696512, 5523.0, 5532.861678458071, 5538.723063846832, 5562.7774889004395, 5571.860703482445, 5028.346314979066, 5327.067763141474, 5419.187121931703, 5470.781168753245, 5501.119820828688, 6152.969235153841, 6200.224971878515, 6249.937500624981, 6289.339619339995, 6312.842178945517, 6760.459163266927, 6763.357481039294, 6788.422984046362, 6802.3537763912345, 6805.693743781541, 6063.787767428163, 6827.419669328113, 6845.863082738367, 6866.416354609864, 6873.965630171867, 5776.220210271559, 5838.737256823453, 6182.072689096582, 6336.081268216083, 6388.808335749982, 7042.295770422917, 7064.646767661597, 7073.292670732885, 7095.467839912028, 7098.574085554883, 6409.294977552466, 6492.383223593751, 6524.478041756648, 6650.73397064112, 6694.564853284521, 5794.942050579441, 6654.833629159213, 6780.830479238059, 6862.553933994274, 6872.484563657746, 6139.737467782837, 6682.164729408824, 6723.831904202434, 6727.159105111861, 6731.596104233761, 5529.225908372831, 6656.833579160463, 6713.12729345182, 6743.52795304324, 6790.388865002174, 5432.592555558349, 5435.619506634576, 5436.048691478975, 5456.890862182813, 5472.480114389126, 4725.362076119749, 4743.976280118577, 4748.216544270226, 4797.064572408347, 4820.132376172322, 5034.244863270465, 6207.844803879939, 6362.554621176566, 6376.1392212051705, 6378.968105159444, 6813.625250611264, 6843.349881761224, 6869.553479023947, 6882.724691012403, 6885.655717214119, 6351.110844481776, 6395.744170233139, 6397.0, 6408.67956602168, 6418.101465794793, 5128.564072053842, 5881.823545293648, 5966.134910438049, 6009.909851352146, 6025.969870150621, 5894.793682221101, 6315.147455093687, 6632.668366581555, 7042.119735033121, 7087.149542054986, 6033.668148251712, 6829.965850170717, 6884.586924784529, 6901.1718593769065, 6914.135733033371, 6750.223724271772, 6832.965835170792, 6893.379595836399, 6991.405730512964, 7063.187733410724, 6630.574426498326, 6690.498212633975, 6693.598384096969, 6720.1599800025, 6752.594844309161, 6585.967070164618, 6688.0, 6847.315268473113, 6853.965730171317, 6866.828329291808, 6384.3934826191435, 6479.514036447191, 6649.667516624246, 6651.534392592472, 6655.301193374819, 6003.789867354796, 6368.840778980562, 6644.069830223676, 6651.966740166268, 6676.265610782712, 6334.271558770801, 6372.171617689671, 6393.1688880445245, 6424.903626445701, 6458.095866578777, 6644.036614690746, 6660.433863121495, 6661.233958094881, 6661.4004739573675, 6677.465802735866, 6460.192475940507, 6468.0, 6485.28661847187, 6485.416312531706, 6600.075989361397], "value": 6147.149871654562, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [693.4279219643794, 748.876435388161, 828.0229329391316, 1290.8321918150639, 1309.9607011789649, 37201.697940572085, 38145.04637384067, 38145.283462244195, 38225.426618600744, 38247.61752382475, 38221.0, 38225.85322440331, 38595.614043859554, 38670.613293867056, 38689.61310386896, 7933.920660793376, 8102.635381407821, 8390.119037501066, 8566.914330856705, 8595.656173753074, 8513.659453621847, 8609.61256743448, 8632.0, 8645.95677021614, 8646.567671616425, 8615.913840861605, 8656.177663122025, 8657.523836189008, 8661.653533858638, 8733.257673097794, 8569.828603427959, 8606.56967151643, 8655.783605409852, 8661.740147795575, 8668.869966950439, 8420.989481262226, 8458.788530286793, 8476.915230847675, 8480.939882514685, 8491.363147763943, 37120.25759484795, 37318.0, 37333.06667333311, 37432.62567374346, 37815.62184378148, 3470.132466883279, 3501.369753444379, 3506.2111025019167, 3506.579210494757, 3512.7365447591533, 29693.554596681068, 29753.809847606288, 29832.40335193284, 29852.701472985427, 29885.40229195404, 3043.3152540678393, 3075.0, 3095.8142511449387, 3102.3640153768424, 3122.3599162171927, 278047.0, 278121.0, 281706.0, 286009.0, 286453.0, 46283.0, 46341.5365846344, 46387.76806115948, 46394.89656137822, 46414.07171856544, 48109.27836082462, 48246.0, 48552.5144748555, 48559.5144048555, 48627.513724863005, 14237.715245695028, 14241.78637320441, 14342.784858227134, 14352.641183970485, 14378.424863005572, 11462.48418821155, 11628.0, 11664.358460284597, 11682.883171168349, 11694.824577631342, 13269.66825829362, 13287.534936277183, 13345.933270333586, 13366.264855433044, 13378.531751388655, 4695.201815691341, 5854.765809367577, 5857.912131318034, 5858.648481091063, 5905.616134951214, 836.2431999040756, 872.821071680304, 978.6525783346951, 983.2478154212033, 1039.1271332081087, 18177.909110454362, 22353.10587576511, 23460.882695586413, 23649.408764780674, 23815.7618423817, 942.5617088054088, 1023.7747695506977, 1029.763154474475, 1080.74062225067, 1125.5722825326325, 10598.364098154136, 11248.437578121062, 12095.57665481704, 12132.57535986254, 12216.694582635506, 375274.0, 377463.0, 377919.0, 379428.0, 379543.0, 50120.24819627708, 50315.993680126194, 50541.74729126331, 50546.0, 50744.238836417484, 1439.9280035998158, 1478.098360000399, 1508.3589474473524, 1515.6665533582593, 1522.6574020845442, 16587.502374928772, 23303.300900972998, 23320.883395582914, 23416.76583234214, 23451.88274058619, 36849.81575092107, 37273.25453490861, 37285.4407183887, 37323.0, 37451.43822842714, 313998.860011397, 317020.0, 318353.0, 318423.0, 319386.0, 25293.861776219746, 25990.740092598833, 30429.543556846224, 31967.840160799045, 32236.677633223368, 37937.0, 38972.220555589294, 39091.80454097825, 39832.40251396174, 40140.397894031026, 22297.665535016662, 27946.860265698542, 28550.42899142047, 28754.424911502072, 28948.565771513866, 28822.0, 28956.7104328954, 28994.710052900045, 29101.0, 29226.977055802945, 32675.183120422178, 33348.99953001414, 33380.0, 33391.49912751262, 33459.665403346626, 27608.585871211548, 28920.0, 29018.0, 29203.561946571244, 29374.0, 21171.78828211698, 21448.713077215416, 23097.88451057734, 23331.416714582272, 23351.649725254476], "value": 17066.67672165858, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [401.4139356539452, 422.5246597577725, 432.2004292059692, 436.5002072626842, 457.0653014585173, 18285.360012399567, 19560.706589401172, 21096.789032109675, 21962.90185490725, 22003.22988695392, 21436.03537840797, 21739.34781956544, 21748.89125554374, 21781.891090544566, 21797.67303490445, 5361.624686271963, 5971.193888825007, 6218.751249949996, 6257.0, 6257.342978987209, 5978.820635380924, 6111.786087486938, 6241.937580624204, 6273.3726627337355, 6279.37206279373, 6203.906941395882, 6247.375262473762, 6252.249730032402, 6255.499560035186, 6266.248050233979, 5964.19483369746, 6135.601185922946, 6156.7537298508005, 6160.4455598995855, 6161.260648722182, 5403.540699040586, 5422.322209723785, 5458.372287186945, 5483.643563168382, 5548.528375088082, 21954.670679939813, 26548.734512655014, 26921.730782692117, 27064.45871082587, 27257.0, 2165.361218440548, 2211.8672879627275, 2233.475133343663, 2241.674957131223, 2244.102359056374, 15919.0, 17253.13734313292, 17548.561285967826, 17653.470395888144, 17749.7337539937, 2056.085042156241, 2078.8752674839557, 2124.6706760452, 2127.0853532980814, 2129.0, 128604.498842541, 135518.0, 136151.0, 138738.0, 139829.0, 11465.254758440675, 11889.940550297366, 12234.265944043385, 12237.93881030607, 12289.93855030737, 15839.445619403266, 15945.282462289224, 16009.59976000586, 16073.839261607469, 16081.0, 8791.912080879238, 8931.955340223258, 8932.508712020901, 9043.547822608974, 9055.864162037575, 7553.773386798405, 8327.66689332432, 8423.241908228289, 8698.695545655999, 8699.43453675509, 7462.368891286739, 7734.613269336511, 7739.7678069658, 7744.6902123914415, 7754.844903102019, 2162.362103179566, 2175.8803265820534, 2176.5211653436218, 2179.466030822459, 2205.2722601541454, 816.3673153306236, 966.2366730283138, 974.8342781727123, 1004.5730564510054, 1016.6441745389054, 19378.031098445023, 19664.70502942457, 20736.274230401865, 20802.895985520277, 20861.269855555285, 948.6110694615174, 984.8919965039331, 1003.4531180506641, 1043.801677681238, 1045.283980473363, 10060.446675432775, 10218.693439196835, 10756.408397538054, 10791.676249712522, 10814.729631759112, 656861.0, 670239.0, 684516.0, 709234.0, 712727.0, 136998.0, 139687.6031239695, 140593.0, 140770.0, 141541.0, 1168.9181757276908, 1220.7924652809045, 1269.9682507937375, 1271.377025257617, 1280.6990357266036, 18607.53481162982, 19059.42821715351, 20245.696314555, 21088.789112108683, 21325.36023919285, 61950.69024654848, 63777.681111594145, 64992.0, 65602.67198664167, 65733.34266657272, 88674.55662721646, 88944.66081061395, 89461.55269223612, 89739.55130224307, 90026.09973900177, 8462.323014158976, 8696.130386961253, 8719.433236839584, 8824.558772061242, 8861.91138088611, 13351.332433378098, 13562.593122205955, 13573.79639305431, 13680.863191368357, 14057.718845622825, 10667.093297069836, 11368.829467557827, 11760.647180584596, 11833.644990650295, 12084.516619335129, 9621.56702948383, 10274.486275686335, 10289.434081125311, 11084.279521831055, 11365.374904380338, 10593.89406105929, 12026.518939242333, 12108.455119519467, 12206.0, 12301.200421972542, 8651.437656552303, 8716.564171791511, 8751.474911505331, 8868.778280543038, 9069.818603628022, 11657.242279251821, 11780.58767943152, 11942.164048516868, 11979.34113623759, 12147.967422769163], "value": 10467.363723026558, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1399.6290982889534, 1448.6161167290677, 1459.3287087939548, 1464.1946929188944, 1498.8276348219954, 11051.17116216284, 11365.147613928968, 11376.943115284423, 11512.079033677317, 11744.941275293611, 11341.752407235197, 11392.54429822806, 11487.0, 11499.310041397523, 11544.422778861066, 2457.8648174350405, 2496.1762618335997, 2505.549001179787, 2506.962395564059, 2510.083819405922, 2414.33605758417, 2430.744771798962, 2458.4345600511892, 2483.3543278747566, 2488.029668429314, 2434.47658753368, 2449.1672831237374, 2468.02513007362, 2468.950620987579, 2471.468634243637, 2431.650434009124, 2435.476372579904, 2455.1529722245796, 2470.4564995701007, 2476.6532685424054, 2377.405648587853, 2444.64552639868, 2475.851448913053, 2477.938051548708, 2487.054919130737, 11364.0, 11419.31484110956, 11441.942790286079, 11511.539538418454, 11521.654350369503, 6406.4554512866, 6565.113709649232, 6660.766873159416, 6675.065490831287, 6678.465722742169, 3935.980320098381, 4169.60388763067, 4216.2621541230465, 4239.7668128252935, 4251.80866860992, 1201.7956947318976, 1216.8661447240797, 1262.9052821038458, 1273.178799674205, 1273.8789864918813, 14707.485238016725, 15091.698166036618, 15728.921355393264, 15832.287547060294, 15901.840981590152, 5317.813876514381, 5456.7544460498575, 5640.041192997201, 5653.575981801381, 5660.830175094754, 11776.470058847372, 11857.88142118585, 11858.584949526898, 11872.525098995944, 11901.107416943763, 6458.2895881453005, 6465.191851018622, 6468.320826313169, 6473.579217350857, 6489.772857949949, 5428.782848686087, 6117.715279791205, 6135.018397056449, 6166.075088736636, 6171.5988460750805, 4277.272863613131, 4354.586314300136, 4367.803448844746, 4380.277254253013, 4422.3366495026, 1093.3658478082693, 1282.9166104203198, 1295.9222446653232, 1297.6172029251393, 1314.0210543145417, 770.7572114783825, 782.5461232485145, 797.2784629909941, 798.4650284309411, 814.9511029338258, 5922.555808314393, 6002.939970600325, 6152.384761523812, 6186.81439556805, 6253.96873015632, 562.1707980728393, 564.3256308711051, 565.3639655387689, 581.1606266167532, 590.7223604905773, 3740.952533290683, 3992.3412636915164, 4011.197760447922, 4197.874063778091, 4300.333448315516, 14121.08212966154, 14851.0, 14876.214854217564, 14878.851211487747, 14891.47879824201, 14366.712665746836, 14417.42330306776, 14559.272036398137, 14603.269836508132, 14778.852211477963, 10619.82293629804, 10713.625023124154, 10778.514966826511, 10787.946060269805, 10813.243072984815, 5454.0, 5788.942110578841, 6874.209465911485, 6931.584104953719, 6950.131233595801, 12581.182223155465, 12652.114351995271, 12657.493700252255, 12660.240385576526, 12909.806352904527, 13224.471021159046, 13311.66720831987, 13394.93302533481, 13505.459781609019, 13705.862941370458, 7988.960055199686, 8160.918390816016, 8178.918210817816, 8179.0, 8188.959055204924, 10376.325538839952, 10388.532516036645, 10402.895971040496, 10464.267501274837, 10489.790204196026, 8759.60581773809, 8828.205461508494, 8949.955250223707, 9058.637654493747, 9072.546372681207, 8584.399092063495, 8641.265492433213, 8694.177614188306, 8759.430637008574, 8771.91228087711, 7876.4092693047105, 7960.681572737258, 8055.0, 8064.6370913307865, 8075.232852878967, 5287.391949925808, 5991.490723288569, 5992.550558708202, 6001.06983417571, 6116.418940200674, 5908.231929849092, 5947.881042379215, 6119.0, 6156.815295541141, 6257.655828929453], "value": 5238.181944490197, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [386.55353067207386, 402.59539163141056, 404.2198556785404, 405.1613160757234, 414.2440046914381, 4812.855614331567, 6235.844103897399, 6249.9062514062325, 6282.277538083122, 6401.847667419868, 5209.765560549775, 5901.704914754267, 6067.87864242716, 6090.421409966058, 6093.0, 4475.865724028285, 4491.438570178728, 4507.4140361752925, 4510.954890451103, 4606.585407313341, 4407.338899165119, 4421.712588681726, 4456.844010459635, 4483.0, 4492.550744925514, 4012.6557603202964, 4255.468066491689, 4424.77876106195, 4425.269830477967, 4436.66724995625, 3840.7119466040017, 4417.712848664826, 4423.447069116361, 4435.334699795024, 4451.332300154971, 3635.72732045095, 3699.2046709957217, 3735.14091758897, 3803.2773772983323, 3842.1163132479687, 4861.562459378673, 5904.7342869570975, 5916.875793599203, 5943.435373639497, 5971.611845230046, 4795.544423279783, 5308.787648494055, 5313.341291524553, 5323.494268044568, 5339.839804805862, 5633.6619802811965, 5765.818007308491, 5862.794802181903, 6387.776427825004, 6456.967715161394, 3978.189923584762, 4321.962728945062, 4323.156984388045, 4338.43600331958, 4349.108432771274, 6029.728662210211, 6577.901331480031, 6657.672512866676, 6743.639726095827, 6765.188177418692, 4405.713628614129, 4411.911761764747, 4426.004149066434, 4429.512753597101, 4442.600995462479, 4077.0, 4250.3199488081755, 4497.527759585262, 4500.8649740507835, 4513.887152821206, 4222.944263934017, 4670.696404733681, 5044.1929291313945, 5148.897022059537, 5276.604254680838, 5520.0, 5937.346891841894, 6145.508359331243, 6564.7374105036215, 6651.86696266072, 1301.7526669932683, 1520.6274462756696, 1530.0, 1538.5461288919796, 1539.61434708763, 870.4907629036958, 976.7460460280237, 1019.1133713669155, 1033.8294181459976, 1044.3159730376615, 6219.0, 6228.688565571703, 6246.250449946036, 6266.467350275278, 6270.968645156745, 6624.701888415033, 6832.59004459724, 6834.760783372558, 6844.110265665531, 6863.279355667583, 5057.620678449131, 6139.355367686418, 6226.595271307441, 6234.441389652587, 6243.406876346739, 3034.499307614263, 3140.0893740815363, 3442.6729460701195, 3585.7848529088337, 3600.0, 5563.443655634485, 6680.13158289429, 6814.931850681529, 6878.243393226741, 6964.512484126164, 6020.886136064842, 6653.538190869409, 6894.655267236618, 6958.190870373739, 7014.824629384204, 5778.884422311614, 6100.328963813977, 6377.8724425512155, 6938.01064387989, 7034.155901291775, 5443.809466668806, 5612.915806262828, 5931.228940237742, 6758.966205168943, 6818.897716534157, 5553.361363443256, 6313.779017734358, 6336.683165841781, 6439.903401449075, 6679.666016699242, 5677.914831277617, 6338.58799178052, 7003.964980175067, 7012.158540975116, 7022.683979221049, 3900.317444447154, 4442.844500442469, 4449.466064072332, 4462.286034234637, 4469.865904022885, 6204.720787564661, 6601.801945941629, 6788.287229840796, 6838.3161683833205, 6892.448604111759, 5457.426970168234, 6568.638724870178, 6609.239937407067, 6611.33886611335, 6617.636030018395, 6323.114763933145, 6502.544821862424, 6512.804615861532, 6559.245686746085, 6607.636579988147, 5836.678982655994, 5939.485431215013, 6282.680637066267, 6378.1070650108095, 6461.676916154268, 5196.662216955886, 6570.0, 6583.736650533925, 6601.24085730128, 6601.702923368364, 6315.55791094619, 6396.360363963752, 6424.38968298011, 6426.871462570816, 6517.837054073686], "value": 4700.337368854605, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [787.5707739282091, 812.0458461307963, 818.1246066708624, 853.3173461231014, 854.7947394174212, 12484.250944943298, 14357.425702971896, 16098.436554720573, 16501.257443415045, 16813.411530596433, 14912.701745965074, 15111.0, 15181.696366072672, 15304.311305991228, 15424.69150616987, 979.5445118020137, 993.8111758765848, 1020.1838529176666, 1028.2596530498029, 1028.3058935218698, 1009.7778488732467, 1022.8670272864517, 1025.9487025648725, 1027.917766578672, 1028.3778314119925, 1053.6996955867542, 1071.4803320389606, 1078.8273876179812, 1079.7678499122687, 1087.8041952448557, 1073.6295977887617, 1075.639660713662, 1076.2304951959347, 1079.0234837472021, 1080.8811030786646, 776.9611519424006, 791.3510921044746, 800.7917941335237, 801.3028665061375, 806.5805780993909, 16585.004899705935, 16973.15134243283, 17142.828571714246, 17160.742588861176, 17205.311787528484, 14785.260736963217, 14824.703505929821, 14866.479673211494, 14880.776788348183, 15573.0, 13013.609591712264, 13263.602091937257, 13447.529336473275, 13608.727825443535, 13653.795193072112, 3355.228297491566, 3375.5443015193127, 3422.6235114137426, 3424.332255210235, 3429.4684323929832, 115763.4211828944, 117353.0, 118037.81962180354, 118934.0, 120310.3984480072, 8121.8375632488205, 11033.117350611932, 11049.447527623586, 11154.386508741934, 11457.968782809587, 23858.52282954331, 24213.63679544808, 24391.512169756505, 24505.387365316008, 24650.87674561616, 7996.120426753053, 8095.433319667567, 8140.715074972348, 8151.470154439942, 8304.335653147733, 4141.834326626961, 4170.291050521419, 4176.791160442027, 4196.81114349855, 4202.0, 4059.8579049733116, 4154.4183814266025, 4166.854160104443, 4183.769892655872, 4196.853110141192, 864.2783275964514, 883.5052370672444, 891.5809569502323, 895.2793001633747, 898.5937008581549, 545.8035107361346, 608.1850320570456, 614.6158650843223, 622.8754249150188, 652.4030512081392, 8887.97788254346, 9641.951790241144, 9843.950780246196, 10175.510143183148, 10216.182705383551, 589.0281036290155, 599.7870755881686, 616.2974209401286, 635.4312889963456, 639.1084437210066, 4797.969705447441, 5103.106956282636, 5208.489238782257, 5212.8957420851375, 5403.216533602685, 100310.4984475073, 100353.49823250982, 102526.48736756269, 102840.97159028464, 103908.48045759722, 89880.55059724659, 89966.0, 90608.0, 90859.0, 92625.073749263, 77870.0, 78558.6072069636, 79371.0, 79679.60160199161, 80386.0, 9370.39092458988, 10391.220658450495, 10797.838032429678, 10852.565897364333, 10920.071793897609, 40277.597224028556, 40501.1899762009, 40600.28290797578, 41792.791036044626, 41932.37101443542, 53719.731401342746, 54198.10306639249, 54834.69401018934, 55151.62120946848, 55178.72410637921, 13277.859259481116, 13368.264745438706, 13465.0, 13674.247916364695, 13839.377228024965, 13388.330583470593, 13615.748717643826, 13694.863051369757, 13763.0, 13813.51652692151, 12405.161213539655, 12623.621291361274, 12679.93660031694, 12811.551595694105, 13167.802482962954, 11469.0, 13410.32948352559, 13462.86537134616, 13528.120672156278, 13543.661408464866, 13245.801312980506, 13364.599062028155, 13441.395137219044, 13513.189208647513, 13534.0, 9499.097585729345, 9641.505566637183, 9926.851097233692, 10052.095311422006, 10142.137918276734, 10907.127429805438, 10967.850797526628, 11127.937909115339, 11274.0, 11296.548138074713], "value": 7778.8714764577135, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1666.6999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 122.0, 122.0, 122.0, 134.0, 172.0, 125.0, 125.0, 121.0, 121.0], "value": 127.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 138.0, 157.0, 188.0, 110.0, 163.0, 155.0, 121.0, 180.0, 155.0], "value": 153.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [224.0, 227.0, 221.0, 227.0, 228.0, 307.0, 222.0, 261.0, 201.0, 276.0], "value": 239.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 60.0, 66.0, 91.0, 58.0, 71.0, 53.0, 50.0, 68.0, 54.0], "value": 64.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 103.0, 124.0, 108.0, 109.0, 101.0, 85.0, 72.0, 72.0, 89.0], "value": 97.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 93.0, 92.0, 92.0, 91.0, 136.0, 92.0, 87.0, 93.0, 91.0], "value": 95.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 128.0, 113.0, 108.0, 100.0, 100.0, 101.0, 113.0, 163.0, 101.0], "value": 112.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 104.0, 104.0, 104.0, 102.0, 103.0, 102.0, 133.0, 107.0, 109.0], "value": 107.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 50.0, 51.0, 52.0, 53.0, 53.0, 51.0, 52.0, 52.0, 52.0], "value": 51.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 60.0, 65.0, 69.0, 68.0, 65.0, 60.0, 61.0, 60.0, 77.0], "value": 64.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 88.0, 94.0, 83.0, 118.0, 110.0, 109.0, 88.0, 88.0, 90.0], "value": 95.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 171.0, 159.0, 169.0, 179.0, 183.0, 171.0, 160.0, 246.0, 142.0], "value": 174.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [202.0, 199.0, 198.0, 198.0, 240.0, 201.0, 199.0, 204.0, 249.0, 194.0], "value": 208.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 72.0, 83.0, 85.0, 70.0, 73.0, 73.0, 73.0, 71.0, 73.0], "value": 74.6, "unit": "score"}]}]} 10:41:51 INFO - Return code: 0 10:41:51 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:41:51 ERROR - # TBPL SUCCESS # 10:41:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:41:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:41:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:41:51 INFO - 2017-04-07 10:41:51.454 screenresolution[1659:10858] starting screenresolution argv=screenresolution get 10:41:51 INFO - 2017-04-07 10:41:51.457 screenresolution[1659:10858] Display 0: 1600x1200x32@60 10:41:51 INFO - 2017-04-07 10:41:51.463 screenresolution[1660:10861] starting screenresolution argv=screenresolution list 10:41:51 INFO - Available Modes on Display 0 10:41:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:41:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:41:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:41:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:41:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:41:51 INFO - Intel Iris: 10:41:51 INFO - Chipset Model: Intel Iris 10:41:51 INFO - Type: GPU 10:41:51 INFO - Bus: Built-In 10:41:51 INFO - VRAM (Dynamic, Max): 1536 MB 10:41:51 INFO - Vendor: Intel (0x8086) 10:41:51 INFO - Device ID: 0x0a2e 10:41:51 INFO - Revision ID: 0x0009 10:41:51 INFO - Displays: 10:41:51 INFO - Display: 10:41:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:41:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:41:51 INFO - Display Serial Number: 1600x1200 60 10:41:51 INFO - Main Display: Yes 10:41:51 INFO - Mirror: Off 10:41:51 INFO - Online: Yes 10:41:51 INFO - Rotation: Supported 10:41:51 INFO - Return code: 0 10:41:51 INFO - Running post-action listener: _resource_record_post_action 10:41:51 INFO - [mozharness: 2017-04-07 17:41:51.700504Z] Finished run-tests step (success) 10:41:51 INFO - Running post-run listener: _resource_record_post_run 10:41:51 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 40286720; Write bytes: 521532928; Read time: 396; Write time: 1651 10:41:51 INFO - TinderboxPrint: CPU usage
54.5% 10:41:51 INFO - TinderboxPrint: I/O read bytes / time
40,286,720 / 396 10:41:51 INFO - TinderboxPrint: I/O write bytes / time
521,532,928 / 1,651 10:41:51 INFO - TinderboxPrint: CPU idle
2,188.5 (45.5%) 10:41:51 INFO - TinderboxPrint: CPU system
972.3 (20.2%) 10:41:51 INFO - TinderboxPrint: CPU user
1,648.9 (34.3%) 10:41:51 INFO - TinderboxPrint: Swap in / out
247,857,152 / 0 10:41:51 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 274432; Write bytes: 209286656; Read time: 7; Write time: 414 10:41:51 INFO - run-tests - Wall time: 1183s; CPU: 55.0%; Read bytes: 35441152; Write bytes: 310906880; Read time: 356; Write time: 1230 10:41:52 INFO - Running post-run listener: _upload_blobber_files 10:41:52 INFO - Blob upload gear active. 10:41:52 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:41:52 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 10:41:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:41:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:41:52 INFO - (blobuploader) - INFO - Open directory for files ... 10:41:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 10:41:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:41:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:41:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 10:41:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:41:53 INFO - (blobuploader) - INFO - Done attempting. 10:41:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 10:41:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:41:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:41:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 10:41:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:41:54 INFO - (blobuploader) - INFO - Done attempting. 10:41:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 10:41:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:41:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:41:55 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 10:41:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:41:55 INFO - (blobuploader) - INFO - Done attempting. 10:41:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:41:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:41:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:41:57 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:41:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:41:57 INFO - (blobuploader) - INFO - Done attempting. 10:41:57 INFO - (blobuploader) - INFO - Iteration through files over. 10:41:58 INFO - Return code: 0 10:41:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:41:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:41:58 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/401283daf9875dec50cfbd6d1e4015bac803a980426f6463b731c3d578f46ba1d82bbb2de8c1ce02985b51fe686989bd6d51a482642c9cb2d9f56bb53ab96074", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bdaeb13f9b440111be7ffcaf5548e99fbb48f21fbb2aaa3cf60a936b95078ddf83e0efeb3593e5aec532fee16db2f139955412d7b9cbdea492b30e852c22394c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1b6fe80f13bd2a1697f916ab8de5f50ea55975449d6a660d468905956bdfc91412923bf84b52067564da2f49d3cfeaf4593724b756151bc03a94458a9b23230d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/795343e611e7b3dde93c34acb3b87ac90046c615e283d8909159dc99d3ed050bfc8775ba5328c85ee4351d5aac6d1e5052ae5f54c37d7b25af76ed2df928bd57"} 10:41:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:41:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:41:58 INFO - Contents: 10:41:58 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/401283daf9875dec50cfbd6d1e4015bac803a980426f6463b731c3d578f46ba1d82bbb2de8c1ce02985b51fe686989bd6d51a482642c9cb2d9f56bb53ab96074", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bdaeb13f9b440111be7ffcaf5548e99fbb48f21fbb2aaa3cf60a936b95078ddf83e0efeb3593e5aec532fee16db2f139955412d7b9cbdea492b30e852c22394c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1b6fe80f13bd2a1697f916ab8de5f50ea55975449d6a660d468905956bdfc91412923bf84b52067564da2f49d3cfeaf4593724b756151bc03a94458a9b23230d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/795343e611e7b3dde93c34acb3b87ac90046c615e283d8909159dc99d3ed050bfc8775ba5328c85ee4351d5aac6d1e5052ae5f54c37d7b25af76ed2df928bd57"} 10:41:58 INFO - Running post-run listener: copy_logs_to_upload_dir 10:41:58 INFO - Copying logs to upload dir... 10:41:58 INFO - mkdir: /builds/slave/test/build/upload/logs 10:41:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1286.463267 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2017-04-07 10:41:58.056521) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-07 10:41:58.059523) ========= 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.7sjNOCmQIs/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.cWAPegPazC/Listeners TMPDIR=/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/401283daf9875dec50cfbd6d1e4015bac803a980426f6463b731c3d578f46ba1d82bbb2de8c1ce02985b51fe686989bd6d51a482642c9cb2d9f56bb53ab96074", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bdaeb13f9b440111be7ffcaf5548e99fbb48f21fbb2aaa3cf60a936b95078ddf83e0efeb3593e5aec532fee16db2f139955412d7b9cbdea492b30e852c22394c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1b6fe80f13bd2a1697f916ab8de5f50ea55975449d6a660d468905956bdfc91412923bf84b52067564da2f49d3cfeaf4593724b756151bc03a94458a9b23230d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/795343e611e7b3dde93c34acb3b87ac90046c615e283d8909159dc99d3ed050bfc8775ba5328c85ee4351d5aac6d1e5052ae5f54c37d7b25af76ed2df928bd57"} build_url:https://queue.taskcluster.net/v1/task/Yb79z2tSRUCUTb6pnWKZzw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011046 build_url: 'https://queue.taskcluster.net/v1/task/Yb79z2tSRUCUTb6pnWKZzw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/401283daf9875dec50cfbd6d1e4015bac803a980426f6463b731c3d578f46ba1d82bbb2de8c1ce02985b51fe686989bd6d51a482642c9cb2d9f56bb53ab96074", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bdaeb13f9b440111be7ffcaf5548e99fbb48f21fbb2aaa3cf60a936b95078ddf83e0efeb3593e5aec532fee16db2f139955412d7b9cbdea492b30e852c22394c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1b6fe80f13bd2a1697f916ab8de5f50ea55975449d6a660d468905956bdfc91412923bf84b52067564da2f49d3cfeaf4593724b756151bc03a94458a9b23230d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/795343e611e7b3dde93c34acb3b87ac90046c615e283d8909159dc99d3ed050bfc8775ba5328c85ee4351d5aac6d1e5052ae5f54c37d7b25af76ed2df928bd57"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-07 10:41:58.098116) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-07 10:41:58.098746) ========= 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.7sjNOCmQIs/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.cWAPegPazC/Listeners TMPDIR=/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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.004997 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-07 10:41:58.148943) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-07 10:41:58.149310) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-07 10:41:58.149622) ========= ========= Total master_lag: 0.18 =========