builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0167 starttime: 1487275083.44 results: success (0) buildid: 20170216102419 builduid: 0986f5cf689849debf81a78dadf4f8b2 revision: 3a3e07e22ea8a9f6523172c6e85ff363b9b1a53d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-16 11:58:03.439384) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-16 11:58:03.439864) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-16 11:58:03.440154) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-16 11:58:03.464912) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-16 11:58:03.465320) ========= 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.wmHpCgrqwr/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.WCOUbl0dfX/Listeners TMPDIR=/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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-16 11:58:03-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 145M=0s 2017-02-16 11:58:03 (145 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.184746 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-16 11:58:03.673070) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-16 11:58:03.673403) ========= 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.wmHpCgrqwr/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.WCOUbl0dfX/Listeners TMPDIR=/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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.019548 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-16 11:58:03.722113) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-16 11:58:03.722476) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 3a3e07e22ea8a9f6523172c6e85ff363b9b1a53d --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 3a3e07e22ea8a9f6523172c6e85ff363b9b1a53d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.wmHpCgrqwr/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.WCOUbl0dfX/Listeners TMPDIR=/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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-16 11:58:03,808 truncating revision to first 12 chars 2017-02-16 11:58:03,808 Setting DEBUG logging. 2017-02-16 11:58:03,808 attempt 1/10 2017-02-16 11:58:03,808 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/3a3e07e22ea8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-16 11:58:05,513 unpacking tar archive at: mozilla-aurora-3a3e07e22ea8/testing/mozharness/ program finished with exit code 0 elapsedTime=1.953496 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-16 11:58:05.701480) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-16 11:58:05.702027) ========= script_repo_revision: 3a3e07e22ea8a9f6523172c6e85ff363b9b1a53d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-16 11:58:05.702703) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-16 11:58:05.703185) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-16 11:58:05.724596) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2017-02-16 11:58:05.725229) ========= /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.wmHpCgrqwr/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.WCOUbl0dfX/Listeners TMPDIR=/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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 11:58:05 INFO - MultiFileLogger online at 20170216 11:58:05 in /builds/slave/test 11:58:05 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 11:58:05 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:58:05 INFO - {'append_to_log': False, 11:58:05 INFO - 'base_work_dir': '/builds/slave/test', 11:58:05 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 11:58:05 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:58:05 INFO - 'branch': 'Mozilla-Aurora', 11:58:05 INFO - 'buildbot_json_path': 'buildprops.json', 11:58:05 INFO - 'config_files': ('talos/mac_config.py',), 11:58:05 INFO - 'default_actions': ('clobber', 11:58:05 INFO - 'read-buildbot-config', 11:58:05 INFO - 'download-and-extract', 11:58:05 INFO - 'populate-webroot', 11:58:05 INFO - 'create-virtualenv', 11:58:05 INFO - 'install', 11:58:05 INFO - 'run-tests'), 11:58:05 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:58:05 INFO - 'download_minidump_stackwalk': True, 11:58:05 INFO - 'download_symbols': 'ondemand', 11:58:05 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:58:05 INFO - 'tooltool.py': '/tools/tooltool.py', 11:58:05 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:58:05 INFO - '/tools/misc-python/virtualenv.py')}, 11:58:05 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:58:05 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:58:05 INFO - 'installer_path': 'installer.exe', 11:58:05 INFO - 'log_level': 'info', 11:58:05 INFO - 'log_name': 'talos', 11:58:05 INFO - 'log_to_console': True, 11:58:05 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:58:05 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:58:05 INFO - 'opt_config_files': (), 11:58:05 INFO - 'pip_index': False, 11:58:05 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:58:05 INFO - 'cmd': ('bash', 11:58:05 INFO - '-c', 11:58:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:58:05 INFO - 'enabled': True, 11:58:05 INFO - 'halt_on_failure': False, 11:58:05 INFO - 'name': 'check_screen_resolution'},), 11:58:05 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:58:05 INFO - 'cmd': ('bash', 11:58:05 INFO - '-c', 11:58:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:58:05 INFO - 'enabled': True, 11:58:05 INFO - 'halt_on_failure': False, 11:58:05 INFO - 'name': 'check_screen_resolution'},), 11:58:05 INFO - 'run_cmd_checks_enabled': True, 11:58:05 INFO - 'sps_profile': False, 11:58:05 INFO - 'sps_profile_interval': 0, 11:58:05 INFO - 'suite': 'dromaeojs-e10s', 11:58:05 INFO - 'system_bits': '32', 11:58:05 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:58:05 INFO - 'title': 't-yosemite-r7-0167', 11:58:05 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:58:05 INFO - 'use_talos_json': True, 11:58:05 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:58:05 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:58:05 INFO - 'work_dir': 'build'} 11:58:05 INFO - [mozharness: 2017-02-16 19:58:05.935055Z] Running clobber step. 11:58:05 INFO - Running pre-action listener: _resource_record_pre_action 11:58:05 INFO - Running main action method: clobber 11:58:05 INFO - rmtree: /builds/slave/test/build 11:58:05 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:58:06 INFO - Running post-action listener: _resource_record_post_action 11:58:06 INFO - [mozharness: 2017-02-16 19:58:06.260705Z] Finished clobber step (success) 11:58:06 INFO - [mozharness: 2017-02-16 19:58:06.260841Z] Running read-buildbot-config step. 11:58:06 INFO - Running pre-action listener: _resource_record_pre_action 11:58:06 INFO - Running main action method: read_buildbot_config 11:58:06 INFO - Using buildbot properties: 11:58:06 INFO - { 11:58:06 INFO - "project": "", 11:58:06 INFO - "product": "firefox", 11:58:06 INFO - "script_repo_revision": "production", 11:58:06 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 11:58:06 INFO - "repository": "", 11:58:06 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 11:58:06 INFO - "buildid": "20170216102419", 11:58:06 INFO - "pgo_build": "False", 11:58:06 INFO - "basedir": "/builds/slave/test", 11:58:06 INFO - "buildnumber": 47, 11:58:06 INFO - "slavename": "t-yosemite-r7-0167", 11:58:06 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 11:58:06 INFO - "platform": "yosemite_r7", 11:58:06 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 11:58:06 INFO - "branch": "mozilla-aurora", 11:58:06 INFO - "repo_path": "releases/mozilla-aurora", 11:58:06 INFO - "revision": "3a3e07e22ea8a9f6523172c6e85ff363b9b1a53d", 11:58:06 INFO - "stage_platform": "macosx64", 11:58:06 INFO - "builduid": "0986f5cf689849debf81a78dadf4f8b2", 11:58:06 INFO - "slavebuilddir": "test" 11:58:06 INFO - } 11:58:06 INFO - Found installer url https://queue.taskcluster.net/v1/task/bFahilKxT8C1Z3Wze7ik8Q/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg. 11:58:06 INFO - Running post-action listener: _resource_record_post_action 11:58:06 INFO - [mozharness: 2017-02-16 19:58:06.264220Z] Finished read-buildbot-config step (success) 11:58:06 INFO - [mozharness: 2017-02-16 19:58:06.264282Z] Running download-and-extract step. 11:58:06 INFO - Running pre-action listener: _resource_record_pre_action 11:58:06 INFO - Running main action method: download_and_extract 11:58:06 INFO - mkdir: /builds/slave/test/build/tests 11:58:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:58:06 INFO - trying https://queue.taskcluster.net/v1/task/bFahilKxT8C1Z3Wze7ik8Q/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json 11:58:06 INFO - Downloading https://queue.taskcluster.net/v1/task/bFahilKxT8C1Z3Wze7ik8Q/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 11:58:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bFahilKxT8C1Z3Wze7ik8Q/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 11:58:06 INFO - Downloaded 1199 bytes. 11:58:06 INFO - Reading from file /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 11:58:06 INFO - Using the following test package requirements: 11:58:06 INFO - {u'common': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 11:58:06 INFO - u'cppunittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 11:58:06 INFO - u'firefox-53.0a2.en-US.mac.cppunittest.tests.zip'], 11:58:06 INFO - u'gtest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 11:58:06 INFO - u'firefox-53.0a2.en-US.mac.gtest.tests.zip'], 11:58:06 INFO - u'jittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 11:58:06 INFO - u'jsshell-mac.zip'], 11:58:06 INFO - u'mochitest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 11:58:06 INFO - u'firefox-53.0a2.en-US.mac.mochitest.tests.zip'], 11:58:06 INFO - u'mozbase': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 11:58:06 INFO - u'reftest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 11:58:06 INFO - u'firefox-53.0a2.en-US.mac.reftest.tests.zip'], 11:58:06 INFO - u'talos': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 11:58:06 INFO - u'firefox-53.0a2.en-US.mac.talos.tests.zip'], 11:58:06 INFO - u'web-platform': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 11:58:06 INFO - u'firefox-53.0a2.en-US.mac.web-platform.tests.zip'], 11:58:06 INFO - u'xpcshell': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 11:58:06 INFO - u'firefox-53.0a2.en-US.mac.xpcshell.tests.zip']} 11:58:06 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip'] for test suite category: common 11:58:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bFahilKxT8C1Z3Wze7ik8Q/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 11:58:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bFahilKxT8C1Z3Wze7ik8Q/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 11:58:06 INFO - Fetch https://queue.taskcluster.net/v1/task/bFahilKxT8C1Z3Wze7ik8Q/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 11:58:07 INFO - Expected file size: 16358123 11:58:07 INFO - Obtained file size: 16358123 11:58:14 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 11:58:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bFahilKxT8C1Z3Wze7ik8Q/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 11:58:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bFahilKxT8C1Z3Wze7ik8Q/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 11:58:14 INFO - Fetch https://queue.taskcluster.net/v1/task/bFahilKxT8C1Z3Wze7ik8Q/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 11:58:15 INFO - Expected file size: 16358123 11:58:15 INFO - Obtained file size: 16358123 11:58:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bFahilKxT8C1Z3Wze7ik8Q/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip 11:58:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bFahilKxT8C1Z3Wze7ik8Q/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip'}, attempt #1 11:58:22 INFO - Fetch https://queue.taskcluster.net/v1/task/bFahilKxT8C1Z3Wze7ik8Q/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip into memory 11:58:23 INFO - Expected file size: 14403485 11:58:23 INFO - Obtained file size: 14403485 11:58:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:58:23 INFO - trying https://queue.taskcluster.net/v1/task/bFahilKxT8C1Z3Wze7ik8Q/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 11:58:23 INFO - Downloading https://queue.taskcluster.net/v1/task/bFahilKxT8C1Z3Wze7ik8Q/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg 11:58:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bFahilKxT8C1Z3Wze7ik8Q/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 11:58:25 INFO - Downloaded 73946075 bytes. 11:58:25 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/bFahilKxT8C1Z3Wze7ik8Q/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 11:58:25 INFO - mkdir: /builds/slave/test/properties 11:58:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:58:25 INFO - Writing to file /builds/slave/test/properties/build_url 11:58:25 INFO - Contents: 11:58:25 INFO - build_url:https://queue.taskcluster.net/v1/task/bFahilKxT8C1Z3Wze7ik8Q/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 11:58:26 INFO - Running post-action listener: _resource_record_post_action 11:58:26 INFO - Running post-action listener: set_extra_try_arguments 11:58:26 INFO - [mozharness: 2017-02-16 19:58:26.349612Z] Finished download-and-extract step (success) 11:58:26 INFO - [mozharness: 2017-02-16 19:58:26.349773Z] Running populate-webroot step. 11:58:26 INFO - Running pre-action listener: _resource_record_pre_action 11:58:26 INFO - Running main action method: populate_webroot 11:58:26 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:58:26 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:58:26 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:58:26 INFO - '--tppagecycles', 11:58:26 INFO - '7'], 11:58:26 INFO - 'tests': ['tsvgm']}}, 11:58:26 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:58:26 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:58:26 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:58:26 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:58:26 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:58:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:58:26 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 11:58:26 INFO - 'talos_options': ['--disable-e10s'], 11:58:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:58:26 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:58:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:58:26 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 11:58:26 INFO - 'talos_options': ['--disable-e10s'], 11:58:26 INFO - 'tests': ['damp', 'tps']}, 11:58:26 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:58:26 INFO - 'tests': ['damp', 'tps']}, 11:58:26 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:58:26 INFO - 'tests': ['dromaeo_dom']}, 11:58:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:58:26 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:58:26 INFO - 'tests': ['basic_compositor_video']}, 11:58:26 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:58:26 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:58:26 INFO - 'tests': ['a11yr', 11:58:26 INFO - 'ts_paint', 11:58:26 INFO - 'tpaint', 11:58:26 INFO - 'sessionrestore', 11:58:26 INFO - 'sessionrestore_no_auto_restore', 11:58:26 INFO - 'tabpaint']}, 11:58:26 INFO - 'other-e10s': {'tests': ['a11yr', 11:58:26 INFO - 'ts_paint', 11:58:26 INFO - 'tpaint', 11:58:26 INFO - 'sessionrestore', 11:58:26 INFO - 'sessionrestore_no_auto_restore', 11:58:26 INFO - 'tabpaint']}, 11:58:26 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:58:26 INFO - 'ts_paint', 11:58:26 INFO - 'tpaint', 11:58:26 INFO - 'sessionrestore', 11:58:26 INFO - 'sessionrestore_no_auto_restore', 11:58:26 INFO - 'tabpaint']}, 11:58:26 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:58:26 INFO - 'ts_paint', 11:58:26 INFO - 'tpaint', 11:58:26 INFO - 'sessionrestore', 11:58:26 INFO - 'sessionrestore_no_auto_restore', 11:58:26 INFO - 'tabpaint']}, 11:58:26 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:58:26 INFO - 'tests': ['a11yr', 11:58:26 INFO - 'ts_paint', 11:58:26 INFO - 'tpaint', 11:58:26 INFO - 'sessionrestore', 11:58:26 INFO - 'sessionrestore_no_auto_restore', 11:58:26 INFO - 'tabpaint']}, 11:58:26 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:58:26 INFO - 'tests': ['a11yr', 11:58:26 INFO - 'ts_paint', 11:58:26 INFO - 'tpaint', 11:58:26 INFO - 'sessionrestore', 11:58:26 INFO - 'sessionrestore_no_auto_restore', 11:58:26 INFO - 'tabpaint']}, 11:58:26 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:58:26 INFO - 'tests': ['tsvgx', 11:58:26 INFO - 'tsvgr_opacity', 11:58:26 INFO - 'tart', 11:58:26 INFO - 'tscrollx', 11:58:26 INFO - 'cart', 11:58:26 INFO - 'tsvg_static']}, 11:58:26 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:58:26 INFO - 'tsvgr_opacity', 11:58:26 INFO - 'tart', 11:58:26 INFO - 'tscrollx', 11:58:26 INFO - 'cart', 11:58:26 INFO - 'tsvg_static']}, 11:58:26 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 11:58:26 INFO - 'talos_options': ['--disable-e10s'], 11:58:26 INFO - 'tests': ['tp5o']}, 11:58:26 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:58:26 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 11:58:26 INFO - 'talos_options': ['--disable-e10s', 11:58:26 INFO - '--xperf_path', 11:58:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:58:26 INFO - 'tests': ['tp5n']}, 11:58:26 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:58:26 INFO - 'talos_options': ['--xperf_path', 11:58:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:58:26 INFO - 'tests': ['tp5n']}}, 11:58:26 INFO - 'talos.zip': {'path': '', 11:58:26 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:58:26 INFO - Running post-action listener: _resource_record_post_action 11:58:26 INFO - [mozharness: 2017-02-16 19:58:26.368657Z] Finished populate-webroot step (success) 11:58:26 INFO - [mozharness: 2017-02-16 19:58:26.368787Z] Running create-virtualenv step. 11:58:26 INFO - Running pre-action listener: _resource_record_pre_action 11:58:26 INFO - Running main action method: create_virtualenv 11:58:26 INFO - Creating virtualenv /builds/slave/test/build/venv 11:58:26 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 11:58:26 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:58:26 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:58:26 INFO - Using real prefix '/tools/python27' 11:58:26 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:58:27 INFO - Installing distribute.............................................................................................................................................................................................done. 11:58:30 INFO - Installing pip.................done. 11:58:30 INFO - Return code: 0 11:58:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:58:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:58:30 INFO - Reading from file tmpfile_stdout 11:58:30 INFO - Output received: 11:58:30 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) 11:58:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:58:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:58:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:58:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:58:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:58:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:58:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:58:30 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 0x10c66bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c66e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6d0cdb5e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c449d30>, '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 0x10c370db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c43ebe8>, '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.wmHpCgrqwr/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.WCOUbl0dfX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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 11:58:30 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 11:58:30 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 11:58:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wmHpCgrqwr/Render', 11:58:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:58:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:58:30 INFO - 'HOME': '/Users/cltbld', 11:58:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:58:30 INFO - 'LOGNAME': 'cltbld', 11:58:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:58:30 INFO - 'MOZ_NO_REMOTE': '1', 11:58:30 INFO - 'NO_EM_RESTART': '1', 11:58:30 INFO - 'PAGER': '/bin/cat', 11:58:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:58:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:58:30 INFO - 'PWD': '/builds/slave/test', 11:58:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:58:30 INFO - 'SHELL': '/bin/bash', 11:58:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WCOUbl0dfX/Listeners', 11:58:30 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 11:58:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:58:30 INFO - 'USER': 'cltbld', 11:58:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:58:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:58:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:58:30 INFO - 'XPC_FLAGS': '0x0', 11:58:30 INFO - 'XPC_SERVICE_NAME': '0', 11:58:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:58:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:58:30 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 11:58:30 INFO - Cleaning up... 11:58:30 INFO - Return code: 0 11:58:30 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:58:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:58:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:58:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:58:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:58:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:58:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:58:30 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 0x10c66bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c66e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6d0cdb5e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c449d30>, '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 0x10c370db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c43ebe8>, '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.wmHpCgrqwr/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.WCOUbl0dfX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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 11:58:30 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 11:58:30 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 11:58:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wmHpCgrqwr/Render', 11:58:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:58:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:58:30 INFO - 'HOME': '/Users/cltbld', 11:58:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:58:30 INFO - 'LOGNAME': 'cltbld', 11:58:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:58:30 INFO - 'MOZ_NO_REMOTE': '1', 11:58:30 INFO - 'NO_EM_RESTART': '1', 11:58:30 INFO - 'PAGER': '/bin/cat', 11:58:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:58:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:58:30 INFO - 'PWD': '/builds/slave/test', 11:58:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:58:30 INFO - 'SHELL': '/bin/bash', 11:58:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WCOUbl0dfX/Listeners', 11:58:30 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 11:58:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:58:30 INFO - 'USER': 'cltbld', 11:58:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:58:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:58:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:58:30 INFO - 'XPC_FLAGS': '0x0', 11:58:30 INFO - 'XPC_SERVICE_NAME': '0', 11:58:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:58:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:58:30 INFO - Downloading/unpacking psutil>=3.1.1 11:58:30 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 11:58:30 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 11:58:30 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 11:58:30 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 11:58:30 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 11:58:30 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 11:58:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:58:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:58:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:58:34 INFO - Installing collected packages: psutil 11:58:34 INFO - Running setup.py install for psutil 11:58:34 INFO - building 'psutil._psutil_osx' extension 11:58:34 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 11:58:34 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 11:58:34 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 11:58:34 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 11:58:34 INFO - building 'psutil._psutil_posix' extension 11:58:34 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 11:58:35 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:58:35 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:58:35 INFO - ^ 11:58:35 INFO - 1 warning generated. 11:58:35 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 11:58:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:58:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:58:35 INFO - Successfully installed psutil 11:58:35 INFO - Cleaning up... 11:58:35 INFO - Return code: 0 11:58:35 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:58:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:58:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:58:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:58:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:58:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:58:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:58:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c66bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c66e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6d0cdb5e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c449d30>, '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 0x10c370db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c43ebe8>, '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.wmHpCgrqwr/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.WCOUbl0dfX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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 11:58:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 11:58:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 11:58:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wmHpCgrqwr/Render', 11:58:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:58:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:58:35 INFO - 'HOME': '/Users/cltbld', 11:58:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:58:35 INFO - 'LOGNAME': 'cltbld', 11:58:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:58:35 INFO - 'MOZ_NO_REMOTE': '1', 11:58:35 INFO - 'NO_EM_RESTART': '1', 11:58:35 INFO - 'PAGER': '/bin/cat', 11:58:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:58:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:58:35 INFO - 'PWD': '/builds/slave/test', 11:58:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:58:35 INFO - 'SHELL': '/bin/bash', 11:58:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WCOUbl0dfX/Listeners', 11:58:35 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 11:58:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:58:35 INFO - 'USER': 'cltbld', 11:58:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:58:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:58:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:58:35 INFO - 'XPC_FLAGS': '0x0', 11:58:35 INFO - 'XPC_SERVICE_NAME': '0', 11:58:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:58:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:58:35 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:58:35 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 11:58:35 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 11:58:35 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 11:58:35 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 11:58:35 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 11:58:35 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 11:58:38 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:58:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:58:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:58:38 INFO - Installing collected packages: mozsystemmonitor 11:58:38 INFO - Running setup.py install for mozsystemmonitor 11:58:38 INFO - Successfully installed mozsystemmonitor 11:58:38 INFO - Cleaning up... 11:58:38 INFO - Return code: 0 11:58:38 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:58: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')]} 11:58:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:58:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:58: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')]} 11:58:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:58:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:58: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', '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 0x10c66bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c66e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6d0cdb5e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c449d30>, '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 0x10c370db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c43ebe8>, '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.wmHpCgrqwr/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.WCOUbl0dfX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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 11:58: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 11:58: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 jsonschema==2.5.1 11:58:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wmHpCgrqwr/Render', 11:58:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:58:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:58:38 INFO - 'HOME': '/Users/cltbld', 11:58:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:58:38 INFO - 'LOGNAME': 'cltbld', 11:58:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:58:38 INFO - 'MOZ_NO_REMOTE': '1', 11:58:38 INFO - 'NO_EM_RESTART': '1', 11:58:38 INFO - 'PAGER': '/bin/cat', 11:58:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:58:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:58:38 INFO - 'PWD': '/builds/slave/test', 11:58:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:58:38 INFO - 'SHELL': '/bin/bash', 11:58:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WCOUbl0dfX/Listeners', 11:58:38 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 11:58:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:58:38 INFO - 'USER': 'cltbld', 11:58:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:58:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:58:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:58:38 INFO - 'XPC_FLAGS': '0x0', 11:58:38 INFO - 'XPC_SERVICE_NAME': '0', 11:58:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:58:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:58:38 INFO - Downloading/unpacking jsonschema==2.5.1 11:58: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 11:58: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 11:58: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 11:58: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 11:58: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 11:58: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 11:58:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:58:41 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:58:41 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 11:58:41 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 11:58:41 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 11:58:41 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 11:58:41 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 11:58:41 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 11:58:41 INFO - Downloading functools32-3.2.3-2.tar.gz 11:58:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:58:41 INFO - warning: no files found matching '*.txt' 11:58:41 INFO - no previously-included directories found matching 'build' 11:58:41 INFO - no previously-included directories found matching 'dist' 11:58:41 INFO - no previously-included directories found matching '.git*' 11:58:41 INFO - Installing collected packages: jsonschema, functools32 11:58:41 INFO - Running setup.py install for functools32 11:58:42 INFO - warning: no files found matching '*.txt' 11:58:42 INFO - no previously-included directories found matching 'build' 11:58:42 INFO - no previously-included directories found matching 'dist' 11:58:42 INFO - no previously-included directories found matching '.git*' 11:58:42 INFO - Successfully installed jsonschema functools32 11:58:42 INFO - Cleaning up... 11:58:42 INFO - Return code: 0 11:58:42 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:58:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:58:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:58:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:58:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:58:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:58:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:58:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10c66bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c66e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6d0cdb5e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c449d30>, '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 0x10c370db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c43ebe8>, '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.wmHpCgrqwr/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.WCOUbl0dfX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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 11:58:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 11:58:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 11:58:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wmHpCgrqwr/Render', 11:58:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:58:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:58:42 INFO - 'HOME': '/Users/cltbld', 11:58:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:58:42 INFO - 'LOGNAME': 'cltbld', 11:58:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:58:42 INFO - 'MOZ_NO_REMOTE': '1', 11:58:42 INFO - 'NO_EM_RESTART': '1', 11:58:42 INFO - 'PAGER': '/bin/cat', 11:58:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:58:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:58:42 INFO - 'PWD': '/builds/slave/test', 11:58:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:58:42 INFO - 'SHELL': '/bin/bash', 11:58:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WCOUbl0dfX/Listeners', 11:58:42 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 11:58:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:58:42 INFO - 'USER': 'cltbld', 11:58:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:58:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:58:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:58:42 INFO - 'XPC_FLAGS': '0x0', 11:58:42 INFO - 'XPC_SERVICE_NAME': '0', 11:58:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:58:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:58:42 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:58:42 INFO - Cleaning up... 11:58:42 INFO - Return code: 0 11:58:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:58:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:58:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:58:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:58:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:58:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:58:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:58:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10c66bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c66e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6d0cdb5e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c449d30>, '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 0x10c370db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c43ebe8>, '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.wmHpCgrqwr/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.WCOUbl0dfX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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 11:58:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 11:58:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 11:58:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wmHpCgrqwr/Render', 11:58:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:58:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:58:42 INFO - 'HOME': '/Users/cltbld', 11:58:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:58:42 INFO - 'LOGNAME': 'cltbld', 11:58:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:58:42 INFO - 'MOZ_NO_REMOTE': '1', 11:58:42 INFO - 'NO_EM_RESTART': '1', 11:58:42 INFO - 'PAGER': '/bin/cat', 11:58:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:58:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:58:42 INFO - 'PWD': '/builds/slave/test', 11:58:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:58:42 INFO - 'SHELL': '/bin/bash', 11:58:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WCOUbl0dfX/Listeners', 11:58:42 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 11:58:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:58:42 INFO - 'USER': 'cltbld', 11:58:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:58:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:58:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:58:42 INFO - 'XPC_FLAGS': '0x0', 11:58:42 INFO - 'XPC_SERVICE_NAME': '0', 11:58:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:58:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:58:42 INFO - Downloading/unpacking blobuploader==1.2.4 11:58: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 11:58: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 11:58: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 11:58: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 11:58: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 11:58: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 11:58:45 INFO - Downloading blobuploader-1.2.4.tar.gz 11:58:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:58:45 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:58: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 11:58: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 11:58: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 11:58: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 11:58: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 11:58: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 11:58:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:58:46 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:58: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 11:58: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 11:58: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 11:58: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 11:58: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 11:58: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 11:58:46 INFO - Downloading docopt-0.6.1.tar.gz 11:58:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:58:46 INFO - Installing collected packages: blobuploader, requests, docopt 11:58:46 INFO - Running setup.py install for blobuploader 11:58:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:58:46 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:58:46 INFO - Running setup.py install for requests 11:58:47 INFO - Running setup.py install for docopt 11:58:47 INFO - Successfully installed blobuploader requests docopt 11:58:47 INFO - Cleaning up... 11:58:47 INFO - Return code: 0 11:58:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:58:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:58:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:58:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:58:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:58:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:58:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:58:47 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 0x10c66bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c66e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6d0cdb5e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c449d30>, '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 0x10c370db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c43ebe8>, '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.wmHpCgrqwr/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.WCOUbl0dfX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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 11:58:47 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 11:58:47 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 11:58:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wmHpCgrqwr/Render', 11:58:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:58:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:58:47 INFO - 'HOME': '/Users/cltbld', 11:58:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:58:47 INFO - 'LOGNAME': 'cltbld', 11:58:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:58:47 INFO - 'MOZ_NO_REMOTE': '1', 11:58:47 INFO - 'NO_EM_RESTART': '1', 11:58:47 INFO - 'PAGER': '/bin/cat', 11:58:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:58:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:58:47 INFO - 'PWD': '/builds/slave/test', 11:58:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:58:47 INFO - 'SHELL': '/bin/bash', 11:58:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WCOUbl0dfX/Listeners', 11:58:47 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 11:58:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:58:47 INFO - 'USER': 'cltbld', 11:58:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:58:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:58:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:58:47 INFO - 'XPC_FLAGS': '0x0', 11:58:47 INFO - 'XPC_SERVICE_NAME': '0', 11:58:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:58:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:58:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:58:47 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-361Jal-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:58:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:58:47 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-mCFO0i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:58:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:58:47 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-nUYIRD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:58:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:58:48 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-ZvnmVq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:58:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:58:48 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-1pPxK2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:58:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:58:48 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-T1qHL3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:58:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:58:48 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-rSkH_4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:58:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:58:48 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-b9g0_P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:58:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:58:48 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-aOxI_W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:58:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:58:48 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-ECndZh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:58:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:58:48 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-wAMhga-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:58:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:58:48 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-MioFIP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:58:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:58:48 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-3ly6jn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:58:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:58:49 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-oPISf8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:58:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:58:49 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-_PVKJt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:58:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:58:49 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-4EqWkD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:58:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:58:49 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-61aZRs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:58:49 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:58:49 INFO - Running setup.py install for manifestparser 11:58:49 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:58:49 INFO - Running setup.py install for mozcrash 11:58:49 INFO - Running setup.py install for mozdebug 11:58:49 INFO - Running setup.py install for mozdevice 11:58:50 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:58:50 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:58:50 INFO - Running setup.py install for mozfile 11:58:50 INFO - Running setup.py install for mozhttpd 11:58:50 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:58:50 INFO - Running setup.py install for mozinfo 11:58:50 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:58:50 INFO - Running setup.py install for mozInstall 11:58:50 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:58:50 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:58:50 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:58:50 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:58:50 INFO - Running setup.py install for mozleak 11:58:50 INFO - Running setup.py install for mozlog 11:58:50 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:58:50 INFO - Running setup.py install for moznetwork 11:58:51 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:58:51 INFO - Running setup.py install for mozprocess 11:58:51 INFO - Running setup.py install for mozprofile 11:58:51 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:58:51 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:58:51 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:58:51 INFO - Running setup.py install for mozrunner 11:58:51 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:58:51 INFO - Running setup.py install for mozscreenshot 11:58:51 INFO - Running setup.py install for moztest 11:58:51 INFO - Running setup.py install for mozversion 11:58:52 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:58:52 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:58:52 INFO - Cleaning up... 11:58:52 INFO - Return code: 0 11:58:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:58:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:58:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:58:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:58:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:58:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:58:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:58:52 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 0x10c66bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c66e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6d0cdb5e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c449d30>, '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 0x10c370db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c43ebe8>, '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.wmHpCgrqwr/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.WCOUbl0dfX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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 11:58:52 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 11:58:52 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 11:58:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wmHpCgrqwr/Render', 11:58:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:58:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:58:52 INFO - 'HOME': '/Users/cltbld', 11:58:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:58:52 INFO - 'LOGNAME': 'cltbld', 11:58:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:58:52 INFO - 'MOZ_NO_REMOTE': '1', 11:58:52 INFO - 'NO_EM_RESTART': '1', 11:58:52 INFO - 'PAGER': '/bin/cat', 11:58:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:58:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:58:52 INFO - 'PWD': '/builds/slave/test', 11:58:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:58:52 INFO - 'SHELL': '/bin/bash', 11:58:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WCOUbl0dfX/Listeners', 11:58:52 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 11:58:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:58:52 INFO - 'USER': 'cltbld', 11:58:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:58:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:58:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:58:52 INFO - 'XPC_FLAGS': '0x0', 11:58:52 INFO - 'XPC_SERVICE_NAME': '0', 11:58:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:58:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:58:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:58:52 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-sUkWMN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:58:52 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)) 11:58:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:58:52 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-0S9zab-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:58:52 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)) 11:58:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:58:52 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-CM4eHb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:58:52 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)) 11:58:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:58:52 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-9Nc1ak-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:58:52 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)) 11:58:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:58:52 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-sLxH5l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:58:52 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)) 11:58:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:58:52 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-wNrUps-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:58:52 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)) 11:58:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:58:52 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-ZAIrVI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:58:53 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)) 11:58:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:58:53 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-UpltSj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:58:53 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)) 11:58:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:58:53 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-1vFzJk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:58:53 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)) 11:58:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:58:53 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-ObzKKI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:58:53 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)) 11:58:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:58:53 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-VZZDFl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:58:53 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)) 11:58:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:58:53 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-hdkOcs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:58:53 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)) 11:58:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:58:53 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-IvSXei-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:58:53 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)) 11:58:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:58:53 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-aBW_Av-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:58:53 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)) 11:58:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:58:53 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-E8RC0S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:58:53 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)) 11:58:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:58:53 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-MdeCdT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:58:54 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)) 11:58:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:58:54 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-zHba_J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:58:54 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)) 11:58:54 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)) 11:58:54 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)) 11:58:54 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)) 11:58:54 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)) 11:58:54 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)) 11:58:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:58:54 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 11:58:54 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 11:58:54 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 11:58:54 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 11:58:54 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 11:58:54 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 11:58:56 INFO - Downloading blessings-1.6.tar.gz 11:58:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:58:56 INFO - Installing collected packages: blessings 11:58:56 INFO - Running setup.py install for blessings 11:58:56 INFO - Successfully installed blessings 11:58:56 INFO - Cleaning up... 11:58:57 INFO - Return code: 0 11:58:57 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:58:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:58:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:58:57 INFO - Reading from file tmpfile_stdout 11:58:57 INFO - Current package versions: 11:58:57 INFO - blessings == 1.6 11:58:57 INFO - blobuploader == 1.2.4 11:58:57 INFO - docopt == 0.6.1 11:58:57 INFO - functools32 == 3.2.3-2 11:58:57 INFO - jsonschema == 2.5.1 11:58:57 INFO - manifestparser == 1.1 11:58:57 INFO - mozInstall == 1.12 11:58:57 INFO - mozcrash == 1.0 11:58:57 INFO - mozdebug == 0.1 11:58:57 INFO - mozdevice == 0.49 11:58:57 INFO - mozfile == 1.2 11:58:57 INFO - mozhttpd == 0.7 11:58:57 INFO - mozinfo == 0.9 11:58:57 INFO - mozleak == 0.1 11:58:57 INFO - mozlog == 3.4 11:58:57 INFO - moznetwork == 0.27 11:58:57 INFO - mozprocess == 0.24 11:58:57 INFO - mozprofile == 0.28 11:58:57 INFO - mozrunner == 6.13 11:58:57 INFO - mozscreenshot == 0.1 11:58:57 INFO - mozsystemmonitor == 0.3 11:58:57 INFO - moztest == 0.8 11:58:57 INFO - mozversion == 1.4 11:58:57 INFO - psutil == 3.1.1 11:58:57 INFO - requests == 1.2.3 11:58:57 INFO - wsgiref == 0.1.2 11:58:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:58:57 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')]} 11:58:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:58:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:58:57 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')]} 11:58:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:58:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:58:57 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 0x10c66bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c66e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6d0cdb5e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c449d30>, '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 0x10c370db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c43ebe8>, '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.wmHpCgrqwr/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.WCOUbl0dfX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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 11:58:57 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 11:58:57 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 11:58:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wmHpCgrqwr/Render', 11:58:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:58:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:58:57 INFO - 'HOME': '/Users/cltbld', 11:58:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:58:57 INFO - 'LOGNAME': 'cltbld', 11:58:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:58:57 INFO - 'MOZ_NO_REMOTE': '1', 11:58:57 INFO - 'NO_EM_RESTART': '1', 11:58:57 INFO - 'PAGER': '/bin/cat', 11:58:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:58:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:58:57 INFO - 'PWD': '/builds/slave/test', 11:58:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:58:57 INFO - 'SHELL': '/bin/bash', 11:58:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WCOUbl0dfX/Listeners', 11:58:57 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 11:58:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:58:57 INFO - 'USER': 'cltbld', 11:58:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:58:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:58:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:58:57 INFO - 'XPC_FLAGS': '0x0', 11:58:57 INFO - 'XPC_SERVICE_NAME': '0', 11:58:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:58:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:58:57 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)) 11:58:57 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)) 11:58:57 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)) 11:58:57 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)) 11:58:57 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)) 11:58:57 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)) 11:58:57 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)) 11:58:57 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)) 11:58:57 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)) 11:58:57 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)) 11:58:57 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)) 11:58:57 INFO - Cleaning up... 11:58:57 INFO - Return code: 0 11:58:57 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:58:57 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')]} 11:58:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:58:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:58:57 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')]} 11:58:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:58:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:58:57 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 0x10c66bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c66e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6d0cdb5e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c449d30>, '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 0x10c370db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c43ebe8>, '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.wmHpCgrqwr/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.WCOUbl0dfX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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 11:58:57 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 11:58:57 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 11:58:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wmHpCgrqwr/Render', 11:58:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:58:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:58:57 INFO - 'HOME': '/Users/cltbld', 11:58:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:58:57 INFO - 'LOGNAME': 'cltbld', 11:58:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:58:57 INFO - 'MOZ_NO_REMOTE': '1', 11:58:57 INFO - 'NO_EM_RESTART': '1', 11:58:57 INFO - 'PAGER': '/bin/cat', 11:58:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:58:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:58:57 INFO - 'PWD': '/builds/slave/test', 11:58:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:58:57 INFO - 'SHELL': '/bin/bash', 11:58:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WCOUbl0dfX/Listeners', 11:58:57 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 11:58:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:58:57 INFO - 'USER': 'cltbld', 11:58:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:58:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:58:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:58:57 INFO - 'XPC_FLAGS': '0x0', 11:58:57 INFO - 'XPC_SERVICE_NAME': '0', 11:58:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:58:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:58:57 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:58:57 INFO - Cleaning up... 11:58:57 INFO - Return code: 0 11:58:57 INFO - Running post-action listener: _resource_record_post_action 11:58:57 INFO - Running post-action listener: _start_resource_monitoring 11:58:57 INFO - Starting resource monitoring. 11:58:57 INFO - [mozharness: 2017-02-16 19:58:57.886041Z] Finished create-virtualenv step (success) 11:58:57 INFO - [mozharness: 2017-02-16 19:58:57.887059Z] Running install step. 11:58:57 INFO - Running pre-action listener: _resource_record_pre_action 11:58:57 INFO - Running main action method: install 11:58:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:58:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:58:58 INFO - Reading from file tmpfile_stdout 11:58:58 INFO - Detecting whether we're running mozinstall >=1.0... 11:58:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:58:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:58:58 INFO - Reading from file tmpfile_stdout 11:58:58 INFO - Output received: 11:58:58 INFO - Usage: mozinstall [options] installer 11:58:58 INFO - Options: 11:58:58 INFO - -h, --help show this help message and exit 11:58:58 INFO - -d DEST, --destination=DEST 11:58:58 INFO - Directory to install application into. [default: 11:58:58 INFO - "/builds/slave/test"] 11:58:58 INFO - --app=APP Application being installed. [default: firefox] 11:58:58 INFO - mkdir: /builds/slave/test/build/application 11:58:58 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'] 11:58:58 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 11:59:17 INFO - Reading from file tmpfile_stdout 11:59:17 INFO - Output received: 11:59:17 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 11:59:17 INFO - Running post-action listener: _resource_record_post_action 11:59:17 INFO - [mozharness: 2017-02-16 19:59:17.938586Z] Finished install step (success) 11:59:17 INFO - [mozharness: 2017-02-16 19:59:17.938768Z] Running run-tests step. 11:59:17 INFO - Running pre-action listener: _resource_record_pre_action 11:59:17 INFO - Running main action method: run_tests 11:59:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:59:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:59:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:59:17 INFO - 2017-02-16 11:59:17.952 screenresolution[1984:19577] starting screenresolution argv=screenresolution get 11:59:17 INFO - 2017-02-16 11:59:17.954 screenresolution[1984:19577] Display 0: 1600x1200x32@60 11:59:17 INFO - 2017-02-16 11:59:17.959 screenresolution[1985:19580] starting screenresolution argv=screenresolution list 11:59:17 INFO - Available Modes on Display 0 11:59:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:59:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:59:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:59:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:59:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:59:18 INFO - Intel Iris: 11:59:18 INFO - Chipset Model: Intel Iris 11:59:18 INFO - Type: GPU 11:59:18 INFO - Bus: Built-In 11:59:18 INFO - VRAM (Dynamic, Max): 1536 MB 11:59:18 INFO - Vendor: Intel (0x8086) 11:59:18 INFO - Device ID: 0x0a2e 11:59:18 INFO - Revision ID: 0x0009 11:59:18 INFO - Displays: 11:59:18 INFO - Display: 11:59:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:59:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:59:18 INFO - Display Serial Number: 1600x1200 60 11:59:18 INFO - Main Display: Yes 11:59:18 INFO - Mirror: Off 11:59:18 INFO - Online: Yes 11:59:18 INFO - Rotation: Supported 11:59:18 INFO - Return code: 0 11:59:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:59:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:59:18 INFO - Python 2.7.3 11:59:18 INFO - Return code: 0 11:59:18 INFO - grabbing minidump binary from tooltool 11:59:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:59:18 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 0x10c449d30>, '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 0x10c370db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c43ebe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:59:18 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 11:59:18 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 11:59:18 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 11:59:18 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:59:18 INFO - Return code: 0 11:59:18 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:59:18 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:59:18 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:59:18 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:59:18 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:59:18 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/bFahilKxT8C1Z3Wze7ik8Q/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0167', '--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 11:59:18 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/bFahilKxT8C1Z3Wze7ik8Q/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0167 --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 11:59:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wmHpCgrqwr/Render', 11:59:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:59:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:59:18 INFO - 'HOME': '/Users/cltbld', 11:59:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:59:18 INFO - 'LOGNAME': 'cltbld', 11:59:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:59:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:59:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:59:18 INFO - 'MOZ_NO_REMOTE': '1', 11:59:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:59:18 INFO - 'NO_EM_RESTART': '1', 11:59:18 INFO - 'PAGER': '/bin/cat', 11:59:18 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:59:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:59:18 INFO - 'PWD': '/builds/slave/test', 11:59:18 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:59:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:59:18 INFO - 'SHELL': '/bin/bash', 11:59:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WCOUbl0dfX/Listeners', 11:59:18 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 11:59:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:59:18 INFO - 'USER': 'cltbld', 11:59:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:59:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:59:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:59:18 INFO - 'XPC_FLAGS': '0x0', 11:59:18 INFO - 'XPC_SERVICE_NAME': '0', 11:59:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:59:18 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/bFahilKxT8C1Z3Wze7ik8Q/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0167', '--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 11:59:18 INFO - mozversion application_buildid: 20170216102419 11:59:18 INFO - mozversion application_changeset: 3a3e07e22ea8a9f6523172c6e85ff363b9b1a53d 11:59:18 INFO - mozversion application_display_name: FirefoxDeveloperEdition 11:59:18 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:59:18 INFO - mozversion application_name: Firefox 11:59:18 INFO - mozversion application_remotingname: firefox-dev 11:59:18 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 11:59:18 INFO - mozversion application_vendor: Mozilla 11:59:18 INFO - mozversion application_version: 53.0a2 11:59:18 INFO - mozversion platform_buildid: 20170216102419 11:59:18 INFO - mozversion platform_changeset: 3a3e07e22ea8a9f6523172c6e85ff363b9b1a53d 11:59:18 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 11:59:18 INFO - mozversion platform_version: 53.0a2 11:59:18 INFO - using testdate: 1487275158 11:59:18 INFO - actual date: 1487275158 11:59:18 INFO - starting webserver on 'localhost:49294' 11:59:18 INFO - SUITE-START | Running 2 tests 11:59:18 INFO - TEST-START | dromaeo_css 11:59:18 INFO - operating with platform_type : mac_ 11:59:18 INFO - Initialising browser for dromaeo_css test... 11:59:18 INFO - TEST-INFO | started process 1995 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/tmpjqpMWm/profile http://localhost:49294/getInfo.html) 11:59:21 INFO - PROCESS | 1995 | 2017-02-16 11:59:21.721 plugin-container[1997:19754] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c3f, name = 'com.apple.tsm.portname' 11:59:21 INFO - PROCESS | 1995 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:59:21 INFO - PROCESS | 1995 | 2017-02-16 11:59:21.726 plugin-container[1997:19754] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b23, name = 'com.apple.CFPasteboardClient' 11:59:21 INFO - PROCESS | 1995 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:59:21 INFO - PROCESS | 1995 | 2017-02-16 11:59:21.726 plugin-container[1997:19754] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:59:21 INFO - PROCESS | 1995 | __metrics Screen width/height:1600/1200 11:59:21 INFO - PROCESS | 1995 | colorDepth:24 11:59:21 INFO - PROCESS | 1995 | Browser inner width/height: 1024/689 11:59:21 INFO - PROCESS | 1995 | __metrics 11:59:21 ERROR - PROCESS | 1995 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 11:59:22 INFO - TEST-INFO | 1995: exit 0 11:59:22 INFO - Browser initialized. 11:59:22 INFO - Running cycle 1/1 for dromaeo_css test... 11:59:22 INFO - TEST-INFO | started process 1998 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/tmpjqpMWm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:59:25 INFO - PROCESS | 1998 | 2017-02-16 11:59:25.065 plugin-container[1999:19883] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4743, name = 'com.apple.tsm.portname' 11:59:25 INFO - PROCESS | 1998 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:59:25 INFO - PROCESS | 1998 | 2017-02-16 11:59:25.066 plugin-container[1999:19883] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x910b, name = 'com.apple.CFPasteboardClient' 11:59:25 INFO - PROCESS | 1998 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:59:25 INFO - PROCESS | 1998 | 2017-02-16 11:59:25.066 plugin-container[1999:19883] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:59:34 INFO - PROCESS | 1998 | RSS: Main: 218746880 11:59:34 INFO - PROCESS | 1998 | 12:02:38 INFO - PROCESS | 1998 | Cycle 1(1): loaded http://localhost:49294/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49294/tests/dromaeo/cssquery-ext.html) 12:02:39 INFO - PROCESS | 1998 | RSS: Main: 237862912 12:02:39 INFO - PROCESS | 1998 | 12:04:23 INFO - PROCESS | 1998 | ************************* 12:04:23 INFO - PROCESS | 1998 | A coding exception was thrown and uncaught in a Task. 12:04:23 INFO - PROCESS | 1998 | 12:04:23 ERROR - PROCESS | 1998 | Full message: TypeError: NetworkError when attempting to fetch resource. 12:04:23 INFO - PROCESS | 1998 | Full stack: 12:04:23 INFO - PROCESS | 1998 | ************************* 12:05:42 INFO - PROCESS | 1998 | Cycle 1(1): loaded http://localhost:49294/tests/dromaeo/cssquery-ext.html (next: http://localhost:49294/tests/dromaeo/cssquery-jquery.html) 12:05:42 INFO - PROCESS | 1998 | RSS: Main: 247676928 12:05:42 INFO - PROCESS | 1998 | 12:08:44 INFO - PROCESS | 1998 | Cycle 1(1): loaded http://localhost:49294/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49294/tests/dromaeo/cssquery-mootools.html) 12:08:45 INFO - PROCESS | 1998 | RSS: Main: 254869504 12:08:45 INFO - PROCESS | 1998 | 12:11:47 INFO - PROCESS | 1998 | Cycle 1(1): loaded http://localhost:49294/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49294/tests/dromaeo/cssquery-prototype.html) 12:11:48 INFO - PROCESS | 1998 | RSS: Main: 252289024 12:11:48 INFO - PROCESS | 1998 | 12:14:50 INFO - PROCESS | 1998 | Cycle 1(1): loaded http://localhost:49294/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49294/tests/dromaeo/cssquery-yui.html) 12:14:51 INFO - PROCESS | 1998 | RSS: Main: 255799296 12:14:51 INFO - PROCESS | 1998 | 12:17:54 INFO - PROCESS | 1998 | Cycle 1(1): loaded http://localhost:49294/tests/dromaeo/cssquery-yui.html (next: http://localhost:49294/tests/dromaeo/cssquery-dojo.html) 12:17:54 INFO - PROCESS | 1998 | RSS: Main: 257069056 12:17:54 INFO - PROCESS | 1998 | 12:17:54 INFO - PROCESS | 1998 | __start_tp_report 12:17:54 INFO - PROCESS | 1998 | _x_x_mozilla_page_load 12:17:54 INFO - PROCESS | 1998 | _x_x_mozilla_page_load_details 12:17:54 INFO - PROCESS | 1998 | |i|pagename|runs| 12:17:54 INFO - PROCESS | 1998 | |0;dojo.html;3265.5428240046394;3270.0516850113468;3317.0712200583844;3323.335332933414;3338.2322065924836;5397.568194544437;5411.837644870655;5439.864003399917;5492.972535137319;5495;5478.287822583058;5503;5535.778568857241;5543.6396634218745;5546.389897111315;4878.853634390974;5021.849344519652;5080.923786143211;5101.336826212587;5107.591392688576;5086.949130508703;5097.158968770148;5132.871678208037;5141.691498510083;5173.534381905628;4800.255960326155;4816.277558366256;5129.923051154217;5148.2020286855595;5152.871178220537;5073.441921388663;5087.465816089331;5101.260317253996;5121.052605268037;5169.948300516984;4962.255661650745;5066.544011038987;5068.949310506885;5080.212567052113;5124.052050370656;5349.786008559653;5436.266104075979;5478.397376288605;5579.525740312079;5591.580631452616;5906.26171728534;5940.2871655401195;5967.910481342783;5992.580519363646;6087.817365479043;6124.448799608012;6137.570370074097;6142.969285153546;6154.5076393888385;6157.7229024694;5649.954758369674;5652.7173641318595;5657.12314591239;5666.716664166775;5678.716064196774;6083.300420451616;6098.5426093042315;6117.370705100086;6133.946794548733;6134;5222.529972302511;5642.927843709682;5949.524038076944;5971.880562388815;5987;6022.126791615191;6085.330613632497;6109.083637454418;6122.571420000557;6126.23422072241;5133.717645529457;5500.80747173847;5769;5876.823695289148;6017.578769486182;5091.8981620367385;5274.340707411574;5901.58688891782;5909.645421274738;5920.733566989496;4938.160512712776;4942.085714142895;4948.950510494849;4954.653174277766;4957.603391728653;4342.652587792969;4360.106178233455;4370.21336159488;4393.319035549495;4402.3396490526675;4864.6594738368685;5558.583106267046;5676.8864622707315;5706.429357064343;5729.140628905614;6023.427774361428;6024.216851809237;6051.243594550681;6143.849652621547;6145.631262124287;5542.418046105101;5617.466340697627;5647.717614119278;5701.600887937804;5713.914291285634;5222.556082732935;5340.25236466895;5360.65155764874;5368.973155134199;5369;6120.812855101315;6150.415710507495;6179.3820617937845;6272.278687950853;6298.622082674871;5968.791092311748;6077.4226448487325;6082.452579267887;6083.117947897618;6119.541034422347;6199.258319022339;6208.00671892495;6221.440070393686;6224.1597384352535;6230;5778.653280803165;5849.034909240098;6018.781306326478;6021.036634138517;6037.78867739627;5986.880262394815;6022.789202377896;6043.516518678407;6125.418593525838;6126.059675755388;5752.424757524214;5806.332271788629;5861.9413805863105;5906.70466476683;5907.409259074058;4814.470408255159;5672.14917762339;5811.1573821794755;5811.215485909348;5872.324682661549;4630.212863813093;4960.925586116139;5520;5663.461971112738;5754.884902302014;5839.532837373085;5840.2991641003355;5863.706814659336;5866.5893387462465;5869.911951320819;4629.930551041804;5684.346300175367;5698.230738850202;5727.599068065196;5728.283964504437 12:17:54 INFO - PROCESS | 1998 | |1;ext.html;423.3649525711433;460.8732598535403;469.21640859764193;499.8625378021046;527.2723641374902;36836.63163368365;42023.579764202346;42319.57680423195;43847.90380240496;43924.780376098155;42250;42629;42632.57367426325;42690;42834.92912677184;8166.101728809826;9243.815123697505;9495.57566365044;10707.946460267687;11056.502457389437;10951.178661600372;11064.0595549378;11069.391183484906;11072.501737421835;11080;11011.00900918921;11052.336859788398;11062.115030797557;11084.722881927935;11107.555697772079;11008.779824403508;11035.503402346914;11064.778704425948;11080.556777728842;11101.777964440666;10847.457627118612;10872.67381978542;10921.726956826064;10923.126149908068;10950.123990080854;37476;37815.81092094549;37822.432663510066;37872;37884.81057594722;3900.726949113563;3956.268090403256;3969.4045893115976;3979.602039796026;4027.496562929634;28252.434951301064;28553.714462855314;29798.914076014687;30115;30167;3175.7776955613117;3212.887548935776;3233.4826427771677;3356.6475520070285;3362.0250127463005;434023;480755;495235;496026;498359;58501.829963400494;58566.41433585695;58606.41393586095;58646.7067664659;58877;60410;60979.08531372033;61239.081413778826;61297.080543791875;61342.4664383394;13436.865631343757;15765.527034188992;16219.837801621832;16240.593985150466;16249.187540622921;11213.215074944676;11530.192886498033;12481.31352775588;12693.048021398432;12707.364631768374;16001.839981600267;17851.57187425003;18107.909460452614;18330.816691833177;18419.81580184181;7819.530828150329;7922.138297500198;7951.244631759973;7974.202579741979;8019.198080191934;867.3408209760628;869.1004810021598;871.5598622695499;884.2439714044399;899.0829354058824;29175.562366564518;30481;30691.69308306933;31256.374872502423;31385;990.2770977186694;1003.8745156855393;1069.9358038517714;1085.847981282621;1194.75507520958;12987;13717.45130194781;13975.09161904473;15674.529764107096;15814.9209253953;619543;626843;627693;627716;627958;38575.6142438572;43910;46271.76864115725;47907;47913.76043119762;1438.9136651800925;1445.6880824416694;1467.9633009174643;1477.0399240493764;1491.5898128014667;29142;30320.938767143045;30549;30651.21138251864;30769.230769230948;49860.501394985586;49937.25094123519;50482.24276635927;50659.24011139909;50782.74608626933;465204.34795652964;477817;553385.233073832;554363;555586.8882622406;24438.75561244365;25070.874645626656;25140.62289065702;25886.74113258843;26476.86761566257;42414.36378454264;43086.78456607697;43421.565784343016;43803;44191.33712994372;35359;35643.82178089093;35746;35801.82099089592;37170.25659486849;32349;33717.83141084279;35287.29425411529;36492.63507364999;36499.452508212926;34195.65804342024;34667.13332166716;34826.651733483355;35347;35382.646173537934;27812.30469238285;30015;31321.686783131878;32160.678393215767;32328.676713232566;21112.472188195417;23912.760872391053;24104.517909642058;24418.26745197647;24751.23263110684 12:17:54 INFO - PROCESS | 1998 | |2;jquery.html;347.8539013614282;385.78396098185016;398.52177387135424;405.6207446037953;420.8548050922433;18255.90464572123;19865;21879.562408751855;21985.890070549623;22081.889590552044;20541.486462838435;21208.89395553024;21458.785412145913;21780.910954452254;22503.662445063295;4541.43232095988;4679.976600117003;5350.892982140354;5357.08929481989;5367.677939323653;3879.35990561557;4069.9186016279655;5213.582913366921;5303.443138470463;5676.659400435967;5913.467787899088;6030.8793824123495;6031.6984150792505;6033.818985430444;6034.396560343975;5958.52331813456;5989.9101513477335;6477.838054048663;6484.1894763154605;6530.93469065308;5580.302462192226;5749.425057494259;5779.711014449303;5781.392953739839;5788.768449262025;13345.065845390827;13436.798448023288;13460;13649.795253071112;13694.863051369459;2511.4725907559437;2608.321836322551;2613.59489279162;2696.406790506105;2697.7167397423186;5822.505087067604;5895.204147440084;10284.537195826206;10493.108085812715;13879.791803122962;1817.8363947244811;1851.741825599587;1862.6926557118059;1868.093974422404;1884.1992153334777;117734;119733;134581;135625;141276.29361853228;11896.940515297369;11971.820422693667;12096.334701591497;12130.33283169434;12302.323372214436;15629.921850390903;15652.608684782972;15674.921625392028;15684;15684.666803321617;8573.228409443056;8595.011573668982;8762.693305734269;8926;8986.281097512183;7296.598687072262;8473.618687159093;8672.436291641023;8675.696350627824;8843.646254149891;4129.401236820644;4779.187538118528;4779.761011949458;4792.376990991219;4911.705297682151;1699.2098674116596;1765.523308706642;1924.3649595633408;2042.6629606114825;2051.189780036877;999.2505620784411;1013.6198925402974;1025.7999690060367;1034.1726618705006;1073.6886302972207;17614.383496577557;19835.909025003475;21678.45803854916;21881.452963676034;21955.792431416427;999.4403134244847;1051.3113910388709;1054.5887104029434;1092.5138313450598;1110.2616759854768;9919.65281215154;10341;11259.943700281447;11428.657140285804;11494.597689080842;732551.3372433104;747810;760793;771222;778479;157133;159260;162338.3766162347;164280;168765.15617421834;878.9057623259083;1015.9034891685426;1023.1661196125134;1042.8592140060994;1042.864427624404;18885.244590216236;19338.323158689378;19532;19628.705569416186;19665.901670492127;59223;59624.105638416324;60748.69625651844;60930.78138437295;61898.69050654718;83816;86394.56802715946;88013;89328.5533572328;89446.10553894378;7399.778006659809;7511.32398084175;7530.962345188458;7643.770686879402;7762.262585054411;8686.913130868863;10994;12150.088743344108;12582.414012006535;13243.470261189444;9665.855012174683;10967.890321096687;11367.54529818831;11431.085513159094;11804.940975295412;9177.724668259963;9324.673636422693;11340.149488788209;11811.350375729417;11868.76262474763;11330.433478326215;11454.255473394203;11644;11734.882651173379;12042.819357709817;7497.137829149718;7632.80917977055;7685.730999414994;7752.341051010727;7793.454458187873;10989;11364.043557024228;11366.772664546497;11439.599614013803;11569.942150289195 12:17:54 INFO - PROCESS | 1998 | |3;mootools.html;1406.6061502779223;1418.8581141885807;1433.6559225785809;1434.2685230532425;1448.9927550362247;7496.88754668679;7749.767506974786;8049.718259860899;8453.492790432578;8532.744017679464;7964.60176991151;8081.878771818428;8178.304844088259;8357.20606542379;8369.20492553208;2291.7708229177115;2344.6014177589764;2347.8473899196497;2349.647552867075;2356.1871154451774;2069.244725675125;2146.4097373222335;2332.7084114485688;2374.3351861478814;2380.8928598213124;2255.76314486979;2334.159702507096;2337.4273303040777;2340.2745149003777;2345.659879317498;2325.2326732178426;2336.5443738471004;2347.1784875293765;2349.553584818879;2356.2931120663734;2308.2613563659634;2312.7687231276736;2367.2188177901426;2376.7979721723673;2378.559966406203;8362.45644033136;8399.076101628816;8433.19884610961;8450.323974082059;8454.87317690235;5070.898582028339;5202.06362854686;5205.531502164786;5219.269302297681;5220.895582088375;3272.6236482804543;3492.580890293156;3530.6645868642436;3546.982265088684;3577.5528058992627;1172.3376292394769;1196.6410076976958;1248.2822377133184;1248.7877060899577;1256.8994480441543;9158.992510823804;10701.464926753632;10794.622188223453;10818.29681070728;10830.891691083067;3732.2908647356917;3797.7531460454984;4767.6424268179335;4770.475247722748;4790.928136077961;8696.565171741515;8723.60743766532;8796.38425310235;8808.911910880937;8815.030346661862;5176.197689358156;5183.118869792144;5187.818426355059;5200;5206.114960456732;4003.91992160161;4618.976905115453;4765.11845308619;4843.927341089887;4869.050535145612;3698.0754811297174;3709.3137769512728;3764.7741135531955;3788.8673896413493;3791.9431208531896;953.952302384878;1070.1813112968584;1088.2327958789035;1106.3417266726285;1152.475623591264;841.2554888923224;848.5333066813228;869.387082107113;887.0331338840566;895.3016647015338;5310.203469479609;5407.945920540744;5429.457054294618;5524.392316845144;5666.744996475086;835.0980940584154;940;969.4958621516913;982.6069572171189;1004.598160735697;3424.537687412217;3800.3349413852466;3880.3403421418425;3885;3887.9611203888167;9712.95143524292;9998.300118991747;10067.496625168713;10140.13808826258;10179;9620.470874101991;9827.11555959967;10018.298719089593;10033.397996120257;10061.094501494901;7655.425843061793;7999.000124984377;8013.959930200428;8017.158198389202;8033.55815430157;4090.050864091749;4494.820207191676;5285.471452854684;5428.375736790162;5737.454941780524;9154.725358239264;9173.35786494939;9370.625174992923;9380;9421.811563768822;9144;9222.538873056454;9336.11306925841;9367.953160234156;9482.85775713351;7139.535930164523;7227.710891564278;7264.309890560388;7282.198958114497;7284.890726638999;7776.805579860549;7814.6874125034365;7851.254130857559;7855;7876.803079923047;6962.860742785218;7087.433005359662;7107.82230444243;7160.964195178991;7203.351698346964;6845.760398386033;6977.755778547727;7036.155661320674;7051.788446346618;7059.152901651834;6322.525810564318;6344.9048264277;6362;6428.550001500037;6429.839254018687;4928.482509336468;4970.65205435616;4972.825951091694;5004.574611158092;5032.169692000779;4102.610252026053;4643.164230438553;4856.417229932289;4901.2893130496595;5073.264376665436 12:17:54 INFO - PROCESS | 1998 | |4;prototype.html;358.70765326259095;362.6174386022746;365.35697172975557;370.2059083266393;377.1815161100412;4921.114199444099;5151.304573882525;5217.530422261995;5405.91891121633;5450.945490545093;4309.676774241937;4509.346144809009;4840.322354870312;5265.447128051557;5272.789088436458;3948.4077388391756;4161.313383291753;4165.58344165584;4192.9790351048205;4198.286291330466;3596.29872174926;4019.3873981911697;4057.2088442753675;4204.894877628069;4207.474065741782;3937.5078115235597;4113.856015039474;4118.670506359484;4147.440095587087;4178;3416.863325467003;4129.174165166979;4129.625465357416;4130.380442933569;4155.4182414462;3373.510840928076;3590.9461358079648;3674;3686.870959516431;3689.3543629864666;4986.5013498650205;4990.725510096943;5113.872153196163;5131.153359695646;5173.456787037345;4063.593640635913;4780.211265141247;4800.3279540864305;4803.831865884677;4826.420829500483;5673.602847800656;5676.858078548028;5680.687562184078;5690.715464226772;5724.112762521816;3572.821358932043;3817.599152089046;3838.7696738195796;3851.9807400963095;3853.7687738735485;4812.807487700487;5228.9738551307;5595.552355811566;5866.823995280149;5935.228420305332;3688.8524459021337;3893.279743247508;3897.4738410314812;3899.1479047452235;3910.550286717007;3693.612170722089;3937.783421911822;3948.5853985331128;4163.458750362494;4212.410262563244;4804.1984255904035;4806.4712881583;4826.6621336506105;4836.540528649773;4868.78090485929;4513.907973038479;5681.57388195887;5702.51528620072;5707.6575405475805;5768.596198266165;1108.7948729485072;1330.82699249097;1371.6845125621162;1383.3014327764415;1385.6951470676436;962.2157941277836;964.6189755046718;988.7824678570847;994.7612572982576;1002.774375765447;4924.104219875302;5070.340855688811;5124.948750512522;5231.169090818259;5238.271370447743;5299.7280652643085;5540.199435183581;5577.358603760538;5581.665100094007;5692;4340.9364705646685;4884.96706567389;5040.823571174991;5090.185570308807;5162.896742065138;2595.662563866723;2696.32591852037;2867.2796322206927;2908.8545572721277;2956.364381657954;5578.4494428043545;5714.142928535732;5838.306891001647;5844.561657875676;5900.76396944126;4865.659403841697;5212.68723876561;5465.672059676433;5481.643693159932;5686.914696279559;5030.673006254582;5230.869228269324;5294.444083371268;5390.51485366311;5732.971335143297;4677.532246775295;4891.877703057452;5267.446918073547;5333.226682130991;5516.668999860021;4520.59314661682;4565.885852853705;5144.356955380577;5236.350549577023;5496.7526461310135;5217.4260831307765;5434.864128396822;5806.5645076618575;5825.679587622722;5842.510159909196;3887.203123359761;3999.2601368746873;4002.4996875390575;4002.779847108437;4024.3561030235023;5075.720835354091;5525.093842624288;5649.661020338793;5799.913001305068;5802.5357971361345;5236.9738151309;5558.416366281483;5573.136163894603;5670.347909990404;5693.5160511357;5004.999000200048;5634.915476267777;5671.461211184931;5675.631083979528;5684.289463817023;4689.390379250675;5147.6139289554185;5447.782088716565;5529;5610.607257491936;4881.877953051202;5135.409427915837;5231.42454330031;5600.215969764155;5725.370209277059;5578.386377498391;5580.107182850887;5584.218209450599;5591.916121258266;5638.295213098363 12:17:54 INFO - PROCESS | 1998 | |5;yui.html;718.9460790440718;737.3732327521607;750.3471979377941;787.5314188058103;839.9635318324259;14787.926060369697;15212.239388030595;15632.921835390836;15646.687066258668;15860.920695396535;13510.392032358543;14330.140191588485;14595.635109122277;14624.049436786601;14726.558203253893;870.3559366069095;884.6196135661663;886.5257087458211;887.3078998381272;891.6032365597312;687.110192300969;708.9822754431154;863.8920134983127;888.5690440136528;903.2773780975226;907.8865141857268;929.7489677787028;933.8879334479872;936.1106948399014;939.3002213351028;926.0276709455045;927.6057675487891;932.1936524905941;935.2331088507426;945.938513996588;695.4505940307201;696.7631005458118;696.9686364113627;699.098163369253;699.8950157476369;11627.534898604046;14556.854431455657;15187.164705941168;15192;15198.924005379902;11801.586944456903;13953.372098255602;14455.927720361331;14508.492202772852;14515.346809393497;8604;11644.602647682332;11648.88351116495;11682.41587920609;11772.823407648892;3603.4774957631;3615.403458429356;3617.330793803155;3687.299413111527;3770.5098337215986;107467;108329.99999999921;111157;113968.4301578495;114553;10672.626458074086;10693.465326733787;10716.946415267874;10761.730956726144;10790.838137427945;22024.339269821932;22856.65715014276;22966;23010;23030.309090727304;6419.711112999927;7460.552366858006;7506.211847756016;7563.205863384375;7570.637285288591;4154.813033413504;4210.347396153601;4269.082147338275;4327.6105150536605;4462.576055274743;3633.3096711624707;3678.172411207457;3691.077230692327;3705.55533335996;3722.3113723961155;682.9078074459984;727.4871215792854;729.7008226627057;739.6043116932523;749.0561892016099;580.4079838564725;664.4684252597999;710.4742490557054;716.9569825810468;723.6779633063236;9440.24478041755;9760.755981100529;9905.900940990497;10151.695449136538;10360.585576576852;656.1699450195506;677.1941389746187;690.5097380859545;698.179638924262;698.7624207769383;4782.880427989328;4898.995705880286;4906.803727850846;4909.165441874889;4972.8508144755715;76886.23113768903;109907.45046274878;111148.33277500844;112272.31591526134;113383;86501.56749216214;87003.56498217594;87343.56328218318;88847;89710;65224.36939076561;69357;83623.74564381539;83927.5803620978;84209.57895210608;8209.384296177692;8515.74452766418;9222.616607508658;9545.499554411383;9619.855702164614;55427;55605.72197138988;56172.719136404055;56206;57719;66876.6656166716;67225.66387168033;69494.65252673705;73324.63337683277;73450.89823652542;10919.344839309666;12689.23864568129;13402.664933376744;13705.246211458465;13794;14511.419543218153;14551.345189466289;14680.55958321252;14687.118772873662;14864.182469964257;13565.186088834702;13614.319284035957;13642.386092625657;13806.37871295774;14033.578992630237;13786.379612917242;13939.372728227052;13951.232682202577;14064;14253.64365890861;12754.742453270524;13359.933200333937;13488.5279015234;13492.055556110978;13586.660333491742;11098.500567474644;11169.441527923735;11192.496337664661;11226.607068752555;11640.35978021217;9317.301202409983;9436.764080898032;9928.702138935843;11304.226780547346;11631.47658355393 12:17:54 INFO - PROCESS | 1998 | __end_tp_report 12:17:54 INFO - PROCESS | 1998 | __start_cc_report 12:17:54 INFO - PROCESS | 1998 | _x_x_mozilla_cycle_collect,393 12:17:54 INFO - PROCESS | 1998 | __end_cc_report 12:17:54 INFO - PROCESS | 1998 | __startTimestamp1487276274611__endTimestamp 12:17:54 INFO - PROCESS | 1998 | 12:17:54 INFO - PROCESS | 1998 | ------- Summary: start ------- 12:17:54 INFO - PROCESS | 1998 | Number of tests: 6 12:17:54 INFO - PROCESS | 1998 | 12:17:54 INFO - PROCESS | 1998 | [#0] dojo.html Cycles:165 Average:5533.32 Median:5676.89 stddev:614.18 (10.8%) stddev-sans-first:589.73 12:17:54 INFO - PROCESS | 1998 | Values: 3265.5 3270.1 3317.1 3323.3 3338.2 5397.6 5411.8 5439.9 5493.0 5495.0 5478.3 5503.0 5535.8 5543.6 5546.4 4878.9 5021.8 5080.9 5101.3 5107.6 5086.9 5097.2 5132.9 5141.7 5173.5 4800.3 4816.3 5129.9 5148.2 5152.9 5073.4 5087.5 5101.3 5121.1 5169.9 4962.3 5066.5 5068.9 5080.2 5124.1 5349.8 5436.3 5478.4 5579.5 5591.6 5906.3 5940.3 5967.9 5992.6 6087.8 6124.4 6137.6 6143.0 6154.5 6157.7 5650.0 5652.7 5657.1 5666.7 5678.7 6083.3 6098.5 6117.4 6133.9 6134.0 5222.5 5642.9 5949.5 5971.9 5987.0 6022.1 6085.3 6109.1 6122.6 6126.2 5133.7 5500.8 5769.0 5876.8 6017.6 5091.9 5274.3 5901.6 5909.6 5920.7 4938.2 4942.1 4949.0 4954.7 4957.6 4342.7 4360.1 4370.2 4393.3 4402.3 4864.7 5558.6 5676.9 5706.4 5729.1 6023.4 6024.2 6051.2 6143.8 6145.6 5542.4 5617.5 5647.7 5701.6 5713.9 5222.6 5340.3 5360.7 5369.0 5369.0 6120.8 6150.4 6179.4 6272.3 6298.6 5968.8 6077.4 6082.5 6083.1 6119.5 6199.3 6208.0 6221.4 6224.2 6230.0 5778.7 5849.0 6018.8 6021.0 6037.8 5986.9 6022.8 6043.5 6125.4 6126.1 5752.4 5806.3 5861.9 5906.7 5907.4 4814.5 5672.1 5811.2 5811.2 5872.3 4630.2 4960.9 5520.0 5663.5 5754.9 5839.5 5840.3 5863.7 5866.6 5869.9 4629.9 5684.3 5698.2 5727.6 5728.3 12:17:54 INFO - PROCESS | 1998 | 12:17:54 INFO - PROCESS | 1998 | [#1] ext.html Cycles:180 Average:67141.92 Median:35056.97 stddev:146042.71 (416.6%) stddev-sans-first:146366.49 12:17:54 INFO - PROCESS | 1998 | Values: 423.4 460.9 469.2 499.9 527.3 36836.6 42023.6 42319.6 43847.9 43924.8 42250.0 42629.0 42632.6 42690.0 42834.9 8166.1 9243.8 9495.6 10707.9 11056.5 10951.2 11064.1 11069.4 11072.5 11080.0 11011.0 11052.3 11062.1 11084.7 11107.6 11008.8 11035.5 11064.8 11080.6 11101.8 10847.5 10872.7 10921.7 10923.1 10950.1 37476.0 37815.8 37822.4 37872.0 37884.8 3900.7 3956.3 3969.4 3979.6 4027.5 28252.4 28553.7 29798.9 30115.0 30167.0 3175.8 3212.9 3233.5 3356.6 3362.0 434023.0 480755.0 495235.0 496026.0 498359.0 58501.8 58566.4 58606.4 58646.7 58877.0 60410.0 60979.1 61239.1 61297.1 61342.5 13436.9 15765.5 16219.8 16240.6 16249.2 11213.2 11530.2 12481.3 12693.0 12707.4 16001.8 17851.6 18107.9 18330.8 18419.8 7819.5 7922.1 7951.2 7974.2 8019.2 867.3 869.1 871.6 884.2 899.1 29175.6 30481.0 30691.7 31256.4 31385.0 990.3 1003.9 1069.9 1085.8 1194.8 12987.0 13717.5 13975.1 15674.5 15814.9 619543.0 626843.0 627693.0 627716.0 627958.0 38575.6 43910.0 46271.8 47907.0 47913.8 1438.9 1445.7 1468.0 1477.0 1491.6 29142.0 30320.9 30549.0 30651.2 30769.2 49860.5 49937.3 50482.2 50659.2 50782.7 465204.3 477817.0 553385.2 554363.0 555586.9 24438.8 25070.9 25140.6 25886.7 26476.9 42414.4 43086.8 43421.6 43803.0 44191.3 35359.0 35643.8 35746.0 35801.8 37170.3 32349.0 33717.8 35287.3 36492.6 36499.5 34195.7 34667.1 34826.7 35347.0 35382.6 27812.3 30015.0 31321.7 32160.7 32328.7 21112.5 23912.8 24104.5 24418.3 24751.2 12:17:54 INFO - PROCESS | 1998 | 12:17:54 INFO - PROCESS | 1998 | [#2] jquery.html Cycles:180 Average:40918.83 Median:21918.62 stddev:126515.19 (577.2%) stddev-sans-first:126833.42 12:17:54 INFO - PROCESS | 1998 | Values: 347.9 385.8 398.5 405.6 420.9 18255.9 19865.0 21879.6 21985.9 22081.9 20541.5 21208.9 21458.8 21780.9 22503.7 4541.4 4680.0 5350.9 5357.1 5367.7 3879.4 4069.9 5213.6 5303.4 5676.7 5913.5 6030.9 6031.7 6033.8 6034.4 5958.5 5989.9 6477.8 6484.2 6530.9 5580.3 5749.4 5779.7 5781.4 5788.8 13345.1 13436.8 13460.0 13649.8 13694.9 2511.5 2608.3 2613.6 2696.4 2697.7 5822.5 5895.2 10284.5 10493.1 13879.8 1817.8 1851.7 1862.7 1868.1 1884.2 117734.0 119733.0 134581.0 135625.0 141276.3 11896.9 11971.8 12096.3 12130.3 12302.3 15629.9 15652.6 15674.9 15684.0 15684.7 8573.2 8595.0 8762.7 8926.0 8986.3 7296.6 8473.6 8672.4 8675.7 8843.6 4129.4 4779.2 4779.8 4792.4 4911.7 1699.2 1765.5 1924.4 2042.7 2051.2 999.3 1013.6 1025.8 1034.2 1073.7 17614.4 19835.9 21678.5 21881.5 21955.8 999.4 1051.3 1054.6 1092.5 1110.3 9919.7 10341.0 11259.9 11428.7 11494.6 732551.3 747810.0 760793.0 771222.0 778479.0 157133.0 159260.0 162338.4 164280.0 168765.2 878.9 1015.9 1023.2 1042.9 1042.9 18885.2 19338.3 19532.0 19628.7 19665.9 59223.0 59624.1 60748.7 60930.8 61898.7 83816.0 86394.6 88013.0 89328.6 89446.1 7399.8 7511.3 7531.0 7643.8 7762.3 8686.9 10994.0 12150.1 12582.4 13243.5 9665.9 10967.9 11367.5 11431.1 11804.9 9177.7 9324.7 11340.1 11811.4 11868.8 11330.4 11454.3 11644.0 11734.9 12042.8 7497.1 7632.8 7685.7 7752.3 7793.5 10989.0 11364.0 11366.8 11439.6 11569.9 12:17:54 INFO - PROCESS | 1998 | 12:17:54 INFO - PROCESS | 1998 | [#3] mootools.html Cycles:180 Average:5378.79 Median:5193.91 stddev:2934.08 (56.5%) stddev-sans-first:2927.12 12:17:54 INFO - PROCESS | 1998 | Values: 1406.6 1418.9 1433.7 1434.3 1449.0 7496.9 7749.8 8049.7 8453.5 8532.7 7964.6 8081.9 8178.3 8357.2 8369.2 2291.8 2344.6 2347.8 2349.6 2356.2 2069.2 2146.4 2332.7 2374.3 2380.9 2255.8 2334.2 2337.4 2340.3 2345.7 2325.2 2336.5 2347.2 2349.6 2356.3 2308.3 2312.8 2367.2 2376.8 2378.6 8362.5 8399.1 8433.2 8450.3 8454.9 5070.9 5202.1 5205.5 5219.3 5220.9 3272.6 3492.6 3530.7 3547.0 3577.6 1172.3 1196.6 1248.3 1248.8 1256.9 9159.0 10701.5 10794.6 10818.3 10830.9 3732.3 3797.8 4767.6 4770.5 4790.9 8696.6 8723.6 8796.4 8808.9 8815.0 5176.2 5183.1 5187.8 5200.0 5206.1 4003.9 4619.0 4765.1 4843.9 4869.1 3698.1 3709.3 3764.8 3788.9 3791.9 954.0 1070.2 1088.2 1106.3 1152.5 841.3 848.5 869.4 887.0 895.3 5310.2 5407.9 5429.5 5524.4 5666.7 835.1 940.0 969.5 982.6 1004.6 3424.5 3800.3 3880.3 3885.0 3888.0 9713.0 9998.3 10067.5 10140.1 10179.0 9620.5 9827.1 10018.3 10033.4 10061.1 7655.4 7999.0 8014.0 8017.2 8033.6 4090.1 4494.8 5285.5 5428.4 5737.5 9154.7 9173.4 9370.6 9380.0 9421.8 9144.0 9222.5 9336.1 9368.0 9482.9 7139.5 7227.7 7264.3 7282.2 7284.9 7776.8 7814.7 7851.3 7855.0 7876.8 6962.9 7087.4 7107.8 7161.0 7203.4 6845.8 6977.8 7036.2 7051.8 7059.2 6322.5 6344.9 6362.0 6428.6 6429.8 4928.5 4970.7 4972.8 5004.6 5032.2 4102.6 4643.2 4856.4 4901.3 5073.3 12:17:54 INFO - PROCESS | 1998 | 12:17:54 INFO - PROCESS | 1998 | [#4] prototype.html Cycles:180 Average:4523.12 Median:5035.75 stddev:1339.03 (26.6%) stddev-sans-first:1305.79 12:17:54 INFO - PROCESS | 1998 | Values: 358.7 362.6 365.4 370.2 377.2 4921.1 5151.3 5217.5 5405.9 5450.9 4309.7 4509.3 4840.3 5265.4 5272.8 3948.4 4161.3 4165.6 4193.0 4198.3 3596.3 4019.4 4057.2 4204.9 4207.5 3937.5 4113.9 4118.7 4147.4 4178.0 3416.9 4129.2 4129.6 4130.4 4155.4 3373.5 3590.9 3674.0 3686.9 3689.4 4986.5 4990.7 5113.9 5131.2 5173.5 4063.6 4780.2 4800.3 4803.8 4826.4 5673.6 5676.9 5680.7 5690.7 5724.1 3572.8 3817.6 3838.8 3852.0 3853.8 4812.8 5229.0 5595.6 5866.8 5935.2 3688.9 3893.3 3897.5 3899.1 3910.6 3693.6 3937.8 3948.6 4163.5 4212.4 4804.2 4806.5 4826.7 4836.5 4868.8 4513.9 5681.6 5702.5 5707.7 5768.6 1108.8 1330.8 1371.7 1383.3 1385.7 962.2 964.6 988.8 994.8 1002.8 4924.1 5070.3 5124.9 5231.2 5238.3 5299.7 5540.2 5577.4 5581.7 5692.0 4340.9 4885.0 5040.8 5090.2 5162.9 2595.7 2696.3 2867.3 2908.9 2956.4 5578.4 5714.1 5838.3 5844.6 5900.8 4865.7 5212.7 5465.7 5481.6 5686.9 5030.7 5230.9 5294.4 5390.5 5733.0 4677.5 4891.9 5267.4 5333.2 5516.7 4520.6 4565.9 5144.4 5236.4 5496.8 5217.4 5434.9 5806.6 5825.7 5842.5 3887.2 3999.3 4002.5 4002.8 4024.4 5075.7 5525.1 5649.7 5799.9 5802.5 5237.0 5558.4 5573.1 5670.3 5693.5 5005.0 5634.9 5671.5 5675.6 5684.3 4689.4 5147.6 5447.8 5529.0 5610.6 4881.9 5135.4 5231.4 5600.2 5725.4 5578.4 5580.1 5584.2 5591.9 5638.3 12:17:54 INFO - PROCESS | 1998 | 12:17:54 INFO - PROCESS | 1998 | [#5] yui.html Cycles:180 Average:20831.33 Median:4395.09 stddev:30280.30 (689.0%) stddev-sans-first:30327.58 12:17:54 INFO - PROCESS | 1998 | Values: 718.9 737.4 750.3 787.5 840.0 14787.9 15212.2 15632.9 15646.7 15860.9 13510.4 14330.1 14595.6 14624.0 14726.6 870.4 884.6 886.5 887.3 891.6 687.1 709.0 863.9 888.6 903.3 907.9 929.7 933.9 936.1 939.3 926.0 927.6 932.2 935.2 945.9 695.5 696.8 697.0 699.1 699.9 11627.5 14556.9 15187.2 15192.0 15198.9 11801.6 13953.4 14455.9 14508.5 14515.3 8604.0 11644.6 11648.9 11682.4 11772.8 3603.5 3615.4 3617.3 3687.3 3770.5 107467.0 108330.0 111157.0 113968.4 114553.0 10672.6 10693.5 10716.9 10761.7 10790.8 22024.3 22856.7 22966.0 23010.0 23030.3 6419.7 7460.6 7506.2 7563.2 7570.6 4154.8 4210.3 4269.1 4327.6 4462.6 3633.3 3678.2 3691.1 3705.6 3722.3 682.9 727.5 729.7 739.6 749.1 580.4 664.5 710.5 717.0 723.7 9440.2 9760.8 9905.9 10151.7 10360.6 656.2 677.2 690.5 698.2 698.8 4782.9 4899.0 4906.8 4909.2 4972.9 76886.2 109907.5 111148.3 112272.3 113383.0 86501.6 87003.6 87343.6 88847.0 89710.0 65224.4 69357.0 83623.7 83927.6 84209.6 8209.4 8515.7 9222.6 9545.5 9619.9 55427.0 55605.7 56172.7 56206.0 57719.0 66876.7 67225.7 69494.7 73324.6 73450.9 10919.3 12689.2 13402.7 13705.2 13794.0 14511.4 14551.3 14680.6 14687.1 14864.2 13565.2 13614.3 13642.4 13806.4 14033.6 13786.4 13939.4 13951.2 14064.0 14253.6 12754.7 13359.9 13488.5 13492.1 13586.7 11098.5 11169.4 11192.5 11226.6 11640.4 9317.3 9436.8 9928.7 11304.2 11631.5 12:17:54 INFO - PROCESS | 1998 | -------- Summary: end -------- 12:17:54 INFO - PROCESS | 1998 | 12:17:55 INFO - TEST-INFO | 1998: exit 0 12:17:55 INFO - TEST-OK | dromaeo_css | took 1116674ms 12:17:55 INFO - TEST-START | kraken 12:17:55 INFO - operating with platform_type : mac_ 12:17:55 INFO - Initialising browser for kraken test... 12:17:55 INFO - TEST-INFO | started process 2008 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/tmpxW6Edy/profile http://localhost:49294/getInfo.html) 12:17:57 INFO - PROCESS | 2008 | 2017-02-16 12:17:57.973 plugin-container[2009:21988] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c4b, name = 'com.apple.tsm.portname' 12:17:57 INFO - PROCESS | 2008 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:17:57 INFO - PROCESS | 2008 | 2017-02-16 12:17:57.976 plugin-container[2009:21988] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x440f, name = 'com.apple.CFPasteboardClient' 12:17:57 INFO - PROCESS | 2008 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:17:57 INFO - PROCESS | 2008 | 2017-02-16 12:17:57.976 plugin-container[2009:21988] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:17:58 INFO - PROCESS | 2008 | __metrics Screen width/height:1600/1200 12:17:58 INFO - PROCESS | 2008 | colorDepth:24 12:17:58 INFO - PROCESS | 2008 | Browser inner width/height: 1024/689 12:17:58 INFO - PROCESS | 2008 | __metrics 12:17:58 ERROR - PROCESS | 2008 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 12:17:58 INFO - TEST-INFO | 2008: exit 0 12:17:58 INFO - Browser initialized. 12:17:58 INFO - Running cycle 1/1 for kraken test... 12:17:58 INFO - TEST-INFO | started process 2010 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/tmpxW6Edy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:18:01 INFO - PROCESS | 2010 | 2017-02-16 12:18:01.104 plugin-container[2011:22115] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 12:18:01 INFO - PROCESS | 2010 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:18:01 INFO - PROCESS | 2010 | 2017-02-16 12:18:01.105 plugin-container[2011:22115] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 12:18:01 INFO - PROCESS | 2010 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:18:01 INFO - PROCESS | 2010 | 2017-02-16 12:18:01.105 plugin-container[2011:22115] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:18:10 INFO - PROCESS | 2010 | RSS: Main: 219246592 12:18:10 INFO - PROCESS | 2010 | 12:18:13 INFO - PROCESS | 2010 | Cycle 1(1): loaded http://localhost:49294/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49294/tests/kraken/driver.html?testName=audio-beat-detection) 12:18:14 INFO - PROCESS | 2010 | RSS: Main: 233586688 12:18:14 INFO - PROCESS | 2010 | 12:18:17 INFO - PROCESS | 2010 | Cycle 1(1): loaded http://localhost:49294/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49294/tests/kraken/driver.html?testName=audio-dft) 12:18:18 INFO - PROCESS | 2010 | RSS: Main: 241266688 12:18:18 INFO - PROCESS | 2010 | 12:18:21 INFO - PROCESS | 2010 | Cycle 1(1): loaded http://localhost:49294/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49294/tests/kraken/driver.html?testName=audio-fft) 12:18:22 INFO - PROCESS | 2010 | RSS: Main: 245121024 12:18:22 INFO - PROCESS | 2010 | 12:18:24 INFO - PROCESS | 2010 | Cycle 1(1): loaded http://localhost:49294/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49294/tests/kraken/driver.html?testName=audio-oscillator) 12:18:24 INFO - PROCESS | 2010 | RSS: Main: 246554624 12:18:24 INFO - PROCESS | 2010 | 12:18:26 INFO - PROCESS | 2010 | Cycle 1(1): loaded http://localhost:49294/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49294/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:18:27 INFO - PROCESS | 2010 | RSS: Main: 246337536 12:18:27 INFO - PROCESS | 2010 | 12:18:30 INFO - PROCESS | 2010 | Cycle 1(1): loaded http://localhost:49294/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49294/tests/kraken/driver.html?testName=imaging-darkroom) 12:18:30 INFO - PROCESS | 2010 | RSS: Main: 250949632 12:18:30 INFO - PROCESS | 2010 | 12:18:33 INFO - PROCESS | 2010 | Cycle 1(1): loaded http://localhost:49294/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49294/tests/kraken/driver.html?testName=imaging-desaturate) 12:18:34 INFO - PROCESS | 2010 | RSS: Main: 250224640 12:18:34 INFO - PROCESS | 2010 | 12:18:37 INFO - PROCESS | 2010 | Cycle 1(1): loaded http://localhost:49294/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49294/tests/kraken/driver.html?testName=json-parse-financial) 12:18:38 INFO - PROCESS | 2010 | RSS: Main: 250294272 12:18:38 INFO - PROCESS | 2010 | 12:18:39 INFO - PROCESS | 2010 | Cycle 1(1): loaded http://localhost:49294/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49294/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:18:40 INFO - PROCESS | 2010 | RSS: Main: 248991744 12:18:40 INFO - PROCESS | 2010 | 12:18:42 INFO - PROCESS | 2010 | Cycle 1(1): loaded http://localhost:49294/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49294/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:18:42 INFO - PROCESS | 2010 | RSS: Main: 249118720 12:18:42 INFO - PROCESS | 2010 | 12:18:45 INFO - PROCESS | 2010 | Cycle 1(1): loaded http://localhost:49294/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49294/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:18:45 INFO - PROCESS | 2010 | RSS: Main: 249122816 12:18:45 INFO - PROCESS | 2010 | 12:18:48 INFO - PROCESS | 2010 | Cycle 1(1): loaded http://localhost:49294/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49294/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:18:49 INFO - PROCESS | 2010 | RSS: Main: 249323520 12:18:49 INFO - PROCESS | 2010 | 12:18:52 INFO - PROCESS | 2010 | Cycle 1(1): loaded http://localhost:49294/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49294/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:18:52 INFO - PROCESS | 2010 | RSS: Main: 249470976 12:18:52 INFO - PROCESS | 2010 | 12:18:54 INFO - PROCESS | 2010 | Cycle 1(1): loaded http://localhost:49294/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49294/tests/kraken/driver.html?testName=ai-astar) 12:18:54 INFO - PROCESS | 2010 | RSS: Main: 251346944 12:18:54 INFO - PROCESS | 2010 | 12:18:54 INFO - PROCESS | 2010 | __start_tp_report 12:18:54 INFO - PROCESS | 2010 | _x_x_mozilla_page_load 12:18:54 INFO - PROCESS | 2010 | _x_x_mozilla_page_load_details 12:18:54 INFO - PROCESS | 2010 | |i|pagename|runs| 12:18:54 INFO - PROCESS | 2010 | |0;ai-astar;108;119;119;117;117;116;115;124;115;119 12:18:54 INFO - PROCESS | 2010 | |1;audio-beat-detection;142;112;115;153;124;148;150;124;148;149 12:18:54 INFO - PROCESS | 2010 | |2;audio-dft;202;195;197;233;274;241;195;199;195;192 12:18:54 INFO - PROCESS | 2010 | |3;audio-fft;81;59;58;87;62;61;67;49;75;77 12:18:54 INFO - PROCESS | 2010 | |4;audio-oscillator;91;71;85;95;92;70;81;69;74;104 12:18:54 INFO - PROCESS | 2010 | |5;imaging-gaussian-blur;89;86;88;86;91;101;88;87;89;86 12:18:54 INFO - PROCESS | 2010 | |6;imaging-darkroom;173;99;99;100;108;99;101;100;100;101 12:18:54 INFO - PROCESS | 2010 | |7;imaging-desaturate;142;146;91;93;92;90;115;146;143;91 12:18:54 INFO - PROCESS | 2010 | |8;json-parse-financial;55;56;56;56;60;60;59;49;58;93 12:18:54 INFO - PROCESS | 2010 | |9;json-stringify-tinderbox;65;75;70;74;72;68;70;72;72;74 12:18:54 INFO - PROCESS | 2010 | |10;stanford-crypto-aes;86;81;84;85;90;85;82;85;87;86 12:18:54 INFO - PROCESS | 2010 | |11;stanford-crypto-ccm;139;138;138;144;145;157;154;143;186;148 12:18:54 INFO - PROCESS | 2010 | |12;stanford-crypto-pbkdf2;170;173;170;166;187;171;167;170;168;163 12:18:54 INFO - PROCESS | 2010 | |13;stanford-crypto-sha256-iterative;68;67;67;65;67;69;68;66;68;67 12:18:54 INFO - PROCESS | 2010 | __end_tp_report 12:18:54 INFO - PROCESS | 2010 | __start_cc_report 12:18:54 INFO - PROCESS | 2010 | _x_x_mozilla_cycle_collect,979 12:18:54 INFO - PROCESS | 2010 | __end_cc_report 12:18:54 INFO - PROCESS | 2010 | __startTimestamp1487276334739__endTimestamp 12:18:54 INFO - PROCESS | 2010 | 12:18:54 INFO - PROCESS | 2010 | ------- Summary: start ------- 12:18:54 INFO - PROCESS | 2010 | Number of tests: 14 12:18:54 INFO - PROCESS | 2010 | 12:18:54 INFO - PROCESS | 2010 | [#0] ai-astar Cycles:10 Average:116.90 Median:118.00 stddev:4.09 (3.5%) stddev-sans-first:2.80 12:18:54 INFO - PROCESS | 2010 | Values: 108.0 119.0 119.0 117.0 117.0 116.0 115.0 124.0 115.0 119.0 12:18:54 INFO - PROCESS | 2010 | 12:18:54 INFO - PROCESS | 2010 | [#1] audio-beat-detection Cycles:10 Average:136.50 Median:148.00 stddev:15.92 (10.8%) stddev-sans-first:16.76 12:18:54 INFO - PROCESS | 2010 | Values: 142.0 112.0 115.0 153.0 124.0 148.0 150.0 124.0 148.0 149.0 12:18:54 INFO - PROCESS | 2010 | 12:18:54 INFO - PROCESS | 2010 | [#2] audio-dft Cycles:10 Average:212.30 Median:200.50 stddev:27.66 (13.8%) stddev-sans-first:29.09 12:18:54 INFO - PROCESS | 2010 | Values: 202.0 195.0 197.0 233.0 274.0 241.0 195.0 199.0 195.0 192.0 12:18:54 INFO - PROCESS | 2010 | 12:18:54 INFO - PROCESS | 2010 | [#3] audio-fft Cycles:10 Average:67.60 Median:71.00 stddev:11.96 (16.8%) stddev-sans-first:11.66 12:18:54 INFO - PROCESS | 2010 | Values: 81.0 59.0 58.0 87.0 62.0 61.0 67.0 49.0 75.0 77.0 12:18:54 INFO - PROCESS | 2010 | 12:18:54 INFO - PROCESS | 2010 | [#4] audio-oscillator Cycles:10 Average:83.20 Median:83.00 stddev:12.15 (14.6%) stddev-sans-first:12.55 12:18:54 INFO - PROCESS | 2010 | Values: 91.0 71.0 85.0 95.0 92.0 70.0 81.0 69.0 74.0 104.0 12:18:54 INFO - PROCESS | 2010 | 12:18:54 INFO - PROCESS | 2010 | [#5] imaging-gaussian-blur Cycles:10 Average:89.10 Median:88.00 stddev:4.48 (5.1%) stddev-sans-first:4.76 12:18:54 INFO - PROCESS | 2010 | Values: 89.0 86.0 88.0 86.0 91.0 101.0 88.0 87.0 89.0 86.0 12:18:54 INFO - PROCESS | 2010 | 12:18:54 INFO - PROCESS | 2010 | [#6] imaging-darkroom Cycles:10 Average:108.00 Median:140.50 stddev:22.99 (16.4%) stddev-sans-first:2.82 12:18:54 INFO - PROCESS | 2010 | Values: 173.0 99.0 99.0 100.0 108.0 99.0 101.0 100.0 100.0 101.0 12:18:54 INFO - PROCESS | 2010 | 12:18:54 INFO - PROCESS | 2010 | [#7] imaging-desaturate Cycles:10 Average:114.90 Median:90.50 stddev:26.30 (29.1%) stddev-sans-first:26.00 12:18:54 INFO - PROCESS | 2010 | Values: 142.0 146.0 91.0 93.0 92.0 90.0 115.0 146.0 143.0 91.0 12:18:54 INFO - PROCESS | 2010 | 12:18:54 INFO - PROCESS | 2010 | [#8] json-parse-financial Cycles:10 Average:60.20 Median:58.50 stddev:11.96 (20.4%) stddev-sans-first:12.54 12:18:54 INFO - PROCESS | 2010 | Values: 55.0 56.0 56.0 56.0 60.0 60.0 59.0 49.0 58.0 93.0 12:18:54 INFO - PROCESS | 2010 | 12:18:54 INFO - PROCESS | 2010 | [#9] json-stringify-tinderbox Cycles:10 Average:71.20 Median:72.00 stddev:3.05 (4.2%) stddev-sans-first:2.26 12:18:54 INFO - PROCESS | 2010 | Values: 65.0 75.0 70.0 74.0 72.0 68.0 70.0 72.0 72.0 74.0 12:18:54 INFO - PROCESS | 2010 | 12:18:54 INFO - PROCESS | 2010 | [#10] stanford-crypto-aes Cycles:10 Average:85.10 Median:85.50 stddev:2.51 (2.9%) stddev-sans-first:2.65 12:18:54 INFO - PROCESS | 2010 | Values: 86.0 81.0 84.0 85.0 90.0 85.0 82.0 85.0 87.0 86.0 12:18:54 INFO - PROCESS | 2010 | 12:18:54 INFO - PROCESS | 2010 | [#11] stanford-crypto-ccm Cycles:10 Average:149.20 Median:146.50 stddev:14.44 (9.9%) stddev-sans-first:14.84 12:18:54 INFO - PROCESS | 2010 | Values: 139.0 138.0 138.0 144.0 145.0 157.0 154.0 143.0 186.0 148.0 12:18:54 INFO - PROCESS | 2010 | 12:18:54 INFO - PROCESS | 2010 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.50 Median:170.00 stddev:6.45 (3.8%) stddev-sans-first:6.84 12:18:54 INFO - PROCESS | 2010 | Values: 170.0 173.0 170.0 166.0 187.0 171.0 167.0 170.0 168.0 163.0 12:18:54 INFO - PROCESS | 2010 | 12:18:54 INFO - PROCESS | 2010 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.20 Median:67.50 stddev:1.14 (1.7%) stddev-sans-first:1.17 12:18:54 INFO - PROCESS | 2010 | Values: 68.0 67.0 67.0 65.0 67.0 69.0 68.0 66.0 68.0 67.0 12:18:54 INFO - PROCESS | 2010 | -------- Summary: end -------- 12:18:54 INFO - PROCESS | 2010 | 12:18:55 INFO - TEST-INFO | 2010: exit 0 12:18:55 INFO - TEST-OK | kraken | took 60382ms 12:18:55 INFO - SUITE-END | took 1177s 12:18:55 INFO - Completed test suite (00:19:37) 12:18:55 INFO - javascript benchmark 12:18:55 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7225.69180636228, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3265.5428240046394, 3270.0516850113468, 3317.0712200583844, 3323.335332933414, 3338.2322065924836, 5397.568194544437, 5411.837644870655, 5439.864003399917, 5492.972535137319, 5495.0, 5478.287822583058, 5503.0, 5535.778568857241, 5543.6396634218745, 5546.389897111315, 4878.853634390974, 5021.849344519652, 5080.923786143211, 5101.336826212587, 5107.591392688576, 5086.949130508703, 5097.158968770148, 5132.871678208037, 5141.691498510083, 5173.534381905628, 4800.255960326155, 4816.277558366256, 5129.923051154217, 5148.2020286855595, 5152.871178220537, 5073.441921388663, 5087.465816089331, 5101.260317253996, 5121.052605268037, 5169.948300516984, 4962.255661650745, 5066.544011038987, 5068.949310506885, 5080.212567052113, 5124.052050370656, 5349.786008559653, 5436.266104075979, 5478.397376288605, 5579.525740312079, 5591.580631452616, 5906.26171728534, 5940.2871655401195, 5967.910481342783, 5992.580519363646, 6087.817365479043, 6124.448799608012, 6137.570370074097, 6142.969285153546, 6154.5076393888385, 6157.7229024694, 5649.954758369674, 5652.7173641318595, 5657.12314591239, 5666.716664166775, 5678.716064196774, 6083.300420451616, 6098.5426093042315, 6117.370705100086, 6133.946794548733, 6134.0, 5222.529972302511, 5642.927843709682, 5949.524038076944, 5971.880562388815, 5987.0, 6022.126791615191, 6085.330613632497, 6109.083637454418, 6122.571420000557, 6126.23422072241, 5133.717645529457, 5500.80747173847, 5769.0, 5876.823695289148, 6017.578769486182, 5091.8981620367385, 5274.340707411574, 5901.58688891782, 5909.645421274738, 5920.733566989496, 4938.160512712776, 4942.085714142895, 4948.950510494849, 4954.653174277766, 4957.603391728653, 4342.652587792969, 4360.106178233455, 4370.21336159488, 4393.319035549495, 4402.3396490526675, 4864.6594738368685, 5558.583106267046, 5676.8864622707315, 5706.429357064343, 5729.140628905614, 6023.427774361428, 6024.216851809237, 6051.243594550681, 6143.849652621547, 6145.631262124287, 5542.418046105101, 5617.466340697627, 5647.717614119278, 5701.600887937804, 5713.914291285634, 5222.556082732935, 5340.25236466895, 5360.65155764874, 5368.973155134199, 5369.0, 6120.812855101315, 6150.415710507495, 6179.3820617937845, 6272.278687950853, 6298.622082674871, 5968.791092311748, 6077.4226448487325, 6082.452579267887, 6083.117947897618, 6119.541034422347, 6199.258319022339, 6208.00671892495, 6221.440070393686, 6224.1597384352535, 6230.0, 5778.653280803165, 5849.034909240098, 6018.781306326478, 6021.036634138517, 6037.78867739627, 5986.880262394815, 6022.789202377896, 6043.516518678407, 6125.418593525838, 6126.059675755388, 5752.424757524214, 5806.332271788629, 5861.9413805863105, 5906.70466476683, 5907.409259074058, 4814.470408255159, 5672.14917762339, 5811.1573821794755, 5811.215485909348, 5872.324682661549, 4630.212863813093, 4960.925586116139, 5520.0, 5663.461971112738, 5754.884902302014, 5839.532837373085, 5840.2991641003355, 5863.706814659336, 5866.5893387462465, 5869.911951320819, 4629.930551041804, 5684.346300175367, 5698.230738850202, 5727.599068065196, 5728.283964504437], "value": 5496.847830883213, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [423.3649525711433, 460.8732598535403, 469.21640859764193, 499.8625378021046, 527.2723641374902, 36836.63163368365, 42023.579764202346, 42319.57680423195, 43847.90380240496, 43924.780376098155, 42250.0, 42629.0, 42632.57367426325, 42690.0, 42834.92912677184, 8166.101728809826, 9243.815123697505, 9495.57566365044, 10707.946460267687, 11056.502457389437, 10951.178661600372, 11064.0595549378, 11069.391183484906, 11072.501737421835, 11080.0, 11011.00900918921, 11052.336859788398, 11062.115030797557, 11084.722881927935, 11107.555697772079, 11008.779824403508, 11035.503402346914, 11064.778704425948, 11080.556777728842, 11101.777964440666, 10847.457627118612, 10872.67381978542, 10921.726956826064, 10923.126149908068, 10950.123990080854, 37476.0, 37815.81092094549, 37822.432663510066, 37872.0, 37884.81057594722, 3900.726949113563, 3956.268090403256, 3969.4045893115976, 3979.602039796026, 4027.496562929634, 28252.434951301064, 28553.714462855314, 29798.914076014687, 30115.0, 30167.0, 3175.7776955613117, 3212.887548935776, 3233.4826427771677, 3356.6475520070285, 3362.0250127463005, 434023.0, 480755.0, 495235.0, 496026.0, 498359.0, 58501.829963400494, 58566.41433585695, 58606.41393586095, 58646.7067664659, 58877.0, 60410.0, 60979.08531372033, 61239.081413778826, 61297.080543791875, 61342.4664383394, 13436.865631343757, 15765.527034188992, 16219.837801621832, 16240.593985150466, 16249.187540622921, 11213.215074944676, 11530.192886498033, 12481.31352775588, 12693.048021398432, 12707.364631768374, 16001.839981600267, 17851.57187425003, 18107.909460452614, 18330.816691833177, 18419.81580184181, 7819.530828150329, 7922.138297500198, 7951.244631759973, 7974.202579741979, 8019.198080191934, 867.3408209760628, 869.1004810021598, 871.5598622695499, 884.2439714044399, 899.0829354058824, 29175.562366564518, 30481.0, 30691.69308306933, 31256.374872502423, 31385.0, 990.2770977186694, 1003.8745156855393, 1069.9358038517714, 1085.847981282621, 1194.75507520958, 12987.0, 13717.45130194781, 13975.09161904473, 15674.529764107096, 15814.9209253953, 619543.0, 626843.0, 627693.0, 627716.0, 627958.0, 38575.6142438572, 43910.0, 46271.76864115725, 47907.0, 47913.76043119762, 1438.9136651800925, 1445.6880824416694, 1467.9633009174643, 1477.0399240493764, 1491.5898128014667, 29142.0, 30320.938767143045, 30549.0, 30651.21138251864, 30769.230769230948, 49860.501394985586, 49937.25094123519, 50482.24276635927, 50659.24011139909, 50782.74608626933, 465204.34795652964, 477817.0, 553385.233073832, 554363.0, 555586.8882622406, 24438.75561244365, 25070.874645626656, 25140.62289065702, 25886.74113258843, 26476.86761566257, 42414.36378454264, 43086.78456607697, 43421.565784343016, 43803.0, 44191.33712994372, 35359.0, 35643.82178089093, 35746.0, 35801.82099089592, 37170.25659486849, 32349.0, 33717.83141084279, 35287.29425411529, 36492.63507364999, 36499.452508212926, 34195.65804342024, 34667.13332166716, 34826.651733483355, 35347.0, 35382.646173537934, 27812.30469238285, 30015.0, 31321.686783131878, 32160.678393215767, 32328.676713232566, 21112.472188195417, 23912.760872391053, 24104.517909642058, 24418.26745197647, 24751.23263110684], "value": 19677.455500643144, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.8539013614282, 385.78396098185016, 398.52177387135424, 405.6207446037953, 420.8548050922433, 18255.90464572123, 19865.0, 21879.562408751855, 21985.890070549623, 22081.889590552044, 20541.486462838435, 21208.89395553024, 21458.785412145913, 21780.910954452254, 22503.662445063295, 4541.43232095988, 4679.976600117003, 5350.892982140354, 5357.08929481989, 5367.677939323653, 3879.35990561557, 4069.9186016279655, 5213.582913366921, 5303.443138470463, 5676.659400435967, 5913.467787899088, 6030.8793824123495, 6031.6984150792505, 6033.818985430444, 6034.396560343975, 5958.52331813456, 5989.9101513477335, 6477.838054048663, 6484.1894763154605, 6530.93469065308, 5580.302462192226, 5749.425057494259, 5779.711014449303, 5781.392953739839, 5788.768449262025, 13345.065845390827, 13436.798448023288, 13460.0, 13649.795253071112, 13694.863051369459, 2511.4725907559437, 2608.321836322551, 2613.59489279162, 2696.406790506105, 2697.7167397423186, 5822.505087067604, 5895.204147440084, 10284.537195826206, 10493.108085812715, 13879.791803122962, 1817.8363947244811, 1851.741825599587, 1862.6926557118059, 1868.093974422404, 1884.1992153334777, 117734.0, 119733.0, 134581.0, 135625.0, 141276.29361853228, 11896.940515297369, 11971.820422693667, 12096.334701591497, 12130.33283169434, 12302.323372214436, 15629.921850390903, 15652.608684782972, 15674.921625392028, 15684.0, 15684.666803321617, 8573.228409443056, 8595.011573668982, 8762.693305734269, 8926.0, 8986.281097512183, 7296.598687072262, 8473.618687159093, 8672.436291641023, 8675.696350627824, 8843.646254149891, 4129.401236820644, 4779.187538118528, 4779.761011949458, 4792.376990991219, 4911.705297682151, 1699.2098674116596, 1765.523308706642, 1924.3649595633408, 2042.6629606114825, 2051.189780036877, 999.2505620784411, 1013.6198925402974, 1025.7999690060367, 1034.1726618705006, 1073.6886302972207, 17614.383496577557, 19835.909025003475, 21678.45803854916, 21881.452963676034, 21955.792431416427, 999.4403134244847, 1051.3113910388709, 1054.5887104029434, 1092.5138313450598, 1110.2616759854768, 9919.65281215154, 10341.0, 11259.943700281447, 11428.657140285804, 11494.597689080842, 732551.3372433104, 747810.0, 760793.0, 771222.0, 778479.0, 157133.0, 159260.0, 162338.3766162347, 164280.0, 168765.15617421834, 878.9057623259083, 1015.9034891685426, 1023.1661196125134, 1042.8592140060994, 1042.864427624404, 18885.244590216236, 19338.323158689378, 19532.0, 19628.705569416186, 19665.901670492127, 59223.0, 59624.105638416324, 60748.69625651844, 60930.78138437295, 61898.69050654718, 83816.0, 86394.56802715946, 88013.0, 89328.5533572328, 89446.10553894378, 7399.778006659809, 7511.32398084175, 7530.962345188458, 7643.770686879402, 7762.262585054411, 8686.913130868863, 10994.0, 12150.088743344108, 12582.414012006535, 13243.470261189444, 9665.855012174683, 10967.890321096687, 11367.54529818831, 11431.085513159094, 11804.940975295412, 9177.724668259963, 9324.673636422693, 11340.149488788209, 11811.350375729417, 11868.76262474763, 11330.433478326215, 11454.255473394203, 11644.0, 11734.882651173379, 12042.819357709817, 7497.137829149718, 7632.80917977055, 7685.730999414994, 7752.341051010727, 7793.454458187873, 10989.0, 11364.043557024228, 11366.772664546497, 11439.599614013803, 11569.942150289195], "value": 9667.92350015386, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1406.6061502779223, 1418.8581141885807, 1433.6559225785809, 1434.2685230532425, 1448.9927550362247, 7496.88754668679, 7749.767506974786, 8049.718259860899, 8453.492790432578, 8532.744017679464, 7964.60176991151, 8081.878771818428, 8178.304844088259, 8357.20606542379, 8369.20492553208, 2291.7708229177115, 2344.6014177589764, 2347.8473899196497, 2349.647552867075, 2356.1871154451774, 2069.244725675125, 2146.4097373222335, 2332.7084114485688, 2374.3351861478814, 2380.8928598213124, 2255.76314486979, 2334.159702507096, 2337.4273303040777, 2340.2745149003777, 2345.659879317498, 2325.2326732178426, 2336.5443738471004, 2347.1784875293765, 2349.553584818879, 2356.2931120663734, 2308.2613563659634, 2312.7687231276736, 2367.2188177901426, 2376.7979721723673, 2378.559966406203, 8362.45644033136, 8399.076101628816, 8433.19884610961, 8450.323974082059, 8454.87317690235, 5070.898582028339, 5202.06362854686, 5205.531502164786, 5219.269302297681, 5220.895582088375, 3272.6236482804543, 3492.580890293156, 3530.6645868642436, 3546.982265088684, 3577.5528058992627, 1172.3376292394769, 1196.6410076976958, 1248.2822377133184, 1248.7877060899577, 1256.8994480441543, 9158.992510823804, 10701.464926753632, 10794.622188223453, 10818.29681070728, 10830.891691083067, 3732.2908647356917, 3797.7531460454984, 4767.6424268179335, 4770.475247722748, 4790.928136077961, 8696.565171741515, 8723.60743766532, 8796.38425310235, 8808.911910880937, 8815.030346661862, 5176.197689358156, 5183.118869792144, 5187.818426355059, 5200.0, 5206.114960456732, 4003.91992160161, 4618.976905115453, 4765.11845308619, 4843.927341089887, 4869.050535145612, 3698.0754811297174, 3709.3137769512728, 3764.7741135531955, 3788.8673896413493, 3791.9431208531896, 953.952302384878, 1070.1813112968584, 1088.2327958789035, 1106.3417266726285, 1152.475623591264, 841.2554888923224, 848.5333066813228, 869.387082107113, 887.0331338840566, 895.3016647015338, 5310.203469479609, 5407.945920540744, 5429.457054294618, 5524.392316845144, 5666.744996475086, 835.0980940584154, 940.0, 969.4958621516913, 982.6069572171189, 1004.598160735697, 3424.537687412217, 3800.3349413852466, 3880.3403421418425, 3885.0, 3887.9611203888167, 9712.95143524292, 9998.300118991747, 10067.496625168713, 10140.13808826258, 10179.0, 9620.470874101991, 9827.11555959967, 10018.298719089593, 10033.397996120257, 10061.094501494901, 7655.425843061793, 7999.000124984377, 8013.959930200428, 8017.158198389202, 8033.55815430157, 4090.050864091749, 4494.820207191676, 5285.471452854684, 5428.375736790162, 5737.454941780524, 9154.725358239264, 9173.35786494939, 9370.625174992923, 9380.0, 9421.811563768822, 9144.0, 9222.538873056454, 9336.11306925841, 9367.953160234156, 9482.85775713351, 7139.535930164523, 7227.710891564278, 7264.309890560388, 7282.198958114497, 7284.890726638999, 7776.805579860549, 7814.6874125034365, 7851.254130857559, 7855.0, 7876.803079923047, 6962.860742785218, 7087.433005359662, 7107.82230444243, 7160.964195178991, 7203.351698346964, 6845.760398386033, 6977.755778547727, 7036.155661320674, 7051.788446346618, 7059.152901651834, 6322.525810564318, 6344.9048264277, 6362.0, 6428.550001500037, 6429.839254018687, 4928.482509336468, 4970.65205435616, 4972.825951091694, 5004.574611158092, 5032.169692000779, 4102.610252026053, 4643.164230438553, 4856.417229932289, 4901.2893130496595, 5073.264376665436], "value": 4368.700308195742, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [358.70765326259095, 362.6174386022746, 365.35697172975557, 370.2059083266393, 377.1815161100412, 4921.114199444099, 5151.304573882525, 5217.530422261995, 5405.91891121633, 5450.945490545093, 4309.676774241937, 4509.346144809009, 4840.322354870312, 5265.447128051557, 5272.789088436458, 3948.4077388391756, 4161.313383291753, 4165.58344165584, 4192.9790351048205, 4198.286291330466, 3596.29872174926, 4019.3873981911697, 4057.2088442753675, 4204.894877628069, 4207.474065741782, 3937.5078115235597, 4113.856015039474, 4118.670506359484, 4147.440095587087, 4178.0, 3416.863325467003, 4129.174165166979, 4129.625465357416, 4130.380442933569, 4155.4182414462, 3373.510840928076, 3590.9461358079648, 3674.0, 3686.870959516431, 3689.3543629864666, 4986.5013498650205, 4990.725510096943, 5113.872153196163, 5131.153359695646, 5173.456787037345, 4063.593640635913, 4780.211265141247, 4800.3279540864305, 4803.831865884677, 4826.420829500483, 5673.602847800656, 5676.858078548028, 5680.687562184078, 5690.715464226772, 5724.112762521816, 3572.821358932043, 3817.599152089046, 3838.7696738195796, 3851.9807400963095, 3853.7687738735485, 4812.807487700487, 5228.9738551307, 5595.552355811566, 5866.823995280149, 5935.228420305332, 3688.8524459021337, 3893.279743247508, 3897.4738410314812, 3899.1479047452235, 3910.550286717007, 3693.612170722089, 3937.783421911822, 3948.5853985331128, 4163.458750362494, 4212.410262563244, 4804.1984255904035, 4806.4712881583, 4826.6621336506105, 4836.540528649773, 4868.78090485929, 4513.907973038479, 5681.57388195887, 5702.51528620072, 5707.6575405475805, 5768.596198266165, 1108.7948729485072, 1330.82699249097, 1371.6845125621162, 1383.3014327764415, 1385.6951470676436, 962.2157941277836, 964.6189755046718, 988.7824678570847, 994.7612572982576, 1002.774375765447, 4924.104219875302, 5070.340855688811, 5124.948750512522, 5231.169090818259, 5238.271370447743, 5299.7280652643085, 5540.199435183581, 5577.358603760538, 5581.665100094007, 5692.0, 4340.9364705646685, 4884.96706567389, 5040.823571174991, 5090.185570308807, 5162.896742065138, 2595.662563866723, 2696.32591852037, 2867.2796322206927, 2908.8545572721277, 2956.364381657954, 5578.4494428043545, 5714.142928535732, 5838.306891001647, 5844.561657875676, 5900.76396944126, 4865.659403841697, 5212.68723876561, 5465.672059676433, 5481.643693159932, 5686.914696279559, 5030.673006254582, 5230.869228269324, 5294.444083371268, 5390.51485366311, 5732.971335143297, 4677.532246775295, 4891.877703057452, 5267.446918073547, 5333.226682130991, 5516.668999860021, 4520.59314661682, 4565.885852853705, 5144.356955380577, 5236.350549577023, 5496.7526461310135, 5217.4260831307765, 5434.864128396822, 5806.5645076618575, 5825.679587622722, 5842.510159909196, 3887.203123359761, 3999.2601368746873, 4002.4996875390575, 4002.779847108437, 4024.3561030235023, 5075.720835354091, 5525.093842624288, 5649.661020338793, 5799.913001305068, 5802.5357971361345, 5236.9738151309, 5558.416366281483, 5573.136163894603, 5670.347909990404, 5693.5160511357, 5004.999000200048, 5634.915476267777, 5671.461211184931, 5675.631083979528, 5684.289463817023, 4689.390379250675, 5147.6139289554185, 5447.782088716565, 5529.0, 5610.607257491936, 4881.877953051202, 5135.409427915837, 5231.42454330031, 5600.215969764155, 5725.370209277059, 5578.386377498391, 5580.107182850887, 5584.218209450599, 5591.916121258266, 5638.295213098363], "value": 4116.65254519771, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [718.9460790440718, 737.3732327521607, 750.3471979377941, 787.5314188058103, 839.9635318324259, 14787.926060369697, 15212.239388030595, 15632.921835390836, 15646.687066258668, 15860.920695396535, 13510.392032358543, 14330.140191588485, 14595.635109122277, 14624.049436786601, 14726.558203253893, 870.3559366069095, 884.6196135661663, 886.5257087458211, 887.3078998381272, 891.6032365597312, 687.110192300969, 708.9822754431154, 863.8920134983127, 888.5690440136528, 903.2773780975226, 907.8865141857268, 929.7489677787028, 933.8879334479872, 936.1106948399014, 939.3002213351028, 926.0276709455045, 927.6057675487891, 932.1936524905941, 935.2331088507426, 945.938513996588, 695.4505940307201, 696.7631005458118, 696.9686364113627, 699.098163369253, 699.8950157476369, 11627.534898604046, 14556.854431455657, 15187.164705941168, 15192.0, 15198.924005379902, 11801.586944456903, 13953.372098255602, 14455.927720361331, 14508.492202772852, 14515.346809393497, 8604.0, 11644.602647682332, 11648.88351116495, 11682.41587920609, 11772.823407648892, 3603.4774957631, 3615.403458429356, 3617.330793803155, 3687.299413111527, 3770.5098337215986, 107467.0, 108329.99999999921, 111157.0, 113968.4301578495, 114553.0, 10672.626458074086, 10693.465326733787, 10716.946415267874, 10761.730956726144, 10790.838137427945, 22024.339269821932, 22856.65715014276, 22966.0, 23010.0, 23030.309090727304, 6419.711112999927, 7460.552366858006, 7506.211847756016, 7563.205863384375, 7570.637285288591, 4154.813033413504, 4210.347396153601, 4269.082147338275, 4327.6105150536605, 4462.576055274743, 3633.3096711624707, 3678.172411207457, 3691.077230692327, 3705.55533335996, 3722.3113723961155, 682.9078074459984, 727.4871215792854, 729.7008226627057, 739.6043116932523, 749.0561892016099, 580.4079838564725, 664.4684252597999, 710.4742490557054, 716.9569825810468, 723.6779633063236, 9440.24478041755, 9760.755981100529, 9905.900940990497, 10151.695449136538, 10360.585576576852, 656.1699450195506, 677.1941389746187, 690.5097380859545, 698.179638924262, 698.7624207769383, 4782.880427989328, 4898.995705880286, 4906.803727850846, 4909.165441874889, 4972.8508144755715, 76886.23113768903, 109907.45046274878, 111148.33277500844, 112272.31591526134, 113383.0, 86501.56749216214, 87003.56498217594, 87343.56328218318, 88847.0, 89710.0, 65224.36939076561, 69357.0, 83623.74564381539, 83927.5803620978, 84209.57895210608, 8209.384296177692, 8515.74452766418, 9222.616607508658, 9545.499554411383, 9619.855702164614, 55427.0, 55605.72197138988, 56172.719136404055, 56206.0, 57719.0, 66876.6656166716, 67225.66387168033, 69494.65252673705, 73324.63337683277, 73450.89823652542, 10919.344839309666, 12689.23864568129, 13402.664933376744, 13705.246211458465, 13794.0, 14511.419543218153, 14551.345189466289, 14680.55958321252, 14687.118772873662, 14864.182469964257, 13565.186088834702, 13614.319284035957, 13642.386092625657, 13806.37871295774, 14033.578992630237, 13786.379612917242, 13939.372728227052, 13951.232682202577, 14064.0, 14253.64365890861, 12754.742453270524, 13359.933200333937, 13488.5279015234, 13492.055556110978, 13586.660333491742, 11098.500567474644, 11169.441527923735, 11192.496337664661, 11226.607068752555, 11640.35978021217, 9317.301202409983, 9436.764080898032, 9928.702138935843, 11304.226780547346, 11631.47658355393], "value": 7566.8759661130925, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1531.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 119.0, 119.0, 117.0, 117.0, 116.0, 115.0, 124.0, 115.0, 119.0], "value": 116.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 112.0, 115.0, 153.0, 124.0, 148.0, 150.0, 124.0, 148.0, 149.0], "value": 136.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [202.0, 195.0, 197.0, 233.0, 274.0, 241.0, 195.0, 199.0, 195.0, 192.0], "value": 212.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 59.0, 58.0, 87.0, 62.0, 61.0, 67.0, 49.0, 75.0, 77.0], "value": 67.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 71.0, 85.0, 95.0, 92.0, 70.0, 81.0, 69.0, 74.0, 104.0], "value": 83.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 88.0, 86.0, 91.0, 101.0, 88.0, 87.0, 89.0, 86.0], "value": 89.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 99.0, 99.0, 100.0, 108.0, 99.0, 101.0, 100.0, 100.0, 101.0], "value": 108.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 146.0, 91.0, 93.0, 92.0, 90.0, 115.0, 146.0, 143.0, 91.0], "value": 114.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 56.0, 56.0, 60.0, 60.0, 59.0, 49.0, 58.0, 93.0], "value": 60.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 75.0, 70.0, 74.0, 72.0, 68.0, 70.0, 72.0, 72.0, 74.0], "value": 71.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 81.0, 84.0, 85.0, 90.0, 85.0, 82.0, 85.0, 87.0, 86.0], "value": 85.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 138.0, 138.0, 144.0, 145.0, 157.0, 154.0, 143.0, 186.0, 148.0], "value": 149.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 173.0, 170.0, 166.0, 187.0, 171.0, 167.0, 170.0, 168.0, 163.0], "value": 170.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 67.0, 65.0, 67.0, 69.0, 68.0, 66.0, 68.0, 67.0], "value": 67.2, "unit": "score"}]}]} 12:18:55 INFO - Return code: 0 12:18:56 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:18:56 ERROR - # TBPL SUCCESS # 12:18:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:18:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:18:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:18:56 INFO - 2017-02-16 12:18:56.070 screenresolution[2015:22494] starting screenresolution argv=screenresolution get 12:18:56 INFO - 2017-02-16 12:18:56.073 screenresolution[2015:22494] Display 0: 1600x1200x32@60 12:18:56 INFO - 2017-02-16 12:18:56.078 screenresolution[2016:22498] starting screenresolution argv=screenresolution list 12:18:56 INFO - Available Modes on Display 0 12:18:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:18:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:18:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:18:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:18:56 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:18:56 INFO - Intel Iris: 12:18:56 INFO - Chipset Model: Intel Iris 12:18:56 INFO - Type: GPU 12:18:56 INFO - Bus: Built-In 12:18:56 INFO - VRAM (Dynamic, Max): 1536 MB 12:18:56 INFO - Vendor: Intel (0x8086) 12:18:56 INFO - Device ID: 0x0a2e 12:18:56 INFO - Revision ID: 0x0009 12:18:56 INFO - Displays: 12:18:56 INFO - Display: 12:18:56 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:18:56 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:18:56 INFO - Display Serial Number: 1600x1200 60 12:18:56 INFO - Main Display: Yes 12:18:56 INFO - Mirror: Off 12:18:56 INFO - Online: Yes 12:18:56 INFO - Rotation: Supported 12:18:56 INFO - Return code: 0 12:18:56 INFO - Running post-action listener: _resource_record_post_action 12:18:56 INFO - [mozharness: 2017-02-16 20:18:56.312410Z] Finished run-tests step (success) 12:18:56 INFO - Running post-run listener: _resource_record_post_run 12:18:56 INFO - Total resource usage - Wall time: 1197s; CPU: 54.0%; Read bytes: 31990272; Write bytes: 509564928; Read time: 293; Write time: 2904 12:18:56 INFO - TinderboxPrint: CPU usage
54.4% 12:18:56 INFO - TinderboxPrint: I/O read bytes / time
31,990,272 / 293 12:18:56 INFO - TinderboxPrint: I/O write bytes / time
509,564,928 / 2,904 12:18:56 INFO - TinderboxPrint: CPU idle
2,185.0 (45.6%) 12:18:56 INFO - TinderboxPrint: CPU system
965.0 (20.1%) 12:18:56 INFO - TinderboxPrint: CPU user
1,639.7 (34.2%) 12:18:56 INFO - TinderboxPrint: Swap in / out
234,303,488 / 0 12:18:56 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 208716288; Write bytes: 211445760; Read time: 15910; Write time: 505 12:18:56 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 29725184; Write bytes: 296779776; Read time: 280; Write time: 2393 12:18:56 INFO - Running post-run listener: _upload_blobber_files 12:18:56 INFO - Blob upload gear active. 12:18:56 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:18:56 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 12:18:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:18:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:18:57 INFO - (blobuploader) - INFO - Open directory for files ... 12:18:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:18:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:18:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:18:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:18:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:18:57 INFO - (blobuploader) - INFO - Done attempting. 12:18:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:18:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:18:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:18:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:18:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:18:59 INFO - (blobuploader) - INFO - Done attempting. 12:18:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:18:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:18:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:19:00 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:19:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:19:00 INFO - (blobuploader) - INFO - Done attempting. 12:19:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:19:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:19:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:19:02 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:19:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:19:02 INFO - (blobuploader) - INFO - Done attempting. 12:19:02 INFO - (blobuploader) - INFO - Iteration through files over. 12:19:02 INFO - Return code: 0 12:19:02 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:19:02 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:19:02 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/438a4be0685562e2ce91af6eb96a61d57c4971ff2e70ce3dc8c37d62bade154b510e00cad7f42df292b7831df9f1d0da087560136892887dbbb15f0d41767fd0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/92913e2f16bea3989827172dfc445021e752b8ec6016c044b4dbb6c9954b7f782127d6259512e92c18e507beb33aee201578a683532079ccddd5a9e048382354", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0d14e3757d8e0c1ca26d9b9d2a0893e12e502a64096df3828e331f91eb6ef9c782d707ea4258a039c2daabc8d52636af4a6c1ac4f660e28936da184193caa70e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4c5510d1667b5afdc1137cf5e6b905b45e083b4cb8758d54b131581249d5d37e7395dd5e12b916966a4a8f524ec70c13dbed52402e1b2e8be22fbbba623e8e57"} 12:19:02 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:19:02 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:19:02 INFO - Contents: 12:19:02 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/438a4be0685562e2ce91af6eb96a61d57c4971ff2e70ce3dc8c37d62bade154b510e00cad7f42df292b7831df9f1d0da087560136892887dbbb15f0d41767fd0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/92913e2f16bea3989827172dfc445021e752b8ec6016c044b4dbb6c9954b7f782127d6259512e92c18e507beb33aee201578a683532079ccddd5a9e048382354", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0d14e3757d8e0c1ca26d9b9d2a0893e12e502a64096df3828e331f91eb6ef9c782d707ea4258a039c2daabc8d52636af4a6c1ac4f660e28936da184193caa70e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4c5510d1667b5afdc1137cf5e6b905b45e083b4cb8758d54b131581249d5d37e7395dd5e12b916966a4a8f524ec70c13dbed52402e1b2e8be22fbbba623e8e57"} 12:19:02 INFO - Running post-run listener: copy_logs_to_upload_dir 12:19:02 INFO - Copying logs to upload dir... 12:19:02 INFO - mkdir: /builds/slave/test/build/upload/logs 12:19:02 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1256.705563 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2017-02-16 12:19:02.472194) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-16 12:19:02.475589) ========= 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.wmHpCgrqwr/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.WCOUbl0dfX/Listeners TMPDIR=/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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/438a4be0685562e2ce91af6eb96a61d57c4971ff2e70ce3dc8c37d62bade154b510e00cad7f42df292b7831df9f1d0da087560136892887dbbb15f0d41767fd0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/92913e2f16bea3989827172dfc445021e752b8ec6016c044b4dbb6c9954b7f782127d6259512e92c18e507beb33aee201578a683532079ccddd5a9e048382354", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0d14e3757d8e0c1ca26d9b9d2a0893e12e502a64096df3828e331f91eb6ef9c782d707ea4258a039c2daabc8d52636af4a6c1ac4f660e28936da184193caa70e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4c5510d1667b5afdc1137cf5e6b905b45e083b4cb8758d54b131581249d5d37e7395dd5e12b916966a4a8f524ec70c13dbed52402e1b2e8be22fbbba623e8e57"} build_url:https://queue.taskcluster.net/v1/task/bFahilKxT8C1Z3Wze7ik8Q/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011123 build_url: 'https://queue.taskcluster.net/v1/task/bFahilKxT8C1Z3Wze7ik8Q/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/438a4be0685562e2ce91af6eb96a61d57c4971ff2e70ce3dc8c37d62bade154b510e00cad7f42df292b7831df9f1d0da087560136892887dbbb15f0d41767fd0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/92913e2f16bea3989827172dfc445021e752b8ec6016c044b4dbb6c9954b7f782127d6259512e92c18e507beb33aee201578a683532079ccddd5a9e048382354", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0d14e3757d8e0c1ca26d9b9d2a0893e12e502a64096df3828e331f91eb6ef9c782d707ea4258a039c2daabc8d52636af4a6c1ac4f660e28936da184193caa70e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4c5510d1667b5afdc1137cf5e6b905b45e083b4cb8758d54b131581249d5d37e7395dd5e12b916966a4a8f524ec70c13dbed52402e1b2e8be22fbbba623e8e57"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-16 12:19:02.510704) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-16 12:19:02.511032) ========= 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.wmHpCgrqwr/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.WCOUbl0dfX/Listeners TMPDIR=/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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.005014 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-16 12:19:02.560240) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-16 12:19:02.560556) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-16 12:19:02.560918) ========= ========= Total master_lag: 0.19 =========