builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0184 starttime: 1489593162.25 results: success (0) buildid: 20170315081436 builduid: 65fc37f2961e43899a1a43e6828f8fee revision: ce8120111f7f2902b3ac88ac35773d9957714a7a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-15 08:52:42.247177) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-15 08:52:42.253255) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-15 08:52:42.253607) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-15 08:52:42.282308) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-15 08:52:42.282694) ========= 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.8Bi9t31hGm/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.plO1YjuOrl/Listeners TMPDIR=/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/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-03-15 08:52:42-- 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% 15.9M=0.001s 2017-03-15 08:52:42 (15.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.163591 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-15 08:52:42.472939) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-15 08:52:42.473275) ========= 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.8Bi9t31hGm/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.plO1YjuOrl/Listeners TMPDIR=/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/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.027254 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-15 08:52:42.524631) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-03-15 08:52:42.525023) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev ce8120111f7f2902b3ac88ac35773d9957714a7a --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 ce8120111f7f2902b3ac88ac35773d9957714a7a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.8Bi9t31hGm/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.plO1YjuOrl/Listeners TMPDIR=/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/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-03-15 08:52:42,626 truncating revision to first 12 chars 2017-03-15 08:52:42,626 Setting DEBUG logging. 2017-03-15 08:52:42,627 attempt 1/10 2017-03-15 08:52:42,627 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/ce8120111f7f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-15 08:52:44,607 unpacking tar archive at: mozilla-aurora-ce8120111f7f/testing/mozharness/ program finished with exit code 0 elapsedTime=2.241981 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-03-15 08:52:44.792238) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-15 08:52:44.792572) ========= script_repo_revision: ce8120111f7f2902b3ac88ac35773d9957714a7a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-15 08:52:44.792984) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-15 08:52:44.793258) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-15 08:52:44.814956) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 25 secs) (at 2017-03-15 08:52:44.815355) ========= /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.8Bi9t31hGm/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.plO1YjuOrl/Listeners TMPDIR=/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/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 08:52:45 INFO - MultiFileLogger online at 20170315 08:52:45 in /builds/slave/test 08:52:45 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 08:52:45 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:52:45 INFO - {'append_to_log': False, 08:52:45 INFO - 'base_work_dir': '/builds/slave/test', 08:52:45 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 08:52:45 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:52:45 INFO - 'branch': 'Mozilla-Aurora', 08:52:45 INFO - 'buildbot_json_path': 'buildprops.json', 08:52:45 INFO - 'config_files': ('talos/mac_config.py',), 08:52:45 INFO - 'default_actions': ('clobber', 08:52:45 INFO - 'read-buildbot-config', 08:52:45 INFO - 'download-and-extract', 08:52:45 INFO - 'populate-webroot', 08:52:45 INFO - 'create-virtualenv', 08:52:45 INFO - 'install', 08:52:45 INFO - 'run-tests'), 08:52:45 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:52:45 INFO - 'download_minidump_stackwalk': True, 08:52:45 INFO - 'download_symbols': 'ondemand', 08:52:45 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:52:45 INFO - 'tooltool.py': '/tools/tooltool.py', 08:52:45 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:52:45 INFO - '/tools/misc-python/virtualenv.py')}, 08:52:45 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:52:45 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:52:45 INFO - 'gecko_profile': False, 08:52:45 INFO - 'gecko_profile_interval': 0, 08:52:45 INFO - 'installer_path': 'installer.exe', 08:52:45 INFO - 'log_level': 'info', 08:52:45 INFO - 'log_name': 'talos', 08:52:45 INFO - 'log_to_console': True, 08:52:45 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:52:45 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:52:45 INFO - 'opt_config_files': (), 08:52:45 INFO - 'pip_index': False, 08:52:45 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:52:45 INFO - 'cmd': ('bash', 08:52:45 INFO - '-c', 08:52:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:52:45 INFO - 'enabled': True, 08:52:45 INFO - 'halt_on_failure': False, 08:52:45 INFO - 'name': 'check_screen_resolution'},), 08:52:45 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:52:45 INFO - 'cmd': ('bash', 08:52:45 INFO - '-c', 08:52:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:52:45 INFO - 'enabled': True, 08:52:45 INFO - 'halt_on_failure': False, 08:52:45 INFO - 'name': 'check_screen_resolution'},), 08:52:45 INFO - 'run_cmd_checks_enabled': True, 08:52:45 INFO - 'suite': 'dromaeojs-e10s', 08:52:45 INFO - 'system_bits': '32', 08:52:45 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:52:45 INFO - 'title': 't-yosemite-r7-0184', 08:52:45 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:52:45 INFO - 'use_talos_json': True, 08:52:45 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:52:45 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:52:45 INFO - 'work_dir': 'build'} 08:52:45 INFO - [mozharness: 2017-03-15 15:52:45.029396Z] Running clobber step. 08:52:45 INFO - Running pre-action listener: _resource_record_pre_action 08:52:45 INFO - Running main action method: clobber 08:52:45 INFO - rmtree: /builds/slave/test/build 08:52:45 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:52:47 INFO - Running post-action listener: _resource_record_post_action 08:52:47 INFO - [mozharness: 2017-03-15 15:52:47.487630Z] Finished clobber step (success) 08:52:47 INFO - [mozharness: 2017-03-15 15:52:47.487793Z] Running read-buildbot-config step. 08:52:47 INFO - Running pre-action listener: _resource_record_pre_action 08:52:47 INFO - Running main action method: read_buildbot_config 08:52:47 INFO - Using buildbot properties: 08:52:47 INFO - { 08:52:47 INFO - "project": "", 08:52:47 INFO - "product": "firefox", 08:52:47 INFO - "script_repo_revision": "production", 08:52:47 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 08:52:47 INFO - "repository": "", 08:52:47 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 08:52:47 INFO - "buildid": "20170315081436", 08:52:47 INFO - "pgo_build": "False", 08:52:47 INFO - "basedir": "/builds/slave/test", 08:52:47 INFO - "buildnumber": 21, 08:52:47 INFO - "slavename": "t-yosemite-r7-0184", 08:52:47 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 08:52:47 INFO - "platform": "yosemite_r7", 08:52:47 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 08:52:47 INFO - "branch": "mozilla-aurora", 08:52:47 INFO - "repo_path": "releases/mozilla-aurora", 08:52:47 INFO - "revision": "ce8120111f7f2902b3ac88ac35773d9957714a7a", 08:52:47 INFO - "stage_platform": "macosx64", 08:52:47 INFO - "builduid": "65fc37f2961e43899a1a43e6828f8fee", 08:52:47 INFO - "slavebuilddir": "test" 08:52:47 INFO - } 08:52:47 INFO - Found installer url https://queue.taskcluster.net/v1/task/ZHCR6iIaRiioMPIZAq9B5Q/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 08:52:47 INFO - Running post-action listener: _resource_record_post_action 08:52:47 INFO - [mozharness: 2017-03-15 15:52:47.491130Z] Finished read-buildbot-config step (success) 08:52:47 INFO - [mozharness: 2017-03-15 15:52:47.491232Z] Running download-and-extract step. 08:52:47 INFO - Running pre-action listener: _resource_record_pre_action 08:52:47 INFO - Running main action method: download_and_extract 08:52:47 INFO - mkdir: /builds/slave/test/build/tests 08:52:47 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')]} 08:52:47 INFO - trying https://queue.taskcluster.net/v1/task/ZHCR6iIaRiioMPIZAq9B5Q/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 08:52:47 INFO - Downloading https://queue.taskcluster.net/v1/task/ZHCR6iIaRiioMPIZAq9B5Q/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 08:52:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZHCR6iIaRiioMPIZAq9B5Q/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json'}, attempt #1 08:52:48 INFO - Downloaded 1199 bytes. 08:52:48 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 08:52:48 INFO - Using the following test package requirements: 08:52:48 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 08:52:48 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 08:52:48 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 08:52:48 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 08:52:48 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 08:52:48 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 08:52:48 INFO - u'jsshell-mac.zip'], 08:52:48 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 08:52:48 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 08:52:48 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 08:52:48 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 08:52:48 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 08:52:48 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 08:52:48 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 08:52:48 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 08:52:48 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 08:52:48 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 08:52:48 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 08:52:48 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 08:52:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZHCR6iIaRiioMPIZAq9B5Q/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 08:52:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZHCR6iIaRiioMPIZAq9B5Q/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:52:48 INFO - Fetch https://queue.taskcluster.net/v1/task/ZHCR6iIaRiioMPIZAq9B5Q/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 08:52:49 INFO - Expected file size: 51781594 08:52:49 INFO - Obtained file size: 51781594 08:53:05 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip', u'firefox-54.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 08:53:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZHCR6iIaRiioMPIZAq9B5Q/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 08:53:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZHCR6iIaRiioMPIZAq9B5Q/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:53:05 INFO - Fetch https://queue.taskcluster.net/v1/task/ZHCR6iIaRiioMPIZAq9B5Q/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 08:53:07 INFO - Expected file size: 51781594 08:53:07 INFO - Obtained file size: 51781594 08:53:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZHCR6iIaRiioMPIZAq9B5Q/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 08:53:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZHCR6iIaRiioMPIZAq9B5Q/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 08:53:22 INFO - Fetch https://queue.taskcluster.net/v1/task/ZHCR6iIaRiioMPIZAq9B5Q/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 08:53:23 INFO - Expected file size: 14404424 08:53:23 INFO - Obtained file size: 14404424 08:53:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:23 INFO - trying https://queue.taskcluster.net/v1/task/ZHCR6iIaRiioMPIZAq9B5Q/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 08:53:23 INFO - Downloading https://queue.taskcluster.net/v1/task/ZHCR6iIaRiioMPIZAq9B5Q/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 08:53:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZHCR6iIaRiioMPIZAq9B5Q/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg'}, attempt #1 08:53:26 INFO - Downloaded 74622355 bytes. 08:53:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ZHCR6iIaRiioMPIZAq9B5Q/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 08:53:26 INFO - mkdir: /builds/slave/test/properties 08:53:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:53:26 INFO - Writing to file /builds/slave/test/properties/build_url 08:53:26 INFO - Contents: 08:53:26 INFO - build_url:https://queue.taskcluster.net/v1/task/ZHCR6iIaRiioMPIZAq9B5Q/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 08:53:27 INFO - Running post-action listener: _resource_record_post_action 08:53:27 INFO - Running post-action listener: set_extra_try_arguments 08:53:27 INFO - [mozharness: 2017-03-15 15:53:27.693395Z] Finished download-and-extract step (success) 08:53:27 INFO - [mozharness: 2017-03-15 15:53:27.693530Z] Running populate-webroot step. 08:53:27 INFO - Running pre-action listener: _resource_record_pre_action 08:53:27 INFO - Running main action method: populate_webroot 08:53:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:53:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:53:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:53:27 INFO - '--tppagecycles', 08:53:27 INFO - '7'], 08:53:27 INFO - 'tests': ['tsvgm']}}, 08:53:27 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:53:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:53:27 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:53:27 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:53:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:53:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:53:27 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:53:27 INFO - 'talos_options': ['--disable-e10s'], 08:53:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:53:27 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:53:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:53:27 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:53:27 INFO - 'talos_options': ['--disable-e10s'], 08:53:27 INFO - 'tests': ['damp', 'tps']}, 08:53:27 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:53:27 INFO - 'tests': ['damp', 'tps']}, 08:53:27 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:53:27 INFO - 'tests': ['dromaeo_dom']}, 08:53:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:53:27 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:53:27 INFO - 'tests': ['basic_compositor_video']}, 08:53:27 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:53:27 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:53:27 INFO - 'tests': ['a11yr', 08:53:27 INFO - 'ts_paint', 08:53:27 INFO - 'tpaint', 08:53:27 INFO - 'sessionrestore', 08:53:27 INFO - 'sessionrestore_no_auto_restore', 08:53:27 INFO - 'tabpaint']}, 08:53:27 INFO - 'other-e10s': {'tests': ['a11yr', 08:53:27 INFO - 'ts_paint', 08:53:27 INFO - 'tpaint', 08:53:27 INFO - 'sessionrestore', 08:53:27 INFO - 'sessionrestore_no_auto_restore', 08:53:27 INFO - 'tabpaint']}, 08:53:27 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:53:27 INFO - 'ts_paint', 08:53:27 INFO - 'tpaint', 08:53:27 INFO - 'sessionrestore', 08:53:27 INFO - 'sessionrestore_no_auto_restore', 08:53:27 INFO - 'tabpaint']}, 08:53:27 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:53:27 INFO - 'ts_paint', 08:53:27 INFO - 'tpaint', 08:53:27 INFO - 'sessionrestore', 08:53:27 INFO - 'sessionrestore_no_auto_restore', 08:53:27 INFO - 'tabpaint']}, 08:53:27 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:53:27 INFO - 'tests': ['a11yr', 08:53:27 INFO - 'ts_paint', 08:53:27 INFO - 'tpaint', 08:53:27 INFO - 'sessionrestore', 08:53:27 INFO - 'sessionrestore_no_auto_restore', 08:53:27 INFO - 'tabpaint']}, 08:53:27 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:53:27 INFO - 'tests': ['a11yr', 08:53:27 INFO - 'ts_paint', 08:53:27 INFO - 'tpaint', 08:53:27 INFO - 'sessionrestore', 08:53:27 INFO - 'sessionrestore_no_auto_restore', 08:53:27 INFO - 'tabpaint']}, 08:53:27 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:53:27 INFO - 'tests': ['tsvgx', 08:53:27 INFO - 'tsvgr_opacity', 08:53:27 INFO - 'tart', 08:53:27 INFO - 'tscrollx', 08:53:27 INFO - 'cart', 08:53:27 INFO - 'tsvg_static']}, 08:53:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:53:27 INFO - 'tsvgr_opacity', 08:53:27 INFO - 'tart', 08:53:27 INFO - 'tscrollx', 08:53:27 INFO - 'cart', 08:53:27 INFO - 'tsvg_static']}, 08:53:27 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:53:27 INFO - 'talos_options': ['--disable-e10s'], 08:53:27 INFO - 'tests': ['tp5o']}, 08:53:27 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:53:27 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:53:27 INFO - 'talos_options': ['--disable-e10s', 08:53:27 INFO - '--xperf_path', 08:53:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:53:27 INFO - 'tests': ['tp5n']}, 08:53:27 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:53:27 INFO - 'talos_options': ['--xperf_path', 08:53:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:53:27 INFO - 'tests': ['tp5n']}}, 08:53:27 INFO - 'talos.zip': {'path': '', 08:53:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:53:27 INFO - Running post-action listener: _resource_record_post_action 08:53:27 INFO - [mozharness: 2017-03-15 15:53:27.711799Z] Finished populate-webroot step (success) 08:53:27 INFO - [mozharness: 2017-03-15 15:53:27.711938Z] Running create-virtualenv step. 08:53:27 INFO - Running pre-action listener: _resource_record_pre_action 08:53:27 INFO - Running main action method: create_virtualenv 08:53:27 INFO - Creating virtualenv /builds/slave/test/build/venv 08:53:27 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 08:53:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:53:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:53:27 INFO - Using real prefix '/tools/python27' 08:53:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:53:28 INFO - Installing distribute.............................................................................................................................................................................................done. 08:53:31 INFO - Installing pip.................done. 08:53:31 INFO - Return code: 0 08:53:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:53:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:53:31 INFO - Reading from file tmpfile_stdout 08:53:31 INFO - Output received: 08:53:31 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) 08:53:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:53:31 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')]} 08:53:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100bd2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100bd5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc15b472ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009b0d30>, '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 0x1008d7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009a5be8>, '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.8Bi9t31hGm/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.plO1YjuOrl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/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 08:53:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:53:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:53:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8Bi9t31hGm/Render', 08:53:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:53:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:53:32 INFO - 'HOME': '/Users/cltbld', 08:53:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:53:32 INFO - 'LOGNAME': 'cltbld', 08:53:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:32 INFO - 'MOZ_NO_REMOTE': '1', 08:53:32 INFO - 'NO_EM_RESTART': '1', 08:53:32 INFO - 'PAGER': '/bin/cat', 08:53:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:53:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:32 INFO - 'PWD': '/builds/slave/test', 08:53:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:53:32 INFO - 'SHELL': '/bin/bash', 08:53:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.plO1YjuOrl/Listeners', 08:53:32 INFO - 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/', 08:53:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:53:32 INFO - 'USER': 'cltbld', 08:53:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:53:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:53:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:32 INFO - 'XPC_FLAGS': '0x0', 08:53:32 INFO - 'XPC_SERVICE_NAME': '0', 08:53:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:53:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:32 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 08:53:32 INFO - Cleaning up... 08:53:32 INFO - Return code: 0 08:53:32 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:53:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53: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')]} 08:53:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:33 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 0x100bd2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100bd5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc15b472ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009b0d30>, '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 0x1008d7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009a5be8>, '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.8Bi9t31hGm/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.plO1YjuOrl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/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 08:53:33 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 08:53:33 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 08:53:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8Bi9t31hGm/Render', 08:53:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:53:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:53:33 INFO - 'HOME': '/Users/cltbld', 08:53:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:53:33 INFO - 'LOGNAME': 'cltbld', 08:53:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:33 INFO - 'MOZ_NO_REMOTE': '1', 08:53:33 INFO - 'NO_EM_RESTART': '1', 08:53:33 INFO - 'PAGER': '/bin/cat', 08:53:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:53:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:33 INFO - 'PWD': '/builds/slave/test', 08:53:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:53:33 INFO - 'SHELL': '/bin/bash', 08:53:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.plO1YjuOrl/Listeners', 08:53:33 INFO - 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/', 08:53:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:53:33 INFO - 'USER': 'cltbld', 08:53:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:53:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:53:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:33 INFO - 'XPC_FLAGS': '0x0', 08:53:33 INFO - 'XPC_SERVICE_NAME': '0', 08:53:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:53:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:33 INFO - Downloading/unpacking psutil>=3.1.1 08:53:33 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 08:53:33 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 08:53:33 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 08:53:33 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 08:53:33 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 08:53:33 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 08:53:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:53:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:53:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:53:37 INFO - Installing collected packages: psutil 08:53:37 INFO - Running setup.py install for psutil 08:53:37 INFO - building 'psutil._psutil_osx' extension 08:53:37 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 08:53:38 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 08:53:38 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 08:53:38 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 08:53:38 INFO - building 'psutil._psutil_posix' extension 08:53:38 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 08:53:38 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:53:38 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:53:38 INFO - ^ 08:53:38 INFO - 1 warning generated. 08:53:38 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 08:53:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:53:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:53:38 INFO - Successfully installed psutil 08:53:38 INFO - Cleaning up... 08:53:38 INFO - Return code: 0 08:53:38 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:53: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')]} 08:53:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53: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')]} 08:53:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100bd2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100bd5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc15b472ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009b0d30>, '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 0x1008d7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009a5be8>, '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.8Bi9t31hGm/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.plO1YjuOrl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/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 08:53:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:53:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:53:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8Bi9t31hGm/Render', 08:53:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:53:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:53:38 INFO - 'HOME': '/Users/cltbld', 08:53:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:53:38 INFO - 'LOGNAME': 'cltbld', 08:53:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:38 INFO - 'MOZ_NO_REMOTE': '1', 08:53:38 INFO - 'NO_EM_RESTART': '1', 08:53:38 INFO - 'PAGER': '/bin/cat', 08:53:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:53:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:38 INFO - 'PWD': '/builds/slave/test', 08:53:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:53:38 INFO - 'SHELL': '/bin/bash', 08:53:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.plO1YjuOrl/Listeners', 08:53:38 INFO - 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/', 08:53:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:53:38 INFO - 'USER': 'cltbld', 08:53:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:53:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:53:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:38 INFO - 'XPC_FLAGS': '0x0', 08:53:38 INFO - 'XPC_SERVICE_NAME': '0', 08:53:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:53:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:39 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:53:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:53:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:53:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:53:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:53:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:53:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:53:41 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:53:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:53:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:53:41 INFO - Installing collected packages: mozsystemmonitor 08:53:41 INFO - Running setup.py install for mozsystemmonitor 08:53:41 INFO - Successfully installed mozsystemmonitor 08:53:41 INFO - Cleaning up... 08:53:42 INFO - Return code: 0 08:53:42 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:53:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:42 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 0x100bd2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100bd5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc15b472ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009b0d30>, '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 0x1008d7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009a5be8>, '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.8Bi9t31hGm/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.plO1YjuOrl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/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 08:53:42 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 08:53:42 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 08:53:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8Bi9t31hGm/Render', 08:53:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:53:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:53:42 INFO - 'HOME': '/Users/cltbld', 08:53:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:53:42 INFO - 'LOGNAME': 'cltbld', 08:53:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:42 INFO - 'MOZ_NO_REMOTE': '1', 08:53:42 INFO - 'NO_EM_RESTART': '1', 08:53:42 INFO - 'PAGER': '/bin/cat', 08:53:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:53:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:42 INFO - 'PWD': '/builds/slave/test', 08:53:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:53:42 INFO - 'SHELL': '/bin/bash', 08:53:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.plO1YjuOrl/Listeners', 08:53:42 INFO - 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/', 08:53:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:53:42 INFO - 'USER': 'cltbld', 08:53:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:53:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:53:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:42 INFO - 'XPC_FLAGS': '0x0', 08:53:42 INFO - 'XPC_SERVICE_NAME': '0', 08:53:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:53:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:42 INFO - Downloading/unpacking jsonschema==2.5.1 08:53:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:53:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:53:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:53:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:53:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:53:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:53:44 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:53:44 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:53:44 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 08:53:44 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 08:53:44 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 08:53:44 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 08:53:44 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 08:53:44 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 08:53:45 INFO - Downloading functools32-3.2.3-2.tar.gz 08:53:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:53:45 INFO - warning: no files found matching '*.txt' 08:53:45 INFO - no previously-included directories found matching 'build' 08:53:45 INFO - no previously-included directories found matching 'dist' 08:53:45 INFO - no previously-included directories found matching '.git*' 08:53:45 INFO - Installing collected packages: jsonschema, functools32 08:53:45 INFO - Running setup.py install for functools32 08:53:45 INFO - warning: no files found matching '*.txt' 08:53:45 INFO - no previously-included directories found matching 'build' 08:53:45 INFO - no previously-included directories found matching 'dist' 08:53:45 INFO - no previously-included directories found matching '.git*' 08:53:45 INFO - Successfully installed jsonschema functools32 08:53:45 INFO - Cleaning up... 08:53:45 INFO - Return code: 0 08:53:45 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:53:45 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')]} 08:53:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:45 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')]} 08:53:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:45 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 0x100bd2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100bd5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc15b472ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009b0d30>, '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 0x1008d7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009a5be8>, '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.8Bi9t31hGm/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.plO1YjuOrl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/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 08:53:45 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 08:53:45 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 08:53:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8Bi9t31hGm/Render', 08:53:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:53:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:53:45 INFO - 'HOME': '/Users/cltbld', 08:53:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:53:45 INFO - 'LOGNAME': 'cltbld', 08:53:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:45 INFO - 'MOZ_NO_REMOTE': '1', 08:53:45 INFO - 'NO_EM_RESTART': '1', 08:53:45 INFO - 'PAGER': '/bin/cat', 08:53:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:53:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:45 INFO - 'PWD': '/builds/slave/test', 08:53:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:53:45 INFO - 'SHELL': '/bin/bash', 08:53:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.plO1YjuOrl/Listeners', 08:53:45 INFO - 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/', 08:53:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:53:45 INFO - 'USER': 'cltbld', 08:53:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:53:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:53:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:45 INFO - 'XPC_FLAGS': '0x0', 08:53:45 INFO - 'XPC_SERVICE_NAME': '0', 08:53:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:53:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:45 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:53:45 INFO - Cleaning up... 08:53:45 INFO - Return code: 0 08:53:45 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:53:45 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')]} 08:53:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:45 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')]} 08:53:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:45 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 0x100bd2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100bd5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc15b472ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009b0d30>, '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 0x1008d7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009a5be8>, '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.8Bi9t31hGm/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.plO1YjuOrl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/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 08:53:45 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 08:53:45 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 08:53:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8Bi9t31hGm/Render', 08:53:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:53:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:53:46 INFO - 'HOME': '/Users/cltbld', 08:53:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:53:46 INFO - 'LOGNAME': 'cltbld', 08:53:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:46 INFO - 'MOZ_NO_REMOTE': '1', 08:53:46 INFO - 'NO_EM_RESTART': '1', 08:53:46 INFO - 'PAGER': '/bin/cat', 08:53:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:53:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:46 INFO - 'PWD': '/builds/slave/test', 08:53:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:53:46 INFO - 'SHELL': '/bin/bash', 08:53:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.plO1YjuOrl/Listeners', 08:53:46 INFO - 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/', 08:53:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:53:46 INFO - 'USER': 'cltbld', 08:53:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:53:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:53:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:46 INFO - 'XPC_FLAGS': '0x0', 08:53:46 INFO - 'XPC_SERVICE_NAME': '0', 08:53:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:53:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:46 INFO - Downloading/unpacking blobuploader==1.2.4 08:53:46 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 08:53:46 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 08:53:46 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 08:53:46 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 08:53:46 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 08:53:46 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 08:53:48 INFO - Downloading blobuploader-1.2.4.tar.gz 08:53:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:53:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:53:48 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 08:53:48 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 08:53:48 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 08:53:48 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 08:53:48 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 08:53:48 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 08:53:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:53:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:53:49 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 08:53:49 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 08:53:49 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 08:53:49 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 08:53:49 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 08:53:49 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 08:53:50 INFO - Downloading docopt-0.6.1.tar.gz 08:53:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:53:50 INFO - Installing collected packages: blobuploader, requests, docopt 08:53:50 INFO - Running setup.py install for blobuploader 08:53:50 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:53:50 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:53:50 INFO - Running setup.py install for requests 08:53:50 INFO - Running setup.py install for docopt 08:53:51 INFO - Successfully installed blobuploader requests docopt 08:53:51 INFO - Cleaning up... 08:53:51 INFO - Return code: 0 08:53:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:53:51 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')]} 08:53:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:51 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')]} 08:53:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:51 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 0x100bd2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100bd5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc15b472ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009b0d30>, '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 0x1008d7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009a5be8>, '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.8Bi9t31hGm/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.plO1YjuOrl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/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 08:53:51 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 08:53:51 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 08:53:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8Bi9t31hGm/Render', 08:53:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:53:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:53:51 INFO - 'HOME': '/Users/cltbld', 08:53:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:53:51 INFO - 'LOGNAME': 'cltbld', 08:53:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:51 INFO - 'MOZ_NO_REMOTE': '1', 08:53:51 INFO - 'NO_EM_RESTART': '1', 08:53:51 INFO - 'PAGER': '/bin/cat', 08:53:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:53:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:51 INFO - 'PWD': '/builds/slave/test', 08:53:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:53:51 INFO - 'SHELL': '/bin/bash', 08:53:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.plO1YjuOrl/Listeners', 08:53:51 INFO - 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/', 08:53:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:53:51 INFO - 'USER': 'cltbld', 08:53:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:53:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:53:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:51 INFO - 'XPC_FLAGS': '0x0', 08:53:51 INFO - 'XPC_SERVICE_NAME': '0', 08:53:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:53:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:53:51 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-oMfQef-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:53:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:53:51 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-q8rFyL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:53:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:53:51 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-Pt1TJp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:53:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:53:51 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-OWSPaN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:53:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:53:51 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-I59uVc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:53:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:53:51 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-uagWj6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:53:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:53:52 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-d5aAgI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:53:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:53:52 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-G7SGJQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:53:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:53:52 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-KnCZYN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:53:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:53:52 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-yiTkFC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:53:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:53:52 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-gaMudz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:53:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:53:52 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-juJlKY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:53:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:53:52 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-AjKP6C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:53:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:53:52 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-SmYnUx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:53:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:53:52 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-qMlJuD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:53:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:53:52 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-Ek1mqE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:53:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:53:53 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-M6KvgF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:53:53 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:53:53 INFO - Running setup.py install for manifestparser 08:53:53 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:53:53 INFO - Running setup.py install for mozcrash 08:53:53 INFO - Running setup.py install for mozdebug 08:53:53 INFO - Running setup.py install for mozdevice 08:53:53 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:53:53 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:53:53 INFO - Running setup.py install for mozfile 08:53:53 INFO - Running setup.py install for mozhttpd 08:53:54 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:53:54 INFO - Running setup.py install for mozinfo 08:53:54 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:53:54 INFO - Running setup.py install for mozInstall 08:53:54 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:53:54 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:53:54 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:53:54 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:53:54 INFO - Running setup.py install for mozleak 08:53:54 INFO - Running setup.py install for mozlog 08:53:54 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:53:54 INFO - Running setup.py install for moznetwork 08:53:54 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:53:54 INFO - Running setup.py install for mozprocess 08:53:54 INFO - Running setup.py install for mozprofile 08:53:55 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:53:55 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:53:55 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:53:55 INFO - Running setup.py install for mozrunner 08:53:55 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:53:55 INFO - Running setup.py install for mozscreenshot 08:53:55 INFO - Running setup.py install for moztest 08:53:55 INFO - Running setup.py install for mozversion 08:53:55 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:53:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:53:55 INFO - Cleaning up... 08:53:55 INFO - Return code: 0 08:53:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:53:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:55 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 0x100bd2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100bd5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc15b472ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009b0d30>, '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 0x1008d7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009a5be8>, '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.8Bi9t31hGm/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.plO1YjuOrl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/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 08:53:55 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 08:53:55 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 08:53:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8Bi9t31hGm/Render', 08:53:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:53:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:53:55 INFO - 'HOME': '/Users/cltbld', 08:53:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:53:55 INFO - 'LOGNAME': 'cltbld', 08:53:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:55 INFO - 'MOZ_NO_REMOTE': '1', 08:53:55 INFO - 'NO_EM_RESTART': '1', 08:53:55 INFO - 'PAGER': '/bin/cat', 08:53:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:53:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:55 INFO - 'PWD': '/builds/slave/test', 08:53:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:53:55 INFO - 'SHELL': '/bin/bash', 08:53:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.plO1YjuOrl/Listeners', 08:53:55 INFO - 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/', 08:53:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:53:55 INFO - 'USER': 'cltbld', 08:53:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:53:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:53:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:55 INFO - 'XPC_FLAGS': '0x0', 08:53:55 INFO - 'XPC_SERVICE_NAME': '0', 08:53:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:53:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:53:55 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-Q6YSYr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:53:56 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)) 08:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:53:56 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-FcLEi5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:53:56 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)) 08:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:53:56 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-GqpQ_x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:53:56 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)) 08:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:53:56 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-WP41Ro-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:53:56 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)) 08:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:53:56 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-IOXp2I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:53:56 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)) 08:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:53:56 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-iWdDSv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:53:56 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)) 08:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:53:56 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-gNObq2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:53:56 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)) 08:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:53:56 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-cIL0D6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:53:56 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)) 08:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:53:56 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-GAUbjn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:53:56 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)) 08:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:53:56 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-kRea6v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:53:57 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)) 08:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:53:57 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-wCSchX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:53:57 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)) 08:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:53:57 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-AHbBC5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:53:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 08:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:53:57 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-icffJd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:53:57 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)) 08:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:53:57 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-OD8W1g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:53:57 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)) 08:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:53:57 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-cX_aZ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:53:57 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)) 08:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:53:57 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-ud0sIP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:53:57 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)) 08:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:53:57 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-W3J47Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:53:57 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)) 08:53:57 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)) 08:53:57 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)) 08:53:57 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)) 08:53:57 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)) 08:53:57 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)) 08:53:57 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:53:57 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 08:53:57 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 08:53:57 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 08:53:57 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 08:53:57 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 08:53:57 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 08:54:00 INFO - Downloading blessings-1.6.tar.gz 08:54:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:54:00 INFO - Installing collected packages: blessings 08:54:00 INFO - Running setup.py install for blessings 08:54:00 INFO - Successfully installed blessings 08:54:00 INFO - Cleaning up... 08:54:00 INFO - Return code: 0 08:54:00 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:54:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:54:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:54:01 INFO - Reading from file tmpfile_stdout 08:54:01 INFO - Current package versions: 08:54:01 INFO - blessings == 1.6 08:54:01 INFO - blobuploader == 1.2.4 08:54:01 INFO - docopt == 0.6.1 08:54:01 INFO - functools32 == 3.2.3-2 08:54:01 INFO - jsonschema == 2.5.1 08:54:01 INFO - manifestparser == 1.1 08:54:01 INFO - mozInstall == 1.12 08:54:01 INFO - mozcrash == 1.0 08:54:01 INFO - mozdebug == 0.1 08:54:01 INFO - mozdevice == 0.49 08:54:01 INFO - mozfile == 1.2 08:54:01 INFO - mozhttpd == 0.7 08:54:01 INFO - mozinfo == 0.9 08:54:01 INFO - mozleak == 0.1 08:54:01 INFO - mozlog == 3.4 08:54:01 INFO - moznetwork == 0.27 08:54:01 INFO - mozprocess == 0.25 08:54:01 INFO - mozprofile == 0.28 08:54:01 INFO - mozrunner == 6.13 08:54:01 INFO - mozscreenshot == 0.1 08:54:01 INFO - mozsystemmonitor == 0.3 08:54:01 INFO - moztest == 0.8 08:54:01 INFO - mozversion == 1.4 08:54:01 INFO - psutil == 3.1.1 08:54:01 INFO - requests == 1.2.3 08:54:01 INFO - wsgiref == 0.1.2 08:54:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:54:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:54:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:54:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:54:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:54:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:54:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:54:01 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 0x100bd2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100bd5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc15b472ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009b0d30>, '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 0x1008d7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009a5be8>, '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.8Bi9t31hGm/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.plO1YjuOrl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/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 08:54:01 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 08:54:01 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 08:54:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8Bi9t31hGm/Render', 08:54:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:54:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:54:01 INFO - 'HOME': '/Users/cltbld', 08:54:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:54:01 INFO - 'LOGNAME': 'cltbld', 08:54:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:54:01 INFO - 'MOZ_NO_REMOTE': '1', 08:54:01 INFO - 'NO_EM_RESTART': '1', 08:54:01 INFO - 'PAGER': '/bin/cat', 08:54:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:54:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:54:01 INFO - 'PWD': '/builds/slave/test', 08:54:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:54:01 INFO - 'SHELL': '/bin/bash', 08:54:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.plO1YjuOrl/Listeners', 08:54:01 INFO - 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/', 08:54:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:54:01 INFO - 'USER': 'cltbld', 08:54:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:54:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:54:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:54:01 INFO - 'XPC_FLAGS': '0x0', 08:54:01 INFO - 'XPC_SERVICE_NAME': '0', 08:54:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:54:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:54:01 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)) 08:54:01 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)) 08:54:01 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)) 08:54:01 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)) 08:54:01 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)) 08:54:01 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)) 08:54:01 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)) 08:54:01 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)) 08:54:01 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)) 08:54:01 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)) 08:54:01 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)) 08:54:01 INFO - Cleaning up... 08:54:01 INFO - Return code: 0 08:54:01 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:54:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:54:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:54:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:54:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:54:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:54:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:54:01 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 0x100bd2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100bd5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc15b472ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009b0d30>, '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 0x1008d7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009a5be8>, '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.8Bi9t31hGm/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.plO1YjuOrl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/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 08:54:01 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 08:54:01 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 08:54:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8Bi9t31hGm/Render', 08:54:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:54:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:54:01 INFO - 'HOME': '/Users/cltbld', 08:54:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:54:01 INFO - 'LOGNAME': 'cltbld', 08:54:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:54:01 INFO - 'MOZ_NO_REMOTE': '1', 08:54:01 INFO - 'NO_EM_RESTART': '1', 08:54:01 INFO - 'PAGER': '/bin/cat', 08:54:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:54:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:54:01 INFO - 'PWD': '/builds/slave/test', 08:54:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:54:01 INFO - 'SHELL': '/bin/bash', 08:54:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.plO1YjuOrl/Listeners', 08:54:01 INFO - 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/', 08:54:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:54:01 INFO - 'USER': 'cltbld', 08:54:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:54:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:54:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:54:01 INFO - 'XPC_FLAGS': '0x0', 08:54:01 INFO - 'XPC_SERVICE_NAME': '0', 08:54:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:54:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:54:01 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:54:01 INFO - Cleaning up... 08:54:01 INFO - Return code: 0 08:54:01 INFO - Running post-action listener: _resource_record_post_action 08:54:01 INFO - Running post-action listener: _start_resource_monitoring 08:54:01 INFO - Starting resource monitoring. 08:54:01 INFO - [mozharness: 2017-03-15 15:54:01.677624Z] Finished create-virtualenv step (success) 08:54:01 INFO - [mozharness: 2017-03-15 15:54:01.678585Z] Running install step. 08:54:01 INFO - Running pre-action listener: _resource_record_pre_action 08:54:01 INFO - Running main action method: install 08:54:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:54:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:54:01 INFO - Reading from file tmpfile_stdout 08:54:01 INFO - Detecting whether we're running mozinstall >=1.0... 08:54:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:54:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:54:02 INFO - Reading from file tmpfile_stdout 08:54:02 INFO - Output received: 08:54:02 INFO - Usage: mozinstall [options] installer 08:54:02 INFO - Options: 08:54:02 INFO - -h, --help show this help message and exit 08:54:02 INFO - -d DEST, --destination=DEST 08:54:02 INFO - Directory to install application into. [default: 08:54:02 INFO - "/builds/slave/test"] 08:54:02 INFO - --app=APP Application being installed. [default: firefox] 08:54:02 INFO - mkdir: /builds/slave/test/build/application 08:54:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:54:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 08:54:22 INFO - Reading from file tmpfile_stdout 08:54:22 INFO - Output received: 08:54:22 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 08:54:22 INFO - Running post-action listener: _resource_record_post_action 08:54:22 INFO - [mozharness: 2017-03-15 15:54:22.201036Z] Finished install step (success) 08:54:22 INFO - [mozharness: 2017-03-15 15:54:22.201181Z] Running run-tests step. 08:54:22 INFO - Running pre-action listener: _resource_record_pre_action 08:54:22 INFO - Running main action method: run_tests 08:54:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:54:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:54:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:54:22 INFO - 2017-03-15 08:54:22.215 screenresolution[1620:7485] starting screenresolution argv=screenresolution get 08:54:22 INFO - 2017-03-15 08:54:22.218 screenresolution[1620:7485] Display 0: 1600x1200x32@60 08:54:22 INFO - 2017-03-15 08:54:22.224 screenresolution[1621:7488] starting screenresolution argv=screenresolution list 08:54:22 INFO - Available Modes on Display 0 08:54:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:54:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:54:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:54:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:54:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:54:22 INFO - Intel Iris: 08:54:22 INFO - Chipset Model: Intel Iris 08:54:22 INFO - Type: GPU 08:54:22 INFO - Bus: Built-In 08:54:22 INFO - VRAM (Dynamic, Max): 1536 MB 08:54:22 INFO - Vendor: Intel (0x8086) 08:54:22 INFO - Device ID: 0x0a2e 08:54:22 INFO - Revision ID: 0x0009 08:54:22 INFO - Displays: 08:54:22 INFO - Display: 08:54:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:54:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:54:22 INFO - Display Serial Number: 1600x1200 60 08:54:22 INFO - Main Display: Yes 08:54:22 INFO - Mirror: Off 08:54:22 INFO - Online: Yes 08:54:22 INFO - Rotation: Supported 08:54:22 INFO - Return code: 0 08:54:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:54:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:54:22 INFO - Python 2.7.3 08:54:22 INFO - Return code: 0 08:54:22 INFO - grabbing minidump binary from tooltool 08:54:22 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')]} 08:54:22 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 0x1009b0d30>, '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 0x1008d7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009a5be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:54:22 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 08:54:22 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 08:54:22 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 08:54:22 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:54:22 INFO - Return code: 0 08:54:22 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:54:22 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:54:22 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:54:22 INFO - ENV: RUST_BACKTRACE is now 1 08:54:22 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:54:22 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:54:22 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/ZHCR6iIaRiioMPIZAq9B5Q/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0184', '--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 08:54:22 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/ZHCR6iIaRiioMPIZAq9B5Q/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0184 --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 08:54:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8Bi9t31hGm/Render', 08:54:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:54:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:54:22 INFO - 'HOME': '/Users/cltbld', 08:54:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:54:22 INFO - 'LOGNAME': 'cltbld', 08:54:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:54:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:54:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:54:22 INFO - 'MOZ_NO_REMOTE': '1', 08:54:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:54:22 INFO - 'NO_EM_RESTART': '1', 08:54:22 INFO - 'PAGER': '/bin/cat', 08:54:22 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:54:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:54:22 INFO - 'PWD': '/builds/slave/test', 08:54:22 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:54:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:54:22 INFO - 'RUST_BACKTRACE': '1', 08:54:22 INFO - 'SHELL': '/bin/bash', 08:54:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.plO1YjuOrl/Listeners', 08:54:22 INFO - 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/', 08:54:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:54:22 INFO - 'USER': 'cltbld', 08:54:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:54:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:54:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:54:22 INFO - 'XPC_FLAGS': '0x0', 08:54:22 INFO - 'XPC_SERVICE_NAME': '0', 08:54:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:54:22 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/ZHCR6iIaRiioMPIZAq9B5Q/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0184', '--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 08:54:22 INFO - mozversion application_buildid: 20170315081436 08:54:22 INFO - mozversion application_changeset: ce8120111f7f2902b3ac88ac35773d9957714a7a 08:54:22 INFO - mozversion application_display_name: FirefoxDeveloperEdition 08:54:22 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:54:22 INFO - mozversion application_name: Firefox 08:54:22 INFO - mozversion application_remotingname: firefox-dev 08:54:22 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:54:22 INFO - mozversion application_vendor: Mozilla 08:54:22 INFO - mozversion application_version: 54.0a2 08:54:22 INFO - mozversion platform_buildid: 20170315081436 08:54:22 INFO - mozversion platform_changeset: ce8120111f7f2902b3ac88ac35773d9957714a7a 08:54:22 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:54:22 INFO - mozversion platform_version: 54.0a2 08:54:22 INFO - using testdate: 1489593262 08:54:22 INFO - actual date: 1489593262 08:54:22 INFO - starting webserver on 'localhost:49244' 08:54:22 INFO - SUITE-START | Running 2 tests 08:54:22 INFO - TEST-START | dromaeo_css 08:54:22 INFO - operating with platform_type : mac_ 08:54:22 INFO - Initialising browser for dromaeo_css test... 08:54:22 INFO - TEST-INFO | started process 1631 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/tmpPZK8ge/profile http://localhost:49244/getInfo.html) 08:54:25 INFO - PROCESS | 1631 | 2017-03-15 08:54:25.513 plugin-container[1633:7684] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x903f, name = 'com.apple.tsm.portname' 08:54:25 INFO - PROCESS | 1631 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:54:25 INFO - PROCESS | 1631 | 2017-03-15 08:54:25.516 plugin-container[1633:7684] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b07, name = 'com.apple.CFPasteboardClient' 08:54:25 INFO - PROCESS | 1631 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:54:25 INFO - PROCESS | 1631 | 2017-03-15 08:54:25.516 plugin-container[1633:7684] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:54:25 INFO - PROCESS | 1631 | __metrics Screen width/height:1600/1200 08:54:25 INFO - PROCESS | 1631 | colorDepth:24 08:54:25 INFO - PROCESS | 1631 | Browser inner width/height: 1024/689 08:54:25 INFO - PROCESS | 1631 | __metrics 08:54:25 ERROR - PROCESS | 1631 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3353: TypeError: this.tabs is undefined 08:54:26 INFO - TEST-INFO | 1631: exit 0 08:54:26 INFO - Browser initialized. 08:54:26 INFO - Running cycle 1/1 for dromaeo_css test... 08:54:26 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/tmpPZK8ge/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:54:28 INFO - PROCESS | 1634 | 2017-03-15 08:54:28.819 plugin-container[1635:7810] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 08:54:28 INFO - PROCESS | 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:54:28 INFO - PROCESS | 1634 | 2017-03-15 08:54:28.820 plugin-container[1635:7810] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 08:54:28 INFO - PROCESS | 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:54:28 INFO - PROCESS | 1634 | 2017-03-15 08:54:28.820 plugin-container[1635:7810] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:54:38 INFO - PROCESS | 1634 | RSS: Main: 220323840 08:54:38 INFO - PROCESS | 1634 | 08:57:41 INFO - PROCESS | 1634 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49244/tests/dromaeo/cssquery-ext.html) 08:57:43 INFO - PROCESS | 1634 | RSS: Main: 238141440 08:57:43 INFO - PROCESS | 1634 | 08:59:26 INFO - PROCESS | 1634 | ************************* 08:59:26 INFO - PROCESS | 1634 | A coding exception was thrown and uncaught in a Task. 08:59:26 INFO - PROCESS | 1634 | 08:59:26 ERROR - PROCESS | 1634 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:59:26 INFO - PROCESS | 1634 | Full stack: 08:59:26 INFO - PROCESS | 1634 | ************************* 09:00:45 INFO - PROCESS | 1634 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-ext.html (next: http://localhost:49244/tests/dromaeo/cssquery-jquery.html) 09:00:46 INFO - PROCESS | 1634 | RSS: Main: 246382592 09:00:46 INFO - PROCESS | 1634 | 09:03:48 INFO - PROCESS | 1634 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49244/tests/dromaeo/cssquery-mootools.html) 09:03:49 INFO - PROCESS | 1634 | RSS: Main: 254976000 09:03:49 INFO - PROCESS | 1634 | 09:06:51 INFO - PROCESS | 1634 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49244/tests/dromaeo/cssquery-prototype.html) 09:06:51 INFO - PROCESS | 1634 | RSS: Main: 251322368 09:06:51 INFO - PROCESS | 1634 | 09:09:55 INFO - PROCESS | 1634 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49244/tests/dromaeo/cssquery-yui.html) 09:09:55 INFO - PROCESS | 1634 | RSS: Main: 256774144 09:09:55 INFO - PROCESS | 1634 | 09:12:58 INFO - PROCESS | 1634 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-yui.html (next: http://localhost:49244/tests/dromaeo/cssquery-dojo.html) 09:12:58 INFO - PROCESS | 1634 | RSS: Main: 253059072 09:12:58 INFO - PROCESS | 1634 | 09:12:58 INFO - PROCESS | 1634 | __start_tp_report 09:12:58 INFO - PROCESS | 1634 | _x_x_mozilla_page_load 09:12:58 INFO - PROCESS | 1634 | _x_x_mozilla_page_load_details 09:12:58 INFO - PROCESS | 1634 | |i|pagename|runs| 09:12:58 INFO - PROCESS | 1634 | |0;dojo.html;2934.281101130223;3484.3553942520634;3512.543369361983;3512.596051454082;3529.699975502082;5036.6222533309965;5844.795432159875;5975.163477113208;6105.633661980284;6107.847303817396;4877.756112194394;6014.75940962361;6077.513798896089;6093.756249750005;6099.146119543257;5368.812091576786;5438.238646589461;5440.809571664992;5486.1222204447295;5497.945020549803;5449.264349312852;5452.59105567082;5460.31746031746;5462.262594549745;5472.1244600863865;5319.069162896497;5328.946710532903;5494.2582751328655;5503.587230957674;5523.25436066132;5446.891062178754;5470.152126420411;5505.256790333294;5505.339359276913;5536.086545719952;5397.7571009304675;5476.3702174250075;5478.698671573062;5493.176023596492;5513.669179849222;6056.757729690807;6099.512039036911;6166.475849552793;6192.597481163755;6195.44241018306;5756.65460072397;6268.65522396268;6297.118403423525;6319.4944404448;6355.8411039723915;6569.343065693441;6579.407853293226;6583.045458408552;6585.341465853424;6587.835304117388;5213.426523082534;6216.782412615582;6223.346548612421;6223.906641400383;6227.19046523949;6361.61830290184;6431.807045788634;6494.545381823227;6580.243272023683;6593.505487088488;5165.457626949192;6392.648404337806;6421.646809425526;6444.484441244783;6448.3229260927865;6498.252700939453;6522.05430212626;6532.281449040602;6552.312007239267;6553.213614366211;6137.346341658241;6199.380061993765;6432.2281326241155;6486.935130648728;6493.5454518184215;5565.972170139124;6099.756009759649;6294.527910406738;6344.841378965562;6388.265349484869;5210.947890521122;5211.270422140828;5212.609054320941;5221.817236396708;5247.9737601311745;4639.582437580567;4656;4668.976655116703;4681.29780532916;4706.388169538007;5521.3926468088475;6029.668368239789;6114.11345354921;6115.6330620162935;6226.221722284715;4998.825041123543;6296.874062518724;6505.121808555785;6514.413702766774;6516.641584712792;6004.639721616717;6067.969660151671;6118.9846826261555;6168.506519478431;6215.875682486325;5730.226419433407;5776.335721392094;5796.826095217066;5802.709864506758;5810.912836307459;5687.857803554944;6454.806355809334;6497.805065848032;6765.86468270622;6794.9320506796275;6265.467435268053;6377.4579160771855;6421.486281097407;6472.352764723679;6490.513211509061;5497.257870187634;5788;6722.899156512558;6771.864562708817;6773.796786096425;6275.152854364785;6449.161608990801;6466.838329041811;6473.158489396348;6499.5775274607;6318.526110541636;6403.423691867754;6410.0705397718;6425.8714825704155;6478.319776423407;6047.03247480401;6353.936460635519;6356.650384228912;6358.078078678658;6375.203099612549;6336.968315158395;6363.713632886624;6364.713587888464;6368.299487056327;6379.266384365672;5159.122949098587;5997.520198384032;6073.665948372882;6207.441330280297;6213.03697926822;6315.273743519554;6356.046593011085;6390.456811171102;6391.840203994937;6401.423871851555;6276.529260305404;6310.7791227306825;6314.526410519137;6334.398232167937;6356.841078973062 09:12:58 INFO - PROCESS | 1634 | |1;ext.html;755.7014979083262;811.7524155132684;860.6385318166374;869.5347988825979;947.5593848860278;38009.049773755636;39950;40028.79985600072;40054.59945400545;40085.198296034134;38575.22849543014;38646.806765966205;38662;38780.03049923753;38804.80597597015;7248.492605517616;7387.667554960013;7736;7909.209079092102;11024.614138505154;11096.223264371498;11219.102471802236;11244.9879510844;11246.66260012201;11279.661610151708;11257.774844503105;11265.155113366489;11273.267237629569;11286.435678216098;11301.773964520704;11253.943730281337;11279.774404511905;11280.210385273034;11282.887171128266;11293.265937714023;10785.083267922237;10820.404877731722;10854.72863178419;10855.83716244249;10867.78264434707;30020.399592008038;37033;39916.60083399158;39999.40000899989;40131.39802902959;3219.613646362428;3517.208628058692;3538;3559.181388280684;3672.761270517408;26647;26660.800263988167;26821.597676034875;27952.301192470146;28318.575221371695;2683.18162960297;2842.346260360129;2889.511901370796;2920.2845302900714;3059.6481404638307;284453;288492.1150788486;290313;331859;332822;42375.78812105981;42881.57118428838;52424.95150097053;52806.471935280926;53623.46376536263;56048;56407;56460.43539564634;56541.717291414105;56592;14675.48635797742;14783.891208159432;14828.481003164838;14866.776998345034;14929.70140597182;12736.490540378281;12804.743905122032;12825.422855971503;12841.972642188603;12847.293398863152;10343.586256549805;12377.814332785016;12424.695406982317;12455.626331210078;12592.81110783339;4499.0776890737325;5293.894122117613;5295.152775555893;5312.814051508179;5321.6540924839765;1083.7832433513329;1110.2838669058413;1125.1561329003248;1129.4183495499824;1162.686074759827;20753.896230518752;23974.801259936932;26803;27064.729352706614;27231.591526127126;1090.4002798460815;1171.4142928535732;1223.8959688426582;1240.5189620758483;1250.593557093948;11996.820047699292;13863.653408664864;13912.930435347758;13930.930345348208;13957.79063314051;437540;437838;438076.80961594987;438617;438990;56528.434715652314;56833.14750278749;56843.57891052757;56906.14640780391;57295;1396.60196843899;1425.5794540610336;1426.8787153092103;1438.6475313548037;1438.7482190616809;15815.051096934221;24352.51294974126;24912.263160526065;25538.233852984442;26463.206103816916;33624.831875840464;36980.81509592543;39923.40114898221;43486;46849.76575117103;409680;411043;411195;411592;411831;33189;33826.32347353089;34221.31557368889;34418.311633767684;34597;38527;39246.60753392544;42958;44080.89797754953;44476.11047779091;35643.64356356403;35758;35987.280254395286;36084;37076.073098171684;34842;35331.116722081126;36412;36487.635123648426;36609.718659846774;35516.82241588879;35693;35756.82121589479;35761.46357804583;36616.26767464689;30661.693383065885;32088.87688930876;32132.518012230303;32229.51655725119;32422;19024.546190195488;22908.770912290664;24011.51976960486;24521.651309178167;24528.264152075466 09:12:58 INFO - PROCESS | 1634 | |2;jquery.html;332.88681848171626;400.87372477669516;405.4445409788588;419.3374468340023;449.11748414365786;19457.221711131555;19770.71490353128;21178.941052947368;21689.783102168974;21904.671429928563;22499.325020249376;22854.314370568867;22890.198843040496;22912.198073067448;23186.42033949152;6506.154199954023;6959.686814093353;6979.930200698004;6991.230964593916;6994.6152961587095;6852.143482064742;7288.3076107769675;7661.923380766205;7779.688812447495;7824.943632609582;6993.440524758033;7308.086489188851;7731;7757.379409647215;7788.415868809862;7320.780376588711;7326.853462930738;7344.779656610311;7378.889316660255;7388.335049845513;7503.587302698349;7533.585652789095;7571.810704732426;7572.356349710281;7603.429742769259;25588.744112558823;25601.35996600081;25670.358241043938;26341.604875926878;26564.867175664;2613.9477210455875;2861.642294713161;2991.41667374862;3006.729394354497;3029.0458505570896;15078.246087695572;15231.847681523153;15309.08145511273;15397.692046159125;15597.064176149468;1651.6531528379055;1891.3096719697212;1893.848492120641;1899.4681489182913;1926.4991102313359;130072;167893;173804;175111.1244443795;182296.08851955656;21527.67708484374;21573.892130539247;21691.915404229727;21777.455563610718;21861.67207491889;25728;31620.367592648017;31897.202569935936;32235.67764322327;32351.191220219684;8192;9239.676611318571;9377.671781487616;9519.048095190425;9558.856617150748;7666.080070391477;7745.845083098419;7792.2986931176465;7885.5662938538935;7921.089074756362;9585.329026968186;9655.889572699229;9810.460424676712;9827.656032038843;9838.754031149136;3256.153400115987;3383.9661603384143;3608.6752192302292;4251.128518653731;4288.978555107205;844.4848642328175;884.4030279561271;886.4548302793736;919.7838507950627;929.1080562659922;18173.45479635613;19942.60114797725;21747.67378489324;21779.89110054471;21852.89073554622;842.7850898021088;846.7375113714768;858.7552547523926;930.7905721212809;941.9152276295166;10620.787584248426;11271.38007409584;11281.266717663326;11311.717207069725;11330.886691133148;782623;783717;789193;790631;806859;159805.40194598137;160116;162108;162232.37767622407;169671;1164.895159435655;1193.0932491306496;1213.3266037349179;1221.3099598726692;1233.617578550652;18518.629627407645;18788.624227515098;19027.619447611247;19184.328548500736;19198.29854367729;48812.511874880794;59968;63188;65896.67051664711;67104;97491;98738.50630746801;99165.01669966705;99206.00793992257;99788;9193.908060919306;10495.947520262349;10892.529508516249;11389.974902258837;11941.46263418131;12494.437750301076;13466.259355735529;13724.519641812489;13732.931335343259;13925.79111313311;11312.377819220023;11432.256903301259;11584.13119016094;11839.76320473603;11862.703432414259;10111.848322275024;11468.483918223534;11797.803160819574;11915.642530724092;11950.701232469259;6604.768833090819;9218.44689318643;11612.129090318429;12034.75930481403;12129.69675758113;11161.107111431229;11823.349715765715;11914.52341906314;12082.516699331929;12111.757764844477;6198.597091189058;11190.66428007161;11219.046381057702;11490.138239631893;11584.420778961186 09:12:58 INFO - PROCESS | 1634 | |3;mootools.html;1387.7155183187444;1545.2505534815612;1556.4941394046937;1557.984420155798;1601.7677436771664;10904.182186336016;11044.613438529654;11365.659030229086;11591.768164636713;11638.825417618742;10674.306170098938;11028.117750579955;11046.226764126515;11083.667489975294;11106.555737770499;2337.345543247893;2396.1134380279323;2398.5322862041908;2399.568077746005;2410.6384042393693;2185.0822654485073;2241.2827895073583;2401.9639705404434;2404.519096180762;2422.527607116613;2353.682252895863;2376.524695060995;2379.809615230786;2383.201627454796;2393.3896856301617;2320.5474932388192;2335.9182428615004;2350.2009316832273;2351.071326825894;2352.5059737455153;2334.731505876829;2384.9165279215144;2410.20463247128;2412.433078226633;2421.2373102472666;9606.087421694929;10428.84356734642;10685.572577096907;10844.566217351297;10876.564937402494;5757.424257574251;5926.466618004357;5971.253593300838;5978.8505287367725;5988.520918326567;2998.5202367621296;3589.928201435983;3642.380795264785;3680.3191409589313;3712.981435092834;935.2564711054728;1007.8560833454009;1132.6262333430032;1170.0405667352775;1202.1765090912752;13612.727745445036;13758.449662013507;14987.175705336202;15553.377864885391;15738.685226295525;4092.956296144464;4533.0707205023555;4761.642876784255;4830.29960655703;4995.650304478724;10961.945190273998;12763.91506721939;12830.230186188859;12845.550405735754;12852.871471285354;5914.260717410324;5981.970090149522;5989.4609485145675;5994.441445224273;5994.6403215807195;4992.2511623256805;5012.67417617861;5051.595872330205;5223.712695801767;5277.8416647500635;3799.7910114943943;4136.627703506639;4198.601132892431;4303.376010478463;4303.483581970184;1148.9827652585218;1162.6512046386051;1165.947532361046;1174.8531433570804;1179.4633441783967;807.5423464307119;833.3749687734199;838.2832678060292;864.4942708515463;899.7255836969787;5596.636218645856;6271.310155882849;6292.622442653455;6460.224773027267;6500;917.2478567574658;941.5668792355456;942.688912658821;950.2872845365976;975.4391225045628;3974.980125099356;3999.6601138618307;4078.4494093297617;4117.5882411759185;4140.399642051946;12567.497300108076;12808.80786788199;13467.932660336635;13700.93149534246;13893.930530347487;13551.864481355258;13739.313034348243;13751.17492950406;13763;13765.724685506233;8490.872636910452;9933.3543319684;10201.13290370312;10235.078842904175;10260.435676037889;5808.767649293981;6502.447291980235;6593.571417857824;6818.18181818185;6818.454523638196;11647.88352116468;11739.589114380955;12073.335966521574;12096.213746106476;12137.332446715161;12056.75886482283;12286.569970051005;12309.076819238771;12324.691882703004;12340.136190466941;9075.954620226856;9323.487208203615;9334.579943902676;9337.953310233406;9345.252379809735;10112.84830727525;10188.898111018794;10210.183185345006;10320.329178603366;10344.689659310232;8545.529995850287;8573.528455934735;8639.784005399664;8646.9623645163;8770.912290877264;8806.471611703319;8822.470651760916;8877.911220887709;8924.464532128093;9082.59128339213;7687.385009199363;7813.804654883674;7817.648205830637;7821.726239581587;7865.488743231672;5592.440755924375;5619.915701264566;5702.942970570241;5758.884822303614;5847.181394604674;5700.144978253295;5798.782255726344;5807.245058142415;5821.79623713168;5905.350411454822 09:12:58 INFO - PROCESS | 1634 | |4;prototype.html;354.46829755366946;371.0834239428614;381.64507008482116;392.6760422651312;395.4384773621457;4436.334549817529;4861.2465067914445;5329.760160792764;5427.647202931812;5428.7785248319105;4578.793954272058;4650.558326918649;4689.788959496822;5144;5170.57809102497;4291.656667466611;4418.02803383256;4457.821687132511;4460.6208472279895;4516.277395616702;4405.317175837734;4407.603315701586;4457.487388950263;4500.819967201308;4544.773061200021;3979.781112038837;4103.261412945669;4191.538930717618;4482.125985432842;4504.909901801979;3762.2475504898857;4002.7598344099156;4423.429682462744;4457;4494.70784399013;3542.81315759221;3688.8524459021605;4044.312466880608;4063.085805693725;4089.8773036808943;4452.799624016927;4716.858494245178;5190.273361729361;5259.474752321822;5323.44103869092;4637.6289896808175;4793.44875339337;4903.950960490421;5057.797688092472;5114.846554603368;5155.845324640267;5238.240455133984;5287.312649355597;5745.224394706703;5855.033919403293;4255.3829694694095;4340.565943405666;4595.655325850575;4633.953660463386;4694.976525117352;5306.787728490895;5372.597055220874;5411.458854114597;5963.314218864843;6148.44663980244;4192.769397683157;4481.588299685589;4605.539446055368;4667.809708524304;4670.346151538787;3988.80055997199;4117.649999750055;4699.577038066591;4791.592714619296;4793.113274044313;4100.938485922714;4657.161710892072;5079.517445842639;5092.210707340369;5141.280220769096;4751.524847515221;5415.377231618336;5445.801923576807;5572.693501857437;5775.5668324875805;1463.7072585482947;1567.1850637668576;1603.3346161343145;1615.1681883829838;1615.3215649427268;1108.141190577309;1128.6501184632791;1173.4543437301695;1202.1284568687806;1208.7824191645589;4766.213574760126;4801.5438533339275;5148.56237219833;5158.807506809591;5335.786568537215;5303.764703530843;5428.941356435457;5574;5685.687287199244;5961.287614135558;4732.313814496879;5056.721880296545;5124.026434977342;5354.277172581652;5478.780848766005;2804.8176868503483;2960.9703902961123;2976.6753794561646;3040.950871949174;3129.9687003130134;5424.945750542523;6056.576704474445;6074.78504299142;6241.563090583615;6304.747810087545;5886.823395298148;5890.734916928748;6004.579679422486;6073.878522429527;6169.180091872909;5470.781168753205;5732.942670573241;5760.703841635683;5867.647941123546;6206.410391012847;5128.692278463304;5502.532284755841;5737.8565535861935;5996.70016499182;6015.969920150371;4586.403767510203;4860.684055536379;5327.946720532745;5828.883422331445;5996.910046349221;5461.836144915659;5521.5306698931045;6038.547108966933;6203.751849925952;6263.906041409291;3744.681702055356;4362.956370436382;4379.37237657403;4703.270992996091;4703.623710103252;4821.70968789822;5255.132903071103;5360.41035486104;5394.922954662405;5951.940480595139;4986.975065124651;5701.344345400332;5780.479756821906;5799.76800927975;5966.880662386641;5654.745536450787;5807.448292412214;5833.387494313206;5836.824895253149;5882.499987500939;5250.95676329232;5427.375851777097;5480.794225270434;5485.4788795064405;5614.29821272341;5069.847904562869;5809.912851307318;5834.94165058344;5836.941630583639;5861.834609311095;5217.165253559412;5321.414644389191;5329.573634109184;5675.849903303706;5691 09:12:58 INFO - PROCESS | 1634 | |5;yui.html;645.4481418387276;657.8585604095122;722.4762047515552;734.6951015328639;766.9156392796791;13678.726425471497;15456.690866182684;16329.755053674204;16353.672926541461;16363.181840907951;12945.935270323658;14761.409543618242;15108.169050702234;15131.84868151321;15474;910.39003867409;937.3625934364629;938.015084161631;938.6808485115059;939.2251392601097;889.6441423430647;922.7139586728135;940.47333493244;941.9811603767921;944.6363150187193;889.3729920406131;940.4404379394249;992.7270000749816;996.4967691315875;996.9900300997006;972.397113789448;982.7297493189359;989.515137582583;989.9208063354914;995.7610173558367;725.314577724051;733.4645708632675;733.5928559649392;734.5812886654609;734.6510407556368;12795;13024.153430027112;15625.687486250325;16456.917715411466;16471.752923706033;13307.600771976857;13357;13401.597952061455;13419.597412077555;13585.116967397087;9450.810983780355;10689.091427228695;11052.778944421147;11072.335659860353;11202.551897924073;3298.4062868683627;3332.500124981272;3364.764466487347;3387.9830600847085;3440.4839274108836;83014.99999999939;120292.7970720299;123319;124248;124624.37687811593;7845.5684937329;9450.480223587769;9487.14615684578;9534.18959388446;10221;19135.330263440712;22678.886605566866;23097.307080787603;23245.837708114865;23304.417389565395;6085.543584231112;7111.928880711127;7177.138743350832;7201.459890508233;7397.926020739831;3744.6255374462335;3863.16941857502;3882.107115363482;3947.486826712509;3976.781277029791;3419.247765491588;3514.578250609943;3720.4233343831747;3879.18537107205;3917.7845218512684;641.4771960851891;642.56947844944;646.3439608797074;657.4510283912987;658.9308122647149;589.9469047785719;621.4965877639127;629.9244090709144;633.7369991453587;647.5143642268299;9171;9641.566129524188;9749.902500975042;10034.548445319979;10069.446180460145;603.5443240353505;624.54720327762;627.626562195493;641.6759536434164;653.7973228299243;4560.021430910122;4873.878153046202;4877.3903262092235;4890.902181956341;4901.09329773993;107290.39064413884;108472;109096;109934.45032774785;111495;63840;68658.65670671615;74607;95194;97057.54413683659;73982;75017.62491187618;77597;78253.2174678246;78981.81527277095;9124.498152601405;10098.94950525272;10192;10345.327553708985;10381.636642717469;51340.743296284774;51611.741941291555;52050.73974630103;52219.38903054756;52851.471485286194;64854.67572662106;66874.66562667351;67466.32533674796;68019.31980680328;68418.94743157714;11370.829437558608;11474.196806223485;11506.252093613888;11726.41367931617;12377.876221238033;13219.867801322249;13220.471181152647;13439.46242150303;13552.59342219735;13908;12280.692982675504;12418.316992565495;12486.12597118193;12847.357632118543;12945.741085178055;12310.815337769762;12317.3246540286;12461.813072804096;12497.812532811833;12614.558490453157;9586.760330991781;11975.161738678557;12214.145009849226;12243.265404075784;12499.56251531192;10218.489075546044;10428.165746740395;10750.193735469715;10844.728881778019;11127.443627818415;8579.356548259031;10453.738656533647;10460.790784184426;10543.630972915911;10630.362178269328 09:12:58 INFO - PROCESS | 1634 | __end_tp_report 09:12:58 INFO - PROCESS | 1634 | __start_cc_report 09:12:58 INFO - PROCESS | 1634 | _x_x_mozilla_cycle_collect,421 09:12:58 INFO - PROCESS | 1634 | __end_cc_report 09:12:58 INFO - PROCESS | 1634 | __startTimestamp1489594378700__endTimestamp 09:12:58 INFO - PROCESS | 1634 | 09:12:58 INFO - PROCESS | 1634 | ------- Summary: start ------- 09:12:58 INFO - PROCESS | 1634 | Number of tests: 6 09:12:58 INFO - PROCESS | 1634 | 09:12:58 INFO - PROCESS | 1634 | [#0] dojo.html Cycles:165 Average:5945.79 Median:6199.38 stddev:679.84 (11.0%) stddev-sans-first:639.56 09:12:58 INFO - PROCESS | 1634 | Values: 2934.3 3484.4 3512.5 3512.6 3529.7 5036.6 5844.8 5975.2 6105.6 6107.8 4877.8 6014.8 6077.5 6093.8 6099.1 5368.8 5438.2 5440.8 5486.1 5497.9 5449.3 5452.6 5460.3 5462.3 5472.1 5319.1 5328.9 5494.3 5503.6 5523.3 5446.9 5470.2 5505.3 5505.3 5536.1 5397.8 5476.4 5478.7 5493.2 5513.7 6056.8 6099.5 6166.5 6192.6 6195.4 5756.7 6268.7 6297.1 6319.5 6355.8 6569.3 6579.4 6583.0 6585.3 6587.8 5213.4 6216.8 6223.3 6223.9 6227.2 6361.6 6431.8 6494.5 6580.2 6593.5 5165.5 6392.6 6421.6 6444.5 6448.3 6498.3 6522.1 6532.3 6552.3 6553.2 6137.3 6199.4 6432.2 6486.9 6493.5 5566.0 6099.8 6294.5 6344.8 6388.3 5210.9 5211.3 5212.6 5221.8 5248.0 4639.6 4656.0 4669.0 4681.3 4706.4 5521.4 6029.7 6114.1 6115.6 6226.2 4998.8 6296.9 6505.1 6514.4 6516.6 6004.6 6068.0 6119.0 6168.5 6215.9 5730.2 5776.3 5796.8 5802.7 5810.9 5687.9 6454.8 6497.8 6765.9 6794.9 6265.5 6377.5 6421.5 6472.4 6490.5 5497.3 5788.0 6722.9 6771.9 6773.8 6275.2 6449.2 6466.8 6473.2 6499.6 6318.5 6403.4 6410.1 6425.9 6478.3 6047.0 6353.9 6356.7 6358.1 6375.2 6337.0 6363.7 6364.7 6368.3 6379.3 5159.1 5997.5 6073.7 6207.4 6213.0 6315.3 6356.0 6390.5 6391.8 6401.4 6276.5 6310.8 6314.5 6334.4 6356.8 09:12:58 INFO - PROCESS | 1634 | 09:12:58 INFO - PROCESS | 1634 | [#1] ext.html Cycles:180 Average:53046.44 Median:182140.50 stddev:103047.28 (56.6%) stddev-sans-first:103261.56 09:12:58 INFO - PROCESS | 1634 | Values: 755.7 811.8 860.6 869.5 947.6 38009.0 39950.0 40028.8 40054.6 40085.2 38575.2 38646.8 38662.0 38780.0 38804.8 7248.5 7387.7 7736.0 7909.2 11024.6 11096.2 11219.1 11245.0 11246.7 11279.7 11257.8 11265.2 11273.3 11286.4 11301.8 11253.9 11279.8 11280.2 11282.9 11293.3 10785.1 10820.4 10854.7 10855.8 10867.8 30020.4 37033.0 39916.6 39999.4 40131.4 3219.6 3517.2 3538.0 3559.2 3672.8 26647.0 26660.8 26821.6 27952.3 28318.6 2683.2 2842.3 2889.5 2920.3 3059.6 284453.0 288492.1 290313.0 331859.0 332822.0 42375.8 42881.6 52425.0 52806.5 53623.5 56048.0 56407.0 56460.4 56541.7 56592.0 14675.5 14783.9 14828.5 14866.8 14929.7 12736.5 12804.7 12825.4 12842.0 12847.3 10343.6 12377.8 12424.7 12455.6 12592.8 4499.1 5293.9 5295.2 5312.8 5321.7 1083.8 1110.3 1125.2 1129.4 1162.7 20753.9 23974.8 26803.0 27064.7 27231.6 1090.4 1171.4 1223.9 1240.5 1250.6 11996.8 13863.7 13912.9 13930.9 13957.8 437540.0 437838.0 438076.8 438617.0 438990.0 56528.4 56833.1 56843.6 56906.1 57295.0 1396.6 1425.6 1426.9 1438.6 1438.7 15815.1 24352.5 24912.3 25538.2 26463.2 33624.8 36980.8 39923.4 43486.0 46849.8 409680.0 411043.0 411195.0 411592.0 411831.0 33189.0 33826.3 34221.3 34418.3 34597.0 38527.0 39246.6 42958.0 44080.9 44476.1 35643.6 35758.0 35987.3 36084.0 37076.1 34842.0 35331.1 36412.0 36487.6 36609.7 35516.8 35693.0 35756.8 35761.5 36616.3 30661.7 32088.9 32132.5 32229.5 32422.0 19024.5 22908.8 24011.5 24521.7 24528.3 09:12:58 INFO - PROCESS | 1634 | 09:12:58 INFO - PROCESS | 1634 | [#2] jquery.html Cycles:180 Average:45069.28 Median:14477.78 stddev:132189.61 (913.1%) stddev-sans-first:132517.75 09:12:58 INFO - PROCESS | 1634 | Values: 332.9 400.9 405.4 419.3 449.1 19457.2 19770.7 21178.9 21689.8 21904.7 22499.3 22854.3 22890.2 22912.2 23186.4 6506.2 6959.7 6979.9 6991.2 6994.6 6852.1 7288.3 7661.9 7779.7 7824.9 6993.4 7308.1 7731.0 7757.4 7788.4 7320.8 7326.9 7344.8 7378.9 7388.3 7503.6 7533.6 7571.8 7572.4 7603.4 25588.7 25601.4 25670.4 26341.6 26564.9 2613.9 2861.6 2991.4 3006.7 3029.0 15078.2 15231.8 15309.1 15397.7 15597.1 1651.7 1891.3 1893.8 1899.5 1926.5 130072.0 167893.0 173804.0 175111.1 182296.1 21527.7 21573.9 21691.9 21777.5 21861.7 25728.0 31620.4 31897.2 32235.7 32351.2 8192.0 9239.7 9377.7 9519.0 9558.9 7666.1 7745.8 7792.3 7885.6 7921.1 9585.3 9655.9 9810.5 9827.7 9838.8 3256.2 3384.0 3608.7 4251.1 4289.0 844.5 884.4 886.5 919.8 929.1 18173.5 19942.6 21747.7 21779.9 21852.9 842.8 846.7 858.8 930.8 941.9 10620.8 11271.4 11281.3 11311.7 11330.9 782623.0 783717.0 789193.0 790631.0 806859.0 159805.4 160116.0 162108.0 162232.4 169671.0 1164.9 1193.1 1213.3 1221.3 1233.6 18518.6 18788.6 19027.6 19184.3 19198.3 48812.5 59968.0 63188.0 65896.7 67104.0 97491.0 98738.5 99165.0 99206.0 99788.0 9193.9 10495.9 10892.5 11390.0 11941.5 12494.4 13466.3 13724.5 13732.9 13925.8 11312.4 11432.3 11584.1 11839.8 11862.7 10111.8 11468.5 11797.8 11915.6 11950.7 6604.8 9218.4 11612.1 12034.8 12129.7 11161.1 11823.3 11914.5 12082.5 12111.8 6198.6 11190.7 11219.0 11490.1 11584.4 09:12:58 INFO - PROCESS | 1634 | 09:12:58 INFO - PROCESS | 1634 | [#3] mootools.html Cycles:180 Average:6691.58 Median:2710.52 stddev:4187.82 (154.5%) stddev-sans-first:4180.61 09:12:58 INFO - PROCESS | 1634 | Values: 1387.7 1545.3 1556.5 1558.0 1601.8 10904.2 11044.6 11365.7 11591.8 11638.8 10674.3 11028.1 11046.2 11083.7 11106.6 2337.3 2396.1 2398.5 2399.6 2410.6 2185.1 2241.3 2402.0 2404.5 2422.5 2353.7 2376.5 2379.8 2383.2 2393.4 2320.5 2335.9 2350.2 2351.1 2352.5 2334.7 2384.9 2410.2 2412.4 2421.2 9606.1 10428.8 10685.6 10844.6 10876.6 5757.4 5926.5 5971.3 5978.9 5988.5 2998.5 3589.9 3642.4 3680.3 3713.0 935.3 1007.9 1132.6 1170.0 1202.2 13612.7 13758.4 14987.2 15553.4 15738.7 4093.0 4533.1 4761.6 4830.3 4995.7 10961.9 12763.9 12830.2 12845.6 12852.9 5914.3 5982.0 5989.5 5994.4 5994.6 4992.3 5012.7 5051.6 5223.7 5277.8 3799.8 4136.6 4198.6 4303.4 4303.5 1149.0 1162.7 1165.9 1174.9 1179.5 807.5 833.4 838.3 864.5 899.7 5596.6 6271.3 6292.6 6460.2 6500.0 917.2 941.6 942.7 950.3 975.4 3975.0 3999.7 4078.4 4117.6 4140.4 12567.5 12808.8 13467.9 13700.9 13893.9 13551.9 13739.3 13751.2 13763.0 13765.7 8490.9 9933.4 10201.1 10235.1 10260.4 5808.8 6502.4 6593.6 6818.2 6818.5 11647.9 11739.6 12073.3 12096.2 12137.3 12056.8 12286.6 12309.1 12324.7 12340.1 9076.0 9323.5 9334.6 9338.0 9345.3 10112.8 10188.9 10210.2 10320.3 10344.7 8545.5 8573.5 8639.8 8647.0 8770.9 8806.5 8822.5 8877.9 8924.5 9082.6 7687.4 7813.8 7817.6 7821.7 7865.5 5592.4 5619.9 5702.9 5758.9 5847.2 5700.1 5798.8 5807.2 5821.8 5905.4 09:12:58 INFO - PROCESS | 1634 | 09:12:58 INFO - PROCESS | 1634 | [#4] prototype.html Cycles:180 Average:4729.91 Median:5119.44 stddev:1330.34 (26.0%) stddev-sans-first:1292.91 09:12:58 INFO - PROCESS | 1634 | Values: 354.5 371.1 381.6 392.7 395.4 4436.3 4861.2 5329.8 5427.6 5428.8 4578.8 4650.6 4689.8 5144.0 5170.6 4291.7 4418.0 4457.8 4460.6 4516.3 4405.3 4407.6 4457.5 4500.8 4544.8 3979.8 4103.3 4191.5 4482.1 4504.9 3762.2 4002.8 4423.4 4457.0 4494.7 3542.8 3688.9 4044.3 4063.1 4089.9 4452.8 4716.9 5190.3 5259.5 5323.4 4637.6 4793.4 4904.0 5057.8 5114.8 5155.8 5238.2 5287.3 5745.2 5855.0 4255.4 4340.6 4595.7 4634.0 4695.0 5306.8 5372.6 5411.5 5963.3 6148.4 4192.8 4481.6 4605.5 4667.8 4670.3 3988.8 4117.6 4699.6 4791.6 4793.1 4100.9 4657.2 5079.5 5092.2 5141.3 4751.5 5415.4 5445.8 5572.7 5775.6 1463.7 1567.2 1603.3 1615.2 1615.3 1108.1 1128.7 1173.5 1202.1 1208.8 4766.2 4801.5 5148.6 5158.8 5335.8 5303.8 5428.9 5574.0 5685.7 5961.3 4732.3 5056.7 5124.0 5354.3 5478.8 2804.8 2961.0 2976.7 3041.0 3130.0 5424.9 6056.6 6074.8 6241.6 6304.7 5886.8 5890.7 6004.6 6073.9 6169.2 5470.8 5732.9 5760.7 5867.6 6206.4 5128.7 5502.5 5737.9 5996.7 6016.0 4586.4 4860.7 5327.9 5828.9 5996.9 5461.8 5521.5 6038.5 6203.8 6263.9 3744.7 4363.0 4379.4 4703.3 4703.6 4821.7 5255.1 5360.4 5394.9 5951.9 4987.0 5701.3 5780.5 5799.8 5966.9 5654.7 5807.4 5833.4 5836.8 5882.5 5251.0 5427.4 5480.8 5485.5 5614.3 5069.8 5809.9 5834.9 5836.9 5861.8 5217.2 5321.4 5329.6 5675.8 5691.0 09:12:58 INFO - PROCESS | 1634 | 09:12:58 INFO - PROCESS | 1634 | [#5] yui.html Cycles:180 Average:20372.59 Median:3932.64 stddev:30384.70 (772.6%) stddev-sans-first:30433.84 09:12:58 INFO - PROCESS | 1634 | Values: 645.4 657.9 722.5 734.7 766.9 13678.7 15456.7 16329.8 16353.7 16363.2 12945.9 14761.4 15108.2 15131.8 15474.0 910.4 937.4 938.0 938.7 939.2 889.6 922.7 940.5 942.0 944.6 889.4 940.4 992.7 996.5 997.0 972.4 982.7 989.5 989.9 995.8 725.3 733.5 733.6 734.6 734.7 12795.0 13024.2 15625.7 16456.9 16471.8 13307.6 13357.0 13401.6 13419.6 13585.1 9450.8 10689.1 11052.8 11072.3 11202.6 3298.4 3332.5 3364.8 3388.0 3440.5 83015.0 120292.8 123319.0 124248.0 124624.4 7845.6 9450.5 9487.1 9534.2 10221.0 19135.3 22678.9 23097.3 23245.8 23304.4 6085.5 7111.9 7177.1 7201.5 7397.9 3744.6 3863.2 3882.1 3947.5 3976.8 3419.2 3514.6 3720.4 3879.2 3917.8 641.5 642.6 646.3 657.5 658.9 589.9 621.5 629.9 633.7 647.5 9171.0 9641.6 9749.9 10034.5 10069.4 603.5 624.5 627.6 641.7 653.8 4560.0 4873.9 4877.4 4890.9 4901.1 107290.4 108472.0 109096.0 109934.5 111495.0 63840.0 68658.7 74607.0 95194.0 97057.5 73982.0 75017.6 77597.0 78253.2 78981.8 9124.5 10098.9 10192.0 10345.3 10381.6 51340.7 51611.7 52050.7 52219.4 52851.5 64854.7 66874.7 67466.3 68019.3 68418.9 11370.8 11474.2 11506.3 11726.4 12377.9 13219.9 13220.5 13439.5 13552.6 13908.0 12280.7 12418.3 12486.1 12847.4 12945.7 12310.8 12317.3 12461.8 12497.8 12614.6 9586.8 11975.2 12214.1 12243.3 12499.6 10218.5 10428.2 10750.2 10844.7 11127.4 8579.4 10453.7 10460.8 10543.6 10630.4 09:12:58 INFO - PROCESS | 1634 | -------- Summary: end -------- 09:12:58 INFO - PROCESS | 1634 | 09:12:59 INFO - TEST-INFO | 1634: exit 0 09:12:59 INFO - TEST-OK | dromaeo_css | took 1116579ms 09:12:59 INFO - TEST-START | kraken 09:12:59 INFO - operating with platform_type : mac_ 09:12:59 INFO - Initialising browser for kraken test... 09:12:59 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/tmpIo5zmX/profile http://localhost:49244/getInfo.html) 09:13:02 INFO - PROCESS | 1646 | 2017-03-15 09:13:02.308 plugin-container[1647:10269] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893f, name = 'com.apple.tsm.portname' 09:13:02 INFO - PROCESS | 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:13:02 INFO - PROCESS | 1646 | 2017-03-15 09:13:02.311 plugin-container[1647:10269] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 09:13:02 INFO - PROCESS | 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:13:02 INFO - PROCESS | 1646 | 2017-03-15 09:13:02.311 plugin-container[1647:10269] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:13:02 INFO - PROCESS | 1646 | __metrics Screen width/height:1600/1200 09:13:02 INFO - PROCESS | 1646 | colorDepth:24 09:13:02 INFO - PROCESS | 1646 | Browser inner width/height: 1024/689 09:13:02 INFO - PROCESS | 1646 | __metrics 09:13:02 ERROR - PROCESS | 1646 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3353: TypeError: this.tabs is undefined 09:13:03 INFO - TEST-INFO | 1646: exit 0 09:13:03 INFO - Browser initialized. 09:13:03 INFO - Running cycle 1/1 for kraken test... 09:13:03 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/tmpIo5zmX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:13:05 INFO - PROCESS | 1648 | 2017-03-15 09:13:05.816 plugin-container[1649:10399] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a47, name = 'com.apple.tsm.portname' 09:13:05 INFO - PROCESS | 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:13:05 INFO - PROCESS | 1648 | 2017-03-15 09:13:05.816 plugin-container[1649:10399] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9017, name = 'com.apple.CFPasteboardClient' 09:13:05 INFO - PROCESS | 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:13:05 INFO - PROCESS | 1648 | 2017-03-15 09:13:05.816 plugin-container[1649:10399] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:13:15 INFO - PROCESS | 1648 | RSS: Main: 220127232 09:13:15 INFO - PROCESS | 1648 | 09:13:18 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection) 09:13:19 INFO - PROCESS | 1648 | RSS: Main: 234708992 09:13:19 INFO - PROCESS | 1648 | 09:13:22 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-dft) 09:13:22 INFO - PROCESS | 1648 | RSS: Main: 242896896 09:13:22 INFO - PROCESS | 1648 | 09:13:26 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-fft) 09:13:26 INFO - PROCESS | 1648 | RSS: Main: 246747136 09:13:26 INFO - PROCESS | 1648 | 09:13:28 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator) 09:13:29 INFO - PROCESS | 1648 | RSS: Main: 248008704 09:13:29 INFO - PROCESS | 1648 | 09:13:31 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:13:31 INFO - PROCESS | 1648 | RSS: Main: 248049664 09:13:31 INFO - PROCESS | 1648 | 09:13:35 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom) 09:13:35 INFO - PROCESS | 1648 | RSS: Main: 252465152 09:13:35 INFO - PROCESS | 1648 | 09:13:39 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate) 09:13:39 INFO - PROCESS | 1648 | RSS: Main: 252084224 09:13:39 INFO - PROCESS | 1648 | 09:13:43 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial) 09:13:43 INFO - PROCESS | 1648 | RSS: Main: 249413632 09:13:43 INFO - PROCESS | 1648 | 09:13:45 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:13:46 INFO - PROCESS | 1648 | RSS: Main: 250261504 09:13:46 INFO - PROCESS | 1648 | 09:13:47 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:13:48 INFO - PROCESS | 1648 | RSS: Main: 250609664 09:13:48 INFO - PROCESS | 1648 | 09:13:50 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:13:51 INFO - PROCESS | 1648 | RSS: Main: 250687488 09:13:51 INFO - PROCESS | 1648 | 09:13:54 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:13:55 INFO - PROCESS | 1648 | RSS: Main: 251260928 09:13:55 INFO - PROCESS | 1648 | 09:13:58 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:13:59 INFO - PROCESS | 1648 | RSS: Main: 250761216 09:13:59 INFO - PROCESS | 1648 | 09:14:00 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49244/tests/kraken/driver.html?testName=ai-astar) 09:14:01 INFO - PROCESS | 1648 | RSS: Main: 254132224 09:14:01 INFO - PROCESS | 1648 | 09:14:01 INFO - PROCESS | 1648 | __start_tp_report 09:14:01 INFO - PROCESS | 1648 | _x_x_mozilla_page_load 09:14:01 INFO - PROCESS | 1648 | _x_x_mozilla_page_load_details 09:14:01 INFO - PROCESS | 1648 | |i|pagename|runs| 09:14:01 INFO - PROCESS | 1648 | |0;ai-astar;107;120;119;120;119;134;177;124;121;119 09:14:01 INFO - PROCESS | 1648 | |1;audio-beat-detection;135;113;111;145;112;114;120;143;119;145 09:14:01 INFO - PROCESS | 1648 | |2;audio-dft;201;196;197;198;194;197;193;196;200;194 09:14:01 INFO - PROCESS | 1648 | |3;audio-fft;83;58;60;91;62;63;78;85;98;83 09:14:01 INFO - PROCESS | 1648 | |4;audio-oscillator;90;73;85;73;71;70;82;70;70;84 09:14:01 INFO - PROCESS | 1648 | |5;imaging-gaussian-blur;137;136;88;91;104;92;136;101;94;89 09:14:01 INFO - PROCESS | 1648 | |6;imaging-darkroom;119;120;113;93;131;115;90;146;114;108 09:14:01 INFO - PROCESS | 1648 | |7;imaging-desaturate;133;106;132;148;143;91;145;143;93;87 09:14:01 INFO - PROCESS | 1648 | |8;json-parse-financial;67;68;70;69;71;71;69;70;69;69 09:14:01 INFO - PROCESS | 1648 | |9;json-stringify-tinderbox;58;63;57;68;79;83;82;56;58;60 09:14:01 INFO - PROCESS | 1648 | |10;stanford-crypto-aes;82;88;86;87;85;89;110;100;104;102 09:14:01 INFO - PROCESS | 1648 | |11;stanford-crypto-ccm;143;149;140;142;157;142;138;142;210;181 09:14:01 INFO - PROCESS | 1648 | |12;stanford-crypto-pbkdf2;209;184;167;168;178;221;225;175;167;171 09:14:01 INFO - PROCESS | 1648 | |13;stanford-crypto-sha256-iterative;77;84;84;80;67;67;67;66;67;68 09:14:01 INFO - PROCESS | 1648 | __end_tp_report 09:14:01 INFO - PROCESS | 1648 | __start_cc_report 09:14:01 INFO - PROCESS | 1648 | _x_x_mozilla_cycle_collect,1014 09:14:01 INFO - PROCESS | 1648 | __end_cc_report 09:14:01 INFO - PROCESS | 1648 | __startTimestamp1489594441053__endTimestamp 09:14:01 INFO - PROCESS | 1648 | 09:14:01 INFO - PROCESS | 1648 | ------- Summary: start ------- 09:14:01 INFO - PROCESS | 1648 | Number of tests: 14 09:14:01 INFO - PROCESS | 1648 | 09:14:01 INFO - PROCESS | 1648 | [#0] ai-astar Cycles:10 Average:126.00 Median:120.50 stddev:19.07 (15.8%) stddev-sans-first:18.95 09:14:01 INFO - PROCESS | 1648 | Values: 107.0 120.0 119.0 120.0 119.0 134.0 177.0 124.0 121.0 119.0 09:14:01 INFO - PROCESS | 1648 | 09:14:01 INFO - PROCESS | 1648 | [#1] audio-beat-detection Cycles:10 Average:125.70 Median:127.50 stddev:14.57 (11.4%) stddev-sans-first:15.06 09:14:01 INFO - PROCESS | 1648 | Values: 135.0 113.0 111.0 145.0 112.0 114.0 120.0 143.0 119.0 145.0 09:14:01 INFO - PROCESS | 1648 | 09:14:01 INFO - PROCESS | 1648 | [#2] audio-dft Cycles:10 Average:196.60 Median:197.00 stddev:2.59 (1.3%) stddev-sans-first:2.20 09:14:01 INFO - PROCESS | 1648 | Values: 201.0 196.0 197.0 198.0 194.0 197.0 193.0 196.0 200.0 194.0 09:14:01 INFO - PROCESS | 1648 | 09:14:01 INFO - PROCESS | 1648 | [#3] audio-fft Cycles:10 Average:76.10 Median:83.00 stddev:14.29 (17.2%) stddev-sans-first:14.93 09:14:01 INFO - PROCESS | 1648 | Values: 83.0 58.0 60.0 91.0 62.0 63.0 78.0 85.0 98.0 83.0 09:14:01 INFO - PROCESS | 1648 | 09:14:01 INFO - PROCESS | 1648 | [#4] audio-oscillator Cycles:10 Average:76.80 Median:77.50 stddev:7.61 (9.8%) stddev-sans-first:6.40 09:14:01 INFO - PROCESS | 1648 | Values: 90.0 73.0 85.0 73.0 71.0 70.0 82.0 70.0 70.0 84.0 09:14:01 INFO - PROCESS | 1648 | 09:14:01 INFO - PROCESS | 1648 | [#5] imaging-gaussian-blur Cycles:10 Average:106.80 Median:88.50 stddev:20.98 (23.7%) stddev-sans-first:19.20 09:14:01 INFO - PROCESS | 1648 | Values: 137.0 136.0 88.0 91.0 104.0 92.0 136.0 101.0 94.0 89.0 09:14:01 INFO - PROCESS | 1648 | 09:14:01 INFO - PROCESS | 1648 | [#6] imaging-darkroom Cycles:10 Average:114.90 Median:125.50 stddev:16.40 (13.1%) stddev-sans-first:17.33 09:14:01 INFO - PROCESS | 1648 | Values: 119.0 120.0 113.0 93.0 131.0 115.0 90.0 146.0 114.0 108.0 09:14:01 INFO - PROCESS | 1648 | 09:14:01 INFO - PROCESS | 1648 | [#7] imaging-desaturate Cycles:10 Average:122.10 Median:146.50 stddev:24.92 (17.0%) stddev-sans-first:26.12 09:14:01 INFO - PROCESS | 1648 | Values: 133.0 106.0 132.0 148.0 143.0 91.0 145.0 143.0 93.0 87.0 09:14:01 INFO - PROCESS | 1648 | 09:14:01 INFO - PROCESS | 1648 | [#8] json-parse-financial Cycles:10 Average:69.30 Median:69.50 stddev:1.25 (1.8%) stddev-sans-first:1.01 09:14:01 INFO - PROCESS | 1648 | Values: 67.0 68.0 70.0 69.0 71.0 71.0 69.0 70.0 69.0 69.0 09:14:01 INFO - PROCESS | 1648 | 09:14:01 INFO - PROCESS | 1648 | [#9] json-stringify-tinderbox Cycles:10 Average:66.40 Median:65.50 stddev:10.91 (16.6%) stddev-sans-first:11.14 09:14:01 INFO - PROCESS | 1648 | Values: 58.0 63.0 57.0 68.0 79.0 83.0 82.0 56.0 58.0 60.0 09:14:01 INFO - PROCESS | 1648 | 09:14:01 INFO - PROCESS | 1648 | [#10] stanford-crypto-aes Cycles:10 Average:93.30 Median:85.50 stddev:9.72 (11.4%) stddev-sans-first:9.41 09:14:01 INFO - PROCESS | 1648 | Values: 82.0 88.0 86.0 87.0 85.0 89.0 110.0 100.0 104.0 102.0 09:14:01 INFO - PROCESS | 1648 | 09:14:01 INFO - PROCESS | 1648 | [#11] stanford-crypto-ccm Cycles:10 Average:154.40 Median:146.00 stddev:23.34 (16.0%) stddev-sans-first:24.39 09:14:01 INFO - PROCESS | 1648 | Values: 143.0 149.0 140.0 142.0 157.0 142.0 138.0 142.0 210.0 181.0 09:14:01 INFO - PROCESS | 1648 | 09:14:01 INFO - PROCESS | 1648 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:186.50 Median:181.00 stddev:22.93 (12.7%) stddev-sans-first:22.83 09:14:01 INFO - PROCESS | 1648 | Values: 209.0 184.0 167.0 168.0 178.0 221.0 225.0 175.0 167.0 171.0 09:14:01 INFO - PROCESS | 1648 | 09:14:01 INFO - PROCESS | 1648 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.70 Median:72.50 stddev:7.63 (10.5%) stddev-sans-first:7.93 09:14:01 INFO - PROCESS | 1648 | Values: 77.0 84.0 84.0 80.0 67.0 67.0 67.0 66.0 67.0 68.0 09:14:01 INFO - PROCESS | 1648 | -------- Summary: end -------- 09:14:01 INFO - PROCESS | 1648 | 09:14:01 INFO - TEST-INFO | 1648: exit 0 09:14:01 INFO - TEST-OK | kraken | took 62596ms 09:14:01 INFO - SUITE-END | took 1179s 09:14:02 INFO - Completed test suite (00:19:39) 09:14:02 INFO - javascript benchmark 09:14:02 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7689.095903045829, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2934.281101130223, 3484.3553942520634, 3512.543369361983, 3512.596051454082, 3529.699975502082, 5036.6222533309965, 5844.795432159875, 5975.163477113208, 6105.633661980284, 6107.847303817396, 4877.756112194394, 6014.75940962361, 6077.513798896089, 6093.756249750005, 6099.146119543257, 5368.812091576786, 5438.238646589461, 5440.809571664992, 5486.1222204447295, 5497.945020549803, 5449.264349312852, 5452.59105567082, 5460.31746031746, 5462.262594549745, 5472.1244600863865, 5319.069162896497, 5328.946710532903, 5494.2582751328655, 5503.587230957674, 5523.25436066132, 5446.891062178754, 5470.152126420411, 5505.256790333294, 5505.339359276913, 5536.086545719952, 5397.7571009304675, 5476.3702174250075, 5478.698671573062, 5493.176023596492, 5513.669179849222, 6056.757729690807, 6099.512039036911, 6166.475849552793, 6192.597481163755, 6195.44241018306, 5756.65460072397, 6268.65522396268, 6297.118403423525, 6319.4944404448, 6355.8411039723915, 6569.343065693441, 6579.407853293226, 6583.045458408552, 6585.341465853424, 6587.835304117388, 5213.426523082534, 6216.782412615582, 6223.346548612421, 6223.906641400383, 6227.19046523949, 6361.61830290184, 6431.807045788634, 6494.545381823227, 6580.243272023683, 6593.505487088488, 5165.457626949192, 6392.648404337806, 6421.646809425526, 6444.484441244783, 6448.3229260927865, 6498.252700939453, 6522.05430212626, 6532.281449040602, 6552.312007239267, 6553.213614366211, 6137.346341658241, 6199.380061993765, 6432.2281326241155, 6486.935130648728, 6493.5454518184215, 5565.972170139124, 6099.756009759649, 6294.527910406738, 6344.841378965562, 6388.265349484869, 5210.947890521122, 5211.270422140828, 5212.609054320941, 5221.817236396708, 5247.9737601311745, 4639.582437580567, 4656.0, 4668.976655116703, 4681.29780532916, 4706.388169538007, 5521.3926468088475, 6029.668368239789, 6114.11345354921, 6115.6330620162935, 6226.221722284715, 4998.825041123543, 6296.874062518724, 6505.121808555785, 6514.413702766774, 6516.641584712792, 6004.639721616717, 6067.969660151671, 6118.9846826261555, 6168.506519478431, 6215.875682486325, 5730.226419433407, 5776.335721392094, 5796.826095217066, 5802.709864506758, 5810.912836307459, 5687.857803554944, 6454.806355809334, 6497.805065848032, 6765.86468270622, 6794.9320506796275, 6265.467435268053, 6377.4579160771855, 6421.486281097407, 6472.352764723679, 6490.513211509061, 5497.257870187634, 5788.0, 6722.899156512558, 6771.864562708817, 6773.796786096425, 6275.152854364785, 6449.161608990801, 6466.838329041811, 6473.158489396348, 6499.5775274607, 6318.526110541636, 6403.423691867754, 6410.0705397718, 6425.8714825704155, 6478.319776423407, 6047.03247480401, 6353.936460635519, 6356.650384228912, 6358.078078678658, 6375.203099612549, 6336.968315158395, 6363.713632886624, 6364.713587888464, 6368.299487056327, 6379.266384365672, 5159.122949098587, 5997.520198384032, 6073.665948372882, 6207.441330280297, 6213.03697926822, 6315.273743519554, 6356.046593011085, 6390.456811171102, 6391.840203994937, 6401.423871851555, 6276.529260305404, 6310.7791227306825, 6314.526410519137, 6334.398232167937, 6356.841078973062], "value": 5905.007950183577, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [755.7014979083262, 811.7524155132684, 860.6385318166374, 869.5347988825979, 947.5593848860278, 38009.049773755636, 39950.0, 40028.79985600072, 40054.59945400545, 40085.198296034134, 38575.22849543014, 38646.806765966205, 38662.0, 38780.03049923753, 38804.80597597015, 7248.492605517616, 7387.667554960013, 7736.0, 7909.209079092102, 11024.614138505154, 11096.223264371498, 11219.102471802236, 11244.9879510844, 11246.66260012201, 11279.661610151708, 11257.774844503105, 11265.155113366489, 11273.267237629569, 11286.435678216098, 11301.773964520704, 11253.943730281337, 11279.774404511905, 11280.210385273034, 11282.887171128266, 11293.265937714023, 10785.083267922237, 10820.404877731722, 10854.72863178419, 10855.83716244249, 10867.78264434707, 30020.399592008038, 37033.0, 39916.60083399158, 39999.40000899989, 40131.39802902959, 3219.613646362428, 3517.208628058692, 3538.0, 3559.181388280684, 3672.761270517408, 26647.0, 26660.800263988167, 26821.597676034875, 27952.301192470146, 28318.575221371695, 2683.18162960297, 2842.346260360129, 2889.511901370796, 2920.2845302900714, 3059.6481404638307, 284453.0, 288492.1150788486, 290313.0, 331859.0, 332822.0, 42375.78812105981, 42881.57118428838, 52424.95150097053, 52806.471935280926, 53623.46376536263, 56048.0, 56407.0, 56460.43539564634, 56541.717291414105, 56592.0, 14675.48635797742, 14783.891208159432, 14828.481003164838, 14866.776998345034, 14929.70140597182, 12736.490540378281, 12804.743905122032, 12825.422855971503, 12841.972642188603, 12847.293398863152, 10343.586256549805, 12377.814332785016, 12424.695406982317, 12455.626331210078, 12592.81110783339, 4499.0776890737325, 5293.894122117613, 5295.152775555893, 5312.814051508179, 5321.6540924839765, 1083.7832433513329, 1110.2838669058413, 1125.1561329003248, 1129.4183495499824, 1162.686074759827, 20753.896230518752, 23974.801259936932, 26803.0, 27064.729352706614, 27231.591526127126, 1090.4002798460815, 1171.4142928535732, 1223.8959688426582, 1240.5189620758483, 1250.593557093948, 11996.820047699292, 13863.653408664864, 13912.930435347758, 13930.930345348208, 13957.79063314051, 437540.0, 437838.0, 438076.80961594987, 438617.0, 438990.0, 56528.434715652314, 56833.14750278749, 56843.57891052757, 56906.14640780391, 57295.0, 1396.60196843899, 1425.5794540610336, 1426.8787153092103, 1438.6475313548037, 1438.7482190616809, 15815.051096934221, 24352.51294974126, 24912.263160526065, 25538.233852984442, 26463.206103816916, 33624.831875840464, 36980.81509592543, 39923.40114898221, 43486.0, 46849.76575117103, 409680.0, 411043.0, 411195.0, 411592.0, 411831.0, 33189.0, 33826.32347353089, 34221.31557368889, 34418.311633767684, 34597.0, 38527.0, 39246.60753392544, 42958.0, 44080.89797754953, 44476.11047779091, 35643.64356356403, 35758.0, 35987.280254395286, 36084.0, 37076.073098171684, 34842.0, 35331.116722081126, 36412.0, 36487.635123648426, 36609.718659846774, 35516.82241588879, 35693.0, 35756.82121589479, 35761.46357804583, 36616.26767464689, 30661.693383065885, 32088.87688930876, 32132.518012230303, 32229.51655725119, 32422.0, 19024.546190195488, 22908.770912290664, 24011.51976960486, 24521.651309178167, 24528.264152075466], "value": 18615.30283716765, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [332.88681848171626, 400.87372477669516, 405.4445409788588, 419.3374468340023, 449.11748414365786, 19457.221711131555, 19770.71490353128, 21178.941052947368, 21689.783102168974, 21904.671429928563, 22499.325020249376, 22854.314370568867, 22890.198843040496, 22912.198073067448, 23186.42033949152, 6506.154199954023, 6959.686814093353, 6979.930200698004, 6991.230964593916, 6994.6152961587095, 6852.143482064742, 7288.3076107769675, 7661.923380766205, 7779.688812447495, 7824.943632609582, 6993.440524758033, 7308.086489188851, 7731.0, 7757.379409647215, 7788.415868809862, 7320.780376588711, 7326.853462930738, 7344.779656610311, 7378.889316660255, 7388.335049845513, 7503.587302698349, 7533.585652789095, 7571.810704732426, 7572.356349710281, 7603.429742769259, 25588.744112558823, 25601.35996600081, 25670.358241043938, 26341.604875926878, 26564.867175664, 2613.9477210455875, 2861.642294713161, 2991.41667374862, 3006.729394354497, 3029.0458505570896, 15078.246087695572, 15231.847681523153, 15309.08145511273, 15397.692046159125, 15597.064176149468, 1651.6531528379055, 1891.3096719697212, 1893.848492120641, 1899.4681489182913, 1926.4991102313359, 130072.0, 167893.0, 173804.0, 175111.1244443795, 182296.08851955656, 21527.67708484374, 21573.892130539247, 21691.915404229727, 21777.455563610718, 21861.67207491889, 25728.0, 31620.367592648017, 31897.202569935936, 32235.67764322327, 32351.191220219684, 8192.0, 9239.676611318571, 9377.671781487616, 9519.048095190425, 9558.856617150748, 7666.080070391477, 7745.845083098419, 7792.2986931176465, 7885.5662938538935, 7921.089074756362, 9585.329026968186, 9655.889572699229, 9810.460424676712, 9827.656032038843, 9838.754031149136, 3256.153400115987, 3383.9661603384143, 3608.6752192302292, 4251.128518653731, 4288.978555107205, 844.4848642328175, 884.4030279561271, 886.4548302793736, 919.7838507950627, 929.1080562659922, 18173.45479635613, 19942.60114797725, 21747.67378489324, 21779.89110054471, 21852.89073554622, 842.7850898021088, 846.7375113714768, 858.7552547523926, 930.7905721212809, 941.9152276295166, 10620.787584248426, 11271.38007409584, 11281.266717663326, 11311.717207069725, 11330.886691133148, 782623.0, 783717.0, 789193.0, 790631.0, 806859.0, 159805.40194598137, 160116.0, 162108.0, 162232.37767622407, 169671.0, 1164.895159435655, 1193.0932491306496, 1213.3266037349179, 1221.3099598726692, 1233.617578550652, 18518.629627407645, 18788.624227515098, 19027.619447611247, 19184.328548500736, 19198.29854367729, 48812.511874880794, 59968.0, 63188.0, 65896.67051664711, 67104.0, 97491.0, 98738.50630746801, 99165.01669966705, 99206.00793992257, 99788.0, 9193.908060919306, 10495.947520262349, 10892.529508516249, 11389.974902258837, 11941.46263418131, 12494.437750301076, 13466.259355735529, 13724.519641812489, 13732.931335343259, 13925.79111313311, 11312.377819220023, 11432.256903301259, 11584.13119016094, 11839.76320473603, 11862.703432414259, 10111.848322275024, 11468.483918223534, 11797.803160819574, 11915.642530724092, 11950.701232469259, 6604.768833090819, 9218.44689318643, 11612.129090318429, 12034.75930481403, 12129.69675758113, 11161.107111431229, 11823.349715765715, 11914.52341906314, 12082.516699331929, 12111.757764844477, 6198.597091189058, 11190.66428007161, 11219.046381057702, 11490.138239631893, 11584.420778961186], "value": 11587.948382022392, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1387.7155183187444, 1545.2505534815612, 1556.4941394046937, 1557.984420155798, 1601.7677436771664, 10904.182186336016, 11044.613438529654, 11365.659030229086, 11591.768164636713, 11638.825417618742, 10674.306170098938, 11028.117750579955, 11046.226764126515, 11083.667489975294, 11106.555737770499, 2337.345543247893, 2396.1134380279323, 2398.5322862041908, 2399.568077746005, 2410.6384042393693, 2185.0822654485073, 2241.2827895073583, 2401.9639705404434, 2404.519096180762, 2422.527607116613, 2353.682252895863, 2376.524695060995, 2379.809615230786, 2383.201627454796, 2393.3896856301617, 2320.5474932388192, 2335.9182428615004, 2350.2009316832273, 2351.071326825894, 2352.5059737455153, 2334.731505876829, 2384.9165279215144, 2410.20463247128, 2412.433078226633, 2421.2373102472666, 9606.087421694929, 10428.84356734642, 10685.572577096907, 10844.566217351297, 10876.564937402494, 5757.424257574251, 5926.466618004357, 5971.253593300838, 5978.8505287367725, 5988.520918326567, 2998.5202367621296, 3589.928201435983, 3642.380795264785, 3680.3191409589313, 3712.981435092834, 935.2564711054728, 1007.8560833454009, 1132.6262333430032, 1170.0405667352775, 1202.1765090912752, 13612.727745445036, 13758.449662013507, 14987.175705336202, 15553.377864885391, 15738.685226295525, 4092.956296144464, 4533.0707205023555, 4761.642876784255, 4830.29960655703, 4995.650304478724, 10961.945190273998, 12763.91506721939, 12830.230186188859, 12845.550405735754, 12852.871471285354, 5914.260717410324, 5981.970090149522, 5989.4609485145675, 5994.441445224273, 5994.6403215807195, 4992.2511623256805, 5012.67417617861, 5051.595872330205, 5223.712695801767, 5277.8416647500635, 3799.7910114943943, 4136.627703506639, 4198.601132892431, 4303.376010478463, 4303.483581970184, 1148.9827652585218, 1162.6512046386051, 1165.947532361046, 1174.8531433570804, 1179.4633441783967, 807.5423464307119, 833.3749687734199, 838.2832678060292, 864.4942708515463, 899.7255836969787, 5596.636218645856, 6271.310155882849, 6292.622442653455, 6460.224773027267, 6500.0, 917.2478567574658, 941.5668792355456, 942.688912658821, 950.2872845365976, 975.4391225045628, 3974.980125099356, 3999.6601138618307, 4078.4494093297617, 4117.5882411759185, 4140.399642051946, 12567.497300108076, 12808.80786788199, 13467.932660336635, 13700.93149534246, 13893.930530347487, 13551.864481355258, 13739.313034348243, 13751.17492950406, 13763.0, 13765.724685506233, 8490.872636910452, 9933.3543319684, 10201.13290370312, 10235.078842904175, 10260.435676037889, 5808.767649293981, 6502.447291980235, 6593.571417857824, 6818.18181818185, 6818.454523638196, 11647.88352116468, 11739.589114380955, 12073.335966521574, 12096.213746106476, 12137.332446715161, 12056.75886482283, 12286.569970051005, 12309.076819238771, 12324.691882703004, 12340.136190466941, 9075.954620226856, 9323.487208203615, 9334.579943902676, 9337.953310233406, 9345.252379809735, 10112.84830727525, 10188.898111018794, 10210.183185345006, 10320.329178603366, 10344.689659310232, 8545.529995850287, 8573.528455934735, 8639.784005399664, 8646.9623645163, 8770.912290877264, 8806.471611703319, 8822.470651760916, 8877.911220887709, 8924.464532128093, 9082.59128339213, 7687.385009199363, 7813.804654883674, 7817.648205830637, 7821.726239581587, 7865.488743231672, 5592.440755924375, 5619.915701264566, 5702.942970570241, 5758.884822303614, 5847.181394604674, 5700.144978253295, 5798.782255726344, 5807.245058142415, 5821.79623713168, 5905.350411454822], "value": 5090.47434593509, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [354.46829755366946, 371.0834239428614, 381.64507008482116, 392.6760422651312, 395.4384773621457, 4436.334549817529, 4861.2465067914445, 5329.760160792764, 5427.647202931812, 5428.7785248319105, 4578.793954272058, 4650.558326918649, 4689.788959496822, 5144.0, 5170.57809102497, 4291.656667466611, 4418.02803383256, 4457.821687132511, 4460.6208472279895, 4516.277395616702, 4405.317175837734, 4407.603315701586, 4457.487388950263, 4500.819967201308, 4544.773061200021, 3979.781112038837, 4103.261412945669, 4191.538930717618, 4482.125985432842, 4504.909901801979, 3762.2475504898857, 4002.7598344099156, 4423.429682462744, 4457.0, 4494.70784399013, 3542.81315759221, 3688.8524459021605, 4044.312466880608, 4063.085805693725, 4089.8773036808943, 4452.799624016927, 4716.858494245178, 5190.273361729361, 5259.474752321822, 5323.44103869092, 4637.6289896808175, 4793.44875339337, 4903.950960490421, 5057.797688092472, 5114.846554603368, 5155.845324640267, 5238.240455133984, 5287.312649355597, 5745.224394706703, 5855.033919403293, 4255.3829694694095, 4340.565943405666, 4595.655325850575, 4633.953660463386, 4694.976525117352, 5306.787728490895, 5372.597055220874, 5411.458854114597, 5963.314218864843, 6148.44663980244, 4192.769397683157, 4481.588299685589, 4605.539446055368, 4667.809708524304, 4670.346151538787, 3988.80055997199, 4117.649999750055, 4699.577038066591, 4791.592714619296, 4793.113274044313, 4100.938485922714, 4657.161710892072, 5079.517445842639, 5092.210707340369, 5141.280220769096, 4751.524847515221, 5415.377231618336, 5445.801923576807, 5572.693501857437, 5775.5668324875805, 1463.7072585482947, 1567.1850637668576, 1603.3346161343145, 1615.1681883829838, 1615.3215649427268, 1108.141190577309, 1128.6501184632791, 1173.4543437301695, 1202.1284568687806, 1208.7824191645589, 4766.213574760126, 4801.5438533339275, 5148.56237219833, 5158.807506809591, 5335.786568537215, 5303.764703530843, 5428.941356435457, 5574.0, 5685.687287199244, 5961.287614135558, 4732.313814496879, 5056.721880296545, 5124.026434977342, 5354.277172581652, 5478.780848766005, 2804.8176868503483, 2960.9703902961123, 2976.6753794561646, 3040.950871949174, 3129.9687003130134, 5424.945750542523, 6056.576704474445, 6074.78504299142, 6241.563090583615, 6304.747810087545, 5886.823395298148, 5890.734916928748, 6004.579679422486, 6073.878522429527, 6169.180091872909, 5470.781168753205, 5732.942670573241, 5760.703841635683, 5867.647941123546, 6206.410391012847, 5128.692278463304, 5502.532284755841, 5737.8565535861935, 5996.70016499182, 6015.969920150371, 4586.403767510203, 4860.684055536379, 5327.946720532745, 5828.883422331445, 5996.910046349221, 5461.836144915659, 5521.5306698931045, 6038.547108966933, 6203.751849925952, 6263.906041409291, 3744.681702055356, 4362.956370436382, 4379.37237657403, 4703.270992996091, 4703.623710103252, 4821.70968789822, 5255.132903071103, 5360.41035486104, 5394.922954662405, 5951.940480595139, 4986.975065124651, 5701.344345400332, 5780.479756821906, 5799.76800927975, 5966.880662386641, 5654.745536450787, 5807.448292412214, 5833.387494313206, 5836.824895253149, 5882.499987500939, 5250.95676329232, 5427.375851777097, 5480.794225270434, 5485.4788795064405, 5614.29821272341, 5069.847904562869, 5809.912851307318, 5834.94165058344, 5836.941630583639, 5861.834609311095, 5217.165253559412, 5321.414644389191, 5329.573634109184, 5675.849903303706, 5691.0], "value": 4339.756625638101, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [645.4481418387276, 657.8585604095122, 722.4762047515552, 734.6951015328639, 766.9156392796791, 13678.726425471497, 15456.690866182684, 16329.755053674204, 16353.672926541461, 16363.181840907951, 12945.935270323658, 14761.409543618242, 15108.169050702234, 15131.84868151321, 15474.0, 910.39003867409, 937.3625934364629, 938.015084161631, 938.6808485115059, 939.2251392601097, 889.6441423430647, 922.7139586728135, 940.47333493244, 941.9811603767921, 944.6363150187193, 889.3729920406131, 940.4404379394249, 992.7270000749816, 996.4967691315875, 996.9900300997006, 972.397113789448, 982.7297493189359, 989.515137582583, 989.9208063354914, 995.7610173558367, 725.314577724051, 733.4645708632675, 733.5928559649392, 734.5812886654609, 734.6510407556368, 12795.0, 13024.153430027112, 15625.687486250325, 16456.917715411466, 16471.752923706033, 13307.600771976857, 13357.0, 13401.597952061455, 13419.597412077555, 13585.116967397087, 9450.810983780355, 10689.091427228695, 11052.778944421147, 11072.335659860353, 11202.551897924073, 3298.4062868683627, 3332.500124981272, 3364.764466487347, 3387.9830600847085, 3440.4839274108836, 83014.99999999939, 120292.7970720299, 123319.0, 124248.0, 124624.37687811593, 7845.5684937329, 9450.480223587769, 9487.14615684578, 9534.18959388446, 10221.0, 19135.330263440712, 22678.886605566866, 23097.307080787603, 23245.837708114865, 23304.417389565395, 6085.543584231112, 7111.928880711127, 7177.138743350832, 7201.459890508233, 7397.926020739831, 3744.6255374462335, 3863.16941857502, 3882.107115363482, 3947.486826712509, 3976.781277029791, 3419.247765491588, 3514.578250609943, 3720.4233343831747, 3879.18537107205, 3917.7845218512684, 641.4771960851891, 642.56947844944, 646.3439608797074, 657.4510283912987, 658.9308122647149, 589.9469047785719, 621.4965877639127, 629.9244090709144, 633.7369991453587, 647.5143642268299, 9171.0, 9641.566129524188, 9749.902500975042, 10034.548445319979, 10069.446180460145, 603.5443240353505, 624.54720327762, 627.626562195493, 641.6759536434164, 653.7973228299243, 4560.021430910122, 4873.878153046202, 4877.3903262092235, 4890.902181956341, 4901.09329773993, 107290.39064413884, 108472.0, 109096.0, 109934.45032774785, 111495.0, 63840.0, 68658.65670671615, 74607.0, 95194.0, 97057.54413683659, 73982.0, 75017.62491187618, 77597.0, 78253.2174678246, 78981.81527277095, 9124.498152601405, 10098.94950525272, 10192.0, 10345.327553708985, 10381.636642717469, 51340.743296284774, 51611.741941291555, 52050.73974630103, 52219.38903054756, 52851.471485286194, 64854.67572662106, 66874.66562667351, 67466.32533674796, 68019.31980680328, 68418.94743157714, 11370.829437558608, 11474.196806223485, 11506.252093613888, 11726.41367931617, 12377.876221238033, 13219.867801322249, 13220.471181152647, 13439.46242150303, 13552.59342219735, 13908.0, 12280.692982675504, 12418.316992565495, 12486.12597118193, 12847.357632118543, 12945.741085178055, 12310.815337769762, 12317.3246540286, 12461.813072804096, 12497.812532811833, 12614.558490453157, 9586.760330991781, 11975.161738678557, 12214.145009849226, 12243.265404075784, 12499.56251531192, 10218.489075546044, 10428.165746740395, 10750.193735469715, 10844.728881778019, 11127.443627818415, 8579.356548259031, 10453.738656533647, 10460.790784184426, 10543.630972915911, 10630.362178269328], "value": 7343.293900541549, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1587.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 120.0, 119.0, 120.0, 119.0, 134.0, 177.0, 124.0, 121.0, 119.0], "value": 126.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 113.0, 111.0, 145.0, 112.0, 114.0, 120.0, 143.0, 119.0, 145.0], "value": 125.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [201.0, 196.0, 197.0, 198.0, 194.0, 197.0, 193.0, 196.0, 200.0, 194.0], "value": 196.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 58.0, 60.0, 91.0, 62.0, 63.0, 78.0, 85.0, 98.0, 83.0], "value": 76.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 73.0, 85.0, 73.0, 71.0, 70.0, 82.0, 70.0, 70.0, 84.0], "value": 76.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 136.0, 88.0, 91.0, 104.0, 92.0, 136.0, 101.0, 94.0, 89.0], "value": 106.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 120.0, 113.0, 93.0, 131.0, 115.0, 90.0, 146.0, 114.0, 108.0], "value": 114.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 106.0, 132.0, 148.0, 143.0, 91.0, 145.0, 143.0, 93.0, 87.0], "value": 122.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 70.0, 69.0, 71.0, 71.0, 69.0, 70.0, 69.0, 69.0], "value": 69.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 63.0, 57.0, 68.0, 79.0, 83.0, 82.0, 56.0, 58.0, 60.0], "value": 66.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 88.0, 86.0, 87.0, 85.0, 89.0, 110.0, 100.0, 104.0, 102.0], "value": 93.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 149.0, 140.0, 142.0, 157.0, 142.0, 138.0, 142.0, 210.0, 181.0], "value": 154.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [209.0, 184.0, 167.0, 168.0, 178.0, 221.0, 225.0, 175.0, 167.0, 171.0], "value": 186.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 84.0, 84.0, 80.0, 67.0, 67.0, 67.0, 66.0, 67.0, 68.0], "value": 72.7, "unit": "score"}]}]} 09:14:02 INFO - Return code: 0 09:14:02 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:14:02 ERROR - # TBPL SUCCESS # 09:14:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:14:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:14:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:14:02 INFO - 2017-03-15 09:14:02.405 screenresolution[1653:10793] starting screenresolution argv=screenresolution get 09:14:02 INFO - 2017-03-15 09:14:02.408 screenresolution[1653:10793] Display 0: 1600x1200x32@60 09:14:02 INFO - 2017-03-15 09:14:02.413 screenresolution[1654:10796] starting screenresolution argv=screenresolution list 09:14:02 INFO - Available Modes on Display 0 09:14:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:14:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:14:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:14:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:14:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:14:02 INFO - Intel Iris: 09:14:02 INFO - Chipset Model: Intel Iris 09:14:02 INFO - Type: GPU 09:14:02 INFO - Bus: Built-In 09:14:02 INFO - VRAM (Dynamic, Max): 1536 MB 09:14:02 INFO - Vendor: Intel (0x8086) 09:14:02 INFO - Device ID: 0x0a2e 09:14:02 INFO - Revision ID: 0x0009 09:14:02 INFO - Displays: 09:14:02 INFO - Display: 09:14:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:14:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:14:02 INFO - Display Serial Number: 1600x1200 60 09:14:02 INFO - Main Display: Yes 09:14:02 INFO - Mirror: Off 09:14:02 INFO - Online: Yes 09:14:02 INFO - Rotation: Supported 09:14:02 INFO - Return code: 0 09:14:02 INFO - Running post-action listener: _resource_record_post_action 09:14:02 INFO - [mozharness: 2017-03-15 16:14:02.655769Z] Finished run-tests step (success) 09:14:02 INFO - Running post-run listener: _resource_record_post_run 09:14:02 INFO - Total resource usage - Wall time: 1200s; CPU: 55.0%; Read bytes: 40618496; Write bytes: 515025920; Read time: 403; Write time: 1699 09:14:02 INFO - TinderboxPrint: CPU usage
54.5% 09:14:02 INFO - TinderboxPrint: I/O read bytes / time
40,618,496 / 403 09:14:02 INFO - TinderboxPrint: I/O write bytes / time
515,025,920 / 1,699 09:14:02 INFO - TinderboxPrint: CPU idle
2,182.4 (45.5%) 09:14:02 INFO - TinderboxPrint: CPU system
966.3 (20.1%) 09:14:02 INFO - TinderboxPrint: CPU user
1,652.9 (34.4%) 09:14:02 INFO - TinderboxPrint: Swap in / out
248,532,992 / 0 09:14:02 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 204241408; Write bytes: 203388928; Read time: 15470; Write time: 432 09:14:02 INFO - run-tests - Wall time: 1180s; CPU: 55.0%; Read bytes: 38525440; Write bytes: 300880896; Read time: 379; Write time: 1233 09:14:03 INFO - Running post-run listener: _upload_blobber_files 09:14:03 INFO - Blob upload gear active. 09:14:03 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:14:03 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 09:14:03 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'] 09:14:03 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 09:14:03 INFO - (blobuploader) - INFO - Open directory for files ... 09:14:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:14:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:14:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:14:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:14:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:14:04 INFO - (blobuploader) - INFO - Done attempting. 09:14:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:14:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:14:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:14:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:14:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:14:05 INFO - (blobuploader) - INFO - Done attempting. 09:14:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:14:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:14:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:14:06 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:14:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:14:06 INFO - (blobuploader) - INFO - Done attempting. 09:14:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:14:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:14:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:14:08 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:14:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:14:08 INFO - (blobuploader) - INFO - Done attempting. 09:14:08 INFO - (blobuploader) - INFO - Iteration through files over. 09:14:08 INFO - Return code: 0 09:14:08 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:14:08 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:14:08 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/47aee63b1ba28beb0778aa00db16408abf9a837e135f4f40c9ccb4e33bbe7cf64157164bd6dd33bbb23fab3b4dff0eb5f44a6b6b8aeb62a4afd9f942b6110247", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a70e03d47a7489b5753be18d26cc40966ccbe2049ce4eda56b3eeab87a04265ff283344381e0c805abd469d795d728724e7d6d6c904e42c202a66396ebbe116d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3df30f2d0b45e2361bc8754b8782471036db2f6414f2ae9a038e0881145f4980d683af8f096314ba20ac6962525b5fc982f4a1c3dcb0cdbf0c3ca1e78e568591", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/56b166f9a9b6752b7845cd9a4ad44352aedcb8148e1971930ba41a09507c81c04c1b508675328dafc4a083488caecf781e185a6bb70005aa276e04c3cfb461db"} 09:14:08 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:14:08 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:14:08 INFO - Contents: 09:14:08 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/47aee63b1ba28beb0778aa00db16408abf9a837e135f4f40c9ccb4e33bbe7cf64157164bd6dd33bbb23fab3b4dff0eb5f44a6b6b8aeb62a4afd9f942b6110247", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a70e03d47a7489b5753be18d26cc40966ccbe2049ce4eda56b3eeab87a04265ff283344381e0c805abd469d795d728724e7d6d6c904e42c202a66396ebbe116d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3df30f2d0b45e2361bc8754b8782471036db2f6414f2ae9a038e0881145f4980d683af8f096314ba20ac6962525b5fc982f4a1c3dcb0cdbf0c3ca1e78e568591", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/56b166f9a9b6752b7845cd9a4ad44352aedcb8148e1971930ba41a09507c81c04c1b508675328dafc4a083488caecf781e185a6bb70005aa276e04c3cfb461db"} 09:14:08 INFO - Running post-run listener: copy_logs_to_upload_dir 09:14:08 INFO - Copying logs to upload dir... 09:14:08 INFO - mkdir: /builds/slave/test/build/upload/logs 09:14:08 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1283.917239 ========= master_lag: 2.07 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 25 secs) (at 2017-03-15 09:14:10.803814) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-15 09:14:10.807213) ========= 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.8Bi9t31hGm/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.plO1YjuOrl/Listeners TMPDIR=/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/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/47aee63b1ba28beb0778aa00db16408abf9a837e135f4f40c9ccb4e33bbe7cf64157164bd6dd33bbb23fab3b4dff0eb5f44a6b6b8aeb62a4afd9f942b6110247", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a70e03d47a7489b5753be18d26cc40966ccbe2049ce4eda56b3eeab87a04265ff283344381e0c805abd469d795d728724e7d6d6c904e42c202a66396ebbe116d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3df30f2d0b45e2361bc8754b8782471036db2f6414f2ae9a038e0881145f4980d683af8f096314ba20ac6962525b5fc982f4a1c3dcb0cdbf0c3ca1e78e568591", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/56b166f9a9b6752b7845cd9a4ad44352aedcb8148e1971930ba41a09507c81c04c1b508675328dafc4a083488caecf781e185a6bb70005aa276e04c3cfb461db"} build_url:https://queue.taskcluster.net/v1/task/ZHCR6iIaRiioMPIZAq9B5Q/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011128 build_url: 'https://queue.taskcluster.net/v1/task/ZHCR6iIaRiioMPIZAq9B5Q/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/47aee63b1ba28beb0778aa00db16408abf9a837e135f4f40c9ccb4e33bbe7cf64157164bd6dd33bbb23fab3b4dff0eb5f44a6b6b8aeb62a4afd9f942b6110247", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a70e03d47a7489b5753be18d26cc40966ccbe2049ce4eda56b3eeab87a04265ff283344381e0c805abd469d795d728724e7d6d6c904e42c202a66396ebbe116d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3df30f2d0b45e2361bc8754b8782471036db2f6414f2ae9a038e0881145f4980d683af8f096314ba20ac6962525b5fc982f4a1c3dcb0cdbf0c3ca1e78e568591", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/56b166f9a9b6752b7845cd9a4ad44352aedcb8148e1971930ba41a09507c81c04c1b508675328dafc4a083488caecf781e185a6bb70005aa276e04c3cfb461db"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-15 09:14:10.844359) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-15 09:14:10.844670) ========= 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.8Bi9t31hGm/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.plO1YjuOrl/Listeners TMPDIR=/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/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.005214 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-15 09:14:10.893692) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-15 09:14:10.894079) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-15 09:14:10.894393) ========= ========= Total master_lag: 2.22 =========