builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0361 starttime: 1482508671.36 results: success (0) buildid: 20161223062836 builduid: 5178cd0d87334f38ae7bcbdcb11e3629 revision: bbbd2f7539f224a482cc6d2dd10e6a5f31c8baf3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-23 07:57:51.364083) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-23 07:57:51.364557) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-23 07:57:51.364839) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-23 07:57:51.489674) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-23 07:57:51.490061) ========= 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.0hxTnN1zwb/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.90LM3GUIRR/Listeners TMPDIR=/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/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 --2016-12-23 07:57:51-- 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% 173M=0s 2016-12-23 07:57:52 (173 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.743792 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-23 07:57:52.258258) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-23 07:57:52.258567) ========= 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.0hxTnN1zwb/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.90LM3GUIRR/Listeners TMPDIR=/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/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.025086 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-23 07:57:52.309985) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-23 07:57:52.310542) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev bbbd2f7539f224a482cc6d2dd10e6a5f31c8baf3 --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 bbbd2f7539f224a482cc6d2dd10e6a5f31c8baf3 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.0hxTnN1zwb/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.90LM3GUIRR/Listeners TMPDIR=/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/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 2016-12-23 07:57:52,408 truncating revision to first 12 chars 2016-12-23 07:57:52,408 Setting DEBUG logging. 2016-12-23 07:57:52,408 attempt 1/10 2016-12-23 07:57:52,408 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/bbbd2f7539f2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-23 07:57:53,050 unpacking tar archive at: mozilla-central-bbbd2f7539f2/testing/mozharness/ program finished with exit code 0 elapsedTime=0.917736 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-23 07:57:53.251912) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-23 07:57:53.252315) ========= script_repo_revision: bbbd2f7539f224a482cc6d2dd10e6a5f31c8baf3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-23 07:57:53.252745) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-23 07:57:53.253013) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-23 07:57:53.272909) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 23 secs) (at 2016-12-23 07:57:53.273319) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --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', '--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.0hxTnN1zwb/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.90LM3GUIRR/Listeners TMPDIR=/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/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 07:57:53 INFO - MultiFileLogger online at 20161223 07:57:53 in /builds/slave/test 07:57:53 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 07:57:53 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:57:53 INFO - {'append_to_log': False, 07:57:53 INFO - 'base_work_dir': '/builds/slave/test', 07:57:53 INFO - 'blob_upload_branch': 'Firefox', 07:57:53 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:57:53 INFO - 'branch': 'Firefox', 07:57:53 INFO - 'buildbot_json_path': 'buildprops.json', 07:57:53 INFO - 'config_files': ('talos/mac_config.py',), 07:57:53 INFO - 'default_actions': ('clobber', 07:57:53 INFO - 'read-buildbot-config', 07:57:53 INFO - 'download-and-extract', 07:57:53 INFO - 'populate-webroot', 07:57:53 INFO - 'create-virtualenv', 07:57:53 INFO - 'install', 07:57:53 INFO - 'run-tests'), 07:57:53 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:57:53 INFO - 'download_minidump_stackwalk': True, 07:57:53 INFO - 'download_symbols': 'ondemand', 07:57:53 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:57:53 INFO - 'tooltool.py': '/tools/tooltool.py', 07:57:53 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:57:53 INFO - '/tools/misc-python/virtualenv.py')}, 07:57:53 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:57:53 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:57:53 INFO - 'installer_path': 'installer.exe', 07:57:53 INFO - 'log_level': 'info', 07:57:53 INFO - 'log_name': 'talos', 07:57:53 INFO - 'log_to_console': True, 07:57:53 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:57:53 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:57:53 INFO - 'opt_config_files': (), 07:57:53 INFO - 'pip_index': False, 07:57:53 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:57:53 INFO - 'cmd': ('bash', 07:57:53 INFO - '-c', 07:57:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:57:53 INFO - 'enabled': True, 07:57:53 INFO - 'halt_on_failure': False, 07:57:53 INFO - 'name': 'check_screen_resolution'},), 07:57:53 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:57:53 INFO - 'cmd': ('bash', 07:57:53 INFO - '-c', 07:57:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:57:53 INFO - 'enabled': True, 07:57:53 INFO - 'halt_on_failure': False, 07:57:53 INFO - 'name': 'check_screen_resolution'},), 07:57:53 INFO - 'run_cmd_checks_enabled': True, 07:57:53 INFO - 'sps_profile': False, 07:57:53 INFO - 'sps_profile_interval': 0, 07:57:53 INFO - 'suite': 'dromaeojs', 07:57:53 INFO - 'system_bits': '32', 07:57:53 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:57:53 INFO - 'title': 't-yosemite-r7-0361', 07:57:53 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:57:53 INFO - 'use_talos_json': True, 07:57:53 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:57:53 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:57:53 INFO - 'work_dir': 'build'} 07:57:53 INFO - [mozharness: 2016-12-23 15:57:53.476889Z] Running clobber step. 07:57:53 INFO - Running pre-action listener: _resource_record_pre_action 07:57:53 INFO - Running main action method: clobber 07:57:53 INFO - rmtree: /builds/slave/test/build 07:57:53 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:57:55 INFO - Running post-action listener: _resource_record_post_action 07:57:55 INFO - [mozharness: 2016-12-23 15:57:55.862482Z] Finished clobber step (success) 07:57:55 INFO - [mozharness: 2016-12-23 15:57:55.862620Z] Running read-buildbot-config step. 07:57:55 INFO - Running pre-action listener: _resource_record_pre_action 07:57:55 INFO - Running main action method: read_buildbot_config 07:57:55 INFO - Using buildbot properties: 07:57:55 INFO - { 07:57:55 INFO - "project": "", 07:57:55 INFO - "product": "firefox", 07:57:55 INFO - "script_repo_revision": "production", 07:57:55 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 07:57:55 INFO - "repository": "", 07:57:55 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 07:57:55 INFO - "buildid": "20161223062836", 07:57:55 INFO - "pgo_build": "False", 07:57:55 INFO - "basedir": "/builds/slave/test", 07:57:55 INFO - "buildnumber": 158, 07:57:55 INFO - "slavename": "t-yosemite-r7-0361", 07:57:55 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 07:57:55 INFO - "platform": "yosemite_r7", 07:57:55 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 07:57:55 INFO - "branch": "mozilla-central", 07:57:55 INFO - "repo_path": "mozilla-central", 07:57:55 INFO - "revision": "bbbd2f7539f224a482cc6d2dd10e6a5f31c8baf3", 07:57:55 INFO - "stage_platform": "macosx64", 07:57:55 INFO - "builduid": "5178cd0d87334f38ae7bcbdcb11e3629", 07:57:55 INFO - "slavebuilddir": "test" 07:57:55 INFO - } 07:57:55 INFO - Found installer url https://queue.taskcluster.net/v1/task/OVun-xbOTUS6TnAaqtLVfA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 07:57:55 INFO - Running post-action listener: _resource_record_post_action 07:57:55 INFO - [mozharness: 2016-12-23 15:57:55.866064Z] Finished read-buildbot-config step (success) 07:57:55 INFO - [mozharness: 2016-12-23 15:57:55.866202Z] Running download-and-extract step. 07:57:55 INFO - Running pre-action listener: _resource_record_pre_action 07:57:55 INFO - Running main action method: download_and_extract 07:57:55 INFO - mkdir: /builds/slave/test/build/tests 07:57:55 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')]} 07:57:55 INFO - trying https://queue.taskcluster.net/v1/task/OVun-xbOTUS6TnAaqtLVfA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 07:57:55 INFO - Downloading https://queue.taskcluster.net/v1/task/OVun-xbOTUS6TnAaqtLVfA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 07:57:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OVun-xbOTUS6TnAaqtLVfA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 07:57:56 INFO - Downloaded 1199 bytes. 07:57:56 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 07:57:56 INFO - Using the following test package requirements: 07:57:56 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 07:57:56 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:57:56 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 07:57:56 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:57:56 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 07:57:56 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:57:56 INFO - u'jsshell-mac.zip'], 07:57:56 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:57:56 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 07:57:56 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 07:57:56 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:57:56 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 07:57:56 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:57:56 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 07:57:56 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:57:56 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 07:57:56 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:57:56 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 07:57:56 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 07:57:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OVun-xbOTUS6TnAaqtLVfA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 07:57:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OVun-xbOTUS6TnAaqtLVfA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:57:56 INFO - Fetch https://queue.taskcluster.net/v1/task/OVun-xbOTUS6TnAaqtLVfA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 07:57:57 INFO - Expected file size: 16289478 07:57:57 INFO - Obtained file size: 16289478 07:58:04 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 07:58:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OVun-xbOTUS6TnAaqtLVfA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 07:58:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OVun-xbOTUS6TnAaqtLVfA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:58:04 INFO - Fetch https://queue.taskcluster.net/v1/task/OVun-xbOTUS6TnAaqtLVfA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 07:58:05 INFO - Expected file size: 16289478 07:58:05 INFO - Obtained file size: 16289478 07:58:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OVun-xbOTUS6TnAaqtLVfA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 07:58:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OVun-xbOTUS6TnAaqtLVfA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 07:58:11 INFO - Fetch https://queue.taskcluster.net/v1/task/OVun-xbOTUS6TnAaqtLVfA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 07:58:12 INFO - Expected file size: 14409776 07:58:12 INFO - Obtained file size: 14409776 07:58:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:58:12 INFO - trying https://queue.taskcluster.net/v1/task/OVun-xbOTUS6TnAaqtLVfA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 07:58:12 INFO - Downloading https://queue.taskcluster.net/v1/task/OVun-xbOTUS6TnAaqtLVfA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 07:58:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OVun-xbOTUS6TnAaqtLVfA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 07:58:57 WARNING - Socket error when accessing https://queue.taskcluster.net/v1/task/OVun-xbOTUS6TnAaqtLVfA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg: The read operation timed out 07:58:57 INFO - retry: attempt #1 caught exception: The read operation timed out 07:58:57 INFO - retry: Failed, sleeping 30 seconds before retrying 07:59:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OVun-xbOTUS6TnAaqtLVfA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #2 07:59:33 INFO - Downloaded 69694163 bytes. 07:59:33 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/OVun-xbOTUS6TnAaqtLVfA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 07:59:33 INFO - mkdir: /builds/slave/test/properties 07:59:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:59:33 INFO - Writing to file /builds/slave/test/properties/build_url 07:59:33 INFO - Contents: 07:59:33 INFO - build_url:https://queue.taskcluster.net/v1/task/OVun-xbOTUS6TnAaqtLVfA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 07:59:34 INFO - Running post-action listener: _resource_record_post_action 07:59:34 INFO - Running post-action listener: set_extra_try_arguments 07:59:34 INFO - [mozharness: 2016-12-23 15:59:34.445880Z] Finished download-and-extract step (success) 07:59:34 INFO - [mozharness: 2016-12-23 15:59:34.446003Z] Running populate-webroot step. 07:59:34 INFO - Running pre-action listener: _resource_record_pre_action 07:59:34 INFO - Running main action method: populate_webroot 07:59:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:59:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:59:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:59:34 INFO - '--tppagecycles', 07:59:34 INFO - '7'], 07:59:34 INFO - 'tests': ['tsvgm']}}, 07:59:34 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:59:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:59:34 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:59:34 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:59:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:59:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:59:34 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 07:59:34 INFO - 'talos_options': ['--disable-e10s'], 07:59:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:59:34 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 07:59:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:59:34 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 07:59:34 INFO - 'talos_options': ['--disable-e10s'], 07:59:34 INFO - 'tests': ['damp', 'tps']}, 07:59:34 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 07:59:34 INFO - 'tests': ['damp', 'tps']}, 07:59:34 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:59:34 INFO - 'tests': ['dromaeo_dom']}, 07:59:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:59:34 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:59:34 INFO - 'tests': ['basic_compositor_video']}, 07:59:34 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:59:34 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:59:34 INFO - 'tests': ['a11yr', 07:59:34 INFO - 'ts_paint', 07:59:34 INFO - 'tpaint', 07:59:34 INFO - 'sessionrestore', 07:59:34 INFO - 'sessionrestore_no_auto_restore', 07:59:34 INFO - 'tabpaint']}, 07:59:34 INFO - 'other-e10s': {'tests': ['a11yr', 07:59:34 INFO - 'ts_paint', 07:59:34 INFO - 'tpaint', 07:59:34 INFO - 'sessionrestore', 07:59:34 INFO - 'sessionrestore_no_auto_restore', 07:59:34 INFO - 'tabpaint']}, 07:59:34 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:59:34 INFO - 'ts_paint', 07:59:34 INFO - 'tpaint', 07:59:34 INFO - 'sessionrestore', 07:59:34 INFO - 'sessionrestore_no_auto_restore', 07:59:34 INFO - 'tabpaint']}, 07:59:34 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:59:34 INFO - 'ts_paint', 07:59:34 INFO - 'tpaint', 07:59:34 INFO - 'sessionrestore', 07:59:34 INFO - 'sessionrestore_no_auto_restore', 07:59:34 INFO - 'tabpaint']}, 07:59:34 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:59:34 INFO - 'tests': ['a11yr', 07:59:34 INFO - 'ts_paint', 07:59:34 INFO - 'tpaint', 07:59:34 INFO - 'sessionrestore', 07:59:34 INFO - 'sessionrestore_no_auto_restore', 07:59:34 INFO - 'tabpaint']}, 07:59:34 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:59:34 INFO - 'tests': ['a11yr', 07:59:34 INFO - 'ts_paint', 07:59:34 INFO - 'tpaint', 07:59:34 INFO - 'sessionrestore', 07:59:34 INFO - 'sessionrestore_no_auto_restore', 07:59:34 INFO - 'tabpaint']}, 07:59:34 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:59:34 INFO - 'tests': ['tsvgx', 07:59:34 INFO - 'tsvgr_opacity', 07:59:34 INFO - 'tart', 07:59:34 INFO - 'tscrollx', 07:59:34 INFO - 'cart', 07:59:34 INFO - 'tsvg_static']}, 07:59:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:59:34 INFO - 'tsvgr_opacity', 07:59:34 INFO - 'tart', 07:59:34 INFO - 'tscrollx', 07:59:34 INFO - 'cart', 07:59:34 INFO - 'tsvg_static']}, 07:59:34 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 07:59:34 INFO - 'talos_options': ['--disable-e10s'], 07:59:34 INFO - 'tests': ['tp5o']}, 07:59:34 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 07:59:34 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 07:59:34 INFO - 'talos_options': ['--disable-e10s', 07:59:34 INFO - '--xperf_path', 07:59:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:59:34 INFO - 'tests': ['tp5n']}, 07:59:34 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 07:59:34 INFO - 'talos_options': ['--xperf_path', 07:59:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:59:34 INFO - 'tests': ['tp5n']}}, 07:59:34 INFO - 'talos.zip': {'path': '', 07:59:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:59:34 INFO - Running post-action listener: _resource_record_post_action 07:59:34 INFO - [mozharness: 2016-12-23 15:59:34.464110Z] Finished populate-webroot step (success) 07:59:34 INFO - [mozharness: 2016-12-23 15:59:34.464216Z] Running create-virtualenv step. 07:59:34 INFO - Running pre-action listener: _resource_record_pre_action 07:59:34 INFO - Running main action method: create_virtualenv 07:59:34 INFO - Creating virtualenv /builds/slave/test/build/venv 07:59:34 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 07:59:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:59:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:59:34 INFO - Using real prefix '/tools/python27' 07:59:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:59:35 INFO - Installing distribute.............................................................................................................................................................................................done. 07:59:38 INFO - Installing pip.................done. 07:59:38 INFO - Return code: 0 07:59:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:59:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:59:38 INFO - Reading from file tmpfile_stdout 07:59:38 INFO - Output received: 07:59:38 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) 07:59:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:59:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:59:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:59:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:59:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:59:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:59:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:59:38 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 0x10fd37b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd3a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa4e1485c90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb15d30>, '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 0x10fa3cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fb0abe8>, '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.0hxTnN1zwb/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.90LM3GUIRR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/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 07:59:38 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 07:59:38 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 07:59:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0hxTnN1zwb/Render', 07:59:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:59:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:59:38 INFO - 'HOME': '/Users/cltbld', 07:59:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:59:38 INFO - 'LOGNAME': 'cltbld', 07:59:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:59:38 INFO - 'MOZ_NO_REMOTE': '1', 07:59:38 INFO - 'NO_EM_RESTART': '1', 07:59:38 INFO - 'PAGER': '/bin/cat', 07:59:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:59:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:59:38 INFO - 'PWD': '/builds/slave/test', 07:59:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:59:38 INFO - 'SHELL': '/bin/bash', 07:59:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.90LM3GUIRR/Listeners', 07:59:38 INFO - 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/', 07:59:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:59:38 INFO - 'USER': 'cltbld', 07:59:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:59:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:59:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:59:38 INFO - 'XPC_FLAGS': '0x0', 07:59:38 INFO - 'XPC_SERVICE_NAME': '0', 07:59:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:59:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:59:38 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 07:59:38 INFO - Cleaning up... 07:59:38 INFO - Return code: 0 07:59:38 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:59:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:59:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:59:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:59:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:59:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:59:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:59:38 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 0x10fd37b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd3a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa4e1485c90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb15d30>, '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 0x10fa3cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fb0abe8>, '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.0hxTnN1zwb/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.90LM3GUIRR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/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 07:59:38 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 07:59:38 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 07:59:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0hxTnN1zwb/Render', 07:59:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:59:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:59:38 INFO - 'HOME': '/Users/cltbld', 07:59:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:59:38 INFO - 'LOGNAME': 'cltbld', 07:59:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:59:38 INFO - 'MOZ_NO_REMOTE': '1', 07:59:38 INFO - 'NO_EM_RESTART': '1', 07:59:38 INFO - 'PAGER': '/bin/cat', 07:59:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:59:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:59:38 INFO - 'PWD': '/builds/slave/test', 07:59:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:59:38 INFO - 'SHELL': '/bin/bash', 07:59:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.90LM3GUIRR/Listeners', 07:59:38 INFO - 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/', 07:59:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:59:38 INFO - 'USER': 'cltbld', 07:59:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:59:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:59:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:59:38 INFO - 'XPC_FLAGS': '0x0', 07:59:38 INFO - 'XPC_SERVICE_NAME': '0', 07:59:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:59:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:59:38 INFO - Downloading/unpacking psutil>=3.1.1 07:59:38 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 07:59:38 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 07:59:38 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 07:59:38 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 07:59:38 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 07:59:38 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 07:59:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:59:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:59:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:59:43 INFO - Installing collected packages: psutil 07:59:43 INFO - Running setup.py install for psutil 07:59:43 INFO - building 'psutil._psutil_osx' extension 07:59:43 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 07:59:43 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 07:59:43 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 07:59:43 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 07:59:43 INFO - building 'psutil._psutil_posix' extension 07:59:43 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 07:59:43 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:59:43 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:59:43 INFO - ^ 07:59:43 INFO - 1 warning generated. 07:59:43 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 07:59:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:59:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:59:44 INFO - Successfully installed psutil 07:59:44 INFO - Cleaning up... 07:59:44 INFO - Return code: 0 07:59:44 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:59: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')]} 07:59:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:59:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:59: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')]} 07:59:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:59:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:59:44 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 0x10fd37b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd3a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa4e1485c90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb15d30>, '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 0x10fa3cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fb0abe8>, '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.0hxTnN1zwb/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.90LM3GUIRR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/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 07:59:44 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 07:59:44 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 07:59:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0hxTnN1zwb/Render', 07:59:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:59:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:59:44 INFO - 'HOME': '/Users/cltbld', 07:59:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:59:44 INFO - 'LOGNAME': 'cltbld', 07:59:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:59:44 INFO - 'MOZ_NO_REMOTE': '1', 07:59:44 INFO - 'NO_EM_RESTART': '1', 07:59:44 INFO - 'PAGER': '/bin/cat', 07:59:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:59:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:59:44 INFO - 'PWD': '/builds/slave/test', 07:59:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:59:44 INFO - 'SHELL': '/bin/bash', 07:59:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.90LM3GUIRR/Listeners', 07:59:44 INFO - 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/', 07:59:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:59:44 INFO - 'USER': 'cltbld', 07:59:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:59:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:59:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:59:44 INFO - 'XPC_FLAGS': '0x0', 07:59:44 INFO - 'XPC_SERVICE_NAME': '0', 07:59:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:59:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:59:44 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:59:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:59:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:59:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:59:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:59:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:59:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:59:47 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:59:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:59:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:59:47 INFO - Installing collected packages: mozsystemmonitor 07:59:47 INFO - Running setup.py install for mozsystemmonitor 07:59:47 INFO - Successfully installed mozsystemmonitor 07:59:47 INFO - Cleaning up... 07:59:47 INFO - Return code: 0 07:59:47 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:59:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:59:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:59:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:59:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:59:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:59:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:59:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10fd37b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd3a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa4e1485c90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb15d30>, '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 0x10fa3cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fb0abe8>, '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.0hxTnN1zwb/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.90LM3GUIRR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/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 07:59:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 07:59:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 07:59:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0hxTnN1zwb/Render', 07:59:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:59:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:59:47 INFO - 'HOME': '/Users/cltbld', 07:59:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:59:47 INFO - 'LOGNAME': 'cltbld', 07:59:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:59:47 INFO - 'MOZ_NO_REMOTE': '1', 07:59:47 INFO - 'NO_EM_RESTART': '1', 07:59:47 INFO - 'PAGER': '/bin/cat', 07:59:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:59:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:59:47 INFO - 'PWD': '/builds/slave/test', 07:59:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:59:47 INFO - 'SHELL': '/bin/bash', 07:59:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.90LM3GUIRR/Listeners', 07:59:47 INFO - 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/', 07:59:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:59:47 INFO - 'USER': 'cltbld', 07:59:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:59:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:59:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:59:47 INFO - 'XPC_FLAGS': '0x0', 07:59:47 INFO - 'XPC_SERVICE_NAME': '0', 07:59:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:59:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:59:47 INFO - Downloading/unpacking jsonschema==2.5.1 07:59:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:59:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:59:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:59:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:59:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:59:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:59:50 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:59:50 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:59: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 07:59: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 07:59: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 07:59: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 07:59: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 07:59: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 07:59:50 INFO - Downloading functools32-3.2.3-2.tar.gz 07:59:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:59:50 INFO - warning: no files found matching '*.txt' 07:59:50 INFO - no previously-included directories found matching 'build' 07:59:50 INFO - no previously-included directories found matching 'dist' 07:59:50 INFO - no previously-included directories found matching '.git*' 07:59:50 INFO - Installing collected packages: jsonschema, functools32 07:59:51 INFO - Running setup.py install for functools32 07:59:51 INFO - warning: no files found matching '*.txt' 07:59:51 INFO - no previously-included directories found matching 'build' 07:59:51 INFO - no previously-included directories found matching 'dist' 07:59:51 INFO - no previously-included directories found matching '.git*' 07:59:51 INFO - Successfully installed jsonschema functools32 07:59:51 INFO - Cleaning up... 07:59:51 INFO - Return code: 0 07:59:51 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:59:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:59:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:59:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:59:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:59:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:59:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:59:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10fd37b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd3a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa4e1485c90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb15d30>, '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 0x10fa3cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fb0abe8>, '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.0hxTnN1zwb/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.90LM3GUIRR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/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 07:59:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 07:59:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 07:59:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0hxTnN1zwb/Render', 07:59:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:59:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:59:51 INFO - 'HOME': '/Users/cltbld', 07:59:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:59:51 INFO - 'LOGNAME': 'cltbld', 07:59:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:59:51 INFO - 'MOZ_NO_REMOTE': '1', 07:59:51 INFO - 'NO_EM_RESTART': '1', 07:59:51 INFO - 'PAGER': '/bin/cat', 07:59:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:59:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:59:51 INFO - 'PWD': '/builds/slave/test', 07:59:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:59:51 INFO - 'SHELL': '/bin/bash', 07:59:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.90LM3GUIRR/Listeners', 07:59:51 INFO - 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/', 07:59:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:59:51 INFO - 'USER': 'cltbld', 07:59:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:59:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:59:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:59:51 INFO - 'XPC_FLAGS': '0x0', 07:59:51 INFO - 'XPC_SERVICE_NAME': '0', 07:59:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:59:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:59:51 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:59:51 INFO - Cleaning up... 07:59:51 INFO - Return code: 0 07:59:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:59:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:59:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:59:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:59:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:59:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:59:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:59:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10fd37b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd3a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa4e1485c90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb15d30>, '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 0x10fa3cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fb0abe8>, '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.0hxTnN1zwb/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.90LM3GUIRR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/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 07:59:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:59:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 07:59:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0hxTnN1zwb/Render', 07:59:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:59:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:59:51 INFO - 'HOME': '/Users/cltbld', 07:59:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:59:51 INFO - 'LOGNAME': 'cltbld', 07:59:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:59:51 INFO - 'MOZ_NO_REMOTE': '1', 07:59:51 INFO - 'NO_EM_RESTART': '1', 07:59:51 INFO - 'PAGER': '/bin/cat', 07:59:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:59:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:59:51 INFO - 'PWD': '/builds/slave/test', 07:59:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:59:51 INFO - 'SHELL': '/bin/bash', 07:59:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.90LM3GUIRR/Listeners', 07:59:51 INFO - 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/', 07:59:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:59:51 INFO - 'USER': 'cltbld', 07:59:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:59:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:59:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:59:51 INFO - 'XPC_FLAGS': '0x0', 07:59:51 INFO - 'XPC_SERVICE_NAME': '0', 07:59:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:59:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:59:51 INFO - Downloading/unpacking blobuploader==1.2.4 07:59:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:59:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:59:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:59:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:59:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:59:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:59:54 INFO - Downloading blobuploader-1.2.4.tar.gz 07:59:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:59:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:59:54 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 07:59:54 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 07:59:54 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 07:59:54 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 07:59:54 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 07:59:54 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 07:59:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:59:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:59: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 07:59: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 07:59: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 07:59: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 07:59: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 07:59: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 07:59:55 INFO - Downloading docopt-0.6.1.tar.gz 07:59:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:59:55 INFO - Installing collected packages: blobuploader, requests, docopt 07:59:55 INFO - Running setup.py install for blobuploader 07:59:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:59:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:59:55 INFO - Running setup.py install for requests 07:59:56 INFO - Running setup.py install for docopt 07:59:56 INFO - Successfully installed blobuploader requests docopt 07:59:56 INFO - Cleaning up... 07:59:56 INFO - Return code: 0 07:59:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:59:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:59:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:59:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:59:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:59:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:59:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:59:56 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 0x10fd37b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd3a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa4e1485c90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb15d30>, '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 0x10fa3cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fb0abe8>, '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.0hxTnN1zwb/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.90LM3GUIRR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/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 07:59:56 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 07:59:56 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 07:59:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0hxTnN1zwb/Render', 07:59:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:59:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:59:56 INFO - 'HOME': '/Users/cltbld', 07:59:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:59:56 INFO - 'LOGNAME': 'cltbld', 07:59:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:59:56 INFO - 'MOZ_NO_REMOTE': '1', 07:59:56 INFO - 'NO_EM_RESTART': '1', 07:59:56 INFO - 'PAGER': '/bin/cat', 07:59:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:59:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:59:56 INFO - 'PWD': '/builds/slave/test', 07:59:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:59:56 INFO - 'SHELL': '/bin/bash', 07:59:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.90LM3GUIRR/Listeners', 07:59:56 INFO - 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/', 07:59:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:59:56 INFO - 'USER': 'cltbld', 07:59:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:59:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:59:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:59:56 INFO - 'XPC_FLAGS': '0x0', 07:59:56 INFO - 'XPC_SERVICE_NAME': '0', 07:59:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:59:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:59:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:59:56 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-Cx7VLa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:59:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:59:57 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-4wVNCZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:59:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:59:57 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-W0ZO4R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:59:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:59:57 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-pq3maG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:59:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:59:57 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-ikBePE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:59:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:59:57 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-g4fDWN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:59:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:59:57 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-nnIo8t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:59:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:59:57 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-ZdSMXA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:59:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:59:57 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-VmOYxt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:59:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:59:57 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-RetkYw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:59:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:59:57 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-7RySsa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:59:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:59:58 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-wnO5d0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:59:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:59:58 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-r9_s_7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:59:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:59:58 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-RuferC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:59:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:59:58 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-tAXqBG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:59:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:59:58 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-e03ZpF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:59:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:59:58 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-eysY1B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:59:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:59:58 INFO - Running setup.py install for manifestparser 07:59:58 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:59:58 INFO - Running setup.py install for mozcrash 07:59:58 INFO - Running setup.py install for mozdebug 07:59:59 INFO - Running setup.py install for mozdevice 07:59:59 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:59:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:59:59 INFO - Running setup.py install for mozfile 07:59:59 INFO - Running setup.py install for mozhttpd 07:59:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:59:59 INFO - Running setup.py install for mozinfo 07:59:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:59:59 INFO - Running setup.py install for mozInstall 07:59:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:59:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:59:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:59:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:59:59 INFO - Running setup.py install for mozleak 08:00:00 INFO - Running setup.py install for mozlog 08:00:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:00:00 INFO - Running setup.py install for moznetwork 08:00:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:00:00 INFO - Running setup.py install for mozprocess 08:00:00 INFO - Running setup.py install for mozprofile 08:00:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:00:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:00:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:00:00 INFO - Running setup.py install for mozrunner 08:00:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:00:00 INFO - Running setup.py install for mozscreenshot 08:00:00 INFO - Running setup.py install for moztest 08:00:01 INFO - Running setup.py install for mozversion 08:00:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:00:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:00:01 INFO - Cleaning up... 08:00:01 INFO - Return code: 0 08:00:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:00: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')]} 08:00:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:00:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00: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')]} 08:00:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:00:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:01 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 0x10fd37b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd3a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa4e1485c90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb15d30>, '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 0x10fa3cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fb0abe8>, '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.0hxTnN1zwb/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.90LM3GUIRR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/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 08:00:01 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 08:00:01 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 08:00:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0hxTnN1zwb/Render', 08:00:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:00:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:00:01 INFO - 'HOME': '/Users/cltbld', 08:00:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:00:01 INFO - 'LOGNAME': 'cltbld', 08:00:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:00:01 INFO - 'MOZ_NO_REMOTE': '1', 08:00:01 INFO - 'NO_EM_RESTART': '1', 08:00:01 INFO - 'PAGER': '/bin/cat', 08:00:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:00:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:00:01 INFO - 'PWD': '/builds/slave/test', 08:00:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:00:01 INFO - 'SHELL': '/bin/bash', 08:00:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.90LM3GUIRR/Listeners', 08:00:01 INFO - 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/', 08:00:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:00:01 INFO - 'USER': 'cltbld', 08:00:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:00:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:00:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:00:01 INFO - 'XPC_FLAGS': '0x0', 08:00:01 INFO - 'XPC_SERVICE_NAME': '0', 08:00:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:00:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:00:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:00:01 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-CmLTFu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:00:01 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)) 08:00:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:00:01 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-aPKdt_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:00:01 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)) 08:00:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:00:01 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-mvg4y1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:00:01 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)) 08:00:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:00:01 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-3WS8GR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:00:01 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)) 08:00:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:00:01 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-aXdZMr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:00:02 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)) 08:00:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:00:02 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-JsZqrj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:00:02 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)) 08:00:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:00:02 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-TGPp9s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:00:02 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)) 08:00:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:00:02 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-WGF1b5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:00:02 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)) 08:00:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:00:02 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-k7Nqbe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:00:02 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)) 08:00:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:00:02 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-yBNl4M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:00:02 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)) 08:00:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:00:02 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-9POQRN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:00:02 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)) 08:00:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:00:02 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-No3CtA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:00:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 08:00:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:00:02 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-NhIyA7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:00:02 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)) 08:00:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:00:02 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-Q_Ud2T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:00:03 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)) 08:00:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:00:03 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-q7E67C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:00:03 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)) 08:00:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:00:03 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-asqjli-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:00:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 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)) 08:00:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:00:03 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-H_dg0l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:00:03 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)) 08:00:03 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)) 08:00:03 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)) 08:00:03 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)) 08:00:03 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)) 08:00:03 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)) 08:00:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:00:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:00:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:00:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:05 INFO - Downloading blessings-1.6.tar.gz 08:00:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:00:06 INFO - Installing collected packages: blessings 08:00:06 INFO - Running setup.py install for blessings 08:00:06 INFO - Successfully installed blessings 08:00:06 INFO - Cleaning up... 08:00:06 INFO - Return code: 0 08:00:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:00:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:00:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:00:06 INFO - Reading from file tmpfile_stdout 08:00:06 INFO - Current package versions: 08:00:06 INFO - blessings == 1.6 08:00:06 INFO - blobuploader == 1.2.4 08:00:06 INFO - docopt == 0.6.1 08:00:06 INFO - functools32 == 3.2.3-2 08:00:06 INFO - jsonschema == 2.5.1 08:00:06 INFO - manifestparser == 1.1 08:00:06 INFO - mozInstall == 1.12 08:00:06 INFO - mozcrash == 1.0 08:00:06 INFO - mozdebug == 0.1 08:00:06 INFO - mozdevice == 0.49 08:00:06 INFO - mozfile == 1.2 08:00:06 INFO - mozhttpd == 0.7 08:00:06 INFO - mozinfo == 0.9 08:00:06 INFO - mozleak == 0.1 08:00:06 INFO - mozlog == 3.4 08:00:06 INFO - moznetwork == 0.27 08:00:06 INFO - mozprocess == 0.23 08:00:06 INFO - mozprofile == 0.28 08:00:06 INFO - mozrunner == 6.13 08:00:06 INFO - mozscreenshot == 0.1 08:00:06 INFO - mozsystemmonitor == 0.3 08:00:06 INFO - moztest == 0.7 08:00:06 INFO - mozversion == 1.4 08:00:06 INFO - psutil == 3.1.1 08:00:06 INFO - requests == 1.2.3 08:00:06 INFO - wsgiref == 0.1.2 08:00:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:00:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:00:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:00:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:00:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:00:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:06 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 0x10fd37b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd3a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa4e1485c90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb15d30>, '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 0x10fa3cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fb0abe8>, '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.0hxTnN1zwb/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.90LM3GUIRR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/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 08:00:06 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 08:00:06 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 08:00:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0hxTnN1zwb/Render', 08:00:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:00:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:00:06 INFO - 'HOME': '/Users/cltbld', 08:00:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:00:06 INFO - 'LOGNAME': 'cltbld', 08:00:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:00:06 INFO - 'MOZ_NO_REMOTE': '1', 08:00:06 INFO - 'NO_EM_RESTART': '1', 08:00:06 INFO - 'PAGER': '/bin/cat', 08:00:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:00:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:00:06 INFO - 'PWD': '/builds/slave/test', 08:00:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:00:06 INFO - 'SHELL': '/bin/bash', 08:00:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.90LM3GUIRR/Listeners', 08:00:06 INFO - 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/', 08:00:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:00:06 INFO - 'USER': 'cltbld', 08:00:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:00:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:00:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:00:06 INFO - 'XPC_FLAGS': '0x0', 08:00:06 INFO - 'XPC_SERVICE_NAME': '0', 08:00:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:00:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:00:06 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)) 08:00:06 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)) 08:00:06 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)) 08:00:06 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)) 08:00:06 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)) 08:00:06 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)) 08:00:06 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)) 08:00:06 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)) 08:00:06 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)) 08:00:06 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)) 08:00:06 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)) 08:00:06 INFO - Cleaning up... 08:00:06 INFO - Return code: 0 08:00:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:00:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:00:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:00:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:00:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:00:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:06 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 0x10fd37b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd3a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa4e1485c90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb15d30>, '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 0x10fa3cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fb0abe8>, '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.0hxTnN1zwb/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.90LM3GUIRR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/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 08:00:06 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 08:00:06 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 08:00:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0hxTnN1zwb/Render', 08:00:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:00:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:00:06 INFO - 'HOME': '/Users/cltbld', 08:00:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:00:06 INFO - 'LOGNAME': 'cltbld', 08:00:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:00:06 INFO - 'MOZ_NO_REMOTE': '1', 08:00:06 INFO - 'NO_EM_RESTART': '1', 08:00:06 INFO - 'PAGER': '/bin/cat', 08:00:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:00:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:00:06 INFO - 'PWD': '/builds/slave/test', 08:00:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:00:06 INFO - 'SHELL': '/bin/bash', 08:00:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.90LM3GUIRR/Listeners', 08:00:06 INFO - 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/', 08:00:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:00:06 INFO - 'USER': 'cltbld', 08:00:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:00:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:00:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:00:06 INFO - 'XPC_FLAGS': '0x0', 08:00:06 INFO - 'XPC_SERVICE_NAME': '0', 08:00:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:00:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:00:07 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:00:07 INFO - Cleaning up... 08:00:07 INFO - Return code: 0 08:00:07 INFO - Running post-action listener: _resource_record_post_action 08:00:07 INFO - Running post-action listener: _start_resource_monitoring 08:00:07 INFO - Starting resource monitoring. 08:00:07 INFO - [mozharness: 2016-12-23 16:00:07.168469Z] Finished create-virtualenv step (success) 08:00:07 INFO - [mozharness: 2016-12-23 16:00:07.169413Z] Running install step. 08:00:07 INFO - Running pre-action listener: _resource_record_pre_action 08:00:07 INFO - Running main action method: install 08:00:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:00:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:00:07 INFO - Reading from file tmpfile_stdout 08:00:07 INFO - Detecting whether we're running mozinstall >=1.0... 08:00:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:00:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:00:07 INFO - Reading from file tmpfile_stdout 08:00:07 INFO - Output received: 08:00:07 INFO - Usage: mozinstall [options] installer 08:00:07 INFO - Options: 08:00:07 INFO - -h, --help show this help message and exit 08:00:07 INFO - -d DEST, --destination=DEST 08:00:07 INFO - Directory to install application into. [default: 08:00:07 INFO - "/builds/slave/test"] 08:00:07 INFO - --app=APP Application being installed. [default: firefox] 08:00:07 INFO - mkdir: /builds/slave/test/build/application 08:00:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:00:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 08:00:29 INFO - Reading from file tmpfile_stdout 08:00:29 INFO - Output received: 08:00:29 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:00:29 INFO - Running post-action listener: _resource_record_post_action 08:00:29 INFO - [mozharness: 2016-12-23 16:00:29.346116Z] Finished install step (success) 08:00:29 INFO - [mozharness: 2016-12-23 16:00:29.346276Z] Running run-tests step. 08:00:29 INFO - Running pre-action listener: _resource_record_pre_action 08:00:29 INFO - Running main action method: run_tests 08:00:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:00:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:00:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:00:29 INFO - 2016-12-23 08:00:29.360 screenresolution[1642:9291] starting screenresolution argv=screenresolution get 08:00:29 INFO - 2016-12-23 08:00:29.363 screenresolution[1642:9291] Display 0: 1600x1200x32@60 08:00:29 INFO - 2016-12-23 08:00:29.368 screenresolution[1643:9293] starting screenresolution argv=screenresolution list 08:00:29 INFO - Available Modes on Display 0 08:00:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:00:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:00:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:00:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:00:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:00:29 INFO - Intel Iris: 08:00:29 INFO - Chipset Model: Intel Iris 08:00:29 INFO - Type: GPU 08:00:29 INFO - Bus: Built-In 08:00:29 INFO - VRAM (Dynamic, Max): 1536 MB 08:00:29 INFO - Vendor: Intel (0x8086) 08:00:29 INFO - Device ID: 0x0a2e 08:00:29 INFO - Revision ID: 0x0009 08:00:29 INFO - Displays: 08:00:29 INFO - Display: 08:00:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:00:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:00:29 INFO - Display Serial Number: 1600x1200 60 08:00:29 INFO - Main Display: Yes 08:00:29 INFO - Mirror: Off 08:00:29 INFO - Online: Yes 08:00:29 INFO - Rotation: Supported 08:00:29 INFO - Return code: 0 08:00:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:00:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:00:29 INFO - Python 2.7.3 08:00:29 INFO - Return code: 0 08:00:29 INFO - grabbing minidump binary from tooltool 08:00:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:00:29 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 0x10fb15d30>, '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 0x10fa3cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fb0abe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:00:29 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 08:00:29 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 08:00:29 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 08:00:29 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:00:29 INFO - Return code: 0 08:00:29 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:00:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:00:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:00:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:00:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:00:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OVun-xbOTUS6TnAaqtLVfA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0361', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 08:00:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/OVun-xbOTUS6TnAaqtLVfA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0361 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 08:00:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0hxTnN1zwb/Render', 08:00:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:00:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:00:29 INFO - 'HOME': '/Users/cltbld', 08:00:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:00:29 INFO - 'LOGNAME': 'cltbld', 08:00:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:00:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:00:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:00:29 INFO - 'MOZ_NO_REMOTE': '1', 08:00:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:00:29 INFO - 'NO_EM_RESTART': '1', 08:00:29 INFO - 'PAGER': '/bin/cat', 08:00:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:00:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:00:29 INFO - 'PWD': '/builds/slave/test', 08:00:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:00:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:00:29 INFO - 'SHELL': '/bin/bash', 08:00:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.90LM3GUIRR/Listeners', 08:00:29 INFO - 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/', 08:00:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:00:29 INFO - 'USER': 'cltbld', 08:00:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:00:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:00:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:00:29 INFO - 'XPC_FLAGS': '0x0', 08:00:29 INFO - 'XPC_SERVICE_NAME': '0', 08:00:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:00:29 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OVun-xbOTUS6TnAaqtLVfA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0361', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 08:00:29 INFO - mozversion application_buildid: 20161223062836 08:00:29 INFO - mozversion application_changeset: bbbd2f7539f224a482cc6d2dd10e6a5f31c8baf3 08:00:29 INFO - mozversion application_display_name: Nightly 08:00:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:00:29 INFO - mozversion application_name: Firefox 08:00:29 INFO - mozversion application_remotingname: firefox 08:00:29 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:00:29 INFO - mozversion application_vendor: Mozilla 08:00:29 INFO - mozversion application_version: 53.0a1 08:00:29 INFO - mozversion platform_buildid: 20161223062836 08:00:29 INFO - mozversion platform_changeset: bbbd2f7539f224a482cc6d2dd10e6a5f31c8baf3 08:00:29 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:00:29 INFO - mozversion platform_version: 53.0a1 08:00:29 INFO - using testdate: 1482508829 08:00:29 INFO - actual date: 1482508829 08:00:29 INFO - starting webserver on 'localhost:49251' 08:00:29 INFO - SUITE-START | Running 2 tests 08:00:29 INFO - TEST-START | dromaeo_css 08:00:29 INFO - operating with platform_type : mac_ 08:00:29 INFO - Initialising browser for dromaeo_css test... 08:00:29 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/tmpzuVgXM/profile http://localhost:49251/getInfo.html) 08:00:30 INFO - PROCESS | 1653 | 1482508830483 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4979 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3825 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2829 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 08:00:31 INFO - PROCESS | 1653 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 08:00:31 INFO - PROCESS | 1653 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 08:00:32 INFO - PROCESS | 1653 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 08:00:32 INFO - PROCESS | 1653 | __metrics Screen width/height:1600/1200 08:00:32 INFO - PROCESS | 1653 | colorDepth:24 08:00:32 INFO - PROCESS | 1653 | Browser inner width/height: 1024/689 08:00:32 INFO - PROCESS | 1653 | __metrics 08:00:32 INFO - PROCESS | 1653 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:00:32 INFO - PROCESS | 1653 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:00:33 INFO - TEST-INFO | 1653: exit 0 08:00:33 INFO - Browser initialized. 08:00:33 INFO - Running cycle 1/1 for dromaeo_css test... 08:00:33 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/tmpzuVgXM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:00:45 INFO - PROCESS | 1656 | RSS: Main: 235524096 08:00:45 INFO - PROCESS | 1656 | 08:01:36 INFO - PROCESS | 1656 | 1482508896673 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 08:03:49 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49251/tests/dromaeo/cssquery-ext.html) 08:03:52 INFO - PROCESS | 1656 | RSS: Main: 475066368 08:03:52 INFO - PROCESS | 1656 | 08:05:34 INFO - PROCESS | 1656 | ************************* 08:05:34 INFO - PROCESS | 1656 | A coding exception was thrown and uncaught in a Task. 08:05:34 INFO - PROCESS | 1656 | 08:05:34 INFO - PROCESS | 1656 | Full message: ReferenceError: fetch is not defined 08:05:34 INFO - PROCESS | 1656 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 08:05:34 INFO - PROCESS | 1656 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 08:05:34 INFO - PROCESS | 1656 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 08:05:34 INFO - PROCESS | 1656 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 08:05:34 INFO - PROCESS | 1656 | TaskImpl@resource://gre/modules/Task.jsm:277:3 08:05:34 INFO - PROCESS | 1656 | asyncFunction@resource://gre/modules/Task.jsm:252:14 08:05:34 INFO - PROCESS | 1656 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 08:05:34 INFO - PROCESS | 1656 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 08:05:34 INFO - PROCESS | 1656 | TaskImpl@resource://gre/modules/Task.jsm:277:3 08:05:34 INFO - PROCESS | 1656 | asyncFunction@resource://gre/modules/Task.jsm:252:14 08:05:34 INFO - PROCESS | 1656 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 08:05:34 INFO - PROCESS | 1656 | 08:05:34 INFO - PROCESS | 1656 | ************************* 08:06:55 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-ext.html (next: http://localhost:49251/tests/dromaeo/cssquery-jquery.html) 08:06:56 INFO - PROCESS | 1656 | RSS: Main: 371056640 08:06:56 INFO - PROCESS | 1656 | 08:09:58 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49251/tests/dromaeo/cssquery-mootools.html) 08:09:59 INFO - PROCESS | 1656 | RSS: Main: 349401088 08:09:59 INFO - PROCESS | 1656 | 08:13:01 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49251/tests/dromaeo/cssquery-prototype.html) 08:13:02 INFO - PROCESS | 1656 | RSS: Main: 354934784 08:13:02 INFO - PROCESS | 1656 | 08:16:05 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49251/tests/dromaeo/cssquery-yui.html) 08:16:06 INFO - PROCESS | 1656 | RSS: Main: 337866752 08:16:06 INFO - PROCESS | 1656 | 08:19:09 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-yui.html (next: http://localhost:49251/tests/dromaeo/cssquery-dojo.html) 08:19:09 INFO - PROCESS | 1656 | RSS: Main: 438747136 08:19:09 INFO - PROCESS | 1656 | 08:19:09 INFO - PROCESS | 1656 | __start_tp_report 08:19:09 INFO - PROCESS | 1656 | _x_x_mozilla_page_load 08:19:09 INFO - PROCESS | 1656 | _x_x_mozilla_page_load_details 08:19:09 INFO - PROCESS | 1656 | |i|pagename|runs| 08:19:09 INFO - PROCESS | 1656 | |0;dojo.html;3254.1864533866533;3337.2824842658824;3345.113544910599;3364.42804723197;3385.221399078212;5543.445655434454;5608.158776183569;5650.6892120933335;5666.405027472118;5692.943070569293;5638.126090455986;5672.290963629546;5679;5691.146328050794;5701.6864072476;5133.845984620468;5152.768125434364;5167.638265321429;5191.870203244911;5205.349331333583;4687.624990000809;4959.603231741452;5257.553107985826;5260.184671375943;5267.55225805807;5242.082635538784;5256.737163141846;5260.368755749316;5263.315768950031;5265.8946821063555;5251.36983561975;5257.815976440826;5263.236830659571;5270.209468579744;5274.103402421579;5101.515356041207;5123.846284611468;5200.5059519345605;5205.7397130143345;5215.791368345262;5630.8029218977545;5635.633683810539;5684.459976302244;5724.484796368347;5741.942580574183;6175.999999999955;6185.257769067695;6199.132121502993;6200.379962003809;6204.689765511706;5556.499915007668;6002.339742628307;6128.686460748359;6139.140520327158;6157.661328626924;5710.543156547466;5719.8570035749435;5725.456081672234;5729.369769325371;5733.16869053989;6326.177596912372;6331.715072821734;6332.208473940757;6348.333424990402;6348.714307856158;5867.647941123546;5905.43898329658;5907.320658124285;6012.098185272244;6069.696515174223;6183.134361189437;6216.937830621727;6221.62670239778;6230.7819226326865;6245.469135123474;6061.121137435047;6153.292371377349;6165.815025549241;6168.784092556739;6169.198004259418;5980.760769569256;6184.072389141575;6193;6208.410201030895;6212.968935155295;4836.12949669063;4857.781399837016;4875.07373599015;4892.779824907817;4901.1913034349645;4364.10535840152;4370.541093185233;4379.978100109479;4385.780710964439;4418.226810308183;5761.107028410603;5764.106563482583;5785.218995435646;5785.681787501644;5798.9420105799245;6069.635821850703;6130.356312587114;6132.692058233554;6159.784407545714;6161.353057928943;5686.09022556389;5728.026235539968;5730.7421166047625;5734.512566431817;5745.482906538432;5317.441668624816;5320.255164277004;5339.3058902342445;5344.893102137936;5346.732663366816;6309.684515774285;6329.715162817776;6333.240011198685;6341.8731625368155;6344.746210151542;6273.059041143682;6273.623582584877;6277.96861015692;6289.622622642656;6291.96854015727;6234.94006018987;6300.21247344082;6314.463270622048;6316.305206427382;6334.619922804646;6087.4521293083835;6109.236345456818;6114.0828875669;6114.938850611437;6125.448709616156;6124.411449639877;6125.754381768298;6132.294322189991;6141.63943600049;6142.314611233224;5969.074793407029;5971.820845374646;5977.163197152315;5979.431953964367;5984.940150598438;4902.779374928208;5696.658200507983;5973.880522389441;5975.731092100779;5977.6114552553945;5669.744861481326;5684.431556844283;5798.391168927202;5866.6186697864505;5886.323072846678;6034.517238620988;6053.697315134314;6070.6357618543025;6077.270727512727;6119.357467465852;5867.325257595431;5871.588988770745;5872.383399742966;5889;5892.5285977122585 08:19:09 INFO - PROCESS | 1656 | |1;ext.html;415.8170405021788;432.25436122688365;480.77643895588557;482.54399592385187;513.8381409855897;29508.114756557312;39570.010749731235;39964.80105596837;39986.400203996964;42652.3602145968;40086.799566002126;40551.797241013825;40646.7967660162;40692.59307406925;40722.796386018104;9276.814463710738;9442.527873606326;9483.05169483053;9495.667651632195;9497.81004379912;8377.204165604287;8641.006284277326;8645.95677021614;8738.126187381275;8742;11087.113030957504;11442;11448.771024579504;11607.245529040585;11635.709107272343;11319.547218111265;11386.146039047104;11387.886121138765;11396.71508212293;11576.942115289454;8353.749387518385;8576.914230857674;9254.722358329194;11196.048335891459;11299.830502542469;38550.22899542021;38711.61288387136;38755.224895502215;38758.22483550342;38909.80545097256;3322.0864262327814;3394.2871996880685;3953.09078911849;3990.541087774914;3991.2217117661776;29415.705842941512;30392.54411183834;30528.694713053028;31058.689413105807;31068.37863242745;3167.7782555221143;3174.5238214267815;3201.375731732313;3231.3537292541587;3293.818839963801;469301;472433;473268;473540;473633;52291.73854130705;58727.70636146877;58976;59130.70434647799;59238;59476.70261648664;62516;62617.37382626207;62625;62637.37362626316;15399.92300038515;15617.062976221463;15632.531024069296;15643.374265029499;15650.217489125498;12155.817662735066;12182.329971851455;12199.573014944433;12205.816912746315;12213.694657633452;14683.853161468249;15037.924810375878;15047.849521504864;15396.07623542591;16673.91619544726;5573.832785016456;7007.020993118761;7058.6823592938445;7126.821829454201;7171.354578087997;840.3403328387187;853.3599800149888;959.3764053365229;1017.3285631483186;1046.7487802927242;28211.57682634762;28906.421871562452;29017.12948611545;29289.974850880117;29655;870.4951128345544;1022.4070039377143;1041.1618646989245;1077.9245452818227;1110.7945030169444;11557.537698492135;14998.62503437401;15061.472848450252;15108.848911510744;15415.691686166214;598426.0078699666;599392;606683;607173;607601;56514;56710;56824;56827;56966.43033569694;1299.9740005199842;1335.833020872391;1357.5316515802033;1359.4766015084463;1362.8023936529346;28514.14457566276;28524.429511409242;28660.713392865804;29283.414331712822;29283.707162928098;49083;49506.504934950186;49594.00811983812;49612;49652.25521617251;539485;543428;545229;545267;548133;23584.764152358257;24199.758002420454;24375;38104.80947595244;38483;32695.673043269264;40055.19475649809;40986.79506602448;41527.58472415237;41847.1630567393;34198.31603367869;34465;35318.47022294616;35366.938991830284;35630.465543016355;34463.4830477548;34465.65534344625;34492.827535863165;35304.47043294404;35537.466937995436;33298.833505832314;33485;34630.653693462744;34664.48003279902;34834.129146771535;29219.707802921697;30761.538576920917;31033.379332412773;31186.75252989946;31252.843735781174;22375.328740137822;23436.414089647897;23590.52818943646;23646.645300320164;23776.4055898597 08:19:09 INFO - PROCESS | 1656 | |2;jquery.html;327.9786813857098;368.52092280035964;371.50775222829753;393.94681717968103;401.8995251187203;18956.241750329988;19280.807191928077;19864.404067877986;20026;20232;20359.69460458094;20536.38390848273;20674.586508269826;20747.17011319549;21122.683159752578;5577.553795696334;5730.111832665933;5887.676177810219;5913.113033045035;5981.611195272293;5952.255968003999;5953.970230148843;5965.701714914258;5975.462208401243;5977.491913187362;3826.368649172883;3988.5811989741096;3998.4202290667836;4003.9199216015663;6366.713497892606;6375.93624063758;6395.648239346834;6400.615963042233;6412.871742565169;6419.165508483868;5442.755076021588;5666.688332141731;5734.483896449339;5745.913811292834;5777.855553611152;13276.336183190802;13505.932470337684;13513.86486135136;18705.625887482172;24475.877620611962;2101.674240492726;2212.9889350553144;2453.852768833876;2893.971060289391;2923.5468502382164;10115.089641932187;10250;10253.948730256376;13908.860911390857;14450.783238251435;1805.3049575913237;1812.8459080978;1877.3335465909543;1896.7723873135174;1929.8359639430664;82414.58792706058;102996.42508937261;104755;107068.92931070668;107666;11009.394483303495;11165.83251251232;11205;11220.102391808636;11350.943245283886;14105.576832694831;14128;14152.929235353757;14181.432742690384;14182;6925.134358205224;8082.353411727047;8205.645460343792;8230.135835737281;8239.794005149799;7855.685772569148;8096.190380961974;8270.669173233124;8274.669013239403;8290.005199376114;4392;4480.887977800581;4481.910361792811;4559.680822342404;4578.88552786183;1864.2077117225126;1915.79884112169;1923.567197380606;1924.604661620342;1964.2634012245408;817.9672813087528;888.3958907942532;1014.624588902111;1017.5929628148651;1058.296233005043;19778.90110549438;21245.46886327823;21348;21472.67413443989;21788.45528861791;870.9782255443538;939.6617217801586;954.179405711088;980.1472718734603;1000.4547521600745;10741.087007604441;11202.327860328407;11273.71815704614;11291.378974156336;11315.490802913888;347939;377455;548635;567308;568897;77977.22022779699;88366.41069113291;95587.56618650726;114447.97592834318;117866;978.4814048554277;1038.3406536849154;1074.3178081918038;1115.9944200279108;1118.2619471149003;19459.70810437873;19541.51146221356;19623.31318403849;19787.40637780869;19895.204191832163;36639.63360366469;43956;53327.46672533225;53738.46261537491;54159.729201353744;60311.396886030576;72203.55592888007;75123.74133810581;77593;79661;7018.719251229893;7318.524295920748;7960.283574478093;8038.276555110068;8248.092709801796;10552.788944221225;12643.810342845049;12868.356582170667;12954.870451295743;13244;10861.185411094357;11315.71710707206;11370.31778093317;11430.142739294752;11493.563304586927;10981.066609338295;11178.329300242012;11188.216824822184;11507.410832562606;11523.884761152281;9248.768780780534;11183.832242516532;11645.010174135294;11714.589989350332;11753.529858805552;8029.919700802917;8112.22933821286;8213.753587392388;8216.958915205385;8223.794405139919;10819.513121909376;11046.613368532062;11354.091672666333;11372;11406.14453915943 08:19:09 INFO - PROCESS | 1656 | |3;mootools.html;1460.306354481621;1504.495993842063;1709.3162734906043;1875.4186202277288;1902.5624106455514;7926.920730792697;8346.123657016016;8354.289885359736;8355;8365.665373385058;7327;7552.9622351888165;7564.054493188351;7646.6941322347175;8098.327551907871;2071.3164655663595;2111.8416118791074;2224.232639739287;2296.873671948042;2297.6668383084443;2317.4901521665292;2323.2913961241816;2324.721033475985;2327.4763178284834;2329.895154718042;2257.0181970842655;2270.6821045053707;2301.631738921773;2302.240260713969;2309.872956987365;2259.830512711545;2266;2286;2294.7705229477087;2296.781805728453;2257.108442165352;2267.399139228106;2302.378357843373;2315.455867871049;2325.4883925536355;8026.478278911852;8164;8256.380771442105;8256.669733210665;8262.793430164294;4976.651634385595;5150.510701483353;5153.3815942087185;5158.484151584849;5164.870878228075;2979.5232762758073;3432.8798492052906;3478.0609235506527;3514.7363947704025;3514.841832117561;1025.661531694539;1027.306568066552;1058.3438268273642;1060.448566745296;1065.4193464561833;9503.667371642028;10476.895231047669;10533.420661863594;10612.469376531142;10619.681409557648;4132.264448931456;4295.358354611892;4367.2575662137515;4397.604215620546;4413.271810151353;8463.703770368007;8478.618462169217;8479.15208479147;8479.95760021196;8503.23470887623;4304.246756817544;4974.8756281093265;4982.402111746614;4994.525520075587;4997.625178111583;4509.346144808985;4645.6980296280635;4670;4689.343491911135;4691.13214055394;2819.7885158613185;3419.0340554181444;3429.125572978924;3454.9270437737155;3513.4378499440477;1127.0870594818223;1132.2696860524916;1136.1138312116561;1147.540983606564;1150.6260465348796;823.0206651920088;847.1782371100023;889.675268526996;894.2622336572354;938.202527851321;4300.526942036374;5363.544098751572;5483.862903427446;5713.143028545668;5740.68426236561;838.4591938199827;847.0682249525472;858.4463021351194;883.6995421556702;917.5091326140483;3640.9987253505496;3653.7442379033214;3684.6499582539614;3735.8418890144144;3809.257194846978;9604.567794449267;9778.217742580577;9884.851727224097;9889.20886329092;9910.702678919644;9824.214062874953;9836.95081524602;9837;9862.457564834003;9867.950660246654;7605.6577454014705;7767.029121359943;7779.183185765526;7798.337141342936;7805.921940780633;4827.831025914076;5468.726563671721;5476.616636835383;5582;5811.27359080115;8774.561271936505;9073.546322683971;9091.136342047495;9101.044390338919;9263.397879137834;8728.258098061482;9031.909680903369;9224.354295199271;9322.860157097784;9327.067293270618;7079.1858936223;7176.96411517939;7187.8562428752175;7239.131304243525;7253.057102576631;7076.716931322895;7341.853162936818;7352.558846469229;7780.572068536285;7874.488158269694;6091.695415229309;6896.344847239504;7004.614746189009;7146.571205727673;7171.605561694157;6902.585844849325;7003.929960700328;7036.894446583203;7053.894191587233;7067.25793791665;6173.290071641639;6287.528435367274;6299.937000630119;6303.180586523723;6332.936670633235;4629.513901040342;4698.177818881613;4738.31294462308;4783.569478746929;4836.298736683231;4642.094791515622;4692.296155576691;4837.830675926326;4955.776990035385;4966.577840883565 08:19:09 INFO - PROCESS | 1656 | |4;prototype.html;316.9904902852914;341.5116383571493;347.4458239108622;356.05645040642304;364.96897763690083;4357.1264356326765;5018.924716129261;5124.256982737501;5176.456472070426;5288.497592728694;4667.8330417395655;5005.798608334001;5183;5197.7920883164625;5202.271681964528;3716.275326311357;3913.9021524461973;4084.2852500812387;4092.845761552027;4094.5810248442585;3504.351694936445;3878.747881387715;3974.4237085622567;3979.5423526294558;4108.85619003335;3409.3294285799902;3499.6325385834375;3703.703703703697;4027.5771044040393;4066.3493840985448;3558.448440491702;3735.4210097434943;3759.1729819439815;4068.5117785865737;4076.634327500291;3011.578379026938;3346;3391.5421418108485;3485.4074807282823;3583.5341405617105;4740.525947405266;4807.158747219222;4988.201887697951;5048.697078175322;5060.873478163038;3924.646781789624;4648.697834640738;4650.302454631798;4660.39414876064;4675.976620116911;4823.080457932889;5150.665206761549;5392.49010277123;5473;5491.28613280275;3399.7960122392738;4001.579834117405;4016.176683779818;4017.906212216577;4038.454808600831;4663.903982564095;4919.631027672903;5645.939232180224;5647.124695672136;5736.856578585527;3979.2837289288204;4078.877633670935;4094.61101195386;4102.466679331727;4111.814968326433;3686.645152910838;3976.343903255988;4174.352975288835;4195.77623859355;4202.915941681211;3544.9970766516835;4028.637422631977;4190.916181676349;4548.0221752323405;4751.358566593535;4979.128652485801;5483.972580137074;5499.230107784913;5557.221988921554;5580.441955804468;1113.4210210690396;1238.5169783784331;1351.1892864281353;1366.5353779714746;1370.9177449353072;900.9504477253814;952.219180272184;967.8530940835133;970.8203982263304;973.1241882305982;4316.417283666665;4681.274402467623;4994.875128121754;5008.524190201925;5037.395512538519;5394.271773310631;5420.1842382801415;5444.482774136451;5451.400345961941;5486.204500347428;4254.7021708480725;4455.019895622958;4705.482396936334;4741.581793285381;4856.17445034345;2276.7495575486682;2465.4822487277875;2623.619575161629;2826.795099822142;2862.1127450490417;4975.577075948512;5455.072637651609;5654.617671304772;5704.71476426177;5721.170430287585;4798.4001999750035;4831.541003604652;5125.718085505258;5392.24751955616;5508.421615730291;4919.532644398776;5284.233786101071;5587.580931430159;5606.55147588192;5625.634333768292;4498.358099293669;5171;5387.892242155213;5396.21754845553;5410.648307859977;4631.444226692818;4937.728424936663;5308.469153084815;5315.787368505216;5354.804530142623;5656.604037717485;5669.404712505293;5772.624779389326;5802.216700745345;5848.912266316093;3538.6815186633326;4055.371417430303;4056.558068914534;4089.1003979124544;4091.243120022805;4876.731779752147;5266.73666316675;5495.719276500207;5661.348944871393;5681.857953551194;4795.616350692006;4886.364772579542;5168.508991645787;5224.738763061756;5483.479069488392;4665.953340466552;4668.813247470063;4916.197767272621;5285.339332583427;5511.5039646432015;4350.456192975878;4516.532127058785;4819.49395313487;5210.635255532076;5388.51815750656;5168.741562921914;5471.343438787371;5539.723013849372;5560.026995275892;5597.916031259616;4695.647826413101;5387.164989426645;5446.537044351275;5448.477763456401;5459.727013649381 08:19:09 INFO - PROCESS | 1656 | |5;yui.html;624.3039011502175;728.4463807506297;787.9369650427963;794.8370584030274;805.3114587028091;15328.846711532895;15893.2848021839;15897.602559936007;15899.28453219608;15936.760948585767;14423.423063077491;14585.92707036466;14596.781048284285;14597.693680364291;14608.780868286984;701.3897908819334;726.6548389514991;729.0413106764614;861.4142383179451;867.7310033889482;890.1587999340626;901.0358915959926;903.5437104262338;907.2061945797427;908.7001289574424;937.4750139921666;955.6702937486556;956.9330146889686;960.2078285414561;960.5725452173785;950.8003319302954;952.166854002751;953.4708236928502;959.4770849886828;961.2982522758391;486.511056388331;548.2078396716746;563.6589863132787;709.3863807806262;709.9929000709978;14551.490697825526;15199.544013679608;15225.923870380577;15230;15257.771133433007;13894.722105557934;13921.30393480322;14019.43922243109;14045.508407205698;14062.437502499888;12544.81182782259;12664.746705065847;12699.619011429671;12716.618501444971;12891.484340626364;4384.912301753979;4394.868153955386;4409.316555933835;4466.441694788152;4477.7984990675495;103026.93946121035;105804.47097764538;108649;109930;112569.99999999919;10947.83578246327;11099.611513596985;11111.111111111091;11225.101991840633;11328.207025508136;22077;22466.438339041655;22601.321960341214;22853;22937.655935160987;7423.146338171179;7748.181605172825;7762.922370776333;7878.172791856887;8040.597970101471;4295.978520107442;4310.181065597527;4335.7615331157085;4354.390385346054;4455.242608756519;3392.6098498672477;3485.9651403486146;3487.093355727529;3489.0056333944704;3513.789172649598;671.318611609217;674.7941877727341;675.6148995072762;709.7870638808337;721.7329588052454;521.9817306394334;558.5419955636339;584.0450862839289;608.5375114912698;679.4462513051847;8194.426390152752;8663.003754568304;9180;9324.020977797234;9356.859647105299;522.2297111760199;594.6016169166669;614.708013693492;674.9021391898148;678.731900899142;3769.359208934488;4013.478247827764;4111.958880411217;4309.827606895689;4346.1090476452255;99837;100019.49990250148;100458;101097;101475.49262253642;88129.55935220284;88625.11374886168;89545.10454895368;90282;90651.54674226718;77260;79084;79570.2042979586;79766.60116699379;80975.59512202401;8437.156284371758;8439.071702112886;8454.112318206746;8559.57202139903;8587.570621468776;44863.102737944406;45225;48458;51196;51408;60282.698586508544;66312.67374652576;69547.6522617404;70401.64799175972;74135.62932185305;11723.706907327385;11764.764704705687;11854.585089521825;11898.821517677068;12029.15795894314;12760.74478510406;12806.231626102464;12856.228626282453;12937.28844913501;13357.198568085945;9952.013872624904;12197.695057623276;12318.384080795746;12330.075244356529;12773.297468639314;11621.12841536905;11857.5849845255;12056.63830085099;12229.75540489203;12324;12025.819612705991;12273.754524909631;12533.248005119722;12606.873931260938;12608;9878.62912900913;9955.070826885776;10347.534360953625;10536.789264214825;10738.677839664822;9936.900630993598;10607.787844242917;10633.627823026158;10635.680929572125;10670 08:19:09 INFO - PROCESS | 1656 | __end_tp_report 08:19:09 INFO - PROCESS | 1656 | __start_cc_report 08:19:09 INFO - PROCESS | 1656 | _x_x_mozilla_cycle_collect,2896 08:19:09 INFO - PROCESS | 1656 | __end_cc_report 08:19:09 INFO - PROCESS | 1656 | __startTimestamp1482509949953__endTimestamp 08:19:09 INFO - PROCESS | 1656 | 08:19:09 INFO - PROCESS | 1656 | ------- Summary: start ------- 08:19:09 INFO - PROCESS | 1656 | Number of tests: 6 08:19:09 INFO - PROCESS | 1656 | 08:19:09 INFO - PROCESS | 1656 | [#0] dojo.html Cycles:165 Average:5690.71 Median:5867.33 stddev:633.26 (10.8%) stddev-sans-first:605.67 08:19:09 INFO - PROCESS | 1656 | Values: 3254.2 3337.3 3345.1 3364.4 3385.2 5543.4 5608.2 5650.7 5666.4 5692.9 5638.1 5672.3 5679.0 5691.1 5701.7 5133.8 5152.8 5167.6 5191.9 5205.3 4687.6 4959.6 5257.6 5260.2 5267.6 5242.1 5256.7 5260.4 5263.3 5265.9 5251.4 5257.8 5263.2 5270.2 5274.1 5101.5 5123.8 5200.5 5205.7 5215.8 5630.8 5635.6 5684.5 5724.5 5741.9 6176.0 6185.3 6199.1 6200.4 6204.7 5556.5 6002.3 6128.7 6139.1 6157.7 5710.5 5719.9 5725.5 5729.4 5733.2 6326.2 6331.7 6332.2 6348.3 6348.7 5867.6 5905.4 5907.3 6012.1 6069.7 6183.1 6216.9 6221.6 6230.8 6245.5 6061.1 6153.3 6165.8 6168.8 6169.2 5980.8 6184.1 6193.0 6208.4 6213.0 4836.1 4857.8 4875.1 4892.8 4901.2 4364.1 4370.5 4380.0 4385.8 4418.2 5761.1 5764.1 5785.2 5785.7 5798.9 6069.6 6130.4 6132.7 6159.8 6161.4 5686.1 5728.0 5730.7 5734.5 5745.5 5317.4 5320.3 5339.3 5344.9 5346.7 6309.7 6329.7 6333.2 6341.9 6344.7 6273.1 6273.6 6278.0 6289.6 6292.0 6234.9 6300.2 6314.5 6316.3 6334.6 6087.5 6109.2 6114.1 6114.9 6125.4 6124.4 6125.8 6132.3 6141.6 6142.3 5969.1 5971.8 5977.2 5979.4 5984.9 4902.8 5696.7 5973.9 5975.7 5977.6 5669.7 5684.4 5798.4 5866.6 5886.3 6034.5 6053.7 6070.6 6077.3 6119.4 5867.3 5871.6 5872.4 5889.0 5892.5 08:19:09 INFO - PROCESS | 1656 | 08:19:09 INFO - PROCESS | 1656 | [#1] ext.html Cycles:180 Average:66819.01 Median:35311.47 stddev:144983.27 (410.6%) stddev-sans-first:145304.27 08:19:09 INFO - PROCESS | 1656 | Values: 415.8 432.3 480.8 482.5 513.8 29508.1 39570.0 39964.8 39986.4 42652.4 40086.8 40551.8 40646.8 40692.6 40722.8 9276.8 9442.5 9483.1 9495.7 9497.8 8377.2 8641.0 8646.0 8738.1 8742.0 11087.1 11442.0 11448.8 11607.2 11635.7 11319.5 11386.1 11387.9 11396.7 11576.9 8353.7 8576.9 9254.7 11196.0 11299.8 38550.2 38711.6 38755.2 38758.2 38909.8 3322.1 3394.3 3953.1 3990.5 3991.2 29415.7 30392.5 30528.7 31058.7 31068.4 3167.8 3174.5 3201.4 3231.4 3293.8 469301.0 472433.0 473268.0 473540.0 473633.0 52291.7 58727.7 58976.0 59130.7 59238.0 59476.7 62516.0 62617.4 62625.0 62637.4 15399.9 15617.1 15632.5 15643.4 15650.2 12155.8 12182.3 12199.6 12205.8 12213.7 14683.9 15037.9 15047.8 15396.1 16673.9 5573.8 7007.0 7058.7 7126.8 7171.4 840.3 853.4 959.4 1017.3 1046.7 28211.6 28906.4 29017.1 29290.0 29655.0 870.5 1022.4 1041.2 1077.9 1110.8 11557.5 14998.6 15061.5 15108.8 15415.7 598426.0 599392.0 606683.0 607173.0 607601.0 56514.0 56710.0 56824.0 56827.0 56966.4 1300.0 1335.8 1357.5 1359.5 1362.8 28514.1 28524.4 28660.7 29283.4 29283.7 49083.0 49506.5 49594.0 49612.0 49652.3 539485.0 543428.0 545229.0 545267.0 548133.0 23584.8 24199.8 24375.0 38104.8 38483.0 32695.7 40055.2 40986.8 41527.6 41847.2 34198.3 34465.0 35318.5 35366.9 35630.5 34463.5 34465.7 34492.8 35304.5 35537.5 33298.8 33485.0 34630.7 34664.5 34834.1 29219.7 30761.5 31033.4 31186.8 31252.8 22375.3 23436.4 23590.5 23646.6 23776.4 08:19:09 INFO - PROCESS | 1656 | 08:19:09 INFO - PROCESS | 1656 | [#2] jquery.html Cycles:180 Average:30030.67 Median:21410.34 stddev:82089.21 (383.4%) stddev-sans-first:82289.19 08:19:09 INFO - PROCESS | 1656 | Values: 328.0 368.5 371.5 393.9 401.9 18956.2 19280.8 19864.4 20026.0 20232.0 20359.7 20536.4 20674.6 20747.2 21122.7 5577.6 5730.1 5887.7 5913.1 5981.6 5952.3 5954.0 5965.7 5975.5 5977.5 3826.4 3988.6 3998.4 4003.9 6366.7 6375.9 6395.6 6400.6 6412.9 6419.2 5442.8 5666.7 5734.5 5745.9 5777.9 13276.3 13505.9 13513.9 18705.6 24475.9 2101.7 2213.0 2453.9 2894.0 2923.5 10115.1 10250.0 10253.9 13908.9 14450.8 1805.3 1812.8 1877.3 1896.8 1929.8 82414.6 102996.4 104755.0 107068.9 107666.0 11009.4 11165.8 11205.0 11220.1 11350.9 14105.6 14128.0 14152.9 14181.4 14182.0 6925.1 8082.4 8205.6 8230.1 8239.8 7855.7 8096.2 8270.7 8274.7 8290.0 4392.0 4480.9 4481.9 4559.7 4578.9 1864.2 1915.8 1923.6 1924.6 1964.3 818.0 888.4 1014.6 1017.6 1058.3 19778.9 21245.5 21348.0 21472.7 21788.5 871.0 939.7 954.2 980.1 1000.5 10741.1 11202.3 11273.7 11291.4 11315.5 347939.0 377455.0 548635.0 567308.0 568897.0 77977.2 88366.4 95587.6 114448.0 117866.0 978.5 1038.3 1074.3 1116.0 1118.3 19459.7 19541.5 19623.3 19787.4 19895.2 36639.6 43956.0 53327.5 53738.5 54159.7 60311.4 72203.6 75123.7 77593.0 79661.0 7018.7 7318.5 7960.3 8038.3 8248.1 10552.8 12643.8 12868.4 12954.9 13244.0 10861.2 11315.7 11370.3 11430.1 11493.6 10981.1 11178.3 11188.2 11507.4 11523.9 9248.8 11183.8 11645.0 11714.6 11753.5 8029.9 8112.2 8213.8 8217.0 8223.8 10819.5 11046.6 11354.1 11372.0 11406.1 08:19:09 INFO - PROCESS | 1656 | 08:19:09 INFO - PROCESS | 1656 | [#3] mootools.html Cycles:180 Average:5278.53 Median:5264.21 stddev:2873.74 (54.6%) stddev-sans-first:2867.48 08:19:09 INFO - PROCESS | 1656 | Values: 1460.3 1504.5 1709.3 1875.4 1902.6 7926.9 8346.1 8354.3 8355.0 8365.7 7327.0 7553.0 7564.1 7646.7 8098.3 2071.3 2111.8 2224.2 2296.9 2297.7 2317.5 2323.3 2324.7 2327.5 2329.9 2257.0 2270.7 2301.6 2302.2 2309.9 2259.8 2266.0 2286.0 2294.8 2296.8 2257.1 2267.4 2302.4 2315.5 2325.5 8026.5 8164.0 8256.4 8256.7 8262.8 4976.7 5150.5 5153.4 5158.5 5164.9 2979.5 3432.9 3478.1 3514.7 3514.8 1025.7 1027.3 1058.3 1060.4 1065.4 9503.7 10476.9 10533.4 10612.5 10619.7 4132.3 4295.4 4367.3 4397.6 4413.3 8463.7 8478.6 8479.2 8480.0 8503.2 4304.2 4974.9 4982.4 4994.5 4997.6 4509.3 4645.7 4670.0 4689.3 4691.1 2819.8 3419.0 3429.1 3454.9 3513.4 1127.1 1132.3 1136.1 1147.5 1150.6 823.0 847.2 889.7 894.3 938.2 4300.5 5363.5 5483.9 5713.1 5740.7 838.5 847.1 858.4 883.7 917.5 3641.0 3653.7 3684.6 3735.8 3809.3 9604.6 9778.2 9884.9 9889.2 9910.7 9824.2 9837.0 9837.0 9862.5 9868.0 7605.7 7767.0 7779.2 7798.3 7805.9 4827.8 5468.7 5476.6 5582.0 5811.3 8774.6 9073.5 9091.1 9101.0 9263.4 8728.3 9031.9 9224.4 9322.9 9327.1 7079.2 7177.0 7187.9 7239.1 7253.1 7076.7 7341.9 7352.6 7780.6 7874.5 6091.7 6896.3 7004.6 7146.6 7171.6 6902.6 7003.9 7036.9 7053.9 7067.3 6173.3 6287.5 6299.9 6303.2 6332.9 4629.5 4698.2 4738.3 4783.6 4836.3 4642.1 4692.3 4837.8 4955.8 4966.6 08:19:09 INFO - PROCESS | 1656 | 08:19:09 INFO - PROCESS | 1656 | [#4] prototype.html Cycles:180 Average:4381.96 Median:4821.29 stddev:1306.57 (27.1%) stddev-sans-first:1274.11 08:19:09 INFO - PROCESS | 1656 | Values: 317.0 341.5 347.4 356.1 365.0 4357.1 5018.9 5124.3 5176.5 5288.5 4667.8 5005.8 5183.0 5197.8 5202.3 3716.3 3913.9 4084.3 4092.8 4094.6 3504.4 3878.7 3974.4 3979.5 4108.9 3409.3 3499.6 3703.7 4027.6 4066.3 3558.4 3735.4 3759.2 4068.5 4076.6 3011.6 3346.0 3391.5 3485.4 3583.5 4740.5 4807.2 4988.2 5048.7 5060.9 3924.6 4648.7 4650.3 4660.4 4676.0 4823.1 5150.7 5392.5 5473.0 5491.3 3399.8 4001.6 4016.2 4017.9 4038.5 4663.9 4919.6 5645.9 5647.1 5736.9 3979.3 4078.9 4094.6 4102.5 4111.8 3686.6 3976.3 4174.4 4195.8 4202.9 3545.0 4028.6 4190.9 4548.0 4751.4 4979.1 5484.0 5499.2 5557.2 5580.4 1113.4 1238.5 1351.2 1366.5 1370.9 901.0 952.2 967.9 970.8 973.1 4316.4 4681.3 4994.9 5008.5 5037.4 5394.3 5420.2 5444.5 5451.4 5486.2 4254.7 4455.0 4705.5 4741.6 4856.2 2276.7 2465.5 2623.6 2826.8 2862.1 4975.6 5455.1 5654.6 5704.7 5721.2 4798.4 4831.5 5125.7 5392.2 5508.4 4919.5 5284.2 5587.6 5606.6 5625.6 4498.4 5171.0 5387.9 5396.2 5410.6 4631.4 4937.7 5308.5 5315.8 5354.8 5656.6 5669.4 5772.6 5802.2 5848.9 3538.7 4055.4 4056.6 4089.1 4091.2 4876.7 5266.7 5495.7 5661.3 5681.9 4795.6 4886.4 5168.5 5224.7 5483.5 4666.0 4668.8 4916.2 5285.3 5511.5 4350.5 4516.5 4819.5 5210.6 5388.5 5168.7 5471.3 5539.7 5560.0 5597.9 4695.6 5387.2 5446.5 5448.5 5459.7 08:19:09 INFO - PROCESS | 1656 | 08:19:09 INFO - PROCESS | 1656 | [#5] yui.html Cycles:180 Average:20275.63 Median:4402.09 stddev:29529.74 (670.8%) stddev-sans-first:29575.71 08:19:09 INFO - PROCESS | 1656 | Values: 624.3 728.4 787.9 794.8 805.3 15328.8 15893.3 15897.6 15899.3 15936.8 14423.4 14585.9 14596.8 14597.7 14608.8 701.4 726.7 729.0 861.4 867.7 890.2 901.0 903.5 907.2 908.7 937.5 955.7 956.9 960.2 960.6 950.8 952.2 953.5 959.5 961.3 486.5 548.2 563.7 709.4 710.0 14551.5 15199.5 15225.9 15230.0 15257.8 13894.7 13921.3 14019.4 14045.5 14062.4 12544.8 12664.7 12699.6 12716.6 12891.5 4384.9 4394.9 4409.3 4466.4 4477.8 103026.9 105804.5 108649.0 109930.0 112570.0 10947.8 11099.6 11111.1 11225.1 11328.2 22077.0 22466.4 22601.3 22853.0 22937.7 7423.1 7748.2 7762.9 7878.2 8040.6 4296.0 4310.2 4335.8 4354.4 4455.2 3392.6 3486.0 3487.1 3489.0 3513.8 671.3 674.8 675.6 709.8 721.7 522.0 558.5 584.0 608.5 679.4 8194.4 8663.0 9180.0 9324.0 9356.9 522.2 594.6 614.7 674.9 678.7 3769.4 4013.5 4112.0 4309.8 4346.1 99837.0 100019.5 100458.0 101097.0 101475.5 88129.6 88625.1 89545.1 90282.0 90651.5 77260.0 79084.0 79570.2 79766.6 80975.6 8437.2 8439.1 8454.1 8559.6 8587.6 44863.1 45225.0 48458.0 51196.0 51408.0 60282.7 66312.7 69547.7 70401.6 74135.6 11723.7 11764.8 11854.6 11898.8 12029.2 12760.7 12806.2 12856.2 12937.3 13357.2 9952.0 12197.7 12318.4 12330.1 12773.3 11621.1 11857.6 12056.6 12229.8 12324.0 12025.8 12273.8 12533.2 12606.9 12608.0 9878.6 9955.1 10347.5 10536.8 10738.7 9936.9 10607.8 10633.6 10635.7 10670.0 08:19:09 INFO - PROCESS | 1656 | -------- Summary: end -------- 08:19:09 INFO - PROCESS | 1656 | 08:19:10 INFO - TEST-INFO | 1656: exit 0 08:19:10 INFO - TEST-OK | dromaeo_css | took 1120653ms 08:19:10 INFO - TEST-START | kraken 08:19:10 INFO - operating with platform_type : mac_ 08:19:10 INFO - Initialising browser for kraken test... 08:19:10 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/tmpwR288l/profile http://localhost:49251/getInfo.html) 08:19:11 INFO - PROCESS | 1661 | 1482509951111 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4979 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3825 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2829 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 08:19:12 INFO - PROCESS | 1661 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 08:19:12 INFO - PROCESS | 1661 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 08:19:12 INFO - PROCESS | 1661 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 08:19:12 INFO - PROCESS | 1661 | __metrics Screen width/height:1600/1200 08:19:12 INFO - PROCESS | 1661 | colorDepth:24 08:19:12 INFO - PROCESS | 1661 | Browser inner width/height: 1024/689 08:19:12 INFO - PROCESS | 1661 | __metrics 08:19:12 INFO - PROCESS | 1661 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:19:12 INFO - PROCESS | 1661 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:19:13 INFO - TEST-INFO | 1661: exit 0 08:19:13 INFO - Browser initialized. 08:19:13 INFO - Running cycle 1/1 for kraken test... 08:19:13 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/tmpwR288l/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:19:25 INFO - PROCESS | 1662 | RSS: Main: 235388928 08:19:25 INFO - PROCESS | 1662 | 08:19:28 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49251/tests/kraken/driver.html?testName=audio-beat-detection) 08:19:29 INFO - PROCESS | 1662 | RSS: Main: 286785536 08:19:29 INFO - PROCESS | 1662 | 08:19:31 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49251/tests/kraken/driver.html?testName=audio-dft) 08:19:32 INFO - PROCESS | 1662 | RSS: Main: 288358400 08:19:32 INFO - PROCESS | 1662 | 08:19:35 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49251/tests/kraken/driver.html?testName=audio-fft) 08:19:36 INFO - PROCESS | 1662 | RSS: Main: 378494976 08:19:36 INFO - PROCESS | 1662 | 08:19:38 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49251/tests/kraken/driver.html?testName=audio-oscillator) 08:19:39 INFO - PROCESS | 1662 | RSS: Main: 282308608 08:19:39 INFO - PROCESS | 1662 | 08:19:41 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49251/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:19:41 INFO - PROCESS | 1662 | RSS: Main: 285982720 08:19:41 INFO - PROCESS | 1662 | 08:19:44 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49251/tests/kraken/driver.html?testName=imaging-darkroom) 08:19:45 INFO - PROCESS | 1662 | RSS: Main: 329043968 08:19:45 INFO - PROCESS | 1662 | 08:19:48 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49251/tests/kraken/driver.html?testName=imaging-desaturate) 08:19:48 INFO - PROCESS | 1662 | RSS: Main: 329269248 08:19:48 INFO - PROCESS | 1662 | 08:19:51 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49251/tests/kraken/driver.html?testName=json-parse-financial) 08:19:52 INFO - PROCESS | 1662 | RSS: Main: 328200192 08:19:52 INFO - PROCESS | 1662 | 08:19:53 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49251/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:19:54 INFO - PROCESS | 1662 | RSS: Main: 304640000 08:19:54 INFO - PROCESS | 1662 | 08:19:56 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:19:56 INFO - PROCESS | 1662 | RSS: Main: 308690944 08:19:56 INFO - PROCESS | 1662 | 08:19:59 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:19:59 INFO - PROCESS | 1662 | RSS: Main: 309452800 08:19:59 INFO - PROCESS | 1662 | 08:20:02 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:20:03 INFO - PROCESS | 1662 | RSS: Main: 325361664 08:20:03 INFO - PROCESS | 1662 | 08:20:06 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:20:06 INFO - PROCESS | 1662 | RSS: Main: 319254528 08:20:06 INFO - PROCESS | 1662 | 08:20:08 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49251/tests/kraken/driver.html?testName=ai-astar) 08:20:08 INFO - PROCESS | 1662 | RSS: Main: 363077632 08:20:08 INFO - PROCESS | 1662 | 08:20:08 INFO - PROCESS | 1662 | __start_tp_report 08:20:08 INFO - PROCESS | 1662 | _x_x_mozilla_page_load 08:20:08 INFO - PROCESS | 1662 | _x_x_mozilla_page_load_details 08:20:08 INFO - PROCESS | 1662 | |i|pagename|runs| 08:20:08 INFO - PROCESS | 1662 | |0;ai-astar;114;115;115;114;114;115;115;115;116;114 08:20:08 INFO - PROCESS | 1662 | |1;audio-beat-detection;146;110;109;147;111;111;107;109;131;110 08:20:08 INFO - PROCESS | 1662 | |2;audio-dft;185;185;182;183;182;184;181;181;180;185 08:20:08 INFO - PROCESS | 1662 | |3;audio-fft;80;58;61;101;84;50;74;74;96;71 08:20:08 INFO - PROCESS | 1662 | |4;audio-oscillator;91;69;88;69;72;68;83;69;68;84 08:20:08 INFO - PROCESS | 1662 | |5;imaging-gaussian-blur;88;86;91;87;90;87;90;88;90;87 08:20:08 INFO - PROCESS | 1662 | |6;imaging-darkroom;95;117;147;94;94;95;95;94;95;95 08:20:08 INFO - PROCESS | 1662 | |7;imaging-desaturate;89;87;86;86;85;88;113;140;95;86 08:20:08 INFO - PROCESS | 1662 | |8;json-parse-financial;57;55;53;53;54;54;54;54;54;54 08:20:08 INFO - PROCESS | 1662 | |9;json-stringify-tinderbox;66;73;75;74;74;72;72;72;72;70 08:20:08 INFO - PROCESS | 1662 | |10;stanford-crypto-aes;89;90;85;89;85;85;89;86;86;87 08:20:08 INFO - PROCESS | 1662 | |11;stanford-crypto-ccm;139;141;143;152;151;142;135;139;184;139 08:20:08 INFO - PROCESS | 1662 | |12;stanford-crypto-pbkdf2;174;173;172;172;172;169;172;169;179;170 08:20:08 INFO - PROCESS | 1662 | |13;stanford-crypto-sha256-iterative;66;67;67;67;67;68;66;67;66;66 08:20:08 INFO - PROCESS | 1662 | __end_tp_report 08:20:08 INFO - PROCESS | 1662 | __start_cc_report 08:20:08 INFO - PROCESS | 1662 | _x_x_mozilla_cycle_collect,1947 08:20:08 INFO - PROCESS | 1662 | __end_cc_report 08:20:08 INFO - PROCESS | 1662 | __startTimestamp1482510008871__endTimestamp 08:20:08 INFO - PROCESS | 1662 | 08:20:08 INFO - PROCESS | 1662 | ------- Summary: start ------- 08:20:08 INFO - PROCESS | 1662 | Number of tests: 14 08:20:08 INFO - PROCESS | 1662 | 08:20:08 INFO - PROCESS | 1662 | [#0] ai-astar Cycles:10 Average:114.70 Median:115.00 stddev:0.67 (0.6%) stddev-sans-first:0.67 08:20:08 INFO - PROCESS | 1662 | Values: 114.0 115.0 115.0 114.0 114.0 115.0 115.0 115.0 116.0 114.0 08:20:08 INFO - PROCESS | 1662 | 08:20:08 INFO - PROCESS | 1662 | [#1] audio-beat-detection Cycles:10 Average:119.10 Median:111.00 stddev:15.95 (14.4%) stddev-sans-first:13.63 08:20:08 INFO - PROCESS | 1662 | Values: 146.0 110.0 109.0 147.0 111.0 111.0 107.0 109.0 131.0 110.0 08:20:08 INFO - PROCESS | 1662 | 08:20:08 INFO - PROCESS | 1662 | [#2] audio-dft Cycles:10 Average:182.80 Median:183.50 stddev:1.87 (1.0%) stddev-sans-first:1.81 08:20:08 INFO - PROCESS | 1662 | Values: 185.0 185.0 182.0 183.0 182.0 184.0 181.0 181.0 180.0 185.0 08:20:08 INFO - PROCESS | 1662 | 08:20:08 INFO - PROCESS | 1662 | [#3] audio-fft Cycles:10 Average:74.90 Median:74.00 stddev:16.16 (21.8%) stddev-sans-first:17.04 08:20:08 INFO - PROCESS | 1662 | Values: 80.0 58.0 61.0 101.0 84.0 50.0 74.0 74.0 96.0 71.0 08:20:08 INFO - PROCESS | 1662 | 08:20:08 INFO - PROCESS | 1662 | [#4] audio-oscillator Cycles:10 Average:76.10 Median:77.50 stddev:9.27 (12.0%) stddev-sans-first:8.11 08:20:08 INFO - PROCESS | 1662 | Values: 91.0 69.0 88.0 69.0 72.0 68.0 83.0 69.0 68.0 84.0 08:20:08 INFO - PROCESS | 1662 | 08:20:08 INFO - PROCESS | 1662 | [#5] imaging-gaussian-blur Cycles:10 Average:88.40 Median:89.00 stddev:1.71 (1.9%) stddev-sans-first:1.81 08:20:08 INFO - PROCESS | 1662 | Values: 88.0 86.0 91.0 87.0 90.0 87.0 90.0 88.0 90.0 87.0 08:20:08 INFO - PROCESS | 1662 | 08:20:08 INFO - PROCESS | 1662 | [#6] imaging-darkroom Cycles:10 Average:102.10 Median:95.00 stddev:17.28 (18.2%) stddev-sans-first:18.13 08:20:08 INFO - PROCESS | 1662 | Values: 95.0 117.0 147.0 94.0 94.0 95.0 95.0 94.0 95.0 95.0 08:20:08 INFO - PROCESS | 1662 | 08:20:08 INFO - PROCESS | 1662 | [#7] imaging-desaturate Cycles:10 Average:95.50 Median:86.50 stddev:17.76 (20.5%) stddev-sans-first:18.68 08:20:08 INFO - PROCESS | 1662 | Values: 89.0 87.0 86.0 86.0 85.0 88.0 113.0 140.0 95.0 86.0 08:20:08 INFO - PROCESS | 1662 | 08:20:08 INFO - PROCESS | 1662 | [#8] json-parse-financial Cycles:10 Average:54.20 Median:54.00 stddev:1.14 (2.1%) stddev-sans-first:0.60 08:20:08 INFO - PROCESS | 1662 | Values: 57.0 55.0 53.0 53.0 54.0 54.0 54.0 54.0 54.0 54.0 08:20:08 INFO - PROCESS | 1662 | 08:20:08 INFO - PROCESS | 1662 | [#9] json-stringify-tinderbox Cycles:10 Average:72.00 Median:72.50 stddev:2.54 (3.5%) stddev-sans-first:1.50 08:20:08 INFO - PROCESS | 1662 | Values: 66.0 73.0 75.0 74.0 74.0 72.0 72.0 72.0 72.0 70.0 08:20:08 INFO - PROCESS | 1662 | 08:20:08 INFO - PROCESS | 1662 | [#10] stanford-crypto-aes Cycles:10 Average:87.10 Median:88.00 stddev:1.97 (2.2%) stddev-sans-first:1.96 08:20:08 INFO - PROCESS | 1662 | Values: 89.0 90.0 85.0 89.0 85.0 85.0 89.0 86.0 86.0 87.0 08:20:08 INFO - PROCESS | 1662 | 08:20:08 INFO - PROCESS | 1662 | [#11] stanford-crypto-ccm Cycles:10 Average:146.50 Median:142.50 stddev:14.22 (10.0%) stddev-sans-first:14.82 08:20:08 INFO - PROCESS | 1662 | Values: 139.0 141.0 143.0 152.0 151.0 142.0 135.0 139.0 184.0 139.0 08:20:08 INFO - PROCESS | 1662 | 08:20:08 INFO - PROCESS | 1662 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.20 Median:172.00 stddev:2.90 (1.7%) stddev-sans-first:3.00 08:20:08 INFO - PROCESS | 1662 | Values: 174.0 173.0 172.0 172.0 172.0 169.0 172.0 169.0 179.0 170.0 08:20:08 INFO - PROCESS | 1662 | 08:20:08 INFO - PROCESS | 1662 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.70 Median:67.00 stddev:0.67 (1.0%) stddev-sans-first:0.67 08:20:08 INFO - PROCESS | 1662 | Values: 66.0 67.0 67.0 67.0 67.0 68.0 66.0 67.0 66.0 66.0 08:20:08 INFO - PROCESS | 1662 | -------- Summary: end -------- 08:20:08 INFO - PROCESS | 1662 | 08:20:09 INFO - TEST-INFO | 1662: exit 0 08:20:09 INFO - TEST-OK | kraken | took 59154ms 08:20:09 INFO - SUITE-END | took 1179s 08:20:10 INFO - Completed test suite (00:19:40) 08:20:10 INFO - javascript benchmark 08:20:10 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3254.1864533866533, 3337.2824842658824, 3345.113544910599, 3364.42804723197, 3385.221399078212, 5543.445655434454, 5608.158776183569, 5650.6892120933335, 5666.405027472118, 5692.943070569293, 5638.126090455986, 5672.290963629546, 5679.0, 5691.146328050794, 5701.6864072476, 5133.845984620468, 5152.768125434364, 5167.638265321429, 5191.870203244911, 5205.349331333583, 4687.624990000809, 4959.603231741452, 5257.553107985826, 5260.184671375943, 5267.55225805807, 5242.082635538784, 5256.737163141846, 5260.368755749316, 5263.315768950031, 5265.8946821063555, 5251.36983561975, 5257.815976440826, 5263.236830659571, 5270.209468579744, 5274.103402421579, 5101.515356041207, 5123.846284611468, 5200.5059519345605, 5205.7397130143345, 5215.791368345262, 5630.8029218977545, 5635.633683810539, 5684.459976302244, 5724.484796368347, 5741.942580574183, 6175.999999999955, 6185.257769067695, 6199.132121502993, 6200.379962003809, 6204.689765511706, 5556.499915007668, 6002.339742628307, 6128.686460748359, 6139.140520327158, 6157.661328626924, 5710.543156547466, 5719.8570035749435, 5725.456081672234, 5729.369769325371, 5733.16869053989, 6326.177596912372, 6331.715072821734, 6332.208473940757, 6348.333424990402, 6348.714307856158, 5867.647941123546, 5905.43898329658, 5907.320658124285, 6012.098185272244, 6069.696515174223, 6183.134361189437, 6216.937830621727, 6221.62670239778, 6230.7819226326865, 6245.469135123474, 6061.121137435047, 6153.292371377349, 6165.815025549241, 6168.784092556739, 6169.198004259418, 5980.760769569256, 6184.072389141575, 6193.0, 6208.410201030895, 6212.968935155295, 4836.12949669063, 4857.781399837016, 4875.07373599015, 4892.779824907817, 4901.1913034349645, 4364.10535840152, 4370.541093185233, 4379.978100109479, 4385.780710964439, 4418.226810308183, 5761.107028410603, 5764.106563482583, 5785.218995435646, 5785.681787501644, 5798.9420105799245, 6069.635821850703, 6130.356312587114, 6132.692058233554, 6159.784407545714, 6161.353057928943, 5686.09022556389, 5728.026235539968, 5730.7421166047625, 5734.512566431817, 5745.482906538432, 5317.441668624816, 5320.255164277004, 5339.3058902342445, 5344.893102137936, 5346.732663366816, 6309.684515774285, 6329.715162817776, 6333.240011198685, 6341.8731625368155, 6344.746210151542, 6273.059041143682, 6273.623582584877, 6277.96861015692, 6289.622622642656, 6291.96854015727, 6234.94006018987, 6300.21247344082, 6314.463270622048, 6316.305206427382, 6334.619922804646, 6087.4521293083835, 6109.236345456818, 6114.0828875669, 6114.938850611437, 6125.448709616156, 6124.411449639877, 6125.754381768298, 6132.294322189991, 6141.63943600049, 6142.314611233224, 5969.074793407029, 5971.820845374646, 5977.163197152315, 5979.431953964367, 5984.940150598438, 4902.779374928208, 5696.658200507983, 5973.880522389441, 5975.731092100779, 5977.6114552553945, 5669.744861481326, 5684.431556844283, 5798.391168927202, 5866.6186697864505, 5886.323072846678, 6034.517238620988, 6053.697315134314, 6070.6357618543025, 6077.270727512727, 6119.357467465852, 5867.325257595431, 5871.588988770745, 5872.383399742966, 5889.0, 5892.5285977122585], "value": 5649.978225900809, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [415.8170405021788, 432.25436122688365, 480.77643895588557, 482.54399592385187, 513.8381409855897, 29508.114756557312, 39570.010749731235, 39964.80105596837, 39986.400203996964, 42652.3602145968, 40086.799566002126, 40551.797241013825, 40646.7967660162, 40692.59307406925, 40722.796386018104, 9276.814463710738, 9442.527873606326, 9483.05169483053, 9495.667651632195, 9497.81004379912, 8377.204165604287, 8641.006284277326, 8645.95677021614, 8738.126187381275, 8742.0, 11087.113030957504, 11442.0, 11448.771024579504, 11607.245529040585, 11635.709107272343, 11319.547218111265, 11386.146039047104, 11387.886121138765, 11396.71508212293, 11576.942115289454, 8353.749387518385, 8576.914230857674, 9254.722358329194, 11196.048335891459, 11299.830502542469, 38550.22899542021, 38711.61288387136, 38755.224895502215, 38758.22483550342, 38909.80545097256, 3322.0864262327814, 3394.2871996880685, 3953.09078911849, 3990.541087774914, 3991.2217117661776, 29415.705842941512, 30392.54411183834, 30528.694713053028, 31058.689413105807, 31068.37863242745, 3167.7782555221143, 3174.5238214267815, 3201.375731732313, 3231.3537292541587, 3293.818839963801, 469301.0, 472433.0, 473268.0, 473540.0, 473633.0, 52291.73854130705, 58727.70636146877, 58976.0, 59130.70434647799, 59238.0, 59476.70261648664, 62516.0, 62617.37382626207, 62625.0, 62637.37362626316, 15399.92300038515, 15617.062976221463, 15632.531024069296, 15643.374265029499, 15650.217489125498, 12155.817662735066, 12182.329971851455, 12199.573014944433, 12205.816912746315, 12213.694657633452, 14683.853161468249, 15037.924810375878, 15047.849521504864, 15396.07623542591, 16673.91619544726, 5573.832785016456, 7007.020993118761, 7058.6823592938445, 7126.821829454201, 7171.354578087997, 840.3403328387187, 853.3599800149888, 959.3764053365229, 1017.3285631483186, 1046.7487802927242, 28211.57682634762, 28906.421871562452, 29017.12948611545, 29289.974850880117, 29655.0, 870.4951128345544, 1022.4070039377143, 1041.1618646989245, 1077.9245452818227, 1110.7945030169444, 11557.537698492135, 14998.62503437401, 15061.472848450252, 15108.848911510744, 15415.691686166214, 598426.0078699666, 599392.0, 606683.0, 607173.0, 607601.0, 56514.0, 56710.0, 56824.0, 56827.0, 56966.43033569694, 1299.9740005199842, 1335.833020872391, 1357.5316515802033, 1359.4766015084463, 1362.8023936529346, 28514.14457566276, 28524.429511409242, 28660.713392865804, 29283.414331712822, 29283.707162928098, 49083.0, 49506.504934950186, 49594.00811983812, 49612.0, 49652.25521617251, 539485.0, 543428.0, 545229.0, 545267.0, 548133.0, 23584.764152358257, 24199.758002420454, 24375.0, 38104.80947595244, 38483.0, 32695.673043269264, 40055.19475649809, 40986.79506602448, 41527.58472415237, 41847.1630567393, 34198.31603367869, 34465.0, 35318.47022294616, 35366.938991830284, 35630.465543016355, 34463.4830477548, 34465.65534344625, 34492.827535863165, 35304.47043294404, 35537.466937995436, 33298.833505832314, 33485.0, 34630.653693462744, 34664.48003279902, 34834.129146771535, 29219.707802921697, 30761.538576920917, 31033.379332412773, 31186.75252989946, 31252.843735781174, 22375.328740137822, 23436.414089647897, 23590.52818943646, 23646.645300320164, 23776.4055898597], "value": 19272.690117425253, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [327.9786813857098, 368.52092280035964, 371.50775222829753, 393.94681717968103, 401.8995251187203, 18956.241750329988, 19280.807191928077, 19864.404067877986, 20026.0, 20232.0, 20359.69460458094, 20536.38390848273, 20674.586508269826, 20747.17011319549, 21122.683159752578, 5577.553795696334, 5730.111832665933, 5887.676177810219, 5913.113033045035, 5981.611195272293, 5952.255968003999, 5953.970230148843, 5965.701714914258, 5975.462208401243, 5977.491913187362, 3826.368649172883, 3988.5811989741096, 3998.4202290667836, 4003.9199216015663, 6366.713497892606, 6375.93624063758, 6395.648239346834, 6400.615963042233, 6412.871742565169, 6419.165508483868, 5442.755076021588, 5666.688332141731, 5734.483896449339, 5745.913811292834, 5777.855553611152, 13276.336183190802, 13505.932470337684, 13513.86486135136, 18705.625887482172, 24475.877620611962, 2101.674240492726, 2212.9889350553144, 2453.852768833876, 2893.971060289391, 2923.5468502382164, 10115.089641932187, 10250.0, 10253.948730256376, 13908.860911390857, 14450.783238251435, 1805.3049575913237, 1812.8459080978, 1877.3335465909543, 1896.7723873135174, 1929.8359639430664, 82414.58792706058, 102996.42508937261, 104755.0, 107068.92931070668, 107666.0, 11009.394483303495, 11165.83251251232, 11205.0, 11220.102391808636, 11350.943245283886, 14105.576832694831, 14128.0, 14152.929235353757, 14181.432742690384, 14182.0, 6925.134358205224, 8082.353411727047, 8205.645460343792, 8230.135835737281, 8239.794005149799, 7855.685772569148, 8096.190380961974, 8270.669173233124, 8274.669013239403, 8290.005199376114, 4392.0, 4480.887977800581, 4481.910361792811, 4559.680822342404, 4578.88552786183, 1864.2077117225126, 1915.79884112169, 1923.567197380606, 1924.604661620342, 1964.2634012245408, 817.9672813087528, 888.3958907942532, 1014.624588902111, 1017.5929628148651, 1058.296233005043, 19778.90110549438, 21245.46886327823, 21348.0, 21472.67413443989, 21788.45528861791, 870.9782255443538, 939.6617217801586, 954.179405711088, 980.1472718734603, 1000.4547521600745, 10741.087007604441, 11202.327860328407, 11273.71815704614, 11291.378974156336, 11315.490802913888, 347939.0, 377455.0, 548635.0, 567308.0, 568897.0, 77977.22022779699, 88366.41069113291, 95587.56618650726, 114447.97592834318, 117866.0, 978.4814048554277, 1038.3406536849154, 1074.3178081918038, 1115.9944200279108, 1118.2619471149003, 19459.70810437873, 19541.51146221356, 19623.31318403849, 19787.40637780869, 19895.204191832163, 36639.63360366469, 43956.0, 53327.46672533225, 53738.46261537491, 54159.729201353744, 60311.396886030576, 72203.55592888007, 75123.74133810581, 77593.0, 79661.0, 7018.719251229893, 7318.524295920748, 7960.283574478093, 8038.276555110068, 8248.092709801796, 10552.788944221225, 12643.810342845049, 12868.356582170667, 12954.870451295743, 13244.0, 10861.185411094357, 11315.71710707206, 11370.31778093317, 11430.142739294752, 11493.563304586927, 10981.066609338295, 11178.329300242012, 11188.216824822184, 11507.410832562606, 11523.884761152281, 9248.768780780534, 11183.832242516532, 11645.010174135294, 11714.589989350332, 11753.529858805552, 8029.919700802917, 8112.22933821286, 8213.753587392388, 8216.958915205385, 8223.794405139919, 10819.513121909376, 11046.613368532062, 11354.091672666333, 11372.0, 11406.14453915943], "value": 9273.638577836264, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1460.306354481621, 1504.495993842063, 1709.3162734906043, 1875.4186202277288, 1902.5624106455514, 7926.920730792697, 8346.123657016016, 8354.289885359736, 8355.0, 8365.665373385058, 7327.0, 7552.9622351888165, 7564.054493188351, 7646.6941322347175, 8098.327551907871, 2071.3164655663595, 2111.8416118791074, 2224.232639739287, 2296.873671948042, 2297.6668383084443, 2317.4901521665292, 2323.2913961241816, 2324.721033475985, 2327.4763178284834, 2329.895154718042, 2257.0181970842655, 2270.6821045053707, 2301.631738921773, 2302.240260713969, 2309.872956987365, 2259.830512711545, 2266.0, 2286.0, 2294.7705229477087, 2296.781805728453, 2257.108442165352, 2267.399139228106, 2302.378357843373, 2315.455867871049, 2325.4883925536355, 8026.478278911852, 8164.0, 8256.380771442105, 8256.669733210665, 8262.793430164294, 4976.651634385595, 5150.510701483353, 5153.3815942087185, 5158.484151584849, 5164.870878228075, 2979.5232762758073, 3432.8798492052906, 3478.0609235506527, 3514.7363947704025, 3514.841832117561, 1025.661531694539, 1027.306568066552, 1058.3438268273642, 1060.448566745296, 1065.4193464561833, 9503.667371642028, 10476.895231047669, 10533.420661863594, 10612.469376531142, 10619.681409557648, 4132.264448931456, 4295.358354611892, 4367.2575662137515, 4397.604215620546, 4413.271810151353, 8463.703770368007, 8478.618462169217, 8479.15208479147, 8479.95760021196, 8503.23470887623, 4304.246756817544, 4974.8756281093265, 4982.402111746614, 4994.525520075587, 4997.625178111583, 4509.346144808985, 4645.6980296280635, 4670.0, 4689.343491911135, 4691.13214055394, 2819.7885158613185, 3419.0340554181444, 3429.125572978924, 3454.9270437737155, 3513.4378499440477, 1127.0870594818223, 1132.2696860524916, 1136.1138312116561, 1147.540983606564, 1150.6260465348796, 823.0206651920088, 847.1782371100023, 889.675268526996, 894.2622336572354, 938.202527851321, 4300.526942036374, 5363.544098751572, 5483.862903427446, 5713.143028545668, 5740.68426236561, 838.4591938199827, 847.0682249525472, 858.4463021351194, 883.6995421556702, 917.5091326140483, 3640.9987253505496, 3653.7442379033214, 3684.6499582539614, 3735.8418890144144, 3809.257194846978, 9604.567794449267, 9778.217742580577, 9884.851727224097, 9889.20886329092, 9910.702678919644, 9824.214062874953, 9836.95081524602, 9837.0, 9862.457564834003, 9867.950660246654, 7605.6577454014705, 7767.029121359943, 7779.183185765526, 7798.337141342936, 7805.921940780633, 4827.831025914076, 5468.726563671721, 5476.616636835383, 5582.0, 5811.27359080115, 8774.561271936505, 9073.546322683971, 9091.136342047495, 9101.044390338919, 9263.397879137834, 8728.258098061482, 9031.909680903369, 9224.354295199271, 9322.860157097784, 9327.067293270618, 7079.1858936223, 7176.96411517939, 7187.8562428752175, 7239.131304243525, 7253.057102576631, 7076.716931322895, 7341.853162936818, 7352.558846469229, 7780.572068536285, 7874.488158269694, 6091.695415229309, 6896.344847239504, 7004.614746189009, 7146.571205727673, 7171.605561694157, 6902.585844849325, 7003.929960700328, 7036.894446583203, 7053.894191587233, 7067.25793791665, 6173.290071641639, 6287.528435367274, 6299.937000630119, 6303.180586523723, 6332.936670633235, 4629.513901040342, 4698.177818881613, 4738.31294462308, 4783.569478746929, 4836.298736683231, 4642.094791515622, 4692.296155576691, 4837.830675926326, 4955.776990035385, 4966.577840883565], "value": 4291.204497948216, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [316.9904902852914, 341.5116383571493, 347.4458239108622, 356.05645040642304, 364.96897763690083, 4357.1264356326765, 5018.924716129261, 5124.256982737501, 5176.456472070426, 5288.497592728694, 4667.8330417395655, 5005.798608334001, 5183.0, 5197.7920883164625, 5202.271681964528, 3716.275326311357, 3913.9021524461973, 4084.2852500812387, 4092.845761552027, 4094.5810248442585, 3504.351694936445, 3878.747881387715, 3974.4237085622567, 3979.5423526294558, 4108.85619003335, 3409.3294285799902, 3499.6325385834375, 3703.703703703697, 4027.5771044040393, 4066.3493840985448, 3558.448440491702, 3735.4210097434943, 3759.1729819439815, 4068.5117785865737, 4076.634327500291, 3011.578379026938, 3346.0, 3391.5421418108485, 3485.4074807282823, 3583.5341405617105, 4740.525947405266, 4807.158747219222, 4988.201887697951, 5048.697078175322, 5060.873478163038, 3924.646781789624, 4648.697834640738, 4650.302454631798, 4660.39414876064, 4675.976620116911, 4823.080457932889, 5150.665206761549, 5392.49010277123, 5473.0, 5491.28613280275, 3399.7960122392738, 4001.579834117405, 4016.176683779818, 4017.906212216577, 4038.454808600831, 4663.903982564095, 4919.631027672903, 5645.939232180224, 5647.124695672136, 5736.856578585527, 3979.2837289288204, 4078.877633670935, 4094.61101195386, 4102.466679331727, 4111.814968326433, 3686.645152910838, 3976.343903255988, 4174.352975288835, 4195.77623859355, 4202.915941681211, 3544.9970766516835, 4028.637422631977, 4190.916181676349, 4548.0221752323405, 4751.358566593535, 4979.128652485801, 5483.972580137074, 5499.230107784913, 5557.221988921554, 5580.441955804468, 1113.4210210690396, 1238.5169783784331, 1351.1892864281353, 1366.5353779714746, 1370.9177449353072, 900.9504477253814, 952.219180272184, 967.8530940835133, 970.8203982263304, 973.1241882305982, 4316.417283666665, 4681.274402467623, 4994.875128121754, 5008.524190201925, 5037.395512538519, 5394.271773310631, 5420.1842382801415, 5444.482774136451, 5451.400345961941, 5486.204500347428, 4254.7021708480725, 4455.019895622958, 4705.482396936334, 4741.581793285381, 4856.17445034345, 2276.7495575486682, 2465.4822487277875, 2623.619575161629, 2826.795099822142, 2862.1127450490417, 4975.577075948512, 5455.072637651609, 5654.617671304772, 5704.71476426177, 5721.170430287585, 4798.4001999750035, 4831.541003604652, 5125.718085505258, 5392.24751955616, 5508.421615730291, 4919.532644398776, 5284.233786101071, 5587.580931430159, 5606.55147588192, 5625.634333768292, 4498.358099293669, 5171.0, 5387.892242155213, 5396.21754845553, 5410.648307859977, 4631.444226692818, 4937.728424936663, 5308.469153084815, 5315.787368505216, 5354.804530142623, 5656.604037717485, 5669.404712505293, 5772.624779389326, 5802.216700745345, 5848.912266316093, 3538.6815186633326, 4055.371417430303, 4056.558068914534, 4089.1003979124544, 4091.243120022805, 4876.731779752147, 5266.73666316675, 5495.719276500207, 5661.348944871393, 5681.857953551194, 4795.616350692006, 4886.364772579542, 5168.508991645787, 5224.738763061756, 5483.479069488392, 4665.953340466552, 4668.813247470063, 4916.197767272621, 5285.339332583427, 5511.5039646432015, 4350.456192975878, 4516.532127058785, 4819.49395313487, 5210.635255532076, 5388.51815750656, 5168.741562921914, 5471.343438787371, 5539.723013849372, 5560.026995275892, 5597.916031259616, 4695.647826413101, 5387.164989426645, 5446.537044351275, 5448.477763456401, 5459.727013649381], "value": 3984.6640305146034, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [624.3039011502175, 728.4463807506297, 787.9369650427963, 794.8370584030274, 805.3114587028091, 15328.846711532895, 15893.2848021839, 15897.602559936007, 15899.28453219608, 15936.760948585767, 14423.423063077491, 14585.92707036466, 14596.781048284285, 14597.693680364291, 14608.780868286984, 701.3897908819334, 726.6548389514991, 729.0413106764614, 861.4142383179451, 867.7310033889482, 890.1587999340626, 901.0358915959926, 903.5437104262338, 907.2061945797427, 908.7001289574424, 937.4750139921666, 955.6702937486556, 956.9330146889686, 960.2078285414561, 960.5725452173785, 950.8003319302954, 952.166854002751, 953.4708236928502, 959.4770849886828, 961.2982522758391, 486.511056388331, 548.2078396716746, 563.6589863132787, 709.3863807806262, 709.9929000709978, 14551.490697825526, 15199.544013679608, 15225.923870380577, 15230.0, 15257.771133433007, 13894.722105557934, 13921.30393480322, 14019.43922243109, 14045.508407205698, 14062.437502499888, 12544.81182782259, 12664.746705065847, 12699.619011429671, 12716.618501444971, 12891.484340626364, 4384.912301753979, 4394.868153955386, 4409.316555933835, 4466.441694788152, 4477.7984990675495, 103026.93946121035, 105804.47097764538, 108649.0, 109930.0, 112569.99999999919, 10947.83578246327, 11099.611513596985, 11111.111111111091, 11225.101991840633, 11328.207025508136, 22077.0, 22466.438339041655, 22601.321960341214, 22853.0, 22937.655935160987, 7423.146338171179, 7748.181605172825, 7762.922370776333, 7878.172791856887, 8040.597970101471, 4295.978520107442, 4310.181065597527, 4335.7615331157085, 4354.390385346054, 4455.242608756519, 3392.6098498672477, 3485.9651403486146, 3487.093355727529, 3489.0056333944704, 3513.789172649598, 671.318611609217, 674.7941877727341, 675.6148995072762, 709.7870638808337, 721.7329588052454, 521.9817306394334, 558.5419955636339, 584.0450862839289, 608.5375114912698, 679.4462513051847, 8194.426390152752, 8663.003754568304, 9180.0, 9324.020977797234, 9356.859647105299, 522.2297111760199, 594.6016169166669, 614.708013693492, 674.9021391898148, 678.731900899142, 3769.359208934488, 4013.478247827764, 4111.958880411217, 4309.827606895689, 4346.1090476452255, 99837.0, 100019.49990250148, 100458.0, 101097.0, 101475.49262253642, 88129.55935220284, 88625.11374886168, 89545.10454895368, 90282.0, 90651.54674226718, 77260.0, 79084.0, 79570.2042979586, 79766.60116699379, 80975.59512202401, 8437.156284371758, 8439.071702112886, 8454.112318206746, 8559.57202139903, 8587.570621468776, 44863.102737944406, 45225.0, 48458.0, 51196.0, 51408.0, 60282.698586508544, 66312.67374652576, 69547.6522617404, 70401.64799175972, 74135.62932185305, 11723.706907327385, 11764.764704705687, 11854.585089521825, 11898.821517677068, 12029.15795894314, 12760.74478510406, 12806.231626102464, 12856.228626282453, 12937.28844913501, 13357.198568085945, 9952.013872624904, 12197.695057623276, 12318.384080795746, 12330.075244356529, 12773.297468639314, 11621.12841536905, 11857.5849845255, 12056.63830085099, 12229.75540489203, 12324.0, 12025.819612705991, 12273.754524909631, 12533.248005119722, 12606.873931260938, 12608.0, 9878.62912900913, 9955.070826885776, 10347.534360953625, 10536.789264214825, 10738.677839664822, 9936.900630993598, 10607.787844242917, 10633.627823026158, 10635.680929572125, 10670.0], "value": 7328.019258236953, "unit": "score"}], "name": "dromaeo_css", "value": 7085.559034454118, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 115.0, 115.0, 114.0, 114.0, 115.0, 115.0, 115.0, 116.0, 114.0], "value": 114.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 110.0, 109.0, 147.0, 111.0, 111.0, 107.0, 109.0, 131.0, 110.0], "value": 119.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 185.0, 182.0, 183.0, 182.0, 184.0, 181.0, 181.0, 180.0, 185.0], "value": 182.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 58.0, 61.0, 101.0, 84.0, 50.0, 74.0, 74.0, 96.0, 71.0], "value": 74.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 69.0, 88.0, 69.0, 72.0, 68.0, 83.0, 69.0, 68.0, 84.0], "value": 76.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 91.0, 87.0, 90.0, 87.0, 90.0, 88.0, 90.0, 87.0], "value": 88.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 117.0, 147.0, 94.0, 94.0, 95.0, 95.0, 94.0, 95.0, 95.0], "value": 102.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 86.0, 86.0, 85.0, 88.0, 113.0, 140.0, 95.0, 86.0], "value": 95.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 55.0, 53.0, 53.0, 54.0, 54.0, 54.0, 54.0, 54.0, 54.0], "value": 54.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 73.0, 75.0, 74.0, 74.0, 72.0, 72.0, 72.0, 72.0, 70.0], "value": 72.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 90.0, 85.0, 89.0, 85.0, 85.0, 89.0, 86.0, 86.0, 87.0], "value": 87.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 141.0, 143.0, 152.0, 151.0, 142.0, 135.0, 139.0, 184.0, 139.0], "value": 146.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 173.0, 172.0, 172.0, 172.0, 169.0, 172.0, 169.0, 179.0, 170.0], "value": 172.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 67.0, 67.0, 67.0, 68.0, 66.0, 67.0, 66.0, 66.0], "value": 66.7, "unit": "score"}], "name": "kraken", "value": 1452.3000000000002, "alertThreshold": 2.0}]} 08:20:10 INFO - Return code: 0 08:20:10 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:20:10 INFO - # TBPL SUCCESS # 08:20:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:20:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:20:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:20:10 INFO - 2016-12-23 08:20:10.200 screenresolution[1666:11408] starting screenresolution argv=screenresolution get 08:20:10 INFO - 2016-12-23 08:20:10.204 screenresolution[1666:11408] Display 0: 1600x1200x32@60 08:20:10 INFO - 2016-12-23 08:20:10.210 screenresolution[1667:11411] starting screenresolution argv=screenresolution list 08:20:10 INFO - Available Modes on Display 0 08:20:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:20:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:20:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:20:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:20:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:20:10 INFO - Intel Iris: 08:20:10 INFO - Chipset Model: Intel Iris 08:20:10 INFO - Type: GPU 08:20:10 INFO - Bus: Built-In 08:20:10 INFO - VRAM (Dynamic, Max): 1536 MB 08:20:10 INFO - Vendor: Intel (0x8086) 08:20:10 INFO - Device ID: 0x0a2e 08:20:10 INFO - Revision ID: 0x0009 08:20:10 INFO - Displays: 08:20:10 INFO - Display: 08:20:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:20:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:20:10 INFO - Display Serial Number: 1600x1200 60 08:20:10 INFO - Main Display: Yes 08:20:10 INFO - Mirror: Off 08:20:10 INFO - Online: Yes 08:20:10 INFO - Rotation: Supported 08:20:10 INFO - Return code: 0 08:20:10 INFO - Running post-action listener: _resource_record_post_action 08:20:10 INFO - [mozharness: 2016-12-23 16:20:10.448800Z] Finished run-tests step (success) 08:20:10 INFO - Running post-run listener: _resource_record_post_run 08:20:10 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 32246784; Write bytes: 550644736; Read time: 195; Write time: 1655 08:20:10 INFO - TinderboxPrint: CPU usage
53.9% 08:20:10 INFO - TinderboxPrint: I/O read bytes / time
32,246,784 / 195 08:20:10 INFO - TinderboxPrint: I/O write bytes / time
550,644,736 / 1,655 08:20:10 INFO - TinderboxPrint: CPU idle
2,216.4 (46.1%) 08:20:10 INFO - TinderboxPrint: CPU system
963.7 (20.0%) 08:20:10 INFO - TinderboxPrint: CPU user
1,629.5 (33.9%) 08:20:10 INFO - TinderboxPrint: Swap in / out
277,409,792 / 0 08:20:10 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 247886336; Write bytes: 242254848; Read time: 18100; Write time: 516 08:20:10 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 28916736; Write bytes: 296728576; Read time: 172; Write time: 1104 08:20:10 INFO - Running post-run listener: _upload_blobber_files 08:20:10 INFO - Blob upload gear active. 08:20:10 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:20:10 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 08:20:10 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'] 08:20:10 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 08:20:11 INFO - (blobuploader) - INFO - Open directory for files ... 08:20:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:20:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:20:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:20:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:20:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:20:12 INFO - (blobuploader) - INFO - Done attempting. 08:20:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:20:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:20:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:20:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:20:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:20:13 INFO - (blobuploader) - INFO - Done attempting. 08:20:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 08:20:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:20:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:20:14 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 08:20:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:20:14 INFO - (blobuploader) - INFO - Done attempting. 08:20:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:20:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:20:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:20:16 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:20:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:20:16 INFO - (blobuploader) - INFO - Done attempting. 08:20:16 INFO - (blobuploader) - INFO - Iteration through files over. 08:20:16 INFO - Return code: 0 08:20:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:20:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:20:16 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/62fb777aa3d69b08c467b050ce206257b7fb0aaae82c3d5ed3dd13ae9fcf77afb83938f526093975ff68ac45cb4229811afbe3209f39615b3553358b57cf5b09", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/11420110892e5a47164e10aefbebf3c60977a0de8d06e6f8e4a5787d711f690e3d3de9c5f811a9f0f76a2b11768e1ab0f9f628cc50ed7da3d06045afb78694d2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3ec73c264add78655c7d3faf576b37f8340ae1d3683a53c0d60eb23fe2a78c367d96775286e3468ed474575245e305856d4d556bc6f1b610b1340bf2899f3a76", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bfdb4cf04926e50b0991a81a7193033af91ede214a0bd2f30d715e6fa381319536015e7f5b6ce736023dc33254a063fb06b3acd9af701c205c7a7e47ada27696"} 08:20:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:20:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:20:16 INFO - Contents: 08:20:16 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/62fb777aa3d69b08c467b050ce206257b7fb0aaae82c3d5ed3dd13ae9fcf77afb83938f526093975ff68ac45cb4229811afbe3209f39615b3553358b57cf5b09", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/11420110892e5a47164e10aefbebf3c60977a0de8d06e6f8e4a5787d711f690e3d3de9c5f811a9f0f76a2b11768e1ab0f9f628cc50ed7da3d06045afb78694d2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3ec73c264add78655c7d3faf576b37f8340ae1d3683a53c0d60eb23fe2a78c367d96775286e3468ed474575245e305856d4d556bc6f1b610b1340bf2899f3a76", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bfdb4cf04926e50b0991a81a7193033af91ede214a0bd2f30d715e6fa381319536015e7f5b6ce736023dc33254a063fb06b3acd9af701c205c7a7e47ada27696"} 08:20:16 INFO - Running post-run listener: copy_logs_to_upload_dir 08:20:16 INFO - Copying logs to upload dir... 08:20:16 INFO - mkdir: /builds/slave/test/build/upload/logs 08:20:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1343.062168 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 23 secs) (at 2016-12-23 08:20:16.385712) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-23 08:20:16.389443) ========= 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.0hxTnN1zwb/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.90LM3GUIRR/Listeners TMPDIR=/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/62fb777aa3d69b08c467b050ce206257b7fb0aaae82c3d5ed3dd13ae9fcf77afb83938f526093975ff68ac45cb4229811afbe3209f39615b3553358b57cf5b09", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/11420110892e5a47164e10aefbebf3c60977a0de8d06e6f8e4a5787d711f690e3d3de9c5f811a9f0f76a2b11768e1ab0f9f628cc50ed7da3d06045afb78694d2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3ec73c264add78655c7d3faf576b37f8340ae1d3683a53c0d60eb23fe2a78c367d96775286e3468ed474575245e305856d4d556bc6f1b610b1340bf2899f3a76", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bfdb4cf04926e50b0991a81a7193033af91ede214a0bd2f30d715e6fa381319536015e7f5b6ce736023dc33254a063fb06b3acd9af701c205c7a7e47ada27696"} build_url:https://queue.taskcluster.net/v1/task/OVun-xbOTUS6TnAaqtLVfA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011291 build_url: 'https://queue.taskcluster.net/v1/task/OVun-xbOTUS6TnAaqtLVfA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/62fb777aa3d69b08c467b050ce206257b7fb0aaae82c3d5ed3dd13ae9fcf77afb83938f526093975ff68ac45cb4229811afbe3209f39615b3553358b57cf5b09", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/11420110892e5a47164e10aefbebf3c60977a0de8d06e6f8e4a5787d711f690e3d3de9c5f811a9f0f76a2b11768e1ab0f9f628cc50ed7da3d06045afb78694d2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3ec73c264add78655c7d3faf576b37f8340ae1d3683a53c0d60eb23fe2a78c367d96775286e3468ed474575245e305856d4d556bc6f1b610b1340bf2899f3a76", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bfdb4cf04926e50b0991a81a7193033af91ede214a0bd2f30d715e6fa381319536015e7f5b6ce736023dc33254a063fb06b3acd9af701c205c7a7e47ada27696"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-23 08:20:16.427809) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-23 08:20:16.428211) ========= 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.0hxTnN1zwb/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.90LM3GUIRR/Listeners TMPDIR=/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/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.005308 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-23 08:20:16.476929) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-23 08:20:16.477251) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-23 08:20:16.477562) ========= ========= Total master_lag: 0.20 =========