builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0298 starttime: 1485376489.51 results: success (0) buildid: 20170125112431 builduid: 04e11c033e5b46a9847b2c7df93bb13b revision: f3a3afaf7e41b6ea973e97b00528f6d58bdb08cb ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-25 12:34:49.506865) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-25 12:34:49.507329) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-25 12:34:49.507611) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-25 12:34:49.539879) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-25 12:34:49.540196) ========= 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.IEPyEBJPGu/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.2BI05gQGvJ/Listeners TMPDIR=/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 12:34:49-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 155M=0s 2017-01-25 12:34:49 (155 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.374395 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-25 12:34:49.938249) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-25 12:34:49.938601) ========= 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.IEPyEBJPGu/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.2BI05gQGvJ/Listeners TMPDIR=/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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.021343 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-25 12:34:49.989106) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-25 12:34:49.992472) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev f3a3afaf7e41b6ea973e97b00528f6d58bdb08cb --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 f3a3afaf7e41b6ea973e97b00528f6d58bdb08cb --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.IEPyEBJPGu/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.2BI05gQGvJ/Listeners TMPDIR=/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 12:34:50,095 truncating revision to first 12 chars 2017-01-25 12:34:50,095 Setting DEBUG logging. 2017-01-25 12:34:50,095 attempt 1/10 2017-01-25 12:34:50,095 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/f3a3afaf7e41?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-25 12:34:51,172 unpacking tar archive at: mozilla-aurora-f3a3afaf7e41/testing/mozharness/ program finished with exit code 0 elapsedTime=1.348907 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-25 12:34:51.365487) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-25 12:34:51.365765) ========= script_repo_revision: f3a3afaf7e41b6ea973e97b00528f6d58bdb08cb ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-25 12:34:51.366135) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-25 12:34:51.366441) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-25 12:34:51.387233) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2017-01-25 12:34:51.387554) ========= /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.IEPyEBJPGu/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.2BI05gQGvJ/Listeners TMPDIR=/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 12:34:51 INFO - MultiFileLogger online at 20170125 12:34:51 in /builds/slave/test 12:34:51 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 12:34:51 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:34:51 INFO - {'append_to_log': False, 12:34:51 INFO - 'base_work_dir': '/builds/slave/test', 12:34:51 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 12:34:51 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:34:51 INFO - 'branch': 'Mozilla-Aurora', 12:34:51 INFO - 'buildbot_json_path': 'buildprops.json', 12:34:51 INFO - 'config_files': ('talos/mac_config.py',), 12:34:51 INFO - 'default_actions': ('clobber', 12:34:51 INFO - 'read-buildbot-config', 12:34:51 INFO - 'download-and-extract', 12:34:51 INFO - 'populate-webroot', 12:34:51 INFO - 'create-virtualenv', 12:34:51 INFO - 'install', 12:34:51 INFO - 'run-tests'), 12:34:51 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:34:51 INFO - 'download_minidump_stackwalk': True, 12:34:51 INFO - 'download_symbols': 'ondemand', 12:34:51 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:34:51 INFO - 'tooltool.py': '/tools/tooltool.py', 12:34:51 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:34:51 INFO - '/tools/misc-python/virtualenv.py')}, 12:34:51 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:34:51 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:34:51 INFO - 'installer_path': 'installer.exe', 12:34:51 INFO - 'log_level': 'info', 12:34:51 INFO - 'log_name': 'talos', 12:34:51 INFO - 'log_to_console': True, 12:34:51 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:34:51 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:34:51 INFO - 'opt_config_files': (), 12:34:51 INFO - 'pip_index': False, 12:34:51 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:34:51 INFO - 'cmd': ('bash', 12:34:51 INFO - '-c', 12:34:51 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:34:51 INFO - 'enabled': True, 12:34:51 INFO - 'halt_on_failure': False, 12:34:51 INFO - 'name': 'check_screen_resolution'},), 12:34:51 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:34:51 INFO - 'cmd': ('bash', 12:34:51 INFO - '-c', 12:34:51 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:34:51 INFO - 'enabled': True, 12:34:51 INFO - 'halt_on_failure': False, 12:34:51 INFO - 'name': 'check_screen_resolution'},), 12:34:51 INFO - 'run_cmd_checks_enabled': True, 12:34:51 INFO - 'sps_profile': False, 12:34:51 INFO - 'sps_profile_interval': 0, 12:34:51 INFO - 'suite': 'dromaeojs-e10s', 12:34:51 INFO - 'system_bits': '32', 12:34:51 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:34:51 INFO - 'title': 't-yosemite-r7-0298', 12:34:51 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:34:51 INFO - 'use_talos_json': True, 12:34:51 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:34:51 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:34:51 INFO - 'work_dir': 'build'} 12:34:51 INFO - [mozharness: 2017-01-25 20:34:51.607406Z] Running clobber step. 12:34:51 INFO - Running pre-action listener: _resource_record_pre_action 12:34:51 INFO - Running main action method: clobber 12:34:51 INFO - rmtree: /builds/slave/test/build 12:34:51 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:34:52 INFO - Running post-action listener: _resource_record_post_action 12:34:52 INFO - [mozharness: 2017-01-25 20:34:52.591269Z] Finished clobber step (success) 12:34:52 INFO - [mozharness: 2017-01-25 20:34:52.591459Z] Running read-buildbot-config step. 12:34:52 INFO - Running pre-action listener: _resource_record_pre_action 12:34:52 INFO - Running main action method: read_buildbot_config 12:34:52 INFO - Using buildbot properties: 12:34:52 INFO - { 12:34:52 INFO - "project": "", 12:34:52 INFO - "product": "firefox", 12:34:52 INFO - "script_repo_revision": "production", 12:34:52 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 12:34:52 INFO - "repository": "", 12:34:52 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 12:34:52 INFO - "buildid": "20170125112431", 12:34:52 INFO - "pgo_build": "False", 12:34:52 INFO - "basedir": "/builds/slave/test", 12:34:52 INFO - "buildnumber": 35, 12:34:52 INFO - "slavename": "t-yosemite-r7-0298", 12:34:52 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 12:34:52 INFO - "platform": "yosemite_r7", 12:34:52 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 12:34:52 INFO - "branch": "mozilla-aurora", 12:34:52 INFO - "repo_path": "releases/mozilla-aurora", 12:34:52 INFO - "revision": "f3a3afaf7e41b6ea973e97b00528f6d58bdb08cb", 12:34:52 INFO - "stage_platform": "macosx64", 12:34:52 INFO - "builduid": "04e11c033e5b46a9847b2c7df93bb13b", 12:34:52 INFO - "slavebuilddir": "test" 12:34:52 INFO - } 12:34:52 INFO - Found installer url https://queue.taskcluster.net/v1/task/fDe9cMOyQlynK_zBjgF6DA/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg. 12:34:52 INFO - Running post-action listener: _resource_record_post_action 12:34:52 INFO - [mozharness: 2017-01-25 20:34:52.597053Z] Finished read-buildbot-config step (success) 12:34:52 INFO - [mozharness: 2017-01-25 20:34:52.597245Z] Running download-and-extract step. 12:34:52 INFO - Running pre-action listener: _resource_record_pre_action 12:34:52 INFO - Running main action method: download_and_extract 12:34:52 INFO - mkdir: /builds/slave/test/build/tests 12:34:52 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')]} 12:34:52 INFO - trying https://queue.taskcluster.net/v1/task/fDe9cMOyQlynK_zBjgF6DA/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json 12:34:52 INFO - Downloading https://queue.taskcluster.net/v1/task/fDe9cMOyQlynK_zBjgF6DA/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 12:34:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fDe9cMOyQlynK_zBjgF6DA/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 12:34:53 INFO - Downloaded 1199 bytes. 12:34:53 INFO - Reading from file /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 12:34:53 INFO - Using the following test package requirements: 12:34:53 INFO - {u'common': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 12:34:53 INFO - u'cppunittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 12:34:53 INFO - u'firefox-53.0a2.en-US.mac.cppunittest.tests.zip'], 12:34:53 INFO - u'gtest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 12:34:53 INFO - u'firefox-53.0a2.en-US.mac.gtest.tests.zip'], 12:34:53 INFO - u'jittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 12:34:53 INFO - u'jsshell-mac.zip'], 12:34:53 INFO - u'mochitest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 12:34:53 INFO - u'firefox-53.0a2.en-US.mac.mochitest.tests.zip'], 12:34:53 INFO - u'mozbase': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 12:34:53 INFO - u'reftest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 12:34:53 INFO - u'firefox-53.0a2.en-US.mac.reftest.tests.zip'], 12:34:53 INFO - u'talos': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 12:34:53 INFO - u'firefox-53.0a2.en-US.mac.talos.tests.zip'], 12:34:53 INFO - u'web-platform': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 12:34:53 INFO - u'firefox-53.0a2.en-US.mac.web-platform.tests.zip'], 12:34:53 INFO - u'xpcshell': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 12:34:53 INFO - u'firefox-53.0a2.en-US.mac.xpcshell.tests.zip']} 12:34:53 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip'] for test suite category: common 12:34:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fDe9cMOyQlynK_zBjgF6DA/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 12:34:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fDe9cMOyQlynK_zBjgF6DA/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 12:34:53 INFO - Fetch https://queue.taskcluster.net/v1/task/fDe9cMOyQlynK_zBjgF6DA/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 12:34:54 INFO - Expected file size: 16349609 12:34:54 INFO - Obtained file size: 16349609 12:35:00 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 12:35:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fDe9cMOyQlynK_zBjgF6DA/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 12:35:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fDe9cMOyQlynK_zBjgF6DA/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 12:35:00 INFO - Fetch https://queue.taskcluster.net/v1/task/fDe9cMOyQlynK_zBjgF6DA/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 12:35:01 INFO - Expected file size: 16349609 12:35:01 INFO - Obtained file size: 16349609 12:35:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fDe9cMOyQlynK_zBjgF6DA/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip 12:35:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fDe9cMOyQlynK_zBjgF6DA/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip'}, attempt #1 12:35:08 INFO - Fetch https://queue.taskcluster.net/v1/task/fDe9cMOyQlynK_zBjgF6DA/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip into memory 12:35:09 INFO - Expected file size: 14403485 12:35:09 INFO - Obtained file size: 14403485 12:35:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:35:09 INFO - trying https://queue.taskcluster.net/v1/task/fDe9cMOyQlynK_zBjgF6DA/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 12:35:09 INFO - Downloading https://queue.taskcluster.net/v1/task/fDe9cMOyQlynK_zBjgF6DA/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg 12:35:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fDe9cMOyQlynK_zBjgF6DA/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 12:35:11 INFO - Downloaded 73888155 bytes. 12:35:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fDe9cMOyQlynK_zBjgF6DA/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 12:35:11 INFO - mkdir: /builds/slave/test/properties 12:35:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:35:11 INFO - Writing to file /builds/slave/test/properties/build_url 12:35:11 INFO - Contents: 12:35:11 INFO - build_url:https://queue.taskcluster.net/v1/task/fDe9cMOyQlynK_zBjgF6DA/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 12:35:12 INFO - Running post-action listener: _resource_record_post_action 12:35:12 INFO - Running post-action listener: set_extra_try_arguments 12:35:12 INFO - [mozharness: 2017-01-25 20:35:12.147500Z] Finished download-and-extract step (success) 12:35:12 INFO - [mozharness: 2017-01-25 20:35:12.147635Z] Running populate-webroot step. 12:35:12 INFO - Running pre-action listener: _resource_record_pre_action 12:35:12 INFO - Running main action method: populate_webroot 12:35:12 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:35:12 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:35:12 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:35:12 INFO - '--tppagecycles', 12:35:12 INFO - '7'], 12:35:12 INFO - 'tests': ['tsvgm']}}, 12:35:12 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:35:12 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:35:12 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:35:12 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:35:12 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:35:12 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:35:12 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 12:35:12 INFO - 'talos_options': ['--disable-e10s'], 12:35:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:35:12 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:35:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:35:12 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 12:35:12 INFO - 'talos_options': ['--disable-e10s'], 12:35:12 INFO - 'tests': ['damp', 'tps']}, 12:35:12 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:35:12 INFO - 'tests': ['damp', 'tps']}, 12:35:12 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:35:12 INFO - 'tests': ['dromaeo_dom']}, 12:35:12 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:35:12 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:35:12 INFO - 'tests': ['basic_compositor_video']}, 12:35:12 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:35:12 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:35:12 INFO - 'tests': ['a11yr', 12:35:12 INFO - 'ts_paint', 12:35:12 INFO - 'tpaint', 12:35:12 INFO - 'sessionrestore', 12:35:12 INFO - 'sessionrestore_no_auto_restore', 12:35:12 INFO - 'tabpaint']}, 12:35:12 INFO - 'other-e10s': {'tests': ['a11yr', 12:35:12 INFO - 'ts_paint', 12:35:12 INFO - 'tpaint', 12:35:12 INFO - 'sessionrestore', 12:35:12 INFO - 'sessionrestore_no_auto_restore', 12:35:12 INFO - 'tabpaint']}, 12:35:12 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:35:12 INFO - 'ts_paint', 12:35:12 INFO - 'tpaint', 12:35:12 INFO - 'sessionrestore', 12:35:12 INFO - 'sessionrestore_no_auto_restore', 12:35:12 INFO - 'tabpaint']}, 12:35:12 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:35:12 INFO - 'ts_paint', 12:35:12 INFO - 'tpaint', 12:35:12 INFO - 'sessionrestore', 12:35:12 INFO - 'sessionrestore_no_auto_restore', 12:35:12 INFO - 'tabpaint']}, 12:35:12 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:35:12 INFO - 'tests': ['a11yr', 12:35:12 INFO - 'ts_paint', 12:35:12 INFO - 'tpaint', 12:35:12 INFO - 'sessionrestore', 12:35:12 INFO - 'sessionrestore_no_auto_restore', 12:35:12 INFO - 'tabpaint']}, 12:35:12 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:35:12 INFO - 'tests': ['a11yr', 12:35:12 INFO - 'ts_paint', 12:35:12 INFO - 'tpaint', 12:35:12 INFO - 'sessionrestore', 12:35:12 INFO - 'sessionrestore_no_auto_restore', 12:35:12 INFO - 'tabpaint']}, 12:35:12 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:35:12 INFO - 'tests': ['tsvgx', 12:35:12 INFO - 'tsvgr_opacity', 12:35:12 INFO - 'tart', 12:35:12 INFO - 'tscrollx', 12:35:12 INFO - 'cart', 12:35:12 INFO - 'tsvg_static']}, 12:35:12 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:35:12 INFO - 'tsvgr_opacity', 12:35:12 INFO - 'tart', 12:35:12 INFO - 'tscrollx', 12:35:12 INFO - 'cart', 12:35:12 INFO - 'tsvg_static']}, 12:35:12 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 12:35:12 INFO - 'talos_options': ['--disable-e10s'], 12:35:12 INFO - 'tests': ['tp5o']}, 12:35:12 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:35:12 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 12:35:12 INFO - 'talos_options': ['--disable-e10s', 12:35:12 INFO - '--xperf_path', 12:35:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:35:12 INFO - 'tests': ['tp5n']}, 12:35:12 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:35:12 INFO - 'talos_options': ['--xperf_path', 12:35:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:35:12 INFO - 'tests': ['tp5n']}}, 12:35:12 INFO - 'talos.zip': {'path': '', 12:35:12 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:35:12 INFO - Running post-action listener: _resource_record_post_action 12:35:12 INFO - [mozharness: 2017-01-25 20:35:12.166146Z] Finished populate-webroot step (success) 12:35:12 INFO - [mozharness: 2017-01-25 20:35:12.166281Z] Running create-virtualenv step. 12:35:12 INFO - Running pre-action listener: _resource_record_pre_action 12:35:12 INFO - Running main action method: create_virtualenv 12:35:12 INFO - Creating virtualenv /builds/slave/test/build/venv 12:35:12 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 12:35:12 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:35:12 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:35:12 INFO - Using real prefix '/tools/python27' 12:35:12 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:35:13 INFO - Installing distribute.............................................................................................................................................................................................done. 12:35:15 INFO - Installing pip.................done. 12:35:15 INFO - Return code: 0 12:35:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:35:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:35:16 INFO - Reading from file tmpfile_stdout 12:35:16 INFO - Output received: 12:35:16 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) 12:35:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:35:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:35:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:35:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:35:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:35:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:35:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:35:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106879b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10687c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb3a499660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106657d30>, '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 0x10657edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10664cbe8>, '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.IEPyEBJPGu/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.2BI05gQGvJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 12:35:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 12:35:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 12:35:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IEPyEBJPGu/Render', 12:35:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:35:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:35:16 INFO - 'HOME': '/Users/cltbld', 12:35:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:35:16 INFO - 'LOGNAME': 'cltbld', 12:35:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:35:16 INFO - 'MOZ_NO_REMOTE': '1', 12:35:16 INFO - 'NO_EM_RESTART': '1', 12:35:16 INFO - 'PAGER': '/bin/cat', 12:35:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:35:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:35:16 INFO - 'PWD': '/builds/slave/test', 12:35:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:35:16 INFO - 'SHELL': '/bin/bash', 12:35:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2BI05gQGvJ/Listeners', 12:35:16 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 12:35:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:35:16 INFO - 'USER': 'cltbld', 12:35:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:35:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:35:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:35:16 INFO - 'XPC_FLAGS': '0x0', 12:35:16 INFO - 'XPC_SERVICE_NAME': '0', 12:35:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:35:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:35:16 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 12:35:16 INFO - Cleaning up... 12:35:16 INFO - Return code: 0 12:35:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:35:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:35:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:35:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:35:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:35:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:35:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:35:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x106879b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10687c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb3a499660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106657d30>, '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 0x10657edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10664cbe8>, '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.IEPyEBJPGu/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.2BI05gQGvJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 12:35:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 12:35:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 12:35:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IEPyEBJPGu/Render', 12:35:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:35:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:35:16 INFO - 'HOME': '/Users/cltbld', 12:35:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:35:16 INFO - 'LOGNAME': 'cltbld', 12:35:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:35:16 INFO - 'MOZ_NO_REMOTE': '1', 12:35:16 INFO - 'NO_EM_RESTART': '1', 12:35:16 INFO - 'PAGER': '/bin/cat', 12:35:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:35:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:35:16 INFO - 'PWD': '/builds/slave/test', 12:35:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:35:16 INFO - 'SHELL': '/bin/bash', 12:35:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2BI05gQGvJ/Listeners', 12:35:16 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 12:35:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:35:16 INFO - 'USER': 'cltbld', 12:35:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:35:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:35:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:35:16 INFO - 'XPC_FLAGS': '0x0', 12:35:16 INFO - 'XPC_SERVICE_NAME': '0', 12:35:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:35:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:35:16 INFO - Downloading/unpacking psutil>=3.1.1 12:35:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:35:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:35:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:35:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:35:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:35:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:35:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:35:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:35:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:35:20 INFO - Installing collected packages: psutil 12:35:20 INFO - Running setup.py install for psutil 12:35:20 INFO - building 'psutil._psutil_osx' extension 12:35:20 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 12:35:20 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 12:35:20 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 12:35:20 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 12:35:20 INFO - building 'psutil._psutil_posix' extension 12:35:20 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 12:35:21 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:35:21 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:35:21 INFO - ^ 12:35:21 INFO - 1 warning generated. 12:35:21 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 12:35:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:35:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:35:21 INFO - Successfully installed psutil 12:35:21 INFO - Cleaning up... 12:35:21 INFO - Return code: 0 12:35:21 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:35:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:35:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:35:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:35:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:35:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:35:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:35:21 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 0x106879b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10687c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb3a499660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106657d30>, '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 0x10657edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10664cbe8>, '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.IEPyEBJPGu/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.2BI05gQGvJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 12:35:21 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 12:35:21 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 12:35:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IEPyEBJPGu/Render', 12:35:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:35:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:35:21 INFO - 'HOME': '/Users/cltbld', 12:35:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:35:21 INFO - 'LOGNAME': 'cltbld', 12:35:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:35:21 INFO - 'MOZ_NO_REMOTE': '1', 12:35:21 INFO - 'NO_EM_RESTART': '1', 12:35:21 INFO - 'PAGER': '/bin/cat', 12:35:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:35:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:35:21 INFO - 'PWD': '/builds/slave/test', 12:35:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:35:21 INFO - 'SHELL': '/bin/bash', 12:35:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2BI05gQGvJ/Listeners', 12:35:21 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 12:35:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:35:21 INFO - 'USER': 'cltbld', 12:35:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:35:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:35:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:35:21 INFO - 'XPC_FLAGS': '0x0', 12:35:21 INFO - 'XPC_SERVICE_NAME': '0', 12:35:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:35:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:35:21 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:35: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 12:35: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 12:35: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 12:35: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 12:35: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 12:35: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 12:35:24 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:35:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:35:24 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:35:24 INFO - Installing collected packages: mozsystemmonitor 12:35:24 INFO - Running setup.py install for mozsystemmonitor 12:35:24 INFO - Successfully installed mozsystemmonitor 12:35:24 INFO - Cleaning up... 12:35:24 INFO - Return code: 0 12:35:24 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:35: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')]} 12:35:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:35:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:35: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')]} 12:35:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:35:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:35:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x106879b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10687c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb3a499660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106657d30>, '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 0x10657edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10664cbe8>, '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.IEPyEBJPGu/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.2BI05gQGvJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 12:35:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 12:35:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 12:35:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IEPyEBJPGu/Render', 12:35:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:35:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:35:24 INFO - 'HOME': '/Users/cltbld', 12:35:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:35:24 INFO - 'LOGNAME': 'cltbld', 12:35:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:35:24 INFO - 'MOZ_NO_REMOTE': '1', 12:35:24 INFO - 'NO_EM_RESTART': '1', 12:35:24 INFO - 'PAGER': '/bin/cat', 12:35:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:35:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:35:24 INFO - 'PWD': '/builds/slave/test', 12:35:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:35:24 INFO - 'SHELL': '/bin/bash', 12:35:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2BI05gQGvJ/Listeners', 12:35:24 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 12:35:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:35:24 INFO - 'USER': 'cltbld', 12:35:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:35:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:35:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:35:24 INFO - 'XPC_FLAGS': '0x0', 12:35:24 INFO - 'XPC_SERVICE_NAME': '0', 12:35:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:35:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:35:24 INFO - Downloading/unpacking jsonschema==2.5.1 12:35:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:35:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:35:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:35:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:35:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:35:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:35:27 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:35:27 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:35:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:35:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:35:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:35:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:35:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:35:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:35:27 INFO - Downloading functools32-3.2.3-2.tar.gz 12:35:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:35:27 INFO - warning: no files found matching '*.txt' 12:35:27 INFO - no previously-included directories found matching 'build' 12:35:27 INFO - no previously-included directories found matching 'dist' 12:35:27 INFO - no previously-included directories found matching '.git*' 12:35:27 INFO - Installing collected packages: jsonschema, functools32 12:35:27 INFO - Running setup.py install for functools32 12:35:28 INFO - warning: no files found matching '*.txt' 12:35:28 INFO - no previously-included directories found matching 'build' 12:35:28 INFO - no previously-included directories found matching 'dist' 12:35:28 INFO - no previously-included directories found matching '.git*' 12:35:28 INFO - Successfully installed jsonschema functools32 12:35:28 INFO - Cleaning up... 12:35:28 INFO - Return code: 0 12:35:28 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:35:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:35:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:35:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:35:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:35:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:35:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:35:28 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 0x106879b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10687c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb3a499660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106657d30>, '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 0x10657edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10664cbe8>, '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.IEPyEBJPGu/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.2BI05gQGvJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 12:35:28 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 12:35:28 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 12:35:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IEPyEBJPGu/Render', 12:35:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:35:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:35:28 INFO - 'HOME': '/Users/cltbld', 12:35:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:35:28 INFO - 'LOGNAME': 'cltbld', 12:35:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:35:28 INFO - 'MOZ_NO_REMOTE': '1', 12:35:28 INFO - 'NO_EM_RESTART': '1', 12:35:28 INFO - 'PAGER': '/bin/cat', 12:35:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:35:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:35:28 INFO - 'PWD': '/builds/slave/test', 12:35:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:35:28 INFO - 'SHELL': '/bin/bash', 12:35:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2BI05gQGvJ/Listeners', 12:35:28 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 12:35:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:35:28 INFO - 'USER': 'cltbld', 12:35:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:35:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:35:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:35:28 INFO - 'XPC_FLAGS': '0x0', 12:35:28 INFO - 'XPC_SERVICE_NAME': '0', 12:35:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:35:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:35:28 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:35:28 INFO - Cleaning up... 12:35:28 INFO - Return code: 0 12:35:28 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:35:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:35:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:35:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:35:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:35:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:35:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:35:28 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 0x106879b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10687c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb3a499660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106657d30>, '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 0x10657edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10664cbe8>, '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.IEPyEBJPGu/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.2BI05gQGvJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 12:35:28 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 12:35:28 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 12:35:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IEPyEBJPGu/Render', 12:35:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:35:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:35:28 INFO - 'HOME': '/Users/cltbld', 12:35:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:35:28 INFO - 'LOGNAME': 'cltbld', 12:35:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:35:28 INFO - 'MOZ_NO_REMOTE': '1', 12:35:28 INFO - 'NO_EM_RESTART': '1', 12:35:28 INFO - 'PAGER': '/bin/cat', 12:35:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:35:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:35:28 INFO - 'PWD': '/builds/slave/test', 12:35:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:35:28 INFO - 'SHELL': '/bin/bash', 12:35:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2BI05gQGvJ/Listeners', 12:35:28 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 12:35:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:35:28 INFO - 'USER': 'cltbld', 12:35:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:35:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:35:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:35:28 INFO - 'XPC_FLAGS': '0x0', 12:35:28 INFO - 'XPC_SERVICE_NAME': '0', 12:35:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:35:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:35:28 INFO - Downloading/unpacking blobuploader==1.2.4 12:35:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:35:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:35:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:35:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:35:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:35:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:35:31 INFO - Downloading blobuploader-1.2.4.tar.gz 12:35:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:35:31 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:35: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 12:35: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 12:35: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 12:35: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 12:35: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 12:35: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 12:35:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:35:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:35:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:35:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:35:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:35:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:35:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:35:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:35:32 INFO - Downloading docopt-0.6.1.tar.gz 12:35:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:35:32 INFO - Installing collected packages: blobuploader, requests, docopt 12:35:32 INFO - Running setup.py install for blobuploader 12:35:32 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:35:32 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:35:32 INFO - Running setup.py install for requests 12:35:33 INFO - Running setup.py install for docopt 12:35:33 INFO - Successfully installed blobuploader requests docopt 12:35:33 INFO - Cleaning up... 12:35:33 INFO - Return code: 0 12:35:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:35:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:35:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:35:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:35:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:35:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:35:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:35:33 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 0x106879b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10687c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb3a499660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106657d30>, '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 0x10657edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10664cbe8>, '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.IEPyEBJPGu/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.2BI05gQGvJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 12:35:33 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 12:35:33 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 12:35:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IEPyEBJPGu/Render', 12:35:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:35:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:35:33 INFO - 'HOME': '/Users/cltbld', 12:35:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:35:33 INFO - 'LOGNAME': 'cltbld', 12:35:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:35:33 INFO - 'MOZ_NO_REMOTE': '1', 12:35:33 INFO - 'NO_EM_RESTART': '1', 12:35:33 INFO - 'PAGER': '/bin/cat', 12:35:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:35:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:35:33 INFO - 'PWD': '/builds/slave/test', 12:35:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:35:33 INFO - 'SHELL': '/bin/bash', 12:35:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2BI05gQGvJ/Listeners', 12:35:33 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 12:35:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:35:33 INFO - 'USER': 'cltbld', 12:35:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:35:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:35:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:35:33 INFO - 'XPC_FLAGS': '0x0', 12:35:33 INFO - 'XPC_SERVICE_NAME': '0', 12:35:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:35:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:35:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:35:33 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-1Hc6Ea-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:35:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:35:33 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-K0690i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:35:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:35:33 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-_mRstN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:35:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:35:34 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-ocTgzi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:35:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:35:34 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-MMPJvo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:35:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:35:34 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-Gl8dlh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:35:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:35:34 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-SxTuUq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:35:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:35:34 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-wDqdBf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:35:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:35:34 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-W50EIL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:35:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:35:34 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-770CS2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:35:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:35:34 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-r1qfrH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:35:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:35:34 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-ivxjgp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:35:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:35:35 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-STEmiQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:35:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:35:35 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-UWjqy1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:35:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:35:35 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-xJaLZP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:35:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:35:35 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-hi5Ifd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:35:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:35:35 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-gGQtUw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:35:35 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:35:35 INFO - Running setup.py install for manifestparser 12:35:35 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:35:35 INFO - Running setup.py install for mozcrash 12:35:35 INFO - Running setup.py install for mozdebug 12:35:35 INFO - Running setup.py install for mozdevice 12:35:36 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:35:36 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:35:36 INFO - Running setup.py install for mozfile 12:35:36 INFO - Running setup.py install for mozhttpd 12:35:36 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:35:36 INFO - Running setup.py install for mozinfo 12:35:36 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:35:36 INFO - Running setup.py install for mozInstall 12:35:36 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:35:36 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:35:36 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:35:36 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:35:36 INFO - Running setup.py install for mozleak 12:35:36 INFO - Running setup.py install for mozlog 12:35:37 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:35:37 INFO - Running setup.py install for moznetwork 12:35:37 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:35:37 INFO - Running setup.py install for mozprocess 12:35:37 INFO - Running setup.py install for mozprofile 12:35:37 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:35:37 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:35:37 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:35:37 INFO - Running setup.py install for mozrunner 12:35:37 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:35:37 INFO - Running setup.py install for mozscreenshot 12:35:37 INFO - Running setup.py install for moztest 12:35:38 INFO - Running setup.py install for mozversion 12:35:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:35:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:35:38 INFO - Cleaning up... 12:35:38 INFO - Return code: 0 12:35:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:35:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:35:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:35:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:35:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:35:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:35:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:35:38 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 0x106879b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10687c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb3a499660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106657d30>, '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 0x10657edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10664cbe8>, '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.IEPyEBJPGu/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.2BI05gQGvJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 12:35:38 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 12:35:38 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 12:35:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IEPyEBJPGu/Render', 12:35:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:35:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:35:38 INFO - 'HOME': '/Users/cltbld', 12:35:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:35:38 INFO - 'LOGNAME': 'cltbld', 12:35:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:35:38 INFO - 'MOZ_NO_REMOTE': '1', 12:35:38 INFO - 'NO_EM_RESTART': '1', 12:35:38 INFO - 'PAGER': '/bin/cat', 12:35:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:35:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:35:38 INFO - 'PWD': '/builds/slave/test', 12:35:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:35:38 INFO - 'SHELL': '/bin/bash', 12:35:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2BI05gQGvJ/Listeners', 12:35:38 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 12:35:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:35:38 INFO - 'USER': 'cltbld', 12:35:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:35:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:35:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:35:38 INFO - 'XPC_FLAGS': '0x0', 12:35:38 INFO - 'XPC_SERVICE_NAME': '0', 12:35:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:35:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:35:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:35:38 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-E0Cahb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:35:38 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)) 12:35:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:35:38 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-Zrjyo6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:35:38 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)) 12:35:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:35:38 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-qnYjpO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:35:38 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)) 12:35:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:35:38 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-hZlsuV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:35:38 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)) 12:35:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:35:38 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-F6k0nQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:35:39 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)) 12:35:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:35:39 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-yat7Py-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:35:39 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)) 12:35:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:35:39 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-8uFy5P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:35:39 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)) 12:35:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:35:39 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-V3O2aA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:35:39 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)) 12:35:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:35:39 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-JDAOtZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:35:39 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)) 12:35:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:35:39 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-Epvb13-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:35:39 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)) 12:35:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:35:39 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-j9bcR_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:35:39 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)) 12:35:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:35:39 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-LsUyC7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:35:39 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)) 12:35:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:35:39 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-qf3UN1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:35:39 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)) 12:35:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:35:39 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-m3ZqSu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:35:39 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)) 12:35:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:35:39 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-fBuXvU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:35:40 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)) 12:35:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:35:40 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-Gso0cC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:35:40 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)) 12:35:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:35:40 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-aKZmze-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:35:40 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)) 12:35:40 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)) 12:35:40 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)) 12:35:40 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)) 12:35:40 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)) 12:35:40 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)) 12:35:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:35:40 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 12:35:40 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 12:35:40 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 12:35:40 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 12:35:40 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 12:35:40 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 12:35:43 INFO - Downloading blessings-1.6.tar.gz 12:35:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:35:43 INFO - Installing collected packages: blessings 12:35:43 INFO - Running setup.py install for blessings 12:35:43 INFO - Successfully installed blessings 12:35:43 INFO - Cleaning up... 12:35:43 INFO - Return code: 0 12:35:43 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:35:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:35:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:35:43 INFO - Reading from file tmpfile_stdout 12:35:43 INFO - Current package versions: 12:35:43 INFO - blessings == 1.6 12:35:43 INFO - blobuploader == 1.2.4 12:35:43 INFO - docopt == 0.6.1 12:35:43 INFO - functools32 == 3.2.3-2 12:35:43 INFO - jsonschema == 2.5.1 12:35:43 INFO - manifestparser == 1.1 12:35:43 INFO - mozInstall == 1.12 12:35:43 INFO - mozcrash == 1.0 12:35:43 INFO - mozdebug == 0.1 12:35:43 INFO - mozdevice == 0.49 12:35:43 INFO - mozfile == 1.2 12:35:43 INFO - mozhttpd == 0.7 12:35:43 INFO - mozinfo == 0.9 12:35:43 INFO - mozleak == 0.1 12:35:43 INFO - mozlog == 3.4 12:35:43 INFO - moznetwork == 0.27 12:35:43 INFO - mozprocess == 0.24 12:35:43 INFO - mozprofile == 0.28 12:35:43 INFO - mozrunner == 6.13 12:35:43 INFO - mozscreenshot == 0.1 12:35:43 INFO - mozsystemmonitor == 0.3 12:35:43 INFO - moztest == 0.8 12:35:43 INFO - mozversion == 1.4 12:35:43 INFO - psutil == 3.1.1 12:35:43 INFO - requests == 1.2.3 12:35:43 INFO - wsgiref == 0.1.2 12:35:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:35:43 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')]} 12:35:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:35:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:35:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:35:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:35:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:35:44 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 0x106879b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10687c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb3a499660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106657d30>, '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 0x10657edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10664cbe8>, '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.IEPyEBJPGu/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.2BI05gQGvJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 12:35:44 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 12:35:44 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 12:35:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IEPyEBJPGu/Render', 12:35:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:35:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:35:44 INFO - 'HOME': '/Users/cltbld', 12:35:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:35:44 INFO - 'LOGNAME': 'cltbld', 12:35:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:35:44 INFO - 'MOZ_NO_REMOTE': '1', 12:35:44 INFO - 'NO_EM_RESTART': '1', 12:35:44 INFO - 'PAGER': '/bin/cat', 12:35:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:35:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:35:44 INFO - 'PWD': '/builds/slave/test', 12:35:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:35:44 INFO - 'SHELL': '/bin/bash', 12:35:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2BI05gQGvJ/Listeners', 12:35:44 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 12:35:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:35:44 INFO - 'USER': 'cltbld', 12:35:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:35:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:35:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:35:44 INFO - 'XPC_FLAGS': '0x0', 12:35:44 INFO - 'XPC_SERVICE_NAME': '0', 12:35:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:35:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:35:44 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)) 12:35:44 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)) 12:35:44 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)) 12:35:44 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)) 12:35:44 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)) 12:35:44 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)) 12:35:44 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)) 12:35:44 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)) 12:35:44 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)) 12:35:44 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)) 12:35:44 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)) 12:35:44 INFO - Cleaning up... 12:35:44 INFO - Return code: 0 12:35:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:35:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:35:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:35:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:35:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:35:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:35:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:35:44 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 0x106879b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10687c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb3a499660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106657d30>, '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 0x10657edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10664cbe8>, '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.IEPyEBJPGu/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.2BI05gQGvJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 12:35:44 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 12:35:44 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 12:35:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IEPyEBJPGu/Render', 12:35:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:35:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:35:44 INFO - 'HOME': '/Users/cltbld', 12:35:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:35:44 INFO - 'LOGNAME': 'cltbld', 12:35:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:35:44 INFO - 'MOZ_NO_REMOTE': '1', 12:35:44 INFO - 'NO_EM_RESTART': '1', 12:35:44 INFO - 'PAGER': '/bin/cat', 12:35:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:35:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:35:44 INFO - 'PWD': '/builds/slave/test', 12:35:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:35:44 INFO - 'SHELL': '/bin/bash', 12:35:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2BI05gQGvJ/Listeners', 12:35:44 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 12:35:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:35:44 INFO - 'USER': 'cltbld', 12:35:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:35:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:35:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:35:44 INFO - 'XPC_FLAGS': '0x0', 12:35:44 INFO - 'XPC_SERVICE_NAME': '0', 12:35:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:35:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:35:44 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:35:44 INFO - Cleaning up... 12:35:44 INFO - Return code: 0 12:35:44 INFO - Running post-action listener: _resource_record_post_action 12:35:44 INFO - Running post-action listener: _start_resource_monitoring 12:35:44 INFO - Starting resource monitoring. 12:35:44 INFO - [mozharness: 2017-01-25 20:35:44.838042Z] Finished create-virtualenv step (success) 12:35:44 INFO - [mozharness: 2017-01-25 20:35:44.839007Z] Running install step. 12:35:44 INFO - Running pre-action listener: _resource_record_pre_action 12:35:44 INFO - Running main action method: install 12:35:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:35:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:35:45 INFO - Reading from file tmpfile_stdout 12:35:45 INFO - Detecting whether we're running mozinstall >=1.0... 12:35:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:35:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:35:45 INFO - Reading from file tmpfile_stdout 12:35:45 INFO - Output received: 12:35:45 INFO - Usage: mozinstall [options] installer 12:35:45 INFO - Options: 12:35:45 INFO - -h, --help show this help message and exit 12:35:45 INFO - -d DEST, --destination=DEST 12:35:45 INFO - Directory to install application into. [default: 12:35:45 INFO - "/builds/slave/test"] 12:35:45 INFO - --app=APP Application being installed. [default: firefox] 12:35:45 INFO - mkdir: /builds/slave/test/build/application 12:35:45 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'] 12:35:45 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 12:36:05 INFO - Reading from file tmpfile_stdout 12:36:05 INFO - Output received: 12:36:05 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 12:36:05 INFO - Running post-action listener: _resource_record_post_action 12:36:05 INFO - [mozharness: 2017-01-25 20:36:05.319205Z] Finished install step (success) 12:36:05 INFO - [mozharness: 2017-01-25 20:36:05.319330Z] Running run-tests step. 12:36:05 INFO - Running pre-action listener: _resource_record_pre_action 12:36:05 INFO - Running main action method: run_tests 12:36:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:36:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:36:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:36:05 INFO - 2017-01-25 12:36:05.334 screenresolution[2473:26528] starting screenresolution argv=screenresolution get 12:36:05 INFO - 2017-01-25 12:36:05.336 screenresolution[2473:26528] Display 0: 1600x1200x32@60 12:36:05 INFO - 2017-01-25 12:36:05.342 screenresolution[2474:26531] starting screenresolution argv=screenresolution list 12:36:05 INFO - Available Modes on Display 0 12:36:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:36:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:36:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:36:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:36:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:36:05 INFO - Intel Iris: 12:36:05 INFO - Chipset Model: Intel Iris 12:36:05 INFO - Type: GPU 12:36:05 INFO - Bus: Built-In 12:36:05 INFO - VRAM (Dynamic, Max): 1536 MB 12:36:05 INFO - Vendor: Intel (0x8086) 12:36:05 INFO - Device ID: 0x0a2e 12:36:05 INFO - Revision ID: 0x0009 12:36:05 INFO - Displays: 12:36:05 INFO - Display: 12:36:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:36:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:36:05 INFO - Display Serial Number: 1600x1200 60 12:36:05 INFO - Main Display: Yes 12:36:05 INFO - Mirror: Off 12:36:05 INFO - Online: Yes 12:36:05 INFO - Rotation: Supported 12:36:05 INFO - Return code: 0 12:36:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:36:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:36:05 INFO - Python 2.7.3 12:36:05 INFO - Return code: 0 12:36:05 INFO - grabbing minidump binary from tooltool 12:36:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:36:05 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 0x106657d30>, '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 0x10657edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10664cbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:36:05 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 12:36:05 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 12:36:05 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 12:36:05 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:36:05 INFO - Return code: 0 12:36:05 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:36:05 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:36:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:36:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:36:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:36:05 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/fDe9cMOyQlynK_zBjgF6DA/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0298', '--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 12:36:05 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/fDe9cMOyQlynK_zBjgF6DA/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0298 --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 12:36:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IEPyEBJPGu/Render', 12:36:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:36:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:36:05 INFO - 'HOME': '/Users/cltbld', 12:36:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:36:05 INFO - 'LOGNAME': 'cltbld', 12:36:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:36:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:36:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:36:05 INFO - 'MOZ_NO_REMOTE': '1', 12:36:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:36:05 INFO - 'NO_EM_RESTART': '1', 12:36:05 INFO - 'PAGER': '/bin/cat', 12:36:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:36:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:36:05 INFO - 'PWD': '/builds/slave/test', 12:36:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:36:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:36:05 INFO - 'SHELL': '/bin/bash', 12:36:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2BI05gQGvJ/Listeners', 12:36:05 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 12:36:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:36:05 INFO - 'USER': 'cltbld', 12:36:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:36:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:36:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:36:05 INFO - 'XPC_FLAGS': '0x0', 12:36:05 INFO - 'XPC_SERVICE_NAME': '0', 12:36:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:36:05 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/fDe9cMOyQlynK_zBjgF6DA/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0298', '--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 12:36:05 INFO - mozversion application_buildid: 20170125112431 12:36:05 INFO - mozversion application_changeset: f3a3afaf7e41b6ea973e97b00528f6d58bdb08cb 12:36:05 INFO - mozversion application_display_name: FirefoxDeveloperEdition 12:36:05 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:36:05 INFO - mozversion application_name: Firefox 12:36:05 INFO - mozversion application_remotingname: firefox-dev 12:36:05 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 12:36:05 INFO - mozversion application_vendor: Mozilla 12:36:05 INFO - mozversion application_version: 53.0a2 12:36:05 INFO - mozversion platform_buildid: 20170125112431 12:36:05 INFO - mozversion platform_changeset: f3a3afaf7e41b6ea973e97b00528f6d58bdb08cb 12:36:05 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 12:36:05 INFO - mozversion platform_version: 53.0a2 12:36:05 INFO - using testdate: 1485376565 12:36:05 INFO - actual date: 1485376565 12:36:05 INFO - starting webserver on 'localhost:52319' 12:36:05 INFO - SUITE-START | Running 2 tests 12:36:05 INFO - TEST-START | dromaeo_css 12:36:05 INFO - operating with platform_type : mac_ 12:36:05 INFO - Initialising browser for dromaeo_css test... 12:36:05 INFO - TEST-INFO | started process 2484 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/tmp54KGxv/profile http://localhost:52319/getInfo.html) 12:36:08 INFO - PROCESS | 2484 | 2017-01-25 12:36:08.744 plugin-container[2486:26699] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a4b, name = 'com.apple.tsm.portname' 12:36:08 INFO - PROCESS | 2484 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:36:08 INFO - PROCESS | 2484 | 2017-01-25 12:36:08.747 plugin-container[2486:26699] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x970b, name = 'com.apple.CFPasteboardClient' 12:36:08 INFO - PROCESS | 2484 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:36:08 INFO - PROCESS | 2484 | 2017-01-25 12:36:08.747 plugin-container[2486:26699] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:36:08 INFO - PROCESS | 2484 | __metrics Screen width/height:1600/1200 12:36:08 INFO - PROCESS | 2484 | colorDepth:24 12:36:08 INFO - PROCESS | 2484 | Browser inner width/height: 1024/689 12:36:08 INFO - PROCESS | 2484 | __metrics 12:36:08 ERROR - PROCESS | 2484 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3280: TypeError: this.tabs is undefined 12:36:08 INFO - PROCESS | 2484 | JavaScript error: resource://app/modules/webrtcUI.jsm, line 66: NS_ERROR_XPC_NOT_ENOUGH_ARGS: Not enough arguments [nsIMessageListenerManager.removeMessageListener] 12:36:09 INFO - TEST-INFO | 2484: exit 0 12:36:09 INFO - Browser initialized. 12:36:09 INFO - Running cycle 1/1 for dromaeo_css test... 12:36:09 INFO - TEST-INFO | started process 2487 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/tmp54KGxv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:36:12 INFO - PROCESS | 2487 | 2017-01-25 12:36:12.014 plugin-container[2488:26833] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a43, name = 'com.apple.tsm.portname' 12:36:12 INFO - PROCESS | 2487 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:36:12 INFO - PROCESS | 2487 | 2017-01-25 12:36:12.015 plugin-container[2488:26833] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4623, name = 'com.apple.CFPasteboardClient' 12:36:12 INFO - PROCESS | 2487 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:36:12 INFO - PROCESS | 2487 | 2017-01-25 12:36:12.015 plugin-container[2488:26833] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:36:21 INFO - PROCESS | 2487 | RSS: Main: 218611712 12:36:21 INFO - PROCESS | 2487 | 12:39:24 INFO - PROCESS | 2487 | Cycle 1(1): loaded http://localhost:52319/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52319/tests/dromaeo/cssquery-ext.html) 12:39:26 INFO - PROCESS | 2487 | RSS: Main: 239022080 12:39:26 INFO - PROCESS | 2487 | 12:41:09 INFO - PROCESS | 2487 | ************************* 12:41:09 INFO - PROCESS | 2487 | A coding exception was thrown and uncaught in a Task. 12:41:09 INFO - PROCESS | 2487 | 12:41:09 ERROR - PROCESS | 2487 | Full message: TypeError: NetworkError when attempting to fetch resource. 12:41:09 INFO - PROCESS | 2487 | Full stack: 12:41:09 INFO - PROCESS | 2487 | ************************* 12:42:28 INFO - PROCESS | 2487 | Cycle 1(1): loaded http://localhost:52319/tests/dromaeo/cssquery-ext.html (next: http://localhost:52319/tests/dromaeo/cssquery-jquery.html) 12:42:29 INFO - PROCESS | 2487 | RSS: Main: 247914496 12:42:29 INFO - PROCESS | 2487 | 12:45:31 INFO - PROCESS | 2487 | Cycle 1(1): loaded http://localhost:52319/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52319/tests/dromaeo/cssquery-mootools.html) 12:45:32 INFO - PROCESS | 2487 | RSS: Main: 254160896 12:45:32 INFO - PROCESS | 2487 | 12:48:33 INFO - PROCESS | 2487 | Cycle 1(1): loaded http://localhost:52319/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52319/tests/dromaeo/cssquery-prototype.html) 12:48:34 INFO - PROCESS | 2487 | RSS: Main: 253915136 12:48:34 INFO - PROCESS | 2487 | 12:51:37 INFO - PROCESS | 2487 | Cycle 1(1): loaded http://localhost:52319/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52319/tests/dromaeo/cssquery-yui.html) 12:51:38 INFO - PROCESS | 2487 | RSS: Main: 257933312 12:51:38 INFO - PROCESS | 2487 | 12:54:40 INFO - PROCESS | 2487 | Cycle 1(1): loaded http://localhost:52319/tests/dromaeo/cssquery-yui.html (next: http://localhost:52319/tests/dromaeo/cssquery-dojo.html) 12:54:40 INFO - PROCESS | 2487 | RSS: Main: 259092480 12:54:40 INFO - PROCESS | 2487 | 12:54:40 INFO - PROCESS | 2487 | __start_tp_report 12:54:40 INFO - PROCESS | 2487 | _x_x_mozilla_page_load 12:54:40 INFO - PROCESS | 2487 | _x_x_mozilla_page_load_details 12:54:40 INFO - PROCESS | 2487 | |i|pagename|runs| 12:54:40 INFO - PROCESS | 2487 | |0;dojo.html;3145.6539780624125;3407.4888766684994;3411.9317613647727;3421.914452138696;3437.6906078452935;5830.271216097988;5895.204147440094;5915.852103697404;5923.703814809264;5951.345352011286;5863.8534036649;5937.52499800015;5938.732757025933;5984.551158663106;5990.520758339333;5291.550218231455;5371.543418809386;5376.193570964367;5396.487333703311;5416.593755468356;5407.702576358299;5423.593230507708;5425;5429.348478182624;5443.156310771817;5262.394824595182;5299.258103865462;5406.54044406224;5409.83770486886;5453.836384908459;5309.442508536606;5325.094733895227;5350.705711185883;5404.297441332602;5442.210879422461;4411.514733379326;5292.1003429416705;5341.599380046512;5387.24578559002;5414.8917021659345;5160.845174644766;5669.092945128759;5831.562632802515;5865.88268234633;5998.670073145975;6221;6241.7191226394925;6243.43809057187;6284.7486100555925;6329.651869147195;6273.843153921142;6290.591111577733;6301.6534090625;6302.621842689453;6311.810645680637;6031.09533569968;6053.969730151321;6066.787662431838;6067.302260240129;6075.726592303357;6204.5967012144065;6231.3145553989025;6253.843653908598;6285.214348206474;6308.337624549448;6240.157578726905;6246.937530624727;6249.9062514062325;6270.122182894398;6275.3724627537795;6268.623882567061;6304.148939893055;6306.24325080984;6309.842253943688;6321.905171422432;6145.354737752561;6214.720337584819;6249;6268.435840774352;6336.936630633634;6248.250209974832;6251.843703907439;6302.369763023753;6307.990721484541;6308.842278943063;4350.456192975878;5059.06407314648;5060.49395060491;5061.190209566452;5064.341635587423;4128.504579450425;4345.3699213613845;4518.186726389215;4526.59260666535;4542.11428771393;4426.15902978433;4988.176950803149;5335.599830012765;6003.6397816131175;6039.305479869783;6239.376062393724;6269.810860705687;6309.558330916791;6315.715792789336;6335.334789847092;5322.3879253885525;5566.691827420553;5733.3406658234;5815.94184058154;5847.883042339129;5555.111182210827;5566.749496272678;5576.972115139399;5577.721113944286;5590.161475778666;6403.935960640334;6405.167328247298;6414.03789431589;6424.935750642434;6436.00241962506;5756.56825738063;6079.209702738615;6211.472024877936;6231.688415579293;6271.529635277464;5071.847844564669;6266.060090986389;6333.461655759128;6447.580907241015;6453.354664533696;6287.842803929939;6289.150964619717;6304.275008374096;6308.463780578519;6320.462760665211;6125.601835880653;6142.32434432221;6274.372562743689;6279.748810047547;6303.9684801575695;4816.301636262652;6099.451049405575;6148.047052706837;6182.474489668429;6245.812625621239;6199.752009919553;6257.1240026395435;6270.310265870842;6273.090401891792;6281.560290779602;6038.7282572283475;6092.817215483543;6141.877162456815;6177.7837775677635;6183.350748171557;6100.572959892765;6157.168782214344;6168.136460895388;6186.381361863778;6198.194234749454;6134.877302454015;6146.508279337731;6158.568900176944;6164.537659675452;6164.537659675632 12:54:40 INFO - PROCESS | 2487 | |1;ext.html;421.5868448920056;471.25541644202156;478.94492133404646;496.60519886690054;545.7216819422096;29950;42304;42505.99999999996;44828.77585612075;44878.775606122006;43795.343069853974;44262;44271.77864110683;44342.33486497705;44361.669149925474;8573.18554737299;9318.906810931889;9482.241420686329;9505.334626576143;9548.28387870909;8631.956840215822;11265.15511336653;11614.709632259219;11627.476763545661;11828.940855295712;11505.42472876357;11541.711457213552;11544.595939142131;11572.884271157265;11609.941950290236;11478.253913495639;11524.250923689933;11532.25040372373;11575.0161236295;11585.6524304271;11433.885661143366;11465.598704045402;11494.655160345203;11516.42417879111;11524.88475115255;34487.655123448945;41631.75104746863;41911.1617767643;41919.1616167678;41941.79029104865;3583.942736892623;3663.285659296438;4035.051762835731;4195.139996300751;4247.702660813744;24215.515689686283;29548.409031819458;29865.954691585906;30005.69994300051;32248.838755806304;3062.5099984002677;3121.250899784058;3236.8867089651985;3309;3378.8310584470923;460701;463899;464242;465540;466133;63561.68219158875;63938.6803065991;64152.679236603515;64208;64410.71178576496;58518.41481585215;66618.00072998909;66989.66505167443;67366;67398;16203.918980405022;16984.660306793794;16985.915070424817;17033.91483042577;17063.48809535716;11806;12100.576479823163;12896.677583060498;13136.60590182277;13198.670033249246;14576.906731995143;15283.006604570668;16686.83313166877;17248.965062096064;17297.56756081083;6501.024846273097;6878.76440156951;7118.822029449305;7169.247229040876;7284.453665975073;883.306604315622;890.8975467821153;896.5741272895453;912.8128733609594;922.4640972780246;30164.113785558162;30210.09369718868;30540.389192216033;30826.229344266125;31173.688263117077;1048.6015314180715;1050.2175878970063;1096.9122470202365;1104.26566333389;1167.626359564948;14235.85764142366;15861;16051.598710032342;16176.191190440666;16653.41713040038;580713;585019.149808505;592648;592702;594677;61495.07757383643;61528.38471615316;61667.383326166164;61743.38256617466;61833.69083154555;1439.3393451882635;1445.4435042508499;1455.23600109941;1474.086066638691;1481.451862810767;28796.280092997844;29921.102366929026;29988.250293742833;30515.54226686557;30666.540001899542;50897.74551127221;51177;51243.74378128235;51270.48729512657;51348.22977655413;516030;518205;519205;519470;520317;18010;24105.397365066012;24169.03323867096;25895.741042589332;26150.08474703376;34228.657713422544;41507.792461037505;42172.57827421809;42662.36006459843;43164.92087697833;29505.37720425397;35303;35405.114872128404;35647.82176089103;35665.82167089148;26475.867620661775;32491.675083248865;35231;35356.93929182128;35615.643843561236;33753.64985400556;33981.98054058382;34267.82866085654;34564.135896602784;34857.65142348645;29767.702322976493;30657.386852263277;31355.529667054558;31602.525962111045;31689.36621267608;20461.488462788548;21621.675674865204;22478.438039049153;23682.934267958328;23769.762302376756 12:54:40 INFO - PROCESS | 2487 | |2;jquery.html;390.1105479506726;390.2176136845624;399.1956208240395;400.7475290566943;418.41839842618765;15675.216239188052;16039.599010024755;20485.38543843687;21030.684539731898;21542.89228553857;19878.204871805112;20125.993700314997;21158.25946091887;21455.892720536416;22032.559348813054;4785.904281914369;5047.747612619372;5889.146073819294;5900.085486749561;5911.822645320647;5714.342850572175;5770.740316685759;5860.267466566679;5881.411858814127;5898.587098903078;5729.885402291951;5793.71031448428;5806.071028635419;5808.506276966463;5819.854503637401;5575.916361254584;5763.855903602402;5771.971140144315;5789.594728369016;5817.650940943557;5103.770330335144;5151.768170432302;5152.871178220537;5153.742312884341;5160.14857548504;25871.09451169219;25966.74033259681;26288.474230515283;26408;26686.065987690337;2762.1437354420195;2810.662720473536;2838.886444542216;2840.4461130079644;2843.331817022998;15799.131047792367;16113.597160070974;16189.35242590295;16372.263248153862;16603;1866.7946525882141;1873.7189421586734;1880.9059547022594;1881.5202123458566;1894.4127320530542;121896;124663;131511;134959;138604.30697846547;12023.879761202452;12029.338386388832;12180.695482612828;12273.877261227273;12358.691032724253;13307.201567905957;14263.78604320936;14832.406703731946;15986.280617372246;16015.519534413987;8393.580320983927;8728.69449569262;8775.517346545874;8780.736577902673;8943.642254309885;8706.1729135732;8707.606782914703;8721.651133954698;8818.603162857687;8855.557222138867;3743.4384842273876;4674.883127921761;4684.344191813149;4686.74222917736;4688.296755486636;2015.8488113391554;2066.1941842681067;2070.472029632434;2076.0761461149646;2076.9480762981048;981.6796408830112;997.7256254530063;1004.492731170754;1012.8176928152856;1020.5968642386364;21080.578388432146;21334.57330853374;21530;21625.269303634548;21983;845.9679191386499;963.3449254506862;1066.6106870992187;1084.1164450972433;1093.5297821936645;9043.457392556467;10081.798364032826;10606.05307005552;11524.250923689933;11658.94170529142;748950;753503;767889;768430;785053;144883.2755836214;145281.27359363346;145812;154516.22741886444;157945;977.6920270115033;997.4613708597421;1000.8348622477209;1028.7633844215723;1063.8616979792732;19719.901400492905;20272.898635506728;20538.794612053687;20609.072591733107;20704.757714537176;45129.548704512534;47750.76124619355;48990.75504622454;55121.51953162037;60392.094118589135;81295.59352203201;88458.55770721105;89761;90750;92545.01788757055;8454.239118479367;8454.323654107533;8487.787805304917;8675.869861952202;8702.347323950855;11819.645410637695;13158.539451119164;13223.272720000108;13346.399412026289;13555.457781688621;9130.406523575946;11170.050545703381;11496;11539.826902596637;11616.88383116158;11042.558297668003;11468.541258349573;11508.36703981289;11563.248388854698;11731.23746956445;11408.34578986059;11434.599789007345;11555.306681599131;11812.645620631394;12011.75976480483;8428.789280268042;8494.617742201739;8593.69922052725;8675.783105422162;8712.607932642919;11122.499487523242;11316.660500185008;11358.772824543628;11409.657710268706;11484.94257528707 12:54:40 INFO - PROCESS | 2487 | |3;mootools.html;1431.4684480496242;1433.354990254385;1450.3111022264425;1453.1208618785633;1463.0124665850549;7573.166951635316;7796.142424333319;7995.640196191171;8475.91524084759;8496.362772792043;7241.782746517613;7931.889535465039;7969.203079692028;8331.541765202912;8345.749627511184;2283.748787633359;2336.147306233221;2337.5792357375667;2338.707661542307;2348.1429278313376;2063.1644184105385;2246.955060898781;2341.91803286885;2348.953020939589;2354.0348457132577;2295.9426014349606;2304.9078036878504;2312.329424466911;2314.9421264468356;2327.6857624220766;2275.8065564427043;2287.1537531113427;2302.447412620959;2305.2738387408;2313.3175713164655;2270.3302525754775;2300.183434880627;2300.8849557522226;2301.620232661609;2340.32130682102;8386.790330241733;8460.915390846074;8475.110113438062;8481.448705834102;8523.616437260276;4516.503184649719;5049.343585333883;5198.934218485239;5214.556762675177;5223.973880130613;3289.8848540300974;3360.4959256111533;3480.4083305838067;3532.7880327180196;3543.6102028776813;1187.8277649740826;1195.4500929572407;1202.1464760020397;1215.817627355895;1234.4383305596023;10851.620193293276;10910;10987.670369888836;10990;11031.007209351117;4451.465824101064;4628.143793398232;4707.587723682881;4719.244920812654;4727.267273572602;9447.527623618791;9580.233581313478;9585.424874507413;9610.375325603813;9616.711498655051;5231.973840130775;5248.737563121905;5251.868703282448;5258.2112683097075;5262.60530460217;4167.937480937789;4242.257604919127;4702.811887524529;4710.929336059962;4758.547937945889;3768.4158955361963;3799.810009499514;3865.729398942047;3880.3403421418425;3891.6302951220155;1286.8069789531646;1304.1588175626669;1306.5884246462333;1314.704191556892;1316.6642506161058;803.4295650088503;863.9784005399915;930.9898759845508;955.4697143085655;956.2111258212003;4889.853304400874;5444.509994100471;5685.948099601587;5809.680467574324;5901.321348044944;1014.1987829614521;1017.2472370445817;1036.7978244242452;1039.0648416425126;1046.2780681329857;3699;3768.962310376916;3780.1872597391143;3866.478025466582;3957.881263562098;9765.218782497383;10110.039546243095;10121.342112762646;10129.189664826796;10165.085142337226;9976.950115249378;10088.041636044563;10101.747456313504;10115.342502737298;10128.493575321205;7631.313181813553;7846.058472983279;7894.407919406068;7920.643571039317;7931.286184243446;4814.20565606671;5125.051865404912;5224.555912747459;5555.444455554574;5579.609427340048;7400.814979625552;8169.959150204211;8822.073682263526;9306.534673266175;9314.72055838326;8055.919440805751;8810.383273170817;8950.820983580423;9153.954230228806;9305.767355816159;6582.967085164735;6763.797086087426;6985.1617805863625;7170.713171473083;7293.489455738048;7711.421643376657;7951.9662443881925;8019.278264956182;8020.398470114648;8027.39794515402;6055.394460553909;6949.7567585134275;7089.858202836018;7115.466340024415;7188.209296977433;6995.615241161785;7024.789256322114;7048.506604537838;7062.717491300496;7085.468589855885;6329.525285603506;6438.5171112165835;6511.772087976898;6526.738930442729;6566.310537393505;4930.506949305184;5087.4403815581;5104.9489505104475;5112.437631860418;5133.8716532087;4569.794359253775;5037.471065538212;5055.974720126376;5144.511271429208;5214.504622060898 12:54:40 INFO - PROCESS | 2487 | |4;prototype.html;319.54464887535255;324.6769464382939;337.51229473410916;344.7586689317478;376.7701701961801;4873.634477414194;5281.339832520935;5319.936012797437;5332.280142180809;5519.558435325174;4533.3426653135275;5378.596605254612;5444.564434845212;5458.344998600173;5472.699001554913;3320.684534969174;3511.4732790081566;4137.77242251676;4173.5409104998425;4193.895152621194;3845.42318652203;4178.707490475668;4212.557681443435;4238.9152216955645;4239.7668128252935;3471.409860323751;3805.6384643458828;3812.809359532026;3843.942340864889;4208.242516347056;4061.8375264989363;4175.603317684815;4203.684723645739;4236.4704411948505;4236.957630423687;3160.8419579020956;3693.519842420468;3697.6856967157823;3733.7199710021855;3752.3058234226755;4573.474050484204;5117.437081920985;5224.555912747421;5272.542918079807;5325.360956685184;3941.7043721720984;4418.182636212279;4433.069055498349;4683.461401938786;4876.878078048042;4856.595984987419;5076.69010600176;5650.74571644277;5709.714514274269;5744.770209191627;3514.455259434766;4159.9792001040105;4162.125953549758;4177.436046133764;4183.824411306104;5323.482807399872;5624.775008999635;5654;5659.349174844863;5982.222311099573;3822.522184726909;3873;3942.48747662802;3954.8728612345917;3959.9604003959594;3639.3085313790825;3697.0757310672334;4006.1587066716315;4025.637692607621;4051.47330846988;3928.685705143582;3949.328614135604;4639.559241872017;4718;4741.928871066937;5359.5712343012465;5406.513413792699;5769.365369809318;5770.394108618619;5793.971030144907;1201.423316807937;1270.2568997136777;1288.671388795851;1294.1458637299338;1294.8769866862633;814.9609248208582;839.4963022186639;865.523961820996;886.4504007515253;886.534569351083;4321.611055005002;4559.814448243481;4646.7444290564345;4994.850154495371;5298.019861330414;5293.782430041112;5865.824025279248;5937.248511688976;5951.9702401488585;5992.730327135289;4964.826231081895;5291.444398338196;5297.364316282071;5326.201069839555;5363.973180134074;2522.8864701088496;2536.8964500442366;2903.6951120132503;2914.519104347801;2938;5068;5127.077345995252;5317.7872885084935;5863.071741932915;5967.313758917693;5641.661500309994;5668.603197776116;5805.023558862193;5839.386864379264;6051.394860513913;4971.726555039435;5398.949593479671;5766.855828604318;6115.938840611537;6232.875342493216;4376.671749618855;5049.419316778617;5884.205317378241;5927.31835838884;6024.728887199999;5478.45215478449;5818.679972601378;5868.67722275279;5883.176355310175;5910.601404192521;6098.603590766586;6105.8168254952425;6134.8159555213415;6171.228596425447;6222.81331560054;3851;3877.302085624615;4231.4710661167355;4294.957050429456;4311.805968731352;5058.443571207091;5608.214849921096;5777.140315543833;5831.825045248649;5860.520413413472;5023.4725353838785;5726.141078838207;5728.684922329312;5745.1382292656435;5757.280339957505;4437.866863994085;5298.94701053;5394.445748415694;5533.529649979636;5627.268455100974;5002.7993281611425;5286.920696189483;5412;5529.225908372911;5557.110862262019;5511.834644960658;5530.695811730394;5677.489025987681;5680.829575112753;5692.146178073156;4675.929861052019;5518.53092487127;5529.419410961791;5537.197106419466;5552.194931734956 12:54:40 INFO - PROCESS | 2487 | |5;yui.html;695.6521739130435;738.1068906622986;756.8107973006748;790.0045942112939;815.8816971539127;14944.77582836257;15493.690126197469;15522.534323970285;15576.766348504752;15621.531354059382;14154.575362739108;14306;14314.212718300489;14314.284285785721;14315.141091534515;674.1236392689509;692.5533031194901;837.5728378526965;839.613777662273;865.1348651348651;849.800296930221;858.3004851046409;865.277493293101;867.1718508824058;868.6178081644057;907.3693782820909;927.0312523413049;928.0533855467419;929.0894922975488;930.5440334236243;914.5107367558358;916.3402350307767;919.7332773495679;921.1525396635078;922.2760133295365;669.933006699331;689.2039694153261;690.3959035843637;690.4441924250976;690.5166383531548;15019.57314055163;15139.697206055927;15254.711793230994;15642.45251416195;15970.961887477277;14352.354144063438;14557.053791503518;14714.33785479656;14778.556643300719;14826.777598336033;10015.74960625983;10371;10476;11330;11945.163838531307;3622.2936527377174;3729.6940260333777;3730;3865.8840234793;3869.5163104611925;84200;104023;105574.47212763964;106648;106989;9262.629494820267;9582.233421326277;11134.220604557604;11199.60801371948;11329.03703185222;18638.347657832182;23180.304590862303;23215.651765223534;23411.648825267293;23670;7792.337651299703;7858.17489163641;7971.760847174594;7971.920280797118;8029.4379393441895;4262.722923009995;4268.765217912983;4347.913041739147;4374.496932852699;4470;3591.586967498719;3649.105969037603;3670.4861319415304;3675.467057276733;3679.7424180307657;652.6508318049821;653.9280679125293;659.9637019963852;671.1744554198364;673.0375562945036;674.231376231097;675.8040168351222;690.6788343420334;712.127643636541;741.499487845702;10258.692239232834;10372.740681482872;10387.584496620202;10598.735031624272;10772.992664304636;675.3482889012126;702.7540360873653;708.0865683268629;709.9112610923635;713.757322510349;4932.21084626458;5030.119729047402;5039.269305950616;5045.344105266292;5094.133997220481;89958.55020724854;104313.47843260736;109135.81728365389;111284.33073503904;113185.43407283076;89550;90305.54847225854;92012.53993729988;93420.53289733508;94665.52667236757;82754;82961;84275;85402;86534.1346586526;9778.364406313567;9803.754906127404;9837.60649574009;9897.802043959224;10069.798604028025;54288;54574.908501830534;54841.451585483635;56238.59403514945;56325.591860202185;57877.71061144667;71279.64360178166;71953.28046719467;73381.79854604157;74042.62978685288;11720.88279117198;11937.343446110548;12015.45930433115;12224.266544007389;12312.938435308124;12350.75298494043;12750.362481876055;12854.871451285368;12888.742225155633;12898.48406063747;11681.06551475863;11985.940070299594;12145.392730363623;12418.316992565495;12497.18768280059;11544.538218471167;12177;13755.10591811529;14025.29873506341;14120.223387713775;13145.474181032652;13313.600591982256;13347.66630834237;13401.731965360834;13706.862931370559;10681.412522311037;11769.705757355792;11806.173567850168;12032.699182520157;12086.274823510617;11363;11480.024197943269;11761.647150585497;11775.646730598095;11835.408229588314 12:54:40 INFO - PROCESS | 2487 | __end_tp_report 12:54:40 INFO - PROCESS | 2487 | __start_cc_report 12:54:40 INFO - PROCESS | 2487 | _x_x_mozilla_cycle_collect,371 12:54:40 INFO - PROCESS | 2487 | __end_cc_report 12:54:40 INFO - PROCESS | 2487 | __startTimestamp1485377680913__endTimestamp 12:54:40 INFO - PROCESS | 2487 | 12:54:40 INFO - PROCESS | 2487 | ------- Summary: start ------- 12:54:40 INFO - PROCESS | 2487 | Number of tests: 6 12:54:40 INFO - PROCESS | 2487 | 12:54:40 INFO - PROCESS | 2487 | [#0] dojo.html Cycles:165 Average:5804.75 Median:6100.57 stddev:674.05 (11.0%) stddev-sans-first:643.03 12:54:40 INFO - PROCESS | 2487 | Values: 3145.7 3407.5 3411.9 3421.9 3437.7 5830.3 5895.2 5915.9 5923.7 5951.3 5863.9 5937.5 5938.7 5984.6 5990.5 5291.6 5371.5 5376.2 5396.5 5416.6 5407.7 5423.6 5425.0 5429.3 5443.2 5262.4 5299.3 5406.5 5409.8 5453.8 5309.4 5325.1 5350.7 5404.3 5442.2 4411.5 5292.1 5341.6 5387.2 5414.9 5160.8 5669.1 5831.6 5865.9 5998.7 6221.0 6241.7 6243.4 6284.7 6329.7 6273.8 6290.6 6301.7 6302.6 6311.8 6031.1 6054.0 6066.8 6067.3 6075.7 6204.6 6231.3 6253.8 6285.2 6308.3 6240.2 6246.9 6249.9 6270.1 6275.4 6268.6 6304.1 6306.2 6309.8 6321.9 6145.4 6214.7 6249.0 6268.4 6336.9 6248.3 6251.8 6302.4 6308.0 6308.8 4350.5 5059.1 5060.5 5061.2 5064.3 4128.5 4345.4 4518.2 4526.6 4542.1 4426.2 4988.2 5335.6 6003.6 6039.3 6239.4 6269.8 6309.6 6315.7 6335.3 5322.4 5566.7 5733.3 5815.9 5847.9 5555.1 5566.7 5577.0 5577.7 5590.2 6403.9 6405.2 6414.0 6424.9 6436.0 5756.6 6079.2 6211.5 6231.7 6271.5 5071.8 6266.1 6333.5 6447.6 6453.4 6287.8 6289.2 6304.3 6308.5 6320.5 6125.6 6142.3 6274.4 6279.7 6304.0 4816.3 6099.5 6148.0 6182.5 6245.8 6199.8 6257.1 6270.3 6273.1 6281.6 6038.7 6092.8 6141.9 6177.8 6183.4 6100.6 6157.2 6168.1 6186.4 6198.2 6134.9 6146.5 6158.6 6164.5 6164.5 12:54:40 INFO - PROCESS | 2487 | 12:54:40 INFO - PROCESS | 2487 | [#1] ext.html Cycles:180 Average:66202.65 Median:34248.24 stddev:140365.77 (409.8%) stddev-sans-first:140672.64 12:54:40 INFO - PROCESS | 2487 | Values: 421.6 471.3 478.9 496.6 545.7 29950.0 42304.0 42506.0 44828.8 44878.8 43795.3 44262.0 44271.8 44342.3 44361.7 8573.2 9318.9 9482.2 9505.3 9548.3 8632.0 11265.2 11614.7 11627.5 11828.9 11505.4 11541.7 11544.6 11572.9 11609.9 11478.3 11524.3 11532.3 11575.0 11585.7 11433.9 11465.6 11494.7 11516.4 11524.9 34487.7 41631.8 41911.2 41919.2 41941.8 3583.9 3663.3 4035.1 4195.1 4247.7 24215.5 29548.4 29866.0 30005.7 32248.8 3062.5 3121.3 3236.9 3309.0 3378.8 460701.0 463899.0 464242.0 465540.0 466133.0 63561.7 63938.7 64152.7 64208.0 64410.7 58518.4 66618.0 66989.7 67366.0 67398.0 16203.9 16984.7 16985.9 17033.9 17063.5 11806.0 12100.6 12896.7 13136.6 13198.7 14576.9 15283.0 16686.8 17249.0 17297.6 6501.0 6878.8 7118.8 7169.2 7284.5 883.3 890.9 896.6 912.8 922.5 30164.1 30210.1 30540.4 30826.2 31173.7 1048.6 1050.2 1096.9 1104.3 1167.6 14235.9 15861.0 16051.6 16176.2 16653.4 580713.0 585019.1 592648.0 592702.0 594677.0 61495.1 61528.4 61667.4 61743.4 61833.7 1439.3 1445.4 1455.2 1474.1 1481.5 28796.3 29921.1 29988.3 30515.5 30666.5 50897.7 51177.0 51243.7 51270.5 51348.2 516030.0 518205.0 519205.0 519470.0 520317.0 18010.0 24105.4 24169.0 25895.7 26150.1 34228.7 41507.8 42172.6 42662.4 43164.9 29505.4 35303.0 35405.1 35647.8 35665.8 26475.9 32491.7 35231.0 35356.9 35615.6 33753.6 33982.0 34267.8 34564.1 34857.7 29767.7 30657.4 31355.5 31602.5 31689.4 20461.5 21621.7 22478.4 23682.9 23769.8 12:54:40 INFO - PROCESS | 2487 | 12:54:40 INFO - PROCESS | 2487 | [#2] jquery.html Cycles:180 Average:41179.27 Median:22007.78 stddev:127094.96 (577.5%) stddev-sans-first:127414.59 12:54:40 INFO - PROCESS | 2487 | Values: 390.1 390.2 399.2 400.7 418.4 15675.2 16039.6 20485.4 21030.7 21542.9 19878.2 20126.0 21158.3 21455.9 22032.6 4785.9 5047.7 5889.1 5900.1 5911.8 5714.3 5770.7 5860.3 5881.4 5898.6 5729.9 5793.7 5806.1 5808.5 5819.9 5575.9 5763.9 5772.0 5789.6 5817.7 5103.8 5151.8 5152.9 5153.7 5160.1 25871.1 25966.7 26288.5 26408.0 26686.1 2762.1 2810.7 2838.9 2840.4 2843.3 15799.1 16113.6 16189.4 16372.3 16603.0 1866.8 1873.7 1880.9 1881.5 1894.4 121896.0 124663.0 131511.0 134959.0 138604.3 12023.9 12029.3 12180.7 12273.9 12358.7 13307.2 14263.8 14832.4 15986.3 16015.5 8393.6 8728.7 8775.5 8780.7 8943.6 8706.2 8707.6 8721.7 8818.6 8855.6 3743.4 4674.9 4684.3 4686.7 4688.3 2015.8 2066.2 2070.5 2076.1 2076.9 981.7 997.7 1004.5 1012.8 1020.6 21080.6 21334.6 21530.0 21625.3 21983.0 846.0 963.3 1066.6 1084.1 1093.5 9043.5 10081.8 10606.1 11524.3 11658.9 748950.0 753503.0 767889.0 768430.0 785053.0 144883.3 145281.3 145812.0 154516.2 157945.0 977.7 997.5 1000.8 1028.8 1063.9 19719.9 20272.9 20538.8 20609.1 20704.8 45129.5 47750.8 48990.8 55121.5 60392.1 81295.6 88458.6 89761.0 90750.0 92545.0 8454.2 8454.3 8487.8 8675.9 8702.3 11819.6 13158.5 13223.3 13346.4 13555.5 9130.4 11170.1 11496.0 11539.8 11616.9 11042.6 11468.5 11508.4 11563.2 11731.2 11408.3 11434.6 11555.3 11812.6 12011.8 8428.8 8494.6 8593.7 8675.8 8712.6 11122.5 11316.7 11358.8 11409.7 11484.9 12:54:40 INFO - PROCESS | 2487 | 12:54:40 INFO - PROCESS | 2487 | [#3] mootools.html Cycles:180 Average:5405.66 Median:5071.71 stddev:2931.80 (57.8%) stddev-sans-first:2924.81 12:54:40 INFO - PROCESS | 2487 | Values: 1431.5 1433.4 1450.3 1453.1 1463.0 7573.2 7796.1 7995.6 8475.9 8496.4 7241.8 7931.9 7969.2 8331.5 8345.7 2283.7 2336.1 2337.6 2338.7 2348.1 2063.2 2247.0 2341.9 2349.0 2354.0 2295.9 2304.9 2312.3 2314.9 2327.7 2275.8 2287.2 2302.4 2305.3 2313.3 2270.3 2300.2 2300.9 2301.6 2340.3 8386.8 8460.9 8475.1 8481.4 8523.6 4516.5 5049.3 5198.9 5214.6 5224.0 3289.9 3360.5 3480.4 3532.8 3543.6 1187.8 1195.5 1202.1 1215.8 1234.4 10851.6 10910.0 10987.7 10990.0 11031.0 4451.5 4628.1 4707.6 4719.2 4727.3 9447.5 9580.2 9585.4 9610.4 9616.7 5232.0 5248.7 5251.9 5258.2 5262.6 4167.9 4242.3 4702.8 4710.9 4758.5 3768.4 3799.8 3865.7 3880.3 3891.6 1286.8 1304.2 1306.6 1314.7 1316.7 803.4 864.0 931.0 955.5 956.2 4889.9 5444.5 5685.9 5809.7 5901.3 1014.2 1017.2 1036.8 1039.1 1046.3 3699.0 3769.0 3780.2 3866.5 3957.9 9765.2 10110.0 10121.3 10129.2 10165.1 9977.0 10088.0 10101.7 10115.3 10128.5 7631.3 7846.1 7894.4 7920.6 7931.3 4814.2 5125.1 5224.6 5555.4 5579.6 7400.8 8170.0 8822.1 9306.5 9314.7 8055.9 8810.4 8950.8 9154.0 9305.8 6583.0 6763.8 6985.2 7170.7 7293.5 7711.4 7952.0 8019.3 8020.4 8027.4 6055.4 6949.8 7089.9 7115.5 7188.2 6995.6 7024.8 7048.5 7062.7 7085.5 6329.5 6438.5 6511.8 6526.7 6566.3 4930.5 5087.4 5104.9 5112.4 5133.9 4569.8 5037.5 5056.0 5144.5 5214.5 12:54:40 INFO - PROCESS | 2487 | 12:54:40 INFO - PROCESS | 2487 | [#4] prototype.html Cycles:180 Average:4582.71 Median:5175.82 stddev:1416.57 (27.4%) stddev-sans-first:1383.93 12:54:40 INFO - PROCESS | 2487 | Values: 319.5 324.7 337.5 344.8 376.8 4873.6 5281.3 5319.9 5332.3 5519.6 4533.3 5378.6 5444.6 5458.3 5472.7 3320.7 3511.5 4137.8 4173.5 4193.9 3845.4 4178.7 4212.6 4238.9 4239.8 3471.4 3805.6 3812.8 3843.9 4208.2 4061.8 4175.6 4203.7 4236.5 4237.0 3160.8 3693.5 3697.7 3733.7 3752.3 4573.5 5117.4 5224.6 5272.5 5325.4 3941.7 4418.2 4433.1 4683.5 4876.9 4856.6 5076.7 5650.7 5709.7 5744.8 3514.5 4160.0 4162.1 4177.4 4183.8 5323.5 5624.8 5654.0 5659.3 5982.2 3822.5 3873.0 3942.5 3954.9 3960.0 3639.3 3697.1 4006.2 4025.6 4051.5 3928.7 3949.3 4639.6 4718.0 4741.9 5359.6 5406.5 5769.4 5770.4 5794.0 1201.4 1270.3 1288.7 1294.1 1294.9 815.0 839.5 865.5 886.5 886.5 4321.6 4559.8 4646.7 4994.9 5298.0 5293.8 5865.8 5937.2 5952.0 5992.7 4964.8 5291.4 5297.4 5326.2 5364.0 2522.9 2536.9 2903.7 2914.5 2938.0 5068.0 5127.1 5317.8 5863.1 5967.3 5641.7 5668.6 5805.0 5839.4 6051.4 4971.7 5398.9 5766.9 6115.9 6232.9 4376.7 5049.4 5884.2 5927.3 6024.7 5478.5 5818.7 5868.7 5883.2 5910.6 6098.6 6105.8 6134.8 6171.2 6222.8 3851.0 3877.3 4231.5 4295.0 4311.8 5058.4 5608.2 5777.1 5831.8 5860.5 5023.5 5726.1 5728.7 5745.1 5757.3 4437.9 5298.9 5394.4 5533.5 5627.3 5002.8 5286.9 5412.0 5529.2 5557.1 5511.8 5530.7 5677.5 5680.8 5692.1 4675.9 5518.5 5529.4 5537.2 5552.2 12:54:40 INFO - PROCESS | 2487 | 12:54:40 INFO - PROCESS | 2487 | [#5] yui.html Cycles:180 Average:20853.98 Median:3867.70 stddev:30143.78 (779.4%) stddev-sans-first:30190.34 12:54:40 INFO - PROCESS | 2487 | Values: 695.7 738.1 756.8 790.0 815.9 14944.8 15493.7 15522.5 15576.8 15621.5 14154.6 14306.0 14314.2 14314.3 14315.1 674.1 692.6 837.6 839.6 865.1 849.8 858.3 865.3 867.2 868.6 907.4 927.0 928.1 929.1 930.5 914.5 916.3 919.7 921.2 922.3 669.9 689.2 690.4 690.4 690.5 15019.6 15139.7 15254.7 15642.5 15971.0 14352.4 14557.1 14714.3 14778.6 14826.8 10015.7 10371.0 10476.0 11330.0 11945.2 3622.3 3729.7 3730.0 3865.9 3869.5 84200.0 104023.0 105574.5 106648.0 106989.0 9262.6 9582.2 11134.2 11199.6 11329.0 18638.3 23180.3 23215.7 23411.6 23670.0 7792.3 7858.2 7971.8 7971.9 8029.4 4262.7 4268.8 4347.9 4374.5 4470.0 3591.6 3649.1 3670.5 3675.5 3679.7 652.7 653.9 660.0 671.2 673.0 674.2 675.8 690.7 712.1 741.5 10258.7 10372.7 10387.6 10598.7 10773.0 675.3 702.8 708.1 709.9 713.8 4932.2 5030.1 5039.3 5045.3 5094.1 89958.6 104313.5 109135.8 111284.3 113185.4 89550.0 90305.5 92012.5 93420.5 94665.5 82754.0 82961.0 84275.0 85402.0 86534.1 9778.4 9803.8 9837.6 9897.8 10069.8 54288.0 54574.9 54841.5 56238.6 56325.6 57877.7 71279.6 71953.3 73381.8 74042.6 11720.9 11937.3 12015.5 12224.3 12312.9 12350.8 12750.4 12854.9 12888.7 12898.5 11681.1 11985.9 12145.4 12418.3 12497.2 11544.5 12177.0 13755.1 14025.3 14120.2 13145.5 13313.6 13347.7 13401.7 13706.9 10681.4 11769.7 11806.2 12032.7 12086.3 11363.0 11480.0 11761.6 11775.6 11835.4 12:54:40 INFO - PROCESS | 2487 | -------- Summary: end -------- 12:54:40 INFO - PROCESS | 2487 | 12:54:41 INFO - TEST-INFO | 2487: exit 0 12:54:41 INFO - TEST-OK | dromaeo_css | took 1115717ms 12:54:41 INFO - TEST-START | kraken 12:54:41 INFO - operating with platform_type : mac_ 12:54:41 INFO - Initialising browser for kraken test... 12:54:41 INFO - TEST-INFO | started process 2492 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/tmpDw8lIS/profile http://localhost:52319/getInfo.html) 12:54:44 INFO - PROCESS | 2492 | 2017-01-25 12:54:44.568 plugin-container[2493:28815] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4847, name = 'com.apple.tsm.portname' 12:54:44 INFO - PROCESS | 2492 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:54:44 INFO - PROCESS | 2492 | 2017-01-25 12:54:44.572 plugin-container[2493:28815] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d1f, name = 'com.apple.CFPasteboardClient' 12:54:44 INFO - PROCESS | 2492 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:54:44 INFO - PROCESS | 2492 | 2017-01-25 12:54:44.573 plugin-container[2493:28815] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:54:44 INFO - PROCESS | 2492 | __metrics Screen width/height:1600/1200 12:54:44 INFO - PROCESS | 2492 | colorDepth:24 12:54:44 INFO - PROCESS | 2492 | Browser inner width/height: 1024/689 12:54:44 INFO - PROCESS | 2492 | __metrics 12:54:44 ERROR - PROCESS | 2492 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3280: TypeError: this.tabs is undefined 12:54:44 INFO - PROCESS | 2492 | JavaScript error: resource://app/modules/webrtcUI.jsm, line 66: NS_ERROR_XPC_NOT_ENOUGH_ARGS: Not enough arguments [nsIMessageListenerManager.removeMessageListener] 12:54:45 INFO - TEST-INFO | 2492: exit 0 12:54:45 INFO - Browser initialized. 12:54:45 INFO - Running cycle 1/1 for kraken test... 12:54:45 INFO - TEST-INFO | started process 2494 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/tmpDw8lIS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:54:47 INFO - PROCESS | 2494 | 2017-01-25 12:54:47.875 plugin-container[2495:28940] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x444b, name = 'com.apple.tsm.portname' 12:54:47 INFO - PROCESS | 2494 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:54:47 INFO - PROCESS | 2494 | 2017-01-25 12:54:47.876 plugin-container[2495:28940] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 12:54:47 INFO - PROCESS | 2494 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:54:47 INFO - PROCESS | 2494 | 2017-01-25 12:54:47.876 plugin-container[2495:28940] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:54:57 INFO - PROCESS | 2494 | RSS: Main: 218963968 12:54:57 INFO - PROCESS | 2494 | 12:55:00 INFO - PROCESS | 2494 | Cycle 1(1): loaded http://localhost:52319/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52319/tests/kraken/driver.html?testName=audio-beat-detection) 12:55:01 INFO - PROCESS | 2494 | RSS: Main: 233037824 12:55:01 INFO - PROCESS | 2494 | 12:55:03 INFO - PROCESS | 2494 | Cycle 1(1): loaded http://localhost:52319/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52319/tests/kraken/driver.html?testName=audio-dft) 12:55:04 INFO - PROCESS | 2494 | RSS: Main: 241360896 12:55:04 INFO - PROCESS | 2494 | 12:55:07 INFO - PROCESS | 2494 | Cycle 1(1): loaded http://localhost:52319/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52319/tests/kraken/driver.html?testName=audio-fft) 12:55:08 INFO - PROCESS | 2494 | RSS: Main: 244736000 12:55:08 INFO - PROCESS | 2494 | 12:55:10 INFO - PROCESS | 2494 | Cycle 1(1): loaded http://localhost:52319/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52319/tests/kraken/driver.html?testName=audio-oscillator) 12:55:11 INFO - PROCESS | 2494 | RSS: Main: 246054912 12:55:11 INFO - PROCESS | 2494 | 12:55:13 INFO - PROCESS | 2494 | Cycle 1(1): loaded http://localhost:52319/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52319/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:55:13 INFO - PROCESS | 2494 | RSS: Main: 246198272 12:55:13 INFO - PROCESS | 2494 | 12:55:16 INFO - PROCESS | 2494 | Cycle 1(1): loaded http://localhost:52319/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52319/tests/kraken/driver.html?testName=imaging-darkroom) 12:55:17 INFO - PROCESS | 2494 | RSS: Main: 250114048 12:55:17 INFO - PROCESS | 2494 | 12:55:20 INFO - PROCESS | 2494 | Cycle 1(1): loaded http://localhost:52319/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52319/tests/kraken/driver.html?testName=imaging-desaturate) 12:55:20 INFO - PROCESS | 2494 | RSS: Main: 250191872 12:55:20 INFO - PROCESS | 2494 | 12:55:23 INFO - PROCESS | 2494 | Cycle 1(1): loaded http://localhost:52319/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52319/tests/kraken/driver.html?testName=json-parse-financial) 12:55:24 INFO - PROCESS | 2494 | RSS: Main: 249491456 12:55:24 INFO - PROCESS | 2494 | 12:55:26 INFO - PROCESS | 2494 | Cycle 1(1): loaded http://localhost:52319/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52319/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:55:26 INFO - PROCESS | 2494 | RSS: Main: 248344576 12:55:26 INFO - PROCESS | 2494 | 12:55:28 INFO - PROCESS | 2494 | Cycle 1(1): loaded http://localhost:52319/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52319/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:55:29 INFO - PROCESS | 2494 | RSS: Main: 248434688 12:55:29 INFO - PROCESS | 2494 | 12:55:31 INFO - PROCESS | 2494 | Cycle 1(1): loaded http://localhost:52319/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52319/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:55:32 INFO - PROCESS | 2494 | RSS: Main: 248692736 12:55:32 INFO - PROCESS | 2494 | 12:55:35 INFO - PROCESS | 2494 | Cycle 1(1): loaded http://localhost:52319/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52319/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:55:35 INFO - PROCESS | 2494 | RSS: Main: 249040896 12:55:35 INFO - PROCESS | 2494 | 12:55:39 INFO - PROCESS | 2494 | Cycle 1(1): loaded http://localhost:52319/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52319/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:55:39 INFO - PROCESS | 2494 | RSS: Main: 257409024 12:55:39 INFO - PROCESS | 2494 | 12:55:41 INFO - PROCESS | 2494 | Cycle 1(1): loaded http://localhost:52319/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52319/tests/kraken/driver.html?testName=ai-astar) 12:55:41 INFO - PROCESS | 2494 | RSS: Main: 258523136 12:55:41 INFO - PROCESS | 2494 | 12:55:41 INFO - PROCESS | 2494 | __start_tp_report 12:55:41 INFO - PROCESS | 2494 | _x_x_mozilla_page_load 12:55:41 INFO - PROCESS | 2494 | _x_x_mozilla_page_load_details 12:55:41 INFO - PROCESS | 2494 | |i|pagename|runs| 12:55:41 INFO - PROCESS | 2494 | |0;ai-astar;114;120;119;118;119;118;119;119;120;119 12:55:41 INFO - PROCESS | 2494 | |1;audio-beat-detection;145;143;110;157;144;141;143;114;128;113 12:55:41 INFO - PROCESS | 2494 | |2;audio-dft;230;281;192;193;196;197;198;198;194;227 12:55:41 INFO - PROCESS | 2494 | |3;audio-fft;75;66;70;85;51;69;85;80;70;60 12:55:41 INFO - PROCESS | 2494 | |4;audio-oscillator;80;67;86;74;71;70;82;69;73;83 12:55:41 INFO - PROCESS | 2494 | |5;imaging-gaussian-blur;92;87;89;87;89;86;91;106;109;74 12:55:41 INFO - PROCESS | 2494 | |6;imaging-darkroom;97;97;96;100;95;149;123;96;96;96 12:55:41 INFO - PROCESS | 2494 | |7;imaging-desaturate;93;94;93;91;95;93;78;151;147;95 12:55:41 INFO - PROCESS | 2494 | |8;json-parse-financial;56;58;57;59;68;92;78;64;58;58 12:55:41 INFO - PROCESS | 2494 | |9;json-stringify-tinderbox;70;79;69;79;72;73;77;104;91;74 12:55:41 INFO - PROCESS | 2494 | |10;stanford-crypto-aes;89;87;86;84;87;85;88;86;85;83 12:55:41 INFO - PROCESS | 2494 | |11;stanford-crypto-ccm;145;153;146;152;149;164;157;143;200;146 12:55:41 INFO - PROCESS | 2494 | |12;stanford-crypto-pbkdf2;206;154;218;167;208;228;248;233;175;198 12:55:41 INFO - PROCESS | 2494 | |13;stanford-crypto-sha256-iterative;66;67;72;82;89;87;73;67;87;62 12:55:41 INFO - PROCESS | 2494 | __end_tp_report 12:55:41 INFO - PROCESS | 2494 | __start_cc_report 12:55:41 INFO - PROCESS | 2494 | _x_x_mozilla_cycle_collect,955 12:55:41 INFO - PROCESS | 2494 | __end_cc_report 12:55:41 INFO - PROCESS | 2494 | __startTimestamp1485377741898__endTimestamp 12:55:41 INFO - PROCESS | 2494 | 12:55:41 INFO - PROCESS | 2494 | ------- Summary: start ------- 12:55:41 INFO - PROCESS | 2494 | Number of tests: 14 12:55:41 INFO - PROCESS | 2494 | 12:55:41 INFO - PROCESS | 2494 | [#0] ai-astar Cycles:10 Average:118.50 Median:119.00 stddev:1.72 (1.4%) stddev-sans-first:0.71 12:55:41 INFO - PROCESS | 2494 | Values: 114.0 120.0 119.0 118.0 119.0 118.0 119.0 119.0 120.0 119.0 12:55:41 INFO - PROCESS | 2494 | 12:55:41 INFO - PROCESS | 2494 | [#1] audio-beat-detection Cycles:10 Average:133.80 Median:143.00 stddev:16.38 (11.5%) stddev-sans-first:16.86 12:55:41 INFO - PROCESS | 2494 | Values: 145.0 143.0 110.0 157.0 144.0 141.0 143.0 114.0 128.0 113.0 12:55:41 INFO - PROCESS | 2494 | 12:55:41 INFO - PROCESS | 2494 | [#2] audio-dft Cycles:10 Average:210.60 Median:198.00 stddev:28.38 (14.3%) stddev-sans-first:29.22 12:55:41 INFO - PROCESS | 2494 | Values: 230.0 281.0 192.0 193.0 196.0 197.0 198.0 198.0 194.0 227.0 12:55:41 INFO - PROCESS | 2494 | 12:55:41 INFO - PROCESS | 2494 | [#3] audio-fft Cycles:10 Average:71.10 Median:72.50 stddev:10.75 (14.8%) stddev-sans-first:11.31 12:55:41 INFO - PROCESS | 2494 | Values: 75.0 66.0 70.0 85.0 51.0 69.0 85.0 80.0 70.0 60.0 12:55:41 INFO - PROCESS | 2494 | 12:55:41 INFO - PROCESS | 2494 | [#4] audio-oscillator Cycles:10 Average:75.50 Median:77.00 stddev:6.69 (8.7%) stddev-sans-first:6.89 12:55:41 INFO - PROCESS | 2494 | Values: 80.0 67.0 86.0 74.0 71.0 70.0 82.0 69.0 73.0 83.0 12:55:41 INFO - PROCESS | 2494 | 12:55:41 INFO - PROCESS | 2494 | [#5] imaging-gaussian-blur Cycles:10 Average:91.00 Median:88.00 stddev:10.02 (11.4%) stddev-sans-first:10.62 12:55:41 INFO - PROCESS | 2494 | Values: 92.0 87.0 89.0 87.0 89.0 86.0 91.0 106.0 109.0 74.0 12:55:41 INFO - PROCESS | 2494 | 12:55:41 INFO - PROCESS | 2494 | [#6] imaging-darkroom Cycles:10 Average:104.50 Median:96.00 stddev:17.75 (18.5%) stddev-sans-first:18.61 12:55:41 INFO - PROCESS | 2494 | Values: 97.0 97.0 96.0 100.0 95.0 149.0 123.0 96.0 96.0 96.0 12:55:41 INFO - PROCESS | 2494 | 12:55:41 INFO - PROCESS | 2494 | [#7] imaging-desaturate Cycles:10 Average:103.00 Median:93.00 stddev:24.76 (26.6%) stddev-sans-first:26.00 12:55:41 INFO - PROCESS | 2494 | Values: 93.0 94.0 93.0 91.0 95.0 93.0 78.0 151.0 147.0 95.0 12:55:41 INFO - PROCESS | 2494 | 12:55:41 INFO - PROCESS | 2494 | [#8] json-parse-financial Cycles:10 Average:64.80 Median:61.50 stddev:11.72 (19.1%) stddev-sans-first:11.99 12:55:41 INFO - PROCESS | 2494 | Values: 56.0 58.0 57.0 59.0 68.0 92.0 78.0 64.0 58.0 58.0 12:55:41 INFO - PROCESS | 2494 | 12:55:41 INFO - PROCESS | 2494 | [#9] json-stringify-tinderbox Cycles:10 Average:78.80 Median:75.50 stddev:10.87 (14.4%) stddev-sans-first:11.05 12:55:41 INFO - PROCESS | 2494 | Values: 70.0 79.0 69.0 79.0 72.0 73.0 77.0 104.0 91.0 74.0 12:55:41 INFO - PROCESS | 2494 | 12:55:41 INFO - PROCESS | 2494 | [#10] stanford-crypto-aes Cycles:10 Average:86.00 Median:86.50 stddev:1.83 (2.1%) stddev-sans-first:1.58 12:55:41 INFO - PROCESS | 2494 | Values: 89.0 87.0 86.0 84.0 87.0 85.0 88.0 86.0 85.0 83.0 12:55:41 INFO - PROCESS | 2494 | 12:55:41 INFO - PROCESS | 2494 | [#11] stanford-crypto-ccm Cycles:10 Average:155.50 Median:152.50 stddev:16.87 (11.1%) stddev-sans-first:17.46 12:55:41 INFO - PROCESS | 2494 | Values: 145.0 153.0 146.0 152.0 149.0 164.0 157.0 143.0 200.0 146.0 12:55:41 INFO - PROCESS | 2494 | 12:55:41 INFO - PROCESS | 2494 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:203.50 Median:213.00 stddev:30.39 (14.3%) stddev-sans-first:32.22 12:55:41 INFO - PROCESS | 2494 | Values: 206.0 154.0 218.0 167.0 208.0 228.0 248.0 233.0 175.0 198.0 12:55:41 INFO - PROCESS | 2494 | 12:55:41 INFO - PROCESS | 2494 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.20 Median:77.50 stddev:10.13 (13.1%) stddev-sans-first:10.18 12:55:41 INFO - PROCESS | 2494 | Values: 66.0 67.0 72.0 82.0 89.0 87.0 73.0 67.0 87.0 62.0 12:55:41 INFO - PROCESS | 2494 | -------- Summary: end -------- 12:55:41 INFO - PROCESS | 2494 | 12:55:42 INFO - TEST-INFO | 2494: exit 0 12:55:42 INFO - TEST-OK | kraken | took 61107ms 12:55:42 INFO - SUITE-END | took 1176s 12:55:43 INFO - Completed test suite (00:19:37) 12:55:43 INFO - javascript benchmark 12:55:43 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7361.590666046076, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3145.6539780624125, 3407.4888766684994, 3411.9317613647727, 3421.914452138696, 3437.6906078452935, 5830.271216097988, 5895.204147440094, 5915.852103697404, 5923.703814809264, 5951.345352011286, 5863.8534036649, 5937.52499800015, 5938.732757025933, 5984.551158663106, 5990.520758339333, 5291.550218231455, 5371.543418809386, 5376.193570964367, 5396.487333703311, 5416.593755468356, 5407.702576358299, 5423.593230507708, 5425.0, 5429.348478182624, 5443.156310771817, 5262.394824595182, 5299.258103865462, 5406.54044406224, 5409.83770486886, 5453.836384908459, 5309.442508536606, 5325.094733895227, 5350.705711185883, 5404.297441332602, 5442.210879422461, 4411.514733379326, 5292.1003429416705, 5341.599380046512, 5387.24578559002, 5414.8917021659345, 5160.845174644766, 5669.092945128759, 5831.562632802515, 5865.88268234633, 5998.670073145975, 6221.0, 6241.7191226394925, 6243.43809057187, 6284.7486100555925, 6329.651869147195, 6273.843153921142, 6290.591111577733, 6301.6534090625, 6302.621842689453, 6311.810645680637, 6031.09533569968, 6053.969730151321, 6066.787662431838, 6067.302260240129, 6075.726592303357, 6204.5967012144065, 6231.3145553989025, 6253.843653908598, 6285.214348206474, 6308.337624549448, 6240.157578726905, 6246.937530624727, 6249.9062514062325, 6270.122182894398, 6275.3724627537795, 6268.623882567061, 6304.148939893055, 6306.24325080984, 6309.842253943688, 6321.905171422432, 6145.354737752561, 6214.720337584819, 6249.0, 6268.435840774352, 6336.936630633634, 6248.250209974832, 6251.843703907439, 6302.369763023753, 6307.990721484541, 6308.842278943063, 4350.456192975878, 5059.06407314648, 5060.49395060491, 5061.190209566452, 5064.341635587423, 4128.504579450425, 4345.3699213613845, 4518.186726389215, 4526.59260666535, 4542.11428771393, 4426.15902978433, 4988.176950803149, 5335.599830012765, 6003.6397816131175, 6039.305479869783, 6239.376062393724, 6269.810860705687, 6309.558330916791, 6315.715792789336, 6335.334789847092, 5322.3879253885525, 5566.691827420553, 5733.3406658234, 5815.94184058154, 5847.883042339129, 5555.111182210827, 5566.749496272678, 5576.972115139399, 5577.721113944286, 5590.161475778666, 6403.935960640334, 6405.167328247298, 6414.03789431589, 6424.935750642434, 6436.00241962506, 5756.56825738063, 6079.209702738615, 6211.472024877936, 6231.688415579293, 6271.529635277464, 5071.847844564669, 6266.060090986389, 6333.461655759128, 6447.580907241015, 6453.354664533696, 6287.842803929939, 6289.150964619717, 6304.275008374096, 6308.463780578519, 6320.462760665211, 6125.601835880653, 6142.32434432221, 6274.372562743689, 6279.748810047547, 6303.9684801575695, 4816.301636262652, 6099.451049405575, 6148.047052706837, 6182.474489668429, 6245.812625621239, 6199.752009919553, 6257.1240026395435, 6270.310265870842, 6273.090401891792, 6281.560290779602, 6038.7282572283475, 6092.817215483543, 6141.877162456815, 6177.7837775677635, 6183.350748171557, 6100.572959892765, 6157.168782214344, 6168.136460895388, 6186.381361863778, 6198.194234749454, 6134.877302454015, 6146.508279337731, 6158.568900176944, 6164.537659675452, 6164.537659675632], "value": 5762.242483361918, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [421.5868448920056, 471.25541644202156, 478.94492133404646, 496.60519886690054, 545.7216819422096, 29950.0, 42304.0, 42505.99999999996, 44828.77585612075, 44878.775606122006, 43795.343069853974, 44262.0, 44271.77864110683, 44342.33486497705, 44361.669149925474, 8573.18554737299, 9318.906810931889, 9482.241420686329, 9505.334626576143, 9548.28387870909, 8631.956840215822, 11265.15511336653, 11614.709632259219, 11627.476763545661, 11828.940855295712, 11505.42472876357, 11541.711457213552, 11544.595939142131, 11572.884271157265, 11609.941950290236, 11478.253913495639, 11524.250923689933, 11532.25040372373, 11575.0161236295, 11585.6524304271, 11433.885661143366, 11465.598704045402, 11494.655160345203, 11516.42417879111, 11524.88475115255, 34487.655123448945, 41631.75104746863, 41911.1617767643, 41919.1616167678, 41941.79029104865, 3583.942736892623, 3663.285659296438, 4035.051762835731, 4195.139996300751, 4247.702660813744, 24215.515689686283, 29548.409031819458, 29865.954691585906, 30005.69994300051, 32248.838755806304, 3062.5099984002677, 3121.250899784058, 3236.8867089651985, 3309.0, 3378.8310584470923, 460701.0, 463899.0, 464242.0, 465540.0, 466133.0, 63561.68219158875, 63938.6803065991, 64152.679236603515, 64208.0, 64410.71178576496, 58518.41481585215, 66618.00072998909, 66989.66505167443, 67366.0, 67398.0, 16203.918980405022, 16984.660306793794, 16985.915070424817, 17033.91483042577, 17063.48809535716, 11806.0, 12100.576479823163, 12896.677583060498, 13136.60590182277, 13198.670033249246, 14576.906731995143, 15283.006604570668, 16686.83313166877, 17248.965062096064, 17297.56756081083, 6501.024846273097, 6878.76440156951, 7118.822029449305, 7169.247229040876, 7284.453665975073, 883.306604315622, 890.8975467821153, 896.5741272895453, 912.8128733609594, 922.4640972780246, 30164.113785558162, 30210.09369718868, 30540.389192216033, 30826.229344266125, 31173.688263117077, 1048.6015314180715, 1050.2175878970063, 1096.9122470202365, 1104.26566333389, 1167.626359564948, 14235.85764142366, 15861.0, 16051.598710032342, 16176.191190440666, 16653.41713040038, 580713.0, 585019.149808505, 592648.0, 592702.0, 594677.0, 61495.07757383643, 61528.38471615316, 61667.383326166164, 61743.38256617466, 61833.69083154555, 1439.3393451882635, 1445.4435042508499, 1455.23600109941, 1474.086066638691, 1481.451862810767, 28796.280092997844, 29921.102366929026, 29988.250293742833, 30515.54226686557, 30666.540001899542, 50897.74551127221, 51177.0, 51243.74378128235, 51270.48729512657, 51348.22977655413, 516030.0, 518205.0, 519205.0, 519470.0, 520317.0, 18010.0, 24105.397365066012, 24169.03323867096, 25895.741042589332, 26150.08474703376, 34228.657713422544, 41507.792461037505, 42172.57827421809, 42662.36006459843, 43164.92087697833, 29505.37720425397, 35303.0, 35405.114872128404, 35647.82176089103, 35665.82167089148, 26475.867620661775, 32491.675083248865, 35231.0, 35356.93929182128, 35615.643843561236, 33753.64985400556, 33981.98054058382, 34267.82866085654, 34564.135896602784, 34857.65142348645, 29767.702322976493, 30657.386852263277, 31355.529667054558, 31602.525962111045, 31689.36621267608, 20461.488462788548, 21621.675674865204, 22478.438039049153, 23682.934267958328, 23769.762302376756], "value": 19827.8409447767, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [390.1105479506726, 390.2176136845624, 399.1956208240395, 400.7475290566943, 418.41839842618765, 15675.216239188052, 16039.599010024755, 20485.38543843687, 21030.684539731898, 21542.89228553857, 19878.204871805112, 20125.993700314997, 21158.25946091887, 21455.892720536416, 22032.559348813054, 4785.904281914369, 5047.747612619372, 5889.146073819294, 5900.085486749561, 5911.822645320647, 5714.342850572175, 5770.740316685759, 5860.267466566679, 5881.411858814127, 5898.587098903078, 5729.885402291951, 5793.71031448428, 5806.071028635419, 5808.506276966463, 5819.854503637401, 5575.916361254584, 5763.855903602402, 5771.971140144315, 5789.594728369016, 5817.650940943557, 5103.770330335144, 5151.768170432302, 5152.871178220537, 5153.742312884341, 5160.14857548504, 25871.09451169219, 25966.74033259681, 26288.474230515283, 26408.0, 26686.065987690337, 2762.1437354420195, 2810.662720473536, 2838.886444542216, 2840.4461130079644, 2843.331817022998, 15799.131047792367, 16113.597160070974, 16189.35242590295, 16372.263248153862, 16603.0, 1866.7946525882141, 1873.7189421586734, 1880.9059547022594, 1881.5202123458566, 1894.4127320530542, 121896.0, 124663.0, 131511.0, 134959.0, 138604.30697846547, 12023.879761202452, 12029.338386388832, 12180.695482612828, 12273.877261227273, 12358.691032724253, 13307.201567905957, 14263.78604320936, 14832.406703731946, 15986.280617372246, 16015.519534413987, 8393.580320983927, 8728.69449569262, 8775.517346545874, 8780.736577902673, 8943.642254309885, 8706.1729135732, 8707.606782914703, 8721.651133954698, 8818.603162857687, 8855.557222138867, 3743.4384842273876, 4674.883127921761, 4684.344191813149, 4686.74222917736, 4688.296755486636, 2015.8488113391554, 2066.1941842681067, 2070.472029632434, 2076.0761461149646, 2076.9480762981048, 981.6796408830112, 997.7256254530063, 1004.492731170754, 1012.8176928152856, 1020.5968642386364, 21080.578388432146, 21334.57330853374, 21530.0, 21625.269303634548, 21983.0, 845.9679191386499, 963.3449254506862, 1066.6106870992187, 1084.1164450972433, 1093.5297821936645, 9043.457392556467, 10081.798364032826, 10606.05307005552, 11524.250923689933, 11658.94170529142, 748950.0, 753503.0, 767889.0, 768430.0, 785053.0, 144883.2755836214, 145281.27359363346, 145812.0, 154516.22741886444, 157945.0, 977.6920270115033, 997.4613708597421, 1000.8348622477209, 1028.7633844215723, 1063.8616979792732, 19719.901400492905, 20272.898635506728, 20538.794612053687, 20609.072591733107, 20704.757714537176, 45129.548704512534, 47750.76124619355, 48990.75504622454, 55121.51953162037, 60392.094118589135, 81295.59352203201, 88458.55770721105, 89761.0, 90750.0, 92545.01788757055, 8454.239118479367, 8454.323654107533, 8487.787805304917, 8675.869861952202, 8702.347323950855, 11819.645410637695, 13158.539451119164, 13223.272720000108, 13346.399412026289, 13555.457781688621, 9130.406523575946, 11170.050545703381, 11496.0, 11539.826902596637, 11616.88383116158, 11042.558297668003, 11468.541258349573, 11508.36703981289, 11563.248388854698, 11731.23746956445, 11408.34578986059, 11434.599789007345, 11555.306681599131, 11812.645620631394, 12011.75976480483, 8428.789280268042, 8494.617742201739, 8593.69922052725, 8675.783105422162, 8712.607932642919, 11122.499487523242, 11316.660500185008, 11358.772824543628, 11409.657710268706, 11484.94257528707], "value": 10074.46854450929, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1431.4684480496242, 1433.354990254385, 1450.3111022264425, 1453.1208618785633, 1463.0124665850549, 7573.166951635316, 7796.142424333319, 7995.640196191171, 8475.91524084759, 8496.362772792043, 7241.782746517613, 7931.889535465039, 7969.203079692028, 8331.541765202912, 8345.749627511184, 2283.748787633359, 2336.147306233221, 2337.5792357375667, 2338.707661542307, 2348.1429278313376, 2063.1644184105385, 2246.955060898781, 2341.91803286885, 2348.953020939589, 2354.0348457132577, 2295.9426014349606, 2304.9078036878504, 2312.329424466911, 2314.9421264468356, 2327.6857624220766, 2275.8065564427043, 2287.1537531113427, 2302.447412620959, 2305.2738387408, 2313.3175713164655, 2270.3302525754775, 2300.183434880627, 2300.8849557522226, 2301.620232661609, 2340.32130682102, 8386.790330241733, 8460.915390846074, 8475.110113438062, 8481.448705834102, 8523.616437260276, 4516.503184649719, 5049.343585333883, 5198.934218485239, 5214.556762675177, 5223.973880130613, 3289.8848540300974, 3360.4959256111533, 3480.4083305838067, 3532.7880327180196, 3543.6102028776813, 1187.8277649740826, 1195.4500929572407, 1202.1464760020397, 1215.817627355895, 1234.4383305596023, 10851.620193293276, 10910.0, 10987.670369888836, 10990.0, 11031.007209351117, 4451.465824101064, 4628.143793398232, 4707.587723682881, 4719.244920812654, 4727.267273572602, 9447.527623618791, 9580.233581313478, 9585.424874507413, 9610.375325603813, 9616.711498655051, 5231.973840130775, 5248.737563121905, 5251.868703282448, 5258.2112683097075, 5262.60530460217, 4167.937480937789, 4242.257604919127, 4702.811887524529, 4710.929336059962, 4758.547937945889, 3768.4158955361963, 3799.810009499514, 3865.729398942047, 3880.3403421418425, 3891.6302951220155, 1286.8069789531646, 1304.1588175626669, 1306.5884246462333, 1314.704191556892, 1316.6642506161058, 803.4295650088503, 863.9784005399915, 930.9898759845508, 955.4697143085655, 956.2111258212003, 4889.853304400874, 5444.509994100471, 5685.948099601587, 5809.680467574324, 5901.321348044944, 1014.1987829614521, 1017.2472370445817, 1036.7978244242452, 1039.0648416425126, 1046.2780681329857, 3699.0, 3768.962310376916, 3780.1872597391143, 3866.478025466582, 3957.881263562098, 9765.218782497383, 10110.039546243095, 10121.342112762646, 10129.189664826796, 10165.085142337226, 9976.950115249378, 10088.041636044563, 10101.747456313504, 10115.342502737298, 10128.493575321205, 7631.313181813553, 7846.058472983279, 7894.407919406068, 7920.643571039317, 7931.286184243446, 4814.20565606671, 5125.051865404912, 5224.555912747459, 5555.444455554574, 5579.609427340048, 7400.814979625552, 8169.959150204211, 8822.073682263526, 9306.534673266175, 9314.72055838326, 8055.919440805751, 8810.383273170817, 8950.820983580423, 9153.954230228806, 9305.767355816159, 6582.967085164735, 6763.797086087426, 6985.1617805863625, 7170.713171473083, 7293.489455738048, 7711.421643376657, 7951.9662443881925, 8019.278264956182, 8020.398470114648, 8027.39794515402, 6055.394460553909, 6949.7567585134275, 7089.858202836018, 7115.466340024415, 7188.209296977433, 6995.615241161785, 7024.789256322114, 7048.506604537838, 7062.717491300496, 7085.468589855885, 6329.525285603506, 6438.5171112165835, 6511.772087976898, 6526.738930442729, 6566.310537393505, 4930.506949305184, 5087.4403815581, 5104.9489505104475, 5112.437631860418, 5133.8716532087, 4569.794359253775, 5037.471065538212, 5055.974720126376, 5144.511271429208, 5214.504622060898], "value": 4419.256755979184, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [319.54464887535255, 324.6769464382939, 337.51229473410916, 344.7586689317478, 376.7701701961801, 4873.634477414194, 5281.339832520935, 5319.936012797437, 5332.280142180809, 5519.558435325174, 4533.3426653135275, 5378.596605254612, 5444.564434845212, 5458.344998600173, 5472.699001554913, 3320.684534969174, 3511.4732790081566, 4137.77242251676, 4173.5409104998425, 4193.895152621194, 3845.42318652203, 4178.707490475668, 4212.557681443435, 4238.9152216955645, 4239.7668128252935, 3471.409860323751, 3805.6384643458828, 3812.809359532026, 3843.942340864889, 4208.242516347056, 4061.8375264989363, 4175.603317684815, 4203.684723645739, 4236.4704411948505, 4236.957630423687, 3160.8419579020956, 3693.519842420468, 3697.6856967157823, 3733.7199710021855, 3752.3058234226755, 4573.474050484204, 5117.437081920985, 5224.555912747421, 5272.542918079807, 5325.360956685184, 3941.7043721720984, 4418.182636212279, 4433.069055498349, 4683.461401938786, 4876.878078048042, 4856.595984987419, 5076.69010600176, 5650.74571644277, 5709.714514274269, 5744.770209191627, 3514.455259434766, 4159.9792001040105, 4162.125953549758, 4177.436046133764, 4183.824411306104, 5323.482807399872, 5624.775008999635, 5654.0, 5659.349174844863, 5982.222311099573, 3822.522184726909, 3873.0, 3942.48747662802, 3954.8728612345917, 3959.9604003959594, 3639.3085313790825, 3697.0757310672334, 4006.1587066716315, 4025.637692607621, 4051.47330846988, 3928.685705143582, 3949.328614135604, 4639.559241872017, 4718.0, 4741.928871066937, 5359.5712343012465, 5406.513413792699, 5769.365369809318, 5770.394108618619, 5793.971030144907, 1201.423316807937, 1270.2568997136777, 1288.671388795851, 1294.1458637299338, 1294.8769866862633, 814.9609248208582, 839.4963022186639, 865.523961820996, 886.4504007515253, 886.534569351083, 4321.611055005002, 4559.814448243481, 4646.7444290564345, 4994.850154495371, 5298.019861330414, 5293.782430041112, 5865.824025279248, 5937.248511688976, 5951.9702401488585, 5992.730327135289, 4964.826231081895, 5291.444398338196, 5297.364316282071, 5326.201069839555, 5363.973180134074, 2522.8864701088496, 2536.8964500442366, 2903.6951120132503, 2914.519104347801, 2938.0, 5068.0, 5127.077345995252, 5317.7872885084935, 5863.071741932915, 5967.313758917693, 5641.661500309994, 5668.603197776116, 5805.023558862193, 5839.386864379264, 6051.394860513913, 4971.726555039435, 5398.949593479671, 5766.855828604318, 6115.938840611537, 6232.875342493216, 4376.671749618855, 5049.419316778617, 5884.205317378241, 5927.31835838884, 6024.728887199999, 5478.45215478449, 5818.679972601378, 5868.67722275279, 5883.176355310175, 5910.601404192521, 6098.603590766586, 6105.8168254952425, 6134.8159555213415, 6171.228596425447, 6222.81331560054, 3851.0, 3877.302085624615, 4231.4710661167355, 4294.957050429456, 4311.805968731352, 5058.443571207091, 5608.214849921096, 5777.140315543833, 5831.825045248649, 5860.520413413472, 5023.4725353838785, 5726.141078838207, 5728.684922329312, 5745.1382292656435, 5757.280339957505, 4437.866863994085, 5298.94701053, 5394.445748415694, 5533.529649979636, 5627.268455100974, 5002.7993281611425, 5286.920696189483, 5412.0, 5529.225908372911, 5557.110862262019, 5511.834644960658, 5530.695811730394, 5677.489025987681, 5680.829575112753, 5692.146178073156, 4675.929861052019, 5518.53092487127, 5529.419410961791, 5537.197106419466, 5552.194931734956], "value": 4135.08641317788, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [695.6521739130435, 738.1068906622986, 756.8107973006748, 790.0045942112939, 815.8816971539127, 14944.77582836257, 15493.690126197469, 15522.534323970285, 15576.766348504752, 15621.531354059382, 14154.575362739108, 14306.0, 14314.212718300489, 14314.284285785721, 14315.141091534515, 674.1236392689509, 692.5533031194901, 837.5728378526965, 839.613777662273, 865.1348651348651, 849.800296930221, 858.3004851046409, 865.277493293101, 867.1718508824058, 868.6178081644057, 907.3693782820909, 927.0312523413049, 928.0533855467419, 929.0894922975488, 930.5440334236243, 914.5107367558358, 916.3402350307767, 919.7332773495679, 921.1525396635078, 922.2760133295365, 669.933006699331, 689.2039694153261, 690.3959035843637, 690.4441924250976, 690.5166383531548, 15019.57314055163, 15139.697206055927, 15254.711793230994, 15642.45251416195, 15970.961887477277, 14352.354144063438, 14557.053791503518, 14714.33785479656, 14778.556643300719, 14826.777598336033, 10015.74960625983, 10371.0, 10476.0, 11330.0, 11945.163838531307, 3622.2936527377174, 3729.6940260333777, 3730.0, 3865.8840234793, 3869.5163104611925, 84200.0, 104023.0, 105574.47212763964, 106648.0, 106989.0, 9262.629494820267, 9582.233421326277, 11134.220604557604, 11199.60801371948, 11329.03703185222, 18638.347657832182, 23180.304590862303, 23215.651765223534, 23411.648825267293, 23670.0, 7792.337651299703, 7858.17489163641, 7971.760847174594, 7971.920280797118, 8029.4379393441895, 4262.722923009995, 4268.765217912983, 4347.913041739147, 4374.496932852699, 4470.0, 3591.586967498719, 3649.105969037603, 3670.4861319415304, 3675.467057276733, 3679.7424180307657, 652.6508318049821, 653.9280679125293, 659.9637019963852, 671.1744554198364, 673.0375562945036, 674.231376231097, 675.8040168351222, 690.6788343420334, 712.127643636541, 741.499487845702, 10258.692239232834, 10372.740681482872, 10387.584496620202, 10598.735031624272, 10772.992664304636, 675.3482889012126, 702.7540360873653, 708.0865683268629, 709.9112610923635, 713.757322510349, 4932.21084626458, 5030.119729047402, 5039.269305950616, 5045.344105266292, 5094.133997220481, 89958.55020724854, 104313.47843260736, 109135.81728365389, 111284.33073503904, 113185.43407283076, 89550.0, 90305.54847225854, 92012.53993729988, 93420.53289733508, 94665.52667236757, 82754.0, 82961.0, 84275.0, 85402.0, 86534.1346586526, 9778.364406313567, 9803.754906127404, 9837.60649574009, 9897.802043959224, 10069.798604028025, 54288.0, 54574.908501830534, 54841.451585483635, 56238.59403514945, 56325.591860202185, 57877.71061144667, 71279.64360178166, 71953.28046719467, 73381.79854604157, 74042.62978685288, 11720.88279117198, 11937.343446110548, 12015.45930433115, 12224.266544007389, 12312.938435308124, 12350.75298494043, 12750.362481876055, 12854.871451285368, 12888.742225155633, 12898.48406063747, 11681.06551475863, 11985.940070299594, 12145.392730363623, 12418.316992565495, 12497.18768280059, 11544.538218471167, 12177.0, 13755.10591811529, 14025.29873506341, 14120.223387713775, 13145.474181032652, 13313.600591982256, 13347.66630834237, 13401.731965360834, 13706.862931370559, 10681.412522311037, 11769.705757355792, 11806.173567850168, 12032.699182520157, 12086.274823510617, 11363.0, 11480.024197943269, 11761.647150585497, 11775.646730598095, 11835.408229588314], "value": 7565.888978655221, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1571.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 120.0, 119.0, 118.0, 119.0, 118.0, 119.0, 119.0, 120.0, 119.0], "value": 118.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 143.0, 110.0, 157.0, 144.0, 141.0, 143.0, 114.0, 128.0, 113.0], "value": 133.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [230.0, 281.0, 192.0, 193.0, 196.0, 197.0, 198.0, 198.0, 194.0, 227.0], "value": 210.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 66.0, 70.0, 85.0, 51.0, 69.0, 85.0, 80.0, 70.0, 60.0], "value": 71.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 67.0, 86.0, 74.0, 71.0, 70.0, 82.0, 69.0, 73.0, 83.0], "value": 75.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 87.0, 89.0, 87.0, 89.0, 86.0, 91.0, 106.0, 109.0, 74.0], "value": 91.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 97.0, 96.0, 100.0, 95.0, 149.0, 123.0, 96.0, 96.0, 96.0], "value": 104.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 93.0, 91.0, 95.0, 93.0, 78.0, 151.0, 147.0, 95.0], "value": 103.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 58.0, 57.0, 59.0, 68.0, 92.0, 78.0, 64.0, 58.0, 58.0], "value": 64.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 79.0, 69.0, 79.0, 72.0, 73.0, 77.0, 104.0, 91.0, 74.0], "value": 78.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 86.0, 84.0, 87.0, 85.0, 88.0, 86.0, 85.0, 83.0], "value": 86.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 153.0, 146.0, 152.0, 149.0, 164.0, 157.0, 143.0, 200.0, 146.0], "value": 155.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [206.0, 154.0, 218.0, 167.0, 208.0, 228.0, 248.0, 233.0, 175.0, 198.0], "value": 203.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 72.0, 82.0, 89.0, 87.0, 73.0, 67.0, 87.0, 62.0], "value": 75.2, "unit": "score"}]}]} 12:55:43 INFO - Return code: 0 12:55:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:55:43 ERROR - # TBPL SUCCESS # 12:55:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:55:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:55:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:55:43 INFO - 2017-01-25 12:55:43.223 screenresolution[2499:29353] starting screenresolution argv=screenresolution get 12:55:43 INFO - 2017-01-25 12:55:43.225 screenresolution[2499:29353] Display 0: 1600x1200x32@60 12:55:43 INFO - 2017-01-25 12:55:43.231 screenresolution[2500:29356] starting screenresolution argv=screenresolution list 12:55:43 INFO - Available Modes on Display 0 12:55:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:55:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:55:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:55:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:55:43 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:55:43 INFO - Intel Iris: 12:55:43 INFO - Chipset Model: Intel Iris 12:55:43 INFO - Type: GPU 12:55:43 INFO - Bus: Built-In 12:55:43 INFO - VRAM (Dynamic, Max): 1536 MB 12:55:43 INFO - Vendor: Intel (0x8086) 12:55:43 INFO - Device ID: 0x0a2e 12:55:43 INFO - Revision ID: 0x0009 12:55:43 INFO - Displays: 12:55:43 INFO - Display: 12:55:43 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:55:43 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:55:43 INFO - Display Serial Number: 1600x1200 60 12:55:43 INFO - Main Display: Yes 12:55:43 INFO - Mirror: Off 12:55:43 INFO - Online: Yes 12:55:43 INFO - Rotation: Supported 12:55:43 INFO - Return code: 0 12:55:43 INFO - Running post-action listener: _resource_record_post_action 12:55:43 INFO - [mozharness: 2017-01-25 20:55:43.467606Z] Finished run-tests step (success) 12:55:43 INFO - Running post-run listener: _resource_record_post_run 12:55:43 INFO - Total resource usage - Wall time: 1198s; CPU: 54.0%; Read bytes: 344064; Write bytes: 509828096; Read time: 3; Write time: 1634 12:55:43 INFO - TinderboxPrint: CPU usage
54.2% 12:55:43 INFO - TinderboxPrint: I/O read bytes / time
344,064 / 3 12:55:43 INFO - TinderboxPrint: I/O write bytes / time
509,828,096 / 1,634 12:55:43 INFO - TinderboxPrint: CPU idle
2,193.8 (45.8%) 12:55:43 INFO - TinderboxPrint: CPU system
968.3 (20.2%) 12:55:43 INFO - TinderboxPrint: CPU user
1,631.7 (34.0%) 12:55:43 INFO - TinderboxPrint: Swap in / out
207,224,832 / 0 12:55:43 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 202934784; Write bytes: 200284160; Read time: 15626; Write time: 447 12:55:43 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 344064; Write bytes: 298812416; Read time: 3; Write time: 1148 12:55:43 INFO - Running post-run listener: _upload_blobber_files 12:55:43 INFO - Blob upload gear active. 12:55:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:55:43 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 12:55:43 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'] 12:55:43 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 12:55:44 INFO - (blobuploader) - INFO - Open directory for files ... 12:55:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:55:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:55:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:55:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:55:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:55:45 INFO - (blobuploader) - INFO - Done attempting. 12:55:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:55:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:55:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:55:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:55:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:55:46 INFO - (blobuploader) - INFO - Done attempting. 12:55:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:55:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:55:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:55:47 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:55:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:55:47 INFO - (blobuploader) - INFO - Done attempting. 12:55:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:55:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:55:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:55:49 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:55:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:55:49 INFO - (blobuploader) - INFO - Done attempting. 12:55:49 INFO - (blobuploader) - INFO - Iteration through files over. 12:55:49 INFO - Return code: 0 12:55:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:55:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:55:49 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a49778d579970e3b2a3bfc56b1eb9d542fa8d6912e41472fea12c9bf5cb85afae1e6b342b373063262795376ec2bc78345461f0d8ac3c9def86f81ed896afdcc", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/da450489f4c0e0d488490646c8889d6f1d19763e4e9ed94909824a606085c0947655e23544549fbb10b995e6025968d67bb84ff6106215dc782c84c3628a7856", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0c21fce5f7904ffcd0fdfb62df87ef730fa673201322d9a735e98882e736336f0dfcf424f46fa5f1cee8d17f12c96d0c19bb12f6489e4539f7b5899d21a90ca2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c54f3347056754134a66e6cc8e3d1a0adb0d9595988d50bacd45db70133b329aff45024305682e8accc0a4a40be21719928ebfb7061d5d36bff21dbc50088088"} 12:55:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:55:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:55:49 INFO - Contents: 12:55:49 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a49778d579970e3b2a3bfc56b1eb9d542fa8d6912e41472fea12c9bf5cb85afae1e6b342b373063262795376ec2bc78345461f0d8ac3c9def86f81ed896afdcc", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/da450489f4c0e0d488490646c8889d6f1d19763e4e9ed94909824a606085c0947655e23544549fbb10b995e6025968d67bb84ff6106215dc782c84c3628a7856", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0c21fce5f7904ffcd0fdfb62df87ef730fa673201322d9a735e98882e736336f0dfcf424f46fa5f1cee8d17f12c96d0c19bb12f6489e4539f7b5899d21a90ca2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c54f3347056754134a66e6cc8e3d1a0adb0d9595988d50bacd45db70133b329aff45024305682e8accc0a4a40be21719928ebfb7061d5d36bff21dbc50088088"} 12:55:49 INFO - Running post-run listener: copy_logs_to_upload_dir 12:55:49 INFO - Copying logs to upload dir... 12:55:49 INFO - mkdir: /builds/slave/test/build/upload/logs 12:55:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1258.136090 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2017-01-25 12:55:49.581818) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-25 12:55:49.586782) ========= 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.IEPyEBJPGu/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.2BI05gQGvJ/Listeners TMPDIR=/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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/a49778d579970e3b2a3bfc56b1eb9d542fa8d6912e41472fea12c9bf5cb85afae1e6b342b373063262795376ec2bc78345461f0d8ac3c9def86f81ed896afdcc", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/da450489f4c0e0d488490646c8889d6f1d19763e4e9ed94909824a606085c0947655e23544549fbb10b995e6025968d67bb84ff6106215dc782c84c3628a7856", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0c21fce5f7904ffcd0fdfb62df87ef730fa673201322d9a735e98882e736336f0dfcf424f46fa5f1cee8d17f12c96d0c19bb12f6489e4539f7b5899d21a90ca2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c54f3347056754134a66e6cc8e3d1a0adb0d9595988d50bacd45db70133b329aff45024305682e8accc0a4a40be21719928ebfb7061d5d36bff21dbc50088088"} build_url:https://queue.taskcluster.net/v1/task/fDe9cMOyQlynK_zBjgF6DA/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011140 build_url: 'https://queue.taskcluster.net/v1/task/fDe9cMOyQlynK_zBjgF6DA/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/a49778d579970e3b2a3bfc56b1eb9d542fa8d6912e41472fea12c9bf5cb85afae1e6b342b373063262795376ec2bc78345461f0d8ac3c9def86f81ed896afdcc", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/da450489f4c0e0d488490646c8889d6f1d19763e4e9ed94909824a606085c0947655e23544549fbb10b995e6025968d67bb84ff6106215dc782c84c3628a7856", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0c21fce5f7904ffcd0fdfb62df87ef730fa673201322d9a735e98882e736336f0dfcf424f46fa5f1cee8d17f12c96d0c19bb12f6489e4539f7b5899d21a90ca2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c54f3347056754134a66e6cc8e3d1a0adb0d9595988d50bacd45db70133b329aff45024305682e8accc0a4a40be21719928ebfb7061d5d36bff21dbc50088088"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-25 12:55:49.628448) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-25 12:55:49.628757) ========= 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.IEPyEBJPGu/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.2BI05gQGvJ/Listeners TMPDIR=/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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.005281 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-25 12:55:49.676916) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-25 12:55:49.677203) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-25 12:55:49.680534) ========= ========= Total master_lag: 0.21 =========