builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0030 starttime: 1482904421.54 results: success (0) buildid: 20161227203236 builduid: 267bb6f0ef5748d3aff1686163db8dc6 revision: a0866731bbe884fffce6f007b851f7ac453013fa ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-27 21:53:41.540465) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-27 21:53:41.540901) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-27 21:53:41.541183) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-27 21:53:41.608783) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-27 21:53:41.609117) ========= 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.y0JEsVsY8E/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.7ExKa1K88E/Listeners TMPDIR=/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-12-27 21:53:41-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 164M=0s 2016-12-27 21:53:42 (164 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.695416 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-27 21:53:42.331580) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-27 21:53:42.331918) ========= 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.y0JEsVsY8E/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.7ExKa1K88E/Listeners TMPDIR=/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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.026584 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-27 21:53:42.381324) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-27 21:53:42.381673) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev a0866731bbe884fffce6f007b851f7ac453013fa --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev a0866731bbe884fffce6f007b851f7ac453013fa --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.y0JEsVsY8E/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.7ExKa1K88E/Listeners TMPDIR=/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-12-27 21:53:42,474 truncating revision to first 12 chars 2016-12-27 21:53:42,474 Setting DEBUG logging. 2016-12-27 21:53:42,474 attempt 1/10 2016-12-27 21:53:42,474 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/a0866731bbe8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-27 21:53:43,426 unpacking tar archive at: mozilla-central-a0866731bbe8/testing/mozharness/ program finished with exit code 0 elapsedTime=1.227259 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-27 21:53:43.638408) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-27 21:53:43.638696) ========= script_repo_revision: a0866731bbe884fffce6f007b851f7ac453013fa ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-27 21:53:43.639103) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-27 21:53:43.639384) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-27 21:53:43.658907) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2016-12-27 21:53:43.659206) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.y0JEsVsY8E/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.7ExKa1K88E/Listeners TMPDIR=/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 21:53:43 INFO - MultiFileLogger online at 20161227 21:53:43 in /builds/slave/test 21:53:43 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 21:53:43 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:53:43 INFO - {'append_to_log': False, 21:53:43 INFO - 'base_work_dir': '/builds/slave/test', 21:53:43 INFO - 'blob_upload_branch': 'Firefox', 21:53:43 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:53:43 INFO - 'branch': 'Firefox', 21:53:43 INFO - 'buildbot_json_path': 'buildprops.json', 21:53:43 INFO - 'config_files': ('talos/mac_config.py',), 21:53:43 INFO - 'default_actions': ('clobber', 21:53:43 INFO - 'read-buildbot-config', 21:53:43 INFO - 'download-and-extract', 21:53:43 INFO - 'populate-webroot', 21:53:43 INFO - 'create-virtualenv', 21:53:43 INFO - 'install', 21:53:43 INFO - 'run-tests'), 21:53:43 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:53:43 INFO - 'download_minidump_stackwalk': True, 21:53:43 INFO - 'download_symbols': 'ondemand', 21:53:43 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:53:43 INFO - 'tooltool.py': '/tools/tooltool.py', 21:53:43 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:53:43 INFO - '/tools/misc-python/virtualenv.py')}, 21:53:43 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:53:43 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:53:43 INFO - 'installer_path': 'installer.exe', 21:53:43 INFO - 'log_level': 'info', 21:53:43 INFO - 'log_name': 'talos', 21:53:43 INFO - 'log_to_console': True, 21:53:43 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 21:53:43 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 21:53:43 INFO - 'opt_config_files': (), 21:53:43 INFO - 'pip_index': False, 21:53:43 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:53:43 INFO - 'cmd': ('bash', 21:53:43 INFO - '-c', 21:53:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:53:43 INFO - 'enabled': True, 21:53:43 INFO - 'halt_on_failure': False, 21:53:43 INFO - 'name': 'check_screen_resolution'},), 21:53:43 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:53:43 INFO - 'cmd': ('bash', 21:53:43 INFO - '-c', 21:53:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:53:43 INFO - 'enabled': True, 21:53:43 INFO - 'halt_on_failure': False, 21:53:43 INFO - 'name': 'check_screen_resolution'},), 21:53:43 INFO - 'run_cmd_checks_enabled': True, 21:53:43 INFO - 'sps_profile': False, 21:53:43 INFO - 'sps_profile_interval': 0, 21:53:43 INFO - 'suite': 'dromaeojs', 21:53:43 INFO - 'system_bits': '32', 21:53:43 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:53:43 INFO - 'title': 't-yosemite-r7-0030', 21:53:43 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:53:43 INFO - 'use_talos_json': True, 21:53:43 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:53:43 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:53:43 INFO - 'work_dir': 'build'} 21:53:43 INFO - [mozharness: 2016-12-28 05:53:43.864511Z] Running clobber step. 21:53:43 INFO - Running pre-action listener: _resource_record_pre_action 21:53:43 INFO - Running main action method: clobber 21:53:43 INFO - rmtree: /builds/slave/test/build 21:53:43 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:53:46 INFO - Running post-action listener: _resource_record_post_action 21:53:46 INFO - [mozharness: 2016-12-28 05:53:46.329010Z] Finished clobber step (success) 21:53:46 INFO - [mozharness: 2016-12-28 05:53:46.329142Z] Running read-buildbot-config step. 21:53:46 INFO - Running pre-action listener: _resource_record_pre_action 21:53:46 INFO - Running main action method: read_buildbot_config 21:53:46 INFO - Using buildbot properties: 21:53:46 INFO - { 21:53:46 INFO - "project": "", 21:53:46 INFO - "product": "firefox", 21:53:46 INFO - "script_repo_revision": "production", 21:53:46 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 21:53:46 INFO - "repository": "", 21:53:46 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 21:53:46 INFO - "buildid": "20161227203236", 21:53:46 INFO - "pgo_build": "False", 21:53:46 INFO - "basedir": "/builds/slave/test", 21:53:46 INFO - "buildnumber": 43, 21:53:46 INFO - "slavename": "t-yosemite-r7-0030", 21:53:46 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 21:53:46 INFO - "platform": "yosemite_r7", 21:53:46 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 21:53:46 INFO - "branch": "mozilla-central", 21:53:46 INFO - "repo_path": "mozilla-central", 21:53:46 INFO - "revision": "a0866731bbe884fffce6f007b851f7ac453013fa", 21:53:46 INFO - "stage_platform": "macosx64", 21:53:46 INFO - "builduid": "267bb6f0ef5748d3aff1686163db8dc6", 21:53:46 INFO - "slavebuilddir": "test" 21:53:46 INFO - } 21:53:46 INFO - Found installer url https://queue.taskcluster.net/v1/task/KAwfhjqJQ2KzE0s2BaWa_g/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 21:53:46 INFO - Running post-action listener: _resource_record_post_action 21:53:46 INFO - [mozharness: 2016-12-28 05:53:46.332421Z] Finished read-buildbot-config step (success) 21:53:46 INFO - [mozharness: 2016-12-28 05:53:46.332556Z] Running download-and-extract step. 21:53:46 INFO - Running pre-action listener: _resource_record_pre_action 21:53:46 INFO - Running main action method: download_and_extract 21:53:46 INFO - mkdir: /builds/slave/test/build/tests 21:53:46 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')]} 21:53:46 INFO - trying https://queue.taskcluster.net/v1/task/KAwfhjqJQ2KzE0s2BaWa_g/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 21:53:46 INFO - Downloading https://queue.taskcluster.net/v1/task/KAwfhjqJQ2KzE0s2BaWa_g/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 21:53:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KAwfhjqJQ2KzE0s2BaWa_g/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 21:53:47 INFO - Downloaded 1199 bytes. 21:53:47 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 21:53:47 INFO - Using the following test package requirements: 21:53:47 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 21:53:47 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 21:53:47 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 21:53:47 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 21:53:47 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 21:53:47 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 21:53:47 INFO - u'jsshell-mac.zip'], 21:53:47 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 21:53:47 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 21:53:47 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 21:53:47 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 21:53:47 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 21:53:47 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 21:53:47 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 21:53:47 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 21:53:47 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 21:53:47 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 21:53:47 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 21:53:47 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 21:53:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KAwfhjqJQ2KzE0s2BaWa_g/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 21:53:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KAwfhjqJQ2KzE0s2BaWa_g/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 21:53:47 INFO - Fetch https://queue.taskcluster.net/v1/task/KAwfhjqJQ2KzE0s2BaWa_g/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 21:53:48 INFO - Expected file size: 16294453 21:53:48 INFO - Obtained file size: 16294453 21:53:55 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 21:53:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KAwfhjqJQ2KzE0s2BaWa_g/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 21:53:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KAwfhjqJQ2KzE0s2BaWa_g/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 21:53:55 INFO - Fetch https://queue.taskcluster.net/v1/task/KAwfhjqJQ2KzE0s2BaWa_g/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 21:53:56 INFO - Expected file size: 16294453 21:53:56 INFO - Obtained file size: 16294453 21:54:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KAwfhjqJQ2KzE0s2BaWa_g/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 21:54:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KAwfhjqJQ2KzE0s2BaWa_g/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 21:54:02 INFO - Fetch https://queue.taskcluster.net/v1/task/KAwfhjqJQ2KzE0s2BaWa_g/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 21:54:03 INFO - Expected file size: 14409776 21:54:03 INFO - Obtained file size: 14409776 21:54:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:54:03 INFO - trying https://queue.taskcluster.net/v1/task/KAwfhjqJQ2KzE0s2BaWa_g/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 21:54:03 INFO - Downloading https://queue.taskcluster.net/v1/task/KAwfhjqJQ2KzE0s2BaWa_g/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 21:54:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KAwfhjqJQ2KzE0s2BaWa_g/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 21:54:05 INFO - Downloaded 69636101 bytes. 21:54:05 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KAwfhjqJQ2KzE0s2BaWa_g/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 21:54:05 INFO - mkdir: /builds/slave/test/properties 21:54:05 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:54:05 INFO - Writing to file /builds/slave/test/properties/build_url 21:54:05 INFO - Contents: 21:54:05 INFO - build_url:https://queue.taskcluster.net/v1/task/KAwfhjqJQ2KzE0s2BaWa_g/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 21:54:06 INFO - Running post-action listener: _resource_record_post_action 21:54:06 INFO - Running post-action listener: set_extra_try_arguments 21:54:06 INFO - [mozharness: 2016-12-28 05:54:06.453207Z] Finished download-and-extract step (success) 21:54:06 INFO - [mozharness: 2016-12-28 05:54:06.453327Z] Running populate-webroot step. 21:54:06 INFO - Running pre-action listener: _resource_record_pre_action 21:54:06 INFO - Running main action method: populate_webroot 21:54:06 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:54:06 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:54:06 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:54:06 INFO - '--tppagecycles', 21:54:06 INFO - '7'], 21:54:06 INFO - 'tests': ['tsvgm']}}, 21:54:06 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:54:06 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:54:06 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:54:06 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:54:06 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:54:06 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:54:06 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 21:54:06 INFO - 'talos_options': ['--disable-e10s'], 21:54:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:54:06 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 21:54:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:54:06 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 21:54:06 INFO - 'talos_options': ['--disable-e10s'], 21:54:06 INFO - 'tests': ['damp', 'tps']}, 21:54:06 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 21:54:06 INFO - 'tests': ['damp', 'tps']}, 21:54:06 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:54:06 INFO - 'tests': ['dromaeo_dom']}, 21:54:06 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:54:06 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:54:06 INFO - 'tests': ['basic_compositor_video']}, 21:54:06 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 21:54:06 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:54:06 INFO - 'tests': ['a11yr', 21:54:06 INFO - 'ts_paint', 21:54:06 INFO - 'tpaint', 21:54:06 INFO - 'sessionrestore', 21:54:06 INFO - 'sessionrestore_no_auto_restore', 21:54:06 INFO - 'tabpaint']}, 21:54:06 INFO - 'other-e10s': {'tests': ['a11yr', 21:54:06 INFO - 'ts_paint', 21:54:06 INFO - 'tpaint', 21:54:06 INFO - 'sessionrestore', 21:54:06 INFO - 'sessionrestore_no_auto_restore', 21:54:06 INFO - 'tabpaint']}, 21:54:06 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:54:06 INFO - 'ts_paint', 21:54:06 INFO - 'tpaint', 21:54:06 INFO - 'sessionrestore', 21:54:06 INFO - 'sessionrestore_no_auto_restore', 21:54:06 INFO - 'tabpaint']}, 21:54:06 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:54:06 INFO - 'ts_paint', 21:54:06 INFO - 'tpaint', 21:54:06 INFO - 'sessionrestore', 21:54:06 INFO - 'sessionrestore_no_auto_restore', 21:54:06 INFO - 'tabpaint']}, 21:54:06 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:54:06 INFO - 'tests': ['a11yr', 21:54:06 INFO - 'ts_paint', 21:54:06 INFO - 'tpaint', 21:54:06 INFO - 'sessionrestore', 21:54:06 INFO - 'sessionrestore_no_auto_restore', 21:54:06 INFO - 'tabpaint']}, 21:54:06 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:54:06 INFO - 'tests': ['a11yr', 21:54:06 INFO - 'ts_paint', 21:54:06 INFO - 'tpaint', 21:54:06 INFO - 'sessionrestore', 21:54:06 INFO - 'sessionrestore_no_auto_restore', 21:54:06 INFO - 'tabpaint']}, 21:54:06 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:54:06 INFO - 'tests': ['tsvgx', 21:54:06 INFO - 'tsvgr_opacity', 21:54:06 INFO - 'tart', 21:54:06 INFO - 'tscrollx', 21:54:06 INFO - 'cart', 21:54:06 INFO - 'tsvg_static']}, 21:54:06 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:54:06 INFO - 'tsvgr_opacity', 21:54:06 INFO - 'tart', 21:54:06 INFO - 'tscrollx', 21:54:06 INFO - 'cart', 21:54:06 INFO - 'tsvg_static']}, 21:54:06 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 21:54:06 INFO - 'talos_options': ['--disable-e10s'], 21:54:06 INFO - 'tests': ['tp5o']}, 21:54:06 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 21:54:06 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 21:54:06 INFO - 'talos_options': ['--disable-e10s', 21:54:06 INFO - '--xperf_path', 21:54:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:54:06 INFO - 'tests': ['tp5n']}, 21:54:06 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 21:54:06 INFO - 'talos_options': ['--xperf_path', 21:54:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:54:06 INFO - 'tests': ['tp5n']}}, 21:54:06 INFO - 'talos.zip': {'path': '', 21:54:06 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:54:06 INFO - Running post-action listener: _resource_record_post_action 21:54:06 INFO - [mozharness: 2016-12-28 05:54:06.471770Z] Finished populate-webroot step (success) 21:54:06 INFO - [mozharness: 2016-12-28 05:54:06.471881Z] Running create-virtualenv step. 21:54:06 INFO - Running pre-action listener: _resource_record_pre_action 21:54:06 INFO - Running main action method: create_virtualenv 21:54:06 INFO - Creating virtualenv /builds/slave/test/build/venv 21:54:06 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 21:54:06 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:54:06 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:54:06 INFO - Using real prefix '/tools/python27' 21:54:06 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:54:07 INFO - Installing distribute.............................................................................................................................................................................................done. 21:54:10 INFO - Installing pip.................done. 21:54:10 INFO - Return code: 0 21:54:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 21:54:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 21:54:10 INFO - Reading from file tmpfile_stdout 21:54:10 INFO - Output received: 21:54:10 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) 21:54:10 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:54:10 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')]} 21:54:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:54:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:54:10 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')]} 21:54:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:54:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:54:10 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 0x10cbe8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbeb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9adbd71a90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9c6d30>, '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 0x10c8eddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c9bbbe8>, '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.y0JEsVsY8E/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.7ExKa1K88E/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 21:54:10 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 21:54:10 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 21:54:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y0JEsVsY8E/Render', 21:54:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:54:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:54:10 INFO - 'HOME': '/Users/cltbld', 21:54:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:54:10 INFO - 'LOGNAME': 'cltbld', 21:54:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:54:10 INFO - 'MOZ_NO_REMOTE': '1', 21:54:10 INFO - 'NO_EM_RESTART': '1', 21:54:10 INFO - 'PAGER': '/bin/cat', 21:54:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:54:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:54:10 INFO - 'PWD': '/builds/slave/test', 21:54:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:54:10 INFO - 'SHELL': '/bin/bash', 21:54:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7ExKa1K88E/Listeners', 21:54:10 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 21:54:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:54:10 INFO - 'USER': 'cltbld', 21:54:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:54:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:54:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:54:10 INFO - 'XPC_FLAGS': '0x0', 21:54:10 INFO - 'XPC_SERVICE_NAME': '0', 21:54:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:54:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:54:10 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 21:54:10 INFO - Cleaning up... 21:54:10 INFO - Return code: 0 21:54:10 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:54:10 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')]} 21:54:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:54:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:54:10 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')]} 21:54:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:54:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:54:10 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 0x10cbe8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbeb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9adbd71a90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9c6d30>, '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 0x10c8eddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c9bbbe8>, '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.y0JEsVsY8E/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.7ExKa1K88E/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 21:54:10 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 21:54:10 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 21:54:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y0JEsVsY8E/Render', 21:54:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:54:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:54:10 INFO - 'HOME': '/Users/cltbld', 21:54:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:54:10 INFO - 'LOGNAME': 'cltbld', 21:54:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:54:10 INFO - 'MOZ_NO_REMOTE': '1', 21:54:10 INFO - 'NO_EM_RESTART': '1', 21:54:10 INFO - 'PAGER': '/bin/cat', 21:54:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:54:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:54:10 INFO - 'PWD': '/builds/slave/test', 21:54:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:54:10 INFO - 'SHELL': '/bin/bash', 21:54:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7ExKa1K88E/Listeners', 21:54:10 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 21:54:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:54:10 INFO - 'USER': 'cltbld', 21:54:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:54:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:54:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:54:10 INFO - 'XPC_FLAGS': '0x0', 21:54:10 INFO - 'XPC_SERVICE_NAME': '0', 21:54:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:54:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:54:10 INFO - Downloading/unpacking psutil>=3.1.1 21:54:10 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 21:54:10 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 21:54:10 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 21:54:10 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 21:54:10 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 21:54:10 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 21:54:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:54:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:54:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:54:14 INFO - Installing collected packages: psutil 21:54:14 INFO - Running setup.py install for psutil 21:54:14 INFO - building 'psutil._psutil_osx' extension 21:54:14 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 21:54:15 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 21:54:15 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 21:54:15 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 21:54:15 INFO - building 'psutil._psutil_posix' extension 21:54:15 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 21:54:15 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 21:54:15 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 21:54:15 INFO - ^ 21:54:15 INFO - 1 warning generated. 21:54:15 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 21:54:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:54:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:54:16 INFO - Successfully installed psutil 21:54:16 INFO - Cleaning up... 21:54:16 INFO - Return code: 0 21:54:16 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:54:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:54:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:54:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:54:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:54:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:54:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:54:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cbe8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbeb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9adbd71a90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9c6d30>, '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 0x10c8eddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c9bbbe8>, '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.y0JEsVsY8E/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.7ExKa1K88E/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 21:54:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 21:54:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 21:54:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y0JEsVsY8E/Render', 21:54:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:54:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:54:16 INFO - 'HOME': '/Users/cltbld', 21:54:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:54:16 INFO - 'LOGNAME': 'cltbld', 21:54:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:54:16 INFO - 'MOZ_NO_REMOTE': '1', 21:54:16 INFO - 'NO_EM_RESTART': '1', 21:54:16 INFO - 'PAGER': '/bin/cat', 21:54:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:54:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:54:16 INFO - 'PWD': '/builds/slave/test', 21:54:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:54:16 INFO - 'SHELL': '/bin/bash', 21:54:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7ExKa1K88E/Listeners', 21:54:16 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 21:54:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:54:16 INFO - 'USER': 'cltbld', 21:54:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:54:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:54:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:54:16 INFO - 'XPC_FLAGS': '0x0', 21:54:16 INFO - 'XPC_SERVICE_NAME': '0', 21:54:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:54:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:54:16 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:54:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:54:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:54:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:54:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:54:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:54:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:54:18 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:54:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:54:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:54:19 INFO - Installing collected packages: mozsystemmonitor 21:54:19 INFO - Running setup.py install for mozsystemmonitor 21:54:19 INFO - Successfully installed mozsystemmonitor 21:54:19 INFO - Cleaning up... 21:54:19 INFO - Return code: 0 21:54:19 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:54:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:54:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:54:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:54:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:54:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:54:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:54:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cbe8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbeb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9adbd71a90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9c6d30>, '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 0x10c8eddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c9bbbe8>, '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.y0JEsVsY8E/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.7ExKa1K88E/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 21:54:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 21:54:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 21:54:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y0JEsVsY8E/Render', 21:54:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:54:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:54:19 INFO - 'HOME': '/Users/cltbld', 21:54:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:54:19 INFO - 'LOGNAME': 'cltbld', 21:54:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:54:19 INFO - 'MOZ_NO_REMOTE': '1', 21:54:19 INFO - 'NO_EM_RESTART': '1', 21:54:19 INFO - 'PAGER': '/bin/cat', 21:54:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:54:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:54:19 INFO - 'PWD': '/builds/slave/test', 21:54:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:54:19 INFO - 'SHELL': '/bin/bash', 21:54:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7ExKa1K88E/Listeners', 21:54:19 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 21:54:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:54:19 INFO - 'USER': 'cltbld', 21:54:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:54:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:54:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:54:19 INFO - 'XPC_FLAGS': '0x0', 21:54:19 INFO - 'XPC_SERVICE_NAME': '0', 21:54:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:54:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:54:19 INFO - Downloading/unpacking jsonschema==2.5.1 21:54:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:54:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:54:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:54:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:54:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:54:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:54:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:54:22 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:54:22 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 21:54:22 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 21:54:22 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 21:54:22 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 21:54:22 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 21:54:22 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 21:54:22 INFO - Downloading functools32-3.2.3-2.tar.gz 21:54:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:54:22 INFO - warning: no files found matching '*.txt' 21:54:22 INFO - no previously-included directories found matching 'build' 21:54:22 INFO - no previously-included directories found matching 'dist' 21:54:22 INFO - no previously-included directories found matching '.git*' 21:54:22 INFO - Installing collected packages: jsonschema, functools32 21:54:22 INFO - Running setup.py install for functools32 21:54:23 INFO - warning: no files found matching '*.txt' 21:54:23 INFO - no previously-included directories found matching 'build' 21:54:23 INFO - no previously-included directories found matching 'dist' 21:54:23 INFO - no previously-included directories found matching '.git*' 21:54:23 INFO - Successfully installed jsonschema functools32 21:54:23 INFO - Cleaning up... 21:54:23 INFO - Return code: 0 21:54:23 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:54:23 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')]} 21:54:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:54:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:54:23 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')]} 21:54:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:54:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:54:23 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 0x10cbe8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbeb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9adbd71a90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9c6d30>, '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 0x10c8eddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c9bbbe8>, '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.y0JEsVsY8E/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.7ExKa1K88E/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 21:54:23 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 21:54:23 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 21:54:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y0JEsVsY8E/Render', 21:54:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:54:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:54:23 INFO - 'HOME': '/Users/cltbld', 21:54:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:54:23 INFO - 'LOGNAME': 'cltbld', 21:54:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:54:23 INFO - 'MOZ_NO_REMOTE': '1', 21:54:23 INFO - 'NO_EM_RESTART': '1', 21:54:23 INFO - 'PAGER': '/bin/cat', 21:54:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:54:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:54:23 INFO - 'PWD': '/builds/slave/test', 21:54:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:54:23 INFO - 'SHELL': '/bin/bash', 21:54:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7ExKa1K88E/Listeners', 21:54:23 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 21:54:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:54:23 INFO - 'USER': 'cltbld', 21:54:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:54:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:54:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:54:23 INFO - 'XPC_FLAGS': '0x0', 21:54:23 INFO - 'XPC_SERVICE_NAME': '0', 21:54:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:54:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:54:23 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:54:23 INFO - Cleaning up... 21:54:23 INFO - Return code: 0 21:54:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:54:23 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')]} 21:54:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:54:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:54:23 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')]} 21:54:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:54:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:54:23 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 0x10cbe8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbeb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9adbd71a90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9c6d30>, '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 0x10c8eddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c9bbbe8>, '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.y0JEsVsY8E/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.7ExKa1K88E/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 21:54:23 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 21:54:23 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 21:54:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y0JEsVsY8E/Render', 21:54:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:54:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:54:23 INFO - 'HOME': '/Users/cltbld', 21:54:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:54:23 INFO - 'LOGNAME': 'cltbld', 21:54:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:54:23 INFO - 'MOZ_NO_REMOTE': '1', 21:54:23 INFO - 'NO_EM_RESTART': '1', 21:54:23 INFO - 'PAGER': '/bin/cat', 21:54:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:54:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:54:23 INFO - 'PWD': '/builds/slave/test', 21:54:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:54:23 INFO - 'SHELL': '/bin/bash', 21:54:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7ExKa1K88E/Listeners', 21:54:23 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 21:54:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:54:23 INFO - 'USER': 'cltbld', 21:54:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:54:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:54:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:54:23 INFO - 'XPC_FLAGS': '0x0', 21:54:23 INFO - 'XPC_SERVICE_NAME': '0', 21:54:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:54:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:54:23 INFO - Downloading/unpacking blobuploader==1.2.4 21:54: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 21:54: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 21:54: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 21:54: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 21:54: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 21:54: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 21:54:26 INFO - Downloading blobuploader-1.2.4.tar.gz 21:54:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:54:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:54:26 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 21:54:26 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 21:54:26 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 21:54:26 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 21:54:26 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 21:54:26 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 21:54:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:54:26 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:54:26 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 21:54:26 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 21:54:26 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 21:54:26 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 21:54:26 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 21:54:26 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 21:54:27 INFO - Downloading docopt-0.6.1.tar.gz 21:54:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:54:27 INFO - Installing collected packages: blobuploader, requests, docopt 21:54:27 INFO - Running setup.py install for blobuploader 21:54:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:54:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:54:27 INFO - Running setup.py install for requests 21:54:28 INFO - Running setup.py install for docopt 21:54:28 INFO - Successfully installed blobuploader requests docopt 21:54:28 INFO - Cleaning up... 21:54:28 INFO - Return code: 0 21:54:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:54:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:54:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:54:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:54:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:54:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:54:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:54:28 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 0x10cbe8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbeb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9adbd71a90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9c6d30>, '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 0x10c8eddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c9bbbe8>, '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.y0JEsVsY8E/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.7ExKa1K88E/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 21:54:28 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 21:54:28 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 21:54:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y0JEsVsY8E/Render', 21:54:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:54:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:54:28 INFO - 'HOME': '/Users/cltbld', 21:54:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:54:28 INFO - 'LOGNAME': 'cltbld', 21:54:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:54:28 INFO - 'MOZ_NO_REMOTE': '1', 21:54:28 INFO - 'NO_EM_RESTART': '1', 21:54:28 INFO - 'PAGER': '/bin/cat', 21:54:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:54:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:54:28 INFO - 'PWD': '/builds/slave/test', 21:54:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:54:28 INFO - 'SHELL': '/bin/bash', 21:54:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7ExKa1K88E/Listeners', 21:54:28 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 21:54:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:54:28 INFO - 'USER': 'cltbld', 21:54:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:54:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:54:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:54:28 INFO - 'XPC_FLAGS': '0x0', 21:54:28 INFO - 'XPC_SERVICE_NAME': '0', 21:54:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:54:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:54:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:54:28 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-QC8tp0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:54:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:54:28 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-qepUeo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:54:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:54:28 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-VNWUV1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:54:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:54:29 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-3dRt8P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:54:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:54:29 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-ARy9ti-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:54:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:54:29 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-CugU0P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:54:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:54:29 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-rToYtM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:54:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:54:29 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-Rlgz5U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:54:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:54:29 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-KBA_SB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:54:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:54:29 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-owNODm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:54:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:54:29 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-DamAZv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:54:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:54:29 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-l9I0L2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:54:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:54:29 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-b49xAk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:54:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:54:30 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-uPJLtg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:54:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:54:30 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-5OFoxE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:54:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:54:30 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-ngHrOo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:54:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:54:30 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-15xLsr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:54:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:54:30 INFO - Running setup.py install for manifestparser 21:54:30 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:54:30 INFO - Running setup.py install for mozcrash 21:54:30 INFO - Running setup.py install for mozdebug 21:54:30 INFO - Running setup.py install for mozdevice 21:54:31 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:54:31 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:54:31 INFO - Running setup.py install for mozfile 21:54:31 INFO - Running setup.py install for mozhttpd 21:54:31 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:54:31 INFO - Running setup.py install for mozinfo 21:54:31 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:54:31 INFO - Running setup.py install for mozInstall 21:54:31 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:54:31 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:54:31 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:54:31 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:54:31 INFO - Running setup.py install for mozleak 21:54:31 INFO - Running setup.py install for mozlog 21:54:31 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:54:31 INFO - Running setup.py install for moznetwork 21:54:32 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:54:32 INFO - Running setup.py install for mozprocess 21:54:32 INFO - Running setup.py install for mozprofile 21:54:32 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:54:32 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:54:32 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:54:32 INFO - Running setup.py install for mozrunner 21:54:32 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:54:32 INFO - Running setup.py install for mozscreenshot 21:54:32 INFO - Running setup.py install for moztest 21:54:32 INFO - Running setup.py install for mozversion 21:54:32 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:54:33 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:54:33 INFO - Cleaning up... 21:54:33 INFO - Return code: 0 21:54:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:54:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:54:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:54:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:54:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:54:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:54:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:54:33 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 0x10cbe8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbeb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9adbd71a90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9c6d30>, '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 0x10c8eddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c9bbbe8>, '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.y0JEsVsY8E/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.7ExKa1K88E/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 21:54:33 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 21:54:33 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 21:54:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y0JEsVsY8E/Render', 21:54:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:54:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:54:33 INFO - 'HOME': '/Users/cltbld', 21:54:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:54:33 INFO - 'LOGNAME': 'cltbld', 21:54:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:54:33 INFO - 'MOZ_NO_REMOTE': '1', 21:54:33 INFO - 'NO_EM_RESTART': '1', 21:54:33 INFO - 'PAGER': '/bin/cat', 21:54:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:54:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:54:33 INFO - 'PWD': '/builds/slave/test', 21:54:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:54:33 INFO - 'SHELL': '/bin/bash', 21:54:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7ExKa1K88E/Listeners', 21:54:33 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 21:54:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:54:33 INFO - 'USER': 'cltbld', 21:54:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:54:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:54:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:54:33 INFO - 'XPC_FLAGS': '0x0', 21:54:33 INFO - 'XPC_SERVICE_NAME': '0', 21:54:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:54:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:54:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:54:33 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-QwzNft-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:54:33 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)) 21:54:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:54:33 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-06sHzT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:54:33 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)) 21:54:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:54:33 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-towof0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:54:33 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)) 21:54:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:54:33 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-KhUdBK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:54:33 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)) 21:54:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:54:33 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-azolLd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:54:33 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)) 21:54:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:54:33 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-Qi1usY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:54:33 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)) 21:54:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:54:33 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-l5556O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:54:34 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)) 21:54:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:54:34 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-ULQChD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:54:34 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)) 21:54:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:54:34 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-AowPiy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:54:34 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)) 21:54:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:54:34 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-4qOQtu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:54:34 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)) 21:54:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:54:34 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-nWpgJZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:54:34 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)) 21:54:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:54:34 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-0R3cuj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:54:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 21:54:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:54:34 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-GPgzUV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:54:34 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)) 21:54:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:54:34 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-Ylx6I8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:54:34 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)) 21:54:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:54:34 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-gDbSGi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:54:34 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)) 21:54:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:54:34 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-WrNKPw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:54:34 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 21:54:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:54:34 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-CWyewH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:54:35 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)) 21:54:35 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)) 21:54:35 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)) 21:54:35 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)) 21:54:35 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)) 21:54:35 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)) 21:54:35 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:54:35 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 21:54:35 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 21:54:35 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 21:54:35 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 21:54:35 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 21:54:35 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 21:54:37 INFO - Downloading blessings-1.6.tar.gz 21:54:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:54:37 INFO - Installing collected packages: blessings 21:54:37 INFO - Running setup.py install for blessings 21:54:37 INFO - Successfully installed blessings 21:54:37 INFO - Cleaning up... 21:54:38 INFO - Return code: 0 21:54:38 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:54:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:54:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:54:38 INFO - Reading from file tmpfile_stdout 21:54:38 INFO - Current package versions: 21:54:38 INFO - blessings == 1.6 21:54:38 INFO - blobuploader == 1.2.4 21:54:38 INFO - docopt == 0.6.1 21:54:38 INFO - functools32 == 3.2.3-2 21:54:38 INFO - jsonschema == 2.5.1 21:54:38 INFO - manifestparser == 1.1 21:54:38 INFO - mozInstall == 1.12 21:54:38 INFO - mozcrash == 1.0 21:54:38 INFO - mozdebug == 0.1 21:54:38 INFO - mozdevice == 0.49 21:54:38 INFO - mozfile == 1.2 21:54:38 INFO - mozhttpd == 0.7 21:54:38 INFO - mozinfo == 0.9 21:54:38 INFO - mozleak == 0.1 21:54:38 INFO - mozlog == 3.4 21:54:38 INFO - moznetwork == 0.27 21:54:38 INFO - mozprocess == 0.23 21:54:38 INFO - mozprofile == 0.28 21:54:38 INFO - mozrunner == 6.13 21:54:38 INFO - mozscreenshot == 0.1 21:54:38 INFO - mozsystemmonitor == 0.3 21:54:38 INFO - moztest == 0.7 21:54:38 INFO - mozversion == 1.4 21:54:38 INFO - psutil == 3.1.1 21:54:38 INFO - requests == 1.2.3 21:54:38 INFO - wsgiref == 0.1.2 21:54:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:54:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:54:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:54:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:54:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:54:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:54:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:54:38 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 0x10cbe8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbeb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9adbd71a90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9c6d30>, '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 0x10c8eddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c9bbbe8>, '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.y0JEsVsY8E/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.7ExKa1K88E/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 21:54:38 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 21:54:38 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 21:54:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y0JEsVsY8E/Render', 21:54:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:54:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:54:38 INFO - 'HOME': '/Users/cltbld', 21:54:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:54:38 INFO - 'LOGNAME': 'cltbld', 21:54:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:54:38 INFO - 'MOZ_NO_REMOTE': '1', 21:54:38 INFO - 'NO_EM_RESTART': '1', 21:54:38 INFO - 'PAGER': '/bin/cat', 21:54:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:54:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:54:38 INFO - 'PWD': '/builds/slave/test', 21:54:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:54:38 INFO - 'SHELL': '/bin/bash', 21:54:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7ExKa1K88E/Listeners', 21:54:38 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 21:54:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:54:38 INFO - 'USER': 'cltbld', 21:54:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:54:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:54:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:54:38 INFO - 'XPC_FLAGS': '0x0', 21:54:38 INFO - 'XPC_SERVICE_NAME': '0', 21:54:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:54:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:54:38 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)) 21:54:38 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)) 21:54:38 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)) 21:54:38 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)) 21:54:38 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)) 21:54:38 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)) 21:54:38 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)) 21:54:38 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)) 21:54:38 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)) 21:54:38 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)) 21:54:38 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)) 21:54:38 INFO - Cleaning up... 21:54:38 INFO - Return code: 0 21:54:38 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:54:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:54:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:54:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:54:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:54:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:54:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:54:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cbe8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbeb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9adbd71a90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9c6d30>, '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 0x10c8eddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c9bbbe8>, '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.y0JEsVsY8E/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.7ExKa1K88E/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 21:54:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 21:54:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 21:54:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y0JEsVsY8E/Render', 21:54:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:54:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:54:38 INFO - 'HOME': '/Users/cltbld', 21:54:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:54:38 INFO - 'LOGNAME': 'cltbld', 21:54:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:54:38 INFO - 'MOZ_NO_REMOTE': '1', 21:54:38 INFO - 'NO_EM_RESTART': '1', 21:54:38 INFO - 'PAGER': '/bin/cat', 21:54:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:54:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:54:38 INFO - 'PWD': '/builds/slave/test', 21:54:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:54:38 INFO - 'SHELL': '/bin/bash', 21:54:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7ExKa1K88E/Listeners', 21:54:38 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 21:54:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:54:38 INFO - 'USER': 'cltbld', 21:54:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:54:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:54:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:54:38 INFO - 'XPC_FLAGS': '0x0', 21:54:38 INFO - 'XPC_SERVICE_NAME': '0', 21:54:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:54:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:54:38 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:54:38 INFO - Cleaning up... 21:54:38 INFO - Return code: 0 21:54:38 INFO - Running post-action listener: _resource_record_post_action 21:54:38 INFO - Running post-action listener: _start_resource_monitoring 21:54:38 INFO - Starting resource monitoring. 21:54:38 INFO - [mozharness: 2016-12-28 05:54:38.933681Z] Finished create-virtualenv step (success) 21:54:38 INFO - [mozharness: 2016-12-28 05:54:38.934644Z] Running install step. 21:54:38 INFO - Running pre-action listener: _resource_record_pre_action 21:54:38 INFO - Running main action method: install 21:54:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:54:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:54:39 INFO - Reading from file tmpfile_stdout 21:54:39 INFO - Detecting whether we're running mozinstall >=1.0... 21:54:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:54:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:54:39 INFO - Reading from file tmpfile_stdout 21:54:39 INFO - Output received: 21:54:39 INFO - Usage: mozinstall [options] installer 21:54:39 INFO - Options: 21:54:39 INFO - -h, --help show this help message and exit 21:54:39 INFO - -d DEST, --destination=DEST 21:54:39 INFO - Directory to install application into. [default: 21:54:39 INFO - "/builds/slave/test"] 21:54:39 INFO - --app=APP Application being installed. [default: firefox] 21:54:39 INFO - mkdir: /builds/slave/test/build/application 21:54:39 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'] 21:54:39 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 21:55:01 INFO - Reading from file tmpfile_stdout 21:55:01 INFO - Output received: 21:55:01 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 21:55:01 INFO - Running post-action listener: _resource_record_post_action 21:55:01 INFO - [mozharness: 2016-12-28 05:55:01.090636Z] Finished install step (success) 21:55:01 INFO - [mozharness: 2016-12-28 05:55:01.090786Z] Running run-tests step. 21:55:01 INFO - Running pre-action listener: _resource_record_pre_action 21:55:01 INFO - Running main action method: run_tests 21:55:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:55:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:55:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:55:01 INFO - 2016-12-27 21:55:01.103 screenresolution[1644:10575] starting screenresolution argv=screenresolution get 21:55:01 INFO - 2016-12-27 21:55:01.106 screenresolution[1644:10575] Display 0: 1600x1200x32@60 21:55:01 INFO - 2016-12-27 21:55:01.111 screenresolution[1645:10578] starting screenresolution argv=screenresolution list 21:55:01 INFO - Available Modes on Display 0 21:55:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:55:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:55:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:55:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:55:01 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:55:01 INFO - Intel Iris: 21:55:01 INFO - Chipset Model: Intel Iris 21:55:01 INFO - Type: GPU 21:55:01 INFO - Bus: Built-In 21:55:01 INFO - VRAM (Dynamic, Max): 1536 MB 21:55:01 INFO - Vendor: Intel (0x8086) 21:55:01 INFO - Device ID: 0x0a2e 21:55:01 INFO - Revision ID: 0x0009 21:55:01 INFO - Displays: 21:55:01 INFO - Display: 21:55:01 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:55:01 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:55:01 INFO - Display Serial Number: 1600x1200 60 21:55:01 INFO - Main Display: Yes 21:55:01 INFO - Mirror: Off 21:55:01 INFO - Online: Yes 21:55:01 INFO - Rotation: Supported 21:55:01 INFO - Return code: 0 21:55:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:55:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:55:01 INFO - Python 2.7.3 21:55:01 INFO - Return code: 0 21:55:01 INFO - grabbing minidump binary from tooltool 21:55:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:55:01 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 0x10c9c6d30>, '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 0x10c8eddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c9bbbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:55:01 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 21:55:01 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 21:55:01 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 21:55:01 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:55:01 INFO - Return code: 0 21:55:01 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 21:55:01 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:55:01 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:55:01 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 21:55:01 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:55:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KAwfhjqJQ2KzE0s2BaWa_g/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0030', '--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 21:55:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/KAwfhjqJQ2KzE0s2BaWa_g/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0030 --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 21:55:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y0JEsVsY8E/Render', 21:55:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:55:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:55:01 INFO - 'HOME': '/Users/cltbld', 21:55:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:55:01 INFO - 'LOGNAME': 'cltbld', 21:55:01 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:55:01 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 21:55:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:55:01 INFO - 'MOZ_NO_REMOTE': '1', 21:55:01 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:55:01 INFO - 'NO_EM_RESTART': '1', 21:55:01 INFO - 'PAGER': '/bin/cat', 21:55:01 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:55:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:55:01 INFO - 'PWD': '/builds/slave/test', 21:55:01 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:55:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:55:01 INFO - 'SHELL': '/bin/bash', 21:55:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7ExKa1K88E/Listeners', 21:55:01 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 21:55:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:55:01 INFO - 'USER': 'cltbld', 21:55:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:55:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:55:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:55:01 INFO - 'XPC_FLAGS': '0x0', 21:55:01 INFO - 'XPC_SERVICE_NAME': '0', 21:55:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:55:01 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KAwfhjqJQ2KzE0s2BaWa_g/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0030', '--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 21:55:01 INFO - mozversion application_buildid: 20161227203236 21:55:01 INFO - mozversion application_changeset: a0866731bbe884fffce6f007b851f7ac453013fa 21:55:01 INFO - mozversion application_display_name: Nightly 21:55:01 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:55:01 INFO - mozversion application_name: Firefox 21:55:01 INFO - mozversion application_remotingname: firefox 21:55:01 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 21:55:01 INFO - mozversion application_vendor: Mozilla 21:55:01 INFO - mozversion application_version: 53.0a1 21:55:01 INFO - mozversion platform_buildid: 20161227203236 21:55:01 INFO - mozversion platform_changeset: a0866731bbe884fffce6f007b851f7ac453013fa 21:55:01 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 21:55:01 INFO - mozversion platform_version: 53.0a1 21:55:01 INFO - using testdate: 1482904501 21:55:01 INFO - actual date: 1482904501 21:55:01 INFO - starting webserver on 'localhost:49240' 21:55:01 INFO - SUITE-START | Running 2 tests 21:55:01 INFO - TEST-START | dromaeo_css 21:55:01 INFO - operating with platform_type : mac_ 21:55:01 INFO - Initialising browser for dromaeo_css test... 21:55:01 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/tmp88pEmQ/profile http://localhost:49240/getInfo.html) 21:55:02 INFO - PROCESS | 1655 | 1482904502234 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:4986 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3827 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2831 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 21:55:04 INFO - PROCESS | 1655 | __metrics Screen width/height:1600/1200 21:55:04 INFO - PROCESS | 1655 | colorDepth:24 21:55:04 INFO - PROCESS | 1655 | Browser inner width/height: 1024/689 21:55:04 INFO - PROCESS | 1655 | __metrics 21:55:04 INFO - PROCESS | 1655 | 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" 21:55:04 INFO - PROCESS | 1655 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:55:05 INFO - TEST-INFO | 1655: exit 0 21:55:05 INFO - Browser initialized. 21:55:05 INFO - Running cycle 1/1 for dromaeo_css test... 21:55:05 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/tmp88pEmQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:55:17 INFO - PROCESS | 1658 | RSS: Main: 234184704 21:55:17 INFO - PROCESS | 1658 | 21:58:21 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49240/tests/dromaeo/cssquery-ext.html) 21:58:22 INFO - PROCESS | 1658 | RSS: Main: 332869632 21:58:22 INFO - PROCESS | 1658 | 22:00:05 INFO - PROCESS | 1658 | ************************* 22:00:05 INFO - PROCESS | 1658 | A coding exception was thrown and uncaught in a Task. 22:00:05 INFO - PROCESS | 1658 | 22:00:05 INFO - PROCESS | 1658 | Full message: ReferenceError: fetch is not defined 22:00:05 INFO - PROCESS | 1658 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 22:00:05 INFO - PROCESS | 1658 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 22:00:05 INFO - PROCESS | 1658 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 22:00:05 INFO - PROCESS | 1658 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 22:00:05 INFO - PROCESS | 1658 | TaskImpl@resource://gre/modules/Task.jsm:277:3 22:00:05 INFO - PROCESS | 1658 | asyncFunction@resource://gre/modules/Task.jsm:252:14 22:00:05 INFO - PROCESS | 1658 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 22:00:05 INFO - PROCESS | 1658 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 22:00:05 INFO - PROCESS | 1658 | TaskImpl@resource://gre/modules/Task.jsm:277:3 22:00:05 INFO - PROCESS | 1658 | asyncFunction@resource://gre/modules/Task.jsm:252:14 22:00:05 INFO - PROCESS | 1658 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 22:00:05 INFO - PROCESS | 1658 | 22:00:05 INFO - PROCESS | 1658 | ************************* 22:01:25 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-ext.html (next: http://localhost:49240/tests/dromaeo/cssquery-jquery.html) 22:01:26 INFO - PROCESS | 1658 | RSS: Main: 363184128 22:01:26 INFO - PROCESS | 1658 | 22:04:28 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49240/tests/dromaeo/cssquery-mootools.html) 22:04:29 INFO - PROCESS | 1658 | RSS: Main: 338423808 22:04:29 INFO - PROCESS | 1658 | 22:07:31 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49240/tests/dromaeo/cssquery-prototype.html) 22:07:32 INFO - PROCESS | 1658 | RSS: Main: 334667776 22:07:32 INFO - PROCESS | 1658 | 22:10:35 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49240/tests/dromaeo/cssquery-yui.html) 22:10:36 INFO - PROCESS | 1658 | RSS: Main: 325156864 22:10:36 INFO - PROCESS | 1658 | 22:13:39 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-yui.html (next: http://localhost:49240/tests/dromaeo/cssquery-dojo.html) 22:13:40 INFO - PROCESS | 1658 | RSS: Main: 475672576 22:13:40 INFO - PROCESS | 1658 | 22:13:40 INFO - PROCESS | 1658 | __start_tp_report 22:13:40 INFO - PROCESS | 1658 | _x_x_mozilla_page_load 22:13:40 INFO - PROCESS | 1658 | _x_x_mozilla_page_load_details 22:13:40 INFO - PROCESS | 1658 | |i|pagename|runs| 22:13:40 INFO - PROCESS | 1658 | |0;dojo.html;3323.451630480971;3323.58455193101;3328.0015995201443;3346.832658367081;3385.492176173575;5489.066858634032;5529.861753456165;5595.468430499106;5625.1281051437045;5655.010373184697;5598.636088654245;5627.577931655122;5659.094544872821;5668.433156684329;5700.942990570093;5057.443681195084;5081.237814327844;5103.540681338679;5156.742162891859;5187.533122019017;5235.057689615867;5254.527092561669;5259.13224317989;5272.736363181844;5321.866953326159;5060.696358218519;5203.687778733288;5226.451222621608;5235.162374020158;5262.578993680497;5007.4992500749995;5098.7705553249825;5106.8212612558755;5129.794808207667;5145.665531740462;5101.362329708786;5137.948620513822;5144.434112247687;5148.897022059576;5160.6129540284255;5632.239647647556;5678.858028549278;5689.061304884689;5690.516306113986;5716.857078573027;5974.402559744034;6028.577999540034;6038.486728628071;6105.755769769204;6193.938060619381;6185.443310102068;6198.194234749499;6233.812985610439;6239.812805615838;6259.937400626027;5710.942890571083;5711.685857277848;5715.399883012307;5732.885342293131;5779.537636989031;6139.737467782926;6206.503479721611;6254.3745625437095;6328.335524769925;6354.364563543609;5808.07070868659;5908.822735317947;5913.852153696106;6034.155218269446;6042.244719410074;5040.949590504121;6061.727222275008;6131.50947924165;6141.455323060992;6142.590862071594;5997.880042399128;6030.909536356959;6104.755809767648;6112.877742445126;6123.020316749388;6104.398524058527;6104.610739188311;6124.116761842186;6128.667102172749;6134.110553969649;4887.608991280689;4892.510748925079;4895.485973972753;4924.704517728947;4930.260460930889;4325.6755743318745;4347.369631403429;4353.3469979503325;4380.978095109568;4408.4269045024585;5623.184638227434;5645.040343141676;5659.236003139606;5745.080787074048;5745.9425405745405;6238.594491358047;6239.313675495692;6246.812595622138;6249.937500625027;6261.686915654199;5660.122680984454;5664.915026274527;5721.799737009184;5726.455986681259;5778.422157784272;5232.3721153461065;5263.552598029134;5282.102042652758;5310.9734451327495;5332.120200167006;6244.625322480575;6311.368863113652;6312.6528040956355;6320.146780184617;6333.018382150774;6214.40963108504;6231.626102433687;6239.500839932885;6261.812145635638;6268.905966410416;6201.906971395523;6284.4344009039405;6298.937010630018;6313.589616674902;6319.6208227506495;5940.346561878104;6081.148639190428;6105.511559075352;6125.816225513242;6172.567920245539;6224.62652240867;6225.595336303126;6232.937670623236;6248.343923887926;6253.530985176039;6040.063790112539;6040.486558642565;6065.909011364744;6096.329403765493;6098.176746139215;5649.887002260014;5910.231669882888;5959.344472107978;5979.2824861015215;5994.910076348771;5864.120381942743;5880.235569375954;5891.675957822361;5894.233749612523;5925.555583331181;5835.358110607915;5889.823305300848;5897.5576831736935;5899.4985426239255;5924.259467566554;5755.309362876482;5792.333881603669;5796.536277097906;5811.82564523065;5838.065909454467 22:13:40 INFO - PROCESS | 1658 | |1;ext.html;481.7783819443056;486.8801755961293;506.6782593053412;508.0575532387421;511.6392942975203;29854.955076572325;39721.2055758885;41378;41503.58496415035;41509.792451037705;42118.78940605293;42547.78726106373;42593;42638.786806066004;42641.78679106608;8287.088420273765;9250.583723732432;9458.62165513382;9460.006699296579;9485.146336829685;8396.370272229577;8598.87101693475;8607.31141508681;8608.698695545658;11092.944535277313;11148.777024459507;11439.370834604093;11449.599264025761;11457;11464.656060318162;10426.582936682524;10500.474976251233;11268.830967535494;11356.602518911799;11374;11038.282511636799;11158.93990070942;11179.049780768644;11260.54957801687;11305.773884522347;39729;39904.00239994023;39916;39931.40102898459;40004.599954000376;3250.4799232122746;3979.9801000994808;4008.3386241270155;4010.859619913318;4027.0335119571387;29315.973940911965;30150.24624384386;30342.29694397372;30534;30588.776448942015;3131.4989601663624;3253.1541799132397;3269.54226408303;3291.5556399886186;3331.850066747002;339395;339602;340691;340696;342979;61719.76560468766;61888.69055654693;62201.37798622046;62304;62415;63963.76579747218;64240.678796605716;64730.02904956429;64879;65053.67473162604;15309.846901531066;15759.606009849616;15788.36846526149;15801.762973555406;15813.209339532978;12144.271343719229;12189.634310970685;12208.206466579644;12208.877911220952;12230.205036672587;15583.376664933276;16040.43858464948;17482.562935926704;18015;18171.636567268844;6465.256495502983;6498.512611554058;6543.770968016097;6567.605943643397;6574.671266436659;837.5895811052541;839.9244068033784;842.30509829391;858.6651206029653;875.8248350329959;28890.110439258326;29373.11880643584;29704;29953.550696739567;30020.54969175464;964.5900492290748;1030.407515678488;1062.3307316390624;1072.7157303314627;1129.5764088466824;15213;15295.158766267961;15420.383184672712;15638.765418518731;15665.843341566666;649744;665586;667179.95380416;859667;861665;50417;50878.98242035213;50930.49069509257;50945;50988.49011509911;1341.496938648007;1367.5282027700227;1369.8013788000685;1371.8765311122047;1382.8755412012968;27322.180334589993;27422.725772742815;27463.450730985667;27907.720922791323;28115.156545303675;43906;44458.77770611256;44459.33311000273;44464;44504.77747611241;441916;444119.11761763936;445340.54659454286;447212;448637;23924.282271531883;24189.51620967606;24208.878955605815;24225.39436514101;24234;33971.320573588884;34886.65113348936;39838.20323593454;40814.59185408108;44678.77660611806;34880.47679284863;35076.82461587676;35141.47287790736;36021.81989090038;36514.452283216306;34240.65759342375;34490.82754586211;35203;35371.64628353684;35546.822265888506;33825.646974121744;34064.14839629029;34397.82801086079;34463.82768086143;34959.475607865395;29882.8505857478;30381.088567343017;30571.847140764155;30976.69023309828;31167;23442.413939651644;23538.882305589046;23712.40718982039;23865.926033328196;23880 22:13:40 INFO - PROCESS | 1658 | |2;jquery.html;353.7594435783666;372.97948612826286;379.7835233916668;380.6421963354447;404.54690746364076;14226;14685.559433217008;15154.469593564214;18714.71927921082;19850.60298794027;19389.612207755872;19448.70826937597;19891.900540497278;20061.799382006175;20303.796962030374;5647.548196144309;5923.318818335882;5925.229720136376;5927.555433342495;5934.465898069173;5470.152126420411;5772.740226689788;5827.096799995987;5829;5833.766649334021;4057.8173982170874;4298.183345164409;5779.624324418899;6253.43719065284;6409.2949775524885;6438.806835794933;6478.611283322968;6494.480441564663;6494.902576461357;6501.609903405811;4756.904861902743;5512.283403157564;5580.079286917654;5589.105743081088;5596.664200148004;22972.310830675106;23196.072157113864;23623;23843.284701458982;24246.15138470145;2314.271004633552;2676.2105178972056;2677.6652918385203;2686.3821321096257;2747.821391609539;14157.858421415756;14665.70668586633;14881.627959300995;15072.321745521478;15223.688605916152;1799.5141311845728;1808.7829460464698;1823.2706917233081;1827.0408035781188;1842.4288470574031;102630;105039.79570544229;106088;106823.93176068217;108066.379004315;10276.537555810006;10339.741506462247;10459.581616735397;10577.576896924036;10611.204159688054;10975.890241097646;13081.280529570775;13087.149335293176;13093.672658183621;13124.409401576951;7862.764117076496;8004.399670024772;8013.315539294402;8034.276915077671;8183.304419124322;7729.381649468029;7743.806404839811;7993.8401231976195;8176.468529545679;8192.590370481572;6279.717412716439;6362.172917520693;6373.458256048288;6380.425761681378;6395.808125756234;1667.9491920090265;1803.9549011274823;1810.08590661715;1818.6817306971227;1838.6230822681318;911.4485736129678;988.728099772554;1015.167562598662;1035.9792804143874;1079.255313833468;18373.356932507297;19578.67660987211;20683.27608533694;20698.896505517376;20819.1672333108;1043.4417586591137;1046.210111365914;1051.3429106808244;1057.8201705710048;1073.0932362153967;10279;11166.497507612177;11174.38540880243;11183.608573700045;11264.94367528157;436690;442823;442981.06585203746;535537;545236.2738186363;95892.52053739686;99484;124480.37759811143;127199;135097.9735303971;994.9303548751662;1019.5463018956493;1031.1080915008463;1032.9129923864652;1042.890496497872;15512.636530335609;15697.979631323928;18048.368307109715;18307.63384732271;18590.721139182653;41298.58701412947;51078.48921510737;51289.71775705637;52244;53225.20162197493;73335;73696;75014;76507.61746191233;76845.00507466997;7402.9629851850395;7787.727429539939;7970.442069055111;7988.041435027834;8022.9197708022175;7264.128304603481;10786.784264314827;11808.409579521162;12403.75192496163;12688.555900543437;9338.334823457688;10567.154627629925;10692;10751.408672523086;10819.945900270448;10606.575736970435;10699.572017119228;10746.140308775435;11115.277506962022;11131.276467029616;10814.243002989715;10910.399928004035;11053.944730276296;11063.280886742335;11202.719932001764;7714.614269286401;7775.883361749691;7941.245581669732;7975.1227364988645;8019.679212831421;9264.95367523158;10303.227257955534;10489.737756555842;10707.892921071001;10962.945185274022 22:13:40 INFO - PROCESS | 1658 | |3;mootools.html;1287.5107459165515;1376.4700590272748;1400.1459109942928;1401.9719605607888;1404.3048690898006;7938.801529961746;8229.794255143624;8351.123132071136;8359.373047021474;8364.12176721444;6573.539852210347;7355.6322183890725;7597.012388389516;7605.885911711329;8088.757337279891;2214.7010153629217;2280.441291883491;2287.1537531113513;2287.3709729824263;2294;2224.5773303072447;2304.043821813945;2324.976750232493;2325.197806756666;2325.988370058156;2004.3686238834794;2077.1172251793005;2079.095593416861;2183.825293976478;2245.124401483423;2284.360379093848;2296.8966396512283;2299;2304.3893368257427;2306.4695120122296;2254.0420321363354;2281.8744969026698;2288.187693368863;2289.553537060274;2293.346396277053;8140.308073813733;8141.063777665585;8235.341172706168;8238.670453181865;8244.505329680238;4935.950640493585;5113.795448182068;5115.846524604231;5124.103281925648;5124.590032797368;3189.3142974260413;3329.916752081193;3375.5274261603395;3379.509971054208;3398.796072235674;1154.5266440759333;1181.80500217464;1184.5498710490044;1227.09808290907;1230.280286032663;10048.045435683598;10450.581976720921;10677.466126693713;10681.732956676145;10698.197635177392;4379.58393952574;4451.221036318631;4485.102979404132;4569.29175977724;4591.104734576792;9318.347715659838;9328.673496427593;9341.392809467363;9342.299327550325;9344.112309330603;5134;5136.203888397305;5140.306058682105;5145.176771716508;5150;4636.559526844944;4646.372739680168;4662.044280922402;4678.345031695565;4709.222978208626;3330.2673411849355;3462.79223246606;3651.415773476231;3664.3037822814113;3734.402495600691;1208.3354155214772;1232.3468561662332;1234.3519652182677;1245.4582256718174;1248.719038216394;623.3486007122568;751.9511231769917;758.7956984958594;760.0803028335775;779.4582764978398;4816.34979277793;5270.736463176826;5280.471952804765;5364.739286267724;5451.345838499326;483.8040593559614;518.4911906352571;530.6046994988758;539.7921800106909;653.0139489371084;3201.3437245364644;3270.787398819069;3675.448682697563;3705.7591256568235;3765.3787125124595;9391.577379017961;9678.661246856327;9779.26655500826;9780.070893265129;9824.164945979528;8221.671133154727;9716.514174291398;9792.951035244778;9823.656172033945;9838;6711.295313991961;7283.053203083671;7664.578448185403;7670.271324224078;7693.653785579662;4899.779509922133;5332.1202001670845;5387.595930305321;5392.325959255093;5599.412061733622;7144.678489467882;7561.054868141482;8499.113845908785;9039.548022598976;9081.137291957253;8972.41679290844;9200.907990920274;9229.815403691755;9270.44377337335;9272.304577156605;6869.690863911236;6945.617991010543;6959.686814093479;7061;7064.752733654503;7623.542587444549;7708.8843667346155;7710.228977102245;7728.4203684725;7732.30409263169;6784.457243420416;6979.115638777603;7024.578525288499;7081.044059051962;7107.218205997439;6803.422021211768;6882.174139103339;6900.896486552605;6981.685824137824;7062.964685176541;6230.937690623035;6241.251049874044;6314.242290924934;6319.399657032574;6322.304546499973;4586.151561961048;4641.234196357698;4655.953440465552;4692.601128904082;4761.214399624023;4697.0840686065885;4705.670603057753;4773.1408346497965;4851.442084160367;4924.261360795909 22:13:40 INFO - PROCESS | 1658 | |4;prototype.html;347.91997840496686;351.8205715085306;357.2266044014708;366.8825975687781;368.981550922454;5159.78129404356;5212.739363031843;5248.448912864147;5261.36965493809;5264.3682758069035;3815.5802861685193;4970.478099799523;4987.680728461265;5054.494550544944;5067.923981140286;3852.112464892197;4023.1551374211376;4052.7770972596495;4070.6946978976694;4080.9591904081026;3680.4479328100733;4028.5769994150633;4028.939565906514;4062.756234625917;4077.633013028827;3668.7982160981137;3673.797941113238;3825.016487140035;3859.9614003860024;3947.881563553098;3253.3493301339827;3898.2203559288255;3901;3904.629060239273;3934.072304570754;2827.8303301801957;3380.509826075205;3393.86424543018;3408.199073217792;3412.9658703412897;4848.636352273594;5034.219695947134;5037.596992240612;5051.772670229849;5085.999260436323;4153.8961525961795;4312.396264522969;4414.6688998324935;4466.731996080245;4468.620167285752;4916.336294600219;5388.275751759438;5423.484768946943;5427.701476418795;5452.890942181174;3417.7607567470286;3473.079633896994;3671.541057367829;3682.6869716074166;3699.6855267302312;4794.760261986887;4986.354503013997;5400.945990540123;5638.661680299195;5710.2576665033275;3639.94540081899;3721.4555959277136;3745.550533935945;3759.1166075972124;3791.696664266852;3384.2216290252886;3808.847646094125;3830.6552410283207;3849.9615003850163;3866.7679939203176;3715.6655900968503;4100.692448066347;4155.667546596265;4489.2592722201125;4502.5047244803045;4529.867533116721;4712.410948631421;5468.945310546923;5548.528375088163;5559.360673522516;1021.8861441029313;1140.3670962615827;1238.578883179723;1241.931693756834;1265.9935351395663;757.8863170524465;809.688270016048;826.1779529368317;844.8943882014748;857.0914830279924;4010.598940105966;4529.977350113229;4822.5050234426735;4854.68444551103;4863.659543831898;4887.413510378778;5235;5360.276362691065;5382.623216374816;5429.212764149255;4189.512722983358;4649.767511624405;4692.366530518337;4713.6431784107945;4872.634552408584;2459.1270099114913;2690.636764036869;2721.3468767496056;2735.439234956869;2753.848538330411;4501.729896206172;5149.716765577929;5417.729113544307;5462.820941979389;5511.834644960658;4631;4918.9089292582;5047.899042019139;5084.186530155232;5370.543503802221;4638.814447422065;5308.628396012319;5372.931421402866;5391.379991301051;5394.64934779246;4863.586595139453;5013.298138260573;5242.584502184312;5261.500157485033;5261.631685781958;5135.793088624096;5541.753105551219;5555.111422004532;5578.972105139448;5605.113366616278;4750.501197374226;5183.159978207833;5768.625354167859;5802.085311846996;5865.648061116347;3338.4324664806554;3634.6183650716293;3651.342758303531;3783.4703141560753;3798.164403831153;5088.592912566912;5396.541293990055;5610.719464026701;5668.773249069991;5672.262605861211;4635.026644404713;5495.313085864267;5511.724413779375;5529.253550770594;5544.306961629796;4880.438749543848;5096.050161747686;5287.762050707651;5464.972675136599;5496.230527726201;5086.694798312114;5317.574594032545;5395.973020134874;5415.9419420619915;5428.430014848384;5126.743947732829;5274.340707411574;5366.5170134686505;5494.313210848644;5528.226048353315;4521.756516957731;5334.706591137525;5383;5386.865328366822;5394.244805727123 22:13:40 INFO - PROCESS | 1658 | |5;yui.html;717.6124892558018;730.7296300368864;766.0118447203108;767.9769606911793;819.5123901278739;14514;15136.470223542177;15181.92409037956;15183.772243416357;15187.696246075071;13737.725245495085;13958.44166233352;13987.860121398782;14005.509807156726;14008.439662413517;683.5010442377068;709.8012556484192;711.4664001998501;848.9490630562156;856.1224744636751;874.3398733955886;877.9604917778684;883.9381243312971;890.9599068041921;895.0691281067682;942.2885721280423;943.2972435535536;943.3019565521499;944.598545618113;944.8393773058563;613.929398119213;939.2720641502856;939.7697564096806;941.4539567051094;941.7174847545778;554.7697705452232;560.2492659835799;561.3460318728676;562.1174755633657;656.579788935082;14663.046901951338;14725.337359818726;14784.186869722162;15194.620134496727;15268.31292591836;12306.384680766016;12315.199512031779;12354.629361119181;12451.066170037193;12482.750344993141;12534.373281335897;12591.307478088702;12810.10329276951;12890.613281601567;13214.603561893158;4512.390827238313;4623.514530974267;4640.628749700016;4652.906941861143;4672.182368085605;70867.64566177188;70887;70978;77919;104839;10602.045815876454;11092.445377731243;11154.330740155616;11155.442227888572;11276.267042642203;21327.36017919465;21619.135234590754;21680.89159554192;21811.563768724536;21892.671609925863;7498.962505187439;7651.3496352810635;7746.767596972099;7752.341051010614;7892.9210707893335;3997.700172487075;4116.238495878272;4117.649999749995;4120.361343991686;4122.6083522065355;3335.566376371105;3405.8637654494037;3435.484677298375;3436.1753179236803;3449.3101379724158;625.4433554136822;628.4438272129198;632.40237975114;640.8814369341686;641.0160403780175;577.7140315543749;582.260529128005;606.7269728622138;613.8035828534826;626.7900253415066;8461.404425858065;8673.306135509145;8751.474911505331;8837.248833849195;8922.197002269826;508.4915084915085;574.704027425876;621.5898202254994;628.1457218183267;629.6725702634604;4058.2086493133556;4300.6989510734575;4308.784560771949;4363.432753741993;4414.690971631955;99149;100126;101665.49167254116;102452;103858;86076;86663;88330.55834720786;88917;90531.54734226418;61976.76046479045;66059.33940660628;67123.66438167875;69479.30520694729;71369;9054.139856713602;9192.724218273463;9742.805143896941;9843.360181588174;9922.950385248028;48458;48476;49096.50903490919;51605.48394516007;51702;49267.753661231465;50068.49931500638;64659.353406465336;65092.67453662701;65815.67092164508;12332.630021099381;12411.689707757378;12594.992400607745;12595;12907.031382781825;12623.800738929787;13994.37025333842;14252;14764.040337378035;14844.851551484779;13009.869901300866;13065.346732663518;13442.93278533601;13603.115797473132;13916.165030098227;12916.741665166832;13155.473781048651;13224.867751322363;13313.068085234328;13368.264745439094;12125.636230913087;12177.634670959886;12944.857840128281;13027.47890084386;13869.791953120914;10730;11042.834357484804;11164.633054001884;11200.551977920792;11500.53997840077;10248.64129755455;10335.379877207391;10346.32748871321;10371.58513659445;10379.273450858367 22:13:40 INFO - PROCESS | 1658 | __end_tp_report 22:13:40 INFO - PROCESS | 1658 | __start_cc_report 22:13:40 INFO - PROCESS | 1658 | _x_x_mozilla_cycle_collect,1096 22:13:40 INFO - PROCESS | 1658 | __end_cc_report 22:13:40 INFO - PROCESS | 1658 | __startTimestamp1482905620035__endTimestamp 22:13:40 INFO - PROCESS | 1658 | 22:13:40 INFO - PROCESS | 1658 | ------- Summary: start ------- 22:13:40 INFO - PROCESS | 1658 | Number of tests: 6 22:13:40 INFO - PROCESS | 1658 | 22:13:40 INFO - PROCESS | 1658 | [#0] dojo.html Cycles:165 Average:5675.27 Median:5835.36 stddev:629.55 (10.8%) stddev-sans-first:603.84 22:13:40 INFO - PROCESS | 1658 | Values: 3323.5 3323.6 3328.0 3346.8 3385.5 5489.1 5529.9 5595.5 5625.1 5655.0 5598.6 5627.6 5659.1 5668.4 5700.9 5057.4 5081.2 5103.5 5156.7 5187.5 5235.1 5254.5 5259.1 5272.7 5321.9 5060.7 5203.7 5226.5 5235.2 5262.6 5007.5 5098.8 5106.8 5129.8 5145.7 5101.4 5137.9 5144.4 5148.9 5160.6 5632.2 5678.9 5689.1 5690.5 5716.9 5974.4 6028.6 6038.5 6105.8 6193.9 6185.4 6198.2 6233.8 6239.8 6259.9 5710.9 5711.7 5715.4 5732.9 5779.5 6139.7 6206.5 6254.4 6328.3 6354.4 5808.1 5908.8 5913.9 6034.2 6042.2 5040.9 6061.7 6131.5 6141.5 6142.6 5997.9 6030.9 6104.8 6112.9 6123.0 6104.4 6104.6 6124.1 6128.7 6134.1 4887.6 4892.5 4895.5 4924.7 4930.3 4325.7 4347.4 4353.3 4381.0 4408.4 5623.2 5645.0 5659.2 5745.1 5745.9 6238.6 6239.3 6246.8 6249.9 6261.7 5660.1 5664.9 5721.8 5726.5 5778.4 5232.4 5263.6 5282.1 5311.0 5332.1 6244.6 6311.4 6312.7 6320.1 6333.0 6214.4 6231.6 6239.5 6261.8 6268.9 6201.9 6284.4 6298.9 6313.6 6319.6 5940.3 6081.1 6105.5 6125.8 6172.6 6224.6 6225.6 6232.9 6248.3 6253.5 6040.1 6040.5 6065.9 6096.3 6098.2 5649.9 5910.2 5959.3 5979.3 5994.9 5864.1 5880.2 5891.7 5894.2 5925.6 5835.4 5889.8 5897.6 5899.5 5924.3 5755.3 5792.3 5796.5 5811.8 5838.1 22:13:40 INFO - PROCESS | 1658 | 22:13:40 INFO - PROCESS | 1658 | [#1] ext.html Cycles:180 Average:64142.03 Median:188688.41 stddev:145087.17 (76.9%) stddev-sans-first:145415.44 22:13:40 INFO - PROCESS | 1658 | Values: 481.8 486.9 506.7 508.1 511.6 29855.0 39721.2 41378.0 41503.6 41509.8 42118.8 42547.8 42593.0 42638.8 42641.8 8287.1 9250.6 9458.6 9460.0 9485.1 8396.4 8598.9 8607.3 8608.7 11092.9 11148.8 11439.4 11449.6 11457.0 11464.7 10426.6 10500.5 11268.8 11356.6 11374.0 11038.3 11158.9 11179.0 11260.5 11305.8 39729.0 39904.0 39916.0 39931.4 40004.6 3250.5 3980.0 4008.3 4010.9 4027.0 29316.0 30150.2 30342.3 30534.0 30588.8 3131.5 3253.2 3269.5 3291.6 3331.9 339395.0 339602.0 340691.0 340696.0 342979.0 61719.8 61888.7 62201.4 62304.0 62415.0 63963.8 64240.7 64730.0 64879.0 65053.7 15309.8 15759.6 15788.4 15801.8 15813.2 12144.3 12189.6 12208.2 12208.9 12230.2 15583.4 16040.4 17482.6 18015.0 18171.6 6465.3 6498.5 6543.8 6567.6 6574.7 837.6 839.9 842.3 858.7 875.8 28890.1 29373.1 29704.0 29953.6 30020.5 964.6 1030.4 1062.3 1072.7 1129.6 15213.0 15295.2 15420.4 15638.8 15665.8 649744.0 665586.0 667180.0 859667.0 861665.0 50417.0 50879.0 50930.5 50945.0 50988.5 1341.5 1367.5 1369.8 1371.9 1382.9 27322.2 27422.7 27463.5 27907.7 28115.2 43906.0 44458.8 44459.3 44464.0 44504.8 441916.0 444119.1 445340.5 447212.0 448637.0 23924.3 24189.5 24208.9 24225.4 24234.0 33971.3 34886.7 39838.2 40814.6 44678.8 34880.5 35076.8 35141.5 36021.8 36514.5 34240.7 34490.8 35203.0 35371.6 35546.8 33825.6 34064.1 34397.8 34463.8 34959.5 29882.9 30381.1 30571.8 30976.7 31167.0 23442.4 23538.9 23712.4 23865.9 23880.0 22:13:40 INFO - PROCESS | 1658 | 22:13:40 INFO - PROCESS | 1658 | [#2] jquery.html Cycles:180 Average:30669.59 Median:20759.03 stddev:81240.72 (391.4%) stddev-sans-first:81436.74 22:13:40 INFO - PROCESS | 1658 | Values: 353.8 373.0 379.8 380.6 404.5 14226.0 14685.6 15154.5 18714.7 19850.6 19389.6 19448.7 19891.9 20061.8 20303.8 5647.5 5923.3 5925.2 5927.6 5934.5 5470.2 5772.7 5827.1 5829.0 5833.8 4057.8 4298.2 5779.6 6253.4 6409.3 6438.8 6478.6 6494.5 6494.9 6501.6 4756.9 5512.3 5580.1 5589.1 5596.7 22972.3 23196.1 23623.0 23843.3 24246.2 2314.3 2676.2 2677.7 2686.4 2747.8 14157.9 14665.7 14881.6 15072.3 15223.7 1799.5 1808.8 1823.3 1827.0 1842.4 102630.0 105039.8 106088.0 106823.9 108066.4 10276.5 10339.7 10459.6 10577.6 10611.2 10975.9 13081.3 13087.1 13093.7 13124.4 7862.8 8004.4 8013.3 8034.3 8183.3 7729.4 7743.8 7993.8 8176.5 8192.6 6279.7 6362.2 6373.5 6380.4 6395.8 1667.9 1804.0 1810.1 1818.7 1838.6 911.4 988.7 1015.2 1036.0 1079.3 18373.4 19578.7 20683.3 20698.9 20819.2 1043.4 1046.2 1051.3 1057.8 1073.1 10279.0 11166.5 11174.4 11183.6 11264.9 436690.0 442823.0 442981.1 535537.0 545236.3 95892.5 99484.0 124480.4 127199.0 135098.0 994.9 1019.5 1031.1 1032.9 1042.9 15512.6 15698.0 18048.4 18307.6 18590.7 41298.6 51078.5 51289.7 52244.0 53225.2 73335.0 73696.0 75014.0 76507.6 76845.0 7403.0 7787.7 7970.4 7988.0 8022.9 7264.1 10786.8 11808.4 12403.8 12688.6 9338.3 10567.2 10692.0 10751.4 10819.9 10606.6 10699.6 10746.1 11115.3 11131.3 10814.2 10910.4 11053.9 11063.3 11202.7 7714.6 7775.9 7941.2 7975.1 8019.7 9265.0 10303.2 10489.7 10707.9 10962.9 22:13:40 INFO - PROCESS | 1658 | 22:13:40 INFO - PROCESS | 1658 | [#3] mootools.html Cycles:180 Average:5245.53 Median:5142.74 stddev:2882.39 (56.0%) stddev-sans-first:2875.12 22:13:40 INFO - PROCESS | 1658 | Values: 1287.5 1376.5 1400.1 1402.0 1404.3 7938.8 8229.8 8351.1 8359.4 8364.1 6573.5 7355.6 7597.0 7605.9 8088.8 2214.7 2280.4 2287.2 2287.4 2294.0 2224.6 2304.0 2325.0 2325.2 2326.0 2004.4 2077.1 2079.1 2183.8 2245.1 2284.4 2296.9 2299.0 2304.4 2306.5 2254.0 2281.9 2288.2 2289.6 2293.3 8140.3 8141.1 8235.3 8238.7 8244.5 4936.0 5113.8 5115.8 5124.1 5124.6 3189.3 3329.9 3375.5 3379.5 3398.8 1154.5 1181.8 1184.5 1227.1 1230.3 10048.0 10450.6 10677.5 10681.7 10698.2 4379.6 4451.2 4485.1 4569.3 4591.1 9318.3 9328.7 9341.4 9342.3 9344.1 5134.0 5136.2 5140.3 5145.2 5150.0 4636.6 4646.4 4662.0 4678.3 4709.2 3330.3 3462.8 3651.4 3664.3 3734.4 1208.3 1232.3 1234.4 1245.5 1248.7 623.3 752.0 758.8 760.1 779.5 4816.3 5270.7 5280.5 5364.7 5451.3 483.8 518.5 530.6 539.8 653.0 3201.3 3270.8 3675.4 3705.8 3765.4 9391.6 9678.7 9779.3 9780.1 9824.2 8221.7 9716.5 9793.0 9823.7 9838.0 6711.3 7283.1 7664.6 7670.3 7693.7 4899.8 5332.1 5387.6 5392.3 5599.4 7144.7 7561.1 8499.1 9039.5 9081.1 8972.4 9200.9 9229.8 9270.4 9272.3 6869.7 6945.6 6959.7 7061.0 7064.8 7623.5 7708.9 7710.2 7728.4 7732.3 6784.5 6979.1 7024.6 7081.0 7107.2 6803.4 6882.2 6900.9 6981.7 7063.0 6230.9 6241.3 6314.2 6319.4 6322.3 4586.2 4641.2 4656.0 4692.6 4761.2 4697.1 4705.7 4773.1 4851.4 4924.3 22:13:40 INFO - PROCESS | 1658 | 22:13:40 INFO - PROCESS | 1658 | [#4] prototype.html Cycles:180 Average:4331.97 Median:4868.15 stddev:1326.15 (27.2%) stddev-sans-first:1295.72 22:13:40 INFO - PROCESS | 1658 | Values: 347.9 351.8 357.2 366.9 369.0 5159.8 5212.7 5248.4 5261.4 5264.4 3815.6 4970.5 4987.7 5054.5 5067.9 3852.1 4023.2 4052.8 4070.7 4081.0 3680.4 4028.6 4028.9 4062.8 4077.6 3668.8 3673.8 3825.0 3860.0 3947.9 3253.3 3898.2 3901.0 3904.6 3934.1 2827.8 3380.5 3393.9 3408.2 3413.0 4848.6 5034.2 5037.6 5051.8 5086.0 4153.9 4312.4 4414.7 4466.7 4468.6 4916.3 5388.3 5423.5 5427.7 5452.9 3417.8 3473.1 3671.5 3682.7 3699.7 4794.8 4986.4 5400.9 5638.7 5710.3 3639.9 3721.5 3745.6 3759.1 3791.7 3384.2 3808.8 3830.7 3850.0 3866.8 3715.7 4100.7 4155.7 4489.3 4502.5 4529.9 4712.4 5468.9 5548.5 5559.4 1021.9 1140.4 1238.6 1241.9 1266.0 757.9 809.7 826.2 844.9 857.1 4010.6 4530.0 4822.5 4854.7 4863.7 4887.4 5235.0 5360.3 5382.6 5429.2 4189.5 4649.8 4692.4 4713.6 4872.6 2459.1 2690.6 2721.3 2735.4 2753.8 4501.7 5149.7 5417.7 5462.8 5511.8 4631.0 4918.9 5047.9 5084.2 5370.5 4638.8 5308.6 5372.9 5391.4 5394.6 4863.6 5013.3 5242.6 5261.5 5261.6 5135.8 5541.8 5555.1 5579.0 5605.1 4750.5 5183.2 5768.6 5802.1 5865.6 3338.4 3634.6 3651.3 3783.5 3798.2 5088.6 5396.5 5610.7 5668.8 5672.3 4635.0 5495.3 5511.7 5529.3 5544.3 4880.4 5096.1 5287.8 5465.0 5496.2 5086.7 5317.6 5396.0 5415.9 5428.4 5126.7 5274.3 5366.5 5494.3 5528.2 4521.8 5334.7 5383.0 5386.9 5394.2 22:13:40 INFO - PROCESS | 1658 | 22:13:40 INFO - PROCESS | 1658 | [#5] yui.html Cycles:180 Average:18975.56 Median:4567.95 stddev:26446.26 (579.0%) stddev-sans-first:26484.92 22:13:40 INFO - PROCESS | 1658 | Values: 717.6 730.7 766.0 768.0 819.5 14514.0 15136.5 15181.9 15183.8 15187.7 13737.7 13958.4 13987.9 14005.5 14008.4 683.5 709.8 711.5 848.9 856.1 874.3 878.0 883.9 891.0 895.1 942.3 943.3 943.3 944.6 944.8 613.9 939.3 939.8 941.5 941.7 554.8 560.2 561.3 562.1 656.6 14663.0 14725.3 14784.2 15194.6 15268.3 12306.4 12315.2 12354.6 12451.1 12482.8 12534.4 12591.3 12810.1 12890.6 13214.6 4512.4 4623.5 4640.6 4652.9 4672.2 70867.6 70887.0 70978.0 77919.0 104839.0 10602.0 11092.4 11154.3 11155.4 11276.3 21327.4 21619.1 21680.9 21811.6 21892.7 7499.0 7651.3 7746.8 7752.3 7892.9 3997.7 4116.2 4117.6 4120.4 4122.6 3335.6 3405.9 3435.5 3436.2 3449.3 625.4 628.4 632.4 640.9 641.0 577.7 582.3 606.7 613.8 626.8 8461.4 8673.3 8751.5 8837.2 8922.2 508.5 574.7 621.6 628.1 629.7 4058.2 4300.7 4308.8 4363.4 4414.7 99149.0 100126.0 101665.5 102452.0 103858.0 86076.0 86663.0 88330.6 88917.0 90531.5 61976.8 66059.3 67123.7 69479.3 71369.0 9054.1 9192.7 9742.8 9843.4 9923.0 48458.0 48476.0 49096.5 51605.5 51702.0 49267.8 50068.5 64659.4 65092.7 65815.7 12332.6 12411.7 12595.0 12595.0 12907.0 12623.8 13994.4 14252.0 14764.0 14844.9 13009.9 13065.3 13442.9 13603.1 13916.2 12916.7 13155.5 13224.9 13313.1 13368.3 12125.6 12177.6 12944.9 13027.5 13869.8 10730.0 11042.8 11164.6 11200.6 11500.5 10248.6 10335.4 10346.3 10371.6 10379.3 22:13:40 INFO - PROCESS | 1658 | -------- Summary: end -------- 22:13:40 INFO - PROCESS | 1658 | 22:13:40 INFO - TEST-INFO | 1658: exit 0 22:13:40 INFO - TEST-OK | dromaeo_css | took 1119005ms 22:13:40 INFO - TEST-START | kraken 22:13:40 INFO - operating with platform_type : mac_ 22:13:40 INFO - Initialising browser for kraken test... 22:13:40 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/tmp7SHWo1/profile http://localhost:49240/getInfo.html) 22:13:41 INFO - PROCESS | 1663 | 1482905621182 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:4986 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3827 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2831 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 22:13:43 INFO - PROCESS | 1663 | __metrics Screen width/height:1600/1200 22:13:43 INFO - PROCESS | 1663 | colorDepth:24 22:13:43 INFO - PROCESS | 1663 | Browser inner width/height: 1024/689 22:13:43 INFO - PROCESS | 1663 | __metrics 22:13:43 INFO - PROCESS | 1663 | 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" 22:13:43 INFO - PROCESS | 1663 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:13:43 INFO - TEST-INFO | 1663: exit 0 22:13:43 INFO - Browser initialized. 22:13:43 INFO - Running cycle 1/1 for kraken test... 22:13:43 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/tmp7SHWo1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:13:55 INFO - PROCESS | 1664 | RSS: Main: 233861120 22:13:55 INFO - PROCESS | 1664 | 22:13:58 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection) 22:13:59 INFO - PROCESS | 1664 | RSS: Main: 285687808 22:13:59 INFO - PROCESS | 1664 | 22:14:02 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-dft) 22:14:02 INFO - PROCESS | 1664 | RSS: Main: 286240768 22:14:02 INFO - PROCESS | 1664 | 22:14:06 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-fft) 22:14:06 INFO - PROCESS | 1664 | RSS: Main: 378994688 22:14:06 INFO - PROCESS | 1664 | 22:14:08 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator) 22:14:09 INFO - PROCESS | 1664 | RSS: Main: 281137152 22:14:09 INFO - PROCESS | 1664 | 22:14:11 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:14:11 INFO - PROCESS | 1664 | RSS: Main: 284405760 22:14:11 INFO - PROCESS | 1664 | 22:14:14 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom) 22:14:15 INFO - PROCESS | 1664 | RSS: Main: 325509120 22:14:15 INFO - PROCESS | 1664 | 22:14:18 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate) 22:14:18 INFO - PROCESS | 1664 | RSS: Main: 327503872 22:14:18 INFO - PROCESS | 1664 | 22:14:21 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial) 22:14:22 INFO - PROCESS | 1664 | RSS: Main: 326725632 22:14:22 INFO - PROCESS | 1664 | 22:14:24 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:14:24 INFO - PROCESS | 1664 | RSS: Main: 303104000 22:14:24 INFO - PROCESS | 1664 | 22:14:26 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:14:27 INFO - PROCESS | 1664 | RSS: Main: 306085888 22:14:27 INFO - PROCESS | 1664 | 22:14:29 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:14:30 INFO - PROCESS | 1664 | RSS: Main: 308338688 22:14:30 INFO - PROCESS | 1664 | 22:14:33 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:14:33 INFO - PROCESS | 1664 | RSS: Main: 324268032 22:14:33 INFO - PROCESS | 1664 | 22:14:36 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:14:37 INFO - PROCESS | 1664 | RSS: Main: 318021632 22:14:37 INFO - PROCESS | 1664 | 22:14:39 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49240/tests/kraken/driver.html?testName=ai-astar) 22:14:39 INFO - PROCESS | 1664 | RSS: Main: 362037248 22:14:39 INFO - PROCESS | 1664 | 22:14:39 INFO - PROCESS | 1664 | __start_tp_report 22:14:39 INFO - PROCESS | 1664 | _x_x_mozilla_page_load 22:14:39 INFO - PROCESS | 1664 | _x_x_mozilla_page_load_details 22:14:39 INFO - PROCESS | 1664 | |i|pagename|runs| 22:14:39 INFO - PROCESS | 1664 | |0;ai-astar;115;114;114;115;114;114;114;114;114;116 22:14:39 INFO - PROCESS | 1664 | |1;audio-beat-detection;136;110;108;141;110;112;107;124;128;95 22:14:39 INFO - PROCESS | 1664 | |2;audio-dft;187;183;184;184;184;182;181;183;183;185 22:14:39 INFO - PROCESS | 1664 | |3;audio-fft;82;59;59;88;60;60;58;58;73;59 22:14:39 INFO - PROCESS | 1664 | |4;audio-oscillator;88;69;86;70;70;70;83;70;70;107 22:14:39 INFO - PROCESS | 1664 | |5;imaging-gaussian-blur;94;131;81;129;88;117;134;86;89;86 22:14:39 INFO - PROCESS | 1664 | |6;imaging-darkroom;93;94;95;95;94;94;94;94;93;101 22:14:39 INFO - PROCESS | 1664 | |7;imaging-desaturate;110;137;141;87;86;85;87;88;87;86 22:14:39 INFO - PROCESS | 1664 | |8;json-parse-financial;57;58;58;58;56;58;59;56;55;55 22:14:39 INFO - PROCESS | 1664 | |9;json-stringify-tinderbox;66;72;72;74;75;74;69;72;71;71 22:14:39 INFO - PROCESS | 1664 | |10;stanford-crypto-aes;85;86;84;87;89;98;109;108;85;86 22:14:39 INFO - PROCESS | 1664 | |11;stanford-crypto-ccm;141;139;158;151;133;139;139;138;180;147 22:14:39 INFO - PROCESS | 1664 | |12;stanford-crypto-pbkdf2;171;170;172;170;172;178;170;172;173;228 22:14:39 INFO - PROCESS | 1664 | |13;stanford-crypto-sha256-iterative;68;66;68;68;67;67;68;77;84;85 22:14:39 INFO - PROCESS | 1664 | __end_tp_report 22:14:39 INFO - PROCESS | 1664 | __start_cc_report 22:14:39 INFO - PROCESS | 1664 | _x_x_mozilla_cycle_collect,2076 22:14:39 INFO - PROCESS | 1664 | __end_cc_report 22:14:39 INFO - PROCESS | 1664 | __startTimestamp1482905679584__endTimestamp 22:14:39 INFO - PROCESS | 1664 | 22:14:39 INFO - PROCESS | 1664 | ------- Summary: start ------- 22:14:39 INFO - PROCESS | 1664 | Number of tests: 14 22:14:39 INFO - PROCESS | 1664 | 22:14:39 INFO - PROCESS | 1664 | [#0] ai-astar Cycles:10 Average:114.40 Median:114.00 stddev:0.70 (0.6%) stddev-sans-first:0.71 22:14:39 INFO - PROCESS | 1664 | Values: 115.0 114.0 114.0 115.0 114.0 114.0 114.0 114.0 114.0 116.0 22:14:39 INFO - PROCESS | 1664 | 22:14:39 INFO - PROCESS | 1664 | [#1] audio-beat-detection Cycles:10 Average:117.10 Median:126.00 stddev:14.51 (11.5%) stddev-sans-first:13.68 22:14:39 INFO - PROCESS | 1664 | Values: 136.0 110.0 108.0 141.0 110.0 112.0 107.0 124.0 128.0 95.0 22:14:39 INFO - PROCESS | 1664 | 22:14:39 INFO - PROCESS | 1664 | [#2] audio-dft Cycles:10 Average:183.60 Median:184.00 stddev:1.65 (0.9%) stddev-sans-first:1.20 22:14:39 INFO - PROCESS | 1664 | Values: 187.0 183.0 184.0 184.0 184.0 182.0 181.0 183.0 183.0 185.0 22:14:39 INFO - PROCESS | 1664 | 22:14:39 INFO - PROCESS | 1664 | [#3] audio-fft Cycles:10 Average:65.60 Median:60.00 stddev:11.23 (18.7%) stddev-sans-first:10.22 22:14:39 INFO - PROCESS | 1664 | Values: 82.0 59.0 59.0 88.0 60.0 60.0 58.0 58.0 73.0 59.0 22:14:39 INFO - PROCESS | 1664 | 22:14:39 INFO - PROCESS | 1664 | [#4] audio-oscillator Cycles:10 Average:78.30 Median:70.00 stddev:12.61 (18.0%) stddev-sans-first:12.87 22:14:39 INFO - PROCESS | 1664 | Values: 88.0 69.0 86.0 70.0 70.0 70.0 83.0 70.0 70.0 107.0 22:14:39 INFO - PROCESS | 1664 | 22:14:39 INFO - PROCESS | 1664 | [#5] imaging-gaussian-blur Cycles:10 Average:103.50 Median:86.00 stddev:21.55 (25.1%) stddev-sans-first:22.58 22:14:39 INFO - PROCESS | 1664 | Values: 94.0 131.0 81.0 129.0 88.0 117.0 134.0 86.0 89.0 86.0 22:14:39 INFO - PROCESS | 1664 | 22:14:39 INFO - PROCESS | 1664 | [#6] imaging-darkroom Cycles:10 Average:94.70 Median:94.00 stddev:2.31 (2.5%) stddev-sans-first:2.37 22:14:39 INFO - PROCESS | 1664 | Values: 93.0 94.0 95.0 95.0 94.0 94.0 94.0 94.0 93.0 101.0 22:14:39 INFO - PROCESS | 1664 | 22:14:39 INFO - PROCESS | 1664 | [#7] imaging-desaturate Cycles:10 Average:99.40 Median:86.50 stddev:22.15 (25.6%) stddev-sans-first:23.16 22:14:39 INFO - PROCESS | 1664 | Values: 110.0 137.0 141.0 87.0 86.0 85.0 87.0 88.0 87.0 86.0 22:14:39 INFO - PROCESS | 1664 | 22:14:39 INFO - PROCESS | 1664 | [#8] json-parse-financial Cycles:10 Average:57.00 Median:58.00 stddev:1.41 (2.4%) stddev-sans-first:1.50 22:14:39 INFO - PROCESS | 1664 | Values: 57.0 58.0 58.0 58.0 56.0 58.0 59.0 56.0 55.0 55.0 22:14:39 INFO - PROCESS | 1664 | 22:14:39 INFO - PROCESS | 1664 | [#9] json-stringify-tinderbox Cycles:10 Average:71.60 Median:72.00 stddev:2.63 (3.7%) stddev-sans-first:1.86 22:14:39 INFO - PROCESS | 1664 | Values: 66.0 72.0 72.0 74.0 75.0 74.0 69.0 72.0 71.0 71.0 22:14:39 INFO - PROCESS | 1664 | 22:14:39 INFO - PROCESS | 1664 | [#10] stanford-crypto-aes Cycles:10 Average:91.70 Median:86.00 stddev:9.71 (11.3%) stddev-sans-first:9.99 22:14:39 INFO - PROCESS | 1664 | Values: 85.0 86.0 84.0 87.0 89.0 98.0 109.0 108.0 85.0 86.0 22:14:39 INFO - PROCESS | 1664 | 22:14:39 INFO - PROCESS | 1664 | [#11] stanford-crypto-ccm Cycles:10 Average:146.50 Median:144.00 stddev:13.86 (9.6%) stddev-sans-first:14.56 22:14:39 INFO - PROCESS | 1664 | Values: 141.0 139.0 158.0 151.0 133.0 139.0 139.0 138.0 180.0 147.0 22:14:39 INFO - PROCESS | 1664 | 22:14:39 INFO - PROCESS | 1664 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.60 Median:172.00 stddev:17.86 (10.4%) stddev-sans-first:18.79 22:14:39 INFO - PROCESS | 1664 | Values: 171.0 170.0 172.0 170.0 172.0 178.0 170.0 172.0 173.0 228.0 22:14:39 INFO - PROCESS | 1664 | 22:14:39 INFO - PROCESS | 1664 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.80 Median:68.00 stddev:7.36 (10.8%) stddev-sans-first:7.68 22:14:39 INFO - PROCESS | 1664 | Values: 68.0 66.0 68.0 68.0 67.0 67.0 68.0 77.0 84.0 85.0 22:14:39 INFO - PROCESS | 1664 | -------- Summary: end -------- 22:14:39 INFO - PROCESS | 1664 | 22:14:40 INFO - TEST-INFO | 1664: exit 0 22:14:40 INFO - TEST-OK | kraken | took 59808ms 22:14:40 INFO - SUITE-END | took 1178s 22:14:40 INFO - Completed test suite (00:19:39) 22:14:40 INFO - javascript benchmark 22:14:40 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3323.451630480971, 3323.58455193101, 3328.0015995201443, 3346.832658367081, 3385.492176173575, 5489.066858634032, 5529.861753456165, 5595.468430499106, 5625.1281051437045, 5655.010373184697, 5598.636088654245, 5627.577931655122, 5659.094544872821, 5668.433156684329, 5700.942990570093, 5057.443681195084, 5081.237814327844, 5103.540681338679, 5156.742162891859, 5187.533122019017, 5235.057689615867, 5254.527092561669, 5259.13224317989, 5272.736363181844, 5321.866953326159, 5060.696358218519, 5203.687778733288, 5226.451222621608, 5235.162374020158, 5262.578993680497, 5007.4992500749995, 5098.7705553249825, 5106.8212612558755, 5129.794808207667, 5145.665531740462, 5101.362329708786, 5137.948620513822, 5144.434112247687, 5148.897022059576, 5160.6129540284255, 5632.239647647556, 5678.858028549278, 5689.061304884689, 5690.516306113986, 5716.857078573027, 5974.402559744034, 6028.577999540034, 6038.486728628071, 6105.755769769204, 6193.938060619381, 6185.443310102068, 6198.194234749499, 6233.812985610439, 6239.812805615838, 6259.937400626027, 5710.942890571083, 5711.685857277848, 5715.399883012307, 5732.885342293131, 5779.537636989031, 6139.737467782926, 6206.503479721611, 6254.3745625437095, 6328.335524769925, 6354.364563543609, 5808.07070868659, 5908.822735317947, 5913.852153696106, 6034.155218269446, 6042.244719410074, 5040.949590504121, 6061.727222275008, 6131.50947924165, 6141.455323060992, 6142.590862071594, 5997.880042399128, 6030.909536356959, 6104.755809767648, 6112.877742445126, 6123.020316749388, 6104.398524058527, 6104.610739188311, 6124.116761842186, 6128.667102172749, 6134.110553969649, 4887.608991280689, 4892.510748925079, 4895.485973972753, 4924.704517728947, 4930.260460930889, 4325.6755743318745, 4347.369631403429, 4353.3469979503325, 4380.978095109568, 4408.4269045024585, 5623.184638227434, 5645.040343141676, 5659.236003139606, 5745.080787074048, 5745.9425405745405, 6238.594491358047, 6239.313675495692, 6246.812595622138, 6249.937500625027, 6261.686915654199, 5660.122680984454, 5664.915026274527, 5721.799737009184, 5726.455986681259, 5778.422157784272, 5232.3721153461065, 5263.552598029134, 5282.102042652758, 5310.9734451327495, 5332.120200167006, 6244.625322480575, 6311.368863113652, 6312.6528040956355, 6320.146780184617, 6333.018382150774, 6214.40963108504, 6231.626102433687, 6239.500839932885, 6261.812145635638, 6268.905966410416, 6201.906971395523, 6284.4344009039405, 6298.937010630018, 6313.589616674902, 6319.6208227506495, 5940.346561878104, 6081.148639190428, 6105.511559075352, 6125.816225513242, 6172.567920245539, 6224.62652240867, 6225.595336303126, 6232.937670623236, 6248.343923887926, 6253.530985176039, 6040.063790112539, 6040.486558642565, 6065.909011364744, 6096.329403765493, 6098.176746139215, 5649.887002260014, 5910.231669882888, 5959.344472107978, 5979.2824861015215, 5994.910076348771, 5864.120381942743, 5880.235569375954, 5891.675957822361, 5894.233749612523, 5925.555583331181, 5835.358110607915, 5889.823305300848, 5897.5576831736935, 5899.4985426239255, 5924.259467566554, 5755.309362876482, 5792.333881603669, 5796.536277097906, 5811.82564523065, 5838.065909454467], "value": 5634.832979369151, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [481.7783819443056, 486.8801755961293, 506.6782593053412, 508.0575532387421, 511.6392942975203, 29854.955076572325, 39721.2055758885, 41378.0, 41503.58496415035, 41509.792451037705, 42118.78940605293, 42547.78726106373, 42593.0, 42638.786806066004, 42641.78679106608, 8287.088420273765, 9250.583723732432, 9458.62165513382, 9460.006699296579, 9485.146336829685, 8396.370272229577, 8598.87101693475, 8607.31141508681, 8608.698695545658, 11092.944535277313, 11148.777024459507, 11439.370834604093, 11449.599264025761, 11457.0, 11464.656060318162, 10426.582936682524, 10500.474976251233, 11268.830967535494, 11356.602518911799, 11374.0, 11038.282511636799, 11158.93990070942, 11179.049780768644, 11260.54957801687, 11305.773884522347, 39729.0, 39904.00239994023, 39916.0, 39931.40102898459, 40004.599954000376, 3250.4799232122746, 3979.9801000994808, 4008.3386241270155, 4010.859619913318, 4027.0335119571387, 29315.973940911965, 30150.24624384386, 30342.29694397372, 30534.0, 30588.776448942015, 3131.4989601663624, 3253.1541799132397, 3269.54226408303, 3291.5556399886186, 3331.850066747002, 339395.0, 339602.0, 340691.0, 340696.0, 342979.0, 61719.76560468766, 61888.69055654693, 62201.37798622046, 62304.0, 62415.0, 63963.76579747218, 64240.678796605716, 64730.02904956429, 64879.0, 65053.67473162604, 15309.846901531066, 15759.606009849616, 15788.36846526149, 15801.762973555406, 15813.209339532978, 12144.271343719229, 12189.634310970685, 12208.206466579644, 12208.877911220952, 12230.205036672587, 15583.376664933276, 16040.43858464948, 17482.562935926704, 18015.0, 18171.636567268844, 6465.256495502983, 6498.512611554058, 6543.770968016097, 6567.605943643397, 6574.671266436659, 837.5895811052541, 839.9244068033784, 842.30509829391, 858.6651206029653, 875.8248350329959, 28890.110439258326, 29373.11880643584, 29704.0, 29953.550696739567, 30020.54969175464, 964.5900492290748, 1030.407515678488, 1062.3307316390624, 1072.7157303314627, 1129.5764088466824, 15213.0, 15295.158766267961, 15420.383184672712, 15638.765418518731, 15665.843341566666, 649744.0, 665586.0, 667179.95380416, 859667.0, 861665.0, 50417.0, 50878.98242035213, 50930.49069509257, 50945.0, 50988.49011509911, 1341.496938648007, 1367.5282027700227, 1369.8013788000685, 1371.8765311122047, 1382.8755412012968, 27322.180334589993, 27422.725772742815, 27463.450730985667, 27907.720922791323, 28115.156545303675, 43906.0, 44458.77770611256, 44459.33311000273, 44464.0, 44504.77747611241, 441916.0, 444119.11761763936, 445340.54659454286, 447212.0, 448637.0, 23924.282271531883, 24189.51620967606, 24208.878955605815, 24225.39436514101, 24234.0, 33971.320573588884, 34886.65113348936, 39838.20323593454, 40814.59185408108, 44678.77660611806, 34880.47679284863, 35076.82461587676, 35141.47287790736, 36021.81989090038, 36514.452283216306, 34240.65759342375, 34490.82754586211, 35203.0, 35371.64628353684, 35546.822265888506, 33825.646974121744, 34064.14839629029, 34397.82801086079, 34463.82768086143, 34959.475607865395, 29882.8505857478, 30381.088567343017, 30571.847140764155, 30976.69023309828, 31167.0, 23442.413939651644, 23538.882305589046, 23712.40718982039, 23865.926033328196, 23880.0], "value": 19141.48119185885, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [353.7594435783666, 372.97948612826286, 379.7835233916668, 380.6421963354447, 404.54690746364076, 14226.0, 14685.559433217008, 15154.469593564214, 18714.71927921082, 19850.60298794027, 19389.612207755872, 19448.70826937597, 19891.900540497278, 20061.799382006175, 20303.796962030374, 5647.548196144309, 5923.318818335882, 5925.229720136376, 5927.555433342495, 5934.465898069173, 5470.152126420411, 5772.740226689788, 5827.096799995987, 5829.0, 5833.766649334021, 4057.8173982170874, 4298.183345164409, 5779.624324418899, 6253.43719065284, 6409.2949775524885, 6438.806835794933, 6478.611283322968, 6494.480441564663, 6494.902576461357, 6501.609903405811, 4756.904861902743, 5512.283403157564, 5580.079286917654, 5589.105743081088, 5596.664200148004, 22972.310830675106, 23196.072157113864, 23623.0, 23843.284701458982, 24246.15138470145, 2314.271004633552, 2676.2105178972056, 2677.6652918385203, 2686.3821321096257, 2747.821391609539, 14157.858421415756, 14665.70668586633, 14881.627959300995, 15072.321745521478, 15223.688605916152, 1799.5141311845728, 1808.7829460464698, 1823.2706917233081, 1827.0408035781188, 1842.4288470574031, 102630.0, 105039.79570544229, 106088.0, 106823.93176068217, 108066.379004315, 10276.537555810006, 10339.741506462247, 10459.581616735397, 10577.576896924036, 10611.204159688054, 10975.890241097646, 13081.280529570775, 13087.149335293176, 13093.672658183621, 13124.409401576951, 7862.764117076496, 8004.399670024772, 8013.315539294402, 8034.276915077671, 8183.304419124322, 7729.381649468029, 7743.806404839811, 7993.8401231976195, 8176.468529545679, 8192.590370481572, 6279.717412716439, 6362.172917520693, 6373.458256048288, 6380.425761681378, 6395.808125756234, 1667.9491920090265, 1803.9549011274823, 1810.08590661715, 1818.6817306971227, 1838.6230822681318, 911.4485736129678, 988.728099772554, 1015.167562598662, 1035.9792804143874, 1079.255313833468, 18373.356932507297, 19578.67660987211, 20683.27608533694, 20698.896505517376, 20819.1672333108, 1043.4417586591137, 1046.210111365914, 1051.3429106808244, 1057.8201705710048, 1073.0932362153967, 10279.0, 11166.497507612177, 11174.38540880243, 11183.608573700045, 11264.94367528157, 436690.0, 442823.0, 442981.06585203746, 535537.0, 545236.2738186363, 95892.52053739686, 99484.0, 124480.37759811143, 127199.0, 135097.9735303971, 994.9303548751662, 1019.5463018956493, 1031.1080915008463, 1032.9129923864652, 1042.890496497872, 15512.636530335609, 15697.979631323928, 18048.368307109715, 18307.63384732271, 18590.721139182653, 41298.58701412947, 51078.48921510737, 51289.71775705637, 52244.0, 53225.20162197493, 73335.0, 73696.0, 75014.0, 76507.61746191233, 76845.00507466997, 7402.9629851850395, 7787.727429539939, 7970.442069055111, 7988.041435027834, 8022.9197708022175, 7264.128304603481, 10786.784264314827, 11808.409579521162, 12403.75192496163, 12688.555900543437, 9338.334823457688, 10567.154627629925, 10692.0, 10751.408672523086, 10819.945900270448, 10606.575736970435, 10699.572017119228, 10746.140308775435, 11115.277506962022, 11131.276467029616, 10814.243002989715, 10910.399928004035, 11053.944730276296, 11063.280886742335, 11202.719932001764, 7714.614269286401, 7775.883361749691, 7941.245581669732, 7975.1227364988645, 8019.679212831421, 9264.95367523158, 10303.227257955534, 10489.737756555842, 10707.892921071001, 10962.945185274022], "value": 9383.909483478894, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1287.5107459165515, 1376.4700590272748, 1400.1459109942928, 1401.9719605607888, 1404.3048690898006, 7938.801529961746, 8229.794255143624, 8351.123132071136, 8359.373047021474, 8364.12176721444, 6573.539852210347, 7355.6322183890725, 7597.012388389516, 7605.885911711329, 8088.757337279891, 2214.7010153629217, 2280.441291883491, 2287.1537531113513, 2287.3709729824263, 2294.0, 2224.5773303072447, 2304.043821813945, 2324.976750232493, 2325.197806756666, 2325.988370058156, 2004.3686238834794, 2077.1172251793005, 2079.095593416861, 2183.825293976478, 2245.124401483423, 2284.360379093848, 2296.8966396512283, 2299.0, 2304.3893368257427, 2306.4695120122296, 2254.0420321363354, 2281.8744969026698, 2288.187693368863, 2289.553537060274, 2293.346396277053, 8140.308073813733, 8141.063777665585, 8235.341172706168, 8238.670453181865, 8244.505329680238, 4935.950640493585, 5113.795448182068, 5115.846524604231, 5124.103281925648, 5124.590032797368, 3189.3142974260413, 3329.916752081193, 3375.5274261603395, 3379.509971054208, 3398.796072235674, 1154.5266440759333, 1181.80500217464, 1184.5498710490044, 1227.09808290907, 1230.280286032663, 10048.045435683598, 10450.581976720921, 10677.466126693713, 10681.732956676145, 10698.197635177392, 4379.58393952574, 4451.221036318631, 4485.102979404132, 4569.29175977724, 4591.104734576792, 9318.347715659838, 9328.673496427593, 9341.392809467363, 9342.299327550325, 9344.112309330603, 5134.0, 5136.203888397305, 5140.306058682105, 5145.176771716508, 5150.0, 4636.559526844944, 4646.372739680168, 4662.044280922402, 4678.345031695565, 4709.222978208626, 3330.2673411849355, 3462.79223246606, 3651.415773476231, 3664.3037822814113, 3734.402495600691, 1208.3354155214772, 1232.3468561662332, 1234.3519652182677, 1245.4582256718174, 1248.719038216394, 623.3486007122568, 751.9511231769917, 758.7956984958594, 760.0803028335775, 779.4582764978398, 4816.34979277793, 5270.736463176826, 5280.471952804765, 5364.739286267724, 5451.345838499326, 483.8040593559614, 518.4911906352571, 530.6046994988758, 539.7921800106909, 653.0139489371084, 3201.3437245364644, 3270.787398819069, 3675.448682697563, 3705.7591256568235, 3765.3787125124595, 9391.577379017961, 9678.661246856327, 9779.26655500826, 9780.070893265129, 9824.164945979528, 8221.671133154727, 9716.514174291398, 9792.951035244778, 9823.656172033945, 9838.0, 6711.295313991961, 7283.053203083671, 7664.578448185403, 7670.271324224078, 7693.653785579662, 4899.779509922133, 5332.1202001670845, 5387.595930305321, 5392.325959255093, 5599.412061733622, 7144.678489467882, 7561.054868141482, 8499.113845908785, 9039.548022598976, 9081.137291957253, 8972.41679290844, 9200.907990920274, 9229.815403691755, 9270.44377337335, 9272.304577156605, 6869.690863911236, 6945.617991010543, 6959.686814093479, 7061.0, 7064.752733654503, 7623.542587444549, 7708.8843667346155, 7710.228977102245, 7728.4203684725, 7732.30409263169, 6784.457243420416, 6979.115638777603, 7024.578525288499, 7081.044059051962, 7107.218205997439, 6803.422021211768, 6882.174139103339, 6900.896486552605, 6981.685824137824, 7062.964685176541, 6230.937690623035, 6241.251049874044, 6314.242290924934, 6319.399657032574, 6322.304546499973, 4586.151561961048, 4641.234196357698, 4655.953440465552, 4692.601128904082, 4761.214399624023, 4697.0840686065885, 4705.670603057753, 4773.1408346497965, 4851.442084160367, 4924.261360795909], "value": 4204.048322781608, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.91997840496686, 351.8205715085306, 357.2266044014708, 366.8825975687781, 368.981550922454, 5159.78129404356, 5212.739363031843, 5248.448912864147, 5261.36965493809, 5264.3682758069035, 3815.5802861685193, 4970.478099799523, 4987.680728461265, 5054.494550544944, 5067.923981140286, 3852.112464892197, 4023.1551374211376, 4052.7770972596495, 4070.6946978976694, 4080.9591904081026, 3680.4479328100733, 4028.5769994150633, 4028.939565906514, 4062.756234625917, 4077.633013028827, 3668.7982160981137, 3673.797941113238, 3825.016487140035, 3859.9614003860024, 3947.881563553098, 3253.3493301339827, 3898.2203559288255, 3901.0, 3904.629060239273, 3934.072304570754, 2827.8303301801957, 3380.509826075205, 3393.86424543018, 3408.199073217792, 3412.9658703412897, 4848.636352273594, 5034.219695947134, 5037.596992240612, 5051.772670229849, 5085.999260436323, 4153.8961525961795, 4312.396264522969, 4414.6688998324935, 4466.731996080245, 4468.620167285752, 4916.336294600219, 5388.275751759438, 5423.484768946943, 5427.701476418795, 5452.890942181174, 3417.7607567470286, 3473.079633896994, 3671.541057367829, 3682.6869716074166, 3699.6855267302312, 4794.760261986887, 4986.354503013997, 5400.945990540123, 5638.661680299195, 5710.2576665033275, 3639.94540081899, 3721.4555959277136, 3745.550533935945, 3759.1166075972124, 3791.696664266852, 3384.2216290252886, 3808.847646094125, 3830.6552410283207, 3849.9615003850163, 3866.7679939203176, 3715.6655900968503, 4100.692448066347, 4155.667546596265, 4489.2592722201125, 4502.5047244803045, 4529.867533116721, 4712.410948631421, 5468.945310546923, 5548.528375088163, 5559.360673522516, 1021.8861441029313, 1140.3670962615827, 1238.578883179723, 1241.931693756834, 1265.9935351395663, 757.8863170524465, 809.688270016048, 826.1779529368317, 844.8943882014748, 857.0914830279924, 4010.598940105966, 4529.977350113229, 4822.5050234426735, 4854.68444551103, 4863.659543831898, 4887.413510378778, 5235.0, 5360.276362691065, 5382.623216374816, 5429.212764149255, 4189.512722983358, 4649.767511624405, 4692.366530518337, 4713.6431784107945, 4872.634552408584, 2459.1270099114913, 2690.636764036869, 2721.3468767496056, 2735.439234956869, 2753.848538330411, 4501.729896206172, 5149.716765577929, 5417.729113544307, 5462.820941979389, 5511.834644960658, 4631.0, 4918.9089292582, 5047.899042019139, 5084.186530155232, 5370.543503802221, 4638.814447422065, 5308.628396012319, 5372.931421402866, 5391.379991301051, 5394.64934779246, 4863.586595139453, 5013.298138260573, 5242.584502184312, 5261.500157485033, 5261.631685781958, 5135.793088624096, 5541.753105551219, 5555.111422004532, 5578.972105139448, 5605.113366616278, 4750.501197374226, 5183.159978207833, 5768.625354167859, 5802.085311846996, 5865.648061116347, 3338.4324664806554, 3634.6183650716293, 3651.342758303531, 3783.4703141560753, 3798.164403831153, 5088.592912566912, 5396.541293990055, 5610.719464026701, 5668.773249069991, 5672.262605861211, 4635.026644404713, 5495.313085864267, 5511.724413779375, 5529.253550770594, 5544.306961629796, 4880.438749543848, 5096.050161747686, 5287.762050707651, 5464.972675136599, 5496.230527726201, 5086.694798312114, 5317.574594032545, 5395.973020134874, 5415.9419420619915, 5428.430014848384, 5126.743947732829, 5274.340707411574, 5366.5170134686505, 5494.313210848644, 5528.226048353315, 4521.756516957731, 5334.706591137525, 5383.0, 5386.865328366822, 5394.244805727123], "value": 3920.786810321145, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [717.6124892558018, 730.7296300368864, 766.0118447203108, 767.9769606911793, 819.5123901278739, 14514.0, 15136.470223542177, 15181.92409037956, 15183.772243416357, 15187.696246075071, 13737.725245495085, 13958.44166233352, 13987.860121398782, 14005.509807156726, 14008.439662413517, 683.5010442377068, 709.8012556484192, 711.4664001998501, 848.9490630562156, 856.1224744636751, 874.3398733955886, 877.9604917778684, 883.9381243312971, 890.9599068041921, 895.0691281067682, 942.2885721280423, 943.2972435535536, 943.3019565521499, 944.598545618113, 944.8393773058563, 613.929398119213, 939.2720641502856, 939.7697564096806, 941.4539567051094, 941.7174847545778, 554.7697705452232, 560.2492659835799, 561.3460318728676, 562.1174755633657, 656.579788935082, 14663.046901951338, 14725.337359818726, 14784.186869722162, 15194.620134496727, 15268.31292591836, 12306.384680766016, 12315.199512031779, 12354.629361119181, 12451.066170037193, 12482.750344993141, 12534.373281335897, 12591.307478088702, 12810.10329276951, 12890.613281601567, 13214.603561893158, 4512.390827238313, 4623.514530974267, 4640.628749700016, 4652.906941861143, 4672.182368085605, 70867.64566177188, 70887.0, 70978.0, 77919.0, 104839.0, 10602.045815876454, 11092.445377731243, 11154.330740155616, 11155.442227888572, 11276.267042642203, 21327.36017919465, 21619.135234590754, 21680.89159554192, 21811.563768724536, 21892.671609925863, 7498.962505187439, 7651.3496352810635, 7746.767596972099, 7752.341051010614, 7892.9210707893335, 3997.700172487075, 4116.238495878272, 4117.649999749995, 4120.361343991686, 4122.6083522065355, 3335.566376371105, 3405.8637654494037, 3435.484677298375, 3436.1753179236803, 3449.3101379724158, 625.4433554136822, 628.4438272129198, 632.40237975114, 640.8814369341686, 641.0160403780175, 577.7140315543749, 582.260529128005, 606.7269728622138, 613.8035828534826, 626.7900253415066, 8461.404425858065, 8673.306135509145, 8751.474911505331, 8837.248833849195, 8922.197002269826, 508.4915084915085, 574.704027425876, 621.5898202254994, 628.1457218183267, 629.6725702634604, 4058.2086493133556, 4300.6989510734575, 4308.784560771949, 4363.432753741993, 4414.690971631955, 99149.0, 100126.0, 101665.49167254116, 102452.0, 103858.0, 86076.0, 86663.0, 88330.55834720786, 88917.0, 90531.54734226418, 61976.76046479045, 66059.33940660628, 67123.66438167875, 69479.30520694729, 71369.0, 9054.139856713602, 9192.724218273463, 9742.805143896941, 9843.360181588174, 9922.950385248028, 48458.0, 48476.0, 49096.50903490919, 51605.48394516007, 51702.0, 49267.753661231465, 50068.49931500638, 64659.353406465336, 65092.67453662701, 65815.67092164508, 12332.630021099381, 12411.689707757378, 12594.992400607745, 12595.0, 12907.031382781825, 12623.800738929787, 13994.37025333842, 14252.0, 14764.040337378035, 14844.851551484779, 13009.869901300866, 13065.346732663518, 13442.93278533601, 13603.115797473132, 13916.165030098227, 12916.741665166832, 13155.473781048651, 13224.867751322363, 13313.068085234328, 13368.264745439094, 12125.636230913087, 12177.634670959886, 12944.857840128281, 13027.47890084386, 13869.791953120914, 10730.0, 11042.834357484804, 11164.633054001884, 11200.551977920792, 11500.53997840077, 10248.64129755455, 10335.379877207391, 10346.32748871321, 10371.58513659445, 10379.273450858367], "value": 7208.930028269383, "unit": "score"}], "name": "dromaeo_css", "value": 7025.872534691962, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 114.0, 114.0, 115.0, 114.0, 114.0, 114.0, 114.0, 114.0, 116.0], "value": 114.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 110.0, 108.0, 141.0, 110.0, 112.0, 107.0, 124.0, 128.0, 95.0], "value": 117.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 183.0, 184.0, 184.0, 184.0, 182.0, 181.0, 183.0, 183.0, 185.0], "value": 183.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 59.0, 59.0, 88.0, 60.0, 60.0, 58.0, 58.0, 73.0, 59.0], "value": 65.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 69.0, 86.0, 70.0, 70.0, 70.0, 83.0, 70.0, 70.0, 107.0], "value": 78.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 131.0, 81.0, 129.0, 88.0, 117.0, 134.0, 86.0, 89.0, 86.0], "value": 103.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 95.0, 95.0, 94.0, 94.0, 94.0, 94.0, 93.0, 101.0], "value": 94.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 137.0, 141.0, 87.0, 86.0, 85.0, 87.0, 88.0, 87.0, 86.0], "value": 99.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 58.0, 58.0, 58.0, 56.0, 58.0, 59.0, 56.0, 55.0, 55.0], "value": 57.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 72.0, 72.0, 74.0, 75.0, 74.0, 69.0, 72.0, 71.0, 71.0], "value": 71.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 84.0, 87.0, 89.0, 98.0, 109.0, 108.0, 85.0, 86.0], "value": 91.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 139.0, 158.0, 151.0, 133.0, 139.0, 139.0, 138.0, 180.0, 147.0], "value": 146.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 170.0, 172.0, 170.0, 172.0, 178.0, 170.0, 172.0, 173.0, 228.0], "value": 177.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 68.0, 68.0, 67.0, 67.0, 68.0, 77.0, 84.0, 85.0], "value": 71.8, "unit": "score"}], "name": "kraken", "value": 1472.8, "alertThreshold": 2.0}]} 22:14:40 INFO - Return code: 0 22:14:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:14:40 INFO - # TBPL SUCCESS # 22:14:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:14:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:14:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:14:40 INFO - 2016-12-27 22:14:40.867 screenresolution[1668:12699] starting screenresolution argv=screenresolution get 22:14:40 INFO - 2016-12-27 22:14:40.870 screenresolution[1668:12699] Display 0: 1600x1200x32@60 22:14:40 INFO - 2016-12-27 22:14:40.876 screenresolution[1669:12702] starting screenresolution argv=screenresolution list 22:14:40 INFO - Available Modes on Display 0 22:14:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:14:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:14:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:14:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:14:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:14:41 INFO - Intel Iris: 22:14:41 INFO - Chipset Model: Intel Iris 22:14:41 INFO - Type: GPU 22:14:41 INFO - Bus: Built-In 22:14:41 INFO - VRAM (Dynamic, Max): 1536 MB 22:14:41 INFO - Vendor: Intel (0x8086) 22:14:41 INFO - Device ID: 0x0a2e 22:14:41 INFO - Revision ID: 0x0009 22:14:41 INFO - Displays: 22:14:41 INFO - Display: 22:14:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:14:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:14:41 INFO - Display Serial Number: 1600x1200 60 22:14:41 INFO - Main Display: Yes 22:14:41 INFO - Mirror: Off 22:14:41 INFO - Online: Yes 22:14:41 INFO - Rotation: Supported 22:14:41 INFO - Return code: 0 22:14:41 INFO - Running post-action listener: _resource_record_post_action 22:14:41 INFO - [mozharness: 2016-12-28 06:14:41.112351Z] Finished run-tests step (success) 22:14:41 INFO - Running post-run listener: _resource_record_post_run 22:14:41 INFO - Total resource usage - Wall time: 1201s; CPU: 54.0%; Read bytes: 33426432; Write bytes: 552200192; Read time: 306; Write time: 1700 22:14:41 INFO - TinderboxPrint: CPU usage
53.8% 22:14:41 INFO - TinderboxPrint: I/O read bytes / time
33,426,432 / 306 22:14:41 INFO - TinderboxPrint: I/O write bytes / time
552,200,192 / 1,700 22:14:41 INFO - TinderboxPrint: CPU idle
2,219.5 (46.2%) 22:14:41 INFO - TinderboxPrint: CPU system
962.2 (20.0%) 22:14:41 INFO - TinderboxPrint: CPU user
1,623.9 (33.8%) 22:14:41 INFO - TinderboxPrint: Swap in / out
278,147,072 / 0 22:14:41 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 249690624; Write bytes: 245225472; Read time: 18239; Write time: 463 22:14:41 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 29027328; Write bytes: 297558016; Read time: 274; Write time: 1205 22:14:41 INFO - Running post-run listener: _upload_blobber_files 22:14:41 INFO - Blob upload gear active. 22:14:41 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:14:41 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 22:14:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:14:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:14:41 INFO - (blobuploader) - INFO - Open directory for files ... 22:14:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:14:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:14:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:14:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:14:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:14:42 INFO - (blobuploader) - INFO - Done attempting. 22:14:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:14:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:14:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:14:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:14:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:14:43 INFO - (blobuploader) - INFO - Done attempting. 22:14:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 22:14:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:14:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:14:44 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 22:14:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:14:44 INFO - (blobuploader) - INFO - Done attempting. 22:14:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:14:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:14:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:14:46 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:14:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:14:46 INFO - (blobuploader) - INFO - Done attempting. 22:14:46 INFO - (blobuploader) - INFO - Iteration through files over. 22:14:46 INFO - Return code: 0 22:14:46 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:14:46 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:14:46 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bcae2e98bcf26072ac8664d8fd8526b9cba0504a29c0332a937f3d7eb627f56944793c6e860cf82299392a145290b10b95c463432f8a41c59039e223bb517b25", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/15e788bac2e87ea46fa9fa8276ec1b88ed3d930bd7256657065389249651f23dbf0e54b8d093e344e7f2c1615264f01cdd577eb7057b43ef7beaa21da0cd1d70", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7f17000934738d0f9a7f52c5737954cd58e15e8069373bd380ab09234ab1df32ab609b0180a4d42225773c776571df85ec4ab5a975c516c3e037a57c37fe747a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4b6cdeeeb1fd53acefb9cc99031b618f54be33c1a6cbfd79ae3712486de41a2be8588f805d0659b0fbe4de806b3cb04a69c005d26b1b0efaf5e5f6ab1a3df688"} 22:14:46 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:14:46 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:14:46 INFO - Contents: 22:14:46 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bcae2e98bcf26072ac8664d8fd8526b9cba0504a29c0332a937f3d7eb627f56944793c6e860cf82299392a145290b10b95c463432f8a41c59039e223bb517b25", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/15e788bac2e87ea46fa9fa8276ec1b88ed3d930bd7256657065389249651f23dbf0e54b8d093e344e7f2c1615264f01cdd577eb7057b43ef7beaa21da0cd1d70", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7f17000934738d0f9a7f52c5737954cd58e15e8069373bd380ab09234ab1df32ab609b0180a4d42225773c776571df85ec4ab5a975c516c3e037a57c37fe747a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4b6cdeeeb1fd53acefb9cc99031b618f54be33c1a6cbfd79ae3712486de41a2be8588f805d0659b0fbe4de806b3cb04a69c005d26b1b0efaf5e5f6ab1a3df688"} 22:14:46 INFO - Running post-run listener: copy_logs_to_upload_dir 22:14:46 INFO - Copying logs to upload dir... 22:14:46 INFO - mkdir: /builds/slave/test/build/upload/logs 22:14:46 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1263.111214 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2016-12-27 22:14:46.820931) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-27 22:14:46.828744) ========= 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.y0JEsVsY8E/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.7ExKa1K88E/Listeners TMPDIR=/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bcae2e98bcf26072ac8664d8fd8526b9cba0504a29c0332a937f3d7eb627f56944793c6e860cf82299392a145290b10b95c463432f8a41c59039e223bb517b25", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/15e788bac2e87ea46fa9fa8276ec1b88ed3d930bd7256657065389249651f23dbf0e54b8d093e344e7f2c1615264f01cdd577eb7057b43ef7beaa21da0cd1d70", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7f17000934738d0f9a7f52c5737954cd58e15e8069373bd380ab09234ab1df32ab609b0180a4d42225773c776571df85ec4ab5a975c516c3e037a57c37fe747a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4b6cdeeeb1fd53acefb9cc99031b618f54be33c1a6cbfd79ae3712486de41a2be8588f805d0659b0fbe4de806b3cb04a69c005d26b1b0efaf5e5f6ab1a3df688"} build_url:https://queue.taskcluster.net/v1/task/KAwfhjqJQ2KzE0s2BaWa_g/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011191 build_url: 'https://queue.taskcluster.net/v1/task/KAwfhjqJQ2KzE0s2BaWa_g/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bcae2e98bcf26072ac8664d8fd8526b9cba0504a29c0332a937f3d7eb627f56944793c6e860cf82299392a145290b10b95c463432f8a41c59039e223bb517b25", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/15e788bac2e87ea46fa9fa8276ec1b88ed3d930bd7256657065389249651f23dbf0e54b8d093e344e7f2c1615264f01cdd577eb7057b43ef7beaa21da0cd1d70", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7f17000934738d0f9a7f52c5737954cd58e15e8069373bd380ab09234ab1df32ab609b0180a4d42225773c776571df85ec4ab5a975c516c3e037a57c37fe747a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4b6cdeeeb1fd53acefb9cc99031b618f54be33c1a6cbfd79ae3712486de41a2be8588f805d0659b0fbe4de806b3cb04a69c005d26b1b0efaf5e5f6ab1a3df688"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-27 22:14:46.865798) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-27 22:14:46.866080) ========= 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.y0JEsVsY8E/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.7ExKa1K88E/Listeners TMPDIR=/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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.005085 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-27 22:14:46.913755) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-27 22:14:46.914022) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-27 22:14:46.914291) ========= ========= Total master_lag: 0.20 =========