builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0252 starttime: 1485336934.05 results: success (0) buildid: 20170125004018 builduid: 8e9033a887bc492fb45f7879d8601400 revision: 48f7caa80dfc6cafc778269b12e9e69379111c56 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-25 01:35:34.050238) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-25 01:35:34.050716) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 4 secs) (at 2017-01-25 01:35:34.050996) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 4 secs) (at 2017-01-25 01:35:38.871054) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-25 01:35:38.871469) ========= 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.EIhn4L4aOD/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.QJagvaKtwu/Listeners TMPDIR=/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/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-25 01:35:38-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 7.32M=0.002s 2017-01-25 01:35:39 (7.32 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.121501 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-25 01:35:39.016535) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-25 01:35:39.016844) ========= 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.EIhn4L4aOD/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.QJagvaKtwu/Listeners TMPDIR=/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/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.020052 ========= master_lag: 0.26 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-25 01:35:39.298613) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-25 01:35:39.298984) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 48f7caa80dfc6cafc778269b12e9e69379111c56 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 48f7caa80dfc6cafc778269b12e9e69379111c56 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.EIhn4L4aOD/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.QJagvaKtwu/Listeners TMPDIR=/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/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-25 01:35:39,392 truncating revision to first 12 chars 2017-01-25 01:35:39,392 Setting DEBUG logging. 2017-01-25 01:35:39,392 attempt 1/10 2017-01-25 01:35:39,392 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/48f7caa80dfc?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-25 01:35:40,755 unpacking tar archive at: mozilla-aurora-48f7caa80dfc/testing/mozharness/ program finished with exit code 0 elapsedTime=1.634690 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-25 01:35:40.956523) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-25 01:35:40.956876) ========= script_repo_revision: 48f7caa80dfc6cafc778269b12e9e69379111c56 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-25 01:35:40.957355) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-25 01:35:40.957705) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-25 01:35:40.980922) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2017-01-25 01:35:40.981479) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.EIhn4L4aOD/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.QJagvaKtwu/Listeners TMPDIR=/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/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 01:35:41 INFO - MultiFileLogger online at 20170125 01:35:41 in /builds/slave/test 01:35:41 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 01:35:41 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:35:41 INFO - {'append_to_log': False, 01:35:41 INFO - 'base_work_dir': '/builds/slave/test', 01:35:41 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 01:35:41 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:35:41 INFO - 'branch': 'Mozilla-Aurora', 01:35:41 INFO - 'buildbot_json_path': 'buildprops.json', 01:35:41 INFO - 'config_files': ('talos/mac_config.py',), 01:35:41 INFO - 'default_actions': ('clobber', 01:35:41 INFO - 'read-buildbot-config', 01:35:41 INFO - 'download-and-extract', 01:35:41 INFO - 'populate-webroot', 01:35:41 INFO - 'create-virtualenv', 01:35:41 INFO - 'install', 01:35:41 INFO - 'run-tests'), 01:35:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:35:41 INFO - 'download_minidump_stackwalk': True, 01:35:41 INFO - 'download_symbols': 'ondemand', 01:35:41 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:35:41 INFO - 'tooltool.py': '/tools/tooltool.py', 01:35:41 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:35:41 INFO - '/tools/misc-python/virtualenv.py')}, 01:35:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:35:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:35:41 INFO - 'installer_path': 'installer.exe', 01:35:41 INFO - 'log_level': 'info', 01:35:41 INFO - 'log_name': 'talos', 01:35:41 INFO - 'log_to_console': True, 01:35:41 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 01:35:41 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 01:35:41 INFO - 'opt_config_files': (), 01:35:41 INFO - 'pip_index': False, 01:35:41 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:35:41 INFO - 'cmd': ('bash', 01:35:41 INFO - '-c', 01:35:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:35:41 INFO - 'enabled': True, 01:35:41 INFO - 'halt_on_failure': False, 01:35:41 INFO - 'name': 'check_screen_resolution'},), 01:35:41 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:35:41 INFO - 'cmd': ('bash', 01:35:41 INFO - '-c', 01:35:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:35:41 INFO - 'enabled': True, 01:35:41 INFO - 'halt_on_failure': False, 01:35:41 INFO - 'name': 'check_screen_resolution'},), 01:35:41 INFO - 'run_cmd_checks_enabled': True, 01:35:41 INFO - 'sps_profile': False, 01:35:41 INFO - 'sps_profile_interval': 0, 01:35:41 INFO - 'suite': 'dromaeojs-e10s', 01:35:41 INFO - 'system_bits': '32', 01:35:41 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:35:41 INFO - 'title': 't-yosemite-r7-0252', 01:35:41 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:35:41 INFO - 'use_talos_json': True, 01:35:41 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:35:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:35:41 INFO - 'work_dir': 'build'} 01:35:41 INFO - [mozharness: 2017-01-25 09:35:41.180718Z] Running clobber step. 01:35:41 INFO - Running pre-action listener: _resource_record_pre_action 01:35:41 INFO - Running main action method: clobber 01:35:41 INFO - rmtree: /builds/slave/test/build 01:35:41 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:35:42 INFO - Running post-action listener: _resource_record_post_action 01:35:42 INFO - [mozharness: 2017-01-25 09:35:42.815587Z] Finished clobber step (success) 01:35:42 INFO - [mozharness: 2017-01-25 09:35:42.815841Z] Running read-buildbot-config step. 01:35:42 INFO - Running pre-action listener: _resource_record_pre_action 01:35:42 INFO - Running main action method: read_buildbot_config 01:35:42 INFO - Using buildbot properties: 01:35:42 INFO - { 01:35:42 INFO - "project": "", 01:35:42 INFO - "product": "firefox", 01:35:42 INFO - "script_repo_revision": "production", 01:35:42 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 01:35:42 INFO - "repository": "", 01:35:42 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 01:35:42 INFO - "buildid": "20170125004018", 01:35:42 INFO - "stage_platform": "macosx64", 01:35:42 INFO - "basedir": "/builds/slave/test", 01:35:42 INFO - "nightly_build": "True", 01:35:42 INFO - "buildnumber": 83, 01:35:42 INFO - "slavename": "t-yosemite-r7-0252", 01:35:42 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 01:35:42 INFO - "platform": "yosemite_r7", 01:35:42 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 01:35:42 INFO - "branch": "mozilla-aurora", 01:35:42 INFO - "repo_path": "releases/mozilla-aurora", 01:35:42 INFO - "revision": "48f7caa80dfc6cafc778269b12e9e69379111c56", 01:35:42 INFO - "pgo_build": "False", 01:35:42 INFO - "builduid": "8e9033a887bc492fb45f7879d8601400", 01:35:42 INFO - "slavebuilddir": "test" 01:35:42 INFO - } 01:35:42 INFO - Found installer url https://queue.taskcluster.net/v1/task/FTt4EiIhRjKSVdgkr4R-vw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg. 01:35:42 INFO - Running post-action listener: _resource_record_post_action 01:35:42 INFO - [mozharness: 2017-01-25 09:35:42.819431Z] Finished read-buildbot-config step (success) 01:35:42 INFO - [mozharness: 2017-01-25 09:35:42.819561Z] Running download-and-extract step. 01:35:42 INFO - Running pre-action listener: _resource_record_pre_action 01:35:42 INFO - Running main action method: download_and_extract 01:35:42 INFO - mkdir: /builds/slave/test/build/tests 01:35:42 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')]} 01:35:42 INFO - trying https://queue.taskcluster.net/v1/task/FTt4EiIhRjKSVdgkr4R-vw/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json 01:35:42 INFO - Downloading https://queue.taskcluster.net/v1/task/FTt4EiIhRjKSVdgkr4R-vw/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 01:35:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FTt4EiIhRjKSVdgkr4R-vw/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json'}, attempt #1 01:35:43 INFO - Downloaded 1199 bytes. 01:35:43 INFO - Reading from file /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 01:35:43 INFO - Using the following test package requirements: 01:35:43 INFO - {u'common': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 01:35:43 INFO - u'cppunittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 01:35:43 INFO - u'firefox-53.0a2.en-US.mac.cppunittest.tests.zip'], 01:35:43 INFO - u'gtest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 01:35:43 INFO - u'firefox-53.0a2.en-US.mac.gtest.tests.zip'], 01:35:43 INFO - u'jittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 01:35:43 INFO - u'jsshell-mac.zip'], 01:35:43 INFO - u'mochitest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 01:35:43 INFO - u'firefox-53.0a2.en-US.mac.mochitest.tests.zip'], 01:35:43 INFO - u'mozbase': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 01:35:43 INFO - u'reftest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 01:35:43 INFO - u'firefox-53.0a2.en-US.mac.reftest.tests.zip'], 01:35:43 INFO - u'talos': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 01:35:43 INFO - u'firefox-53.0a2.en-US.mac.talos.tests.zip'], 01:35:43 INFO - u'web-platform': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 01:35:43 INFO - u'firefox-53.0a2.en-US.mac.web-platform.tests.zip'], 01:35:43 INFO - u'xpcshell': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 01:35:43 INFO - u'firefox-53.0a2.en-US.mac.xpcshell.tests.zip']} 01:35:43 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip'] for test suite category: common 01:35:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FTt4EiIhRjKSVdgkr4R-vw/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 01:35:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FTt4EiIhRjKSVdgkr4R-vw/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 01:35:43 INFO - Fetch https://queue.taskcluster.net/v1/task/FTt4EiIhRjKSVdgkr4R-vw/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 01:35:44 INFO - Expected file size: 16349498 01:35:44 INFO - Obtained file size: 16349498 01:35:51 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip', u'firefox-53.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 01:35:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FTt4EiIhRjKSVdgkr4R-vw/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 01:35:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FTt4EiIhRjKSVdgkr4R-vw/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 01:35:51 INFO - Fetch https://queue.taskcluster.net/v1/task/FTt4EiIhRjKSVdgkr4R-vw/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 01:35:52 INFO - Expected file size: 16349498 01:35:52 INFO - Obtained file size: 16349498 01:35:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FTt4EiIhRjKSVdgkr4R-vw/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip 01:35:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FTt4EiIhRjKSVdgkr4R-vw/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip'}, attempt #1 01:35:58 INFO - Fetch https://queue.taskcluster.net/v1/task/FTt4EiIhRjKSVdgkr4R-vw/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip into memory 01:35:59 INFO - Expected file size: 14403485 01:35:59 INFO - Obtained file size: 14403485 01:35:59 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')]} 01:35:59 INFO - trying https://queue.taskcluster.net/v1/task/FTt4EiIhRjKSVdgkr4R-vw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 01:35:59 INFO - Downloading https://queue.taskcluster.net/v1/task/FTt4EiIhRjKSVdgkr4R-vw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg 01:35:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FTt4EiIhRjKSVdgkr4R-vw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg'}, attempt #1 01:36:02 INFO - Downloaded 73912687 bytes. 01:36:02 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FTt4EiIhRjKSVdgkr4R-vw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 01:36:02 INFO - mkdir: /builds/slave/test/properties 01:36:02 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:36:02 INFO - Writing to file /builds/slave/test/properties/build_url 01:36:02 INFO - Contents: 01:36:02 INFO - build_url:https://queue.taskcluster.net/v1/task/FTt4EiIhRjKSVdgkr4R-vw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 01:36:02 INFO - Running post-action listener: _resource_record_post_action 01:36:02 INFO - Running post-action listener: set_extra_try_arguments 01:36:02 INFO - [mozharness: 2017-01-25 09:36:02.696706Z] Finished download-and-extract step (success) 01:36:02 INFO - [mozharness: 2017-01-25 09:36:02.696832Z] Running populate-webroot step. 01:36:02 INFO - Running pre-action listener: _resource_record_pre_action 01:36:02 INFO - Running main action method: populate_webroot 01:36:02 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:36:02 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:36:02 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:36:02 INFO - '--tppagecycles', 01:36:02 INFO - '7'], 01:36:02 INFO - 'tests': ['tsvgm']}}, 01:36:02 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 01:36:02 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:36:02 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:36:02 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:36:02 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:36:02 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:36:02 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 01:36:02 INFO - 'talos_options': ['--disable-e10s'], 01:36:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:36:02 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 01:36:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:36:02 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 01:36:02 INFO - 'talos_options': ['--disable-e10s'], 01:36:02 INFO - 'tests': ['damp', 'tps']}, 01:36:02 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 01:36:02 INFO - 'tests': ['damp', 'tps']}, 01:36:02 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:36:02 INFO - 'tests': ['dromaeo_dom']}, 01:36:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:36:02 INFO - 'g4': {'talos_options': ['--disable-e10s'], 01:36:02 INFO - 'tests': ['basic_compositor_video']}, 01:36:02 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 01:36:02 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:36:02 INFO - 'tests': ['a11yr', 01:36:02 INFO - 'ts_paint', 01:36:02 INFO - 'tpaint', 01:36:02 INFO - 'sessionrestore', 01:36:02 INFO - 'sessionrestore_no_auto_restore', 01:36:02 INFO - 'tabpaint']}, 01:36:02 INFO - 'other-e10s': {'tests': ['a11yr', 01:36:02 INFO - 'ts_paint', 01:36:02 INFO - 'tpaint', 01:36:02 INFO - 'sessionrestore', 01:36:02 INFO - 'sessionrestore_no_auto_restore', 01:36:02 INFO - 'tabpaint']}, 01:36:02 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:36:02 INFO - 'ts_paint', 01:36:02 INFO - 'tpaint', 01:36:02 INFO - 'sessionrestore', 01:36:02 INFO - 'sessionrestore_no_auto_restore', 01:36:02 INFO - 'tabpaint']}, 01:36:02 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:36:02 INFO - 'ts_paint', 01:36:02 INFO - 'tpaint', 01:36:02 INFO - 'sessionrestore', 01:36:02 INFO - 'sessionrestore_no_auto_restore', 01:36:02 INFO - 'tabpaint']}, 01:36:02 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:36:02 INFO - 'tests': ['a11yr', 01:36:02 INFO - 'ts_paint', 01:36:02 INFO - 'tpaint', 01:36:02 INFO - 'sessionrestore', 01:36:02 INFO - 'sessionrestore_no_auto_restore', 01:36:02 INFO - 'tabpaint']}, 01:36:02 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:36:02 INFO - 'tests': ['a11yr', 01:36:02 INFO - 'ts_paint', 01:36:02 INFO - 'tpaint', 01:36:02 INFO - 'sessionrestore', 01:36:02 INFO - 'sessionrestore_no_auto_restore', 01:36:02 INFO - 'tabpaint']}, 01:36:02 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:36:02 INFO - 'tests': ['tsvgx', 01:36:02 INFO - 'tsvgr_opacity', 01:36:02 INFO - 'tart', 01:36:02 INFO - 'tscrollx', 01:36:02 INFO - 'cart', 01:36:02 INFO - 'tsvg_static']}, 01:36:02 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:36:02 INFO - 'tsvgr_opacity', 01:36:02 INFO - 'tart', 01:36:02 INFO - 'tscrollx', 01:36:02 INFO - 'cart', 01:36:02 INFO - 'tsvg_static']}, 01:36:02 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 01:36:02 INFO - 'talos_options': ['--disable-e10s'], 01:36:02 INFO - 'tests': ['tp5o']}, 01:36:02 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 01:36:02 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 01:36:02 INFO - 'talos_options': ['--disable-e10s', 01:36:02 INFO - '--xperf_path', 01:36:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:36:02 INFO - 'tests': ['tp5n']}, 01:36:02 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 01:36:02 INFO - 'talos_options': ['--xperf_path', 01:36:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:36:02 INFO - 'tests': ['tp5n']}}, 01:36:02 INFO - 'talos.zip': {'path': '', 01:36:02 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:36:02 INFO - Running post-action listener: _resource_record_post_action 01:36:02 INFO - [mozharness: 2017-01-25 09:36:02.715329Z] Finished populate-webroot step (success) 01:36:02 INFO - [mozharness: 2017-01-25 09:36:02.715459Z] Running create-virtualenv step. 01:36:02 INFO - Running pre-action listener: _resource_record_pre_action 01:36:02 INFO - Running main action method: create_virtualenv 01:36:02 INFO - Creating virtualenv /builds/slave/test/build/venv 01:36:02 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 01:36:02 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:36:02 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:36:02 INFO - Using real prefix '/tools/python27' 01:36:02 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:36:03 INFO - Installing distribute.............................................................................................................................................................................................done. 01:36:06 INFO - Installing pip.................done. 01:36:06 INFO - Return code: 0 01:36:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 01:36:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 01:36:06 INFO - Reading from file tmpfile_stdout 01:36:06 INFO - Output received: 01:36:06 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) 01:36:06 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:36:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:36:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:36:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10babab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10babd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff51a673ee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b898d30>, '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 0x10b7bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b88dbe8>, '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.EIhn4L4aOD/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.QJagvaKtwu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/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 01:36:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 01:36:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 01:36:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EIhn4L4aOD/Render', 01:36:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:36:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:36:06 INFO - 'HOME': '/Users/cltbld', 01:36:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:36:06 INFO - 'LOGNAME': 'cltbld', 01:36:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:36:06 INFO - 'MOZ_NO_REMOTE': '1', 01:36:06 INFO - 'NO_EM_RESTART': '1', 01:36:06 INFO - 'PAGER': '/bin/cat', 01:36:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:36:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:36:06 INFO - 'PWD': '/builds/slave/test', 01:36:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:36:06 INFO - 'SHELL': '/bin/bash', 01:36:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QJagvaKtwu/Listeners', 01:36:06 INFO - 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', 01:36:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:36:06 INFO - 'USER': 'cltbld', 01:36:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:36:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:36:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:36:06 INFO - 'XPC_FLAGS': '0x0', 01:36:06 INFO - 'XPC_SERVICE_NAME': '0', 01:36:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:36:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:36:06 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 01:36:06 INFO - Cleaning up... 01:36:06 INFO - Return code: 0 01:36:06 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 01:36:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:36:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:36:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10babab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10babd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff51a673ee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b898d30>, '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 0x10b7bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b88dbe8>, '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.EIhn4L4aOD/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.QJagvaKtwu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/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 01:36:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 01:36:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 01:36:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EIhn4L4aOD/Render', 01:36:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:36:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:36:06 INFO - 'HOME': '/Users/cltbld', 01:36:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:36:06 INFO - 'LOGNAME': 'cltbld', 01:36:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:36:06 INFO - 'MOZ_NO_REMOTE': '1', 01:36:06 INFO - 'NO_EM_RESTART': '1', 01:36:06 INFO - 'PAGER': '/bin/cat', 01:36:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:36:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:36:06 INFO - 'PWD': '/builds/slave/test', 01:36:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:36:06 INFO - 'SHELL': '/bin/bash', 01:36:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QJagvaKtwu/Listeners', 01:36:06 INFO - 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', 01:36:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:36:06 INFO - 'USER': 'cltbld', 01:36:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:36:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:36:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:36:06 INFO - 'XPC_FLAGS': '0x0', 01:36:06 INFO - 'XPC_SERVICE_NAME': '0', 01:36:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:36:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:36:07 INFO - Downloading/unpacking psutil>=3.1.1 01:36:07 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 01:36:07 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 01:36:07 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 01:36:07 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 01:36:07 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 01:36:07 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 01:36:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:36:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:36:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:36:10 INFO - Installing collected packages: psutil 01:36:10 INFO - Running setup.py install for psutil 01:36:10 INFO - building 'psutil._psutil_osx' extension 01:36:10 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 01:36:11 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 01:36:11 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 01:36:11 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 01:36:11 INFO - building 'psutil._psutil_posix' extension 01:36:11 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 01:36:11 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 01:36:11 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 01:36:11 INFO - ^ 01:36:11 INFO - 1 warning generated. 01:36:11 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 01:36:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:36:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:36:11 INFO - Successfully installed psutil 01:36:11 INFO - Cleaning up... 01:36:11 INFO - Return code: 0 01:36:11 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 01:36:11 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')]} 01:36:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:36:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36:11 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')]} 01:36:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:36:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36:11 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 0x10babab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10babd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff51a673ee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b898d30>, '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 0x10b7bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b88dbe8>, '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.EIhn4L4aOD/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.QJagvaKtwu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/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 01:36:11 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 01:36:11 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 01:36:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EIhn4L4aOD/Render', 01:36:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:36:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:36:11 INFO - 'HOME': '/Users/cltbld', 01:36:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:36:11 INFO - 'LOGNAME': 'cltbld', 01:36:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:36:11 INFO - 'MOZ_NO_REMOTE': '1', 01:36:11 INFO - 'NO_EM_RESTART': '1', 01:36:11 INFO - 'PAGER': '/bin/cat', 01:36:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:36:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:36:11 INFO - 'PWD': '/builds/slave/test', 01:36:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:36:11 INFO - 'SHELL': '/bin/bash', 01:36:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QJagvaKtwu/Listeners', 01:36:11 INFO - 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', 01:36:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:36:11 INFO - 'USER': 'cltbld', 01:36:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:36:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:36:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:36:11 INFO - 'XPC_FLAGS': '0x0', 01:36:11 INFO - 'XPC_SERVICE_NAME': '0', 01:36:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:36:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:36:11 INFO - Downloading/unpacking mozsystemmonitor==0.3 01:36:11 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 01:36:11 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 01:36:11 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 01:36:11 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 01:36:11 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 01:36:11 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 01:36:14 INFO - Downloading mozsystemmonitor-0.3.tar.gz 01:36:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:36:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 01:36:14 INFO - Installing collected packages: mozsystemmonitor 01:36:14 INFO - Running setup.py install for mozsystemmonitor 01:36:14 INFO - Successfully installed mozsystemmonitor 01:36:14 INFO - Cleaning up... 01:36:14 INFO - Return code: 0 01:36:14 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 01:36:14 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')]} 01:36:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:36:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36:14 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')]} 01:36:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:36:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36:14 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 0x10babab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10babd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff51a673ee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b898d30>, '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 0x10b7bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b88dbe8>, '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.EIhn4L4aOD/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.QJagvaKtwu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/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 01:36:14 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 01:36:14 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 01:36:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EIhn4L4aOD/Render', 01:36:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:36:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:36:14 INFO - 'HOME': '/Users/cltbld', 01:36:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:36:14 INFO - 'LOGNAME': 'cltbld', 01:36:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:36:14 INFO - 'MOZ_NO_REMOTE': '1', 01:36:14 INFO - 'NO_EM_RESTART': '1', 01:36:14 INFO - 'PAGER': '/bin/cat', 01:36:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:36:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:36:14 INFO - 'PWD': '/builds/slave/test', 01:36:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:36:14 INFO - 'SHELL': '/bin/bash', 01:36:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QJagvaKtwu/Listeners', 01:36:14 INFO - 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', 01:36:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:36:14 INFO - 'USER': 'cltbld', 01:36:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:36:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:36:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:36:14 INFO - 'XPC_FLAGS': '0x0', 01:36:14 INFO - 'XPC_SERVICE_NAME': '0', 01:36:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:36:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:36:15 INFO - Downloading/unpacking jsonschema==2.5.1 01:36:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:36:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:36:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:36:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:36:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:36:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:36:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:36:17 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 01:36:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:36:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:36:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:36:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:36:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:36:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:36:17 INFO - Downloading functools32-3.2.3-2.tar.gz 01:36:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:36:18 INFO - warning: no files found matching '*.txt' 01:36:18 INFO - no previously-included directories found matching 'build' 01:36:18 INFO - no previously-included directories found matching 'dist' 01:36:18 INFO - no previously-included directories found matching '.git*' 01:36:18 INFO - Installing collected packages: jsonschema, functools32 01:36:18 INFO - Running setup.py install for functools32 01:36:18 INFO - warning: no files found matching '*.txt' 01:36:18 INFO - no previously-included directories found matching 'build' 01:36:18 INFO - no previously-included directories found matching 'dist' 01:36:18 INFO - no previously-included directories found matching '.git*' 01:36:18 INFO - Successfully installed jsonschema functools32 01:36:18 INFO - Cleaning up... 01:36:18 INFO - Return code: 0 01:36:18 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 01:36: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')]} 01:36:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:36:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36: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')]} 01:36:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:36:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36:18 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 0x10babab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10babd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff51a673ee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b898d30>, '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 0x10b7bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b88dbe8>, '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.EIhn4L4aOD/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.QJagvaKtwu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/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 01:36:18 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 01:36:18 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 01:36:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EIhn4L4aOD/Render', 01:36:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:36:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:36:18 INFO - 'HOME': '/Users/cltbld', 01:36:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:36:18 INFO - 'LOGNAME': 'cltbld', 01:36:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:36:18 INFO - 'MOZ_NO_REMOTE': '1', 01:36:18 INFO - 'NO_EM_RESTART': '1', 01:36:18 INFO - 'PAGER': '/bin/cat', 01:36:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:36:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:36:18 INFO - 'PWD': '/builds/slave/test', 01:36:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:36:18 INFO - 'SHELL': '/bin/bash', 01:36:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QJagvaKtwu/Listeners', 01:36:18 INFO - 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', 01:36:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:36:18 INFO - 'USER': 'cltbld', 01:36:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:36:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:36:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:36:18 INFO - 'XPC_FLAGS': '0x0', 01:36:18 INFO - 'XPC_SERVICE_NAME': '0', 01:36:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:36:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:36:18 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 01:36:18 INFO - Cleaning up... 01:36:18 INFO - Return code: 0 01:36:18 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:36: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')]} 01:36:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:36:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36: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')]} 01:36:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:36:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36:18 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 0x10babab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10babd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff51a673ee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b898d30>, '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 0x10b7bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b88dbe8>, '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.EIhn4L4aOD/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.QJagvaKtwu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/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 01:36:18 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 01:36:18 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 01:36:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EIhn4L4aOD/Render', 01:36:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:36:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:36:18 INFO - 'HOME': '/Users/cltbld', 01:36:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:36:18 INFO - 'LOGNAME': 'cltbld', 01:36:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:36:18 INFO - 'MOZ_NO_REMOTE': '1', 01:36:18 INFO - 'NO_EM_RESTART': '1', 01:36:18 INFO - 'PAGER': '/bin/cat', 01:36:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:36:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:36:18 INFO - 'PWD': '/builds/slave/test', 01:36:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:36:18 INFO - 'SHELL': '/bin/bash', 01:36:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QJagvaKtwu/Listeners', 01:36:18 INFO - 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', 01:36:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:36:18 INFO - 'USER': 'cltbld', 01:36:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:36:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:36:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:36:18 INFO - 'XPC_FLAGS': '0x0', 01:36:18 INFO - 'XPC_SERVICE_NAME': '0', 01:36:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:36:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:36:18 INFO - Downloading/unpacking blobuploader==1.2.4 01:36:18 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 01:36:18 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 01:36:18 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 01:36:18 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 01:36:18 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 01:36:18 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 01:36:21 INFO - Downloading blobuploader-1.2.4.tar.gz 01:36:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:36:21 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:36:21 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 01:36:21 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 01:36:21 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 01:36:21 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 01:36:21 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 01:36:21 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 01:36:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:36:22 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:36:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:36:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:36:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:36:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:36:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:36:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:36:22 INFO - Downloading docopt-0.6.1.tar.gz 01:36:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:36:22 INFO - Installing collected packages: blobuploader, requests, docopt 01:36:22 INFO - Running setup.py install for blobuploader 01:36:22 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:36:22 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:36:22 INFO - Running setup.py install for requests 01:36:23 INFO - Running setup.py install for docopt 01:36:23 INFO - Successfully installed blobuploader requests docopt 01:36:23 INFO - Cleaning up... 01:36:23 INFO - Return code: 0 01:36:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:36:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:36:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:36:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36:24 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 0x10babab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10babd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff51a673ee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b898d30>, '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 0x10b7bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b88dbe8>, '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.EIhn4L4aOD/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.QJagvaKtwu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/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 01:36:24 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 01:36:24 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 01:36:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EIhn4L4aOD/Render', 01:36:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:36:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:36:24 INFO - 'HOME': '/Users/cltbld', 01:36:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:36:24 INFO - 'LOGNAME': 'cltbld', 01:36:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:36:24 INFO - 'MOZ_NO_REMOTE': '1', 01:36:24 INFO - 'NO_EM_RESTART': '1', 01:36:24 INFO - 'PAGER': '/bin/cat', 01:36:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:36:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:36:24 INFO - 'PWD': '/builds/slave/test', 01:36:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:36:24 INFO - 'SHELL': '/bin/bash', 01:36:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QJagvaKtwu/Listeners', 01:36:24 INFO - 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', 01:36:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:36:24 INFO - 'USER': 'cltbld', 01:36:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:36:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:36:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:36:24 INFO - 'XPC_FLAGS': '0x0', 01:36:24 INFO - 'XPC_SERVICE_NAME': '0', 01:36:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:36:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:36:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:36:24 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-RyLZMY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:36:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:36:24 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-KEXiH1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:36:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:36:25 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-NWr_YQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:36:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:36:25 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-GgJQNT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:36:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:36:25 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-NZy36w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:36:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:36:25 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-eSHwLf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:36:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:36:25 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-a8Vq3G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:36:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:36:25 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-hy_jTu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:36:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:36:25 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-BO8gvc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:36:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:36:25 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-Q5UVod-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:36:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:36:25 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-4qk59O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:36:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:36:26 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-dsTTEM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:36:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:36:26 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-p85PpK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:36:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:36:26 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-lzXX74-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:36:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:36:26 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-T5QZpd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:36:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:36:26 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-m6DUio-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:36:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:36:26 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-9pCIM9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:36:26 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:36:26 INFO - Running setup.py install for manifestparser 01:36:26 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:36:26 INFO - Running setup.py install for mozcrash 01:36:26 INFO - Running setup.py install for mozdebug 01:36:27 INFO - Running setup.py install for mozdevice 01:36:27 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:36:27 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:36:27 INFO - Running setup.py install for mozfile 01:36:27 INFO - Running setup.py install for mozhttpd 01:36:27 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:36:27 INFO - Running setup.py install for mozinfo 01:36:27 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:36:27 INFO - Running setup.py install for mozInstall 01:36:27 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:36:27 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:36:27 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:36:27 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:36:27 INFO - Running setup.py install for mozleak 01:36:27 INFO - Running setup.py install for mozlog 01:36:28 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:36:28 INFO - Running setup.py install for moznetwork 01:36:28 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:36:28 INFO - Running setup.py install for mozprocess 01:36:28 INFO - Running setup.py install for mozprofile 01:36:28 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:36:28 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:36:28 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:36:28 INFO - Running setup.py install for mozrunner 01:36:28 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:36:28 INFO - Running setup.py install for mozscreenshot 01:36:28 INFO - Running setup.py install for moztest 01:36:29 INFO - Running setup.py install for mozversion 01:36:29 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:36:29 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:36:29 INFO - Cleaning up... 01:36:29 INFO - Return code: 0 01:36:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:36:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:36:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:36:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36:29 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 0x10babab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10babd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff51a673ee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b898d30>, '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 0x10b7bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b88dbe8>, '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.EIhn4L4aOD/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.QJagvaKtwu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/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 01:36:29 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 01:36:29 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 01:36:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EIhn4L4aOD/Render', 01:36:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:36:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:36:29 INFO - 'HOME': '/Users/cltbld', 01:36:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:36:29 INFO - 'LOGNAME': 'cltbld', 01:36:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:36:29 INFO - 'MOZ_NO_REMOTE': '1', 01:36:29 INFO - 'NO_EM_RESTART': '1', 01:36:29 INFO - 'PAGER': '/bin/cat', 01:36:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:36:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:36:29 INFO - 'PWD': '/builds/slave/test', 01:36:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:36:29 INFO - 'SHELL': '/bin/bash', 01:36:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QJagvaKtwu/Listeners', 01:36:29 INFO - 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', 01:36:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:36:29 INFO - 'USER': 'cltbld', 01:36:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:36:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:36:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:36:29 INFO - 'XPC_FLAGS': '0x0', 01:36:29 INFO - 'XPC_SERVICE_NAME': '0', 01:36:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:36:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:36:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:36:29 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-3FKsJq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:36:30 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)) 01:36:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:36:30 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-E1N4a9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:36:30 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)) 01:36:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:36:30 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-Id4waB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:36:30 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)) 01:36:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:36:30 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-RLTpJL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:36:30 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)) 01:36:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:36:30 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-qcpFKe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:36:30 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)) 01:36:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:36:30 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-gOe5b8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:36:30 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)) 01:36:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:36:30 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-qcaXL8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:36:30 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)) 01:36:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:36:30 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-isqixz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:36:30 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)) 01:36:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:36:30 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-idi7w8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:36:30 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)) 01:36:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:36:30 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-8GLwzE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:36:31 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)) 01:36:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:36:31 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-P_UDhq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:36:31 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)) 01:36:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:36:31 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-LaaS1I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:36:31 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)) 01:36:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:36:31 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-iRLgJr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:36:31 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)) 01:36:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:36:31 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-O7JIta-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:36:31 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)) 01:36:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:36:31 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-ACeWNA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:36:31 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)) 01:36:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:36:31 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-VOawk_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:36:31 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)) 01:36:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:36:31 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-lqhs5a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:36:31 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)) 01:36:31 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)) 01:36:31 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)) 01:36:31 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)) 01:36:31 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)) 01:36:31 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)) 01:36:31 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:36:31 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 01:36:31 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 01:36:31 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 01:36:31 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 01:36:31 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 01:36:31 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 01:36:34 INFO - Downloading blessings-1.6.tar.gz 01:36:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:36:34 INFO - Installing collected packages: blessings 01:36:34 INFO - Running setup.py install for blessings 01:36:35 INFO - Successfully installed blessings 01:36:35 INFO - Cleaning up... 01:36:35 INFO - Return code: 0 01:36:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:36:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:36:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:36:35 INFO - Reading from file tmpfile_stdout 01:36:35 INFO - Current package versions: 01:36:35 INFO - blessings == 1.6 01:36:35 INFO - blobuploader == 1.2.4 01:36:35 INFO - docopt == 0.6.1 01:36:35 INFO - functools32 == 3.2.3-2 01:36:35 INFO - jsonschema == 2.5.1 01:36:35 INFO - manifestparser == 1.1 01:36:35 INFO - mozInstall == 1.12 01:36:35 INFO - mozcrash == 1.0 01:36:35 INFO - mozdebug == 0.1 01:36:35 INFO - mozdevice == 0.49 01:36:35 INFO - mozfile == 1.2 01:36:35 INFO - mozhttpd == 0.7 01:36:35 INFO - mozinfo == 0.9 01:36:35 INFO - mozleak == 0.1 01:36:35 INFO - mozlog == 3.4 01:36:35 INFO - moznetwork == 0.27 01:36:35 INFO - mozprocess == 0.24 01:36:35 INFO - mozprofile == 0.28 01:36:35 INFO - mozrunner == 6.13 01:36:35 INFO - mozscreenshot == 0.1 01:36:35 INFO - mozsystemmonitor == 0.3 01:36:35 INFO - moztest == 0.8 01:36:35 INFO - mozversion == 1.4 01:36:35 INFO - psutil == 3.1.1 01:36:35 INFO - requests == 1.2.3 01:36:35 INFO - wsgiref == 0.1.2 01:36:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:36: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')]} 01:36:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:36:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36: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')]} 01:36:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:36:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36:35 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 0x10babab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10babd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff51a673ee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b898d30>, '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 0x10b7bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b88dbe8>, '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.EIhn4L4aOD/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.QJagvaKtwu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/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 01:36:35 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 01:36:35 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 01:36:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EIhn4L4aOD/Render', 01:36:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:36:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:36:35 INFO - 'HOME': '/Users/cltbld', 01:36:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:36:35 INFO - 'LOGNAME': 'cltbld', 01:36:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:36:35 INFO - 'MOZ_NO_REMOTE': '1', 01:36:35 INFO - 'NO_EM_RESTART': '1', 01:36:35 INFO - 'PAGER': '/bin/cat', 01:36:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:36:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:36:35 INFO - 'PWD': '/builds/slave/test', 01:36:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:36:35 INFO - 'SHELL': '/bin/bash', 01:36:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QJagvaKtwu/Listeners', 01:36:35 INFO - 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', 01:36:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:36:35 INFO - 'USER': 'cltbld', 01:36:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:36:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:36:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:36:35 INFO - 'XPC_FLAGS': '0x0', 01:36:35 INFO - 'XPC_SERVICE_NAME': '0', 01:36:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:36:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:36:35 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)) 01:36:35 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)) 01:36:35 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)) 01:36:35 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)) 01:36:35 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)) 01:36:35 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)) 01:36:35 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)) 01:36:35 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)) 01:36:35 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)) 01:36:35 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)) 01:36:35 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)) 01:36:35 INFO - Cleaning up... 01:36:35 INFO - Return code: 0 01:36:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:36: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')]} 01:36:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:36:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36: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')]} 01:36:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:36:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36: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']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10babab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10babd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff51a673ee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b898d30>, '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 0x10b7bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b88dbe8>, '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.EIhn4L4aOD/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.QJagvaKtwu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/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 01:36: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'] in /builds/slave/test/build 01:36: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 01:36:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EIhn4L4aOD/Render', 01:36:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:36:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:36:35 INFO - 'HOME': '/Users/cltbld', 01:36:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:36:35 INFO - 'LOGNAME': 'cltbld', 01:36:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:36:35 INFO - 'MOZ_NO_REMOTE': '1', 01:36:35 INFO - 'NO_EM_RESTART': '1', 01:36:35 INFO - 'PAGER': '/bin/cat', 01:36:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:36:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:36:35 INFO - 'PWD': '/builds/slave/test', 01:36:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:36:35 INFO - 'SHELL': '/bin/bash', 01:36:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QJagvaKtwu/Listeners', 01:36:35 INFO - 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', 01:36:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:36:35 INFO - 'USER': 'cltbld', 01:36:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:36:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:36:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:36:35 INFO - 'XPC_FLAGS': '0x0', 01:36:35 INFO - 'XPC_SERVICE_NAME': '0', 01:36:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:36:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:36:35 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 01:36:35 INFO - Cleaning up... 01:36:35 INFO - Return code: 0 01:36:35 INFO - Running post-action listener: _resource_record_post_action 01:36:35 INFO - Running post-action listener: _start_resource_monitoring 01:36:35 INFO - Starting resource monitoring. 01:36:35 INFO - [mozharness: 2017-01-25 09:36:35.974752Z] Finished create-virtualenv step (success) 01:36:35 INFO - [mozharness: 2017-01-25 09:36:35.975817Z] Running install step. 01:36:35 INFO - Running pre-action listener: _resource_record_pre_action 01:36:35 INFO - Running main action method: install 01:36:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:36:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:36:36 INFO - Reading from file tmpfile_stdout 01:36:36 INFO - Detecting whether we're running mozinstall >=1.0... 01:36:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:36:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:36:36 INFO - Reading from file tmpfile_stdout 01:36:36 INFO - Output received: 01:36:36 INFO - Usage: mozinstall [options] installer 01:36:36 INFO - Options: 01:36:36 INFO - -h, --help show this help message and exit 01:36:36 INFO - -d DEST, --destination=DEST 01:36:36 INFO - Directory to install application into. [default: 01:36:36 INFO - "/builds/slave/test"] 01:36:36 INFO - --app=APP Application being installed. [default: firefox] 01:36:36 INFO - mkdir: /builds/slave/test/build/application 01:36:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 01:36:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 01:36:56 INFO - Reading from file tmpfile_stdout 01:36:56 INFO - Output received: 01:36:56 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 01:36:56 INFO - Running post-action listener: _resource_record_post_action 01:36:56 INFO - [mozharness: 2017-01-25 09:36:56.456687Z] Finished install step (success) 01:36:56 INFO - [mozharness: 2017-01-25 09:36:56.456835Z] Running run-tests step. 01:36:56 INFO - Running pre-action listener: _resource_record_pre_action 01:36:56 INFO - Running main action method: run_tests 01:36:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:36:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:36:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:36:56 INFO - 2017-01-25 01:36:56.471 screenresolution[2229:19883] starting screenresolution argv=screenresolution get 01:36:56 INFO - 2017-01-25 01:36:56.474 screenresolution[2229:19883] Display 0: 1600x1200x32@60 01:36:56 INFO - 2017-01-25 01:36:56.480 screenresolution[2230:19887] starting screenresolution argv=screenresolution list 01:36:56 INFO - Available Modes on Display 0 01:36:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:36:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:36:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:36:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:36:56 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:36:56 INFO - Intel Iris: 01:36:56 INFO - Chipset Model: Intel Iris 01:36:56 INFO - Type: GPU 01:36:56 INFO - Bus: Built-In 01:36:56 INFO - VRAM (Dynamic, Max): 1536 MB 01:36:56 INFO - Vendor: Intel (0x8086) 01:36:56 INFO - Device ID: 0x0a2e 01:36:56 INFO - Revision ID: 0x0009 01:36:56 INFO - Displays: 01:36:56 INFO - Display: 01:36:56 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:36:56 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:36:56 INFO - Display Serial Number: 1600x1200 60 01:36:56 INFO - Main Display: Yes 01:36:56 INFO - Mirror: Off 01:36:56 INFO - Online: Yes 01:36:56 INFO - Rotation: Supported 01:36:56 INFO - Return code: 0 01:36:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:36:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:36:56 INFO - Python 2.7.3 01:36:56 INFO - Return code: 0 01:36:56 INFO - grabbing minidump binary from tooltool 01:36:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:56 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 0x10b898d30>, '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 0x10b7bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b88dbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:36:56 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 01:36:56 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 01:36:56 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 01:36:56 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:36:56 INFO - Return code: 0 01:36:56 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 01:36:56 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:36:56 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:36:56 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 01:36:56 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:36:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FTt4EiIhRjKSVdgkr4R-vw/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0252', '--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 01:36:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/FTt4EiIhRjKSVdgkr4R-vw/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0252 --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 01:36:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EIhn4L4aOD/Render', 01:36:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:36:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:36:56 INFO - 'HOME': '/Users/cltbld', 01:36:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:36:56 INFO - 'LOGNAME': 'cltbld', 01:36:56 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:36:56 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 01:36:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:36:56 INFO - 'MOZ_NO_REMOTE': '1', 01:36:56 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:36:56 INFO - 'NO_EM_RESTART': '1', 01:36:56 INFO - 'PAGER': '/bin/cat', 01:36:56 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:36:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:36:56 INFO - 'PWD': '/builds/slave/test', 01:36:56 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:36:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:36:56 INFO - 'SHELL': '/bin/bash', 01:36:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QJagvaKtwu/Listeners', 01:36:56 INFO - 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', 01:36:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:36:56 INFO - 'USER': 'cltbld', 01:36:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:36:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:36:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:36:56 INFO - 'XPC_FLAGS': '0x0', 01:36:56 INFO - 'XPC_SERVICE_NAME': '0', 01:36:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:36:56 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FTt4EiIhRjKSVdgkr4R-vw/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0252', '--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 01:36:57 INFO - mozversion application_buildid: 20170125004018 01:36:57 INFO - mozversion application_changeset: 48f7caa80dfc6cafc778269b12e9e69379111c56 01:36:57 INFO - mozversion application_display_name: FirefoxDeveloperEdition 01:36:57 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:36:57 INFO - mozversion application_name: Firefox 01:36:57 INFO - mozversion application_remotingname: firefox-dev 01:36:57 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 01:36:57 INFO - mozversion application_vendor: Mozilla 01:36:57 INFO - mozversion application_version: 53.0a2 01:36:57 INFO - mozversion platform_buildid: 20170125004018 01:36:57 INFO - mozversion platform_changeset: 48f7caa80dfc6cafc778269b12e9e69379111c56 01:36:57 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 01:36:57 INFO - mozversion platform_version: 53.0a2 01:36:57 INFO - using testdate: 1485337017 01:36:57 INFO - actual date: 1485337017 01:36:57 INFO - starting webserver on 'localhost:53556' 01:36:57 INFO - SUITE-START | Running 2 tests 01:36:57 INFO - TEST-START | dromaeo_css 01:36:57 INFO - operating with platform_type : mac_ 01:36:57 INFO - Initialising browser for dromaeo_css test... 01:36:57 INFO - TEST-INFO | started process 2240 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/tmpLv40Mu/profile http://localhost:53556/getInfo.html) 01:37:00 INFO - PROCESS | 2240 | 2017-01-25 01:37:00.121 plugin-container[2243:20058] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e33, name = 'com.apple.tsm.portname' 01:37:00 INFO - PROCESS | 2240 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:37:00 INFO - PROCESS | 2240 | 2017-01-25 01:37:00.122 plugin-container[2243:20058] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x950f, name = 'com.apple.CFPasteboardClient' 01:37:00 INFO - PROCESS | 2240 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:37:00 INFO - PROCESS | 2240 | 2017-01-25 01:37:00.122 plugin-container[2243:20058] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 01:37:00 INFO - PROCESS | 2240 | __metrics Screen width/height:1600/1200 01:37:00 INFO - PROCESS | 2240 | colorDepth:24 01:37:00 INFO - PROCESS | 2240 | Browser inner width/height: 1024/689 01:37:00 INFO - PROCESS | 2240 | __metrics 01:37:00 ERROR - PROCESS | 2240 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3280: TypeError: this.tabs is undefined 01:37:00 INFO - PROCESS | 2240 | JavaScript error: resource://app/modules/webrtcUI.jsm, line 66: NS_ERROR_XPC_NOT_ENOUGH_ARGS: Not enough arguments [nsIMessageListenerManager.removeMessageListener] 01:37:01 INFO - TEST-INFO | 2240: exit 0 01:37:01 INFO - Browser initialized. 01:37:01 INFO - Running cycle 1/1 for dromaeo_css test... 01:37:01 INFO - TEST-INFO | started process 2244 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/tmpLv40Mu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:37:03 INFO - PROCESS | 2244 | 2017-01-25 01:37:03.652 plugin-container[2245:20192] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4643, name = 'com.apple.tsm.portname' 01:37:03 INFO - PROCESS | 2244 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:37:03 INFO - PROCESS | 2244 | 2017-01-25 01:37:03.652 plugin-container[2245:20192] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4717, name = 'com.apple.CFPasteboardClient' 01:37:03 INFO - PROCESS | 2244 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:37:03 INFO - PROCESS | 2244 | 2017-01-25 01:37:03.652 plugin-container[2245:20192] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 01:37:13 INFO - PROCESS | 2244 | RSS: Main: 219103232 01:37:13 INFO - PROCESS | 2244 | 01:40:16 INFO - PROCESS | 2244 | Cycle 1(1): loaded http://localhost:53556/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53556/tests/dromaeo/cssquery-ext.html) 01:40:17 INFO - PROCESS | 2244 | RSS: Main: 239665152 01:40:17 INFO - PROCESS | 2244 | 01:42:01 INFO - PROCESS | 2244 | ************************* 01:42:01 INFO - PROCESS | 2244 | A coding exception was thrown and uncaught in a Task. 01:42:01 INFO - PROCESS | 2244 | 01:42:01 ERROR - PROCESS | 2244 | Full message: TypeError: NetworkError when attempting to fetch resource. 01:42:01 INFO - PROCESS | 2244 | Full stack: 01:42:01 INFO - PROCESS | 2244 | ************************* 01:43:19 INFO - PROCESS | 2244 | Cycle 1(1): loaded http://localhost:53556/tests/dromaeo/cssquery-ext.html (next: http://localhost:53556/tests/dromaeo/cssquery-jquery.html) 01:43:20 INFO - PROCESS | 2244 | RSS: Main: 247492608 01:43:20 INFO - PROCESS | 2244 | 01:46:22 INFO - PROCESS | 2244 | Cycle 1(1): loaded http://localhost:53556/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53556/tests/dromaeo/cssquery-mootools.html) 01:46:23 INFO - PROCESS | 2244 | RSS: Main: 254943232 01:46:23 INFO - PROCESS | 2244 | 01:49:25 INFO - PROCESS | 2244 | Cycle 1(1): loaded http://localhost:53556/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53556/tests/dromaeo/cssquery-prototype.html) 01:49:25 INFO - PROCESS | 2244 | RSS: Main: 251785216 01:49:25 INFO - PROCESS | 2244 | 01:52:28 INFO - PROCESS | 2244 | Cycle 1(1): loaded http://localhost:53556/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53556/tests/dromaeo/cssquery-yui.html) 01:52:29 INFO - PROCESS | 2244 | RSS: Main: 256835584 01:52:29 INFO - PROCESS | 2244 | 01:55:31 INFO - PROCESS | 2244 | Cycle 1(1): loaded http://localhost:53556/tests/dromaeo/cssquery-yui.html (next: http://localhost:53556/tests/dromaeo/cssquery-dojo.html) 01:55:31 INFO - PROCESS | 2244 | RSS: Main: 257986560 01:55:31 INFO - PROCESS | 2244 | 01:55:31 INFO - PROCESS | 2244 | __start_tp_report 01:55:31 INFO - PROCESS | 2244 | _x_x_mozilla_page_load 01:55:31 INFO - PROCESS | 2244 | _x_x_mozilla_page_load_details 01:55:31 INFO - PROCESS | 2244 | |i|pagename|runs| 01:55:31 INFO - PROCESS | 2244 | |0;dojo.html;3287.934241315174;3307.503874418838;3317.5853018372704;3322.903441864185;3331.0506505645894;5706.258186435763;5784.421557844214;5795.391483894188;5810.680412577307;5813.33146688131;4697.67116301859;5066.9493305066935;5301.496357845998;5585.469380408855;5787.623804452718;5193.9871725013545;5215.843524694265;5220.843374698765;5272.077386457374;5287.550558202538;5269.130593452076;5269.209618557209;5272.209168624718;5289.920651190216;5304.549113325372;5139.357580302462;5170.819021334254;5258.290130832346;5261.8947621047555;5316;5212.192110222922;5230.790768369261;5265.341832270966;5322.228276899867;5370.489803468664;4212.757236615206;5166.664166829144;5202.453742357074;5218.425973142951;5264.736763161826;5761.798337058225;5768.884622307531;5852.707364631794;5875.588708790387;5891.410858914117;5003.5997120230295;6134.8773024539705;6182.783602573888;6191.226096737908;6205.875882482325;6221.688915554249;6235.939890218674;6248.1564988726395;6249.406306400884;6271.059341098826;5909.172715819789;5950.077737950624;5975.43233392827;5995.760169593167;6034.306054803754;6135.07955838608;6136.54971344134;6195.690215489298;6225.844353891189;6281.8743625127245;5268.947310526923;5443.265159203457;6201.81394558164;6204.906926396107;6231.127642130105;6233.844153896098;6236.563440559117;6256.874862502725;6261.530385221126;6270.843228919313;6028.5478589106;6107.461915314292;6159.014557670751;6255.906161407583;6286.245650521967;6172.814815555541;6242.188515493048;6248.31268560458;6255.093011513305;6291.842703932438;5022.096022715946;5029.547340739351;5049.520295571915;5051.873703157377;5053.191489361684;4391.978040109843;4397.252467080604;4497.078098989699;4497.437820272466;4499.1451624191295;5702.657840529581;5753.741081651336;5754.079347304495;5797.391273916262;5838.2702162229725;6176.4441200292185;6238.064290356483;6238.344973777688;6244.0321750128805;6280.05799130125;5639.295088113986;5699.601027928088;5727.427257274323;5747.25285712863;5769.07694768835;5459.481349271813;5476.644018138808;5500.697461639568;5522.144067669517;5557.360903496069;6357.713902874381;6363.1091647168505;6364.140840986408;6398.232212134573;6414.518911081781;6124.533510047256;6186.721597528032;6200.658963757037;6231.501479881508;6252.437280644764;6307.211598550181;6363.904541431974;6403.96798016007;6407.903881441689;6419.64691941929;5165.276861239505;5746.252987111816;6209.720562574786;6213.316535181217;6232.034034724625;4936.7284799336385;6056.454919057306;6154.353792851686;6202.81391558254;6231.657258850807;5246.3704355477585;6077.997130473599;6103.786367477117;6111;6147;6057.666828324484;6163.476104530986;6182.041783523561;6196.907046394217;6210.596311239755;4616.469106052847;4959.528844759742;5626.987142314422;5718.170865224436;6081.422264884829;6019.826133903846;6084.574079814371;6104.938950610437;6114.266288045463;6173.166622505904;6010.789622363196;6029.246344206974;6036.396360363929;6051.152838602688;6064.545159112996 01:55:32 INFO - PROCESS | 2244 | |1;ext.html;415.98128084236214;427.9293916503777;428.4087958617108;428.6677824685868;487.7512468640993;40782.18435631286;42008.15983680324;42122.15755684885;42138.789306053506;42144.946376340566;43325;43834.56165438345;43913.560864391424;43926.560734392646;43966.56033439664;8408.663653453854;9344.252459803216;9549.665761698341;9574.569144388484;9612.423254604746;8387.748367548953;11258.4370781461;11558.942205289004;11581.652550423501;11691.240069395504;11446.599369022086;11471.426428678533;11484.36835974021;11517;11520.942395288012;11452.942735286311;11505;11512.539498420054;11524.59663911759;11545.942270288595;11369.488373023234;11460.369679667614;11461.197716159872;11489;11498.965093141658;41358.79320603378;41452.37821432681;41628.37557436641;41650;41722;4022.7787471689044;4071.0636553592544;4109.445224894661;4157.168566286755;4222.577742225752;29911.70088299111;31033.91381301666;31052.689473105205;31093.53359699606;31526.369472610648;2822.520171570838;3216.3245718399166;3222.0978126124724;3343.7157841583494;3344.966550334514;366617;369670;370468;371936;371993;66530.66734666296;67030.32969670338;67050.3294967044;67080.32919670838;67130;60489.69755151284;70605.29394705986;70835;70846.9372959406;71042.64478677574;16031.957922734984;16505.257263423176;16522.58693532647;16530.338786448407;16538.58653533671;11348.262362946381;11634.94182529082;12334.259944403364;12672.49310027607;12764.680882977815;18508.44474665762;18774.87350758959;18823.87056776598;18829.90585047066;18923.621527569372;5842.941570584239;6622.437092847065;6784.525083244224;6853.246142924274;6880.621565813928;876.1370050500379;896.3994123936978;896.8340856941356;911.9133682300171;929.3355250995571;25258.36854078663;30533.694663053528;30917.536236956465;31509.369812604076;31523;1034.7516596017051;1068.9144868410508;1090.9018188363086;1117.1677100559973;1196.0431654676224;13391.330433478288;15252.161131137895;15737.737687714041;16054.598635033984;16244.02535847853;235092.6490735105;235863.6413635876;236504;236682;237294;50058.74970625196;62183;62358.688206559586;62510.68744656248;62519.06221406683;1292.7220647560819;1354.3160703844692;1430.577979496051;1446.2985452055746;1446.8770154536771;27193;29292.267693307836;30400.84799575988;30578.541321880635;31032.689673103883;53120.203196951305;53406.329683516444;53511.46488535065;53717.73141134269;53723.46276537185;553009;556216;557314;558234;558337;36168.638313616524;41120.1775964485;41525;42453.15093698171;42677.5732242686;44888.87777805581;46550.53449465462;47132.76433617947;47307;47596.810079748284;35524.28951421009;39282.607173929035;39323.803380982914;40444.94438195226;40554.59445405626;31716.524252136696;32185;32500.512492312162;38861;38879.41680874846;37534.8123259382;37876.431853521666;38025.80987095047;38046.61953380431;38948.41577376398;23587.52824943457;31411.214719632193;34634.826825866716;34861;34881.8255908729;24962.62556061624;25306.493870122864;25562.48875022452;25724.871375643;26045 01:55:32 INFO - PROCESS | 2244 | |2;jquery.html;392.10795440373147;393.6142580271336;401.23364374045576;401.9075612609099;418.1850767668319;15585.610359741013;18388.816111838878;19920.402387928367;20950.6857397139;21046.579068418643;16267.91866040671;19026;20795.584088318225;21211.893940530314;21477.248296309634;5610.719464026803;5664.433556644344;5874.441928016831;5887.35239123696;5917.437843404869;5745.798897038604;5833.300003999505;5849.824505264849;5854.121881717734;5860.238169038019;5755.45323194298;6057.697115144225;6114.816555503342;6140.478059364937;6153.569250152491;6022.668753218593;6034.456898879122;6044.335123136451;6198.4731297839735;6655.500837437212;5883.7058147092475;5884.617499862538;5891.587588868781;5916.201312822757;5917.940820591782;12611.486621605374;13136.60590182296;13175.60473185806;13524.729505409836;13626.1143025704;2632.842029478218;2648.192301348088;2728.9863550682317;2730.7405796449307;2744.1355972868682;9977.75055623608;9993.950030249802;10110.545025473797;10516.842247366296;10548.31435956668;1807.5571484986262;1859.172668162668;1860.804615515365;1865.6082222733241;1866.141574875559;121799.3910030453;125640;126497;128178;130079.69920300771;12158.452869620889;12284.385780710929;12359.752804943852;12402.25586464815;12420.316882571364;13987.020908536508;16012.359505619877;16016.839831601535;16039.278232479566;16039.919800400923;7846.254605812439;8240.876386854203;8426;8527.957360213159;8595.398322117391;8220.506769593843;8296.419250652396;8340.29107525855;8376.246137847624;8399.454035487674;4757.405324334458;4848.224284114524;4849.75751212438;4876.731779752147;4915.115279249698;2033.4204751646005;2072.0986370928695;2079.1059844266806;2079.105984426711;2084.1975839301676;904.1952662130709;1050.5167622893555;1054.1408751867205;1055.931364461323;1065.8720953485476;21195.046222920006;21565.67651485229;21565.813880236423;21571.02930368137;21689.45776355572;1024.5952848624752;1069.8876617955002;1075.2903083964545;1077.9730506737237;1086.8315411111291;5965.612235204698;8628.22345988864;11276.436178191057;11391.715207119889;11448.313101213955;756746;768085;768924;774676;779589;159684;165648;166541;170325.14837425982;175213.4957300847;440.7135362014716;458.35143272269363;461.5799622343718;462.6506987224691;463.693961985095;11216.214864959375;18150.636987260445;18408.81591184071;20081.497962551053;20674;51280.743596281776;59825.70087149536;60345.396546033975;63696.68151659212;64663.030054548275;75010;80076.78883071554;81092;81336.5933170354;82987.5850620743;6623.205215374186;8316.83366332682;8409.495430274203;8702.434341767765;8933.597988090682;11246.83129753038;11658.65024049246;12446.377681115728;12641.620751377106;13058.738825223632;10526.842097368393;11510.13673974472;11653.359065251154;11704.94147529257;11738.647840564796;8912.86630700553;11170.329780213213;11260.930211629882;11355.432228388383;11586.667533233449;9700.563474643517;11054.557817687202;11611.361375124448;11677.474513647076;11760.88239117598;8606.913930860612;8617.784555386166;8649.04860465361;8691.304695624209;8791.560421979004;8319.459235149696;10383.844242336521;11457.255278406878;11606.70983225426;11782.764344713229 01:55:32 INFO - PROCESS | 2244 | |3;mootools.html;1332;1455.3378212913133;1465.7434948883943;1469.8456662050485;1490.8434614365494;6636.53544251902;7529.774106776806;7803.18066603007;8519.95740021299;8555.187257210562;8005.519668819875;8252.216039476256;8273.214044665761;8364.874526882102;8375.53934533602;2263.004278117631;2314.0743702519044;2316.2124877541632;2317.884105794712;2322.8606283623035;2069.1826728442256;2151.8601290916117;2322.036354912718;2350.8354415190943;2372.9288121356385;2244.7642997485273;2332.055517515409;2334.2297041976108;2337;2340.8595484270913;2281.2813963601498;2316.733575638806;2321.9071237150492;2325.221050947943;2338.9649155262687;2324.6629238760283;2336.7663233676667;2352.2002519143402;2371.4071482129466;2380;8288.171182881724;8353.832923341499;8379.37154713399;8386.287165590933;8400.117987611275;5069.797208111671;5217;5236.345456817898;5242.737863106868;5247.186686063628;3297.55483009793;3480.843362048714;3482.2687235680537;3515.4726790981304;3537.4163263061564;1148.0930065248444;1170.2861254634665;1214.0469731260919;1215.1615385384057;1236.8701286364892;10685.358878467317;10712.678619641423;10834.458277086114;10874.510647020903;10890.782184356269;4560.247559152769;4638.976805115953;4643.024964757371;4714.481407045222;4763.476017638057;9533;9627.951860240793;9627.989061148619;9634.325597208128;9640.71077867665;5226.843194704165;5233;5234.842954711365;5239.423663397023;5256.185291279858;4534.9319760203625;4630.351750754897;4718.504557021532;4766.356541866873;4776.164171270014;3151.2909595340866;3232.870685172614;3733.1973625670066;3782.6973842092566;3827.6172382762056;1071.978560428787;1157.1957489544855;1188.839506666589;1227.4844565282604;1282.429318953057;782.6008735544913;813.894193754808;823.4812068396872;937.9249660027318;942.3356533643902;5578.2748242728185;5646.6047376684055;5654.208410822488;5702.54379649627;5844.620099693507;969.6460791810938;983.5967253426061;1003.7841863999129;1008.4302369161401;1011.6914341126027;3778.9418962690493;3853.344931361675;3858.961410385916;3885.611438856092;3897;9752.609895604239;9994.700158995242;10036.548355324028;10040.347377420445;10102.949485252526;9984.650537231308;9985.1512621428;10006.299559030944;10017.799644007078;10027;7491.812704682427;7736.381089512825;7878.330341920886;7914.485558438683;8033.076196237391;5649.915251271152;5717.914231286617;5791.623544469596;5795.275590551181;5962.940370596412;7529.887051694339;7754.612269386395;8876.245519130945;9029.968257142353;9120;9027.593758281024;9052.23056040244;9232.676856309998;9240.353175277665;9281.62873485053;7271.781846544613;7308.378787803096;7311.8903216450735;7318.524295920748;7334.193238743627;7584.8483030340185;7816.257455541714;7869.803254918673;7881.5665138417935;7957.124716281089;6957.095577574883;7050.118735158106;7133.857322853618;7161.355478007004;7198.20819709821;7003.299670032956;7059.223485416703;7062.71749130029;7102.751403700846;7115.53749006313;6453.935460645521;6462.095306656977;6515.511336649675;6523.412892839668;6540.640264785292;4906.484819094087;4963.950360496348;4969.776360063734;5085.669431486942;5175.81884634036;4953.380827396575;4979.402471703419;5077.441481436965;5156.123459011902;5232.633715639869 01:55:32 INFO - PROCESS | 2244 | |4;prototype.html;326.84786128895627;357.37994579404744;360.87189047888;368.123865200822;373.9439084137379;4711.929321060178;4817.181079216532;5110.974445127774;5349;5611.494965453108;5219;5389.380221274555;5453.863653408666;5457.017736807373;5476.698781567012;4282.743035417877;4293.29160688486;4308.97845510772;4309.340903752062;4352.434183556134;3811.00913762421;3885.417187421895;4066.7966601669946;4079.4492743479695;4330;3586.282743451307;3874.8062596870045;4057.6753859691303;4243.936340954888;4244.172386384656;3198.168476196194;3456.913577160566;4096.160287141159;4250.383694364331;4265.848220980349;3765.7928813915223;3782.3759079752085;3794.658480736747;3813.675837553811;3845.1156234066043;4615.676902616819;5007.622903701475;5288.365396152448;5316.0962636351915;5351.598630102758;4197.664186865043;4946.703197808143;4950.504949505057;4962.429320628102;4968.2299243616935;5131.358580177478;5797.942020579782;5822.330432000332;5828.3297420796725;5836.328822185461;4029.879103626896;4134.098314775207;4206.74759514427;4209.684273679486;4300.913981720348;6054.424829641177;6058.000429929057;6075.574709770318;6112.327643959161;6128.9693551531955;4111.527174374985;4261.083866968616;4261.850835220815;4265.722728022669;4268.679849011337;3699.6300369962787;3754.5869954305003;3763.7553559018575;4099.200655872136;4352.018867076063;3910.1788624389196;4760.214564596803;4779.713217206979;4784.784684689197;4826.565609095128;4901.779419926112;5657.755293835349;5821.912671309934;5864.266966629171;5942.64344139353;1181.5805389086922;1207.8646072691643;1217.5008246618843;1289.6066699656694;1316.4997301025548;797.452941293888;858.6393714639867;874.1215078845719;875.7197696737111;889.786451251695;4623.56076172763;4768.928466073012;4861.708297502161;5067.442581316052;5117.777833287552;4861.079873449968;5473.972630136904;5747.281589801275;5766.88466230673;5791.623544469596;4329.935050974238;4710.8351207707565;4842.612590992782;5049.9495005049475;5206.843794686166;2762.654668166479;2905.7384835364496;3021.54676798482;3040.3767227718263;3059.831709255968;5961.880762384728;5969.075153174913;5973.940260597426;5992.700364981733;6010.54920880927;4419.0719948810465;5601.80393686219;5842.676419449576;5872.911906321408;5895.4399332063385;5561.499465048165;5830.970845145747;5841.970790146107;5992.43071908168;6111.463742546971;5308.708021058879;5396.325459317585;5572.637778544382;5693.772249110155;5765.884682306247;4810.903781924412;4923.335349727741;4942.975285123552;5636.718164091697;5708.65748055118;5021.372328458942;5901.940980590139;5906.940930590639;5928.733207005781;5957.791477298274;3468.809215493172;3644.9817750911075;3766.5291838520184;3802.4676545284237;3811.828467718904;4679.695819771704;4944.9368385797225;5405.783768649368;5737.02470579994;5749.655020698771;5007.098722230034;5286.259923610774;5484.341878974548;5658.575606829422;5752.597318187687;4667.416572928384;4755.429348478205;5682.204491371129;5754.3382511010095;5765.827025189251;4781.832635857728;5623;5636.774529018793;5645.181448689999;5694.800681976111;5724.885502289848;5766.59633825645;5767.088734047635;5771.0856017710785;5813.709314534171;4264.04059086703;5023.8492845214705;5577;5622.746976385991;5632.422921581963 01:55:32 INFO - PROCESS | 2244 | |5;yui.html;712.1809918593617;766.344775217189;787.9763607091786;852.8464876322263;868.1188593577518;14725.410983560658;15375.999999999985;15688.450904218354;15837.920810395946;15939.760903586426;14177.521055600773;14272.072315299523;14325.140491570486;14400.423983040666;14446.638834029154;691.332863786446;696.303696303695;865.8917635295588;870.3559366069095;871.1375738019346;710.2932582080837;850.1286181663783;868.8653258744905;873.7509809704236;881.6870011146048;924.6440120553568;929.251952178496;933.9159475647191;936.2930987104727;938.943663380196;913.9360244782868;917.655879045358;927.9118483744033;929.3123088914189;929.5677509957868;691.9861602767967;694.9965250173767;696.140266770538;698.3435570563705;698.7903628911357;12821.935890320581;14740.926295368561;15210.391584336614;15271.541853744406;15363.15502647354;11424.485898134604;12397.566085187065;13911.86088139126;14334.068285561509;14391.13653180802;11658.125640576905;11952.64142075739;11973.102017348734;12008.63974080779;12175.756484870342;3659.1583935694675;3675.5773086094905;3709.7217708671687;3866.478025466582;3877.7673339599714;89461;95563.56654650255;109610.45194774056;111869;113874.43062784716;11062.502187401586;11206.551737930391;11224.607138750105;11231.719207019727;11405.600803971822;22349.638774075353;22456.88771556132;22599.661005084938;22651.43371415695;22667.546649067255;7683;7747.302742753067;7779.844403112019;7782.533048017137;7941.404394670423;4321.395004699345;4331.371951067078;4381.715188512737;4434.935615452333;4459.643228541709;3555.5555555555557;3645.635436456333;3665.7617254878883;3774.698024158116;3965.960340396559;700.7792545348199;702.6978399288253;705.6471764117941;709.2234003765972;721.7618185998608;566.5552541255095;591.2757071002658;623.3890787028647;683.1665368250726;716.9032180655649;10227.232957528215;10247.623588307268;10320.277580569438;10485.26603137773;10656.520456579472;582.8280657206134;588.932845711547;597.9804433440972;612.5558969746987;616.8982117950488;5003.54968052877;5142.948570514322;5186.740662966837;5189.636725429184;5210.869728256748;77384;92788;99731;104092;105911.47044264729;81949;83307.83460826935;83450.58274708588;87496;89019.55490222508;56375.71812140913;73164.63417682878;76349.61825190838;77008.61495692485;77196;8515.74452766418;10971;11170.664880053611;11240.325580465198;11297.322160670386;47382.81542961454;48302.75848620734;48519.272210916155;49074.75462622664;49609.50390496193;52280.47719522756;53866.03736794514;62475.68762156342;62529.06206406817;64770.676146618964;12824.743505130033;13142.605721827978;13252.271125087827;13279.867201327863;13479.19124852512;14501.709965800414;14715.705685886009;14829.555113346616;15125.39498420051;15623.453179138676;13308.800367994294;13894.791578126134;14000.929995349958;14588.854111459174;14630.853691463375;13856.168629882239;13925.791113133513;14365.281735913371;14485.493007744679;14523.709525809214;13496.257705825881;13617.25105119228;13809.171449713049;13892.583222503341;14355.210463424612;11185.087871502064;11776.293422394683;11868.465919033837;11926;12009.81985270239;11018.614348497764;11159.888401115884;11300.94349528247;11315.151363647925;11337.829932551183 01:55:32 INFO - PROCESS | 2244 | __end_tp_report 01:55:32 INFO - PROCESS | 2244 | __start_cc_report 01:55:32 INFO - PROCESS | 2244 | _x_x_mozilla_cycle_collect,382 01:55:32 INFO - PROCESS | 2244 | __end_cc_report 01:55:32 INFO - PROCESS | 2244 | __startTimestamp1485338131993__endTimestamp 01:55:32 INFO - PROCESS | 2244 | 01:55:32 INFO - PROCESS | 2244 | ------- Summary: start ------- 01:55:32 INFO - PROCESS | 2244 | Number of tests: 6 01:55:32 INFO - PROCESS | 2244 | 01:55:32 INFO - PROCESS | 2244 | [#0] dojo.html Cycles:165 Average:5705.82 Median:5950.08 stddev:663.94 (11.2%) stddev-sans-first:638.31 01:55:32 INFO - PROCESS | 2244 | Values: 3287.9 3307.5 3317.6 3322.9 3331.1 5706.3 5784.4 5795.4 5810.7 5813.3 4697.7 5066.9 5301.5 5585.5 5787.6 5194.0 5215.8 5220.8 5272.1 5287.6 5269.1 5269.2 5272.2 5289.9 5304.5 5139.4 5170.8 5258.3 5261.9 5316.0 5212.2 5230.8 5265.3 5322.2 5370.5 4212.8 5166.7 5202.5 5218.4 5264.7 5761.8 5768.9 5852.7 5875.6 5891.4 5003.6 6134.9 6182.8 6191.2 6205.9 6221.7 6235.9 6248.2 6249.4 6271.1 5909.2 5950.1 5975.4 5995.8 6034.3 6135.1 6136.5 6195.7 6225.8 6281.9 5268.9 5443.3 6201.8 6204.9 6231.1 6233.8 6236.6 6256.9 6261.5 6270.8 6028.5 6107.5 6159.0 6255.9 6286.2 6172.8 6242.2 6248.3 6255.1 6291.8 5022.1 5029.5 5049.5 5051.9 5053.2 4392.0 4397.3 4497.1 4497.4 4499.1 5702.7 5753.7 5754.1 5797.4 5838.3 6176.4 6238.1 6238.3 6244.0 6280.1 5639.3 5699.6 5727.4 5747.3 5769.1 5459.5 5476.6 5500.7 5522.1 5557.4 6357.7 6363.1 6364.1 6398.2 6414.5 6124.5 6186.7 6200.7 6231.5 6252.4 6307.2 6363.9 6404.0 6407.9 6419.6 5165.3 5746.3 6209.7 6213.3 6232.0 4936.7 6056.5 6154.4 6202.8 6231.7 5246.4 6078.0 6103.8 6111.0 6147.0 6057.7 6163.5 6182.0 6196.9 6210.6 4616.5 4959.5 5627.0 5718.2 6081.4 6019.8 6084.6 6104.9 6114.3 6173.2 6010.8 6029.2 6036.4 6051.2 6064.5 01:55:32 INFO - PROCESS | 2244 | 01:55:32 INFO - PROCESS | 2244 | [#1] ext.html Cycles:180 Average:56162.57 Median:370069.00 stddev:108877.67 (29.4%) stddev-sans-first:109102.65 01:55:32 INFO - PROCESS | 2244 | Values: 416.0 427.9 428.4 428.7 487.8 40782.2 42008.2 42122.2 42138.8 42144.9 43325.0 43834.6 43913.6 43926.6 43966.6 8408.7 9344.3 9549.7 9574.6 9612.4 8387.7 11258.4 11558.9 11581.7 11691.2 11446.6 11471.4 11484.4 11517.0 11520.9 11452.9 11505.0 11512.5 11524.6 11545.9 11369.5 11460.4 11461.2 11489.0 11499.0 41358.8 41452.4 41628.4 41650.0 41722.0 4022.8 4071.1 4109.4 4157.2 4222.6 29911.7 31033.9 31052.7 31093.5 31526.4 2822.5 3216.3 3222.1 3343.7 3345.0 366617.0 369670.0 370468.0 371936.0 371993.0 66530.7 67030.3 67050.3 67080.3 67130.0 60489.7 70605.3 70835.0 70846.9 71042.6 16032.0 16505.3 16522.6 16530.3 16538.6 11348.3 11634.9 12334.3 12672.5 12764.7 18508.4 18774.9 18823.9 18829.9 18923.6 5842.9 6622.4 6784.5 6853.2 6880.6 876.1 896.4 896.8 911.9 929.3 25258.4 30533.7 30917.5 31509.4 31523.0 1034.8 1068.9 1090.9 1117.2 1196.0 13391.3 15252.2 15737.7 16054.6 16244.0 235092.6 235863.6 236504.0 236682.0 237294.0 50058.7 62183.0 62358.7 62510.7 62519.1 1292.7 1354.3 1430.6 1446.3 1446.9 27193.0 29292.3 30400.8 30578.5 31032.7 53120.2 53406.3 53511.5 53717.7 53723.5 553009.0 556216.0 557314.0 558234.0 558337.0 36168.6 41120.2 41525.0 42453.2 42677.6 44888.9 46550.5 47132.8 47307.0 47596.8 35524.3 39282.6 39323.8 40444.9 40554.6 31716.5 32185.0 32500.5 38861.0 38879.4 37534.8 37876.4 38025.8 38046.6 38948.4 23587.5 31411.2 34634.8 34861.0 34881.8 24962.6 25306.5 25562.5 25724.9 26045.0 01:55:32 INFO - PROCESS | 2244 | 01:55:32 INFO - PROCESS | 2244 | [#2] jquery.html Cycles:180 Average:41026.05 Median:21630.24 stddev:128304.28 (593.2%) stddev-sans-first:128627.93 01:55:32 INFO - PROCESS | 2244 | Values: 392.1 393.6 401.2 401.9 418.2 15585.6 18388.8 19920.4 20950.7 21046.6 16267.9 19026.0 20795.6 21211.9 21477.2 5610.7 5664.4 5874.4 5887.4 5917.4 5745.8 5833.3 5849.8 5854.1 5860.2 5755.5 6057.7 6114.8 6140.5 6153.6 6022.7 6034.5 6044.3 6198.5 6655.5 5883.7 5884.6 5891.6 5916.2 5917.9 12611.5 13136.6 13175.6 13524.7 13626.1 2632.8 2648.2 2729.0 2730.7 2744.1 9977.8 9994.0 10110.5 10516.8 10548.3 1807.6 1859.2 1860.8 1865.6 1866.1 121799.4 125640.0 126497.0 128178.0 130079.7 12158.5 12284.4 12359.8 12402.3 12420.3 13987.0 16012.4 16016.8 16039.3 16039.9 7846.3 8240.9 8426.0 8528.0 8595.4 8220.5 8296.4 8340.3 8376.2 8399.5 4757.4 4848.2 4849.8 4876.7 4915.1 2033.4 2072.1 2079.1 2079.1 2084.2 904.2 1050.5 1054.1 1055.9 1065.9 21195.0 21565.7 21565.8 21571.0 21689.5 1024.6 1069.9 1075.3 1078.0 1086.8 5965.6 8628.2 11276.4 11391.7 11448.3 756746.0 768085.0 768924.0 774676.0 779589.0 159684.0 165648.0 166541.0 170325.1 175213.5 440.7 458.4 461.6 462.7 463.7 11216.2 18150.6 18408.8 20081.5 20674.0 51280.7 59825.7 60345.4 63696.7 64663.0 75010.0 80076.8 81092.0 81336.6 82987.6 6623.2 8316.8 8409.5 8702.4 8933.6 11246.8 11658.7 12446.4 12641.6 13058.7 10526.8 11510.1 11653.4 11704.9 11738.6 8912.9 11170.3 11260.9 11355.4 11586.7 9700.6 11054.6 11611.4 11677.5 11760.9 8606.9 8617.8 8649.0 8691.3 8791.6 8319.5 10383.8 11457.3 11606.7 11782.8 01:55:32 INFO - PROCESS | 2244 | 01:55:32 INFO - PROCESS | 2244 | [#3] mootools.html Cycles:180 Average:5430.99 Median:5229.74 stddev:2937.31 (56.2%) stddev-sans-first:2929.39 01:55:32 INFO - PROCESS | 2244 | Values: 1332.0 1455.3 1465.7 1469.8 1490.8 6636.5 7529.8 7803.2 8520.0 8555.2 8005.5 8252.2 8273.2 8364.9 8375.5 2263.0 2314.1 2316.2 2317.9 2322.9 2069.2 2151.9 2322.0 2350.8 2372.9 2244.8 2332.1 2334.2 2337.0 2340.9 2281.3 2316.7 2321.9 2325.2 2339.0 2324.7 2336.8 2352.2 2371.4 2380.0 8288.2 8353.8 8379.4 8386.3 8400.1 5069.8 5217.0 5236.3 5242.7 5247.2 3297.6 3480.8 3482.3 3515.5 3537.4 1148.1 1170.3 1214.0 1215.2 1236.9 10685.4 10712.7 10834.5 10874.5 10890.8 4560.2 4639.0 4643.0 4714.5 4763.5 9533.0 9628.0 9628.0 9634.3 9640.7 5226.8 5233.0 5234.8 5239.4 5256.2 4534.9 4630.4 4718.5 4766.4 4776.2 3151.3 3232.9 3733.2 3782.7 3827.6 1072.0 1157.2 1188.8 1227.5 1282.4 782.6 813.9 823.5 937.9 942.3 5578.3 5646.6 5654.2 5702.5 5844.6 969.6 983.6 1003.8 1008.4 1011.7 3778.9 3853.3 3859.0 3885.6 3897.0 9752.6 9994.7 10036.5 10040.3 10102.9 9984.7 9985.2 10006.3 10017.8 10027.0 7491.8 7736.4 7878.3 7914.5 8033.1 5649.9 5717.9 5791.6 5795.3 5962.9 7529.9 7754.6 8876.2 9030.0 9120.0 9027.6 9052.2 9232.7 9240.4 9281.6 7271.8 7308.4 7311.9 7318.5 7334.2 7584.8 7816.3 7869.8 7881.6 7957.1 6957.1 7050.1 7133.9 7161.4 7198.2 7003.3 7059.2 7062.7 7102.8 7115.5 6453.9 6462.1 6515.5 6523.4 6540.6 4906.5 4964.0 4969.8 5085.7 5175.8 4953.4 4979.4 5077.4 5156.1 5232.6 01:55:32 INFO - PROCESS | 2244 | 01:55:32 INFO - PROCESS | 2244 | [#4] prototype.html Cycles:180 Average:4599.13 Median:4987.66 stddev:1400.40 (28.1%) stddev-sans-first:1367.12 01:55:32 INFO - PROCESS | 2244 | Values: 326.8 357.4 360.9 368.1 373.9 4711.9 4817.2 5111.0 5349.0 5611.5 5219.0 5389.4 5453.9 5457.0 5476.7 4282.7 4293.3 4309.0 4309.3 4352.4 3811.0 3885.4 4066.8 4079.4 4330.0 3586.3 3874.8 4057.7 4243.9 4244.2 3198.2 3456.9 4096.2 4250.4 4265.8 3765.8 3782.4 3794.7 3813.7 3845.1 4615.7 5007.6 5288.4 5316.1 5351.6 4197.7 4946.7 4950.5 4962.4 4968.2 5131.4 5797.9 5822.3 5828.3 5836.3 4029.9 4134.1 4206.7 4209.7 4300.9 6054.4 6058.0 6075.6 6112.3 6129.0 4111.5 4261.1 4261.9 4265.7 4268.7 3699.6 3754.6 3763.8 4099.2 4352.0 3910.2 4760.2 4779.7 4784.8 4826.6 4901.8 5657.8 5821.9 5864.3 5942.6 1181.6 1207.9 1217.5 1289.6 1316.5 797.5 858.6 874.1 875.7 889.8 4623.6 4768.9 4861.7 5067.4 5117.8 4861.1 5474.0 5747.3 5766.9 5791.6 4329.9 4710.8 4842.6 5049.9 5206.8 2762.7 2905.7 3021.5 3040.4 3059.8 5961.9 5969.1 5973.9 5992.7 6010.5 4419.1 5601.8 5842.7 5872.9 5895.4 5561.5 5831.0 5842.0 5992.4 6111.5 5308.7 5396.3 5572.6 5693.8 5765.9 4810.9 4923.3 4943.0 5636.7 5708.7 5021.4 5901.9 5906.9 5928.7 5957.8 3468.8 3645.0 3766.5 3802.5 3811.8 4679.7 4944.9 5405.8 5737.0 5749.7 5007.1 5286.3 5484.3 5658.6 5752.6 4667.4 4755.4 5682.2 5754.3 5765.8 4781.8 5623.0 5636.8 5645.2 5694.8 5724.9 5766.6 5767.1 5771.1 5813.7 4264.0 5023.8 5577.0 5622.7 5632.4 01:55:32 INFO - PROCESS | 2244 | 01:55:32 INFO - PROCESS | 2244 | [#5] yui.html Cycles:180 Average:19846.07 Median:3820.59 stddev:27806.46 (727.8%) stddev-sans-first:27847.35 01:55:32 INFO - PROCESS | 2244 | Values: 712.2 766.3 788.0 852.8 868.1 14725.4 15376.0 15688.5 15837.9 15939.8 14177.5 14272.1 14325.1 14400.4 14446.6 691.3 696.3 865.9 870.4 871.1 710.3 850.1 868.9 873.8 881.7 924.6 929.3 933.9 936.3 938.9 913.9 917.7 927.9 929.3 929.6 692.0 695.0 696.1 698.3 698.8 12821.9 14740.9 15210.4 15271.5 15363.2 11424.5 12397.6 13911.9 14334.1 14391.1 11658.1 11952.6 11973.1 12008.6 12175.8 3659.2 3675.6 3709.7 3866.5 3877.8 89461.0 95563.6 109610.5 111869.0 113874.4 11062.5 11206.6 11224.6 11231.7 11405.6 22349.6 22456.9 22599.7 22651.4 22667.5 7683.0 7747.3 7779.8 7782.5 7941.4 4321.4 4331.4 4381.7 4434.9 4459.6 3555.6 3645.6 3665.8 3774.7 3966.0 700.8 702.7 705.6 709.2 721.8 566.6 591.3 623.4 683.2 716.9 10227.2 10247.6 10320.3 10485.3 10656.5 582.8 588.9 598.0 612.6 616.9 5003.5 5142.9 5186.7 5189.6 5210.9 77384.0 92788.0 99731.0 104092.0 105911.5 81949.0 83307.8 83450.6 87496.0 89019.6 56375.7 73164.6 76349.6 77008.6 77196.0 8515.7 10971.0 11170.7 11240.3 11297.3 47382.8 48302.8 48519.3 49074.8 49609.5 52280.5 53866.0 62475.7 62529.1 64770.7 12824.7 13142.6 13252.3 13279.9 13479.2 14501.7 14715.7 14829.6 15125.4 15623.5 13308.8 13894.8 14000.9 14588.9 14630.9 13856.2 13925.8 14365.3 14485.5 14523.7 13496.3 13617.3 13809.2 13892.6 14355.2 11185.1 11776.3 11868.5 11926.0 12009.8 11018.6 11159.9 11300.9 11315.2 11337.8 01:55:32 INFO - PROCESS | 2244 | -------- Summary: end -------- 01:55:32 INFO - PROCESS | 2244 | 01:55:32 INFO - TEST-INFO | 2244: exit 0 01:55:32 INFO - TEST-OK | dromaeo_css | took 1115561ms 01:55:32 INFO - TEST-START | kraken 01:55:32 INFO - operating with platform_type : mac_ 01:55:32 INFO - Initialising browser for kraken test... 01:55:32 INFO - TEST-INFO | started process 2248 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/tmp5jPEyi/profile http://localhost:53556/getInfo.html) 01:55:35 INFO - PROCESS | 2248 | 2017-01-25 01:55:35.373 plugin-container[2250:22129] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883b, name = 'com.apple.tsm.portname' 01:55:35 INFO - PROCESS | 2248 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:55:35 INFO - PROCESS | 2248 | 2017-01-25 01:55:35.374 plugin-container[2250:22129] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0b, name = 'com.apple.CFPasteboardClient' 01:55:35 INFO - PROCESS | 2248 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:55:35 INFO - PROCESS | 2248 | 2017-01-25 01:55:35.374 plugin-container[2250:22129] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 01:55:35 INFO - PROCESS | 2248 | __metrics Screen width/height:1600/1200 01:55:35 INFO - PROCESS | 2248 | colorDepth:24 01:55:35 INFO - PROCESS | 2248 | Browser inner width/height: 1024/689 01:55:35 INFO - PROCESS | 2248 | __metrics 01:55:35 ERROR - PROCESS | 2248 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3280: TypeError: this.tabs is undefined 01:55:35 INFO - PROCESS | 2248 | JavaScript error: resource://app/modules/webrtcUI.jsm, line 66: NS_ERROR_XPC_NOT_ENOUGH_ARGS: Not enough arguments [nsIMessageListenerManager.removeMessageListener] 01:55:36 INFO - TEST-INFO | 2248: exit 0 01:55:36 INFO - Browser initialized. 01:55:36 INFO - Running cycle 1/1 for kraken test... 01:55:36 INFO - TEST-INFO | started process 2251 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/tmp5jPEyi/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:55:38 INFO - PROCESS | 2251 | 2017-01-25 01:55:38.787 plugin-container[2252:22257] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 01:55:38 INFO - PROCESS | 2251 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:55:38 INFO - PROCESS | 2251 | 2017-01-25 01:55:38.788 plugin-container[2252:22257] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d13, name = 'com.apple.CFPasteboardClient' 01:55:38 INFO - PROCESS | 2251 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:55:38 INFO - PROCESS | 2251 | 2017-01-25 01:55:38.788 plugin-container[2252:22257] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 01:55:48 INFO - PROCESS | 2251 | RSS: Main: 219140096 01:55:48 INFO - PROCESS | 2251 | 01:55:51 INFO - PROCESS | 2251 | Cycle 1(1): loaded http://localhost:53556/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53556/tests/kraken/driver.html?testName=audio-beat-detection) 01:55:52 INFO - PROCESS | 2251 | RSS: Main: 233234432 01:55:52 INFO - PROCESS | 2251 | 01:55:55 INFO - PROCESS | 2251 | Cycle 1(1): loaded http://localhost:53556/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53556/tests/kraken/driver.html?testName=audio-dft) 01:55:55 INFO - PROCESS | 2251 | RSS: Main: 241139712 01:55:55 INFO - PROCESS | 2251 | 01:55:59 INFO - PROCESS | 2251 | Cycle 1(1): loaded http://localhost:53556/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53556/tests/kraken/driver.html?testName=audio-fft) 01:55:59 INFO - PROCESS | 2251 | RSS: Main: 245395456 01:55:59 INFO - PROCESS | 2251 | 01:56:01 INFO - PROCESS | 2251 | Cycle 1(1): loaded http://localhost:53556/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53556/tests/kraken/driver.html?testName=audio-oscillator) 01:56:02 INFO - PROCESS | 2251 | RSS: Main: 246177792 01:56:02 INFO - PROCESS | 2251 | 01:56:04 INFO - PROCESS | 2251 | Cycle 1(1): loaded http://localhost:53556/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53556/tests/kraken/driver.html?testName=imaging-gaussian-blur) 01:56:05 INFO - PROCESS | 2251 | RSS: Main: 246964224 01:56:05 INFO - PROCESS | 2251 | 01:56:08 INFO - PROCESS | 2251 | Cycle 1(1): loaded http://localhost:53556/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53556/tests/kraken/driver.html?testName=imaging-darkroom) 01:56:08 INFO - PROCESS | 2251 | RSS: Main: 250163200 01:56:08 INFO - PROCESS | 2251 | 01:56:12 INFO - PROCESS | 2251 | Cycle 1(1): loaded http://localhost:53556/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53556/tests/kraken/driver.html?testName=imaging-desaturate) 01:56:12 INFO - PROCESS | 2251 | RSS: Main: 249729024 01:56:12 INFO - PROCESS | 2251 | 01:56:15 INFO - PROCESS | 2251 | Cycle 1(1): loaded http://localhost:53556/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53556/tests/kraken/driver.html?testName=json-parse-financial) 01:56:16 INFO - PROCESS | 2251 | RSS: Main: 249245696 01:56:16 INFO - PROCESS | 2251 | 01:56:18 INFO - PROCESS | 2251 | Cycle 1(1): loaded http://localhost:53556/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53556/tests/kraken/driver.html?testName=json-stringify-tinderbox) 01:56:18 INFO - PROCESS | 2251 | RSS: Main: 248926208 01:56:18 INFO - PROCESS | 2251 | 01:56:20 INFO - PROCESS | 2251 | Cycle 1(1): loaded http://localhost:53556/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53556/tests/kraken/driver.html?testName=stanford-crypto-aes) 01:56:21 INFO - PROCESS | 2251 | RSS: Main: 248958976 01:56:21 INFO - PROCESS | 2251 | 01:56:23 INFO - PROCESS | 2251 | Cycle 1(1): loaded http://localhost:53556/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53556/tests/kraken/driver.html?testName=stanford-crypto-ccm) 01:56:24 INFO - PROCESS | 2251 | RSS: Main: 249008128 01:56:24 INFO - PROCESS | 2251 | 01:56:27 INFO - PROCESS | 2251 | Cycle 1(1): loaded http://localhost:53556/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53556/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 01:56:28 INFO - PROCESS | 2251 | RSS: Main: 249274368 01:56:28 INFO - PROCESS | 2251 | 01:56:30 INFO - PROCESS | 2251 | Cycle 1(1): loaded http://localhost:53556/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53556/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 01:56:31 INFO - PROCESS | 2251 | RSS: Main: 249065472 01:56:31 INFO - PROCESS | 2251 | 01:56:33 INFO - PROCESS | 2251 | Cycle 1(1): loaded http://localhost:53556/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53556/tests/kraken/driver.html?testName=ai-astar) 01:56:33 INFO - PROCESS | 2251 | RSS: Main: 250716160 01:56:33 INFO - PROCESS | 2251 | 01:56:33 INFO - PROCESS | 2251 | __start_tp_report 01:56:33 INFO - PROCESS | 2251 | _x_x_mozilla_page_load 01:56:33 INFO - PROCESS | 2251 | _x_x_mozilla_page_load_details 01:56:33 INFO - PROCESS | 2251 | |i|pagename|runs| 01:56:33 INFO - PROCESS | 2251 | |0;ai-astar;114;126;164;117;119;167;147;123;170;120 01:56:33 INFO - PROCESS | 2251 | |1;audio-beat-detection;141;111;111;144;123;146;97;171;139;142 01:56:33 INFO - PROCESS | 2251 | |2;audio-dft;195;200;195;199;202;200;198;200;208;195 01:56:33 INFO - PROCESS | 2251 | |3;audio-fft;87;60;59;90;60;65;85;50;86;51 01:56:33 INFO - PROCESS | 2251 | |4;audio-oscillator;99;101;119;73;59;85;116;58;94;115 01:56:33 INFO - PROCESS | 2251 | |5;imaging-gaussian-blur;92;89;91;108;133;132;96;102;136;87 01:56:33 INFO - PROCESS | 2251 | |6;imaging-darkroom;115;102;171;103;170;114;170;160;102;150 01:56:33 INFO - PROCESS | 2251 | |7;imaging-desaturate;95;108;128;92;93;92;121;94;146;79 01:56:33 INFO - PROCESS | 2251 | |8;json-parse-financial;54;66;62;61;64;58;62;66;53;57 01:56:33 INFO - PROCESS | 2251 | |9;json-stringify-tinderbox;76;70;72;79;78;71;71;88;111;88 01:56:33 INFO - PROCESS | 2251 | |10;stanford-crypto-aes;85;85;87;89;86;85;92;90;85;85 01:56:33 INFO - PROCESS | 2251 | |11;stanford-crypto-ccm;139;149;150;142;143;137;135;155;191;154 01:56:33 INFO - PROCESS | 2251 | |12;stanford-crypto-pbkdf2;168;182;169;170;172;167;169;173;174;172 01:56:33 INFO - PROCESS | 2251 | |13;stanford-crypto-sha256-iterative;70;82;88;84;74;65;65;66;68;69 01:56:33 INFO - PROCESS | 2251 | __end_tp_report 01:56:33 INFO - PROCESS | 2251 | __start_cc_report 01:56:33 INFO - PROCESS | 2251 | _x_x_mozilla_cycle_collect,961 01:56:33 INFO - PROCESS | 2251 | __end_cc_report 01:56:33 INFO - PROCESS | 2251 | __startTimestamp1485338193610__endTimestamp 01:56:33 INFO - PROCESS | 2251 | 01:56:33 INFO - PROCESS | 2251 | ------- Summary: start ------- 01:56:33 INFO - PROCESS | 2251 | Number of tests: 14 01:56:33 INFO - PROCESS | 2251 | 01:56:33 INFO - PROCESS | 2251 | [#0] ai-astar Cycles:10 Average:136.70 Median:136.50 stddev:22.79 (16.7%) stddev-sans-first:22.65 01:56:33 INFO - PROCESS | 2251 | Values: 114.0 126.0 164.0 117.0 119.0 167.0 147.0 123.0 170.0 120.0 01:56:33 INFO - PROCESS | 2251 | 01:56:33 INFO - PROCESS | 2251 | [#1] audio-beat-detection Cycles:10 Average:132.50 Median:143.00 stddev:21.80 (15.2%) stddev-sans-first:22.90 01:56:33 INFO - PROCESS | 2251 | Values: 141.0 111.0 111.0 144.0 123.0 146.0 97.0 171.0 139.0 142.0 01:56:33 INFO - PROCESS | 2251 | 01:56:33 INFO - PROCESS | 2251 | [#2] audio-dft Cycles:10 Average:199.20 Median:200.00 stddev:3.97 (2.0%) stddev-sans-first:3.91 01:56:33 INFO - PROCESS | 2251 | Values: 195.0 200.0 195.0 199.0 202.0 200.0 198.0 200.0 208.0 195.0 01:56:33 INFO - PROCESS | 2251 | 01:56:33 INFO - PROCESS | 2251 | [#3] audio-fft Cycles:10 Average:69.30 Median:75.00 stddev:15.89 (21.2%) stddev-sans-first:15.51 01:56:33 INFO - PROCESS | 2251 | Values: 87.0 60.0 59.0 90.0 60.0 65.0 85.0 50.0 86.0 51.0 01:56:33 INFO - PROCESS | 2251 | 01:56:33 INFO - PROCESS | 2251 | [#4] audio-oscillator Cycles:10 Average:91.90 Median:66.00 stddev:22.66 (34.3%) stddev-sans-first:23.89 01:56:33 INFO - PROCESS | 2251 | Values: 99.0 101.0 119.0 73.0 59.0 85.0 116.0 58.0 94.0 115.0 01:56:33 INFO - PROCESS | 2251 | 01:56:33 INFO - PROCESS | 2251 | [#5] imaging-gaussian-blur Cycles:10 Average:106.60 Median:88.00 stddev:19.70 (22.4%) stddev-sans-first:20.17 01:56:33 INFO - PROCESS | 2251 | Values: 92.0 89.0 91.0 108.0 133.0 132.0 96.0 102.0 136.0 87.0 01:56:33 INFO - PROCESS | 2251 | 01:56:33 INFO - PROCESS | 2251 | [#6] imaging-darkroom Cycles:10 Average:135.70 Median:155.00 stddev:30.97 (20.0%) stddev-sans-first:31.93 01:56:33 INFO - PROCESS | 2251 | Values: 115.0 102.0 171.0 103.0 170.0 114.0 170.0 160.0 102.0 150.0 01:56:33 INFO - PROCESS | 2251 | 01:56:33 INFO - PROCESS | 2251 | [#7] imaging-desaturate Cycles:10 Average:104.80 Median:92.00 stddev:20.69 (22.5%) stddev-sans-first:21.64 01:56:33 INFO - PROCESS | 2251 | Values: 95.0 108.0 128.0 92.0 93.0 92.0 121.0 94.0 146.0 79.0 01:56:33 INFO - PROCESS | 2251 | 01:56:33 INFO - PROCESS | 2251 | [#8] json-parse-financial Cycles:10 Average:60.30 Median:62.00 stddev:4.64 (7.5%) stddev-sans-first:4.33 01:56:33 INFO - PROCESS | 2251 | Values: 54.0 66.0 62.0 61.0 64.0 58.0 62.0 66.0 53.0 57.0 01:56:33 INFO - PROCESS | 2251 | 01:56:33 INFO - PROCESS | 2251 | [#9] json-stringify-tinderbox Cycles:10 Average:80.40 Median:77.00 stddev:12.62 (16.4%) stddev-sans-first:13.29 01:56:33 INFO - PROCESS | 2251 | Values: 76.0 70.0 72.0 79.0 78.0 71.0 71.0 88.0 111.0 88.0 01:56:33 INFO - PROCESS | 2251 | 01:56:33 INFO - PROCESS | 2251 | [#10] stanford-crypto-aes Cycles:10 Average:86.90 Median:86.50 stddev:2.56 (3.0%) stddev-sans-first:2.62 01:56:33 INFO - PROCESS | 2251 | Values: 85.0 85.0 87.0 89.0 86.0 85.0 92.0 90.0 85.0 85.0 01:56:33 INFO - PROCESS | 2251 | 01:56:33 INFO - PROCESS | 2251 | [#11] stanford-crypto-ccm Cycles:10 Average:149.50 Median:149.50 stddev:16.15 (10.8%) stddev-sans-first:16.68 01:56:33 INFO - PROCESS | 2251 | Values: 139.0 149.0 150.0 142.0 143.0 137.0 135.0 155.0 191.0 154.0 01:56:33 INFO - PROCESS | 2251 | 01:56:33 INFO - PROCESS | 2251 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.60 Median:172.00 stddev:4.30 (2.5%) stddev-sans-first:4.36 01:56:33 INFO - PROCESS | 2251 | Values: 168.0 182.0 169.0 170.0 172.0 167.0 169.0 173.0 174.0 172.0 01:56:33 INFO - PROCESS | 2251 | 01:56:33 INFO - PROCESS | 2251 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.10 Median:72.00 stddev:8.53 (11.8%) stddev-sans-first:8.97 01:56:33 INFO - PROCESS | 2251 | Values: 70.0 82.0 88.0 84.0 74.0 65.0 65.0 66.0 68.0 69.0 01:56:33 INFO - PROCESS | 2251 | -------- Summary: end -------- 01:56:33 INFO - PROCESS | 2251 | 01:56:34 INFO - TEST-INFO | 2251: exit 0 01:56:34 INFO - TEST-OK | kraken | took 61838ms 01:56:34 INFO - SUITE-END | took 1177s 01:56:34 INFO - Completed test suite (00:19:37) 01:56:34 INFO - javascript benchmark 01:56:34 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7283.900735224854, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3287.934241315174, 3307.503874418838, 3317.5853018372704, 3322.903441864185, 3331.0506505645894, 5706.258186435763, 5784.421557844214, 5795.391483894188, 5810.680412577307, 5813.33146688131, 4697.67116301859, 5066.9493305066935, 5301.496357845998, 5585.469380408855, 5787.623804452718, 5193.9871725013545, 5215.843524694265, 5220.843374698765, 5272.077386457374, 5287.550558202538, 5269.130593452076, 5269.209618557209, 5272.209168624718, 5289.920651190216, 5304.549113325372, 5139.357580302462, 5170.819021334254, 5258.290130832346, 5261.8947621047555, 5316.0, 5212.192110222922, 5230.790768369261, 5265.341832270966, 5322.228276899867, 5370.489803468664, 4212.757236615206, 5166.664166829144, 5202.453742357074, 5218.425973142951, 5264.736763161826, 5761.798337058225, 5768.884622307531, 5852.707364631794, 5875.588708790387, 5891.410858914117, 5003.5997120230295, 6134.8773024539705, 6182.783602573888, 6191.226096737908, 6205.875882482325, 6221.688915554249, 6235.939890218674, 6248.1564988726395, 6249.406306400884, 6271.059341098826, 5909.172715819789, 5950.077737950624, 5975.43233392827, 5995.760169593167, 6034.306054803754, 6135.07955838608, 6136.54971344134, 6195.690215489298, 6225.844353891189, 6281.8743625127245, 5268.947310526923, 5443.265159203457, 6201.81394558164, 6204.906926396107, 6231.127642130105, 6233.844153896098, 6236.563440559117, 6256.874862502725, 6261.530385221126, 6270.843228919313, 6028.5478589106, 6107.461915314292, 6159.014557670751, 6255.906161407583, 6286.245650521967, 6172.814815555541, 6242.188515493048, 6248.31268560458, 6255.093011513305, 6291.842703932438, 5022.096022715946, 5029.547340739351, 5049.520295571915, 5051.873703157377, 5053.191489361684, 4391.978040109843, 4397.252467080604, 4497.078098989699, 4497.437820272466, 4499.1451624191295, 5702.657840529581, 5753.741081651336, 5754.079347304495, 5797.391273916262, 5838.2702162229725, 6176.4441200292185, 6238.064290356483, 6238.344973777688, 6244.0321750128805, 6280.05799130125, 5639.295088113986, 5699.601027928088, 5727.427257274323, 5747.25285712863, 5769.07694768835, 5459.481349271813, 5476.644018138808, 5500.697461639568, 5522.144067669517, 5557.360903496069, 6357.713902874381, 6363.1091647168505, 6364.140840986408, 6398.232212134573, 6414.518911081781, 6124.533510047256, 6186.721597528032, 6200.658963757037, 6231.501479881508, 6252.437280644764, 6307.211598550181, 6363.904541431974, 6403.96798016007, 6407.903881441689, 6419.64691941929, 5165.276861239505, 5746.252987111816, 6209.720562574786, 6213.316535181217, 6232.034034724625, 4936.7284799336385, 6056.454919057306, 6154.353792851686, 6202.81391558254, 6231.657258850807, 5246.3704355477585, 6077.997130473599, 6103.786367477117, 6111.0, 6147.0, 6057.666828324484, 6163.476104530986, 6182.041783523561, 6196.907046394217, 6210.596311239755, 4616.469106052847, 4959.528844759742, 5626.987142314422, 5718.170865224436, 6081.422264884829, 6019.826133903846, 6084.574079814371, 6104.938950610437, 6114.266288045463, 6173.166622505904, 6010.789622363196, 6029.246344206974, 6036.396360363929, 6051.152838602688, 6064.545159112996], "value": 5664.9326036314715, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [415.98128084236214, 427.9293916503777, 428.4087958617108, 428.6677824685868, 487.7512468640993, 40782.18435631286, 42008.15983680324, 42122.15755684885, 42138.789306053506, 42144.946376340566, 43325.0, 43834.56165438345, 43913.560864391424, 43926.560734392646, 43966.56033439664, 8408.663653453854, 9344.252459803216, 9549.665761698341, 9574.569144388484, 9612.423254604746, 8387.748367548953, 11258.4370781461, 11558.942205289004, 11581.652550423501, 11691.240069395504, 11446.599369022086, 11471.426428678533, 11484.36835974021, 11517.0, 11520.942395288012, 11452.942735286311, 11505.0, 11512.539498420054, 11524.59663911759, 11545.942270288595, 11369.488373023234, 11460.369679667614, 11461.197716159872, 11489.0, 11498.965093141658, 41358.79320603378, 41452.37821432681, 41628.37557436641, 41650.0, 41722.0, 4022.7787471689044, 4071.0636553592544, 4109.445224894661, 4157.168566286755, 4222.577742225752, 29911.70088299111, 31033.91381301666, 31052.689473105205, 31093.53359699606, 31526.369472610648, 2822.520171570838, 3216.3245718399166, 3222.0978126124724, 3343.7157841583494, 3344.966550334514, 366617.0, 369670.0, 370468.0, 371936.0, 371993.0, 66530.66734666296, 67030.32969670338, 67050.3294967044, 67080.32919670838, 67130.0, 60489.69755151284, 70605.29394705986, 70835.0, 70846.9372959406, 71042.64478677574, 16031.957922734984, 16505.257263423176, 16522.58693532647, 16530.338786448407, 16538.58653533671, 11348.262362946381, 11634.94182529082, 12334.259944403364, 12672.49310027607, 12764.680882977815, 18508.44474665762, 18774.87350758959, 18823.87056776598, 18829.90585047066, 18923.621527569372, 5842.941570584239, 6622.437092847065, 6784.525083244224, 6853.246142924274, 6880.621565813928, 876.1370050500379, 896.3994123936978, 896.8340856941356, 911.9133682300171, 929.3355250995571, 25258.36854078663, 30533.694663053528, 30917.536236956465, 31509.369812604076, 31523.0, 1034.7516596017051, 1068.9144868410508, 1090.9018188363086, 1117.1677100559973, 1196.0431654676224, 13391.330433478288, 15252.161131137895, 15737.737687714041, 16054.598635033984, 16244.02535847853, 235092.6490735105, 235863.6413635876, 236504.0, 236682.0, 237294.0, 50058.74970625196, 62183.0, 62358.688206559586, 62510.68744656248, 62519.06221406683, 1292.7220647560819, 1354.3160703844692, 1430.577979496051, 1446.2985452055746, 1446.8770154536771, 27193.0, 29292.267693307836, 30400.84799575988, 30578.541321880635, 31032.689673103883, 53120.203196951305, 53406.329683516444, 53511.46488535065, 53717.73141134269, 53723.46276537185, 553009.0, 556216.0, 557314.0, 558234.0, 558337.0, 36168.638313616524, 41120.1775964485, 41525.0, 42453.15093698171, 42677.5732242686, 44888.87777805581, 46550.53449465462, 47132.76433617947, 47307.0, 47596.810079748284, 35524.28951421009, 39282.607173929035, 39323.803380982914, 40444.94438195226, 40554.59445405626, 31716.524252136696, 32185.0, 32500.512492312162, 38861.0, 38879.41680874846, 37534.8123259382, 37876.431853521666, 38025.80987095047, 38046.61953380431, 38948.41577376398, 23587.52824943457, 31411.214719632193, 34634.826825866716, 34861.0, 34881.8255908729, 24962.62556061624, 25306.493870122864, 25562.48875022452, 25724.871375643, 26045.0], "value": 19888.60935159657, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [392.10795440373147, 393.6142580271336, 401.23364374045576, 401.9075612609099, 418.1850767668319, 15585.610359741013, 18388.816111838878, 19920.402387928367, 20950.6857397139, 21046.579068418643, 16267.91866040671, 19026.0, 20795.584088318225, 21211.893940530314, 21477.248296309634, 5610.719464026803, 5664.433556644344, 5874.441928016831, 5887.35239123696, 5917.437843404869, 5745.798897038604, 5833.300003999505, 5849.824505264849, 5854.121881717734, 5860.238169038019, 5755.45323194298, 6057.697115144225, 6114.816555503342, 6140.478059364937, 6153.569250152491, 6022.668753218593, 6034.456898879122, 6044.335123136451, 6198.4731297839735, 6655.500837437212, 5883.7058147092475, 5884.617499862538, 5891.587588868781, 5916.201312822757, 5917.940820591782, 12611.486621605374, 13136.60590182296, 13175.60473185806, 13524.729505409836, 13626.1143025704, 2632.842029478218, 2648.192301348088, 2728.9863550682317, 2730.7405796449307, 2744.1355972868682, 9977.75055623608, 9993.950030249802, 10110.545025473797, 10516.842247366296, 10548.31435956668, 1807.5571484986262, 1859.172668162668, 1860.804615515365, 1865.6082222733241, 1866.141574875559, 121799.3910030453, 125640.0, 126497.0, 128178.0, 130079.69920300771, 12158.452869620889, 12284.385780710929, 12359.752804943852, 12402.25586464815, 12420.316882571364, 13987.020908536508, 16012.359505619877, 16016.839831601535, 16039.278232479566, 16039.919800400923, 7846.254605812439, 8240.876386854203, 8426.0, 8527.957360213159, 8595.398322117391, 8220.506769593843, 8296.419250652396, 8340.29107525855, 8376.246137847624, 8399.454035487674, 4757.405324334458, 4848.224284114524, 4849.75751212438, 4876.731779752147, 4915.115279249698, 2033.4204751646005, 2072.0986370928695, 2079.1059844266806, 2079.105984426711, 2084.1975839301676, 904.1952662130709, 1050.5167622893555, 1054.1408751867205, 1055.931364461323, 1065.8720953485476, 21195.046222920006, 21565.67651485229, 21565.813880236423, 21571.02930368137, 21689.45776355572, 1024.5952848624752, 1069.8876617955002, 1075.2903083964545, 1077.9730506737237, 1086.8315411111291, 5965.612235204698, 8628.22345988864, 11276.436178191057, 11391.715207119889, 11448.313101213955, 756746.0, 768085.0, 768924.0, 774676.0, 779589.0, 159684.0, 165648.0, 166541.0, 170325.14837425982, 175213.4957300847, 440.7135362014716, 458.35143272269363, 461.5799622343718, 462.6506987224691, 463.693961985095, 11216.214864959375, 18150.636987260445, 18408.81591184071, 20081.497962551053, 20674.0, 51280.743596281776, 59825.70087149536, 60345.396546033975, 63696.68151659212, 64663.030054548275, 75010.0, 80076.78883071554, 81092.0, 81336.5933170354, 82987.5850620743, 6623.205215374186, 8316.83366332682, 8409.495430274203, 8702.434341767765, 8933.597988090682, 11246.83129753038, 11658.65024049246, 12446.377681115728, 12641.620751377106, 13058.738825223632, 10526.842097368393, 11510.13673974472, 11653.359065251154, 11704.94147529257, 11738.647840564796, 8912.86630700553, 11170.329780213213, 11260.930211629882, 11355.432228388383, 11586.667533233449, 9700.563474643517, 11054.557817687202, 11611.361375124448, 11677.474513647076, 11760.88239117598, 8606.913930860612, 8617.784555386166, 8649.04860465361, 8691.304695624209, 8791.560421979004, 8319.459235149696, 10383.844242336521, 11457.255278406878, 11606.70983225426, 11782.764344713229], "value": 9553.650416572173, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1332.0, 1455.3378212913133, 1465.7434948883943, 1469.8456662050485, 1490.8434614365494, 6636.53544251902, 7529.774106776806, 7803.18066603007, 8519.95740021299, 8555.187257210562, 8005.519668819875, 8252.216039476256, 8273.214044665761, 8364.874526882102, 8375.53934533602, 2263.004278117631, 2314.0743702519044, 2316.2124877541632, 2317.884105794712, 2322.8606283623035, 2069.1826728442256, 2151.8601290916117, 2322.036354912718, 2350.8354415190943, 2372.9288121356385, 2244.7642997485273, 2332.055517515409, 2334.2297041976108, 2337.0, 2340.8595484270913, 2281.2813963601498, 2316.733575638806, 2321.9071237150492, 2325.221050947943, 2338.9649155262687, 2324.6629238760283, 2336.7663233676667, 2352.2002519143402, 2371.4071482129466, 2380.0, 8288.171182881724, 8353.832923341499, 8379.37154713399, 8386.287165590933, 8400.117987611275, 5069.797208111671, 5217.0, 5236.345456817898, 5242.737863106868, 5247.186686063628, 3297.55483009793, 3480.843362048714, 3482.2687235680537, 3515.4726790981304, 3537.4163263061564, 1148.0930065248444, 1170.2861254634665, 1214.0469731260919, 1215.1615385384057, 1236.8701286364892, 10685.358878467317, 10712.678619641423, 10834.458277086114, 10874.510647020903, 10890.782184356269, 4560.247559152769, 4638.976805115953, 4643.024964757371, 4714.481407045222, 4763.476017638057, 9533.0, 9627.951860240793, 9627.989061148619, 9634.325597208128, 9640.71077867665, 5226.843194704165, 5233.0, 5234.842954711365, 5239.423663397023, 5256.185291279858, 4534.9319760203625, 4630.351750754897, 4718.504557021532, 4766.356541866873, 4776.164171270014, 3151.2909595340866, 3232.870685172614, 3733.1973625670066, 3782.6973842092566, 3827.6172382762056, 1071.978560428787, 1157.1957489544855, 1188.839506666589, 1227.4844565282604, 1282.429318953057, 782.6008735544913, 813.894193754808, 823.4812068396872, 937.9249660027318, 942.3356533643902, 5578.2748242728185, 5646.6047376684055, 5654.208410822488, 5702.54379649627, 5844.620099693507, 969.6460791810938, 983.5967253426061, 1003.7841863999129, 1008.4302369161401, 1011.6914341126027, 3778.9418962690493, 3853.344931361675, 3858.961410385916, 3885.611438856092, 3897.0, 9752.609895604239, 9994.700158995242, 10036.548355324028, 10040.347377420445, 10102.949485252526, 9984.650537231308, 9985.1512621428, 10006.299559030944, 10017.799644007078, 10027.0, 7491.812704682427, 7736.381089512825, 7878.330341920886, 7914.485558438683, 8033.076196237391, 5649.915251271152, 5717.914231286617, 5791.623544469596, 5795.275590551181, 5962.940370596412, 7529.887051694339, 7754.612269386395, 8876.245519130945, 9029.968257142353, 9120.0, 9027.593758281024, 9052.23056040244, 9232.676856309998, 9240.353175277665, 9281.62873485053, 7271.781846544613, 7308.378787803096, 7311.8903216450735, 7318.524295920748, 7334.193238743627, 7584.8483030340185, 7816.257455541714, 7869.803254918673, 7881.5665138417935, 7957.124716281089, 6957.095577574883, 7050.118735158106, 7133.857322853618, 7161.355478007004, 7198.20819709821, 7003.299670032956, 7059.223485416703, 7062.71749130029, 7102.751403700846, 7115.53749006313, 6453.935460645521, 6462.095306656977, 6515.511336649675, 6523.412892839668, 6540.640264785292, 4906.484819094087, 4963.950360496348, 4969.776360063734, 5085.669431486942, 5175.81884634036, 4953.380827396575, 4979.402471703419, 5077.441481436965, 5156.123459011902, 5232.633715639869], "value": 4420.294403267877, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [326.84786128895627, 357.37994579404744, 360.87189047888, 368.123865200822, 373.9439084137379, 4711.929321060178, 4817.181079216532, 5110.974445127774, 5349.0, 5611.494965453108, 5219.0, 5389.380221274555, 5453.863653408666, 5457.017736807373, 5476.698781567012, 4282.743035417877, 4293.29160688486, 4308.97845510772, 4309.340903752062, 4352.434183556134, 3811.00913762421, 3885.417187421895, 4066.7966601669946, 4079.4492743479695, 4330.0, 3586.282743451307, 3874.8062596870045, 4057.6753859691303, 4243.936340954888, 4244.172386384656, 3198.168476196194, 3456.913577160566, 4096.160287141159, 4250.383694364331, 4265.848220980349, 3765.7928813915223, 3782.3759079752085, 3794.658480736747, 3813.675837553811, 3845.1156234066043, 4615.676902616819, 5007.622903701475, 5288.365396152448, 5316.0962636351915, 5351.598630102758, 4197.664186865043, 4946.703197808143, 4950.504949505057, 4962.429320628102, 4968.2299243616935, 5131.358580177478, 5797.942020579782, 5822.330432000332, 5828.3297420796725, 5836.328822185461, 4029.879103626896, 4134.098314775207, 4206.74759514427, 4209.684273679486, 4300.913981720348, 6054.424829641177, 6058.000429929057, 6075.574709770318, 6112.327643959161, 6128.9693551531955, 4111.527174374985, 4261.083866968616, 4261.850835220815, 4265.722728022669, 4268.679849011337, 3699.6300369962787, 3754.5869954305003, 3763.7553559018575, 4099.200655872136, 4352.018867076063, 3910.1788624389196, 4760.214564596803, 4779.713217206979, 4784.784684689197, 4826.565609095128, 4901.779419926112, 5657.755293835349, 5821.912671309934, 5864.266966629171, 5942.64344139353, 1181.5805389086922, 1207.8646072691643, 1217.5008246618843, 1289.6066699656694, 1316.4997301025548, 797.452941293888, 858.6393714639867, 874.1215078845719, 875.7197696737111, 889.786451251695, 4623.56076172763, 4768.928466073012, 4861.708297502161, 5067.442581316052, 5117.777833287552, 4861.079873449968, 5473.972630136904, 5747.281589801275, 5766.88466230673, 5791.623544469596, 4329.935050974238, 4710.8351207707565, 4842.612590992782, 5049.9495005049475, 5206.843794686166, 2762.654668166479, 2905.7384835364496, 3021.54676798482, 3040.3767227718263, 3059.831709255968, 5961.880762384728, 5969.075153174913, 5973.940260597426, 5992.700364981733, 6010.54920880927, 4419.0719948810465, 5601.80393686219, 5842.676419449576, 5872.911906321408, 5895.4399332063385, 5561.499465048165, 5830.970845145747, 5841.970790146107, 5992.43071908168, 6111.463742546971, 5308.708021058879, 5396.325459317585, 5572.637778544382, 5693.772249110155, 5765.884682306247, 4810.903781924412, 4923.335349727741, 4942.975285123552, 5636.718164091697, 5708.65748055118, 5021.372328458942, 5901.940980590139, 5906.940930590639, 5928.733207005781, 5957.791477298274, 3468.809215493172, 3644.9817750911075, 3766.5291838520184, 3802.4676545284237, 3811.828467718904, 4679.695819771704, 4944.9368385797225, 5405.783768649368, 5737.02470579994, 5749.655020698771, 5007.098722230034, 5286.259923610774, 5484.341878974548, 5658.575606829422, 5752.597318187687, 4667.416572928384, 4755.429348478205, 5682.204491371129, 5754.3382511010095, 5765.827025189251, 4781.832635857728, 5623.0, 5636.774529018793, 5645.181448689999, 5694.800681976111, 5724.885502289848, 5766.59633825645, 5767.088734047635, 5771.0856017710785, 5813.709314534171, 4264.04059086703, 5023.8492845214705, 5577.0, 5622.746976385991, 5632.422921581963], "value": 4159.443102457617, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [712.1809918593617, 766.344775217189, 787.9763607091786, 852.8464876322263, 868.1188593577518, 14725.410983560658, 15375.999999999985, 15688.450904218354, 15837.920810395946, 15939.760903586426, 14177.521055600773, 14272.072315299523, 14325.140491570486, 14400.423983040666, 14446.638834029154, 691.332863786446, 696.303696303695, 865.8917635295588, 870.3559366069095, 871.1375738019346, 710.2932582080837, 850.1286181663783, 868.8653258744905, 873.7509809704236, 881.6870011146048, 924.6440120553568, 929.251952178496, 933.9159475647191, 936.2930987104727, 938.943663380196, 913.9360244782868, 917.655879045358, 927.9118483744033, 929.3123088914189, 929.5677509957868, 691.9861602767967, 694.9965250173767, 696.140266770538, 698.3435570563705, 698.7903628911357, 12821.935890320581, 14740.926295368561, 15210.391584336614, 15271.541853744406, 15363.15502647354, 11424.485898134604, 12397.566085187065, 13911.86088139126, 14334.068285561509, 14391.13653180802, 11658.125640576905, 11952.64142075739, 11973.102017348734, 12008.63974080779, 12175.756484870342, 3659.1583935694675, 3675.5773086094905, 3709.7217708671687, 3866.478025466582, 3877.7673339599714, 89461.0, 95563.56654650255, 109610.45194774056, 111869.0, 113874.43062784716, 11062.502187401586, 11206.551737930391, 11224.607138750105, 11231.719207019727, 11405.600803971822, 22349.638774075353, 22456.88771556132, 22599.661005084938, 22651.43371415695, 22667.546649067255, 7683.0, 7747.302742753067, 7779.844403112019, 7782.533048017137, 7941.404394670423, 4321.395004699345, 4331.371951067078, 4381.715188512737, 4434.935615452333, 4459.643228541709, 3555.5555555555557, 3645.635436456333, 3665.7617254878883, 3774.698024158116, 3965.960340396559, 700.7792545348199, 702.6978399288253, 705.6471764117941, 709.2234003765972, 721.7618185998608, 566.5552541255095, 591.2757071002658, 623.3890787028647, 683.1665368250726, 716.9032180655649, 10227.232957528215, 10247.623588307268, 10320.277580569438, 10485.26603137773, 10656.520456579472, 582.8280657206134, 588.932845711547, 597.9804433440972, 612.5558969746987, 616.8982117950488, 5003.54968052877, 5142.948570514322, 5186.740662966837, 5189.636725429184, 5210.869728256748, 77384.0, 92788.0, 99731.0, 104092.0, 105911.47044264729, 81949.0, 83307.83460826935, 83450.58274708588, 87496.0, 89019.55490222508, 56375.71812140913, 73164.63417682878, 76349.61825190838, 77008.61495692485, 77196.0, 8515.74452766418, 10971.0, 11170.664880053611, 11240.325580465198, 11297.322160670386, 47382.81542961454, 48302.75848620734, 48519.272210916155, 49074.75462622664, 49609.50390496193, 52280.47719522756, 53866.03736794514, 62475.68762156342, 62529.06206406817, 64770.676146618964, 12824.743505130033, 13142.605721827978, 13252.271125087827, 13279.867201327863, 13479.19124852512, 14501.709965800414, 14715.705685886009, 14829.555113346616, 15125.39498420051, 15623.453179138676, 13308.800367994294, 13894.791578126134, 14000.929995349958, 14588.854111459174, 14630.853691463375, 13856.168629882239, 13925.791113133513, 14365.281735913371, 14485.493007744679, 14523.709525809214, 13496.257705825881, 13617.25105119228, 13809.171449713049, 13892.583222503341, 14355.210463424612, 11185.087871502064, 11776.293422394683, 11868.465919033837, 11926.0, 12009.81985270239, 11018.614348497764, 11159.888401115884, 11300.94349528247, 11315.151363647925, 11337.829932551183], "value": 7545.409176203179, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1598.4999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 126.0, 164.0, 117.0, 119.0, 167.0, 147.0, 123.0, 170.0, 120.0], "value": 136.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 111.0, 111.0, 144.0, 123.0, 146.0, 97.0, 171.0, 139.0, 142.0], "value": 132.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 200.0, 195.0, 199.0, 202.0, 200.0, 198.0, 200.0, 208.0, 195.0], "value": 199.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 60.0, 59.0, 90.0, 60.0, 65.0, 85.0, 50.0, 86.0, 51.0], "value": 69.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 101.0, 119.0, 73.0, 59.0, 85.0, 116.0, 58.0, 94.0, 115.0], "value": 91.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 89.0, 91.0, 108.0, 133.0, 132.0, 96.0, 102.0, 136.0, 87.0], "value": 106.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 102.0, 171.0, 103.0, 170.0, 114.0, 170.0, 160.0, 102.0, 150.0], "value": 135.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 108.0, 128.0, 92.0, 93.0, 92.0, 121.0, 94.0, 146.0, 79.0], "value": 104.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 66.0, 62.0, 61.0, 64.0, 58.0, 62.0, 66.0, 53.0, 57.0], "value": 60.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 70.0, 72.0, 79.0, 78.0, 71.0, 71.0, 88.0, 111.0, 88.0], "value": 80.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 87.0, 89.0, 86.0, 85.0, 92.0, 90.0, 85.0, 85.0], "value": 86.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 149.0, 150.0, 142.0, 143.0, 137.0, 135.0, 155.0, 191.0, 154.0], "value": 149.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 182.0, 169.0, 170.0, 172.0, 167.0, 169.0, 173.0, 174.0, 172.0], "value": 171.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 82.0, 88.0, 84.0, 74.0, 65.0, 65.0, 66.0, 68.0, 69.0], "value": 73.1, "unit": "score"}]}]} 01:56:34 INFO - Return code: 0 01:56:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 01:56:34 ERROR - # TBPL SUCCESS # 01:56:34 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:56:34 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:56:34 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:56:34 INFO - 2017-01-25 01:56:34.909 screenresolution[2256:22645] starting screenresolution argv=screenresolution get 01:56:34 INFO - 2017-01-25 01:56:34.912 screenresolution[2256:22645] Display 0: 1600x1200x32@60 01:56:34 INFO - 2017-01-25 01:56:34.918 screenresolution[2257:22648] starting screenresolution argv=screenresolution list 01:56:34 INFO - Available Modes on Display 0 01:56:34 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:56:34 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:56:34 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:56:34 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:56:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:56:35 INFO - Intel Iris: 01:56:35 INFO - Chipset Model: Intel Iris 01:56:35 INFO - Type: GPU 01:56:35 INFO - Bus: Built-In 01:56:35 INFO - VRAM (Dynamic, Max): 1536 MB 01:56:35 INFO - Vendor: Intel (0x8086) 01:56:35 INFO - Device ID: 0x0a2e 01:56:35 INFO - Revision ID: 0x0009 01:56:35 INFO - Displays: 01:56:35 INFO - Display: 01:56:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:56:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:56:35 INFO - Display Serial Number: 1600x1200 60 01:56:35 INFO - Main Display: Yes 01:56:35 INFO - Mirror: Off 01:56:35 INFO - Online: Yes 01:56:35 INFO - Rotation: Supported 01:56:35 INFO - Return code: 0 01:56:35 INFO - Running post-action listener: _resource_record_post_action 01:56:35 INFO - [mozharness: 2017-01-25 09:56:35.153586Z] Finished run-tests step (success) 01:56:35 INFO - Running post-run listener: _resource_record_post_run 01:56:35 INFO - Total resource usage - Wall time: 1198s; CPU: 54.0%; Read bytes: 1155072; Write bytes: 510424576; Read time: 10; Write time: 1624 01:56:35 INFO - TinderboxPrint: CPU usage
54.4% 01:56:35 INFO - TinderboxPrint: I/O read bytes / time
1,155,072 / 10 01:56:35 INFO - TinderboxPrint: I/O write bytes / time
510,424,576 / 1,624 01:56:35 INFO - TinderboxPrint: CPU idle
2,186.7 (45.6%) 01:56:35 INFO - TinderboxPrint: CPU system
967.7 (20.2%) 01:56:35 INFO - TinderboxPrint: CPU user
1,639.3 (34.2%) 01:56:35 INFO - TinderboxPrint: Swap in / out
208,076,800 / 0 01:56:35 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 202881536; Write bytes: 200509440; Read time: 15675; Write time: 451 01:56:35 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 1155072; Write bytes: 299191808; Read time: 10; Write time: 1139 01:56:35 INFO - Running post-run listener: _upload_blobber_files 01:56:35 INFO - Blob upload gear active. 01:56:35 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 01:56:35 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 01:56:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 01:56:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 01:56:36 INFO - (blobuploader) - INFO - Open directory for files ... 01:56:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 01:56:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:56:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:56:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 01:56:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:56:36 INFO - (blobuploader) - INFO - Done attempting. 01:56:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 01:56:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:56:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:56:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 01:56:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:56:37 INFO - (blobuploader) - INFO - Done attempting. 01:56:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 01:56:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:56:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:56:38 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 01:56:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:56:38 INFO - (blobuploader) - INFO - Done attempting. 01:56:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 01:56:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:56:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:56:40 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 01:56:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:56:40 INFO - (blobuploader) - INFO - Done attempting. 01:56:40 INFO - (blobuploader) - INFO - Iteration through files over. 01:56:40 INFO - Return code: 0 01:56:40 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 01:56:40 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 01:56:40 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/32b8e13b822f40e4f26fe8f979e071d1ec2cc51f1a27985b499bb997b8b8a2e171b2a46cae3d4fceb7927f9ec6c63a83f5cee78c802a0af0ab2b5e55f9bab236", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/525e8d4190339b12fc008197533cf74735a05a67b19197b6d6507c469298fea99ad13224f387a0b676317f94a77534436c2f05d67552962776bacf7b470d5a00", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/674167583637574700602a7c167c04e7bdf438259ed79ec572d0d127c973d82568af4646db98402053a445d0ba243e93e5850850cbf115d5c20a8e3fef8dcf22", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/57f28450f04ba6bb72468e3bade993425fcb37c0cbb22be931d8243da43fc559e48c96e18921c4d2e534e4a860ca3beee1f9cbe034dc6d561b8577471dea6f21"} 01:56:40 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 01:56:40 INFO - Writing to file /builds/slave/test/properties/blobber_files 01:56:40 INFO - Contents: 01:56:40 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/32b8e13b822f40e4f26fe8f979e071d1ec2cc51f1a27985b499bb997b8b8a2e171b2a46cae3d4fceb7927f9ec6c63a83f5cee78c802a0af0ab2b5e55f9bab236", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/525e8d4190339b12fc008197533cf74735a05a67b19197b6d6507c469298fea99ad13224f387a0b676317f94a77534436c2f05d67552962776bacf7b470d5a00", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/674167583637574700602a7c167c04e7bdf438259ed79ec572d0d127c973d82568af4646db98402053a445d0ba243e93e5850850cbf115d5c20a8e3fef8dcf22", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/57f28450f04ba6bb72468e3bade993425fcb37c0cbb22be931d8243da43fc559e48c96e18921c4d2e534e4a860ca3beee1f9cbe034dc6d561b8577471dea6f21"} 01:56:40 INFO - Running post-run listener: copy_logs_to_upload_dir 01:56:40 INFO - Copying logs to upload dir... 01:56:40 INFO - mkdir: /builds/slave/test/build/upload/logs 01:56:40 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1260.020472 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2017-01-25 01:56:41.044719) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-25 01:56:41.048856) ========= 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.EIhn4L4aOD/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.QJagvaKtwu/Listeners TMPDIR=/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/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/Mozilla-Aurora/sha512/32b8e13b822f40e4f26fe8f979e071d1ec2cc51f1a27985b499bb997b8b8a2e171b2a46cae3d4fceb7927f9ec6c63a83f5cee78c802a0af0ab2b5e55f9bab236", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/525e8d4190339b12fc008197533cf74735a05a67b19197b6d6507c469298fea99ad13224f387a0b676317f94a77534436c2f05d67552962776bacf7b470d5a00", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/674167583637574700602a7c167c04e7bdf438259ed79ec572d0d127c973d82568af4646db98402053a445d0ba243e93e5850850cbf115d5c20a8e3fef8dcf22", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/57f28450f04ba6bb72468e3bade993425fcb37c0cbb22be931d8243da43fc559e48c96e18921c4d2e534e4a860ca3beee1f9cbe034dc6d561b8577471dea6f21"} build_url:https://queue.taskcluster.net/v1/task/FTt4EiIhRjKSVdgkr4R-vw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011471 build_url: 'https://queue.taskcluster.net/v1/task/FTt4EiIhRjKSVdgkr4R-vw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/32b8e13b822f40e4f26fe8f979e071d1ec2cc51f1a27985b499bb997b8b8a2e171b2a46cae3d4fceb7927f9ec6c63a83f5cee78c802a0af0ab2b5e55f9bab236", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/525e8d4190339b12fc008197533cf74735a05a67b19197b6d6507c469298fea99ad13224f387a0b676317f94a77534436c2f05d67552962776bacf7b470d5a00", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/674167583637574700602a7c167c04e7bdf438259ed79ec572d0d127c973d82568af4646db98402053a445d0ba243e93e5850850cbf115d5c20a8e3fef8dcf22", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/57f28450f04ba6bb72468e3bade993425fcb37c0cbb22be931d8243da43fc559e48c96e18921c4d2e534e4a860ca3beee1f9cbe034dc6d561b8577471dea6f21"}' ========= master_lag: 0.07 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-25 01:56:41.132721) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-25 01:56:41.133060) ========= 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.EIhn4L4aOD/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.QJagvaKtwu/Listeners TMPDIR=/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/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.005210 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-25 01:56:41.159863) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-25 01:56:41.160184) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-25 01:56:41.160550) ========= ========= Total master_lag: 0.44 =========