builder: graphics_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0318 starttime: 1484057443.89 results: success (0) buildid: 20170110052108 builduid: f5880a62313141e9b4a1bd33ad7c9434 revision: a4d644d5881f79bcd80b1f8d1b355b958dc4ec1d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-10 06:10:43.886663) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-10 06:10:43.887081) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-10 06:10:43.887352) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-10 06:10:43.912426) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-10 06:10:43.912725) ========= 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.P5cwJiLYdc/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.xXCg2SJib0/Listeners TMPDIR=/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-01-10 06:10:43-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 20.9M=0.001s 2017-01-10 06:10:44 (20.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.131541 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-10 06:10:44.067795) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-10 06:10:44.068103) ========= 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.P5cwJiLYdc/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.xXCg2SJib0/Listeners TMPDIR=/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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.027238 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-10 06:10:44.116637) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-10 06:10:44.116996) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev a4d644d5881f79bcd80b1f8d1b355b958dc4ec1d --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 a4d644d5881f79bcd80b1f8d1b355b958dc4ec1d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.P5cwJiLYdc/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.xXCg2SJib0/Listeners TMPDIR=/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-01-10 06:10:44,211 truncating revision to first 12 chars 2017-01-10 06:10:44,211 Setting DEBUG logging. 2017-01-10 06:10:44,211 attempt 1/10 2017-01-10 06:10:44,211 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/a4d644d5881f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-10 06:10:45,900 unpacking tar archive at: graphics-a4d644d5881f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.970587 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-10 06:10:46.110594) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-10 06:10:46.110923) ========= script_repo_revision: a4d644d5881f79bcd80b1f8d1b355b958dc4ec1d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-10 06:10:46.111307) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-10 06:10:46.111571) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-10 06:10:46.130993) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2017-01-10 06:10:46.131305) ========= /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.P5cwJiLYdc/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.xXCg2SJib0/Listeners TMPDIR=/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 06:10:46 INFO - MultiFileLogger online at 20170110 06:10:46 in /builds/slave/test 06:10:46 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 06:10:46 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:10:46 INFO - {'append_to_log': False, 06:10:46 INFO - 'base_work_dir': '/builds/slave/test', 06:10:46 INFO - 'blob_upload_branch': 'Graphics', 06:10:46 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:10:46 INFO - 'branch': 'Graphics', 06:10:46 INFO - 'buildbot_json_path': 'buildprops.json', 06:10:46 INFO - 'config_files': ('talos/mac_config.py',), 06:10:46 INFO - 'default_actions': ('clobber', 06:10:46 INFO - 'read-buildbot-config', 06:10:46 INFO - 'download-and-extract', 06:10:46 INFO - 'populate-webroot', 06:10:46 INFO - 'create-virtualenv', 06:10:46 INFO - 'install', 06:10:46 INFO - 'run-tests'), 06:10:46 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:10:46 INFO - 'download_minidump_stackwalk': True, 06:10:46 INFO - 'download_symbols': 'ondemand', 06:10:46 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:10:46 INFO - 'tooltool.py': '/tools/tooltool.py', 06:10:46 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:10:46 INFO - '/tools/misc-python/virtualenv.py')}, 06:10:46 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:10:46 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:10:46 INFO - 'installer_path': 'installer.exe', 06:10:46 INFO - 'log_level': 'info', 06:10:46 INFO - 'log_name': 'talos', 06:10:46 INFO - 'log_to_console': True, 06:10:46 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 06:10:46 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 06:10:46 INFO - 'opt_config_files': (), 06:10:46 INFO - 'pip_index': False, 06:10:46 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:10:46 INFO - 'cmd': ('bash', 06:10:46 INFO - '-c', 06:10:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:10:46 INFO - 'enabled': True, 06:10:46 INFO - 'halt_on_failure': False, 06:10:46 INFO - 'name': 'check_screen_resolution'},), 06:10:46 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:10:46 INFO - 'cmd': ('bash', 06:10:46 INFO - '-c', 06:10:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:10:46 INFO - 'enabled': True, 06:10:46 INFO - 'halt_on_failure': False, 06:10:46 INFO - 'name': 'check_screen_resolution'},), 06:10:46 INFO - 'run_cmd_checks_enabled': True, 06:10:46 INFO - 'sps_profile': False, 06:10:46 INFO - 'sps_profile_interval': 0, 06:10:46 INFO - 'suite': 'dromaeojs', 06:10:46 INFO - 'system_bits': '32', 06:10:46 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:10:46 INFO - 'title': 't-yosemite-r7-0318', 06:10:46 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:10:46 INFO - 'use_talos_json': True, 06:10:46 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:10:46 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:10:46 INFO - 'work_dir': 'build'} 06:10:46 INFO - [mozharness: 2017-01-10 14:10:46.332682Z] Running clobber step. 06:10:46 INFO - Running pre-action listener: _resource_record_pre_action 06:10:46 INFO - Running main action method: clobber 06:10:46 INFO - rmtree: /builds/slave/test/build 06:10:46 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:10:48 INFO - Running post-action listener: _resource_record_post_action 06:10:48 INFO - [mozharness: 2017-01-10 14:10:48.642056Z] Finished clobber step (success) 06:10:48 INFO - [mozharness: 2017-01-10 14:10:48.642224Z] Running read-buildbot-config step. 06:10:48 INFO - Running pre-action listener: _resource_record_pre_action 06:10:48 INFO - Running main action method: read_buildbot_config 06:10:48 INFO - Using buildbot properties: 06:10:48 INFO - { 06:10:48 INFO - "project": "", 06:10:48 INFO - "product": "firefox", 06:10:48 INFO - "script_repo_revision": "default", 06:10:48 INFO - "builddir": "graphics_yosemite_r7_test-dromaeojs", 06:10:48 INFO - "repository": "", 06:10:48 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 graphics talos dromaeojs", 06:10:48 INFO - "buildid": "20170110052108", 06:10:48 INFO - "pgo_build": "False", 06:10:48 INFO - "basedir": "/builds/slave/test", 06:10:48 INFO - "buildnumber": 0, 06:10:48 INFO - "slavename": "t-yosemite-r7-0318", 06:10:48 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 06:10:48 INFO - "platform": "yosemite_r7", 06:10:48 INFO - "scheduler": "tests-graphics-macosx64-talos", 06:10:48 INFO - "branch": "graphics", 06:10:48 INFO - "repo_path": "projects/graphics", 06:10:48 INFO - "revision": "a4d644d5881f79bcd80b1f8d1b355b958dc4ec1d", 06:10:48 INFO - "stage_platform": "macosx64", 06:10:48 INFO - "builduid": "f5880a62313141e9b4a1bd33ad7c9434", 06:10:48 INFO - "slavebuilddir": "test" 06:10:48 INFO - } 06:10:48 INFO - Found installer url https://queue.taskcluster.net/v1/task/AwHs_ZPsR2CI3R0FAaOaLA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 06:10:48 INFO - Running post-action listener: _resource_record_post_action 06:10:48 INFO - [mozharness: 2017-01-10 14:10:48.645577Z] Finished read-buildbot-config step (success) 06:10:48 INFO - [mozharness: 2017-01-10 14:10:48.645692Z] Running download-and-extract step. 06:10:48 INFO - Running pre-action listener: _resource_record_pre_action 06:10:48 INFO - Running main action method: download_and_extract 06:10:48 INFO - mkdir: /builds/slave/test/build/tests 06:10:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:10:48 INFO - trying https://queue.taskcluster.net/v1/task/AwHs_ZPsR2CI3R0FAaOaLA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 06:10:48 INFO - Downloading https://queue.taskcluster.net/v1/task/AwHs_ZPsR2CI3R0FAaOaLA/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 06:10:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AwHs_ZPsR2CI3R0FAaOaLA/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 06:10:49 INFO - Downloaded 1199 bytes. 06:10:49 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 06:10:49 INFO - Using the following test package requirements: 06:10:49 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 06:10:49 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 06:10:49 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 06:10:49 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 06:10:49 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 06:10:49 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 06:10:49 INFO - u'jsshell-mac.zip'], 06:10:49 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 06:10:49 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 06:10:49 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 06:10:49 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 06:10:49 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 06:10:49 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 06:10:49 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 06:10:49 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 06:10:49 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 06:10:49 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 06:10:49 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 06:10:49 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 06:10:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AwHs_ZPsR2CI3R0FAaOaLA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 06:10:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AwHs_ZPsR2CI3R0FAaOaLA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:10:49 INFO - Fetch https://queue.taskcluster.net/v1/task/AwHs_ZPsR2CI3R0FAaOaLA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 06:10:50 INFO - Expected file size: 16304854 06:10:50 INFO - Obtained file size: 16304854 06:10:56 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 06:10:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AwHs_ZPsR2CI3R0FAaOaLA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 06:10:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AwHs_ZPsR2CI3R0FAaOaLA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:10:56 INFO - Fetch https://queue.taskcluster.net/v1/task/AwHs_ZPsR2CI3R0FAaOaLA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 06:10:57 INFO - Expected file size: 16304854 06:10:57 INFO - Obtained file size: 16304854 06:11:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AwHs_ZPsR2CI3R0FAaOaLA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 06:11:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AwHs_ZPsR2CI3R0FAaOaLA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 06:11:04 INFO - Fetch https://queue.taskcluster.net/v1/task/AwHs_ZPsR2CI3R0FAaOaLA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 06:11:04 INFO - Expected file size: 14403537 06:11:05 INFO - Obtained file size: 14403537 06:11:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:11:05 INFO - trying https://queue.taskcluster.net/v1/task/AwHs_ZPsR2CI3R0FAaOaLA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 06:11:05 INFO - Downloading https://queue.taskcluster.net/v1/task/AwHs_ZPsR2CI3R0FAaOaLA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 06:11:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AwHs_ZPsR2CI3R0FAaOaLA/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 06:11:07 INFO - Downloaded 69842804 bytes. 06:11:07 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/AwHs_ZPsR2CI3R0FAaOaLA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 06:11:07 INFO - mkdir: /builds/slave/test/properties 06:11:07 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:11:07 INFO - Writing to file /builds/slave/test/properties/build_url 06:11:07 INFO - Contents: 06:11:07 INFO - build_url:https://queue.taskcluster.net/v1/task/AwHs_ZPsR2CI3R0FAaOaLA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 06:11:08 INFO - Running post-action listener: _resource_record_post_action 06:11:08 INFO - Running post-action listener: set_extra_try_arguments 06:11:08 INFO - [mozharness: 2017-01-10 14:11:08.358685Z] Finished download-and-extract step (success) 06:11:08 INFO - [mozharness: 2017-01-10 14:11:08.358823Z] Running populate-webroot step. 06:11:08 INFO - Running pre-action listener: _resource_record_pre_action 06:11:08 INFO - Running main action method: populate_webroot 06:11:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:11:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:11:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:11:08 INFO - '--tppagecycles', 06:11:08 INFO - '7'], 06:11:08 INFO - 'tests': ['tsvgm']}}, 06:11:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:11:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:11:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:11:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:11:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:11:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:11:08 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 06:11:08 INFO - 'talos_options': ['--disable-e10s'], 06:11:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:11:08 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 06:11:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:11:08 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 06:11:08 INFO - 'talos_options': ['--disable-e10s'], 06:11:08 INFO - 'tests': ['damp', 'tps']}, 06:11:08 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 06:11:08 INFO - 'tests': ['damp', 'tps']}, 06:11:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:11:08 INFO - 'tests': ['dromaeo_dom']}, 06:11:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:11:08 INFO - 'g4': {'talos_options': ['--disable-e10s'], 06:11:08 INFO - 'tests': ['basic_compositor_video']}, 06:11:08 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 06:11:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:11:08 INFO - 'tests': ['a11yr', 06:11:08 INFO - 'ts_paint', 06:11:08 INFO - 'tpaint', 06:11:08 INFO - 'sessionrestore', 06:11:08 INFO - 'sessionrestore_no_auto_restore', 06:11:08 INFO - 'tabpaint']}, 06:11:08 INFO - 'other-e10s': {'tests': ['a11yr', 06:11:08 INFO - 'ts_paint', 06:11:08 INFO - 'tpaint', 06:11:08 INFO - 'sessionrestore', 06:11:08 INFO - 'sessionrestore_no_auto_restore', 06:11:08 INFO - 'tabpaint']}, 06:11:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:11:08 INFO - 'ts_paint', 06:11:08 INFO - 'tpaint', 06:11:08 INFO - 'sessionrestore', 06:11:08 INFO - 'sessionrestore_no_auto_restore', 06:11:08 INFO - 'tabpaint']}, 06:11:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:11:08 INFO - 'ts_paint', 06:11:08 INFO - 'tpaint', 06:11:08 INFO - 'sessionrestore', 06:11:08 INFO - 'sessionrestore_no_auto_restore', 06:11:08 INFO - 'tabpaint']}, 06:11:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:11:08 INFO - 'tests': ['a11yr', 06:11:08 INFO - 'ts_paint', 06:11:08 INFO - 'tpaint', 06:11:08 INFO - 'sessionrestore', 06:11:08 INFO - 'sessionrestore_no_auto_restore', 06:11:08 INFO - 'tabpaint']}, 06:11:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:11:08 INFO - 'tests': ['a11yr', 06:11:08 INFO - 'ts_paint', 06:11:08 INFO - 'tpaint', 06:11:08 INFO - 'sessionrestore', 06:11:08 INFO - 'sessionrestore_no_auto_restore', 06:11:08 INFO - 'tabpaint']}, 06:11:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:11:08 INFO - 'tests': ['tsvgx', 06:11:08 INFO - 'tsvgr_opacity', 06:11:08 INFO - 'tart', 06:11:08 INFO - 'tscrollx', 06:11:08 INFO - 'cart', 06:11:08 INFO - 'tsvg_static']}, 06:11:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:11:08 INFO - 'tsvgr_opacity', 06:11:08 INFO - 'tart', 06:11:08 INFO - 'tscrollx', 06:11:08 INFO - 'cart', 06:11:08 INFO - 'tsvg_static']}, 06:11:08 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 06:11:08 INFO - 'talos_options': ['--disable-e10s'], 06:11:08 INFO - 'tests': ['tp5o']}, 06:11:08 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 06:11:08 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 06:11:08 INFO - 'talos_options': ['--disable-e10s', 06:11:08 INFO - '--xperf_path', 06:11:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:11:08 INFO - 'tests': ['tp5n']}, 06:11:08 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 06:11:08 INFO - 'talos_options': ['--xperf_path', 06:11:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:11:08 INFO - 'tests': ['tp5n']}}, 06:11:08 INFO - 'talos.zip': {'path': '', 06:11:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:11:08 INFO - Running post-action listener: _resource_record_post_action 06:11:08 INFO - [mozharness: 2017-01-10 14:11:08.376750Z] Finished populate-webroot step (success) 06:11:08 INFO - [mozharness: 2017-01-10 14:11:08.376855Z] Running create-virtualenv step. 06:11:08 INFO - Running pre-action listener: _resource_record_pre_action 06:11:08 INFO - Running main action method: create_virtualenv 06:11:08 INFO - Creating virtualenv /builds/slave/test/build/venv 06:11:08 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 06:11:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:11:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:11:08 INFO - Using real prefix '/tools/python27' 06:11:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:11:09 INFO - Installing distribute.............................................................................................................................................................................................done. 06:11:12 INFO - Installing pip.................done. 06:11:12 INFO - Return code: 0 06:11:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 06:11:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 06:11:12 INFO - Reading from file tmpfile_stdout 06:11:12 INFO - Output received: 06:11:12 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) 06:11:12 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:11: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')]} 06:11:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:11:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:11: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')]} 06:11:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:11:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:11: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100c2cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c2f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef094dd970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a0ad30>, '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 0x100931db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009ffbe8>, '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.P5cwJiLYdc/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.xXCg2SJib0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 06:11: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', 'pip>=1.5'] in /builds/slave/test/build 06:11: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 pip>=1.5 06:11:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P5cwJiLYdc/Render', 06:11:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:11:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:11:12 INFO - 'HOME': '/Users/cltbld', 06:11:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:11:12 INFO - 'LOGNAME': 'cltbld', 06:11:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:11:12 INFO - 'MOZ_NO_REMOTE': '1', 06:11:12 INFO - 'NO_EM_RESTART': '1', 06:11:12 INFO - 'PAGER': '/bin/cat', 06:11:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:11:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:11:12 INFO - 'PWD': '/builds/slave/test', 06:11:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:11:12 INFO - 'SHELL': '/bin/bash', 06:11:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xXCg2SJib0/Listeners', 06:11:12 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 06:11:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:11:12 INFO - 'USER': 'cltbld', 06:11:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:11:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:11:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:11:12 INFO - 'XPC_FLAGS': '0x0', 06:11:12 INFO - 'XPC_SERVICE_NAME': '0', 06:11:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:11:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:11:12 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 06:11:12 INFO - Cleaning up... 06:11:12 INFO - Return code: 0 06:11:12 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:11: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')]} 06:11:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:11:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:11: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')]} 06:11:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:11:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:11: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', '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 0x100c2cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c2f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef094dd970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a0ad30>, '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 0x100931db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009ffbe8>, '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.P5cwJiLYdc/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.xXCg2SJib0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 06:11: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', 'psutil>=3.1.1'] in /builds/slave/test/build 06:11: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 psutil>=3.1.1 06:11:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P5cwJiLYdc/Render', 06:11:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:11:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:11:12 INFO - 'HOME': '/Users/cltbld', 06:11:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:11:12 INFO - 'LOGNAME': 'cltbld', 06:11:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:11:12 INFO - 'MOZ_NO_REMOTE': '1', 06:11:12 INFO - 'NO_EM_RESTART': '1', 06:11:12 INFO - 'PAGER': '/bin/cat', 06:11:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:11:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:11:12 INFO - 'PWD': '/builds/slave/test', 06:11:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:11:12 INFO - 'SHELL': '/bin/bash', 06:11:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xXCg2SJib0/Listeners', 06:11:12 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 06:11:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:11:12 INFO - 'USER': 'cltbld', 06:11:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:11:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:11:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:11:12 INFO - 'XPC_FLAGS': '0x0', 06:11:12 INFO - 'XPC_SERVICE_NAME': '0', 06:11:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:11:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:11:12 INFO - Downloading/unpacking psutil>=3.1.1 06:11: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 06:11: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 06:11: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 06:11: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 06:11: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 06:11: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 06:11:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:11:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:11:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:11:16 INFO - Installing collected packages: psutil 06:11:16 INFO - Running setup.py install for psutil 06:11:16 INFO - building 'psutil._psutil_osx' extension 06:11:16 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 06:11:17 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 06:11:17 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 06:11:17 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 06:11:17 INFO - building 'psutil._psutil_posix' extension 06:11:17 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 06:11:17 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 06:11:17 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 06:11:17 INFO - ^ 06:11:17 INFO - 1 warning generated. 06:11:17 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 06:11:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:11:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:11:17 INFO - Successfully installed psutil 06:11:17 INFO - Cleaning up... 06:11:17 INFO - Return code: 0 06:11:17 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:11:17 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')]} 06:11:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:11:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:11:17 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')]} 06:11:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:11:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:11:17 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 0x100c2cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c2f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef094dd970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a0ad30>, '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 0x100931db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009ffbe8>, '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.P5cwJiLYdc/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.xXCg2SJib0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 06:11:17 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 06:11:17 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 06:11:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P5cwJiLYdc/Render', 06:11:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:11:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:11:17 INFO - 'HOME': '/Users/cltbld', 06:11:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:11:17 INFO - 'LOGNAME': 'cltbld', 06:11:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:11:17 INFO - 'MOZ_NO_REMOTE': '1', 06:11:17 INFO - 'NO_EM_RESTART': '1', 06:11:17 INFO - 'PAGER': '/bin/cat', 06:11:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:11:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:11:17 INFO - 'PWD': '/builds/slave/test', 06:11:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:11:17 INFO - 'SHELL': '/bin/bash', 06:11:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xXCg2SJib0/Listeners', 06:11:17 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 06:11:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:11:17 INFO - 'USER': 'cltbld', 06:11:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:11:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:11:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:11:17 INFO - 'XPC_FLAGS': '0x0', 06:11:17 INFO - 'XPC_SERVICE_NAME': '0', 06:11:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:11:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:11:17 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:11:17 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 06:11:17 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 06:11:17 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 06:11:17 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 06:11:17 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 06:11:17 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 06:11:20 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:11:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:11:20 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:11:20 INFO - Installing collected packages: mozsystemmonitor 06:11:20 INFO - Running setup.py install for mozsystemmonitor 06:11:20 INFO - Successfully installed mozsystemmonitor 06:11:20 INFO - Cleaning up... 06:11:20 INFO - Return code: 0 06:11:20 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 06:11:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:11:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:11:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:11:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:11:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:11:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:11:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100c2cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c2f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef094dd970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a0ad30>, '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 0x100931db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009ffbe8>, '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.P5cwJiLYdc/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.xXCg2SJib0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 06:11:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 06:11:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 06:11:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P5cwJiLYdc/Render', 06:11:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:11:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:11:20 INFO - 'HOME': '/Users/cltbld', 06:11:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:11:20 INFO - 'LOGNAME': 'cltbld', 06:11:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:11:20 INFO - 'MOZ_NO_REMOTE': '1', 06:11:20 INFO - 'NO_EM_RESTART': '1', 06:11:20 INFO - 'PAGER': '/bin/cat', 06:11:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:11:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:11:20 INFO - 'PWD': '/builds/slave/test', 06:11:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:11:20 INFO - 'SHELL': '/bin/bash', 06:11:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xXCg2SJib0/Listeners', 06:11:20 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 06:11:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:11:20 INFO - 'USER': 'cltbld', 06:11:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:11:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:11:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:11:20 INFO - 'XPC_FLAGS': '0x0', 06:11:20 INFO - 'XPC_SERVICE_NAME': '0', 06:11:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:11:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:11:20 INFO - Downloading/unpacking jsonschema==2.5.1 06:11: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 06:11: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 06:11: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 06:11: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 06:11: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 06:11: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 06:11:23 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:11:23 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:11:23 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 06:11:23 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 06:11:23 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 06:11:23 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 06:11:23 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 06:11:23 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 06:11:23 INFO - Downloading functools32-3.2.3-2.tar.gz 06:11:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:11:24 INFO - warning: no files found matching '*.txt' 06:11:24 INFO - no previously-included directories found matching 'build' 06:11:24 INFO - no previously-included directories found matching 'dist' 06:11:24 INFO - no previously-included directories found matching '.git*' 06:11:24 INFO - Installing collected packages: jsonschema, functools32 06:11:24 INFO - Running setup.py install for functools32 06:11:24 INFO - warning: no files found matching '*.txt' 06:11:24 INFO - no previously-included directories found matching 'build' 06:11:24 INFO - no previously-included directories found matching 'dist' 06:11:24 INFO - no previously-included directories found matching '.git*' 06:11:24 INFO - Successfully installed jsonschema functools32 06:11:24 INFO - Cleaning up... 06:11:24 INFO - Return code: 0 06:11:24 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 06:11:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:11:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:11:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:11:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:11:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:11:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:11:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x100c2cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c2f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef094dd970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a0ad30>, '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 0x100931db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009ffbe8>, '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.P5cwJiLYdc/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.xXCg2SJib0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 06:11:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 06:11:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 06:11:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P5cwJiLYdc/Render', 06:11:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:11:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:11:24 INFO - 'HOME': '/Users/cltbld', 06:11:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:11:24 INFO - 'LOGNAME': 'cltbld', 06:11:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:11:24 INFO - 'MOZ_NO_REMOTE': '1', 06:11:24 INFO - 'NO_EM_RESTART': '1', 06:11:24 INFO - 'PAGER': '/bin/cat', 06:11:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:11:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:11:24 INFO - 'PWD': '/builds/slave/test', 06:11:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:11:24 INFO - 'SHELL': '/bin/bash', 06:11:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xXCg2SJib0/Listeners', 06:11:24 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 06:11:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:11:24 INFO - 'USER': 'cltbld', 06:11:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:11:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:11:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:11:24 INFO - 'XPC_FLAGS': '0x0', 06:11:24 INFO - 'XPC_SERVICE_NAME': '0', 06:11:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:11:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:11:24 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 06:11:24 INFO - Cleaning up... 06:11:24 INFO - Return code: 0 06:11:24 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:11:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:11:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:11:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:11:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:11:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:11:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:11:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x100c2cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c2f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef094dd970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a0ad30>, '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 0x100931db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009ffbe8>, '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.P5cwJiLYdc/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.xXCg2SJib0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 06:11:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 06:11:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 06:11:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P5cwJiLYdc/Render', 06:11:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:11:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:11:24 INFO - 'HOME': '/Users/cltbld', 06:11:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:11:24 INFO - 'LOGNAME': 'cltbld', 06:11:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:11:24 INFO - 'MOZ_NO_REMOTE': '1', 06:11:24 INFO - 'NO_EM_RESTART': '1', 06:11:24 INFO - 'PAGER': '/bin/cat', 06:11:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:11:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:11:24 INFO - 'PWD': '/builds/slave/test', 06:11:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:11:24 INFO - 'SHELL': '/bin/bash', 06:11:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xXCg2SJib0/Listeners', 06:11:24 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 06:11:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:11:24 INFO - 'USER': 'cltbld', 06:11:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:11:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:11:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:11:24 INFO - 'XPC_FLAGS': '0x0', 06:11:24 INFO - 'XPC_SERVICE_NAME': '0', 06:11:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:11:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:11:24 INFO - Downloading/unpacking blobuploader==1.2.4 06:11:24 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 06:11:24 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 06:11:24 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 06:11:24 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 06:11:24 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 06:11:24 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 06:11:27 INFO - Downloading blobuploader-1.2.4.tar.gz 06:11:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:11:27 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:11:27 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 06:11:27 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 06:11:27 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 06:11:27 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 06:11:27 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 06:11:27 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 06:11:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:11:28 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:11: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 06:11: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 06:11: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 06:11: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 06:11: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 06:11: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 06:11:28 INFO - Downloading docopt-0.6.1.tar.gz 06:11:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:11:28 INFO - Installing collected packages: blobuploader, requests, docopt 06:11:28 INFO - Running setup.py install for blobuploader 06:11:29 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:11:29 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:11:29 INFO - Running setup.py install for requests 06:11:29 INFO - Running setup.py install for docopt 06:11:29 INFO - Successfully installed blobuploader requests docopt 06:11:29 INFO - Cleaning up... 06:11:29 INFO - Return code: 0 06:11:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:11:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:11:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:11:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:11:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:11:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:11:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:11:29 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 0x100c2cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c2f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef094dd970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a0ad30>, '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 0x100931db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009ffbe8>, '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.P5cwJiLYdc/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.xXCg2SJib0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 06:11:29 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 06:11:29 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 06:11:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P5cwJiLYdc/Render', 06:11:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:11:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:11:29 INFO - 'HOME': '/Users/cltbld', 06:11:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:11:29 INFO - 'LOGNAME': 'cltbld', 06:11:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:11:29 INFO - 'MOZ_NO_REMOTE': '1', 06:11:29 INFO - 'NO_EM_RESTART': '1', 06:11:29 INFO - 'PAGER': '/bin/cat', 06:11:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:11:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:11:29 INFO - 'PWD': '/builds/slave/test', 06:11:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:11:29 INFO - 'SHELL': '/bin/bash', 06:11:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xXCg2SJib0/Listeners', 06:11:29 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 06:11:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:11:29 INFO - 'USER': 'cltbld', 06:11:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:11:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:11:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:11:29 INFO - 'XPC_FLAGS': '0x0', 06:11:29 INFO - 'XPC_SERVICE_NAME': '0', 06:11:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:11:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:11:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:11:30 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-W0fp8g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:11:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:11:30 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-wUihpg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:11:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:11:30 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-YNhbRj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:11:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:11:30 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-nT5jwI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:11:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:11:30 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-xxKlMt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:11:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:11:30 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-a6XQXD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:11:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:11:30 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-JCXVsh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:11:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:11:30 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-Zz4ej9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:11:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:11:30 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-5I16zG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:11:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:11:30 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-UacJB_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:11:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:11:31 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-UjAIHL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:11:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:11:31 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-U7aLms-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:11:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:11:31 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-9mUjdY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:11:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:11:31 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-in4YHJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:11:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:11:31 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-iDpvfT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:11:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:11:31 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-_JlTY7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:11:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:11:31 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-TRpent-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:11:31 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:11:31 INFO - Running setup.py install for manifestparser 06:11:31 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:11:31 INFO - Running setup.py install for mozcrash 06:11:32 INFO - Running setup.py install for mozdebug 06:11:32 INFO - Running setup.py install for mozdevice 06:11:32 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:11:32 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:11:32 INFO - Running setup.py install for mozfile 06:11:32 INFO - Running setup.py install for mozhttpd 06:11:32 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:11:32 INFO - Running setup.py install for mozinfo 06:11:32 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:11:32 INFO - Running setup.py install for mozInstall 06:11:32 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:11:32 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:11:32 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:11:32 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:11:32 INFO - Running setup.py install for mozleak 06:11:33 INFO - Running setup.py install for mozlog 06:11:33 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:11:33 INFO - Running setup.py install for moznetwork 06:11:33 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:11:33 INFO - Running setup.py install for mozprocess 06:11:33 INFO - Running setup.py install for mozprofile 06:11:33 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:11:33 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:11:33 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:11:33 INFO - Running setup.py install for mozrunner 06:11:33 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:11:33 INFO - Running setup.py install for mozscreenshot 06:11:34 INFO - Running setup.py install for moztest 06:11:34 INFO - Running setup.py install for mozversion 06:11:34 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:11:34 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:11:34 INFO - Cleaning up... 06:11:34 INFO - Return code: 0 06:11:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:11:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:11:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:11:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:11:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:11:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:11:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:11:35 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 0x100c2cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c2f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef094dd970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a0ad30>, '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 0x100931db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009ffbe8>, '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.P5cwJiLYdc/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.xXCg2SJib0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 06:11:35 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 06:11:35 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 06:11:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P5cwJiLYdc/Render', 06:11:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:11:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:11:35 INFO - 'HOME': '/Users/cltbld', 06:11:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:11:35 INFO - 'LOGNAME': 'cltbld', 06:11:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:11:35 INFO - 'MOZ_NO_REMOTE': '1', 06:11:35 INFO - 'NO_EM_RESTART': '1', 06:11:35 INFO - 'PAGER': '/bin/cat', 06:11:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:11:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:11:35 INFO - 'PWD': '/builds/slave/test', 06:11:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:11:35 INFO - 'SHELL': '/bin/bash', 06:11:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xXCg2SJib0/Listeners', 06:11:35 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 06:11:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:11:35 INFO - 'USER': 'cltbld', 06:11:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:11:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:11:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:11:35 INFO - 'XPC_FLAGS': '0x0', 06:11:35 INFO - 'XPC_SERVICE_NAME': '0', 06:11:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:11:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:11:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:11:35 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-Vmim9f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:11:35 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)) 06:11:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:11:35 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-ybjZ4H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:11:35 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)) 06:11:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:11:35 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-0ZyeaG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:11:35 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)) 06:11:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:11:35 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-GlHooH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:11:35 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)) 06:11:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:11:35 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-ZM5GqX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:11:36 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)) 06:11:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:11:36 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-tUJHio-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:11:36 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)) 06:11:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:11:36 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-0QfER8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:11:36 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)) 06:11:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:11:36 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-pUxQM8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:11:36 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)) 06:11:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:11:36 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-agDIeB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:11:36 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)) 06:11:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:11:36 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-OmWPQl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:11:36 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)) 06:11:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:11:36 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-2TwkDr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:11:36 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)) 06:11:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:11:36 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-bjxMLL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:11:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 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)) 06:11:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:11:36 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-V63b9z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:11:36 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)) 06:11:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:11:36 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-quf7BH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:11:36 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)) 06:11:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:11:36 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-tk10hC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:11:37 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)) 06:11:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:11:37 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-cx9ATV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:11:37 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 06:11:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:11:37 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-faAysd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:11:37 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)) 06:11:37 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)) 06:11:37 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)) 06:11:37 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)) 06:11:37 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)) 06:11:37 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)) 06:11:37 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:11:37 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 06:11:37 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 06:11:37 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 06:11:37 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 06:11:37 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 06:11:37 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 06:11:40 INFO - Downloading blessings-1.6.tar.gz 06:11:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:11:40 INFO - Installing collected packages: blessings 06:11:40 INFO - Running setup.py install for blessings 06:11:40 INFO - Successfully installed blessings 06:11:40 INFO - Cleaning up... 06:11:40 INFO - Return code: 0 06:11:40 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:11:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:11:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:11:40 INFO - Reading from file tmpfile_stdout 06:11:40 INFO - Current package versions: 06:11:40 INFO - blessings == 1.6 06:11:40 INFO - blobuploader == 1.2.4 06:11:40 INFO - docopt == 0.6.1 06:11:40 INFO - functools32 == 3.2.3-2 06:11:40 INFO - jsonschema == 2.5.1 06:11:40 INFO - manifestparser == 1.1 06:11:40 INFO - mozInstall == 1.12 06:11:40 INFO - mozcrash == 1.0 06:11:40 INFO - mozdebug == 0.1 06:11:40 INFO - mozdevice == 0.49 06:11:40 INFO - mozfile == 1.2 06:11:40 INFO - mozhttpd == 0.7 06:11:40 INFO - mozinfo == 0.9 06:11:40 INFO - mozleak == 0.1 06:11:40 INFO - mozlog == 3.4 06:11:40 INFO - moznetwork == 0.27 06:11:40 INFO - mozprocess == 0.24 06:11:40 INFO - mozprofile == 0.28 06:11:40 INFO - mozrunner == 6.13 06:11:40 INFO - mozscreenshot == 0.1 06:11:40 INFO - mozsystemmonitor == 0.3 06:11:40 INFO - moztest == 0.8 06:11:40 INFO - mozversion == 1.4 06:11:40 INFO - psutil == 3.1.1 06:11:40 INFO - requests == 1.2.3 06:11:40 INFO - wsgiref == 0.1.2 06:11:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:11:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:11:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:11:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:11:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:11:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:11:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:11:40 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 0x100c2cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c2f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef094dd970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a0ad30>, '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 0x100931db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009ffbe8>, '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.P5cwJiLYdc/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.xXCg2SJib0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 06:11:41 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 06:11:41 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 06:11:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P5cwJiLYdc/Render', 06:11:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:11:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:11:41 INFO - 'HOME': '/Users/cltbld', 06:11:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:11:41 INFO - 'LOGNAME': 'cltbld', 06:11:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:11:41 INFO - 'MOZ_NO_REMOTE': '1', 06:11:41 INFO - 'NO_EM_RESTART': '1', 06:11:41 INFO - 'PAGER': '/bin/cat', 06:11:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:11:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:11:41 INFO - 'PWD': '/builds/slave/test', 06:11:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:11:41 INFO - 'SHELL': '/bin/bash', 06:11:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xXCg2SJib0/Listeners', 06:11:41 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 06:11:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:11:41 INFO - 'USER': 'cltbld', 06:11:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:11:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:11:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:11:41 INFO - 'XPC_FLAGS': '0x0', 06:11:41 INFO - 'XPC_SERVICE_NAME': '0', 06:11:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:11:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:11:41 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)) 06:11:41 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)) 06:11:41 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)) 06:11:41 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)) 06:11:41 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)) 06:11:41 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)) 06:11:41 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)) 06:11:41 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)) 06:11:41 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)) 06:11:41 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)) 06:11:41 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)) 06:11:41 INFO - Cleaning up... 06:11:41 INFO - Return code: 0 06:11:41 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:11:41 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')]} 06:11:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:11:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:11:41 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')]} 06:11:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:11:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:11:41 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 0x100c2cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c2f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef094dd970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a0ad30>, '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 0x100931db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009ffbe8>, '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.P5cwJiLYdc/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.xXCg2SJib0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 06:11:41 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 06:11:41 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 06:11:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P5cwJiLYdc/Render', 06:11:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:11:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:11:41 INFO - 'HOME': '/Users/cltbld', 06:11:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:11:41 INFO - 'LOGNAME': 'cltbld', 06:11:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:11:41 INFO - 'MOZ_NO_REMOTE': '1', 06:11:41 INFO - 'NO_EM_RESTART': '1', 06:11:41 INFO - 'PAGER': '/bin/cat', 06:11:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:11:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:11:41 INFO - 'PWD': '/builds/slave/test', 06:11:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:11:41 INFO - 'SHELL': '/bin/bash', 06:11:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xXCg2SJib0/Listeners', 06:11:41 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 06:11:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:11:41 INFO - 'USER': 'cltbld', 06:11:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:11:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:11:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:11:41 INFO - 'XPC_FLAGS': '0x0', 06:11:41 INFO - 'XPC_SERVICE_NAME': '0', 06:11:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:11:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:11:41 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:11:41 INFO - Cleaning up... 06:11:41 INFO - Return code: 0 06:11:41 INFO - Running post-action listener: _resource_record_post_action 06:11:41 INFO - Running post-action listener: _start_resource_monitoring 06:11:41 INFO - Starting resource monitoring. 06:11:41 INFO - [mozharness: 2017-01-10 14:11:41.563030Z] Finished create-virtualenv step (success) 06:11:41 INFO - [mozharness: 2017-01-10 14:11:41.563963Z] Running install step. 06:11:41 INFO - Running pre-action listener: _resource_record_pre_action 06:11:41 INFO - Running main action method: install 06:11:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:11:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:11:41 INFO - Reading from file tmpfile_stdout 06:11:41 INFO - Detecting whether we're running mozinstall >=1.0... 06:11:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:11:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:11:41 INFO - Reading from file tmpfile_stdout 06:11:41 INFO - Output received: 06:11:41 INFO - Usage: mozinstall [options] installer 06:11:41 INFO - Options: 06:11:41 INFO - -h, --help show this help message and exit 06:11:41 INFO - -d DEST, --destination=DEST 06:11:41 INFO - Directory to install application into. [default: 06:11:41 INFO - "/builds/slave/test"] 06:11:41 INFO - --app=APP Application being installed. [default: firefox] 06:11:41 INFO - mkdir: /builds/slave/test/build/application 06:11:41 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'] 06:11:41 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 06:12:04 INFO - Reading from file tmpfile_stdout 06:12:04 INFO - Output received: 06:12:04 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 06:12:04 INFO - Running post-action listener: _resource_record_post_action 06:12:04 INFO - [mozharness: 2017-01-10 14:12:04.304481Z] Finished install step (success) 06:12:04 INFO - [mozharness: 2017-01-10 14:12:04.304656Z] Running run-tests step. 06:12:04 INFO - Running pre-action listener: _resource_record_pre_action 06:12:04 INFO - Running main action method: run_tests 06:12:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:12:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:12:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:12:04 INFO - 2017-01-10 06:12:04.318 screenresolution[1627:7402] starting screenresolution argv=screenresolution get 06:12:04 INFO - 2017-01-10 06:12:04.321 screenresolution[1627:7402] Display 0: 1600x1200x32@60 06:12:04 INFO - 2017-01-10 06:12:04.327 screenresolution[1628:7405] starting screenresolution argv=screenresolution list 06:12:04 INFO - Available Modes on Display 0 06:12:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:12:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:12:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:12:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:12:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:12:04 INFO - Intel Iris: 06:12:04 INFO - Chipset Model: Intel Iris 06:12:04 INFO - Type: GPU 06:12:04 INFO - Bus: Built-In 06:12:04 INFO - VRAM (Dynamic, Max): 1536 MB 06:12:04 INFO - Vendor: Intel (0x8086) 06:12:04 INFO - Device ID: 0x0a2e 06:12:04 INFO - Revision ID: 0x0009 06:12:04 INFO - Displays: 06:12:04 INFO - Display: 06:12:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:12:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:12:04 INFO - Display Serial Number: 1600x1200 60 06:12:04 INFO - Main Display: Yes 06:12:04 INFO - Mirror: Off 06:12:04 INFO - Online: Yes 06:12:04 INFO - Rotation: Supported 06:12:04 INFO - Return code: 0 06:12:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:12:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:12:04 INFO - Python 2.7.3 06:12:04 INFO - Return code: 0 06:12:04 INFO - grabbing minidump binary from tooltool 06:12: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')]} 06:12:04 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 0x100a0ad30>, '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 0x100931db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009ffbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:12:04 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 06:12:04 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 06:12:04 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 06:12:04 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:12:04 INFO - Return code: 0 06:12:04 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 06:12:04 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:12:04 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:12:04 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 06:12:04 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:12:04 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/AwHs_ZPsR2CI3R0FAaOaLA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0318', '--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 06:12:04 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/AwHs_ZPsR2CI3R0FAaOaLA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0318 --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 06:12:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P5cwJiLYdc/Render', 06:12:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:12:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:12:04 INFO - 'HOME': '/Users/cltbld', 06:12:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:12:04 INFO - 'LOGNAME': 'cltbld', 06:12:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:12:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 06:12:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:12:04 INFO - 'MOZ_NO_REMOTE': '1', 06:12:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:12:04 INFO - 'NO_EM_RESTART': '1', 06:12:04 INFO - 'PAGER': '/bin/cat', 06:12:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:12:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:12:04 INFO - 'PWD': '/builds/slave/test', 06:12:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:12:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:12:04 INFO - 'SHELL': '/bin/bash', 06:12:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xXCg2SJib0/Listeners', 06:12:04 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 06:12:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:12:04 INFO - 'USER': 'cltbld', 06:12:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:12:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:12:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:12:04 INFO - 'XPC_FLAGS': '0x0', 06:12:04 INFO - 'XPC_SERVICE_NAME': '0', 06:12:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:12:04 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/AwHs_ZPsR2CI3R0FAaOaLA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0318', '--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 06:12:04 INFO - mozversion application_buildid: 20170110052108 06:12:04 INFO - mozversion application_changeset: a4d644d5881f79bcd80b1f8d1b355b958dc4ec1d 06:12:04 INFO - mozversion application_display_name: Nightly 06:12:04 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:12:04 INFO - mozversion application_name: Firefox 06:12:04 INFO - mozversion application_remotingname: firefox 06:12:04 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 06:12:04 INFO - mozversion application_vendor: Mozilla 06:12:04 INFO - mozversion application_version: 53.0a1 06:12:04 INFO - mozversion platform_buildid: 20170110052108 06:12:04 INFO - mozversion platform_changeset: a4d644d5881f79bcd80b1f8d1b355b958dc4ec1d 06:12:04 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 06:12:04 INFO - mozversion platform_version: 53.0a1 06:12:04 INFO - using testdate: 1484057524 06:12:04 INFO - actual date: 1484057524 06:12:04 INFO - starting webserver on 'localhost:49241' 06:12:04 INFO - SUITE-START | Running 2 tests 06:12:04 INFO - TEST-START | dromaeo_css 06:12:04 INFO - operating with platform_type : mac_ 06:12:04 INFO - Initialising browser for dromaeo_css test... 06:12:04 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/tmpKyFTx1/profile http://localhost:49241/getInfo.html) 06:12:05 INFO - PROCESS | 1638 | 1484057525550 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:4906 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2060 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3761 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2794 < callProvider()@resource://gre/modules/AddonManager.jsm:267 < _startProvider()@resource://gre/modules/AddonManager.jsm:973 < startup()@resource://gre/modules/AddonManager.jsm:1155 < startup()@resource://gre/modules/AddonManager.jsm:3124 < observe()@resource://gre/components/addonManager.js:65 06:12:07 INFO - PROCESS | 1638 | __metrics Screen width/height:1600/1200 06:12:07 INFO - PROCESS | 1638 | colorDepth:24 06:12:07 INFO - PROCESS | 1638 | Browser inner width/height: 1024/689 06:12:07 INFO - PROCESS | 1638 | __metrics 06:12:07 INFO - PROCESS | 1638 | 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" 06:12:07 INFO - PROCESS | 1638 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:12:08 INFO - TEST-INFO | 1638: exit 0 06:12:08 INFO - Browser initialized. 06:12:08 INFO - Running cycle 1/1 for dromaeo_css test... 06:12:08 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/tmpKyFTx1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:12:20 INFO - PROCESS | 1640 | RSS: Main: 234622976 06:12:20 INFO - PROCESS | 1640 | 06:15:25 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 06:15:27 INFO - PROCESS | 1640 | RSS: Main: 358404096 06:15:27 INFO - PROCESS | 1640 | 06:17:09 INFO - PROCESS | 1640 | ************************* 06:17:09 INFO - PROCESS | 1640 | A coding exception was thrown and uncaught in a Task. 06:17:09 INFO - PROCESS | 1640 | 06:17:09 INFO - PROCESS | 1640 | Full message: ReferenceError: fetch is not defined 06:17:09 INFO - PROCESS | 1640 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 06:17:09 INFO - PROCESS | 1640 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 06:17:09 INFO - PROCESS | 1640 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 06:17:09 INFO - PROCESS | 1640 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 06:17:09 INFO - PROCESS | 1640 | TaskImpl@resource://gre/modules/Task.jsm:277:3 06:17:09 INFO - PROCESS | 1640 | asyncFunction@resource://gre/modules/Task.jsm:252:14 06:17:09 INFO - PROCESS | 1640 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 06:17:09 INFO - PROCESS | 1640 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 06:17:09 INFO - PROCESS | 1640 | TaskImpl@resource://gre/modules/Task.jsm:277:3 06:17:09 INFO - PROCESS | 1640 | asyncFunction@resource://gre/modules/Task.jsm:252:14 06:17:09 INFO - PROCESS | 1640 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 06:17:09 INFO - PROCESS | 1640 | 06:17:09 INFO - PROCESS | 1640 | ************************* 06:18:30 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 06:18:30 INFO - PROCESS | 1640 | RSS: Main: 368013312 06:18:30 INFO - PROCESS | 1640 | 06:21:33 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 06:21:33 INFO - PROCESS | 1640 | RSS: Main: 359727104 06:21:33 INFO - PROCESS | 1640 | 06:24:36 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 06:24:36 INFO - PROCESS | 1640 | RSS: Main: 352714752 06:24:36 INFO - PROCESS | 1640 | 06:27:40 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 06:27:40 INFO - PROCESS | 1640 | RSS: Main: 332017664 06:27:40 INFO - PROCESS | 1640 | 06:30:44 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 06:30:44 INFO - PROCESS | 1640 | RSS: Main: 389713920 06:30:44 INFO - PROCESS | 1640 | 06:30:44 INFO - PROCESS | 1640 | __start_tp_report 06:30:44 INFO - PROCESS | 1640 | _x_x_mozilla_page_load 06:30:44 INFO - PROCESS | 1640 | _x_x_mozilla_page_load_details 06:30:44 INFO - PROCESS | 1640 | |i|pagename|runs| 06:30:44 INFO - PROCESS | 1640 | |0;dojo.html;3290.9341813163737;3315.1214928044064;3327.584051993501;3341.3150304187643;3344.84948177332;4229.492460904692;5431.859309544995;5582.051051321275;5630.127330263806;5650.8022219222285;5600.131979543167;5630.380658127613;5632.887342253152;5658.122990936401;5659.236003139575;4179.414881916527;5132.692038477703;5150.356205474316;5190.351206099238;5233.345831771028;4262.8721138365745;5222.3472065991755;5224;5227.973860130694;5240.580753539727;5215.504527069941;5225.399079105894;5225.529702326789;5230.529252367286;5231.450697676746;5211.192265198882;5215.947840521584;5220.2430647556275;5224.817131400421;5238.345206849144;5006.774695138727;5169.689818610896;5172.689638621695;5178.741062946838;5197.532222100029;5707.600467967244;5773.24947756789;5811.186433899257;5874.647521148745;5884.705764711747;6101.847453813646;6182.814515564541;6226.875462490725;6227.470664993481;6233.2208473940755;5026.29631851541;6097.524678803529;6166.445019948182;6181.690915454209;6204.100405441231;5718.0851063830005;5797.652140871562;5850.64896106235;5871.853203669857;5876.08920617305;6251.53113516479;6251.937480625226;6271.529635277281;6276.497880169667;6281.685915704196;6033.7586496540525;6167.599106058182;6168.167297414791;6185.969070154711;6199.132121502993;6145.84635384119;6150.661713605705;6174.012158054689;6204.968975155095;6216.658083805434;6195.628262304276;6202.162708034448;6206.658633775186;6209.96895015522;6212.9068063979075;6232.875342493125;6235.376462353728;6236.937630623635;6237.376262373727;6254;4875.780589873464;4968.6025117990475;4977.800887964441;4985.626078044161;5020.472850350734;4286.292761694285;4398.890027749268;4402.493713222956;4409.536998615164;4446.733196008186;5803.65178089316;5820.1560773688425;5828.912566311508;5834.978878696211;5858.882822343615;6212.937870621236;6224.906626400608;6240.438360547572;6240.469560087353;6304.968475157595;5834.474897259182;5835.124731290341;5835.708214589254;5842.503387212135;5873.148393482921;5323.334583177103;5375.19372094189;5392.541633961078;5393.110136827458;5402.459754024566;6215.627062376272;6218.906716399167;6227.0970709246;6236.064590311489;6247.281562620356;5261.973690131525;6188.81433556976;6228.968855155696;6263.530235232284;6284.937150628435;6267.059941008885;6285.779997700059;6292.590981586182;6298.527610429144;6300.180976473029;6193.163922870355;6237.594556353823;6243.687815609293;6245.500359971101;6255.781047663492;6135.814547850547;6167.475764560063;6210.596311239755;6280.685965701788;6285.308616052139;4991.50084991498;6129.786714140983;6141.631502109888;6151.477124444473;6168.722407491584;5981.850453738692;6063.696815159313;6135.601185922901;6137.478314343152;6171.84570385744;5809.622374545641;5846.795362162304;5969.313528944227;5980.073088671263;5992.191054207626;5323.228131920927;6049.606775559574;6076.149339092619;6128.540359472968;6155.045967874987;5874.148248503858;5994.31065427464;5994.850128746816;5999.640021598718;6016.909746353721 06:30:44 INFO - PROCESS | 1640 | |1;ext.html;491.33914884480373;501.48347202381535;505.5828941123574;509.81901424994123;571.6794205385562;39836.40245396318;42277;42296.94257643557;42359.788201059026;42420.575794242046;43079.78460107703;43456.91357716059;43653.56346436535;43654;43730.562694373046;8518.57407129641;9422.481763503021;9620.71137865865;9641.276904232176;9643.710688679352;8590.153117079826;11297.604583839568;11554.422278886064;11561.24851884629;11755.17713760037;11552.018078463341;11618.302901825917;11638.825417618742;11657.70855728605;11670.474828632732;8600.956995215047;11577.594784182555;11634.069274458023;11670.649880503555;11701.063914886789;8653;11189.272697274651;11452.599159029394;11457;11625;40234;40645.39031914494;40664.593354066375;41045.384319235236;41487.585124148674;4041.312976793952;4060.918781624351;4067.2882245607198;4095.3037983542868;4213.9578604213875;30102;30616.775328986812;30920.536191957137;31200.063998080095;31205.68794312073;3172.2069482629345;3205.6634053424523;3265;3349.2631621043333;3356.060303115132;469908;473048;473790.63104684255;474204;476262;59499;59704.70147649321;59861.401385985584;59896;59964;64409.35590644128;64475;65409.34590654032;66189.38822035528;66343;16172.919135404247;16434.83565164357;16436.589085272724;16437.83562164387;16460.753088703677;11458.828117578243;11632.476538555786;12634.305113218861;12717.491300348067;12725.809112863315;15087.868409869303;16432.83567164337;18187.45437636873;18215.544611384823;18384.81615183831;7388.371988381048;7588.089429268523;7638.427117966175;7677.769666910001;7690.15408305086;871.4945331707594;891.1845661219911;910.9043550427242;916.6425094213262;926.5413620258015;29726.76286849098;30007.699923000928;30160.24599385033;30330.393392132035;30564.847175763978;1069.2996087562658;1073.43278812934;1112.477135746198;1117.3351855645878;1210.8002179640437;13961.79057314141;15681.294341754648;15710.764338534931;15734.606634834221;16198.67602647964;603154;604955;607250;608382;608429;61349.693251533456;61600.383996159464;61675.38324616786;61751.69124154351;61889.76220475565;1427.5503216486773;1460.4742292774592;1477.2392218007735;1478.031889112633;1480.7704805755125;27766.5835012479;29139.271518212216;29220.26949326284;29951.550726738682;30326;51837.92648294026;52396.95206095933;52421.951560969326;52498.73750631375;52639.73680131575;430244;431238;431582;432651;433046;40538.79730601328;41290.38064428976;41413.75758727243;41441.58558414377;41524;45798.54201458076;45918;46273;46437;46623.30065048959;38228;38763.23447928086;39684.80945571637;39793.40309895412;39824.80087599543;38669.806650966566;38687.41968870525;39413.80293098516;39961.019490254876;40139.79930100331;34459.79390721313;35286;36378.63621363859;37224.44163337606;37648.81175594214;31217;32452.513212302307;32511.349773003934;32608.02175934726;32661.34677306488;23852.642210367205;23861.64207536923;24252.75747242505;24427.267181984567;24485.632715508924 06:30:44 INFO - PROCESS | 1640 | |2;jquery.html;365.22936603766067;382.7225261685277;387.6259409669669;392.3819983525946;414.56263641857845;15276.074533421384;15331.770023449657;17974.101294935266;20822.47943801402;21643.891780541115;21600.567988640218;22056.558868822613;22278.55442891141;22352.441188970282;22611.660825087638;5745.425457454263;5827.688770026732;5909.468147866691;5976.3724808895095;6007.8197654070445;4177.122804211104;5803.100519419476;5993.160957465958;6009.308929473101;6022.277326720778;4343.413639158704;4387.407699960512;5080.034793389263;6642.136522252124;6753.797386078426;6511.088447617338;6571;6651.76718814844;6659.700313485905;6745.898811517782;5812.680302583356;5903.645781253095;5906.055031195031;5914.083317085816;5915.852103697399;27288.2703777336;27892.721072789216;28003.85998070017;28193.73128209215;28421.85789071062;2756;2785.331520435081;2786.5680819473014;2792.427552351763;2806.1300996691093;14843.777343339858;15183.392664293415;15832.445864394806;15835;16343.019418834789;1785.5536115971008;1807.286121981823;1828.5611453251124;1834.9816501834944;1837.751903493038;107345;125655.37172314171;131218;140742.29628851893;151778.48221517864;9415.482148481762;11438.94280528592;11472.827907581393;11633.418329083512;11769.823452648217;15083.622909427351;15091.471798487;15103.4713785017;15104.471343502924;15131.54605361841;8692.174243446863;8942.68700595476;8985.910140898639;9110.59002344896;9125.269978401711;7831.060272767304;8248;8913.28693704502;8920.955395222982;9102.362834601647;7293.598852063082;7469.663865126083;7470.589117598584;7481.364084052916;7494.587797671072;1881.1346780480858;1942.300771722179;1943.5141214696325;1945.0760888578095;1950.2394066314148;940.7083804020775;989.8366769482955;995.9950200248952;1012.367270455965;1026.7381817636604;17489.562760930825;21556.353309400743;21646;21718.565628687655;21774.891125544273;1004.8442491413842;1008.9243306751877;1041.3231399590175;1041.4272150317295;1043.0925095167102;10518.947405262925;11264.831027534594;11322.94338528302;11344.262622929484;11424.314541127558;532832;536269;538660;715993;728987;150873;157415.42584574237;164175;164495;169211;949.4777872170417;1003.9999999999853;1014.8477728340808;1072.9356238625708;1077.9974623599971;19284.614307714048;20475;20691.379258622266;20694.275700350416;20710;59112.70443647754;60314.69842650759;61143;61505;62492.68753656203;92136;92785.60821587816;92832;94698;95030;8304.87542686848;8429.957850210709;8557.657693692183;8608.48349099056;8766.0357360689;13057.347132643139;13144;13162.40769165409;13418.530351437652;13657.590272291847;11395.943020284845;11456.599019034295;11616.709582260512;11753.823692644446;11823.349715765715;9295.628174872929;10396.896031039592;11428.542858285575;11540.24988375753;11580.36308003068;11465.656030319104;11811;11869.287842729464;11884.405779711153;12119.333436661069;8225.095239523767;8348.791280218044;8461.661533538836;8665.870011949699;8720.694775682821;10575.471226438802;10962;11049.613263535737;11203.71990700239;11234.662960110883 06:30:44 INFO - PROCESS | 1640 | |3;mootools.html;1427.3576890399322;1445.5085271007856;1453.2951518513519;1469.5664778890227;1473.0130812355724;7858.528488290699;8251.87622185667;8266.338692904568;8280.62737176827;8320.667173313068;7264.927350726492;7546.094468663754;7885.3297469715;8122.878156827637;8135.389845761562;1884.3028079610563;1991.9343151413996;2124.6600543912978;2161.2651698422533;2202.5484775620957;2324.4421338878633;2340.4031971847157;2356.304890057437;2356.469794296278;2360;2307.8615283082986;2309.4803669174385;2329.6389059695775;2332.2536788227776;2337.5208082343165;2269.171752310403;2272.874991875446;2296.334063121693;2299.5515874404414;2301.7813307735737;2292.5644127615865;2311.4221444638843;2333.1134169015836;2334.4280651240556;2349.847259928099;8195.221453961864;8261.215184557457;8274.462159959583;8278.420510564263;8287.8342433151;4940.25896115582;5083.161278389061;5096.694198348074;5098.388193416777;5099.107656160157;3236.3365510070616;3322;3522.2427178156563;3523.6828685418177;3535.9292814143573;1196.814493753461;1213.8179273109015;1230.8953738932112;1243.5958313548133;1245.5453759377851;10328.741781455448;10461.372317660966;10470.528826202763;10485.266031377807;10491.160707143486;4304.225239456865;4504.9099018020115;4605.171069207508;4607.884802879955;4630;9172.724818255463;9172.816543669089;9174.86237706435;9202.953985230031;9205;5031.647784655039;5122.974385128051;5128.564072053917;5138.537531622172;5138.8972220556125;3861.6331448512346;4190.266703326905;4697.389339385858;4697.459792123881;4710.740909250024;3227.644959054502;3275.279438523521;3740.625937406292;3826.1582451860545;3865.536135663738;1258.930758808256;1268.492602958824;1268.8921441677376;1278.9040821938393;1280.9487620495076;778.2334400615389;780.8945792317965;781.7303030454484;792.4650860669026;806.6128258435983;5341.839744807662;5414.160805075219;5452.345718513804;5529.668219906738;5621.8594535136945;985.2807450560987;1002.684154491333;1014.7666036811574;1019.9592016319413;1061.0079575596842;3570.1967057412394;3602.945955810665;3631.654992775682;3635.8727444539313;3670.4494325851338;9532.951375348706;9592.95203523992;9698.030196980386;9834.950825245971;9866.062724041205;9121.771955701028;9592.136707696342;9694.272929530314;9752.366096203725;9792.16766574835;7549;7739.148693643695;7742;7745.031871016123;7751.961240193763;5172.793088276427;5389.59578031657;5556.305461817273;5709.942900570941;5847.502962248086;8763.255123314335;9097.40866843653;9104.271658267191;9190.586423611087;9195.77010574741;6792.830179245559;8984.910150898408;9168.587413566273;9222.631094756134;9235.815283694154;6053.273607167168;6506.3818937200895;6573.57271777333;6949.818530849869;7065.046218760402;7827.25641064098;7836.568988705676;7845.6077196141105;7848.529088254723;7853.489523180975;6835.701216768798;6929.514933954575;6930.653467326512;6982.79051628452;7050.541714788523;6581.934180658323;6812.897806533005;6854.280300568368;6890.896636550356;6914.308569143045;6161.229846269216;6213.720382582885;6297.685115744286;6312.747490100344;6322.114903913363;4864.708117512961;4886.511348865085;4904.926426103682;4947.678400903929;5024.924626130538;4818.975905120452;4826.565609095198;4927.728974906271;4971.055499455092;4985.501449854985 06:30:44 INFO - PROCESS | 1640 | |4;prototype.html;306.19165403335194;351.7133536168023;363.22995250069846;370.80532720321844;372.87135938101346;4585.908281834361;4882.340883980666;5397.757100930457;5423.83728488145;5453.836384908454;4726.716397016173;5067;5174.689518628885;5178.896422071566;5252.135491900378;3688.9670892150107;3855.8650447234354;3858.3633700439395;3955.307821131305;4207.347861081537;4166.541680415152;4181.3309870420735;4202.432671589342;4238.682098842583;4264.123785534355;4021.959780402188;4129.2360913231;4175.958240417603;4182.519010313822;4197.614787120258;3644.2529281497496;4121.867380790591;4146.889832625546;4157.355609880443;4187.853425130121;3500.2474467989505;3628.8367023483743;3653.780773153619;3661.029827095822;3679.9264014719824;4163.001759155592;5113.386393632751;5215.061288967984;5241.9213711794355;5302.329766123658;3668.5149108347177;4552.977235113836;4566.1917920479;4787.677976181549;4813.374261346003;5612.719364031823;5649.293838270216;5657.886842263131;5660.377358490563;5681.147827825859;3416.855353456595;3853.364194907837;4024.678025757932;4048.696347773899;4060.939085913714;4907.852764417074;5378.91931621026;5551.777928882881;5642.605017648766;5900;3789.8484060637265;4115.506139263308;4117.444145040441;4125.8968525786495;4129.752214867118;3574.8748793792092;3705.6664900159117;4209.431726716915;4212.851445762622;4217.62041416274;4559.677693468857;4754.667173297905;4780.972091000452;4802.807887684523;4846.5153484651255;4669.813207471731;5552.805651802249;5596.992541342597;5637.943620563824;5674.354017752363;1179.7994340962057;1292.2763252578586;1295.5659853949032;1361.5506882728675;1365.706373129782;770.5107256891802;849.6006876767909;949.7083965806497;966.6853079811451;980.9656662016795;4362.8438463807115;4556.794944227451;4695.859124226278;4707.446542640921;5010.924836127461;4860.684055536379;4962.429320628174;5468.558784009935;5533.229366602729;5565.220869078333;4618.122556714214;5004.774785134678;5102.540771330524;5384.380796208487;5459.7342103026795;2618.3323252570854;2914.7522460591085;2922.722341377566;2927.2389178813237;2934.6038284831275;5674.829000684494;5783.508401785895;5794.594378393556;5809.2738407699035;5888.617239879394;5394.352677678704;5439.891202176013;5953.124765698878;5994.100884867217;6077.787277445268;5574.853681332716;5882.058870580687;5981.970090149522;6003.718586936936;6117.694115294306;5584;5823.35943046273;5836.387179346108;5854.677992710442;5860.241927421842;4637.93043104347;4847.146518517858;5282.418933917189;5689.914651280152;5792.913106303325;5626.803061892814;5690.0611399120335;5771;6034.9094763577705;6131.754729810757;3852.5762166162262;3880.883573492687;3922.2153919965776;3926.3521518949055;3934.960650393459;5180.507851754077;5871.181832720233;5887.264091988502;5906.852328691817;5914.1907593894275;4654.500819140594;5231.3199284092825;5742.626729262584;5762.423757624204;5766.336871259691;5588.636738611977;5659.886802264014;5667.489925906688;5689.630174038674;5703.344115426779;5330.813421530228;5436.238926550207;5522.806701765419;5541.030319693957;5561.833145005657;5263.578833714952;5370.4629537045985;5657.632253903483;5697.7720891165545;5710.600257981901;5320.414754377093;5555.8609935176055;5558.360788509373;5575.303087114111;5592.636478628876 06:30:44 INFO - PROCESS | 1640 | |5;yui.html;722.9891551626727;757.0650246945025;776.8485145396646;801.7835184500185;812.3825892321836;15217.010894291878;15855.682886342294;15894.205289735524;15956.12241326725;15973.760393594106;11902.642920712393;12442.19125756825;14410.639734006654;14443.927780361084;14444.638884027905;672.8418821576913;698.3923986132047;704.8625518023962;837.9539125348103;870.5125129927261;850.6639877248483;856.7215654912147;864.9697260595881;865.4677373415336;865.8701194820765;921.4010892919622;922.0594993107027;922.8292765838307;923.7829110159141;925.8009527951489;708.1749761527814;731.4258307228799;916.7662246127262;917.2111983694018;918.6095909238613;655.5312951239887;672.7914346552535;675.0920012583068;675.2302375292171;680.129434324066;12845.293508857008;15014.47449339268;15251.38994440221;15334.769978450333;15338.693226135527;13381.330933453288;13424.932875335659;13445.26051067191;13611.387487563083;13727.656808579766;13215.801762973562;13250.801237981437;13290.601281961557;13487.325633718374;13949.232792196424;3913.88258352247;4493.9101217975785;4507;4523.344115103291;4617.399738034068;114130.42934785273;114718.42640786743;114948;116057.83942160639;116199;10643.627473038407;11013;11064.114870810316;11064.391458469861;11106.278091924;20918.58162836765;21387.03758330879;21395.46511337229;21416.357509274745;21478.57042859134;7389.334959853639;7648.655810488542;7687.577183254974;7744.728934487266;7745.496542724817;3747.9437808432895;4181.829087855405;4271.444712187395;4296.398504209413;4487.461504619467;3332.3501917125927;3431.725461963037;3432.2105915639545;3436.43298855691;3438.0889064397957;611.6391329115754;614.0758158970721;618.7865186510646;629.8960671489153;633.8288662061218;632.2821807770281;639.9888176680834;658.6311665467354;660.2623204354243;670.9798706038729;9675.70972870815;9725.270604704534;9755.804883902283;9925.2059835213;10008.449535275484;610.7282259394527;640.5132099604241;642.591954109144;656.9113169722121;660.8975608780647;4597.310403439444;4600.88497787558;4771.809127634856;4775.904481910342;4782.832600858953;84541.73187402193;102304.48847755714;107802;108266.45866770616;109986;94852;96250.51874740582;96666.0333396671;96706.51646741721;98245;77833.22166778259;78495;78870.60564697254;80767;80942.38115237544;8931.19619234272;9257.324777396461;9281.414766022783;9324.347295689236;9459.763505912131;34589.65410345864;41357.79321103375;46677;47700.761496192295;48256.517434825204;60486.69756651189;61814.69092654508;62277.37722622716;62863;63346;10768.461576920967;12991.675208119872;13544.729105418033;13709.34132449544;13796.931015344859;15168.573028315417;15183.468578599695;15290.847091528942;15604.621668314481;15790.233245582387;14117.364718587483;14251.64370890736;14445.133292002514;14445.34995925207;14564.927175364055;13719.245441500812;14010.439582416997;14078.788818167941;14512.85487145115;14787.852121478647;13681.863181368457;13908.652283692989;14349.56951291463;14562.27188640585;14676.192809395585;11052.889471105185;11488.94255528717;11569;11639.243449175776;11710.765784684088;11048.735903147339;11242;11273;11417.0866330695;11480.483378247833 06:30:44 INFO - PROCESS | 1640 | __end_tp_report 06:30:44 INFO - PROCESS | 1640 | __start_cc_report 06:30:44 INFO - PROCESS | 1640 | _x_x_mozilla_cycle_collect,1716 06:30:44 INFO - PROCESS | 1640 | __end_cc_report 06:30:44 INFO - PROCESS | 1640 | __startTimestamp1484058644431__endTimestamp 06:30:44 INFO - PROCESS | 1640 | 06:30:44 INFO - PROCESS | 1640 | ------- Summary: start ------- 06:30:44 INFO - PROCESS | 1640 | Number of tests: 6 06:30:44 INFO - PROCESS | 1640 | 06:30:44 INFO - PROCESS | 1640 | [#0] dojo.html Cycles:165 Average:5702.90 Median:5969.31 stddev:673.29 (11.3%) stddev-sans-first:648.22 06:30:44 INFO - PROCESS | 1640 | Values: 3290.9 3315.1 3327.6 3341.3 3344.8 4229.5 5431.9 5582.1 5630.1 5650.8 5600.1 5630.4 5632.9 5658.1 5659.2 4179.4 5132.7 5150.4 5190.4 5233.3 4262.9 5222.3 5224.0 5228.0 5240.6 5215.5 5225.4 5225.5 5230.5 5231.5 5211.2 5215.9 5220.2 5224.8 5238.3 5006.8 5169.7 5172.7 5178.7 5197.5 5707.6 5773.2 5811.2 5874.6 5884.7 6101.8 6182.8 6226.9 6227.5 6233.2 5026.3 6097.5 6166.4 6181.7 6204.1 5718.1 5797.7 5850.6 5871.9 5876.1 6251.5 6251.9 6271.5 6276.5 6281.7 6033.8 6167.6 6168.2 6186.0 6199.1 6145.8 6150.7 6174.0 6205.0 6216.7 6195.6 6202.2 6206.7 6210.0 6212.9 6232.9 6235.4 6236.9 6237.4 6254.0 4875.8 4968.6 4977.8 4985.6 5020.5 4286.3 4398.9 4402.5 4409.5 4446.7 5803.7 5820.2 5828.9 5835.0 5858.9 6212.9 6224.9 6240.4 6240.5 6305.0 5834.5 5835.1 5835.7 5842.5 5873.1 5323.3 5375.2 5392.5 5393.1 5402.5 6215.6 6218.9 6227.1 6236.1 6247.3 5262.0 6188.8 6229.0 6263.5 6284.9 6267.1 6285.8 6292.6 6298.5 6300.2 6193.2 6237.6 6243.7 6245.5 6255.8 6135.8 6167.5 6210.6 6280.7 6285.3 4991.5 6129.8 6141.6 6151.5 6168.7 5981.9 6063.7 6135.6 6137.5 6171.8 5809.6 5846.8 5969.3 5980.1 5992.2 5323.2 6049.6 6076.1 6128.5 6155.0 5874.1 5994.3 5994.9 5999.6 6016.9 06:30:44 INFO - PROCESS | 1640 | 06:30:44 INFO - PROCESS | 1640 | [#1] ext.html Cycles:180 Average:65558.98 Median:39898.71 stddev:135523.37 (339.7%) stddev-sans-first:135815.50 06:30:44 INFO - PROCESS | 1640 | Values: 491.3 501.5 505.6 509.8 571.7 39836.4 42277.0 42296.9 42359.8 42420.6 43079.8 43456.9 43653.6 43654.0 43730.6 8518.6 9422.5 9620.7 9641.3 9643.7 8590.2 11297.6 11554.4 11561.2 11755.2 11552.0 11618.3 11638.8 11657.7 11670.5 8601.0 11577.6 11634.1 11670.6 11701.1 8653.0 11189.3 11452.6 11457.0 11625.0 40234.0 40645.4 40664.6 41045.4 41487.6 4041.3 4060.9 4067.3 4095.3 4214.0 30102.0 30616.8 30920.5 31200.1 31205.7 3172.2 3205.7 3265.0 3349.3 3356.1 469908.0 473048.0 473790.6 474204.0 476262.0 59499.0 59704.7 59861.4 59896.0 59964.0 64409.4 64475.0 65409.3 66189.4 66343.0 16172.9 16434.8 16436.6 16437.8 16460.8 11458.8 11632.5 12634.3 12717.5 12725.8 15087.9 16432.8 18187.5 18215.5 18384.8 7388.4 7588.1 7638.4 7677.8 7690.2 871.5 891.2 910.9 916.6 926.5 29726.8 30007.7 30160.2 30330.4 30564.8 1069.3 1073.4 1112.5 1117.3 1210.8 13961.8 15681.3 15710.8 15734.6 16198.7 603154.0 604955.0 607250.0 608382.0 608429.0 61349.7 61600.4 61675.4 61751.7 61889.8 1427.6 1460.5 1477.2 1478.0 1480.8 27766.6 29139.3 29220.3 29951.6 30326.0 51837.9 52397.0 52422.0 52498.7 52639.7 430244.0 431238.0 431582.0 432651.0 433046.0 40538.8 41290.4 41413.8 41441.6 41524.0 45798.5 45918.0 46273.0 46437.0 46623.3 38228.0 38763.2 39684.8 39793.4 39824.8 38669.8 38687.4 39413.8 39961.0 40139.8 34459.8 35286.0 36378.6 37224.4 37648.8 31217.0 32452.5 32511.3 32608.0 32661.3 23852.6 23861.6 24252.8 24427.3 24485.6 06:30:44 INFO - PROCESS | 1640 | 06:30:44 INFO - PROCESS | 1640 | [#2] jquery.html Cycles:180 Average:37782.45 Median:22167.56 stddev:104393.30 (470.9%) stddev-sans-first:104648.35 06:30:44 INFO - PROCESS | 1640 | Values: 365.2 382.7 387.6 392.4 414.6 15276.1 15331.8 17974.1 20822.5 21643.9 21600.6 22056.6 22278.6 22352.4 22611.7 5745.4 5827.7 5909.5 5976.4 6007.8 4177.1 5803.1 5993.2 6009.3 6022.3 4343.4 4387.4 5080.0 6642.1 6753.8 6511.1 6571.0 6651.8 6659.7 6745.9 5812.7 5903.6 5906.1 5914.1 5915.9 27288.3 27892.7 28003.9 28193.7 28421.9 2756.0 2785.3 2786.6 2792.4 2806.1 14843.8 15183.4 15832.4 15835.0 16343.0 1785.6 1807.3 1828.6 1835.0 1837.8 107345.0 125655.4 131218.0 140742.3 151778.5 9415.5 11438.9 11472.8 11633.4 11769.8 15083.6 15091.5 15103.5 15104.5 15131.5 8692.2 8942.7 8985.9 9110.6 9125.3 7831.1 8248.0 8913.3 8921.0 9102.4 7293.6 7469.7 7470.6 7481.4 7494.6 1881.1 1942.3 1943.5 1945.1 1950.2 940.7 989.8 996.0 1012.4 1026.7 17489.6 21556.4 21646.0 21718.6 21774.9 1004.8 1008.9 1041.3 1041.4 1043.1 10518.9 11264.8 11322.9 11344.3 11424.3 532832.0 536269.0 538660.0 715993.0 728987.0 150873.0 157415.4 164175.0 164495.0 169211.0 949.5 1004.0 1014.8 1072.9 1078.0 19284.6 20475.0 20691.4 20694.3 20710.0 59112.7 60314.7 61143.0 61505.0 62492.7 92136.0 92785.6 92832.0 94698.0 95030.0 8304.9 8430.0 8557.7 8608.5 8766.0 13057.3 13144.0 13162.4 13418.5 13657.6 11395.9 11456.6 11616.7 11753.8 11823.3 9295.6 10396.9 11428.5 11540.2 11580.4 11465.7 11811.0 11869.3 11884.4 12119.3 8225.1 8348.8 8461.7 8665.9 8720.7 10575.5 10962.0 11049.6 11203.7 11234.7 06:30:44 INFO - PROCESS | 1640 | 06:30:44 INFO - PROCESS | 1640 | [#3] mootools.html Cycles:180 Average:5301.31 Median:5125.77 stddev:2862.20 (55.8%) stddev-sans-first:2855.42 06:30:44 INFO - PROCESS | 1640 | Values: 1427.4 1445.5 1453.3 1469.6 1473.0 7858.5 8251.9 8266.3 8280.6 8320.7 7264.9 7546.1 7885.3 8122.9 8135.4 1884.3 1991.9 2124.7 2161.3 2202.5 2324.4 2340.4 2356.3 2356.5 2360.0 2307.9 2309.5 2329.6 2332.3 2337.5 2269.2 2272.9 2296.3 2299.6 2301.8 2292.6 2311.4 2333.1 2334.4 2349.8 8195.2 8261.2 8274.5 8278.4 8287.8 4940.3 5083.2 5096.7 5098.4 5099.1 3236.3 3322.0 3522.2 3523.7 3535.9 1196.8 1213.8 1230.9 1243.6 1245.5 10328.7 10461.4 10470.5 10485.3 10491.2 4304.2 4504.9 4605.2 4607.9 4630.0 9172.7 9172.8 9174.9 9203.0 9205.0 5031.6 5123.0 5128.6 5138.5 5138.9 3861.6 4190.3 4697.4 4697.5 4710.7 3227.6 3275.3 3740.6 3826.2 3865.5 1258.9 1268.5 1268.9 1278.9 1280.9 778.2 780.9 781.7 792.5 806.6 5341.8 5414.2 5452.3 5529.7 5621.9 985.3 1002.7 1014.8 1020.0 1061.0 3570.2 3602.9 3631.7 3635.9 3670.4 9533.0 9593.0 9698.0 9835.0 9866.1 9121.8 9592.1 9694.3 9752.4 9792.2 7549.0 7739.1 7742.0 7745.0 7752.0 5172.8 5389.6 5556.3 5709.9 5847.5 8763.3 9097.4 9104.3 9190.6 9195.8 6792.8 8984.9 9168.6 9222.6 9235.8 6053.3 6506.4 6573.6 6949.8 7065.0 7827.3 7836.6 7845.6 7848.5 7853.5 6835.7 6929.5 6930.7 6982.8 7050.5 6581.9 6812.9 6854.3 6890.9 6914.3 6161.2 6213.7 6297.7 6312.7 6322.1 4864.7 4886.5 4904.9 4947.7 5024.9 4819.0 4826.6 4927.7 4971.1 4985.5 06:30:44 INFO - PROCESS | 1640 | 06:30:44 INFO - PROCESS | 1640 | [#4] prototype.html Cycles:180 Average:4583.66 Median:5144.04 stddev:1387.65 (27.0%) stddev-sans-first:1353.89 06:30:44 INFO - PROCESS | 1640 | Values: 306.2 351.7 363.2 370.8 372.9 4585.9 4882.3 5397.8 5423.8 5453.8 4726.7 5067.0 5174.7 5178.9 5252.1 3689.0 3855.9 3858.4 3955.3 4207.3 4166.5 4181.3 4202.4 4238.7 4264.1 4022.0 4129.2 4176.0 4182.5 4197.6 3644.3 4121.9 4146.9 4157.4 4187.9 3500.2 3628.8 3653.8 3661.0 3679.9 4163.0 5113.4 5215.1 5241.9 5302.3 3668.5 4553.0 4566.2 4787.7 4813.4 5612.7 5649.3 5657.9 5660.4 5681.1 3416.9 3853.4 4024.7 4048.7 4060.9 4907.9 5378.9 5551.8 5642.6 5900.0 3789.8 4115.5 4117.4 4125.9 4129.8 3574.9 3705.7 4209.4 4212.9 4217.6 4559.7 4754.7 4781.0 4802.8 4846.5 4669.8 5552.8 5597.0 5637.9 5674.4 1179.8 1292.3 1295.6 1361.6 1365.7 770.5 849.6 949.7 966.7 981.0 4362.8 4556.8 4695.9 4707.4 5010.9 4860.7 4962.4 5468.6 5533.2 5565.2 4618.1 5004.8 5102.5 5384.4 5459.7 2618.3 2914.8 2922.7 2927.2 2934.6 5674.8 5783.5 5794.6 5809.3 5888.6 5394.4 5439.9 5953.1 5994.1 6077.8 5574.9 5882.1 5982.0 6003.7 6117.7 5584.0 5823.4 5836.4 5854.7 5860.2 4637.9 4847.1 5282.4 5689.9 5792.9 5626.8 5690.1 5771.0 6034.9 6131.8 3852.6 3880.9 3922.2 3926.4 3935.0 5180.5 5871.2 5887.3 5906.9 5914.2 4654.5 5231.3 5742.6 5762.4 5766.3 5588.6 5659.9 5667.5 5689.6 5703.3 5330.8 5436.2 5522.8 5541.0 5561.8 5263.6 5370.5 5657.6 5697.8 5710.6 5320.4 5555.9 5558.4 5575.3 5592.6 06:30:44 INFO - PROCESS | 1640 | 06:30:44 INFO - PROCESS | 1640 | [#5] yui.html Cycles:180 Average:20751.90 Median:4283.92 stddev:30358.35 (708.7%) stddev-sans-first:30406.26 06:30:44 INFO - PROCESS | 1640 | Values: 723.0 757.1 776.8 801.8 812.4 15217.0 15855.7 15894.2 15956.1 15973.8 11902.6 12442.2 14410.6 14443.9 14444.6 672.8 698.4 704.9 838.0 870.5 850.7 856.7 865.0 865.5 865.9 921.4 922.1 922.8 923.8 925.8 708.2 731.4 916.8 917.2 918.6 655.5 672.8 675.1 675.2 680.1 12845.3 15014.5 15251.4 15334.8 15338.7 13381.3 13424.9 13445.3 13611.4 13727.7 13215.8 13250.8 13290.6 13487.3 13949.2 3913.9 4493.9 4507.0 4523.3 4617.4 114130.4 114718.4 114948.0 116057.8 116199.0 10643.6 11013.0 11064.1 11064.4 11106.3 20918.6 21387.0 21395.5 21416.4 21478.6 7389.3 7648.7 7687.6 7744.7 7745.5 3747.9 4181.8 4271.4 4296.4 4487.5 3332.4 3431.7 3432.2 3436.4 3438.1 611.6 614.1 618.8 629.9 633.8 632.3 640.0 658.6 660.3 671.0 9675.7 9725.3 9755.8 9925.2 10008.4 610.7 640.5 642.6 656.9 660.9 4597.3 4600.9 4771.8 4775.9 4782.8 84541.7 102304.5 107802.0 108266.5 109986.0 94852.0 96250.5 96666.0 96706.5 98245.0 77833.2 78495.0 78870.6 80767.0 80942.4 8931.2 9257.3 9281.4 9324.3 9459.8 34589.7 41357.8 46677.0 47700.8 48256.5 60486.7 61814.7 62277.4 62863.0 63346.0 10768.5 12991.7 13544.7 13709.3 13796.9 15168.6 15183.5 15290.8 15604.6 15790.2 14117.4 14251.6 14445.1 14445.3 14564.9 13719.2 14010.4 14078.8 14512.9 14787.9 13681.9 13908.7 14349.6 14562.3 14676.2 11052.9 11488.9 11569.0 11639.2 11710.8 11048.7 11242.0 11273.0 11417.1 11480.5 06:30:44 INFO - PROCESS | 1640 | -------- Summary: end -------- 06:30:44 INFO - PROCESS | 1640 | 06:30:45 INFO - TEST-INFO | 1640: exit 0 06:30:45 INFO - TEST-OK | dromaeo_css | took 1120139ms 06:30:45 INFO - TEST-START | kraken 06:30:45 INFO - operating with platform_type : mac_ 06:30:45 INFO - Initialising browser for kraken test... 06:30:45 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/tmpPEfYIR/profile http://localhost:49241/getInfo.html) 06:30:45 INFO - PROCESS | 1650 | 1484058645536 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:4906 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2060 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3761 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2794 < callProvider()@resource://gre/modules/AddonManager.jsm:267 < _startProvider()@resource://gre/modules/AddonManager.jsm:973 < startup()@resource://gre/modules/AddonManager.jsm:1155 < startup()@resource://gre/modules/AddonManager.jsm:3124 < observe()@resource://gre/components/addonManager.js:65 06:30:47 INFO - PROCESS | 1650 | __metrics Screen width/height:1600/1200 06:30:47 INFO - PROCESS | 1650 | colorDepth:24 06:30:47 INFO - PROCESS | 1650 | Browser inner width/height: 1024/689 06:30:47 INFO - PROCESS | 1650 | __metrics 06:30:47 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" 06:30:47 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] 06:30:48 INFO - TEST-INFO | 1650: exit 0 06:30:48 INFO - Browser initialized. 06:30:48 INFO - Running cycle 1/1 for kraken test... 06:30:48 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/tmpPEfYIR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:31:00 INFO - PROCESS | 1651 | RSS: Main: 234229760 06:31:00 INFO - PROCESS | 1651 | 06:31:03 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 06:31:03 INFO - PROCESS | 1651 | RSS: Main: 287264768 06:31:03 INFO - PROCESS | 1651 | 06:31:06 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 06:31:07 INFO - PROCESS | 1651 | RSS: Main: 287707136 06:31:07 INFO - PROCESS | 1651 | 06:31:10 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 06:31:11 INFO - PROCESS | 1651 | RSS: Main: 380411904 06:31:11 INFO - PROCESS | 1651 | 06:31:13 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 06:31:13 INFO - PROCESS | 1651 | RSS: Main: 283500544 06:31:13 INFO - PROCESS | 1651 | 06:31:15 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:31:16 INFO - PROCESS | 1651 | RSS: Main: 287739904 06:31:16 INFO - PROCESS | 1651 | 06:31:19 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 06:31:19 INFO - PROCESS | 1651 | RSS: Main: 329084928 06:31:19 INFO - PROCESS | 1651 | 06:31:22 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 06:31:23 INFO - PROCESS | 1651 | RSS: Main: 330665984 06:31:23 INFO - PROCESS | 1651 | 06:31:26 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 06:31:26 INFO - PROCESS | 1651 | RSS: Main: 328208384 06:31:26 INFO - PROCESS | 1651 | 06:31:28 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:31:28 INFO - PROCESS | 1651 | RSS: Main: 304189440 06:31:28 INFO - PROCESS | 1651 | 06:31:30 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:31:31 INFO - PROCESS | 1651 | RSS: Main: 308326400 06:31:31 INFO - PROCESS | 1651 | 06:31:33 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:31:34 INFO - PROCESS | 1651 | RSS: Main: 310202368 06:31:34 INFO - PROCESS | 1651 | 06:31:37 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:31:37 INFO - PROCESS | 1651 | RSS: Main: 326131712 06:31:37 INFO - PROCESS | 1651 | 06:31:40 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:31:41 INFO - PROCESS | 1651 | RSS: Main: 320151552 06:31:41 INFO - PROCESS | 1651 | 06:31:43 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 06:31:43 INFO - PROCESS | 1651 | RSS: Main: 364457984 06:31:43 INFO - PROCESS | 1651 | 06:31:43 INFO - PROCESS | 1651 | __start_tp_report 06:31:43 INFO - PROCESS | 1651 | _x_x_mozilla_page_load 06:31:43 INFO - PROCESS | 1651 | _x_x_mozilla_page_load_details 06:31:43 INFO - PROCESS | 1651 | |i|pagename|runs| 06:31:43 INFO - PROCESS | 1651 | |0;ai-astar;115;115;115;113;114;117;112;113;115;114 06:31:43 INFO - PROCESS | 1651 | |1;audio-beat-detection;141;109;111;144;112;133;136;113;128;112 06:31:43 INFO - PROCESS | 1651 | |2;audio-dft;183;180;183;184;183;180;181;184;185;181 06:31:43 INFO - PROCESS | 1651 | |3;audio-fft;82;60;59;92;60;59;58;59;73;59 06:31:43 INFO - PROCESS | 1651 | |4;audio-oscillator;85;69;86;68;68;68;84;68;69;84 06:31:43 INFO - PROCESS | 1651 | |5;imaging-gaussian-blur;88;89;90;83;88;88;91;88;90;91 06:31:43 INFO - PROCESS | 1651 | |6;imaging-darkroom;98;99;97;98;106;119;99;98;96;98 06:31:43 INFO - PROCESS | 1651 | |7;imaging-desaturate;86;85;88;87;87;84;84;85;84;84 06:31:43 INFO - PROCESS | 1651 | |8;json-parse-financial;55;54;53;54;57;55;54;56;56;55 06:31:43 INFO - PROCESS | 1651 | |9;json-stringify-tinderbox;64;67;71;78;72;70;69;72;69;70 06:31:43 INFO - PROCESS | 1651 | |10;stanford-crypto-aes;87;87;87;89;87;86;88;89;87;87 06:31:43 INFO - PROCESS | 1651 | |11;stanford-crypto-ccm;139;141;136;140;138;140;140;138;182;154 06:31:43 INFO - PROCESS | 1651 | |12;stanford-crypto-pbkdf2;175;180;172;173;172;176;174;171;197;224 06:31:43 INFO - PROCESS | 1651 | |13;stanford-crypto-sha256-iterative;66;67;67;68;66;63;80;81;83;73 06:31:43 INFO - PROCESS | 1651 | __end_tp_report 06:31:43 INFO - PROCESS | 1651 | __start_cc_report 06:31:43 INFO - PROCESS | 1651 | _x_x_mozilla_cycle_collect,1987 06:31:43 INFO - PROCESS | 1651 | __end_cc_report 06:31:43 INFO - PROCESS | 1651 | __startTimestamp1484058703588__endTimestamp 06:31:43 INFO - PROCESS | 1651 | 06:31:43 INFO - PROCESS | 1651 | ------- Summary: start ------- 06:31:43 INFO - PROCESS | 1651 | Number of tests: 14 06:31:43 INFO - PROCESS | 1651 | 06:31:43 INFO - PROCESS | 1651 | [#0] ai-astar Cycles:10 Average:114.30 Median:115.00 stddev:1.42 (1.2%) stddev-sans-first:1.48 06:31:43 INFO - PROCESS | 1651 | Values: 115.0 115.0 115.0 113.0 114.0 117.0 112.0 113.0 115.0 114.0 06:31:43 INFO - PROCESS | 1651 | 06:31:43 INFO - PROCESS | 1651 | [#1] audio-beat-detection Cycles:10 Average:123.90 Median:130.50 stddev:13.88 (10.6%) stddev-sans-first:13.27 06:31:43 INFO - PROCESS | 1651 | Values: 141.0 109.0 111.0 144.0 112.0 133.0 136.0 113.0 128.0 112.0 06:31:43 INFO - PROCESS | 1651 | 06:31:43 INFO - PROCESS | 1651 | [#2] audio-dft Cycles:10 Average:182.40 Median:183.00 stddev:1.78 (1.0%) stddev-sans-first:1.87 06:31:43 INFO - PROCESS | 1651 | Values: 183.0 180.0 183.0 184.0 183.0 180.0 181.0 184.0 185.0 181.0 06:31:43 INFO - PROCESS | 1651 | 06:31:43 INFO - PROCESS | 1651 | [#3] audio-fft Cycles:10 Average:66.10 Median:60.00 stddev:12.08 (20.1%) stddev-sans-first:11.36 06:31:43 INFO - PROCESS | 1651 | Values: 82.0 60.0 59.0 92.0 60.0 59.0 58.0 59.0 73.0 59.0 06:31:43 INFO - PROCESS | 1651 | 06:31:43 INFO - PROCESS | 1651 | [#4] audio-oscillator Cycles:10 Average:74.90 Median:76.50 stddev:8.50 (11.1%) stddev-sans-first:8.20 06:31:43 INFO - PROCESS | 1651 | Values: 85.0 69.0 86.0 68.0 68.0 68.0 84.0 68.0 69.0 84.0 06:31:43 INFO - PROCESS | 1651 | 06:31:43 INFO - PROCESS | 1651 | [#5] imaging-gaussian-blur Cycles:10 Average:88.60 Median:89.50 stddev:2.32 (2.6%) stddev-sans-first:2.45 06:31:43 INFO - PROCESS | 1651 | Values: 88.0 89.0 90.0 83.0 88.0 88.0 91.0 88.0 90.0 91.0 06:31:43 INFO - PROCESS | 1651 | 06:31:43 INFO - PROCESS | 1651 | [#6] imaging-darkroom Cycles:10 Average:100.80 Median:98.00 stddev:6.94 (7.1%) stddev-sans-first:7.29 06:31:43 INFO - PROCESS | 1651 | Values: 98.0 99.0 97.0 98.0 106.0 119.0 99.0 98.0 96.0 98.0 06:31:43 INFO - PROCESS | 1651 | 06:31:43 INFO - PROCESS | 1651 | [#7] imaging-desaturate Cycles:10 Average:85.40 Median:85.50 stddev:1.51 (1.8%) stddev-sans-first:1.58 06:31:43 INFO - PROCESS | 1651 | Values: 86.0 85.0 88.0 87.0 87.0 84.0 84.0 85.0 84.0 84.0 06:31:43 INFO - PROCESS | 1651 | 06:31:43 INFO - PROCESS | 1651 | [#8] json-parse-financial Cycles:10 Average:54.90 Median:55.00 stddev:1.20 (2.2%) stddev-sans-first:1.27 06:31:43 INFO - PROCESS | 1651 | Values: 55.0 54.0 53.0 54.0 57.0 55.0 54.0 56.0 56.0 55.0 06:31:43 INFO - PROCESS | 1651 | 06:31:43 INFO - PROCESS | 1651 | [#9] json-stringify-tinderbox Cycles:10 Average:70.20 Median:70.50 stddev:3.65 (5.2%) stddev-sans-first:3.10 06:31:43 INFO - PROCESS | 1651 | Values: 64.0 67.0 71.0 78.0 72.0 70.0 69.0 72.0 69.0 70.0 06:31:43 INFO - PROCESS | 1651 | 06:31:43 INFO - PROCESS | 1651 | [#10] stanford-crypto-aes Cycles:10 Average:87.40 Median:87.00 stddev:0.97 (1.1%) stddev-sans-first:1.01 06:31:43 INFO - PROCESS | 1651 | Values: 87.0 87.0 87.0 89.0 87.0 86.0 88.0 89.0 87.0 87.0 06:31:43 INFO - PROCESS | 1651 | 06:31:43 INFO - PROCESS | 1651 | [#11] stanford-crypto-ccm Cycles:10 Average:144.80 Median:140.00 stddev:13.97 (10.0%) stddev-sans-first:14.66 06:31:43 INFO - PROCESS | 1651 | Values: 139.0 141.0 136.0 140.0 138.0 140.0 140.0 138.0 182.0 154.0 06:31:43 INFO - PROCESS | 1651 | 06:31:43 INFO - PROCESS | 1651 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.40 Median:175.50 stddev:16.80 (9.6%) stddev-sans-first:17.66 06:31:43 INFO - PROCESS | 1651 | Values: 175.0 180.0 172.0 173.0 172.0 176.0 174.0 171.0 197.0 224.0 06:31:43 INFO - PROCESS | 1651 | 06:31:43 INFO - PROCESS | 1651 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.40 Median:70.50 stddev:7.32 (10.4%) stddev-sans-first:7.50 06:31:43 INFO - PROCESS | 1651 | Values: 66.0 67.0 67.0 68.0 66.0 63.0 80.0 81.0 83.0 73.0 06:31:43 INFO - PROCESS | 1651 | -------- Summary: end -------- 06:31:43 INFO - PROCESS | 1651 | 06:31:44 INFO - TEST-INFO | 1651: exit 0 06:31:44 INFO - TEST-OK | kraken | took 59502ms 06:31:44 INFO - SUITE-END | took 1179s 06:31:44 INFO - Completed test suite (00:19:39) 06:31:44 INFO - javascript benchmark 06:31:44 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3290.9341813163737, 3315.1214928044064, 3327.584051993501, 3341.3150304187643, 3344.84948177332, 4229.492460904692, 5431.859309544995, 5582.051051321275, 5630.127330263806, 5650.8022219222285, 5600.131979543167, 5630.380658127613, 5632.887342253152, 5658.122990936401, 5659.236003139575, 4179.414881916527, 5132.692038477703, 5150.356205474316, 5190.351206099238, 5233.345831771028, 4262.8721138365745, 5222.3472065991755, 5224.0, 5227.973860130694, 5240.580753539727, 5215.504527069941, 5225.399079105894, 5225.529702326789, 5230.529252367286, 5231.450697676746, 5211.192265198882, 5215.947840521584, 5220.2430647556275, 5224.817131400421, 5238.345206849144, 5006.774695138727, 5169.689818610896, 5172.689638621695, 5178.741062946838, 5197.532222100029, 5707.600467967244, 5773.24947756789, 5811.186433899257, 5874.647521148745, 5884.705764711747, 6101.847453813646, 6182.814515564541, 6226.875462490725, 6227.470664993481, 6233.2208473940755, 5026.29631851541, 6097.524678803529, 6166.445019948182, 6181.690915454209, 6204.100405441231, 5718.0851063830005, 5797.652140871562, 5850.64896106235, 5871.853203669857, 5876.08920617305, 6251.53113516479, 6251.937480625226, 6271.529635277281, 6276.497880169667, 6281.685915704196, 6033.7586496540525, 6167.599106058182, 6168.167297414791, 6185.969070154711, 6199.132121502993, 6145.84635384119, 6150.661713605705, 6174.012158054689, 6204.968975155095, 6216.658083805434, 6195.628262304276, 6202.162708034448, 6206.658633775186, 6209.96895015522, 6212.9068063979075, 6232.875342493125, 6235.376462353728, 6236.937630623635, 6237.376262373727, 6254.0, 4875.780589873464, 4968.6025117990475, 4977.800887964441, 4985.626078044161, 5020.472850350734, 4286.292761694285, 4398.890027749268, 4402.493713222956, 4409.536998615164, 4446.733196008186, 5803.65178089316, 5820.1560773688425, 5828.912566311508, 5834.978878696211, 5858.882822343615, 6212.937870621236, 6224.906626400608, 6240.438360547572, 6240.469560087353, 6304.968475157595, 5834.474897259182, 5835.124731290341, 5835.708214589254, 5842.503387212135, 5873.148393482921, 5323.334583177103, 5375.19372094189, 5392.541633961078, 5393.110136827458, 5402.459754024566, 6215.627062376272, 6218.906716399167, 6227.0970709246, 6236.064590311489, 6247.281562620356, 5261.973690131525, 6188.81433556976, 6228.968855155696, 6263.530235232284, 6284.937150628435, 6267.059941008885, 6285.779997700059, 6292.590981586182, 6298.527610429144, 6300.180976473029, 6193.163922870355, 6237.594556353823, 6243.687815609293, 6245.500359971101, 6255.781047663492, 6135.814547850547, 6167.475764560063, 6210.596311239755, 6280.685965701788, 6285.308616052139, 4991.50084991498, 6129.786714140983, 6141.631502109888, 6151.477124444473, 6168.722407491584, 5981.850453738692, 6063.696815159313, 6135.601185922901, 6137.478314343152, 6171.84570385744, 5809.622374545641, 5846.795362162304, 5969.313528944227, 5980.073088671263, 5992.191054207626, 5323.228131920927, 6049.606775559574, 6076.149339092619, 6128.540359472968, 6155.045967874987, 5874.148248503858, 5994.31065427464, 5994.850128746816, 5999.640021598718, 6016.909746353721], "value": 5660.043400252435, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [491.33914884480373, 501.48347202381535, 505.5828941123574, 509.81901424994123, 571.6794205385562, 39836.40245396318, 42277.0, 42296.94257643557, 42359.788201059026, 42420.575794242046, 43079.78460107703, 43456.91357716059, 43653.56346436535, 43654.0, 43730.562694373046, 8518.57407129641, 9422.481763503021, 9620.71137865865, 9641.276904232176, 9643.710688679352, 8590.153117079826, 11297.604583839568, 11554.422278886064, 11561.24851884629, 11755.17713760037, 11552.018078463341, 11618.302901825917, 11638.825417618742, 11657.70855728605, 11670.474828632732, 8600.956995215047, 11577.594784182555, 11634.069274458023, 11670.649880503555, 11701.063914886789, 8653.0, 11189.272697274651, 11452.599159029394, 11457.0, 11625.0, 40234.0, 40645.39031914494, 40664.593354066375, 41045.384319235236, 41487.585124148674, 4041.312976793952, 4060.918781624351, 4067.2882245607198, 4095.3037983542868, 4213.9578604213875, 30102.0, 30616.775328986812, 30920.536191957137, 31200.063998080095, 31205.68794312073, 3172.2069482629345, 3205.6634053424523, 3265.0, 3349.2631621043333, 3356.060303115132, 469908.0, 473048.0, 473790.63104684255, 474204.0, 476262.0, 59499.0, 59704.70147649321, 59861.401385985584, 59896.0, 59964.0, 64409.35590644128, 64475.0, 65409.34590654032, 66189.38822035528, 66343.0, 16172.919135404247, 16434.83565164357, 16436.589085272724, 16437.83562164387, 16460.753088703677, 11458.828117578243, 11632.476538555786, 12634.305113218861, 12717.491300348067, 12725.809112863315, 15087.868409869303, 16432.83567164337, 18187.45437636873, 18215.544611384823, 18384.81615183831, 7388.371988381048, 7588.089429268523, 7638.427117966175, 7677.769666910001, 7690.15408305086, 871.4945331707594, 891.1845661219911, 910.9043550427242, 916.6425094213262, 926.5413620258015, 29726.76286849098, 30007.699923000928, 30160.24599385033, 30330.393392132035, 30564.847175763978, 1069.2996087562658, 1073.43278812934, 1112.477135746198, 1117.3351855645878, 1210.8002179640437, 13961.79057314141, 15681.294341754648, 15710.764338534931, 15734.606634834221, 16198.67602647964, 603154.0, 604955.0, 607250.0, 608382.0, 608429.0, 61349.693251533456, 61600.383996159464, 61675.38324616786, 61751.69124154351, 61889.76220475565, 1427.5503216486773, 1460.4742292774592, 1477.2392218007735, 1478.031889112633, 1480.7704805755125, 27766.5835012479, 29139.271518212216, 29220.26949326284, 29951.550726738682, 30326.0, 51837.92648294026, 52396.95206095933, 52421.951560969326, 52498.73750631375, 52639.73680131575, 430244.0, 431238.0, 431582.0, 432651.0, 433046.0, 40538.79730601328, 41290.38064428976, 41413.75758727243, 41441.58558414377, 41524.0, 45798.54201458076, 45918.0, 46273.0, 46437.0, 46623.30065048959, 38228.0, 38763.23447928086, 39684.80945571637, 39793.40309895412, 39824.80087599543, 38669.806650966566, 38687.41968870525, 39413.80293098516, 39961.019490254876, 40139.79930100331, 34459.79390721313, 35286.0, 36378.63621363859, 37224.44163337606, 37648.81175594214, 31217.0, 32452.513212302307, 32511.349773003934, 32608.02175934726, 32661.34677306488, 23852.642210367205, 23861.64207536923, 24252.75747242505, 24427.267181984567, 24485.632715508924], "value": 20464.75633486611, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [365.22936603766067, 382.7225261685277, 387.6259409669669, 392.3819983525946, 414.56263641857845, 15276.074533421384, 15331.770023449657, 17974.101294935266, 20822.47943801402, 21643.891780541115, 21600.567988640218, 22056.558868822613, 22278.55442891141, 22352.441188970282, 22611.660825087638, 5745.425457454263, 5827.688770026732, 5909.468147866691, 5976.3724808895095, 6007.8197654070445, 4177.122804211104, 5803.100519419476, 5993.160957465958, 6009.308929473101, 6022.277326720778, 4343.413639158704, 4387.407699960512, 5080.034793389263, 6642.136522252124, 6753.797386078426, 6511.088447617338, 6571.0, 6651.76718814844, 6659.700313485905, 6745.898811517782, 5812.680302583356, 5903.645781253095, 5906.055031195031, 5914.083317085816, 5915.852103697399, 27288.2703777336, 27892.721072789216, 28003.85998070017, 28193.73128209215, 28421.85789071062, 2756.0, 2785.331520435081, 2786.5680819473014, 2792.427552351763, 2806.1300996691093, 14843.777343339858, 15183.392664293415, 15832.445864394806, 15835.0, 16343.019418834789, 1785.5536115971008, 1807.286121981823, 1828.5611453251124, 1834.9816501834944, 1837.751903493038, 107345.0, 125655.37172314171, 131218.0, 140742.29628851893, 151778.48221517864, 9415.482148481762, 11438.94280528592, 11472.827907581393, 11633.418329083512, 11769.823452648217, 15083.622909427351, 15091.471798487, 15103.4713785017, 15104.471343502924, 15131.54605361841, 8692.174243446863, 8942.68700595476, 8985.910140898639, 9110.59002344896, 9125.269978401711, 7831.060272767304, 8248.0, 8913.28693704502, 8920.955395222982, 9102.362834601647, 7293.598852063082, 7469.663865126083, 7470.589117598584, 7481.364084052916, 7494.587797671072, 1881.1346780480858, 1942.300771722179, 1943.5141214696325, 1945.0760888578095, 1950.2394066314148, 940.7083804020775, 989.8366769482955, 995.9950200248952, 1012.367270455965, 1026.7381817636604, 17489.562760930825, 21556.353309400743, 21646.0, 21718.565628687655, 21774.891125544273, 1004.8442491413842, 1008.9243306751877, 1041.3231399590175, 1041.4272150317295, 1043.0925095167102, 10518.947405262925, 11264.831027534594, 11322.94338528302, 11344.262622929484, 11424.314541127558, 532832.0, 536269.0, 538660.0, 715993.0, 728987.0, 150873.0, 157415.42584574237, 164175.0, 164495.0, 169211.0, 949.4777872170417, 1003.9999999999853, 1014.8477728340808, 1072.9356238625708, 1077.9974623599971, 19284.614307714048, 20475.0, 20691.379258622266, 20694.275700350416, 20710.0, 59112.70443647754, 60314.69842650759, 61143.0, 61505.0, 62492.68753656203, 92136.0, 92785.60821587816, 92832.0, 94698.0, 95030.0, 8304.87542686848, 8429.957850210709, 8557.657693692183, 8608.48349099056, 8766.0357360689, 13057.347132643139, 13144.0, 13162.40769165409, 13418.530351437652, 13657.590272291847, 11395.943020284845, 11456.599019034295, 11616.709582260512, 11753.823692644446, 11823.349715765715, 9295.628174872929, 10396.896031039592, 11428.542858285575, 11540.24988375753, 11580.36308003068, 11465.656030319104, 11811.0, 11869.287842729464, 11884.405779711153, 12119.333436661069, 8225.095239523767, 8348.791280218044, 8461.661533538836, 8665.870011949699, 8720.694775682821, 10575.471226438802, 10962.0, 11049.613263535737, 11203.71990700239, 11234.662960110883], "value": 10272.586342758725, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1427.3576890399322, 1445.5085271007856, 1453.2951518513519, 1469.5664778890227, 1473.0130812355724, 7858.528488290699, 8251.87622185667, 8266.338692904568, 8280.62737176827, 8320.667173313068, 7264.927350726492, 7546.094468663754, 7885.3297469715, 8122.878156827637, 8135.389845761562, 1884.3028079610563, 1991.9343151413996, 2124.6600543912978, 2161.2651698422533, 2202.5484775620957, 2324.4421338878633, 2340.4031971847157, 2356.304890057437, 2356.469794296278, 2360.0, 2307.8615283082986, 2309.4803669174385, 2329.6389059695775, 2332.2536788227776, 2337.5208082343165, 2269.171752310403, 2272.874991875446, 2296.334063121693, 2299.5515874404414, 2301.7813307735737, 2292.5644127615865, 2311.4221444638843, 2333.1134169015836, 2334.4280651240556, 2349.847259928099, 8195.221453961864, 8261.215184557457, 8274.462159959583, 8278.420510564263, 8287.8342433151, 4940.25896115582, 5083.161278389061, 5096.694198348074, 5098.388193416777, 5099.107656160157, 3236.3365510070616, 3322.0, 3522.2427178156563, 3523.6828685418177, 3535.9292814143573, 1196.814493753461, 1213.8179273109015, 1230.8953738932112, 1243.5958313548133, 1245.5453759377851, 10328.741781455448, 10461.372317660966, 10470.528826202763, 10485.266031377807, 10491.160707143486, 4304.225239456865, 4504.9099018020115, 4605.171069207508, 4607.884802879955, 4630.0, 9172.724818255463, 9172.816543669089, 9174.86237706435, 9202.953985230031, 9205.0, 5031.647784655039, 5122.974385128051, 5128.564072053917, 5138.537531622172, 5138.8972220556125, 3861.6331448512346, 4190.266703326905, 4697.389339385858, 4697.459792123881, 4710.740909250024, 3227.644959054502, 3275.279438523521, 3740.625937406292, 3826.1582451860545, 3865.536135663738, 1258.930758808256, 1268.492602958824, 1268.8921441677376, 1278.9040821938393, 1280.9487620495076, 778.2334400615389, 780.8945792317965, 781.7303030454484, 792.4650860669026, 806.6128258435983, 5341.839744807662, 5414.160805075219, 5452.345718513804, 5529.668219906738, 5621.8594535136945, 985.2807450560987, 1002.684154491333, 1014.7666036811574, 1019.9592016319413, 1061.0079575596842, 3570.1967057412394, 3602.945955810665, 3631.654992775682, 3635.8727444539313, 3670.4494325851338, 9532.951375348706, 9592.95203523992, 9698.030196980386, 9834.950825245971, 9866.062724041205, 9121.771955701028, 9592.136707696342, 9694.272929530314, 9752.366096203725, 9792.16766574835, 7549.0, 7739.148693643695, 7742.0, 7745.031871016123, 7751.961240193763, 5172.793088276427, 5389.59578031657, 5556.305461817273, 5709.942900570941, 5847.502962248086, 8763.255123314335, 9097.40866843653, 9104.271658267191, 9190.586423611087, 9195.77010574741, 6792.830179245559, 8984.910150898408, 9168.587413566273, 9222.631094756134, 9235.815283694154, 6053.273607167168, 6506.3818937200895, 6573.57271777333, 6949.818530849869, 7065.046218760402, 7827.25641064098, 7836.568988705676, 7845.6077196141105, 7848.529088254723, 7853.489523180975, 6835.701216768798, 6929.514933954575, 6930.653467326512, 6982.79051628452, 7050.541714788523, 6581.934180658323, 6812.897806533005, 6854.280300568368, 6890.896636550356, 6914.308569143045, 6161.229846269216, 6213.720382582885, 6297.685115744286, 6312.747490100344, 6322.114903913363, 4864.708117512961, 4886.511348865085, 4904.926426103682, 4947.678400903929, 5024.924626130538, 4818.975905120452, 4826.565609095198, 4927.728974906271, 4971.055499455092, 4985.501449854985], "value": 4329.5117839630875, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [306.19165403335194, 351.7133536168023, 363.22995250069846, 370.80532720321844, 372.87135938101346, 4585.908281834361, 4882.340883980666, 5397.757100930457, 5423.83728488145, 5453.836384908454, 4726.716397016173, 5067.0, 5174.689518628885, 5178.896422071566, 5252.135491900378, 3688.9670892150107, 3855.8650447234354, 3858.3633700439395, 3955.307821131305, 4207.347861081537, 4166.541680415152, 4181.3309870420735, 4202.432671589342, 4238.682098842583, 4264.123785534355, 4021.959780402188, 4129.2360913231, 4175.958240417603, 4182.519010313822, 4197.614787120258, 3644.2529281497496, 4121.867380790591, 4146.889832625546, 4157.355609880443, 4187.853425130121, 3500.2474467989505, 3628.8367023483743, 3653.780773153619, 3661.029827095822, 3679.9264014719824, 4163.001759155592, 5113.386393632751, 5215.061288967984, 5241.9213711794355, 5302.329766123658, 3668.5149108347177, 4552.977235113836, 4566.1917920479, 4787.677976181549, 4813.374261346003, 5612.719364031823, 5649.293838270216, 5657.886842263131, 5660.377358490563, 5681.147827825859, 3416.855353456595, 3853.364194907837, 4024.678025757932, 4048.696347773899, 4060.939085913714, 4907.852764417074, 5378.91931621026, 5551.777928882881, 5642.605017648766, 5900.0, 3789.8484060637265, 4115.506139263308, 4117.444145040441, 4125.8968525786495, 4129.752214867118, 3574.8748793792092, 3705.6664900159117, 4209.431726716915, 4212.851445762622, 4217.62041416274, 4559.677693468857, 4754.667173297905, 4780.972091000452, 4802.807887684523, 4846.5153484651255, 4669.813207471731, 5552.805651802249, 5596.992541342597, 5637.943620563824, 5674.354017752363, 1179.7994340962057, 1292.2763252578586, 1295.5659853949032, 1361.5506882728675, 1365.706373129782, 770.5107256891802, 849.6006876767909, 949.7083965806497, 966.6853079811451, 980.9656662016795, 4362.8438463807115, 4556.794944227451, 4695.859124226278, 4707.446542640921, 5010.924836127461, 4860.684055536379, 4962.429320628174, 5468.558784009935, 5533.229366602729, 5565.220869078333, 4618.122556714214, 5004.774785134678, 5102.540771330524, 5384.380796208487, 5459.7342103026795, 2618.3323252570854, 2914.7522460591085, 2922.722341377566, 2927.2389178813237, 2934.6038284831275, 5674.829000684494, 5783.508401785895, 5794.594378393556, 5809.2738407699035, 5888.617239879394, 5394.352677678704, 5439.891202176013, 5953.124765698878, 5994.100884867217, 6077.787277445268, 5574.853681332716, 5882.058870580687, 5981.970090149522, 6003.718586936936, 6117.694115294306, 5584.0, 5823.35943046273, 5836.387179346108, 5854.677992710442, 5860.241927421842, 4637.93043104347, 4847.146518517858, 5282.418933917189, 5689.914651280152, 5792.913106303325, 5626.803061892814, 5690.0611399120335, 5771.0, 6034.9094763577705, 6131.754729810757, 3852.5762166162262, 3880.883573492687, 3922.2153919965776, 3926.3521518949055, 3934.960650393459, 5180.507851754077, 5871.181832720233, 5887.264091988502, 5906.852328691817, 5914.1907593894275, 4654.500819140594, 5231.3199284092825, 5742.626729262584, 5762.423757624204, 5766.336871259691, 5588.636738611977, 5659.886802264014, 5667.489925906688, 5689.630174038674, 5703.344115426779, 5330.813421530228, 5436.238926550207, 5522.806701765419, 5541.030319693957, 5561.833145005657, 5263.578833714952, 5370.4629537045985, 5657.632253903483, 5697.7720891165545, 5710.600257981901, 5320.414754377093, 5555.8609935176055, 5558.360788509373, 5575.303087114111, 5592.636478628876], "value": 4150.917588682359, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [722.9891551626727, 757.0650246945025, 776.8485145396646, 801.7835184500185, 812.3825892321836, 15217.010894291878, 15855.682886342294, 15894.205289735524, 15956.12241326725, 15973.760393594106, 11902.642920712393, 12442.19125756825, 14410.639734006654, 14443.927780361084, 14444.638884027905, 672.8418821576913, 698.3923986132047, 704.8625518023962, 837.9539125348103, 870.5125129927261, 850.6639877248483, 856.7215654912147, 864.9697260595881, 865.4677373415336, 865.8701194820765, 921.4010892919622, 922.0594993107027, 922.8292765838307, 923.7829110159141, 925.8009527951489, 708.1749761527814, 731.4258307228799, 916.7662246127262, 917.2111983694018, 918.6095909238613, 655.5312951239887, 672.7914346552535, 675.0920012583068, 675.2302375292171, 680.129434324066, 12845.293508857008, 15014.47449339268, 15251.38994440221, 15334.769978450333, 15338.693226135527, 13381.330933453288, 13424.932875335659, 13445.26051067191, 13611.387487563083, 13727.656808579766, 13215.801762973562, 13250.801237981437, 13290.601281961557, 13487.325633718374, 13949.232792196424, 3913.88258352247, 4493.9101217975785, 4507.0, 4523.344115103291, 4617.399738034068, 114130.42934785273, 114718.42640786743, 114948.0, 116057.83942160639, 116199.0, 10643.627473038407, 11013.0, 11064.114870810316, 11064.391458469861, 11106.278091924, 20918.58162836765, 21387.03758330879, 21395.46511337229, 21416.357509274745, 21478.57042859134, 7389.334959853639, 7648.655810488542, 7687.577183254974, 7744.728934487266, 7745.496542724817, 3747.9437808432895, 4181.829087855405, 4271.444712187395, 4296.398504209413, 4487.461504619467, 3332.3501917125927, 3431.725461963037, 3432.2105915639545, 3436.43298855691, 3438.0889064397957, 611.6391329115754, 614.0758158970721, 618.7865186510646, 629.8960671489153, 633.8288662061218, 632.2821807770281, 639.9888176680834, 658.6311665467354, 660.2623204354243, 670.9798706038729, 9675.70972870815, 9725.270604704534, 9755.804883902283, 9925.2059835213, 10008.449535275484, 610.7282259394527, 640.5132099604241, 642.591954109144, 656.9113169722121, 660.8975608780647, 4597.310403439444, 4600.88497787558, 4771.809127634856, 4775.904481910342, 4782.832600858953, 84541.73187402193, 102304.48847755714, 107802.0, 108266.45866770616, 109986.0, 94852.0, 96250.51874740582, 96666.0333396671, 96706.51646741721, 98245.0, 77833.22166778259, 78495.0, 78870.60564697254, 80767.0, 80942.38115237544, 8931.19619234272, 9257.324777396461, 9281.414766022783, 9324.347295689236, 9459.763505912131, 34589.65410345864, 41357.79321103375, 46677.0, 47700.761496192295, 48256.517434825204, 60486.69756651189, 61814.69092654508, 62277.37722622716, 62863.0, 63346.0, 10768.461576920967, 12991.675208119872, 13544.729105418033, 13709.34132449544, 13796.931015344859, 15168.573028315417, 15183.468578599695, 15290.847091528942, 15604.621668314481, 15790.233245582387, 14117.364718587483, 14251.64370890736, 14445.133292002514, 14445.34995925207, 14564.927175364055, 13719.245441500812, 14010.439582416997, 14078.788818167941, 14512.85487145115, 14787.852121478647, 13681.863181368457, 13908.652283692989, 14349.56951291463, 14562.27188640585, 14676.192809395585, 11052.889471105185, 11488.94255528717, 11569.0, 11639.243449175776, 11710.765784684088, 11048.735903147339, 11242.0, 11273.0, 11417.0866330695, 11480.483378247833], "value": 7522.497545304267, "unit": "score"}], "name": "dromaeo_css", "value": 7374.798017706976, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 115.0, 115.0, 113.0, 114.0, 117.0, 112.0, 113.0, 115.0, 114.0], "value": 114.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 109.0, 111.0, 144.0, 112.0, 133.0, 136.0, 113.0, 128.0, 112.0], "value": 123.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 180.0, 183.0, 184.0, 183.0, 180.0, 181.0, 184.0, 185.0, 181.0], "value": 182.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 60.0, 59.0, 92.0, 60.0, 59.0, 58.0, 59.0, 73.0, 59.0], "value": 66.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 69.0, 86.0, 68.0, 68.0, 68.0, 84.0, 68.0, 69.0, 84.0], "value": 74.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 89.0, 90.0, 83.0, 88.0, 88.0, 91.0, 88.0, 90.0, 91.0], "value": 88.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 99.0, 97.0, 98.0, 106.0, 119.0, 99.0, 98.0, 96.0, 98.0], "value": 100.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 88.0, 87.0, 87.0, 84.0, 84.0, 85.0, 84.0, 84.0], "value": 85.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 53.0, 54.0, 57.0, 55.0, 54.0, 56.0, 56.0, 55.0], "value": 54.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 67.0, 71.0, 78.0, 72.0, 70.0, 69.0, 72.0, 69.0, 70.0], "value": 70.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 87.0, 87.0, 89.0, 87.0, 86.0, 88.0, 89.0, 87.0, 87.0], "value": 87.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 141.0, 136.0, 140.0, 138.0, 140.0, 140.0, 138.0, 182.0, 154.0], "value": 144.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 180.0, 172.0, 173.0, 172.0, 176.0, 174.0, 171.0, 197.0, 224.0], "value": 181.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 67.0, 68.0, 66.0, 63.0, 80.0, 81.0, 83.0, 73.0], "value": 71.4, "unit": "score"}], "name": "kraken", "value": 1446.5000000000002, "alertThreshold": 2.0}]} 06:31:44 INFO - Return code: 0 06:31:44 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:31:44 INFO - # TBPL SUCCESS # 06:31:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:31:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:31:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:31:44 INFO - 2017-01-10 06:31:44.872 screenresolution[1655:9742] starting screenresolution argv=screenresolution get 06:31:44 INFO - 2017-01-10 06:31:44.875 screenresolution[1655:9742] Display 0: 1600x1200x32@60 06:31:44 INFO - 2017-01-10 06:31:44.881 screenresolution[1656:9745] starting screenresolution argv=screenresolution list 06:31:44 INFO - Available Modes on Display 0 06:31:44 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:31:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:31:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:31:44 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:31:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:31:45 INFO - Intel Iris: 06:31:45 INFO - Chipset Model: Intel Iris 06:31:45 INFO - Type: GPU 06:31:45 INFO - Bus: Built-In 06:31:45 INFO - VRAM (Dynamic, Max): 1536 MB 06:31:45 INFO - Vendor: Intel (0x8086) 06:31:45 INFO - Device ID: 0x0a2e 06:31:45 INFO - Revision ID: 0x0009 06:31:45 INFO - Displays: 06:31:45 INFO - Display: 06:31:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:31:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:31:45 INFO - Display Serial Number: 1600x1200 60 06:31:45 INFO - Main Display: Yes 06:31:45 INFO - Mirror: Off 06:31:45 INFO - Online: Yes 06:31:45 INFO - Rotation: Supported 06:31:45 INFO - Return code: 0 06:31:45 INFO - Running post-action listener: _resource_record_post_action 06:31:45 INFO - [mozharness: 2017-01-10 14:31:45.127476Z] Finished run-tests step (success) 06:31:45 INFO - Running post-run listener: _resource_record_post_run 06:31:45 INFO - Total resource usage - Wall time: 1203s; CPU: 54.0%; Read bytes: 37385216; Write bytes: 551178752; Read time: 240; Write time: 1638 06:31:45 INFO - TinderboxPrint: CPU usage
53.9% 06:31:45 INFO - TinderboxPrint: I/O read bytes / time
37,385,216 / 240 06:31:45 INFO - TinderboxPrint: I/O write bytes / time
551,178,752 / 1,638 06:31:45 INFO - TinderboxPrint: CPU idle
2,218.3 (46.1%) 06:31:45 INFO - TinderboxPrint: CPU system
961.9 (20.0%) 06:31:45 INFO - TinderboxPrint: CPU user
1,633.4 (33.9%) 06:31:45 INFO - TinderboxPrint: Swap in / out
287,395,840 / 0 06:31:45 INFO - install - Wall time: 23s; CPU: 54.0%; Read bytes: 235911680; Write bytes: 231090176; Read time: 17985; Write time: 479 06:31:45 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 36447232; Write bytes: 297343488; Read time: 226; Write time: 1110 06:31:45 INFO - Running post-run listener: _upload_blobber_files 06:31:45 INFO - Blob upload gear active. 06:31:45 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:31:45 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 06:31:45 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'] 06:31:45 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 06:31:45 INFO - (blobuploader) - INFO - Open directory for files ... 06:31:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:31:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:31:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:31:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:31:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:31:47 INFO - (blobuploader) - INFO - Done attempting. 06:31:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:31:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:31:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:31:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:31:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:31:48 INFO - (blobuploader) - INFO - Done attempting. 06:31:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 06:31:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:31:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:31:49 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 06:31:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:31:49 INFO - (blobuploader) - INFO - Done attempting. 06:31:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:31:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:31:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:31:52 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:31:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:31:52 INFO - (blobuploader) - INFO - Done attempting. 06:31:52 INFO - (blobuploader) - INFO - Iteration through files over. 06:31:52 INFO - Return code: 0 06:31:52 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:31:52 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:31:52 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/4b7cad41796aa947e9e7f1cef2b5318ccb75db0621188f82c596b2d2f34c85b8815d61229e81508be82db7156b4d9376bf644c8e491b990b751e5bb452be4bb2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/05bc26dc478df7ce229e769413cb65dfe71ec4c82dc961226abf9059f1fa6cd7e6269312e2d5ccd2e4df977f37763a1e7887b06517aec1bc0fe52c572304fa28", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/22c4590916693cd14ea73ee5f3e2403fe52585f115fc0aa7429c2de7f2f64a78da8c046b4364d1306dd948dc3feb1239cd9cda1a54d757dceb21647cc2c5a378", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/01e54963b46d3516d7047cb5a2b57e740275b914cc60a501de2b1f396491c2882879a15fc4fe27a9e452c3db848dfeb9fa1ee331e6de06e4f991b45fe82c7907"} 06:31:52 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:31:52 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:31:52 INFO - Contents: 06:31:52 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/4b7cad41796aa947e9e7f1cef2b5318ccb75db0621188f82c596b2d2f34c85b8815d61229e81508be82db7156b4d9376bf644c8e491b990b751e5bb452be4bb2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/05bc26dc478df7ce229e769413cb65dfe71ec4c82dc961226abf9059f1fa6cd7e6269312e2d5ccd2e4df977f37763a1e7887b06517aec1bc0fe52c572304fa28", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/22c4590916693cd14ea73ee5f3e2403fe52585f115fc0aa7429c2de7f2f64a78da8c046b4364d1306dd948dc3feb1239cd9cda1a54d757dceb21647cc2c5a378", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/01e54963b46d3516d7047cb5a2b57e740275b914cc60a501de2b1f396491c2882879a15fc4fe27a9e452c3db848dfeb9fa1ee331e6de06e4f991b45fe82c7907"} 06:31:52 INFO - Running post-run listener: copy_logs_to_upload_dir 06:31:52 INFO - Copying logs to upload dir... 06:31:52 INFO - mkdir: /builds/slave/test/build/upload/logs 06:31:52 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.260150 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2017-01-10 06:31:52.430810) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-10 06:31:52.434318) ========= 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.P5cwJiLYdc/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.xXCg2SJib0/Listeners TMPDIR=/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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/4b7cad41796aa947e9e7f1cef2b5318ccb75db0621188f82c596b2d2f34c85b8815d61229e81508be82db7156b4d9376bf644c8e491b990b751e5bb452be4bb2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/05bc26dc478df7ce229e769413cb65dfe71ec4c82dc961226abf9059f1fa6cd7e6269312e2d5ccd2e4df977f37763a1e7887b06517aec1bc0fe52c572304fa28", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/22c4590916693cd14ea73ee5f3e2403fe52585f115fc0aa7429c2de7f2f64a78da8c046b4364d1306dd948dc3feb1239cd9cda1a54d757dceb21647cc2c5a378", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/01e54963b46d3516d7047cb5a2b57e740275b914cc60a501de2b1f396491c2882879a15fc4fe27a9e452c3db848dfeb9fa1ee331e6de06e4f991b45fe82c7907"} build_url:https://queue.taskcluster.net/v1/task/AwHs_ZPsR2CI3R0FAaOaLA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011157 build_url: 'https://queue.taskcluster.net/v1/task/AwHs_ZPsR2CI3R0FAaOaLA/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/4b7cad41796aa947e9e7f1cef2b5318ccb75db0621188f82c596b2d2f34c85b8815d61229e81508be82db7156b4d9376bf644c8e491b990b751e5bb452be4bb2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/05bc26dc478df7ce229e769413cb65dfe71ec4c82dc961226abf9059f1fa6cd7e6269312e2d5ccd2e4df977f37763a1e7887b06517aec1bc0fe52c572304fa28", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/22c4590916693cd14ea73ee5f3e2403fe52585f115fc0aa7429c2de7f2f64a78da8c046b4364d1306dd948dc3feb1239cd9cda1a54d757dceb21647cc2c5a378", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/01e54963b46d3516d7047cb5a2b57e740275b914cc60a501de2b1f396491c2882879a15fc4fe27a9e452c3db848dfeb9fa1ee331e6de06e4f991b45fe82c7907"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-10 06:31:52.469496) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-10 06:31:52.469881) ========= 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.P5cwJiLYdc/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.xXCg2SJib0/Listeners TMPDIR=/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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.005352 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-10 06:31:52.518969) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-10 06:31:52.519290) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-10 06:31:52.519640) ========= ========= Total master_lag: 0.17 =========