builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0063 starttime: 1487525220.38 results: success (0) buildid: 20170219085119 builduid: 7c062e890ff24841ab79a589c67ed2fc revision: 2ddf71e1526a67d4d3f0166fd17f18dbcb223c2c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-19 09:27:00.382383) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-19 09:27:00.382860) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-19 09:27:00.383137) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-19 09:27:00.410712) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-19 09:27:00.411035) ========= 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.uRZMCxLsJg/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.XLMlVdtSuY/Listeners TMPDIR=/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/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-02-19 09:27:00-- 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% 2.07M=0.006s 2017-02-19 09:27:00 (2.07 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.206758 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-19 09:27:00.664375) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-19 09:27:00.664944) ========= 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.uRZMCxLsJg/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.XLMlVdtSuY/Listeners TMPDIR=/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/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.027618 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-19 09:27:00.727453) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-19 09:27:00.727786) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 2ddf71e1526a67d4d3f0166fd17f18dbcb223c2c --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 2ddf71e1526a67d4d3f0166fd17f18dbcb223c2c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.uRZMCxLsJg/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.XLMlVdtSuY/Listeners TMPDIR=/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/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-02-19 09:27:00,835 truncating revision to first 12 chars 2017-02-19 09:27:00,835 Setting DEBUG logging. 2017-02-19 09:27:00,835 attempt 1/10 2017-02-19 09:27:00,835 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/2ddf71e1526a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-19 09:27:01,267 unpacking tar archive at: mozilla-aurora-2ddf71e1526a/testing/mozharness/ program finished with exit code 0 elapsedTime=0.699936 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-19 09:27:01.454207) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-19 09:27:01.454513) ========= script_repo_revision: 2ddf71e1526a67d4d3f0166fd17f18dbcb223c2c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-19 09:27:01.454992) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-19 09:27:01.455352) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-19 09:27:01.478016) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2017-02-19 09:27:01.478429) ========= /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.uRZMCxLsJg/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.XLMlVdtSuY/Listeners TMPDIR=/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/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 09:27:01 INFO - MultiFileLogger online at 20170219 09:27:01 in /builds/slave/test 09:27:01 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 09:27:01 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:27:01 INFO - {'append_to_log': False, 09:27:01 INFO - 'base_work_dir': '/builds/slave/test', 09:27:01 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:27:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:27:01 INFO - 'branch': 'Mozilla-Aurora', 09:27:01 INFO - 'buildbot_json_path': 'buildprops.json', 09:27:01 INFO - 'config_files': ('talos/mac_config.py',), 09:27:01 INFO - 'default_actions': ('clobber', 09:27:01 INFO - 'read-buildbot-config', 09:27:01 INFO - 'download-and-extract', 09:27:01 INFO - 'populate-webroot', 09:27:01 INFO - 'create-virtualenv', 09:27:01 INFO - 'install', 09:27:01 INFO - 'run-tests'), 09:27:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:27:01 INFO - 'download_minidump_stackwalk': True, 09:27:01 INFO - 'download_symbols': 'ondemand', 09:27:01 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:27:01 INFO - 'tooltool.py': '/tools/tooltool.py', 09:27:01 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:27:01 INFO - '/tools/misc-python/virtualenv.py')}, 09:27:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:27:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:27:01 INFO - 'installer_path': 'installer.exe', 09:27:01 INFO - 'log_level': 'info', 09:27:01 INFO - 'log_name': 'talos', 09:27:01 INFO - 'log_to_console': True, 09:27:01 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:27:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:27:01 INFO - 'opt_config_files': (), 09:27:01 INFO - 'pip_index': False, 09:27:01 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:27:01 INFO - 'cmd': ('bash', 09:27:01 INFO - '-c', 09:27:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:27:01 INFO - 'enabled': True, 09:27:01 INFO - 'halt_on_failure': False, 09:27:01 INFO - 'name': 'check_screen_resolution'},), 09:27:01 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:27:01 INFO - 'cmd': ('bash', 09:27:01 INFO - '-c', 09:27:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:27:01 INFO - 'enabled': True, 09:27:01 INFO - 'halt_on_failure': False, 09:27:01 INFO - 'name': 'check_screen_resolution'},), 09:27:01 INFO - 'run_cmd_checks_enabled': True, 09:27:01 INFO - 'sps_profile': False, 09:27:01 INFO - 'sps_profile_interval': 0, 09:27:01 INFO - 'suite': 'dromaeojs-e10s', 09:27:01 INFO - 'system_bits': '32', 09:27:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:27:01 INFO - 'title': 't-yosemite-r7-0063', 09:27:01 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:27:01 INFO - 'use_talos_json': True, 09:27:01 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:27:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:27:01 INFO - 'work_dir': 'build'} 09:27:01 INFO - [mozharness: 2017-02-19 17:27:01.696621Z] Running clobber step. 09:27:01 INFO - Running pre-action listener: _resource_record_pre_action 09:27:01 INFO - Running main action method: clobber 09:27:01 INFO - rmtree: /builds/slave/test/build 09:27:01 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:27:04 INFO - Running post-action listener: _resource_record_post_action 09:27:04 INFO - [mozharness: 2017-02-19 17:27:04.162687Z] Finished clobber step (success) 09:27:04 INFO - [mozharness: 2017-02-19 17:27:04.162816Z] Running read-buildbot-config step. 09:27:04 INFO - Running pre-action listener: _resource_record_pre_action 09:27:04 INFO - Running main action method: read_buildbot_config 09:27:04 INFO - Using buildbot properties: 09:27:04 INFO - { 09:27:04 INFO - "project": "", 09:27:04 INFO - "product": "firefox", 09:27:04 INFO - "script_repo_revision": "production", 09:27:04 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 09:27:04 INFO - "repository": "", 09:27:04 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 09:27:04 INFO - "buildid": "20170219085119", 09:27:04 INFO - "pgo_build": "False", 09:27:04 INFO - "basedir": "/builds/slave/test", 09:27:04 INFO - "buildnumber": 6, 09:27:04 INFO - "slavename": "t-yosemite-r7-0063", 09:27:04 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 09:27:04 INFO - "platform": "yosemite_r7", 09:27:04 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 09:27:04 INFO - "branch": "mozilla-aurora", 09:27:04 INFO - "repo_path": "releases/mozilla-aurora", 09:27:04 INFO - "revision": "2ddf71e1526a67d4d3f0166fd17f18dbcb223c2c", 09:27:04 INFO - "stage_platform": "macosx64", 09:27:04 INFO - "builduid": "7c062e890ff24841ab79a589c67ed2fc", 09:27:04 INFO - "slavebuilddir": "test" 09:27:04 INFO - } 09:27:04 INFO - Found installer url https://queue.taskcluster.net/v1/task/Zjw2Lr60R_ybtJ_P0-SnEw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg. 09:27:04 INFO - Running post-action listener: _resource_record_post_action 09:27:04 INFO - [mozharness: 2017-02-19 17:27:04.166234Z] Finished read-buildbot-config step (success) 09:27:04 INFO - [mozharness: 2017-02-19 17:27:04.166318Z] Running download-and-extract step. 09:27:04 INFO - Running pre-action listener: _resource_record_pre_action 09:27:04 INFO - Running main action method: download_and_extract 09:27:04 INFO - mkdir: /builds/slave/test/build/tests 09:27:04 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')]} 09:27:04 INFO - trying https://queue.taskcluster.net/v1/task/Zjw2Lr60R_ybtJ_P0-SnEw/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json 09:27:04 INFO - Downloading https://queue.taskcluster.net/v1/task/Zjw2Lr60R_ybtJ_P0-SnEw/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 09:27:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Zjw2Lr60R_ybtJ_P0-SnEw/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 09:27:04 INFO - Downloaded 1199 bytes. 09:27:04 INFO - Reading from file /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 09:27:04 INFO - Using the following test package requirements: 09:27:04 INFO - {u'common': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 09:27:04 INFO - u'cppunittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 09:27:04 INFO - u'firefox-53.0a2.en-US.mac.cppunittest.tests.zip'], 09:27:04 INFO - u'gtest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 09:27:04 INFO - u'firefox-53.0a2.en-US.mac.gtest.tests.zip'], 09:27:04 INFO - u'jittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 09:27:04 INFO - u'jsshell-mac.zip'], 09:27:04 INFO - u'mochitest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 09:27:04 INFO - u'firefox-53.0a2.en-US.mac.mochitest.tests.zip'], 09:27:04 INFO - u'mozbase': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 09:27:04 INFO - u'reftest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 09:27:04 INFO - u'firefox-53.0a2.en-US.mac.reftest.tests.zip'], 09:27:04 INFO - u'talos': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 09:27:04 INFO - u'firefox-53.0a2.en-US.mac.talos.tests.zip'], 09:27:04 INFO - u'web-platform': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 09:27:04 INFO - u'firefox-53.0a2.en-US.mac.web-platform.tests.zip'], 09:27:04 INFO - u'xpcshell': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 09:27:04 INFO - u'firefox-53.0a2.en-US.mac.xpcshell.tests.zip']} 09:27:04 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip'] for test suite category: common 09:27:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Zjw2Lr60R_ybtJ_P0-SnEw/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 09:27:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Zjw2Lr60R_ybtJ_P0-SnEw/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:27:04 INFO - Fetch https://queue.taskcluster.net/v1/task/Zjw2Lr60R_ybtJ_P0-SnEw/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 09:27:06 INFO - Expected file size: 16358984 09:27:06 INFO - Obtained file size: 16358984 09:27:13 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 09:27:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Zjw2Lr60R_ybtJ_P0-SnEw/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 09:27:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Zjw2Lr60R_ybtJ_P0-SnEw/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:27:13 INFO - Fetch https://queue.taskcluster.net/v1/task/Zjw2Lr60R_ybtJ_P0-SnEw/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 09:27:15 INFO - Expected file size: 16358984 09:27:15 INFO - Obtained file size: 16358984 09:27:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Zjw2Lr60R_ybtJ_P0-SnEw/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip 09:27:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Zjw2Lr60R_ybtJ_P0-SnEw/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip'}, attempt #1 09:27:21 INFO - Fetch https://queue.taskcluster.net/v1/task/Zjw2Lr60R_ybtJ_P0-SnEw/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip into memory 09:27:23 INFO - Expected file size: 14403485 09:27:23 INFO - Obtained file size: 14403485 09:27:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:24 INFO - trying https://queue.taskcluster.net/v1/task/Zjw2Lr60R_ybtJ_P0-SnEw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 09:27:24 INFO - Downloading https://queue.taskcluster.net/v1/task/Zjw2Lr60R_ybtJ_P0-SnEw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg 09:27:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Zjw2Lr60R_ybtJ_P0-SnEw/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 09:27:27 INFO - Downloaded 73959717 bytes. 09:27:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Zjw2Lr60R_ybtJ_P0-SnEw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 09:27:27 INFO - mkdir: /builds/slave/test/properties 09:27:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:27:27 INFO - Writing to file /builds/slave/test/properties/build_url 09:27:27 INFO - Contents: 09:27:27 INFO - build_url:https://queue.taskcluster.net/v1/task/Zjw2Lr60R_ybtJ_P0-SnEw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 09:27:27 INFO - Running post-action listener: _resource_record_post_action 09:27:27 INFO - Running post-action listener: set_extra_try_arguments 09:27:27 INFO - [mozharness: 2017-02-19 17:27:27.799525Z] Finished download-and-extract step (success) 09:27:27 INFO - [mozharness: 2017-02-19 17:27:27.799627Z] Running populate-webroot step. 09:27:27 INFO - Running pre-action listener: _resource_record_pre_action 09:27:27 INFO - Running main action method: populate_webroot 09:27:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:27:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:27:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:27:27 INFO - '--tppagecycles', 09:27:27 INFO - '7'], 09:27:27 INFO - 'tests': ['tsvgm']}}, 09:27:27 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:27:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:27:27 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:27:27 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:27:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:27:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:27:27 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:27:27 INFO - 'talos_options': ['--disable-e10s'], 09:27:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:27:27 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:27:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:27:27 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:27:27 INFO - 'talos_options': ['--disable-e10s'], 09:27:27 INFO - 'tests': ['damp', 'tps']}, 09:27:27 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:27:27 INFO - 'tests': ['damp', 'tps']}, 09:27:27 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:27:27 INFO - 'tests': ['dromaeo_dom']}, 09:27:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:27:27 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:27:27 INFO - 'tests': ['basic_compositor_video']}, 09:27:27 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:27:27 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:27:27 INFO - 'tests': ['a11yr', 09:27:27 INFO - 'ts_paint', 09:27:27 INFO - 'tpaint', 09:27:27 INFO - 'sessionrestore', 09:27:27 INFO - 'sessionrestore_no_auto_restore', 09:27:27 INFO - 'tabpaint']}, 09:27:27 INFO - 'other-e10s': {'tests': ['a11yr', 09:27:27 INFO - 'ts_paint', 09:27:27 INFO - 'tpaint', 09:27:27 INFO - 'sessionrestore', 09:27:27 INFO - 'sessionrestore_no_auto_restore', 09:27:27 INFO - 'tabpaint']}, 09:27:27 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:27:27 INFO - 'ts_paint', 09:27:27 INFO - 'tpaint', 09:27:27 INFO - 'sessionrestore', 09:27:27 INFO - 'sessionrestore_no_auto_restore', 09:27:27 INFO - 'tabpaint']}, 09:27:27 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:27:27 INFO - 'ts_paint', 09:27:27 INFO - 'tpaint', 09:27:27 INFO - 'sessionrestore', 09:27:27 INFO - 'sessionrestore_no_auto_restore', 09:27:27 INFO - 'tabpaint']}, 09:27:27 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:27:27 INFO - 'tests': ['a11yr', 09:27:27 INFO - 'ts_paint', 09:27:27 INFO - 'tpaint', 09:27:27 INFO - 'sessionrestore', 09:27:27 INFO - 'sessionrestore_no_auto_restore', 09:27:27 INFO - 'tabpaint']}, 09:27:27 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:27:27 INFO - 'tests': ['a11yr', 09:27:27 INFO - 'ts_paint', 09:27:27 INFO - 'tpaint', 09:27:27 INFO - 'sessionrestore', 09:27:27 INFO - 'sessionrestore_no_auto_restore', 09:27:27 INFO - 'tabpaint']}, 09:27:27 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:27:27 INFO - 'tests': ['tsvgx', 09:27:27 INFO - 'tsvgr_opacity', 09:27:27 INFO - 'tart', 09:27:27 INFO - 'tscrollx', 09:27:27 INFO - 'cart', 09:27:27 INFO - 'tsvg_static']}, 09:27:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:27:27 INFO - 'tsvgr_opacity', 09:27:27 INFO - 'tart', 09:27:27 INFO - 'tscrollx', 09:27:27 INFO - 'cart', 09:27:27 INFO - 'tsvg_static']}, 09:27:27 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:27:27 INFO - 'talos_options': ['--disable-e10s'], 09:27:27 INFO - 'tests': ['tp5o']}, 09:27:27 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:27:27 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:27:27 INFO - 'talos_options': ['--disable-e10s', 09:27:27 INFO - '--xperf_path', 09:27:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:27:27 INFO - 'tests': ['tp5n']}, 09:27:27 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:27:27 INFO - 'talos_options': ['--xperf_path', 09:27:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:27:27 INFO - 'tests': ['tp5n']}}, 09:27:27 INFO - 'talos.zip': {'path': '', 09:27:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:27:27 INFO - Running post-action listener: _resource_record_post_action 09:27:27 INFO - [mozharness: 2017-02-19 17:27:27.818400Z] Finished populate-webroot step (success) 09:27:27 INFO - [mozharness: 2017-02-19 17:27:27.818510Z] Running create-virtualenv step. 09:27:27 INFO - Running pre-action listener: _resource_record_pre_action 09:27:27 INFO - Running main action method: create_virtualenv 09:27:27 INFO - Creating virtualenv /builds/slave/test/build/venv 09:27:27 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:27:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:27:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:27:27 INFO - Using real prefix '/tools/python27' 09:27:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:27:28 INFO - Installing distribute.............................................................................................................................................................................................done. 09:27:31 INFO - Installing pip.................done. 09:27:31 INFO - Return code: 0 09:27:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:27:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:27:31 INFO - Reading from file tmpfile_stdout 09:27:31 INFO - Output received: 09:27:31 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 09:27:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:27: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')]} 09:27:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27: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')]} 09:27:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e53bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e53e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe93a586a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e319d30>, '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 0x10e240db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e30ebe8>, '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.uRZMCxLsJg/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.XLMlVdtSuY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/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 09:27: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', 'pip>=1.5'] in /builds/slave/test/build 09:27: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 pip>=1.5 09:27:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uRZMCxLsJg/Render', 09:27:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:31 INFO - 'HOME': '/Users/cltbld', 09:27:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:31 INFO - 'LOGNAME': 'cltbld', 09:27:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:31 INFO - 'MOZ_NO_REMOTE': '1', 09:27:31 INFO - 'NO_EM_RESTART': '1', 09:27:31 INFO - 'PAGER': '/bin/cat', 09:27:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:31 INFO - 'PWD': '/builds/slave/test', 09:27:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:31 INFO - 'SHELL': '/bin/bash', 09:27:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XLMlVdtSuY/Listeners', 09:27:31 INFO - 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/', 09:27:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:31 INFO - 'USER': 'cltbld', 09:27:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:31 INFO - 'XPC_FLAGS': '0x0', 09:27:31 INFO - 'XPC_SERVICE_NAME': '0', 09:27:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:32 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:27:32 INFO - Cleaning up... 09:27:32 INFO - Return code: 0 09:27:32 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:27:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10e53bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e53e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe93a586a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e319d30>, '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 0x10e240db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e30ebe8>, '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.uRZMCxLsJg/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.XLMlVdtSuY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/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 09:27:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:27:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:27:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uRZMCxLsJg/Render', 09:27:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:32 INFO - 'HOME': '/Users/cltbld', 09:27:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:32 INFO - 'LOGNAME': 'cltbld', 09:27:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:32 INFO - 'MOZ_NO_REMOTE': '1', 09:27:32 INFO - 'NO_EM_RESTART': '1', 09:27:32 INFO - 'PAGER': '/bin/cat', 09:27:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:32 INFO - 'PWD': '/builds/slave/test', 09:27:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:32 INFO - 'SHELL': '/bin/bash', 09:27:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XLMlVdtSuY/Listeners', 09:27:32 INFO - 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/', 09:27:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:32 INFO - 'USER': 'cltbld', 09:27:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:32 INFO - 'XPC_FLAGS': '0x0', 09:27:32 INFO - 'XPC_SERVICE_NAME': '0', 09:27:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:32 INFO - Downloading/unpacking psutil>=3.1.1 09:27:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:27:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:27:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:27:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:27:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:27:36 INFO - Installing collected packages: psutil 09:27:36 INFO - Running setup.py install for psutil 09:27:37 INFO - building 'psutil._psutil_osx' extension 09:27:37 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:27:37 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:27:37 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:27:37 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 09:27:37 INFO - building 'psutil._psutil_posix' extension 09:27:37 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 09:27:37 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:27:37 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:27:37 INFO - ^ 09:27:37 INFO - 1 warning generated. 09:27:37 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 09:27:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:27:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:27:38 INFO - Successfully installed psutil 09:27:38 INFO - Cleaning up... 09:27:38 INFO - Return code: 0 09:27:38 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:27:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e53bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e53e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe93a586a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e319d30>, '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 0x10e240db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e30ebe8>, '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.uRZMCxLsJg/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.XLMlVdtSuY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/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 09:27:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:27:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:27:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uRZMCxLsJg/Render', 09:27:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:38 INFO - 'HOME': '/Users/cltbld', 09:27:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:38 INFO - 'LOGNAME': 'cltbld', 09:27:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:38 INFO - 'MOZ_NO_REMOTE': '1', 09:27:38 INFO - 'NO_EM_RESTART': '1', 09:27:38 INFO - 'PAGER': '/bin/cat', 09:27:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:38 INFO - 'PWD': '/builds/slave/test', 09:27:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:38 INFO - 'SHELL': '/bin/bash', 09:27:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XLMlVdtSuY/Listeners', 09:27:38 INFO - 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/', 09:27:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:38 INFO - 'USER': 'cltbld', 09:27:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:38 INFO - 'XPC_FLAGS': '0x0', 09:27:38 INFO - 'XPC_SERVICE_NAME': '0', 09:27:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:38 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:27:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:27:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:27:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:41 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:27:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:27:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:27:41 INFO - Installing collected packages: mozsystemmonitor 09:27:41 INFO - Running setup.py install for mozsystemmonitor 09:27:41 INFO - Successfully installed mozsystemmonitor 09:27:41 INFO - Cleaning up... 09:27:41 INFO - Return code: 0 09:27:41 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:27:41 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')]} 09:27:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:41 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')]} 09:27:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:41 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 0x10e53bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e53e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe93a586a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e319d30>, '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 0x10e240db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e30ebe8>, '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.uRZMCxLsJg/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.XLMlVdtSuY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/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 09:27:41 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 09:27:41 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 09:27:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uRZMCxLsJg/Render', 09:27:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:41 INFO - 'HOME': '/Users/cltbld', 09:27:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:41 INFO - 'LOGNAME': 'cltbld', 09:27:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:41 INFO - 'MOZ_NO_REMOTE': '1', 09:27:41 INFO - 'NO_EM_RESTART': '1', 09:27:41 INFO - 'PAGER': '/bin/cat', 09:27:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:41 INFO - 'PWD': '/builds/slave/test', 09:27:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:41 INFO - 'SHELL': '/bin/bash', 09:27:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XLMlVdtSuY/Listeners', 09:27:41 INFO - 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/', 09:27:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:41 INFO - 'USER': 'cltbld', 09:27:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:41 INFO - 'XPC_FLAGS': '0x0', 09:27:41 INFO - 'XPC_SERVICE_NAME': '0', 09:27:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:42 INFO - Downloading/unpacking jsonschema==2.5.1 09:27:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:27:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:27:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:45 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:27:45 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:27:45 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 09:27:45 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 09:27:45 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 09:27:45 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 09:27:45 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 09:27:45 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 09:27:45 INFO - Downloading functools32-3.2.3-2.tar.gz 09:27:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:27:46 INFO - warning: no files found matching '*.txt' 09:27:46 INFO - no previously-included directories found matching 'build' 09:27:46 INFO - no previously-included directories found matching 'dist' 09:27:46 INFO - no previously-included directories found matching '.git*' 09:27:46 INFO - Installing collected packages: jsonschema, functools32 09:27:46 INFO - Running setup.py install for functools32 09:27:46 INFO - warning: no files found matching '*.txt' 09:27:46 INFO - no previously-included directories found matching 'build' 09:27:46 INFO - no previously-included directories found matching 'dist' 09:27:46 INFO - no previously-included directories found matching '.git*' 09:27:46 INFO - Successfully installed jsonschema functools32 09:27:46 INFO - Cleaning up... 09:27:46 INFO - Return code: 0 09:27:46 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:27:46 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')]} 09:27:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:46 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')]} 09:27:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:46 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 0x10e53bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e53e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe93a586a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e319d30>, '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 0x10e240db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e30ebe8>, '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.uRZMCxLsJg/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.XLMlVdtSuY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/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 09:27:46 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 09:27:46 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 09:27:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uRZMCxLsJg/Render', 09:27:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:46 INFO - 'HOME': '/Users/cltbld', 09:27:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:46 INFO - 'LOGNAME': 'cltbld', 09:27:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:46 INFO - 'MOZ_NO_REMOTE': '1', 09:27:46 INFO - 'NO_EM_RESTART': '1', 09:27:46 INFO - 'PAGER': '/bin/cat', 09:27:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:46 INFO - 'PWD': '/builds/slave/test', 09:27:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:46 INFO - 'SHELL': '/bin/bash', 09:27:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XLMlVdtSuY/Listeners', 09:27:46 INFO - 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/', 09:27:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:46 INFO - 'USER': 'cltbld', 09:27:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:46 INFO - 'XPC_FLAGS': '0x0', 09:27:46 INFO - 'XPC_SERVICE_NAME': '0', 09:27:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:46 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:27:46 INFO - Cleaning up... 09:27:46 INFO - Return code: 0 09:27:46 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:27:46 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')]} 09:27:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:46 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')]} 09:27:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:46 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 0x10e53bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e53e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe93a586a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e319d30>, '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 0x10e240db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e30ebe8>, '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.uRZMCxLsJg/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.XLMlVdtSuY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/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 09:27:46 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 09:27:46 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 09:27:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uRZMCxLsJg/Render', 09:27:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:46 INFO - 'HOME': '/Users/cltbld', 09:27:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:46 INFO - 'LOGNAME': 'cltbld', 09:27:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:46 INFO - 'MOZ_NO_REMOTE': '1', 09:27:46 INFO - 'NO_EM_RESTART': '1', 09:27:46 INFO - 'PAGER': '/bin/cat', 09:27:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:46 INFO - 'PWD': '/builds/slave/test', 09:27:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:46 INFO - 'SHELL': '/bin/bash', 09:27:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XLMlVdtSuY/Listeners', 09:27:46 INFO - 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/', 09:27:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:46 INFO - 'USER': 'cltbld', 09:27:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:46 INFO - 'XPC_FLAGS': '0x0', 09:27:46 INFO - 'XPC_SERVICE_NAME': '0', 09:27:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:46 INFO - Downloading/unpacking blobuploader==1.2.4 09:27:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:27:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:27:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:49 INFO - Downloading blobuploader-1.2.4.tar.gz 09:27:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:27:49 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:27:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:27:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:27:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:27:50 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:27:50 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 09:27:50 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 09:27:50 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 09:27:50 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 09:27:50 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 09:27:50 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 09:27:50 INFO - Downloading docopt-0.6.1.tar.gz 09:27:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:27:50 INFO - Installing collected packages: blobuploader, requests, docopt 09:27:50 INFO - Running setup.py install for blobuploader 09:27:50 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:27:51 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:27:51 INFO - Running setup.py install for requests 09:27:51 INFO - Running setup.py install for docopt 09:27:51 INFO - Successfully installed blobuploader requests docopt 09:27:51 INFO - Cleaning up... 09:27:51 INFO - Return code: 0 09:27:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:27: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')]} 09:27:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27: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')]} 09:27:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e53bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e53e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe93a586a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e319d30>, '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 0x10e240db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e30ebe8>, '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.uRZMCxLsJg/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.XLMlVdtSuY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/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 09:27:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:27:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:27:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uRZMCxLsJg/Render', 09:27:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:51 INFO - 'HOME': '/Users/cltbld', 09:27:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:51 INFO - 'LOGNAME': 'cltbld', 09:27:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:51 INFO - 'MOZ_NO_REMOTE': '1', 09:27:51 INFO - 'NO_EM_RESTART': '1', 09:27:51 INFO - 'PAGER': '/bin/cat', 09:27:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:51 INFO - 'PWD': '/builds/slave/test', 09:27:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:51 INFO - 'SHELL': '/bin/bash', 09:27:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XLMlVdtSuY/Listeners', 09:27:51 INFO - 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/', 09:27:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:51 INFO - 'USER': 'cltbld', 09:27:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:51 INFO - 'XPC_FLAGS': '0x0', 09:27:51 INFO - 'XPC_SERVICE_NAME': '0', 09:27:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:27:52 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-c78bOw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:27:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:27:52 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-VjLyr6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:27:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:27:52 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-LXInf5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:27:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:27:52 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-lIRDlf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:27:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:27:52 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-MhE2rK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:27:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:27:52 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-sdOLz_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:27:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:27:52 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-4oOGZQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:27:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:27:52 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-GiaQTC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:27:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:27:52 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-VfdyT3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:27:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:27:52 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-aG60wz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:27:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:27:53 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-lbPeD5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:27:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:27:53 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-WAbW4J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:27:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:27:53 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-AJK9hs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:27:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:27:53 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-J22kVG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:27:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:27:53 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-zRjqX_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:27:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:27:53 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-srzXzz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:27:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:27:53 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-f8X5Cq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:27:53 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:27:53 INFO - Running setup.py install for manifestparser 09:27:53 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:27:53 INFO - Running setup.py install for mozcrash 09:27:54 INFO - Running setup.py install for mozdebug 09:27:54 INFO - Running setup.py install for mozdevice 09:27:54 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:27:54 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:27:54 INFO - Running setup.py install for mozfile 09:27:54 INFO - Running setup.py install for mozhttpd 09:27:54 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:27:54 INFO - Running setup.py install for mozinfo 09:27:54 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:27:54 INFO - Running setup.py install for mozInstall 09:27:54 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:27:54 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:27:54 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:27:54 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:27:55 INFO - Running setup.py install for mozleak 09:27:55 INFO - Running setup.py install for mozlog 09:27:55 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:27:55 INFO - Running setup.py install for moznetwork 09:27:55 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:27:55 INFO - Running setup.py install for mozprocess 09:27:55 INFO - Running setup.py install for mozprofile 09:27:55 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:27:55 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:27:55 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:27:55 INFO - Running setup.py install for mozrunner 09:27:55 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:27:55 INFO - Running setup.py install for mozscreenshot 09:27:56 INFO - Running setup.py install for moztest 09:27:56 INFO - Running setup.py install for mozversion 09:27:56 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:27:56 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:27:56 INFO - Cleaning up... 09:27:56 INFO - Return code: 0 09:27:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:27: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')]} 09:27:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27: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')]} 09:27:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:56 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 0x10e53bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e53e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe93a586a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e319d30>, '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 0x10e240db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e30ebe8>, '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.uRZMCxLsJg/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.XLMlVdtSuY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/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 09:27:56 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 09:27:56 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 09:27:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uRZMCxLsJg/Render', 09:27:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:56 INFO - 'HOME': '/Users/cltbld', 09:27:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:56 INFO - 'LOGNAME': 'cltbld', 09:27:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:56 INFO - 'MOZ_NO_REMOTE': '1', 09:27:56 INFO - 'NO_EM_RESTART': '1', 09:27:56 INFO - 'PAGER': '/bin/cat', 09:27:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:56 INFO - 'PWD': '/builds/slave/test', 09:27:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:56 INFO - 'SHELL': '/bin/bash', 09:27:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XLMlVdtSuY/Listeners', 09:27:56 INFO - 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/', 09:27:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:56 INFO - 'USER': 'cltbld', 09:27:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:56 INFO - 'XPC_FLAGS': '0x0', 09:27:56 INFO - 'XPC_SERVICE_NAME': '0', 09:27:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:27:56 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-z85Q8E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:27:56 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:27:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:27:56 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-hIfCXC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:27:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:27:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:27:56 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-fYRvcS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:27:57 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)) 09:27:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:27:57 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-TE3642-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:27:57 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)) 09:27:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:27:57 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-19yBMB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:27:57 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)) 09:27:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:27:57 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-xO8dfm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:27:57 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)) 09:27:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:27:57 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-0sMqy8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:27:57 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)) 09:27:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:27:57 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-m4sZD4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:27:57 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)) 09:27:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:27:57 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-Bb3yQo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:27:57 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)) 09:27:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:27:57 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-hfLgO8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:27:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:27:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:27:57 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-8iRZC1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:27:57 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:27:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:27:57 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-Y1b3lx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:27:58 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)) 09:27:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:27:58 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-g9Dk3j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:27:58 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)) 09:27:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:27:58 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-pEajsy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:27:58 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)) 09:27:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:27:58 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-udv8yn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:27:58 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)) 09:27:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:27:58 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-zNpVSg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:27:58 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)) 09:27:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:27:58 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-TxskCG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:27:58 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)) 09:27:58 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)) 09:27:58 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)) 09:27:58 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)) 09:27:58 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)) 09:27:58 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)) 09:27:58 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:27:58 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 09:27:58 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 09:27:58 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 09:27:58 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 09:27:58 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 09:27:58 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 09:28:01 INFO - Downloading blessings-1.6.tar.gz 09:28:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:28:01 INFO - Installing collected packages: blessings 09:28:01 INFO - Running setup.py install for blessings 09:28:01 INFO - Successfully installed blessings 09:28:01 INFO - Cleaning up... 09:28:01 INFO - Return code: 0 09:28:01 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:28:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:28:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:28:01 INFO - Reading from file tmpfile_stdout 09:28:01 INFO - Current package versions: 09:28:01 INFO - blessings == 1.6 09:28:01 INFO - blobuploader == 1.2.4 09:28:01 INFO - docopt == 0.6.1 09:28:01 INFO - functools32 == 3.2.3-2 09:28:01 INFO - jsonschema == 2.5.1 09:28:01 INFO - manifestparser == 1.1 09:28:01 INFO - mozInstall == 1.12 09:28:01 INFO - mozcrash == 1.0 09:28:01 INFO - mozdebug == 0.1 09:28:01 INFO - mozdevice == 0.49 09:28:01 INFO - mozfile == 1.2 09:28:01 INFO - mozhttpd == 0.7 09:28:01 INFO - mozinfo == 0.9 09:28:01 INFO - mozleak == 0.1 09:28:01 INFO - mozlog == 3.4 09:28:01 INFO - moznetwork == 0.27 09:28:01 INFO - mozprocess == 0.24 09:28:01 INFO - mozprofile == 0.28 09:28:01 INFO - mozrunner == 6.13 09:28:01 INFO - mozscreenshot == 0.1 09:28:01 INFO - mozsystemmonitor == 0.3 09:28:01 INFO - moztest == 0.8 09:28:01 INFO - mozversion == 1.4 09:28:01 INFO - psutil == 3.1.1 09:28:01 INFO - requests == 1.2.3 09:28:01 INFO - wsgiref == 0.1.2 09:28:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:28:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:28:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:28:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:28:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:28:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:28:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:28:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e53bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e53e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe93a586a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e319d30>, '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 0x10e240db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e30ebe8>, '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.uRZMCxLsJg/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.XLMlVdtSuY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/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 09:28:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:28:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:28:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uRZMCxLsJg/Render', 09:28:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:28:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:28:01 INFO - 'HOME': '/Users/cltbld', 09:28:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:28:01 INFO - 'LOGNAME': 'cltbld', 09:28:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:28:01 INFO - 'MOZ_NO_REMOTE': '1', 09:28:01 INFO - 'NO_EM_RESTART': '1', 09:28:01 INFO - 'PAGER': '/bin/cat', 09:28:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:28:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:28:01 INFO - 'PWD': '/builds/slave/test', 09:28:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:28:01 INFO - 'SHELL': '/bin/bash', 09:28:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XLMlVdtSuY/Listeners', 09:28:01 INFO - 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/', 09:28:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:28:01 INFO - 'USER': 'cltbld', 09:28:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:28:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:28:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:28:01 INFO - 'XPC_FLAGS': '0x0', 09:28:01 INFO - 'XPC_SERVICE_NAME': '0', 09:28:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:28:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:28:02 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)) 09:28:02 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)) 09:28:02 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)) 09:28:02 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)) 09:28:02 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)) 09:28:02 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)) 09:28:02 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)) 09:28:02 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)) 09:28:02 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)) 09:28:02 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)) 09:28:02 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)) 09:28:02 INFO - Cleaning up... 09:28:02 INFO - Return code: 0 09:28:02 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:28:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:28:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:28:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:28:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:28:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:28:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:28:02 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 0x10e53bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e53e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe93a586a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e319d30>, '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 0x10e240db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e30ebe8>, '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.uRZMCxLsJg/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.XLMlVdtSuY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/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 09:28:02 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 09:28:02 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 09:28:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uRZMCxLsJg/Render', 09:28:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:28:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:28:02 INFO - 'HOME': '/Users/cltbld', 09:28:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:28:02 INFO - 'LOGNAME': 'cltbld', 09:28:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:28:02 INFO - 'MOZ_NO_REMOTE': '1', 09:28:02 INFO - 'NO_EM_RESTART': '1', 09:28:02 INFO - 'PAGER': '/bin/cat', 09:28:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:28:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:28:02 INFO - 'PWD': '/builds/slave/test', 09:28:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:28:02 INFO - 'SHELL': '/bin/bash', 09:28:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XLMlVdtSuY/Listeners', 09:28:02 INFO - 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/', 09:28:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:28:02 INFO - 'USER': 'cltbld', 09:28:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:28:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:28:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:28:02 INFO - 'XPC_FLAGS': '0x0', 09:28:02 INFO - 'XPC_SERVICE_NAME': '0', 09:28:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:28:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:28:02 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:28:02 INFO - Cleaning up... 09:28:02 INFO - Return code: 0 09:28:02 INFO - Running post-action listener: _resource_record_post_action 09:28:02 INFO - Running post-action listener: _start_resource_monitoring 09:28:02 INFO - Starting resource monitoring. 09:28:02 INFO - [mozharness: 2017-02-19 17:28:02.387719Z] Finished create-virtualenv step (success) 09:28:02 INFO - [mozharness: 2017-02-19 17:28:02.388673Z] Running install step. 09:28:02 INFO - Running pre-action listener: _resource_record_pre_action 09:28:02 INFO - Running main action method: install 09:28:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:28:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:28:02 INFO - Reading from file tmpfile_stdout 09:28:02 INFO - Detecting whether we're running mozinstall >=1.0... 09:28:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:28:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:28:02 INFO - Reading from file tmpfile_stdout 09:28:02 INFO - Output received: 09:28:02 INFO - Usage: mozinstall [options] installer 09:28:02 INFO - Options: 09:28:02 INFO - -h, --help show this help message and exit 09:28:02 INFO - -d DEST, --destination=DEST 09:28:02 INFO - Directory to install application into. [default: 09:28:02 INFO - "/builds/slave/test"] 09:28:02 INFO - --app=APP Application being installed. [default: firefox] 09:28:02 INFO - mkdir: /builds/slave/test/build/application 09:28:02 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'] 09:28:02 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 09:28:22 INFO - Reading from file tmpfile_stdout 09:28:22 INFO - Output received: 09:28:22 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 09:28:22 INFO - Running post-action listener: _resource_record_post_action 09:28:22 INFO - [mozharness: 2017-02-19 17:28:22.717046Z] Finished install step (success) 09:28:22 INFO - [mozharness: 2017-02-19 17:28:22.717238Z] Running run-tests step. 09:28:22 INFO - Running pre-action listener: _resource_record_pre_action 09:28:22 INFO - Running main action method: run_tests 09:28:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:28:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:28:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:28:22 INFO - 2017-02-19 09:28:22.731 screenresolution[2412:14978] starting screenresolution argv=screenresolution get 09:28:22 INFO - 2017-02-19 09:28:22.734 screenresolution[2412:14978] Display 0: 1600x1200x32@60 09:28:22 INFO - 2017-02-19 09:28:22.740 screenresolution[2413:14980] starting screenresolution argv=screenresolution list 09:28:22 INFO - Available Modes on Display 0 09:28:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:28:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:28:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:28:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:28:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:28:22 INFO - Intel Iris: 09:28:22 INFO - Chipset Model: Intel Iris 09:28:22 INFO - Type: GPU 09:28:22 INFO - Bus: Built-In 09:28:22 INFO - VRAM (Dynamic, Max): 1536 MB 09:28:22 INFO - Vendor: Intel (0x8086) 09:28:22 INFO - Device ID: 0x0a2e 09:28:22 INFO - Revision ID: 0x0009 09:28:22 INFO - Displays: 09:28:22 INFO - Display: 09:28:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:28:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:28:22 INFO - Display Serial Number: 1600x1200 60 09:28:22 INFO - Main Display: Yes 09:28:22 INFO - Mirror: Off 09:28:22 INFO - Online: Yes 09:28:22 INFO - Rotation: Supported 09:28:22 INFO - Return code: 0 09:28:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:28:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:28:22 INFO - Python 2.7.3 09:28:22 INFO - Return code: 0 09:28:22 INFO - grabbing minidump binary from tooltool 09:28:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:28:22 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e319d30>, '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 0x10e240db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e30ebe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:28:22 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:28:22 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 09:28:22 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:28:23 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:28:23 INFO - Return code: 0 09:28:23 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:28:23 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:28:23 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:28:23 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:28:23 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:28:23 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/Zjw2Lr60R_ybtJ_P0-SnEw/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0063', '--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 09:28:23 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/Zjw2Lr60R_ybtJ_P0-SnEw/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0063 --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 09:28:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uRZMCxLsJg/Render', 09:28:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:28:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:28:23 INFO - 'HOME': '/Users/cltbld', 09:28:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:28:23 INFO - 'LOGNAME': 'cltbld', 09:28:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:28:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:28:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:28:23 INFO - 'MOZ_NO_REMOTE': '1', 09:28:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:28:23 INFO - 'NO_EM_RESTART': '1', 09:28:23 INFO - 'PAGER': '/bin/cat', 09:28:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:28:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:28:23 INFO - 'PWD': '/builds/slave/test', 09:28:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:28:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:28:23 INFO - 'SHELL': '/bin/bash', 09:28:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XLMlVdtSuY/Listeners', 09:28:23 INFO - 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/', 09:28:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:28:23 INFO - 'USER': 'cltbld', 09:28:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:28:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:28:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:28:23 INFO - 'XPC_FLAGS': '0x0', 09:28:23 INFO - 'XPC_SERVICE_NAME': '0', 09:28:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:28:23 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/Zjw2Lr60R_ybtJ_P0-SnEw/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0063', '--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 09:28:23 INFO - mozversion application_buildid: 20170219085119 09:28:23 INFO - mozversion application_changeset: 2ddf71e1526a67d4d3f0166fd17f18dbcb223c2c 09:28:23 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:28:23 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:28:23 INFO - mozversion application_name: Firefox 09:28:23 INFO - mozversion application_remotingname: firefox-dev 09:28:23 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:28:23 INFO - mozversion application_vendor: Mozilla 09:28:23 INFO - mozversion application_version: 53.0a2 09:28:23 INFO - mozversion platform_buildid: 20170219085119 09:28:23 INFO - mozversion platform_changeset: 2ddf71e1526a67d4d3f0166fd17f18dbcb223c2c 09:28:23 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:28:23 INFO - mozversion platform_version: 53.0a2 09:28:23 INFO - using testdate: 1487525303 09:28:23 INFO - actual date: 1487525303 09:28:23 INFO - starting webserver on 'localhost:49243' 09:28:23 INFO - SUITE-START | Running 2 tests 09:28:23 INFO - TEST-START | dromaeo_css 09:28:23 INFO - operating with platform_type : mac_ 09:28:23 INFO - Initialising browser for dromaeo_css test... 09:28:23 INFO - TEST-INFO | started process 2423 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/tmpMzcBv3/profile http://localhost:49243/getInfo.html) 09:28:26 INFO - PROCESS | 2423 | 2017-02-19 09:28:26.213 plugin-container[2425:15164] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a53, name = 'com.apple.tsm.portname' 09:28:26 INFO - PROCESS | 2423 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:28:26 INFO - PROCESS | 2423 | 2017-02-19 09:28:26.217 plugin-container[2425:15164] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c07, name = 'com.apple.CFPasteboardClient' 09:28:26 INFO - PROCESS | 2423 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:28:26 INFO - PROCESS | 2423 | 2017-02-19 09:28:26.217 plugin-container[2425:15164] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:28:26 INFO - PROCESS | 2423 | __metrics Screen width/height:1600/1200 09:28:26 INFO - PROCESS | 2423 | colorDepth:24 09:28:26 INFO - PROCESS | 2423 | Browser inner width/height: 1024/689 09:28:26 INFO - PROCESS | 2423 | __metrics 09:28:26 ERROR - PROCESS | 2423 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 09:28:27 INFO - TEST-INFO | 2423: exit 0 09:28:27 INFO - Browser initialized. 09:28:27 INFO - Running cycle 1/1 for dromaeo_css test... 09:28:27 INFO - TEST-INFO | started process 2428 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/tmpMzcBv3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:28:29 INFO - PROCESS | 2428 | 2017-02-19 09:28:29.662 plugin-container[2429:15292] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8743, name = 'com.apple.tsm.portname' 09:28:29 INFO - PROCESS | 2428 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:28:29 INFO - PROCESS | 2428 | 2017-02-19 09:28:29.662 plugin-container[2429:15292] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x451f, name = 'com.apple.CFPasteboardClient' 09:28:29 INFO - PROCESS | 2428 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:28:29 INFO - PROCESS | 2428 | 2017-02-19 09:28:29.662 plugin-container[2429:15292] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:28:39 INFO - PROCESS | 2428 | RSS: Main: 219365376 09:28:39 INFO - PROCESS | 2428 | 09:31:42 INFO - PROCESS | 2428 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49243/tests/dromaeo/cssquery-ext.html) 09:31:44 INFO - PROCESS | 2428 | RSS: Main: 238436352 09:31:44 INFO - PROCESS | 2428 | 09:33:27 INFO - PROCESS | 2428 | ************************* 09:33:27 INFO - PROCESS | 2428 | A coding exception was thrown and uncaught in a Task. 09:33:27 INFO - PROCESS | 2428 | 09:33:27 ERROR - PROCESS | 2428 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:33:27 INFO - PROCESS | 2428 | Full stack: 09:33:27 INFO - PROCESS | 2428 | ************************* 09:34:46 INFO - PROCESS | 2428 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-ext.html (next: http://localhost:49243/tests/dromaeo/cssquery-jquery.html) 09:34:47 INFO - PROCESS | 2428 | RSS: Main: 248586240 09:34:47 INFO - PROCESS | 2428 | 09:37:48 INFO - PROCESS | 2428 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49243/tests/dromaeo/cssquery-mootools.html) 09:37:49 INFO - PROCESS | 2428 | RSS: Main: 255078400 09:37:49 INFO - PROCESS | 2428 | 09:40:51 INFO - PROCESS | 2428 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49243/tests/dromaeo/cssquery-prototype.html) 09:40:52 INFO - PROCESS | 2428 | RSS: Main: 251535360 09:40:52 INFO - PROCESS | 2428 | 09:43:54 INFO - PROCESS | 2428 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49243/tests/dromaeo/cssquery-yui.html) 09:43:55 INFO - PROCESS | 2428 | RSS: Main: 255524864 09:43:55 INFO - PROCESS | 2428 | 09:46:58 INFO - PROCESS | 2428 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-yui.html (next: http://localhost:49243/tests/dromaeo/cssquery-dojo.html) 09:46:58 INFO - PROCESS | 2428 | RSS: Main: 257191936 09:46:58 INFO - PROCESS | 2428 | 09:46:58 INFO - PROCESS | 2428 | __start_tp_report 09:46:58 INFO - PROCESS | 2428 | _x_x_mozilla_page_load 09:46:58 INFO - PROCESS | 2428 | _x_x_mozilla_page_load_details 09:46:58 INFO - PROCESS | 2428 | |i|pagename|runs| 09:46:58 INFO - PROCESS | 2428 | |0;dojo.html;3329.2675611365507;3363.360961417332;3376.1222082258614;3380.5605271314726;3383.8646454141835;4336.609705126538;5553.72231388431;5623.971880140603;5632.943670563293;5743.54051675865;5669.971650141754;5696.25948626679;5696.572757043228;5705.115707065401;5744.942550574493;4484.058347746961;5159.071367153911;5224.947750522503;5258.500442457979;5289.497497737728;5133.563647090002;5257.5531079858065;5262.000219958215;5265.262863199156;5269.683818970855;5100.311457953166;5122.385313762354;5226.55574276187;5258.763355648985;5290.656107353029;5212.29633999409;5259.447757985433;5273.604479664041;5281.39263984639;5333.5199832015305;5084;5203;5208.062548741225;5228.47715228478;5294.020606187868;5534.723263836792;5703.0589952657765;5707.258056452676;5785.623934444247;5786.508146807527;6123.984276525883;6192.318844927055;6201.472874805647;6214.285357183937;6280.057991301295;6222.346653601352;6238.251409830849;6238.376162383771;6239.2824825145235;6261.060840873905;5845.473907348359;5856.4143585641095;5866.2373891393845;5873.177755114245;5909.556783241274;6176.135341052256;6209.875802483925;6235.064740288903;6240.126382306481;6252.5935814171935;6097.085437184458;6144.832481828527;6155.538334624921;6158.230221222348;6159.630422174684;5482.369527504388;6150.538709596798;6196.349383314777;6213.068039794067;6236;6097.5731698780655;6138.631682099089;6141.332587233735;6170.074488826712;6199.597026193283;6246.219222597176;6255.530835187379;6256.655883926428;6275.5920865144535;6288.056791481315;5004.999000199975;5027.522385373383;5029.874253143701;5036.622253331015;5048.848534543896;4347.130573885235;4364.148990946798;4403.603675669206;4471.083427897273;4503.414556107685;5745.913811292834;5754.971225143931;5822.097574875901;5853.443922827325;5856.824295271149;5443.836684899459;5998.830228105565;6254.593451425642;6264.780732674426;6277.2153480814895;5652.152177173457;5693.17448969897;5743.798967036134;5752.654840709571;5789.768409263583;5477.096279113902;5483;5483.835484935458;5489.819688766935;5503.697296648643;5398.838034858961;6369.108324834527;6371.713272902731;6380.074889141141;6395.136656551306;5279.1756410320295;5697.544196464356;6193.473554747897;6214.782482613087;6252.531060170414;6147.846303842261;6209.534284928558;6323.968380158254;6324.051392291193;6324.525660575383;6160.537959653134;6179.16581261524;6186.072089186658;6206.627602343874;6207.844803879939;6038.63768173911;6123.099607468126;6237.96881015592;6241.937580624317;6258.749650013949;5960.284765828128;6016.639001659914;6076.726547305293;6099.146119543178;6147.415995480422;6106.572539922163;6108.755649774137;6118.418750218722;6119.204503414528;6138.0179171332375;5905.881882362414;5914.674692891932;5920.111983202554;5950.434708702666;6050.2437195350585;5883.911741323969;5941.494972927349;6015.218021657157;6051.062085376773;6101.816945491642;5946.702664866652;5955.583109182316;5959.22530071088;5965.164876917322;6011.519078473799 09:46:58 INFO - PROCESS | 2428 | |1;ext.html;459.9977000114999;472.9219678753006;505.5904717179083;513.753398368783;514.4700958013243;39526.40710389343;42643.93390165243;42704.35943460851;42894.49869254577;42981.28074877001;44407.11185776291;44719.776401117946;44860.77569612156;44929.55070449303;45069.323960140624;8469.32245420368;9106.180443760077;9338.533073346305;9358.204552613037;9379.390339627937;8500.617472213735;11244.831327530093;11513.136514761385;11544.960953514183;11695.181337306392;11230.494627741771;11289.096872250242;11295;11310.830337544943;11363.147763917697;11306.943465282662;11334.433278336091;11342.262752921033;11375.601853935155;11416;11222.887771122267;11229.438528073564;11236.719082022933;11302.717432064264;11304.943475282653;41213.587864121575;41265;41491.377629335584;41566.37650435246;41605.375919361235;3623.873164439232;3849.6920246380223;3926.1362500249897;3965.5637879833193;4022.7787471689044;29947.101586952427;30557.69442305593;30807.38385232283;31025;31134.53298200506;3122.8906988255303;3161.2571045804216;3193;3196.5524826524306;3201.743860491155;413110;422799;424251;424778;425320;67589;67645;68273.3172668277;68503.3149668507;68685.65657171683;71131;71298.9305160423;71494.85515434545;71562.9265561017;71576.64211678908;16656.50030499087;17229.65540689179;17248.913755431393;17294.913525432545;17295.56761080983;11215.990560849563;11961.88038119625;12336.938315308365;13035.413406396736;13087.541936032194;18124.728129078074;18878.3392581259;19144.617107658047;19167.424977250706;19402.70895936534;5945.227120474397;6381.978883378637;6772.966135169293;6794.728210871609;6858.828529286808;787.3897729259869;881.2201201936205;886.5567216391804;921.3734660430837;922.4050487435567;27766.583501247496;29331.85334073316;30506.38987220243;30660.265058328652;30683.539746903814;932.440535678601;1064.3656949051037;1079.0773888325525;1098.4672433869566;1120.6750042487588;15824.129672868103;15861.127637980022;15952.122633255283;16186.676266474604;16830.99014059136;214489;218274;218310;218627;219003;63323;63460;63582.68208658927;63586.36413635897;63611.68194159092;1426.12293439536;1452.869241768267;1487.8586534279227;1489.791429199913;1492.417956996772;29300.267493312836;30254.243643909078;30556.541651875683;30669.410507177643;30940.69059309468;50700.49299507105;53294.46705532895;54410.45589544054;55227.44772552223;55283.72358138183;431065;431218;431586;431731;432917;42758.78620606877;43269.78365108154;43660.56339436565;43752.781236093615;43864.780676096416;47514.52485475101;47755.52244477511;48139.75930120327;48203;48765.293214737314;37953.62046379501;39203.41194882136;39644.008899776585;41438.58561414347;41598;38404.46382144683;39350;39490.802545987084;40251.59748402596;41299.793501032305;36983.6301636991;37287.627123728416;37373.4393984085;37383.6261637391;38310.42534362042;32523.674763252064;33296.833515832266;33821.492677610346;33923.83038084794;35045.77339793095;22428.32715018552;24465;24862.751372486044;25105.24684259475;25105.87447062753 09:46:58 INFO - PROCESS | 2428 | |2;jquery.html;337.76525314906144;372.56782132726033;389.4022675193577;406.10656555577737;423.36917992191644;19055.809441905592;20988;21103.47241318964;21114.683279750818;21264.36206913795;20936.057877395517;20972.475688107806;21630.783692163073;21654.675179872316;21847.890760546215;4622.722636641813;5757.481826635602;5990.5806593538255;6015.789447369343;6035.728392222339;5913.645181289115;5971.164037034819;5973.22348094747;5983.491403230731;5987.43119403656;6535.477161827042;6833.692483838215;6873.106496155518;6901.482388820833;6908.1364829396325;7595.5442673439775;7717.151113377524;7774.261445162757;7785.377169826429;7888.447808653396;6981.197162326347;6983.82540436488;7702.730404435818;7709.42179336552;7714.99705038347;24090.675012874286;28966.710332896822;29205.85397073001;29764.958226462186;29825;2349.800266977309;2451.9999999999823;2850.1876965064857;2916.402137561795;2944.720251576097;16042.278097485641;17108.82891171097;17190.82809171905;17366.392176273897;17596.91201543984;1682.4195652499868;1937.6705959986696;1949.9512512187166;1973.9309124180584;1977.555050113713;141656;142361;144616.2769186158;149064.254678727;151863;23165.841707914875;24419.63370549443;24498.142565010137;24559.38601534976;24884.751152488607;42161.57838421638;42515.57484425178;43457.348139777925;43501.56498434975;43667.78166109213;8396.748097557083;8569.185927336894;8857.070007649105;8876.778080548038;8898.243649289754;8637.179467950535;8658.610362533702;8692.478816207138;8808.251298639687;8820.294376449869;4813.927791083067;4831.4685384607665;4920.975395123001;4926.261060840831;5199.324087868628;2015.748031496063;2045.7136000959877;2054.065400242886;2058.835293176542;2068;1009.848522721583;1018.6587493189717;1025.2105878473683;1031.376017509396;1063.7925604507202;20947.790522094583;21190;21267.255646052316;21375.358739237847;21423.67864482034;1032.7108409645311;1038.6341960322152;1060.877999030106;1074.8280275156094;1085.7502774361942;10931.56273749057;10986.945065274622;11045.944770276097;11063.170262230366;11069;772061;772653;774958;788829;788963;112106.43946780213;140971;165482.3451765491;165941.340586595;167647.48528771833;997.7256254529917;1012.6759436980243;1032.6592224565875;1041.317936751429;1081.8214994525815;19542;19841;19962.90018549898;20220.494487637927;20280.59438811245;57707.71146144242;60072.69963650154;60507.78984420199;60771.69614152078;62148.892555371145;86219.5689021551;86419.56790216008;88576.11423885936;91086.08913910776;91228;6851.383375496229;7065.975433562002;8350.58040133191;8523.829523409373;8533.87199192025;8788.648454061766;12932;12965.35173241353;13253.734925301633;13472.528461504193;9101.954490227772;9821.607135714778;11397.97418232363;11451.969322760991;11655.475503602189;10398.22013349017;10997.717134479288;11284.717882053013;11586.362750048827;11644.708882278012;11307.491162897853;11419.429028548706;11713.180077394842;11740.295582265091;11846.644600661994;6964.686589103401;7473.364763995121;7731.38148948094;7770.728024519115;7844.960775196316;10303.278770486293;11142.888571114185;11218.66344009681;11444.771104578029;11472.827907581226 09:46:58 INFO - PROCESS | 2428 | |3;mootools.html;1271.7901546244866;1348.6426097084268;1401.1102949626986;1410.2032351721277;1441.5819412370413;7417.554946703188;7666.386689064875;8052.194780521945;8434.746957591267;8475.279601233895;7912.169222231669;8170.142135075821;8240.752777416687;8339.999200095981;8360.456570322925;2274.4086537500284;2320.269115228706;2321.6517522371696;2322.953540929189;2330.5105927755108;1988.3339081407673;2069.617120832643;2301.436148143707;2344.202970989863;2349.6254691005784;2225.7774222577696;2315.4833584002504;2322.001539338084;2323.337848713117;2340.96488552671;2110.2086717480943;2122.7558830734433;2275.635898256271;2319.3389883883015;2330.196082351586;2338.754430784777;2341.906323747048;2344.800691941187;2346.835721499496;2357.26924653358;8333.416660833744;8407.663693452254;8411.200935911078;8416.663333466655;8416.705415310496;5055.873603159914;5212.061828870802;5214.791408343662;5216.9739151304;5219;3479.1823921378455;3499.7900125992523;3513.3149035938004;3559.288142371536;3575.821208939569;1213.2720367779261;1214.8542174938975;1257.9308138052495;1262.2552693910636;1270.7585558743808;10539;10765.62320318793;10837.783244335147;10849.294795838245;10852.240343175981;4384.6273066789045;4677.766111694402;4707.082118986833;4741.478437371887;4749.64377671676;9416.764580885532;9483.288753343526;9505.619775209052;9513.714588562354;9515.762105947406;5227.372715274191;5240.4759524047295;5249.921251181236;5253.553447956967;5260.237265596467;4629;4660.39414876064;4697.718136911796;4808.7595620219545;4814.638902082288;3914.64768170866;3947.842086316515;3972.5828787977425;4008.6191811777835;4063.004563881868;1185.247367921376;1226.7730469863104;1275.7639836630253;1294.695746499572;1299.9675008124873;734.6179986407038;757.526545908807;767.6046835879415;805.2672068417712;828.6850996621246;5348.411674715856;5353.357597088374;5435.1847222916085;5614.719264036782;5645.237892884408;958.1568219966385;987.521052289639;1001.1490233301636;1002.9548670309854;1023.1712313026323;3253.4469140246215;3569.5538057742783;3630.2739452109686;3737.4393840923535;3756.6806821420487;9294.209992150607;10014.499275036365;10034;10039.648612298535;10055.69832905014;9760.023997600183;9887.159591434665;9910.900890991143;9931.453770042717;9993.500324983721;7399.48203625741;7723.845523089507;7752.689892404241;7789.610519474117;7839.725609603751;5489.231507589021;5519.944800551943;5540.972295138498;5663.490285874291;5664.0937450007805;8704.865189035158;8993;9039.774005649911;9104.271658267191;9133.908660913306;9027.50348730826;9264.44413335202;9268.72193834186;9350.532473376168;9362.672306469241;7627.656755445907;7859.214078592095;7888.960555197187;7919.049714034353;7932.682692692228;6462.806115816533;7806.570638614704;7925.0886148093705;7926.405519585939;7926.603669816602;5605.887882242251;6931.168259808654;7081.539699919489;7127.964360178166;7263.382612477787;6907.447404207752;7007.6846541904715;7008.194057683228;7043.295670432915;7055.3650171484815;6288.27684816252;6321.905171422341;6351.142595749515;6365.267994180728;6414.262359828679;3973.7814420207164;4773.427188737373;4871;4930.630202734737;5041.269015992587;4817.879553011203;4920.704757714549;4948.752562371795;5013.598912087097;5049.040682270357 09:46:58 INFO - PROCESS | 2428 | |4;prototype.html;340;352.6209324975652;358.6216541548666;359.58647555311387;361.20174414543857;5176.637635365521;5234.293370394996;5340.706261155645;5385.526335513591;5576.33084029916;4487.932681009779;4896.314515967759;5305.445351614418;5342.889558624341;5423.864403389917;4203.201391735561;4220.391565211754;4233.920350310682;4249.766262855542;4250.298700714378;4076.51081870176;4159.584041595846;4211.97894010531;4222.8310867565415;4229.830806767725;4130.822715526076;4164.625183733463;4168.832726836491;4182.393552934823;4207.368894665794;3807.6482848588907;3930.3318435865826;4004.619561141687;4201.970517223269;4204.516480604739;3344.1973926257765;3718.609545997658;3733.1413774831667;3743.2300097927337;3747.4940882980723;4401.163778882003;4858.51815196365;4991.425986011619;4997.375328083989;5231.581473482112;4262.744235345858;4623.144718227139;4784.6889952153;4878.512148785128;4909.165441874854;4284.871453856389;4831.9275210871865;4948.426818054591;5542.86142846428;5659.151127330892;3937.4881265435597;4040.151568170688;4040.7979601019833;4067.905028910084;4069.2878746219294;5454.481824226692;5941.049432090888;6001.579889407743;6081.604695694766;6084.878302433926;3637.436197389409;4161.542230354658;4165.166966606691;4172.228137794517;4207.705460617789;3402.897913062612;3416.6241713411505;3706.369917114097;4148.79256037197;4248.299030659969;4078.7552746835286;4439.533848945879;4462.464504259509;4486.282194848874;4893.045856058035;5304.9734751326;5625.915611265835;5790.681512516852;5821.2141360916585;5857.7949771757785;1218.6831423829867;1269.6762325607096;1289.3939848271298;1335.045442508611;1391.1583491987403;927.803469051335;943.3067643579791;943.3444305244252;959.9856002159974;968.3899143539666;5164.302819119446;5177.430482646906;5187.533122018961;5201.5786581406255;5239.607029472805;5377.542908852792;5490.396056433789;5567.387587365387;5706.743196556165;5776.566757493205;4559.658025648089;4616.446026476844;4633.745144017112;4694.366260554782;5252.501012403815;2660.9467810643678;2669.346010227468;2730.863456827151;2922.4657055046273;2924.8830046798316;5018.397792264878;5218.633981082181;5608.9439105609235;5813.476787089182;5842.503387212049;5346.91979620306;5361.8123365682795;5395.219577539403;5474.698891561003;5696.430356964353;4790.508935353666;5123.87190320245;5432.0765469871;5610.905873354738;5755.482006619424;4625;5028.949710502849;5384.307846076961;5539.473749993744;5573.749181286771;4400.427944367212;4640.86077417678;4976.009596161564;5169.889937499622;5488.56091512686;5393.97514472249;5689.630174038674;5734.770609175586;5763.279590051244;5808.30300363959;3812.9115416512714;3943.397357743875;3948.1511475033008;3960.2475529649273;3982.581828908039;5565.443455654402;5589.283126720848;5690.492019614722;5757.646952965966;5860.94139058621;5257.868553286198;5363.812266570651;5561.054620714569;5676.829695108988;5677.990664947584;5691.231683722644;5722.971385143214;5758.798442054508;5770.278715160605;5835.44563266489;4577.221872281787;5344.866378340573;5575.964772857797;5616.775328986795;5700.600957932905;4519.389882365838;5034.841986343088;5526.309211348582;5717.342505611741;5796.76812927495;5452.100403433547;5549.611527193058;5603.635763675348;5610.410906854721;5659.717363928994 09:46:58 INFO - PROCESS | 2428 | |5;yui.html;694.5346617766096;697.5465947134365;700.4396482813747;809.9433039687223;812.9350550778481;14442.133471991674;15008.024478408923;15285;15420.228988550556;15422.845771542281;14175.858241417583;14280.21458819767;14397.712045759105;14404.207768572724;14408.927955360236;846.4455781463151;854.5043874552775;854.9700760473385;855.7689423855586;855.9315254779634;669.9832504187385;683.9726410943556;830.779843341515;849.9830003399928;867.5315329721974;904.3759805734055;905.1943770044666;906.0214967834755;908.3006085314304;908.9818203635923;895.9641614335419;897.9595918183697;898.3307435960186;898.3352319283748;898.9190972812446;665.8235567574549;670.5038271678923;672.5191488086042;673.3603077076748;675.1729131813489;14408.567742967727;14871.925640371837;15022.549323520203;15056;15087.170205638686;13852.653683657889;14391;14432.134071955716;14531.854681453156;14535.854641453556;11347.829782553268;11642;11650.475728592233;11654.359010254517;11657.883421165765;3603.621619729917;3656.433252845813;3683.0424089736593;3720.609336019706;3722.1997270586685;78809;103442;105214.47392762988;106540;106666.86666266709;10615.575376984834;10847.783044339069;10873.401962892116;11177.72055698614;11619.535218591162;23784.643230351558;23975.640365394534;23991.880040599684;24005.27984160478;24022.7597724024;7642.961785191039;7664.9616751917;7702;7800.687972481151;7822.06135263772;4125.29869922114;4152.626263636227;4216.915661686749;4230;4372.060007098489;3533.911652208715;3633.237020225782;3639.9808053744996;3644.0525463379713;3649.5802982657333;578.8060999565108;590.0824218340435;600.5435868739702;603.46894732635;610.2524407600743;547.6440313795985;561.1274468201985;566.396787421395;574.1855735874616;576.4091805898987;9723.173530249867;10111.292209545409;10143.7464063399;10372.377657340432;10424.635137770141;550.6695982410522;558.4499268220799;588.1236956934138;600.879824035186;663.233964770681;4457.687961842705;4919.385076865392;4967.5032496750755;5006.0989021975665;5008.699478031257;103989.48005259925;105699.47150264353;106233;106759;108449;76670;89962.65056024164;90937.54531227301;92362.53818730998;95362.09275814446;53056.46943530515;71522.64238678773;82115;82130.58934705288;84091.57954210312;8264.628091736007;9514.38156519824;9568.569414376509;9569.138777510057;9584.233261339215;38665.42001869918;49077.754611226715;50357.24464132968;50435.982560697164;51368.74315628398;52568;66286.66856665685;66996;68120.76180203949;68348;9371.531423428938;11170.27393219438;11395.772084558428;11516.712082198012;11933.16467847277;12162.128422408488;12474.126811123135;12483.687907802378;12703.42834572465;12840.42218100169;11658.358790266617;11784.116191285517;11851.940740296242;11893.46479408446;11975.461104250155;11056.186934683283;11682.182247242612;11708.238964467282;11907.702307442383;11992.11673601015;11288.661340159808;11664.88335116672;11732.706682332668;11742.823857641972;11744.765104697688;9740.729109027328;9922.657979159989;10096.444695541522;10163.288569800043;10637.095846853104;9328.906710933075;9683.9031609683;9836.311458197857;9850.113489785954;9852 09:46:58 INFO - PROCESS | 2428 | __end_tp_report 09:46:58 INFO - PROCESS | 2428 | __start_cc_report 09:46:58 INFO - PROCESS | 2428 | _x_x_mozilla_cycle_collect,383 09:46:58 INFO - PROCESS | 2428 | __end_cc_report 09:46:58 INFO - PROCESS | 2428 | __startTimestamp1487526418829__endTimestamp 09:46:58 INFO - PROCESS | 2428 | 09:46:58 INFO - PROCESS | 2428 | ------- Summary: start ------- 09:46:58 INFO - PROCESS | 2428 | Number of tests: 6 09:46:58 INFO - PROCESS | 2428 | 09:46:58 INFO - PROCESS | 2428 | [#0] dojo.html Cycles:165 Average:5716.60 Median:5914.67 stddev:636.59 (10.8%) stddev-sans-first:610.37 09:46:58 INFO - PROCESS | 2428 | Values: 3329.3 3363.4 3376.1 3380.6 3383.9 4336.6 5553.7 5624.0 5632.9 5743.5 5670.0 5696.3 5696.6 5705.1 5744.9 4484.1 5159.1 5224.9 5258.5 5289.5 5133.6 5257.6 5262.0 5265.3 5269.7 5100.3 5122.4 5226.6 5258.8 5290.7 5212.3 5259.4 5273.6 5281.4 5333.5 5084.0 5203.0 5208.1 5228.5 5294.0 5534.7 5703.1 5707.3 5785.6 5786.5 6124.0 6192.3 6201.5 6214.3 6280.1 6222.3 6238.3 6238.4 6239.3 6261.1 5845.5 5856.4 5866.2 5873.2 5909.6 6176.1 6209.9 6235.1 6240.1 6252.6 6097.1 6144.8 6155.5 6158.2 6159.6 5482.4 6150.5 6196.3 6213.1 6236.0 6097.6 6138.6 6141.3 6170.1 6199.6 6246.2 6255.5 6256.7 6275.6 6288.1 5005.0 5027.5 5029.9 5036.6 5048.8 4347.1 4364.1 4403.6 4471.1 4503.4 5745.9 5755.0 5822.1 5853.4 5856.8 5443.8 5998.8 6254.6 6264.8 6277.2 5652.2 5693.2 5743.8 5752.7 5789.8 5477.1 5483.0 5483.8 5489.8 5503.7 5398.8 6369.1 6371.7 6380.1 6395.1 5279.2 5697.5 6193.5 6214.8 6252.5 6147.8 6209.5 6324.0 6324.1 6324.5 6160.5 6179.2 6186.1 6206.6 6207.8 6038.6 6123.1 6238.0 6241.9 6258.7 5960.3 6016.6 6076.7 6099.1 6147.4 6106.6 6108.8 6118.4 6119.2 6138.0 5905.9 5914.7 5920.1 5950.4 6050.2 5883.9 5941.5 6015.2 6051.1 6101.8 5946.7 5955.6 5959.2 5965.2 6011.5 09:46:58 INFO - PROCESS | 2428 | 09:46:58 INFO - PROCESS | 2428 | [#1] ext.html Cycles:180 Average:54177.50 Median:21127.08 stddev:97944.51 (463.6%) stddev-sans-first:98136.23 09:46:58 INFO - PROCESS | 2428 | Values: 460.0 472.9 505.6 513.8 514.5 39526.4 42643.9 42704.4 42894.5 42981.3 44407.1 44719.8 44860.8 44929.6 45069.3 8469.3 9106.2 9338.5 9358.2 9379.4 8500.6 11244.8 11513.1 11545.0 11695.2 11230.5 11289.1 11295.0 11310.8 11363.1 11306.9 11334.4 11342.3 11375.6 11416.0 11222.9 11229.4 11236.7 11302.7 11304.9 41213.6 41265.0 41491.4 41566.4 41605.4 3623.9 3849.7 3926.1 3965.6 4022.8 29947.1 30557.7 30807.4 31025.0 31134.5 3122.9 3161.3 3193.0 3196.6 3201.7 413110.0 422799.0 424251.0 424778.0 425320.0 67589.0 67645.0 68273.3 68503.3 68685.7 71131.0 71298.9 71494.9 71562.9 71576.6 16656.5 17229.7 17248.9 17294.9 17295.6 11216.0 11961.9 12336.9 13035.4 13087.5 18124.7 18878.3 19144.6 19167.4 19402.7 5945.2 6382.0 6773.0 6794.7 6858.8 787.4 881.2 886.6 921.4 922.4 27766.6 29331.9 30506.4 30660.3 30683.5 932.4 1064.4 1079.1 1098.5 1120.7 15824.1 15861.1 15952.1 16186.7 16831.0 214489.0 218274.0 218310.0 218627.0 219003.0 63323.0 63460.0 63582.7 63586.4 63611.7 1426.1 1452.9 1487.9 1489.8 1492.4 29300.3 30254.2 30556.5 30669.4 30940.7 50700.5 53294.5 54410.5 55227.4 55283.7 431065.0 431218.0 431586.0 431731.0 432917.0 42758.8 43269.8 43660.6 43752.8 43864.8 47514.5 47755.5 48139.8 48203.0 48765.3 37953.6 39203.4 39644.0 41438.6 41598.0 38404.5 39350.0 39490.8 40251.6 41299.8 36983.6 37287.6 37373.4 37383.6 38310.4 32523.7 33296.8 33821.5 33923.8 35045.8 22428.3 24465.0 24862.8 25105.2 25105.9 09:46:58 INFO - PROCESS | 2428 | 09:46:58 INFO - PROCESS | 2428 | [#2] jquery.html Cycles:180 Average:43622.04 Median:13475.07 stddev:129639.88 (962.1%) stddev-sans-first:129962.82 09:46:58 INFO - PROCESS | 2428 | Values: 337.8 372.6 389.4 406.1 423.4 19055.8 20988.0 21103.5 21114.7 21264.4 20936.1 20972.5 21630.8 21654.7 21847.9 4622.7 5757.5 5990.6 6015.8 6035.7 5913.6 5971.2 5973.2 5983.5 5987.4 6535.5 6833.7 6873.1 6901.5 6908.1 7595.5 7717.2 7774.3 7785.4 7888.4 6981.2 6983.8 7702.7 7709.4 7715.0 24090.7 28966.7 29205.9 29765.0 29825.0 2349.8 2452.0 2850.2 2916.4 2944.7 16042.3 17108.8 17190.8 17366.4 17596.9 1682.4 1937.7 1950.0 1973.9 1977.6 141656.0 142361.0 144616.3 149064.3 151863.0 23165.8 24419.6 24498.1 24559.4 24884.8 42161.6 42515.6 43457.3 43501.6 43667.8 8396.7 8569.2 8857.1 8876.8 8898.2 8637.2 8658.6 8692.5 8808.3 8820.3 4813.9 4831.5 4921.0 4926.3 5199.3 2015.7 2045.7 2054.1 2058.8 2068.0 1009.8 1018.7 1025.2 1031.4 1063.8 20947.8 21190.0 21267.3 21375.4 21423.7 1032.7 1038.6 1060.9 1074.8 1085.8 10931.6 10986.9 11045.9 11063.2 11069.0 772061.0 772653.0 774958.0 788829.0 788963.0 112106.4 140971.0 165482.3 165941.3 167647.5 997.7 1012.7 1032.7 1041.3 1081.8 19542.0 19841.0 19962.9 20220.5 20280.6 57707.7 60072.7 60507.8 60771.7 62148.9 86219.6 86419.6 88576.1 91086.1 91228.0 6851.4 7066.0 8350.6 8523.8 8533.9 8788.6 12932.0 12965.4 13253.7 13472.5 9102.0 9821.6 11398.0 11452.0 11655.5 10398.2 10997.7 11284.7 11586.4 11644.7 11307.5 11419.4 11713.2 11740.3 11846.6 6964.7 7473.4 7731.4 7770.7 7845.0 10303.3 11142.9 11218.7 11444.8 11472.8 09:46:58 INFO - PROCESS | 2428 | 09:46:58 INFO - PROCESS | 2428 | [#3] mootools.html Cycles:180 Average:5413.64 Median:5217.99 stddev:2947.08 (56.5%) stddev-sans-first:2938.91 09:46:58 INFO - PROCESS | 2428 | Values: 1271.8 1348.6 1401.1 1410.2 1441.6 7417.6 7666.4 8052.2 8434.7 8475.3 7912.2 8170.1 8240.8 8340.0 8360.5 2274.4 2320.3 2321.7 2323.0 2330.5 1988.3 2069.6 2301.4 2344.2 2349.6 2225.8 2315.5 2322.0 2323.3 2341.0 2110.2 2122.8 2275.6 2319.3 2330.2 2338.8 2341.9 2344.8 2346.8 2357.3 8333.4 8407.7 8411.2 8416.7 8416.7 5055.9 5212.1 5214.8 5217.0 5219.0 3479.2 3499.8 3513.3 3559.3 3575.8 1213.3 1214.9 1257.9 1262.3 1270.8 10539.0 10765.6 10837.8 10849.3 10852.2 4384.6 4677.8 4707.1 4741.5 4749.6 9416.8 9483.3 9505.6 9513.7 9515.8 5227.4 5240.5 5249.9 5253.6 5260.2 4629.0 4660.4 4697.7 4808.8 4814.6 3914.6 3947.8 3972.6 4008.6 4063.0 1185.2 1226.8 1275.8 1294.7 1300.0 734.6 757.5 767.6 805.3 828.7 5348.4 5353.4 5435.2 5614.7 5645.2 958.2 987.5 1001.1 1003.0 1023.2 3253.4 3569.6 3630.3 3737.4 3756.7 9294.2 10014.5 10034.0 10039.6 10055.7 9760.0 9887.2 9910.9 9931.5 9993.5 7399.5 7723.8 7752.7 7789.6 7839.7 5489.2 5519.9 5541.0 5663.5 5664.1 8704.9 8993.0 9039.8 9104.3 9133.9 9027.5 9264.4 9268.7 9350.5 9362.7 7627.7 7859.2 7889.0 7919.0 7932.7 6462.8 7806.6 7925.1 7926.4 7926.6 5605.9 6931.2 7081.5 7128.0 7263.4 6907.4 7007.7 7008.2 7043.3 7055.4 6288.3 6321.9 6351.1 6365.3 6414.3 3973.8 4773.4 4871.0 4930.6 5041.3 4817.9 4920.7 4948.8 5013.6 5049.0 09:46:58 INFO - PROCESS | 2428 | 09:46:58 INFO - PROCESS | 2428 | [#4] prototype.html Cycles:180 Average:4541.46 Median:5023.67 stddev:1351.43 (26.9%) stddev-sans-first:1317.91 09:46:58 INFO - PROCESS | 2428 | Values: 340.0 352.6 358.6 359.6 361.2 5176.6 5234.3 5340.7 5385.5 5576.3 4487.9 4896.3 5305.4 5342.9 5423.9 4203.2 4220.4 4233.9 4249.8 4250.3 4076.5 4159.6 4212.0 4222.8 4229.8 4130.8 4164.6 4168.8 4182.4 4207.4 3807.6 3930.3 4004.6 4202.0 4204.5 3344.2 3718.6 3733.1 3743.2 3747.5 4401.2 4858.5 4991.4 4997.4 5231.6 4262.7 4623.1 4784.7 4878.5 4909.2 4284.9 4831.9 4948.4 5542.9 5659.2 3937.5 4040.2 4040.8 4067.9 4069.3 5454.5 5941.0 6001.6 6081.6 6084.9 3637.4 4161.5 4165.2 4172.2 4207.7 3402.9 3416.6 3706.4 4148.8 4248.3 4078.8 4439.5 4462.5 4486.3 4893.0 5305.0 5625.9 5790.7 5821.2 5857.8 1218.7 1269.7 1289.4 1335.0 1391.2 927.8 943.3 943.3 960.0 968.4 5164.3 5177.4 5187.5 5201.6 5239.6 5377.5 5490.4 5567.4 5706.7 5776.6 4559.7 4616.4 4633.7 4694.4 5252.5 2660.9 2669.3 2730.9 2922.5 2924.9 5018.4 5218.6 5608.9 5813.5 5842.5 5346.9 5361.8 5395.2 5474.7 5696.4 4790.5 5123.9 5432.1 5610.9 5755.5 4625.0 5028.9 5384.3 5539.5 5573.7 4400.4 4640.9 4976.0 5169.9 5488.6 5394.0 5689.6 5734.8 5763.3 5808.3 3812.9 3943.4 3948.2 3960.2 3982.6 5565.4 5589.3 5690.5 5757.6 5860.9 5257.9 5363.8 5561.1 5676.8 5678.0 5691.2 5723.0 5758.8 5770.3 5835.4 4577.2 5344.9 5576.0 5616.8 5700.6 4519.4 5034.8 5526.3 5717.3 5796.8 5452.1 5549.6 5603.6 5610.4 5659.7 09:46:58 INFO - PROCESS | 2428 | 09:46:58 INFO - PROCESS | 2428 | [#5] yui.html Cycles:180 Average:19901.52 Median:4414.87 stddev:29073.34 (658.5%) stddev-sans-first:29119.13 09:46:58 INFO - PROCESS | 2428 | Values: 694.5 697.5 700.4 809.9 812.9 14442.1 15008.0 15285.0 15420.2 15422.8 14175.9 14280.2 14397.7 14404.2 14408.9 846.4 854.5 855.0 855.8 855.9 670.0 684.0 830.8 850.0 867.5 904.4 905.2 906.0 908.3 909.0 896.0 898.0 898.3 898.3 898.9 665.8 670.5 672.5 673.4 675.2 14408.6 14871.9 15022.5 15056.0 15087.2 13852.7 14391.0 14432.1 14531.9 14535.9 11347.8 11642.0 11650.5 11654.4 11657.9 3603.6 3656.4 3683.0 3720.6 3722.2 78809.0 103442.0 105214.5 106540.0 106666.9 10615.6 10847.8 10873.4 11177.7 11619.5 23784.6 23975.6 23991.9 24005.3 24022.8 7643.0 7665.0 7702.0 7800.7 7822.1 4125.3 4152.6 4216.9 4230.0 4372.1 3533.9 3633.2 3640.0 3644.1 3649.6 578.8 590.1 600.5 603.5 610.3 547.6 561.1 566.4 574.2 576.4 9723.2 10111.3 10143.7 10372.4 10424.6 550.7 558.4 588.1 600.9 663.2 4457.7 4919.4 4967.5 5006.1 5008.7 103989.5 105699.5 106233.0 106759.0 108449.0 76670.0 89962.7 90937.5 92362.5 95362.1 53056.5 71522.6 82115.0 82130.6 84091.6 8264.6 9514.4 9568.6 9569.1 9584.2 38665.4 49077.8 50357.2 50436.0 51368.7 52568.0 66286.7 66996.0 68120.8 68348.0 9371.5 11170.3 11395.8 11516.7 11933.2 12162.1 12474.1 12483.7 12703.4 12840.4 11658.4 11784.1 11851.9 11893.5 11975.5 11056.2 11682.2 11708.2 11907.7 11992.1 11288.7 11664.9 11732.7 11742.8 11744.8 9740.7 9922.7 10096.4 10163.3 10637.1 9328.9 9683.9 9836.3 9850.1 9852.0 09:46:58 INFO - PROCESS | 2428 | -------- Summary: end -------- 09:46:58 INFO - PROCESS | 2428 | 09:46:59 INFO - TEST-INFO | 2428: exit 0 09:46:59 INFO - TEST-OK | dromaeo_css | took 1116142ms 09:46:59 INFO - TEST-START | kraken 09:46:59 INFO - operating with platform_type : mac_ 09:46:59 INFO - Initialising browser for kraken test... 09:46:59 INFO - TEST-INFO | started process 2549 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/tmpZAaAr8/profile http://localhost:49243/getInfo.html) 09:47:02 INFO - PROCESS | 2549 | 2017-02-19 09:47:02.257 plugin-container[2550:17575] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x473b, name = 'com.apple.tsm.portname' 09:47:02 INFO - PROCESS | 2549 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:47:02 INFO - PROCESS | 2549 | 2017-02-19 09:47:02.260 plugin-container[2550:17575] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x441b, name = 'com.apple.CFPasteboardClient' 09:47:02 INFO - PROCESS | 2549 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:47:02 INFO - PROCESS | 2549 | 2017-02-19 09:47:02.260 plugin-container[2550:17575] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:47:02 INFO - PROCESS | 2549 | __metrics Screen width/height:1600/1200 09:47:02 INFO - PROCESS | 2549 | colorDepth:24 09:47:02 INFO - PROCESS | 2549 | Browser inner width/height: 1024/689 09:47:02 INFO - PROCESS | 2549 | __metrics 09:47:02 ERROR - PROCESS | 2549 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 09:47:03 INFO - TEST-INFO | 2549: exit 0 09:47:03 INFO - Browser initialized. 09:47:03 INFO - Running cycle 1/1 for kraken test... 09:47:03 INFO - TEST-INFO | started process 2551 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/tmpZAaAr8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:47:05 INFO - PROCESS | 2551 | 2017-02-19 09:47:05.644 plugin-container[2552:17707] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x473f, name = 'com.apple.tsm.portname' 09:47:05 INFO - PROCESS | 2551 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:47:05 INFO - PROCESS | 2551 | 2017-02-19 09:47:05.645 plugin-container[2552:17707] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c23, name = 'com.apple.CFPasteboardClient' 09:47:05 INFO - PROCESS | 2551 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:47:05 INFO - PROCESS | 2551 | 2017-02-19 09:47:05.645 plugin-container[2552:17707] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:47:15 INFO - PROCESS | 2551 | RSS: Main: 219090944 09:47:15 INFO - PROCESS | 2551 | 09:47:18 INFO - PROCESS | 2551 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection) 09:47:19 INFO - PROCESS | 2551 | RSS: Main: 233668608 09:47:19 INFO - PROCESS | 2551 | 09:47:22 INFO - PROCESS | 2551 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-dft) 09:47:22 INFO - PROCESS | 2551 | RSS: Main: 241606656 09:47:22 INFO - PROCESS | 2551 | 09:47:26 INFO - PROCESS | 2551 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-fft) 09:47:26 INFO - PROCESS | 2551 | RSS: Main: 245366784 09:47:26 INFO - PROCESS | 2551 | 09:47:28 INFO - PROCESS | 2551 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator) 09:47:29 INFO - PROCESS | 2551 | RSS: Main: 245686272 09:47:29 INFO - PROCESS | 2551 | 09:47:31 INFO - PROCESS | 2551 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:47:31 INFO - PROCESS | 2551 | RSS: Main: 245686272 09:47:31 INFO - PROCESS | 2551 | 09:47:34 INFO - PROCESS | 2551 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom) 09:47:35 INFO - PROCESS | 2551 | RSS: Main: 251559936 09:47:35 INFO - PROCESS | 2551 | 09:47:38 INFO - PROCESS | 2551 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate) 09:47:39 INFO - PROCESS | 2551 | RSS: Main: 250269696 09:47:39 INFO - PROCESS | 2551 | 09:47:42 INFO - PROCESS | 2551 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial) 09:47:43 INFO - PROCESS | 2551 | RSS: Main: 250425344 09:47:43 INFO - PROCESS | 2551 | 09:47:45 INFO - PROCESS | 2551 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:47:45 INFO - PROCESS | 2551 | RSS: Main: 249954304 09:47:45 INFO - PROCESS | 2551 | 09:47:47 INFO - PROCESS | 2551 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:47:48 INFO - PROCESS | 2551 | RSS: Main: 249909248 09:47:48 INFO - PROCESS | 2551 | 09:47:50 INFO - PROCESS | 2551 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:47:51 INFO - PROCESS | 2551 | RSS: Main: 249987072 09:47:51 INFO - PROCESS | 2551 | 09:47:54 INFO - PROCESS | 2551 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:47:54 INFO - PROCESS | 2551 | RSS: Main: 250257408 09:47:54 INFO - PROCESS | 2551 | 09:47:57 INFO - PROCESS | 2551 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:47:58 INFO - PROCESS | 2551 | RSS: Main: 250073088 09:47:58 INFO - PROCESS | 2551 | 09:48:00 INFO - PROCESS | 2551 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49243/tests/kraken/driver.html?testName=ai-astar) 09:48:00 INFO - PROCESS | 2551 | RSS: Main: 252018688 09:48:00 INFO - PROCESS | 2551 | 09:48:00 INFO - PROCESS | 2551 | __start_tp_report 09:48:00 INFO - PROCESS | 2551 | _x_x_mozilla_page_load 09:48:00 INFO - PROCESS | 2551 | _x_x_mozilla_page_load_details 09:48:00 INFO - PROCESS | 2551 | |i|pagename|runs| 09:48:00 INFO - PROCESS | 2551 | |0;ai-astar;105;118;119;117;119;140;153;119;118;117 09:48:00 INFO - PROCESS | 2551 | |1;audio-beat-detection;145;112;109;159;142;143;147;111;130;112 09:48:00 INFO - PROCESS | 2551 | |2;audio-dft;197;193;193;204;197;195;245;217;261;273 09:48:00 INFO - PROCESS | 2551 | |3;audio-fft;80;57;61;83;61;59;63;73;63;52 09:48:00 INFO - PROCESS | 2551 | |4;audio-oscillator;90;69;112;61;88;104;115;102;83;81 09:48:00 INFO - PROCESS | 2551 | |5;imaging-gaussian-blur;91;91;92;95;78;82;138;134;108;89 09:48:00 INFO - PROCESS | 2551 | |6;imaging-darkroom;104;135;152;128;140;166;101;114;158;120 09:48:00 INFO - PROCESS | 2551 | |7;imaging-desaturate;82;80;139;76;145;76;145;147;103;143 09:48:00 INFO - PROCESS | 2551 | |8;json-parse-financial;66;79;60;63;62;57;56;88;97;98 09:48:00 INFO - PROCESS | 2551 | |9;json-stringify-tinderbox;72;68;68;76;85;81;105;105;105;72 09:48:00 INFO - PROCESS | 2551 | |10;stanford-crypto-aes;77;82;85;84;86;89;85;85;84;84 09:48:00 INFO - PROCESS | 2551 | |11;stanford-crypto-ccm;150;162;140;138;138;143;138;149;217;160 09:48:00 INFO - PROCESS | 2551 | |12;stanford-crypto-pbkdf2;169;173;172;169;170;170;166;171;170;201 09:48:00 INFO - PROCESS | 2551 | |13;stanford-crypto-sha256-iterative;67;69;67;63;65;68;88;86;89;87 09:48:00 INFO - PROCESS | 2551 | __end_tp_report 09:48:00 INFO - PROCESS | 2551 | __start_cc_report 09:48:00 INFO - PROCESS | 2551 | _x_x_mozilla_cycle_collect,971 09:48:00 INFO - PROCESS | 2551 | __end_cc_report 09:48:00 INFO - PROCESS | 2551 | __startTimestamp1487526480423__endTimestamp 09:48:00 INFO - PROCESS | 2551 | 09:48:00 INFO - PROCESS | 2551 | ------- Summary: start ------- 09:48:00 INFO - PROCESS | 2551 | Number of tests: 14 09:48:00 INFO - PROCESS | 2551 | 09:48:00 INFO - PROCESS | 2551 | [#0] ai-astar Cycles:10 Average:122.50 Median:119.00 stddev:13.66 (11.5%) stddev-sans-first:12.94 09:48:00 INFO - PROCESS | 2551 | Values: 105.0 118.0 119.0 117.0 119.0 140.0 153.0 119.0 118.0 117.0 09:48:00 INFO - PROCESS | 2551 | 09:48:00 INFO - PROCESS | 2551 | [#1] audio-beat-detection Cycles:10 Average:131.00 Median:142.50 stddev:18.58 (13.0%) stddev-sans-first:19.01 09:48:00 INFO - PROCESS | 2551 | Values: 145.0 112.0 109.0 159.0 142.0 143.0 147.0 111.0 130.0 112.0 09:48:00 INFO - PROCESS | 2551 | 09:48:00 INFO - PROCESS | 2551 | [#2] audio-dft Cycles:10 Average:217.50 Median:210.50 stddev:30.66 (14.6%) stddev-sans-first:31.61 09:48:00 INFO - PROCESS | 2551 | Values: 197.0 193.0 193.0 204.0 197.0 195.0 245.0 217.0 261.0 273.0 09:48:00 INFO - PROCESS | 2551 | 09:48:00 INFO - PROCESS | 2551 | [#3] audio-fft Cycles:10 Average:65.20 Median:63.00 stddev:10.12 (16.1%) stddev-sans-first:9.21 09:48:00 INFO - PROCESS | 2551 | Values: 80.0 57.0 61.0 83.0 61.0 59.0 63.0 73.0 63.0 52.0 09:48:00 INFO - PROCESS | 2551 | 09:48:00 INFO - PROCESS | 2551 | [#4] audio-oscillator Cycles:10 Average:90.50 Median:75.00 stddev:17.83 (23.8%) stddev-sans-first:18.92 09:48:00 INFO - PROCESS | 2551 | Values: 90.0 69.0 112.0 61.0 88.0 104.0 115.0 102.0 83.0 81.0 09:48:00 INFO - PROCESS | 2551 | 09:48:00 INFO - PROCESS | 2551 | [#5] imaging-gaussian-blur Cycles:10 Average:99.80 Median:90.00 stddev:20.67 (23.0%) stddev-sans-first:21.67 09:48:00 INFO - PROCESS | 2551 | Values: 91.0 91.0 92.0 95.0 78.0 82.0 138.0 134.0 108.0 89.0 09:48:00 INFO - PROCESS | 2551 | 09:48:00 INFO - PROCESS | 2551 | [#6] imaging-darkroom Cycles:10 Average:131.80 Median:137.50 stddev:22.44 (16.3%) stddev-sans-first:21.43 09:48:00 INFO - PROCESS | 2551 | Values: 104.0 135.0 152.0 128.0 140.0 166.0 101.0 114.0 158.0 120.0 09:48:00 INFO - PROCESS | 2551 | 09:48:00 INFO - PROCESS | 2551 | [#7] imaging-desaturate Cycles:10 Average:113.60 Median:111.50 stddev:32.77 (29.4%) stddev-sans-first:32.70 09:48:00 INFO - PROCESS | 2551 | Values: 82.0 80.0 139.0 76.0 145.0 76.0 145.0 147.0 103.0 143.0 09:48:00 INFO - PROCESS | 2551 | 09:48:00 INFO - PROCESS | 2551 | [#8] json-parse-financial Cycles:10 Average:72.60 Median:72.50 stddev:16.48 (22.7%) stddev-sans-first:17.31 09:48:00 INFO - PROCESS | 2551 | Values: 66.0 79.0 60.0 63.0 62.0 57.0 56.0 88.0 97.0 98.0 09:48:00 INFO - PROCESS | 2551 | 09:48:00 INFO - PROCESS | 2551 | [#9] json-stringify-tinderbox Cycles:10 Average:83.70 Median:72.00 stddev:15.62 (21.7%) stddev-sans-first:15.98 09:48:00 INFO - PROCESS | 2551 | Values: 72.0 68.0 68.0 76.0 85.0 81.0 105.0 105.0 105.0 72.0 09:48:00 INFO - PROCESS | 2551 | 09:48:00 INFO - PROCESS | 2551 | [#10] stanford-crypto-aes Cycles:10 Average:84.10 Median:85.00 stddev:3.07 (3.6%) stddev-sans-first:1.90 09:48:00 INFO - PROCESS | 2551 | Values: 77.0 82.0 85.0 84.0 86.0 89.0 85.0 85.0 84.0 84.0 09:48:00 INFO - PROCESS | 2551 | 09:48:00 INFO - PROCESS | 2551 | [#11] stanford-crypto-ccm Cycles:10 Average:153.50 Median:149.50 stddev:24.02 (16.1%) stddev-sans-first:25.44 09:48:00 INFO - PROCESS | 2551 | Values: 150.0 162.0 140.0 138.0 138.0 143.0 138.0 149.0 217.0 160.0 09:48:00 INFO - PROCESS | 2551 | 09:48:00 INFO - PROCESS | 2551 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.10 Median:170.50 stddev:9.98 (5.9%) stddev-sans-first:10.48 09:48:00 INFO - PROCESS | 2551 | Values: 169.0 173.0 172.0 169.0 170.0 170.0 166.0 171.0 170.0 201.0 09:48:00 INFO - PROCESS | 2551 | 09:48:00 INFO - PROCESS | 2551 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.90 Median:77.50 stddev:10.99 (14.2%) stddev-sans-first:11.28 09:48:00 INFO - PROCESS | 2551 | Values: 67.0 69.0 67.0 63.0 65.0 68.0 88.0 86.0 89.0 87.0 09:48:00 INFO - PROCESS | 2551 | -------- Summary: end -------- 09:48:00 INFO - PROCESS | 2551 | 09:48:01 INFO - TEST-INFO | 2551: exit 0 09:48:01 INFO - TEST-OK | kraken | took 61826ms 09:48:01 INFO - SUITE-END | took 1177s 09:48:01 INFO - Completed test suite (00:19:38) 09:48:01 INFO - javascript benchmark 09:48:01 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7387.131286190031, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3329.2675611365507, 3363.360961417332, 3376.1222082258614, 3380.5605271314726, 3383.8646454141835, 4336.609705126538, 5553.72231388431, 5623.971880140603, 5632.943670563293, 5743.54051675865, 5669.971650141754, 5696.25948626679, 5696.572757043228, 5705.115707065401, 5744.942550574493, 4484.058347746961, 5159.071367153911, 5224.947750522503, 5258.500442457979, 5289.497497737728, 5133.563647090002, 5257.5531079858065, 5262.000219958215, 5265.262863199156, 5269.683818970855, 5100.311457953166, 5122.385313762354, 5226.55574276187, 5258.763355648985, 5290.656107353029, 5212.29633999409, 5259.447757985433, 5273.604479664041, 5281.39263984639, 5333.5199832015305, 5084.0, 5203.0, 5208.062548741225, 5228.47715228478, 5294.020606187868, 5534.723263836792, 5703.0589952657765, 5707.258056452676, 5785.623934444247, 5786.508146807527, 6123.984276525883, 6192.318844927055, 6201.472874805647, 6214.285357183937, 6280.057991301295, 6222.346653601352, 6238.251409830849, 6238.376162383771, 6239.2824825145235, 6261.060840873905, 5845.473907348359, 5856.4143585641095, 5866.2373891393845, 5873.177755114245, 5909.556783241274, 6176.135341052256, 6209.875802483925, 6235.064740288903, 6240.126382306481, 6252.5935814171935, 6097.085437184458, 6144.832481828527, 6155.538334624921, 6158.230221222348, 6159.630422174684, 5482.369527504388, 6150.538709596798, 6196.349383314777, 6213.068039794067, 6236.0, 6097.5731698780655, 6138.631682099089, 6141.332587233735, 6170.074488826712, 6199.597026193283, 6246.219222597176, 6255.530835187379, 6256.655883926428, 6275.5920865144535, 6288.056791481315, 5004.999000199975, 5027.522385373383, 5029.874253143701, 5036.622253331015, 5048.848534543896, 4347.130573885235, 4364.148990946798, 4403.603675669206, 4471.083427897273, 4503.414556107685, 5745.913811292834, 5754.971225143931, 5822.097574875901, 5853.443922827325, 5856.824295271149, 5443.836684899459, 5998.830228105565, 6254.593451425642, 6264.780732674426, 6277.2153480814895, 5652.152177173457, 5693.17448969897, 5743.798967036134, 5752.654840709571, 5789.768409263583, 5477.096279113902, 5483.0, 5483.835484935458, 5489.819688766935, 5503.697296648643, 5398.838034858961, 6369.108324834527, 6371.713272902731, 6380.074889141141, 6395.136656551306, 5279.1756410320295, 5697.544196464356, 6193.473554747897, 6214.782482613087, 6252.531060170414, 6147.846303842261, 6209.534284928558, 6323.968380158254, 6324.051392291193, 6324.525660575383, 6160.537959653134, 6179.16581261524, 6186.072089186658, 6206.627602343874, 6207.844803879939, 6038.63768173911, 6123.099607468126, 6237.96881015592, 6241.937580624317, 6258.749650013949, 5960.284765828128, 6016.639001659914, 6076.726547305293, 6099.146119543178, 6147.415995480422, 6106.572539922163, 6108.755649774137, 6118.418750218722, 6119.204503414528, 6138.0179171332375, 5905.881882362414, 5914.674692891932, 5920.111983202554, 5950.434708702666, 6050.2437195350585, 5883.911741323969, 5941.494972927349, 6015.218021657157, 6051.062085376773, 6101.816945491642, 5946.702664866652, 5955.583109182316, 5959.22530071088, 5965.164876917322, 6011.519078473799], "value": 5677.306128768593, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [459.9977000114999, 472.9219678753006, 505.5904717179083, 513.753398368783, 514.4700958013243, 39526.40710389343, 42643.93390165243, 42704.35943460851, 42894.49869254577, 42981.28074877001, 44407.11185776291, 44719.776401117946, 44860.77569612156, 44929.55070449303, 45069.323960140624, 8469.32245420368, 9106.180443760077, 9338.533073346305, 9358.204552613037, 9379.390339627937, 8500.617472213735, 11244.831327530093, 11513.136514761385, 11544.960953514183, 11695.181337306392, 11230.494627741771, 11289.096872250242, 11295.0, 11310.830337544943, 11363.147763917697, 11306.943465282662, 11334.433278336091, 11342.262752921033, 11375.601853935155, 11416.0, 11222.887771122267, 11229.438528073564, 11236.719082022933, 11302.717432064264, 11304.943475282653, 41213.587864121575, 41265.0, 41491.377629335584, 41566.37650435246, 41605.375919361235, 3623.873164439232, 3849.6920246380223, 3926.1362500249897, 3965.5637879833193, 4022.7787471689044, 29947.101586952427, 30557.69442305593, 30807.38385232283, 31025.0, 31134.53298200506, 3122.8906988255303, 3161.2571045804216, 3193.0, 3196.5524826524306, 3201.743860491155, 413110.0, 422799.0, 424251.0, 424778.0, 425320.0, 67589.0, 67645.0, 68273.3172668277, 68503.3149668507, 68685.65657171683, 71131.0, 71298.9305160423, 71494.85515434545, 71562.9265561017, 71576.64211678908, 16656.50030499087, 17229.65540689179, 17248.913755431393, 17294.913525432545, 17295.56761080983, 11215.990560849563, 11961.88038119625, 12336.938315308365, 13035.413406396736, 13087.541936032194, 18124.728129078074, 18878.3392581259, 19144.617107658047, 19167.424977250706, 19402.70895936534, 5945.227120474397, 6381.978883378637, 6772.966135169293, 6794.728210871609, 6858.828529286808, 787.3897729259869, 881.2201201936205, 886.5567216391804, 921.3734660430837, 922.4050487435567, 27766.583501247496, 29331.85334073316, 30506.38987220243, 30660.265058328652, 30683.539746903814, 932.440535678601, 1064.3656949051037, 1079.0773888325525, 1098.4672433869566, 1120.6750042487588, 15824.129672868103, 15861.127637980022, 15952.122633255283, 16186.676266474604, 16830.99014059136, 214489.0, 218274.0, 218310.0, 218627.0, 219003.0, 63323.0, 63460.0, 63582.68208658927, 63586.36413635897, 63611.68194159092, 1426.12293439536, 1452.869241768267, 1487.8586534279227, 1489.791429199913, 1492.417956996772, 29300.267493312836, 30254.243643909078, 30556.541651875683, 30669.410507177643, 30940.69059309468, 50700.49299507105, 53294.46705532895, 54410.45589544054, 55227.44772552223, 55283.72358138183, 431065.0, 431218.0, 431586.0, 431731.0, 432917.0, 42758.78620606877, 43269.78365108154, 43660.56339436565, 43752.781236093615, 43864.780676096416, 47514.52485475101, 47755.52244477511, 48139.75930120327, 48203.0, 48765.293214737314, 37953.62046379501, 39203.41194882136, 39644.008899776585, 41438.58561414347, 41598.0, 38404.46382144683, 39350.0, 39490.802545987084, 40251.59748402596, 41299.793501032305, 36983.6301636991, 37287.627123728416, 37373.4393984085, 37383.6261637391, 38310.42534362042, 32523.674763252064, 33296.833515832266, 33821.492677610346, 33923.83038084794, 35045.77339793095, 22428.32715018552, 24465.0, 24862.751372486044, 25105.24684259475, 25105.87447062753], "value": 20044.58606952174, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [337.76525314906144, 372.56782132726033, 389.4022675193577, 406.10656555577737, 423.36917992191644, 19055.809441905592, 20988.0, 21103.47241318964, 21114.683279750818, 21264.36206913795, 20936.057877395517, 20972.475688107806, 21630.783692163073, 21654.675179872316, 21847.890760546215, 4622.722636641813, 5757.481826635602, 5990.5806593538255, 6015.789447369343, 6035.728392222339, 5913.645181289115, 5971.164037034819, 5973.22348094747, 5983.491403230731, 5987.43119403656, 6535.477161827042, 6833.692483838215, 6873.106496155518, 6901.482388820833, 6908.1364829396325, 7595.5442673439775, 7717.151113377524, 7774.261445162757, 7785.377169826429, 7888.447808653396, 6981.197162326347, 6983.82540436488, 7702.730404435818, 7709.42179336552, 7714.99705038347, 24090.675012874286, 28966.710332896822, 29205.85397073001, 29764.958226462186, 29825.0, 2349.800266977309, 2451.9999999999823, 2850.1876965064857, 2916.402137561795, 2944.720251576097, 16042.278097485641, 17108.82891171097, 17190.82809171905, 17366.392176273897, 17596.91201543984, 1682.4195652499868, 1937.6705959986696, 1949.9512512187166, 1973.9309124180584, 1977.555050113713, 141656.0, 142361.0, 144616.2769186158, 149064.254678727, 151863.0, 23165.841707914875, 24419.63370549443, 24498.142565010137, 24559.38601534976, 24884.751152488607, 42161.57838421638, 42515.57484425178, 43457.348139777925, 43501.56498434975, 43667.78166109213, 8396.748097557083, 8569.185927336894, 8857.070007649105, 8876.778080548038, 8898.243649289754, 8637.179467950535, 8658.610362533702, 8692.478816207138, 8808.251298639687, 8820.294376449869, 4813.927791083067, 4831.4685384607665, 4920.975395123001, 4926.261060840831, 5199.324087868628, 2015.748031496063, 2045.7136000959877, 2054.065400242886, 2058.835293176542, 2068.0, 1009.848522721583, 1018.6587493189717, 1025.2105878473683, 1031.376017509396, 1063.7925604507202, 20947.790522094583, 21190.0, 21267.255646052316, 21375.358739237847, 21423.67864482034, 1032.7108409645311, 1038.6341960322152, 1060.877999030106, 1074.8280275156094, 1085.7502774361942, 10931.56273749057, 10986.945065274622, 11045.944770276097, 11063.170262230366, 11069.0, 772061.0, 772653.0, 774958.0, 788829.0, 788963.0, 112106.43946780213, 140971.0, 165482.3451765491, 165941.340586595, 167647.48528771833, 997.7256254529917, 1012.6759436980243, 1032.6592224565875, 1041.317936751429, 1081.8214994525815, 19542.0, 19841.0, 19962.90018549898, 20220.494487637927, 20280.59438811245, 57707.71146144242, 60072.69963650154, 60507.78984420199, 60771.69614152078, 62148.892555371145, 86219.5689021551, 86419.56790216008, 88576.11423885936, 91086.08913910776, 91228.0, 6851.383375496229, 7065.975433562002, 8350.58040133191, 8523.829523409373, 8533.87199192025, 8788.648454061766, 12932.0, 12965.35173241353, 13253.734925301633, 13472.528461504193, 9101.954490227772, 9821.607135714778, 11397.97418232363, 11451.969322760991, 11655.475503602189, 10398.22013349017, 10997.717134479288, 11284.717882053013, 11586.362750048827, 11644.708882278012, 11307.491162897853, 11419.429028548706, 11713.180077394842, 11740.295582265091, 11846.644600661994, 6964.686589103401, 7473.364763995121, 7731.38148948094, 7770.728024519115, 7844.960775196316, 10303.278770486293, 11142.888571114185, 11218.66344009681, 11444.771104578029, 11472.827907581226], "value": 10933.29719940558, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1271.7901546244866, 1348.6426097084268, 1401.1102949626986, 1410.2032351721277, 1441.5819412370413, 7417.554946703188, 7666.386689064875, 8052.194780521945, 8434.746957591267, 8475.279601233895, 7912.169222231669, 8170.142135075821, 8240.752777416687, 8339.999200095981, 8360.456570322925, 2274.4086537500284, 2320.269115228706, 2321.6517522371696, 2322.953540929189, 2330.5105927755108, 1988.3339081407673, 2069.617120832643, 2301.436148143707, 2344.202970989863, 2349.6254691005784, 2225.7774222577696, 2315.4833584002504, 2322.001539338084, 2323.337848713117, 2340.96488552671, 2110.2086717480943, 2122.7558830734433, 2275.635898256271, 2319.3389883883015, 2330.196082351586, 2338.754430784777, 2341.906323747048, 2344.800691941187, 2346.835721499496, 2357.26924653358, 8333.416660833744, 8407.663693452254, 8411.200935911078, 8416.663333466655, 8416.705415310496, 5055.873603159914, 5212.061828870802, 5214.791408343662, 5216.9739151304, 5219.0, 3479.1823921378455, 3499.7900125992523, 3513.3149035938004, 3559.288142371536, 3575.821208939569, 1213.2720367779261, 1214.8542174938975, 1257.9308138052495, 1262.2552693910636, 1270.7585558743808, 10539.0, 10765.62320318793, 10837.783244335147, 10849.294795838245, 10852.240343175981, 4384.6273066789045, 4677.766111694402, 4707.082118986833, 4741.478437371887, 4749.64377671676, 9416.764580885532, 9483.288753343526, 9505.619775209052, 9513.714588562354, 9515.762105947406, 5227.372715274191, 5240.4759524047295, 5249.921251181236, 5253.553447956967, 5260.237265596467, 4629.0, 4660.39414876064, 4697.718136911796, 4808.7595620219545, 4814.638902082288, 3914.64768170866, 3947.842086316515, 3972.5828787977425, 4008.6191811777835, 4063.004563881868, 1185.247367921376, 1226.7730469863104, 1275.7639836630253, 1294.695746499572, 1299.9675008124873, 734.6179986407038, 757.526545908807, 767.6046835879415, 805.2672068417712, 828.6850996621246, 5348.411674715856, 5353.357597088374, 5435.1847222916085, 5614.719264036782, 5645.237892884408, 958.1568219966385, 987.521052289639, 1001.1490233301636, 1002.9548670309854, 1023.1712313026323, 3253.4469140246215, 3569.5538057742783, 3630.2739452109686, 3737.4393840923535, 3756.6806821420487, 9294.209992150607, 10014.499275036365, 10034.0, 10039.648612298535, 10055.69832905014, 9760.023997600183, 9887.159591434665, 9910.900890991143, 9931.453770042717, 9993.500324983721, 7399.48203625741, 7723.845523089507, 7752.689892404241, 7789.610519474117, 7839.725609603751, 5489.231507589021, 5519.944800551943, 5540.972295138498, 5663.490285874291, 5664.0937450007805, 8704.865189035158, 8993.0, 9039.774005649911, 9104.271658267191, 9133.908660913306, 9027.50348730826, 9264.44413335202, 9268.72193834186, 9350.532473376168, 9362.672306469241, 7627.656755445907, 7859.214078592095, 7888.960555197187, 7919.049714034353, 7932.682692692228, 6462.806115816533, 7806.570638614704, 7925.0886148093705, 7926.405519585939, 7926.603669816602, 5605.887882242251, 6931.168259808654, 7081.539699919489, 7127.964360178166, 7263.382612477787, 6907.447404207752, 7007.6846541904715, 7008.194057683228, 7043.295670432915, 7055.3650171484815, 6288.27684816252, 6321.905171422341, 6351.142595749515, 6365.267994180728, 6414.262359828679, 3973.7814420207164, 4773.427188737373, 4871.0, 4930.630202734737, 5041.269015992587, 4817.879553011203, 4920.704757714549, 4948.752562371795, 5013.598912087097, 5049.040682270357], "value": 4396.012493757125, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [340.0, 352.6209324975652, 358.6216541548666, 359.58647555311387, 361.20174414543857, 5176.637635365521, 5234.293370394996, 5340.706261155645, 5385.526335513591, 5576.33084029916, 4487.932681009779, 4896.314515967759, 5305.445351614418, 5342.889558624341, 5423.864403389917, 4203.201391735561, 4220.391565211754, 4233.920350310682, 4249.766262855542, 4250.298700714378, 4076.51081870176, 4159.584041595846, 4211.97894010531, 4222.8310867565415, 4229.830806767725, 4130.822715526076, 4164.625183733463, 4168.832726836491, 4182.393552934823, 4207.368894665794, 3807.6482848588907, 3930.3318435865826, 4004.619561141687, 4201.970517223269, 4204.516480604739, 3344.1973926257765, 3718.609545997658, 3733.1413774831667, 3743.2300097927337, 3747.4940882980723, 4401.163778882003, 4858.51815196365, 4991.425986011619, 4997.375328083989, 5231.581473482112, 4262.744235345858, 4623.144718227139, 4784.6889952153, 4878.512148785128, 4909.165441874854, 4284.871453856389, 4831.9275210871865, 4948.426818054591, 5542.86142846428, 5659.151127330892, 3937.4881265435597, 4040.151568170688, 4040.7979601019833, 4067.905028910084, 4069.2878746219294, 5454.481824226692, 5941.049432090888, 6001.579889407743, 6081.604695694766, 6084.878302433926, 3637.436197389409, 4161.542230354658, 4165.166966606691, 4172.228137794517, 4207.705460617789, 3402.897913062612, 3416.6241713411505, 3706.369917114097, 4148.79256037197, 4248.299030659969, 4078.7552746835286, 4439.533848945879, 4462.464504259509, 4486.282194848874, 4893.045856058035, 5304.9734751326, 5625.915611265835, 5790.681512516852, 5821.2141360916585, 5857.7949771757785, 1218.6831423829867, 1269.6762325607096, 1289.3939848271298, 1335.045442508611, 1391.1583491987403, 927.803469051335, 943.3067643579791, 943.3444305244252, 959.9856002159974, 968.3899143539666, 5164.302819119446, 5177.430482646906, 5187.533122018961, 5201.5786581406255, 5239.607029472805, 5377.542908852792, 5490.396056433789, 5567.387587365387, 5706.743196556165, 5776.566757493205, 4559.658025648089, 4616.446026476844, 4633.745144017112, 4694.366260554782, 5252.501012403815, 2660.9467810643678, 2669.346010227468, 2730.863456827151, 2922.4657055046273, 2924.8830046798316, 5018.397792264878, 5218.633981082181, 5608.9439105609235, 5813.476787089182, 5842.503387212049, 5346.91979620306, 5361.8123365682795, 5395.219577539403, 5474.698891561003, 5696.430356964353, 4790.508935353666, 5123.87190320245, 5432.0765469871, 5610.905873354738, 5755.482006619424, 4625.0, 5028.949710502849, 5384.307846076961, 5539.473749993744, 5573.749181286771, 4400.427944367212, 4640.86077417678, 4976.009596161564, 5169.889937499622, 5488.56091512686, 5393.97514472249, 5689.630174038674, 5734.770609175586, 5763.279590051244, 5808.30300363959, 3812.9115416512714, 3943.397357743875, 3948.1511475033008, 3960.2475529649273, 3982.581828908039, 5565.443455654402, 5589.283126720848, 5690.492019614722, 5757.646952965966, 5860.94139058621, 5257.868553286198, 5363.812266570651, 5561.054620714569, 5676.829695108988, 5677.990664947584, 5691.231683722644, 5722.971385143214, 5758.798442054508, 5770.278715160605, 5835.44563266489, 4577.221872281787, 5344.866378340573, 5575.964772857797, 5616.775328986795, 5700.600957932905, 4519.389882365838, 5034.841986343088, 5526.309211348582, 5717.342505611741, 5796.76812927495, 5452.100403433547, 5549.611527193058, 5603.635763675348, 5610.410906854721, 5659.717363928994], "value": 4124.671216604359, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [694.5346617766096, 697.5465947134365, 700.4396482813747, 809.9433039687223, 812.9350550778481, 14442.133471991674, 15008.024478408923, 15285.0, 15420.228988550556, 15422.845771542281, 14175.858241417583, 14280.21458819767, 14397.712045759105, 14404.207768572724, 14408.927955360236, 846.4455781463151, 854.5043874552775, 854.9700760473385, 855.7689423855586, 855.9315254779634, 669.9832504187385, 683.9726410943556, 830.779843341515, 849.9830003399928, 867.5315329721974, 904.3759805734055, 905.1943770044666, 906.0214967834755, 908.3006085314304, 908.9818203635923, 895.9641614335419, 897.9595918183697, 898.3307435960186, 898.3352319283748, 898.9190972812446, 665.8235567574549, 670.5038271678923, 672.5191488086042, 673.3603077076748, 675.1729131813489, 14408.567742967727, 14871.925640371837, 15022.549323520203, 15056.0, 15087.170205638686, 13852.653683657889, 14391.0, 14432.134071955716, 14531.854681453156, 14535.854641453556, 11347.829782553268, 11642.0, 11650.475728592233, 11654.359010254517, 11657.883421165765, 3603.621619729917, 3656.433252845813, 3683.0424089736593, 3720.609336019706, 3722.1997270586685, 78809.0, 103442.0, 105214.47392762988, 106540.0, 106666.86666266709, 10615.575376984834, 10847.783044339069, 10873.401962892116, 11177.72055698614, 11619.535218591162, 23784.643230351558, 23975.640365394534, 23991.880040599684, 24005.27984160478, 24022.7597724024, 7642.961785191039, 7664.9616751917, 7702.0, 7800.687972481151, 7822.06135263772, 4125.29869922114, 4152.626263636227, 4216.915661686749, 4230.0, 4372.060007098489, 3533.911652208715, 3633.237020225782, 3639.9808053744996, 3644.0525463379713, 3649.5802982657333, 578.8060999565108, 590.0824218340435, 600.5435868739702, 603.46894732635, 610.2524407600743, 547.6440313795985, 561.1274468201985, 566.396787421395, 574.1855735874616, 576.4091805898987, 9723.173530249867, 10111.292209545409, 10143.7464063399, 10372.377657340432, 10424.635137770141, 550.6695982410522, 558.4499268220799, 588.1236956934138, 600.879824035186, 663.233964770681, 4457.687961842705, 4919.385076865392, 4967.5032496750755, 5006.0989021975665, 5008.699478031257, 103989.48005259925, 105699.47150264353, 106233.0, 106759.0, 108449.0, 76670.0, 89962.65056024164, 90937.54531227301, 92362.53818730998, 95362.09275814446, 53056.46943530515, 71522.64238678773, 82115.0, 82130.58934705288, 84091.57954210312, 8264.628091736007, 9514.38156519824, 9568.569414376509, 9569.138777510057, 9584.233261339215, 38665.42001869918, 49077.754611226715, 50357.24464132968, 50435.982560697164, 51368.74315628398, 52568.0, 66286.66856665685, 66996.0, 68120.76180203949, 68348.0, 9371.531423428938, 11170.27393219438, 11395.772084558428, 11516.712082198012, 11933.16467847277, 12162.128422408488, 12474.126811123135, 12483.687907802378, 12703.42834572465, 12840.42218100169, 11658.358790266617, 11784.116191285517, 11851.940740296242, 11893.46479408446, 11975.461104250155, 11056.186934683283, 11682.182247242612, 11708.238964467282, 11907.702307442383, 11992.11673601015, 11288.661340159808, 11664.88335116672, 11732.706682332668, 11742.823857641972, 11744.765104697688, 9740.729109027328, 9922.657979159989, 10096.444695541522, 10163.288569800043, 10637.095846853104, 9328.906710933075, 9683.9031609683, 9836.311458197857, 9850.113489785954, 9852.0], "value": 7202.2232590914755, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1613.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 118.0, 119.0, 117.0, 119.0, 140.0, 153.0, 119.0, 118.0, 117.0], "value": 122.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 112.0, 109.0, 159.0, 142.0, 143.0, 147.0, 111.0, 130.0, 112.0], "value": 131.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 193.0, 193.0, 204.0, 197.0, 195.0, 245.0, 217.0, 261.0, 273.0], "value": 217.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 57.0, 61.0, 83.0, 61.0, 59.0, 63.0, 73.0, 63.0, 52.0], "value": 65.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 69.0, 112.0, 61.0, 88.0, 104.0, 115.0, 102.0, 83.0, 81.0], "value": 90.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 91.0, 92.0, 95.0, 78.0, 82.0, 138.0, 134.0, 108.0, 89.0], "value": 99.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 135.0, 152.0, 128.0, 140.0, 166.0, 101.0, 114.0, 158.0, 120.0], "value": 131.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 80.0, 139.0, 76.0, 145.0, 76.0, 145.0, 147.0, 103.0, 143.0], "value": 113.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 79.0, 60.0, 63.0, 62.0, 57.0, 56.0, 88.0, 97.0, 98.0], "value": 72.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 68.0, 68.0, 76.0, 85.0, 81.0, 105.0, 105.0, 105.0, 72.0], "value": 83.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 82.0, 85.0, 84.0, 86.0, 89.0, 85.0, 85.0, 84.0, 84.0], "value": 84.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 162.0, 140.0, 138.0, 138.0, 143.0, 138.0, 149.0, 217.0, 160.0], "value": 153.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 173.0, 172.0, 169.0, 170.0, 170.0, 166.0, 171.0, 170.0, 201.0], "value": 173.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 69.0, 67.0, 63.0, 65.0, 68.0, 88.0, 86.0, 89.0, 87.0], "value": 74.9, "unit": "score"}]}]} 09:48:01 INFO - Return code: 0 09:48:01 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:48:01 ERROR - # TBPL SUCCESS # 09:48:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:48:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:48:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:48:01 INFO - 2017-02-19 09:48:01.719 screenresolution[2562:18094] starting screenresolution argv=screenresolution get 09:48:01 INFO - 2017-02-19 09:48:01.722 screenresolution[2562:18094] Display 0: 1600x1200x32@60 09:48:01 INFO - 2017-02-19 09:48:01.728 screenresolution[2563:18097] starting screenresolution argv=screenresolution list 09:48:01 INFO - Available Modes on Display 0 09:48:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:48:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:48:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:48:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:48:01 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:48:01 INFO - Intel Iris: 09:48:01 INFO - Chipset Model: Intel Iris 09:48:01 INFO - Type: GPU 09:48:01 INFO - Bus: Built-In 09:48:01 INFO - VRAM (Dynamic, Max): 1536 MB 09:48:01 INFO - Vendor: Intel (0x8086) 09:48:01 INFO - Device ID: 0x0a2e 09:48:01 INFO - Revision ID: 0x0009 09:48:01 INFO - Displays: 09:48:01 INFO - Display: 09:48:01 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:48:01 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:48:01 INFO - Display Serial Number: 1600x1200 60 09:48:01 INFO - Main Display: Yes 09:48:01 INFO - Mirror: Off 09:48:01 INFO - Online: Yes 09:48:01 INFO - Rotation: Supported 09:48:01 INFO - Return code: 0 09:48:01 INFO - Running post-action listener: _resource_record_post_action 09:48:01 INFO - [mozharness: 2017-02-19 17:48:01.963277Z] Finished run-tests step (success) 09:48:01 INFO - Running post-run listener: _resource_record_post_run 09:48:02 INFO - Total resource usage - Wall time: 1199s; CPU: 54.0%; Read bytes: 34607616; Write bytes: 517334528; Read time: 318; Write time: 1590 09:48:02 INFO - TinderboxPrint: CPU usage
54.4% 09:48:02 INFO - TinderboxPrint: I/O read bytes / time
34,607,616 / 318 09:48:02 INFO - TinderboxPrint: I/O write bytes / time
517,334,528 / 1,590 09:48:02 INFO - TinderboxPrint: CPU idle
2,189.6 (45.6%) 09:48:02 INFO - TinderboxPrint: CPU system
966.2 (20.1%) 09:48:02 INFO - TinderboxPrint: CPU user
1,641.7 (34.2%) 09:48:02 INFO - TinderboxPrint: Swap in / out
238,399,488 / 0 09:48:02 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 205638144; Write bytes: 202029056; Read time: 15697; Write time: 410 09:48:02 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 32485888; Write bytes: 304332288; Read time: 289; Write time: 1149 09:48:02 INFO - Running post-run listener: _upload_blobber_files 09:48:02 INFO - Blob upload gear active. 09:48:02 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:48:02 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:48:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:48:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:48:02 INFO - (blobuploader) - INFO - Open directory for files ... 09:48:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:48:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:48:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:48:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:48:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:48:04 INFO - (blobuploader) - INFO - Done attempting. 09:48:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:48:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:48:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:48:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:48:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:48:05 INFO - (blobuploader) - INFO - Done attempting. 09:48:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:48:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:48:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:48:06 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:48:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:48:06 INFO - (blobuploader) - INFO - Done attempting. 09:48:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:48:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:48:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:48:08 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:48:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:48:08 INFO - (blobuploader) - INFO - Done attempting. 09:48:08 INFO - (blobuploader) - INFO - Iteration through files over. 09:48:08 INFO - Return code: 0 09:48:08 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:48:08 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:48:08 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8e1346d5e2c0d50051b93b109033011f005de61833bf04a8d4d855606fd33222122de4932c4bfe07eab8b02f39aa8773d435bc7d0f17363a8dcc1b212ea227c6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f79bbf7813c0f4736e3196006d5d4637f3490d8bbba941d95e7a1f862f01b7c88951a01cd93dc70ec91b37bd91e062aa4b843121d96f1087e1fdf6a05949e5c6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f2f0e7731767ffc7cb0dee2b9079a5a0437e22033aea7fda8eaa8af65824ab68d77ca1094d4b293ac041afb68b854681dbb4098dc54590f7605353fd5127b7b2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f8def622ed0477d3bdb86698aeefbb41e39e95d2128eb29a702fafeb5ff461028ba3cf19e14212aebc2a45a2260464b43437b8c17521cf8f8404b01f1b340453"} 09:48:08 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:48:08 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:48:08 INFO - Contents: 09:48:08 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8e1346d5e2c0d50051b93b109033011f005de61833bf04a8d4d855606fd33222122de4932c4bfe07eab8b02f39aa8773d435bc7d0f17363a8dcc1b212ea227c6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f79bbf7813c0f4736e3196006d5d4637f3490d8bbba941d95e7a1f862f01b7c88951a01cd93dc70ec91b37bd91e062aa4b843121d96f1087e1fdf6a05949e5c6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f2f0e7731767ffc7cb0dee2b9079a5a0437e22033aea7fda8eaa8af65824ab68d77ca1094d4b293ac041afb68b854681dbb4098dc54590f7605353fd5127b7b2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f8def622ed0477d3bdb86698aeefbb41e39e95d2128eb29a702fafeb5ff461028ba3cf19e14212aebc2a45a2260464b43437b8c17521cf8f8404b01f1b340453"} 09:48:08 INFO - Running post-run listener: copy_logs_to_upload_dir 09:48:08 INFO - Copying logs to upload dir... 09:48:08 INFO - mkdir: /builds/slave/test/build/upload/logs 09:48:08 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.203145 ========= master_lag: 2.40 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2017-02-19 09:48:11.080088) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-19 09:48:11.084177) ========= 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.uRZMCxLsJg/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.XLMlVdtSuY/Listeners TMPDIR=/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/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/8e1346d5e2c0d50051b93b109033011f005de61833bf04a8d4d855606fd33222122de4932c4bfe07eab8b02f39aa8773d435bc7d0f17363a8dcc1b212ea227c6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f79bbf7813c0f4736e3196006d5d4637f3490d8bbba941d95e7a1f862f01b7c88951a01cd93dc70ec91b37bd91e062aa4b843121d96f1087e1fdf6a05949e5c6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f2f0e7731767ffc7cb0dee2b9079a5a0437e22033aea7fda8eaa8af65824ab68d77ca1094d4b293ac041afb68b854681dbb4098dc54590f7605353fd5127b7b2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f8def622ed0477d3bdb86698aeefbb41e39e95d2128eb29a702fafeb5ff461028ba3cf19e14212aebc2a45a2260464b43437b8c17521cf8f8404b01f1b340453"} build_url:https://queue.taskcluster.net/v1/task/Zjw2Lr60R_ybtJ_P0-SnEw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011198 build_url: 'https://queue.taskcluster.net/v1/task/Zjw2Lr60R_ybtJ_P0-SnEw/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/8e1346d5e2c0d50051b93b109033011f005de61833bf04a8d4d855606fd33222122de4932c4bfe07eab8b02f39aa8773d435bc7d0f17363a8dcc1b212ea227c6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f79bbf7813c0f4736e3196006d5d4637f3490d8bbba941d95e7a1f862f01b7c88951a01cd93dc70ec91b37bd91e062aa4b843121d96f1087e1fdf6a05949e5c6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f2f0e7731767ffc7cb0dee2b9079a5a0437e22033aea7fda8eaa8af65824ab68d77ca1094d4b293ac041afb68b854681dbb4098dc54590f7605353fd5127b7b2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f8def622ed0477d3bdb86698aeefbb41e39e95d2128eb29a702fafeb5ff461028ba3cf19e14212aebc2a45a2260464b43437b8c17521cf8f8404b01f1b340453"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-19 09:48:11.120348) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-19 09:48:11.120679) ========= 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.uRZMCxLsJg/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.XLMlVdtSuY/Listeners TMPDIR=/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/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.005082 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-19 09:48:11.169344) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-19 09:48:11.172678) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-19 09:48:11.173030) ========= ========= Total master_lag: 2.58 =========