builder: graphics_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0233 starttime: 1482519754.07 results: success (0) buildid: 20161223102637 builduid: e36686c1208045e5ab4375b601329c2a revision: a731b493e8c74008b3a63abaaf7da822122de793 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-23 11:02:34.068835) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-23 11:02:34.069423) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-23 11:02:34.069837) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-23 11:02:34.190925) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-23 11:02:34.191235) ========= 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.HnULz9L8nS/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.7ekeZbwtwN/Listeners TMPDIR=/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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 11:02:34-- 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% 18.8M=0.001s 2016-12-23 11:02:34 (18.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.122660 ========= master_lag: 0.84 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-23 11:02:35.158648) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-23 11:02:35.158979) ========= 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.HnULz9L8nS/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.7ekeZbwtwN/Listeners TMPDIR=/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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.025028 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-23 11:02:35.211042) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-23 11:02:35.211384) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev a731b493e8c74008b3a63abaaf7da822122de793 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/graphics --rev a731b493e8c74008b3a63abaaf7da822122de793 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.HnULz9L8nS/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.7ekeZbwtwN/Listeners TMPDIR=/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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 11:02:35,322 truncating revision to first 12 chars 2016-12-23 11:02:35,322 Setting DEBUG logging. 2016-12-23 11:02:35,322 attempt 1/10 2016-12-23 11:02:35,322 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/a731b493e8c7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-23 11:02:36,109 unpacking tar archive at: graphics-a731b493e8c7/testing/mozharness/ program finished with exit code 0 elapsedTime=1.061005 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-23 11:02:36.308961) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-23 11:02:36.309297) ========= script_repo_revision: a731b493e8c74008b3a63abaaf7da822122de793 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-23 11:02:36.309685) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-23 11:02:36.309965) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-23 11:02:36.329187) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-12-23 11:02:36.329474) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Graphics --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 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', 'Graphics', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Graphics'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.HnULz9L8nS/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.7ekeZbwtwN/Listeners TMPDIR=/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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 11:02:36 INFO - MultiFileLogger online at 20161223 11:02:36 in /builds/slave/test 11:02:36 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Graphics --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 11:02:36 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:02:36 INFO - {'append_to_log': False, 11:02:36 INFO - 'base_work_dir': '/builds/slave/test', 11:02:36 INFO - 'blob_upload_branch': 'Graphics', 11:02:36 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:02:36 INFO - 'branch': 'Graphics', 11:02:36 INFO - 'buildbot_json_path': 'buildprops.json', 11:02:36 INFO - 'config_files': ('talos/mac_config.py',), 11:02:36 INFO - 'default_actions': ('clobber', 11:02:36 INFO - 'read-buildbot-config', 11:02:36 INFO - 'download-and-extract', 11:02:36 INFO - 'populate-webroot', 11:02:36 INFO - 'create-virtualenv', 11:02:36 INFO - 'install', 11:02:36 INFO - 'run-tests'), 11:02:36 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:02:36 INFO - 'download_minidump_stackwalk': True, 11:02:36 INFO - 'download_symbols': 'ondemand', 11:02:36 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:02:36 INFO - 'tooltool.py': '/tools/tooltool.py', 11:02:36 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:02:36 INFO - '/tools/misc-python/virtualenv.py')}, 11:02:36 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:02:36 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:02:36 INFO - 'installer_path': 'installer.exe', 11:02:36 INFO - 'log_level': 'info', 11:02:36 INFO - 'log_name': 'talos', 11:02:36 INFO - 'log_to_console': True, 11:02:36 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:02:36 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:02:36 INFO - 'opt_config_files': (), 11:02:36 INFO - 'pip_index': False, 11:02:36 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:02:36 INFO - 'cmd': ('bash', 11:02:36 INFO - '-c', 11:02:36 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:02:36 INFO - 'enabled': True, 11:02:36 INFO - 'halt_on_failure': False, 11:02:36 INFO - 'name': 'check_screen_resolution'},), 11:02:36 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:02:36 INFO - 'cmd': ('bash', 11:02:36 INFO - '-c', 11:02:36 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:02:36 INFO - 'enabled': True, 11:02:36 INFO - 'halt_on_failure': False, 11:02:36 INFO - 'name': 'check_screen_resolution'},), 11:02:36 INFO - 'run_cmd_checks_enabled': True, 11:02:36 INFO - 'sps_profile': False, 11:02:36 INFO - 'sps_profile_interval': 0, 11:02:36 INFO - 'suite': 'dromaeojs', 11:02:36 INFO - 'system_bits': '32', 11:02:36 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:02:36 INFO - 'title': 't-yosemite-r7-0233', 11:02:36 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:02:36 INFO - 'use_talos_json': True, 11:02:36 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:02:36 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:02:36 INFO - 'work_dir': 'build'} 11:02:36 INFO - [mozharness: 2016-12-23 19:02:36.535242Z] Running clobber step. 11:02:36 INFO - Running pre-action listener: _resource_record_pre_action 11:02:36 INFO - Running main action method: clobber 11:02:36 INFO - rmtree: /builds/slave/test/build 11:02:36 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:02:39 INFO - Running post-action listener: _resource_record_post_action 11:02:39 INFO - [mozharness: 2016-12-23 19:02:39.037885Z] Finished clobber step (success) 11:02:39 INFO - [mozharness: 2016-12-23 19:02:39.038012Z] Running read-buildbot-config step. 11:02:39 INFO - Running pre-action listener: _resource_record_pre_action 11:02:39 INFO - Running main action method: read_buildbot_config 11:02:39 INFO - Using buildbot properties: 11:02:39 INFO - { 11:02:39 INFO - "project": "", 11:02:39 INFO - "product": "firefox", 11:02:39 INFO - "script_repo_revision": "default", 11:02:39 INFO - "builddir": "graphics_yosemite_r7_test-dromaeojs", 11:02:39 INFO - "repository": "", 11:02:39 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 graphics talos dromaeojs", 11:02:39 INFO - "buildid": "20161223102637", 11:02:39 INFO - "pgo_build": "False", 11:02:39 INFO - "basedir": "/builds/slave/test", 11:02:39 INFO - "buildnumber": 1, 11:02:39 INFO - "slavename": "t-yosemite-r7-0233", 11:02:39 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 11:02:39 INFO - "platform": "yosemite_r7", 11:02:39 INFO - "scheduler": "tests-graphics-macosx64-talos", 11:02:39 INFO - "branch": "graphics", 11:02:39 INFO - "repo_path": "projects/graphics", 11:02:39 INFO - "revision": "a731b493e8c74008b3a63abaaf7da822122de793", 11:02:39 INFO - "stage_platform": "macosx64", 11:02:39 INFO - "builduid": "e36686c1208045e5ab4375b601329c2a", 11:02:39 INFO - "slavebuilddir": "test" 11:02:39 INFO - } 11:02:39 INFO - Found installer url https://queue.taskcluster.net/v1/task/KurghkI5RfGWnolUqbECNw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 11:02:39 INFO - Running post-action listener: _resource_record_post_action 11:02:39 INFO - [mozharness: 2016-12-23 19:02:39.042026Z] Finished read-buildbot-config step (success) 11:02:39 INFO - [mozharness: 2016-12-23 19:02:39.042303Z] Running download-and-extract step. 11:02:39 INFO - Running pre-action listener: _resource_record_pre_action 11:02:39 INFO - Running main action method: download_and_extract 11:02:39 INFO - mkdir: /builds/slave/test/build/tests 11:02:39 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')]} 11:02:39 INFO - trying https://queue.taskcluster.net/v1/task/KurghkI5RfGWnolUqbECNw/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 11:02:39 INFO - Downloading https://queue.taskcluster.net/v1/task/KurghkI5RfGWnolUqbECNw/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 11:02:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KurghkI5RfGWnolUqbECNw/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 11:02:40 INFO - Downloaded 1199 bytes. 11:02:40 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 11:02:40 INFO - Using the following test package requirements: 11:02:40 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 11:02:40 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 11:02:40 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 11:02:40 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 11:02:40 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 11:02:40 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 11:02:40 INFO - u'jsshell-mac.zip'], 11:02:40 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 11:02:40 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 11:02:40 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 11:02:40 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 11:02:40 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 11:02:40 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 11:02:40 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 11:02:40 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 11:02:40 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 11:02:40 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 11:02:40 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 11:02:40 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 11:02:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KurghkI5RfGWnolUqbECNw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 11:02:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KurghkI5RfGWnolUqbECNw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 11:02:40 INFO - Fetch https://queue.taskcluster.net/v1/task/KurghkI5RfGWnolUqbECNw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 11:02:41 INFO - Expected file size: 16289454 11:02:41 INFO - Obtained file size: 16289454 11:02:48 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 11:02:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KurghkI5RfGWnolUqbECNw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 11:02:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KurghkI5RfGWnolUqbECNw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 11:02:48 INFO - Fetch https://queue.taskcluster.net/v1/task/KurghkI5RfGWnolUqbECNw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 11:02:49 INFO - Expected file size: 16289454 11:02:49 INFO - Obtained file size: 16289454 11:02:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KurghkI5RfGWnolUqbECNw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 11:02:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KurghkI5RfGWnolUqbECNw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 11:02:55 INFO - Fetch https://queue.taskcluster.net/v1/task/KurghkI5RfGWnolUqbECNw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 11:02:56 INFO - Expected file size: 14409776 11:02:56 INFO - Obtained file size: 14409776 11:02:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:02:57 INFO - trying https://queue.taskcluster.net/v1/task/KurghkI5RfGWnolUqbECNw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 11:02:57 INFO - Downloading https://queue.taskcluster.net/v1/task/KurghkI5RfGWnolUqbECNw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 11:02:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KurghkI5RfGWnolUqbECNw/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 11:02:59 INFO - Downloaded 69648700 bytes. 11:02:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KurghkI5RfGWnolUqbECNw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 11:02:59 INFO - mkdir: /builds/slave/test/properties 11:02:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:02:59 INFO - Writing to file /builds/slave/test/properties/build_url 11:02:59 INFO - Contents: 11:02:59 INFO - build_url:https://queue.taskcluster.net/v1/task/KurghkI5RfGWnolUqbECNw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 11:02:59 INFO - Running post-action listener: _resource_record_post_action 11:02:59 INFO - Running post-action listener: set_extra_try_arguments 11:02:59 INFO - [mozharness: 2016-12-23 19:02:59.737845Z] Finished download-and-extract step (success) 11:02:59 INFO - [mozharness: 2016-12-23 19:02:59.737980Z] Running populate-webroot step. 11:02:59 INFO - Running pre-action listener: _resource_record_pre_action 11:02:59 INFO - Running main action method: populate_webroot 11:02:59 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:02:59 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:02:59 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:02:59 INFO - '--tppagecycles', 11:02:59 INFO - '7'], 11:02:59 INFO - 'tests': ['tsvgm']}}, 11:02:59 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:02:59 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:02:59 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:02:59 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:02:59 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:02:59 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:02:59 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 11:02:59 INFO - 'talos_options': ['--disable-e10s'], 11:02:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:02:59 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:02:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:02:59 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 11:02:59 INFO - 'talos_options': ['--disable-e10s'], 11:02:59 INFO - 'tests': ['damp', 'tps']}, 11:02:59 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:02:59 INFO - 'tests': ['damp', 'tps']}, 11:02:59 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:02:59 INFO - 'tests': ['dromaeo_dom']}, 11:02:59 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:02:59 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:02:59 INFO - 'tests': ['basic_compositor_video']}, 11:02:59 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:02:59 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:02:59 INFO - 'tests': ['a11yr', 11:02:59 INFO - 'ts_paint', 11:02:59 INFO - 'tpaint', 11:02:59 INFO - 'sessionrestore', 11:02:59 INFO - 'sessionrestore_no_auto_restore', 11:02:59 INFO - 'tabpaint']}, 11:02:59 INFO - 'other-e10s': {'tests': ['a11yr', 11:02:59 INFO - 'ts_paint', 11:02:59 INFO - 'tpaint', 11:02:59 INFO - 'sessionrestore', 11:02:59 INFO - 'sessionrestore_no_auto_restore', 11:02:59 INFO - 'tabpaint']}, 11:02:59 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:02:59 INFO - 'ts_paint', 11:02:59 INFO - 'tpaint', 11:02:59 INFO - 'sessionrestore', 11:02:59 INFO - 'sessionrestore_no_auto_restore', 11:02:59 INFO - 'tabpaint']}, 11:02:59 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:02:59 INFO - 'ts_paint', 11:02:59 INFO - 'tpaint', 11:02:59 INFO - 'sessionrestore', 11:02:59 INFO - 'sessionrestore_no_auto_restore', 11:02:59 INFO - 'tabpaint']}, 11:02:59 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:02:59 INFO - 'tests': ['a11yr', 11:02:59 INFO - 'ts_paint', 11:02:59 INFO - 'tpaint', 11:02:59 INFO - 'sessionrestore', 11:02:59 INFO - 'sessionrestore_no_auto_restore', 11:02:59 INFO - 'tabpaint']}, 11:02:59 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:02:59 INFO - 'tests': ['a11yr', 11:02:59 INFO - 'ts_paint', 11:02:59 INFO - 'tpaint', 11:02:59 INFO - 'sessionrestore', 11:02:59 INFO - 'sessionrestore_no_auto_restore', 11:02:59 INFO - 'tabpaint']}, 11:02:59 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:02:59 INFO - 'tests': ['tsvgx', 11:02:59 INFO - 'tsvgr_opacity', 11:02:59 INFO - 'tart', 11:02:59 INFO - 'tscrollx', 11:02:59 INFO - 'cart', 11:02:59 INFO - 'tsvg_static']}, 11:02:59 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:02:59 INFO - 'tsvgr_opacity', 11:02:59 INFO - 'tart', 11:02:59 INFO - 'tscrollx', 11:02:59 INFO - 'cart', 11:02:59 INFO - 'tsvg_static']}, 11:02:59 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 11:02:59 INFO - 'talos_options': ['--disable-e10s'], 11:02:59 INFO - 'tests': ['tp5o']}, 11:02:59 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:02:59 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 11:02:59 INFO - 'talos_options': ['--disable-e10s', 11:02:59 INFO - '--xperf_path', 11:02:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:02:59 INFO - 'tests': ['tp5n']}, 11:02:59 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:02:59 INFO - 'talos_options': ['--xperf_path', 11:02:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:02:59 INFO - 'tests': ['tp5n']}}, 11:02:59 INFO - 'talos.zip': {'path': '', 11:02:59 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:02:59 INFO - Running post-action listener: _resource_record_post_action 11:02:59 INFO - [mozharness: 2016-12-23 19:02:59.756544Z] Finished populate-webroot step (success) 11:02:59 INFO - [mozharness: 2016-12-23 19:02:59.756646Z] Running create-virtualenv step. 11:02:59 INFO - Running pre-action listener: _resource_record_pre_action 11:02:59 INFO - Running main action method: create_virtualenv 11:02:59 INFO - Creating virtualenv /builds/slave/test/build/venv 11:02:59 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 11:02:59 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:02:59 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:02:59 INFO - Using real prefix '/tools/python27' 11:02:59 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:03:00 INFO - Installing distribute.............................................................................................................................................................................................done. 11:03:03 INFO - Installing pip.................done. 11:03:03 INFO - Return code: 0 11:03:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:03:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:03:03 INFO - Reading from file tmpfile_stdout 11:03:03 INFO - Output received: 11:03:03 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) 11:03:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:03:03 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')]} 11:03:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:03:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:03:03 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')]} 11:03:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:03:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:03:03 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 0x10e2d0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2d3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8cb3d91eb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e0aed30>, '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 0x10dfd5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e0a3be8>, '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.HnULz9L8nS/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.7ekeZbwtwN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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 11:03:03 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 11:03:03 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 11:03:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HnULz9L8nS/Render', 11:03:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:03:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:03:03 INFO - 'HOME': '/Users/cltbld', 11:03:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:03:03 INFO - 'LOGNAME': 'cltbld', 11:03:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:03:03 INFO - 'MOZ_NO_REMOTE': '1', 11:03:03 INFO - 'NO_EM_RESTART': '1', 11:03:03 INFO - 'PAGER': '/bin/cat', 11:03:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:03:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:03:03 INFO - 'PWD': '/builds/slave/test', 11:03:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:03:03 INFO - 'SHELL': '/bin/bash', 11:03:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7ekeZbwtwN/Listeners', 11:03:03 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 11:03:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:03:03 INFO - 'USER': 'cltbld', 11:03:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:03:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:03:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:03:03 INFO - 'XPC_FLAGS': '0x0', 11:03:03 INFO - 'XPC_SERVICE_NAME': '0', 11:03:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:03:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:03:04 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 11:03:04 INFO - Cleaning up... 11:03:04 INFO - Return code: 0 11:03:04 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:03:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:03:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:03:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:03:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:03:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:03:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:03:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10e2d0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2d3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8cb3d91eb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e0aed30>, '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 0x10dfd5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e0a3be8>, '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.HnULz9L8nS/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.7ekeZbwtwN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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 11:03:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 11:03:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 11:03:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HnULz9L8nS/Render', 11:03:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:03:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:03:04 INFO - 'HOME': '/Users/cltbld', 11:03:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:03:04 INFO - 'LOGNAME': 'cltbld', 11:03:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:03:04 INFO - 'MOZ_NO_REMOTE': '1', 11:03:04 INFO - 'NO_EM_RESTART': '1', 11:03:04 INFO - 'PAGER': '/bin/cat', 11:03:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:03:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:03:04 INFO - 'PWD': '/builds/slave/test', 11:03:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:03:04 INFO - 'SHELL': '/bin/bash', 11:03:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7ekeZbwtwN/Listeners', 11:03:04 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 11:03:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:03:04 INFO - 'USER': 'cltbld', 11:03:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:03:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:03:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:03:04 INFO - 'XPC_FLAGS': '0x0', 11:03:04 INFO - 'XPC_SERVICE_NAME': '0', 11:03:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:03:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:03:04 INFO - Downloading/unpacking psutil>=3.1.1 11:03:04 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 11:03:04 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 11:03:04 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 11:03:04 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 11:03:04 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 11:03:04 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 11:03:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:03:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:03:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:03:08 INFO - Installing collected packages: psutil 11:03:08 INFO - Running setup.py install for psutil 11:03:08 INFO - building 'psutil._psutil_osx' extension 11:03:08 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 11:03:08 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 11:03:09 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 11:03:09 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 11:03:09 INFO - building 'psutil._psutil_posix' extension 11:03:09 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 11:03:09 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:03:09 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:03:09 INFO - ^ 11:03:09 INFO - 1 warning generated. 11:03:09 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 11:03:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:03:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:03:09 INFO - Successfully installed psutil 11:03:09 INFO - Cleaning up... 11:03:09 INFO - Return code: 0 11:03:09 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:03:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:03:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:03:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:03:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:03:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:03:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:03:09 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 0x10e2d0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2d3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8cb3d91eb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e0aed30>, '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 0x10dfd5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e0a3be8>, '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.HnULz9L8nS/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.7ekeZbwtwN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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 11:03:09 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 11:03:09 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 11:03:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HnULz9L8nS/Render', 11:03:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:03:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:03:09 INFO - 'HOME': '/Users/cltbld', 11:03:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:03:09 INFO - 'LOGNAME': 'cltbld', 11:03:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:03:09 INFO - 'MOZ_NO_REMOTE': '1', 11:03:09 INFO - 'NO_EM_RESTART': '1', 11:03:09 INFO - 'PAGER': '/bin/cat', 11:03:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:03:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:03:09 INFO - 'PWD': '/builds/slave/test', 11:03:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:03:09 INFO - 'SHELL': '/bin/bash', 11:03:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7ekeZbwtwN/Listeners', 11:03:09 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 11:03:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:03:09 INFO - 'USER': 'cltbld', 11:03:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:03:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:03:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:03:09 INFO - 'XPC_FLAGS': '0x0', 11:03:09 INFO - 'XPC_SERVICE_NAME': '0', 11:03:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:03:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:03:09 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:03:09 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 11:03:09 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 11:03:09 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 11:03:09 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 11:03:09 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 11:03:09 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 11:03:12 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:03:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:03:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:03:12 INFO - Installing collected packages: mozsystemmonitor 11:03:12 INFO - Running setup.py install for mozsystemmonitor 11:03:12 INFO - Successfully installed mozsystemmonitor 11:03:12 INFO - Cleaning up... 11:03:12 INFO - Return code: 0 11:03:12 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:03: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')]} 11:03:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:03:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:03: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')]} 11:03:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:03:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:03:12 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 0x10e2d0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2d3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8cb3d91eb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e0aed30>, '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 0x10dfd5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e0a3be8>, '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.HnULz9L8nS/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.7ekeZbwtwN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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 11:03:12 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 11:03:12 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 11:03:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HnULz9L8nS/Render', 11:03:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:03:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:03:12 INFO - 'HOME': '/Users/cltbld', 11:03:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:03:12 INFO - 'LOGNAME': 'cltbld', 11:03:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:03:12 INFO - 'MOZ_NO_REMOTE': '1', 11:03:12 INFO - 'NO_EM_RESTART': '1', 11:03:12 INFO - 'PAGER': '/bin/cat', 11:03:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:03:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:03:12 INFO - 'PWD': '/builds/slave/test', 11:03:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:03:12 INFO - 'SHELL': '/bin/bash', 11:03:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7ekeZbwtwN/Listeners', 11:03:12 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 11:03:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:03:12 INFO - 'USER': 'cltbld', 11:03:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:03:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:03:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:03:12 INFO - 'XPC_FLAGS': '0x0', 11:03:12 INFO - 'XPC_SERVICE_NAME': '0', 11:03:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:03:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:03:12 INFO - Downloading/unpacking jsonschema==2.5.1 11:03:12 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 11:03:12 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 11:03:12 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 11:03:12 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 11:03:12 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 11:03:12 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 11:03:15 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:03:15 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:03:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:03:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:03:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:03:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:03:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:03:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:03:15 INFO - Downloading functools32-3.2.3-2.tar.gz 11:03:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:03:16 INFO - warning: no files found matching '*.txt' 11:03:16 INFO - no previously-included directories found matching 'build' 11:03:16 INFO - no previously-included directories found matching 'dist' 11:03:16 INFO - no previously-included directories found matching '.git*' 11:03:16 INFO - Installing collected packages: jsonschema, functools32 11:03:16 INFO - Running setup.py install for functools32 11:03:16 INFO - warning: no files found matching '*.txt' 11:03:16 INFO - no previously-included directories found matching 'build' 11:03:16 INFO - no previously-included directories found matching 'dist' 11:03:16 INFO - no previously-included directories found matching '.git*' 11:03:16 INFO - Successfully installed jsonschema functools32 11:03:16 INFO - Cleaning up... 11:03:16 INFO - Return code: 0 11:03:16 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:03:16 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')]} 11:03:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:03:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:03:16 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')]} 11:03:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:03:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:03:16 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 0x10e2d0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2d3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8cb3d91eb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e0aed30>, '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 0x10dfd5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e0a3be8>, '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.HnULz9L8nS/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.7ekeZbwtwN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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 11:03:16 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 11:03:16 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 11:03:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HnULz9L8nS/Render', 11:03:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:03:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:03:16 INFO - 'HOME': '/Users/cltbld', 11:03:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:03:16 INFO - 'LOGNAME': 'cltbld', 11:03:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:03:16 INFO - 'MOZ_NO_REMOTE': '1', 11:03:16 INFO - 'NO_EM_RESTART': '1', 11:03:16 INFO - 'PAGER': '/bin/cat', 11:03:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:03:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:03:16 INFO - 'PWD': '/builds/slave/test', 11:03:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:03:16 INFO - 'SHELL': '/bin/bash', 11:03:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7ekeZbwtwN/Listeners', 11:03:16 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 11:03:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:03:16 INFO - 'USER': 'cltbld', 11:03:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:03:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:03:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:03:16 INFO - 'XPC_FLAGS': '0x0', 11:03:16 INFO - 'XPC_SERVICE_NAME': '0', 11:03:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:03:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:03:16 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:03:16 INFO - Cleaning up... 11:03:16 INFO - Return code: 0 11:03:16 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:03:16 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')]} 11:03:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:03:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:03:16 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')]} 11:03:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:03:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:03:16 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 0x10e2d0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2d3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8cb3d91eb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e0aed30>, '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 0x10dfd5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e0a3be8>, '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.HnULz9L8nS/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.7ekeZbwtwN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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 11:03:16 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 11:03:16 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 11:03:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HnULz9L8nS/Render', 11:03:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:03:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:03:16 INFO - 'HOME': '/Users/cltbld', 11:03:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:03:16 INFO - 'LOGNAME': 'cltbld', 11:03:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:03:16 INFO - 'MOZ_NO_REMOTE': '1', 11:03:16 INFO - 'NO_EM_RESTART': '1', 11:03:16 INFO - 'PAGER': '/bin/cat', 11:03:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:03:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:03:16 INFO - 'PWD': '/builds/slave/test', 11:03:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:03:16 INFO - 'SHELL': '/bin/bash', 11:03:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7ekeZbwtwN/Listeners', 11:03:16 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 11:03:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:03:16 INFO - 'USER': 'cltbld', 11:03:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:03:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:03:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:03:16 INFO - 'XPC_FLAGS': '0x0', 11:03:16 INFO - 'XPC_SERVICE_NAME': '0', 11:03:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:03:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:03:16 INFO - Downloading/unpacking blobuploader==1.2.4 11:03:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:03:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:03:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:03:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:03:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:03:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:03:19 INFO - Downloading blobuploader-1.2.4.tar.gz 11:03:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:03:19 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:03:19 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 11:03:19 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 11:03:19 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 11:03:19 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 11:03:19 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 11:03:19 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 11:03:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:03:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:03:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:03:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:03:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:03:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:03:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:03:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:03:20 INFO - Downloading docopt-0.6.1.tar.gz 11:03:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:03:20 INFO - Installing collected packages: blobuploader, requests, docopt 11:03:20 INFO - Running setup.py install for blobuploader 11:03:20 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:03:20 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:03:20 INFO - Running setup.py install for requests 11:03:21 INFO - Running setup.py install for docopt 11:03:21 INFO - Successfully installed blobuploader requests docopt 11:03:21 INFO - Cleaning up... 11:03:21 INFO - Return code: 0 11:03:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:03:21 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')]} 11:03:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:03:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:03:21 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')]} 11:03:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:03:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:03:21 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 0x10e2d0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2d3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8cb3d91eb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e0aed30>, '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 0x10dfd5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e0a3be8>, '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.HnULz9L8nS/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.7ekeZbwtwN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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 11:03:21 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 11:03:21 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 11:03:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HnULz9L8nS/Render', 11:03:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:03:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:03:21 INFO - 'HOME': '/Users/cltbld', 11:03:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:03:21 INFO - 'LOGNAME': 'cltbld', 11:03:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:03:21 INFO - 'MOZ_NO_REMOTE': '1', 11:03:21 INFO - 'NO_EM_RESTART': '1', 11:03:21 INFO - 'PAGER': '/bin/cat', 11:03:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:03:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:03:21 INFO - 'PWD': '/builds/slave/test', 11:03:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:03:21 INFO - 'SHELL': '/bin/bash', 11:03:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7ekeZbwtwN/Listeners', 11:03:21 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 11:03:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:03:21 INFO - 'USER': 'cltbld', 11:03:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:03:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:03:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:03:21 INFO - 'XPC_FLAGS': '0x0', 11:03:21 INFO - 'XPC_SERVICE_NAME': '0', 11:03:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:03:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:03:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:03:21 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-La1BPx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:03:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:03:22 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-pWzBNA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:03:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:03:22 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-m2gDD0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:03:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:03:22 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-7fw__n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:03:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:03:22 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-xfQd1y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:03:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:03:22 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-RiJFn4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:03:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:03:22 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-TyTVIN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:03:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:03:22 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-9OeFCS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:03:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:03:22 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-D5QxIj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:03:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:03:22 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-tj09hq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:03:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:03:22 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-bUiP_U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:03:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:03:23 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-8m4ykd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:03:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:03:23 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-I7IqTc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:03:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:03:23 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-NPELv2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:03:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:03:23 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-E5MI0O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:03:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:03:23 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-6HmkDQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:03:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:03:23 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-eAHkaB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:03:23 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:03:23 INFO - Running setup.py install for manifestparser 11:03:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:03:23 INFO - Running setup.py install for mozcrash 11:03:23 INFO - Running setup.py install for mozdebug 11:03:24 INFO - Running setup.py install for mozdevice 11:03:24 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:03:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:03:24 INFO - Running setup.py install for mozfile 11:03:24 INFO - Running setup.py install for mozhttpd 11:03:24 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:03:24 INFO - Running setup.py install for mozinfo 11:03:24 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:03:24 INFO - Running setup.py install for mozInstall 11:03:24 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:03:24 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:03:24 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:03:24 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:03:24 INFO - Running setup.py install for mozleak 11:03:24 INFO - Running setup.py install for mozlog 11:03:25 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:03:25 INFO - Running setup.py install for moznetwork 11:03:25 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:03:25 INFO - Running setup.py install for mozprocess 11:03:25 INFO - Running setup.py install for mozprofile 11:03:25 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:03:25 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:03:25 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:03:25 INFO - Running setup.py install for mozrunner 11:03:25 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:03:25 INFO - Running setup.py install for mozscreenshot 11:03:25 INFO - Running setup.py install for moztest 11:03:26 INFO - Running setup.py install for mozversion 11:03:26 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:03:26 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:03:26 INFO - Cleaning up... 11:03:26 INFO - Return code: 0 11:03:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:03:26 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')]} 11:03:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:03:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:03:26 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')]} 11:03:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:03:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:03:26 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 0x10e2d0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2d3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8cb3d91eb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e0aed30>, '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 0x10dfd5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e0a3be8>, '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.HnULz9L8nS/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.7ekeZbwtwN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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 11:03:26 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 11:03:26 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 11:03:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HnULz9L8nS/Render', 11:03:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:03:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:03:26 INFO - 'HOME': '/Users/cltbld', 11:03:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:03:26 INFO - 'LOGNAME': 'cltbld', 11:03:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:03:26 INFO - 'MOZ_NO_REMOTE': '1', 11:03:26 INFO - 'NO_EM_RESTART': '1', 11:03:26 INFO - 'PAGER': '/bin/cat', 11:03:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:03:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:03:26 INFO - 'PWD': '/builds/slave/test', 11:03:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:03:26 INFO - 'SHELL': '/bin/bash', 11:03:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7ekeZbwtwN/Listeners', 11:03:26 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 11:03:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:03:26 INFO - 'USER': 'cltbld', 11:03:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:03:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:03:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:03:26 INFO - 'XPC_FLAGS': '0x0', 11:03:26 INFO - 'XPC_SERVICE_NAME': '0', 11:03:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:03:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:03:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:03:26 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-zgmOlB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:03:26 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)) 11:03:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:03:26 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-EEbD0E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:03:26 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)) 11:03:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:03:26 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-nxWFKx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:03:26 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)) 11:03:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:03:26 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-lZhDdq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:03:27 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)) 11:03:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:03:27 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-7fHmHw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:03:27 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)) 11:03:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:03:27 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-WPVkAn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:03:27 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)) 11:03:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:03:27 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-pdRxvx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:03:27 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)) 11:03:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:03:27 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-MJ5y2u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:03:27 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)) 11:03:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:03:27 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-pILL5F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:03:27 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)) 11:03:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:03:27 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-shRT7f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:03:27 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)) 11:03:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:03:27 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-h4Ouqk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:03:27 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)) 11:03:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:03:27 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-IGX1rj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:03:27 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)) 11:03:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:03:27 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-VucB9q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:03:27 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)) 11:03:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:03:27 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-5TitF1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:03:28 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)) 11:03:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:03:28 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-kBbnLI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:03:28 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)) 11:03:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:03:28 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-4Itc23-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:03:28 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)) 11:03:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:03:28 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-hIS_xE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:03:28 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)) 11:03:28 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)) 11:03:28 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)) 11:03:28 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)) 11:03:28 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)) 11:03:28 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)) 11:03:28 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:03:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:03:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:03:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:03:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:03:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:03:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:03:30 INFO - Downloading blessings-1.6.tar.gz 11:03:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:03:31 INFO - Installing collected packages: blessings 11:03:31 INFO - Running setup.py install for blessings 11:03:31 INFO - Successfully installed blessings 11:03:31 INFO - Cleaning up... 11:03:31 INFO - Return code: 0 11:03:31 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:03:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:03:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:03:31 INFO - Reading from file tmpfile_stdout 11:03:31 INFO - Current package versions: 11:03:31 INFO - blessings == 1.6 11:03:31 INFO - blobuploader == 1.2.4 11:03:31 INFO - docopt == 0.6.1 11:03:31 INFO - functools32 == 3.2.3-2 11:03:31 INFO - jsonschema == 2.5.1 11:03:31 INFO - manifestparser == 1.1 11:03:31 INFO - mozInstall == 1.12 11:03:31 INFO - mozcrash == 1.0 11:03:31 INFO - mozdebug == 0.1 11:03:31 INFO - mozdevice == 0.49 11:03:31 INFO - mozfile == 1.2 11:03:31 INFO - mozhttpd == 0.7 11:03:31 INFO - mozinfo == 0.9 11:03:31 INFO - mozleak == 0.1 11:03:31 INFO - mozlog == 3.4 11:03:31 INFO - moznetwork == 0.27 11:03:31 INFO - mozprocess == 0.23 11:03:31 INFO - mozprofile == 0.28 11:03:31 INFO - mozrunner == 6.13 11:03:31 INFO - mozscreenshot == 0.1 11:03:31 INFO - mozsystemmonitor == 0.3 11:03:31 INFO - moztest == 0.7 11:03:31 INFO - mozversion == 1.4 11:03:31 INFO - psutil == 3.1.1 11:03:31 INFO - requests == 1.2.3 11:03:31 INFO - wsgiref == 0.1.2 11:03:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:03:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:03:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:03:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:03:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:03:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:03:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:03:31 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 0x10e2d0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2d3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8cb3d91eb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e0aed30>, '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 0x10dfd5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e0a3be8>, '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.HnULz9L8nS/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.7ekeZbwtwN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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 11:03:31 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 11:03:31 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 11:03:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HnULz9L8nS/Render', 11:03:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:03:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:03:31 INFO - 'HOME': '/Users/cltbld', 11:03:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:03:31 INFO - 'LOGNAME': 'cltbld', 11:03:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:03:31 INFO - 'MOZ_NO_REMOTE': '1', 11:03:31 INFO - 'NO_EM_RESTART': '1', 11:03:31 INFO - 'PAGER': '/bin/cat', 11:03:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:03:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:03:31 INFO - 'PWD': '/builds/slave/test', 11:03:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:03:31 INFO - 'SHELL': '/bin/bash', 11:03:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7ekeZbwtwN/Listeners', 11:03:31 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 11:03:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:03:31 INFO - 'USER': 'cltbld', 11:03:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:03:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:03:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:03:31 INFO - 'XPC_FLAGS': '0x0', 11:03:31 INFO - 'XPC_SERVICE_NAME': '0', 11:03:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:03:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:03:31 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)) 11:03:31 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)) 11:03:31 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)) 11:03:31 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)) 11:03:31 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)) 11:03:31 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)) 11:03:31 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)) 11:03:31 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)) 11:03:31 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)) 11:03:31 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)) 11:03:31 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)) 11:03:31 INFO - Cleaning up... 11:03:31 INFO - Return code: 0 11:03:31 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:03:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:03:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:03:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:03:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:03:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:03:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:03:31 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 0x10e2d0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2d3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8cb3d91eb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e0aed30>, '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 0x10dfd5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e0a3be8>, '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.HnULz9L8nS/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.7ekeZbwtwN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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 11:03:31 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 11:03:31 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 11:03:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HnULz9L8nS/Render', 11:03:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:03:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:03:31 INFO - 'HOME': '/Users/cltbld', 11:03:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:03:31 INFO - 'LOGNAME': 'cltbld', 11:03:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:03:31 INFO - 'MOZ_NO_REMOTE': '1', 11:03:31 INFO - 'NO_EM_RESTART': '1', 11:03:31 INFO - 'PAGER': '/bin/cat', 11:03:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:03:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:03:31 INFO - 'PWD': '/builds/slave/test', 11:03:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:03:31 INFO - 'SHELL': '/bin/bash', 11:03:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7ekeZbwtwN/Listeners', 11:03:31 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 11:03:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:03:31 INFO - 'USER': 'cltbld', 11:03:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:03:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:03:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:03:31 INFO - 'XPC_FLAGS': '0x0', 11:03:31 INFO - 'XPC_SERVICE_NAME': '0', 11:03:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:03:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:03:32 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:03:32 INFO - Cleaning up... 11:03:32 INFO - Return code: 0 11:03:32 INFO - Running post-action listener: _resource_record_post_action 11:03:32 INFO - Running post-action listener: _start_resource_monitoring 11:03:32 INFO - Starting resource monitoring. 11:03:32 INFO - [mozharness: 2016-12-23 19:03:32.171270Z] Finished create-virtualenv step (success) 11:03:32 INFO - [mozharness: 2016-12-23 19:03:32.172242Z] Running install step. 11:03:32 INFO - Running pre-action listener: _resource_record_pre_action 11:03:32 INFO - Running main action method: install 11:03:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:03:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:03:32 INFO - Reading from file tmpfile_stdout 11:03:32 INFO - Detecting whether we're running mozinstall >=1.0... 11:03:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:03:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:03:32 INFO - Reading from file tmpfile_stdout 11:03:32 INFO - Output received: 11:03:32 INFO - Usage: mozinstall [options] installer 11:03:32 INFO - Options: 11:03:32 INFO - -h, --help show this help message and exit 11:03:32 INFO - -d DEST, --destination=DEST 11:03:32 INFO - Directory to install application into. [default: 11:03:32 INFO - "/builds/slave/test"] 11:03:32 INFO - --app=APP Application being installed. [default: firefox] 11:03:32 INFO - mkdir: /builds/slave/test/build/application 11:03:32 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'] 11:03:32 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 11:03:54 INFO - Reading from file tmpfile_stdout 11:03:54 INFO - Output received: 11:03:54 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 11:03:54 INFO - Running post-action listener: _resource_record_post_action 11:03:54 INFO - [mozharness: 2016-12-23 19:03:54.386611Z] Finished install step (success) 11:03:54 INFO - [mozharness: 2016-12-23 19:03:54.386740Z] Running run-tests step. 11:03:54 INFO - Running pre-action listener: _resource_record_pre_action 11:03:54 INFO - Running main action method: run_tests 11:03:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:03:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:03:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:03:54 INFO - 2016-12-23 11:03:54.401 screenresolution[1632:7993] starting screenresolution argv=screenresolution get 11:03:54 INFO - 2016-12-23 11:03:54.403 screenresolution[1632:7993] Display 0: 1600x1200x32@60 11:03:54 INFO - 2016-12-23 11:03:54.409 screenresolution[1633:7995] starting screenresolution argv=screenresolution list 11:03:54 INFO - Available Modes on Display 0 11:03:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:03:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:03:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:03:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:03:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:03:54 INFO - Intel Iris: 11:03:54 INFO - Chipset Model: Intel Iris 11:03:54 INFO - Type: GPU 11:03:54 INFO - Bus: Built-In 11:03:54 INFO - VRAM (Dynamic, Max): 1536 MB 11:03:54 INFO - Vendor: Intel (0x8086) 11:03:54 INFO - Device ID: 0x0a2e 11:03:54 INFO - Revision ID: 0x0009 11:03:54 INFO - Displays: 11:03:54 INFO - Display: 11:03:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:03:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:03:54 INFO - Display Serial Number: 1600x1200 60 11:03:54 INFO - Main Display: Yes 11:03:54 INFO - Mirror: Off 11:03:54 INFO - Online: Yes 11:03:54 INFO - Rotation: Supported 11:03:54 INFO - Return code: 0 11:03:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:03:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:03:54 INFO - Python 2.7.3 11:03:54 INFO - Return code: 0 11:03:54 INFO - grabbing minidump binary from tooltool 11:03:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:03:54 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 0x10e0aed30>, '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 0x10dfd5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e0a3be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:03:54 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 11:03:54 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 11:03:54 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 11:03:54 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:03:54 INFO - Return code: 0 11:03:54 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:03:54 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:03:54 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:03:54 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:03:54 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:03:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KurghkI5RfGWnolUqbECNw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0233', '--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 11:03:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Graphics --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/KurghkI5RfGWnolUqbECNw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0233 --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 11:03:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HnULz9L8nS/Render', 11:03:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:03:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:03:54 INFO - 'HOME': '/Users/cltbld', 11:03:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:03:54 INFO - 'LOGNAME': 'cltbld', 11:03:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:03:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:03:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:03:54 INFO - 'MOZ_NO_REMOTE': '1', 11:03:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:03:54 INFO - 'NO_EM_RESTART': '1', 11:03:54 INFO - 'PAGER': '/bin/cat', 11:03:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:03:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:03:54 INFO - 'PWD': '/builds/slave/test', 11:03:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:03:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:03:54 INFO - 'SHELL': '/bin/bash', 11:03:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7ekeZbwtwN/Listeners', 11:03:54 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 11:03:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:03:54 INFO - 'USER': 'cltbld', 11:03:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:03:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:03:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:03:54 INFO - 'XPC_FLAGS': '0x0', 11:03:54 INFO - 'XPC_SERVICE_NAME': '0', 11:03:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:03:54 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KurghkI5RfGWnolUqbECNw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0233', '--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 11:03:54 INFO - mozversion application_buildid: 20161223102637 11:03:54 INFO - mozversion application_changeset: a731b493e8c74008b3a63abaaf7da822122de793 11:03:54 INFO - mozversion application_display_name: Nightly 11:03:54 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:03:54 INFO - mozversion application_name: Firefox 11:03:54 INFO - mozversion application_remotingname: firefox 11:03:54 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 11:03:54 INFO - mozversion application_vendor: Mozilla 11:03:54 INFO - mozversion application_version: 53.0a1 11:03:54 INFO - mozversion platform_buildid: 20161223102637 11:03:54 INFO - mozversion platform_changeset: a731b493e8c74008b3a63abaaf7da822122de793 11:03:54 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 11:03:54 INFO - mozversion platform_version: 53.0a1 11:03:54 INFO - using testdate: 1482519834 11:03:54 INFO - actual date: 1482519834 11:03:54 INFO - starting webserver on 'localhost:49242' 11:03:54 INFO - SUITE-START | Running 2 tests 11:03:55 INFO - TEST-START | dromaeo_css 11:03:55 INFO - operating with platform_type : mac_ 11:03:55 INFO - Initialising browser for dromaeo_css test... 11:03:55 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/tmpUpp6E4/profile http://localhost:49242/getInfo.html) 11:03:55 INFO - PROCESS | 1643 | 1482519835636 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 11:03:56 INFO - PROCESS | 1643 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 11:03:56 INFO - PROCESS | 1643 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 11:03:57 INFO - PROCESS | 1643 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 11:03:57 INFO - PROCESS | 1643 | __metrics Screen width/height:1600/1200 11:03:57 INFO - PROCESS | 1643 | colorDepth:24 11:03:57 INFO - PROCESS | 1643 | Browser inner width/height: 1024/689 11:03:57 INFO - PROCESS | 1643 | __metrics 11:03:57 INFO - PROCESS | 1643 | 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" 11:03:57 INFO - PROCESS | 1643 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:03:58 INFO - TEST-INFO | 1643: exit 0 11:03:58 INFO - Browser initialized. 11:03:58 INFO - Running cycle 1/1 for dromaeo_css test... 11:03:58 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/tmpUpp6E4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:04:10 INFO - PROCESS | 1646 | RSS: Main: 236085248 11:04:10 INFO - PROCESS | 1646 | 11:05:01 INFO - PROCESS | 1646 | 1482519901377 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] 11:07:15 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49242/tests/dromaeo/cssquery-ext.html) 11:07:16 INFO - PROCESS | 1646 | RSS: Main: 368373760 11:07:16 INFO - PROCESS | 1646 | 11:08:59 INFO - PROCESS | 1646 | ************************* 11:08:59 INFO - PROCESS | 1646 | A coding exception was thrown and uncaught in a Task. 11:08:59 INFO - PROCESS | 1646 | 11:08:59 INFO - PROCESS | 1646 | Full message: ReferenceError: fetch is not defined 11:08:59 INFO - PROCESS | 1646 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 11:08:59 INFO - PROCESS | 1646 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 11:08:59 INFO - PROCESS | 1646 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 11:08:59 INFO - PROCESS | 1646 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 11:08:59 INFO - PROCESS | 1646 | TaskImpl@resource://gre/modules/Task.jsm:277:3 11:08:59 INFO - PROCESS | 1646 | asyncFunction@resource://gre/modules/Task.jsm:252:14 11:08:59 INFO - PROCESS | 1646 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 11:08:59 INFO - PROCESS | 1646 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 11:08:59 INFO - PROCESS | 1646 | TaskImpl@resource://gre/modules/Task.jsm:277:3 11:08:59 INFO - PROCESS | 1646 | asyncFunction@resource://gre/modules/Task.jsm:252:14 11:08:59 INFO - PROCESS | 1646 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 11:08:59 INFO - PROCESS | 1646 | 11:08:59 INFO - PROCESS | 1646 | ************************* 11:10:19 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-ext.html (next: http://localhost:49242/tests/dromaeo/cssquery-jquery.html) 11:10:20 INFO - PROCESS | 1646 | RSS: Main: 371585024 11:10:20 INFO - PROCESS | 1646 | 11:13:22 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49242/tests/dromaeo/cssquery-mootools.html) 11:13:23 INFO - PROCESS | 1646 | RSS: Main: 348635136 11:13:23 INFO - PROCESS | 1646 | 11:16:25 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49242/tests/dromaeo/cssquery-prototype.html) 11:16:26 INFO - PROCESS | 1646 | RSS: Main: 344260608 11:16:26 INFO - PROCESS | 1646 | 11:19:30 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49242/tests/dromaeo/cssquery-yui.html) 11:19:30 INFO - PROCESS | 1646 | RSS: Main: 337399808 11:19:30 INFO - PROCESS | 1646 | 11:22:34 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-yui.html (next: http://localhost:49242/tests/dromaeo/cssquery-dojo.html) 11:22:34 INFO - PROCESS | 1646 | RSS: Main: 411602944 11:22:34 INFO - PROCESS | 1646 | 11:22:34 INFO - PROCESS | 1646 | __start_tp_report 11:22:34 INFO - PROCESS | 1646 | _x_x_mozilla_page_load 11:22:34 INFO - PROCESS | 1646 | _x_x_mozilla_page_load_details 11:22:34 INFO - PROCESS | 1646 | |i|pagename|runs| 11:22:34 INFO - PROCESS | 1646 | |0;dojo.html;2961.2596850787304;3361.0421030006446;3380.205651671858;3388.6441923598004;3416.9487457688138;5545.029619816536;5637.295338082739;5689.4879460848515;5700.1164819453;5725.96932552141;5614.3543492498375;5667.688277144755;5670.319561652607;5675.233843431136;5677.091665333547;5142.254373115896;5156.767945442464;5161.509656582637;5217.817376391827;5237.083510385667;4441.356003379508;5022.096022715909;5164.715940623283;5328.413874473805;5338.572914166857;5289.788408463657;5314.335708036496;5314.4154143044425;5325.4940780625775;5338.119210330291;5059.266406371093;5091.872703182413;5125.743712814345;5152.381714194321;5188.455212202702;5166.61250406221;5181.119209734355;5196.740162991835;5220.6084543659;5254.106801843695;5671.517920976722;5680.0627896397045;5689.715514224314;5694.658320500742;5706.914396284059;6078.939210607881;6118.20463339763;6230.345813689542;6239.188905442309;6247.031710084899;6126.192588752487;6200.87598248037;6227.3772622737815;6235.781747638856;6239.313675495692;5783.739731712041;5811.82564523065;5843.678597677083;5865.237519122529;5870.647761134345;6185.597936134137;6259.874802503924;6272.3100458949475;6273.592216505913;6278.937210627927;6008.729607167688;6174.166487524217;6188.257409110935;6202.379762023762;6203.8138855834395;6168.321484636686;6187.041008643668;6238.064290356483;6238.157848690369;6243.500519958392;6216.751329946842;6264.2796078451565;6264.655443950626;6270.153529273581;6280.214973128359;6255.718492667841;6272.937270627235;6277.089821975788;6282.246130464282;6285.43431091204;4850.199717046718;4907.288443175719;4909.484504127086;4914.975425122851;4958.157113290749;4387.5393083726385;4405.735655860594;4414.073044660593;4439.089986552749;4466.062126953311;5812.070068788974;5829.300483941955;5836.270466191726;5836.854078648068;5847.26909136358;6126.6017708848785;6199.256089269316;6269.404406581368;6272.341404152498;6310.968445157837;5729.427057294321;5782.5663075268685;5791.739371728282;5806.593538452352;5919.230500035054;5397.9460205397445;5400.7569659364635;5420.186971954238;5422.864428389243;5471.644343117685;6272.996320588684;6280.591761535486;6282.403171698681;6282.999999999908;6285.182926219562;6145.3407579954055;6209.130721698875;6217.844553886189;6229.4393504584805;6271.905921411091;5280.471952804843;5745.626534275259;5900.586958912835;6282.968585157045;6298.71655775482;6039.063945088518;6105.247473117015;6133.294671112879;6139.6009259398;6177.382261773787;5072.82245121419;6094.634321940698;6317.905231421624;6337.04944258365;6341.90487142684;6182.319944805978;6198.938010619836;6206.565540412308;6218.906716399167;6237.688115594111;5973.671448070398;5996.2504686914135;6001.609895356788;6084.574079814548;6162.106494558354;5945.286565612154;6039.758409663565;6040.214772079602;6076.0582109771285;6082.178905847831;5088.745562721775;6186.659733714689;6187.752489900534;6190.566660333913;6196.659183744757;5788.913166302593;5857.648541087548;5958.374370691188;6000.369961154016;6013.248343957005 11:22:34 INFO - PROCESS | 1646 | |1;ext.html;451.2869665927834;462.6622565527164;476.44494164298595;501.1030255842043;501.2431228844444;30545;32121.678783212163;39844.2031159377;41364.379534306936;41459.37810932838;41967;42404;42450.78774606123;42470.787646061726;42515.36226956598;9336.859947100782;9567.473788941605;9568.56941437637;9585.424874507517;9591.904080959206;8481;8688.43525170865;11191.552337906474;11464.369459679714;11511;11414.972652461276;11482.253653512536;11495.310281383103;11495.942520287386;11517.654470365902;11456.083513318914;11511.654650360502;11528.596499122574;11533.538658453652;11558.826617600742;8379.62291696875;8623;11074.335539867554;11358;11392.658220253406;39610.20779584421;40118;40288.79855600733;40353;41036.589634103875;3301.884434044821;3492.528508651325;3493.4410494320787;3565.3938830398893;4051.3315302974975;29676.406471870658;30467.085987420414;31504.842475787704;31536.842315788504;31551.684483155102;3098.8915387961315;3264.951025734616;3313.8177400242976;3326.2848487575047;3366.4781958796425;510191;514620.85379146104;515316;515906;517881;65681;66407;66449.33550664528;66489;66712.66643666847;69890;70222;70289.64855175691;70331;70421;15953.202339883192;16134.435294764628;16140;16167.757483637755;16169.757453638205;11011.504482298316;12180.390980450942;12315.93842030784;12329.260244385365;12350.505979760888;14099.85900141006;17900.28398864031;17953.7306940396;17962.64074718498;17966.461006169837;6628.502862285348;8396.874046889301;8473.449225800303;8481.515734746517;8508.234258916727;767.117814513303;786.0920636664662;895.7312806158127;931.6320053578798;966.8198213029573;28375.858120709265;29690.25774355615;29737.959171429327;30216.69783302183;30314.242143946576;1003.5734812704717;1061.756869606723;1083.1226706367868;1098.2916019167592;1203.2299328429813;15312.770308445159;15317.6170595736;15379.92310038465;15597.300639087121;15803.68392632141;601106;603972;605284;605465;606760;58515;59210;59334;59339.70330148322;59340.813183736085;1389.423389293432;1401.7266633006466;1415.221628104559;1416.5467050543932;1417.5818133650598;28634.570481442377;29308.706912930596;29337;29988;30029.399412012073;51070.74464627653;51837.22244166265;51850.22224666557;51888.702782430744;52079.47920520746;586923;588246;589485;589525;591544;25037.624435633115;26996.460070798083;27268.04561840326;27282.04512842041;27298;39973;41772.58227417687;42513;42708.145837083706;43081.56918430901;35307.11732206613;35571.822140890166;35628.93113206608;36524;36775.816120920295;35130;35429.46855797114;35527.11182220465;36381.27237455289;36573.63426365809;28420.57369139423;35330.11674708153;35472.82263588665;35576;35700.107497312776;29735.702642973294;31862.681373185973;31943.361132777678;32038;32277.51583726293;19339.129739162054;21519.278457723656;23179.072837087002;23607.40981475477;24146.39634009164 11:22:34 INFO - PROCESS | 1646 | |2;jquery.html;351.57635049765037;369.1233320862949;370.83497843459656;378.3775688991608;400.82163437270407;14417.207053612046;14666.77999830002;15296.617584560378;19521.804781952178;20360.287389941353;19688.11403486843;20180.091895864683;20744;20855.687164692503;21134.471638209052;5734.741936612862;5941.405859414067;5994.1608174855555;5999.76000959961;6006.129111278862;4694.953050469503;5867.647941123525;5925.970370148143;5936.673482958436;5946.137810017566;4098.241825262321;4332.24185767491;6169.475594574466;6440.162778838744;6457.321981191978;6286.339934306923;6467.579607325509;6541.443977261938;6553.377429144248;6554.311797261266;5545.833624991256;5685.431456854323;5687.43125687428;5687.914681279743;5689.260396148516;25127.87436062808;25552.87223563889;25593.616095758578;25606.871965640054;25975.22074337773;2500.4874000829786;2558.373198566363;2667.373167305682;3114.7196752292175;3217.1957010747315;15332.233388330651;15489.22553872313;16079.758803617955;16206.75689864653;16511.33954641813;1488.9553313400615;1563.695079459506;1707.888987215827;1864.645717313706;1894.4695485264149;99144;102913.48543257236;103518.48240758748;105721;105972;11232.831507527393;11302.321860688386;11362.602308919148;11512.251703639236;11624.070074394198;14368.209748463725;14368.85631143696;14381.856181438261;14393.78409323861;14424.980502729626;7667.348275396542;8177.1414001530175;8310.792230194293;8330.66677332912;8342.874856877152;7431.331180193809;8168.42821002536;8302.796094566374;8335.291500222427;8361.749147525585;3626.5104210931713;4458.286674132123;4461.843835465743;4470.240059189946;4568.862934111982;1844.3821319857998;1884.5006073390562;1891.238791364623;1891.5838515526561;1891.8391936685537;779.6803310642723;825.6532256452305;869.6520393390219;887.7837362812987;907.3603109807569;17864.196111175028;20706.378808635764;21575.56848863014;21640.675389869164;21781.455463613536;883.7967267528504;925.3799954030816;937.8593211018265;942.665353799405;971.7133445633555;8804.459219636537;10758.354498730101;11264.718382040515;11449.885501144881;11491.310521368578;542934;551290;553260;563802.1809891006;567965;93087;122375.77624223643;125231;127321;128646.35676821556;963.2342287881152;1021.545412291538;1034.0125180452499;1035.0580971316067;1035.9223058270659;15796.526104216891;19853.205871764967;20036.799632003494;20109.49726256855;20587.79412205859;52355.73822130865;52429.7378513105;54072;54287.45712542982;54707;73171.53656926725;76542.02668503589;76786.6160669193;78553.21446785687;79487.60256198682;6553;7536.321731044232;7654.081510218587;7952.443328966917;8201.466904651179;7497.812554686177;9027.909720902708;11583.42082895835;11629.59296424621;12650.43073061696;10655.987681170276;10772.24594278393;10893.237473376788;11013.889861101607;11139.33164010162;10567.418791966516;10628.946855265674;10649.148068154374;10735.516901739284;10831.350118992885;9205.861912071457;10843.403612801372;11043.503042362952;11139.498722557342;11141.888581114084;6803.7618683345845;7930.563818990011;7938.722144725138;7948.125706172432;8168.83662326762;8059.637316320663;10218.795624087328;10822.729431764268;10869;11069.72325691832 11:22:34 INFO - PROCESS | 1646 | |3;mootools.html;1308.7120833416648;1500.9174495402751;1652.892561983471;1858.5353661584604;1888.7355770192166;7896.407769417295;8332.666693332267;8347.081820999685;8360.95819520903;8386.454880432768;7360.779176624696;7655.885161722578;7660.846783064336;8072.596370181497;8316.293115085226;2260.660900864875;2305.8501197422197;2306.550222706573;2310.1683393978155;2311.56080344734;2295.2655150351893;2314.4792421705065;2330.941726456835;2332.265336419023;2332.766723327662;2057.32108404227;2198.7251593550805;2274.283600665785;2301.7698230177016;2309.1225334373;2260.5704916065893;2265.784750448705;2285.69143165673;2289.885505724707;2290.1068583252545;2276.612975794119;2304.0760964594583;2305.296884450242;2307.5038866643586;2314.7338056123594;8168.754937351889;8200.507969521848;8289.585520724;8290.917090829074;8301.667933282723;4957.132501812169;5117.309163262949;5128.384593848725;5132.384113906318;5134.306868572732;3178.539111828795;3255.088575198948;3466.7399945004227;3467.5318831957616;3471.5486986691567;1021.8467229915498;1036.388530766851;1043.7703705184847;1062.7715041266165;1064.3188359449957;10493.370397776158;10499.632512862014;10665.893341066645;10681.786364272748;10690.679279621623;4361.367601697736;4435.711678740871;4522.819087236539;4526.932096018562;4537.478190008125;9233.907660923305;9246.26029917605;9250.537473126451;9251.028641992494;9265.166135047743;5038.0931432341795;5111.616628752859;5117.155669314596;5123.077845987684;5129.333186685707;4233.322668373045;4264.424302719093;4386.495553011381;4682.484926658065;4690.624750019991;3701.3522633539023;3731.8134093295225;3797.715171362104;3822.904427389282;3838.677600051984;1203.5366383942246;1205.4093494187784;1209.5101483899034;1219.432963671897;1221.9877801221874;865.6580650643086;876.9429987050821;878.9868151977726;895.8611415230649;919.3288899103692;5355.714628489212;5472.34331880169;5562.749676264578;5741.827745167651;5859.707014649336;735.9484836061424;929.9027147965465;979.9657012004545;1020;1040.7814358984547;3397.303552771676;3594.2092739597247;3632.3824949758605;3655.3968595182027;3739.8878033659034;9491.572879220314;9701.223902087957;9878.357906736039;9898.752531186634;9975.301728879054;9853.50732463374;10001.449920254456;10015.899841001643;10020.849687254697;10045.29682922203;7567.091948966159;7645.4648174628355;7773.378129749606;7832.608369581612;7861.489003214659;5251.816186433456;5309.442508536548;5383.946160538345;5395.865103372447;5517.806876759294;9147.679831205876;9189.77025574366;9275.443473391619;9278.025807290407;9288.72133835986;8842;9180.862287065833;9192.17270445663;9322.906770932475;9342.53287335644;6564.179477565304;6757.155355580552;6761.763338283136;7045.5420397676035;7206.207317195209;7554.320111190019;7726.652300646372;7858.803529911798;7948.125706172201;7973.9202607973175;7042.471814613822;7149.213586505584;7182.640867956686;7212;7287.744928927462;6915.481338899502;6978.79063628092;7002.579845209304;7066.187388450394;7089.539179953286;6199.938000619936;6222.751089956351;6282.623042617458;6286.654234017173;6291.087792270004;4678.578927896369;4719.079779442975;4731.29030645406;4777.737224452689;4866.245731911417;3721.888343349704;4253.638440732572;4728.17256980034;4797.592204662643;4827.565519103297 11:22:34 INFO - PROCESS | 1646 | |4;prototype.html;318.7752634392753;350.4392971246005;355.13524567677706;359.20794647801586;361.1368828499884;3906.667933225679;5129.307543481629;5142.151544995076;5174.715390653512;5216;4392;5015.5736762375245;5160.142348754454;5203.557697595699;5226.425093239741;3546.7162626989907;3856.652901238881;3905.3946638270972;3999.2401443725744;4049.2104039712267;3364.226227967569;3480.207693038087;3957.881263562098;3985.422113793513;4042.555318914917;3396.6433524479944;3682.55809302884;3967.36522156455;4028.7913625912256;4047.150098479323;3347.8493467794005;3730.7015438764834;3816.7709937403565;3827.6937844972476;4070;2828.8019838611303;3234.6252842541826;3330.4837750148768;3429.331280400323;3517.8416971236356;4405.9118817623785;4800.543948324938;4942.975285123552;5024.447310795809;5057.291979122926;3868.388837534681;3882.3594106972314;4294.754521188852;4592.908141837178;4662.510436404196;5159.58723302135;5377.677339359612;5432.592555558309;5463.098588732854;5511.3937466878615;3344.0771556355194;3948.774810952282;3967.5238971323624;3975.60243975603;3992.640662340374;4980.825671101494;5450.5639548836;5490.862728431781;5793.913091303634;5829.504492118175;3406.5060566217758;3950.940735888964;4050.4936882889638;4063.6342729154517;4070.9389359159636;3316.8839090632196;3450.6031806342594;3658.1894808327497;4102.2410853992105;4122.886820558432;4294.720843145185;4354.562994211902;4500.302453119772;4626.768661566908;4699.342092107107;4710.29345598163;5507.889842203133;5520.309961254843;5530.419305972895;5539.612227144061;1268.4038501904274;1341.2086868747485;1350.3855745635713;1352.8647135286392;1360.8775210231126;822.979425514355;892.7143314139413;911.9635214591475;948.170350942925;961.9995204986875;4234.0050088229245;4595.425571803525;4864.927026094611;4897.379901752545;4910.705357678551;4433.800478978454;4950.727709975986;5076.974615126901;5279.841604751864;5282.260483532308;3596.7841929484316;4337.7397356158735;4893.360724157447;4949.381327334083;4955.702657840541;2592.58704006317;2630.592258199982;2630.8026897982727;2807.368342123006;2835.8582070896373;4786.922942337947;5068.11117662936;5446.121088224556;5492.313460817398;5618.005608009098;5039.84880453587;5420.322459692538;5438.809641662523;5556;5884.529237661062;4759.976200118977;4995.825146119869;5459.617826752169;5523.309586301712;5945.583809133319;5702.315722113373;5733;5738.483536481736;5749.338826035059;5750.5112065474905;5191;5264.473552644705;5622.409646987007;5646.915296270641;5687.516561092188;4818.614510839195;4975.875603109806;5395.730213489232;5707.971460142839;5793.91309130355;3287.6547962463596;3565.899215901734;3735.701143908535;3736.757110787899;3750.287445385368;4960.751962401938;5733.971330143323;5738.8235411741325;5745.391290438683;5774.2782152230975;4998.60011199096;5100.413452453015;5132.742478092891;5489.890202196014;5506.834794956158;4416.889577760582;4837.532858242252;5518.2826232589505;5531.538465382866;5534.557235421076;5312.294802739115;5334.493223143795;5379.757910893941;5407.351117865724;5419.783208671609;5240.737963101906;5364.7049412282695;5505.559555235652;5521.889562208814;5538.529225015919;4749.4063242094735;5400.945990540202;5434.402215756343;5451.209574611738;5470.480304371078 11:22:34 INFO - PROCESS | 1646 | |5;yui.html;733.9192688804231;734.9412047036237;749.9625018749061;793.1513280789558;805.4804651000105;14983.700325993486;15625.531234062968;15628.921855390721;15644.530664080068;15757.527274181793;14329.426822927071;14513.056651317656;14517.49188778393;14527.12837229767;14539.49111781088;652.7356420649644;707.5047466773253;710.0485349631492;710.882704353781;862.6549380247889;711.900333953247;715.9713611455536;849.8257857139272;879.1779685993585;884.4251236696167;927.0590350793949;934.5841100710187;940.4357385568672;941.2093841173413;942.7595963029452;889.1819526036062;890.0609856601316;909.8180363927174;937.0957026469456;938.9014153513851;518.3753576939816;556.8691357531018;698.1238545625208;700.973074445934;701.9298070192991;14015.229162396065;14598.34307456167;14621.488247911271;14891.032082914577;14891.47879824201;14086.859131408657;14266.3580138894;14277.428902843874;14337.713245735027;14380.64048398788;10228.488575571266;11225.831612525737;11862;11933.582324618597;11937.94031029848;3713.4429835524616;3725.8137093145233;3736.645018723217;3758.1168425420005;3902.0049887278847;97235;110389;113016;113823;116022.25966610508;10975.560977560968;10991.61529346469;11016.669499915015;11125.666230013112;11153.944230278797;21809.901324112314;21913.671294930587;22058.89705514718;22071.00680469383;22141.446463838533;7640.923590764132;7747.883781743279;7760.844783104306;7861.213878612093;7962.681492740341;4209.284421648266;4301.978490107529;4313.741175529478;4329.372241025034;4452.198604251201;3467.133216695826;3563.2873425314524;3565.16218688608;3595.1012246938267;3618.782873027627;648.2901223160632;653.258551544001;655.5574986883835;665.4443539644451;666.5800545656302;625.3933684326196;665.2814959843357;665.8568407792347;678.4809620640215;696.8675951569186;8730.694425695197;9008.549572521348;9048.764161935665;9141.451512909114;9512.857307140399;572.513363640902;593.9732712027883;645.3030726815028;657.5462930577886;681.6523572977817;4283.785810709452;4455.754933478625;4615.515370886009;4622.745748983838;4671.32265821454;99897;103659.96340036654;104399;104687;106952;91775;93411.53294233486;93711;94097.0590294102;96891;82693;82800.58599706963;83062.58468707738;84592.57703711565;85207;8760.4305720128;8897.154770296811;8989.41568798026;8996.910030899608;9148.408288779387;49630.50369496259;49895;50020.49979500304;50870;51750.96498070093;75491.18614580069;75589.6220518916;76466.79432631259;78007.60996194983;79679;11567.652970410902;11625.825612615987;11683.649490515298;12231.204971676812;12341.259524428562;10700.324918770308;10710.196735245043;10737.355758654505;12924.353782311035;13839;11931;12264.75470490603;12286.262824230575;12713.745725085631;12727.93636031814;10323.225758068325;11788.587399440978;12068.79312068786;12079.57721479744;12335.938320308342;11982.64052078439;12019.09856760764;12274.570390036306;12311.44598493052;12545.996320294173;9481.431114132898;9498.287628427757;9614;9770.120689138012;10033.247506436901;10153.340032897839;10313.587456501657;10508.527116279938;10538.57845686164;10639.787204256027 11:22:34 INFO - PROCESS | 1646 | __end_tp_report 11:22:34 INFO - PROCESS | 1646 | __start_cc_report 11:22:34 INFO - PROCESS | 1646 | _x_x_mozilla_cycle_collect,1517 11:22:34 INFO - PROCESS | 1646 | __end_cc_report 11:22:34 INFO - PROCESS | 1646 | __startTimestamp1482520954446__endTimestamp 11:22:34 INFO - PROCESS | 1646 | 11:22:34 INFO - PROCESS | 1646 | ------- Summary: start ------- 11:22:34 INFO - PROCESS | 1646 | Number of tests: 6 11:22:34 INFO - PROCESS | 1646 | 11:22:34 INFO - PROCESS | 1646 | [#0] dojo.html Cycles:165 Average:5725.39 Median:5945.29 stddev:654.06 (11.0%) stddev-sans-first:619.08 11:22:34 INFO - PROCESS | 1646 | Values: 2961.3 3361.0 3380.2 3388.6 3416.9 5545.0 5637.3 5689.5 5700.1 5726.0 5614.4 5667.7 5670.3 5675.2 5677.1 5142.3 5156.8 5161.5 5217.8 5237.1 4441.4 5022.1 5164.7 5328.4 5338.6 5289.8 5314.3 5314.4 5325.5 5338.1 5059.3 5091.9 5125.7 5152.4 5188.5 5166.6 5181.1 5196.7 5220.6 5254.1 5671.5 5680.1 5689.7 5694.7 5706.9 6078.9 6118.2 6230.3 6239.2 6247.0 6126.2 6200.9 6227.4 6235.8 6239.3 5783.7 5811.8 5843.7 5865.2 5870.6 6185.6 6259.9 6272.3 6273.6 6278.9 6008.7 6174.2 6188.3 6202.4 6203.8 6168.3 6187.0 6238.1 6238.2 6243.5 6216.8 6264.3 6264.7 6270.2 6280.2 6255.7 6272.9 6277.1 6282.2 6285.4 4850.2 4907.3 4909.5 4915.0 4958.2 4387.5 4405.7 4414.1 4439.1 4466.1 5812.1 5829.3 5836.3 5836.9 5847.3 6126.6 6199.3 6269.4 6272.3 6311.0 5729.4 5782.6 5791.7 5806.6 5919.2 5397.9 5400.8 5420.2 5422.9 5471.6 6273.0 6280.6 6282.4 6283.0 6285.2 6145.3 6209.1 6217.8 6229.4 6271.9 5280.5 5745.6 5900.6 6283.0 6298.7 6039.1 6105.2 6133.3 6139.6 6177.4 5072.8 6094.6 6317.9 6337.0 6341.9 6182.3 6198.9 6206.6 6218.9 6237.7 5973.7 5996.3 6001.6 6084.6 6162.1 5945.3 6039.8 6040.2 6076.1 6082.2 5088.7 6186.7 6187.8 6190.6 6196.7 5788.9 5857.6 5958.4 6000.4 6013.2 11:22:34 INFO - PROCESS | 1646 | 11:22:34 INFO - PROCESS | 1646 | [#1] ext.html Cycles:180 Average:70190.45 Median:35451.15 stddev:152498.02 (430.2%) stddev-sans-first:152835.93 11:22:34 INFO - PROCESS | 1646 | Values: 451.3 462.7 476.4 501.1 501.2 30545.0 32121.7 39844.2 41364.4 41459.4 41967.0 42404.0 42450.8 42470.8 42515.4 9336.9 9567.5 9568.6 9585.4 9591.9 8481.0 8688.4 11191.6 11464.4 11511.0 11415.0 11482.3 11495.3 11495.9 11517.7 11456.1 11511.7 11528.6 11533.5 11558.8 8379.6 8623.0 11074.3 11358.0 11392.7 39610.2 40118.0 40288.8 40353.0 41036.6 3301.9 3492.5 3493.4 3565.4 4051.3 29676.4 30467.1 31504.8 31536.8 31551.7 3098.9 3265.0 3313.8 3326.3 3366.5 510191.0 514620.9 515316.0 515906.0 517881.0 65681.0 66407.0 66449.3 66489.0 66712.7 69890.0 70222.0 70289.6 70331.0 70421.0 15953.2 16134.4 16140.0 16167.8 16169.8 11011.5 12180.4 12315.9 12329.3 12350.5 14099.9 17900.3 17953.7 17962.6 17966.5 6628.5 8396.9 8473.4 8481.5 8508.2 767.1 786.1 895.7 931.6 966.8 28375.9 29690.3 29738.0 30216.7 30314.2 1003.6 1061.8 1083.1 1098.3 1203.2 15312.8 15317.6 15379.9 15597.3 15803.7 601106.0 603972.0 605284.0 605465.0 606760.0 58515.0 59210.0 59334.0 59339.7 59340.8 1389.4 1401.7 1415.2 1416.5 1417.6 28634.6 29308.7 29337.0 29988.0 30029.4 51070.7 51837.2 51850.2 51888.7 52079.5 586923.0 588246.0 589485.0 589525.0 591544.0 25037.6 26996.5 27268.0 27282.0 27298.0 39973.0 41772.6 42513.0 42708.1 43081.6 35307.1 35571.8 35628.9 36524.0 36775.8 35130.0 35429.5 35527.1 36381.3 36573.6 28420.6 35330.1 35472.8 35576.0 35700.1 29735.7 31862.7 31943.4 32038.0 32277.5 19339.1 21519.3 23179.1 23607.4 24146.4 11:22:34 INFO - PROCESS | 1646 | 11:22:34 INFO - PROCESS | 1646 | [#2] jquery.html Cycles:180 Average:33117.32 Median:3165.96 stddev:92662.16 (2926.8%) stddev-sans-first:92889.44 11:22:34 INFO - PROCESS | 1646 | Values: 351.6 369.1 370.8 378.4 400.8 14417.2 14666.8 15296.6 19521.8 20360.3 19688.1 20180.1 20744.0 20855.7 21134.5 5734.7 5941.4 5994.2 5999.8 6006.1 4695.0 5867.6 5926.0 5936.7 5946.1 4098.2 4332.2 6169.5 6440.2 6457.3 6286.3 6467.6 6541.4 6553.4 6554.3 5545.8 5685.4 5687.4 5687.9 5689.3 25127.9 25552.9 25593.6 25606.9 25975.2 2500.5 2558.4 2667.4 3114.7 3217.2 15332.2 15489.2 16079.8 16206.8 16511.3 1489.0 1563.7 1707.9 1864.6 1894.5 99144.0 102913.5 103518.5 105721.0 105972.0 11232.8 11302.3 11362.6 11512.3 11624.1 14368.2 14368.9 14381.9 14393.8 14425.0 7667.3 8177.1 8310.8 8330.7 8342.9 7431.3 8168.4 8302.8 8335.3 8361.7 3626.5 4458.3 4461.8 4470.2 4568.9 1844.4 1884.5 1891.2 1891.6 1891.8 779.7 825.7 869.7 887.8 907.4 17864.2 20706.4 21575.6 21640.7 21781.5 883.8 925.4 937.9 942.7 971.7 8804.5 10758.4 11264.7 11449.9 11491.3 542934.0 551290.0 553260.0 563802.2 567965.0 93087.0 122375.8 125231.0 127321.0 128646.4 963.2 1021.5 1034.0 1035.1 1035.9 15796.5 19853.2 20036.8 20109.5 20587.8 52355.7 52429.7 54072.0 54287.5 54707.0 73171.5 76542.0 76786.6 78553.2 79487.6 6553.0 7536.3 7654.1 7952.4 8201.5 7497.8 9027.9 11583.4 11629.6 12650.4 10656.0 10772.2 10893.2 11013.9 11139.3 10567.4 10628.9 10649.1 10735.5 10831.4 9205.9 10843.4 11043.5 11139.5 11141.9 6803.8 7930.6 7938.7 7948.1 8168.8 8059.6 10218.8 10822.7 10869.0 11069.7 11:22:34 INFO - PROCESS | 1646 | 11:22:34 INFO - PROCESS | 1646 | [#3] mootools.html Cycles:180 Average:5345.93 Median:5120.19 stddev:2925.91 (57.1%) stddev-sans-first:2918.38 11:22:34 INFO - PROCESS | 1646 | Values: 1308.7 1500.9 1652.9 1858.5 1888.7 7896.4 8332.7 8347.1 8361.0 8386.5 7360.8 7655.9 7660.8 8072.6 8316.3 2260.7 2305.9 2306.6 2310.2 2311.6 2295.3 2314.5 2330.9 2332.3 2332.8 2057.3 2198.7 2274.3 2301.8 2309.1 2260.6 2265.8 2285.7 2289.9 2290.1 2276.6 2304.1 2305.3 2307.5 2314.7 8168.8 8200.5 8289.6 8290.9 8301.7 4957.1 5117.3 5128.4 5132.4 5134.3 3178.5 3255.1 3466.7 3467.5 3471.5 1021.8 1036.4 1043.8 1062.8 1064.3 10493.4 10499.6 10665.9 10681.8 10690.7 4361.4 4435.7 4522.8 4526.9 4537.5 9233.9 9246.3 9250.5 9251.0 9265.2 5038.1 5111.6 5117.2 5123.1 5129.3 4233.3 4264.4 4386.5 4682.5 4690.6 3701.4 3731.8 3797.7 3822.9 3838.7 1203.5 1205.4 1209.5 1219.4 1222.0 865.7 876.9 879.0 895.9 919.3 5355.7 5472.3 5562.7 5741.8 5859.7 735.9 929.9 980.0 1020.0 1040.8 3397.3 3594.2 3632.4 3655.4 3739.9 9491.6 9701.2 9878.4 9898.8 9975.3 9853.5 10001.4 10015.9 10020.8 10045.3 7567.1 7645.5 7773.4 7832.6 7861.5 5251.8 5309.4 5383.9 5395.9 5517.8 9147.7 9189.8 9275.4 9278.0 9288.7 8842.0 9180.9 9192.2 9322.9 9342.5 6564.2 6757.2 6761.8 7045.5 7206.2 7554.3 7726.7 7858.8 7948.1 7973.9 7042.5 7149.2 7182.6 7212.0 7287.7 6915.5 6978.8 7002.6 7066.2 7089.5 6199.9 6222.8 6282.6 6286.7 6291.1 4678.6 4719.1 4731.3 4777.7 4866.2 3721.9 4253.6 4728.2 4797.6 4827.6 11:22:34 INFO - PROCESS | 1646 | 11:22:34 INFO - PROCESS | 1646 | [#4] prototype.html Cycles:180 Average:4379.41 Median:4946.18 stddev:1343.27 (27.2%) stddev-sans-first:1312.01 11:22:34 INFO - PROCESS | 1646 | Values: 318.8 350.4 355.1 359.2 361.1 3906.7 5129.3 5142.2 5174.7 5216.0 4392.0 5015.6 5160.1 5203.6 5226.4 3546.7 3856.7 3905.4 3999.2 4049.2 3364.2 3480.2 3957.9 3985.4 4042.6 3396.6 3682.6 3967.4 4028.8 4047.2 3347.8 3730.7 3816.8 3827.7 4070.0 2828.8 3234.6 3330.5 3429.3 3517.8 4405.9 4800.5 4943.0 5024.4 5057.3 3868.4 3882.4 4294.8 4592.9 4662.5 5159.6 5377.7 5432.6 5463.1 5511.4 3344.1 3948.8 3967.5 3975.6 3992.6 4980.8 5450.6 5490.9 5793.9 5829.5 3406.5 3950.9 4050.5 4063.6 4070.9 3316.9 3450.6 3658.2 4102.2 4122.9 4294.7 4354.6 4500.3 4626.8 4699.3 4710.3 5507.9 5520.3 5530.4 5539.6 1268.4 1341.2 1350.4 1352.9 1360.9 823.0 892.7 912.0 948.2 962.0 4234.0 4595.4 4864.9 4897.4 4910.7 4433.8 4950.7 5077.0 5279.8 5282.3 3596.8 4337.7 4893.4 4949.4 4955.7 2592.6 2630.6 2630.8 2807.4 2835.9 4786.9 5068.1 5446.1 5492.3 5618.0 5039.8 5420.3 5438.8 5556.0 5884.5 4760.0 4995.8 5459.6 5523.3 5945.6 5702.3 5733.0 5738.5 5749.3 5750.5 5191.0 5264.5 5622.4 5646.9 5687.5 4818.6 4975.9 5395.7 5708.0 5793.9 3287.7 3565.9 3735.7 3736.8 3750.3 4960.8 5734.0 5738.8 5745.4 5774.3 4998.6 5100.4 5132.7 5489.9 5506.8 4416.9 4837.5 5518.3 5531.5 5534.6 5312.3 5334.5 5379.8 5407.4 5419.8 5240.7 5364.7 5505.6 5521.9 5538.5 4749.4 5400.9 5434.4 5451.2 5470.5 11:22:34 INFO - PROCESS | 1646 | 11:22:34 INFO - PROCESS | 1646 | [#5] yui.html Cycles:180 Average:20872.11 Median:4535.64 stddev:31012.86 (683.8%) stddev-sans-first:31063.00 11:22:34 INFO - PROCESS | 1646 | Values: 733.9 734.9 750.0 793.2 805.5 14983.7 15625.5 15628.9 15644.5 15757.5 14329.4 14513.1 14517.5 14527.1 14539.5 652.7 707.5 710.0 710.9 862.7 711.9 716.0 849.8 879.2 884.4 927.1 934.6 940.4 941.2 942.8 889.2 890.1 909.8 937.1 938.9 518.4 556.9 698.1 701.0 701.9 14015.2 14598.3 14621.5 14891.0 14891.5 14086.9 14266.4 14277.4 14337.7 14380.6 10228.5 11225.8 11862.0 11933.6 11937.9 3713.4 3725.8 3736.6 3758.1 3902.0 97235.0 110389.0 113016.0 113823.0 116022.3 10975.6 10991.6 11016.7 11125.7 11153.9 21809.9 21913.7 22058.9 22071.0 22141.4 7640.9 7747.9 7760.8 7861.2 7962.7 4209.3 4302.0 4313.7 4329.4 4452.2 3467.1 3563.3 3565.2 3595.1 3618.8 648.3 653.3 655.6 665.4 666.6 625.4 665.3 665.9 678.5 696.9 8730.7 9008.5 9048.8 9141.5 9512.9 572.5 594.0 645.3 657.5 681.7 4283.8 4455.8 4615.5 4622.7 4671.3 99897.0 103660.0 104399.0 104687.0 106952.0 91775.0 93411.5 93711.0 94097.1 96891.0 82693.0 82800.6 83062.6 84592.6 85207.0 8760.4 8897.2 8989.4 8996.9 9148.4 49630.5 49895.0 50020.5 50870.0 51751.0 75491.2 75589.6 76466.8 78007.6 79679.0 11567.7 11625.8 11683.6 12231.2 12341.3 10700.3 10710.2 10737.4 12924.4 13839.0 11931.0 12264.8 12286.3 12713.7 12727.9 10323.2 11788.6 12068.8 12079.6 12335.9 11982.6 12019.1 12274.6 12311.4 12546.0 9481.4 9498.3 9614.0 9770.1 10033.2 10153.3 10313.6 10508.5 10538.6 10639.8 11:22:34 INFO - PROCESS | 1646 | -------- Summary: end -------- 11:22:34 INFO - PROCESS | 1646 | 11:22:35 INFO - TEST-INFO | 1646: exit 0 11:22:35 INFO - TEST-OK | dromaeo_css | took 1120088ms 11:22:35 INFO - TEST-START | kraken 11:22:35 INFO - operating with platform_type : mac_ 11:22:35 INFO - Initialising browser for kraken test... 11:22:35 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/tmpMODwdh/profile http://localhost:49242/getInfo.html) 11:22:35 INFO - PROCESS | 1650 | 1482520955657 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 11:22:36 INFO - PROCESS | 1650 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 11:22:36 INFO - PROCESS | 1650 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 11:22:37 INFO - PROCESS | 1650 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 11:22:37 INFO - PROCESS | 1650 | __metrics Screen width/height:1600/1200 11:22:37 INFO - PROCESS | 1650 | colorDepth:24 11:22:37 INFO - PROCESS | 1650 | Browser inner width/height: 1024/689 11:22:37 INFO - PROCESS | 1650 | __metrics 11:22:37 INFO - PROCESS | 1650 | 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" 11:22:37 INFO - PROCESS | 1650 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:22:38 INFO - TEST-INFO | 1650: exit 0 11:22:38 INFO - Browser initialized. 11:22:38 INFO - Running cycle 1/1 for kraken test... 11:22:38 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/tmpMODwdh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:22:50 INFO - PROCESS | 1651 | RSS: Main: 235491328 11:22:50 INFO - PROCESS | 1651 | 11:22:52 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection) 11:22:53 INFO - PROCESS | 1651 | RSS: Main: 287178752 11:22:53 INFO - PROCESS | 1651 | 11:22:56 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-dft) 11:22:57 INFO - PROCESS | 1651 | RSS: Main: 288620544 11:22:57 INFO - PROCESS | 1651 | 11:23:00 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-fft) 11:23:01 INFO - PROCESS | 1651 | RSS: Main: 381550592 11:23:01 INFO - PROCESS | 1651 | 11:23:03 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator) 11:23:03 INFO - PROCESS | 1651 | RSS: Main: 283795456 11:23:03 INFO - PROCESS | 1651 | 11:23:05 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:23:06 INFO - PROCESS | 1651 | RSS: Main: 288653312 11:23:06 INFO - PROCESS | 1651 | 11:23:09 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom) 11:23:09 INFO - PROCESS | 1651 | RSS: Main: 329949184 11:23:09 INFO - PROCESS | 1651 | 11:23:12 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate) 11:23:13 INFO - PROCESS | 1651 | RSS: Main: 331587584 11:23:13 INFO - PROCESS | 1651 | 11:23:16 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial) 11:23:16 INFO - PROCESS | 1651 | RSS: Main: 325976064 11:23:16 INFO - PROCESS | 1651 | 11:23:18 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:23:19 INFO - PROCESS | 1651 | RSS: Main: 303972352 11:23:19 INFO - PROCESS | 1651 | 11:23:20 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:23:21 INFO - PROCESS | 1651 | RSS: Main: 309006336 11:23:21 INFO - PROCESS | 1651 | 11:23:23 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:23:24 INFO - PROCESS | 1651 | RSS: Main: 309776384 11:23:24 INFO - PROCESS | 1651 | 11:23:27 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:23:28 INFO - PROCESS | 1651 | RSS: Main: 326529024 11:23:28 INFO - PROCESS | 1651 | 11:23:31 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:23:31 INFO - PROCESS | 1651 | RSS: Main: 320225280 11:23:31 INFO - PROCESS | 1651 | 11:23:33 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49242/tests/kraken/driver.html?testName=ai-astar) 11:23:33 INFO - PROCESS | 1651 | RSS: Main: 364523520 11:23:33 INFO - PROCESS | 1651 | 11:23:33 INFO - PROCESS | 1651 | __start_tp_report 11:23:33 INFO - PROCESS | 1651 | _x_x_mozilla_page_load 11:23:33 INFO - PROCESS | 1651 | _x_x_mozilla_page_load_details 11:23:33 INFO - PROCESS | 1651 | |i|pagename|runs| 11:23:33 INFO - PROCESS | 1651 | |0;ai-astar;112;114;113;113;113;114;113;112;113;113 11:23:33 INFO - PROCESS | 1651 | |1;audio-beat-detection;142;109;109;145;113;138;144;141;114;142 11:23:33 INFO - PROCESS | 1651 | |2;audio-dft;182;183;181;185;183;184;180;183;183;185 11:23:33 INFO - PROCESS | 1651 | |3;audio-fft;78;59;60;85;62;59;68;59;74;59 11:23:33 INFO - PROCESS | 1651 | |4;audio-oscillator;89;70;85;71;70;68;83;68;68;83 11:23:33 INFO - PROCESS | 1651 | |5;imaging-gaussian-blur;87;84;87;84;87;84;88;85;88;84 11:23:33 INFO - PROCESS | 1651 | |6;imaging-darkroom;93;105;149;94;93;93;94;94;94;94 11:23:33 INFO - PROCESS | 1651 | |7;imaging-desaturate;87;85;91;124;85;88;119;139;92;81 11:23:33 INFO - PROCESS | 1651 | |8;json-parse-financial;56;56;56;56;54;57;57;56;55;55 11:23:33 INFO - PROCESS | 1651 | |9;json-stringify-tinderbox;68;82;77;72;73;69;69;69;71;71 11:23:33 INFO - PROCESS | 1651 | |10;stanford-crypto-aes;86;88;88;90;85;84;100;108;109;110 11:23:33 INFO - PROCESS | 1651 | |11;stanford-crypto-ccm;145;129;147;147;153;160;142;157;210;139 11:23:33 INFO - PROCESS | 1651 | |12;stanford-crypto-pbkdf2;165;173;172;169;170;169;172;169;168;159 11:23:33 INFO - PROCESS | 1651 | |13;stanford-crypto-sha256-iterative;66;68;68;65;67;66;67;66;66;65 11:23:33 INFO - PROCESS | 1651 | __end_tp_report 11:23:33 INFO - PROCESS | 1651 | __start_cc_report 11:23:33 INFO - PROCESS | 1651 | _x_x_mozilla_cycle_collect,2025 11:23:33 INFO - PROCESS | 1651 | __end_cc_report 11:23:33 INFO - PROCESS | 1651 | __startTimestamp1482521013807__endTimestamp 11:23:33 INFO - PROCESS | 1651 | 11:23:33 INFO - PROCESS | 1651 | ------- Summary: start ------- 11:23:33 INFO - PROCESS | 1651 | Number of tests: 14 11:23:33 INFO - PROCESS | 1651 | 11:23:33 INFO - PROCESS | 1651 | [#0] ai-astar Cycles:10 Average:113.00 Median:113.00 stddev:0.67 (0.6%) stddev-sans-first:0.60 11:23:33 INFO - PROCESS | 1651 | Values: 112.0 114.0 113.0 113.0 113.0 114.0 113.0 112.0 113.0 113.0 11:23:33 INFO - PROCESS | 1651 | 11:23:33 INFO - PROCESS | 1651 | [#1] audio-beat-detection Cycles:10 Average:129.70 Median:141.50 stddev:16.06 (11.3%) stddev-sans-first:16.40 11:23:33 INFO - PROCESS | 1651 | Values: 142.0 109.0 109.0 145.0 113.0 138.0 144.0 141.0 114.0 142.0 11:23:33 INFO - PROCESS | 1651 | 11:23:33 INFO - PROCESS | 1651 | [#2] audio-dft Cycles:10 Average:182.90 Median:183.00 stddev:1.60 (0.9%) stddev-sans-first:1.66 11:23:33 INFO - PROCESS | 1651 | Values: 182.0 183.0 181.0 185.0 183.0 184.0 180.0 183.0 183.0 185.0 11:23:33 INFO - PROCESS | 1651 | 11:23:33 INFO - PROCESS | 1651 | [#3] audio-fft Cycles:10 Average:66.30 Median:65.00 stddev:9.55 (14.7%) stddev-sans-first:9.14 11:23:33 INFO - PROCESS | 1651 | Values: 78.0 59.0 60.0 85.0 62.0 59.0 68.0 59.0 74.0 59.0 11:23:33 INFO - PROCESS | 1651 | 11:23:33 INFO - PROCESS | 1651 | [#4] audio-oscillator Cycles:10 Average:75.50 Median:77.00 stddev:8.40 (10.9%) stddev-sans-first:7.35 11:23:33 INFO - PROCESS | 1651 | Values: 89.0 70.0 85.0 71.0 70.0 68.0 83.0 68.0 68.0 83.0 11:23:33 INFO - PROCESS | 1651 | 11:23:33 INFO - PROCESS | 1651 | [#5] imaging-gaussian-blur Cycles:10 Average:85.80 Median:87.00 stddev:1.75 (2.0%) stddev-sans-first:1.80 11:23:33 INFO - PROCESS | 1651 | Values: 87.0 84.0 87.0 84.0 87.0 84.0 88.0 85.0 88.0 84.0 11:23:33 INFO - PROCESS | 1651 | 11:23:33 INFO - PROCESS | 1651 | [#6] imaging-darkroom Cycles:10 Average:100.30 Median:94.00 stddev:17.49 (18.6%) stddev-sans-first:18.35 11:23:33 INFO - PROCESS | 1651 | Values: 93.0 105.0 149.0 94.0 93.0 93.0 94.0 94.0 94.0 94.0 11:23:33 INFO - PROCESS | 1651 | 11:23:33 INFO - PROCESS | 1651 | [#7] imaging-desaturate Cycles:10 Average:99.10 Median:86.00 stddev:20.33 (23.6%) stddev-sans-first:21.08 11:23:33 INFO - PROCESS | 1651 | Values: 87.0 85.0 91.0 124.0 85.0 88.0 119.0 139.0 92.0 81.0 11:23:33 INFO - PROCESS | 1651 | 11:23:33 INFO - PROCESS | 1651 | [#8] json-parse-financial Cycles:10 Average:55.80 Median:56.00 stddev:0.92 (1.6%) stddev-sans-first:0.97 11:23:33 INFO - PROCESS | 1651 | Values: 56.0 56.0 56.0 56.0 54.0 57.0 57.0 56.0 55.0 55.0 11:23:33 INFO - PROCESS | 1651 | 11:23:33 INFO - PROCESS | 1651 | [#9] json-stringify-tinderbox Cycles:10 Average:72.10 Median:71.50 stddev:4.36 (6.1%) stddev-sans-first:4.36 11:23:33 INFO - PROCESS | 1651 | Values: 68.0 82.0 77.0 72.0 73.0 69.0 69.0 69.0 71.0 71.0 11:23:33 INFO - PROCESS | 1651 | 11:23:33 INFO - PROCESS | 1651 | [#10] stanford-crypto-aes Cycles:10 Average:94.80 Median:85.50 stddev:10.75 (12.6%) stddev-sans-first:10.92 11:23:33 INFO - PROCESS | 1651 | Values: 86.0 88.0 88.0 90.0 85.0 84.0 100.0 108.0 109.0 110.0 11:23:33 INFO - PROCESS | 1651 | 11:23:33 INFO - PROCESS | 1651 | [#11] stanford-crypto-ccm Cycles:10 Average:152.90 Median:150.00 stddev:21.97 (14.6%) stddev-sans-first:23.11 11:23:33 INFO - PROCESS | 1651 | Values: 145.0 129.0 147.0 147.0 153.0 160.0 142.0 157.0 210.0 139.0 11:23:33 INFO - PROCESS | 1651 | 11:23:33 INFO - PROCESS | 1651 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.60 Median:169.50 stddev:4.09 (2.4%) stddev-sans-first:4.12 11:23:33 INFO - PROCESS | 1651 | Values: 165.0 173.0 172.0 169.0 170.0 169.0 172.0 169.0 168.0 159.0 11:23:33 INFO - PROCESS | 1651 | 11:23:33 INFO - PROCESS | 1651 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.40 Median:66.50 stddev:1.07 (1.6%) stddev-sans-first:1.13 11:23:33 INFO - PROCESS | 1651 | Values: 66.0 68.0 68.0 65.0 67.0 66.0 67.0 66.0 66.0 65.0 11:23:33 INFO - PROCESS | 1651 | -------- Summary: end -------- 11:23:33 INFO - PROCESS | 1651 | 11:23:34 INFO - TEST-INFO | 1651: exit 0 11:23:34 INFO - TEST-OK | kraken | took 59737ms 11:23:34 INFO - SUITE-END | took 1179s 11:23:34 INFO - Completed test suite (00:19:39) 11:23:34 INFO - javascript benchmark 11:23:35 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2961.2596850787304, 3361.0421030006446, 3380.205651671858, 3388.6441923598004, 3416.9487457688138, 5545.029619816536, 5637.295338082739, 5689.4879460848515, 5700.1164819453, 5725.96932552141, 5614.3543492498375, 5667.688277144755, 5670.319561652607, 5675.233843431136, 5677.091665333547, 5142.254373115896, 5156.767945442464, 5161.509656582637, 5217.817376391827, 5237.083510385667, 4441.356003379508, 5022.096022715909, 5164.715940623283, 5328.413874473805, 5338.572914166857, 5289.788408463657, 5314.335708036496, 5314.4154143044425, 5325.4940780625775, 5338.119210330291, 5059.266406371093, 5091.872703182413, 5125.743712814345, 5152.381714194321, 5188.455212202702, 5166.61250406221, 5181.119209734355, 5196.740162991835, 5220.6084543659, 5254.106801843695, 5671.517920976722, 5680.0627896397045, 5689.715514224314, 5694.658320500742, 5706.914396284059, 6078.939210607881, 6118.20463339763, 6230.345813689542, 6239.188905442309, 6247.031710084899, 6126.192588752487, 6200.87598248037, 6227.3772622737815, 6235.781747638856, 6239.313675495692, 5783.739731712041, 5811.82564523065, 5843.678597677083, 5865.237519122529, 5870.647761134345, 6185.597936134137, 6259.874802503924, 6272.3100458949475, 6273.592216505913, 6278.937210627927, 6008.729607167688, 6174.166487524217, 6188.257409110935, 6202.379762023762, 6203.8138855834395, 6168.321484636686, 6187.041008643668, 6238.064290356483, 6238.157848690369, 6243.500519958392, 6216.751329946842, 6264.2796078451565, 6264.655443950626, 6270.153529273581, 6280.214973128359, 6255.718492667841, 6272.937270627235, 6277.089821975788, 6282.246130464282, 6285.43431091204, 4850.199717046718, 4907.288443175719, 4909.484504127086, 4914.975425122851, 4958.157113290749, 4387.5393083726385, 4405.735655860594, 4414.073044660593, 4439.089986552749, 4466.062126953311, 5812.070068788974, 5829.300483941955, 5836.270466191726, 5836.854078648068, 5847.26909136358, 6126.6017708848785, 6199.256089269316, 6269.404406581368, 6272.341404152498, 6310.968445157837, 5729.427057294321, 5782.5663075268685, 5791.739371728282, 5806.593538452352, 5919.230500035054, 5397.9460205397445, 5400.7569659364635, 5420.186971954238, 5422.864428389243, 5471.644343117685, 6272.996320588684, 6280.591761535486, 6282.403171698681, 6282.999999999908, 6285.182926219562, 6145.3407579954055, 6209.130721698875, 6217.844553886189, 6229.4393504584805, 6271.905921411091, 5280.471952804843, 5745.626534275259, 5900.586958912835, 6282.968585157045, 6298.71655775482, 6039.063945088518, 6105.247473117015, 6133.294671112879, 6139.6009259398, 6177.382261773787, 5072.82245121419, 6094.634321940698, 6317.905231421624, 6337.04944258365, 6341.90487142684, 6182.319944805978, 6198.938010619836, 6206.565540412308, 6218.906716399167, 6237.688115594111, 5973.671448070398, 5996.2504686914135, 6001.609895356788, 6084.574079814548, 6162.106494558354, 5945.286565612154, 6039.758409663565, 6040.214772079602, 6076.0582109771285, 6082.178905847831, 5088.745562721775, 6186.659733714689, 6187.752489900534, 6190.566660333913, 6196.659183744757, 5788.913166302593, 5857.648541087548, 5958.374370691188, 6000.369961154016, 6013.248343957005], "value": 5683.0902694338465, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [451.2869665927834, 462.6622565527164, 476.44494164298595, 501.1030255842043, 501.2431228844444, 30545.0, 32121.678783212163, 39844.2031159377, 41364.379534306936, 41459.37810932838, 41967.0, 42404.0, 42450.78774606123, 42470.787646061726, 42515.36226956598, 9336.859947100782, 9567.473788941605, 9568.56941437637, 9585.424874507517, 9591.904080959206, 8481.0, 8688.43525170865, 11191.552337906474, 11464.369459679714, 11511.0, 11414.972652461276, 11482.253653512536, 11495.310281383103, 11495.942520287386, 11517.654470365902, 11456.083513318914, 11511.654650360502, 11528.596499122574, 11533.538658453652, 11558.826617600742, 8379.62291696875, 8623.0, 11074.335539867554, 11358.0, 11392.658220253406, 39610.20779584421, 40118.0, 40288.79855600733, 40353.0, 41036.589634103875, 3301.884434044821, 3492.528508651325, 3493.4410494320787, 3565.3938830398893, 4051.3315302974975, 29676.406471870658, 30467.085987420414, 31504.842475787704, 31536.842315788504, 31551.684483155102, 3098.8915387961315, 3264.951025734616, 3313.8177400242976, 3326.2848487575047, 3366.4781958796425, 510191.0, 514620.85379146104, 515316.0, 515906.0, 517881.0, 65681.0, 66407.0, 66449.33550664528, 66489.0, 66712.66643666847, 69890.0, 70222.0, 70289.64855175691, 70331.0, 70421.0, 15953.202339883192, 16134.435294764628, 16140.0, 16167.757483637755, 16169.757453638205, 11011.504482298316, 12180.390980450942, 12315.93842030784, 12329.260244385365, 12350.505979760888, 14099.85900141006, 17900.28398864031, 17953.7306940396, 17962.64074718498, 17966.461006169837, 6628.502862285348, 8396.874046889301, 8473.449225800303, 8481.515734746517, 8508.234258916727, 767.117814513303, 786.0920636664662, 895.7312806158127, 931.6320053578798, 966.8198213029573, 28375.858120709265, 29690.25774355615, 29737.959171429327, 30216.69783302183, 30314.242143946576, 1003.5734812704717, 1061.756869606723, 1083.1226706367868, 1098.2916019167592, 1203.2299328429813, 15312.770308445159, 15317.6170595736, 15379.92310038465, 15597.300639087121, 15803.68392632141, 601106.0, 603972.0, 605284.0, 605465.0, 606760.0, 58515.0, 59210.0, 59334.0, 59339.70330148322, 59340.813183736085, 1389.423389293432, 1401.7266633006466, 1415.221628104559, 1416.5467050543932, 1417.5818133650598, 28634.570481442377, 29308.706912930596, 29337.0, 29988.0, 30029.399412012073, 51070.74464627653, 51837.22244166265, 51850.22224666557, 51888.702782430744, 52079.47920520746, 586923.0, 588246.0, 589485.0, 589525.0, 591544.0, 25037.624435633115, 26996.460070798083, 27268.04561840326, 27282.04512842041, 27298.0, 39973.0, 41772.58227417687, 42513.0, 42708.145837083706, 43081.56918430901, 35307.11732206613, 35571.822140890166, 35628.93113206608, 36524.0, 36775.816120920295, 35130.0, 35429.46855797114, 35527.11182220465, 36381.27237455289, 36573.63426365809, 28420.57369139423, 35330.11674708153, 35472.82263588665, 35576.0, 35700.107497312776, 29735.702642973294, 31862.681373185973, 31943.361132777678, 32038.0, 32277.51583726293, 19339.129739162054, 21519.278457723656, 23179.072837087002, 23607.40981475477, 24146.39634009164], "value": 19964.606402023277, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [351.57635049765037, 369.1233320862949, 370.83497843459656, 378.3775688991608, 400.82163437270407, 14417.207053612046, 14666.77999830002, 15296.617584560378, 19521.804781952178, 20360.287389941353, 19688.11403486843, 20180.091895864683, 20744.0, 20855.687164692503, 21134.471638209052, 5734.741936612862, 5941.405859414067, 5994.1608174855555, 5999.76000959961, 6006.129111278862, 4694.953050469503, 5867.647941123525, 5925.970370148143, 5936.673482958436, 5946.137810017566, 4098.241825262321, 4332.24185767491, 6169.475594574466, 6440.162778838744, 6457.321981191978, 6286.339934306923, 6467.579607325509, 6541.443977261938, 6553.377429144248, 6554.311797261266, 5545.833624991256, 5685.431456854323, 5687.43125687428, 5687.914681279743, 5689.260396148516, 25127.87436062808, 25552.87223563889, 25593.616095758578, 25606.871965640054, 25975.22074337773, 2500.4874000829786, 2558.373198566363, 2667.373167305682, 3114.7196752292175, 3217.1957010747315, 15332.233388330651, 15489.22553872313, 16079.758803617955, 16206.75689864653, 16511.33954641813, 1488.9553313400615, 1563.695079459506, 1707.888987215827, 1864.645717313706, 1894.4695485264149, 99144.0, 102913.48543257236, 103518.48240758748, 105721.0, 105972.0, 11232.831507527393, 11302.321860688386, 11362.602308919148, 11512.251703639236, 11624.070074394198, 14368.209748463725, 14368.85631143696, 14381.856181438261, 14393.78409323861, 14424.980502729626, 7667.348275396542, 8177.1414001530175, 8310.792230194293, 8330.66677332912, 8342.874856877152, 7431.331180193809, 8168.42821002536, 8302.796094566374, 8335.291500222427, 8361.749147525585, 3626.5104210931713, 4458.286674132123, 4461.843835465743, 4470.240059189946, 4568.862934111982, 1844.3821319857998, 1884.5006073390562, 1891.238791364623, 1891.5838515526561, 1891.8391936685537, 779.6803310642723, 825.6532256452305, 869.6520393390219, 887.7837362812987, 907.3603109807569, 17864.196111175028, 20706.378808635764, 21575.56848863014, 21640.675389869164, 21781.455463613536, 883.7967267528504, 925.3799954030816, 937.8593211018265, 942.665353799405, 971.7133445633555, 8804.459219636537, 10758.354498730101, 11264.718382040515, 11449.885501144881, 11491.310521368578, 542934.0, 551290.0, 553260.0, 563802.1809891006, 567965.0, 93087.0, 122375.77624223643, 125231.0, 127321.0, 128646.35676821556, 963.2342287881152, 1021.545412291538, 1034.0125180452499, 1035.0580971316067, 1035.9223058270659, 15796.526104216891, 19853.205871764967, 20036.799632003494, 20109.49726256855, 20587.79412205859, 52355.73822130865, 52429.7378513105, 54072.0, 54287.45712542982, 54707.0, 73171.53656926725, 76542.02668503589, 76786.6160669193, 78553.21446785687, 79487.60256198682, 6553.0, 7536.321731044232, 7654.081510218587, 7952.443328966917, 8201.466904651179, 7497.812554686177, 9027.909720902708, 11583.42082895835, 11629.59296424621, 12650.43073061696, 10655.987681170276, 10772.24594278393, 10893.237473376788, 11013.889861101607, 11139.33164010162, 10567.418791966516, 10628.946855265674, 10649.148068154374, 10735.516901739284, 10831.350118992885, 9205.861912071457, 10843.403612801372, 11043.503042362952, 11139.498722557342, 11141.888581114084, 6803.7618683345845, 7930.563818990011, 7938.722144725138, 7948.125706172432, 8168.83662326762, 8059.637316320663, 10218.795624087328, 10822.729431764268, 10869.0, 11069.72325691832], "value": 9421.83783353331, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1308.7120833416648, 1500.9174495402751, 1652.892561983471, 1858.5353661584604, 1888.7355770192166, 7896.407769417295, 8332.666693332267, 8347.081820999685, 8360.95819520903, 8386.454880432768, 7360.779176624696, 7655.885161722578, 7660.846783064336, 8072.596370181497, 8316.293115085226, 2260.660900864875, 2305.8501197422197, 2306.550222706573, 2310.1683393978155, 2311.56080344734, 2295.2655150351893, 2314.4792421705065, 2330.941726456835, 2332.265336419023, 2332.766723327662, 2057.32108404227, 2198.7251593550805, 2274.283600665785, 2301.7698230177016, 2309.1225334373, 2260.5704916065893, 2265.784750448705, 2285.69143165673, 2289.885505724707, 2290.1068583252545, 2276.612975794119, 2304.0760964594583, 2305.296884450242, 2307.5038866643586, 2314.7338056123594, 8168.754937351889, 8200.507969521848, 8289.585520724, 8290.917090829074, 8301.667933282723, 4957.132501812169, 5117.309163262949, 5128.384593848725, 5132.384113906318, 5134.306868572732, 3178.539111828795, 3255.088575198948, 3466.7399945004227, 3467.5318831957616, 3471.5486986691567, 1021.8467229915498, 1036.388530766851, 1043.7703705184847, 1062.7715041266165, 1064.3188359449957, 10493.370397776158, 10499.632512862014, 10665.893341066645, 10681.786364272748, 10690.679279621623, 4361.367601697736, 4435.711678740871, 4522.819087236539, 4526.932096018562, 4537.478190008125, 9233.907660923305, 9246.26029917605, 9250.537473126451, 9251.028641992494, 9265.166135047743, 5038.0931432341795, 5111.616628752859, 5117.155669314596, 5123.077845987684, 5129.333186685707, 4233.322668373045, 4264.424302719093, 4386.495553011381, 4682.484926658065, 4690.624750019991, 3701.3522633539023, 3731.8134093295225, 3797.715171362104, 3822.904427389282, 3838.677600051984, 1203.5366383942246, 1205.4093494187784, 1209.5101483899034, 1219.432963671897, 1221.9877801221874, 865.6580650643086, 876.9429987050821, 878.9868151977726, 895.8611415230649, 919.3288899103692, 5355.714628489212, 5472.34331880169, 5562.749676264578, 5741.827745167651, 5859.707014649336, 735.9484836061424, 929.9027147965465, 979.9657012004545, 1020.0, 1040.7814358984547, 3397.303552771676, 3594.2092739597247, 3632.3824949758605, 3655.3968595182027, 3739.8878033659034, 9491.572879220314, 9701.223902087957, 9878.357906736039, 9898.752531186634, 9975.301728879054, 9853.50732463374, 10001.449920254456, 10015.899841001643, 10020.849687254697, 10045.29682922203, 7567.091948966159, 7645.4648174628355, 7773.378129749606, 7832.608369581612, 7861.489003214659, 5251.816186433456, 5309.442508536548, 5383.946160538345, 5395.865103372447, 5517.806876759294, 9147.679831205876, 9189.77025574366, 9275.443473391619, 9278.025807290407, 9288.72133835986, 8842.0, 9180.862287065833, 9192.17270445663, 9322.906770932475, 9342.53287335644, 6564.179477565304, 6757.155355580552, 6761.763338283136, 7045.5420397676035, 7206.207317195209, 7554.320111190019, 7726.652300646372, 7858.803529911798, 7948.125706172201, 7973.9202607973175, 7042.471814613822, 7149.213586505584, 7182.640867956686, 7212.0, 7287.744928927462, 6915.481338899502, 6978.79063628092, 7002.579845209304, 7066.187388450394, 7089.539179953286, 6199.938000619936, 6222.751089956351, 6282.623042617458, 6286.654234017173, 6291.087792270004, 4678.578927896369, 4719.079779442975, 4731.29030645406, 4777.737224452689, 4866.245731911417, 3721.888343349704, 4253.638440732572, 4728.17256980034, 4797.592204662643, 4827.565519103297], "value": 4345.600438833467, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [318.7752634392753, 350.4392971246005, 355.13524567677706, 359.20794647801586, 361.1368828499884, 3906.667933225679, 5129.307543481629, 5142.151544995076, 5174.715390653512, 5216.0, 4392.0, 5015.5736762375245, 5160.142348754454, 5203.557697595699, 5226.425093239741, 3546.7162626989907, 3856.652901238881, 3905.3946638270972, 3999.2401443725744, 4049.2104039712267, 3364.226227967569, 3480.207693038087, 3957.881263562098, 3985.422113793513, 4042.555318914917, 3396.6433524479944, 3682.55809302884, 3967.36522156455, 4028.7913625912256, 4047.150098479323, 3347.8493467794005, 3730.7015438764834, 3816.7709937403565, 3827.6937844972476, 4070.0, 2828.8019838611303, 3234.6252842541826, 3330.4837750148768, 3429.331280400323, 3517.8416971236356, 4405.9118817623785, 4800.543948324938, 4942.975285123552, 5024.447310795809, 5057.291979122926, 3868.388837534681, 3882.3594106972314, 4294.754521188852, 4592.908141837178, 4662.510436404196, 5159.58723302135, 5377.677339359612, 5432.592555558309, 5463.098588732854, 5511.3937466878615, 3344.0771556355194, 3948.774810952282, 3967.5238971323624, 3975.60243975603, 3992.640662340374, 4980.825671101494, 5450.5639548836, 5490.862728431781, 5793.913091303634, 5829.504492118175, 3406.5060566217758, 3950.940735888964, 4050.4936882889638, 4063.6342729154517, 4070.9389359159636, 3316.8839090632196, 3450.6031806342594, 3658.1894808327497, 4102.2410853992105, 4122.886820558432, 4294.720843145185, 4354.562994211902, 4500.302453119772, 4626.768661566908, 4699.342092107107, 4710.29345598163, 5507.889842203133, 5520.309961254843, 5530.419305972895, 5539.612227144061, 1268.4038501904274, 1341.2086868747485, 1350.3855745635713, 1352.8647135286392, 1360.8775210231126, 822.979425514355, 892.7143314139413, 911.9635214591475, 948.170350942925, 961.9995204986875, 4234.0050088229245, 4595.425571803525, 4864.927026094611, 4897.379901752545, 4910.705357678551, 4433.800478978454, 4950.727709975986, 5076.974615126901, 5279.841604751864, 5282.260483532308, 3596.7841929484316, 4337.7397356158735, 4893.360724157447, 4949.381327334083, 4955.702657840541, 2592.58704006317, 2630.592258199982, 2630.8026897982727, 2807.368342123006, 2835.8582070896373, 4786.922942337947, 5068.11117662936, 5446.121088224556, 5492.313460817398, 5618.005608009098, 5039.84880453587, 5420.322459692538, 5438.809641662523, 5556.0, 5884.529237661062, 4759.976200118977, 4995.825146119869, 5459.617826752169, 5523.309586301712, 5945.583809133319, 5702.315722113373, 5733.0, 5738.483536481736, 5749.338826035059, 5750.5112065474905, 5191.0, 5264.473552644705, 5622.409646987007, 5646.915296270641, 5687.516561092188, 4818.614510839195, 4975.875603109806, 5395.730213489232, 5707.971460142839, 5793.91309130355, 3287.6547962463596, 3565.899215901734, 3735.701143908535, 3736.757110787899, 3750.287445385368, 4960.751962401938, 5733.971330143323, 5738.8235411741325, 5745.391290438683, 5774.2782152230975, 4998.60011199096, 5100.413452453015, 5132.742478092891, 5489.890202196014, 5506.834794956158, 4416.889577760582, 4837.532858242252, 5518.2826232589505, 5531.538465382866, 5534.557235421076, 5312.294802739115, 5334.493223143795, 5379.757910893941, 5407.351117865724, 5419.783208671609, 5240.737963101906, 5364.7049412282695, 5505.559555235652, 5521.889562208814, 5538.529225015919, 4749.4063242094735, 5400.945990540202, 5434.402215756343, 5451.209574611738, 5470.480304371078], "value": 3974.219406181087, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [733.9192688804231, 734.9412047036237, 749.9625018749061, 793.1513280789558, 805.4804651000105, 14983.700325993486, 15625.531234062968, 15628.921855390721, 15644.530664080068, 15757.527274181793, 14329.426822927071, 14513.056651317656, 14517.49188778393, 14527.12837229767, 14539.49111781088, 652.7356420649644, 707.5047466773253, 710.0485349631492, 710.882704353781, 862.6549380247889, 711.900333953247, 715.9713611455536, 849.8257857139272, 879.1779685993585, 884.4251236696167, 927.0590350793949, 934.5841100710187, 940.4357385568672, 941.2093841173413, 942.7595963029452, 889.1819526036062, 890.0609856601316, 909.8180363927174, 937.0957026469456, 938.9014153513851, 518.3753576939816, 556.8691357531018, 698.1238545625208, 700.973074445934, 701.9298070192991, 14015.229162396065, 14598.34307456167, 14621.488247911271, 14891.032082914577, 14891.47879824201, 14086.859131408657, 14266.3580138894, 14277.428902843874, 14337.713245735027, 14380.64048398788, 10228.488575571266, 11225.831612525737, 11862.0, 11933.582324618597, 11937.94031029848, 3713.4429835524616, 3725.8137093145233, 3736.645018723217, 3758.1168425420005, 3902.0049887278847, 97235.0, 110389.0, 113016.0, 113823.0, 116022.25966610508, 10975.560977560968, 10991.61529346469, 11016.669499915015, 11125.666230013112, 11153.944230278797, 21809.901324112314, 21913.671294930587, 22058.89705514718, 22071.00680469383, 22141.446463838533, 7640.923590764132, 7747.883781743279, 7760.844783104306, 7861.213878612093, 7962.681492740341, 4209.284421648266, 4301.978490107529, 4313.741175529478, 4329.372241025034, 4452.198604251201, 3467.133216695826, 3563.2873425314524, 3565.16218688608, 3595.1012246938267, 3618.782873027627, 648.2901223160632, 653.258551544001, 655.5574986883835, 665.4443539644451, 666.5800545656302, 625.3933684326196, 665.2814959843357, 665.8568407792347, 678.4809620640215, 696.8675951569186, 8730.694425695197, 9008.549572521348, 9048.764161935665, 9141.451512909114, 9512.857307140399, 572.513363640902, 593.9732712027883, 645.3030726815028, 657.5462930577886, 681.6523572977817, 4283.785810709452, 4455.754933478625, 4615.515370886009, 4622.745748983838, 4671.32265821454, 99897.0, 103659.96340036654, 104399.0, 104687.0, 106952.0, 91775.0, 93411.53294233486, 93711.0, 94097.0590294102, 96891.0, 82693.0, 82800.58599706963, 83062.58468707738, 84592.57703711565, 85207.0, 8760.4305720128, 8897.154770296811, 8989.41568798026, 8996.910030899608, 9148.408288779387, 49630.50369496259, 49895.0, 50020.49979500304, 50870.0, 51750.96498070093, 75491.18614580069, 75589.6220518916, 76466.79432631259, 78007.60996194983, 79679.0, 11567.652970410902, 11625.825612615987, 11683.649490515298, 12231.204971676812, 12341.259524428562, 10700.324918770308, 10710.196735245043, 10737.355758654505, 12924.353782311035, 13839.0, 11931.0, 12264.75470490603, 12286.262824230575, 12713.745725085631, 12727.93636031814, 10323.225758068325, 11788.587399440978, 12068.79312068786, 12079.57721479744, 12335.938320308342, 11982.64052078439, 12019.09856760764, 12274.570390036306, 12311.44598493052, 12545.996320294173, 9481.431114132898, 9498.287628427757, 9614.0, 9770.120689138012, 10033.247506436901, 10153.340032897839, 10313.587456501657, 10508.527116279938, 10538.57845686164, 10639.787204256027], "value": 7316.09341502631, "unit": "score"}], "name": "dromaeo_css", "value": 7163.113130012576, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 114.0, 113.0, 113.0, 113.0, 114.0, 113.0, 112.0, 113.0, 113.0], "value": 113.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 109.0, 109.0, 145.0, 113.0, 138.0, 144.0, 141.0, 114.0, 142.0], "value": 129.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 183.0, 181.0, 185.0, 183.0, 184.0, 180.0, 183.0, 183.0, 185.0], "value": 182.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 59.0, 60.0, 85.0, 62.0, 59.0, 68.0, 59.0, 74.0, 59.0], "value": 66.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 70.0, 85.0, 71.0, 70.0, 68.0, 83.0, 68.0, 68.0, 83.0], "value": 75.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 87.0, 84.0, 87.0, 84.0, 88.0, 85.0, 88.0, 84.0], "value": 85.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 105.0, 149.0, 94.0, 93.0, 93.0, 94.0, 94.0, 94.0, 94.0], "value": 100.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 91.0, 124.0, 85.0, 88.0, 119.0, 139.0, 92.0, 81.0], "value": 99.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 56.0, 56.0, 54.0, 57.0, 57.0, 56.0, 55.0, 55.0], "value": 55.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 82.0, 77.0, 72.0, 73.0, 69.0, 69.0, 69.0, 71.0, 71.0], "value": 72.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 88.0, 88.0, 90.0, 85.0, 84.0, 100.0, 108.0, 109.0, 110.0], "value": 94.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 129.0, 147.0, 147.0, 153.0, 160.0, 142.0, 157.0, 210.0, 139.0], "value": 152.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 173.0, 172.0, 169.0, 170.0, 169.0, 172.0, 169.0, 168.0, 159.0], "value": 168.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 68.0, 65.0, 67.0, 66.0, 67.0, 66.0, 66.0, 65.0], "value": 66.4, "unit": "score"}], "name": "kraken", "value": 1463.2, "alertThreshold": 2.0}]} 11:23:35 INFO - Return code: 0 11:23:35 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:23:35 INFO - # TBPL SUCCESS # 11:23:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:23:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:23:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:23:35 INFO - 2016-12-23 11:23:35.124 screenresolution[1655:10253] starting screenresolution argv=screenresolution get 11:23:35 INFO - 2016-12-23 11:23:35.127 screenresolution[1655:10253] Display 0: 1600x1200x32@60 11:23:35 INFO - 2016-12-23 11:23:35.133 screenresolution[1656:10256] starting screenresolution argv=screenresolution list 11:23:35 INFO - Available Modes on Display 0 11:23:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:23:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:23:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:23:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:23:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:23:35 INFO - Intel Iris: 11:23:35 INFO - Chipset Model: Intel Iris 11:23:35 INFO - Type: GPU 11:23:35 INFO - Bus: Built-In 11:23:35 INFO - VRAM (Dynamic, Max): 1536 MB 11:23:35 INFO - Vendor: Intel (0x8086) 11:23:35 INFO - Device ID: 0x0a2e 11:23:35 INFO - Revision ID: 0x0009 11:23:35 INFO - Displays: 11:23:35 INFO - Display: 11:23:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:23:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:23:35 INFO - Display Serial Number: 1600x1200 60 11:23:35 INFO - Main Display: Yes 11:23:35 INFO - Mirror: Off 11:23:35 INFO - Online: Yes 11:23:35 INFO - Rotation: Supported 11:23:35 INFO - Return code: 0 11:23:35 INFO - Running post-action listener: _resource_record_post_action 11:23:35 INFO - [mozharness: 2016-12-23 19:23:35.379137Z] Finished run-tests step (success) 11:23:35 INFO - Running post-run listener: _resource_record_post_run 11:23:35 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 32128000; Write bytes: 550368256; Read time: 190; Write time: 1665 11:23:35 INFO - TinderboxPrint: CPU usage
53.7% 11:23:35 INFO - TinderboxPrint: I/O read bytes / time
32,128,000 / 190 11:23:35 INFO - TinderboxPrint: I/O write bytes / time
550,368,256 / 1,665 11:23:35 INFO - TinderboxPrint: CPU idle
2,226.6 (46.3%) 11:23:35 INFO - TinderboxPrint: CPU system
962.8 (20.0%) 11:23:35 INFO - TinderboxPrint: CPU user
1,620.3 (33.7%) 11:23:35 INFO - TinderboxPrint: Swap in / out
277,590,016 / 0 11:23:35 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 248183296; Write bytes: 243709952; Read time: 18306; Write time: 536 11:23:35 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 28830720; Write bytes: 294996992; Read time: 167; Write time: 1096 11:23:35 INFO - Running post-run listener: _upload_blobber_files 11:23:35 INFO - Blob upload gear active. 11:23:35 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:23:35 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 11:23:35 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', 'Graphics', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:23:35 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 Graphics -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:23:36 INFO - (blobuploader) - INFO - Open directory for files ... 11:23:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:23:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:23:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:23:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:23:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:23:37 INFO - (blobuploader) - INFO - Done attempting. 11:23:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:23:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:23:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:23:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:23:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:23:38 INFO - (blobuploader) - INFO - Done attempting. 11:23:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 11:23:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:23:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:23:39 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 11:23:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:23:39 INFO - (blobuploader) - INFO - Done attempting. 11:23:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:23:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:23:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:23:41 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:23:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:23:41 INFO - (blobuploader) - INFO - Done attempting. 11:23:41 INFO - (blobuploader) - INFO - Iteration through files over. 11:23:41 INFO - Return code: 0 11:23:41 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:23:41 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:23:41 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/5a7945959e4b8af7df2f735060c107133c5547fa65063f6c6a1a45e2af46656fa9242a1ab0bafdfc3d54dfa9667003bf91e2c3c27a69a17f75d5dc55a382d885", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/cec3c853bc51b7f4a72e28f756ddabe848c0d55259e744a1e3ce280ee2cddb058043a6c02278155623e52e91a2784f3f9de1d042ff4197a357588b40bc45af44", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/6ffa6f2edad2ab11489aced01bb26911ba5b6e5a202e7a19c1935b22b16cc55c415f903e28c10ff447c91398ea09a6cd83cc24e1c653a73975341a14df3a3a3c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/e99da99ecbf984dcc08b41b29dd51bd9ecc89cfe878d967f6278989e545b8afd8f8259f1047d83dda0455f3b2a19c3e6081d398ad2cca4f5281a5248bf6b521c"} 11:23:41 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:23:41 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:23:41 INFO - Contents: 11:23:41 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/5a7945959e4b8af7df2f735060c107133c5547fa65063f6c6a1a45e2af46656fa9242a1ab0bafdfc3d54dfa9667003bf91e2c3c27a69a17f75d5dc55a382d885", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/cec3c853bc51b7f4a72e28f756ddabe848c0d55259e744a1e3ce280ee2cddb058043a6c02278155623e52e91a2784f3f9de1d042ff4197a357588b40bc45af44", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/6ffa6f2edad2ab11489aced01bb26911ba5b6e5a202e7a19c1935b22b16cc55c415f903e28c10ff447c91398ea09a6cd83cc24e1c653a73975341a14df3a3a3c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/e99da99ecbf984dcc08b41b29dd51bd9ecc89cfe878d967f6278989e545b8afd8f8259f1047d83dda0455f3b2a19c3e6081d398ad2cca4f5281a5248bf6b521c"} 11:23:41 INFO - Running post-run listener: copy_logs_to_upload_dir 11:23:41 INFO - Copying logs to upload dir... 11:23:41 INFO - mkdir: /builds/slave/test/build/upload/logs 11:23:41 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.152787 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-12-23 11:23:41.521377) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-23 11:23:41.525443) ========= 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.HnULz9L8nS/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.7ekeZbwtwN/Listeners TMPDIR=/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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/Graphics/sha512/5a7945959e4b8af7df2f735060c107133c5547fa65063f6c6a1a45e2af46656fa9242a1ab0bafdfc3d54dfa9667003bf91e2c3c27a69a17f75d5dc55a382d885", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/cec3c853bc51b7f4a72e28f756ddabe848c0d55259e744a1e3ce280ee2cddb058043a6c02278155623e52e91a2784f3f9de1d042ff4197a357588b40bc45af44", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/6ffa6f2edad2ab11489aced01bb26911ba5b6e5a202e7a19c1935b22b16cc55c415f903e28c10ff447c91398ea09a6cd83cc24e1c653a73975341a14df3a3a3c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/e99da99ecbf984dcc08b41b29dd51bd9ecc89cfe878d967f6278989e545b8afd8f8259f1047d83dda0455f3b2a19c3e6081d398ad2cca4f5281a5248bf6b521c"} build_url:https://queue.taskcluster.net/v1/task/KurghkI5RfGWnolUqbECNw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011069 build_url: 'https://queue.taskcluster.net/v1/task/KurghkI5RfGWnolUqbECNw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/5a7945959e4b8af7df2f735060c107133c5547fa65063f6c6a1a45e2af46656fa9242a1ab0bafdfc3d54dfa9667003bf91e2c3c27a69a17f75d5dc55a382d885", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/cec3c853bc51b7f4a72e28f756ddabe848c0d55259e744a1e3ce280ee2cddb058043a6c02278155623e52e91a2784f3f9de1d042ff4197a357588b40bc45af44", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/6ffa6f2edad2ab11489aced01bb26911ba5b6e5a202e7a19c1935b22b16cc55c415f903e28c10ff447c91398ea09a6cd83cc24e1c653a73975341a14df3a3a3c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/e99da99ecbf984dcc08b41b29dd51bd9ecc89cfe878d967f6278989e545b8afd8f8259f1047d83dda0455f3b2a19c3e6081d398ad2cca4f5281a5248bf6b521c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-23 11:23:41.559268) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-23 11:23:41.559613) ========= 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.HnULz9L8nS/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.7ekeZbwtwN/Listeners TMPDIR=/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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.005307 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-23 11:23:41.607832) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-23 11:23:41.608215) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-23 11:23:41.608593) ========= ========= Total master_lag: 1.01 =========