builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0131 starttime: 1490700565.49 results: success (0) buildid: 20170328030207 builduid: 1e06adb1e094476f9a1d7ea9ac029c42 revision: 5182b2c4b963ed87d038c7d9a4021463917076cd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-28 04:29:25.493790) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-28 04:29:25.494250) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 1 secs) (at 2017-03-28 04:29:25.494532) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 1 secs) (at 2017-03-28 04:29:26.494562) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 04:29:26.495208) ========= 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.Xf0iRV98Li/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.CqMLzsnXXf/Listeners TMPDIR=/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-03-28 04:29:26-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 153M=0s 2017-03-28 04:29:26 (153 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.135408 ========= master_lag: 0.25 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 04:29:26.876853) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 04:29:26.877700) ========= 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.Xf0iRV98Li/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.CqMLzsnXXf/Listeners TMPDIR=/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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.021635 ========= master_lag: 0.45 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 04:29:27.354032) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-28 04:29:27.354385) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 5182b2c4b963ed87d038c7d9a4021463917076cd --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 5182b2c4b963ed87d038c7d9a4021463917076cd --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Xf0iRV98Li/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.CqMLzsnXXf/Listeners TMPDIR=/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-03-28 04:29:27,540 truncating revision to first 12 chars 2017-03-28 04:29:27,540 Setting DEBUG logging. 2017-03-28 04:29:27,540 attempt 1/10 2017-03-28 04:29:27,540 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/5182b2c4b963?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-28 04:29:28,944 unpacking tar archive at: mozilla-central-5182b2c4b963/testing/mozharness/ program finished with exit code 0 elapsedTime=1.665561 ========= master_lag: 0.23 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-28 04:29:29.251849) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-28 04:29:29.252183) ========= script_repo_revision: 5182b2c4b963ed87d038c7d9a4021463917076cd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-28 04:29:29.252573) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-28 04:29:29.252837) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-28 04:29:30.109999) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 59 secs) (at 2017-03-28 04:29:30.110445) ========= /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.Xf0iRV98Li/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.CqMLzsnXXf/Listeners TMPDIR=/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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 04:29:30 INFO - MultiFileLogger online at 20170328 04:29:30 in /builds/slave/test 04:29:30 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 04:29:30 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:29:30 INFO - {'append_to_log': False, 04:29:30 INFO - 'base_work_dir': '/builds/slave/test', 04:29:30 INFO - 'blob_upload_branch': 'Firefox', 04:29:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:29:30 INFO - 'branch': 'Firefox', 04:29:30 INFO - 'buildbot_json_path': 'buildprops.json', 04:29:30 INFO - 'config_files': ('talos/mac_config.py',), 04:29:30 INFO - 'default_actions': ('clobber', 04:29:30 INFO - 'read-buildbot-config', 04:29:30 INFO - 'download-and-extract', 04:29:30 INFO - 'populate-webroot', 04:29:30 INFO - 'create-virtualenv', 04:29:30 INFO - 'install', 04:29:30 INFO - 'run-tests'), 04:29:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:29:30 INFO - 'download_minidump_stackwalk': True, 04:29:30 INFO - 'download_symbols': 'ondemand', 04:29:30 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:29:30 INFO - 'tooltool.py': '/tools/tooltool.py', 04:29:30 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:29:30 INFO - '/tools/misc-python/virtualenv.py')}, 04:29:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:29:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:29:30 INFO - 'gecko_profile': False, 04:29:30 INFO - 'gecko_profile_interval': 0, 04:29:30 INFO - 'installer_path': 'installer.exe', 04:29:30 INFO - 'log_level': 'info', 04:29:30 INFO - 'log_name': 'talos', 04:29:30 INFO - 'log_to_console': True, 04:29:30 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:29:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:29:30 INFO - 'opt_config_files': (), 04:29:30 INFO - 'pip_index': False, 04:29:30 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:29:30 INFO - 'cmd': ('bash', 04:29:30 INFO - '-c', 04:29:30 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:29:30 INFO - 'enabled': True, 04:29:30 INFO - 'halt_on_failure': False, 04:29:30 INFO - 'name': 'check_screen_resolution'},), 04:29:30 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:29:30 INFO - 'cmd': ('bash', 04:29:30 INFO - '-c', 04:29:30 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:29:30 INFO - 'enabled': True, 04:29:30 INFO - 'halt_on_failure': False, 04:29:30 INFO - 'name': 'check_screen_resolution'},), 04:29:30 INFO - 'run_cmd_checks_enabled': True, 04:29:30 INFO - 'suite': 'dromaeojs-e10s', 04:29:30 INFO - 'system_bits': '32', 04:29:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:29:30 INFO - 'title': 't-yosemite-r7-0131', 04:29:30 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:29:30 INFO - 'use_talos_json': True, 04:29:30 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:29:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:29:30 INFO - 'work_dir': 'build'} 04:29:30 INFO - [mozharness: 2017-03-28 11:29:30.432961Z] Running clobber step. 04:29:30 INFO - Running pre-action listener: _resource_record_pre_action 04:29:30 INFO - Running main action method: clobber 04:29:30 INFO - rmtree: /builds/slave/test/build 04:29:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:29:31 INFO - Running post-action listener: _resource_record_post_action 04:29:31 INFO - [mozharness: 2017-03-28 11:29:31.382684Z] Finished clobber step (success) 04:29:31 INFO - [mozharness: 2017-03-28 11:29:31.382960Z] Running read-buildbot-config step. 04:29:31 INFO - Running pre-action listener: _resource_record_pre_action 04:29:31 INFO - Running main action method: read_buildbot_config 04:29:31 INFO - Using buildbot properties: 04:29:31 INFO - { 04:29:31 INFO - "project": "", 04:29:31 INFO - "product": "firefox", 04:29:31 INFO - "script_repo_revision": "production", 04:29:31 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 04:29:31 INFO - "repository": "", 04:29:31 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 04:29:31 INFO - "buildid": "20170328030207", 04:29:31 INFO - "stage_platform": "macosx64", 04:29:31 INFO - "basedir": "/builds/slave/test", 04:29:31 INFO - "nightly_build": "True", 04:29:31 INFO - "buildnumber": 29, 04:29:31 INFO - "slavename": "t-yosemite-r7-0131", 04:29:31 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 04:29:31 INFO - "platform": "yosemite_r7", 04:29:31 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 04:29:31 INFO - "branch": "mozilla-central", 04:29:31 INFO - "repo_path": "mozilla-central", 04:29:31 INFO - "revision": "5182b2c4b963ed87d038c7d9a4021463917076cd", 04:29:31 INFO - "pgo_build": "False", 04:29:31 INFO - "builduid": "1e06adb1e094476f9a1d7ea9ac029c42", 04:29:31 INFO - "slavebuilddir": "test" 04:29:31 INFO - } 04:29:31 INFO - Found installer url https://queue.taskcluster.net/v1/task/andbvLExRdC4vM-RjPR8rw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 04:29:31 INFO - Running post-action listener: _resource_record_post_action 04:29:31 INFO - [mozharness: 2017-03-28 11:29:31.389086Z] Finished read-buildbot-config step (success) 04:29:31 INFO - [mozharness: 2017-03-28 11:29:31.389293Z] Running download-and-extract step. 04:29:31 INFO - Running pre-action listener: _resource_record_pre_action 04:29:31 INFO - Running main action method: download_and_extract 04:29:31 INFO - mkdir: /builds/slave/test/build/tests 04:29:31 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')]} 04:29:31 INFO - trying https://queue.taskcluster.net/v1/task/andbvLExRdC4vM-RjPR8rw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 04:29:31 INFO - Downloading https://queue.taskcluster.net/v1/task/andbvLExRdC4vM-RjPR8rw/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 04:29:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/andbvLExRdC4vM-RjPR8rw/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 04:29:33 INFO - Downloaded 1325 bytes. 04:29:33 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 04:29:33 INFO - Using the following test package requirements: 04:29:33 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:29:33 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 04:29:33 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 04:29:33 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:29:33 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 04:29:33 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:29:33 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 04:29:33 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:29:33 INFO - u'jsshell-mac.zip'], 04:29:33 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:29:33 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 04:29:33 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 04:29:33 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:29:33 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 04:29:33 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:29:33 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 04:29:33 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:29:33 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 04:29:33 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:29:33 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 04:29:33 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:29:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/andbvLExRdC4vM-RjPR8rw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 04:29:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/andbvLExRdC4vM-RjPR8rw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:29:33 INFO - Fetch https://queue.taskcluster.net/v1/task/andbvLExRdC4vM-RjPR8rw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 04:29:51 INFO - Expected file size: 52500895 04:29:51 INFO - Obtained file size: 52500895 04:30:07 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 04:30:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/andbvLExRdC4vM-RjPR8rw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 04:30:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/andbvLExRdC4vM-RjPR8rw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:30:07 INFO - Fetch https://queue.taskcluster.net/v1/task/andbvLExRdC4vM-RjPR8rw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 04:30:09 INFO - Expected file size: 52500895 04:30:09 INFO - Obtained file size: 52500895 04:30:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/andbvLExRdC4vM-RjPR8rw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 04:30:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/andbvLExRdC4vM-RjPR8rw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:30:25 INFO - Fetch https://queue.taskcluster.net/v1/task/andbvLExRdC4vM-RjPR8rw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 04:30:36 INFO - Expected file size: 14404171 04:30:36 INFO - Obtained file size: 14404171 04:30:36 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')]} 04:30:36 INFO - trying https://queue.taskcluster.net/v1/task/andbvLExRdC4vM-RjPR8rw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:30:36 INFO - Downloading https://queue.taskcluster.net/v1/task/andbvLExRdC4vM-RjPR8rw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 04:30:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/andbvLExRdC4vM-RjPR8rw/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 04:30:39 INFO - Downloaded 75643119 bytes. 04:30:39 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/andbvLExRdC4vM-RjPR8rw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:30:39 INFO - mkdir: /builds/slave/test/properties 04:30:39 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:30:39 INFO - Writing to file /builds/slave/test/properties/build_url 04:30:39 INFO - Contents: 04:30:39 INFO - build_url:https://queue.taskcluster.net/v1/task/andbvLExRdC4vM-RjPR8rw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:30:40 INFO - Running post-action listener: _resource_record_post_action 04:30:40 INFO - Running post-action listener: set_extra_try_arguments 04:30:40 INFO - [mozharness: 2017-03-28 11:30:40.795416Z] Finished download-and-extract step (success) 04:30:40 INFO - [mozharness: 2017-03-28 11:30:40.795549Z] Running populate-webroot step. 04:30:40 INFO - Running pre-action listener: _resource_record_pre_action 04:30:40 INFO - Running main action method: populate_webroot 04:30:40 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:30:40 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:30:40 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:30:40 INFO - '--tppagecycles', 04:30:40 INFO - '7'], 04:30:40 INFO - 'tests': ['tsvgm']}}, 04:30:40 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:30:40 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:30:40 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:30:40 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:30:40 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:30:40 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:30:40 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:30:40 INFO - 'talos_options': ['--disable-e10s'], 04:30:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:30:40 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:30:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:30:40 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:30:40 INFO - 'talos_options': ['--disable-e10s'], 04:30:40 INFO - 'tests': ['damp', 'tps']}, 04:30:40 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:30:40 INFO - 'tests': ['damp', 'tps']}, 04:30:40 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:30:40 INFO - 'tests': ['dromaeo_dom']}, 04:30:40 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:30:40 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:30:40 INFO - 'tests': ['basic_compositor_video']}, 04:30:40 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:30:40 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:30:40 INFO - 'tests': ['a11yr', 04:30:40 INFO - 'ts_paint', 04:30:40 INFO - 'tpaint', 04:30:40 INFO - 'sessionrestore', 04:30:40 INFO - 'sessionrestore_no_auto_restore', 04:30:40 INFO - 'tabpaint']}, 04:30:40 INFO - 'other-e10s': {'tests': ['a11yr', 04:30:40 INFO - 'ts_paint', 04:30:40 INFO - 'tpaint', 04:30:40 INFO - 'sessionrestore', 04:30:40 INFO - 'sessionrestore_no_auto_restore', 04:30:40 INFO - 'tabpaint']}, 04:30:40 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:30:40 INFO - 'ts_paint', 04:30:40 INFO - 'tpaint', 04:30:40 INFO - 'sessionrestore', 04:30:40 INFO - 'sessionrestore_no_auto_restore', 04:30:40 INFO - 'tabpaint']}, 04:30:40 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:30:40 INFO - 'ts_paint', 04:30:40 INFO - 'tpaint', 04:30:40 INFO - 'sessionrestore', 04:30:40 INFO - 'sessionrestore_no_auto_restore', 04:30:40 INFO - 'tabpaint']}, 04:30:40 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:30:40 INFO - 'tests': ['a11yr', 04:30:40 INFO - 'ts_paint', 04:30:40 INFO - 'tpaint', 04:30:40 INFO - 'sessionrestore', 04:30:40 INFO - 'sessionrestore_no_auto_restore', 04:30:40 INFO - 'tabpaint']}, 04:30:40 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:30:40 INFO - 'tests': ['a11yr', 04:30:40 INFO - 'ts_paint', 04:30:40 INFO - 'tpaint', 04:30:40 INFO - 'sessionrestore', 04:30:40 INFO - 'sessionrestore_no_auto_restore', 04:30:40 INFO - 'tabpaint']}, 04:30:40 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:30:40 INFO - 'tests': ['tsvgx', 04:30:40 INFO - 'tsvgr_opacity', 04:30:40 INFO - 'tart', 04:30:40 INFO - 'tscrollx', 04:30:40 INFO - 'cart', 04:30:40 INFO - 'tsvg_static']}, 04:30:40 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:30:40 INFO - 'tsvgr_opacity', 04:30:40 INFO - 'tart', 04:30:40 INFO - 'tscrollx', 04:30:40 INFO - 'cart', 04:30:40 INFO - 'tsvg_static']}, 04:30:40 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:30:40 INFO - 'talos_options': ['--disable-e10s'], 04:30:40 INFO - 'tests': ['tp5o']}, 04:30:40 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:30:40 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:30:40 INFO - 'talos_options': ['--disable-e10s', 04:30:40 INFO - '--xperf_path', 04:30:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:30:40 INFO - 'tests': ['tp5n']}, 04:30:40 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:30:40 INFO - 'talos_options': ['--xperf_path', 04:30:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:30:40 INFO - 'tests': ['tp5n']}}, 04:30:40 INFO - 'talos.zip': {'path': '', 04:30:40 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:30:40 INFO - Running post-action listener: _resource_record_post_action 04:30:40 INFO - [mozharness: 2017-03-28 11:30:40.813949Z] Finished populate-webroot step (success) 04:30:40 INFO - [mozharness: 2017-03-28 11:30:40.814080Z] Running create-virtualenv step. 04:30:40 INFO - Running pre-action listener: _resource_record_pre_action 04:30:40 INFO - Running main action method: create_virtualenv 04:30:40 INFO - Creating virtualenv /builds/slave/test/build/venv 04:30:40 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 04:30:40 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:30:40 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:30:40 INFO - Using real prefix '/tools/python27' 04:30:40 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:30:41 INFO - Installing distribute.............................................................................................................................................................................................done. 04:30:44 INFO - Installing pip.................done. 04:30:44 INFO - Return code: 0 04:30:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:30:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:30:44 INFO - Reading from file tmpfile_stdout 04:30:44 INFO - Output received: 04:30:44 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) 04:30:44 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:30:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:30:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:30:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:45 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')]} 04:30:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:30:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:45 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 0x106058b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10605b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc368d58a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e36d30>, '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 0x105d5ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105e2bbe8>, '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.Xf0iRV98Li/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.CqMLzsnXXf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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 04:30:45 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 04:30:45 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 04:30:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xf0iRV98Li/Render', 04:30:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:30:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:30:45 INFO - 'HOME': '/Users/cltbld', 04:30:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:30:45 INFO - 'LOGNAME': 'cltbld', 04:30:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:45 INFO - 'MOZ_NO_REMOTE': '1', 04:30:45 INFO - 'NO_EM_RESTART': '1', 04:30:45 INFO - 'PAGER': '/bin/cat', 04:30:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:30:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:45 INFO - 'PWD': '/builds/slave/test', 04:30:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:30:45 INFO - 'SHELL': '/bin/bash', 04:30:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CqMLzsnXXf/Listeners', 04:30:45 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 04:30:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:30:45 INFO - 'USER': 'cltbld', 04:30:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:30:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:30:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:45 INFO - 'XPC_FLAGS': '0x0', 04:30:45 INFO - 'XPC_SERVICE_NAME': '0', 04:30:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:30:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:30:45 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 04:30:45 INFO - Cleaning up... 04:30:45 INFO - Return code: 0 04:30:45 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:30:45 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')]} 04:30:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:30:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:45 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')]} 04:30:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:30:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:45 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 0x106058b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10605b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc368d58a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e36d30>, '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 0x105d5ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105e2bbe8>, '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.Xf0iRV98Li/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.CqMLzsnXXf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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 04:30:45 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 04:30:45 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 04:30:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xf0iRV98Li/Render', 04:30:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:30:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:30:45 INFO - 'HOME': '/Users/cltbld', 04:30:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:30:45 INFO - 'LOGNAME': 'cltbld', 04:30:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:45 INFO - 'MOZ_NO_REMOTE': '1', 04:30:45 INFO - 'NO_EM_RESTART': '1', 04:30:45 INFO - 'PAGER': '/bin/cat', 04:30:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:30:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:45 INFO - 'PWD': '/builds/slave/test', 04:30:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:30:45 INFO - 'SHELL': '/bin/bash', 04:30:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CqMLzsnXXf/Listeners', 04:30:45 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 04:30:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:30:45 INFO - 'USER': 'cltbld', 04:30:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:30:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:30:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:45 INFO - 'XPC_FLAGS': '0x0', 04:30:45 INFO - 'XPC_SERVICE_NAME': '0', 04:30:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:30:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:30:45 INFO - Downloading/unpacking psutil>=3.1.1 04:30:45 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 04:30:45 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 04:30:45 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 04:30:45 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 04:30:45 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 04:30:45 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 04:30:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:30:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:30:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:30:49 INFO - Installing collected packages: psutil 04:30:49 INFO - Running setup.py install for psutil 04:30:49 INFO - building 'psutil._psutil_osx' extension 04:30:49 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 04:30:50 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 04:30:50 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 04:30:50 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 04:30:50 INFO - building 'psutil._psutil_posix' extension 04:30:50 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 04:30:50 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:30:50 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:30:50 INFO - ^ 04:30:50 INFO - 1 warning generated. 04:30:50 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 04:30:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:30:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:30:50 INFO - Successfully installed psutil 04:30:50 INFO - Cleaning up... 04:30:50 INFO - Return code: 0 04:30:50 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:30:50 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')]} 04:30:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:30:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:50 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')]} 04:30:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:30:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:50 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 0x106058b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10605b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc368d58a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e36d30>, '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 0x105d5ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105e2bbe8>, '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.Xf0iRV98Li/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.CqMLzsnXXf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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 04:30:50 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 04:30:50 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 04:30:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xf0iRV98Li/Render', 04:30:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:30:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:30:50 INFO - 'HOME': '/Users/cltbld', 04:30:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:30:50 INFO - 'LOGNAME': 'cltbld', 04:30:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:50 INFO - 'MOZ_NO_REMOTE': '1', 04:30:50 INFO - 'NO_EM_RESTART': '1', 04:30:50 INFO - 'PAGER': '/bin/cat', 04:30:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:30:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:50 INFO - 'PWD': '/builds/slave/test', 04:30:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:30:50 INFO - 'SHELL': '/bin/bash', 04:30:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CqMLzsnXXf/Listeners', 04:30:50 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 04:30:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:30:50 INFO - 'USER': 'cltbld', 04:30:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:30:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:30:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:50 INFO - 'XPC_FLAGS': '0x0', 04:30:50 INFO - 'XPC_SERVICE_NAME': '0', 04:30:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:30:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:30:50 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:30:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:30:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:30:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:30:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:30:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:30:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:30:54 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:30:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:30:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:30:54 INFO - Installing collected packages: mozsystemmonitor 04:30:54 INFO - Running setup.py install for mozsystemmonitor 04:30:54 INFO - Successfully installed mozsystemmonitor 04:30:54 INFO - Cleaning up... 04:30:54 INFO - Return code: 0 04:30:54 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:30:54 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')]} 04:30:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:30:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:54 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')]} 04:30:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:30:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:54 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 0x106058b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10605b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc368d58a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e36d30>, '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 0x105d5ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105e2bbe8>, '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.Xf0iRV98Li/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.CqMLzsnXXf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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 04:30:54 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 04:30:54 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 04:30:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xf0iRV98Li/Render', 04:30:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:30:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:30:54 INFO - 'HOME': '/Users/cltbld', 04:30:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:30:54 INFO - 'LOGNAME': 'cltbld', 04:30:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:54 INFO - 'MOZ_NO_REMOTE': '1', 04:30:54 INFO - 'NO_EM_RESTART': '1', 04:30:54 INFO - 'PAGER': '/bin/cat', 04:30:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:30:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:54 INFO - 'PWD': '/builds/slave/test', 04:30:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:30:54 INFO - 'SHELL': '/bin/bash', 04:30:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CqMLzsnXXf/Listeners', 04:30:54 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 04:30:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:30:54 INFO - 'USER': 'cltbld', 04:30:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:30:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:30:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:54 INFO - 'XPC_FLAGS': '0x0', 04:30:54 INFO - 'XPC_SERVICE_NAME': '0', 04:30:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:30:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:30:55 INFO - Downloading/unpacking jsonschema==2.5.1 04:30:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:30:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:30:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:30:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:30:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:30:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:31:00 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:31:00 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:31:00 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 04:31:00 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 04:31:00 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 04:31:00 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 04:31:00 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 04:31:00 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 04:31:01 INFO - Downloading functools32-3.2.3-2.tar.gz 04:31:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:31:01 INFO - warning: no files found matching '*.txt' 04:31:01 INFO - no previously-included directories found matching 'build' 04:31:01 INFO - no previously-included directories found matching 'dist' 04:31:01 INFO - no previously-included directories found matching '.git*' 04:31:01 INFO - Installing collected packages: jsonschema, functools32 04:31:01 INFO - Running setup.py install for functools32 04:31:01 INFO - warning: no files found matching '*.txt' 04:31:01 INFO - no previously-included directories found matching 'build' 04:31:01 INFO - no previously-included directories found matching 'dist' 04:31:01 INFO - no previously-included directories found matching '.git*' 04:31:01 INFO - Successfully installed jsonschema functools32 04:31:01 INFO - Cleaning up... 04:31:01 INFO - Return code: 0 04:31:01 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:31:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:31:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:31:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:31:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:31:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x106058b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10605b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc368d58a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e36d30>, '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 0x105d5ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105e2bbe8>, '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.Xf0iRV98Li/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.CqMLzsnXXf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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 04:31:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 04:31:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 04:31:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xf0iRV98Li/Render', 04:31:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:31:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:31:01 INFO - 'HOME': '/Users/cltbld', 04:31:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:31:01 INFO - 'LOGNAME': 'cltbld', 04:31:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:31:01 INFO - 'MOZ_NO_REMOTE': '1', 04:31:01 INFO - 'NO_EM_RESTART': '1', 04:31:01 INFO - 'PAGER': '/bin/cat', 04:31:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:31:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:31:01 INFO - 'PWD': '/builds/slave/test', 04:31:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:31:01 INFO - 'SHELL': '/bin/bash', 04:31:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CqMLzsnXXf/Listeners', 04:31:01 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 04:31:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:31:01 INFO - 'USER': 'cltbld', 04:31:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:31:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:31:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:31:01 INFO - 'XPC_FLAGS': '0x0', 04:31:01 INFO - 'XPC_SERVICE_NAME': '0', 04:31:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:31:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:31:01 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:31:01 INFO - Cleaning up... 04:31:01 INFO - Return code: 0 04:31:01 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:31:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:31:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:31:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:31:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:31:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x106058b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10605b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc368d58a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e36d30>, '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 0x105d5ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105e2bbe8>, '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.Xf0iRV98Li/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.CqMLzsnXXf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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 04:31:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:31:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:31:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xf0iRV98Li/Render', 04:31:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:31:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:31:01 INFO - 'HOME': '/Users/cltbld', 04:31:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:31:01 INFO - 'LOGNAME': 'cltbld', 04:31:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:31:01 INFO - 'MOZ_NO_REMOTE': '1', 04:31:01 INFO - 'NO_EM_RESTART': '1', 04:31:01 INFO - 'PAGER': '/bin/cat', 04:31:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:31:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:31:01 INFO - 'PWD': '/builds/slave/test', 04:31:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:31:01 INFO - 'SHELL': '/bin/bash', 04:31:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CqMLzsnXXf/Listeners', 04:31:01 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 04:31:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:31:01 INFO - 'USER': 'cltbld', 04:31:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:31:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:31:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:31:01 INFO - 'XPC_FLAGS': '0x0', 04:31:01 INFO - 'XPC_SERVICE_NAME': '0', 04:31:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:31:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:31:02 INFO - Downloading/unpacking blobuploader==1.2.4 04:31:02 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 04:31:02 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 04:31:02 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 04:31:02 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 04:31:02 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 04:31:02 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 04:31:04 INFO - Downloading blobuploader-1.2.4.tar.gz 04:31:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:31:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:31:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:31:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:31:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:31:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:31:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:31:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:31:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:31:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:31:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:31:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:31:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:31:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:31:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:31:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:31:06 INFO - Downloading docopt-0.6.1.tar.gz 04:31:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:31:06 INFO - Installing collected packages: blobuploader, requests, docopt 04:31:06 INFO - Running setup.py install for blobuploader 04:31:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:31:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:31:06 INFO - Running setup.py install for requests 04:31:06 INFO - Running setup.py install for docopt 04:31:06 INFO - Successfully installed blobuploader requests docopt 04:31:06 INFO - Cleaning up... 04:31:07 INFO - Return code: 0 04:31:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:31:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:31:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:31:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:31:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:31:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31:07 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 0x106058b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10605b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc368d58a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e36d30>, '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 0x105d5ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105e2bbe8>, '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.Xf0iRV98Li/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.CqMLzsnXXf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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 04:31:07 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 04:31:07 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 04:31:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xf0iRV98Li/Render', 04:31:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:31:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:31:07 INFO - 'HOME': '/Users/cltbld', 04:31:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:31:07 INFO - 'LOGNAME': 'cltbld', 04:31:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:31:07 INFO - 'MOZ_NO_REMOTE': '1', 04:31:07 INFO - 'NO_EM_RESTART': '1', 04:31:07 INFO - 'PAGER': '/bin/cat', 04:31:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:31:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:31:07 INFO - 'PWD': '/builds/slave/test', 04:31:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:31:07 INFO - 'SHELL': '/bin/bash', 04:31:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CqMLzsnXXf/Listeners', 04:31:07 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 04:31:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:31:07 INFO - 'USER': 'cltbld', 04:31:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:31:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:31:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:31:07 INFO - 'XPC_FLAGS': '0x0', 04:31:07 INFO - 'XPC_SERVICE_NAME': '0', 04:31:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:31:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:31:07 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-UWCzMX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:31:07 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-VWtCTK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:31:07 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-6LCjat-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:31:07 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-qPCTvB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:31:07 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-RCX7Zc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:31:07 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-MkEDS2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:31:07 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-M7rTj8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:31:08 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-Ho8LZp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:31:08 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-c87XIk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:31:08 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-vRC9Wd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:31:08 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-Q15w46-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:31:08 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-CzZ6wW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:31:08 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-wMyVRe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:31:08 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-oHGiKQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:31:08 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-uUXZf3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:31:08 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-C3vYBD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:31:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:31:09 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-BmxwaO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:31:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:31:09 INFO - Running setup.py install for manifestparser 04:31:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:31:09 INFO - Running setup.py install for mozcrash 04:31:09 INFO - Running setup.py install for mozdebug 04:31:09 INFO - Running setup.py install for mozdevice 04:31:09 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:31:09 INFO - Running setup.py install for mozfile 04:31:09 INFO - Running setup.py install for mozhttpd 04:31:09 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:31:10 INFO - Running setup.py install for mozinfo 04:31:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:31:10 INFO - Running setup.py install for mozInstall 04:31:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:31:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:31:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:31:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:31:10 INFO - Running setup.py install for mozleak 04:31:10 INFO - Running setup.py install for mozlog 04:31:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:31:10 INFO - Running setup.py install for moznetwork 04:31:10 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:31:10 INFO - Running setup.py install for mozprocess 04:31:10 INFO - Running setup.py install for mozprofile 04:31:11 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:31:11 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:31:11 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:31:11 INFO - Running setup.py install for mozrunner 04:31:11 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:31:11 INFO - Running setup.py install for mozscreenshot 04:31:11 INFO - Running setup.py install for moztest 04:31:11 INFO - Running setup.py install for mozversion 04:31:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:31:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:31:11 INFO - Cleaning up... 04:31:11 INFO - Return code: 0 04:31:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:31:11 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')]} 04:31:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:31:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31:11 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')]} 04:31:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:31:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31:11 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 0x106058b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10605b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc368d58a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e36d30>, '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 0x105d5ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105e2bbe8>, '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.Xf0iRV98Li/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.CqMLzsnXXf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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 04:31:11 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 04:31:11 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 04:31:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xf0iRV98Li/Render', 04:31:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:31:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:31:11 INFO - 'HOME': '/Users/cltbld', 04:31:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:31:11 INFO - 'LOGNAME': 'cltbld', 04:31:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:31:11 INFO - 'MOZ_NO_REMOTE': '1', 04:31:11 INFO - 'NO_EM_RESTART': '1', 04:31:11 INFO - 'PAGER': '/bin/cat', 04:31:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:31:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:31:11 INFO - 'PWD': '/builds/slave/test', 04:31:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:31:11 INFO - 'SHELL': '/bin/bash', 04:31:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CqMLzsnXXf/Listeners', 04:31:11 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 04:31:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:31:11 INFO - 'USER': 'cltbld', 04:31:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:31:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:31:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:31:11 INFO - 'XPC_FLAGS': '0x0', 04:31:11 INFO - 'XPC_SERVICE_NAME': '0', 04:31:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:31:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:31:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:31:11 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-EuUyWL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:31:12 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)) 04:31:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:31:12 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-z8jRNn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:31:12 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)) 04:31:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:31:12 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-Xdac8W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:31:12 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)) 04:31:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:31:12 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-GObR1m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:31:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:31:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:31:12 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-0l5aOc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:31:12 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)) 04:31:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:31:12 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-ReBbWn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:31:12 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)) 04:31:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:31:12 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-atLv6o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:31:12 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)) 04:31:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:31:12 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-qvM3xb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:31:12 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)) 04:31:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:31:12 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-sDzN3q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:31:12 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)) 04:31:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:31:12 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-JM8vAt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:31:13 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)) 04:31:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:31:13 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-_X0g46-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:31:13 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)) 04:31:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:31:13 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-ysWGOz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:31:13 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)) 04:31:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:31:13 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-DXEpDL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:31:13 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)) 04:31:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:31:13 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-RLbnXU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:31:13 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)) 04:31:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:31:13 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-hSRLVk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:31:13 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)) 04:31:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:31:13 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-vG_NiC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:31:13 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)) 04:31:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:31:13 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-wPGaHP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:31:13 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)) 04:31:13 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)) 04:31:13 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)) 04:31:13 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)) 04:31:13 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:31:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:31:13 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:31:13 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 04:31:13 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 04:31:13 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 04:31:13 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 04:31:13 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 04:31:13 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 04:31:18 INFO - Downloading blessings-1.6.tar.gz 04:31:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:31:18 INFO - Installing collected packages: blessings 04:31:18 INFO - Running setup.py install for blessings 04:31:18 INFO - Successfully installed blessings 04:31:18 INFO - Cleaning up... 04:31:18 INFO - Return code: 0 04:31:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:31:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:31:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:31:19 INFO - Reading from file tmpfile_stdout 04:31:19 INFO - Current package versions: 04:31:19 INFO - blessings == 1.6 04:31:19 INFO - blobuploader == 1.2.4 04:31:19 INFO - docopt == 0.6.1 04:31:19 INFO - functools32 == 3.2.3-2 04:31:19 INFO - jsonschema == 2.5.1 04:31:19 INFO - manifestparser == 1.1 04:31:19 INFO - mozInstall == 1.12 04:31:19 INFO - mozcrash == 1.0 04:31:19 INFO - mozdebug == 0.1 04:31:19 INFO - mozdevice == 0.49 04:31:19 INFO - mozfile == 1.2 04:31:19 INFO - mozhttpd == 0.7 04:31:19 INFO - mozinfo == 0.9 04:31:19 INFO - mozleak == 0.1 04:31:19 INFO - mozlog == 3.4 04:31:19 INFO - moznetwork == 0.27 04:31:19 INFO - mozprocess == 0.25 04:31:19 INFO - mozprofile == 0.28 04:31:19 INFO - mozrunner == 6.13 04:31:19 INFO - mozscreenshot == 0.1 04:31:19 INFO - mozsystemmonitor == 0.3 04:31:19 INFO - moztest == 0.8 04:31:19 INFO - mozversion == 1.4 04:31:19 INFO - psutil == 3.1.1 04:31:19 INFO - requests == 1.2.3 04:31:19 INFO - wsgiref == 0.1.2 04:31:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:31: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')]} 04:31:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:31:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31: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')]} 04:31:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:31:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31:19 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 0x106058b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10605b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc368d58a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e36d30>, '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 0x105d5ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105e2bbe8>, '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.Xf0iRV98Li/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.CqMLzsnXXf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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 04:31:19 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 04:31:19 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 04:31:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xf0iRV98Li/Render', 04:31:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:31:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:31:19 INFO - 'HOME': '/Users/cltbld', 04:31:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:31:19 INFO - 'LOGNAME': 'cltbld', 04:31:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:31:19 INFO - 'MOZ_NO_REMOTE': '1', 04:31:19 INFO - 'NO_EM_RESTART': '1', 04:31:19 INFO - 'PAGER': '/bin/cat', 04:31:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:31:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:31:19 INFO - 'PWD': '/builds/slave/test', 04:31:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:31:19 INFO - 'SHELL': '/bin/bash', 04:31:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CqMLzsnXXf/Listeners', 04:31:19 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 04:31:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:31:19 INFO - 'USER': 'cltbld', 04:31:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:31:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:31:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:31:19 INFO - 'XPC_FLAGS': '0x0', 04:31:19 INFO - 'XPC_SERVICE_NAME': '0', 04:31:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:31:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:31:19 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)) 04:31:19 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)) 04:31:19 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)) 04:31:19 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)) 04:31:19 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)) 04:31:19 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)) 04:31:19 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)) 04:31:19 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)) 04:31:19 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)) 04:31:19 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)) 04:31:19 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)) 04:31:19 INFO - Cleaning up... 04:31:19 INFO - Return code: 0 04:31:19 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:31: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')]} 04:31:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:31:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31: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')]} 04:31:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:31:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106058b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10605b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc368d58a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e36d30>, '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 0x105d5ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105e2bbe8>, '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.Xf0iRV98Li/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.CqMLzsnXXf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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 04:31: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', 'jsonschema'] in /builds/slave/test/build 04:31: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 jsonschema 04:31:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xf0iRV98Li/Render', 04:31:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:31:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:31:19 INFO - 'HOME': '/Users/cltbld', 04:31:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:31:19 INFO - 'LOGNAME': 'cltbld', 04:31:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:31:19 INFO - 'MOZ_NO_REMOTE': '1', 04:31:19 INFO - 'NO_EM_RESTART': '1', 04:31:19 INFO - 'PAGER': '/bin/cat', 04:31:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:31:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:31:19 INFO - 'PWD': '/builds/slave/test', 04:31:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:31:19 INFO - 'SHELL': '/bin/bash', 04:31:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CqMLzsnXXf/Listeners', 04:31:19 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 04:31:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:31:19 INFO - 'USER': 'cltbld', 04:31:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:31:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:31:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:31:19 INFO - 'XPC_FLAGS': '0x0', 04:31:19 INFO - 'XPC_SERVICE_NAME': '0', 04:31:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:31:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:31:19 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:31:19 INFO - Cleaning up... 04:31:19 INFO - Return code: 0 04:31:19 INFO - Running post-action listener: _resource_record_post_action 04:31:19 INFO - Running post-action listener: _start_resource_monitoring 04:31:19 INFO - Starting resource monitoring. 04:31:19 INFO - [mozharness: 2017-03-28 11:31:19.655892Z] Finished create-virtualenv step (success) 04:31:19 INFO - [mozharness: 2017-03-28 11:31:19.656898Z] Running install step. 04:31:19 INFO - Running pre-action listener: _resource_record_pre_action 04:31:19 INFO - Running main action method: install 04:31:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:31:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:31:19 INFO - Reading from file tmpfile_stdout 04:31:19 INFO - Detecting whether we're running mozinstall >=1.0... 04:31:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:31:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:31:20 INFO - Reading from file tmpfile_stdout 04:31:20 INFO - Output received: 04:31:20 INFO - Usage: mozinstall [options] installer 04:31:20 INFO - Options: 04:31:20 INFO - -h, --help show this help message and exit 04:31:20 INFO - -d DEST, --destination=DEST 04:31:20 INFO - Directory to install application into. [default: 04:31:20 INFO - "/builds/slave/test"] 04:31:20 INFO - --app=APP Application being installed. [default: firefox] 04:31:20 INFO - mkdir: /builds/slave/test/build/application 04:31:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:31:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 04:31:40 INFO - Reading from file tmpfile_stdout 04:31:40 INFO - Output received: 04:31:40 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 04:31:40 INFO - Running post-action listener: _resource_record_post_action 04:31:40 INFO - [mozharness: 2017-03-28 11:31:40.102683Z] Finished install step (success) 04:31:40 INFO - [mozharness: 2017-03-28 11:31:40.102866Z] Running run-tests step. 04:31:40 INFO - Running pre-action listener: _resource_record_pre_action 04:31:40 INFO - Running main action method: run_tests 04:31:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:31:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:31:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:31:40 INFO - 2017-03-28 04:31:40.117 screenresolution[2249:24079] starting screenresolution argv=screenresolution get 04:31:40 INFO - 2017-03-28 04:31:40.120 screenresolution[2249:24079] Display 0: 1600x1200x32@60 04:31:40 INFO - 2017-03-28 04:31:40.126 screenresolution[2250:24082] starting screenresolution argv=screenresolution list 04:31:40 INFO - Available Modes on Display 0 04:31:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:31:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:31:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:31:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:31:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:31:40 INFO - Intel Iris: 04:31:40 INFO - Chipset Model: Intel Iris 04:31:40 INFO - Type: GPU 04:31:40 INFO - Bus: Built-In 04:31:40 INFO - VRAM (Dynamic, Max): 1536 MB 04:31:40 INFO - Vendor: Intel (0x8086) 04:31:40 INFO - Device ID: 0x0a2e 04:31:40 INFO - Revision ID: 0x0009 04:31:40 INFO - Displays: 04:31:40 INFO - Display: 04:31:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:31:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:31:40 INFO - Display Serial Number: 1600x1200 60 04:31:40 INFO - Main Display: Yes 04:31:40 INFO - Mirror: Off 04:31:40 INFO - Online: Yes 04:31:40 INFO - Rotation: Supported 04:31:40 INFO - Return code: 0 04:31:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:31:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:31:40 INFO - Python 2.7.3 04:31:40 INFO - Return code: 0 04:31:40 INFO - grabbing minidump binary from tooltool 04:31:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:31:40 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e36d30>, '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 0x105d5ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105e2bbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:31:40 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 04:31:40 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 04:31:40 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 04:31:40 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:31:40 INFO - Return code: 0 04:31:40 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:31:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:31:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:31:40 INFO - ENV: RUST_BACKTRACE is now 1 04:31:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:31:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:31:40 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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/andbvLExRdC4vM-RjPR8rw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0131', '--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 04:31:40 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/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/andbvLExRdC4vM-RjPR8rw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0131 --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 04:31:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xf0iRV98Li/Render', 04:31:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:31:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:31:40 INFO - 'HOME': '/Users/cltbld', 04:31:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:31:40 INFO - 'LOGNAME': 'cltbld', 04:31:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:31:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:31:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:31:40 INFO - 'MOZ_NO_REMOTE': '1', 04:31:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:31:40 INFO - 'NO_EM_RESTART': '1', 04:31:40 INFO - 'PAGER': '/bin/cat', 04:31:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:31:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:31:40 INFO - 'PWD': '/builds/slave/test', 04:31:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:31:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:31:40 INFO - 'RUST_BACKTRACE': '1', 04:31:40 INFO - 'SHELL': '/bin/bash', 04:31:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CqMLzsnXXf/Listeners', 04:31:40 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 04:31:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:31:40 INFO - 'USER': 'cltbld', 04:31:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:31:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:31:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:31:40 INFO - 'XPC_FLAGS': '0x0', 04:31:40 INFO - 'XPC_SERVICE_NAME': '0', 04:31:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:31:40 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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/andbvLExRdC4vM-RjPR8rw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0131', '--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 04:31:40 INFO - mozversion application_buildid: 20170328030207 04:31:40 INFO - mozversion application_changeset: 5182b2c4b963ed87d038c7d9a4021463917076cd 04:31:40 INFO - mozversion application_display_name: Nightly 04:31:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:31:40 INFO - mozversion application_name: Firefox 04:31:40 INFO - mozversion application_remotingname: firefox 04:31:40 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:31:40 INFO - mozversion application_vendor: Mozilla 04:31:40 INFO - mozversion application_version: 55.0a1 04:31:40 INFO - mozversion platform_buildid: 20170328030207 04:31:40 INFO - mozversion platform_changeset: 5182b2c4b963ed87d038c7d9a4021463917076cd 04:31:40 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:31:40 INFO - mozversion platform_version: 55.0a1 04:31:40 INFO - using testdate: 1490700700 04:31:40 INFO - actual date: 1490700700 04:31:40 INFO - starting webserver on 'localhost:56015' 04:31:40 INFO - SUITE-START | Running 2 tests 04:31:40 INFO - TEST-START | dromaeo_css 04:31:40 INFO - operating with platform_type : mac_ 04:31:40 INFO - Initialising browser for dromaeo_css test... 04:31:40 INFO - TEST-INFO | started process 2260 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/tmpZQ9hU3/profile http://localhost:56015/getInfo.html) 04:31:43 INFO - PID 2260 | 2017-03-28 04:31:43.851 plugin-container[2262:24256] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x913b, name = 'com.apple.tsm.portname' 04:31:43 INFO - PID 2260 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:31:43 INFO - PID 2260 | 2017-03-28 04:31:43.852 plugin-container[2262:24256] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4d17, name = 'com.apple.CFPasteboardClient' 04:31:43 INFO - PID 2260 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:31:43 INFO - PID 2260 | 2017-03-28 04:31:43.852 plugin-container[2262:24256] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:31:43 INFO - PID 2260 | __metrics Screen width/height:1600/1200 04:31:43 INFO - PID 2260 | colorDepth:24 04:31:43 INFO - PID 2260 | Browser inner width/height: 1024/689 04:31:43 INFO - PID 2260 | __metrics 04:31:44 ERROR - PID 2260 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3396: TypeError: this.tabs is undefined 04:31:44 ERROR - PID 2260 | 1490700704030 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxNightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxNightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924:15 < forceQuit@jar:file:///var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/tmpZQ9hU3/profile/extensions/talos-powers@TalosPowersService.js:237:7 < whenDone@jar:file:///var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/tmpZQ9hU3/profile/extensions/talos-powers@TalosPowersService.js:222:9 < promise callback*forceQuit@jar:file:///var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/tmpZQ9hU3/profile/extensions/talos-powers@TalosPowersService.js:224:7 < receiveMessage@jar:file:///var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/tmpZQ9hU3/profile/extensions/talos-powers@TalosPowersService.js:55:9 04:31:44 INFO - TEST-INFO | 2260: exit 0 04:31:44 INFO - Browser initialized. 04:31:44 INFO - Running cycle 1/1 for dromaeo_css test... 04:31:44 INFO - TEST-INFO | started process 2263 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/tmpZQ9hU3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:31:47 INFO - PID 2263 | 2017-03-28 04:31:47.374 plugin-container[2264:24382] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a4b, name = 'com.apple.tsm.portname' 04:31:47 INFO - PID 2263 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:31:47 INFO - PID 2263 | 2017-03-28 04:31:47.375 plugin-container[2264:24382] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9017, name = 'com.apple.CFPasteboardClient' 04:31:47 INFO - PID 2263 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:31:47 INFO - PID 2263 | 2017-03-28 04:31:47.375 plugin-container[2264:24382] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:31:57 INFO - PID 2263 | RSS: Main: 227893248 04:31:57 INFO - PID 2263 | 04:35:00 INFO - PID 2263 | Cycle 1(1): loaded http://localhost:56015/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56015/tests/dromaeo/cssquery-ext.html) 04:35:02 INFO - PID 2263 | RSS: Main: 251105280 04:35:02 INFO - PID 2263 | 04:36:45 INFO - PID 2263 | ************************* 04:36:45 INFO - PID 2263 | A coding exception was thrown and uncaught in a Task. 04:36:45 INFO - PID 2263 | 04:36:45 ERROR - PID 2263 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:36:45 INFO - PID 2263 | Full stack: 04:36:45 INFO - PID 2263 | ************************* 04:36:45 INFO - PID 2263 | ************************* 04:36:45 INFO - PID 2263 | A coding exception was thrown and uncaught in a Task. 04:36:45 INFO - PID 2263 | 04:36:45 ERROR - PID 2263 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:36:45 INFO - PID 2263 | Full stack: 04:36:45 INFO - PID 2263 | ************************* 04:36:45 INFO - PID 2263 | ************************* 04:36:45 INFO - PID 2263 | A coding exception was thrown and uncaught in a Task. 04:36:45 INFO - PID 2263 | 04:36:45 ERROR - PID 2263 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:36:45 INFO - PID 2263 | Full stack: 04:36:45 INFO - PID 2263 | ************************* 04:36:45 INFO - PID 2263 | ************************* 04:36:45 INFO - PID 2263 | A coding exception was thrown and uncaught in a Task. 04:36:45 INFO - PID 2263 | 04:36:45 ERROR - PID 2263 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:36:45 INFO - PID 2263 | Full stack: 04:36:45 INFO - PID 2263 | ************************* 04:38:04 INFO - PID 2263 | Cycle 1(1): loaded http://localhost:56015/tests/dromaeo/cssquery-ext.html (next: http://localhost:56015/tests/dromaeo/cssquery-jquery.html) 04:38:05 INFO - PID 2263 | RSS: Main: 258170880 04:38:05 INFO - PID 2263 | 04:41:07 INFO - PID 2263 | Cycle 1(1): loaded http://localhost:56015/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56015/tests/dromaeo/cssquery-mootools.html) 04:41:08 INFO - PID 2263 | RSS: Main: 271601664 04:41:08 INFO - PID 2263 | 04:44:10 INFO - PID 2263 | Cycle 1(1): loaded http://localhost:56015/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56015/tests/dromaeo/cssquery-prototype.html) 04:44:10 INFO - PID 2263 | RSS: Main: 264024064 04:44:10 INFO - PID 2263 | 04:47:13 INFO - PID 2263 | Cycle 1(1): loaded http://localhost:56015/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56015/tests/dromaeo/cssquery-yui.html) 04:47:14 INFO - PID 2263 | RSS: Main: 274268160 04:47:14 INFO - PID 2263 | 04:50:17 INFO - PID 2263 | Cycle 1(1): loaded http://localhost:56015/tests/dromaeo/cssquery-yui.html (next: http://localhost:56015/tests/dromaeo/cssquery-dojo.html) 04:50:17 INFO - PID 2263 | RSS: Main: 267976704 04:50:17 INFO - PID 2263 | 04:50:17 INFO - PID 2263 | __start_tp_report 04:50:17 INFO - PID 2263 | _x_x_mozilla_page_load 04:50:17 INFO - PID 2263 | _x_x_mozilla_page_load_details 04:50:17 INFO - PID 2263 | |i|pagename|runs| 04:50:17 INFO - PID 2263 | |0;dojo.html;3009.413164432935;3523.260115375769;3533.73496987726;3564.3584154852133;3580.445031020192;6089.5432842536875;6138.294096178935;6177.320494745574;6188.907166392496;6209.2859321178075;5094.6433749637445;5534.8930213957265;5993.550483713728;6102.580142287625;6138.112525404856;5168.53483186514;5556.305461817273;5647.7458514366745;5726.112452569859;5775.509081728037;5493.752781124839;5611.663300202001;5725.770969161249;5729.971350143243;5730.914036289459;5590.804321848756;5628.0713682242385;5681.204631351614;5705.229793977802;5723;4894.657373983787;5433.402325744165;5554.666719996813;5560.471755183251;5604.691741954191;5551.472610102034;5563.554915606742;5563.610547261694;5567.47109024642;5597.804076857331;5910.379410161957;6065.575409721321;6153.953827849276;6176.042713379434;6187.907181392283;5933.169356290123;6289.559730818844;6520.021996700485;6546.541742078057;6658.766943156967;6751.459883209332;6779.2881747416795;6781.593104413751;6785.796426107225;6804;6225;6245.250569931592;6249.937500625027;6253.593516421418;6257.061440783918;6600.933990660128;6664.933350666432;6811.046453496514;6865.176178858569;6876.896846547305;6308.432241098323;6532.477401807844;6534.509911756637;6592.538522303488;6604.900926486107;6575.408213260829;6580.144581204413;6640.136782218281;6660.600363978177;6724.529282950244;6673.065770792093;6683.766068187688;6687.331266873273;6698.83252918681;6700.229473610597;6352.205974253218;6458.773942912069;6467.870642587122;6590.406863382319;6695.799126026227;5343.225232341367;5373.785048598091;5383.78464861409;5391.083515802323;5409.78360865561;4731.952680473151;4737.976310118427;4749.453812811502;4753.619710423158;4801.1117943180625;6124.816255512342;6237.96881015592;6245.875082498324;6254.906176407358;6261.342559031327;6090.512758979271;6249.375062493714;6503.544751867418;6521.902171467432;6653.9667301663185;6215.81352559424;6218.347073557302;6220.595661282002;6240.719167637377;6262.373762623702;5985.6408615483215;5992.67040312787;6002.369751176151;6034.064719968412;6079.604825686405;6622.403983641495;6694.83262918431;6830.351116643911;6851.212110607344;6921.342472465108;6661.134052573135;6729.596224226562;6733.225679046972;6734;6764.695588698422;5726.79956201531;6488.772892948724;6841.110655614738;6867.656617169221;6883.518153729191;5533.363663178786;6383.904241436289;6475.935240647722;6645.833854153685;6647.9002814958785;5512.448755124456;6617.834554135992;6741.393274605309;6743.055972163803;6758.7296508139125;6239.532035097295;6345.0482427636225;6520.250171230369;6532.8366790830605;6535.705893234721;6311.179546658905;6364.586301890362;6379.266384365857;6510.707018184288;6520.054592084216;6276.089966954857;6428.357164283721;6435.517336199898;6447.258565265054;6448.838779030561;6280.309165991646;6326.240851097714;6384.361563843579;6439.774607888701;6474.158359413246;6344.397282258178;6393.776217832354;6399.328070552525;6417.101605775289;6419.390157934989 04:50:17 INFO - PID 2263 | |1;ext.html;689.3313485918659;728.8979542864;827.1273806134529;1261.2180448122165;1263.1095077970033;37036;38152;38196.42705359422;38278.234435311315;38299.99999999996;39291;39798.801005995;39858.8007059965;39868.401973970416;39880.8035758927;7965.800854978628;8122.9187708123045;8480.957595212047;8719.128087191262;8758.693445729368;8606.827863442728;8650.956745216265;8728.563571821414;8731.34514911381;8743.650253989832;8661.696840610548;8684.435511691752;8733.694320698776;8740.912590874073;8758.430702004382;8666.653333866638;8681.21869031787;8712.912870871305;8726.69456569017;8738.519381434018;8517.105703901125;8549.273311768507;8572.442791218551;8595.097514760922;8628.266597339234;38734.418983715266;38987.830365089096;39048;39107.8044609778;39209;3066.186087842089;3070.6775788542104;3083.182956516525;3085.8765649374;3108.751299896003;28098.43803123926;28117.01590444345;28233.717662823314;28533.57199642007;29135.125946221648;2797.608334833125;2807.1859160843333;2837.5034368985343;2840.6307180066665;2910.272431892027;240553;243512;264366.6781666079;322510;327013;47312;47369.76315118402;47680.52319476761;47690.523094769305;47755;51208.74395627998;51759.70600734936;52003.47996519986;52106;52176;14655.413783448543;14692.926535367254;14734;14737.705245895022;14798.556043318718;10853.728656783644;11063.889361106447;11937.761244775229;11970.341631210367;12072.275663460221;11732.941335293439;13332.73334533304;13946.651333716536;13963.650908727363;14003.71992560143;5033.823816166417;5147.408048074444;5949.940500594938;6005.129256257906;6029.547783916224;798.7958450693735;930.5207817973749;974.9268804839666;1004.3271008424373;1038.3354653022072;17821.643567128845;24212.273631790722;24380.75619243785;24513.63229551558;24532.632010519857;1066.1311031509294;1075.5805235957982;1116.4529380603446;1118.2898859224451;1127.301073334538;11710.238834475902;12348.506059757508;12373.505059797688;12427.763002659609;12492.81260781089;374879;443980;444461;444895;445665;56813.147802782994;56873.715631421575;56949;57045.14432283436;57135.42864571301;1450.2893582144673;1467.4717101843328;1507.66077632534;1512.5479539641847;1518.6810769738258;23862.761372386747;24094;27714.861425692743;28715;29699.85150074236;52630.47369526256;53021.204681930576;53116.4688353127;53294.20058699045;53325.200121998976;387255;389400.0529997332;389510.05244973593;389664;390067;26305.210843674722;26414.603780942918;31897;32689.673103268662;33874.66125338715;40760.59239407686;41718.58281417147;41991;42023.57976420197;42097.36853947255;25045.24864254075;27478;28842;30238;30258.697413025588;29460.11619651414;29655.406891862473;30157.547636785905;30261;30607.846960765055;29415.85292073526;35540.289194216486;36251.27497449984;36269.91190264191;36321.45517817182;28645.710943007198;30172.698273016988;30174.49156424922;30898.69101308958;30929.381412371178;17999.01005444713;24025;24028.87985560061;24158.75841241565;24565.017399304543 04:50:17 INFO - PID 2263 | |2;jquery.html;368.3700871509718;424.1284161049043;430.7846076961519;435.26005790156734;447.21067316186935;19340.70988935167;20560.280390186326;21208.575828483423;21279.468013299676;21420.357389278328;21668.566628667457;22538.43653908649;22751.772482275173;23435.41411464714;23634.936427860746;5914.438128377818;6197.566170368076;6203.658798766066;6217.751289948396;6233.812985610439;5931.88136237275;6046.425589568984;6168.814935551941;6215.409536094064;6234.906476402835;6054.909176362358;6129.41770531801;6172.783952561661;6187.752489900398;6249.093881387196;5381.408045115034;6158.9692051539905;6318.873622527523;6337.239531256256;6360;5273.551748101378;5384.946150538483;5481.972590137064;5615.550755939515;5621.606487545873;27893.442131157466;28403.147905562866;28497.01041854142;28746.281342966595;28993;2022.7269318641943;2034.7151398804178;2041.1631231195138;2064.4013236161495;2101.5901899129676;15879.444219452263;16074.678506429922;16994.49016529506;17047.659046819117;17146.828531714647;1669.2071266148603;1669.5659128626523;1685.2584862660512;1691.8054423741305;1702.4637239269591;162457.18771406185;164298.9999999988;165161.88188627223;165257;166432;12183.147179697336;12327;12446.564370246997;12637.747245055047;12657.746845063048;13825.930870345584;16283;16320.428784992468;16323;16324.673506529803;7425.8143546411775;8998.910010899937;9128.361014728906;9145.085491450802;9203.723888283363;8782.121787821166;8800.207981281716;8891.110888911056;9034.36759426847;9076.500792456347;6751.898721519181;7932.167122452175;7960.323372513285;7996.6409909076965;8002.8799568006525;2215.5125872308067;2224.4327696437626;2226.844120911521;2240.9775902241095;2416.7099948006353;394.13487395167334;567.3971031835082;794.2692722695019;916.4684482999844;927.1794461901266;17712.025838578746;20630.793692063187;20633.38099857007;20718.171273149208;20821.167153313698;964.1611797735875;982.2976571751232;996.1333639733479;1010.7877345757327;1014.1481155829139;10335.276530642783;10693.304935179189;10703.304285221435;10726.946365268124;10790.460476976119;626203;720375;732146;739089;757290;120529;126706.36646816706;131491.34254328857;154563;161483.38516614918;1167.5329868052677;1181.1318680769627;1221.7556488702473;1224.69994851262;1226.7362517058696;15749.606259843596;21742.912854356902;21900.671489927983;22126.668099978837;23018;39740.602593973694;45181.322280165165;58693.06534673164;61343.07985380312;62446.68776656088;85122.14877851142;85142;85651.14348856431;85826.14173858431;86351.5682421609;6946.652667366712;8690.00064992534;8703.260222881127;8768.60541275657;8926.821463570823;13855.792163117763;13898;14056.297185140907;14137.575872723835;14399.352029158923;11672;11717.589884354347;11884.762304753684;11912;11914.702132446759;11194.720131996764;11201.38392388394;11282.097432205568;11428.428578571204;11580.36308003068;11995.471524969149;12120.151589388659;12167.2699638022;12272.938635306766;12282.14025018276;7431.331180193809;8715.651373945226;8734.82530349402;8837.779055523662;8884.422512536665;11625.593104241649;11830.645080647595;11866.584669536525;11908.285502869856;12080.489938757655 04:50:17 INFO - PID 2263 | |3;mootools.html;1372.2109786872547;1433.799268102466;1447.9855201447983;1448.3916754962916;1464.589914823849;10562.313449625768;10750.516226769794;10834.945825270872;10892.292001019938;11028.889711102887;10677.41274229917;11094.112471002321;11095.667129986114;11099.611513597025;11102.333859968387;2441.328634625483;2473.4434752180796;2479.801615870726;2488.9004439822384;2493.5760920643534;2415.2271273192587;2427.5023620157913;2458.840175388603;2459.655648209252;2468.5556599812026;2420.4432980414517;2426.3570153909227;2441.389652586853;2473.146764366291;2474.8391354561895;2384.940376490584;2412.4089598048504;2428.562858685436;2431.890564924574;2450.5588993981078;2343.5430091132234;2409.481961378312;2429.963550546743;2438.244144315268;2464.2114523352534;10779.191560632906;10851.240413171081;10852.457377131192;10868.402237876913;10907.67276981684;6009.789657362015;6223.751049957997;6236.719347629368;6242.531810114214;6244.344343843922;3959.544652364986;3990.1620659661507;3999.1601763629674;4043.5147782266317;4289.077848262607;955.804060167671;1180.5631916191064;1190.2025642819333;1235.3884827010597;1238.9442475088642;13337.533186338529;13603.387847546885;14567.41730330775;15002.24988750569;15182.089074655445;5212.4266330703595;5239.292695486137;5389.272448219519;5423.566114710814;5430.185472179205;8770.649174033095;10989.010989011027;11012.779744405067;11111.111111111091;11116.943890330405;6068.939310606926;6105.45050945417;6126.540509461808;6137.815865524041;6143.477804386588;5653.349864765522;5756.107803290497;5789.8263052108505;5824.359320474744;5878.088896221093;4219.810108545123;4307.913841723148;4321.7406955582765;4338.913221735547;4382.2112019836095;1243.359669770069;1288.2528133682442;1289.6201064860697;1302.667819705969;1304.9934750326377;728.144430294402;743.8698227810112;758.4728613613596;769.0809482668158;801.2828518476048;5760.8271751847515;5841.707914604252;5889.234399528034;5921.881562368729;6056.091586262096;558.7122631844603;577.3389469057989;589.7346194212621;589.9852503687356;590.4302348233974;3774.641409066134;3788.583255841855;3935.665468435158;4098.409424457569;4195.895102622459;12802.935985320013;13166.73666526664;13583.52457663977;13687.31563421825;13696.52062177819;13430.127041742255;13431.462741490426;13605.591832245049;13635.931820340835;13667.17996920188;9745.853812192823;9864.457454840052;9967.900320996841;10103.343282686601;10127.898721012843;6580.901286480803;6639.800805975829;6704.430123439367;6714.194296684429;6762.222344430454;12010.339431331011;12013.699657508632;12211.450484728386;12277.631671049883;12443.626691199279;12261.75476490483;12385.009199264217;12416.317102559446;12526.624201273977;12545.623631291075;8883.156100170474;8955.373123881265;8965.327600429864;9041.59312830911;9120.179183873483;9893.65372211969;9900.752481188028;9940.552675129491;9964.252681048805;9984.051515106054;8517.148285171434;8526.573671316286;8604.82790344177;8624.827503449771;8635.913640863511;6975.372216500539;8305.46014509055;8322;8335.583220838813;8441.32469402434;6631.900521492085;7606.858971154371;7643.388528917562;7746.4190185737425;7790.065192176975;5230.581553475637;5306.8938621228135;5391.353037635509;5469.234307196916;5698.857528561819;4837.564619184291;5837.562182836389;5926.377730338425;5977.372375900642;5986.132010858314 04:50:17 INFO - PID 2263 | |4;prototype.html;396.33020195869;403.5701977394075;404.16338179967465;408.45879210046684;415.72562109008044;5484.9558495035235;5636.661800291985;6387.329330420309;6451;6487.740490380379;5295.425587728288;5338.439463856297;6181.721822517986;6301.716422760976;6308.107567729674;4628.722276663411;4691.296305554169;4767.928481072787;4793.44875339337;4794.736289504076;3645.270945810835;4797.64017698674;4807.927881081769;4816.470188279286;4826.806927722904;3628.618995005526;4700.459447163568;4712.90574188516;4718.169098540882;4741.644376671747;3875.7286989910717;4722.858314250544;4727.66906316558;4737.597304229144;4740.810367585293;4050.858219962287;4064.979675101635;4072.6131017553284;4090.447789548403;4099.590040995907;5955.843402996419;6068.696565171724;6101;6107.908381374283;6158.460384903774;5335.599830012765;5365.436629153961;5366.9463305366835;5419.945800541984;5464.3442786865435;5546.86132846678;5737.426257374271;6497.93502064978;6709.731610735565;6761.898571521431;4030.8387664493384;4203.759890832212;4458.286674132123;4477.2164871147415;4505.842295519674;5833.82498525045;6202.487992363101;6650.800475985679;7068.222495525488;7103.431725461951;3738.476613274144;3808.3906574947873;4088.856890008895;4168.6665066794585;4390.4511936008;4509.729416235036;4601.28680054592;4626.421697287839;4642.744649044335;4651.139539185261;5240.318758561362;5370.892582148414;5447.319085114361;5450.582848459349;5458.6724796512335;5630.662160270397;6195.876082478325;6709.664516774142;6783.762568310085;6785.694643741043;1565.2799712132546;1570.685862827439;1574.2679653960963;1574.5119013105973;1582.1693610854393;885.6590212768131;1031.3554028732042;1049.1763965287214;1197.287613869746;1212.8423304970297;4670.836520721827;5847.26909136358;6033.607815491979;6104.725287362079;6238.157848690459;5949.6430214187285;6150.831342044997;6894.620795856276;6922.376986071277;6930.13373328334;5593;5911.201987731687;5972.13404056418;6141.385861413913;6231.189945307081;3567.0928193522195;3610.6460077470947;3618.782873027627;3622.2755448909797;3625.8549658013912;5870.823875283749;6492.935070649328;6765;6800.251972283045;7121.287871212838;6007.879842403127;7045.8238544035785;7085.610291433917;7120;7205.099362579677;6677.298883617247;6967.198772141269;7026.859462810715;7171.641417929082;7179.569225846361;6572.539922205399;6884.483663725341;6912.101426814482;6927.238003819677;6942.722291108299;6523.706433210422;6649.235337936199;6703.5922456284725;6784.93215067843;6787.91393377077;6800.863982720417;6865.691043902936;6935.618540980295;7081.504294699322;7095.645217739196;4925.778339974638;4944.700714167785;4949.232868905326;4952.826651067196;4964.453910069961;5692.658440493584;6751.257361690112;6823.863522729617;6833.794986150423;6968.442524597918;5599.272094627672;5965.985782417087;6615.801525954229;6692.163479565054;6716.932830671631;6307.400796924285;6692.464602831859;6711.46308295345;6723.361280678328;6735.6968936397;6512.446442052478;6609.4712423007;6616.007598860209;6621;6682.76610318656;5219.956008798332;5623.718814059363;6391.2330520337855;6595.01074838778;6789.388954994074;4944.752762361795;5380.085385484555;5545.251391062155;5828.533717302521;6397.616143031433 04:50:17 INFO - PID 2263 | |5;yui.html;692.2696555134338;722.8265216348075;732.7911545209616;739.9593022383768;742.8737114690502;13701.520446784365;14401.855981440196;14759.77860332096;14804.851951480508;14862.851371486282;15010.249487525607;15092.773608395883;15168.165750883696;15180.772288415683;15201.84798152018;921.5023887100974;935.0602644342426;940.2759874896326;944.1549812917435;987.6592575561419;876.6843936182969;969.2100937735757;973.4791886340809;983.4394395194741;986.595495846703;1007.0634310091611;1025.3796453145815;1026.4149434822154;1028.5114570578953;1030.8350663893777;1009.9242556808232;1011.7318910488726;1015.2842246216474;1015.380617823131;1021.7445638590352;748.3152915082693;754.3437209627605;754.6490881740017;757.5341165183428;761.5963539324167;14634.926825365805;15060;15152.621184470367;15237.542873713806;15298.005629634039;14589.70820583593;14883.255837208097;14890.478833240893;14917.925410372987;15027.09837409759;9486.810263794685;11666.183367164302;11673.474693638807;11687.47406366707;11930.463129159121;2548.3374322676236;3037.2862377341307;3053.8167709937475;3167.683231676837;3179.8410079496166;87339;113755;115786;118590;118757.40621296839;11300.71748206285;11324;11444.94277528607;11733;11897.40512974365;21020.579588408145;25063;25230.747692523208;25304.620430693554;25412.364690882874;7143.642817859086;7548.584827834522;7704.460687751804;7905.5651939142745;7957.681692732342;3429.7084747796716;4038.0914294283552;4120.711550191458;4127.442795222667;4127.711060225815;4082.9387559186634;4138.875833724993;4167.604077612622;4176.0395109124465;4201.243776120327;783.3576467296875;784.7331907151596;795.0578564401203;796.6494742313364;798.944073914832;524.8346770767196;529.2854646227639;540.2652392745863;554.9223108764776;585.809611876142;10097.293189476813;10225.130863876653;10349.844752328721;10387.324823886423;10472.633457828939;526.8261473713666;543.5488544508049;566.1875209074991;567.1181313058235;577.1054864959297;4476.507584165739;4786.617070634341;4787.519637282046;4794.7842347094465;4809.851213938587;96411.51794240985;100423;102384.48807755913;103669.40826479248;104407;56231;56238;86769;88176.559117204;89654;76677.46645067068;77100.22899771042;77781;80140.59929700314;81103;10413.395868517604;10741;10934.835977460185;11068.501917413574;11136.665900023012;34524.30951381008;37342;37409.81295093507;38223.85328440151;38279.808600956814;42741.57258427376;49728.50271497239;50327.74836125796;50550.4834854955;51043;13251.80122298147;13383.799243011557;13572.592822215349;13668.384922678702;13794.448222071005;14387.424503019762;14491.710165796836;14633.780493292823;14749;14801.777973330623;13442.39509222107;13508.861563186629;13588.93205533966;13608.727825443633;13944.790828137384;11234.943825281149;13366.264855433044;13420.597382078553;13442.529511467052;13942.372593233531;13298.335083245605;13350.399232034388;13477;13668.794968075686;13950.860491394957;9874.753131171778;9889.85165222508;9966.950165249127;10147.34042287249;10209.38743675382;7425.680895068694;9119.498427586546;10523.210759193244;10899.673009809718;11250.549978000789 04:50:17 INFO - PID 2263 | __end_tp_report 04:50:17 INFO - PID 2263 | __start_cc_report 04:50:17 INFO - PID 2263 | _x_x_mozilla_cycle_collect,426 04:50:17 INFO - PID 2263 | __end_cc_report 04:50:17 INFO - PID 2263 | __startTimestamp1490701817346__endTimestamp 04:50:17 INFO - PID 2263 | 04:50:17 INFO - PID 2263 | ------- Summary: start ------- 04:50:17 INFO - PID 2263 | Number of tests: 6 04:50:17 INFO - PID 2263 | 04:50:17 INFO - PID 2263 | [#0] dojo.html Cycles:165 Average:6115.62 Median:6276.09 stddev:696.68 (11.1%) stddev-sans-first:654.82 04:50:17 INFO - PID 2263 | Values: 3009.4 3523.3 3533.7 3564.4 3580.4 6089.5 6138.3 6177.3 6188.9 6209.3 5094.6 5534.9 5993.6 6102.6 6138.1 5168.5 5556.3 5647.7 5726.1 5775.5 5493.8 5611.7 5725.8 5730.0 5730.9 5590.8 5628.1 5681.2 5705.2 5723.0 4894.7 5433.4 5554.7 5560.5 5604.7 5551.5 5563.6 5563.6 5567.5 5597.8 5910.4 6065.6 6154.0 6176.0 6187.9 5933.2 6289.6 6520.0 6546.5 6658.8 6751.5 6779.3 6781.6 6785.8 6804.0 6225.0 6245.3 6249.9 6253.6 6257.1 6600.9 6664.9 6811.0 6865.2 6876.9 6308.4 6532.5 6534.5 6592.5 6604.9 6575.4 6580.1 6640.1 6660.6 6724.5 6673.1 6683.8 6687.3 6698.8 6700.2 6352.2 6458.8 6467.9 6590.4 6695.8 5343.2 5373.8 5383.8 5391.1 5409.8 4732.0 4738.0 4749.5 4753.6 4801.1 6124.8 6238.0 6245.9 6254.9 6261.3 6090.5 6249.4 6503.5 6521.9 6654.0 6215.8 6218.3 6220.6 6240.7 6262.4 5985.6 5992.7 6002.4 6034.1 6079.6 6622.4 6694.8 6830.4 6851.2 6921.3 6661.1 6729.6 6733.2 6734.0 6764.7 5726.8 6488.8 6841.1 6867.7 6883.5 5533.4 6383.9 6475.9 6645.8 6647.9 5512.4 6617.8 6741.4 6743.1 6758.7 6239.5 6345.0 6520.3 6532.8 6535.7 6311.2 6364.6 6379.3 6510.7 6520.1 6276.1 6428.4 6435.5 6447.3 6448.8 6280.3 6326.2 6384.4 6439.8 6474.2 6344.4 6393.8 6399.3 6417.1 6419.4 04:50:17 INFO - PID 2263 | 04:50:17 INFO - PID 2263 | [#1] ext.html Cycles:180 Average:50928.66 Median:35895.78 stddev:98849.86 (275.4%) stddev-sans-first:99055.19 04:50:17 INFO - PID 2263 | Values: 689.3 728.9 827.1 1261.2 1263.1 37036.0 38152.0 38196.4 38278.2 38300.0 39291.0 39798.8 39858.8 39868.4 39880.8 7965.8 8122.9 8481.0 8719.1 8758.7 8606.8 8651.0 8728.6 8731.3 8743.7 8661.7 8684.4 8733.7 8740.9 8758.4 8666.7 8681.2 8712.9 8726.7 8738.5 8517.1 8549.3 8572.4 8595.1 8628.3 38734.4 38987.8 39048.0 39107.8 39209.0 3066.2 3070.7 3083.2 3085.9 3108.8 28098.4 28117.0 28233.7 28533.6 29135.1 2797.6 2807.2 2837.5 2840.6 2910.3 240553.0 243512.0 264366.7 322510.0 327013.0 47312.0 47369.8 47680.5 47690.5 47755.0 51208.7 51759.7 52003.5 52106.0 52176.0 14655.4 14692.9 14734.0 14737.7 14798.6 10853.7 11063.9 11937.8 11970.3 12072.3 11732.9 13332.7 13946.7 13963.7 14003.7 5033.8 5147.4 5949.9 6005.1 6029.5 798.8 930.5 974.9 1004.3 1038.3 17821.6 24212.3 24380.8 24513.6 24532.6 1066.1 1075.6 1116.5 1118.3 1127.3 11710.2 12348.5 12373.5 12427.8 12492.8 374879.0 443980.0 444461.0 444895.0 445665.0 56813.1 56873.7 56949.0 57045.1 57135.4 1450.3 1467.5 1507.7 1512.5 1518.7 23862.8 24094.0 27714.9 28715.0 29699.9 52630.5 53021.2 53116.5 53294.2 53325.2 387255.0 389400.1 389510.1 389664.0 390067.0 26305.2 26414.6 31897.0 32689.7 33874.7 40760.6 41718.6 41991.0 42023.6 42097.4 25045.2 27478.0 28842.0 30238.0 30258.7 29460.1 29655.4 30157.5 30261.0 30607.8 29415.9 35540.3 36251.3 36269.9 36321.5 28645.7 30172.7 30174.5 30898.7 30929.4 17999.0 24025.0 24028.9 24158.8 24565.0 04:50:17 INFO - PID 2263 | 04:50:17 INFO - PID 2263 | [#2] jquery.html Cycles:180 Average:40816.53 Median:12389.71 stddev:119950.69 (968.1%) stddev-sans-first:120248.73 04:50:17 INFO - PID 2263 | Values: 368.4 424.1 430.8 435.3 447.2 19340.7 20560.3 21208.6 21279.5 21420.4 21668.6 22538.4 22751.8 23435.4 23634.9 5914.4 6197.6 6203.7 6217.8 6233.8 5931.9 6046.4 6168.8 6215.4 6234.9 6054.9 6129.4 6172.8 6187.8 6249.1 5381.4 6159.0 6318.9 6337.2 6360.0 5273.6 5384.9 5482.0 5615.6 5621.6 27893.4 28403.1 28497.0 28746.3 28993.0 2022.7 2034.7 2041.2 2064.4 2101.6 15879.4 16074.7 16994.5 17047.7 17146.8 1669.2 1669.6 1685.3 1691.8 1702.5 162457.2 164299.0 165161.9 165257.0 166432.0 12183.1 12327.0 12446.6 12637.7 12657.7 13825.9 16283.0 16320.4 16323.0 16324.7 7425.8 8998.9 9128.4 9145.1 9203.7 8782.1 8800.2 8891.1 9034.4 9076.5 6751.9 7932.2 7960.3 7996.6 8002.9 2215.5 2224.4 2226.8 2241.0 2416.7 394.1 567.4 794.3 916.5 927.2 17712.0 20630.8 20633.4 20718.2 20821.2 964.2 982.3 996.1 1010.8 1014.1 10335.3 10693.3 10703.3 10726.9 10790.5 626203.0 720375.0 732146.0 739089.0 757290.0 120529.0 126706.4 131491.3 154563.0 161483.4 1167.5 1181.1 1221.8 1224.7 1226.7 15749.6 21742.9 21900.7 22126.7 23018.0 39740.6 45181.3 58693.1 61343.1 62446.7 85122.1 85142.0 85651.1 85826.1 86351.6 6946.7 8690.0 8703.3 8768.6 8926.8 13855.8 13898.0 14056.3 14137.6 14399.4 11672.0 11717.6 11884.8 11912.0 11914.7 11194.7 11201.4 11282.1 11428.4 11580.4 11995.5 12120.2 12167.3 12272.9 12282.1 7431.3 8715.7 8734.8 8837.8 8884.4 11625.6 11830.6 11866.6 11908.3 12080.5 04:50:17 INFO - PID 2263 | 04:50:17 INFO - PID 2263 | [#3] mootools.html Cycles:180 Average:6641.73 Median:4021.34 stddev:4072.10 (101.3%) stddev-sans-first:4064.27 04:50:17 INFO - PID 2263 | Values: 1372.2 1433.8 1448.0 1448.4 1464.6 10562.3 10750.5 10834.9 10892.3 11028.9 10677.4 11094.1 11095.7 11099.6 11102.3 2441.3 2473.4 2479.8 2488.9 2493.6 2415.2 2427.5 2458.8 2459.7 2468.6 2420.4 2426.4 2441.4 2473.1 2474.8 2384.9 2412.4 2428.6 2431.9 2450.6 2343.5 2409.5 2430.0 2438.2 2464.2 10779.2 10851.2 10852.5 10868.4 10907.7 6009.8 6223.8 6236.7 6242.5 6244.3 3959.5 3990.2 3999.2 4043.5 4289.1 955.8 1180.6 1190.2 1235.4 1238.9 13337.5 13603.4 14567.4 15002.2 15182.1 5212.4 5239.3 5389.3 5423.6 5430.2 8770.6 10989.0 11012.8 11111.1 11116.9 6068.9 6105.5 6126.5 6137.8 6143.5 5653.3 5756.1 5789.8 5824.4 5878.1 4219.8 4307.9 4321.7 4338.9 4382.2 1243.4 1288.3 1289.6 1302.7 1305.0 728.1 743.9 758.5 769.1 801.3 5760.8 5841.7 5889.2 5921.9 6056.1 558.7 577.3 589.7 590.0 590.4 3774.6 3788.6 3935.7 4098.4 4195.9 12802.9 13166.7 13583.5 13687.3 13696.5 13430.1 13431.5 13605.6 13635.9 13667.2 9745.9 9864.5 9967.9 10103.3 10127.9 6580.9 6639.8 6704.4 6714.2 6762.2 12010.3 12013.7 12211.5 12277.6 12443.6 12261.8 12385.0 12416.3 12526.6 12545.6 8883.2 8955.4 8965.3 9041.6 9120.2 9893.7 9900.8 9940.6 9964.3 9984.1 8517.1 8526.6 8604.8 8624.8 8635.9 6975.4 8305.5 8322.0 8335.6 8441.3 6631.9 7606.9 7643.4 7746.4 7790.1 5230.6 5306.9 5391.4 5469.2 5698.9 4837.6 5837.6 5926.4 5977.4 5986.1 04:50:17 INFO - PID 2263 | 04:50:17 INFO - PID 2263 | [#4] prototype.html Cycles:180 Average:5343.32 Median:5831.18 stddev:1653.45 (28.4%) stddev-sans-first:1615.86 04:50:17 INFO - PID 2263 | Values: 396.3 403.6 404.2 408.5 415.7 5485.0 5636.7 6387.3 6451.0 6487.7 5295.4 5338.4 6181.7 6301.7 6308.1 4628.7 4691.3 4767.9 4793.4 4794.7 3645.3 4797.6 4807.9 4816.5 4826.8 3628.6 4700.5 4712.9 4718.2 4741.6 3875.7 4722.9 4727.7 4737.6 4740.8 4050.9 4065.0 4072.6 4090.4 4099.6 5955.8 6068.7 6101.0 6107.9 6158.5 5335.6 5365.4 5366.9 5419.9 5464.3 5546.9 5737.4 6497.9 6709.7 6761.9 4030.8 4203.8 4458.3 4477.2 4505.8 5833.8 6202.5 6650.8 7068.2 7103.4 3738.5 3808.4 4088.9 4168.7 4390.5 4509.7 4601.3 4626.4 4642.7 4651.1 5240.3 5370.9 5447.3 5450.6 5458.7 5630.7 6195.9 6709.7 6783.8 6785.7 1565.3 1570.7 1574.3 1574.5 1582.2 885.7 1031.4 1049.2 1197.3 1212.8 4670.8 5847.3 6033.6 6104.7 6238.2 5949.6 6150.8 6894.6 6922.4 6930.1 5593.0 5911.2 5972.1 6141.4 6231.2 3567.1 3610.6 3618.8 3622.3 3625.9 5870.8 6492.9 6765.0 6800.3 7121.3 6007.9 7045.8 7085.6 7120.0 7205.1 6677.3 6967.2 7026.9 7171.6 7179.6 6572.5 6884.5 6912.1 6927.2 6942.7 6523.7 6649.2 6703.6 6784.9 6787.9 6800.9 6865.7 6935.6 7081.5 7095.6 4925.8 4944.7 4949.2 4952.8 4964.5 5692.7 6751.3 6823.9 6833.8 6968.4 5599.3 5966.0 6615.8 6692.2 6716.9 6307.4 6692.5 6711.5 6723.4 6735.7 6512.4 6609.5 6616.0 6621.0 6682.8 5220.0 5623.7 6391.2 6595.0 6789.4 4944.8 5380.1 5545.3 5828.5 6397.6 04:50:17 INFO - PID 2263 | 04:50:17 INFO - PID 2263 | [#5] yui.html Cycles:180 Average:19426.86 Median:25358.49 stddev:28101.86 (110.8%) stddev-sans-first:28145.49 04:50:17 INFO - PID 2263 | Values: 692.3 722.8 732.8 740.0 742.9 13701.5 14401.9 14759.8 14804.9 14862.9 15010.2 15092.8 15168.2 15180.8 15201.8 921.5 935.1 940.3 944.2 987.7 876.7 969.2 973.5 983.4 986.6 1007.1 1025.4 1026.4 1028.5 1030.8 1009.9 1011.7 1015.3 1015.4 1021.7 748.3 754.3 754.6 757.5 761.6 14634.9 15060.0 15152.6 15237.5 15298.0 14589.7 14883.3 14890.5 14917.9 15027.1 9486.8 11666.2 11673.5 11687.5 11930.5 2548.3 3037.3 3053.8 3167.7 3179.8 87339.0 113755.0 115786.0 118590.0 118757.4 11300.7 11324.0 11444.9 11733.0 11897.4 21020.6 25063.0 25230.7 25304.6 25412.4 7143.6 7548.6 7704.5 7905.6 7957.7 3429.7 4038.1 4120.7 4127.4 4127.7 4082.9 4138.9 4167.6 4176.0 4201.2 783.4 784.7 795.1 796.6 798.9 524.8 529.3 540.3 554.9 585.8 10097.3 10225.1 10349.8 10387.3 10472.6 526.8 543.5 566.2 567.1 577.1 4476.5 4786.6 4787.5 4794.8 4809.9 96411.5 100423.0 102384.5 103669.4 104407.0 56231.0 56238.0 86769.0 88176.6 89654.0 76677.5 77100.2 77781.0 80140.6 81103.0 10413.4 10741.0 10934.8 11068.5 11136.7 34524.3 37342.0 37409.8 38223.9 38279.8 42741.6 49728.5 50327.7 50550.5 51043.0 13251.8 13383.8 13572.6 13668.4 13794.4 14387.4 14491.7 14633.8 14749.0 14801.8 13442.4 13508.9 13588.9 13608.7 13944.8 11234.9 13366.3 13420.6 13442.5 13942.4 13298.3 13350.4 13477.0 13668.8 13950.9 9874.8 9889.9 9967.0 10147.3 10209.4 7425.7 9119.5 10523.2 10899.7 11250.5 04:50:17 INFO - PID 2263 | -------- Summary: end -------- 04:50:17 INFO - PID 2263 | 04:50:17 INFO - TEST-INFO | 2263: exit 0 04:50:18 INFO - TEST-OK | dromaeo_css | took 1117300ms 04:50:18 INFO - TEST-START | kraken 04:50:18 INFO - operating with platform_type : mac_ 04:50:18 INFO - Initialising browser for kraken test... 04:50:18 INFO - TEST-INFO | started process 2268 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/tmpW1k9aj/profile http://localhost:56015/getInfo.html) 04:50:21 INFO - PID 2268 | 2017-03-28 04:50:21.114 plugin-container[2270:26484] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893f, name = 'com.apple.tsm.portname' 04:50:21 INFO - PID 2268 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:50:21 INFO - PID 2268 | 2017-03-28 04:50:21.115 plugin-container[2270:26484] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 04:50:21 INFO - PID 2268 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:50:21 INFO - PID 2268 | 2017-03-28 04:50:21.115 plugin-container[2270:26484] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:50:21 INFO - PID 2268 | __metrics Screen width/height:1600/1200 04:50:21 INFO - PID 2268 | colorDepth:24 04:50:21 INFO - PID 2268 | Browser inner width/height: 1024/689 04:50:21 INFO - PID 2268 | __metrics 04:50:21 ERROR - PID 2268 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3396: TypeError: this.tabs is undefined 04:50:21 ERROR - PID 2268 | 1490701821321 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxNightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxNightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924:15 < forceQuit@jar:file:///var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/tmpW1k9aj/profile/extensions/talos-powers@TalosPowersService.js:237:7 < whenDone@jar:file:///var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/tmpW1k9aj/profile/extensions/talos-powers@TalosPowersService.js:222:9 < promise callback*forceQuit@jar:file:///var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/tmpW1k9aj/profile/extensions/talos-powers@TalosPowersService.js:224:7 < receiveMessage@jar:file:///var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/tmpW1k9aj/profile/extensions/talos-powers@TalosPowersService.js:55:9 04:50:22 INFO - TEST-INFO | 2268: exit 0 04:50:22 INFO - Browser initialized. 04:50:22 INFO - Running cycle 1/1 for kraken test... 04:50:22 INFO - TEST-INFO | started process 2271 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/tmpW1k9aj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:50:24 INFO - PID 2271 | 2017-03-28 04:50:24.747 plugin-container[2272:26612] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 04:50:24 INFO - PID 2271 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:50:24 INFO - PID 2271 | 2017-03-28 04:50:24.747 plugin-container[2272:26612] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x431b, name = 'com.apple.CFPasteboardClient' 04:50:24 INFO - PID 2271 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:50:24 INFO - PID 2271 | 2017-03-28 04:50:24.747 plugin-container[2272:26612] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:50:34 INFO - PID 2271 | RSS: Main: 227958784 04:50:34 INFO - PID 2271 | 04:50:37 INFO - PID 2271 | Cycle 1(1): loaded http://localhost:56015/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56015/tests/kraken/driver.html?testName=audio-beat-detection) 04:50:38 INFO - PID 2271 | RSS: Main: 243335168 04:50:38 INFO - PID 2271 | 04:50:41 INFO - PID 2271 | Cycle 1(1): loaded http://localhost:56015/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56015/tests/kraken/driver.html?testName=audio-dft) 04:50:42 INFO - PID 2271 | RSS: Main: 251748352 04:50:42 INFO - PID 2271 | 04:50:46 INFO - PID 2271 | Cycle 1(1): loaded http://localhost:56015/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56015/tests/kraken/driver.html?testName=audio-fft) 04:50:46 INFO - PID 2271 | RSS: Main: 254140416 04:50:46 INFO - PID 2271 | 04:50:48 INFO - PID 2271 | Cycle 1(1): loaded http://localhost:56015/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56015/tests/kraken/driver.html?testName=audio-oscillator) 04:50:49 INFO - PID 2271 | RSS: Main: 256086016 04:50:49 INFO - PID 2271 | 04:50:51 INFO - PID 2271 | Cycle 1(1): loaded http://localhost:56015/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56015/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:50:52 INFO - PID 2271 | RSS: Main: 256716800 04:50:52 INFO - PID 2271 | 04:50:55 INFO - PID 2271 | Cycle 1(1): loaded http://localhost:56015/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56015/tests/kraken/driver.html?testName=imaging-darkroom) 04:50:56 INFO - PID 2271 | RSS: Main: 262328320 04:50:56 INFO - PID 2271 | 04:50:59 INFO - PID 2271 | Cycle 1(1): loaded http://localhost:56015/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56015/tests/kraken/driver.html?testName=imaging-desaturate) 04:51:00 INFO - PID 2271 | RSS: Main: 263016448 04:51:00 INFO - PID 2271 | 04:51:03 INFO - PID 2271 | Cycle 1(1): loaded http://localhost:56015/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56015/tests/kraken/driver.html?testName=json-parse-financial) 04:51:03 INFO - PID 2271 | RSS: Main: 262705152 04:51:03 INFO - PID 2271 | 04:51:05 INFO - PID 2271 | Cycle 1(1): loaded http://localhost:56015/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56015/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:51:06 INFO - PID 2271 | RSS: Main: 262483968 04:51:06 INFO - PID 2271 | 04:51:08 INFO - PID 2271 | Cycle 1(1): loaded http://localhost:56015/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56015/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:51:08 INFO - PID 2271 | RSS: Main: 262475776 04:51:08 INFO - PID 2271 | 04:51:11 INFO - PID 2271 | Cycle 1(1): loaded http://localhost:56015/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56015/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:51:11 INFO - PID 2271 | RSS: Main: 262492160 04:51:11 INFO - PID 2271 | 04:51:14 INFO - PID 2271 | Cycle 1(1): loaded http://localhost:56015/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56015/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:51:15 INFO - PID 2271 | RSS: Main: 262897664 04:51:15 INFO - PID 2271 | 04:51:18 INFO - PID 2271 | Cycle 1(1): loaded http://localhost:56015/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56015/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:51:19 INFO - PID 2271 | RSS: Main: 259911680 04:51:19 INFO - PID 2271 | 04:51:21 INFO - PID 2271 | Cycle 1(1): loaded http://localhost:56015/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56015/tests/kraken/driver.html?testName=ai-astar) 04:51:21 INFO - PID 2271 | RSS: Main: 263254016 04:51:21 INFO - PID 2271 | 04:51:21 INFO - PID 2271 | __start_tp_report 04:51:21 INFO - PID 2271 | _x_x_mozilla_page_load 04:51:21 INFO - PID 2271 | _x_x_mozilla_page_load_details 04:51:21 INFO - PID 2271 | |i|pagename|runs| 04:51:21 INFO - PID 2271 | |0;ai-astar;146;122;181;184;126;165;132;184;107;170 04:51:21 INFO - PID 2271 | |1;audio-beat-detection;171;160;113;144;108;156;145;155;171;136 04:51:21 INFO - PID 2271 | |2;audio-dft;224;224;248;207;195;308;276;302;319;261 04:51:21 INFO - PID 2271 | |3;audio-fft;86;59;60;90;62;65;53;78;99;95 04:51:21 INFO - PID 2271 | |4;audio-oscillator;90;73;88;74;74;76;88;73;74;88 04:51:21 INFO - PID 2271 | |5;imaging-gaussian-blur;144;94;93;91;93;84;143;130;92;93 04:51:21 INFO - PID 2271 | |6;imaging-darkroom;101;103;140;128;107;155;163;99;99;100 04:51:21 INFO - PID 2271 | |7;imaging-desaturate;102;100;108;163;145;104;100;102;103;101 04:51:21 INFO - PID 2271 | |8;json-parse-financial;68;67;69;86;104;83;70;67;71;83 04:51:21 INFO - PID 2271 | |9;json-stringify-tinderbox;59;59;67;69;65;61;62;62;74;90 04:51:21 INFO - PID 2271 | |10;stanford-crypto-aes;75;114;91;89;86;88;89;88;87;91 04:51:21 INFO - PID 2271 | |11;stanford-crypto-ccm;150;150;146;149;151;152;150;157;201;177 04:51:21 INFO - PID 2271 | |12;stanford-crypto-pbkdf2;198;198;198;222;192;199;198;195;200;259 04:51:21 INFO - PID 2271 | |13;stanford-crypto-sha256-iterative;73;69;71;72;73;75;73;73;72;71 04:51:21 INFO - PID 2271 | __end_tp_report 04:51:21 INFO - PID 2271 | __start_cc_report 04:51:21 INFO - PID 2271 | _x_x_mozilla_cycle_collect,987 04:51:21 INFO - PID 2271 | __end_cc_report 04:51:21 INFO - PID 2271 | __startTimestamp1490701881734__endTimestamp 04:51:21 INFO - PID 2271 | 04:51:21 INFO - PID 2271 | ------- Summary: start ------- 04:51:21 INFO - PID 2271 | Number of tests: 14 04:51:21 INFO - PID 2271 | 04:51:21 INFO - PID 2271 | [#0] ai-astar Cycles:10 Average:151.70 Median:167.50 stddev:28.71 (17.1%) stddev-sans-first:30.38 04:51:21 INFO - PID 2271 | Values: 146.0 122.0 181.0 184.0 126.0 165.0 132.0 184.0 107.0 170.0 04:51:21 INFO - PID 2271 | 04:51:21 INFO - PID 2271 | [#1] audio-beat-detection Cycles:10 Average:145.90 Median:155.50 stddev:21.77 (14.0%) stddev-sans-first:21.11 04:51:21 INFO - PID 2271 | Values: 171.0 160.0 113.0 144.0 108.0 156.0 145.0 155.0 171.0 136.0 04:51:21 INFO - PID 2271 | 04:51:21 INFO - PID 2271 | [#2] audio-dft Cycles:10 Average:256.40 Median:268.50 stddev:44.03 (16.4%) stddev-sans-first:45.11 04:51:21 INFO - PID 2271 | Values: 224.0 224.0 248.0 207.0 195.0 308.0 276.0 302.0 319.0 261.0 04:51:21 INFO - PID 2271 | 04:51:21 INFO - PID 2271 | [#3] audio-fft Cycles:10 Average:74.70 Median:82.00 stddev:16.88 (20.6%) stddev-sans-first:17.40 04:51:21 INFO - PID 2271 | Values: 86.0 59.0 60.0 90.0 62.0 65.0 53.0 78.0 99.0 95.0 04:51:21 INFO - PID 2271 | 04:51:21 INFO - PID 2271 | [#4] audio-oscillator Cycles:10 Average:79.80 Median:82.00 stddev:7.55 (9.2%) stddev-sans-first:7.05 04:51:21 INFO - PID 2271 | Values: 90.0 73.0 88.0 74.0 74.0 76.0 88.0 73.0 74.0 88.0 04:51:21 INFO - PID 2271 | 04:51:21 INFO - PID 2271 | [#5] imaging-gaussian-blur Cycles:10 Average:105.70 Median:92.50 stddev:23.44 (25.3%) stddev-sans-first:20.35 04:51:21 INFO - PID 2271 | Values: 144.0 94.0 93.0 91.0 93.0 84.0 143.0 130.0 92.0 93.0 04:51:21 INFO - PID 2271 | 04:51:21 INFO - PID 2271 | [#6] imaging-darkroom Cycles:10 Average:119.50 Median:147.50 stddev:25.03 (17.0%) stddev-sans-first:25.63 04:51:21 INFO - PID 2271 | Values: 101.0 103.0 140.0 128.0 107.0 155.0 163.0 99.0 99.0 100.0 04:51:21 INFO - PID 2271 | 04:51:21 INFO - PID 2271 | [#7] imaging-desaturate Cycles:10 Average:112.80 Median:103.50 stddev:22.25 (21.5%) stddev-sans-first:23.25 04:51:21 INFO - PID 2271 | Values: 102.0 100.0 108.0 163.0 145.0 104.0 100.0 102.0 103.0 101.0 04:51:21 INFO - PID 2271 | 04:51:21 INFO - PID 2271 | [#8] json-parse-financial Cycles:10 Average:76.80 Median:70.50 stddev:12.07 (17.1%) stddev-sans-first:12.38 04:51:21 INFO - PID 2271 | Values: 68.0 67.0 69.0 86.0 104.0 83.0 70.0 67.0 71.0 83.0 04:51:21 INFO - PID 2271 | 04:51:21 INFO - PID 2271 | [#9] json-stringify-tinderbox Cycles:10 Average:66.80 Median:66.00 stddev:9.43 (14.3%) stddev-sans-first:9.57 04:51:21 INFO - PID 2271 | Values: 59.0 59.0 67.0 69.0 65.0 61.0 62.0 62.0 74.0 90.0 04:51:21 INFO - PID 2271 | 04:51:21 INFO - PID 2271 | [#10] stanford-crypto-aes Cycles:10 Average:89.80 Median:88.50 stddev:9.65 (10.9%) stddev-sans-first:8.62 04:51:21 INFO - PID 2271 | Values: 75.0 114.0 91.0 89.0 86.0 88.0 89.0 88.0 87.0 91.0 04:51:21 INFO - PID 2271 | 04:51:21 INFO - PID 2271 | [#11] stanford-crypto-ccm Cycles:10 Average:158.30 Median:151.50 stddev:17.36 (11.5%) stddev-sans-first:18.15 04:51:21 INFO - PID 2271 | Values: 150.0 150.0 146.0 149.0 151.0 152.0 150.0 157.0 201.0 177.0 04:51:21 INFO - PID 2271 | 04:51:21 INFO - PID 2271 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:205.90 Median:198.50 stddev:20.34 (10.2%) stddev-sans-first:21.37 04:51:21 INFO - PID 2271 | Values: 198.0 198.0 198.0 222.0 192.0 199.0 198.0 195.0 200.0 259.0 04:51:21 INFO - PID 2271 | 04:51:21 INFO - PID 2271 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.20 Median:73.00 stddev:1.62 (2.2%) stddev-sans-first:1.69 04:51:21 INFO - PID 2271 | Values: 73.0 69.0 71.0 72.0 73.0 75.0 73.0 73.0 72.0 71.0 04:51:21 INFO - PID 2271 | -------- Summary: end -------- 04:51:21 INFO - PID 2271 | 04:51:22 INFO - TEST-INFO | 2271: exit 0 04:51:22 INFO - TEST-OK | kraken | took 64631ms 04:51:22 INFO - SUITE-END | took 1181s 04:51:22 INFO - Completed test suite (00:19:42) 04:51:22 INFO - javascript benchmark 04:51:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7664.23727614257, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3009.413164432935, 3523.260115375769, 3533.73496987726, 3564.3584154852133, 3580.445031020192, 6089.5432842536875, 6138.294096178935, 6177.320494745574, 6188.907166392496, 6209.2859321178075, 5094.6433749637445, 5534.8930213957265, 5993.550483713728, 6102.580142287625, 6138.112525404856, 5168.53483186514, 5556.305461817273, 5647.7458514366745, 5726.112452569859, 5775.509081728037, 5493.752781124839, 5611.663300202001, 5725.770969161249, 5729.971350143243, 5730.914036289459, 5590.804321848756, 5628.0713682242385, 5681.204631351614, 5705.229793977802, 5723.0, 4894.657373983787, 5433.402325744165, 5554.666719996813, 5560.471755183251, 5604.691741954191, 5551.472610102034, 5563.554915606742, 5563.610547261694, 5567.47109024642, 5597.804076857331, 5910.379410161957, 6065.575409721321, 6153.953827849276, 6176.042713379434, 6187.907181392283, 5933.169356290123, 6289.559730818844, 6520.021996700485, 6546.541742078057, 6658.766943156967, 6751.459883209332, 6779.2881747416795, 6781.593104413751, 6785.796426107225, 6804.0, 6225.0, 6245.250569931592, 6249.937500625027, 6253.593516421418, 6257.061440783918, 6600.933990660128, 6664.933350666432, 6811.046453496514, 6865.176178858569, 6876.896846547305, 6308.432241098323, 6532.477401807844, 6534.509911756637, 6592.538522303488, 6604.900926486107, 6575.408213260829, 6580.144581204413, 6640.136782218281, 6660.600363978177, 6724.529282950244, 6673.065770792093, 6683.766068187688, 6687.331266873273, 6698.83252918681, 6700.229473610597, 6352.205974253218, 6458.773942912069, 6467.870642587122, 6590.406863382319, 6695.799126026227, 5343.225232341367, 5373.785048598091, 5383.78464861409, 5391.083515802323, 5409.78360865561, 4731.952680473151, 4737.976310118427, 4749.453812811502, 4753.619710423158, 4801.1117943180625, 6124.816255512342, 6237.96881015592, 6245.875082498324, 6254.906176407358, 6261.342559031327, 6090.512758979271, 6249.375062493714, 6503.544751867418, 6521.902171467432, 6653.9667301663185, 6215.81352559424, 6218.347073557302, 6220.595661282002, 6240.719167637377, 6262.373762623702, 5985.6408615483215, 5992.67040312787, 6002.369751176151, 6034.064719968412, 6079.604825686405, 6622.403983641495, 6694.83262918431, 6830.351116643911, 6851.212110607344, 6921.342472465108, 6661.134052573135, 6729.596224226562, 6733.225679046972, 6734.0, 6764.695588698422, 5726.79956201531, 6488.772892948724, 6841.110655614738, 6867.656617169221, 6883.518153729191, 5533.363663178786, 6383.904241436289, 6475.935240647722, 6645.833854153685, 6647.9002814958785, 5512.448755124456, 6617.834554135992, 6741.393274605309, 6743.055972163803, 6758.7296508139125, 6239.532035097295, 6345.0482427636225, 6520.250171230369, 6532.8366790830605, 6535.705893234721, 6311.179546658905, 6364.586301890362, 6379.266384365857, 6510.707018184288, 6520.054592084216, 6276.089966954857, 6428.357164283721, 6435.517336199898, 6447.258565265054, 6448.838779030561, 6280.309165991646, 6326.240851097714, 6384.361563843579, 6439.774607888701, 6474.158359413246, 6344.397282258178, 6393.776217832354, 6399.328070552525, 6417.101605775289, 6419.390157934989], "value": 6070.688471702788, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [689.3313485918659, 728.8979542864, 827.1273806134529, 1261.2180448122165, 1263.1095077970033, 37036.0, 38152.0, 38196.42705359422, 38278.234435311315, 38299.99999999996, 39291.0, 39798.801005995, 39858.8007059965, 39868.401973970416, 39880.8035758927, 7965.800854978628, 8122.9187708123045, 8480.957595212047, 8719.128087191262, 8758.693445729368, 8606.827863442728, 8650.956745216265, 8728.563571821414, 8731.34514911381, 8743.650253989832, 8661.696840610548, 8684.435511691752, 8733.694320698776, 8740.912590874073, 8758.430702004382, 8666.653333866638, 8681.21869031787, 8712.912870871305, 8726.69456569017, 8738.519381434018, 8517.105703901125, 8549.273311768507, 8572.442791218551, 8595.097514760922, 8628.266597339234, 38734.418983715266, 38987.830365089096, 39048.0, 39107.8044609778, 39209.0, 3066.186087842089, 3070.6775788542104, 3083.182956516525, 3085.8765649374, 3108.751299896003, 28098.43803123926, 28117.01590444345, 28233.717662823314, 28533.57199642007, 29135.125946221648, 2797.608334833125, 2807.1859160843333, 2837.5034368985343, 2840.6307180066665, 2910.272431892027, 240553.0, 243512.0, 264366.6781666079, 322510.0, 327013.0, 47312.0, 47369.76315118402, 47680.52319476761, 47690.523094769305, 47755.0, 51208.74395627998, 51759.70600734936, 52003.47996519986, 52106.0, 52176.0, 14655.413783448543, 14692.926535367254, 14734.0, 14737.705245895022, 14798.556043318718, 10853.728656783644, 11063.889361106447, 11937.761244775229, 11970.341631210367, 12072.275663460221, 11732.941335293439, 13332.73334533304, 13946.651333716536, 13963.650908727363, 14003.71992560143, 5033.823816166417, 5147.408048074444, 5949.940500594938, 6005.129256257906, 6029.547783916224, 798.7958450693735, 930.5207817973749, 974.9268804839666, 1004.3271008424373, 1038.3354653022072, 17821.643567128845, 24212.273631790722, 24380.75619243785, 24513.63229551558, 24532.632010519857, 1066.1311031509294, 1075.5805235957982, 1116.4529380603446, 1118.2898859224451, 1127.301073334538, 11710.238834475902, 12348.506059757508, 12373.505059797688, 12427.763002659609, 12492.81260781089, 374879.0, 443980.0, 444461.0, 444895.0, 445665.0, 56813.147802782994, 56873.715631421575, 56949.0, 57045.14432283436, 57135.42864571301, 1450.2893582144673, 1467.4717101843328, 1507.66077632534, 1512.5479539641847, 1518.6810769738258, 23862.761372386747, 24094.0, 27714.861425692743, 28715.0, 29699.85150074236, 52630.47369526256, 53021.204681930576, 53116.4688353127, 53294.20058699045, 53325.200121998976, 387255.0, 389400.0529997332, 389510.05244973593, 389664.0, 390067.0, 26305.210843674722, 26414.603780942918, 31897.0, 32689.673103268662, 33874.66125338715, 40760.59239407686, 41718.58281417147, 41991.0, 42023.57976420197, 42097.36853947255, 25045.24864254075, 27478.0, 28842.0, 30238.0, 30258.697413025588, 29460.11619651414, 29655.406891862473, 30157.547636785905, 30261.0, 30607.846960765055, 29415.85292073526, 35540.289194216486, 36251.27497449984, 36269.91190264191, 36321.45517817182, 28645.710943007198, 30172.698273016988, 30174.49156424922, 30898.69101308958, 30929.381412371178, 17999.01005444713, 24025.0, 24028.87985560061, 24158.75841241565, 24565.017399304543], "value": 17741.104204364055, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [368.3700871509718, 424.1284161049043, 430.7846076961519, 435.26005790156734, 447.21067316186935, 19340.70988935167, 20560.280390186326, 21208.575828483423, 21279.468013299676, 21420.357389278328, 21668.566628667457, 22538.43653908649, 22751.772482275173, 23435.41411464714, 23634.936427860746, 5914.438128377818, 6197.566170368076, 6203.658798766066, 6217.751289948396, 6233.812985610439, 5931.88136237275, 6046.425589568984, 6168.814935551941, 6215.409536094064, 6234.906476402835, 6054.909176362358, 6129.41770531801, 6172.783952561661, 6187.752489900398, 6249.093881387196, 5381.408045115034, 6158.9692051539905, 6318.873622527523, 6337.239531256256, 6360.0, 5273.551748101378, 5384.946150538483, 5481.972590137064, 5615.550755939515, 5621.606487545873, 27893.442131157466, 28403.147905562866, 28497.01041854142, 28746.281342966595, 28993.0, 2022.7269318641943, 2034.7151398804178, 2041.1631231195138, 2064.4013236161495, 2101.5901899129676, 15879.444219452263, 16074.678506429922, 16994.49016529506, 17047.659046819117, 17146.828531714647, 1669.2071266148603, 1669.5659128626523, 1685.2584862660512, 1691.8054423741305, 1702.4637239269591, 162457.18771406185, 164298.9999999988, 165161.88188627223, 165257.0, 166432.0, 12183.147179697336, 12327.0, 12446.564370246997, 12637.747245055047, 12657.746845063048, 13825.930870345584, 16283.0, 16320.428784992468, 16323.0, 16324.673506529803, 7425.8143546411775, 8998.910010899937, 9128.361014728906, 9145.085491450802, 9203.723888283363, 8782.121787821166, 8800.207981281716, 8891.110888911056, 9034.36759426847, 9076.500792456347, 6751.898721519181, 7932.167122452175, 7960.323372513285, 7996.6409909076965, 8002.8799568006525, 2215.5125872308067, 2224.4327696437626, 2226.844120911521, 2240.9775902241095, 2416.7099948006353, 394.13487395167334, 567.3971031835082, 794.2692722695019, 916.4684482999844, 927.1794461901266, 17712.025838578746, 20630.793692063187, 20633.38099857007, 20718.171273149208, 20821.167153313698, 964.1611797735875, 982.2976571751232, 996.1333639733479, 1010.7877345757327, 1014.1481155829139, 10335.276530642783, 10693.304935179189, 10703.304285221435, 10726.946365268124, 10790.460476976119, 626203.0, 720375.0, 732146.0, 739089.0, 757290.0, 120529.0, 126706.36646816706, 131491.34254328857, 154563.0, 161483.38516614918, 1167.5329868052677, 1181.1318680769627, 1221.7556488702473, 1224.69994851262, 1226.7362517058696, 15749.606259843596, 21742.912854356902, 21900.671489927983, 22126.668099978837, 23018.0, 39740.602593973694, 45181.322280165165, 58693.06534673164, 61343.07985380312, 62446.68776656088, 85122.14877851142, 85142.0, 85651.14348856431, 85826.14173858431, 86351.5682421609, 6946.652667366712, 8690.00064992534, 8703.260222881127, 8768.60541275657, 8926.821463570823, 13855.792163117763, 13898.0, 14056.297185140907, 14137.575872723835, 14399.352029158923, 11672.0, 11717.589884354347, 11884.762304753684, 11912.0, 11914.702132446759, 11194.720131996764, 11201.38392388394, 11282.097432205568, 11428.428578571204, 11580.36308003068, 11995.471524969149, 12120.151589388659, 12167.2699638022, 12272.938635306766, 12282.14025018276, 7431.331180193809, 8715.651373945226, 8734.82530349402, 8837.779055523662, 8884.422512536665, 11625.593104241649, 11830.645080647595, 11866.584669536525, 11908.285502869856, 12080.489938757655], "value": 10383.632419899346, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1372.2109786872547, 1433.799268102466, 1447.9855201447983, 1448.3916754962916, 1464.589914823849, 10562.313449625768, 10750.516226769794, 10834.945825270872, 10892.292001019938, 11028.889711102887, 10677.41274229917, 11094.112471002321, 11095.667129986114, 11099.611513597025, 11102.333859968387, 2441.328634625483, 2473.4434752180796, 2479.801615870726, 2488.9004439822384, 2493.5760920643534, 2415.2271273192587, 2427.5023620157913, 2458.840175388603, 2459.655648209252, 2468.5556599812026, 2420.4432980414517, 2426.3570153909227, 2441.389652586853, 2473.146764366291, 2474.8391354561895, 2384.940376490584, 2412.4089598048504, 2428.562858685436, 2431.890564924574, 2450.5588993981078, 2343.5430091132234, 2409.481961378312, 2429.963550546743, 2438.244144315268, 2464.2114523352534, 10779.191560632906, 10851.240413171081, 10852.457377131192, 10868.402237876913, 10907.67276981684, 6009.789657362015, 6223.751049957997, 6236.719347629368, 6242.531810114214, 6244.344343843922, 3959.544652364986, 3990.1620659661507, 3999.1601763629674, 4043.5147782266317, 4289.077848262607, 955.804060167671, 1180.5631916191064, 1190.2025642819333, 1235.3884827010597, 1238.9442475088642, 13337.533186338529, 13603.387847546885, 14567.41730330775, 15002.24988750569, 15182.089074655445, 5212.4266330703595, 5239.292695486137, 5389.272448219519, 5423.566114710814, 5430.185472179205, 8770.649174033095, 10989.010989011027, 11012.779744405067, 11111.111111111091, 11116.943890330405, 6068.939310606926, 6105.45050945417, 6126.540509461808, 6137.815865524041, 6143.477804386588, 5653.349864765522, 5756.107803290497, 5789.8263052108505, 5824.359320474744, 5878.088896221093, 4219.810108545123, 4307.913841723148, 4321.7406955582765, 4338.913221735547, 4382.2112019836095, 1243.359669770069, 1288.2528133682442, 1289.6201064860697, 1302.667819705969, 1304.9934750326377, 728.144430294402, 743.8698227810112, 758.4728613613596, 769.0809482668158, 801.2828518476048, 5760.8271751847515, 5841.707914604252, 5889.234399528034, 5921.881562368729, 6056.091586262096, 558.7122631844603, 577.3389469057989, 589.7346194212621, 589.9852503687356, 590.4302348233974, 3774.641409066134, 3788.583255841855, 3935.665468435158, 4098.409424457569, 4195.895102622459, 12802.935985320013, 13166.73666526664, 13583.52457663977, 13687.31563421825, 13696.52062177819, 13430.127041742255, 13431.462741490426, 13605.591832245049, 13635.931820340835, 13667.17996920188, 9745.853812192823, 9864.457454840052, 9967.900320996841, 10103.343282686601, 10127.898721012843, 6580.901286480803, 6639.800805975829, 6704.430123439367, 6714.194296684429, 6762.222344430454, 12010.339431331011, 12013.699657508632, 12211.450484728386, 12277.631671049883, 12443.626691199279, 12261.75476490483, 12385.009199264217, 12416.317102559446, 12526.624201273977, 12545.623631291075, 8883.156100170474, 8955.373123881265, 8965.327600429864, 9041.59312830911, 9120.179183873483, 9893.65372211969, 9900.752481188028, 9940.552675129491, 9964.252681048805, 9984.051515106054, 8517.148285171434, 8526.573671316286, 8604.82790344177, 8624.827503449771, 8635.913640863511, 6975.372216500539, 8305.46014509055, 8322.0, 8335.583220838813, 8441.32469402434, 6631.900521492085, 7606.858971154371, 7643.388528917562, 7746.4190185737425, 7790.065192176975, 5230.581553475637, 5306.8938621228135, 5391.353037635509, 5469.234307196916, 5698.857528561819, 4837.564619184291, 5837.562182836389, 5926.377730338425, 5977.372375900642, 5986.132010858314], "value": 5046.047731876222, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [396.33020195869, 403.5701977394075, 404.16338179967465, 408.45879210046684, 415.72562109008044, 5484.9558495035235, 5636.661800291985, 6387.329330420309, 6451.0, 6487.740490380379, 5295.425587728288, 5338.439463856297, 6181.721822517986, 6301.716422760976, 6308.107567729674, 4628.722276663411, 4691.296305554169, 4767.928481072787, 4793.44875339337, 4794.736289504076, 3645.270945810835, 4797.64017698674, 4807.927881081769, 4816.470188279286, 4826.806927722904, 3628.618995005526, 4700.459447163568, 4712.90574188516, 4718.169098540882, 4741.644376671747, 3875.7286989910717, 4722.858314250544, 4727.66906316558, 4737.597304229144, 4740.810367585293, 4050.858219962287, 4064.979675101635, 4072.6131017553284, 4090.447789548403, 4099.590040995907, 5955.843402996419, 6068.696565171724, 6101.0, 6107.908381374283, 6158.460384903774, 5335.599830012765, 5365.436629153961, 5366.9463305366835, 5419.945800541984, 5464.3442786865435, 5546.86132846678, 5737.426257374271, 6497.93502064978, 6709.731610735565, 6761.898571521431, 4030.8387664493384, 4203.759890832212, 4458.286674132123, 4477.2164871147415, 4505.842295519674, 5833.82498525045, 6202.487992363101, 6650.800475985679, 7068.222495525488, 7103.431725461951, 3738.476613274144, 3808.3906574947873, 4088.856890008895, 4168.6665066794585, 4390.4511936008, 4509.729416235036, 4601.28680054592, 4626.421697287839, 4642.744649044335, 4651.139539185261, 5240.318758561362, 5370.892582148414, 5447.319085114361, 5450.582848459349, 5458.6724796512335, 5630.662160270397, 6195.876082478325, 6709.664516774142, 6783.762568310085, 6785.694643741043, 1565.2799712132546, 1570.685862827439, 1574.2679653960963, 1574.5119013105973, 1582.1693610854393, 885.6590212768131, 1031.3554028732042, 1049.1763965287214, 1197.287613869746, 1212.8423304970297, 4670.836520721827, 5847.26909136358, 6033.607815491979, 6104.725287362079, 6238.157848690459, 5949.6430214187285, 6150.831342044997, 6894.620795856276, 6922.376986071277, 6930.13373328334, 5593.0, 5911.201987731687, 5972.13404056418, 6141.385861413913, 6231.189945307081, 3567.0928193522195, 3610.6460077470947, 3618.782873027627, 3622.2755448909797, 3625.8549658013912, 5870.823875283749, 6492.935070649328, 6765.0, 6800.251972283045, 7121.287871212838, 6007.879842403127, 7045.8238544035785, 7085.610291433917, 7120.0, 7205.099362579677, 6677.298883617247, 6967.198772141269, 7026.859462810715, 7171.641417929082, 7179.569225846361, 6572.539922205399, 6884.483663725341, 6912.101426814482, 6927.238003819677, 6942.722291108299, 6523.706433210422, 6649.235337936199, 6703.5922456284725, 6784.93215067843, 6787.91393377077, 6800.863982720417, 6865.691043902936, 6935.618540980295, 7081.504294699322, 7095.645217739196, 4925.778339974638, 4944.700714167785, 4949.232868905326, 4952.826651067196, 4964.453910069961, 5692.658440493584, 6751.257361690112, 6823.863522729617, 6833.794986150423, 6968.442524597918, 5599.272094627672, 5965.985782417087, 6615.801525954229, 6692.163479565054, 6716.932830671631, 6307.400796924285, 6692.464602831859, 6711.46308295345, 6723.361280678328, 6735.6968936397, 6512.446442052478, 6609.4712423007, 6616.007598860209, 6621.0, 6682.76610318656, 5219.956008798332, 5623.718814059363, 6391.2330520337855, 6595.01074838778, 6789.388954994074, 4944.752762361795, 5380.085385484555, 5545.251391062155, 5828.533717302521, 6397.616143031433], "value": 4835.441434245366, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [692.2696555134338, 722.8265216348075, 732.7911545209616, 739.9593022383768, 742.8737114690502, 13701.520446784365, 14401.855981440196, 14759.77860332096, 14804.851951480508, 14862.851371486282, 15010.249487525607, 15092.773608395883, 15168.165750883696, 15180.772288415683, 15201.84798152018, 921.5023887100974, 935.0602644342426, 940.2759874896326, 944.1549812917435, 987.6592575561419, 876.6843936182969, 969.2100937735757, 973.4791886340809, 983.4394395194741, 986.595495846703, 1007.0634310091611, 1025.3796453145815, 1026.4149434822154, 1028.5114570578953, 1030.8350663893777, 1009.9242556808232, 1011.7318910488726, 1015.2842246216474, 1015.380617823131, 1021.7445638590352, 748.3152915082693, 754.3437209627605, 754.6490881740017, 757.5341165183428, 761.5963539324167, 14634.926825365805, 15060.0, 15152.621184470367, 15237.542873713806, 15298.005629634039, 14589.70820583593, 14883.255837208097, 14890.478833240893, 14917.925410372987, 15027.09837409759, 9486.810263794685, 11666.183367164302, 11673.474693638807, 11687.47406366707, 11930.463129159121, 2548.3374322676236, 3037.2862377341307, 3053.8167709937475, 3167.683231676837, 3179.8410079496166, 87339.0, 113755.0, 115786.0, 118590.0, 118757.40621296839, 11300.71748206285, 11324.0, 11444.94277528607, 11733.0, 11897.40512974365, 21020.579588408145, 25063.0, 25230.747692523208, 25304.620430693554, 25412.364690882874, 7143.642817859086, 7548.584827834522, 7704.460687751804, 7905.5651939142745, 7957.681692732342, 3429.7084747796716, 4038.0914294283552, 4120.711550191458, 4127.442795222667, 4127.711060225815, 4082.9387559186634, 4138.875833724993, 4167.604077612622, 4176.0395109124465, 4201.243776120327, 783.3576467296875, 784.7331907151596, 795.0578564401203, 796.6494742313364, 798.944073914832, 524.8346770767196, 529.2854646227639, 540.2652392745863, 554.9223108764776, 585.809611876142, 10097.293189476813, 10225.130863876653, 10349.844752328721, 10387.324823886423, 10472.633457828939, 526.8261473713666, 543.5488544508049, 566.1875209074991, 567.1181313058235, 577.1054864959297, 4476.507584165739, 4786.617070634341, 4787.519637282046, 4794.7842347094465, 4809.851213938587, 96411.51794240985, 100423.0, 102384.48807755913, 103669.40826479248, 104407.0, 56231.0, 56238.0, 86769.0, 88176.559117204, 89654.0, 76677.46645067068, 77100.22899771042, 77781.0, 80140.59929700314, 81103.0, 10413.395868517604, 10741.0, 10934.835977460185, 11068.501917413574, 11136.665900023012, 34524.30951381008, 37342.0, 37409.81295093507, 38223.85328440151, 38279.808600956814, 42741.57258427376, 49728.50271497239, 50327.74836125796, 50550.4834854955, 51043.0, 13251.80122298147, 13383.799243011557, 13572.592822215349, 13668.384922678702, 13794.448222071005, 14387.424503019762, 14491.710165796836, 14633.780493292823, 14749.0, 14801.777973330623, 13442.39509222107, 13508.861563186629, 13588.93205533966, 13608.727825443633, 13944.790828137384, 11234.943825281149, 13366.264855433044, 13420.597382078553, 13442.529511467052, 13942.372593233531, 13298.335083245605, 13350.399232034388, 13477.0, 13668.794968075686, 13950.860491394957, 9874.753131171778, 9889.85165222508, 9966.950165249127, 10147.34042287249, 10209.38743675382, 7425.680895068694, 9119.498427586546, 10523.210759193244, 10899.673009809718, 11250.549978000789], "value": 7427.216233867499, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1716.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 122.0, 181.0, 184.0, 126.0, 165.0, 132.0, 184.0, 107.0, 170.0], "value": 151.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 160.0, 113.0, 144.0, 108.0, 156.0, 145.0, 155.0, 171.0, 136.0], "value": 145.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [224.0, 224.0, 248.0, 207.0, 195.0, 308.0, 276.0, 302.0, 319.0, 261.0], "value": 256.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 59.0, 60.0, 90.0, 62.0, 65.0, 53.0, 78.0, 99.0, 95.0], "value": 74.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 73.0, 88.0, 74.0, 74.0, 76.0, 88.0, 73.0, 74.0, 88.0], "value": 79.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 94.0, 93.0, 91.0, 93.0, 84.0, 143.0, 130.0, 92.0, 93.0], "value": 105.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 103.0, 140.0, 128.0, 107.0, 155.0, 163.0, 99.0, 99.0, 100.0], "value": 119.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 100.0, 108.0, 163.0, 145.0, 104.0, 100.0, 102.0, 103.0, 101.0], "value": 112.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 69.0, 86.0, 104.0, 83.0, 70.0, 67.0, 71.0, 83.0], "value": 76.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 67.0, 69.0, 65.0, 61.0, 62.0, 62.0, 74.0, 90.0], "value": 66.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 114.0, 91.0, 89.0, 86.0, 88.0, 89.0, 88.0, 87.0, 91.0], "value": 89.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 150.0, 146.0, 149.0, 151.0, 152.0, 150.0, 157.0, 201.0, 177.0], "value": 158.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 198.0, 198.0, 222.0, 192.0, 199.0, 198.0, 195.0, 200.0, 259.0], "value": 205.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 69.0, 71.0, 72.0, 73.0, 75.0, 73.0, 73.0, 72.0, 71.0], "value": 72.2, "unit": "score"}]}]} 04:51:22 INFO - Return code: 0 04:51:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:51:23 ERROR - # TBPL SUCCESS # 04:51:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:51:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:51:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:51:23 INFO - 2017-03-28 04:51:23.090 screenresolution[2276:27007] starting screenresolution argv=screenresolution get 04:51:23 INFO - 2017-03-28 04:51:23.093 screenresolution[2276:27007] Display 0: 1600x1200x32@60 04:51:23 INFO - 2017-03-28 04:51:23.098 screenresolution[2277:27010] starting screenresolution argv=screenresolution list 04:51:23 INFO - Available Modes on Display 0 04:51:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:51:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:51:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:51:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:51:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:51:23 INFO - Intel Iris: 04:51:23 INFO - Chipset Model: Intel Iris 04:51:23 INFO - Type: GPU 04:51:23 INFO - Bus: Built-In 04:51:23 INFO - VRAM (Dynamic, Max): 1536 MB 04:51:23 INFO - Vendor: Intel (0x8086) 04:51:23 INFO - Device ID: 0x0a2e 04:51:23 INFO - Revision ID: 0x0009 04:51:23 INFO - Displays: 04:51:23 INFO - Display: 04:51:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:51:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:51:23 INFO - Display Serial Number: 1600x1200 60 04:51:23 INFO - Main Display: Yes 04:51:23 INFO - Mirror: Off 04:51:23 INFO - Online: Yes 04:51:23 INFO - Rotation: Supported 04:51:23 INFO - Return code: 0 04:51:23 INFO - Running post-action listener: _resource_record_post_action 04:51:23 INFO - [mozharness: 2017-03-28 11:51:23.332720Z] Finished run-tests step (success) 04:51:23 INFO - Running post-run listener: _resource_record_post_run 04:51:23 INFO - Total resource usage - Wall time: 1203s; CPU: 54.0%; Read bytes: 561152; Write bytes: 517751296; Read time: 5; Write time: 2342 04:51:23 INFO - TinderboxPrint: CPU usage
54.3% 04:51:23 INFO - TinderboxPrint: I/O read bytes / time
561,152 / 5 04:51:23 INFO - TinderboxPrint: I/O write bytes / time
517,751,296 / 2,342 04:51:23 INFO - TinderboxPrint: CPU idle
2,197.7 (45.7%) 04:51:23 INFO - TinderboxPrint: CPU system
973.9 (20.2%) 04:51:23 INFO - TinderboxPrint: CPU user
1,642.2 (34.1%) 04:51:23 INFO - TinderboxPrint: Swap in / out
211,562,496 / 0 04:51:23 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 206764544; Write bytes: 204579328; Read time: 15687; Write time: 444 04:51:23 INFO - run-tests - Wall time: 1183s; CPU: 54.0%; Read bytes: 561152; Write bytes: 302272512; Read time: 5; Write time: 1867 04:51:23 INFO - Running post-run listener: _upload_blobber_files 04:51:23 INFO - Blob upload gear active. 04:51:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:51:23 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 04:51:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:51:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:51:24 INFO - (blobuploader) - INFO - Open directory for files ... 04:51:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:51:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:51:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:51:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:51:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:51:25 INFO - (blobuploader) - INFO - Done attempting. 04:51:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:51:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:51:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:51:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:51:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:51:26 INFO - (blobuploader) - INFO - Done attempting. 04:51:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:51:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:51:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:51:26 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:51:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:51:26 INFO - (blobuploader) - INFO - Done attempting. 04:51:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:51:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:51:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:51:29 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:51:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:51:29 INFO - (blobuploader) - INFO - Done attempting. 04:51:29 INFO - (blobuploader) - INFO - Iteration through files over. 04:51:29 INFO - Return code: 0 04:51:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:51:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:51:29 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/71168b5902ab09d948b520fe9f75f4dcdec1ad157860dd96950209cc38838d6f46ec13cf0af3e789a01913e29866cc6b2ca08360c5a96f9932bbffd43a1298f7", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c9610f138e79fd050595b4d344911a7e070ac92e1fca242949bbc2b5f890b04424c4d2462d8a675c8832a943a27818e1ae6d0e7fe246d15753ee67058acab932", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0064038266374ce61e5e099bdcaeee90f1e03cd0913e15ee8c2dd2442901e39ce9ad6a1cbfee4e244956a0dab50552757be14d02e1cee05b5791b57d225b207b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3456a2a4bbc0dde4e49e0eb6eafa6490fd8d433e805d9daa458da9b44dee23242578365b0158466dfac81a4bf1882cbc1f67d1fe1b18f247a3c7ef1391d7dc2e"} 04:51:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:51:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:51:29 INFO - Contents: 04:51:29 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/71168b5902ab09d948b520fe9f75f4dcdec1ad157860dd96950209cc38838d6f46ec13cf0af3e789a01913e29866cc6b2ca08360c5a96f9932bbffd43a1298f7", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c9610f138e79fd050595b4d344911a7e070ac92e1fca242949bbc2b5f890b04424c4d2462d8a675c8832a943a27818e1ae6d0e7fe246d15753ee67058acab932", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0064038266374ce61e5e099bdcaeee90f1e03cd0913e15ee8c2dd2442901e39ce9ad6a1cbfee4e244956a0dab50552757be14d02e1cee05b5791b57d225b207b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3456a2a4bbc0dde4e49e0eb6eafa6490fd8d433e805d9daa458da9b44dee23242578365b0158466dfac81a4bf1882cbc1f67d1fe1b18f247a3c7ef1391d7dc2e"} 04:51:29 INFO - Running post-run listener: copy_logs_to_upload_dir 04:51:29 INFO - Copying logs to upload dir... 04:51:29 INFO - mkdir: /builds/slave/test/build/upload/logs 04:51:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1319.172024 ========= master_lag: 0.15 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 59 secs) (at 2017-03-28 04:51:29.429160) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-28 04:51:29.432784) ========= 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.Xf0iRV98Li/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.CqMLzsnXXf/Listeners TMPDIR=/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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/71168b5902ab09d948b520fe9f75f4dcdec1ad157860dd96950209cc38838d6f46ec13cf0af3e789a01913e29866cc6b2ca08360c5a96f9932bbffd43a1298f7", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c9610f138e79fd050595b4d344911a7e070ac92e1fca242949bbc2b5f890b04424c4d2462d8a675c8832a943a27818e1ae6d0e7fe246d15753ee67058acab932", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0064038266374ce61e5e099bdcaeee90f1e03cd0913e15ee8c2dd2442901e39ce9ad6a1cbfee4e244956a0dab50552757be14d02e1cee05b5791b57d225b207b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3456a2a4bbc0dde4e49e0eb6eafa6490fd8d433e805d9daa458da9b44dee23242578365b0158466dfac81a4bf1882cbc1f67d1fe1b18f247a3c7ef1391d7dc2e"} build_url:https://queue.taskcluster.net/v1/task/andbvLExRdC4vM-RjPR8rw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011020 build_url: 'https://queue.taskcluster.net/v1/task/andbvLExRdC4vM-RjPR8rw/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/71168b5902ab09d948b520fe9f75f4dcdec1ad157860dd96950209cc38838d6f46ec13cf0af3e789a01913e29866cc6b2ca08360c5a96f9932bbffd43a1298f7", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c9610f138e79fd050595b4d344911a7e070ac92e1fca242949bbc2b5f890b04424c4d2462d8a675c8832a943a27818e1ae6d0e7fe246d15753ee67058acab932", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0064038266374ce61e5e099bdcaeee90f1e03cd0913e15ee8c2dd2442901e39ce9ad6a1cbfee4e244956a0dab50552757be14d02e1cee05b5791b57d225b207b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3456a2a4bbc0dde4e49e0eb6eafa6490fd8d433e805d9daa458da9b44dee23242578365b0158466dfac81a4bf1882cbc1f67d1fe1b18f247a3c7ef1391d7dc2e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-28 04:51:29.467551) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 04:51:29.467933) ========= 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.Xf0iRV98Li/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.CqMLzsnXXf/Listeners TMPDIR=/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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.005271 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 04:51:29.520057) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-28 04:51:29.520452) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-28 04:51:29.520902) ========= ========= Total master_lag: 1.15 =========