builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0252 starttime: 1489852103.78 results: success (0) buildid: 20170318080120 builduid: 2c43c6e1bd7f4a088265dab0e9cfa14c revision: 9df61b09aa1ce0b26486bb30c6ca63e89ac06100 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-18 08:48:23.780420) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-18 08:48:23.781161) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-18 08:48:23.781678) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-18 08:48:23.807374) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-18 08:48:23.807793) ========= 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.QUFyuXtZLe/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.JOAzCl7rj8/Listeners TMPDIR=/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-03-18 08:48:23-- 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% 171M=0s 2017-03-18 08:48:23 (171 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.088452 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-18 08:48:23.919969) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-18 08:48:23.920336) ========= 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.QUFyuXtZLe/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.JOAzCl7rj8/Listeners TMPDIR=/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/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.022623 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-18 08:48:23.969327) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-18 08:48:23.969696) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 9df61b09aa1ce0b26486bb30c6ca63e89ac06100 --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 9df61b09aa1ce0b26486bb30c6ca63e89ac06100 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.QUFyuXtZLe/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.JOAzCl7rj8/Listeners TMPDIR=/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-03-18 08:48:24,062 truncating revision to first 12 chars 2017-03-18 08:48:24,063 Setting DEBUG logging. 2017-03-18 08:48:24,063 attempt 1/10 2017-03-18 08:48:24,063 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/9df61b09aa1c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-18 08:48:25,294 unpacking tar archive at: mozilla-aurora-9df61b09aa1c/testing/mozharness/ program finished with exit code 0 elapsedTime=1.493735 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-18 08:48:25.489805) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-18 08:48:25.490383) ========= script_repo_revision: 9df61b09aa1ce0b26486bb30c6ca63e89ac06100 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-18 08:48:25.491050) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-18 08:48:25.491562) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-18 08:48:25.512626) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-03-18 08:48:25.513376) ========= /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.QUFyuXtZLe/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.JOAzCl7rj8/Listeners TMPDIR=/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 08:48:25 INFO - MultiFileLogger online at 20170318 08:48:25 in /builds/slave/test 08:48:25 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 08:48:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:48:25 INFO - {'append_to_log': False, 08:48:25 INFO - 'base_work_dir': '/builds/slave/test', 08:48:25 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 08:48:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:48:25 INFO - 'branch': 'Mozilla-Aurora', 08:48:25 INFO - 'buildbot_json_path': 'buildprops.json', 08:48:25 INFO - 'config_files': ('talos/mac_config.py',), 08:48:25 INFO - 'default_actions': ('clobber', 08:48:25 INFO - 'read-buildbot-config', 08:48:25 INFO - 'download-and-extract', 08:48:25 INFO - 'populate-webroot', 08:48:25 INFO - 'create-virtualenv', 08:48:25 INFO - 'install', 08:48:25 INFO - 'run-tests'), 08:48:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:48:25 INFO - 'download_minidump_stackwalk': True, 08:48:25 INFO - 'download_symbols': 'ondemand', 08:48:25 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:48:25 INFO - 'tooltool.py': '/tools/tooltool.py', 08:48:25 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:48:25 INFO - '/tools/misc-python/virtualenv.py')}, 08:48:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:48:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:48:25 INFO - 'gecko_profile': False, 08:48:25 INFO - 'gecko_profile_interval': 0, 08:48:25 INFO - 'installer_path': 'installer.exe', 08:48:25 INFO - 'log_level': 'info', 08:48:25 INFO - 'log_name': 'talos', 08:48:25 INFO - 'log_to_console': True, 08:48:25 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:48:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:48:25 INFO - 'opt_config_files': (), 08:48:25 INFO - 'pip_index': False, 08:48:25 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:48:25 INFO - 'cmd': ('bash', 08:48:25 INFO - '-c', 08:48:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:48:25 INFO - 'enabled': True, 08:48:25 INFO - 'halt_on_failure': False, 08:48:25 INFO - 'name': 'check_screen_resolution'},), 08:48:25 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:48:25 INFO - 'cmd': ('bash', 08:48:25 INFO - '-c', 08:48:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:48:25 INFO - 'enabled': True, 08:48:25 INFO - 'halt_on_failure': False, 08:48:25 INFO - 'name': 'check_screen_resolution'},), 08:48:25 INFO - 'run_cmd_checks_enabled': True, 08:48:25 INFO - 'suite': 'dromaeojs-e10s', 08:48:25 INFO - 'system_bits': '32', 08:48:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:48:25 INFO - 'title': 't-yosemite-r7-0252', 08:48:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:48:25 INFO - 'use_talos_json': True, 08:48:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:48:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:48:25 INFO - 'work_dir': 'build'} 08:48:25 INFO - [mozharness: 2017-03-18 15:48:25.728195Z] Running clobber step. 08:48:25 INFO - Running pre-action listener: _resource_record_pre_action 08:48:25 INFO - Running main action method: clobber 08:48:25 INFO - rmtree: /builds/slave/test/build 08:48:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:48:27 INFO - Running post-action listener: _resource_record_post_action 08:48:27 INFO - [mozharness: 2017-03-18 15:48:27.486959Z] Finished clobber step (success) 08:48:27 INFO - [mozharness: 2017-03-18 15:48:27.487196Z] Running read-buildbot-config step. 08:48:27 INFO - Running pre-action listener: _resource_record_pre_action 08:48:27 INFO - Running main action method: read_buildbot_config 08:48:27 INFO - Using buildbot properties: 08:48:27 INFO - { 08:48:27 INFO - "project": "", 08:48:27 INFO - "product": "firefox", 08:48:27 INFO - "script_repo_revision": "production", 08:48:27 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 08:48:27 INFO - "repository": "", 08:48:27 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 08:48:27 INFO - "buildid": "20170318080120", 08:48:27 INFO - "pgo_build": "False", 08:48:27 INFO - "basedir": "/builds/slave/test", 08:48:27 INFO - "buildnumber": 17, 08:48:27 INFO - "slavename": "t-yosemite-r7-0252", 08:48:27 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 08:48:27 INFO - "platform": "yosemite_r7", 08:48:27 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 08:48:27 INFO - "branch": "mozilla-aurora", 08:48:27 INFO - "repo_path": "releases/mozilla-aurora", 08:48:27 INFO - "revision": "9df61b09aa1ce0b26486bb30c6ca63e89ac06100", 08:48:27 INFO - "stage_platform": "macosx64", 08:48:27 INFO - "builduid": "2c43c6e1bd7f4a088265dab0e9cfa14c", 08:48:27 INFO - "slavebuilddir": "test" 08:48:27 INFO - } 08:48:27 INFO - Found installer url https://queue.taskcluster.net/v1/task/J1RTZ3gRTbq94aaP6Xz3HA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 08:48:27 INFO - Running post-action listener: _resource_record_post_action 08:48:27 INFO - [mozharness: 2017-03-18 15:48:27.493030Z] Finished read-buildbot-config step (success) 08:48:27 INFO - [mozharness: 2017-03-18 15:48:27.493174Z] Running download-and-extract step. 08:48:27 INFO - Running pre-action listener: _resource_record_pre_action 08:48:27 INFO - Running main action method: download_and_extract 08:48:27 INFO - mkdir: /builds/slave/test/build/tests 08:48:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:48:27 INFO - trying https://queue.taskcluster.net/v1/task/J1RTZ3gRTbq94aaP6Xz3HA/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 08:48:27 INFO - Downloading https://queue.taskcluster.net/v1/task/J1RTZ3gRTbq94aaP6Xz3HA/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 08:48:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/J1RTZ3gRTbq94aaP6Xz3HA/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json'}, attempt #1 08:48:28 INFO - Downloaded 1199 bytes. 08:48:28 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 08:48:28 INFO - Using the following test package requirements: 08:48:28 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 08:48:28 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 08:48:28 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 08:48:28 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 08:48:28 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 08:48:28 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 08:48:28 INFO - u'jsshell-mac.zip'], 08:48:28 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 08:48:28 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 08:48:28 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 08:48:28 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 08:48:28 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 08:48:28 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 08:48:28 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 08:48:28 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 08:48:28 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 08:48:28 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 08:48:28 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 08:48:28 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 08:48:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/J1RTZ3gRTbq94aaP6Xz3HA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 08:48:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/J1RTZ3gRTbq94aaP6Xz3HA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:48:28 INFO - Fetch https://queue.taskcluster.net/v1/task/J1RTZ3gRTbq94aaP6Xz3HA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 08:48:30 INFO - Expected file size: 51781823 08:48:30 INFO - Obtained file size: 51781823 08:48:46 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip', u'firefox-54.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 08:48:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/J1RTZ3gRTbq94aaP6Xz3HA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 08:48:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/J1RTZ3gRTbq94aaP6Xz3HA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:48:46 INFO - Fetch https://queue.taskcluster.net/v1/task/J1RTZ3gRTbq94aaP6Xz3HA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 08:48:48 INFO - Expected file size: 51781823 08:48:48 INFO - Obtained file size: 51781823 08:49:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/J1RTZ3gRTbq94aaP6Xz3HA/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 08:49:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/J1RTZ3gRTbq94aaP6Xz3HA/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 08:49:03 INFO - Fetch https://queue.taskcluster.net/v1/task/J1RTZ3gRTbq94aaP6Xz3HA/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 08:49:05 INFO - Expected file size: 14404424 08:49:05 INFO - Obtained file size: 14404424 08:49:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:05 INFO - trying https://queue.taskcluster.net/v1/task/J1RTZ3gRTbq94aaP6Xz3HA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 08:49:05 INFO - Downloading https://queue.taskcluster.net/v1/task/J1RTZ3gRTbq94aaP6Xz3HA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 08:49:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/J1RTZ3gRTbq94aaP6Xz3HA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg'}, attempt #1 08:49:07 INFO - Downloaded 74623399 bytes. 08:49:07 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/J1RTZ3gRTbq94aaP6Xz3HA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 08:49:07 INFO - mkdir: /builds/slave/test/properties 08:49:07 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:49:07 INFO - Writing to file /builds/slave/test/properties/build_url 08:49:07 INFO - Contents: 08:49:07 INFO - build_url:https://queue.taskcluster.net/v1/task/J1RTZ3gRTbq94aaP6Xz3HA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 08:49:08 INFO - Running post-action listener: _resource_record_post_action 08:49:08 INFO - Running post-action listener: set_extra_try_arguments 08:49:08 INFO - [mozharness: 2017-03-18 15:49:08.207313Z] Finished download-and-extract step (success) 08:49:08 INFO - [mozharness: 2017-03-18 15:49:08.207430Z] Running populate-webroot step. 08:49:08 INFO - Running pre-action listener: _resource_record_pre_action 08:49:08 INFO - Running main action method: populate_webroot 08:49:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:49:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:49:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:49:08 INFO - '--tppagecycles', 08:49:08 INFO - '7'], 08:49:08 INFO - 'tests': ['tsvgm']}}, 08:49:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:49:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:49:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:49:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:49:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:49:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:49:08 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:49:08 INFO - 'talos_options': ['--disable-e10s'], 08:49:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:49:08 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:49:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:49:08 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:49:08 INFO - 'talos_options': ['--disable-e10s'], 08:49:08 INFO - 'tests': ['damp', 'tps']}, 08:49:08 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:49:08 INFO - 'tests': ['damp', 'tps']}, 08:49:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:49:08 INFO - 'tests': ['dromaeo_dom']}, 08:49:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:49:08 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:49:08 INFO - 'tests': ['basic_compositor_video']}, 08:49:08 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:49:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:49:08 INFO - 'tests': ['a11yr', 08:49:08 INFO - 'ts_paint', 08:49:08 INFO - 'tpaint', 08:49:08 INFO - 'sessionrestore', 08:49:08 INFO - 'sessionrestore_no_auto_restore', 08:49:08 INFO - 'tabpaint']}, 08:49:08 INFO - 'other-e10s': {'tests': ['a11yr', 08:49:08 INFO - 'ts_paint', 08:49:08 INFO - 'tpaint', 08:49:08 INFO - 'sessionrestore', 08:49:08 INFO - 'sessionrestore_no_auto_restore', 08:49:08 INFO - 'tabpaint']}, 08:49:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:49:08 INFO - 'ts_paint', 08:49:08 INFO - 'tpaint', 08:49:08 INFO - 'sessionrestore', 08:49:08 INFO - 'sessionrestore_no_auto_restore', 08:49:08 INFO - 'tabpaint']}, 08:49:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:49:08 INFO - 'ts_paint', 08:49:08 INFO - 'tpaint', 08:49:08 INFO - 'sessionrestore', 08:49:08 INFO - 'sessionrestore_no_auto_restore', 08:49:08 INFO - 'tabpaint']}, 08:49:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:49:08 INFO - 'tests': ['a11yr', 08:49:08 INFO - 'ts_paint', 08:49:08 INFO - 'tpaint', 08:49:08 INFO - 'sessionrestore', 08:49:08 INFO - 'sessionrestore_no_auto_restore', 08:49:08 INFO - 'tabpaint']}, 08:49:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:49:08 INFO - 'tests': ['a11yr', 08:49:08 INFO - 'ts_paint', 08:49:08 INFO - 'tpaint', 08:49:08 INFO - 'sessionrestore', 08:49:08 INFO - 'sessionrestore_no_auto_restore', 08:49:08 INFO - 'tabpaint']}, 08:49:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:49:08 INFO - 'tests': ['tsvgx', 08:49:08 INFO - 'tsvgr_opacity', 08:49:08 INFO - 'tart', 08:49:08 INFO - 'tscrollx', 08:49:08 INFO - 'cart', 08:49:08 INFO - 'tsvg_static']}, 08:49:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:49:08 INFO - 'tsvgr_opacity', 08:49:08 INFO - 'tart', 08:49:08 INFO - 'tscrollx', 08:49:08 INFO - 'cart', 08:49:08 INFO - 'tsvg_static']}, 08:49:08 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:49:08 INFO - 'talos_options': ['--disable-e10s'], 08:49:08 INFO - 'tests': ['tp5o']}, 08:49:08 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:49:08 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:49:08 INFO - 'talos_options': ['--disable-e10s', 08:49:08 INFO - '--xperf_path', 08:49:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:49:08 INFO - 'tests': ['tp5n']}, 08:49:08 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:49:08 INFO - 'talos_options': ['--xperf_path', 08:49:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:49:08 INFO - 'tests': ['tp5n']}}, 08:49:08 INFO - 'talos.zip': {'path': '', 08:49:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:49:08 INFO - Running post-action listener: _resource_record_post_action 08:49:08 INFO - [mozharness: 2017-03-18 15:49:08.226169Z] Finished populate-webroot step (success) 08:49:08 INFO - [mozharness: 2017-03-18 15:49:08.226300Z] Running create-virtualenv step. 08:49:08 INFO - Running pre-action listener: _resource_record_pre_action 08:49:08 INFO - Running main action method: create_virtualenv 08:49:08 INFO - Creating virtualenv /builds/slave/test/build/venv 08:49:08 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 08:49:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:49:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:49:08 INFO - Using real prefix '/tools/python27' 08:49:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:49:09 INFO - Installing distribute.............................................................................................................................................................................................done. 08:49:11 INFO - Installing pip.................done. 08:49:11 INFO - Return code: 0 08:49:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:49:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:49:12 INFO - Reading from file tmpfile_stdout 08:49:12 INFO - Output received: 08:49:12 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 08:49:12 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:49:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:12 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 0x101cd0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101cd3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa56ac63250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101aaed30>, '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 0x1019d5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101aa3be8>, '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.QUFyuXtZLe/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.JOAzCl7rj8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:49:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:49:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:49:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QUFyuXtZLe/Render', 08:49:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:49:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:49:12 INFO - 'HOME': '/Users/cltbld', 08:49:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:49:12 INFO - 'LOGNAME': 'cltbld', 08:49:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:12 INFO - 'MOZ_NO_REMOTE': '1', 08:49:12 INFO - 'NO_EM_RESTART': '1', 08:49:12 INFO - 'PAGER': '/bin/cat', 08:49:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:49:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:12 INFO - 'PWD': '/builds/slave/test', 08:49:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:49:12 INFO - 'SHELL': '/bin/bash', 08:49:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JOAzCl7rj8/Listeners', 08:49:12 INFO - 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', 08:49:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:49:12 INFO - 'USER': 'cltbld', 08:49:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:49:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:49:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:12 INFO - 'XPC_FLAGS': '0x0', 08:49:12 INFO - 'XPC_SERVICE_NAME': '0', 08:49:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:49:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:12 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 08:49:12 INFO - Cleaning up... 08:49:12 INFO - Return code: 0 08:49:12 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:49:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:12 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 0x101cd0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101cd3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa56ac63250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101aaed30>, '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 0x1019d5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101aa3be8>, '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.QUFyuXtZLe/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.JOAzCl7rj8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:49:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:49:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:49:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QUFyuXtZLe/Render', 08:49:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:49:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:49:12 INFO - 'HOME': '/Users/cltbld', 08:49:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:49:12 INFO - 'LOGNAME': 'cltbld', 08:49:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:12 INFO - 'MOZ_NO_REMOTE': '1', 08:49:12 INFO - 'NO_EM_RESTART': '1', 08:49:12 INFO - 'PAGER': '/bin/cat', 08:49:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:49:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:12 INFO - 'PWD': '/builds/slave/test', 08:49:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:49:12 INFO - 'SHELL': '/bin/bash', 08:49:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JOAzCl7rj8/Listeners', 08:49:12 INFO - 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', 08:49:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:49:12 INFO - 'USER': 'cltbld', 08:49:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:49:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:49:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:12 INFO - 'XPC_FLAGS': '0x0', 08:49:12 INFO - 'XPC_SERVICE_NAME': '0', 08:49:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:49:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:12 INFO - Downloading/unpacking psutil>=3.1.1 08:49:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:49:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:49:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:49:16 INFO - Installing collected packages: psutil 08:49:16 INFO - Running setup.py install for psutil 08:49:16 INFO - building 'psutil._psutil_osx' extension 08:49:16 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 08:49:16 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 08:49:16 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 08:49:16 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 08:49:16 INFO - building 'psutil._psutil_posix' extension 08:49:16 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 08:49:16 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:49:16 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:49:16 INFO - ^ 08:49:16 INFO - 1 warning generated. 08:49:16 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 08:49:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:49:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:49:17 INFO - Successfully installed psutil 08:49:17 INFO - Cleaning up... 08:49:17 INFO - Return code: 0 08:49:17 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:49:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:17 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 0x101cd0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101cd3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa56ac63250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101aaed30>, '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 0x1019d5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101aa3be8>, '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.QUFyuXtZLe/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.JOAzCl7rj8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:49:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:49:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:49:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QUFyuXtZLe/Render', 08:49:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:49:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:49:17 INFO - 'HOME': '/Users/cltbld', 08:49:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:49:17 INFO - 'LOGNAME': 'cltbld', 08:49:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:17 INFO - 'MOZ_NO_REMOTE': '1', 08:49:17 INFO - 'NO_EM_RESTART': '1', 08:49:17 INFO - 'PAGER': '/bin/cat', 08:49:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:49:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:17 INFO - 'PWD': '/builds/slave/test', 08:49:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:49:17 INFO - 'SHELL': '/bin/bash', 08:49:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JOAzCl7rj8/Listeners', 08:49:17 INFO - 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', 08:49:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:49:17 INFO - 'USER': 'cltbld', 08:49:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:49:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:49:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:17 INFO - 'XPC_FLAGS': '0x0', 08:49:17 INFO - 'XPC_SERVICE_NAME': '0', 08:49:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:49:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:17 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:49:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:20 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:49:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:49:20 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:49:20 INFO - Installing collected packages: mozsystemmonitor 08:49:20 INFO - Running setup.py install for mozsystemmonitor 08:49:20 INFO - Successfully installed mozsystemmonitor 08:49:20 INFO - Cleaning up... 08:49:20 INFO - Return code: 0 08:49:20 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:49:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x101cd0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101cd3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa56ac63250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101aaed30>, '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 0x1019d5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101aa3be8>, '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.QUFyuXtZLe/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.JOAzCl7rj8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:49:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:49:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:49:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QUFyuXtZLe/Render', 08:49:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:49:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:49:21 INFO - 'HOME': '/Users/cltbld', 08:49:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:49:21 INFO - 'LOGNAME': 'cltbld', 08:49:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:21 INFO - 'MOZ_NO_REMOTE': '1', 08:49:21 INFO - 'NO_EM_RESTART': '1', 08:49:21 INFO - 'PAGER': '/bin/cat', 08:49:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:49:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:21 INFO - 'PWD': '/builds/slave/test', 08:49:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:49:21 INFO - 'SHELL': '/bin/bash', 08:49:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JOAzCl7rj8/Listeners', 08:49:21 INFO - 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', 08:49:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:49:21 INFO - 'USER': 'cltbld', 08:49:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:49:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:49:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:21 INFO - 'XPC_FLAGS': '0x0', 08:49:21 INFO - 'XPC_SERVICE_NAME': '0', 08:49:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:49:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:21 INFO - Downloading/unpacking jsonschema==2.5.1 08:49:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:49:24 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:49:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:24 INFO - Downloading functools32-3.2.3-2.tar.gz 08:49:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:49:25 INFO - warning: no files found matching '*.txt' 08:49:25 INFO - no previously-included directories found matching 'build' 08:49:25 INFO - no previously-included directories found matching 'dist' 08:49:25 INFO - no previously-included directories found matching '.git*' 08:49:25 INFO - Installing collected packages: jsonschema, functools32 08:49:25 INFO - Running setup.py install for functools32 08:49:25 INFO - warning: no files found matching '*.txt' 08:49:25 INFO - no previously-included directories found matching 'build' 08:49:25 INFO - no previously-included directories found matching 'dist' 08:49:25 INFO - no previously-included directories found matching '.git*' 08:49:25 INFO - Successfully installed jsonschema functools32 08:49:25 INFO - Cleaning up... 08:49:25 INFO - Return code: 0 08:49:25 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:49:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:25 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 0x101cd0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101cd3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa56ac63250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101aaed30>, '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 0x1019d5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101aa3be8>, '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.QUFyuXtZLe/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.JOAzCl7rj8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:49:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:49:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 08:49:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QUFyuXtZLe/Render', 08:49:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:49:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:49:25 INFO - 'HOME': '/Users/cltbld', 08:49:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:49:25 INFO - 'LOGNAME': 'cltbld', 08:49:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:25 INFO - 'MOZ_NO_REMOTE': '1', 08:49:25 INFO - 'NO_EM_RESTART': '1', 08:49:25 INFO - 'PAGER': '/bin/cat', 08:49:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:49:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:25 INFO - 'PWD': '/builds/slave/test', 08:49:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:49:25 INFO - 'SHELL': '/bin/bash', 08:49:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JOAzCl7rj8/Listeners', 08:49:25 INFO - 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', 08:49:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:49:25 INFO - 'USER': 'cltbld', 08:49:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:49:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:49:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:25 INFO - 'XPC_FLAGS': '0x0', 08:49:25 INFO - 'XPC_SERVICE_NAME': '0', 08:49:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:49:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:25 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:49:25 INFO - Cleaning up... 08:49:25 INFO - Return code: 0 08:49:25 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:49:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:25 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 0x101cd0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101cd3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa56ac63250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101aaed30>, '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 0x1019d5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101aa3be8>, '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.QUFyuXtZLe/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.JOAzCl7rj8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:49:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:49:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:49:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QUFyuXtZLe/Render', 08:49:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:49:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:49:25 INFO - 'HOME': '/Users/cltbld', 08:49:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:49:25 INFO - 'LOGNAME': 'cltbld', 08:49:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:25 INFO - 'MOZ_NO_REMOTE': '1', 08:49:25 INFO - 'NO_EM_RESTART': '1', 08:49:25 INFO - 'PAGER': '/bin/cat', 08:49:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:49:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:25 INFO - 'PWD': '/builds/slave/test', 08:49:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:49:25 INFO - 'SHELL': '/bin/bash', 08:49:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JOAzCl7rj8/Listeners', 08:49:25 INFO - 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', 08:49:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:49:25 INFO - 'USER': 'cltbld', 08:49:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:49:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:49:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:25 INFO - 'XPC_FLAGS': '0x0', 08:49:25 INFO - 'XPC_SERVICE_NAME': '0', 08:49:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:49:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:25 INFO - Downloading/unpacking blobuploader==1.2.4 08:49:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:28 INFO - Downloading blobuploader-1.2.4.tar.gz 08:49:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:49:28 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:49:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:49:29 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:49:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:29 INFO - Downloading docopt-0.6.1.tar.gz 08:49:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:49:29 INFO - Installing collected packages: blobuploader, requests, docopt 08:49:29 INFO - Running setup.py install for blobuploader 08:49:29 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:49:29 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:49:29 INFO - Running setup.py install for requests 08:49:30 INFO - Running setup.py install for docopt 08:49:30 INFO - Successfully installed blobuploader requests docopt 08:49:30 INFO - Cleaning up... 08:49:30 INFO - Return code: 0 08:49:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:49: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')]} 08:49:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49: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')]} 08:49:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:30 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 0x101cd0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101cd3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa56ac63250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101aaed30>, '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 0x1019d5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101aa3be8>, '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.QUFyuXtZLe/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.JOAzCl7rj8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:49:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:49:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:49:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QUFyuXtZLe/Render', 08:49:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:49:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:49:30 INFO - 'HOME': '/Users/cltbld', 08:49:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:49:30 INFO - 'LOGNAME': 'cltbld', 08:49:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:30 INFO - 'MOZ_NO_REMOTE': '1', 08:49:30 INFO - 'NO_EM_RESTART': '1', 08:49:30 INFO - 'PAGER': '/bin/cat', 08:49:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:49:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:30 INFO - 'PWD': '/builds/slave/test', 08:49:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:49:30 INFO - 'SHELL': '/bin/bash', 08:49:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JOAzCl7rj8/Listeners', 08:49:30 INFO - 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', 08:49:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:49:30 INFO - 'USER': 'cltbld', 08:49:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:49:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:49:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:30 INFO - 'XPC_FLAGS': '0x0', 08:49:30 INFO - 'XPC_SERVICE_NAME': '0', 08:49:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:49:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:49:30 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-n4e_RX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:49:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:49:31 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-gB2cvd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:49:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:49:31 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-IruF8f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:49:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:49:31 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-g51MKw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:49:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:49:31 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-AtCs4b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:49:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:49:31 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-xqOJPM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:49:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:49:31 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-vaLezy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:49:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:49:31 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-_8MEGs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:49:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:49:31 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-lm6ite-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:49:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:49:31 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-1nd2DU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:49:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:49:31 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-wIYO7k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:49:32 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-YJFQyA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:49:32 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-a8gcqU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:49:32 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-AGSs2Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:49:32 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-3Lfbl4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:49:32 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-lmgwnz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:49:32 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-hW8NXW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:49:32 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:49:32 INFO - Running setup.py install for manifestparser 08:49:32 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:49:32 INFO - Running setup.py install for mozcrash 08:49:32 INFO - Running setup.py install for mozdebug 08:49:33 INFO - Running setup.py install for mozdevice 08:49:33 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:49:33 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:49:33 INFO - Running setup.py install for mozfile 08:49:33 INFO - Running setup.py install for mozhttpd 08:49:33 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:49:33 INFO - Running setup.py install for mozinfo 08:49:33 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:49:33 INFO - Running setup.py install for mozInstall 08:49:33 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:49:33 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:49:33 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:49:33 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:49:33 INFO - Running setup.py install for mozleak 08:49:34 INFO - Running setup.py install for mozlog 08:49:34 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:49:34 INFO - Running setup.py install for moznetwork 08:49:34 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:49:34 INFO - Running setup.py install for mozprocess 08:49:34 INFO - Running setup.py install for mozprofile 08:49:34 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:49:34 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:49:34 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:49:34 INFO - Running setup.py install for mozrunner 08:49:34 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:49:34 INFO - Running setup.py install for mozscreenshot 08:49:34 INFO - Running setup.py install for moztest 08:49:35 INFO - Running setup.py install for mozversion 08:49:35 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:49:35 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:49:35 INFO - Cleaning up... 08:49:35 INFO - Return code: 0 08:49:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:49: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')]} 08:49:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49: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')]} 08:49:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:35 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 0x101cd0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101cd3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa56ac63250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101aaed30>, '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 0x1019d5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101aa3be8>, '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.QUFyuXtZLe/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.JOAzCl7rj8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:49:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:49:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:49:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QUFyuXtZLe/Render', 08:49:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:49:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:49:35 INFO - 'HOME': '/Users/cltbld', 08:49:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:49:35 INFO - 'LOGNAME': 'cltbld', 08:49:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:35 INFO - 'MOZ_NO_REMOTE': '1', 08:49:35 INFO - 'NO_EM_RESTART': '1', 08:49:35 INFO - 'PAGER': '/bin/cat', 08:49:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:49:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:35 INFO - 'PWD': '/builds/slave/test', 08:49:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:49:35 INFO - 'SHELL': '/bin/bash', 08:49:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JOAzCl7rj8/Listeners', 08:49:35 INFO - 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', 08:49:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:49:35 INFO - 'USER': 'cltbld', 08:49:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:49:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:49:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:35 INFO - 'XPC_FLAGS': '0x0', 08:49:35 INFO - 'XPC_SERVICE_NAME': '0', 08:49:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:49:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:49:35 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-cCQD8_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:49:35 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 08:49:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:49:35 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-54ec_S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:49:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:49:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:49:35 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-Ltk0PK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:49:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:49:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:49:35 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-axHMIB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:49:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:49:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:49:36 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-323cVg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:49:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 08:49:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:49:36 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-HOZ1Eq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:49:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 08:49:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:49:36 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-cAYnT6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:49:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 08:49:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:49:36 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-AQTgZ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:49:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 08:49:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:49:36 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-g0bDY3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:49:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 08:49:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:49:36 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-dyUwPq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:49:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:49:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:49:36 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-U9_Lfr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:49:36 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 08:49:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:49:36 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-5svYsn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:49:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 08:49:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:49:36 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-xME_DJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:49:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 08:49:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:49:36 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-6e2A8W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:49:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 08:49:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:49:37 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-4mV8Wc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:49:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 08:49:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:49:37 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-36qpZb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:49:37 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 08:49:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:49:37 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-3BqNB7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:49:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 08:49:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:49:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:49:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:49:37 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:49:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:49:37 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:49:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:40 INFO - Downloading blessings-1.6.tar.gz 08:49:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:49:40 INFO - Installing collected packages: blessings 08:49:40 INFO - Running setup.py install for blessings 08:49:40 INFO - Successfully installed blessings 08:49:40 INFO - Cleaning up... 08:49:40 INFO - Return code: 0 08:49:40 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:49:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:49:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:49:40 INFO - Reading from file tmpfile_stdout 08:49:40 INFO - Current package versions: 08:49:40 INFO - blessings == 1.6 08:49:40 INFO - blobuploader == 1.2.4 08:49:40 INFO - docopt == 0.6.1 08:49:40 INFO - functools32 == 3.2.3-2 08:49:40 INFO - jsonschema == 2.5.1 08:49:40 INFO - manifestparser == 1.1 08:49:40 INFO - mozInstall == 1.12 08:49:40 INFO - mozcrash == 1.0 08:49:40 INFO - mozdebug == 0.1 08:49:40 INFO - mozdevice == 0.49 08:49:40 INFO - mozfile == 1.2 08:49:40 INFO - mozhttpd == 0.7 08:49:40 INFO - mozinfo == 0.9 08:49:40 INFO - mozleak == 0.1 08:49:40 INFO - mozlog == 3.4 08:49:40 INFO - moznetwork == 0.27 08:49:40 INFO - mozprocess == 0.25 08:49:40 INFO - mozprofile == 0.28 08:49:40 INFO - mozrunner == 6.13 08:49:40 INFO - mozscreenshot == 0.1 08:49:40 INFO - mozsystemmonitor == 0.3 08:49:40 INFO - moztest == 0.8 08:49:40 INFO - mozversion == 1.4 08:49:40 INFO - psutil == 3.1.1 08:49:40 INFO - requests == 1.2.3 08:49:40 INFO - wsgiref == 0.1.2 08:49:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:49:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:40 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 0x101cd0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101cd3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa56ac63250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101aaed30>, '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 0x1019d5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101aa3be8>, '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.QUFyuXtZLe/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.JOAzCl7rj8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:49:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 08:49:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:49:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QUFyuXtZLe/Render', 08:49:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:49:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:49:40 INFO - 'HOME': '/Users/cltbld', 08:49:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:49:40 INFO - 'LOGNAME': 'cltbld', 08:49:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:40 INFO - 'MOZ_NO_REMOTE': '1', 08:49:40 INFO - 'NO_EM_RESTART': '1', 08:49:40 INFO - 'PAGER': '/bin/cat', 08:49:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:49:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:40 INFO - 'PWD': '/builds/slave/test', 08:49:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:49:40 INFO - 'SHELL': '/bin/bash', 08:49:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JOAzCl7rj8/Listeners', 08:49:40 INFO - 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', 08:49:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:49:40 INFO - 'USER': 'cltbld', 08:49:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:49:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:49:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:40 INFO - 'XPC_FLAGS': '0x0', 08:49:40 INFO - 'XPC_SERVICE_NAME': '0', 08:49:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:49:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:49:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 08:49:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 08:49:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:49:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 08:49:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 08:49:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 08:49:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 08:49:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 08:49:41 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:49:41 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:49:41 INFO - Cleaning up... 08:49:41 INFO - Return code: 0 08:49:41 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:49:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101cd0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101cd3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa56ac63250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101aaed30>, '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 0x1019d5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101aa3be8>, '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.QUFyuXtZLe/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.JOAzCl7rj8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:49:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:49:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:49:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QUFyuXtZLe/Render', 08:49:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:49:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:49:41 INFO - 'HOME': '/Users/cltbld', 08:49:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:49:41 INFO - 'LOGNAME': 'cltbld', 08:49:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:41 INFO - 'MOZ_NO_REMOTE': '1', 08:49:41 INFO - 'NO_EM_RESTART': '1', 08:49:41 INFO - 'PAGER': '/bin/cat', 08:49:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:49:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:41 INFO - 'PWD': '/builds/slave/test', 08:49:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:49:41 INFO - 'SHELL': '/bin/bash', 08:49:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JOAzCl7rj8/Listeners', 08:49:41 INFO - 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', 08:49:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:49:41 INFO - 'USER': 'cltbld', 08:49:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:49:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:49:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:41 INFO - 'XPC_FLAGS': '0x0', 08:49:41 INFO - 'XPC_SERVICE_NAME': '0', 08:49:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:49:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:41 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:49:41 INFO - Cleaning up... 08:49:41 INFO - Return code: 0 08:49:41 INFO - Running post-action listener: _resource_record_post_action 08:49:41 INFO - Running post-action listener: _start_resource_monitoring 08:49:41 INFO - Starting resource monitoring. 08:49:41 INFO - [mozharness: 2017-03-18 15:49:41.350889Z] Finished create-virtualenv step (success) 08:49:41 INFO - [mozharness: 2017-03-18 15:49:41.351889Z] Running install step. 08:49:41 INFO - Running pre-action listener: _resource_record_pre_action 08:49:41 INFO - Running main action method: install 08:49:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:49:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:49:41 INFO - Reading from file tmpfile_stdout 08:49:41 INFO - Detecting whether we're running mozinstall >=1.0... 08:49:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:49:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:49:41 INFO - Reading from file tmpfile_stdout 08:49:41 INFO - Output received: 08:49:41 INFO - Usage: mozinstall [options] installer 08:49:41 INFO - Options: 08:49:41 INFO - -h, --help show this help message and exit 08:49:41 INFO - -d DEST, --destination=DEST 08:49:41 INFO - Directory to install application into. [default: 08:49:41 INFO - "/builds/slave/test"] 08:49:41 INFO - --app=APP Application being installed. [default: firefox] 08:49:41 INFO - mkdir: /builds/slave/test/build/application 08:49:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:49:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 08:50:01 INFO - Reading from file tmpfile_stdout 08:50:01 INFO - Output received: 08:50:01 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 08:50:01 INFO - Running post-action listener: _resource_record_post_action 08:50:01 INFO - [mozharness: 2017-03-18 15:50:01.814717Z] Finished install step (success) 08:50:01 INFO - [mozharness: 2017-03-18 15:50:01.814852Z] Running run-tests step. 08:50:01 INFO - Running pre-action listener: _resource_record_pre_action 08:50:01 INFO - Running main action method: run_tests 08:50:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:50:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:50:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:50:01 INFO - 2017-03-18 08:50:01.829 screenresolution[2245:39703] starting screenresolution argv=screenresolution get 08:50:01 INFO - 2017-03-18 08:50:01.832 screenresolution[2245:39703] Display 0: 1600x1200x32@60 08:50:01 INFO - 2017-03-18 08:50:01.838 screenresolution[2246:39706] starting screenresolution argv=screenresolution list 08:50:01 INFO - Available Modes on Display 0 08:50:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:50:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:50:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:50:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:50:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:50:02 INFO - Intel Iris: 08:50:02 INFO - Chipset Model: Intel Iris 08:50:02 INFO - Type: GPU 08:50:02 INFO - Bus: Built-In 08:50:02 INFO - VRAM (Dynamic, Max): 1536 MB 08:50:02 INFO - Vendor: Intel (0x8086) 08:50:02 INFO - Device ID: 0x0a2e 08:50:02 INFO - Revision ID: 0x0009 08:50:02 INFO - Displays: 08:50:02 INFO - Display: 08:50:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:50:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:50:02 INFO - Display Serial Number: 1600x1200 60 08:50:02 INFO - Main Display: Yes 08:50:02 INFO - Mirror: Off 08:50:02 INFO - Online: Yes 08:50:02 INFO - Rotation: Supported 08:50:02 INFO - Return code: 0 08:50:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:50:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:50:02 INFO - Python 2.7.3 08:50:02 INFO - Return code: 0 08:50:02 INFO - grabbing minidump binary from tooltool 08:50:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:50:02 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 0x101aaed30>, '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 0x1019d5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101aa3be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:50:02 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 08:50:02 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 08:50:02 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 08:50:02 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:50:02 INFO - Return code: 0 08:50:02 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:50:02 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:50:02 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:50:02 INFO - ENV: RUST_BACKTRACE is now 1 08:50:02 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:50:02 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:50:02 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/J1RTZ3gRTbq94aaP6Xz3HA/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0252', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 08:50:02 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/J1RTZ3gRTbq94aaP6Xz3HA/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0252 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 08:50:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QUFyuXtZLe/Render', 08:50:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:50:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:50:02 INFO - 'HOME': '/Users/cltbld', 08:50:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:50:02 INFO - 'LOGNAME': 'cltbld', 08:50:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:50:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:50:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:50:02 INFO - 'MOZ_NO_REMOTE': '1', 08:50:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:50:02 INFO - 'NO_EM_RESTART': '1', 08:50:02 INFO - 'PAGER': '/bin/cat', 08:50:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:50:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:50:02 INFO - 'PWD': '/builds/slave/test', 08:50:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:50:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:50:02 INFO - 'RUST_BACKTRACE': '1', 08:50:02 INFO - 'SHELL': '/bin/bash', 08:50:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JOAzCl7rj8/Listeners', 08:50:02 INFO - 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', 08:50:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:50:02 INFO - 'USER': 'cltbld', 08:50:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:50:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:50:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:50:02 INFO - 'XPC_FLAGS': '0x0', 08:50:02 INFO - 'XPC_SERVICE_NAME': '0', 08:50:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:50:02 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/J1RTZ3gRTbq94aaP6Xz3HA/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0252', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 08:50:02 INFO - mozversion application_buildid: 20170318080120 08:50:02 INFO - mozversion application_changeset: 9df61b09aa1ce0b26486bb30c6ca63e89ac06100 08:50:02 INFO - mozversion application_display_name: FirefoxDeveloperEdition 08:50:02 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:50:02 INFO - mozversion application_name: Firefox 08:50:02 INFO - mozversion application_remotingname: firefox-dev 08:50:02 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:50:02 INFO - mozversion application_vendor: Mozilla 08:50:02 INFO - mozversion application_version: 54.0a2 08:50:02 INFO - mozversion platform_buildid: 20170318080120 08:50:02 INFO - mozversion platform_changeset: 9df61b09aa1ce0b26486bb30c6ca63e89ac06100 08:50:02 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:50:02 INFO - mozversion platform_version: 54.0a2 08:50:02 INFO - using testdate: 1489852202 08:50:02 INFO - actual date: 1489852202 08:50:02 INFO - starting webserver on 'localhost:62507' 08:50:02 INFO - SUITE-START | Running 2 tests 08:50:02 INFO - TEST-START | dromaeo_css 08:50:02 INFO - operating with platform_type : mac_ 08:50:02 INFO - Initialising browser for dromaeo_css test... 08:50:02 INFO - TEST-INFO | started process 2256 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/tmpKPFcGd/profile http://localhost:62507/getInfo.html) 08:50:05 INFO - PROCESS | 2256 | 2017-03-18 08:50:05.047 plugin-container[2258:39879] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x963f, name = 'com.apple.tsm.portname' 08:50:05 INFO - PROCESS | 2256 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:50:05 INFO - PROCESS | 2256 | 2017-03-18 08:50:05.050 plugin-container[2258:39879] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4917, name = 'com.apple.CFPasteboardClient' 08:50:05 INFO - PROCESS | 2256 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:50:05 INFO - PROCESS | 2256 | 2017-03-18 08:50:05.050 plugin-container[2258:39879] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:50:05 INFO - PROCESS | 2256 | __metrics Screen width/height:1600/1200 08:50:05 INFO - PROCESS | 2256 | colorDepth:24 08:50:05 INFO - PROCESS | 2256 | Browser inner width/height: 1024/689 08:50:05 INFO - PROCESS | 2256 | __metrics 08:50:05 ERROR - PROCESS | 2256 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3353: TypeError: this.tabs is undefined 08:50:05 INFO - TEST-INFO | 2256: exit 0 08:50:05 INFO - Browser initialized. 08:50:05 INFO - Running cycle 1/1 for dromaeo_css test... 08:50:05 INFO - TEST-INFO | started process 2259 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/tmpKPFcGd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:50:08 INFO - PROCESS | 2259 | 2017-03-18 08:50:08.413 plugin-container[2260:40013] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 08:50:08 INFO - PROCESS | 2259 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:50:08 INFO - PROCESS | 2259 | 2017-03-18 08:50:08.414 plugin-container[2260:40013] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 08:50:08 INFO - PROCESS | 2259 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:50:08 INFO - PROCESS | 2259 | 2017-03-18 08:50:08.414 plugin-container[2260:40013] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:50:18 INFO - PROCESS | 2259 | RSS: Main: 220004352 08:50:18 INFO - PROCESS | 2259 | 08:53:22 INFO - PROCESS | 2259 | Cycle 1(1): loaded http://localhost:62507/tests/dromaeo/cssquery-dojo.html (next: http://localhost:62507/tests/dromaeo/cssquery-ext.html) 08:53:23 INFO - PROCESS | 2259 | RSS: Main: 238395392 08:53:23 INFO - PROCESS | 2259 | 08:55:06 INFO - PROCESS | 2259 | ************************* 08:55:06 INFO - PROCESS | 2259 | A coding exception was thrown and uncaught in a Task. 08:55:06 INFO - PROCESS | 2259 | 08:55:06 ERROR - PROCESS | 2259 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:55:06 INFO - PROCESS | 2259 | Full stack: 08:55:06 INFO - PROCESS | 2259 | ************************* 08:56:25 INFO - PROCESS | 2259 | Cycle 1(1): loaded http://localhost:62507/tests/dromaeo/cssquery-ext.html (next: http://localhost:62507/tests/dromaeo/cssquery-jquery.html) 08:56:26 INFO - PROCESS | 2259 | RSS: Main: 247873536 08:56:26 INFO - PROCESS | 2259 | 08:59:28 INFO - PROCESS | 2259 | Cycle 1(1): loaded http://localhost:62507/tests/dromaeo/cssquery-jquery.html (next: http://localhost:62507/tests/dromaeo/cssquery-mootools.html) 08:59:29 INFO - PROCESS | 2259 | RSS: Main: 255299584 08:59:29 INFO - PROCESS | 2259 | 09:02:31 INFO - PROCESS | 2259 | Cycle 1(1): loaded http://localhost:62507/tests/dromaeo/cssquery-mootools.html (next: http://localhost:62507/tests/dromaeo/cssquery-prototype.html) 09:02:31 INFO - PROCESS | 2259 | RSS: Main: 251428864 09:02:31 INFO - PROCESS | 2259 | 09:05:34 INFO - PROCESS | 2259 | Cycle 1(1): loaded http://localhost:62507/tests/dromaeo/cssquery-prototype.html (next: http://localhost:62507/tests/dromaeo/cssquery-yui.html) 09:05:35 INFO - PROCESS | 2259 | RSS: Main: 256471040 09:05:35 INFO - PROCESS | 2259 | 09:08:38 INFO - PROCESS | 2259 | Cycle 1(1): loaded http://localhost:62507/tests/dromaeo/cssquery-yui.html (next: http://localhost:62507/tests/dromaeo/cssquery-dojo.html) 09:08:38 INFO - PROCESS | 2259 | RSS: Main: 252702720 09:08:38 INFO - PROCESS | 2259 | 09:08:38 INFO - PROCESS | 2259 | __start_tp_report 09:08:38 INFO - PROCESS | 2259 | _x_x_mozilla_page_load 09:08:38 INFO - PROCESS | 2259 | _x_x_mozilla_page_load_details 09:08:38 INFO - PROCESS | 2259 | |i|pagename|runs| 09:08:38 INFO - PROCESS | 2259 | |0;dojo.html;3484.930301393972;3490.179807745181;3516.2264301853584;3524.154202991281;3533.876314328998;6063.757449702007;6086.634801911889;6113.32753397126;6150.66171360575;6171.9074213886715;6125.509959203264;6133.325334213233;6146.969265153668;6175.289841668209;6200.131981522591;5442.91835622467;5517.944820551783;5526.944730552683;5537.833864984037;5600.327960644729;4806.495317991613;5557.416471270519;5568.303962004749;5571.220029195916;5591.3569939456875;5340.225667278242;5349.839504814861;5476.17857321403;5554.028045092113;5575.386707462176;5447.210154527591;5468.9726551367385;5480.314960629921;5511.4213007634025;5608.523275521586;5388.326459192601;5505.752241149158;5519.116941289374;5533.031719449121;5539.25220095286;6128.0195168773225;6159.599626024339;6200.224971878515;6226.62640241587;6248.843778905518;6256.1866957295715;6301.464375528086;6309.652969086698;6379.776707815251;6468.74125034998;6531.934680653228;6563.50773691975;6589.670516474205;6608.86782264352;6622.2715501294815;6179.536534759821;6217.658028808459;6239.469645080219;6246;6268.185135932391;6372.203474565679;6417.7753778617525;6704.463642908556;6776.457883369319;6796.864062718817;4864.8783780405065;6075.906336859408;6288.245410550763;6411.198600174978;6460.580062295936;5408.269883565747;6518.934810651833;6522.5760325578685;6527.151470308924;6532;6290.465310448663;6304.211973503312;6457.1282876811965;6480.805575832733;6481.773137940149;6327.525435592257;6357.205349331333;6371.39471750183;6388.520860935449;6409.519286053564;5071.746412679351;5083.161278389099;5163.173892177234;5220.216967454836;5245.26566280721;4666.789994450258;4667.603253723471;4670.859874203711;4692.577668009895;4705.12955103307;6215.96892015537;6223.377662233831;6234.501239900797;6287.717052732638;6291.150694656254;6303.590266632654;6366.236051673736;6438.195225596801;6453.354664533603;6599.70301336441;6222.097795819581;6288.874222515524;6292.874142517124;6311.779087731999;6314.052892066226;5781.306243250837;5790.942090579125;5791.5656325775735;5803.767849286066;5825.504832089235;6445.9033114504255;6486.189226346707;6662.56693314933;6776.424003959719;6808.727650894008;6559;6559.704813283509;6593.472522198309;6601.801945941629;6619.470442364695;6733;6804.455643548603;6810.897836532356;6828.658567071726;6851.45188384938;6286.088517164894;6323.336049714714;6501.1548498694865;6511.902321465087;6580.80257592273;6397.936020639734;6408.070829729756;6549.050387693853;6583.44040756541;6592;6242.157308763441;6392.552521323649;6396.360363963567;6406.263279722705;6413.294537600954;5214.582833373397;6320.146780184617;6322.905156422749;6368.617882927039;6384.9042264367;6062.302835174011;6207.906881396692;6220.875582488416;6226.688665566613;6273.62358258506;6424.550281480251;6430.3891130342545;6434;6434.581752186093;6439.227292724903;6113.445900579344;6168.938310616836;6237.812865614039;6283.465905398092;6313.116163737172 09:08:38 INFO - PROCESS | 2259 | |1;ext.html;758.5979430901621;817.6443247187474;837.7026155714723;969.9369540979836;973.8880028796691;39126.41310380343;39935.20129597406;41662.58337416625;41679.79160104199;41701;40278.39582406266;40519.39220911681;40659;40733.59266407335;40768.59231407685;7909.564973926417;8041.919580804176;10549.683509494727;10887.455627218607;11037;10672.786544269149;10724.410157441338;10773;10777.676669699922;10795.29830561015;10771.83842242367;10788.244822862403;10813.459327033655;10814.72963175923;10826.837597436006;10741.83887241692;10772.245942784008;10786.946065269702;10797.352158870493;10800.567977280938;10539.314944528658;10546.209034322457;10578.312409693346;10579.047885690248;10586.100181484582;34800.651993479994;34914.476282855525;34932;35024.12439689002;35146.648533514846;3764.4353346997896;3804.524434445694;3824.541055073409;3824.8470061197518;3846.2499812536566;25580.953523718057;25647.871760641265;26277;26665.066722664615;26710.669104639488;3069.723724864773;3094.9690503094907;3126.3122113135073;3161.9407498488035;3174.904752857418;357213;360576.1971190153;360986;361501.1924940385;362142.9999999974;45955;45994.31008534875;46043.76978115088;46073.30890036652;46125.538744612124;47588.28617570739;47845.521544784104;47925.28112078321;47987.76006120017;48656.513434865905;13377.933110334387;13707;13725.931370343083;13747.656308592364;13749.518766843312;11636.825447618292;11738.41307934617;11759;11770.999465045566;11773.470193841298;11813;12226;12243.326617035971;12246.632601021984;12339.321337326353;4794.376731025014;5713.542916566665;5722.399148089391;5764.538836893123;5780.6531608102805;1105.7125147461718;1121.0471099565468;1125.3360517294673;1132.405487119269;1143.7540928700369;21601.78398216029;22298.55402891933;25442.618360724606;27486;27817.02640407576;941.7316064921464;952.7189479103681;1051.9217801753264;1054.920880933933;1089.7548051688307;13978.370973306226;14144.00991930555;14288;14303.92848035774;14311.856881431053;488277;493810;497146;497798;498817.5059124681;47928.5207147931;48230;48339.75830120827;49873.750631247334;51733;1407.4933024111313;1432.3124900047826;1443.2927865345905;1444.4655477473402;1446.6094154578204;26959.595606065534;26977.03986610326;27361;27698.446031078864;28006.85996570004;40786.36854525904;44582.55417445784;44659.10681786411;46463.30305045359;48213.51786482231;462834;466178.6691066523;471388;472536;473931;26265;26476;33333.66666333403;33446.665533344356;33553.49669754907;39577.01057473471;39732.20535589214;39923.802285931466;40826.9793255171;40960.79519602383;33399.666003339655;33828;34071.4889276666;34340.48489272713;34943.30113397667;33374.833125834215;33474.995750127535;34303.4854477178;34435.82782086074;34634.48048279328;33386.66453341936;33560.83219583886;33934.32131357408;33947.4907876387;34032.31935361229;24815.503689926463;25709.614355785052;26764.59853102244;28355.865765369155;30919;21138.57722845565;23657.763422365555;23917.16289929844;24218.394540136636;24339.878300608383 09:08:38 INFO - PROCESS | 2259 | |2;jquery.html;388.47167851721656;400.5353789604058;418.12403015682133;440.2252036415908;453.97175397724163;19526.51183720406;20250.189992400286;21366.931653417345;21470.926453677333;21547.030383632737;14740;14833.703325933475;15555.533333999998;20826.062827172776;21233.469163270925;6390.913544697412;6487.578307410027;6609.900851487208;6641.435477984377;6673.366030227121;6280.434760871521;6502.967485162568;6516.869662606745;6595.868082638368;6617.305182955793;6579.703913323888;6588.901166482507;6590.967045164767;6594.109795177637;6602.207735071798;6271.968640156792;6545.116409284757;6577.079208910756;6599.142111525519;6614.735410583619;5483.013057649621;6440.710168042449;6462.25684046336;6471.708773105222;6479.708413121373;21804.781952180434;26200.0829970952;26508;26894.789734461996;27191.96060295466;3369.8045932813557;3376.8142752148624;3384.069380920242;3448.2413868948793;3589.4795254687924;14778.926105369404;15078.623034424118;15417.22913854314;15682.215889205494;15777.526674199793;1970.9507262318414;2008.2569449303705;2025.6151331247163;2037.6230397376532;2042.6119037382998;129048.06427903588;130091;132079.33960330233;132781.33609331987;133338.99999999904;22254.443638909157;22582.209622663457;23210;23422.765772342398;23446.296611101694;35155.296894061976;35380.823095884356;35756;35902.820485897406;35988;10329.225308101923;10352.929414117207;10552.366857988392;10725.624603138853;10848.240623156304;8529.65881364751;8561.700340488178;8573.442726222776;8721.171488708562;8731.607077681392;10730.980556847087;10926.726831829108;10960.342379457257;11055.22613417053;11091;4049.676025917978;4338.783060846945;4429.224885645;4544.7045942013665;4604.58558729716;784.2864038776389;828.2918105020242;919.6781126605634;930.3899179565238;939.0890835889177;20644.96775161236;21749.67375489369;21950.670739938912;21966.341009769734;22101.447463813533;942.6606421688186;960.1694534227843;968.2012339819677;983.9114479696862;991.5785791038924;11054.889451105546;11075.889241107487;11562.190646654823;11698.122640801974;11743.295402275891;412984;449455;695118;753344;764507;113262;114095;116381.41809290899;125111.37444312903;133394.66605333824;1261.1802328486558;1267.9683007924875;1304.1979182802509;1309.6987692830512;1311.514739546374;18031.18859651345;18285.360012399502;18538.16578253955;18621.78958367702;18834.775739576882;54235.72882135564;55168.17247741207;55379.44620553743;55556.72221639028;55662.721686391305;72966.27033729595;73252.63373683098;74698;75283.62358188393;76642;10924.890751092387;11019.898010198916;11532.59635912739;12429.067819913724;12502.937485312515;13497.932510337385;13626.454941802216;13695.86304136946;14006.159630422208;14052.648683782987;11374.772504549697;11624.709382265512;11765.76468470643;11799.941000294944;11869.881301186877;9933.255005874444;11381.43092845371;11704.005159561533;11837.112216583962;12062.215955962834;11734.706632333919;11810.40947952616;11964.880351196376;12061.336626485276;12078.698032549257;12445.751084978068;12502;12624.495020199456;12794;12802.16785908913;9165.220956218796;10773.51519181623;11213.327200368005;11279.266847654877;11693.415329233674 09:08:38 INFO - PROCESS | 2259 | |3;mootools.html;1523.086148311013;1558.812942446906;1569.270289315468;1584.437524678739;1595.8882878198524;9418.81162376752;9582.329236953416;10655.893441065597;11352.091832653388;11359.14806389521;10110.140638045776;10379.896201037987;10389.84415233772;10412.791744165112;10456.634017809378;2393.72472165701;2445.2664200739796;2447.8041756659422;2450.215930902112;2452.963205551909;2420.8305418620703;2433.8783060846977;2447.7552244775557;2451.2278632230878;2468.3212116667883;2406.759324067588;2426.684531010966;2436.0377650827913;2444.6210837320245;2452.1294940296116;2370.6799582056547;2376.441536238991;2388.916387926423;2391.2587097999676;2396.952060958771;2350.2831636350907;2350.717913850349;2407.265783935899;2409.662647229389;2414.5291668124723;10203.489825508768;10426.270161088727;11239.213255072149;11330.660080197607;11406.429678516124;4609.930851037204;4634.374359461463;5115.462876397962;5776.740046697909;5953.404659534055;2904.419116176752;3032.454158251514;3647.3434781739275;3651.5618125824803;3717.643060283004;1200.327816422806;1211.7818792617413;1211.8424604801407;1261.974760504794;1268.6067319131098;12785.808212876722;15431.459898903597;15646.530604081896;15670.981386209975;15676.29456674441;4606.677532572688;5028;5051.217061355495;5078.4413714491375;5125.79496820123;12030.7969203079;12492.500299987898;13208.405621747044;13254.337283135805;13281.734365312639;5948.762049518057;6030.638161710312;6044.88169688609;6047.909281360783;6050.636961782219;4452.5992660660695;5078.542931136141;5203.32356793622;5203.739813009334;5328.147496400557;4135.359019352005;4197.727147735387;4254.319308910622;4288.335308027261;4304.225239456865;1149.5976408257043;1160.7678464307173;1163.5113252433998;1172.8358029875824;1174.424531979324;813.8209593889335;826.8883700700449;843.9578021098921;860.9311255099577;900.0234745301291;6286.874262514815;6390.456811171102;6552.082708420825;6563.409293163638;6663.100481435041;846.4540371460373;958.3004406783087;1002.8746406699163;1023.7542989682422;1033.6433930294186;3222.4521831288266;4079.938800917989;4168.2080404723;4198.307279298942;4299.161663475592;11534.826977595343;13792;14254.85745142556;14288.499902503363;14288.642783930278;12881.484740610458;13383.19700817954;13699.246541440118;14131.434742610387;14136.646583835487;9464.337496375188;9534;10046.799064018678;10296.742581435525;10297.897021029694;5761.596688231783;6480.967595161994;6684.16447944007;6838.452923766186;6974.476914231352;11743.47154378072;12170.878291216974;12296.139270250997;12318.384080796104;12366.567170149001;10811.783764324828;12212.32832194238;12509.812352814533;12533.561325353568;12581.433835477243;8125.228103330174;8341.41610087288;9144.817103658022;9314.95342523283;9546;10191.898081019095;10468.63359782404;10533.42066186367;10573.154147668014;10580;9228.861567076365;9244.583993720433;9268.397554158959;9339.626414943597;9430.811383772423;7453.664585093576;8956.820863582821;9118.908810911806;9121.224695900923;9244.907550924405;7920.049594048751;7953.761387158394;7963.323117534843;8046.155153709011;8046.356291496549;5723.599348045597;5866.120081987736;5874.559408044499;6053.303870055;6059.545534085049;4626.953730462652;5920.496757775465;5961.821145365646;6042.637441753509;6054.697265136813 09:08:38 INFO - PROCESS | 2259 | |4;prototype.html;296.49003713612586;380.23382883489757;386.3470734458766;392.8644617606926;399.33909379976143;5428.83713488595;5439.918401223985;5447.855950250447;5459.727013649322;5466.098093814516;4851.975740121304;5350.35795704516;5367.490088441602;5370.570354371651;5415.539679127269;3742.382506886367;4240.787960601973;4324.84863029794;4391.692581519295;4403.757793321366;4070.8398106539644;4285.657147428198;4412.713173643719;4442.267025940716;4455.264881294583;4235.8563187939235;4415.8233670653135;4416.97791511042;4419;4422.093470838487;3673.210259794144;3692.8707495237672;3869.3228684980018;3895.571487136441;4398.450193725785;3561.5726112866364;4030.2745505808944;4035.3946907963746;4077.368007958771;4088.34586466164;4726.621870250372;5259.2899958505495;5275.31190019194;5275.947240527584;5308;4133.9950124189745;4539.750313732743;4607.17070927233;4677.695949763254;5174.818881339135;5757.955925646377;5797.565182611321;5800.53595712342;5812.564057695689;5826.912596311059;4601.654875884288;4608.018552022629;4627.282771170474;4633.212353899845;4655.441347038377;5055.191169412877;5165.612579056586;5625.746841392147;6115.837990781782;6175.567710260284;4070.633642972147;4370.934435983463;4715.95284047162;4720.33915251865;4732.100900828831;4109.9589004109575;4162.458880345535;4756.809727610926;4779.354787103696;4813.229883218668;4298.075913678574;4915.4347250065985;5084.974575127175;5095.566876815438;5177.456367081402;5111.3355263815465;5288.9735551322;5314.601404894571;5750.741216645261;5777;1365.938532766028;1558.586974451771;1619.6193894434798;1634.8119966203801;1637.148682685021;1160.1067178272851;1193.516625766566;1207.2454715802623;1214.180428210954;1228.4656174564093;5123;5148.22386276734;5247.685138891678;5434.673919564839;5434.945650543523;5131.538161565477;5241.187615919539;5386.615225682626;6001.699915004319;6013.729382177812;4909.754512274443;4977.158451372943;5085.771140298695;5402.8919421611345;5496.1755736639825;2917.0018537302017;2983.2690990706983;3108.2283098634016;3109.6423911250045;3114.9065528034193;5241.109011468059;5273.6572122811895;5580.553555715533;6166.4758495527485;6198.442140207403;4609.585137337589;5536.086545719992;5867.647941123546;5960.821175364746;5977.432143946318;5355.812546560852;5604.27144471208;5746.281714785652;5956.255468066492;6067.362926892701;5334.279872217201;5794.246747922743;6029.849253768691;6048.576599637983;6065.272167339947;4865.756712164307;5378.327709036371;5553.305836770403;5555.8888822224135;5812.035861710642;6231.158793562992;6233.501319894307;6234.563580549499;6258.280297765633;6326.43062124411;4419.889502762328;4643.931895664084;4655.2784318430695;4664.300354946785;4677.508861569622;4901.387326584177;5419.13040001199;5926.318473375617;5979.671118088547;5992.460678538952;4732.432108146907;4859.878503037452;4926.965337279212;5747.183879898903;5769.68266745333;4765.571098601143;5114.693118412907;5242.397124330751;5759.452851979056;5781.739821707949;5226.9215961761365;5328.120860058202;5548.0013597552825;5621.634593751393;5639.167270636977;5567.3298010595845;5729.8567535811935;5764;5770;5784.334801497714;4519.367288579667;4848.733319667452;4855.684380515255;5609.540415369709;5715.428457154251 09:08:38 INFO - PROCESS | 2259 | |5;yui.html;674.0866126398732;679.0833370672959;756.2550887375937;818.2186012358198;827.3576949754736;15063.09621422714;16194.514164575052;16366.181690915451;16425.09661968591;16442.83557164428;14196.71606567868;14253.50112746054;14269.643258918532;14310.141391516516;14312.928435357808;769.9422543309246;947.4078700811992;956.7129861041694;957.5786653872274;959.0217977662779;921.230772305126;943.8206740719276;945.4374647084974;965.1362030982267;965.5944503308592;993.2401712689798;994.2990191914719;995.5520015992796;998.6255154317131;999.3753903810118;973.8880028796708;990.6879333010079;993.9960639753829;994.005994005994;995.9940460135243;722.573681527901;727.6180005497104;736.9189389167187;738.9519681220758;740.2856243724831;15748;16164;16265.51203463898;16734.83265167345;17061.232244549024;14119.294035298297;14264.714705705932;14363.994520383578;14372.784408233885;14455.27723613815;11589;11606.883931160664;11750.58872939443;11913.583024594098;12344.691382715415;3216.324571839893;3587.237826873319;3781.2626537825136;3792.336341140317;3814.809259537012;107390;107859;113360;113430;114320.42839785748;10291.794164116676;10543.209259305582;10605.522751476203;10941.23411361213;11065.170112241452;22867.51361161519;22911.427214319774;22920.4269893254;22924;22978.19576314821;7584.962075189589;7599.65801538921;7625.161232264447;7718.845623087507;7949.006374203224;4182.393552934807;4261.936070958938;4371.650267978554;4374.409454723572;4439.178751930903;3395.898123056312;3443.741719371007;3478.860845566149;3484.9651503485147;3487.877924272638;517.7100823538831;577.7891069759592;622.75712472135;637.8469167399791;651.055968845167;616.1804799616589;645.3675398109879;651.491836367634;662.8044726805604;666.53009628212;8519.616617252239;9224.815503690023;9326.766830829149;9965.900340996643;10166.593336266615;626.5989766549412;665.5474277491352;671.9969824655394;672.1900110366996;685.9279775623587;4096.56986016464;4606.262997920317;4702.764861756898;4747.145513807479;4752.619790416758;105320;107486;107519;109568.4521577387;110599.89400106057;69134.61730765356;84158.57920710357;91419.54290228506;91931.08068919225;93178.53410732903;44884.77557612256;64762;73202.63398682972;75551;76106.47787044228;7253.347198751927;8925.73222803317;9274.629014839331;9285.497746214092;9302.720918372459;34772.60909563589;39514.81455556223;53019.734901325246;53530.732346338016;55082.89834203374;66089.66955165194;68001.63996720136;72872;73074.63462682652;73717.63141184475;12833.614991550268;12902.806457903327;13022.934885325512;13062.869371306546;13098.345082746015;14316.57050288493;14636;14774.778378324549;15188.620284492976;15206.005237590833;13427;13545.729085418434;13576.185428873903;13755.31223438844;14099.578648183617;13164.60506184816;13832.792508112185;14061.312642482968;14075.436982520585;14305.856941430453;13108.344582771015;13451.46194152223;13594.524191653245;13606.523771667944;14069.648258793612;10949.288296260718;11139.610113645984;11229.55081796719;11234.494447750034;11651.766964660828;9869.45717985489;11101.722456938642;11169.362891951008;11468.885311146782;11471.483783229607 09:08:38 INFO - PROCESS | 2259 | __end_tp_report 09:08:38 INFO - PROCESS | 2259 | __start_cc_report 09:08:38 INFO - PROCESS | 2259 | _x_x_mozilla_cycle_collect,412 09:08:38 INFO - PROCESS | 2259 | __end_cc_report 09:08:38 INFO - PROCESS | 2259 | __startTimestamp1489853318762__endTimestamp 09:08:38 INFO - PROCESS | 2259 | 09:08:38 INFO - PROCESS | 2259 | ------- Summary: start ------- 09:08:38 INFO - PROCESS | 2259 | Number of tests: 6 09:08:38 INFO - PROCESS | 2259 | 09:08:38 INFO - PROCESS | 2259 | [#0] dojo.html Cycles:165 Average:6035.51 Median:6273.62 stddev:675.54 (10.8%) stddev-sans-first:647.30 09:08:38 INFO - PROCESS | 2259 | Values: 3484.9 3490.2 3516.2 3524.2 3533.9 6063.8 6086.6 6113.3 6150.7 6171.9 6125.5 6133.3 6147.0 6175.3 6200.1 5442.9 5517.9 5526.9 5537.8 5600.3 4806.5 5557.4 5568.3 5571.2 5591.4 5340.2 5349.8 5476.2 5554.0 5575.4 5447.2 5469.0 5480.3 5511.4 5608.5 5388.3 5505.8 5519.1 5533.0 5539.3 6128.0 6159.6 6200.2 6226.6 6248.8 6256.2 6301.5 6309.7 6379.8 6468.7 6531.9 6563.5 6589.7 6608.9 6622.3 6179.5 6217.7 6239.5 6246.0 6268.2 6372.2 6417.8 6704.5 6776.5 6796.9 4864.9 6075.9 6288.2 6411.2 6460.6 5408.3 6518.9 6522.6 6527.2 6532.0 6290.5 6304.2 6457.1 6480.8 6481.8 6327.5 6357.2 6371.4 6388.5 6409.5 5071.7 5083.2 5163.2 5220.2 5245.3 4666.8 4667.6 4670.9 4692.6 4705.1 6216.0 6223.4 6234.5 6287.7 6291.2 6303.6 6366.2 6438.2 6453.4 6599.7 6222.1 6288.9 6292.9 6311.8 6314.1 5781.3 5790.9 5791.6 5803.8 5825.5 6445.9 6486.2 6662.6 6776.4 6808.7 6559.0 6559.7 6593.5 6601.8 6619.5 6733.0 6804.5 6810.9 6828.7 6851.5 6286.1 6323.3 6501.2 6511.9 6580.8 6397.9 6408.1 6549.1 6583.4 6592.0 6242.2 6392.6 6396.4 6406.3 6413.3 5214.6 6320.1 6322.9 6368.6 6384.9 6062.3 6207.9 6220.9 6226.7 6273.6 6424.6 6430.4 6434.0 6434.6 6439.2 6113.4 6168.9 6237.8 6283.5 6313.1 09:08:38 INFO - PROCESS | 2259 | 09:08:38 INFO - PROCESS | 2259 | [#1] ext.html Cycles:180 Average:57025.92 Median:33393.17 stddev:118477.01 (354.8%) stddev-sans-first:118734.04 09:08:38 INFO - PROCESS | 2259 | Values: 758.6 817.6 837.7 969.9 973.9 39126.4 39935.2 41662.6 41679.8 41701.0 40278.4 40519.4 40659.0 40733.6 40768.6 7909.6 8041.9 10549.7 10887.5 11037.0 10672.8 10724.4 10773.0 10777.7 10795.3 10771.8 10788.2 10813.5 10814.7 10826.8 10741.8 10772.2 10786.9 10797.4 10800.6 10539.3 10546.2 10578.3 10579.0 10586.1 34800.7 34914.5 34932.0 35024.1 35146.6 3764.4 3804.5 3824.5 3824.8 3846.2 25581.0 25647.9 26277.0 26665.1 26710.7 3069.7 3095.0 3126.3 3161.9 3174.9 357213.0 360576.2 360986.0 361501.2 362143.0 45955.0 45994.3 46043.8 46073.3 46125.5 47588.3 47845.5 47925.3 47987.8 48656.5 13377.9 13707.0 13725.9 13747.7 13749.5 11636.8 11738.4 11759.0 11771.0 11773.5 11813.0 12226.0 12243.3 12246.6 12339.3 4794.4 5713.5 5722.4 5764.5 5780.7 1105.7 1121.0 1125.3 1132.4 1143.8 21601.8 22298.6 25442.6 27486.0 27817.0 941.7 952.7 1051.9 1054.9 1089.8 13978.4 14144.0 14288.0 14303.9 14311.9 488277.0 493810.0 497146.0 497798.0 498817.5 47928.5 48230.0 48339.8 49873.8 51733.0 1407.5 1432.3 1443.3 1444.5 1446.6 26959.6 26977.0 27361.0 27698.4 28006.9 40786.4 44582.6 44659.1 46463.3 48213.5 462834.0 466178.7 471388.0 472536.0 473931.0 26265.0 26476.0 33333.7 33446.7 33553.5 39577.0 39732.2 39923.8 40827.0 40960.8 33399.7 33828.0 34071.5 34340.5 34943.3 33374.8 33475.0 34303.5 34435.8 34634.5 33386.7 33560.8 33934.3 33947.5 34032.3 24815.5 25709.6 26764.6 28355.9 30919.0 21138.6 23657.8 23917.2 24218.4 24339.9 09:08:38 INFO - PROCESS | 2259 | 09:08:38 INFO - PROCESS | 2259 | [#2] jquery.html Cycles:180 Average:37394.42 Median:21877.73 stddev:105389.91 (481.7%) stddev-sans-first:105648.93 09:08:38 INFO - PROCESS | 2259 | Values: 388.5 400.5 418.1 440.2 454.0 19526.5 20250.2 21366.9 21470.9 21547.0 14740.0 14833.7 15555.5 20826.1 21233.5 6390.9 6487.6 6609.9 6641.4 6673.4 6280.4 6503.0 6516.9 6595.9 6617.3 6579.7 6588.9 6591.0 6594.1 6602.2 6272.0 6545.1 6577.1 6599.1 6614.7 5483.0 6440.7 6462.3 6471.7 6479.7 21804.8 26200.1 26508.0 26894.8 27192.0 3369.8 3376.8 3384.1 3448.2 3589.5 14778.9 15078.6 15417.2 15682.2 15777.5 1971.0 2008.3 2025.6 2037.6 2042.6 129048.1 130091.0 132079.3 132781.3 133339.0 22254.4 22582.2 23210.0 23422.8 23446.3 35155.3 35380.8 35756.0 35902.8 35988.0 10329.2 10352.9 10552.4 10725.6 10848.2 8529.7 8561.7 8573.4 8721.2 8731.6 10731.0 10926.7 10960.3 11055.2 11091.0 4049.7 4338.8 4429.2 4544.7 4604.6 784.3 828.3 919.7 930.4 939.1 20645.0 21749.7 21950.7 21966.3 22101.4 942.7 960.2 968.2 983.9 991.6 11054.9 11075.9 11562.2 11698.1 11743.3 412984.0 449455.0 695118.0 753344.0 764507.0 113262.0 114095.0 116381.4 125111.4 133394.7 1261.2 1268.0 1304.2 1309.7 1311.5 18031.2 18285.4 18538.2 18621.8 18834.8 54235.7 55168.2 55379.4 55556.7 55662.7 72966.3 73252.6 74698.0 75283.6 76642.0 10924.9 11019.9 11532.6 12429.1 12502.9 13497.9 13626.5 13695.9 14006.2 14052.6 11374.8 11624.7 11765.8 11799.9 11869.9 9933.3 11381.4 11704.0 11837.1 12062.2 11734.7 11810.4 11964.9 12061.3 12078.7 12445.8 12502.0 12624.5 12794.0 12802.2 9165.2 10773.5 11213.3 11279.3 11693.4 09:08:38 INFO - PROCESS | 2259 | 09:08:38 INFO - PROCESS | 2259 | [#3] mootools.html Cycles:180 Average:6722.98 Median:2451.68 stddev:4219.29 (172.1%) stddev-sans-first:4213.03 09:08:38 INFO - PROCESS | 2259 | Values: 1523.1 1558.8 1569.3 1584.4 1595.9 9418.8 9582.3 10655.9 11352.1 11359.1 10110.1 10379.9 10389.8 10412.8 10456.6 2393.7 2445.3 2447.8 2450.2 2453.0 2420.8 2433.9 2447.8 2451.2 2468.3 2406.8 2426.7 2436.0 2444.6 2452.1 2370.7 2376.4 2388.9 2391.3 2397.0 2350.3 2350.7 2407.3 2409.7 2414.5 10203.5 10426.3 11239.2 11330.7 11406.4 4609.9 4634.4 5115.5 5776.7 5953.4 2904.4 3032.5 3647.3 3651.6 3717.6 1200.3 1211.8 1211.8 1262.0 1268.6 12785.8 15431.5 15646.5 15671.0 15676.3 4606.7 5028.0 5051.2 5078.4 5125.8 12030.8 12492.5 13208.4 13254.3 13281.7 5948.8 6030.6 6044.9 6047.9 6050.6 4452.6 5078.5 5203.3 5203.7 5328.1 4135.4 4197.7 4254.3 4288.3 4304.2 1149.6 1160.8 1163.5 1172.8 1174.4 813.8 826.9 844.0 860.9 900.0 6286.9 6390.5 6552.1 6563.4 6663.1 846.5 958.3 1002.9 1023.8 1033.6 3222.5 4079.9 4168.2 4198.3 4299.2 11534.8 13792.0 14254.9 14288.5 14288.6 12881.5 13383.2 13699.2 14131.4 14136.6 9464.3 9534.0 10046.8 10296.7 10297.9 5761.6 6481.0 6684.2 6838.5 6974.5 11743.5 12170.9 12296.1 12318.4 12366.6 10811.8 12212.3 12509.8 12533.6 12581.4 8125.2 8341.4 9144.8 9315.0 9546.0 10191.9 10468.6 10533.4 10573.2 10580.0 9228.9 9244.6 9268.4 9339.6 9430.8 7453.7 8956.8 9118.9 9121.2 9244.9 7920.0 7953.8 7963.3 8046.2 8046.4 5723.6 5866.1 5874.6 6053.3 6059.5 4627.0 5920.5 5961.8 6042.6 6054.7 09:08:38 INFO - PROCESS | 2259 | 09:08:38 INFO - PROCESS | 2259 | [#4] prototype.html Cycles:180 Average:4739.99 Median:5118.85 stddev:1331.88 (26.0%) stddev-sans-first:1293.19 09:08:38 INFO - PROCESS | 2259 | Values: 296.5 380.2 386.3 392.9 399.3 5428.8 5439.9 5447.9 5459.7 5466.1 4852.0 5350.4 5367.5 5370.6 5415.5 3742.4 4240.8 4324.8 4391.7 4403.8 4070.8 4285.7 4412.7 4442.3 4455.3 4235.9 4415.8 4417.0 4419.0 4422.1 3673.2 3692.9 3869.3 3895.6 4398.5 3561.6 4030.3 4035.4 4077.4 4088.3 4726.6 5259.3 5275.3 5275.9 5308.0 4134.0 4539.8 4607.2 4677.7 5174.8 5758.0 5797.6 5800.5 5812.6 5826.9 4601.7 4608.0 4627.3 4633.2 4655.4 5055.2 5165.6 5625.7 6115.8 6175.6 4070.6 4370.9 4716.0 4720.3 4732.1 4110.0 4162.5 4756.8 4779.4 4813.2 4298.1 4915.4 5085.0 5095.6 5177.5 5111.3 5289.0 5314.6 5750.7 5777.0 1365.9 1558.6 1619.6 1634.8 1637.1 1160.1 1193.5 1207.2 1214.2 1228.5 5123.0 5148.2 5247.7 5434.7 5434.9 5131.5 5241.2 5386.6 6001.7 6013.7 4909.8 4977.2 5085.8 5402.9 5496.2 2917.0 2983.3 3108.2 3109.6 3114.9 5241.1 5273.7 5580.6 6166.5 6198.4 4609.6 5536.1 5867.6 5960.8 5977.4 5355.8 5604.3 5746.3 5956.3 6067.4 5334.3 5794.2 6029.8 6048.6 6065.3 4865.8 5378.3 5553.3 5555.9 5812.0 6231.2 6233.5 6234.6 6258.3 6326.4 4419.9 4643.9 4655.3 4664.3 4677.5 4901.4 5419.1 5926.3 5979.7 5992.5 4732.4 4859.9 4927.0 5747.2 5769.7 4765.6 5114.7 5242.4 5759.5 5781.7 5226.9 5328.1 5548.0 5621.6 5639.2 5567.3 5729.9 5764.0 5770.0 5784.3 4519.4 4848.7 4855.7 5609.5 5715.4 09:08:38 INFO - PROCESS | 2259 | 09:08:38 INFO - PROCESS | 2259 | [#5] yui.html Cycles:180 Average:20474.97 Median:21664.81 stddev:29709.35 (137.1%) stddev-sans-first:29755.49 09:08:38 INFO - PROCESS | 2259 | Values: 674.1 679.1 756.3 818.2 827.4 15063.1 16194.5 16366.2 16425.1 16442.8 14196.7 14253.5 14269.6 14310.1 14312.9 769.9 947.4 956.7 957.6 959.0 921.2 943.8 945.4 965.1 965.6 993.2 994.3 995.6 998.6 999.4 973.9 990.7 994.0 994.0 996.0 722.6 727.6 736.9 739.0 740.3 15748.0 16164.0 16265.5 16734.8 17061.2 14119.3 14264.7 14364.0 14372.8 14455.3 11589.0 11606.9 11750.6 11913.6 12344.7 3216.3 3587.2 3781.3 3792.3 3814.8 107390.0 107859.0 113360.0 113430.0 114320.4 10291.8 10543.2 10605.5 10941.2 11065.2 22867.5 22911.4 22920.4 22924.0 22978.2 7585.0 7599.7 7625.2 7718.8 7949.0 4182.4 4261.9 4371.7 4374.4 4439.2 3395.9 3443.7 3478.9 3485.0 3487.9 517.7 577.8 622.8 637.8 651.1 616.2 645.4 651.5 662.8 666.5 8519.6 9224.8 9326.8 9965.9 10166.6 626.6 665.5 672.0 672.2 685.9 4096.6 4606.3 4702.8 4747.1 4752.6 105320.0 107486.0 107519.0 109568.5 110599.9 69134.6 84158.6 91419.5 91931.1 93178.5 44884.8 64762.0 73202.6 75551.0 76106.5 7253.3 8925.7 9274.6 9285.5 9302.7 34772.6 39514.8 53019.7 53530.7 55082.9 66089.7 68001.6 72872.0 73074.6 73717.6 12833.6 12902.8 13022.9 13062.9 13098.3 14316.6 14636.0 14774.8 15188.6 15206.0 13427.0 13545.7 13576.2 13755.3 14099.6 13164.6 13832.8 14061.3 14075.4 14305.9 13108.3 13451.5 13594.5 13606.5 14069.6 10949.3 11139.6 11229.6 11234.5 11651.8 9869.5 11101.7 11169.4 11468.9 11471.5 09:08:38 INFO - PROCESS | 2259 | -------- Summary: end -------- 09:08:38 INFO - PROCESS | 2259 | 09:08:39 INFO - TEST-INFO | 2259: exit 0 09:08:39 INFO - TEST-OK | dromaeo_css | took 1117172ms 09:08:39 INFO - TEST-START | kraken 09:08:39 INFO - operating with platform_type : mac_ 09:08:39 INFO - Initialising browser for kraken test... 09:08:39 INFO - TEST-INFO | started process 2263 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/tmpOYlRRJ/profile http://localhost:62507/getInfo.html) 09:08:42 INFO - PROCESS | 2263 | 2017-03-18 09:08:42.362 plugin-container[2264:42206] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 09:08:42 INFO - PROCESS | 2263 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:08:42 INFO - PROCESS | 2263 | 2017-03-18 09:08:42.365 plugin-container[2264:42206] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x910b, name = 'com.apple.CFPasteboardClient' 09:08:42 INFO - PROCESS | 2263 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:08:42 INFO - PROCESS | 2263 | 2017-03-18 09:08:42.365 plugin-container[2264:42206] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:08:42 INFO - PROCESS | 2263 | __metrics Screen width/height:1600/1200 09:08:42 INFO - PROCESS | 2263 | colorDepth:24 09:08:42 INFO - PROCESS | 2263 | Browser inner width/height: 1024/689 09:08:42 INFO - PROCESS | 2263 | __metrics 09:08:42 ERROR - PROCESS | 2263 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3353: TypeError: this.tabs is undefined 09:08:43 INFO - TEST-INFO | 2263: exit 0 09:08:43 INFO - Browser initialized. 09:08:43 INFO - Running cycle 1/1 for kraken test... 09:08:43 INFO - TEST-INFO | started process 2265 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/tmpOYlRRJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:08:45 INFO - PROCESS | 2265 | 2017-03-18 09:08:45.777 plugin-container[2266:42342] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a4b, name = 'com.apple.tsm.portname' 09:08:45 INFO - PROCESS | 2265 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:08:45 INFO - PROCESS | 2265 | 2017-03-18 09:08:45.778 plugin-container[2266:42342] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a4f, name = 'com.apple.CFPasteboardClient' 09:08:45 INFO - PROCESS | 2265 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:08:45 INFO - PROCESS | 2265 | 2017-03-18 09:08:45.778 plugin-container[2266:42342] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:08:55 INFO - PROCESS | 2265 | RSS: Main: 221085696 09:08:55 INFO - PROCESS | 2265 | 09:08:58 INFO - PROCESS | 2265 | Cycle 1(1): loaded http://localhost:62507/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:62507/tests/kraken/driver.html?testName=audio-beat-detection) 09:08:59 INFO - PROCESS | 2265 | RSS: Main: 234897408 09:08:59 INFO - PROCESS | 2265 | 09:09:02 INFO - PROCESS | 2265 | Cycle 1(1): loaded http://localhost:62507/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:62507/tests/kraken/driver.html?testName=audio-dft) 09:09:02 INFO - PROCESS | 2265 | RSS: Main: 243130368 09:09:02 INFO - PROCESS | 2265 | 09:09:06 INFO - PROCESS | 2265 | Cycle 1(1): loaded http://localhost:62507/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:62507/tests/kraken/driver.html?testName=audio-fft) 09:09:06 INFO - PROCESS | 2265 | RSS: Main: 246820864 09:09:06 INFO - PROCESS | 2265 | 09:09:08 INFO - PROCESS | 2265 | Cycle 1(1): loaded http://localhost:62507/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:62507/tests/kraken/driver.html?testName=audio-oscillator) 09:09:09 INFO - PROCESS | 2265 | RSS: Main: 248467456 09:09:09 INFO - PROCESS | 2265 | 09:09:11 INFO - PROCESS | 2265 | Cycle 1(1): loaded http://localhost:62507/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:62507/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:09:12 INFO - PROCESS | 2265 | RSS: Main: 248422400 09:09:12 INFO - PROCESS | 2265 | 09:09:15 INFO - PROCESS | 2265 | Cycle 1(1): loaded http://localhost:62507/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:62507/tests/kraken/driver.html?testName=imaging-darkroom) 09:09:16 INFO - PROCESS | 2265 | RSS: Main: 252198912 09:09:16 INFO - PROCESS | 2265 | 09:09:19 INFO - PROCESS | 2265 | Cycle 1(1): loaded http://localhost:62507/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:62507/tests/kraken/driver.html?testName=imaging-desaturate) 09:09:19 INFO - PROCESS | 2265 | RSS: Main: 252022784 09:09:19 INFO - PROCESS | 2265 | 09:09:22 INFO - PROCESS | 2265 | Cycle 1(1): loaded http://localhost:62507/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:62507/tests/kraken/driver.html?testName=json-parse-financial) 09:09:23 INFO - PROCESS | 2265 | RSS: Main: 251895808 09:09:23 INFO - PROCESS | 2265 | 09:09:25 INFO - PROCESS | 2265 | Cycle 1(1): loaded http://localhost:62507/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:62507/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:09:25 INFO - PROCESS | 2265 | RSS: Main: 251039744 09:09:25 INFO - PROCESS | 2265 | 09:09:27 INFO - PROCESS | 2265 | Cycle 1(1): loaded http://localhost:62507/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:62507/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:09:28 INFO - PROCESS | 2265 | RSS: Main: 251060224 09:09:28 INFO - PROCESS | 2265 | 09:09:30 INFO - PROCESS | 2265 | Cycle 1(1): loaded http://localhost:62507/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:62507/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:09:31 INFO - PROCESS | 2265 | RSS: Main: 251097088 09:09:31 INFO - PROCESS | 2265 | 09:09:34 INFO - PROCESS | 2265 | Cycle 1(1): loaded http://localhost:62507/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:62507/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:09:35 INFO - PROCESS | 2265 | RSS: Main: 251744256 09:09:35 INFO - PROCESS | 2265 | 09:09:38 INFO - PROCESS | 2265 | Cycle 1(1): loaded http://localhost:62507/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:62507/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:09:38 INFO - PROCESS | 2265 | RSS: Main: 251551744 09:09:38 INFO - PROCESS | 2265 | 09:09:40 INFO - PROCESS | 2265 | Cycle 1(1): loaded http://localhost:62507/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:62507/tests/kraken/driver.html?testName=ai-astar) 09:09:40 INFO - PROCESS | 2265 | RSS: Main: 253444096 09:09:40 INFO - PROCESS | 2265 | 09:09:40 INFO - PROCESS | 2265 | __start_tp_report 09:09:40 INFO - PROCESS | 2265 | _x_x_mozilla_page_load 09:09:40 INFO - PROCESS | 2265 | _x_x_mozilla_page_load_details 09:09:40 INFO - PROCESS | 2265 | |i|pagename|runs| 09:09:40 INFO - PROCESS | 2265 | |0;ai-astar;109;126;118;119;122;118;120;119;120;119 09:09:40 INFO - PROCESS | 2265 | |1;audio-beat-detection;147;113;117;189;143;111;125;144;142;144 09:09:40 INFO - PROCESS | 2265 | |2;audio-dft;201;199;198;197;198;198;191;246;199;199 09:09:40 INFO - PROCESS | 2265 | |3;audio-fft;86;60;71;91;85;89;86;91;73;64 09:09:40 INFO - PROCESS | 2265 | |4;audio-oscillator;113;94;119;102;73;73;81;70;69;83 09:09:40 INFO - PROCESS | 2265 | |5;imaging-gaussian-blur;90;135;134;88;89;89;128;117;138;120 09:09:40 INFO - PROCESS | 2265 | |6;imaging-darkroom;96;99;97;95;96;103;98;153;129;97 09:09:40 INFO - PROCESS | 2265 | |7;imaging-desaturate;91;93;86;145;92;90;93;91;92;96 09:09:40 INFO - PROCESS | 2265 | |8;json-parse-financial;67;66;67;68;72;72;81;95;70;89 09:09:40 INFO - PROCESS | 2265 | |9;json-stringify-tinderbox;59;63;60;64;97;86;53;67;90;91 09:09:40 INFO - PROCESS | 2265 | |10;stanford-crypto-aes;84;86;95;106;102;88;82;82;87;75 09:09:40 INFO - PROCESS | 2265 | |11;stanford-crypto-ccm;153;156;142;139;144;138;144;157;213;182 09:09:40 INFO - PROCESS | 2265 | |12;stanford-crypto-pbkdf2;172;162;234;175;167;166;153;201;167;171 09:09:40 INFO - PROCESS | 2265 | |13;stanford-crypto-sha256-iterative;66;65;65;67;66;68;67;67;63;81 09:09:40 INFO - PROCESS | 2265 | __end_tp_report 09:09:40 INFO - PROCESS | 2265 | __start_cc_report 09:09:40 INFO - PROCESS | 2265 | _x_x_mozilla_cycle_collect,1014 09:09:40 INFO - PROCESS | 2265 | __end_cc_report 09:09:40 INFO - PROCESS | 2265 | __startTimestamp1489853380626__endTimestamp 09:09:40 INFO - PROCESS | 2265 | 09:09:40 INFO - PROCESS | 2265 | ------- Summary: start ------- 09:09:40 INFO - PROCESS | 2265 | Number of tests: 14 09:09:40 INFO - PROCESS | 2265 | 09:09:40 INFO - PROCESS | 2265 | [#0] ai-astar Cycles:10 Average:119.00 Median:119.50 stddev:4.24 (3.6%) stddev-sans-first:2.52 09:09:40 INFO - PROCESS | 2265 | Values: 109.0 126.0 118.0 119.0 122.0 118.0 120.0 119.0 120.0 119.0 09:09:40 INFO - PROCESS | 2265 | 09:09:40 INFO - PROCESS | 2265 | [#1] audio-beat-detection Cycles:10 Average:137.50 Median:143.50 stddev:22.99 (16.0%) stddev-sans-first:24.13 09:09:40 INFO - PROCESS | 2265 | Values: 147.0 113.0 117.0 189.0 143.0 111.0 125.0 144.0 142.0 144.0 09:09:40 INFO - PROCESS | 2265 | 09:09:40 INFO - PROCESS | 2265 | [#2] audio-dft Cycles:10 Average:202.60 Median:199.00 stddev:15.47 (7.8%) stddev-sans-first:16.40 09:09:40 INFO - PROCESS | 2265 | Values: 201.0 199.0 198.0 197.0 198.0 198.0 191.0 246.0 199.0 199.0 09:09:40 INFO - PROCESS | 2265 | 09:09:40 INFO - PROCESS | 2265 | [#3] audio-fft Cycles:10 Average:79.60 Median:86.00 stddev:11.57 (13.5%) stddev-sans-first:12.04 09:09:40 INFO - PROCESS | 2265 | Values: 86.0 60.0 71.0 91.0 85.0 89.0 86.0 91.0 73.0 64.0 09:09:40 INFO - PROCESS | 2265 | 09:09:40 INFO - PROCESS | 2265 | [#4] audio-oscillator Cycles:10 Average:87.70 Median:73.00 stddev:18.34 (25.1%) stddev-sans-first:17.01 09:09:40 INFO - PROCESS | 2265 | Values: 113.0 94.0 119.0 102.0 73.0 73.0 81.0 70.0 69.0 83.0 09:09:40 INFO - PROCESS | 2265 | 09:09:40 INFO - PROCESS | 2265 | [#5] imaging-gaussian-blur Cycles:10 Average:112.80 Median:113.00 stddev:21.46 (19.0%) stddev-sans-first:21.12 09:09:40 INFO - PROCESS | 2265 | Values: 90.0 135.0 134.0 88.0 89.0 89.0 128.0 117.0 138.0 120.0 09:09:40 INFO - PROCESS | 2265 | 09:09:40 INFO - PROCESS | 2265 | [#6] imaging-darkroom Cycles:10 Average:106.30 Median:96.50 stddev:19.27 (20.0%) stddev-sans-first:20.08 09:09:40 INFO - PROCESS | 2265 | Values: 96.0 99.0 97.0 95.0 96.0 103.0 98.0 153.0 129.0 97.0 09:09:40 INFO - PROCESS | 2265 | 09:09:40 INFO - PROCESS | 2265 | [#7] imaging-desaturate Cycles:10 Average:96.90 Median:92.00 stddev:17.09 (18.6%) stddev-sans-first:17.99 09:09:40 INFO - PROCESS | 2265 | Values: 91.0 93.0 86.0 145.0 92.0 90.0 93.0 91.0 92.0 96.0 09:09:40 INFO - PROCESS | 2265 | 09:09:40 INFO - PROCESS | 2265 | [#8] json-parse-financial Cycles:10 Average:74.70 Median:72.00 stddev:10.18 (14.1%) stddev-sans-first:10.41 09:09:40 INFO - PROCESS | 2265 | Values: 67.0 66.0 67.0 68.0 72.0 72.0 81.0 95.0 70.0 89.0 09:09:40 INFO - PROCESS | 2265 | 09:09:40 INFO - PROCESS | 2265 | [#9] json-stringify-tinderbox Cycles:10 Average:73.00 Median:76.50 stddev:16.12 (21.1%) stddev-sans-first:16.29 09:09:40 INFO - PROCESS | 2265 | Values: 59.0 63.0 60.0 64.0 97.0 86.0 53.0 67.0 90.0 91.0 09:09:40 INFO - PROCESS | 2265 | 09:09:40 INFO - PROCESS | 2265 | [#10] stanford-crypto-aes Cycles:10 Average:88.70 Median:85.00 stddev:9.58 (11.3%) stddev-sans-first:10.01 09:09:40 INFO - PROCESS | 2265 | Values: 84.0 86.0 95.0 106.0 102.0 88.0 82.0 82.0 87.0 75.0 09:09:40 INFO - PROCESS | 2265 | 09:09:40 INFO - PROCESS | 2265 | [#11] stanford-crypto-ccm Cycles:10 Average:156.80 Median:154.50 stddev:23.63 (15.3%) stddev-sans-first:25.02 09:09:40 INFO - PROCESS | 2265 | Values: 153.0 156.0 142.0 139.0 144.0 138.0 144.0 157.0 213.0 182.0 09:09:40 INFO - PROCESS | 2265 | 09:09:40 INFO - PROCESS | 2265 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.80 Median:171.50 stddev:23.60 (13.8%) stddev-sans-first:24.96 09:09:40 INFO - PROCESS | 2265 | Values: 172.0 162.0 234.0 175.0 167.0 166.0 153.0 201.0 167.0 171.0 09:09:40 INFO - PROCESS | 2265 | 09:09:40 INFO - PROCESS | 2265 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.50 Median:67.00 stddev:4.95 (7.4%) stddev-sans-first:5.22 09:09:40 INFO - PROCESS | 2265 | Values: 66.0 65.0 65.0 67.0 66.0 68.0 67.0 67.0 63.0 81.0 09:09:40 INFO - PROCESS | 2265 | -------- Summary: end -------- 09:09:40 INFO - PROCESS | 2265 | 09:09:41 INFO - TEST-INFO | 2265: exit 0 09:09:41 INFO - TEST-OK | kraken | took 62072ms 09:09:41 INFO - SUITE-END | took 1179s 09:09:41 INFO - Completed test suite (00:19:39) 09:09:41 INFO - javascript benchmark 09:09:41 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7737.079113327352, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3484.930301393972, 3490.179807745181, 3516.2264301853584, 3524.154202991281, 3533.876314328998, 6063.757449702007, 6086.634801911889, 6113.32753397126, 6150.66171360575, 6171.9074213886715, 6125.509959203264, 6133.325334213233, 6146.969265153668, 6175.289841668209, 6200.131981522591, 5442.91835622467, 5517.944820551783, 5526.944730552683, 5537.833864984037, 5600.327960644729, 4806.495317991613, 5557.416471270519, 5568.303962004749, 5571.220029195916, 5591.3569939456875, 5340.225667278242, 5349.839504814861, 5476.17857321403, 5554.028045092113, 5575.386707462176, 5447.210154527591, 5468.9726551367385, 5480.314960629921, 5511.4213007634025, 5608.523275521586, 5388.326459192601, 5505.752241149158, 5519.116941289374, 5533.031719449121, 5539.25220095286, 6128.0195168773225, 6159.599626024339, 6200.224971878515, 6226.62640241587, 6248.843778905518, 6256.1866957295715, 6301.464375528086, 6309.652969086698, 6379.776707815251, 6468.74125034998, 6531.934680653228, 6563.50773691975, 6589.670516474205, 6608.86782264352, 6622.2715501294815, 6179.536534759821, 6217.658028808459, 6239.469645080219, 6246.0, 6268.185135932391, 6372.203474565679, 6417.7753778617525, 6704.463642908556, 6776.457883369319, 6796.864062718817, 4864.8783780405065, 6075.906336859408, 6288.245410550763, 6411.198600174978, 6460.580062295936, 5408.269883565747, 6518.934810651833, 6522.5760325578685, 6527.151470308924, 6532.0, 6290.465310448663, 6304.211973503312, 6457.1282876811965, 6480.805575832733, 6481.773137940149, 6327.525435592257, 6357.205349331333, 6371.39471750183, 6388.520860935449, 6409.519286053564, 5071.746412679351, 5083.161278389099, 5163.173892177234, 5220.216967454836, 5245.26566280721, 4666.789994450258, 4667.603253723471, 4670.859874203711, 4692.577668009895, 4705.12955103307, 6215.96892015537, 6223.377662233831, 6234.501239900797, 6287.717052732638, 6291.150694656254, 6303.590266632654, 6366.236051673736, 6438.195225596801, 6453.354664533603, 6599.70301336441, 6222.097795819581, 6288.874222515524, 6292.874142517124, 6311.779087731999, 6314.052892066226, 5781.306243250837, 5790.942090579125, 5791.5656325775735, 5803.767849286066, 5825.504832089235, 6445.9033114504255, 6486.189226346707, 6662.56693314933, 6776.424003959719, 6808.727650894008, 6559.0, 6559.704813283509, 6593.472522198309, 6601.801945941629, 6619.470442364695, 6733.0, 6804.455643548603, 6810.897836532356, 6828.658567071726, 6851.45188384938, 6286.088517164894, 6323.336049714714, 6501.1548498694865, 6511.902321465087, 6580.80257592273, 6397.936020639734, 6408.070829729756, 6549.050387693853, 6583.44040756541, 6592.0, 6242.157308763441, 6392.552521323649, 6396.360363963567, 6406.263279722705, 6413.294537600954, 5214.582833373397, 6320.146780184617, 6322.905156422749, 6368.617882927039, 6384.9042264367, 6062.302835174011, 6207.906881396692, 6220.875582488416, 6226.688665566613, 6273.62358258506, 6424.550281480251, 6430.3891130342545, 6434.0, 6434.581752186093, 6439.227292724903, 6113.445900579344, 6168.938310616836, 6237.812865614039, 6283.465905398092, 6313.116163737172], "value": 5993.1445595146715, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [758.5979430901621, 817.6443247187474, 837.7026155714723, 969.9369540979836, 973.8880028796691, 39126.41310380343, 39935.20129597406, 41662.58337416625, 41679.79160104199, 41701.0, 40278.39582406266, 40519.39220911681, 40659.0, 40733.59266407335, 40768.59231407685, 7909.564973926417, 8041.919580804176, 10549.683509494727, 10887.455627218607, 11037.0, 10672.786544269149, 10724.410157441338, 10773.0, 10777.676669699922, 10795.29830561015, 10771.83842242367, 10788.244822862403, 10813.459327033655, 10814.72963175923, 10826.837597436006, 10741.83887241692, 10772.245942784008, 10786.946065269702, 10797.352158870493, 10800.567977280938, 10539.314944528658, 10546.209034322457, 10578.312409693346, 10579.047885690248, 10586.100181484582, 34800.651993479994, 34914.476282855525, 34932.0, 35024.12439689002, 35146.648533514846, 3764.4353346997896, 3804.524434445694, 3824.541055073409, 3824.8470061197518, 3846.2499812536566, 25580.953523718057, 25647.871760641265, 26277.0, 26665.066722664615, 26710.669104639488, 3069.723724864773, 3094.9690503094907, 3126.3122113135073, 3161.9407498488035, 3174.904752857418, 357213.0, 360576.1971190153, 360986.0, 361501.1924940385, 362142.9999999974, 45955.0, 45994.31008534875, 46043.76978115088, 46073.30890036652, 46125.538744612124, 47588.28617570739, 47845.521544784104, 47925.28112078321, 47987.76006120017, 48656.513434865905, 13377.933110334387, 13707.0, 13725.931370343083, 13747.656308592364, 13749.518766843312, 11636.825447618292, 11738.41307934617, 11759.0, 11770.999465045566, 11773.470193841298, 11813.0, 12226.0, 12243.326617035971, 12246.632601021984, 12339.321337326353, 4794.376731025014, 5713.542916566665, 5722.399148089391, 5764.538836893123, 5780.6531608102805, 1105.7125147461718, 1121.0471099565468, 1125.3360517294673, 1132.405487119269, 1143.7540928700369, 21601.78398216029, 22298.55402891933, 25442.618360724606, 27486.0, 27817.02640407576, 941.7316064921464, 952.7189479103681, 1051.9217801753264, 1054.920880933933, 1089.7548051688307, 13978.370973306226, 14144.00991930555, 14288.0, 14303.92848035774, 14311.856881431053, 488277.0, 493810.0, 497146.0, 497798.0, 498817.5059124681, 47928.5207147931, 48230.0, 48339.75830120827, 49873.750631247334, 51733.0, 1407.4933024111313, 1432.3124900047826, 1443.2927865345905, 1444.4655477473402, 1446.6094154578204, 26959.595606065534, 26977.03986610326, 27361.0, 27698.446031078864, 28006.85996570004, 40786.36854525904, 44582.55417445784, 44659.10681786411, 46463.30305045359, 48213.51786482231, 462834.0, 466178.6691066523, 471388.0, 472536.0, 473931.0, 26265.0, 26476.0, 33333.66666333403, 33446.665533344356, 33553.49669754907, 39577.01057473471, 39732.20535589214, 39923.802285931466, 40826.9793255171, 40960.79519602383, 33399.666003339655, 33828.0, 34071.4889276666, 34340.48489272713, 34943.30113397667, 33374.833125834215, 33474.995750127535, 34303.4854477178, 34435.82782086074, 34634.48048279328, 33386.66453341936, 33560.83219583886, 33934.32131357408, 33947.4907876387, 34032.31935361229, 24815.503689926463, 25709.614355785052, 26764.59853102244, 28355.865765369155, 30919.0, 21138.57722845565, 23657.763422365555, 23917.16289929844, 24218.394540136636, 24339.878300608383], "value": 18534.466627585654, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [388.47167851721656, 400.5353789604058, 418.12403015682133, 440.2252036415908, 453.97175397724163, 19526.51183720406, 20250.189992400286, 21366.931653417345, 21470.926453677333, 21547.030383632737, 14740.0, 14833.703325933475, 15555.533333999998, 20826.062827172776, 21233.469163270925, 6390.913544697412, 6487.578307410027, 6609.900851487208, 6641.435477984377, 6673.366030227121, 6280.434760871521, 6502.967485162568, 6516.869662606745, 6595.868082638368, 6617.305182955793, 6579.703913323888, 6588.901166482507, 6590.967045164767, 6594.109795177637, 6602.207735071798, 6271.968640156792, 6545.116409284757, 6577.079208910756, 6599.142111525519, 6614.735410583619, 5483.013057649621, 6440.710168042449, 6462.25684046336, 6471.708773105222, 6479.708413121373, 21804.781952180434, 26200.0829970952, 26508.0, 26894.789734461996, 27191.96060295466, 3369.8045932813557, 3376.8142752148624, 3384.069380920242, 3448.2413868948793, 3589.4795254687924, 14778.926105369404, 15078.623034424118, 15417.22913854314, 15682.215889205494, 15777.526674199793, 1970.9507262318414, 2008.2569449303705, 2025.6151331247163, 2037.6230397376532, 2042.6119037382998, 129048.06427903588, 130091.0, 132079.33960330233, 132781.33609331987, 133338.99999999904, 22254.443638909157, 22582.209622663457, 23210.0, 23422.765772342398, 23446.296611101694, 35155.296894061976, 35380.823095884356, 35756.0, 35902.820485897406, 35988.0, 10329.225308101923, 10352.929414117207, 10552.366857988392, 10725.624603138853, 10848.240623156304, 8529.65881364751, 8561.700340488178, 8573.442726222776, 8721.171488708562, 8731.607077681392, 10730.980556847087, 10926.726831829108, 10960.342379457257, 11055.22613417053, 11091.0, 4049.676025917978, 4338.783060846945, 4429.224885645, 4544.7045942013665, 4604.58558729716, 784.2864038776389, 828.2918105020242, 919.6781126605634, 930.3899179565238, 939.0890835889177, 20644.96775161236, 21749.67375489369, 21950.670739938912, 21966.341009769734, 22101.447463813533, 942.6606421688186, 960.1694534227843, 968.2012339819677, 983.9114479696862, 991.5785791038924, 11054.889451105546, 11075.889241107487, 11562.190646654823, 11698.122640801974, 11743.295402275891, 412984.0, 449455.0, 695118.0, 753344.0, 764507.0, 113262.0, 114095.0, 116381.41809290899, 125111.37444312903, 133394.66605333824, 1261.1802328486558, 1267.9683007924875, 1304.1979182802509, 1309.6987692830512, 1311.514739546374, 18031.18859651345, 18285.360012399502, 18538.16578253955, 18621.78958367702, 18834.775739576882, 54235.72882135564, 55168.17247741207, 55379.44620553743, 55556.72221639028, 55662.721686391305, 72966.27033729595, 73252.63373683098, 74698.0, 75283.62358188393, 76642.0, 10924.890751092387, 11019.898010198916, 11532.59635912739, 12429.067819913724, 12502.937485312515, 13497.932510337385, 13626.454941802216, 13695.86304136946, 14006.159630422208, 14052.648683782987, 11374.772504549697, 11624.709382265512, 11765.76468470643, 11799.941000294944, 11869.881301186877, 9933.255005874444, 11381.43092845371, 11704.005159561533, 11837.112216583962, 12062.215955962834, 11734.706632333919, 11810.40947952616, 11964.880351196376, 12061.336626485276, 12078.698032549257, 12445.751084978068, 12502.0, 12624.495020199456, 12794.0, 12802.16785908913, 9165.220956218796, 10773.51519181623, 11213.327200368005, 11279.266847654877, 11693.415329233674], "value": 11428.006102759477, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1523.086148311013, 1558.812942446906, 1569.270289315468, 1584.437524678739, 1595.8882878198524, 9418.81162376752, 9582.329236953416, 10655.893441065597, 11352.091832653388, 11359.14806389521, 10110.140638045776, 10379.896201037987, 10389.84415233772, 10412.791744165112, 10456.634017809378, 2393.72472165701, 2445.2664200739796, 2447.8041756659422, 2450.215930902112, 2452.963205551909, 2420.8305418620703, 2433.8783060846977, 2447.7552244775557, 2451.2278632230878, 2468.3212116667883, 2406.759324067588, 2426.684531010966, 2436.0377650827913, 2444.6210837320245, 2452.1294940296116, 2370.6799582056547, 2376.441536238991, 2388.916387926423, 2391.2587097999676, 2396.952060958771, 2350.2831636350907, 2350.717913850349, 2407.265783935899, 2409.662647229389, 2414.5291668124723, 10203.489825508768, 10426.270161088727, 11239.213255072149, 11330.660080197607, 11406.429678516124, 4609.930851037204, 4634.374359461463, 5115.462876397962, 5776.740046697909, 5953.404659534055, 2904.419116176752, 3032.454158251514, 3647.3434781739275, 3651.5618125824803, 3717.643060283004, 1200.327816422806, 1211.7818792617413, 1211.8424604801407, 1261.974760504794, 1268.6067319131098, 12785.808212876722, 15431.459898903597, 15646.530604081896, 15670.981386209975, 15676.29456674441, 4606.677532572688, 5028.0, 5051.217061355495, 5078.4413714491375, 5125.79496820123, 12030.7969203079, 12492.500299987898, 13208.405621747044, 13254.337283135805, 13281.734365312639, 5948.762049518057, 6030.638161710312, 6044.88169688609, 6047.909281360783, 6050.636961782219, 4452.5992660660695, 5078.542931136141, 5203.32356793622, 5203.739813009334, 5328.147496400557, 4135.359019352005, 4197.727147735387, 4254.319308910622, 4288.335308027261, 4304.225239456865, 1149.5976408257043, 1160.7678464307173, 1163.5113252433998, 1172.8358029875824, 1174.424531979324, 813.8209593889335, 826.8883700700449, 843.9578021098921, 860.9311255099577, 900.0234745301291, 6286.874262514815, 6390.456811171102, 6552.082708420825, 6563.409293163638, 6663.100481435041, 846.4540371460373, 958.3004406783087, 1002.8746406699163, 1023.7542989682422, 1033.6433930294186, 3222.4521831288266, 4079.938800917989, 4168.2080404723, 4198.307279298942, 4299.161663475592, 11534.826977595343, 13792.0, 14254.85745142556, 14288.499902503363, 14288.642783930278, 12881.484740610458, 13383.19700817954, 13699.246541440118, 14131.434742610387, 14136.646583835487, 9464.337496375188, 9534.0, 10046.799064018678, 10296.742581435525, 10297.897021029694, 5761.596688231783, 6480.967595161994, 6684.16447944007, 6838.452923766186, 6974.476914231352, 11743.47154378072, 12170.878291216974, 12296.139270250997, 12318.384080796104, 12366.567170149001, 10811.783764324828, 12212.32832194238, 12509.812352814533, 12533.561325353568, 12581.433835477243, 8125.228103330174, 8341.41610087288, 9144.817103658022, 9314.95342523283, 9546.0, 10191.898081019095, 10468.63359782404, 10533.42066186367, 10573.154147668014, 10580.0, 9228.861567076365, 9244.583993720433, 9268.397554158959, 9339.626414943597, 9430.811383772423, 7453.664585093576, 8956.820863582821, 9118.908810911806, 9121.224695900923, 9244.907550924405, 7920.049594048751, 7953.761387158394, 7963.323117534843, 8046.155153709011, 8046.356291496549, 5723.599348045597, 5866.120081987736, 5874.559408044499, 6053.303870055, 6059.545534085049, 4626.953730462652, 5920.496757775465, 5961.821145365646, 6042.637441753509, 6054.697265136813], "value": 5135.092254911819, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [296.49003713612586, 380.23382883489757, 386.3470734458766, 392.8644617606926, 399.33909379976143, 5428.83713488595, 5439.918401223985, 5447.855950250447, 5459.727013649322, 5466.098093814516, 4851.975740121304, 5350.35795704516, 5367.490088441602, 5370.570354371651, 5415.539679127269, 3742.382506886367, 4240.787960601973, 4324.84863029794, 4391.692581519295, 4403.757793321366, 4070.8398106539644, 4285.657147428198, 4412.713173643719, 4442.267025940716, 4455.264881294583, 4235.8563187939235, 4415.8233670653135, 4416.97791511042, 4419.0, 4422.093470838487, 3673.210259794144, 3692.8707495237672, 3869.3228684980018, 3895.571487136441, 4398.450193725785, 3561.5726112866364, 4030.2745505808944, 4035.3946907963746, 4077.368007958771, 4088.34586466164, 4726.621870250372, 5259.2899958505495, 5275.31190019194, 5275.947240527584, 5308.0, 4133.9950124189745, 4539.750313732743, 4607.17070927233, 4677.695949763254, 5174.818881339135, 5757.955925646377, 5797.565182611321, 5800.53595712342, 5812.564057695689, 5826.912596311059, 4601.654875884288, 4608.018552022629, 4627.282771170474, 4633.212353899845, 4655.441347038377, 5055.191169412877, 5165.612579056586, 5625.746841392147, 6115.837990781782, 6175.567710260284, 4070.633642972147, 4370.934435983463, 4715.95284047162, 4720.33915251865, 4732.100900828831, 4109.9589004109575, 4162.458880345535, 4756.809727610926, 4779.354787103696, 4813.229883218668, 4298.075913678574, 4915.4347250065985, 5084.974575127175, 5095.566876815438, 5177.456367081402, 5111.3355263815465, 5288.9735551322, 5314.601404894571, 5750.741216645261, 5777.0, 1365.938532766028, 1558.586974451771, 1619.6193894434798, 1634.8119966203801, 1637.148682685021, 1160.1067178272851, 1193.516625766566, 1207.2454715802623, 1214.180428210954, 1228.4656174564093, 5123.0, 5148.22386276734, 5247.685138891678, 5434.673919564839, 5434.945650543523, 5131.538161565477, 5241.187615919539, 5386.615225682626, 6001.699915004319, 6013.729382177812, 4909.754512274443, 4977.158451372943, 5085.771140298695, 5402.8919421611345, 5496.1755736639825, 2917.0018537302017, 2983.2690990706983, 3108.2283098634016, 3109.6423911250045, 3114.9065528034193, 5241.109011468059, 5273.6572122811895, 5580.553555715533, 6166.4758495527485, 6198.442140207403, 4609.585137337589, 5536.086545719992, 5867.647941123546, 5960.821175364746, 5977.432143946318, 5355.812546560852, 5604.27144471208, 5746.281714785652, 5956.255468066492, 6067.362926892701, 5334.279872217201, 5794.246747922743, 6029.849253768691, 6048.576599637983, 6065.272167339947, 4865.756712164307, 5378.327709036371, 5553.305836770403, 5555.8888822224135, 5812.035861710642, 6231.158793562992, 6233.501319894307, 6234.563580549499, 6258.280297765633, 6326.43062124411, 4419.889502762328, 4643.931895664084, 4655.2784318430695, 4664.300354946785, 4677.508861569622, 4901.387326584177, 5419.13040001199, 5926.318473375617, 5979.671118088547, 5992.460678538952, 4732.432108146907, 4859.878503037452, 4926.965337279212, 5747.183879898903, 5769.68266745333, 4765.571098601143, 5114.693118412907, 5242.397124330751, 5759.452851979056, 5781.739821707949, 5226.9215961761365, 5328.120860058202, 5548.0013597552825, 5621.634593751393, 5639.167270636977, 5567.3298010595845, 5729.8567535811935, 5764.0, 5770.0, 5784.334801497714, 4519.367288579667, 4848.733319667452, 4855.684380515255, 5609.540415369709, 5715.428457154251], "value": 4348.56433811531, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [674.0866126398732, 679.0833370672959, 756.2550887375937, 818.2186012358198, 827.3576949754736, 15063.09621422714, 16194.514164575052, 16366.181690915451, 16425.09661968591, 16442.83557164428, 14196.71606567868, 14253.50112746054, 14269.643258918532, 14310.141391516516, 14312.928435357808, 769.9422543309246, 947.4078700811992, 956.7129861041694, 957.5786653872274, 959.0217977662779, 921.230772305126, 943.8206740719276, 945.4374647084974, 965.1362030982267, 965.5944503308592, 993.2401712689798, 994.2990191914719, 995.5520015992796, 998.6255154317131, 999.3753903810118, 973.8880028796708, 990.6879333010079, 993.9960639753829, 994.005994005994, 995.9940460135243, 722.573681527901, 727.6180005497104, 736.9189389167187, 738.9519681220758, 740.2856243724831, 15748.0, 16164.0, 16265.51203463898, 16734.83265167345, 17061.232244549024, 14119.294035298297, 14264.714705705932, 14363.994520383578, 14372.784408233885, 14455.27723613815, 11589.0, 11606.883931160664, 11750.58872939443, 11913.583024594098, 12344.691382715415, 3216.324571839893, 3587.237826873319, 3781.2626537825136, 3792.336341140317, 3814.809259537012, 107390.0, 107859.0, 113360.0, 113430.0, 114320.42839785748, 10291.794164116676, 10543.209259305582, 10605.522751476203, 10941.23411361213, 11065.170112241452, 22867.51361161519, 22911.427214319774, 22920.4269893254, 22924.0, 22978.19576314821, 7584.962075189589, 7599.65801538921, 7625.161232264447, 7718.845623087507, 7949.006374203224, 4182.393552934807, 4261.936070958938, 4371.650267978554, 4374.409454723572, 4439.178751930903, 3395.898123056312, 3443.741719371007, 3478.860845566149, 3484.9651503485147, 3487.877924272638, 517.7100823538831, 577.7891069759592, 622.75712472135, 637.8469167399791, 651.055968845167, 616.1804799616589, 645.3675398109879, 651.491836367634, 662.8044726805604, 666.53009628212, 8519.616617252239, 9224.815503690023, 9326.766830829149, 9965.900340996643, 10166.593336266615, 626.5989766549412, 665.5474277491352, 671.9969824655394, 672.1900110366996, 685.9279775623587, 4096.56986016464, 4606.262997920317, 4702.764861756898, 4747.145513807479, 4752.619790416758, 105320.0, 107486.0, 107519.0, 109568.4521577387, 110599.89400106057, 69134.61730765356, 84158.57920710357, 91419.54290228506, 91931.08068919225, 93178.53410732903, 44884.77557612256, 64762.0, 73202.63398682972, 75551.0, 76106.47787044228, 7253.347198751927, 8925.73222803317, 9274.629014839331, 9285.497746214092, 9302.720918372459, 34772.60909563589, 39514.81455556223, 53019.734901325246, 53530.732346338016, 55082.89834203374, 66089.66955165194, 68001.63996720136, 72872.0, 73074.63462682652, 73717.63141184475, 12833.614991550268, 12902.806457903327, 13022.934885325512, 13062.869371306546, 13098.345082746015, 14316.57050288493, 14636.0, 14774.778378324549, 15188.620284492976, 15206.005237590833, 13427.0, 13545.729085418434, 13576.185428873903, 13755.31223438844, 14099.578648183617, 13164.60506184816, 13832.792508112185, 14061.312642482968, 14075.436982520585, 14305.856941430453, 13108.344582771015, 13451.46194152223, 13594.524191653245, 13606.523771667944, 14069.648258793612, 10949.288296260718, 11139.610113645984, 11229.55081796719, 11234.494447750034, 11651.766964660828, 9869.45717985489, 11101.722456938642, 11169.362891951008, 11468.885311146782, 11471.483783229607], "value": 7567.001637944602, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1579.8999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 126.0, 118.0, 119.0, 122.0, 118.0, 120.0, 119.0, 120.0, 119.0], "value": 119.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 113.0, 117.0, 189.0, 143.0, 111.0, 125.0, 144.0, 142.0, 144.0], "value": 137.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [201.0, 199.0, 198.0, 197.0, 198.0, 198.0, 191.0, 246.0, 199.0, 199.0], "value": 202.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 60.0, 71.0, 91.0, 85.0, 89.0, 86.0, 91.0, 73.0, 64.0], "value": 79.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 94.0, 119.0, 102.0, 73.0, 73.0, 81.0, 70.0, 69.0, 83.0], "value": 87.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 135.0, 134.0, 88.0, 89.0, 89.0, 128.0, 117.0, 138.0, 120.0], "value": 112.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 99.0, 97.0, 95.0, 96.0, 103.0, 98.0, 153.0, 129.0, 97.0], "value": 106.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 93.0, 86.0, 145.0, 92.0, 90.0, 93.0, 91.0, 92.0, 96.0], "value": 96.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 67.0, 68.0, 72.0, 72.0, 81.0, 95.0, 70.0, 89.0], "value": 74.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 63.0, 60.0, 64.0, 97.0, 86.0, 53.0, 67.0, 90.0, 91.0], "value": 73.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 86.0, 95.0, 106.0, 102.0, 88.0, 82.0, 82.0, 87.0, 75.0], "value": 88.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 156.0, 142.0, 139.0, 144.0, 138.0, 144.0, 157.0, 213.0, 182.0], "value": 156.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 162.0, 234.0, 175.0, 167.0, 166.0, 153.0, 201.0, 167.0, 171.0], "value": 176.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 65.0, 67.0, 66.0, 68.0, 67.0, 67.0, 63.0, 81.0], "value": 67.5, "unit": "score"}]}]} 09:09:41 INFO - Return code: 0 09:09:41 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:09:41 ERROR - # TBPL SUCCESS # 09:09:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:09:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:09:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:09:41 INFO - 2017-03-18 09:09:41.969 screenresolution[2270:42734] starting screenresolution argv=screenresolution get 09:09:41 INFO - 2017-03-18 09:09:41.972 screenresolution[2270:42734] Display 0: 1600x1200x32@60 09:09:41 INFO - 2017-03-18 09:09:41.978 screenresolution[2271:42737] starting screenresolution argv=screenresolution list 09:09:41 INFO - Available Modes on Display 0 09:09:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:09:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:09:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:09:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:09:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:09:42 INFO - Intel Iris: 09:09:42 INFO - Chipset Model: Intel Iris 09:09:42 INFO - Type: GPU 09:09:42 INFO - Bus: Built-In 09:09:42 INFO - VRAM (Dynamic, Max): 1536 MB 09:09:42 INFO - Vendor: Intel (0x8086) 09:09:42 INFO - Device ID: 0x0a2e 09:09:42 INFO - Revision ID: 0x0009 09:09:42 INFO - Displays: 09:09:42 INFO - Display: 09:09:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:09:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:09:42 INFO - Display Serial Number: 1600x1200 60 09:09:42 INFO - Main Display: Yes 09:09:42 INFO - Mirror: Off 09:09:42 INFO - Online: Yes 09:09:42 INFO - Rotation: Supported 09:09:42 INFO - Return code: 0 09:09:42 INFO - Running post-action listener: _resource_record_post_action 09:09:42 INFO - [mozharness: 2017-03-18 16:09:42.221277Z] Finished run-tests step (success) 09:09:42 INFO - Running post-run listener: _resource_record_post_run 09:09:42 INFO - Total resource usage - Wall time: 1200s; CPU: 55.0%; Read bytes: 883712; Write bytes: 511685632; Read time: 21; Write time: 1621 09:09:42 INFO - TinderboxPrint: CPU usage
54.7% 09:09:42 INFO - TinderboxPrint: I/O read bytes / time
883,712 / 21 09:09:42 INFO - TinderboxPrint: I/O write bytes / time
511,685,632 / 1,621 09:09:42 INFO - TinderboxPrint: CPU idle
2,177.3 (45.3%) 09:09:42 INFO - TinderboxPrint: CPU system
964.6 (20.1%) 09:09:42 INFO - TinderboxPrint: CPU user
1,659.8 (34.6%) 09:09:42 INFO - TinderboxPrint: Swap in / out
208,912,384 / 0 09:09:42 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 203909632; Write bytes: 199614464; Read time: 15713; Write time: 424 09:09:42 INFO - run-tests - Wall time: 1180s; CPU: 55.0%; Read bytes: 883712; Write bytes: 301270016; Read time: 21; Write time: 1157 09:09:42 INFO - Running post-run listener: _upload_blobber_files 09:09:42 INFO - Blob upload gear active. 09:09:42 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:09:42 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:09:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:09:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:09:43 INFO - (blobuploader) - INFO - Open directory for files ... 09:09:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:09:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:09:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:09:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:09:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:09:45 INFO - (blobuploader) - INFO - Done attempting. 09:09:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:09:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:09:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:09:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:09:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:09:46 INFO - (blobuploader) - INFO - Done attempting. 09:09:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:09:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:09:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:09:47 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:09:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:09:47 INFO - (blobuploader) - INFO - Done attempting. 09:09:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:09:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:09:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:09:49 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:09:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:09:49 INFO - (blobuploader) - INFO - Done attempting. 09:09:49 INFO - (blobuploader) - INFO - Iteration through files over. 09:09:49 INFO - Return code: 0 09:09:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:09:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:09:49 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/035dba2e9160df1613bec78dd1dd4b6c933ed9b97fca050c962f5ffba0ffb7e2ed1ed8344718ff32c61b3bbfb7bdffca584b6a3c2a1e84f63a1ab40cdcb85635", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1c5209f18af097a36d5d8a20c6b48cca975c518473c5b1e2501107bfe7ebd491047d34a73b34aa00383bc63b8f2947f630a747226e179683799b0312b44a3dbd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e326969436292695c4339eab7cba7d0ad94e363d1d14883a36451d18dd57971df02d903645c3f8d12dc66faf68573597ac191eaecb3581e32c5c9f871d152078", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3f9bc3e2e3dd0ea46ef5483c0d675cee105cfaeed20154c6f2e22e02b8a32ae518820f6fefc8138d03aab109dcf6fab5c7a31fa1dbd30cd715a55b85d7feef34"} 09:09:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:09:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:09:49 INFO - Contents: 09:09:49 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/035dba2e9160df1613bec78dd1dd4b6c933ed9b97fca050c962f5ffba0ffb7e2ed1ed8344718ff32c61b3bbfb7bdffca584b6a3c2a1e84f63a1ab40cdcb85635", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1c5209f18af097a36d5d8a20c6b48cca975c518473c5b1e2501107bfe7ebd491047d34a73b34aa00383bc63b8f2947f630a747226e179683799b0312b44a3dbd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e326969436292695c4339eab7cba7d0ad94e363d1d14883a36451d18dd57971df02d903645c3f8d12dc66faf68573597ac191eaecb3581e32c5c9f871d152078", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3f9bc3e2e3dd0ea46ef5483c0d675cee105cfaeed20154c6f2e22e02b8a32ae518820f6fefc8138d03aab109dcf6fab5c7a31fa1dbd30cd715a55b85d7feef34"} 09:09:49 INFO - Running post-run listener: copy_logs_to_upload_dir 09:09:49 INFO - Copying logs to upload dir... 09:09:49 INFO - mkdir: /builds/slave/test/build/upload/logs 09:09:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1284.321332 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-03-18 09:09:49.883912) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-18 09:09:49.887652) ========= 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.QUFyuXtZLe/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.JOAzCl7rj8/Listeners TMPDIR=/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/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/035dba2e9160df1613bec78dd1dd4b6c933ed9b97fca050c962f5ffba0ffb7e2ed1ed8344718ff32c61b3bbfb7bdffca584b6a3c2a1e84f63a1ab40cdcb85635", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1c5209f18af097a36d5d8a20c6b48cca975c518473c5b1e2501107bfe7ebd491047d34a73b34aa00383bc63b8f2947f630a747226e179683799b0312b44a3dbd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e326969436292695c4339eab7cba7d0ad94e363d1d14883a36451d18dd57971df02d903645c3f8d12dc66faf68573597ac191eaecb3581e32c5c9f871d152078", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3f9bc3e2e3dd0ea46ef5483c0d675cee105cfaeed20154c6f2e22e02b8a32ae518820f6fefc8138d03aab109dcf6fab5c7a31fa1dbd30cd715a55b85d7feef34"} build_url:https://queue.taskcluster.net/v1/task/J1RTZ3gRTbq94aaP6Xz3HA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011072 build_url: 'https://queue.taskcluster.net/v1/task/J1RTZ3gRTbq94aaP6Xz3HA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/035dba2e9160df1613bec78dd1dd4b6c933ed9b97fca050c962f5ffba0ffb7e2ed1ed8344718ff32c61b3bbfb7bdffca584b6a3c2a1e84f63a1ab40cdcb85635", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1c5209f18af097a36d5d8a20c6b48cca975c518473c5b1e2501107bfe7ebd491047d34a73b34aa00383bc63b8f2947f630a747226e179683799b0312b44a3dbd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e326969436292695c4339eab7cba7d0ad94e363d1d14883a36451d18dd57971df02d903645c3f8d12dc66faf68573597ac191eaecb3581e32c5c9f871d152078", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3f9bc3e2e3dd0ea46ef5483c0d675cee105cfaeed20154c6f2e22e02b8a32ae518820f6fefc8138d03aab109dcf6fab5c7a31fa1dbd30cd715a55b85d7feef34"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-18 09:09:49.927432) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-18 09:09:49.928181) ========= 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.QUFyuXtZLe/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.JOAzCl7rj8/Listeners TMPDIR=/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/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.005268 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-18 09:09:49.978140) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-18 09:09:49.978487) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-18 09:09:49.978837) ========= ========= Total master_lag: 0.20 =========