builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0088 starttime: 1484217057.4 results: success (0) buildid: 20170112011317 builduid: 0192be41b43b4ea99780dfd6d69b904c revision: a2425e6d32feebaa6a0ae749be2d888fc3d11e92 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-12 02:30:57.404044) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-12 02:30:57.404498) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-12 02:30:57.404772) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-12 02:30:57.432219) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-12 02:30:57.432615) ========= 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.lrG39ET1py/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.YGedVUnw6T/Listeners TMPDIR=/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-01-12 02:30:57-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 14.8M=0.001s 2017-01-12 02:30:57 (14.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.130669 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-12 02:30:57.588736) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-12 02:30:57.589229) ========= 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.lrG39ET1py/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.YGedVUnw6T/Listeners TMPDIR=/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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.027343 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-12 02:30:57.639574) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-12 02:30:57.640019) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev a2425e6d32feebaa6a0ae749be2d888fc3d11e92 --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 a2425e6d32feebaa6a0ae749be2d888fc3d11e92 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.lrG39ET1py/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.YGedVUnw6T/Listeners TMPDIR=/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-01-12 02:30:57,733 truncating revision to first 12 chars 2017-01-12 02:30:57,733 Setting DEBUG logging. 2017-01-12 02:30:57,733 attempt 1/10 2017-01-12 02:30:57,733 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/a2425e6d32fe?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-12 02:30:58,787 unpacking tar archive at: mozilla-central-a2425e6d32fe/testing/mozharness/ program finished with exit code 0 elapsedTime=1.337253 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-12 02:30:59.001239) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-12 02:30:59.001563) ========= script_repo_revision: a2425e6d32feebaa6a0ae749be2d888fc3d11e92 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-12 02:30:59.001960) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-12 02:30:59.002250) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-12 02:30:59.023295) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2017-01-12 02:30:59.023851) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.lrG39ET1py/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.YGedVUnw6T/Listeners TMPDIR=/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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 02:30:59 INFO - MultiFileLogger online at 20170112 02:30:59 in /builds/slave/test 02:30:59 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 02:30:59 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:30:59 INFO - {'append_to_log': False, 02:30:59 INFO - 'base_work_dir': '/builds/slave/test', 02:30:59 INFO - 'blob_upload_branch': 'Firefox', 02:30:59 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:30:59 INFO - 'branch': 'Firefox', 02:30:59 INFO - 'buildbot_json_path': 'buildprops.json', 02:30:59 INFO - 'config_files': ('talos/mac_config.py',), 02:30:59 INFO - 'default_actions': ('clobber', 02:30:59 INFO - 'read-buildbot-config', 02:30:59 INFO - 'download-and-extract', 02:30:59 INFO - 'populate-webroot', 02:30:59 INFO - 'create-virtualenv', 02:30:59 INFO - 'install', 02:30:59 INFO - 'run-tests'), 02:30:59 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:30:59 INFO - 'download_minidump_stackwalk': True, 02:30:59 INFO - 'download_symbols': 'ondemand', 02:30:59 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:30:59 INFO - 'tooltool.py': '/tools/tooltool.py', 02:30:59 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:30:59 INFO - '/tools/misc-python/virtualenv.py')}, 02:30:59 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:30:59 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:30:59 INFO - 'installer_path': 'installer.exe', 02:30:59 INFO - 'log_level': 'info', 02:30:59 INFO - 'log_name': 'talos', 02:30:59 INFO - 'log_to_console': True, 02:30:59 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:30:59 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:30:59 INFO - 'opt_config_files': (), 02:30:59 INFO - 'pip_index': False, 02:30:59 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:30:59 INFO - 'cmd': ('bash', 02:30:59 INFO - '-c', 02:30:59 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:30:59 INFO - 'enabled': True, 02:30:59 INFO - 'halt_on_failure': False, 02:30:59 INFO - 'name': 'check_screen_resolution'},), 02:30:59 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:30:59 INFO - 'cmd': ('bash', 02:30:59 INFO - '-c', 02:30:59 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:30:59 INFO - 'enabled': True, 02:30:59 INFO - 'halt_on_failure': False, 02:30:59 INFO - 'name': 'check_screen_resolution'},), 02:30:59 INFO - 'run_cmd_checks_enabled': True, 02:30:59 INFO - 'sps_profile': False, 02:30:59 INFO - 'sps_profile_interval': 0, 02:30:59 INFO - 'suite': 'dromaeojs-e10s', 02:30:59 INFO - 'system_bits': '32', 02:30:59 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:30:59 INFO - 'title': 't-yosemite-r7-0088', 02:30:59 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:30:59 INFO - 'use_talos_json': True, 02:30:59 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:30:59 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:30:59 INFO - 'work_dir': 'build'} 02:30:59 INFO - [mozharness: 2017-01-12 10:30:59.231356Z] Running clobber step. 02:30:59 INFO - Running pre-action listener: _resource_record_pre_action 02:30:59 INFO - Running main action method: clobber 02:30:59 INFO - rmtree: /builds/slave/test/build 02:30:59 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:31:02 INFO - Running post-action listener: _resource_record_post_action 02:31:02 INFO - [mozharness: 2017-01-12 10:31:02.184273Z] Finished clobber step (success) 02:31:02 INFO - [mozharness: 2017-01-12 10:31:02.184400Z] Running read-buildbot-config step. 02:31:02 INFO - Running pre-action listener: _resource_record_pre_action 02:31:02 INFO - Running main action method: read_buildbot_config 02:31:02 INFO - Using buildbot properties: 02:31:02 INFO - { 02:31:02 INFO - "project": "", 02:31:02 INFO - "product": "firefox", 02:31:02 INFO - "script_repo_revision": "production", 02:31:02 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 02:31:02 INFO - "repository": "", 02:31:02 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 02:31:02 INFO - "buildid": "20170112011317", 02:31:02 INFO - "pgo_build": "False", 02:31:02 INFO - "basedir": "/builds/slave/test", 02:31:02 INFO - "buildnumber": 49, 02:31:02 INFO - "slavename": "t-yosemite-r7-0088", 02:31:02 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 02:31:02 INFO - "platform": "yosemite_r7", 02:31:02 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 02:31:02 INFO - "branch": "mozilla-central", 02:31:02 INFO - "repo_path": "mozilla-central", 02:31:02 INFO - "revision": "a2425e6d32feebaa6a0ae749be2d888fc3d11e92", 02:31:02 INFO - "stage_platform": "macosx64", 02:31:02 INFO - "builduid": "0192be41b43b4ea99780dfd6d69b904c", 02:31:02 INFO - "slavebuilddir": "test" 02:31:02 INFO - } 02:31:02 INFO - Found installer url https://queue.taskcluster.net/v1/task/MPCsSl3IRLCWDjPs1QfhlA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 02:31:02 INFO - Running post-action listener: _resource_record_post_action 02:31:02 INFO - [mozharness: 2017-01-12 10:31:02.187530Z] Finished read-buildbot-config step (success) 02:31:02 INFO - [mozharness: 2017-01-12 10:31:02.187655Z] Running download-and-extract step. 02:31:02 INFO - Running pre-action listener: _resource_record_pre_action 02:31:02 INFO - Running main action method: download_and_extract 02:31:02 INFO - mkdir: /builds/slave/test/build/tests 02:31:02 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')]} 02:31:02 INFO - trying https://queue.taskcluster.net/v1/task/MPCsSl3IRLCWDjPs1QfhlA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 02:31:02 INFO - Downloading https://queue.taskcluster.net/v1/task/MPCsSl3IRLCWDjPs1QfhlA/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 02:31:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MPCsSl3IRLCWDjPs1QfhlA/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 02:31:03 INFO - Downloaded 1199 bytes. 02:31:03 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 02:31:03 INFO - Using the following test package requirements: 02:31:03 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 02:31:03 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 02:31:03 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 02:31:03 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 02:31:03 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 02:31:03 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 02:31:03 INFO - u'jsshell-mac.zip'], 02:31:03 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 02:31:03 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 02:31:03 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 02:31:03 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 02:31:03 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 02:31:03 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 02:31:03 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 02:31:03 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 02:31:03 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 02:31:03 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 02:31:03 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 02:31:03 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 02:31:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MPCsSl3IRLCWDjPs1QfhlA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 02:31:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MPCsSl3IRLCWDjPs1QfhlA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 02:31:03 INFO - Fetch https://queue.taskcluster.net/v1/task/MPCsSl3IRLCWDjPs1QfhlA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 02:31:04 INFO - Expected file size: 16314306 02:31:04 INFO - Obtained file size: 16314306 02:31:10 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 02:31:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MPCsSl3IRLCWDjPs1QfhlA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 02:31:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MPCsSl3IRLCWDjPs1QfhlA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 02:31:10 INFO - Fetch https://queue.taskcluster.net/v1/task/MPCsSl3IRLCWDjPs1QfhlA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 02:31:11 INFO - Expected file size: 16314306 02:31:11 INFO - Obtained file size: 16314306 02:31:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MPCsSl3IRLCWDjPs1QfhlA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 02:31:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MPCsSl3IRLCWDjPs1QfhlA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 02:31:18 INFO - Fetch https://queue.taskcluster.net/v1/task/MPCsSl3IRLCWDjPs1QfhlA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 02:31:20 INFO - Expected file size: 14403529 02:31:20 INFO - Obtained file size: 14403529 02:31:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:31:20 INFO - trying https://queue.taskcluster.net/v1/task/MPCsSl3IRLCWDjPs1QfhlA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 02:31:20 INFO - Downloading https://queue.taskcluster.net/v1/task/MPCsSl3IRLCWDjPs1QfhlA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 02:31:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MPCsSl3IRLCWDjPs1QfhlA/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 02:31:22 INFO - Downloaded 69874224 bytes. 02:31:22 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/MPCsSl3IRLCWDjPs1QfhlA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 02:31:22 INFO - mkdir: /builds/slave/test/properties 02:31:22 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:31:22 INFO - Writing to file /builds/slave/test/properties/build_url 02:31:22 INFO - Contents: 02:31:22 INFO - build_url:https://queue.taskcluster.net/v1/task/MPCsSl3IRLCWDjPs1QfhlA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 02:31:23 INFO - Running post-action listener: _resource_record_post_action 02:31:23 INFO - Running post-action listener: set_extra_try_arguments 02:31:23 INFO - [mozharness: 2017-01-12 10:31:23.046428Z] Finished download-and-extract step (success) 02:31:23 INFO - [mozharness: 2017-01-12 10:31:23.046533Z] Running populate-webroot step. 02:31:23 INFO - Running pre-action listener: _resource_record_pre_action 02:31:23 INFO - Running main action method: populate_webroot 02:31:23 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:31:23 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:31:23 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:31:23 INFO - '--tppagecycles', 02:31:23 INFO - '7'], 02:31:23 INFO - 'tests': ['tsvgm']}}, 02:31:23 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:31:23 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:31:23 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:31:23 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:31:23 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:31:23 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:31:23 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 02:31:23 INFO - 'talos_options': ['--disable-e10s'], 02:31:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:31:23 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 02:31:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:31:23 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 02:31:23 INFO - 'talos_options': ['--disable-e10s'], 02:31:23 INFO - 'tests': ['damp', 'tps']}, 02:31:23 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 02:31:23 INFO - 'tests': ['damp', 'tps']}, 02:31:23 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:31:23 INFO - 'tests': ['dromaeo_dom']}, 02:31:23 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:31:23 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:31:23 INFO - 'tests': ['basic_compositor_video']}, 02:31:23 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:31:23 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:31:23 INFO - 'tests': ['a11yr', 02:31:23 INFO - 'ts_paint', 02:31:23 INFO - 'tpaint', 02:31:23 INFO - 'sessionrestore', 02:31:23 INFO - 'sessionrestore_no_auto_restore', 02:31:23 INFO - 'tabpaint']}, 02:31:23 INFO - 'other-e10s': {'tests': ['a11yr', 02:31:23 INFO - 'ts_paint', 02:31:23 INFO - 'tpaint', 02:31:23 INFO - 'sessionrestore', 02:31:23 INFO - 'sessionrestore_no_auto_restore', 02:31:23 INFO - 'tabpaint']}, 02:31:23 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:31:23 INFO - 'ts_paint', 02:31:23 INFO - 'tpaint', 02:31:23 INFO - 'sessionrestore', 02:31:23 INFO - 'sessionrestore_no_auto_restore', 02:31:23 INFO - 'tabpaint']}, 02:31:23 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:31:23 INFO - 'ts_paint', 02:31:23 INFO - 'tpaint', 02:31:23 INFO - 'sessionrestore', 02:31:23 INFO - 'sessionrestore_no_auto_restore', 02:31:23 INFO - 'tabpaint']}, 02:31:23 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:31:23 INFO - 'tests': ['a11yr', 02:31:23 INFO - 'ts_paint', 02:31:23 INFO - 'tpaint', 02:31:23 INFO - 'sessionrestore', 02:31:23 INFO - 'sessionrestore_no_auto_restore', 02:31:23 INFO - 'tabpaint']}, 02:31:23 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:31:23 INFO - 'tests': ['a11yr', 02:31:23 INFO - 'ts_paint', 02:31:23 INFO - 'tpaint', 02:31:23 INFO - 'sessionrestore', 02:31:23 INFO - 'sessionrestore_no_auto_restore', 02:31:23 INFO - 'tabpaint']}, 02:31:23 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:31:23 INFO - 'tests': ['tsvgx', 02:31:23 INFO - 'tsvgr_opacity', 02:31:23 INFO - 'tart', 02:31:23 INFO - 'tscrollx', 02:31:23 INFO - 'cart', 02:31:23 INFO - 'tsvg_static']}, 02:31:23 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:31:23 INFO - 'tsvgr_opacity', 02:31:23 INFO - 'tart', 02:31:23 INFO - 'tscrollx', 02:31:23 INFO - 'cart', 02:31:23 INFO - 'tsvg_static']}, 02:31:23 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 02:31:23 INFO - 'talos_options': ['--disable-e10s'], 02:31:23 INFO - 'tests': ['tp5o']}, 02:31:23 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 02:31:23 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 02:31:23 INFO - 'talos_options': ['--disable-e10s', 02:31:23 INFO - '--xperf_path', 02:31:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:31:23 INFO - 'tests': ['tp5n']}, 02:31:23 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 02:31:23 INFO - 'talos_options': ['--xperf_path', 02:31:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:31:23 INFO - 'tests': ['tp5n']}}, 02:31:23 INFO - 'talos.zip': {'path': '', 02:31:23 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:31:23 INFO - Running post-action listener: _resource_record_post_action 02:31:23 INFO - [mozharness: 2017-01-12 10:31:23.065115Z] Finished populate-webroot step (success) 02:31:23 INFO - [mozharness: 2017-01-12 10:31:23.065225Z] Running create-virtualenv step. 02:31:23 INFO - Running pre-action listener: _resource_record_pre_action 02:31:23 INFO - Running main action method: create_virtualenv 02:31:23 INFO - Creating virtualenv /builds/slave/test/build/venv 02:31:23 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 02:31:23 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:31:23 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:31:23 INFO - Using real prefix '/tools/python27' 02:31:23 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:31:23 INFO - Installing distribute.............................................................................................................................................................................................done. 02:31:26 INFO - Installing pip.................done. 02:31:26 INFO - Return code: 0 02:31:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:31:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:31:27 INFO - Reading from file tmpfile_stdout 02:31:27 INFO - Output received: 02:31:27 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) 02:31:27 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:31:27 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')]} 02:31:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:31:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31:27 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')]} 02:31:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:31:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31:27 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 0x10bde4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bde7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2e15694b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbc2d30>, '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 0x10bae9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bbb7be8>, '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.lrG39ET1py/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.YGedVUnw6T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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 02:31:27 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 02:31:27 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 02:31:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lrG39ET1py/Render', 02:31:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:31:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:31:27 INFO - 'HOME': '/Users/cltbld', 02:31:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:31:27 INFO - 'LOGNAME': 'cltbld', 02:31:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:31:27 INFO - 'MOZ_NO_REMOTE': '1', 02:31:27 INFO - 'NO_EM_RESTART': '1', 02:31:27 INFO - 'PAGER': '/bin/cat', 02:31:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:31:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:31:27 INFO - 'PWD': '/builds/slave/test', 02:31:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:31:27 INFO - 'SHELL': '/bin/bash', 02:31:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YGedVUnw6T/Listeners', 02:31:27 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 02:31:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:31:27 INFO - 'USER': 'cltbld', 02:31:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:31:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:31:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:31:27 INFO - 'XPC_FLAGS': '0x0', 02:31:27 INFO - 'XPC_SERVICE_NAME': '0', 02:31:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:31:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:31:27 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 02:31:27 INFO - Cleaning up... 02:31:27 INFO - Return code: 0 02:31:27 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:31:27 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')]} 02:31:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:31:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31:27 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')]} 02:31:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:31:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31:27 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 0x10bde4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bde7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2e15694b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbc2d30>, '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 0x10bae9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bbb7be8>, '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.lrG39ET1py/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.YGedVUnw6T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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 02:31:27 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 02:31:27 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 02:31:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lrG39ET1py/Render', 02:31:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:31:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:31:27 INFO - 'HOME': '/Users/cltbld', 02:31:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:31:27 INFO - 'LOGNAME': 'cltbld', 02:31:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:31:27 INFO - 'MOZ_NO_REMOTE': '1', 02:31:27 INFO - 'NO_EM_RESTART': '1', 02:31:27 INFO - 'PAGER': '/bin/cat', 02:31:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:31:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:31:27 INFO - 'PWD': '/builds/slave/test', 02:31:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:31:27 INFO - 'SHELL': '/bin/bash', 02:31:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YGedVUnw6T/Listeners', 02:31:27 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 02:31:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:31:27 INFO - 'USER': 'cltbld', 02:31:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:31:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:31:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:31:27 INFO - 'XPC_FLAGS': '0x0', 02:31:27 INFO - 'XPC_SERVICE_NAME': '0', 02:31:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:31:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:31:27 INFO - Downloading/unpacking psutil>=3.1.1 02:31:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:31:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:31:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:31:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:31:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:31:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:31:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:31:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:31:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:31:31 INFO - Installing collected packages: psutil 02:31:31 INFO - Running setup.py install for psutil 02:31:31 INFO - building 'psutil._psutil_osx' extension 02:31:31 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 02:31:31 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 02:31:31 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 02:31:31 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 02:31:31 INFO - building 'psutil._psutil_posix' extension 02:31:31 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 02:31:32 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:31:32 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:31:32 INFO - ^ 02:31:32 INFO - 1 warning generated. 02:31:32 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 02:31:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:31:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:31:32 INFO - Successfully installed psutil 02:31:32 INFO - Cleaning up... 02:31:32 INFO - Return code: 0 02:31:32 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:31:32 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')]} 02:31:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:31:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31:32 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')]} 02:31:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:31:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31:32 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 0x10bde4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bde7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2e15694b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbc2d30>, '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 0x10bae9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bbb7be8>, '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.lrG39ET1py/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.YGedVUnw6T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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 02:31:32 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 02:31:32 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 02:31:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lrG39ET1py/Render', 02:31:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:31:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:31:32 INFO - 'HOME': '/Users/cltbld', 02:31:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:31:32 INFO - 'LOGNAME': 'cltbld', 02:31:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:31:32 INFO - 'MOZ_NO_REMOTE': '1', 02:31:32 INFO - 'NO_EM_RESTART': '1', 02:31:32 INFO - 'PAGER': '/bin/cat', 02:31:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:31:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:31:32 INFO - 'PWD': '/builds/slave/test', 02:31:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:31:32 INFO - 'SHELL': '/bin/bash', 02:31:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YGedVUnw6T/Listeners', 02:31:32 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 02:31:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:31:32 INFO - 'USER': 'cltbld', 02:31:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:31:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:31:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:31:32 INFO - 'XPC_FLAGS': '0x0', 02:31:32 INFO - 'XPC_SERVICE_NAME': '0', 02:31:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:31:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:31:32 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:31:32 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 02:31:32 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 02:31:32 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 02:31:32 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 02:31:32 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 02:31:32 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 02:31:35 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:31:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:31:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:31:35 INFO - Installing collected packages: mozsystemmonitor 02:31:35 INFO - Running setup.py install for mozsystemmonitor 02:31:35 INFO - Successfully installed mozsystemmonitor 02:31:35 INFO - Cleaning up... 02:31:35 INFO - Return code: 0 02:31:35 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:31:35 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')]} 02:31:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:31:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31:35 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')]} 02:31:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:31:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31:35 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 0x10bde4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bde7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2e15694b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbc2d30>, '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 0x10bae9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bbb7be8>, '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.lrG39ET1py/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.YGedVUnw6T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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 02:31:35 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 02:31:35 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 02:31:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lrG39ET1py/Render', 02:31:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:31:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:31:35 INFO - 'HOME': '/Users/cltbld', 02:31:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:31:35 INFO - 'LOGNAME': 'cltbld', 02:31:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:31:35 INFO - 'MOZ_NO_REMOTE': '1', 02:31:35 INFO - 'NO_EM_RESTART': '1', 02:31:35 INFO - 'PAGER': '/bin/cat', 02:31:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:31:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:31:35 INFO - 'PWD': '/builds/slave/test', 02:31:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:31:35 INFO - 'SHELL': '/bin/bash', 02:31:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YGedVUnw6T/Listeners', 02:31:35 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 02:31:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:31:35 INFO - 'USER': 'cltbld', 02:31:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:31:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:31:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:31:35 INFO - 'XPC_FLAGS': '0x0', 02:31:35 INFO - 'XPC_SERVICE_NAME': '0', 02:31:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:31:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:31:35 INFO - Downloading/unpacking jsonschema==2.5.1 02:31: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 02:31: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 02:31: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 02:31: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 02:31: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 02:31: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 02:31:38 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:31:38 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:31:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:31:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:31:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:31:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:31:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:31:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:31:38 INFO - Downloading functools32-3.2.3-2.tar.gz 02:31:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:31:38 INFO - warning: no files found matching '*.txt' 02:31:38 INFO - no previously-included directories found matching 'build' 02:31:38 INFO - no previously-included directories found matching 'dist' 02:31:38 INFO - no previously-included directories found matching '.git*' 02:31:38 INFO - Installing collected packages: jsonschema, functools32 02:31:38 INFO - Running setup.py install for functools32 02:31:39 INFO - warning: no files found matching '*.txt' 02:31:39 INFO - no previously-included directories found matching 'build' 02:31:39 INFO - no previously-included directories found matching 'dist' 02:31:39 INFO - no previously-included directories found matching '.git*' 02:31:39 INFO - Successfully installed jsonschema functools32 02:31:39 INFO - Cleaning up... 02:31:39 INFO - Return code: 0 02:31:39 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:31:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:31:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:31:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:31:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:31:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31:39 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 0x10bde4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bde7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2e15694b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbc2d30>, '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 0x10bae9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bbb7be8>, '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.lrG39ET1py/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.YGedVUnw6T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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 02:31:39 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 02:31:39 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 02:31:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lrG39ET1py/Render', 02:31:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:31:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:31:39 INFO - 'HOME': '/Users/cltbld', 02:31:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:31:39 INFO - 'LOGNAME': 'cltbld', 02:31:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:31:39 INFO - 'MOZ_NO_REMOTE': '1', 02:31:39 INFO - 'NO_EM_RESTART': '1', 02:31:39 INFO - 'PAGER': '/bin/cat', 02:31:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:31:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:31:39 INFO - 'PWD': '/builds/slave/test', 02:31:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:31:39 INFO - 'SHELL': '/bin/bash', 02:31:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YGedVUnw6T/Listeners', 02:31:39 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 02:31:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:31:39 INFO - 'USER': 'cltbld', 02:31:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:31:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:31:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:31:39 INFO - 'XPC_FLAGS': '0x0', 02:31:39 INFO - 'XPC_SERVICE_NAME': '0', 02:31:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:31:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:31:39 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:31:39 INFO - Cleaning up... 02:31:39 INFO - Return code: 0 02:31:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:31:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:31:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:31:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:31:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:31:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31:39 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 0x10bde4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bde7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2e15694b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbc2d30>, '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 0x10bae9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bbb7be8>, '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.lrG39ET1py/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.YGedVUnw6T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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 02:31:39 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 02:31:39 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 02:31:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lrG39ET1py/Render', 02:31:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:31:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:31:39 INFO - 'HOME': '/Users/cltbld', 02:31:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:31:39 INFO - 'LOGNAME': 'cltbld', 02:31:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:31:39 INFO - 'MOZ_NO_REMOTE': '1', 02:31:39 INFO - 'NO_EM_RESTART': '1', 02:31:39 INFO - 'PAGER': '/bin/cat', 02:31:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:31:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:31:39 INFO - 'PWD': '/builds/slave/test', 02:31:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:31:39 INFO - 'SHELL': '/bin/bash', 02:31:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YGedVUnw6T/Listeners', 02:31:39 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 02:31:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:31:39 INFO - 'USER': 'cltbld', 02:31:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:31:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:31:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:31:39 INFO - 'XPC_FLAGS': '0x0', 02:31:39 INFO - 'XPC_SERVICE_NAME': '0', 02:31:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:31:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:31:39 INFO - Downloading/unpacking blobuploader==1.2.4 02:31:39 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 02:31:39 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 02:31:39 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 02:31:39 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 02:31:39 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 02:31:39 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 02:31:42 INFO - Downloading blobuploader-1.2.4.tar.gz 02:31:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:31:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:31:42 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 02:31:42 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 02:31:42 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 02:31:42 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 02:31:42 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 02:31:42 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 02:31:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:31:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:31:43 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 02:31:43 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 02:31:43 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 02:31:43 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 02:31:43 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 02:31:43 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 02:31:43 INFO - Downloading docopt-0.6.1.tar.gz 02:31:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:31:43 INFO - Installing collected packages: blobuploader, requests, docopt 02:31:43 INFO - Running setup.py install for blobuploader 02:31:43 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:31:43 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:31:43 INFO - Running setup.py install for requests 02:31:44 INFO - Running setup.py install for docopt 02:31:44 INFO - Successfully installed blobuploader requests docopt 02:31:44 INFO - Cleaning up... 02:31:44 INFO - Return code: 0 02:31:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:31:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:31:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:31:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:31:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:31:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31:44 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 0x10bde4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bde7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2e15694b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbc2d30>, '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 0x10bae9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bbb7be8>, '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.lrG39ET1py/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.YGedVUnw6T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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 02:31:44 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 02:31:44 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 02:31:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lrG39ET1py/Render', 02:31:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:31:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:31:44 INFO - 'HOME': '/Users/cltbld', 02:31:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:31:44 INFO - 'LOGNAME': 'cltbld', 02:31:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:31:44 INFO - 'MOZ_NO_REMOTE': '1', 02:31:44 INFO - 'NO_EM_RESTART': '1', 02:31:44 INFO - 'PAGER': '/bin/cat', 02:31:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:31:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:31:44 INFO - 'PWD': '/builds/slave/test', 02:31:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:31:44 INFO - 'SHELL': '/bin/bash', 02:31:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YGedVUnw6T/Listeners', 02:31:44 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 02:31:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:31:44 INFO - 'USER': 'cltbld', 02:31:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:31:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:31:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:31:44 INFO - 'XPC_FLAGS': '0x0', 02:31:44 INFO - 'XPC_SERVICE_NAME': '0', 02:31:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:31:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:31:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:31:44 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-sgYBvs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:31:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:31:44 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-ZDdA1A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:31:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:31:44 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-JXGdYK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:31:45 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-AJvBim-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:31:45 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-hhsDQi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:31:45 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-PQk2B1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:31:45 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-Urlia9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:31:45 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-zPuFbl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:31:45 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-hOjfUX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:31:45 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-Yr0NiL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:31:45 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-VUiMl1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:31:45 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-Lt5RYE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:31:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:31:46 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-gCI3TX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:31:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:31:46 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-oWEPFu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:31:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:31:46 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-TA9sfo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:31:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:31:46 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-UxgBPS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:31:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:31:46 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-pDfZEb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:31:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:31:46 INFO - Running setup.py install for manifestparser 02:31:46 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:31:46 INFO - Running setup.py install for mozcrash 02:31:46 INFO - Running setup.py install for mozdebug 02:31:46 INFO - Running setup.py install for mozdevice 02:31:47 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:31:47 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:31:47 INFO - Running setup.py install for mozfile 02:31:47 INFO - Running setup.py install for mozhttpd 02:31:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:31:47 INFO - Running setup.py install for mozinfo 02:31:47 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:31:47 INFO - Running setup.py install for mozInstall 02:31:47 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:31:47 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:31:47 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:31:47 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:31:47 INFO - Running setup.py install for mozleak 02:31:47 INFO - Running setup.py install for mozlog 02:31:47 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:31:48 INFO - Running setup.py install for moznetwork 02:31:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:31:48 INFO - Running setup.py install for mozprocess 02:31:48 INFO - Running setup.py install for mozprofile 02:31:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:31:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:31:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:31:48 INFO - Running setup.py install for mozrunner 02:31:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:31:48 INFO - Running setup.py install for mozscreenshot 02:31:48 INFO - Running setup.py install for moztest 02:31:48 INFO - Running setup.py install for mozversion 02:31:49 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:31:49 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:31:49 INFO - Cleaning up... 02:31:49 INFO - Return code: 0 02:31:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:31:49 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')]} 02:31:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:31:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31:49 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')]} 02:31:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:31:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31:49 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 0x10bde4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bde7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2e15694b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbc2d30>, '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 0x10bae9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bbb7be8>, '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.lrG39ET1py/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.YGedVUnw6T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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 02:31:49 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 02:31:49 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 02:31:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lrG39ET1py/Render', 02:31:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:31:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:31:49 INFO - 'HOME': '/Users/cltbld', 02:31:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:31:49 INFO - 'LOGNAME': 'cltbld', 02:31:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:31:49 INFO - 'MOZ_NO_REMOTE': '1', 02:31:49 INFO - 'NO_EM_RESTART': '1', 02:31:49 INFO - 'PAGER': '/bin/cat', 02:31:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:31:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:31:49 INFO - 'PWD': '/builds/slave/test', 02:31:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:31:49 INFO - 'SHELL': '/bin/bash', 02:31:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YGedVUnw6T/Listeners', 02:31:49 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 02:31:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:31:49 INFO - 'USER': 'cltbld', 02:31:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:31:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:31:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:31:49 INFO - 'XPC_FLAGS': '0x0', 02:31:49 INFO - 'XPC_SERVICE_NAME': '0', 02:31:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:31:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:31:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:31:49 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-3XvlPL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:31:49 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)) 02:31:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:31:49 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-AVPXpw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:31:49 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)) 02:31:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:31:49 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-j9U4M_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:31:49 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)) 02:31:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:31:49 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-99L4Tw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:31:49 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)) 02:31:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:31:49 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-hvbILe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:31:49 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)) 02:31:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:31:49 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-0mqy2V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:31:49 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)) 02:31:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:31:49 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-9gCDNJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:31:50 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)) 02:31:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:31:50 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-VraRuX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:31:50 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)) 02:31:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:31:50 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-KUXkiX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:31:50 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)) 02:31:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:31:50 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-8ad2dG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:31:50 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)) 02:31:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:31:50 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-j5FQcL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:31:50 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)) 02:31:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:31:50 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-NiCwx5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:31:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 02:31:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:31:50 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-96A2j7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:31:50 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)) 02:31:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:31:50 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-ND7Bqo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:31:50 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)) 02:31:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:31:50 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-802aW1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:31:50 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)) 02:31:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:31:50 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-7_pTS5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:31:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 02:31:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:31:51 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-JdrmcL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:31:51 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)) 02:31:51 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)) 02:31:51 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)) 02:31:51 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)) 02:31:51 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)) 02:31:51 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)) 02:31:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:31:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:31:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:31:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:31:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:31:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:31:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:31:53 INFO - Downloading blessings-1.6.tar.gz 02:31:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:31:53 INFO - Installing collected packages: blessings 02:31:53 INFO - Running setup.py install for blessings 02:31:53 INFO - Successfully installed blessings 02:31:53 INFO - Cleaning up... 02:31:54 INFO - Return code: 0 02:31:54 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:31:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:31:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:31:54 INFO - Reading from file tmpfile_stdout 02:31:54 INFO - Current package versions: 02:31:54 INFO - blessings == 1.6 02:31:54 INFO - blobuploader == 1.2.4 02:31:54 INFO - docopt == 0.6.1 02:31:54 INFO - functools32 == 3.2.3-2 02:31:54 INFO - jsonschema == 2.5.1 02:31:54 INFO - manifestparser == 1.1 02:31:54 INFO - mozInstall == 1.12 02:31:54 INFO - mozcrash == 1.0 02:31:54 INFO - mozdebug == 0.1 02:31:54 INFO - mozdevice == 0.49 02:31:54 INFO - mozfile == 1.2 02:31:54 INFO - mozhttpd == 0.7 02:31:54 INFO - mozinfo == 0.9 02:31:54 INFO - mozleak == 0.1 02:31:54 INFO - mozlog == 3.4 02:31:54 INFO - moznetwork == 0.27 02:31:54 INFO - mozprocess == 0.24 02:31:54 INFO - mozprofile == 0.28 02:31:54 INFO - mozrunner == 6.13 02:31:54 INFO - mozscreenshot == 0.1 02:31:54 INFO - mozsystemmonitor == 0.3 02:31:54 INFO - moztest == 0.8 02:31:54 INFO - mozversion == 1.4 02:31:54 INFO - psutil == 3.1.1 02:31:54 INFO - requests == 1.2.3 02:31:54 INFO - wsgiref == 0.1.2 02:31:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:31:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:31:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:31:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:31:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:31:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31:55 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 0x10bde4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bde7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2e15694b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbc2d30>, '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 0x10bae9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bbb7be8>, '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.lrG39ET1py/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.YGedVUnw6T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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 02:31:55 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 02:31:55 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 02:31:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lrG39ET1py/Render', 02:31:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:31:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:31:55 INFO - 'HOME': '/Users/cltbld', 02:31:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:31:55 INFO - 'LOGNAME': 'cltbld', 02:31:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:31:55 INFO - 'MOZ_NO_REMOTE': '1', 02:31:55 INFO - 'NO_EM_RESTART': '1', 02:31:55 INFO - 'PAGER': '/bin/cat', 02:31:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:31:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:31:55 INFO - 'PWD': '/builds/slave/test', 02:31:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:31:55 INFO - 'SHELL': '/bin/bash', 02:31:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YGedVUnw6T/Listeners', 02:31:55 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 02:31:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:31:55 INFO - 'USER': 'cltbld', 02:31:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:31:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:31:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:31:55 INFO - 'XPC_FLAGS': '0x0', 02:31:55 INFO - 'XPC_SERVICE_NAME': '0', 02:31:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:31:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:31:55 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)) 02:31:55 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)) 02:31:55 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)) 02:31:55 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)) 02:31:55 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)) 02:31:55 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)) 02:31:55 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)) 02:31:55 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)) 02:31:55 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)) 02:31:55 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)) 02:31:55 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)) 02:31:55 INFO - Cleaning up... 02:31:55 INFO - Return code: 0 02:31:55 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:31:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:31:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:31:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:31:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:31:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31:55 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 0x10bde4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bde7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2e15694b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbc2d30>, '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 0x10bae9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bbb7be8>, '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.lrG39ET1py/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.YGedVUnw6T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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 02:31:55 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 02:31:55 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 02:31:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lrG39ET1py/Render', 02:31:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:31:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:31:55 INFO - 'HOME': '/Users/cltbld', 02:31:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:31:55 INFO - 'LOGNAME': 'cltbld', 02:31:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:31:55 INFO - 'MOZ_NO_REMOTE': '1', 02:31:55 INFO - 'NO_EM_RESTART': '1', 02:31:55 INFO - 'PAGER': '/bin/cat', 02:31:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:31:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:31:55 INFO - 'PWD': '/builds/slave/test', 02:31:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:31:55 INFO - 'SHELL': '/bin/bash', 02:31:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YGedVUnw6T/Listeners', 02:31:55 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 02:31:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:31:55 INFO - 'USER': 'cltbld', 02:31:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:31:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:31:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:31:55 INFO - 'XPC_FLAGS': '0x0', 02:31:55 INFO - 'XPC_SERVICE_NAME': '0', 02:31:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:31:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:31:55 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:31:55 INFO - Cleaning up... 02:31:55 INFO - Return code: 0 02:31:55 INFO - Running post-action listener: _resource_record_post_action 02:31:55 INFO - Running post-action listener: _start_resource_monitoring 02:31:55 INFO - Starting resource monitoring. 02:31:55 INFO - [mozharness: 2017-01-12 10:31:55.961636Z] Finished create-virtualenv step (success) 02:31:55 INFO - [mozharness: 2017-01-12 10:31:55.962601Z] Running install step. 02:31:55 INFO - Running pre-action listener: _resource_record_pre_action 02:31:55 INFO - Running main action method: install 02:31:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:31:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:31:56 INFO - Reading from file tmpfile_stdout 02:31:56 INFO - Detecting whether we're running mozinstall >=1.0... 02:31:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:31:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:31:56 INFO - Reading from file tmpfile_stdout 02:31:56 INFO - Output received: 02:31:56 INFO - Usage: mozinstall [options] installer 02:31:56 INFO - Options: 02:31:56 INFO - -h, --help show this help message and exit 02:31:56 INFO - -d DEST, --destination=DEST 02:31:56 INFO - Directory to install application into. [default: 02:31:56 INFO - "/builds/slave/test"] 02:31:56 INFO - --app=APP Application being installed. [default: firefox] 02:31:56 INFO - mkdir: /builds/slave/test/build/application 02:31:56 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'] 02:31:56 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 02:32:18 INFO - Reading from file tmpfile_stdout 02:32:18 INFO - Output received: 02:32:18 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 02:32:18 INFO - Running post-action listener: _resource_record_post_action 02:32:18 INFO - [mozharness: 2017-01-12 10:32:18.055693Z] Finished install step (success) 02:32:18 INFO - [mozharness: 2017-01-12 10:32:18.055828Z] Running run-tests step. 02:32:18 INFO - Running pre-action listener: _resource_record_pre_action 02:32:18 INFO - Running main action method: run_tests 02:32:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:32:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:32:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:32:18 INFO - 2017-01-12 02:32:18.070 screenresolution[1617:7343] starting screenresolution argv=screenresolution get 02:32:18 INFO - 2017-01-12 02:32:18.072 screenresolution[1617:7343] Display 0: 1600x1200x32@60 02:32:18 INFO - 2017-01-12 02:32:18.078 screenresolution[1618:7346] starting screenresolution argv=screenresolution list 02:32:18 INFO - Available Modes on Display 0 02:32:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:32:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:32:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:32:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:32:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:32:18 INFO - Intel Iris: 02:32:18 INFO - Chipset Model: Intel Iris 02:32:18 INFO - Type: GPU 02:32:18 INFO - Bus: Built-In 02:32:18 INFO - VRAM (Dynamic, Max): 1536 MB 02:32:18 INFO - Vendor: Intel (0x8086) 02:32:18 INFO - Device ID: 0x0a2e 02:32:18 INFO - Revision ID: 0x0009 02:32:18 INFO - Displays: 02:32:18 INFO - Display: 02:32:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:32:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:32:18 INFO - Display Serial Number: 1600x1200 60 02:32:18 INFO - Main Display: Yes 02:32:18 INFO - Mirror: Off 02:32:18 INFO - Online: Yes 02:32:18 INFO - Rotation: Supported 02:32:18 INFO - Return code: 0 02:32:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:32:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:32:18 INFO - Python 2.7.3 02:32:18 INFO - Return code: 0 02:32:18 INFO - grabbing minidump binary from tooltool 02:32:18 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')]} 02:32:18 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 0x10bbc2d30>, '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 0x10bae9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bbb7be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:32:18 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 02:32:18 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 02:32:18 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 02:32:18 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:32:18 INFO - Return code: 0 02:32:18 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:32:18 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:32:18 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:32:18 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:32:18 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:32:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MPCsSl3IRLCWDjPs1QfhlA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0088', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 02:32:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/MPCsSl3IRLCWDjPs1QfhlA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0088 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 02:32:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lrG39ET1py/Render', 02:32:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:32:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:32:18 INFO - 'HOME': '/Users/cltbld', 02:32:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:32:18 INFO - 'LOGNAME': 'cltbld', 02:32:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:32:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:32:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:32:18 INFO - 'MOZ_NO_REMOTE': '1', 02:32:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:32:18 INFO - 'NO_EM_RESTART': '1', 02:32:18 INFO - 'PAGER': '/bin/cat', 02:32:18 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:32:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:32:18 INFO - 'PWD': '/builds/slave/test', 02:32:18 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:32:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:32:18 INFO - 'SHELL': '/bin/bash', 02:32:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YGedVUnw6T/Listeners', 02:32:18 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 02:32:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:32:18 INFO - 'USER': 'cltbld', 02:32:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:32:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:32:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:32:18 INFO - 'XPC_FLAGS': '0x0', 02:32:18 INFO - 'XPC_SERVICE_NAME': '0', 02:32:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:32:18 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MPCsSl3IRLCWDjPs1QfhlA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0088', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 02:32:18 INFO - mozversion application_buildid: 20170112011317 02:32:18 INFO - mozversion application_changeset: a2425e6d32feebaa6a0ae749be2d888fc3d11e92 02:32:18 INFO - mozversion application_display_name: Nightly 02:32:18 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:32:18 INFO - mozversion application_name: Firefox 02:32:18 INFO - mozversion application_remotingname: firefox 02:32:18 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 02:32:18 INFO - mozversion application_vendor: Mozilla 02:32:18 INFO - mozversion application_version: 53.0a1 02:32:18 INFO - mozversion platform_buildid: 20170112011317 02:32:18 INFO - mozversion platform_changeset: a2425e6d32feebaa6a0ae749be2d888fc3d11e92 02:32:18 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 02:32:18 INFO - mozversion platform_version: 53.0a1 02:32:18 INFO - using testdate: 1484217138 02:32:18 INFO - actual date: 1484217138 02:32:18 INFO - starting webserver on 'localhost:49243' 02:32:18 INFO - SUITE-START | Running 2 tests 02:32:18 INFO - TEST-START | dromaeo_css 02:32:18 INFO - operating with platform_type : mac_ 02:32:18 INFO - Initialising browser for dromaeo_css test... 02:32:18 INFO - TEST-INFO | started process 1628 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/tmpya4MuR/profile http://localhost:49243/getInfo.html) 02:32:21 INFO - PROCESS | 1628 | 2017-01-12 02:32:21.394 plugin-container[1630:7511] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f3f, name = 'com.apple.tsm.portname' 02:32:21 INFO - PROCESS | 1628 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:32:21 INFO - PROCESS | 1628 | 2017-01-12 02:32:21.397 plugin-container[1630:7511] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e57, name = 'com.apple.CFPasteboardClient' 02:32:21 INFO - PROCESS | 1628 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:32:21 INFO - PROCESS | 1628 | 2017-01-12 02:32:21.397 plugin-container[1630:7511] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:32:21 INFO - PROCESS | 1628 | __metrics Screen width/height:1600/1200 02:32:21 INFO - PROCESS | 1628 | colorDepth:24 02:32:21 INFO - PROCESS | 1628 | Browser inner width/height: 1024/689 02:32:21 INFO - PROCESS | 1628 | __metrics 02:32:21 ERROR - PROCESS | 1628 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3242: TypeError: this.tabs is undefined 02:32:22 INFO - TEST-INFO | 1628: exit 0 02:32:22 INFO - Browser initialized. 02:32:22 INFO - Running cycle 1/1 for dromaeo_css test... 02:32:22 INFO - TEST-INFO | started process 1631 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/tmpya4MuR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:32:24 INFO - PROCESS | 1631 | 2017-01-12 02:32:24.692 plugin-container[1632:7637] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8733, name = 'com.apple.tsm.portname' 02:32:24 INFO - PROCESS | 1631 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:32:24 INFO - PROCESS | 1631 | 2017-01-12 02:32:24.693 plugin-container[1632:7637] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a0f, name = 'com.apple.CFPasteboardClient' 02:32:24 INFO - PROCESS | 1631 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:32:24 INFO - PROCESS | 1631 | 2017-01-12 02:32:24.693 plugin-container[1632:7637] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:32:34 INFO - PROCESS | 1631 | RSS: Main: 233205760 02:32:34 INFO - PROCESS | 1631 | 02:35:39 INFO - PROCESS | 1631 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49243/tests/dromaeo/cssquery-ext.html) 02:35:40 INFO - PROCESS | 1631 | RSS: Main: 253194240 02:35:40 INFO - PROCESS | 1631 | 02:37:22 INFO - PROCESS | 1631 | ************************* 02:37:22 INFO - PROCESS | 1631 | A coding exception was thrown and uncaught in a Task. 02:37:22 INFO - PROCESS | 1631 | 02:37:22 INFO - PROCESS | 1631 | Full message: ReferenceError: fetch is not defined 02:37:22 INFO - PROCESS | 1631 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 02:37:22 INFO - PROCESS | 1631 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 02:37:22 INFO - PROCESS | 1631 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 02:37:22 INFO - PROCESS | 1631 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 02:37:22 INFO - PROCESS | 1631 | TaskImpl@resource://gre/modules/Task.jsm:277:3 02:37:22 INFO - PROCESS | 1631 | asyncFunction@resource://gre/modules/Task.jsm:252:14 02:37:22 INFO - PROCESS | 1631 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 02:37:22 INFO - PROCESS | 1631 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 02:37:22 INFO - PROCESS | 1631 | TaskImpl@resource://gre/modules/Task.jsm:277:3 02:37:22 INFO - PROCESS | 1631 | asyncFunction@resource://gre/modules/Task.jsm:252:14 02:37:22 INFO - PROCESS | 1631 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 02:37:22 INFO - PROCESS | 1631 | 02:37:22 INFO - PROCESS | 1631 | ************************* 02:38:43 INFO - PROCESS | 1631 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-ext.html (next: http://localhost:49243/tests/dromaeo/cssquery-jquery.html) 02:38:43 INFO - PROCESS | 1631 | RSS: Main: 263098368 02:38:43 INFO - PROCESS | 1631 | 02:41:45 INFO - PROCESS | 1631 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49243/tests/dromaeo/cssquery-mootools.html) 02:41:46 INFO - PROCESS | 1631 | RSS: Main: 271335424 02:41:46 INFO - PROCESS | 1631 | 02:44:48 INFO - PROCESS | 1631 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49243/tests/dromaeo/cssquery-prototype.html) 02:44:48 INFO - PROCESS | 1631 | RSS: Main: 268574720 02:44:48 INFO - PROCESS | 1631 | 02:47:51 INFO - PROCESS | 1631 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49243/tests/dromaeo/cssquery-yui.html) 02:47:52 INFO - PROCESS | 1631 | RSS: Main: 272994304 02:47:52 INFO - PROCESS | 1631 | 02:50:55 INFO - PROCESS | 1631 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-yui.html (next: http://localhost:49243/tests/dromaeo/cssquery-dojo.html) 02:50:55 INFO - PROCESS | 1631 | RSS: Main: 275316736 02:50:55 INFO - PROCESS | 1631 | 02:50:55 INFO - PROCESS | 1631 | __start_tp_report 02:50:55 INFO - PROCESS | 1631 | _x_x_mozilla_page_load 02:50:55 INFO - PROCESS | 1631 | _x_x_mozilla_page_load_details 02:50:55 INFO - PROCESS | 1631 | |i|pagename|runs| 02:50:55 INFO - PROCESS | 1631 | |0;dojo.html;3379.4423920053187;3398.4902264660313;3408.4546472564393;3420.110771199488;3425.640307767686;5713;5839.386864379243;5848.619839710421;5890.469857712805;5948.851278718034;4284.200635732992;5734.311882574087;5840.415958404157;5842.328132264791;6013.579049466528;5209.635325527214;5263.868403289929;5275.36695596529;5379.408265090837;5453.563714902818;4190.97904510477;5338.67967921924;5426.240326354314;5442.945570544283;5478.91781623276;5254.973725131388;5404.675719456806;5407.459254074561;5429.049916264657;5484.61607687462;4413.6910416270875;5260.631755777097;5264;5342.572594192456;5411.323584551931;4290.871273861789;5252.763625636855;5289.285946397225;5344.198370244456;5368.248445217672;5282.366116066097;5651.519620832234;5655.886882262373;5930.733117009745;5930.851728706773;5340.67955922642;5584.804531841407;6226;6256.124142620037;6322.557420980534;6253.562250642457;6342.016987366919;6348;6355.523335749837;6373.458256048241;5230.581553475713;5841.766329346779;6009.939900600982;6107.938920610737;6117.357677443893;5168.483151684802;6252.843678908063;6321.6207027577575;6451.258105317948;6475.514336424787;6168.8766224675255;6213.627182369072;6254.499640028786;6280.717367718464;6320.178376810985;6159.983602705593;6301.9286721257495;6312.715927783261;6316.084167795736;6341.112244285804;6072.544559158081;6118.801925441459;6138.970878344271;6149.200603921551;6192.659403732838;6107.886786452505;6119.746288422617;6157.014877619559;6159.127423684521;6226.315105338409;5121.8719532011255;5122.615803814729;5150.613703972217;5157.690538567698;5164.948350516522;4585.747783871852;4601.12578610063;4616.699914505546;4620.67655264135;4643.326717625993;5799.739011744482;5874.060150375919;5874.9118763218585;5965.731542080617;5974.103884417372;6190.597611155261;6253.187085678833;6264.498840092781;6328.177336946167;6345.17512723343;5955.4640082391925;6023.427774361428;6045.365236650088;6052.334243233241;6068.817935461943;5462.399136095027;5616.354119276254;5626;5627.155926610959;5645.858853528694;6192;6354.1104245406605;6380.266269379081;6391;6409.775657851953;6281.685915704105;6304.306526282014;6311.147995020613;6331.461825744863;6368.4586810121655;6511.609303441809;6528.804135875931;6530.053142294436;6536.346365363425;6558.901616475852;6153.9845925421805;6189.783357582463;6205.968970155121;6333.461655759128;6342.841428964312;6199.845003874939;6278.0896769967185;6308.621482711052;6308.684565771601;6317.905231421624;6129.877402451837;6130.642739936431;6154.230721159855;6158.907616385847;6278.529110316836;6127.7393367763425;6192.318844927145;6210.658413787104;6271.153394291712;6286.805506953664;5359.3032905722;6035.577509574287;6048.243969503812;6172.135900973957;6195.473384762166;6142.069075791586;6147.954847675996;6199.225096862892;6206.162168107247;6264.937350626436;5681.579605098726;6199.659018754011;6200.7519699211525;6210.627362358273;6224.06639004153 02:50:55 INFO - PROCESS | 1631 | |1;ext.html;443.7869822485205;458.7820785127064;472.2679846238332;502.3068165931017;518.1114388823167;35305.82347088261;40407.79796101019;41056.794716026416;44677.32984005234;44693.32960005602;44016.55983440165;44315.77842110785;44362.33456498155;44413;44421.7778911105;8431.831363372728;9402.482863442508;9654.662086826962;9658.227341812639;9680.515974201297;7465.589392583406;11231.887681123208;11486.42567871603;11503.42482875857;11690.532378704842;11465.197436179471;11533.711657208594;11540.769184616303;11552.884471155265;11569.305841649486;11508.251963622337;11528.135389845753;11594.884051159464;11600;11623.94188029063;11413.828792568118;11508.424578771028;11526.423678816025;11532.654020379403;11534.250273732181;41364.793176034225;41502.58497415017;41774.37338439926;41821;41856.1628767426;3551.1654761131113;4128.731632443882;4132.173565286925;4142.378643203513;4148.270971092957;30514.694853051406;31114;31566.210844728837;31799.84100079508;31868.521972170434;3191.4255434021866;3211.165097074754;3218.6620404857385;3252.9512057319157;3264.6245681746664;450781;457994;458137.9999999967;458901;459895;64986;65230.67384663046;65250.347496524424;65478.01782973259;65548.34451655422;66436;67020.66489667521;67617.66191169112;67684;67714.3228567708;16287.429939952282;16468.83531164697;16483.258253378386;16532.338706451606;16560.420385286456;11418;11635.185537012498;12465.875341246652;12586.118971671895;12691.301978391093;17861.37485188038;18429.986350750838;18456.723149152775;18502.352417665315;18545;5771.422857714279;6506.4469520091325;6523.673816309166;6538.869222615521;6544.087880893052;863.8747381629629;879.2897813752319;891.8706787515774;920.6777627830206;927.2442958988403;30483.115430329548;30853.69146308553;31116;31174.376512470077;31571.52642710361;949.7957939043139;972.547765289144;1052.4579841381694;1074.4949873559415;1245.0537591430577;13850.653733656538;15496.070235785888;16027.759583606023;16172.595685107966;16446.75329870053;512409;568763;575120;617449;619775;60530;60565;60681;60702.08946865801;60839.08741368883;1436.1393719243936;1456.730504856605;1460.3063544816127;1482.85171482853;1487.7247709173837;26065;28467.572986404808;29952.700472995864;30511;30810.843240972918;51354.74322628363;51559.711007225116;51831;51897.96204075973;51952.48047519477;532529;535085;538639;539449;539471;40661.78014659565;40683.796581016904;41037.58962410338;41142.9714257146;41765;45938.31092533548;47067.29399058948;47143.29285060658;47759.52240477551;47955.760221198674;37790.43314350342;38025.429618555194;38473;39392;39632.2073558533;32459.86390476322;38331.23337533289;38690.874916266774;38714.80642596769;40051;32914.1771455706;36185.0953726159;37939.43090853584;38535.80732096321;38722.419163712;30076.699233008265;30548;34476.655233447345;34982;35026.474602881484;22604.434889127904;23141.07435702553;25196.87401563054;25773.871130644227;25862.353441164123 02:50:55 INFO - PROCESS | 1631 | |2;jquery.html;387.80803502266366;391.54385141310377;398.58148943609206;401.4259608759474;420.62765999761353;14364.497242596512;16111.677766444664;19594.804051959476;20812.167513299468;21058.6841197382;20643.483912902186;20907.68638470424;21055.36833894982;21471;21709;5688.886222275552;7473.850522989537;7820.100688420819;7909.723159689412;7930.841383172333;5971.552133589977;7746;7835.882461763078;7875.409344299201;7947.642356093989;7664.961675191616;7931.8413631727335;7938.722144724936;7946.6821327146845;7966.4025198110085;7825.256600622932;7919.643616037292;7955.244251796128;7971.521708697497;7991.5604641744685;7432.777016689508;7642.04474440695;7694.538327700356;7705.499142555772;7713.460057795956;28522.572161417596;28955.552222388797;29035.709642903512;29111.981080662074;29181.27046823825;3274.8690052397874;3314.2211580278617;3374.4094783412806;3399.1162297802503;3427.7943323400677;16888.733344999175;17182.312707491685;17302.653946920993;17494.950302981862;17896.46310610684;1911.3692481481078;2070.8136267735827;2082.791720827905;2086.2698055680544;2094.6962690409805;130667;135794.32102839422;149833;162789;167617;23484;23593.646095308584;23886.402839928793;24011.19776044763;24119.51760964806;41747;42171.94570135771;42364.94087647834;42388.78805605952;42629.57370426318;8321.750347489586;8537.957310213409;8832.072632373509;8850.557472126367;8949;8426.368022398223;8552.61513231906;8601.5699215039;8655.437396569203;8700.08649091849;4832.44426890905;4836.733979631154;4879.365682461257;4893.339399181065;4899.657024008286;1873.4847916822982;1928.9517762056062;2006.5485265815075;2053.209514336991;2063.556335387869;841.87792770048;846.6444093480753;857.0058731871;883.266888482558;904.7285814255697;16799.412020579464;18232.997185154945;19298.842069475875;20492.18031278762;21377;711.9093825497995;844.8859403980384;846.2595229174473;875.5665945357089;894.932880034;10960.068394186424;11180.496877640526;11200.21598488098;11207.66377008691;11399.48702308398;358531;358908;358961;567412;762557;117631.41184294024;162543;164110;168738;169364;893.946363218209;970.922276273336;972.2416515118219;1050.910672592823;1057.5769692123058;15666.295016724047;18313.542161446072;18682.719759203894;18746.812531874508;18785.906070470108;62248.37751622426;62710.68644656747;62821.68589157025;62824.057639136365;63126.05613382432;88802.55598722224;90351.54824225837;90381;91106.08893911241;91209.4871909964;7624.750698543832;7816.374690024898;7834.804129896798;8243.711470098518;8353.707620233263;10640.88270731562;12169.69575760613;12304.507819687471;12635.620931372074;13123.409446575119;9344.878614566296;10733.731656708645;10739.570417183226;10748.4088375137;10984.725381865517;8947.239484643878;9665.323427360017;10713.250072495162;10847.783044339227;11159.38623375722;10173.796524069625;10313.329633573794;10447.791044178923;10648.467576621293;11663.941680291544;8238.50568965864;8284.71003514874;8358.122397148212;8589.570521474026;8628.22345988864;9797.363171394121;10414.114800242065;10660.573577056832;10988.285761425483;11031.889681103086 02:50:55 INFO - PROCESS | 1631 | |3;mootools.html;1279.2260682287215;1450.6953539756657;1466.2668665667165;1474.901198607038;1479.1494890437998;7555.471117021811;7808.375329973603;8183;8576.91423085769;8578.39951203416;8028.277455029046;8288.502689838599;8341.582920853964;8456.281216096639;8493.617787199575;1920.9903950480264;2101.4746313421647;2364.798992085675;2371.027878569787;2378.7740164684415;2097;2344.42561572415;2391.3184742348435;2395.281415575329;2396.4607963208314;2061.443410279224;2255.109231853425;2349.95300093998;2350.1069593554425;2369.123424332999;2317.385892738426;2336.5910965581124;2337.5909215887236;2348.236823032513;2356.9882150589224;2332.720073591163;2333.8949747261413;2342.6251799712136;2358.716953965518;2361.1027809432308;8400.957995210047;8439.156084391572;8469.788255293606;8514.872276915852;8527.872081918777;5117.616178786606;5263.131583288753;5268.81559145428;5274.4198138204765;5275.050490911634;3510.7191424685966;3539.610642829287;3583.677469027774;3586.677199052098;3605.1888325126897;1168.59099315239;1183.7040739815047;1206.5837286136268;1228.2507670321097;1235.05518278517;9400;10310.123639490652;10840.295380800222;10858.23992320538;10867.402292873889;4554.157480866051;4684.297355396649;4693.131770622445;4764.880877978078;4790.952090479121;9513.143817056498;9566.61733530651;9577.952110239405;9583.472908989934;9604.375715578604;5266.578673706094;5287.127623942007;5316.388615309212;5332.413434522199;5361.169018802092;3733.2160246348026;4490.461144662661;4818.349522814446;4880.95119048805;4903.8528844134735;3363.5963684358035;3710.387786015331;3820.3887378019385;3903.7072219583647;3928.4696565963227;1149.4827327702567;1180.9940950295193;1226.926384416938;1292.9870701292866;1303.5307289375817;765.448876808708;775.5501808950795;797.469682661024;824.7814329202766;841.9621117049747;5511.917321240185;5544.77820887168;5545.085060964976;5629.240052592848;5665.3201615806365;982.8476586129161;992.6972273456665;997.9401235925868;1019.3476175247847;1043.2905624175633;3726.366517691999;3733.4773131761576;3821.216650586623;3843.6925045996254;3882.3400021996326;9427.528623568931;9942.204623630092;10055.245856560787;10059.245556583286;10192.082712555757;9863.16163126128;10096.646617368504;10113.241506887012;10132.493375331203;10138.493075346203;7826.334761545332;8030.598470076473;8033.718819841278;8038.276555110068;8052.557109358924;5407.972960135174;5435.402105768283;5470.835874923759;5473.808416705396;5496.80761173357;9255.305852060988;9262.629494820132;9300.162985331353;9364.344495885223;9368.718938431857;9332.20676242527;9461.810763784824;9513.571889264862;9565.856512152463;9566.569504372459;7131.108611423572;7225.458090643117;7232.0959880015;7248.187953011747;7254.927450725425;7683.884741728991;7982.720604778805;8002.439829211899;8049.114597394399;8081.353491720531;7139.643017848983;7161.928380716126;7202.49582529218;7206.243344448757;7303.744368947061;7007.439404847498;7060.823479413056;7083.468739844429;7125.964370178116;7129.893051604334;5241.266222728897;6283.088952102011;6301.4328710416285;6337.271213810471;6353.5870168438905;4975.925361119658;5000.599952003904;5011.674241174312;5076.238564215398;5083.694978301313;4925.729084900365;4930.137225985366;5022.54797068251;5078.009788091286;5088.872778180576 02:50:55 INFO - PROCESS | 1631 | |4;prototype.html;328.94901290300004;362.5033703825759;370.3056768558952;373.1082712317561;375.2214155627075;5184.300119483869;5368.651037682548;5478.917816232754;5508.944910550893;5551.639143455673;5155.834549489214;5317.787288508455;5333.35999680038;5355.651882627636;5404.216388623648;4222.809973551198;4249.362595610668;4251.3622956556455;4258.765767882765;4338.370936214247;3365.646607106243;3625.963740362589;4181.268278051344;4262.509811371686;4315.899445641373;3427.8174029959623;3947.368421052632;4166.645840101979;4166.895827604304;4227.239096962546;3286.4905939579403;3677.3932301170407;3806.781829814433;4217.641500472449;4231.682623803227;3720.683741881943;3730.3328120393776;3734.7198960078103;3736.5516138063335;3761.981190094032;4636.86089417318;5030.974845125751;5238.947610523922;5305.230741542455;5310.751973286275;4072.3077076897;4842.104210720992;4852.883836717539;4854.587360074398;4861.368022157097;5027.360635666268;5153.845384638467;5189.014087323397;5708.372079071299;5717.799877004285;3600.39782296878;3862.4013277941963;3894.6884249259992;3908.902277443058;4088.5298190707854;5560.805371812007;6014.8195554133445;6017.909731354033;6022.367651396584;6055.7274922628585;3419.5383623211046;3496.9650303496637;3939.6060393960947;3942.5071866016747;3956.485656864647;3850.940991227379;3979.7015223857748;3988.6809055275507;3996.2606917720314;4016.879493615196;3963.326234540135;4221.176870510282;4664.883377915511;4703.5183917066015;4711.222648263067;4871.537203965618;5292.682439053669;5490.956718223525;5754;5871.6788722537085;1053.4574694032578;1199.1186477938709;1229.59423390281;1278.5461161287794;1284.653143651209;812.3785304242259;874.0996773323487;876.509154873273;880.3749337969984;880.428435243088;4564.794584243717;4651;4940.580050695731;5128.358955130609;5161.380634323905;4739.881502962454;4889.63327750413;5009.824656137018;5432.826869252331;5618.016847051749;4090.8159132839096;4532.614727748112;5035.892103737172;5073.1122053640465;5121.539061484485;2545.8981640734164;2675.996501312008;2828.6322778038725;2969.3170570768843;2999.2651800309063;5022.598192144693;5261.289725887033;5843.211166492554;5854.385289544622;5873.735681894325;4571.6799824012;4611.492735799059;5620.4741593096405;5626.971865140648;5664.910221511891;6116;6126.877462450815;6161.014237721943;6172.290186628506;6178.41305076026;5784.855378615568;5945.256842894638;5956.26012034469;6009.939900601113;6050.788222412194;5465.398806131243;5759.366469688414;5854.853628659318;5865.296164460121;5932.16949627044;6028.016132014681;6058.485028772604;6059.242594675666;6186.679995001874;6208.4722798562625;3804.391297392404;4036.4752582164133;4292.304539706736;4299.333603291369;4363.738175709467;5708.600397972102;5940.970295148497;5949.25634295713;5985.281766188085;5986.461218490357;4847.158079929604;4941.2693416471775;5661.150827375926;5673.319201695823;5742.339630942328;5747.8563035924435;5771.105478650816;5801.796937107181;5824.271966004249;5838.386969368155;5302.443243459536;5324.653897496651;5700.857478563069;5707;5713;4703.412073490814;5170.741462926763;5806.067935692448;5837.562182836219;5866.794662186803;4773.73744444045;5679.488846003879;5690.4878560928055;5711.8857622848145;5759.452851979056 02:50:55 INFO - PROCESS | 1631 | |5;yui.html;682.3006418421114;715.8639858426895;735.3712575747736;751.8007727952092;781.7726169913238;12479.750404991895;14586.854131458682;15582;16053.759193612075;16873.24070416831;16002.759958600602;16115.919420402912;16384.180790960436;16395.344186232567;16578.50264492064;782.5304817109745;860.105490290099;879.116487929633;881.4446898453965;883.0815951410525;677.5629718831351;701.9333163349473;839.6599377252222;862.6376921692904;866.7356456280831;892.1435421994893;915.3226612306878;916.6425094213262;918.3663272342062;919.1681528216974;896.3546246702397;913.5295322908707;914.1087439746227;914.1087439746261;914.9542522873863;682.7985744205471;683.1528904158857;683.2313647146959;683.2927919603236;685.9519833611649;11760.705982350424;15466.690666186612;15719.68560628781;15832.208389580475;15869.920650396789;14114.788278175836;14280.857191428056;14467.131972081708;14503.637409064857;14510.637234069232;11698.356590387526;11852.585159519376;11870.465829037714;11901.107416943678;12039.578614748529;3671.1005803578028;3684.244729830378;3710.7031437485207;3741.4574886641285;3852.1525264442057;103151;104552.43171352436;105090;105470;106592.46703766509;9119.863202051974;10911.618093366695;11030.683874559969;11086.390248536247;11118.166137539716;21978.560428791334;22348.553028939332;22601.321960340887;22630.434239143822;22643.773562264167;7744.109427415807;7758.379329653614;7790.259925307087;7830.6084695764985;7918.089419716806;4268.189044081614;4311.72804022814;4356.934645980313;4376.540463251376;4405.207062728676;3581.4448760442174;3600.873969411058;3678.7608805427562;3679.300932822755;3682.8158592070827;622.3465361370634;629.6348118091588;629.789020678078;645.4126744662335;648.3905129178557;661.8510835062169;691.1636919327569;697.8988046733195;705.1115594351052;715.6421789105448;10185.541650625739;10263.486825658687;10381.63664271762;10505.212109091848;10600.681979540626;683.1460674157304;713.2760248347931;718.5150023733959;727.6689106456549;729.0959210578844;4619;4865.56209941107;5065.366829146356;5071.442141364447;5077.060743762416;81710;82798.75801862977;84470.57764711136;114397;115533;67612.66193669099;68597.3140268591;79704;80679.59660201661;82830.75753863697;62107.378926211066;68744;68869.31130688729;69505;70676;10221.540030698488;10455.482438753583;10868.967448092419;10991.890081099407;11049.39228342449;38669.033274168374;49368.506314937826;49696.00607987892;50658;50685.74657126691;67463.3253667457;67595.31011724549;67943;68621;70093.9485907722;13221.933890330487;13270.734585308433;13367.866321337051;13457.730845383234;13533.188008719508;14550;14833.258337082887;14975;15058.397664093753;15253.23733813327;13478.989075819549;13504.189748615114;13814.792778108538;14057.789133162807;14539.563813085624;13633.38649760783;13782.035257531876;14031.579052628438;14404;14406.567802965928;13412.530561430303;13603.727925441633;13679.86320136786;14247.073940193854;14348.928255358656;11186.384748838567;11194.776104478027;11281.379524126252;11885.465154067915;11965.581204658143;8056.798580035546;8813.383063185516;8932.330075244512;10164.593416263267;10835.133189344991 02:50:55 INFO - PROCESS | 1631 | __end_tp_report 02:50:55 INFO - PROCESS | 1631 | __start_cc_report 02:50:55 INFO - PROCESS | 1631 | _x_x_mozilla_cycle_collect,391 02:50:55 INFO - PROCESS | 1631 | __end_cc_report 02:50:55 INFO - PROCESS | 1631 | __startTimestamp1484218255646__endTimestamp 02:50:55 INFO - PROCESS | 1631 | 02:50:55 INFO - PROCESS | 1631 | ------- Summary: start ------- 02:50:55 INFO - PROCESS | 1631 | Number of tests: 6 02:50:55 INFO - PROCESS | 1631 | 02:50:55 INFO - PROCESS | 1631 | [#0] dojo.html Cycles:165 Average:5807.55 Median:6107.94 stddev:667.74 (10.9%) stddev-sans-first:642.05 02:50:55 INFO - PROCESS | 1631 | Values: 3379.4 3398.5 3408.5 3420.1 3425.6 5713.0 5839.4 5848.6 5890.5 5948.9 4284.2 5734.3 5840.4 5842.3 6013.6 5209.6 5263.9 5275.4 5379.4 5453.6 4191.0 5338.7 5426.2 5442.9 5478.9 5255.0 5404.7 5407.5 5429.0 5484.6 4413.7 5260.6 5264.0 5342.6 5411.3 4290.9 5252.8 5289.3 5344.2 5368.2 5282.4 5651.5 5655.9 5930.7 5930.9 5340.7 5584.8 6226.0 6256.1 6322.6 6253.6 6342.0 6348.0 6355.5 6373.5 5230.6 5841.8 6009.9 6107.9 6117.4 5168.5 6252.8 6321.6 6451.3 6475.5 6168.9 6213.6 6254.5 6280.7 6320.2 6160.0 6301.9 6312.7 6316.1 6341.1 6072.5 6118.8 6139.0 6149.2 6192.7 6107.9 6119.7 6157.0 6159.1 6226.3 5121.9 5122.6 5150.6 5157.7 5164.9 4585.7 4601.1 4616.7 4620.7 4643.3 5799.7 5874.1 5874.9 5965.7 5974.1 6190.6 6253.2 6264.5 6328.2 6345.2 5955.5 6023.4 6045.4 6052.3 6068.8 5462.4 5616.4 5626.0 5627.2 5645.9 6192.0 6354.1 6380.3 6391.0 6409.8 6281.7 6304.3 6311.1 6331.5 6368.5 6511.6 6528.8 6530.1 6536.3 6558.9 6154.0 6189.8 6206.0 6333.5 6342.8 6199.8 6278.1 6308.6 6308.7 6317.9 6129.9 6130.6 6154.2 6158.9 6278.5 6127.7 6192.3 6210.7 6271.2 6286.8 5359.3 6035.6 6048.2 6172.1 6195.5 6142.1 6148.0 6199.2 6206.2 6264.9 5681.6 6199.7 6200.8 6210.6 6224.1 02:50:55 INFO - PROCESS | 1631 | 02:50:55 INFO - PROCESS | 1631 | [#1] ext.html Cycles:180 Average:67508.98 Median:39057.21 stddev:140300.94 (359.2%) stddev-sans-first:140604.17 02:50:55 INFO - PROCESS | 1631 | Values: 443.8 458.8 472.3 502.3 518.1 35305.8 40407.8 41056.8 44677.3 44693.3 44016.6 44315.8 44362.3 44413.0 44421.8 8431.8 9402.5 9654.7 9658.2 9680.5 7465.6 11231.9 11486.4 11503.4 11690.5 11465.2 11533.7 11540.8 11552.9 11569.3 11508.3 11528.1 11594.9 11600.0 11623.9 11413.8 11508.4 11526.4 11532.7 11534.3 41364.8 41502.6 41774.4 41821.0 41856.2 3551.2 4128.7 4132.2 4142.4 4148.3 30514.7 31114.0 31566.2 31799.8 31868.5 3191.4 3211.2 3218.7 3253.0 3264.6 450781.0 457994.0 458138.0 458901.0 459895.0 64986.0 65230.7 65250.3 65478.0 65548.3 66436.0 67020.7 67617.7 67684.0 67714.3 16287.4 16468.8 16483.3 16532.3 16560.4 11418.0 11635.2 12465.9 12586.1 12691.3 17861.4 18430.0 18456.7 18502.4 18545.0 5771.4 6506.4 6523.7 6538.9 6544.1 863.9 879.3 891.9 920.7 927.2 30483.1 30853.7 31116.0 31174.4 31571.5 949.8 972.5 1052.5 1074.5 1245.1 13850.7 15496.1 16027.8 16172.6 16446.8 512409.0 568763.0 575120.0 617449.0 619775.0 60530.0 60565.0 60681.0 60702.1 60839.1 1436.1 1456.7 1460.3 1482.9 1487.7 26065.0 28467.6 29952.7 30511.0 30810.8 51354.7 51559.7 51831.0 51898.0 51952.5 532529.0 535085.0 538639.0 539449.0 539471.0 40661.8 40683.8 41037.6 41143.0 41765.0 45938.3 47067.3 47143.3 47759.5 47955.8 37790.4 38025.4 38473.0 39392.0 39632.2 32459.9 38331.2 38690.9 38714.8 40051.0 32914.2 36185.1 37939.4 38535.8 38722.4 30076.7 30548.0 34476.7 34982.0 35026.5 22604.4 23141.1 25196.9 25773.9 25862.4 02:50:55 INFO - PROCESS | 1631 | 02:50:55 INFO - PROCESS | 1631 | [#2] jquery.html Cycles:180 Average:35614.63 Median:179674.40 stddev:88134.54 (49.1%) stddev-sans-first:88342.09 02:50:55 INFO - PROCESS | 1631 | Values: 387.8 391.5 398.6 401.4 420.6 14364.5 16111.7 19594.8 20812.2 21058.7 20643.5 20907.7 21055.4 21471.0 21709.0 5688.9 7473.9 7820.1 7909.7 7930.8 5971.6 7746.0 7835.9 7875.4 7947.6 7665.0 7931.8 7938.7 7946.7 7966.4 7825.3 7919.6 7955.2 7971.5 7991.6 7432.8 7642.0 7694.5 7705.5 7713.5 28522.6 28955.6 29035.7 29112.0 29181.3 3274.9 3314.2 3374.4 3399.1 3427.8 16888.7 17182.3 17302.7 17495.0 17896.5 1911.4 2070.8 2082.8 2086.3 2094.7 130667.0 135794.3 149833.0 162789.0 167617.0 23484.0 23593.6 23886.4 24011.2 24119.5 41747.0 42171.9 42364.9 42388.8 42629.6 8321.8 8538.0 8832.1 8850.6 8949.0 8426.4 8552.6 8601.6 8655.4 8700.1 4832.4 4836.7 4879.4 4893.3 4899.7 1873.5 1929.0 2006.5 2053.2 2063.6 841.9 846.6 857.0 883.3 904.7 16799.4 18233.0 19298.8 20492.2 21377.0 711.9 844.9 846.3 875.6 894.9 10960.1 11180.5 11200.2 11207.7 11399.5 358531.0 358908.0 358961.0 567412.0 762557.0 117631.4 162543.0 164110.0 168738.0 169364.0 893.9 970.9 972.2 1050.9 1057.6 15666.3 18313.5 18682.7 18746.8 18785.9 62248.4 62710.7 62821.7 62824.1 63126.1 88802.6 90351.5 90381.0 91106.1 91209.5 7624.8 7816.4 7834.8 8243.7 8353.7 10640.9 12169.7 12304.5 12635.6 13123.4 9344.9 10733.7 10739.6 10748.4 10984.7 8947.2 9665.3 10713.3 10847.8 11159.4 10173.8 10313.3 10447.8 10648.5 11663.9 8238.5 8284.7 8358.1 8589.6 8628.2 9797.4 10414.1 10660.6 10988.3 11031.9 02:50:55 INFO - PROCESS | 1631 | 02:50:55 INFO - PROCESS | 1631 | [#3] mootools.html Cycles:180 Average:5458.66 Median:5264.86 stddev:2972.73 (56.5%) stddev-sans-first:2964.47 02:50:55 INFO - PROCESS | 1631 | Values: 1279.2 1450.7 1466.3 1474.9 1479.1 7555.5 7808.4 8183.0 8576.9 8578.4 8028.3 8288.5 8341.6 8456.3 8493.6 1921.0 2101.5 2364.8 2371.0 2378.8 2097.0 2344.4 2391.3 2395.3 2396.5 2061.4 2255.1 2350.0 2350.1 2369.1 2317.4 2336.6 2337.6 2348.2 2357.0 2332.7 2333.9 2342.6 2358.7 2361.1 8401.0 8439.2 8469.8 8514.9 8527.9 5117.6 5263.1 5268.8 5274.4 5275.1 3510.7 3539.6 3583.7 3586.7 3605.2 1168.6 1183.7 1206.6 1228.3 1235.1 9400.0 10310.1 10840.3 10858.2 10867.4 4554.2 4684.3 4693.1 4764.9 4791.0 9513.1 9566.6 9578.0 9583.5 9604.4 5266.6 5287.1 5316.4 5332.4 5361.2 3733.2 4490.5 4818.3 4881.0 4903.9 3363.6 3710.4 3820.4 3903.7 3928.5 1149.5 1181.0 1226.9 1293.0 1303.5 765.4 775.6 797.5 824.8 842.0 5511.9 5544.8 5545.1 5629.2 5665.3 982.8 992.7 997.9 1019.3 1043.3 3726.4 3733.5 3821.2 3843.7 3882.3 9427.5 9942.2 10055.2 10059.2 10192.1 9863.2 10096.6 10113.2 10132.5 10138.5 7826.3 8030.6 8033.7 8038.3 8052.6 5408.0 5435.4 5470.8 5473.8 5496.8 9255.3 9262.6 9300.2 9364.3 9368.7 9332.2 9461.8 9513.6 9565.9 9566.6 7131.1 7225.5 7232.1 7248.2 7254.9 7683.9 7982.7 8002.4 8049.1 8081.4 7139.6 7161.9 7202.5 7206.2 7303.7 7007.4 7060.8 7083.5 7126.0 7129.9 5241.3 6283.1 6301.4 6337.3 6353.6 4975.9 5000.6 5011.7 5076.2 5083.7 4925.7 4930.1 5022.5 5078.0 5088.9 02:50:55 INFO - PROCESS | 1631 | 02:50:55 INFO - PROCESS | 1631 | [#4] prototype.html Cycles:180 Average:4603.08 Median:5097.33 stddev:1433.23 (28.1%) stddev-sans-first:1400.89 02:50:55 INFO - PROCESS | 1631 | Values: 328.9 362.5 370.3 373.1 375.2 5184.3 5368.7 5478.9 5508.9 5551.6 5155.8 5317.8 5333.4 5355.7 5404.2 4222.8 4249.4 4251.4 4258.8 4338.4 3365.6 3626.0 4181.3 4262.5 4315.9 3427.8 3947.4 4166.6 4166.9 4227.2 3286.5 3677.4 3806.8 4217.6 4231.7 3720.7 3730.3 3734.7 3736.6 3762.0 4636.9 5031.0 5238.9 5305.2 5310.8 4072.3 4842.1 4852.9 4854.6 4861.4 5027.4 5153.8 5189.0 5708.4 5717.8 3600.4 3862.4 3894.7 3908.9 4088.5 5560.8 6014.8 6017.9 6022.4 6055.7 3419.5 3497.0 3939.6 3942.5 3956.5 3850.9 3979.7 3988.7 3996.3 4016.9 3963.3 4221.2 4664.9 4703.5 4711.2 4871.5 5292.7 5491.0 5754.0 5871.7 1053.5 1199.1 1229.6 1278.5 1284.7 812.4 874.1 876.5 880.4 880.4 4564.8 4651.0 4940.6 5128.4 5161.4 4739.9 4889.6 5009.8 5432.8 5618.0 4090.8 4532.6 5035.9 5073.1 5121.5 2545.9 2676.0 2828.6 2969.3 2999.3 5022.6 5261.3 5843.2 5854.4 5873.7 4571.7 4611.5 5620.5 5627.0 5664.9 6116.0 6126.9 6161.0 6172.3 6178.4 5784.9 5945.3 5956.3 6009.9 6050.8 5465.4 5759.4 5854.9 5865.3 5932.2 6028.0 6058.5 6059.2 6186.7 6208.5 3804.4 4036.5 4292.3 4299.3 4363.7 5708.6 5941.0 5949.3 5985.3 5986.5 4847.2 4941.3 5661.2 5673.3 5742.3 5747.9 5771.1 5801.8 5824.3 5838.4 5302.4 5324.7 5700.9 5707.0 5713.0 4703.4 5170.7 5806.1 5837.6 5866.8 4773.7 5679.5 5690.5 5711.9 5759.5 02:50:55 INFO - PROCESS | 1631 | 02:50:55 INFO - PROCESS | 1631 | [#5] yui.html Cycles:180 Average:19719.98 Median:3726.08 stddev:27514.87 (738.4%) stddev-sans-first:27554.93 02:50:55 INFO - PROCESS | 1631 | Values: 682.3 715.9 735.4 751.8 781.8 12479.8 14586.9 15582.0 16053.8 16873.2 16002.8 16115.9 16384.2 16395.3 16578.5 782.5 860.1 879.1 881.4 883.1 677.6 701.9 839.7 862.6 866.7 892.1 915.3 916.6 918.4 919.2 896.4 913.5 914.1 914.1 915.0 682.8 683.2 683.2 683.3 686.0 11760.7 15466.7 15719.7 15832.2 15869.9 14114.8 14280.9 14467.1 14503.6 14510.6 11698.4 11852.6 11870.5 11901.1 12039.6 3671.1 3684.2 3710.7 3741.5 3852.2 103151.0 104552.4 105090.0 105470.0 106592.5 9119.9 10911.6 11030.7 11086.4 11118.2 21978.6 22348.6 22601.3 22630.4 22643.8 7744.1 7758.4 7790.3 7830.6 7918.1 4268.2 4311.7 4356.9 4376.5 4405.2 3581.4 3600.9 3678.8 3679.3 3682.8 622.3 629.6 629.8 645.4 648.4 661.9 691.2 697.9 705.1 715.6 10185.5 10263.5 10381.6 10505.2 10600.7 683.1 713.3 718.5 727.7 729.1 4619.0 4865.6 5065.4 5071.4 5077.1 81710.0 82798.8 84470.6 114397.0 115533.0 67612.7 68597.3 79704.0 80679.6 82830.8 62107.4 68744.0 68869.3 69505.0 70676.0 10221.5 10455.5 10869.0 10991.9 11049.4 38669.0 49368.5 49696.0 50658.0 50685.7 67463.3 67595.3 67943.0 68621.0 70093.9 13221.9 13270.7 13367.9 13457.7 13533.2 14550.0 14833.3 14975.0 15058.4 15253.2 13479.0 13504.2 13814.8 14057.8 14539.6 13633.4 13782.0 14031.6 14404.0 14406.6 13412.5 13603.7 13679.9 14247.1 14348.9 11186.4 11194.8 11281.4 11885.5 11965.6 8056.8 8813.4 8932.3 10164.6 10835.1 02:50:55 INFO - PROCESS | 1631 | -------- Summary: end -------- 02:50:55 INFO - PROCESS | 1631 | 02:50:56 INFO - TEST-INFO | 1631: exit 0 02:50:56 INFO - TEST-OK | dromaeo_css | took 1117621ms 02:50:56 INFO - TEST-START | kraken 02:50:56 INFO - operating with platform_type : mac_ 02:50:56 INFO - Initialising browser for kraken test... 02:50:56 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/tmpjcU8jN/profile http://localhost:49243/getInfo.html) 02:50:58 INFO - PROCESS | 1642 | 2017-01-12 02:50:58.992 plugin-container[1643:10371] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 02:50:58 INFO - PROCESS | 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:50:58 INFO - PROCESS | 1642 | 2017-01-12 02:50:58.995 plugin-container[1643:10371] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 02:50:58 INFO - PROCESS | 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:50:58 INFO - PROCESS | 1642 | 2017-01-12 02:50:58.995 plugin-container[1643:10371] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:50:59 INFO - PROCESS | 1642 | __metrics Screen width/height:1600/1200 02:50:59 INFO - PROCESS | 1642 | colorDepth:24 02:50:59 INFO - PROCESS | 1642 | Browser inner width/height: 1024/689 02:50:59 INFO - PROCESS | 1642 | __metrics 02:50:59 ERROR - PROCESS | 1642 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3242: TypeError: this.tabs is undefined 02:50:59 INFO - TEST-INFO | 1642: exit 0 02:50:59 INFO - Browser initialized. 02:50:59 INFO - Running cycle 1/1 for kraken test... 02:50:59 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/tmpjcU8jN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:51:02 INFO - PROCESS | 1644 | 2017-01-12 02:51:02.132 plugin-container[1645:10484] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 02:51:02 INFO - PROCESS | 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:51:02 INFO - PROCESS | 1644 | 2017-01-12 02:51:02.133 plugin-container[1645:10484] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 02:51:02 INFO - PROCESS | 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:51:02 INFO - PROCESS | 1644 | 2017-01-12 02:51:02.133 plugin-container[1645:10484] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:51:11 INFO - PROCESS | 1644 | RSS: Main: 233144320 02:51:11 INFO - PROCESS | 1644 | 02:51:15 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection) 02:51:16 INFO - PROCESS | 1644 | RSS: Main: 245854208 02:51:16 INFO - PROCESS | 1644 | 02:51:18 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-dft) 02:51:19 INFO - PROCESS | 1644 | RSS: Main: 253825024 02:51:19 INFO - PROCESS | 1644 | 02:51:23 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-fft) 02:51:23 INFO - PROCESS | 1644 | RSS: Main: 256491520 02:51:23 INFO - PROCESS | 1644 | 02:51:25 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator) 02:51:26 INFO - PROCESS | 1644 | RSS: Main: 257658880 02:51:26 INFO - PROCESS | 1644 | 02:51:28 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:51:28 INFO - PROCESS | 1644 | RSS: Main: 259289088 02:51:28 INFO - PROCESS | 1644 | 02:51:31 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom) 02:51:32 INFO - PROCESS | 1644 | RSS: Main: 264523776 02:51:32 INFO - PROCESS | 1644 | 02:51:36 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate) 02:51:36 INFO - PROCESS | 1644 | RSS: Main: 261181440 02:51:36 INFO - PROCESS | 1644 | 02:51:40 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial) 02:51:40 INFO - PROCESS | 1644 | RSS: Main: 262049792 02:51:40 INFO - PROCESS | 1644 | 02:51:42 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:51:42 INFO - PROCESS | 1644 | RSS: Main: 262414336 02:51:42 INFO - PROCESS | 1644 | 02:51:45 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:51:45 INFO - PROCESS | 1644 | RSS: Main: 262508544 02:51:45 INFO - PROCESS | 1644 | 02:51:47 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:51:48 INFO - PROCESS | 1644 | RSS: Main: 262848512 02:51:48 INFO - PROCESS | 1644 | 02:51:51 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:51:52 INFO - PROCESS | 1644 | RSS: Main: 263151616 02:51:52 INFO - PROCESS | 1644 | 02:51:55 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:51:56 INFO - PROCESS | 1644 | RSS: Main: 262946816 02:51:56 INFO - PROCESS | 1644 | 02:51:57 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49243/tests/kraken/driver.html?testName=ai-astar) 02:51:58 INFO - PROCESS | 1644 | RSS: Main: 266371072 02:51:58 INFO - PROCESS | 1644 | 02:51:58 INFO - PROCESS | 1644 | __start_tp_report 02:51:58 INFO - PROCESS | 1644 | _x_x_mozilla_page_load 02:51:58 INFO - PROCESS | 1644 | _x_x_mozilla_page_load_details 02:51:58 INFO - PROCESS | 1644 | |i|pagename|runs| 02:51:58 INFO - PROCESS | 1644 | |0;ai-astar;106;112;157;178;171;117;119;137;178;152 02:51:58 INFO - PROCESS | 1644 | |1;audio-beat-detection;140;113;110;149;112;114;99;117;161;103 02:51:58 INFO - PROCESS | 1644 | |2;audio-dft;204;226;249;192;274;195;277;285;200;194 02:51:58 INFO - PROCESS | 1644 | |3;audio-fft;80;57;60;85;62;76;82;84;61;76 02:51:58 INFO - PROCESS | 1644 | |4;audio-oscillator;87;75;114;100;104;71;82;69;70;82 02:51:58 INFO - PROCESS | 1644 | |5;imaging-gaussian-blur;136;122;92;85;123;133;89;87;89;86 02:51:58 INFO - PROCESS | 1644 | |6;imaging-darkroom;140;109;153;152;153;132;127;112;154;122 02:51:58 INFO - PROCESS | 1644 | |7;imaging-desaturate;89;84;145;128;144;78;135;149;144;116 02:51:58 INFO - PROCESS | 1644 | |8;json-parse-financial;60;60;60;64;62;63;62;64;64;60 02:51:58 INFO - PROCESS | 1644 | |9;json-stringify-tinderbox;73;94;105;110;86;75;69;72;70;71 02:51:58 INFO - PROCESS | 1644 | |10;stanford-crypto-aes;91;91;107;110;110;91;89;91;90;91 02:51:58 INFO - PROCESS | 1644 | |11;stanford-crypto-ccm;145;144;148;142;144;143;140;144;233;147 02:51:58 INFO - PROCESS | 1644 | |12;stanford-crypto-pbkdf2;175;179;161;233;171;173;174;173;172;232 02:51:58 INFO - PROCESS | 1644 | |13;stanford-crypto-sha256-iterative;66;91;89;74;65;78;89;89;89;90 02:51:58 INFO - PROCESS | 1644 | __end_tp_report 02:51:58 INFO - PROCESS | 1644 | __start_cc_report 02:51:58 INFO - PROCESS | 1644 | _x_x_mozilla_cycle_collect,944 02:51:58 INFO - PROCESS | 1644 | __end_cc_report 02:51:58 INFO - PROCESS | 1644 | __startTimestamp1484218318216__endTimestamp 02:51:58 INFO - PROCESS | 1644 | 02:51:58 INFO - PROCESS | 1644 | ------- Summary: start ------- 02:51:58 INFO - PROCESS | 1644 | Number of tests: 14 02:51:58 INFO - PROCESS | 1644 | 02:51:58 INFO - PROCESS | 1644 | [#0] ai-astar Cycles:10 Average:142.70 Median:154.50 stddev:28.14 (18.2%) stddev-sans-first:26.53 02:51:58 INFO - PROCESS | 1644 | Values: 106.0 112.0 157.0 178.0 171.0 117.0 119.0 137.0 178.0 152.0 02:51:58 INFO - PROCESS | 1644 | 02:51:58 INFO - PROCESS | 1644 | [#1] audio-beat-detection Cycles:10 Average:121.80 Median:128.50 stddev:20.76 (16.2%) stddev-sans-first:20.95 02:51:58 INFO - PROCESS | 1644 | Values: 140.0 113.0 110.0 149.0 112.0 114.0 99.0 117.0 161.0 103.0 02:51:58 INFO - PROCESS | 1644 | 02:51:58 INFO - PROCESS | 1644 | [#2] audio-dft Cycles:10 Average:229.60 Median:237.50 stddev:38.13 (16.1%) stddev-sans-first:39.30 02:51:58 INFO - PROCESS | 1644 | Values: 204.0 226.0 249.0 192.0 274.0 195.0 277.0 285.0 200.0 194.0 02:51:58 INFO - PROCESS | 1644 | 02:51:58 INFO - PROCESS | 1644 | [#3] audio-fft Cycles:10 Average:72.30 Median:78.00 stddev:11.05 (14.2%) stddev-sans-first:11.36 02:51:58 INFO - PROCESS | 1644 | Values: 80.0 57.0 60.0 85.0 62.0 76.0 82.0 84.0 61.0 76.0 02:51:58 INFO - PROCESS | 1644 | 02:51:58 INFO - PROCESS | 1644 | [#4] audio-oscillator Cycles:10 Average:85.40 Median:73.00 stddev:15.72 (21.5%) stddev-sans-first:16.66 02:51:58 INFO - PROCESS | 1644 | Values: 87.0 75.0 114.0 100.0 104.0 71.0 82.0 69.0 70.0 82.0 02:51:58 INFO - PROCESS | 1644 | 02:51:58 INFO - PROCESS | 1644 | [#5] imaging-gaussian-blur Cycles:10 Average:104.20 Median:86.50 stddev:21.39 (24.7%) stddev-sans-first:19.35 02:51:58 INFO - PROCESS | 1644 | Values: 136.0 122.0 92.0 85.0 123.0 133.0 89.0 87.0 89.0 86.0 02:51:58 INFO - PROCESS | 1644 | 02:51:58 INFO - PROCESS | 1644 | [#6] imaging-darkroom Cycles:10 Average:135.40 Median:146.00 stddev:17.54 (12.0%) stddev-sans-first:18.52 02:51:58 INFO - PROCESS | 1644 | Values: 140.0 109.0 153.0 152.0 153.0 132.0 127.0 112.0 154.0 122.0 02:51:58 INFO - PROCESS | 1644 | 02:51:58 INFO - PROCESS | 1644 | [#7] imaging-desaturate Cycles:10 Average:121.20 Median:147.00 stddev:27.75 (18.9%) stddev-sans-first:26.87 02:51:58 INFO - PROCESS | 1644 | Values: 89.0 84.0 145.0 128.0 144.0 78.0 135.0 149.0 144.0 116.0 02:51:58 INFO - PROCESS | 1644 | 02:51:58 INFO - PROCESS | 1644 | [#8] json-parse-financial Cycles:10 Average:61.90 Median:62.50 stddev:1.79 (2.9%) stddev-sans-first:1.76 02:51:58 INFO - PROCESS | 1644 | Values: 60.0 60.0 60.0 64.0 62.0 63.0 62.0 64.0 64.0 60.0 02:51:58 INFO - PROCESS | 1644 | 02:51:58 INFO - PROCESS | 1644 | [#9] json-stringify-tinderbox Cycles:10 Average:82.50 Median:72.50 stddev:15.40 (21.2%) stddev-sans-first:15.95 02:51:58 INFO - PROCESS | 1644 | Values: 73.0 94.0 105.0 110.0 86.0 75.0 69.0 72.0 70.0 71.0 02:51:58 INFO - PROCESS | 1644 | 02:51:58 INFO - PROCESS | 1644 | [#10] stanford-crypto-aes Cycles:10 Average:96.10 Median:91.00 stddev:8.96 (9.8%) stddev-sans-first:9.31 02:51:58 INFO - PROCESS | 1644 | Values: 91.0 91.0 107.0 110.0 110.0 91.0 89.0 91.0 90.0 91.0 02:51:58 INFO - PROCESS | 1644 | 02:51:58 INFO - PROCESS | 1644 | [#11] stanford-crypto-ccm Cycles:10 Average:153.00 Median:144.50 stddev:28.20 (19.5%) stddev-sans-first:29.76 02:51:58 INFO - PROCESS | 1644 | Values: 145.0 144.0 148.0 142.0 144.0 143.0 140.0 144.0 233.0 147.0 02:51:58 INFO - PROCESS | 1644 | 02:51:58 INFO - PROCESS | 1644 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:184.30 Median:174.50 stddev:25.81 (14.8%) stddev-sans-first:27.15 02:51:58 INFO - PROCESS | 1644 | Values: 175.0 179.0 161.0 233.0 171.0 173.0 174.0 173.0 172.0 232.0 02:51:58 INFO - PROCESS | 1644 | 02:51:58 INFO - PROCESS | 1644 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:82.00 Median:89.00 stddev:10.36 (11.6%) stddev-sans-first:9.23 02:51:58 INFO - PROCESS | 1644 | Values: 66.0 91.0 89.0 74.0 65.0 78.0 89.0 89.0 89.0 90.0 02:51:58 INFO - PROCESS | 1644 | -------- Summary: end -------- 02:51:58 INFO - PROCESS | 1644 | 02:51:59 INFO - TEST-INFO | 1644: exit 0 02:51:59 INFO - TEST-OK | kraken | took 62829ms 02:51:59 INFO - SUITE-END | took 1180s 02:51:59 INFO - Completed test suite (00:19:40) 02:51:59 INFO - javascript benchmark 02:51:59 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7495.556705289037, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3379.4423920053187, 3398.4902264660313, 3408.4546472564393, 3420.110771199488, 3425.640307767686, 5713.0, 5839.386864379243, 5848.619839710421, 5890.469857712805, 5948.851278718034, 4284.200635732992, 5734.311882574087, 5840.415958404157, 5842.328132264791, 6013.579049466528, 5209.635325527214, 5263.868403289929, 5275.36695596529, 5379.408265090837, 5453.563714902818, 4190.97904510477, 5338.67967921924, 5426.240326354314, 5442.945570544283, 5478.91781623276, 5254.973725131388, 5404.675719456806, 5407.459254074561, 5429.049916264657, 5484.61607687462, 4413.6910416270875, 5260.631755777097, 5264.0, 5342.572594192456, 5411.323584551931, 4290.871273861789, 5252.763625636855, 5289.285946397225, 5344.198370244456, 5368.248445217672, 5282.366116066097, 5651.519620832234, 5655.886882262373, 5930.733117009745, 5930.851728706773, 5340.67955922642, 5584.804531841407, 6226.0, 6256.124142620037, 6322.557420980534, 6253.562250642457, 6342.016987366919, 6348.0, 6355.523335749837, 6373.458256048241, 5230.581553475713, 5841.766329346779, 6009.939900600982, 6107.938920610737, 6117.357677443893, 5168.483151684802, 6252.843678908063, 6321.6207027577575, 6451.258105317948, 6475.514336424787, 6168.8766224675255, 6213.627182369072, 6254.499640028786, 6280.717367718464, 6320.178376810985, 6159.983602705593, 6301.9286721257495, 6312.715927783261, 6316.084167795736, 6341.112244285804, 6072.544559158081, 6118.801925441459, 6138.970878344271, 6149.200603921551, 6192.659403732838, 6107.886786452505, 6119.746288422617, 6157.014877619559, 6159.127423684521, 6226.315105338409, 5121.8719532011255, 5122.615803814729, 5150.613703972217, 5157.690538567698, 5164.948350516522, 4585.747783871852, 4601.12578610063, 4616.699914505546, 4620.67655264135, 4643.326717625993, 5799.739011744482, 5874.060150375919, 5874.9118763218585, 5965.731542080617, 5974.103884417372, 6190.597611155261, 6253.187085678833, 6264.498840092781, 6328.177336946167, 6345.17512723343, 5955.4640082391925, 6023.427774361428, 6045.365236650088, 6052.334243233241, 6068.817935461943, 5462.399136095027, 5616.354119276254, 5626.0, 5627.155926610959, 5645.858853528694, 6192.0, 6354.1104245406605, 6380.266269379081, 6391.0, 6409.775657851953, 6281.685915704105, 6304.306526282014, 6311.147995020613, 6331.461825744863, 6368.4586810121655, 6511.609303441809, 6528.804135875931, 6530.053142294436, 6536.346365363425, 6558.901616475852, 6153.9845925421805, 6189.783357582463, 6205.968970155121, 6333.461655759128, 6342.841428964312, 6199.845003874939, 6278.0896769967185, 6308.621482711052, 6308.684565771601, 6317.905231421624, 6129.877402451837, 6130.642739936431, 6154.230721159855, 6158.907616385847, 6278.529110316836, 6127.7393367763425, 6192.318844927145, 6210.658413787104, 6271.153394291712, 6286.805506953664, 5359.3032905722, 6035.577509574287, 6048.243969503812, 6172.135900973957, 6195.473384762166, 6142.069075791586, 6147.954847675996, 6199.225096862892, 6206.162168107247, 6264.937350626436, 5681.579605098726, 6199.659018754011, 6200.7519699211525, 6210.627362358273, 6224.06639004153], "value": 5767.364118852807, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [443.7869822485205, 458.7820785127064, 472.2679846238332, 502.3068165931017, 518.1114388823167, 35305.82347088261, 40407.79796101019, 41056.794716026416, 44677.32984005234, 44693.32960005602, 44016.55983440165, 44315.77842110785, 44362.33456498155, 44413.0, 44421.7778911105, 8431.831363372728, 9402.482863442508, 9654.662086826962, 9658.227341812639, 9680.515974201297, 7465.589392583406, 11231.887681123208, 11486.42567871603, 11503.42482875857, 11690.532378704842, 11465.197436179471, 11533.711657208594, 11540.769184616303, 11552.884471155265, 11569.305841649486, 11508.251963622337, 11528.135389845753, 11594.884051159464, 11600.0, 11623.94188029063, 11413.828792568118, 11508.424578771028, 11526.423678816025, 11532.654020379403, 11534.250273732181, 41364.793176034225, 41502.58497415017, 41774.37338439926, 41821.0, 41856.1628767426, 3551.1654761131113, 4128.731632443882, 4132.173565286925, 4142.378643203513, 4148.270971092957, 30514.694853051406, 31114.0, 31566.210844728837, 31799.84100079508, 31868.521972170434, 3191.4255434021866, 3211.165097074754, 3218.6620404857385, 3252.9512057319157, 3264.6245681746664, 450781.0, 457994.0, 458137.9999999967, 458901.0, 459895.0, 64986.0, 65230.67384663046, 65250.347496524424, 65478.01782973259, 65548.34451655422, 66436.0, 67020.66489667521, 67617.66191169112, 67684.0, 67714.3228567708, 16287.429939952282, 16468.83531164697, 16483.258253378386, 16532.338706451606, 16560.420385286456, 11418.0, 11635.185537012498, 12465.875341246652, 12586.118971671895, 12691.301978391093, 17861.37485188038, 18429.986350750838, 18456.723149152775, 18502.352417665315, 18545.0, 5771.422857714279, 6506.4469520091325, 6523.673816309166, 6538.869222615521, 6544.087880893052, 863.8747381629629, 879.2897813752319, 891.8706787515774, 920.6777627830206, 927.2442958988403, 30483.115430329548, 30853.69146308553, 31116.0, 31174.376512470077, 31571.52642710361, 949.7957939043139, 972.547765289144, 1052.4579841381694, 1074.4949873559415, 1245.0537591430577, 13850.653733656538, 15496.070235785888, 16027.759583606023, 16172.595685107966, 16446.75329870053, 512409.0, 568763.0, 575120.0, 617449.0, 619775.0, 60530.0, 60565.0, 60681.0, 60702.08946865801, 60839.08741368883, 1436.1393719243936, 1456.730504856605, 1460.3063544816127, 1482.85171482853, 1487.7247709173837, 26065.0, 28467.572986404808, 29952.700472995864, 30511.0, 30810.843240972918, 51354.74322628363, 51559.711007225116, 51831.0, 51897.96204075973, 51952.48047519477, 532529.0, 535085.0, 538639.0, 539449.0, 539471.0, 40661.78014659565, 40683.796581016904, 41037.58962410338, 41142.9714257146, 41765.0, 45938.31092533548, 47067.29399058948, 47143.29285060658, 47759.52240477551, 47955.760221198674, 37790.43314350342, 38025.429618555194, 38473.0, 39392.0, 39632.2073558533, 32459.86390476322, 38331.23337533289, 38690.874916266774, 38714.80642596769, 40051.0, 32914.1771455706, 36185.0953726159, 37939.43090853584, 38535.80732096321, 38722.419163712, 30076.699233008265, 30548.0, 34476.655233447345, 34982.0, 35026.474602881484, 22604.434889127904, 23141.07435702553, 25196.87401563054, 25773.871130644227, 25862.353441164123], "value": 20521.610543510982, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [387.80803502266366, 391.54385141310377, 398.58148943609206, 401.4259608759474, 420.62765999761353, 14364.497242596512, 16111.677766444664, 19594.804051959476, 20812.167513299468, 21058.6841197382, 20643.483912902186, 20907.68638470424, 21055.36833894982, 21471.0, 21709.0, 5688.886222275552, 7473.850522989537, 7820.100688420819, 7909.723159689412, 7930.841383172333, 5971.552133589977, 7746.0, 7835.882461763078, 7875.409344299201, 7947.642356093989, 7664.961675191616, 7931.8413631727335, 7938.722144724936, 7946.6821327146845, 7966.4025198110085, 7825.256600622932, 7919.643616037292, 7955.244251796128, 7971.521708697497, 7991.5604641744685, 7432.777016689508, 7642.04474440695, 7694.538327700356, 7705.499142555772, 7713.460057795956, 28522.572161417596, 28955.552222388797, 29035.709642903512, 29111.981080662074, 29181.27046823825, 3274.8690052397874, 3314.2211580278617, 3374.4094783412806, 3399.1162297802503, 3427.7943323400677, 16888.733344999175, 17182.312707491685, 17302.653946920993, 17494.950302981862, 17896.46310610684, 1911.3692481481078, 2070.8136267735827, 2082.791720827905, 2086.2698055680544, 2094.6962690409805, 130667.0, 135794.32102839422, 149833.0, 162789.0, 167617.0, 23484.0, 23593.646095308584, 23886.402839928793, 24011.19776044763, 24119.51760964806, 41747.0, 42171.94570135771, 42364.94087647834, 42388.78805605952, 42629.57370426318, 8321.750347489586, 8537.957310213409, 8832.072632373509, 8850.557472126367, 8949.0, 8426.368022398223, 8552.61513231906, 8601.5699215039, 8655.437396569203, 8700.08649091849, 4832.44426890905, 4836.733979631154, 4879.365682461257, 4893.339399181065, 4899.657024008286, 1873.4847916822982, 1928.9517762056062, 2006.5485265815075, 2053.209514336991, 2063.556335387869, 841.87792770048, 846.6444093480753, 857.0058731871, 883.266888482558, 904.7285814255697, 16799.412020579464, 18232.997185154945, 19298.842069475875, 20492.18031278762, 21377.0, 711.9093825497995, 844.8859403980384, 846.2595229174473, 875.5665945357089, 894.932880034, 10960.068394186424, 11180.496877640526, 11200.21598488098, 11207.66377008691, 11399.48702308398, 358531.0, 358908.0, 358961.0, 567412.0, 762557.0, 117631.41184294024, 162543.0, 164110.0, 168738.0, 169364.0, 893.946363218209, 970.922276273336, 972.2416515118219, 1050.910672592823, 1057.5769692123058, 15666.295016724047, 18313.542161446072, 18682.719759203894, 18746.812531874508, 18785.906070470108, 62248.37751622426, 62710.68644656747, 62821.68589157025, 62824.057639136365, 63126.05613382432, 88802.55598722224, 90351.54824225837, 90381.0, 91106.08893911241, 91209.4871909964, 7624.750698543832, 7816.374690024898, 7834.804129896798, 8243.711470098518, 8353.707620233263, 10640.88270731562, 12169.69575760613, 12304.507819687471, 12635.620931372074, 13123.409446575119, 9344.878614566296, 10733.731656708645, 10739.570417183226, 10748.4088375137, 10984.725381865517, 8947.239484643878, 9665.323427360017, 10713.250072495162, 10847.783044339227, 11159.38623375722, 10173.796524069625, 10313.329633573794, 10447.791044178923, 10648.467576621293, 11663.941680291544, 8238.50568965864, 8284.71003514874, 8358.122397148212, 8589.570521474026, 8628.22345988864, 9797.363171394121, 10414.114800242065, 10660.573577056832, 10988.285761425483, 11031.889681103086], "value": 10839.90321714276, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1279.2260682287215, 1450.6953539756657, 1466.2668665667165, 1474.901198607038, 1479.1494890437998, 7555.471117021811, 7808.375329973603, 8183.0, 8576.91423085769, 8578.39951203416, 8028.277455029046, 8288.502689838599, 8341.582920853964, 8456.281216096639, 8493.617787199575, 1920.9903950480264, 2101.4746313421647, 2364.798992085675, 2371.027878569787, 2378.7740164684415, 2097.0, 2344.42561572415, 2391.3184742348435, 2395.281415575329, 2396.4607963208314, 2061.443410279224, 2255.109231853425, 2349.95300093998, 2350.1069593554425, 2369.123424332999, 2317.385892738426, 2336.5910965581124, 2337.5909215887236, 2348.236823032513, 2356.9882150589224, 2332.720073591163, 2333.8949747261413, 2342.6251799712136, 2358.716953965518, 2361.1027809432308, 8400.957995210047, 8439.156084391572, 8469.788255293606, 8514.872276915852, 8527.872081918777, 5117.616178786606, 5263.131583288753, 5268.81559145428, 5274.4198138204765, 5275.050490911634, 3510.7191424685966, 3539.610642829287, 3583.677469027774, 3586.677199052098, 3605.1888325126897, 1168.59099315239, 1183.7040739815047, 1206.5837286136268, 1228.2507670321097, 1235.05518278517, 9400.0, 10310.123639490652, 10840.295380800222, 10858.23992320538, 10867.402292873889, 4554.157480866051, 4684.297355396649, 4693.131770622445, 4764.880877978078, 4790.952090479121, 9513.143817056498, 9566.61733530651, 9577.952110239405, 9583.472908989934, 9604.375715578604, 5266.578673706094, 5287.127623942007, 5316.388615309212, 5332.413434522199, 5361.169018802092, 3733.2160246348026, 4490.461144662661, 4818.349522814446, 4880.95119048805, 4903.8528844134735, 3363.5963684358035, 3710.387786015331, 3820.3887378019385, 3903.7072219583647, 3928.4696565963227, 1149.4827327702567, 1180.9940950295193, 1226.926384416938, 1292.9870701292866, 1303.5307289375817, 765.448876808708, 775.5501808950795, 797.469682661024, 824.7814329202766, 841.9621117049747, 5511.917321240185, 5544.77820887168, 5545.085060964976, 5629.240052592848, 5665.3201615806365, 982.8476586129161, 992.6972273456665, 997.9401235925868, 1019.3476175247847, 1043.2905624175633, 3726.366517691999, 3733.4773131761576, 3821.216650586623, 3843.6925045996254, 3882.3400021996326, 9427.528623568931, 9942.204623630092, 10055.245856560787, 10059.245556583286, 10192.082712555757, 9863.16163126128, 10096.646617368504, 10113.241506887012, 10132.493375331203, 10138.493075346203, 7826.334761545332, 8030.598470076473, 8033.718819841278, 8038.276555110068, 8052.557109358924, 5407.972960135174, 5435.402105768283, 5470.835874923759, 5473.808416705396, 5496.80761173357, 9255.305852060988, 9262.629494820132, 9300.162985331353, 9364.344495885223, 9368.718938431857, 9332.20676242527, 9461.810763784824, 9513.571889264862, 9565.856512152463, 9566.569504372459, 7131.108611423572, 7225.458090643117, 7232.0959880015, 7248.187953011747, 7254.927450725425, 7683.884741728991, 7982.720604778805, 8002.439829211899, 8049.114597394399, 8081.353491720531, 7139.643017848983, 7161.928380716126, 7202.49582529218, 7206.243344448757, 7303.744368947061, 7007.439404847498, 7060.823479413056, 7083.468739844429, 7125.964370178116, 7129.893051604334, 5241.266222728897, 6283.088952102011, 6301.4328710416285, 6337.271213810471, 6353.5870168438905, 4975.925361119658, 5000.599952003904, 5011.674241174312, 5076.238564215398, 5083.694978301313, 4925.729084900365, 4930.137225985366, 5022.54797068251, 5078.009788091286, 5088.872778180576], "value": 4430.503621139543, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [328.94901290300004, 362.5033703825759, 370.3056768558952, 373.1082712317561, 375.2214155627075, 5184.300119483869, 5368.651037682548, 5478.917816232754, 5508.944910550893, 5551.639143455673, 5155.834549489214, 5317.787288508455, 5333.35999680038, 5355.651882627636, 5404.216388623648, 4222.809973551198, 4249.362595610668, 4251.3622956556455, 4258.765767882765, 4338.370936214247, 3365.646607106243, 3625.963740362589, 4181.268278051344, 4262.509811371686, 4315.899445641373, 3427.8174029959623, 3947.368421052632, 4166.645840101979, 4166.895827604304, 4227.239096962546, 3286.4905939579403, 3677.3932301170407, 3806.781829814433, 4217.641500472449, 4231.682623803227, 3720.683741881943, 3730.3328120393776, 3734.7198960078103, 3736.5516138063335, 3761.981190094032, 4636.86089417318, 5030.974845125751, 5238.947610523922, 5305.230741542455, 5310.751973286275, 4072.3077076897, 4842.104210720992, 4852.883836717539, 4854.587360074398, 4861.368022157097, 5027.360635666268, 5153.845384638467, 5189.014087323397, 5708.372079071299, 5717.799877004285, 3600.39782296878, 3862.4013277941963, 3894.6884249259992, 3908.902277443058, 4088.5298190707854, 5560.805371812007, 6014.8195554133445, 6017.909731354033, 6022.367651396584, 6055.7274922628585, 3419.5383623211046, 3496.9650303496637, 3939.6060393960947, 3942.5071866016747, 3956.485656864647, 3850.940991227379, 3979.7015223857748, 3988.6809055275507, 3996.2606917720314, 4016.879493615196, 3963.326234540135, 4221.176870510282, 4664.883377915511, 4703.5183917066015, 4711.222648263067, 4871.537203965618, 5292.682439053669, 5490.956718223525, 5754.0, 5871.6788722537085, 1053.4574694032578, 1199.1186477938709, 1229.59423390281, 1278.5461161287794, 1284.653143651209, 812.3785304242259, 874.0996773323487, 876.509154873273, 880.3749337969984, 880.428435243088, 4564.794584243717, 4651.0, 4940.580050695731, 5128.358955130609, 5161.380634323905, 4739.881502962454, 4889.63327750413, 5009.824656137018, 5432.826869252331, 5618.016847051749, 4090.8159132839096, 4532.614727748112, 5035.892103737172, 5073.1122053640465, 5121.539061484485, 2545.8981640734164, 2675.996501312008, 2828.6322778038725, 2969.3170570768843, 2999.2651800309063, 5022.598192144693, 5261.289725887033, 5843.211166492554, 5854.385289544622, 5873.735681894325, 4571.6799824012, 4611.492735799059, 5620.4741593096405, 5626.971865140648, 5664.910221511891, 6116.0, 6126.877462450815, 6161.014237721943, 6172.290186628506, 6178.41305076026, 5784.855378615568, 5945.256842894638, 5956.26012034469, 6009.939900601113, 6050.788222412194, 5465.398806131243, 5759.366469688414, 5854.853628659318, 5865.296164460121, 5932.16949627044, 6028.016132014681, 6058.485028772604, 6059.242594675666, 6186.679995001874, 6208.4722798562625, 3804.391297392404, 4036.4752582164133, 4292.304539706736, 4299.333603291369, 4363.738175709467, 5708.600397972102, 5940.970295148497, 5949.25634295713, 5985.281766188085, 5986.461218490357, 4847.158079929604, 4941.2693416471775, 5661.150827375926, 5673.319201695823, 5742.339630942328, 5747.8563035924435, 5771.105478650816, 5801.796937107181, 5824.271966004249, 5838.386969368155, 5302.443243459536, 5324.653897496651, 5700.857478563069, 5707.0, 5713.0, 4703.412073490814, 5170.741462926763, 5806.067935692448, 5837.562182836219, 5866.794662186803, 4773.73744444045, 5679.488846003879, 5690.4878560928055, 5711.8857622848145, 5759.452851979056], "value": 4153.0597081295155, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [682.3006418421114, 715.8639858426895, 735.3712575747736, 751.8007727952092, 781.7726169913238, 12479.750404991895, 14586.854131458682, 15582.0, 16053.759193612075, 16873.24070416831, 16002.759958600602, 16115.919420402912, 16384.180790960436, 16395.344186232567, 16578.50264492064, 782.5304817109745, 860.105490290099, 879.116487929633, 881.4446898453965, 883.0815951410525, 677.5629718831351, 701.9333163349473, 839.6599377252222, 862.6376921692904, 866.7356456280831, 892.1435421994893, 915.3226612306878, 916.6425094213262, 918.3663272342062, 919.1681528216974, 896.3546246702397, 913.5295322908707, 914.1087439746227, 914.1087439746261, 914.9542522873863, 682.7985744205471, 683.1528904158857, 683.2313647146959, 683.2927919603236, 685.9519833611649, 11760.705982350424, 15466.690666186612, 15719.68560628781, 15832.208389580475, 15869.920650396789, 14114.788278175836, 14280.857191428056, 14467.131972081708, 14503.637409064857, 14510.637234069232, 11698.356590387526, 11852.585159519376, 11870.465829037714, 11901.107416943678, 12039.578614748529, 3671.1005803578028, 3684.244729830378, 3710.7031437485207, 3741.4574886641285, 3852.1525264442057, 103151.0, 104552.43171352436, 105090.0, 105470.0, 106592.46703766509, 9119.863202051974, 10911.618093366695, 11030.683874559969, 11086.390248536247, 11118.166137539716, 21978.560428791334, 22348.553028939332, 22601.321960340887, 22630.434239143822, 22643.773562264167, 7744.109427415807, 7758.379329653614, 7790.259925307087, 7830.6084695764985, 7918.089419716806, 4268.189044081614, 4311.72804022814, 4356.934645980313, 4376.540463251376, 4405.207062728676, 3581.4448760442174, 3600.873969411058, 3678.7608805427562, 3679.300932822755, 3682.8158592070827, 622.3465361370634, 629.6348118091588, 629.789020678078, 645.4126744662335, 648.3905129178557, 661.8510835062169, 691.1636919327569, 697.8988046733195, 705.1115594351052, 715.6421789105448, 10185.541650625739, 10263.486825658687, 10381.63664271762, 10505.212109091848, 10600.681979540626, 683.1460674157304, 713.2760248347931, 718.5150023733959, 727.6689106456549, 729.0959210578844, 4619.0, 4865.56209941107, 5065.366829146356, 5071.442141364447, 5077.060743762416, 81710.0, 82798.75801862977, 84470.57764711136, 114397.0, 115533.0, 67612.66193669099, 68597.3140268591, 79704.0, 80679.59660201661, 82830.75753863697, 62107.378926211066, 68744.0, 68869.31130688729, 69505.0, 70676.0, 10221.540030698488, 10455.482438753583, 10868.967448092419, 10991.890081099407, 11049.39228342449, 38669.033274168374, 49368.506314937826, 49696.00607987892, 50658.0, 50685.74657126691, 67463.3253667457, 67595.31011724549, 67943.0, 68621.0, 70093.9485907722, 13221.933890330487, 13270.734585308433, 13367.866321337051, 13457.730845383234, 13533.188008719508, 14550.0, 14833.258337082887, 14975.0, 15058.397664093753, 15253.23733813327, 13478.989075819549, 13504.189748615114, 13814.792778108538, 14057.789133162807, 14539.563813085624, 13633.38649760783, 13782.035257531876, 14031.579052628438, 14404.0, 14406.567802965928, 13412.530561430303, 13603.727925441633, 13679.86320136786, 14247.073940193854, 14348.928255358656, 11186.384748838567, 11194.776104478027, 11281.379524126252, 11885.465154067915, 11965.581204658143, 8056.798580035546, 8813.383063185516, 8932.330075244512, 10164.593416263267, 10835.133189344991], "value": 7511.706142357986, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1672.3999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 112.0, 157.0, 178.0, 171.0, 117.0, 119.0, 137.0, 178.0, 152.0], "value": 142.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 113.0, 110.0, 149.0, 112.0, 114.0, 99.0, 117.0, 161.0, 103.0], "value": 121.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [204.0, 226.0, 249.0, 192.0, 274.0, 195.0, 277.0, 285.0, 200.0, 194.0], "value": 229.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 57.0, 60.0, 85.0, 62.0, 76.0, 82.0, 84.0, 61.0, 76.0], "value": 72.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 75.0, 114.0, 100.0, 104.0, 71.0, 82.0, 69.0, 70.0, 82.0], "value": 85.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 122.0, 92.0, 85.0, 123.0, 133.0, 89.0, 87.0, 89.0, 86.0], "value": 104.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 109.0, 153.0, 152.0, 153.0, 132.0, 127.0, 112.0, 154.0, 122.0], "value": 135.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 84.0, 145.0, 128.0, 144.0, 78.0, 135.0, 149.0, 144.0, 116.0], "value": 121.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 60.0, 64.0, 62.0, 63.0, 62.0, 64.0, 64.0, 60.0], "value": 61.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 94.0, 105.0, 110.0, 86.0, 75.0, 69.0, 72.0, 70.0, 71.0], "value": 82.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 91.0, 107.0, 110.0, 110.0, 91.0, 89.0, 91.0, 90.0, 91.0], "value": 96.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 144.0, 148.0, 142.0, 144.0, 143.0, 140.0, 144.0, 233.0, 147.0], "value": 153.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 179.0, 161.0, 233.0, 171.0, 173.0, 174.0, 173.0, 172.0, 232.0], "value": 184.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 91.0, 89.0, 74.0, 65.0, 78.0, 89.0, 89.0, 89.0, 90.0], "value": 82.0, "unit": "score"}]}]} 02:51:59 INFO - Return code: 0 02:51:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:51:59 ERROR - # TBPL SUCCESS # 02:51:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:51:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:51:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:51:59 INFO - 2017-01-12 02:51:59.499 screenresolution[1649:10874] starting screenresolution argv=screenresolution get 02:51:59 INFO - 2017-01-12 02:51:59.502 screenresolution[1649:10874] Display 0: 1600x1200x32@60 02:51:59 INFO - 2017-01-12 02:51:59.509 screenresolution[1650:10877] starting screenresolution argv=screenresolution list 02:51:59 INFO - Available Modes on Display 0 02:51:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:51:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:51:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:51:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:51:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:51:59 INFO - Intel Iris: 02:51:59 INFO - Chipset Model: Intel Iris 02:51:59 INFO - Type: GPU 02:51:59 INFO - Bus: Built-In 02:51:59 INFO - VRAM (Dynamic, Max): 1536 MB 02:51:59 INFO - Vendor: Intel (0x8086) 02:51:59 INFO - Device ID: 0x0a2e 02:51:59 INFO - Revision ID: 0x0009 02:51:59 INFO - Displays: 02:51:59 INFO - Display: 02:51:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:51:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:51:59 INFO - Display Serial Number: 1600x1200 60 02:51:59 INFO - Main Display: Yes 02:51:59 INFO - Mirror: Off 02:51:59 INFO - Online: Yes 02:51:59 INFO - Rotation: Supported 02:51:59 INFO - Return code: 0 02:51:59 INFO - Running post-action listener: _resource_record_post_action 02:51:59 INFO - [mozharness: 2017-01-12 10:51:59.747927Z] Finished run-tests step (success) 02:51:59 INFO - Running post-run listener: _resource_record_post_run 02:51:59 INFO - Total resource usage - Wall time: 1204s; CPU: 54.0%; Read bytes: 39606784; Write bytes: 553375744; Read time: 385; Write time: 1784 02:51:59 INFO - TinderboxPrint: CPU usage
54.5% 02:51:59 INFO - TinderboxPrint: I/O read bytes / time
39,606,784 / 385 02:51:59 INFO - TinderboxPrint: I/O write bytes / time
553,375,744 / 1,784 02:51:59 INFO - TinderboxPrint: CPU idle
2,191.1 (45.5%) 02:51:59 INFO - TinderboxPrint: CPU system
972.2 (20.2%) 02:51:59 INFO - TinderboxPrint: CPU user
1,650.5 (34.3%) 02:51:59 INFO - TinderboxPrint: Swap in / out
289,800,192 / 0 02:51:59 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 251290112; Write bytes: 251011072; Read time: 18097; Write time: 474 02:51:59 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 35297792; Write bytes: 300972032; Read time: 351; Write time: 1302 02:52:00 INFO - Running post-run listener: _upload_blobber_files 02:52:00 INFO - Blob upload gear active. 02:52:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:52:00 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 02:52:00 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'] 02:52:00 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 02:52:00 INFO - (blobuploader) - INFO - Open directory for files ... 02:52:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 02:52:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:52:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:52:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 02:52:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:52:01 INFO - (blobuploader) - INFO - Done attempting. 02:52:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 02:52:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:52:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:52:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 02:52:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:52:02 INFO - (blobuploader) - INFO - Done attempting. 02:52:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 02:52:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:52:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:52:03 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 02:52:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:52:03 INFO - (blobuploader) - INFO - Done attempting. 02:52:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:52:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:52:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:52:06 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:52:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:52:06 INFO - (blobuploader) - INFO - Done attempting. 02:52:06 INFO - (blobuploader) - INFO - Iteration through files over. 02:52:06 INFO - Return code: 0 02:52:06 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:52:06 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:52:06 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2fb75ae361b32bce1c945495f226ea99ae3f4a372130ee5d96afbc06d0f6db8b97bd358d7a7593c018e47e57e6ecc05005d8514e7988fb9a73f638846263ce70", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2146850c7aaf6f73df2dfbef416aec5f0be58302aa9df2694f1aaaaede8cd1856c77e75056318811d00e65a194a77b6607432371ec585c973715c5c85ab50882", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dc6ae7c8d2043ac24c05e73173ee1025f41796a148d36d1a1e06ca48798a0821b62e55b37f245450814f9220f0759afd8e3f58e2d4bcb7e1a2996edc4ac12a33", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1257d6ca332fef0da874b41e487fbd352c3104193bf1f90135e9f724b8c51c4757c76db6fb77c6d74ef735bdff145ffbdfc386c97d87b8c5314f6ec6fa567f3b"} 02:52:06 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:52:06 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:52:06 INFO - Contents: 02:52:06 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2fb75ae361b32bce1c945495f226ea99ae3f4a372130ee5d96afbc06d0f6db8b97bd358d7a7593c018e47e57e6ecc05005d8514e7988fb9a73f638846263ce70", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2146850c7aaf6f73df2dfbef416aec5f0be58302aa9df2694f1aaaaede8cd1856c77e75056318811d00e65a194a77b6607432371ec585c973715c5c85ab50882", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dc6ae7c8d2043ac24c05e73173ee1025f41796a148d36d1a1e06ca48798a0821b62e55b37f245450814f9220f0759afd8e3f58e2d4bcb7e1a2996edc4ac12a33", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1257d6ca332fef0da874b41e487fbd352c3104193bf1f90135e9f724b8c51c4757c76db6fb77c6d74ef735bdff145ffbdfc386c97d87b8c5314f6ec6fa567f3b"} 02:52:06 INFO - Running post-run listener: copy_logs_to_upload_dir 02:52:06 INFO - Copying logs to upload dir... 02:52:06 INFO - mkdir: /builds/slave/test/build/upload/logs 02:52:06 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.324672 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2017-01-12 02:52:06.387910) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-12 02:52:06.391348) ========= 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.lrG39ET1py/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.YGedVUnw6T/Listeners TMPDIR=/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2fb75ae361b32bce1c945495f226ea99ae3f4a372130ee5d96afbc06d0f6db8b97bd358d7a7593c018e47e57e6ecc05005d8514e7988fb9a73f638846263ce70", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2146850c7aaf6f73df2dfbef416aec5f0be58302aa9df2694f1aaaaede8cd1856c77e75056318811d00e65a194a77b6607432371ec585c973715c5c85ab50882", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dc6ae7c8d2043ac24c05e73173ee1025f41796a148d36d1a1e06ca48798a0821b62e55b37f245450814f9220f0759afd8e3f58e2d4bcb7e1a2996edc4ac12a33", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1257d6ca332fef0da874b41e487fbd352c3104193bf1f90135e9f724b8c51c4757c76db6fb77c6d74ef735bdff145ffbdfc386c97d87b8c5314f6ec6fa567f3b"} build_url:https://queue.taskcluster.net/v1/task/MPCsSl3IRLCWDjPs1QfhlA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011450 build_url: 'https://queue.taskcluster.net/v1/task/MPCsSl3IRLCWDjPs1QfhlA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2fb75ae361b32bce1c945495f226ea99ae3f4a372130ee5d96afbc06d0f6db8b97bd358d7a7593c018e47e57e6ecc05005d8514e7988fb9a73f638846263ce70", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2146850c7aaf6f73df2dfbef416aec5f0be58302aa9df2694f1aaaaede8cd1856c77e75056318811d00e65a194a77b6607432371ec585c973715c5c85ab50882", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dc6ae7c8d2043ac24c05e73173ee1025f41796a148d36d1a1e06ca48798a0821b62e55b37f245450814f9220f0759afd8e3f58e2d4bcb7e1a2996edc4ac12a33", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1257d6ca332fef0da874b41e487fbd352c3104193bf1f90135e9f724b8c51c4757c76db6fb77c6d74ef735bdff145ffbdfc386c97d87b8c5314f6ec6fa567f3b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-12 02:52:06.425827) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-12 02:52:06.426189) ========= 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.lrG39ET1py/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.YGedVUnw6T/Listeners TMPDIR=/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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.005191 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-12 02:52:06.474905) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-12 02:52:06.475225) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-12 02:52:06.475566) ========= ========= Total master_lag: 0.18 =========