builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0189 starttime: 1488463776.77 results: success (0) buildid: 20170302043024 builduid: c56bd3db52834d4497e84627eb831f18 revision: 4cd258347baab9f8dbe1ffbdf0238cfe3686509f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-02 06:09:36.774883) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-02 06:09:36.775432) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-02 06:09:36.775708) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-02 06:09:36.854798) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-02 06:09:36.855219) ========= 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.v6PR58sQGc/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.Bx0qRRe4On/Listeners TMPDIR=/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/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-02 06:09:36-- 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% 9.60M=0.001s 2017-03-02 06:09:37 (9.60 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.167062 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-02 06:09:37.046551) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-02 06:09:37.046901) ========= 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.v6PR58sQGc/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.Bx0qRRe4On/Listeners TMPDIR=/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/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.020870 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-02 06:09:37.096094) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-02 06:09:37.096472) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 4cd258347baab9f8dbe1ffbdf0238cfe3686509f --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 4cd258347baab9f8dbe1ffbdf0238cfe3686509f --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.v6PR58sQGc/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.Bx0qRRe4On/Listeners TMPDIR=/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/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-02 06:09:37,181 truncating revision to first 12 chars 2017-03-02 06:09:37,181 Setting DEBUG logging. 2017-03-02 06:09:37,181 attempt 1/10 2017-03-02 06:09:37,182 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/4cd258347baa?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-02 06:09:38,787 unpacking tar archive at: mozilla-aurora-4cd258347baa/testing/mozharness/ program finished with exit code 0 elapsedTime=1.859202 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-02 06:09:38.980618) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-02 06:09:38.980932) ========= script_repo_revision: 4cd258347baab9f8dbe1ffbdf0238cfe3686509f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-02 06:09:38.981343) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-02 06:09:38.981604) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-02 06:09:39.003161) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2017-03-02 06:09:39.003522) ========= /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.v6PR58sQGc/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.Bx0qRRe4On/Listeners TMPDIR=/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/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 06:09:39 INFO - MultiFileLogger online at 20170302 06:09:39 in /builds/slave/test 06:09:39 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 06:09:39 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:09:39 INFO - {'append_to_log': False, 06:09:39 INFO - 'base_work_dir': '/builds/slave/test', 06:09:39 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 06:09:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:09:39 INFO - 'branch': 'Mozilla-Aurora', 06:09:39 INFO - 'buildbot_json_path': 'buildprops.json', 06:09:39 INFO - 'config_files': ('talos/mac_config.py',), 06:09:39 INFO - 'default_actions': ('clobber', 06:09:39 INFO - 'read-buildbot-config', 06:09:39 INFO - 'download-and-extract', 06:09:39 INFO - 'populate-webroot', 06:09:39 INFO - 'create-virtualenv', 06:09:39 INFO - 'install', 06:09:39 INFO - 'run-tests'), 06:09:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:09:39 INFO - 'download_minidump_stackwalk': True, 06:09:39 INFO - 'download_symbols': 'ondemand', 06:09:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:09:39 INFO - 'tooltool.py': '/tools/tooltool.py', 06:09:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:09:39 INFO - '/tools/misc-python/virtualenv.py')}, 06:09:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:09:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:09:39 INFO - 'installer_path': 'installer.exe', 06:09:39 INFO - 'log_level': 'info', 06:09:39 INFO - 'log_name': 'talos', 06:09:39 INFO - 'log_to_console': True, 06:09:39 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 06:09:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 06:09:39 INFO - 'opt_config_files': (), 06:09:39 INFO - 'pip_index': False, 06:09:39 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:09:39 INFO - 'cmd': ('bash', 06:09:39 INFO - '-c', 06:09:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:09:39 INFO - 'enabled': True, 06:09:39 INFO - 'halt_on_failure': False, 06:09:39 INFO - 'name': 'check_screen_resolution'},), 06:09:39 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:09:39 INFO - 'cmd': ('bash', 06:09:39 INFO - '-c', 06:09:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:09:39 INFO - 'enabled': True, 06:09:39 INFO - 'halt_on_failure': False, 06:09:39 INFO - 'name': 'check_screen_resolution'},), 06:09:39 INFO - 'run_cmd_checks_enabled': True, 06:09:39 INFO - 'sps_profile': False, 06:09:39 INFO - 'sps_profile_interval': 0, 06:09:39 INFO - 'suite': 'dromaeojs-e10s', 06:09:39 INFO - 'system_bits': '32', 06:09:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:09:39 INFO - 'title': 't-yosemite-r7-0189', 06:09:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:09:39 INFO - 'use_talos_json': True, 06:09:39 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:09:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:09:39 INFO - 'work_dir': 'build'} 06:09:39 INFO - [mozharness: 2017-03-02 14:09:39.210313Z] Running clobber step. 06:09:39 INFO - Running pre-action listener: _resource_record_pre_action 06:09:39 INFO - Running main action method: clobber 06:09:39 INFO - rmtree: /builds/slave/test/build 06:09:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:09:39 INFO - Running post-action listener: _resource_record_post_action 06:09:39 INFO - [mozharness: 2017-03-02 14:09:39.582909Z] Finished clobber step (success) 06:09:39 INFO - [mozharness: 2017-03-02 14:09:39.583069Z] Running read-buildbot-config step. 06:09:39 INFO - Running pre-action listener: _resource_record_pre_action 06:09:39 INFO - Running main action method: read_buildbot_config 06:09:39 INFO - Using buildbot properties: 06:09:39 INFO - { 06:09:39 INFO - "project": "", 06:09:39 INFO - "product": "firefox", 06:09:39 INFO - "script_repo_revision": "production", 06:09:39 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 06:09:39 INFO - "repository": "", 06:09:39 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 06:09:39 INFO - "buildid": "20170302043024", 06:09:39 INFO - "pgo_build": "False", 06:09:39 INFO - "basedir": "/builds/slave/test", 06:09:39 INFO - "buildnumber": 56, 06:09:39 INFO - "slavename": "t-yosemite-r7-0189", 06:09:39 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 06:09:39 INFO - "platform": "yosemite_r7", 06:09:39 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 06:09:39 INFO - "branch": "mozilla-aurora", 06:09:39 INFO - "repo_path": "releases/mozilla-aurora", 06:09:39 INFO - "revision": "4cd258347baab9f8dbe1ffbdf0238cfe3686509f", 06:09:39 INFO - "stage_platform": "macosx64", 06:09:39 INFO - "builduid": "c56bd3db52834d4497e84627eb831f18", 06:09:39 INFO - "slavebuilddir": "test" 06:09:39 INFO - } 06:09:39 INFO - Found installer url https://queue.taskcluster.net/v1/task/DUql8zwqT32G_WHM2Y8DMQ/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg. 06:09:39 INFO - Running post-action listener: _resource_record_post_action 06:09:39 INFO - [mozharness: 2017-03-02 14:09:39.586477Z] Finished read-buildbot-config step (success) 06:09:39 INFO - [mozharness: 2017-03-02 14:09:39.586597Z] Running download-and-extract step. 06:09:39 INFO - Running pre-action listener: _resource_record_pre_action 06:09:39 INFO - Running main action method: download_and_extract 06:09:39 INFO - mkdir: /builds/slave/test/build/tests 06:09:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:09:39 INFO - trying https://queue.taskcluster.net/v1/task/DUql8zwqT32G_WHM2Y8DMQ/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json 06:09:39 INFO - Downloading https://queue.taskcluster.net/v1/task/DUql8zwqT32G_WHM2Y8DMQ/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 06:09:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DUql8zwqT32G_WHM2Y8DMQ/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json'}, attempt #1 06:09:40 INFO - Downloaded 1199 bytes. 06:09:40 INFO - Reading from file /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 06:09:40 INFO - Using the following test package requirements: 06:09:40 INFO - {u'common': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 06:09:40 INFO - u'cppunittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 06:09:40 INFO - u'firefox-53.0a2.en-US.mac.cppunittest.tests.zip'], 06:09:40 INFO - u'gtest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 06:09:40 INFO - u'firefox-53.0a2.en-US.mac.gtest.tests.zip'], 06:09:40 INFO - u'jittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 06:09:40 INFO - u'jsshell-mac.zip'], 06:09:40 INFO - u'mochitest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 06:09:40 INFO - u'firefox-53.0a2.en-US.mac.mochitest.tests.zip'], 06:09:40 INFO - u'mozbase': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 06:09:40 INFO - u'reftest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 06:09:40 INFO - u'firefox-53.0a2.en-US.mac.reftest.tests.zip'], 06:09:40 INFO - u'talos': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 06:09:40 INFO - u'firefox-53.0a2.en-US.mac.talos.tests.zip'], 06:09:40 INFO - u'web-platform': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 06:09:40 INFO - u'firefox-53.0a2.en-US.mac.web-platform.tests.zip'], 06:09:40 INFO - u'xpcshell': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 06:09:40 INFO - u'firefox-53.0a2.en-US.mac.xpcshell.tests.zip']} 06:09:40 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip'] for test suite category: common 06:09:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DUql8zwqT32G_WHM2Y8DMQ/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 06:09:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DUql8zwqT32G_WHM2Y8DMQ/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 06:09:40 INFO - Fetch https://queue.taskcluster.net/v1/task/DUql8zwqT32G_WHM2Y8DMQ/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 06:09:41 INFO - Expected file size: 16357800 06:09:41 INFO - Obtained file size: 16357800 06:09:47 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip', u'firefox-53.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 06:09:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DUql8zwqT32G_WHM2Y8DMQ/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 06:09:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DUql8zwqT32G_WHM2Y8DMQ/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 06:09:47 INFO - Fetch https://queue.taskcluster.net/v1/task/DUql8zwqT32G_WHM2Y8DMQ/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 06:09:48 INFO - Expected file size: 16357800 06:09:48 INFO - Obtained file size: 16357800 06:09:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DUql8zwqT32G_WHM2Y8DMQ/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip 06:09:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DUql8zwqT32G_WHM2Y8DMQ/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip'}, attempt #1 06:09:55 INFO - Fetch https://queue.taskcluster.net/v1/task/DUql8zwqT32G_WHM2Y8DMQ/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip into memory 06:09:56 INFO - Expected file size: 14403472 06:09:56 INFO - Obtained file size: 14403472 06:09:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:09:56 INFO - trying https://queue.taskcluster.net/v1/task/DUql8zwqT32G_WHM2Y8DMQ/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 06:09:56 INFO - Downloading https://queue.taskcluster.net/v1/task/DUql8zwqT32G_WHM2Y8DMQ/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg 06:09:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DUql8zwqT32G_WHM2Y8DMQ/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg'}, attempt #1 06:09:58 INFO - Downloaded 73907814 bytes. 06:09:58 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DUql8zwqT32G_WHM2Y8DMQ/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 06:09:58 INFO - mkdir: /builds/slave/test/properties 06:09:58 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:09:58 INFO - Writing to file /builds/slave/test/properties/build_url 06:09:58 INFO - Contents: 06:09:58 INFO - build_url:https://queue.taskcluster.net/v1/task/DUql8zwqT32G_WHM2Y8DMQ/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 06:09:59 INFO - Running post-action listener: _resource_record_post_action 06:09:59 INFO - Running post-action listener: set_extra_try_arguments 06:09:59 INFO - [mozharness: 2017-03-02 14:09:59.303098Z] Finished download-and-extract step (success) 06:09:59 INFO - [mozharness: 2017-03-02 14:09:59.303223Z] Running populate-webroot step. 06:09:59 INFO - Running pre-action listener: _resource_record_pre_action 06:09:59 INFO - Running main action method: populate_webroot 06:09:59 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:09:59 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:09:59 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:09:59 INFO - '--tppagecycles', 06:09:59 INFO - '7'], 06:09:59 INFO - 'tests': ['tsvgm']}}, 06:09:59 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:09:59 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:09:59 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:09:59 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:09:59 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:09:59 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:09:59 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 06:09:59 INFO - 'talos_options': ['--disable-e10s'], 06:09:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:09:59 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 06:09:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:09:59 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 06:09:59 INFO - 'talos_options': ['--disable-e10s'], 06:09:59 INFO - 'tests': ['damp', 'tps']}, 06:09:59 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 06:09:59 INFO - 'tests': ['damp', 'tps']}, 06:09:59 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:09:59 INFO - 'tests': ['dromaeo_dom']}, 06:09:59 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:09:59 INFO - 'g4': {'talos_options': ['--disable-e10s'], 06:09:59 INFO - 'tests': ['basic_compositor_video']}, 06:09:59 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 06:09:59 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:09:59 INFO - 'tests': ['a11yr', 06:09:59 INFO - 'ts_paint', 06:09:59 INFO - 'tpaint', 06:09:59 INFO - 'sessionrestore', 06:09:59 INFO - 'sessionrestore_no_auto_restore', 06:09:59 INFO - 'tabpaint']}, 06:09:59 INFO - 'other-e10s': {'tests': ['a11yr', 06:09:59 INFO - 'ts_paint', 06:09:59 INFO - 'tpaint', 06:09:59 INFO - 'sessionrestore', 06:09:59 INFO - 'sessionrestore_no_auto_restore', 06:09:59 INFO - 'tabpaint']}, 06:09:59 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:09:59 INFO - 'ts_paint', 06:09:59 INFO - 'tpaint', 06:09:59 INFO - 'sessionrestore', 06:09:59 INFO - 'sessionrestore_no_auto_restore', 06:09:59 INFO - 'tabpaint']}, 06:09:59 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:09:59 INFO - 'ts_paint', 06:09:59 INFO - 'tpaint', 06:09:59 INFO - 'sessionrestore', 06:09:59 INFO - 'sessionrestore_no_auto_restore', 06:09:59 INFO - 'tabpaint']}, 06:09:59 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:09:59 INFO - 'tests': ['a11yr', 06:09:59 INFO - 'ts_paint', 06:09:59 INFO - 'tpaint', 06:09:59 INFO - 'sessionrestore', 06:09:59 INFO - 'sessionrestore_no_auto_restore', 06:09:59 INFO - 'tabpaint']}, 06:09:59 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:09:59 INFO - 'tests': ['a11yr', 06:09:59 INFO - 'ts_paint', 06:09:59 INFO - 'tpaint', 06:09:59 INFO - 'sessionrestore', 06:09:59 INFO - 'sessionrestore_no_auto_restore', 06:09:59 INFO - 'tabpaint']}, 06:09:59 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:09:59 INFO - 'tests': ['tsvgx', 06:09:59 INFO - 'tsvgr_opacity', 06:09:59 INFO - 'tart', 06:09:59 INFO - 'tscrollx', 06:09:59 INFO - 'cart', 06:09:59 INFO - 'tsvg_static']}, 06:09:59 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:09:59 INFO - 'tsvgr_opacity', 06:09:59 INFO - 'tart', 06:09:59 INFO - 'tscrollx', 06:09:59 INFO - 'cart', 06:09:59 INFO - 'tsvg_static']}, 06:09:59 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 06:09:59 INFO - 'talos_options': ['--disable-e10s'], 06:09:59 INFO - 'tests': ['tp5o']}, 06:09:59 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 06:09:59 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 06:09:59 INFO - 'talos_options': ['--disable-e10s', 06:09:59 INFO - '--xperf_path', 06:09:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:09:59 INFO - 'tests': ['tp5n']}, 06:09:59 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 06:09:59 INFO - 'talos_options': ['--xperf_path', 06:09:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:09:59 INFO - 'tests': ['tp5n']}}, 06:09:59 INFO - 'talos.zip': {'path': '', 06:09:59 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:09:59 INFO - Running post-action listener: _resource_record_post_action 06:09:59 INFO - [mozharness: 2017-03-02 14:09:59.321797Z] Finished populate-webroot step (success) 06:09:59 INFO - [mozharness: 2017-03-02 14:09:59.321911Z] Running create-virtualenv step. 06:09:59 INFO - Running pre-action listener: _resource_record_pre_action 06:09:59 INFO - Running main action method: create_virtualenv 06:09:59 INFO - Creating virtualenv /builds/slave/test/build/venv 06:09:59 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 06:09:59 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:09:59 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:09:59 INFO - Using real prefix '/tools/python27' 06:09:59 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:10:00 INFO - Installing distribute.............................................................................................................................................................................................done. 06:10:03 INFO - Installing pip.................done. 06:10:03 INFO - Return code: 0 06:10:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 06:10:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 06:10:03 INFO - Reading from file tmpfile_stdout 06:10:03 INFO - Output received: 06:10:03 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) 06:10:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:10:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:10:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:10:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:10:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:10:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:10:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:10:03 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 0x1060ceb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060d1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb72b474cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105eacd30>, '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 0x105dd3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105ea1be8>, '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.v6PR58sQGc/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.Bx0qRRe4On/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/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 06:10:03 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 06:10:03 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 06:10:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.v6PR58sQGc/Render', 06:10:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:10:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:10:03 INFO - 'HOME': '/Users/cltbld', 06:10:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:10:03 INFO - 'LOGNAME': 'cltbld', 06:10:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:10:03 INFO - 'MOZ_NO_REMOTE': '1', 06:10:03 INFO - 'NO_EM_RESTART': '1', 06:10:03 INFO - 'PAGER': '/bin/cat', 06:10:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:10:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:10:03 INFO - 'PWD': '/builds/slave/test', 06:10:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:10:03 INFO - 'SHELL': '/bin/bash', 06:10:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Bx0qRRe4On/Listeners', 06:10:03 INFO - 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/', 06:10:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:10:03 INFO - 'USER': 'cltbld', 06:10:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:10:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:10:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:10:03 INFO - 'XPC_FLAGS': '0x0', 06:10:03 INFO - 'XPC_SERVICE_NAME': '0', 06:10:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:10:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:10:03 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 06:10:03 INFO - Cleaning up... 06:10:03 INFO - Return code: 0 06:10:03 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:10:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:10:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:10:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:10:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:10:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:10:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:10:03 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 0x1060ceb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060d1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb72b474cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105eacd30>, '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 0x105dd3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105ea1be8>, '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.v6PR58sQGc/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.Bx0qRRe4On/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/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 06:10:03 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 06:10:03 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 06:10:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.v6PR58sQGc/Render', 06:10:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:10:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:10:03 INFO - 'HOME': '/Users/cltbld', 06:10:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:10:03 INFO - 'LOGNAME': 'cltbld', 06:10:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:10:03 INFO - 'MOZ_NO_REMOTE': '1', 06:10:03 INFO - 'NO_EM_RESTART': '1', 06:10:03 INFO - 'PAGER': '/bin/cat', 06:10:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:10:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:10:03 INFO - 'PWD': '/builds/slave/test', 06:10:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:10:03 INFO - 'SHELL': '/bin/bash', 06:10:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Bx0qRRe4On/Listeners', 06:10:03 INFO - 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/', 06:10:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:10:03 INFO - 'USER': 'cltbld', 06:10:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:10:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:10:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:10:03 INFO - 'XPC_FLAGS': '0x0', 06:10:03 INFO - 'XPC_SERVICE_NAME': '0', 06:10:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:10:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:10:03 INFO - Downloading/unpacking psutil>=3.1.1 06:10:03 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 06:10:03 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 06:10:03 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 06:10:03 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 06:10:03 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 06:10:03 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 06:10:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:10:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:10:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:10:07 INFO - Installing collected packages: psutil 06:10:07 INFO - Running setup.py install for psutil 06:10:07 INFO - building 'psutil._psutil_osx' extension 06:10:07 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 06:10:08 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 06:10:08 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 06:10:08 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 06:10:08 INFO - building 'psutil._psutil_posix' extension 06:10:08 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 06:10:08 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 06:10:08 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 06:10:08 INFO - ^ 06:10:08 INFO - 1 warning generated. 06:10:08 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 06:10:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:10:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:10:08 INFO - Successfully installed psutil 06:10:08 INFO - Cleaning up... 06:10:08 INFO - Return code: 0 06:10:08 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:10:08 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')]} 06:10:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:10:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:10:08 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')]} 06:10:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:10:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:10:08 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 0x1060ceb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060d1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb72b474cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105eacd30>, '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 0x105dd3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105ea1be8>, '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.v6PR58sQGc/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.Bx0qRRe4On/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/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 06:10:08 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 06:10:08 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 06:10:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.v6PR58sQGc/Render', 06:10:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:10:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:10:08 INFO - 'HOME': '/Users/cltbld', 06:10:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:10:08 INFO - 'LOGNAME': 'cltbld', 06:10:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:10:08 INFO - 'MOZ_NO_REMOTE': '1', 06:10:08 INFO - 'NO_EM_RESTART': '1', 06:10:08 INFO - 'PAGER': '/bin/cat', 06:10:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:10:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:10:08 INFO - 'PWD': '/builds/slave/test', 06:10:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:10:08 INFO - 'SHELL': '/bin/bash', 06:10:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Bx0qRRe4On/Listeners', 06:10:08 INFO - 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/', 06:10:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:10:08 INFO - 'USER': 'cltbld', 06:10:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:10:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:10:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:10:08 INFO - 'XPC_FLAGS': '0x0', 06:10:08 INFO - 'XPC_SERVICE_NAME': '0', 06:10:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:10:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:10:08 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:10:08 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 06:10:08 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 06:10:08 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 06:10:08 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 06:10:08 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 06:10:08 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 06:10:11 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:10:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:10:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:10:11 INFO - Installing collected packages: mozsystemmonitor 06:10:11 INFO - Running setup.py install for mozsystemmonitor 06:10:11 INFO - Successfully installed mozsystemmonitor 06:10:11 INFO - Cleaning up... 06:10:11 INFO - Return code: 0 06:10:11 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 06:10:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:10:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:10:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:10:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:10:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:10:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:10:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1060ceb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060d1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb72b474cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105eacd30>, '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 0x105dd3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105ea1be8>, '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.v6PR58sQGc/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.Bx0qRRe4On/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/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 06:10:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 06:10:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 06:10:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.v6PR58sQGc/Render', 06:10:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:10:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:10:11 INFO - 'HOME': '/Users/cltbld', 06:10:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:10:11 INFO - 'LOGNAME': 'cltbld', 06:10:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:10:11 INFO - 'MOZ_NO_REMOTE': '1', 06:10:11 INFO - 'NO_EM_RESTART': '1', 06:10:11 INFO - 'PAGER': '/bin/cat', 06:10:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:10:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:10:11 INFO - 'PWD': '/builds/slave/test', 06:10:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:10:11 INFO - 'SHELL': '/bin/bash', 06:10:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Bx0qRRe4On/Listeners', 06:10:11 INFO - 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/', 06:10:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:10:11 INFO - 'USER': 'cltbld', 06:10:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:10:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:10:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:10:11 INFO - 'XPC_FLAGS': '0x0', 06:10:11 INFO - 'XPC_SERVICE_NAME': '0', 06:10:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:10:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:10:12 INFO - Downloading/unpacking jsonschema==2.5.1 06:10:12 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 06:10:12 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 06:10:12 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 06:10:12 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 06:10:12 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 06:10:12 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 06:10:14 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:10:14 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:10:14 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 06:10:14 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 06:10:14 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 06:10:14 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 06:10:14 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 06:10:14 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 06:10:15 INFO - Downloading functools32-3.2.3-2.tar.gz 06:10:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:10:15 INFO - warning: no files found matching '*.txt' 06:10:15 INFO - no previously-included directories found matching 'build' 06:10:15 INFO - no previously-included directories found matching 'dist' 06:10:15 INFO - no previously-included directories found matching '.git*' 06:10:15 INFO - Installing collected packages: jsonschema, functools32 06:10:15 INFO - Running setup.py install for functools32 06:10:15 INFO - warning: no files found matching '*.txt' 06:10:15 INFO - no previously-included directories found matching 'build' 06:10:15 INFO - no previously-included directories found matching 'dist' 06:10:15 INFO - no previously-included directories found matching '.git*' 06:10:15 INFO - Successfully installed jsonschema functools32 06:10:15 INFO - Cleaning up... 06:10:15 INFO - Return code: 0 06:10:15 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 06:10:15 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')]} 06:10:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:10:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:10:15 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')]} 06:10:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:10:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:10:15 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 0x1060ceb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060d1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb72b474cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105eacd30>, '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 0x105dd3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105ea1be8>, '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.v6PR58sQGc/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.Bx0qRRe4On/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/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 06:10:15 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 06:10:15 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 06:10:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.v6PR58sQGc/Render', 06:10:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:10:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:10:15 INFO - 'HOME': '/Users/cltbld', 06:10:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:10:15 INFO - 'LOGNAME': 'cltbld', 06:10:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:10:15 INFO - 'MOZ_NO_REMOTE': '1', 06:10:15 INFO - 'NO_EM_RESTART': '1', 06:10:15 INFO - 'PAGER': '/bin/cat', 06:10:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:10:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:10:15 INFO - 'PWD': '/builds/slave/test', 06:10:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:10:15 INFO - 'SHELL': '/bin/bash', 06:10:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Bx0qRRe4On/Listeners', 06:10:15 INFO - 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/', 06:10:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:10:15 INFO - 'USER': 'cltbld', 06:10:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:10:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:10:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:10:15 INFO - 'XPC_FLAGS': '0x0', 06:10:15 INFO - 'XPC_SERVICE_NAME': '0', 06:10:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:10:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:10:15 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 06:10:15 INFO - Cleaning up... 06:10:15 INFO - Return code: 0 06:10:15 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:10:15 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')]} 06:10:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:10:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:10:15 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')]} 06:10:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:10:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:10:15 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 0x1060ceb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060d1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb72b474cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105eacd30>, '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 0x105dd3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105ea1be8>, '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.v6PR58sQGc/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.Bx0qRRe4On/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/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 06:10:15 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 06:10:15 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 06:10:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.v6PR58sQGc/Render', 06:10:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:10:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:10:15 INFO - 'HOME': '/Users/cltbld', 06:10:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:10:15 INFO - 'LOGNAME': 'cltbld', 06:10:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:10:15 INFO - 'MOZ_NO_REMOTE': '1', 06:10:15 INFO - 'NO_EM_RESTART': '1', 06:10:15 INFO - 'PAGER': '/bin/cat', 06:10:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:10:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:10:15 INFO - 'PWD': '/builds/slave/test', 06:10:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:10:15 INFO - 'SHELL': '/bin/bash', 06:10:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Bx0qRRe4On/Listeners', 06:10:15 INFO - 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/', 06:10:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:10:15 INFO - 'USER': 'cltbld', 06:10:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:10:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:10:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:10:15 INFO - 'XPC_FLAGS': '0x0', 06:10:15 INFO - 'XPC_SERVICE_NAME': '0', 06:10:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:10:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:10:16 INFO - Downloading/unpacking blobuploader==1.2.4 06:10:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:10:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:10:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:10:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:10:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:10:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:10:18 INFO - Downloading blobuploader-1.2.4.tar.gz 06:10:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:10:18 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:10:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:10:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:10:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:10:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:10:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:10:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:10:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:10:19 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:10:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:10:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:10:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:10:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:10:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:10:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:10:19 INFO - Downloading docopt-0.6.1.tar.gz 06:10:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:10:20 INFO - Installing collected packages: blobuploader, requests, docopt 06:10:20 INFO - Running setup.py install for blobuploader 06:10:20 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:10:20 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:10:20 INFO - Running setup.py install for requests 06:10:20 INFO - Running setup.py install for docopt 06:10:20 INFO - Successfully installed blobuploader requests docopt 06:10:20 INFO - Cleaning up... 06:10:20 INFO - Return code: 0 06:10:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:10:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:10:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:10:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:10:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:10:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:10:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:10:20 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 0x1060ceb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060d1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb72b474cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105eacd30>, '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 0x105dd3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105ea1be8>, '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.v6PR58sQGc/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.Bx0qRRe4On/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/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 06:10:20 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 06:10:20 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 06:10:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.v6PR58sQGc/Render', 06:10:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:10:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:10:20 INFO - 'HOME': '/Users/cltbld', 06:10:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:10:20 INFO - 'LOGNAME': 'cltbld', 06:10:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:10:20 INFO - 'MOZ_NO_REMOTE': '1', 06:10:20 INFO - 'NO_EM_RESTART': '1', 06:10:20 INFO - 'PAGER': '/bin/cat', 06:10:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:10:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:10:20 INFO - 'PWD': '/builds/slave/test', 06:10:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:10:20 INFO - 'SHELL': '/bin/bash', 06:10:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Bx0qRRe4On/Listeners', 06:10:20 INFO - 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/', 06:10:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:10:20 INFO - 'USER': 'cltbld', 06:10:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:10:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:10:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:10:20 INFO - 'XPC_FLAGS': '0x0', 06:10:20 INFO - 'XPC_SERVICE_NAME': '0', 06:10:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:10:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:10:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:10:21 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-QQs1Nm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:10:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:10:21 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-SbkjfK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:10:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:10:21 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-mYgcqT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:10:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:10:21 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-EYcsPp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:10:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:10:21 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-erbjKh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:10:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:10:21 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-oAXXbH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:10:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:10:21 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-apA0PN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:10:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:10:21 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-vYYV_r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:10:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:10:22 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-uUWC3a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:10:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:10:22 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-dmj4ha-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:10:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:10:22 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-oP3QQU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:10:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:10:22 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-JWyUPG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:10:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:10:22 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-XKIljl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:10:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:10:22 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-01nnu2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:10:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:10:22 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-FOGKw1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:10:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:10:22 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-q1ivab-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:10:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:10:22 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-cnOA3l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:10:22 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:10:22 INFO - Running setup.py install for manifestparser 06:10:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:10:23 INFO - Running setup.py install for mozcrash 06:10:23 INFO - Running setup.py install for mozdebug 06:10:23 INFO - Running setup.py install for mozdevice 06:10:23 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:10:23 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:10:23 INFO - Running setup.py install for mozfile 06:10:23 INFO - Running setup.py install for mozhttpd 06:10:23 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:10:23 INFO - Running setup.py install for mozinfo 06:10:23 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:10:23 INFO - Running setup.py install for mozInstall 06:10:24 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:10:24 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:10:24 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:10:24 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:10:24 INFO - Running setup.py install for mozleak 06:10:24 INFO - Running setup.py install for mozlog 06:10:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:10:24 INFO - Running setup.py install for moznetwork 06:10:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:10:24 INFO - Running setup.py install for mozprocess 06:10:24 INFO - Running setup.py install for mozprofile 06:10:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:10:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:10:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:10:24 INFO - Running setup.py install for mozrunner 06:10:25 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:10:25 INFO - Running setup.py install for mozscreenshot 06:10:25 INFO - Running setup.py install for moztest 06:10:25 INFO - Running setup.py install for mozversion 06:10:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:10:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:10:25 INFO - Cleaning up... 06:10:25 INFO - Return code: 0 06:10:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:10:25 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')]} 06:10:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:10:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:10:25 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')]} 06:10:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:10:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:10:25 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 0x1060ceb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060d1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb72b474cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105eacd30>, '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 0x105dd3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105ea1be8>, '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.v6PR58sQGc/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.Bx0qRRe4On/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/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 06:10:25 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 06:10:25 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 06:10:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.v6PR58sQGc/Render', 06:10:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:10:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:10:25 INFO - 'HOME': '/Users/cltbld', 06:10:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:10:25 INFO - 'LOGNAME': 'cltbld', 06:10:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:10:25 INFO - 'MOZ_NO_REMOTE': '1', 06:10:25 INFO - 'NO_EM_RESTART': '1', 06:10:25 INFO - 'PAGER': '/bin/cat', 06:10:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:10:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:10:25 INFO - 'PWD': '/builds/slave/test', 06:10:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:10:25 INFO - 'SHELL': '/bin/bash', 06:10:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Bx0qRRe4On/Listeners', 06:10:25 INFO - 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/', 06:10:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:10:25 INFO - 'USER': 'cltbld', 06:10:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:10:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:10:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:10:25 INFO - 'XPC_FLAGS': '0x0', 06:10:25 INFO - 'XPC_SERVICE_NAME': '0', 06:10:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:10:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:10:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:10:25 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-MJ87Gd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:10:25 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)) 06:10:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:10:25 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-xzpZFG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:10:26 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)) 06:10:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:10:26 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-2KDDuT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:10:26 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)) 06:10:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:10:26 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-oA0kBm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:10:26 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)) 06:10:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:10:26 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-UTsmLU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:10:26 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)) 06:10:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:10:26 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-6fbgz0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:10:26 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)) 06:10:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:10:26 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-826paV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:10:26 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)) 06:10:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:10:26 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-ZDk1YL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:10:26 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)) 06:10:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:10:26 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-ZIkviR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:10:26 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)) 06:10:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:10:26 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-MrAI78-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:10:26 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)) 06:10:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:10:26 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-XieGaL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:10:26 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)) 06:10:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:10:26 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-Cc9Unk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:10:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 06:10:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:10:27 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-Xnudi1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:10:27 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)) 06:10:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:10:27 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-_aCwGA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:10:27 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)) 06:10:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:10:27 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-v6g4Rm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:10:27 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)) 06:10:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:10:27 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-O0xDD6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:10:27 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)) 06:10:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:10:27 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-u2kDqg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:10:27 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)) 06:10:27 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)) 06:10:27 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)) 06:10:27 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)) 06:10:27 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)) 06:10:27 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)) 06:10:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:10:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:10:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:10:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:10:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:10:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:10:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:10:30 INFO - Downloading blessings-1.6.tar.gz 06:10:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:10:30 INFO - Installing collected packages: blessings 06:10:30 INFO - Running setup.py install for blessings 06:10:30 INFO - Successfully installed blessings 06:10:30 INFO - Cleaning up... 06:10:30 INFO - Return code: 0 06:10:30 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:10:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:10:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:10:30 INFO - Reading from file tmpfile_stdout 06:10:30 INFO - Current package versions: 06:10:30 INFO - blessings == 1.6 06:10:30 INFO - blobuploader == 1.2.4 06:10:30 INFO - docopt == 0.6.1 06:10:30 INFO - functools32 == 3.2.3-2 06:10:30 INFO - jsonschema == 2.5.1 06:10:30 INFO - manifestparser == 1.1 06:10:30 INFO - mozInstall == 1.12 06:10:30 INFO - mozcrash == 1.0 06:10:30 INFO - mozdebug == 0.1 06:10:30 INFO - mozdevice == 0.49 06:10:30 INFO - mozfile == 1.2 06:10:30 INFO - mozhttpd == 0.7 06:10:30 INFO - mozinfo == 0.9 06:10:30 INFO - mozleak == 0.1 06:10:30 INFO - mozlog == 3.4 06:10:30 INFO - moznetwork == 0.27 06:10:30 INFO - mozprocess == 0.24 06:10:30 INFO - mozprofile == 0.28 06:10:30 INFO - mozrunner == 6.13 06:10:30 INFO - mozscreenshot == 0.1 06:10:30 INFO - mozsystemmonitor == 0.3 06:10:30 INFO - moztest == 0.8 06:10:30 INFO - mozversion == 1.4 06:10:30 INFO - psutil == 3.1.1 06:10:30 INFO - requests == 1.2.3 06:10:30 INFO - wsgiref == 0.1.2 06:10:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:10:30 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')]} 06:10:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:10:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:10:30 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')]} 06:10:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:10:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:10:30 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 0x1060ceb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060d1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb72b474cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105eacd30>, '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 0x105dd3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105ea1be8>, '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.v6PR58sQGc/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.Bx0qRRe4On/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/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 06:10:30 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 06:10:30 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 06:10:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.v6PR58sQGc/Render', 06:10:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:10:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:10:30 INFO - 'HOME': '/Users/cltbld', 06:10:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:10:30 INFO - 'LOGNAME': 'cltbld', 06:10:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:10:30 INFO - 'MOZ_NO_REMOTE': '1', 06:10:30 INFO - 'NO_EM_RESTART': '1', 06:10:30 INFO - 'PAGER': '/bin/cat', 06:10:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:10:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:10:30 INFO - 'PWD': '/builds/slave/test', 06:10:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:10:30 INFO - 'SHELL': '/bin/bash', 06:10:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Bx0qRRe4On/Listeners', 06:10:30 INFO - 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/', 06:10:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:10:30 INFO - 'USER': 'cltbld', 06:10:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:10:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:10:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:10:30 INFO - 'XPC_FLAGS': '0x0', 06:10:30 INFO - 'XPC_SERVICE_NAME': '0', 06:10:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:10:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:10:31 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)) 06:10:31 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)) 06:10:31 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)) 06:10:31 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)) 06:10:31 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)) 06:10:31 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)) 06:10:31 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)) 06:10:31 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)) 06:10:31 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)) 06:10:31 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)) 06:10:31 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)) 06:10:31 INFO - Cleaning up... 06:10:31 INFO - Return code: 0 06:10:31 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:10: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')]} 06:10:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:10:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:10: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')]} 06:10:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:10:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:10:31 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 0x1060ceb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060d1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb72b474cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105eacd30>, '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 0x105dd3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105ea1be8>, '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.v6PR58sQGc/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.Bx0qRRe4On/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/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 06:10:31 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 06:10:31 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 06:10:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.v6PR58sQGc/Render', 06:10:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:10:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:10:31 INFO - 'HOME': '/Users/cltbld', 06:10:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:10:31 INFO - 'LOGNAME': 'cltbld', 06:10:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:10:31 INFO - 'MOZ_NO_REMOTE': '1', 06:10:31 INFO - 'NO_EM_RESTART': '1', 06:10:31 INFO - 'PAGER': '/bin/cat', 06:10:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:10:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:10:31 INFO - 'PWD': '/builds/slave/test', 06:10:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:10:31 INFO - 'SHELL': '/bin/bash', 06:10:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Bx0qRRe4On/Listeners', 06:10:31 INFO - 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/', 06:10:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:10:31 INFO - 'USER': 'cltbld', 06:10:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:10:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:10:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:10:31 INFO - 'XPC_FLAGS': '0x0', 06:10:31 INFO - 'XPC_SERVICE_NAME': '0', 06:10:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:10:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:10:31 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:10:31 INFO - Cleaning up... 06:10:31 INFO - Return code: 0 06:10:31 INFO - Running post-action listener: _resource_record_post_action 06:10:31 INFO - Running post-action listener: _start_resource_monitoring 06:10:31 INFO - Starting resource monitoring. 06:10:31 INFO - [mozharness: 2017-03-02 14:10:31.437598Z] Finished create-virtualenv step (success) 06:10:31 INFO - [mozharness: 2017-03-02 14:10:31.438578Z] Running install step. 06:10:31 INFO - Running pre-action listener: _resource_record_pre_action 06:10:31 INFO - Running main action method: install 06:10:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:10:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:10:31 INFO - Reading from file tmpfile_stdout 06:10:31 INFO - Detecting whether we're running mozinstall >=1.0... 06:10:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:10:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:10:31 INFO - Reading from file tmpfile_stdout 06:10:31 INFO - Output received: 06:10:31 INFO - Usage: mozinstall [options] installer 06:10:31 INFO - Options: 06:10:31 INFO - -h, --help show this help message and exit 06:10:31 INFO - -d DEST, --destination=DEST 06:10:31 INFO - Directory to install application into. [default: 06:10:31 INFO - "/builds/slave/test"] 06:10:31 INFO - --app=APP Application being installed. [default: firefox] 06:10:31 INFO - mkdir: /builds/slave/test/build/application 06:10:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 06:10:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 06:10:51 INFO - Reading from file tmpfile_stdout 06:10:51 INFO - Output received: 06:10:51 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 06:10:51 INFO - Running post-action listener: _resource_record_post_action 06:10:51 INFO - [mozharness: 2017-03-02 14:10:51.426512Z] Finished install step (success) 06:10:51 INFO - [mozharness: 2017-03-02 14:10:51.426668Z] Running run-tests step. 06:10:51 INFO - Running pre-action listener: _resource_record_pre_action 06:10:51 INFO - Running main action method: run_tests 06:10:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:10:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:10:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:10:51 INFO - 2017-03-02 06:10:51.441 screenresolution[84787:718604] starting screenresolution argv=screenresolution get 06:10:51 INFO - 2017-03-02 06:10:51.444 screenresolution[84787:718604] Display 0: 1600x1200x32@60 06:10:51 INFO - 2017-03-02 06:10:51.449 screenresolution[84788:718606] starting screenresolution argv=screenresolution list 06:10:51 INFO - Available Modes on Display 0 06:10:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:10:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:10:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:10:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:10:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:10:51 INFO - Intel Iris: 06:10:51 INFO - Chipset Model: Intel Iris 06:10:51 INFO - Type: GPU 06:10:51 INFO - Bus: Built-In 06:10:51 INFO - VRAM (Dynamic, Max): 1536 MB 06:10:51 INFO - Vendor: Intel (0x8086) 06:10:51 INFO - Device ID: 0x0a2e 06:10:51 INFO - Revision ID: 0x0009 06:10:51 INFO - Displays: 06:10:51 INFO - Display: 06:10:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:10:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:10:51 INFO - Display Serial Number: 1600x1200 60 06:10:51 INFO - Main Display: Yes 06:10:51 INFO - Mirror: Off 06:10:51 INFO - Online: Yes 06:10:51 INFO - Rotation: Supported 06:10:51 INFO - Return code: 0 06:10:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:10:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:10:51 INFO - Python 2.7.3 06:10:51 INFO - Return code: 0 06:10:51 INFO - grabbing minidump binary from tooltool 06:10: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')]} 06:10:51 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 0x105eacd30>, '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 0x105dd3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105ea1be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:10:51 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 06:10:51 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 06:10:51 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 06:10:51 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:10:51 INFO - Return code: 0 06:10:51 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 06:10:51 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:10:51 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:10:51 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 06:10:51 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:10:51 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/DUql8zwqT32G_WHM2Y8DMQ/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0189', '--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 06:10:51 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/DUql8zwqT32G_WHM2Y8DMQ/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0189 --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 06:10:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.v6PR58sQGc/Render', 06:10:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:10:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:10:51 INFO - 'HOME': '/Users/cltbld', 06:10:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:10:51 INFO - 'LOGNAME': 'cltbld', 06:10:51 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:10:51 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 06:10:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:10:51 INFO - 'MOZ_NO_REMOTE': '1', 06:10:51 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:10:51 INFO - 'NO_EM_RESTART': '1', 06:10:51 INFO - 'PAGER': '/bin/cat', 06:10:51 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:10:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:10:51 INFO - 'PWD': '/builds/slave/test', 06:10:51 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:10:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:10:51 INFO - 'SHELL': '/bin/bash', 06:10:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Bx0qRRe4On/Listeners', 06:10:51 INFO - 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/', 06:10:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:10:51 INFO - 'USER': 'cltbld', 06:10:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:10:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:10:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:10:51 INFO - 'XPC_FLAGS': '0x0', 06:10:51 INFO - 'XPC_SERVICE_NAME': '0', 06:10:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:10:51 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/DUql8zwqT32G_WHM2Y8DMQ/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0189', '--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 06:10:52 INFO - mozversion application_buildid: 20170302043024 06:10:52 INFO - mozversion application_changeset: 4cd258347baab9f8dbe1ffbdf0238cfe3686509f 06:10:52 INFO - mozversion application_display_name: FirefoxDeveloperEdition 06:10:52 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:10:52 INFO - mozversion application_name: Firefox 06:10:52 INFO - mozversion application_remotingname: firefox-dev 06:10:52 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 06:10:52 INFO - mozversion application_vendor: Mozilla 06:10:52 INFO - mozversion application_version: 53.0a2 06:10:52 INFO - mozversion platform_buildid: 20170302043024 06:10:52 INFO - mozversion platform_changeset: 4cd258347baab9f8dbe1ffbdf0238cfe3686509f 06:10:52 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 06:10:52 INFO - mozversion platform_version: 53.0a2 06:10:52 INFO - using testdate: 1488463852 06:10:52 INFO - actual date: 1488463852 06:10:52 INFO - starting webserver on 'localhost:49324' 06:10:52 INFO - SUITE-START | Running 2 tests 06:10:52 INFO - TEST-START | dromaeo_css 06:10:52 INFO - operating with platform_type : mac_ 06:10:52 INFO - Initialising browser for dromaeo_css test... 06:10:52 INFO - TEST-INFO | started process 84798 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/tmpXlpysH/profile http://localhost:49324/getInfo.html) 06:10:55 INFO - PROCESS | 84798 | 2017-03-02 06:10:55.248 plugin-container[84801:718779] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c3f, name = 'com.apple.tsm.portname' 06:10:55 INFO - PROCESS | 84798 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:10:55 INFO - PROCESS | 84798 | 2017-03-02 06:10:55.252 plugin-container[84801:718779] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x940b, name = 'com.apple.CFPasteboardClient' 06:10:55 INFO - PROCESS | 84798 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:10:55 INFO - PROCESS | 84798 | 2017-03-02 06:10:55.253 plugin-container[84801:718779] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:10:55 INFO - PROCESS | 84798 | __metrics Screen width/height:1600/1200 06:10:55 INFO - PROCESS | 84798 | colorDepth:24 06:10:55 INFO - PROCESS | 84798 | Browser inner width/height: 1024/689 06:10:55 INFO - PROCESS | 84798 | __metrics 06:10:55 ERROR - PROCESS | 84798 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 06:10:56 INFO - TEST-INFO | 84798: exit 0 06:10:56 INFO - Browser initialized. 06:10:56 INFO - Running cycle 1/1 for dromaeo_css test... 06:10:56 INFO - TEST-INFO | started process 84803 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/tmpXlpysH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:10:58 INFO - PROCESS | 84803 | 2017-03-02 06:10:58.644 plugin-container[84804:718914] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x444f, name = 'com.apple.tsm.portname' 06:10:58 INFO - PROCESS | 84803 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:10:58 INFO - PROCESS | 84803 | 2017-03-02 06:10:58.645 plugin-container[84804:718914] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f03, name = 'com.apple.CFPasteboardClient' 06:10:58 INFO - PROCESS | 84803 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:10:58 INFO - PROCESS | 84803 | 2017-03-02 06:10:58.645 plugin-container[84804:718914] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:11:08 INFO - PROCESS | 84803 | RSS: Main: 218435584 06:11:08 INFO - PROCESS | 84803 | 06:14:11 INFO - PROCESS | 84803 | Cycle 1(1): loaded http://localhost:49324/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49324/tests/dromaeo/cssquery-ext.html) 06:14:12 INFO - PROCESS | 84803 | RSS: Main: 236183552 06:14:12 INFO - PROCESS | 84803 | 06:15:56 INFO - PROCESS | 84803 | ************************* 06:15:56 INFO - PROCESS | 84803 | A coding exception was thrown and uncaught in a Task. 06:15:56 INFO - PROCESS | 84803 | 06:15:56 ERROR - PROCESS | 84803 | Full message: TypeError: NetworkError when attempting to fetch resource. 06:15:56 INFO - PROCESS | 84803 | Full stack: 06:15:56 INFO - PROCESS | 84803 | ************************* 06:17:15 INFO - PROCESS | 84803 | Cycle 1(1): loaded http://localhost:49324/tests/dromaeo/cssquery-ext.html (next: http://localhost:49324/tests/dromaeo/cssquery-jquery.html) 06:17:15 INFO - PROCESS | 84803 | RSS: Main: 248508416 06:17:15 INFO - PROCESS | 84803 | 06:20:17 INFO - PROCESS | 84803 | Cycle 1(1): loaded http://localhost:49324/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49324/tests/dromaeo/cssquery-mootools.html) 06:20:18 INFO - PROCESS | 84803 | RSS: Main: 254771200 06:20:18 INFO - PROCESS | 84803 | 06:23:20 INFO - PROCESS | 84803 | Cycle 1(1): loaded http://localhost:49324/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49324/tests/dromaeo/cssquery-prototype.html) 06:23:20 INFO - PROCESS | 84803 | RSS: Main: 251158528 06:23:20 INFO - PROCESS | 84803 | 06:26:23 INFO - PROCESS | 84803 | Cycle 1(1): loaded http://localhost:49324/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49324/tests/dromaeo/cssquery-yui.html) 06:26:24 INFO - PROCESS | 84803 | RSS: Main: 254955520 06:26:24 INFO - PROCESS | 84803 | 06:29:26 INFO - PROCESS | 84803 | Cycle 1(1): loaded http://localhost:49324/tests/dromaeo/cssquery-yui.html (next: http://localhost:49324/tests/dromaeo/cssquery-dojo.html) 06:29:27 INFO - PROCESS | 84803 | RSS: Main: 256626688 06:29:27 INFO - PROCESS | 84803 | 06:29:27 INFO - PROCESS | 84803 | __start_tp_report 06:29:27 INFO - PROCESS | 84803 | _x_x_mozilla_page_load 06:29:27 INFO - PROCESS | 84803 | _x_x_mozilla_page_load_details 06:29:27 INFO - PROCESS | 84803 | |i|pagename|runs| 06:29:27 INFO - PROCESS | 84803 | |0;dojo.html;3044.665086840447;3362.831858407082;3379.8817041403554;3380.66193380662;3380.71263942565;5622.409646987069;5629.690367029812;5699.772009119635;5724.685142317171;5783.074708046714;5681.318241810978;5707.543396528278;5733.283339582552;5749.3388260350075;5807.477327040566;5213.58291336694;5237.292965449673;5238.4499627539135;5268.104422248227;5311.787528498875;5321.068812957717;5325.946740532603;5328.733563321838;5343.465653434664;5368.248445217672;5270.446603106676;5309.203619457093;5312.760925758331;5327.360716713981;5378.435264297251;5202.375714914197;5207.427183009866;5255.973720131375;5281.970015846911;5297.788088476456;5173.482651734834;5261.079311120577;5318;5324.54741346986;5335.893282134374;5737.282839645044;5848.561357898175;5852.970735146339;5857.648541087548;5864.648121112747;6113.841195312643;6154.78458253959;6166.321704612489;6285.622862628257;6292.33930437302;6191.288001879751;6194.96902515489;6238.033104868752;6265.373462653698;6303.212098487689;5910.202122713422;5926.348101708809;5931.673757943355;5934.5845790794665;5953.910691339634;6116.914073580187;6291.748330066746;6298.496120310364;6311.779087731999;6314.179156709598;6063.363346848606;6112.755489780359;6116.327204007555;6130.7650488535955;6243.469305109116;6246.968765156236;6296.590721603173;6300.21247344082;6312;6315.368463153648;6174.722137503823;6273.18448601688;6293.30773614902;6296.118543403927;6310.337414571496;6109.95128740435;6144.556029333103;6189.81430557084;6211.596246243979;6303.086052522451;4928.211486162197;4982.153033984231;5002.474740152305;5062.721550314768;5101.260317254051;3549.290141971616;4175.498940127143;4475.641948644101;4490.820367185276;4520.005598768265;5851.824445266649;5878.735456904449;5916.526677865761;5916.822495325147;5936.436038576328;6162.168107305546;6171.907421388682;6175.073738939195;6214.1921550198185;6256.655883926428;4895.192293271642;5899.911501327483;5904.793332233351;5914.319853216849;5960.940390596125;4671.27595222741;4711.717296962193;5524.723763811793;5534.861628459321;5606.579506536945;6218.098375735493;6282.591631543935;6292.874142517033;6295.055741638791;6365.777197798055;6251.593646412968;6278.121063051268;6286.654234017173;6298.0867774171575;6311.684415779285;6270.686465676789;6379.93620063812;6384.9042264367;6400.9039864403;6411.294757576569;5062.544371006627;6127.036821038878;6135.417135372132;6257.56197066201;6298.685065746603;5249.238860365302;6160.9383906160365;6201.317855036033;6286.497080233479;6299.811005669837;5069.6197785167;6177.444030037318;6180.351063138306;6197.132401463708;6201.627902325875;6004.339522652592;6088.847778805388;6129.785457508966;6160.507159427325;6204.534659900616;5903.911441328298;5919.7632094717455;5954.523638108854;6055.636661800306;6068.120122582289;6159.648020113036;6180.289266734383;6192.938070619236;6202.844928876814;6203.751849925952;5721.57088218377;5868.94131058684;5918.644881307136;5960.880782384242;6074.120634350488 06:29:27 INFO - PROCESS | 84803 | |1;ext.html;453.89929421153704;471.32835709114505;496.34978178586056;510.8161062017673;526.8472143078508;33753.66246337536;39374.80312598436;40994.795026024825;41038.38442423366;41076.178476430454;43125.137497250114;43618.7819060905;43673;43961.681149565484;44006.339904901455;8305.584720763967;8525.232729054384;9214.677486287981;9459.810803783921;9474.905250947506;11059.391733454657;11297.49161287744;11364.31814091153;11387;11451.083913286959;11346.262492937974;11383.48774305154;11418.371989540572;11422.71443213922;11436.2566433182;11401.429928503583;11457.885421145766;11479.196456248066;11495.482703278372;11506.482208300647;11317.83023254652;11364.545418183263;11384.487698053608;11388.943055284753;11403.258788178824;40279;40506.594934050576;40508.98727531806;40633.39049914254;40701.59298407008;3881.9223615527812;3942.7240093193486;3974.1455587048636;3995.800209989489;4039.091204478969;29844.403111937856;29912.40175196484;30792.076237712903;32224;32488.86288979898;3130.9530357044664;3148.134263077649;3275;3386.6782655647676;3397.3205358928312;439922.800385996;442638;444678;446250;448373;69114.96327555091;70009;70504.64747676229;70593;70685.64657176682;52439;60136.69931650314;73796.26203738002;74455.62772186105;75064;17205.91397043007;17247.827521724874;17389.826101739072;17394.21726022332;17455.825441745674;10509.684709458728;10832.729181770519;12891.935540322238;13012.21926684402;13119.737605247841;21368.893155534435;22005.66991495129;22122.668159977613;22248.555028899656;22603.773962260497;8086.7169649062;8850.645974160961;9117.854340252603;9222.584983675752;9232.21526170283;850.6342272822745;861.1732736572835;864.7838040489878;873.9868901966476;878.4070752242211;29637.85181074081;30069;30190.69809301923;30808.537871931938;30998.22504437362;1001.7595777013464;1028.1928685981468;1053.3837704942541;1071.8177909755361;1129.2377645089532;11809.586664466702;15704.214789260492;15709.607259818367;15771.44799931997;16059.598510037109;638589;640619;647209;647758;649953;59732.70133649304;60036.39963600308;60277.09584356238;60439.69780151071;60591;1386.7711827548544;1401.4394242302956;1425.5794540610545;1426.8287805463203;1431.4632012995128;24162.033518659056;27666.861665691544;29012.419751604426;29474.557881631365;30177;42271.7886410566;51638;51750.482495176075;51772.74113629558;51878.22182667188;453628;455082;458473.4152658564;458895;459732;25721.871390642926;26396.0761373351;26461.33846653849;27292.863535682194;43594;41185.176296473306;41705.58294417139;41966.79016604897;42250;42666.7199984001;34605.30789384249;34656.65343346534;34673.826630866686;34819.82590087033;35616.82191589129;34618.65381346255;34801.12997175091;35865.82067089648;35997.64002359943;36096;33735.66264337325;33932.49101263434;34168;34465.655343447244;34697.8265108683;30003.949861754732;30914.536281955337;31001.8449907749;31146;31299.06102816919;22982.770172298064;23077.53844923126;23379.181728639414;23467.76532234724;23474.530509390057 06:29:27 INFO - PROCESS | 84803 | |2;jquery.html;392.3565352821374;395.8495771606789;400.2255635345606;405.533636318234;424.27237288050986;15898.84101158988;19999.400017999447;20370;21324.680129798046;21360.038798254078;20727;21234.938253087323;21258.89370553149;21379.358619241408;21404.14383424665;5059.898802023967;5333.306670132877;5382.6232163748555;5413.891722165555;5452.509274165324;5175.767090480937;5346.705931173784;5377.65045272058;5385.3537575491;5407.351117865842;4555.74943378114;5341.4925582069645;5386.138217885139;5414.972925135388;5422.75597598109;5367.409584945653;5392.406835248159;5408;5413.106837371849;5775.797847075374;4538.909221815546;5251.238570407308;5283.418823929364;5285.286486324336;5330.946690533084;13549.39027743744;13720.245386503739;24709.752902471104;25697;25775.968961241528;2574.613807928807;2587.107447930461;2589.4821035792916;2627.5139099266507;2647.8676066196804;10060.446675432848;10132.138768204637;10620.628278010232;12612.432440540199;14654.56036318912;1805.250820909321;1806.7470554122433;1828.7988321284647;1829.231722676479;1838.9448316550524;121244;122085;124462.9999999991;128065.7193428063;132887;10846.91530846909;11624.825627615592;11669.59156429521;12196.085293603015;12225.755484890253;15596.376144954302;16046.358145674276;16125.951813132344;16151.273192706356;16166.43417480406;8818.735437936872;8939.524978378624;8942.86585701215;8948.418352807046;9090.681826136186;8652.56737163152;8665.43674661145;8669.826603467895;8818.95590522056;8841.336899732418;3944.7041471889725;4127.215828992542;4853.927191092136;4884.072026314989;4885.902281954341;2012.909419076116;2060.2377120465526;2062.7215325931106;2064.3910046536307;2069.317125348631;1026.3636545341935;1034.9482525873675;1051.9375430814932;1054.7099547624464;1065.2170654568886;19928.60142797136;21165.047572859257;21483;21535.67696484554;21726.51288524031;1042.4058286776617;1052.8035971614781;1056.5932116135343;1074.6883403812806;1078.8327809189589;10574.84137737919;11232.932871377207;11241.66275011751;11273.94363028196;11278.661640150809;716676;728830;737854;739822;772366;93558.53220733853;133026.33486832504;133560;134077;138636;973.0464145137663;993.5975929748463;1004.8442491413842;1011.0748664971616;1013.3565186106875;18386.632267354846;18619.06904654735;19015.383692386302;19046.428607141806;19128.191133053482;48648.75675621599;54624.361269161986;57120;57954.710226448595;59516.702416489374;79896.60051699704;80698;82044.58977705073;83031.16968830234;83984.74022889539;7608.087029556267;7628.237176282328;7885.329746971572;8046.436749427484;8551.615177316911;12441.315727634705;12491.812622810483;13039.022073344728;13524.661883452993;13844.72310553763;11058.668239952814;11082.61210857616;11521.078313734713;11543.134264929995;11657.475413606238;8771.429857059271;9634.951825240829;11276.379799111128;11601.5359385627;11616.651500455;9763.951180244054;11133.498992545194;11630.953214210762;11658.88341116578;11755.05959523219;8301.58492075406;8420.248669612545;8477.872831907403;8706.521141337287;8790.648374065222;8313.043999940084;10172.338797978107;10796.244262901522;10980.176486763685;11155.553777848796 06:29:27 INFO - PROCESS | 84803 | |3;mootools.html;1205.9577914772985;1325.1651459580462;1441.769316909294;1452.3682198243762;1479.9408023679052;7605;7873.055233372003;8157.551334676597;8586.09845966174;8590.055093939669;8126.1873812618705;8323.334133269324;8388.916110838889;8464.788380290496;8512.446690965098;1983.206717313072;2032.7154198412234;2284.18911286493;2332.9385129766;2347.084636991575;2086.6139764143604;2172.0334451169238;2321.6401457774073;2331.522037982218;2361.3506285771377;2259.8757068361233;2336.2290444153386;2337.4273303040695;2349.8472599281076;2357.3399448154546;2006.9698954515693;2300.7354154272307;2321.5124823787023;2325.523292709142;2347.471818840764;2300.6779050932882;2309.307207837642;2364;2368.751281115475;2371.893264803088;7887.842243155104;7894.328982036476;8476.957615211946;8549.358798090107;8558.400911936167;5085.567726743231;5231.398389185254;5245.737713114329;5251.6061295403;5266.9992701387;3324.6010478742705;3531.841067151984;3592.982035089834;3597.0288022234113;3605.080704420356;1026.6663334416344;1186.35936594239;1211.7939950208486;1270.1299609767248;1272.8281681972908;10840.67477975654;10930.398828064453;10977.615783447622;11005.394703291317;11022.724431889186;4595.0809838031855;4691.812327506861;4702.31816386622;4765.904681906342;4768.856934291976;9493.810123797486;9626.9037309626;9652.179564737076;9656.662016829378;9659.178969787507;5203.505666961708;5279.709615971158;5287.4448182941005;5298.841034768963;5301.204819277063;4041.454403655469;4610.870336767513;4820.927686084711;4831.4202295724735;4843.321934929113;3877.3408520551575;3933.4099885017476;3936.1340505088833;3981.7411868228473;4035.1929614077303;1182.2138278045004;1229.8708635593314;1258.704204511939;1289.7291568770477;1296.2222666400085;740.7370383513883;845.2815107158989;845.6828689241535;856.0839901305543;863.6329559937002;5182.922256166161;5250.921236181461;5301.787928482818;5435.130379139318;5558.221848941152;962.3840741925173;973.1387721866058;980.6910823090847;987.4914419074182;997.6258902911409;3625.2386998730035;3647.8905632831056;3683.950074228832;3747.1194269346684;3767.585565587783;9693.41839489633;10039.4478303694;10047.899521004843;10083.445410502347;10148.188144948386;10075.949620251851;10079.445630490394;10095.44475053879;10114.848277275847;10120.595176193017;7939.3251573615735;7989.480683755537;8006.639701213459;8016.679332826739;8029.397795165386;3688.6495782900583;4665.743384113906;4862.635302352267;5377.838664840061;5439.972800136133;7412.444066695128;9203.263738901005;9281.886173659204;9285.117913798178;9348.439093654402;7740.7677769667;9406;9491.905080949102;9566.091221333962;9642.42145471274;7237.1677257114;7280.1991780903;7305.817354565966;7333.413326933939;7387.372073373824;6758.898616520658;7845.882311765214;7859.371250300077;7929.960350198212;7949.046114466301;5972.970135149297;7207.387372073433;7231.674574644048;7248.746293879689;7335.596542190231;6158.507319414526;7101.183363913216;7114.57312561248;7167.749128780468;7194;6391.009393543822;6452.677366131581;6528.347165283433;6538.921078022073;6549.31232220629;4923.926141107814;4928.334674818853;4982.701037937735;4988.376452943382;5005.599552035901;4892.217245240744;4903.877403064952;4917.139500587455;5048.469910659474;5061.316722242597 06:29:27 INFO - PROCESS | 84803 | |4;prototype.html;305.52185829177336;352.8385664446559;359.07538089419745;367.4543303194754;371.4205838891331;5123.743812809364;5311.778290993069;5340.893182136365;5401.756920938557;5446.945530544698;4201.663866890649;5267.973660131694;5314.997026679923;5327.440618735035;5339.172428273614;3382.52644629752;3995.740276882008;4118.361653943629;4187;4241.533431322552;3690.5940346561856;3821.197548514815;4209.73707887634;4234.830606775725;4241;3481.129717570607;3528.752987290891;4010.418489319047;4024.134811015631;4041.6766658667234;3076.8461576921063;3708.870189543353;3955.6044395560502;4058.7767672777986;4072.7759973201464;2983.686712895158;3206.294615184656;3338.549295845054;3537.6285490023433;3545.2377738786286;4028.154087641599;4220.662347012231;5149.2791009258735;5309.256704061435;5326.44072372403;3874.8062596870045;4623.722576645412;4639.559241872017;4652.371929789469;4682.367880336179;5248.580113590903;5481.808136715235;5607.214989901417;5638.633488823213;5653.689047102408;3585.713142948558;3797.9680870734023;3844.519435070616;3884.8050851268945;3924.9018774530578;4798.496157903405;5891.988533644992;5894.997850365448;5896.351401345849;5938.673372964486;3404.744644151649;3678.5769636492146;3858.1897801461446;3911.393733971239;3924.3917192835156;3852.2295540891932;3931.68546516278;3942.586028467027;3942.7634341939574;3951.7431366961055;4521.784325680393;4566.20091483989;4578.2903649934315;4621.537846215351;4628.39830821991;4977.651564390458;5735.827925162168;5736.340320863071;5758.712064396764;5783.71081445926;1141.4692168141687;1223.6267938278731;1244.9377531123407;1262.2895975952636;1294.9482020719065;814.1532805881935;837.1837458477935;856.9288389513108;865.9783505412289;878.3544095090103;4377.708575970097;4391.089875903992;4790.5448982346625;5080.132447023693;5135.281060651511;4549.5905368517;4811.381074168768;5462.754176062007;5571.749271282802;5644.491995720323;4334.934975975363;4432.534583868647;4760.01861625756;5011.4236862761245;5065.1005872797705;2784.693683694792;2898.4637841998874;2898.695636958089;2903.796734228626;2958.600588920468;5538.972305138529;5729.968605651023;5767.250257466587;5768.221290125779;5798.739056742456;4583.106294272585;4789.640776941674;5195.012787723827;5790.36306006339;5809.398173132685;4882.022780773792;4894.608431325485;5225.451327610622;5904.3210030847;5905.0141154720395;4970.900581988412;5505.504504594605;5533.53757577874;5606.775728970795;5625.69058701759;4149.004238982683;4662.254039353687;4969.763606377217;5444.26502422168;5540.750666219949;5386.885884521019;5711.343195532567;5884.705764711662;5907.527397808251;5912.70436478183;3605.0987253186704;3626.7461277711363;3726.5963453572836;3732.2722069196248;3751.2309976454217;5260.526552610283;5564.610477266552;5565.499105080562;5604.159376093618;5649.548036157015;4625.363542780583;4650.023984649759;5284.313039304866;5515.104570100729;5593.272874526286;5102.591792656504;5254.710990895537;5294.708791016377;5641.5431808870535;5764.818212266391;5022.59367377135;5261.764279754681;5433.293671822796;5442.509929308416;5521.779128834802;5396.785723212402;5428.7014214217015;5670.419958918765;5671.200295801905;5738.311402631711;4928.6057115431395;5574.331080270394;5581.748821303132;5599.944000560105;5605.635633683797 06:29:27 INFO - PROCESS | 84803 | |5;yui.html;710.2081179484876;710.318094629156;717.9928200717991;738.7857521318814;739;15137.470188543402;15165;15511.999999999985;15541.611459713513;15571.22143892805;14003.649908752286;15332.386704531806;15852.3659053638;15940.601484962881;16329;682.2427105912458;844.4089137603687;865.9523726195057;866.4627930682985;867.0679020053436;684.6474065856088;835.7826964989116;851.8424091543053;868.1275318305095;870.2081106193365;909.849874770662;911;913.4747520175893;913.5112714697638;914.4787471141452;894.2041582991143;902.120432578231;905.2169873059756;907.7004588485817;909.4634165842185;663.0352836622751;670.2426258328059;674.5379415100672;675.5507587454337;675.6858061001609;14891.32989015486;15254.618634534225;15333.233338333039;15355.4625588105;15379.307931143014;13923.58229253124;14268.85731142696;14325.355359008765;14331.140131592138;14384.712305753827;10095.687560617072;11535.25020873649;11758.470868810924;11763.529458821637;12236.81644775329;3343.648916863743;3538.575370955502;3653.5250417445563;3653.8173091345325;3683.963160368389;108010;108675.82648347068;109517;110715.89284107217;111140.444297778;10418.791624167474;10428.270021098602;10570.41862697559;10906.61826836057;11059.612913547988;21491.462713431978;21682.674759878613;21752.782472175393;21872.343829684818;21940.670889936664;7516.812079697942;7523.0972283326355;7524.548527088392;7600.2779735925;7752;4139.730917490354;4159.209750147463;4238.978805105955;4243.469566304212;4282.293421585466;3499.702525285328;3552.0764601203873;3581.534400527915;3590.6768390844427;3609.4224924012033;627.8179327994831;639.4181295021508;641.4323323858418;644.9129367535322;658.8912829383194;634.1850721822436;642.6240649220165;647.2427260105687;673.7675501951917;691.2568441887225;9631.181349585364;10264;10308.32995855267;10359.067683908484;10503.894961050444;633.5850018238061;664.973401063952;680.2551206428952;689.582802404548;690.6960937187722;4437.866863994085;4961.330220420269;5009.7495125243595;5015.974920125376;5016.849494515171;89095.55452222827;103565.96434035563;106220.46889765657;109507;109583.35624965632;75685.48629027388;95692.0430795697;98098.01901980888;99701.50149249352;99909.50045249728;71230.93153602803;79678.60160699276;82617.58691206506;84089.57955210184;84099.5795021021;9724.659636912675;9929.907710152022;10523.263371563917;10565.63020294286;10897.291676041032;47539.52460475351;48747.75626121847;51103.48896510987;51523.48476515187;54018.729906350214;67403.66298168477;68015.65992170008;68892;70250;71046;12566.308853013172;12603.432845521791;12896.032797540034;12938.223706577635;13173.934130329288;13915.72168556603;14159.787603185754;14213.715725685222;14424.711505770036;14522;13144.868551314365;13450.26023568713;13452.596422107352;13512.662183445493;13545.355114842372;12628.936855316033;13075.346232688518;13105.045064739983;13354;13397.933010334886;12783.360831958551;12855.100946935963;13315;13566.457341706222;13803.585892423243;10542.789144217226;10775.730606734895;10925.672229833117;10930.508127134139;11163.051140653135;9297.442153470813;9598.808023839345;9787.463652987302;10911.236213464981;10919.727006824893 06:29:27 INFO - PROCESS | 84803 | __end_tp_report 06:29:27 INFO - PROCESS | 84803 | __start_cc_report 06:29:27 INFO - PROCESS | 84803 | _x_x_mozilla_cycle_collect,400 06:29:27 INFO - PROCESS | 84803 | __end_cc_report 06:29:27 INFO - PROCESS | 84803 | __startTimestamp1488464967152__endTimestamp 06:29:27 INFO - PROCESS | 84803 | 06:29:27 INFO - PROCESS | 84803 | ------- Summary: start ------- 06:29:27 INFO - PROCESS | 84803 | Number of tests: 6 06:29:27 INFO - PROCESS | 84803 | 06:29:27 INFO - PROCESS | 84803 | [#0] dojo.html Cycles:165 Average:5755.77 Median:5954.52 stddev:669.71 (11.2%) stddev-sans-first:637.10 06:29:27 INFO - PROCESS | 84803 | Values: 3044.7 3362.8 3379.9 3380.7 3380.7 5622.4 5629.7 5699.8 5724.7 5783.1 5681.3 5707.5 5733.3 5749.3 5807.5 5213.6 5237.3 5238.4 5268.1 5311.8 5321.1 5325.9 5328.7 5343.5 5368.2 5270.4 5309.2 5312.8 5327.4 5378.4 5202.4 5207.4 5256.0 5282.0 5297.8 5173.5 5261.1 5318.0 5324.5 5335.9 5737.3 5848.6 5853.0 5857.6 5864.6 6113.8 6154.8 6166.3 6285.6 6292.3 6191.3 6195.0 6238.0 6265.4 6303.2 5910.2 5926.3 5931.7 5934.6 5953.9 6116.9 6291.7 6298.5 6311.8 6314.2 6063.4 6112.8 6116.3 6130.8 6243.5 6247.0 6296.6 6300.2 6312.0 6315.4 6174.7 6273.2 6293.3 6296.1 6310.3 6110.0 6144.6 6189.8 6211.6 6303.1 4928.2 4982.2 5002.5 5062.7 5101.3 3549.3 4175.5 4475.6 4490.8 4520.0 5851.8 5878.7 5916.5 5916.8 5936.4 6162.2 6171.9 6175.1 6214.2 6256.7 4895.2 5899.9 5904.8 5914.3 5960.9 4671.3 4711.7 5524.7 5534.9 5606.6 6218.1 6282.6 6292.9 6295.1 6365.8 6251.6 6278.1 6286.7 6298.1 6311.7 6270.7 6379.9 6384.9 6400.9 6411.3 5062.5 6127.0 6135.4 6257.6 6298.7 5249.2 6160.9 6201.3 6286.5 6299.8 5069.6 6177.4 6180.4 6197.1 6201.6 6004.3 6088.8 6129.8 6160.5 6204.5 5903.9 5919.8 5954.5 6055.6 6068.1 6159.6 6180.3 6192.9 6202.8 6203.8 5721.6 5868.9 5918.6 5960.9 6074.1 06:29:27 INFO - PROCESS | 84803 | 06:29:27 INFO - PROCESS | 84803 | [#1] ext.html Cycles:180 Average:66063.74 Median:34637.65 stddev:139664.62 (403.2%) stddev-sans-first:139969.54 06:29:27 INFO - PROCESS | 84803 | Values: 453.9 471.3 496.3 510.8 526.8 33753.7 39374.8 40994.8 41038.4 41076.2 43125.1 43618.8 43673.0 43961.7 44006.3 8305.6 8525.2 9214.7 9459.8 9474.9 11059.4 11297.5 11364.3 11387.0 11451.1 11346.3 11383.5 11418.4 11422.7 11436.3 11401.4 11457.9 11479.2 11495.5 11506.5 11317.8 11364.5 11384.5 11388.9 11403.3 40279.0 40506.6 40509.0 40633.4 40701.6 3881.9 3942.7 3974.1 3995.8 4039.1 29844.4 29912.4 30792.1 32224.0 32488.9 3131.0 3148.1 3275.0 3386.7 3397.3 439922.8 442638.0 444678.0 446250.0 448373.0 69115.0 70009.0 70504.6 70593.0 70685.6 52439.0 60136.7 73796.3 74455.6 75064.0 17205.9 17247.8 17389.8 17394.2 17455.8 10509.7 10832.7 12891.9 13012.2 13119.7 21368.9 22005.7 22122.7 22248.6 22603.8 8086.7 8850.6 9117.9 9222.6 9232.2 850.6 861.2 864.8 874.0 878.4 29637.9 30069.0 30190.7 30808.5 30998.2 1001.8 1028.2 1053.4 1071.8 1129.2 11809.6 15704.2 15709.6 15771.4 16059.6 638589.0 640619.0 647209.0 647758.0 649953.0 59732.7 60036.4 60277.1 60439.7 60591.0 1386.8 1401.4 1425.6 1426.8 1431.5 24162.0 27666.9 29012.4 29474.6 30177.0 42271.8 51638.0 51750.5 51772.7 51878.2 453628.0 455082.0 458473.4 458895.0 459732.0 25721.9 26396.1 26461.3 27292.9 43594.0 41185.2 41705.6 41966.8 42250.0 42666.7 34605.3 34656.7 34673.8 34819.8 35616.8 34618.7 34801.1 35865.8 35997.6 36096.0 33735.7 33932.5 34168.0 34465.7 34697.8 30003.9 30914.5 31001.8 31146.0 31299.1 22982.8 23077.5 23379.2 23467.8 23474.5 06:29:27 INFO - PROCESS | 84803 | 06:29:27 INFO - PROCESS | 84803 | [#2] jquery.html Cycles:180 Average:39243.74 Median:21391.75 stddev:122476.05 (572.5%) stddev-sans-first:122784.88 06:29:27 INFO - PROCESS | 84803 | Values: 392.4 395.8 400.2 405.5 424.3 15898.8 19999.4 20370.0 21324.7 21360.0 20727.0 21234.9 21258.9 21379.4 21404.1 5059.9 5333.3 5382.6 5413.9 5452.5 5175.8 5346.7 5377.7 5385.4 5407.4 4555.7 5341.5 5386.1 5415.0 5422.8 5367.4 5392.4 5408.0 5413.1 5775.8 4538.9 5251.2 5283.4 5285.3 5330.9 13549.4 13720.2 24709.8 25697.0 25776.0 2574.6 2587.1 2589.5 2627.5 2647.9 10060.4 10132.1 10620.6 12612.4 14654.6 1805.3 1806.7 1828.8 1829.2 1838.9 121244.0 122085.0 124463.0 128065.7 132887.0 10846.9 11624.8 11669.6 12196.1 12225.8 15596.4 16046.4 16126.0 16151.3 16166.4 8818.7 8939.5 8942.9 8948.4 9090.7 8652.6 8665.4 8669.8 8819.0 8841.3 3944.7 4127.2 4853.9 4884.1 4885.9 2012.9 2060.2 2062.7 2064.4 2069.3 1026.4 1034.9 1051.9 1054.7 1065.2 19928.6 21165.0 21483.0 21535.7 21726.5 1042.4 1052.8 1056.6 1074.7 1078.8 10574.8 11232.9 11241.7 11273.9 11278.7 716676.0 728830.0 737854.0 739822.0 772366.0 93558.5 133026.3 133560.0 134077.0 138636.0 973.0 993.6 1004.8 1011.1 1013.4 18386.6 18619.1 19015.4 19046.4 19128.2 48648.8 54624.4 57120.0 57954.7 59516.7 79896.6 80698.0 82044.6 83031.2 83984.7 7608.1 7628.2 7885.3 8046.4 8551.6 12441.3 12491.8 13039.0 13524.7 13844.7 11058.7 11082.6 11521.1 11543.1 11657.5 8771.4 9635.0 11276.4 11601.5 11616.7 9764.0 11133.5 11631.0 11658.9 11755.1 8301.6 8420.2 8477.9 8706.5 8790.6 8313.0 10172.3 10796.2 10980.2 11155.6 06:29:27 INFO - PROCESS | 84803 | 06:29:27 INFO - PROCESS | 84803 | [#3] mootools.html Cycles:180 Average:5418.44 Median:5054.89 stddev:2980.38 (59.0%) stddev-sans-first:2971.92 06:29:27 INFO - PROCESS | 84803 | Values: 1206.0 1325.2 1441.8 1452.4 1479.9 7605.0 7873.1 8157.6 8586.1 8590.1 8126.2 8323.3 8388.9 8464.8 8512.4 1983.2 2032.7 2284.2 2332.9 2347.1 2086.6 2172.0 2321.6 2331.5 2361.4 2259.9 2336.2 2337.4 2349.8 2357.3 2007.0 2300.7 2321.5 2325.5 2347.5 2300.7 2309.3 2364.0 2368.8 2371.9 7887.8 7894.3 8477.0 8549.4 8558.4 5085.6 5231.4 5245.7 5251.6 5267.0 3324.6 3531.8 3593.0 3597.0 3605.1 1026.7 1186.4 1211.8 1270.1 1272.8 10840.7 10930.4 10977.6 11005.4 11022.7 4595.1 4691.8 4702.3 4765.9 4768.9 9493.8 9626.9 9652.2 9656.7 9659.2 5203.5 5279.7 5287.4 5298.8 5301.2 4041.5 4610.9 4820.9 4831.4 4843.3 3877.3 3933.4 3936.1 3981.7 4035.2 1182.2 1229.9 1258.7 1289.7 1296.2 740.7 845.3 845.7 856.1 863.6 5182.9 5250.9 5301.8 5435.1 5558.2 962.4 973.1 980.7 987.5 997.6 3625.2 3647.9 3684.0 3747.1 3767.6 9693.4 10039.4 10047.9 10083.4 10148.2 10075.9 10079.4 10095.4 10114.8 10120.6 7939.3 7989.5 8006.6 8016.7 8029.4 3688.6 4665.7 4862.6 5377.8 5440.0 7412.4 9203.3 9281.9 9285.1 9348.4 7740.8 9406.0 9491.9 9566.1 9642.4 7237.2 7280.2 7305.8 7333.4 7387.4 6758.9 7845.9 7859.4 7930.0 7949.0 5973.0 7207.4 7231.7 7248.7 7335.6 6158.5 7101.2 7114.6 7167.7 7194.0 6391.0 6452.7 6528.3 6538.9 6549.3 4923.9 4928.3 4982.7 4988.4 5005.6 4892.2 4903.9 4917.1 5048.5 5061.3 06:29:27 INFO - PROCESS | 84803 | 06:29:27 INFO - PROCESS | 84803 | [#4] prototype.html Cycles:180 Average:4448.86 Median:4949.18 stddev:1373.56 (27.8%) stddev-sans-first:1341.75 06:29:27 INFO - PROCESS | 84803 | Values: 305.5 352.8 359.1 367.5 371.4 5123.7 5311.8 5340.9 5401.8 5446.9 4201.7 5268.0 5315.0 5327.4 5339.2 3382.5 3995.7 4118.4 4187.0 4241.5 3690.6 3821.2 4209.7 4234.8 4241.0 3481.1 3528.8 4010.4 4024.1 4041.7 3076.8 3708.9 3955.6 4058.8 4072.8 2983.7 3206.3 3338.5 3537.6 3545.2 4028.2 4220.7 5149.3 5309.3 5326.4 3874.8 4623.7 4639.6 4652.4 4682.4 5248.6 5481.8 5607.2 5638.6 5653.7 3585.7 3798.0 3844.5 3884.8 3924.9 4798.5 5892.0 5895.0 5896.4 5938.7 3404.7 3678.6 3858.2 3911.4 3924.4 3852.2 3931.7 3942.6 3942.8 3951.7 4521.8 4566.2 4578.3 4621.5 4628.4 4977.7 5735.8 5736.3 5758.7 5783.7 1141.5 1223.6 1244.9 1262.3 1294.9 814.2 837.2 856.9 866.0 878.4 4377.7 4391.1 4790.5 5080.1 5135.3 4549.6 4811.4 5462.8 5571.7 5644.5 4334.9 4432.5 4760.0 5011.4 5065.1 2784.7 2898.5 2898.7 2903.8 2958.6 5539.0 5730.0 5767.3 5768.2 5798.7 4583.1 4789.6 5195.0 5790.4 5809.4 4882.0 4894.6 5225.5 5904.3 5905.0 4970.9 5505.5 5533.5 5606.8 5625.7 4149.0 4662.3 4969.8 5444.3 5540.8 5386.9 5711.3 5884.7 5907.5 5912.7 3605.1 3626.7 3726.6 3732.3 3751.2 5260.5 5564.6 5565.5 5604.2 5649.5 4625.4 4650.0 5284.3 5515.1 5593.3 5102.6 5254.7 5294.7 5641.5 5764.8 5022.6 5261.8 5433.3 5442.5 5521.8 5396.8 5428.7 5670.4 5671.2 5738.3 4928.6 5574.3 5581.7 5599.9 5605.6 06:29:27 INFO - PROCESS | 84803 | 06:29:27 INFO - PROCESS | 84803 | [#5] yui.html Cycles:180 Average:20844.27 Median:3653.67 stddev:30343.75 (830.5%) stddev-sans-first:30391.21 06:29:27 INFO - PROCESS | 84803 | Values: 710.2 710.3 718.0 738.8 739.0 15137.5 15165.0 15512.0 15541.6 15571.2 14003.6 15332.4 15852.4 15940.6 16329.0 682.2 844.4 866.0 866.5 867.1 684.6 835.8 851.8 868.1 870.2 909.8 911.0 913.5 913.5 914.5 894.2 902.1 905.2 907.7 909.5 663.0 670.2 674.5 675.6 675.7 14891.3 15254.6 15333.2 15355.5 15379.3 13923.6 14268.9 14325.4 14331.1 14384.7 10095.7 11535.3 11758.5 11763.5 12236.8 3343.6 3538.6 3653.5 3653.8 3684.0 108010.0 108675.8 109517.0 110715.9 111140.4 10418.8 10428.3 10570.4 10906.6 11059.6 21491.5 21682.7 21752.8 21872.3 21940.7 7516.8 7523.1 7524.5 7600.3 7752.0 4139.7 4159.2 4239.0 4243.5 4282.3 3499.7 3552.1 3581.5 3590.7 3609.4 627.8 639.4 641.4 644.9 658.9 634.2 642.6 647.2 673.8 691.3 9631.2 10264.0 10308.3 10359.1 10503.9 633.6 665.0 680.3 689.6 690.7 4437.9 4961.3 5009.7 5016.0 5016.8 89095.6 103566.0 106220.5 109507.0 109583.4 75685.5 95692.0 98098.0 99701.5 99909.5 71230.9 79678.6 82617.6 84089.6 84099.6 9724.7 9929.9 10523.3 10565.6 10897.3 47539.5 48747.8 51103.5 51523.5 54018.7 67403.7 68015.7 68892.0 70250.0 71046.0 12566.3 12603.4 12896.0 12938.2 13173.9 13915.7 14159.8 14213.7 14424.7 14522.0 13144.9 13450.3 13452.6 13512.7 13545.4 12628.9 13075.3 13105.0 13354.0 13397.9 12783.4 12855.1 13315.0 13566.5 13803.6 10542.8 10775.7 10925.7 10930.5 11163.1 9297.4 9598.8 9787.5 10911.2 10919.7 06:29:27 INFO - PROCESS | 84803 | -------- Summary: end -------- 06:29:27 INFO - PROCESS | 84803 | 06:29:27 INFO - TEST-INFO | 84803: exit 0 06:29:27 INFO - TEST-OK | dromaeo_css | took 1115759ms 06:29:27 INFO - TEST-START | kraken 06:29:27 INFO - operating with platform_type : mac_ 06:29:27 INFO - Initialising browser for kraken test... 06:29:27 INFO - TEST-INFO | started process 84809 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/tmpTOVEOh/profile http://localhost:49324/getInfo.html) 06:29:30 INFO - PROCESS | 84809 | 2017-03-02 06:29:30.646 plugin-container[84810:720970] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x434b, name = 'com.apple.tsm.portname' 06:29:30 INFO - PROCESS | 84809 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:29:30 INFO - PROCESS | 84809 | 2017-03-02 06:29:30.650 plugin-container[84810:720970] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 06:29:30 INFO - PROCESS | 84809 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:29:30 INFO - PROCESS | 84809 | 2017-03-02 06:29:30.650 plugin-container[84810:720970] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:29:30 INFO - PROCESS | 84809 | __metrics Screen width/height:1600/1200 06:29:30 INFO - PROCESS | 84809 | colorDepth:24 06:29:30 INFO - PROCESS | 84809 | Browser inner width/height: 1024/689 06:29:30 INFO - PROCESS | 84809 | __metrics 06:29:30 ERROR - PROCESS | 84809 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 06:29:31 INFO - TEST-INFO | 84809: exit 0 06:29:31 INFO - Browser initialized. 06:29:31 INFO - Running cycle 1/1 for kraken test... 06:29:31 INFO - TEST-INFO | started process 84811 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/tmpTOVEOh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:29:33 INFO - PROCESS | 84811 | 2017-03-02 06:29:33.898 plugin-container[84812:721092] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8943, name = 'com.apple.tsm.portname' 06:29:33 INFO - PROCESS | 84811 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:29:33 INFO - PROCESS | 84811 | 2017-03-02 06:29:33.898 plugin-container[84812:721092] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x910b, name = 'com.apple.CFPasteboardClient' 06:29:33 INFO - PROCESS | 84811 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:29:33 INFO - PROCESS | 84811 | 2017-03-02 06:29:33.898 plugin-container[84812:721092] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:29:43 INFO - PROCESS | 84811 | RSS: Main: 218656768 06:29:43 INFO - PROCESS | 84811 | 06:29:46 INFO - PROCESS | 84811 | Cycle 1(1): loaded http://localhost:49324/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49324/tests/kraken/driver.html?testName=audio-beat-detection) 06:29:47 INFO - PROCESS | 84811 | RSS: Main: 233082880 06:29:47 INFO - PROCESS | 84811 | 06:29:50 INFO - PROCESS | 84811 | Cycle 1(1): loaded http://localhost:49324/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49324/tests/kraken/driver.html?testName=audio-dft) 06:29:51 INFO - PROCESS | 84811 | RSS: Main: 242200576 06:29:51 INFO - PROCESS | 84811 | 06:29:54 INFO - PROCESS | 84811 | Cycle 1(1): loaded http://localhost:49324/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49324/tests/kraken/driver.html?testName=audio-fft) 06:29:55 INFO - PROCESS | 84811 | RSS: Main: 245047296 06:29:55 INFO - PROCESS | 84811 | 06:29:57 INFO - PROCESS | 84811 | Cycle 1(1): loaded http://localhost:49324/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49324/tests/kraken/driver.html?testName=audio-oscillator) 06:29:57 INFO - PROCESS | 84811 | RSS: Main: 246513664 06:29:57 INFO - PROCESS | 84811 | 06:30:00 INFO - PROCESS | 84811 | Cycle 1(1): loaded http://localhost:49324/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49324/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:30:00 INFO - PROCESS | 84811 | RSS: Main: 246550528 06:30:00 INFO - PROCESS | 84811 | 06:30:03 INFO - PROCESS | 84811 | Cycle 1(1): loaded http://localhost:49324/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49324/tests/kraken/driver.html?testName=imaging-darkroom) 06:30:04 INFO - PROCESS | 84811 | RSS: Main: 250540032 06:30:04 INFO - PROCESS | 84811 | 06:30:07 INFO - PROCESS | 84811 | Cycle 1(1): loaded http://localhost:49324/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49324/tests/kraken/driver.html?testName=imaging-desaturate) 06:30:08 INFO - PROCESS | 84811 | RSS: Main: 249872384 06:30:08 INFO - PROCESS | 84811 | 06:30:11 INFO - PROCESS | 84811 | Cycle 1(1): loaded http://localhost:49324/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49324/tests/kraken/driver.html?testName=json-parse-financial) 06:30:11 INFO - PROCESS | 84811 | RSS: Main: 249782272 06:30:11 INFO - PROCESS | 84811 | 06:30:13 INFO - PROCESS | 84811 | Cycle 1(1): loaded http://localhost:49324/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49324/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:30:14 INFO - PROCESS | 84811 | RSS: Main: 249417728 06:30:14 INFO - PROCESS | 84811 | 06:30:16 INFO - PROCESS | 84811 | Cycle 1(1): loaded http://localhost:49324/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49324/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:30:16 INFO - PROCESS | 84811 | RSS: Main: 249434112 06:30:16 INFO - PROCESS | 84811 | 06:30:18 INFO - PROCESS | 84811 | Cycle 1(1): loaded http://localhost:49324/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49324/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:30:19 INFO - PROCESS | 84811 | RSS: Main: 249479168 06:30:19 INFO - PROCESS | 84811 | 06:30:22 INFO - PROCESS | 84811 | Cycle 1(1): loaded http://localhost:49324/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49324/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:30:23 INFO - PROCESS | 84811 | RSS: Main: 249815040 06:30:23 INFO - PROCESS | 84811 | 06:30:26 INFO - PROCESS | 84811 | Cycle 1(1): loaded http://localhost:49324/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49324/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:30:26 INFO - PROCESS | 84811 | RSS: Main: 249622528 06:30:26 INFO - PROCESS | 84811 | 06:30:28 INFO - PROCESS | 84811 | Cycle 1(1): loaded http://localhost:49324/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49324/tests/kraken/driver.html?testName=ai-astar) 06:30:28 INFO - PROCESS | 84811 | RSS: Main: 251637760 06:30:28 INFO - PROCESS | 84811 | 06:30:28 INFO - PROCESS | 84811 | __start_tp_report 06:30:28 INFO - PROCESS | 84811 | _x_x_mozilla_page_load 06:30:28 INFO - PROCESS | 84811 | _x_x_mozilla_page_load_details 06:30:28 INFO - PROCESS | 84811 | |i|pagename|runs| 06:30:28 INFO - PROCESS | 84811 | |0;ai-astar;105;118;116;156;175;98;175;176;114;167 06:30:28 INFO - PROCESS | 84811 | |1;audio-beat-detection;137;129;142;182;99;111;144;145;168;114 06:30:28 INFO - PROCESS | 84811 | |2;audio-dft;198;203;199;206;202;200;200;227;287;200 06:30:28 INFO - PROCESS | 84811 | |3;audio-fft;84;60;62;120;85;87;85;67;88;86 06:30:28 INFO - PROCESS | 84811 | |4;audio-oscillator;93;99;117;83;69;71;81;72;71;85 06:30:28 INFO - PROCESS | 84811 | |5;imaging-gaussian-blur;80;131;132;86;88;87;91;86;87;85 06:30:28 INFO - PROCESS | 84811 | |6;imaging-darkroom;135;110;154;138;111;157;140;95;148;145 06:30:28 INFO - PROCESS | 84811 | |7;imaging-desaturate;129;144;143;96;90;98;94;91;93;126 06:30:28 INFO - PROCESS | 84811 | |8;json-parse-financial;55;61;55;59;63;62;63;64;62;62 06:30:28 INFO - PROCESS | 84811 | |9;json-stringify-tinderbox;84;70;78;112;102;102;79;70;73;72 06:30:28 INFO - PROCESS | 84811 | |10;stanford-crypto-aes;85;86;89;84;84;83;82;88;83;86 06:30:28 INFO - PROCESS | 84811 | |11;stanford-crypto-ccm;137;139;137;136;138;137;153;143;213;190 06:30:28 INFO - PROCESS | 84811 | |12;stanford-crypto-pbkdf2;169;172;170;170;169;169;168;234;171;172 06:30:28 INFO - PROCESS | 84811 | |13;stanford-crypto-sha256-iterative;66;68;66;69;81;76;67;64;79;89 06:30:28 INFO - PROCESS | 84811 | __end_tp_report 06:30:28 INFO - PROCESS | 84811 | __start_cc_report 06:30:28 INFO - PROCESS | 84811 | _x_x_mozilla_cycle_collect,981 06:30:28 INFO - PROCESS | 84811 | __end_cc_report 06:30:28 INFO - PROCESS | 84811 | __startTimestamp1488465028845__endTimestamp 06:30:28 INFO - PROCESS | 84811 | 06:30:28 INFO - PROCESS | 84811 | ------- Summary: start ------- 06:30:28 INFO - PROCESS | 84811 | Number of tests: 14 06:30:28 INFO - PROCESS | 84811 | 06:30:28 INFO - PROCESS | 84811 | [#0] ai-astar Cycles:10 Average:140.00 Median:171.00 stddev:32.41 (19.0%) stddev-sans-first:31.81 06:30:28 INFO - PROCESS | 84811 | Values: 105.0 118.0 116.0 156.0 175.0 98.0 175.0 176.0 114.0 167.0 06:30:28 INFO - PROCESS | 84811 | 06:30:28 INFO - PROCESS | 84811 | [#1] audio-beat-detection Cycles:10 Average:137.10 Median:144.50 stddev:25.47 (17.6%) stddev-sans-first:27.01 06:30:28 INFO - PROCESS | 84811 | Values: 137.0 129.0 142.0 182.0 99.0 111.0 144.0 145.0 168.0 114.0 06:30:28 INFO - PROCESS | 84811 | 06:30:28 INFO - PROCESS | 84811 | [#2] audio-dft Cycles:10 Average:212.20 Median:202.50 stddev:27.62 (13.6%) stddev-sans-first:28.81 06:30:28 INFO - PROCESS | 84811 | Values: 198.0 203.0 199.0 206.0 202.0 200.0 200.0 227.0 287.0 200.0 06:30:28 INFO - PROCESS | 84811 | 06:30:28 INFO - PROCESS | 84811 | [#3] audio-fft Cycles:10 Average:82.40 Median:85.00 stddev:17.16 (20.2%) stddev-sans-first:18.19 06:30:28 INFO - PROCESS | 84811 | Values: 84.0 60.0 62.0 120.0 85.0 87.0 85.0 67.0 88.0 86.0 06:30:28 INFO - PROCESS | 84811 | 06:30:28 INFO - PROCESS | 84811 | [#4] audio-oscillator Cycles:10 Average:84.10 Median:82.00 stddev:15.32 (18.7%) stddev-sans-first:15.91 06:30:28 INFO - PROCESS | 84811 | Values: 93.0 99.0 117.0 83.0 69.0 71.0 81.0 72.0 71.0 85.0 06:30:28 INFO - PROCESS | 84811 | 06:30:28 INFO - PROCESS | 84811 | [#5] imaging-gaussian-blur Cycles:10 Average:95.30 Median:86.50 stddev:19.28 (22.3%) stddev-sans-first:19.63 06:30:28 INFO - PROCESS | 84811 | Values: 80.0 131.0 132.0 86.0 88.0 87.0 91.0 86.0 87.0 85.0 06:30:28 INFO - PROCESS | 84811 | 06:30:28 INFO - PROCESS | 84811 | [#6] imaging-darkroom Cycles:10 Average:133.30 Median:146.50 stddev:20.87 (14.2%) stddev-sans-first:22.13 06:30:28 INFO - PROCESS | 84811 | Values: 135.0 110.0 154.0 138.0 111.0 157.0 140.0 95.0 148.0 145.0 06:30:28 INFO - PROCESS | 84811 | 06:30:28 INFO - PROCESS | 84811 | [#7] imaging-desaturate Cycles:10 Average:110.40 Median:92.00 stddev:22.38 (24.3%) stddev-sans-first:22.70 06:30:28 INFO - PROCESS | 84811 | Values: 129.0 144.0 143.0 96.0 90.0 98.0 94.0 91.0 93.0 126.0 06:30:28 INFO - PROCESS | 84811 | 06:30:28 INFO - PROCESS | 84811 | [#8] json-parse-financial Cycles:10 Average:60.60 Median:62.00 stddev:3.24 (5.2%) stddev-sans-first:2.73 06:30:28 INFO - PROCESS | 84811 | Values: 55.0 61.0 55.0 59.0 63.0 62.0 63.0 64.0 62.0 62.0 06:30:28 INFO - PROCESS | 84811 | 06:30:28 INFO - PROCESS | 84811 | [#9] json-stringify-tinderbox Cycles:10 Average:84.20 Median:72.50 stddev:15.45 (21.3%) stddev-sans-first:16.39 06:30:28 INFO - PROCESS | 84811 | Values: 84.0 70.0 78.0 112.0 102.0 102.0 79.0 70.0 73.0 72.0 06:30:28 INFO - PROCESS | 84811 | 06:30:28 INFO - PROCESS | 84811 | [#10] stanford-crypto-aes Cycles:10 Average:85.00 Median:85.50 stddev:2.26 (2.6%) stddev-sans-first:2.40 06:30:28 INFO - PROCESS | 84811 | Values: 85.0 86.0 89.0 84.0 84.0 83.0 82.0 88.0 83.0 86.0 06:30:28 INFO - PROCESS | 84811 | 06:30:28 INFO - PROCESS | 84811 | [#11] stanford-crypto-ccm Cycles:10 Average:152.30 Median:141.00 stddev:26.96 (19.1%) stddev-sans-first:28.02 06:30:28 INFO - PROCESS | 84811 | Values: 137.0 139.0 137.0 136.0 138.0 137.0 153.0 143.0 213.0 190.0 06:30:28 INFO - PROCESS | 84811 | 06:30:28 INFO - PROCESS | 84811 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.40 Median:170.50 stddev:20.28 (11.9%) stddev-sans-first:21.34 06:30:28 INFO - PROCESS | 84811 | Values: 169.0 172.0 170.0 170.0 169.0 169.0 168.0 234.0 171.0 172.0 06:30:28 INFO - PROCESS | 84811 | 06:30:28 INFO - PROCESS | 84811 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.50 Median:72.50 stddev:8.29 (11.4%) stddev-sans-first:8.45 06:30:28 INFO - PROCESS | 84811 | Values: 66.0 68.0 66.0 69.0 81.0 76.0 67.0 64.0 79.0 89.0 06:30:28 INFO - PROCESS | 84811 | -------- Summary: end -------- 06:30:28 INFO - PROCESS | 84811 | 06:30:29 INFO - TEST-INFO | 84811: exit 0 06:30:29 INFO - TEST-OK | kraken | took 62110ms 06:30:29 INFO - SUITE-END | took 1177s 06:30:29 INFO - Completed test suite (00:19:37) 06:30:29 INFO - javascript benchmark 06:30:30 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7279.853203151421, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3044.665086840447, 3362.831858407082, 3379.8817041403554, 3380.66193380662, 3380.71263942565, 5622.409646987069, 5629.690367029812, 5699.772009119635, 5724.685142317171, 5783.074708046714, 5681.318241810978, 5707.543396528278, 5733.283339582552, 5749.3388260350075, 5807.477327040566, 5213.58291336694, 5237.292965449673, 5238.4499627539135, 5268.104422248227, 5311.787528498875, 5321.068812957717, 5325.946740532603, 5328.733563321838, 5343.465653434664, 5368.248445217672, 5270.446603106676, 5309.203619457093, 5312.760925758331, 5327.360716713981, 5378.435264297251, 5202.375714914197, 5207.427183009866, 5255.973720131375, 5281.970015846911, 5297.788088476456, 5173.482651734834, 5261.079311120577, 5318.0, 5324.54741346986, 5335.893282134374, 5737.282839645044, 5848.561357898175, 5852.970735146339, 5857.648541087548, 5864.648121112747, 6113.841195312643, 6154.78458253959, 6166.321704612489, 6285.622862628257, 6292.33930437302, 6191.288001879751, 6194.96902515489, 6238.033104868752, 6265.373462653698, 6303.212098487689, 5910.202122713422, 5926.348101708809, 5931.673757943355, 5934.5845790794665, 5953.910691339634, 6116.914073580187, 6291.748330066746, 6298.496120310364, 6311.779087731999, 6314.179156709598, 6063.363346848606, 6112.755489780359, 6116.327204007555, 6130.7650488535955, 6243.469305109116, 6246.968765156236, 6296.590721603173, 6300.21247344082, 6312.0, 6315.368463153648, 6174.722137503823, 6273.18448601688, 6293.30773614902, 6296.118543403927, 6310.337414571496, 6109.95128740435, 6144.556029333103, 6189.81430557084, 6211.596246243979, 6303.086052522451, 4928.211486162197, 4982.153033984231, 5002.474740152305, 5062.721550314768, 5101.260317254051, 3549.290141971616, 4175.498940127143, 4475.641948644101, 4490.820367185276, 4520.005598768265, 5851.824445266649, 5878.735456904449, 5916.526677865761, 5916.822495325147, 5936.436038576328, 6162.168107305546, 6171.907421388682, 6175.073738939195, 6214.1921550198185, 6256.655883926428, 4895.192293271642, 5899.911501327483, 5904.793332233351, 5914.319853216849, 5960.940390596125, 4671.27595222741, 4711.717296962193, 5524.723763811793, 5534.861628459321, 5606.579506536945, 6218.098375735493, 6282.591631543935, 6292.874142517033, 6295.055741638791, 6365.777197798055, 6251.593646412968, 6278.121063051268, 6286.654234017173, 6298.0867774171575, 6311.684415779285, 6270.686465676789, 6379.93620063812, 6384.9042264367, 6400.9039864403, 6411.294757576569, 5062.544371006627, 6127.036821038878, 6135.417135372132, 6257.56197066201, 6298.685065746603, 5249.238860365302, 6160.9383906160365, 6201.317855036033, 6286.497080233479, 6299.811005669837, 5069.6197785167, 6177.444030037318, 6180.351063138306, 6197.132401463708, 6201.627902325875, 6004.339522652592, 6088.847778805388, 6129.785457508966, 6160.507159427325, 6204.534659900616, 5903.911441328298, 5919.7632094717455, 5954.523638108854, 6055.636661800306, 6068.120122582289, 6159.648020113036, 6180.289266734383, 6192.938070619236, 6202.844928876814, 6203.751849925952, 5721.57088218377, 5868.94131058684, 5918.644881307136, 5960.880782384242, 6074.120634350488], "value": 5712.370189905583, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [453.89929421153704, 471.32835709114505, 496.34978178586056, 510.8161062017673, 526.8472143078508, 33753.66246337536, 39374.80312598436, 40994.795026024825, 41038.38442423366, 41076.178476430454, 43125.137497250114, 43618.7819060905, 43673.0, 43961.681149565484, 44006.339904901455, 8305.584720763967, 8525.232729054384, 9214.677486287981, 9459.810803783921, 9474.905250947506, 11059.391733454657, 11297.49161287744, 11364.31814091153, 11387.0, 11451.083913286959, 11346.262492937974, 11383.48774305154, 11418.371989540572, 11422.71443213922, 11436.2566433182, 11401.429928503583, 11457.885421145766, 11479.196456248066, 11495.482703278372, 11506.482208300647, 11317.83023254652, 11364.545418183263, 11384.487698053608, 11388.943055284753, 11403.258788178824, 40279.0, 40506.594934050576, 40508.98727531806, 40633.39049914254, 40701.59298407008, 3881.9223615527812, 3942.7240093193486, 3974.1455587048636, 3995.800209989489, 4039.091204478969, 29844.403111937856, 29912.40175196484, 30792.076237712903, 32224.0, 32488.86288979898, 3130.9530357044664, 3148.134263077649, 3275.0, 3386.6782655647676, 3397.3205358928312, 439922.800385996, 442638.0, 444678.0, 446250.0, 448373.0, 69114.96327555091, 70009.0, 70504.64747676229, 70593.0, 70685.64657176682, 52439.0, 60136.69931650314, 73796.26203738002, 74455.62772186105, 75064.0, 17205.91397043007, 17247.827521724874, 17389.826101739072, 17394.21726022332, 17455.825441745674, 10509.684709458728, 10832.729181770519, 12891.935540322238, 13012.21926684402, 13119.737605247841, 21368.893155534435, 22005.66991495129, 22122.668159977613, 22248.555028899656, 22603.773962260497, 8086.7169649062, 8850.645974160961, 9117.854340252603, 9222.584983675752, 9232.21526170283, 850.6342272822745, 861.1732736572835, 864.7838040489878, 873.9868901966476, 878.4070752242211, 29637.85181074081, 30069.0, 30190.69809301923, 30808.537871931938, 30998.22504437362, 1001.7595777013464, 1028.1928685981468, 1053.3837704942541, 1071.8177909755361, 1129.2377645089532, 11809.586664466702, 15704.214789260492, 15709.607259818367, 15771.44799931997, 16059.598510037109, 638589.0, 640619.0, 647209.0, 647758.0, 649953.0, 59732.70133649304, 60036.39963600308, 60277.09584356238, 60439.69780151071, 60591.0, 1386.7711827548544, 1401.4394242302956, 1425.5794540610545, 1426.8287805463203, 1431.4632012995128, 24162.033518659056, 27666.861665691544, 29012.419751604426, 29474.557881631365, 30177.0, 42271.7886410566, 51638.0, 51750.482495176075, 51772.74113629558, 51878.22182667188, 453628.0, 455082.0, 458473.4152658564, 458895.0, 459732.0, 25721.871390642926, 26396.0761373351, 26461.33846653849, 27292.863535682194, 43594.0, 41185.176296473306, 41705.58294417139, 41966.79016604897, 42250.0, 42666.7199984001, 34605.30789384249, 34656.65343346534, 34673.826630866686, 34819.82590087033, 35616.82191589129, 34618.65381346255, 34801.12997175091, 35865.82067089648, 35997.64002359943, 36096.0, 33735.66264337325, 33932.49101263434, 34168.0, 34465.655343447244, 34697.8265108683, 30003.949861754732, 30914.536281955337, 31001.8449907749, 31146.0, 31299.06102816919, 22982.770172298064, 23077.53844923126, 23379.181728639414, 23467.76532234724, 23474.530509390057], "value": 20213.581338379656, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [392.3565352821374, 395.8495771606789, 400.2255635345606, 405.533636318234, 424.27237288050986, 15898.84101158988, 19999.400017999447, 20370.0, 21324.680129798046, 21360.038798254078, 20727.0, 21234.938253087323, 21258.89370553149, 21379.358619241408, 21404.14383424665, 5059.898802023967, 5333.306670132877, 5382.6232163748555, 5413.891722165555, 5452.509274165324, 5175.767090480937, 5346.705931173784, 5377.65045272058, 5385.3537575491, 5407.351117865842, 4555.74943378114, 5341.4925582069645, 5386.138217885139, 5414.972925135388, 5422.75597598109, 5367.409584945653, 5392.406835248159, 5408.0, 5413.106837371849, 5775.797847075374, 4538.909221815546, 5251.238570407308, 5283.418823929364, 5285.286486324336, 5330.946690533084, 13549.39027743744, 13720.245386503739, 24709.752902471104, 25697.0, 25775.968961241528, 2574.613807928807, 2587.107447930461, 2589.4821035792916, 2627.5139099266507, 2647.8676066196804, 10060.446675432848, 10132.138768204637, 10620.628278010232, 12612.432440540199, 14654.56036318912, 1805.250820909321, 1806.7470554122433, 1828.7988321284647, 1829.231722676479, 1838.9448316550524, 121244.0, 122085.0, 124462.9999999991, 128065.7193428063, 132887.0, 10846.91530846909, 11624.825627615592, 11669.59156429521, 12196.085293603015, 12225.755484890253, 15596.376144954302, 16046.358145674276, 16125.951813132344, 16151.273192706356, 16166.43417480406, 8818.735437936872, 8939.524978378624, 8942.86585701215, 8948.418352807046, 9090.681826136186, 8652.56737163152, 8665.43674661145, 8669.826603467895, 8818.95590522056, 8841.336899732418, 3944.7041471889725, 4127.215828992542, 4853.927191092136, 4884.072026314989, 4885.902281954341, 2012.909419076116, 2060.2377120465526, 2062.7215325931106, 2064.3910046536307, 2069.317125348631, 1026.3636545341935, 1034.9482525873675, 1051.9375430814932, 1054.7099547624464, 1065.2170654568886, 19928.60142797136, 21165.047572859257, 21483.0, 21535.67696484554, 21726.51288524031, 1042.4058286776617, 1052.8035971614781, 1056.5932116135343, 1074.6883403812806, 1078.8327809189589, 10574.84137737919, 11232.932871377207, 11241.66275011751, 11273.94363028196, 11278.661640150809, 716676.0, 728830.0, 737854.0, 739822.0, 772366.0, 93558.53220733853, 133026.33486832504, 133560.0, 134077.0, 138636.0, 973.0464145137663, 993.5975929748463, 1004.8442491413842, 1011.0748664971616, 1013.3565186106875, 18386.632267354846, 18619.06904654735, 19015.383692386302, 19046.428607141806, 19128.191133053482, 48648.75675621599, 54624.361269161986, 57120.0, 57954.710226448595, 59516.702416489374, 79896.60051699704, 80698.0, 82044.58977705073, 83031.16968830234, 83984.74022889539, 7608.087029556267, 7628.237176282328, 7885.329746971572, 8046.436749427484, 8551.615177316911, 12441.315727634705, 12491.812622810483, 13039.022073344728, 13524.661883452993, 13844.72310553763, 11058.668239952814, 11082.61210857616, 11521.078313734713, 11543.134264929995, 11657.475413606238, 8771.429857059271, 9634.951825240829, 11276.379799111128, 11601.5359385627, 11616.651500455, 9763.951180244054, 11133.498992545194, 11630.953214210762, 11658.88341116578, 11755.05959523219, 8301.58492075406, 8420.248669612545, 8477.872831907403, 8706.521141337287, 8790.648374065222, 8313.043999940084, 10172.338797978107, 10796.244262901522, 10980.176486763685, 11155.553777848796], "value": 9719.27374658928, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1205.9577914772985, 1325.1651459580462, 1441.769316909294, 1452.3682198243762, 1479.9408023679052, 7605.0, 7873.055233372003, 8157.551334676597, 8586.09845966174, 8590.055093939669, 8126.1873812618705, 8323.334133269324, 8388.916110838889, 8464.788380290496, 8512.446690965098, 1983.206717313072, 2032.7154198412234, 2284.18911286493, 2332.9385129766, 2347.084636991575, 2086.6139764143604, 2172.0334451169238, 2321.6401457774073, 2331.522037982218, 2361.3506285771377, 2259.8757068361233, 2336.2290444153386, 2337.4273303040695, 2349.8472599281076, 2357.3399448154546, 2006.9698954515693, 2300.7354154272307, 2321.5124823787023, 2325.523292709142, 2347.471818840764, 2300.6779050932882, 2309.307207837642, 2364.0, 2368.751281115475, 2371.893264803088, 7887.842243155104, 7894.328982036476, 8476.957615211946, 8549.358798090107, 8558.400911936167, 5085.567726743231, 5231.398389185254, 5245.737713114329, 5251.6061295403, 5266.9992701387, 3324.6010478742705, 3531.841067151984, 3592.982035089834, 3597.0288022234113, 3605.080704420356, 1026.6663334416344, 1186.35936594239, 1211.7939950208486, 1270.1299609767248, 1272.8281681972908, 10840.67477975654, 10930.398828064453, 10977.615783447622, 11005.394703291317, 11022.724431889186, 4595.0809838031855, 4691.812327506861, 4702.31816386622, 4765.904681906342, 4768.856934291976, 9493.810123797486, 9626.9037309626, 9652.179564737076, 9656.662016829378, 9659.178969787507, 5203.505666961708, 5279.709615971158, 5287.4448182941005, 5298.841034768963, 5301.204819277063, 4041.454403655469, 4610.870336767513, 4820.927686084711, 4831.4202295724735, 4843.321934929113, 3877.3408520551575, 3933.4099885017476, 3936.1340505088833, 3981.7411868228473, 4035.1929614077303, 1182.2138278045004, 1229.8708635593314, 1258.704204511939, 1289.7291568770477, 1296.2222666400085, 740.7370383513883, 845.2815107158989, 845.6828689241535, 856.0839901305543, 863.6329559937002, 5182.922256166161, 5250.921236181461, 5301.787928482818, 5435.130379139318, 5558.221848941152, 962.3840741925173, 973.1387721866058, 980.6910823090847, 987.4914419074182, 997.6258902911409, 3625.2386998730035, 3647.8905632831056, 3683.950074228832, 3747.1194269346684, 3767.585565587783, 9693.41839489633, 10039.4478303694, 10047.899521004843, 10083.445410502347, 10148.188144948386, 10075.949620251851, 10079.445630490394, 10095.44475053879, 10114.848277275847, 10120.595176193017, 7939.3251573615735, 7989.480683755537, 8006.639701213459, 8016.679332826739, 8029.397795165386, 3688.6495782900583, 4665.743384113906, 4862.635302352267, 5377.838664840061, 5439.972800136133, 7412.444066695128, 9203.263738901005, 9281.886173659204, 9285.117913798178, 9348.439093654402, 7740.7677769667, 9406.0, 9491.905080949102, 9566.091221333962, 9642.42145471274, 7237.1677257114, 7280.1991780903, 7305.817354565966, 7333.413326933939, 7387.372073373824, 6758.898616520658, 7845.882311765214, 7859.371250300077, 7929.960350198212, 7949.046114466301, 5972.970135149297, 7207.387372073433, 7231.674574644048, 7248.746293879689, 7335.596542190231, 6158.507319414526, 7101.183363913216, 7114.57312561248, 7167.749128780468, 7194.0, 6391.009393543822, 6452.677366131581, 6528.347165283433, 6538.921078022073, 6549.31232220629, 4923.926141107814, 4928.334674818853, 4982.701037937735, 4988.376452943382, 5005.599552035901, 4892.217245240744, 4903.877403064952, 4917.139500587455, 5048.469910659474, 5061.316722242597], "value": 4394.604436809982, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [305.52185829177336, 352.8385664446559, 359.07538089419745, 367.4543303194754, 371.4205838891331, 5123.743812809364, 5311.778290993069, 5340.893182136365, 5401.756920938557, 5446.945530544698, 4201.663866890649, 5267.973660131694, 5314.997026679923, 5327.440618735035, 5339.172428273614, 3382.52644629752, 3995.740276882008, 4118.361653943629, 4187.0, 4241.533431322552, 3690.5940346561856, 3821.197548514815, 4209.73707887634, 4234.830606775725, 4241.0, 3481.129717570607, 3528.752987290891, 4010.418489319047, 4024.134811015631, 4041.6766658667234, 3076.8461576921063, 3708.870189543353, 3955.6044395560502, 4058.7767672777986, 4072.7759973201464, 2983.686712895158, 3206.294615184656, 3338.549295845054, 3537.6285490023433, 3545.2377738786286, 4028.154087641599, 4220.662347012231, 5149.2791009258735, 5309.256704061435, 5326.44072372403, 3874.8062596870045, 4623.722576645412, 4639.559241872017, 4652.371929789469, 4682.367880336179, 5248.580113590903, 5481.808136715235, 5607.214989901417, 5638.633488823213, 5653.689047102408, 3585.713142948558, 3797.9680870734023, 3844.519435070616, 3884.8050851268945, 3924.9018774530578, 4798.496157903405, 5891.988533644992, 5894.997850365448, 5896.351401345849, 5938.673372964486, 3404.744644151649, 3678.5769636492146, 3858.1897801461446, 3911.393733971239, 3924.3917192835156, 3852.2295540891932, 3931.68546516278, 3942.586028467027, 3942.7634341939574, 3951.7431366961055, 4521.784325680393, 4566.20091483989, 4578.2903649934315, 4621.537846215351, 4628.39830821991, 4977.651564390458, 5735.827925162168, 5736.340320863071, 5758.712064396764, 5783.71081445926, 1141.4692168141687, 1223.6267938278731, 1244.9377531123407, 1262.2895975952636, 1294.9482020719065, 814.1532805881935, 837.1837458477935, 856.9288389513108, 865.9783505412289, 878.3544095090103, 4377.708575970097, 4391.089875903992, 4790.5448982346625, 5080.132447023693, 5135.281060651511, 4549.5905368517, 4811.381074168768, 5462.754176062007, 5571.749271282802, 5644.491995720323, 4334.934975975363, 4432.534583868647, 4760.01861625756, 5011.4236862761245, 5065.1005872797705, 2784.693683694792, 2898.4637841998874, 2898.695636958089, 2903.796734228626, 2958.600588920468, 5538.972305138529, 5729.968605651023, 5767.250257466587, 5768.221290125779, 5798.739056742456, 4583.106294272585, 4789.640776941674, 5195.012787723827, 5790.36306006339, 5809.398173132685, 4882.022780773792, 4894.608431325485, 5225.451327610622, 5904.3210030847, 5905.0141154720395, 4970.900581988412, 5505.504504594605, 5533.53757577874, 5606.775728970795, 5625.69058701759, 4149.004238982683, 4662.254039353687, 4969.763606377217, 5444.26502422168, 5540.750666219949, 5386.885884521019, 5711.343195532567, 5884.705764711662, 5907.527397808251, 5912.70436478183, 3605.0987253186704, 3626.7461277711363, 3726.5963453572836, 3732.2722069196248, 3751.2309976454217, 5260.526552610283, 5564.610477266552, 5565.499105080562, 5604.159376093618, 5649.548036157015, 4625.363542780583, 4650.023984649759, 5284.313039304866, 5515.104570100729, 5593.272874526286, 5102.591792656504, 5254.710990895537, 5294.708791016377, 5641.5431808870535, 5764.818212266391, 5022.59367377135, 5261.764279754681, 5433.293671822796, 5442.509929308416, 5521.779128834802, 5396.785723212402, 5428.7014214217015, 5670.419958918765, 5671.200295801905, 5738.311402631711, 4928.6057115431395, 5574.331080270394, 5581.748821303132, 5599.944000560105, 5605.635633683797], "value": 4023.9620676888103, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [710.2081179484876, 710.318094629156, 717.9928200717991, 738.7857521318814, 739.0, 15137.470188543402, 15165.0, 15511.999999999985, 15541.611459713513, 15571.22143892805, 14003.649908752286, 15332.386704531806, 15852.3659053638, 15940.601484962881, 16329.0, 682.2427105912458, 844.4089137603687, 865.9523726195057, 866.4627930682985, 867.0679020053436, 684.6474065856088, 835.7826964989116, 851.8424091543053, 868.1275318305095, 870.2081106193365, 909.849874770662, 911.0, 913.4747520175893, 913.5112714697638, 914.4787471141452, 894.2041582991143, 902.120432578231, 905.2169873059756, 907.7004588485817, 909.4634165842185, 663.0352836622751, 670.2426258328059, 674.5379415100672, 675.5507587454337, 675.6858061001609, 14891.32989015486, 15254.618634534225, 15333.233338333039, 15355.4625588105, 15379.307931143014, 13923.58229253124, 14268.85731142696, 14325.355359008765, 14331.140131592138, 14384.712305753827, 10095.687560617072, 11535.25020873649, 11758.470868810924, 11763.529458821637, 12236.81644775329, 3343.648916863743, 3538.575370955502, 3653.5250417445563, 3653.8173091345325, 3683.963160368389, 108010.0, 108675.82648347068, 109517.0, 110715.89284107217, 111140.444297778, 10418.791624167474, 10428.270021098602, 10570.41862697559, 10906.61826836057, 11059.612913547988, 21491.462713431978, 21682.674759878613, 21752.782472175393, 21872.343829684818, 21940.670889936664, 7516.812079697942, 7523.0972283326355, 7524.548527088392, 7600.2779735925, 7752.0, 4139.730917490354, 4159.209750147463, 4238.978805105955, 4243.469566304212, 4282.293421585466, 3499.702525285328, 3552.0764601203873, 3581.534400527915, 3590.6768390844427, 3609.4224924012033, 627.8179327994831, 639.4181295021508, 641.4323323858418, 644.9129367535322, 658.8912829383194, 634.1850721822436, 642.6240649220165, 647.2427260105687, 673.7675501951917, 691.2568441887225, 9631.181349585364, 10264.0, 10308.32995855267, 10359.067683908484, 10503.894961050444, 633.5850018238061, 664.973401063952, 680.2551206428952, 689.582802404548, 690.6960937187722, 4437.866863994085, 4961.330220420269, 5009.7495125243595, 5015.974920125376, 5016.849494515171, 89095.55452222827, 103565.96434035563, 106220.46889765657, 109507.0, 109583.35624965632, 75685.48629027388, 95692.0430795697, 98098.01901980888, 99701.50149249352, 99909.50045249728, 71230.93153602803, 79678.60160699276, 82617.58691206506, 84089.57955210184, 84099.5795021021, 9724.659636912675, 9929.907710152022, 10523.263371563917, 10565.63020294286, 10897.291676041032, 47539.52460475351, 48747.75626121847, 51103.48896510987, 51523.48476515187, 54018.729906350214, 67403.66298168477, 68015.65992170008, 68892.0, 70250.0, 71046.0, 12566.308853013172, 12603.432845521791, 12896.032797540034, 12938.223706577635, 13173.934130329288, 13915.72168556603, 14159.787603185754, 14213.715725685222, 14424.711505770036, 14522.0, 13144.868551314365, 13450.26023568713, 13452.596422107352, 13512.662183445493, 13545.355114842372, 12628.936855316033, 13075.346232688518, 13105.045064739983, 13354.0, 13397.933010334886, 12783.360831958551, 12855.100946935963, 13315.0, 13566.457341706222, 13803.585892423243, 10542.789144217226, 10775.730606734895, 10925.672229833117, 10930.508127134139, 11163.051140653135, 9297.442153470813, 9598.808023839345, 9787.463652987302, 10911.236213464981, 10919.727006824893], "value": 7499.282868631083, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1625.8000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 118.0, 116.0, 156.0, 175.0, 98.0, 175.0, 176.0, 114.0, 167.0], "value": 140.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 129.0, 142.0, 182.0, 99.0, 111.0, 144.0, 145.0, 168.0, 114.0], "value": 137.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 203.0, 199.0, 206.0, 202.0, 200.0, 200.0, 227.0, 287.0, 200.0], "value": 212.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 60.0, 62.0, 120.0, 85.0, 87.0, 85.0, 67.0, 88.0, 86.0], "value": 82.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 99.0, 117.0, 83.0, 69.0, 71.0, 81.0, 72.0, 71.0, 85.0], "value": 84.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 131.0, 132.0, 86.0, 88.0, 87.0, 91.0, 86.0, 87.0, 85.0], "value": 95.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 110.0, 154.0, 138.0, 111.0, 157.0, 140.0, 95.0, 148.0, 145.0], "value": 133.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 144.0, 143.0, 96.0, 90.0, 98.0, 94.0, 91.0, 93.0, 126.0], "value": 110.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 61.0, 55.0, 59.0, 63.0, 62.0, 63.0, 64.0, 62.0, 62.0], "value": 60.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 70.0, 78.0, 112.0, 102.0, 102.0, 79.0, 70.0, 73.0, 72.0], "value": 84.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 89.0, 84.0, 84.0, 83.0, 82.0, 88.0, 83.0, 86.0], "value": 85.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 139.0, 137.0, 136.0, 138.0, 137.0, 153.0, 143.0, 213.0, 190.0], "value": 152.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 172.0, 170.0, 170.0, 169.0, 169.0, 168.0, 234.0, 171.0, 172.0], "value": 176.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 66.0, 69.0, 81.0, 76.0, 67.0, 64.0, 79.0, 89.0], "value": 72.5, "unit": "score"}]}]} 06:30:30 INFO - Return code: 0 06:30:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:30:30 ERROR - # TBPL SUCCESS # 06:30:30 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:30:30 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:30:30 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:30:30 INFO - 2017-03-02 06:30:30.139 screenresolution[84817:721479] starting screenresolution argv=screenresolution get 06:30:30 INFO - 2017-03-02 06:30:30.142 screenresolution[84817:721479] Display 0: 1600x1200x32@60 06:30:30 INFO - 2017-03-02 06:30:30.149 screenresolution[84818:721482] starting screenresolution argv=screenresolution list 06:30:30 INFO - Available Modes on Display 0 06:30:30 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:30:30 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:30:30 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:30:30 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:30:30 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:30:30 INFO - Intel Iris: 06:30:30 INFO - Chipset Model: Intel Iris 06:30:30 INFO - Type: GPU 06:30:30 INFO - Bus: Built-In 06:30:30 INFO - VRAM (Dynamic, Max): 1536 MB 06:30:30 INFO - Vendor: Intel (0x8086) 06:30:30 INFO - Device ID: 0x0a2e 06:30:30 INFO - Revision ID: 0x0009 06:30:30 INFO - Displays: 06:30:30 INFO - Display: 06:30:30 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:30:30 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:30:30 INFO - Display Serial Number: 1600x1200 60 06:30:30 INFO - Main Display: Yes 06:30:30 INFO - Mirror: Off 06:30:30 INFO - Online: Yes 06:30:30 INFO - Rotation: Supported 06:30:30 INFO - Return code: 0 06:30:30 INFO - Running post-action listener: _resource_record_post_action 06:30:30 INFO - [mozharness: 2017-03-02 14:30:30.398325Z] Finished run-tests step (success) 06:30:30 INFO - Running post-run listener: _resource_record_post_run 06:30:30 INFO - Total resource usage - Wall time: 1198s; CPU: 54.0%; Read bytes: 8225792; Write bytes: 513175040; Read time: 73; Write time: 2844 06:30:30 INFO - TinderboxPrint: CPU usage
54.5% 06:30:30 INFO - TinderboxPrint: I/O read bytes / time
8,225,792 / 73 06:30:30 INFO - TinderboxPrint: I/O write bytes / time
513,175,040 / 2,844 06:30:30 INFO - TinderboxPrint: CPU idle
2,183.3 (45.5%) 06:30:30 INFO - TinderboxPrint: CPU system
963.4 (20.1%) 06:30:30 INFO - TinderboxPrint: CPU user
1,647.1 (34.4%) 06:30:30 INFO - TinderboxPrint: Swap in / out
224,567,296 / 0 06:30:30 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 195787264; Write bytes: 190456320; Read time: 14822; Write time: 363 06:30:30 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 8193024; Write bytes: 302246912; Read time: 73; Write time: 2423 06:30:30 INFO - Running post-run listener: _upload_blobber_files 06:30:30 INFO - Blob upload gear active. 06:30:30 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:30:30 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 06:30:30 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'] 06:30:30 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 06:30:31 INFO - (blobuploader) - INFO - Open directory for files ... 06:30:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 06:30:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:30:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:30:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 06:30:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:30:32 INFO - (blobuploader) - INFO - Done attempting. 06:30:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 06:30:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:30:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:30:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 06:30:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:30:32 INFO - (blobuploader) - INFO - Done attempting. 06:30:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 06:30:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:30:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:30:33 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 06:30:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:30:33 INFO - (blobuploader) - INFO - Done attempting. 06:30:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:30:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:30:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:30:36 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:30:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:30:36 INFO - (blobuploader) - INFO - Done attempting. 06:30:36 INFO - (blobuploader) - INFO - Iteration through files over. 06:30:36 INFO - Return code: 0 06:30:36 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:30:36 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:30:36 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7e243950975d2092db1a138b07cc9a67eec4e66c31299ad080cd6004478e5812059fe299747a122b00216512f6d9d8e35888d828822760ba4b65a7204c883c7e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ef43e8e5f693970e103c2e4f6dfd1922ad2d49ceb899e53d30b0fc324a13c19681f49b6293b13528ea1661dbab076bdec50382411a2c2bb2192c1dd439944850", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9f7d75f07ea419c244d8b03ff81b8ac37979e4c908b65abac2627e2a6ee0e6bc82134fa54ebca4f986c0618883a0e120780ceac328b179d3712ba7d93de3b88f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2078bc0ce5b017336d5fe30428a2f7360898634ea57b3c329c9e0fa2b13b685cfe60533d923471c526a58f29cc68e127bd91ed8b0647571f30a591f21e5484c4"} 06:30:36 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:30:36 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:30:36 INFO - Contents: 06:30:36 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7e243950975d2092db1a138b07cc9a67eec4e66c31299ad080cd6004478e5812059fe299747a122b00216512f6d9d8e35888d828822760ba4b65a7204c883c7e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ef43e8e5f693970e103c2e4f6dfd1922ad2d49ceb899e53d30b0fc324a13c19681f49b6293b13528ea1661dbab076bdec50382411a2c2bb2192c1dd439944850", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9f7d75f07ea419c244d8b03ff81b8ac37979e4c908b65abac2627e2a6ee0e6bc82134fa54ebca4f986c0618883a0e120780ceac328b179d3712ba7d93de3b88f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2078bc0ce5b017336d5fe30428a2f7360898634ea57b3c329c9e0fa2b13b685cfe60533d923471c526a58f29cc68e127bd91ed8b0647571f30a591f21e5484c4"} 06:30:36 INFO - Running post-run listener: copy_logs_to_upload_dir 06:30:36 INFO - Copying logs to upload dir... 06:30:36 INFO - mkdir: /builds/slave/test/build/upload/logs 06:30:36 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1257.826040 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2017-03-02 06:30:36.870251) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-02 06:30:36.873580) ========= 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.v6PR58sQGc/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.Bx0qRRe4On/Listeners TMPDIR=/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/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/7e243950975d2092db1a138b07cc9a67eec4e66c31299ad080cd6004478e5812059fe299747a122b00216512f6d9d8e35888d828822760ba4b65a7204c883c7e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ef43e8e5f693970e103c2e4f6dfd1922ad2d49ceb899e53d30b0fc324a13c19681f49b6293b13528ea1661dbab076bdec50382411a2c2bb2192c1dd439944850", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9f7d75f07ea419c244d8b03ff81b8ac37979e4c908b65abac2627e2a6ee0e6bc82134fa54ebca4f986c0618883a0e120780ceac328b179d3712ba7d93de3b88f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2078bc0ce5b017336d5fe30428a2f7360898634ea57b3c329c9e0fa2b13b685cfe60533d923471c526a58f29cc68e127bd91ed8b0647571f30a591f21e5484c4"} build_url:https://queue.taskcluster.net/v1/task/DUql8zwqT32G_WHM2Y8DMQ/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011377 build_url: 'https://queue.taskcluster.net/v1/task/DUql8zwqT32G_WHM2Y8DMQ/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7e243950975d2092db1a138b07cc9a67eec4e66c31299ad080cd6004478e5812059fe299747a122b00216512f6d9d8e35888d828822760ba4b65a7204c883c7e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ef43e8e5f693970e103c2e4f6dfd1922ad2d49ceb899e53d30b0fc324a13c19681f49b6293b13528ea1661dbab076bdec50382411a2c2bb2192c1dd439944850", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9f7d75f07ea419c244d8b03ff81b8ac37979e4c908b65abac2627e2a6ee0e6bc82134fa54ebca4f986c0618883a0e120780ceac328b179d3712ba7d93de3b88f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2078bc0ce5b017336d5fe30428a2f7360898634ea57b3c329c9e0fa2b13b685cfe60533d923471c526a58f29cc68e127bd91ed8b0647571f30a591f21e5484c4"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-02 06:30:36.911415) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-02 06:30:36.911823) ========= 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.v6PR58sQGc/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.Bx0qRRe4On/Listeners TMPDIR=/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/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.005271 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-02 06:30:36.961397) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-02 06:30:36.961787) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-02 06:30:36.962119) ========= ========= Total master_lag: 0.19 =========