builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0029 starttime: 1487276320.3 results: success (0) buildid: 20170216103919 builduid: c14fa89dade64009b6127a175e9bedf7 revision: 197dde7656f8d4b8f136233a664891ba8d0b150a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-16 12:18:40.299795) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-16 12:18:40.300256) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-16 12:18:40.300535) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-16 12:18:40.325599) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-16 12:18:40.326093) ========= 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.gV7bdHGAYL/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.F1rQ6eraSl/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-02-16 12:18:40-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 10.0M=0.001s 2017-02-16 12:18:40 (10.0 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.102346 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-16 12:18:40.451968) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-16 12:18:40.452318) ========= 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.gV7bdHGAYL/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.F1rQ6eraSl/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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.021171 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-16 12:18:40.500886) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-16 12:18:40.501245) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 197dde7656f8d4b8f136233a664891ba8d0b150a --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 197dde7656f8d4b8f136233a664891ba8d0b150a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.gV7bdHGAYL/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.F1rQ6eraSl/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-02-16 12:18:40,586 truncating revision to first 12 chars 2017-02-16 12:18:40,586 Setting DEBUG logging. 2017-02-16 12:18:40,586 attempt 1/10 2017-02-16 12:18:40,586 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/197dde7656f8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-16 12:18:41,660 unpacking tar archive at: mozilla-aurora-197dde7656f8/testing/mozharness/ program finished with exit code 0 elapsedTime=1.330012 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-16 12:18:41.854103) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-16 12:18:41.854447) ========= script_repo_revision: 197dde7656f8d4b8f136233a664891ba8d0b150a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-16 12:18:41.854866) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-16 12:18:41.855140) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-16 12:18:41.874953) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2017-02-16 12:18:41.875315) ========= /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.gV7bdHGAYL/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.F1rQ6eraSl/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 12:18:42 INFO - MultiFileLogger online at 20170216 12:18:42 in /builds/slave/test 12:18:42 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 12:18:42 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:18:42 INFO - {'append_to_log': False, 12:18:42 INFO - 'base_work_dir': '/builds/slave/test', 12:18:42 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 12:18:42 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:18:42 INFO - 'branch': 'Mozilla-Aurora', 12:18:42 INFO - 'buildbot_json_path': 'buildprops.json', 12:18:42 INFO - 'config_files': ('talos/mac_config.py',), 12:18:42 INFO - 'default_actions': ('clobber', 12:18:42 INFO - 'read-buildbot-config', 12:18:42 INFO - 'download-and-extract', 12:18:42 INFO - 'populate-webroot', 12:18:42 INFO - 'create-virtualenv', 12:18:42 INFO - 'install', 12:18:42 INFO - 'run-tests'), 12:18:42 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:18:42 INFO - 'download_minidump_stackwalk': True, 12:18:42 INFO - 'download_symbols': 'ondemand', 12:18:42 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:18:42 INFO - 'tooltool.py': '/tools/tooltool.py', 12:18:42 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:18:42 INFO - '/tools/misc-python/virtualenv.py')}, 12:18:42 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:18:42 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:18:42 INFO - 'installer_path': 'installer.exe', 12:18:42 INFO - 'log_level': 'info', 12:18:42 INFO - 'log_name': 'talos', 12:18:42 INFO - 'log_to_console': True, 12:18:42 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:18:42 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:18:42 INFO - 'opt_config_files': (), 12:18:42 INFO - 'pip_index': False, 12:18:42 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:18:42 INFO - 'cmd': ('bash', 12:18:42 INFO - '-c', 12:18:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:18:42 INFO - 'enabled': True, 12:18:42 INFO - 'halt_on_failure': False, 12:18:42 INFO - 'name': 'check_screen_resolution'},), 12:18:42 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:18:42 INFO - 'cmd': ('bash', 12:18:42 INFO - '-c', 12:18:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:18:42 INFO - 'enabled': True, 12:18:42 INFO - 'halt_on_failure': False, 12:18:42 INFO - 'name': 'check_screen_resolution'},), 12:18:42 INFO - 'run_cmd_checks_enabled': True, 12:18:42 INFO - 'sps_profile': False, 12:18:42 INFO - 'sps_profile_interval': 0, 12:18:42 INFO - 'suite': 'dromaeojs-e10s', 12:18:42 INFO - 'system_bits': '32', 12:18:42 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:18:42 INFO - 'title': 't-yosemite-r7-0029', 12:18:42 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:18:42 INFO - 'use_talos_json': True, 12:18:42 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:18:42 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:18:42 INFO - 'work_dir': 'build'} 12:18:42 INFO - [mozharness: 2017-02-16 20:18:42.082650Z] Running clobber step. 12:18:42 INFO - Running pre-action listener: _resource_record_pre_action 12:18:42 INFO - Running main action method: clobber 12:18:42 INFO - rmtree: /builds/slave/test/build 12:18:42 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:18:45 INFO - Running post-action listener: _resource_record_post_action 12:18:45 INFO - [mozharness: 2017-02-16 20:18:45.071232Z] Finished clobber step (success) 12:18:45 INFO - [mozharness: 2017-02-16 20:18:45.071427Z] Running read-buildbot-config step. 12:18:45 INFO - Running pre-action listener: _resource_record_pre_action 12:18:45 INFO - Running main action method: read_buildbot_config 12:18:45 INFO - Using buildbot properties: 12:18:45 INFO - { 12:18:45 INFO - "project": "", 12:18:45 INFO - "product": "firefox", 12:18:45 INFO - "script_repo_revision": "production", 12:18:45 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 12:18:45 INFO - "repository": "", 12:18:45 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 12:18:45 INFO - "buildid": "20170216103919", 12:18:45 INFO - "pgo_build": "False", 12:18:45 INFO - "basedir": "/builds/slave/test", 12:18:45 INFO - "buildnumber": 64, 12:18:45 INFO - "slavename": "t-yosemite-r7-0029", 12:18:45 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 12:18:45 INFO - "platform": "yosemite_r7", 12:18:45 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 12:18:45 INFO - "branch": "mozilla-aurora", 12:18:45 INFO - "repo_path": "releases/mozilla-aurora", 12:18:45 INFO - "revision": "197dde7656f8d4b8f136233a664891ba8d0b150a", 12:18:45 INFO - "stage_platform": "macosx64", 12:18:45 INFO - "builduid": "c14fa89dade64009b6127a175e9bedf7", 12:18:45 INFO - "slavebuilddir": "test" 12:18:45 INFO - } 12:18:45 INFO - Found installer url https://queue.taskcluster.net/v1/task/eVX5LB1ZQ-eIxI4EcNF3hg/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg. 12:18:45 INFO - Running post-action listener: _resource_record_post_action 12:18:45 INFO - [mozharness: 2017-02-16 20:18:45.074787Z] Finished read-buildbot-config step (success) 12:18:45 INFO - [mozharness: 2017-02-16 20:18:45.074894Z] Running download-and-extract step. 12:18:45 INFO - Running pre-action listener: _resource_record_pre_action 12:18:45 INFO - Running main action method: download_and_extract 12:18:45 INFO - mkdir: /builds/slave/test/build/tests 12:18:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:18:45 INFO - trying https://queue.taskcluster.net/v1/task/eVX5LB1ZQ-eIxI4EcNF3hg/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json 12:18:45 INFO - Downloading https://queue.taskcluster.net/v1/task/eVX5LB1ZQ-eIxI4EcNF3hg/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 12:18:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eVX5LB1ZQ-eIxI4EcNF3hg/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json'}, attempt #1 12:18:46 INFO - Downloaded 1199 bytes. 12:18:46 INFO - Reading from file /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 12:18:46 INFO - Using the following test package requirements: 12:18:46 INFO - {u'common': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 12:18:46 INFO - u'cppunittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 12:18:46 INFO - u'firefox-53.0a2.en-US.mac.cppunittest.tests.zip'], 12:18:46 INFO - u'gtest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 12:18:46 INFO - u'firefox-53.0a2.en-US.mac.gtest.tests.zip'], 12:18:46 INFO - u'jittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 12:18:46 INFO - u'jsshell-mac.zip'], 12:18:46 INFO - u'mochitest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 12:18:46 INFO - u'firefox-53.0a2.en-US.mac.mochitest.tests.zip'], 12:18:46 INFO - u'mozbase': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 12:18:46 INFO - u'reftest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 12:18:46 INFO - u'firefox-53.0a2.en-US.mac.reftest.tests.zip'], 12:18:46 INFO - u'talos': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 12:18:46 INFO - u'firefox-53.0a2.en-US.mac.talos.tests.zip'], 12:18:46 INFO - u'web-platform': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 12:18:46 INFO - u'firefox-53.0a2.en-US.mac.web-platform.tests.zip'], 12:18:46 INFO - u'xpcshell': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 12:18:46 INFO - u'firefox-53.0a2.en-US.mac.xpcshell.tests.zip']} 12:18:46 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip'] for test suite category: common 12:18:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eVX5LB1ZQ-eIxI4EcNF3hg/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 12:18:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eVX5LB1ZQ-eIxI4EcNF3hg/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 12:18:46 INFO - Fetch https://queue.taskcluster.net/v1/task/eVX5LB1ZQ-eIxI4EcNF3hg/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 12:18:47 INFO - Expected file size: 16358528 12:18:47 INFO - Obtained file size: 16358528 12:18:54 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip', u'firefox-53.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 12:18:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eVX5LB1ZQ-eIxI4EcNF3hg/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 12:18:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eVX5LB1ZQ-eIxI4EcNF3hg/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 12:18:54 INFO - Fetch https://queue.taskcluster.net/v1/task/eVX5LB1ZQ-eIxI4EcNF3hg/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 12:18:55 INFO - Expected file size: 16358528 12:18:55 INFO - Obtained file size: 16358528 12:19:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eVX5LB1ZQ-eIxI4EcNF3hg/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip 12:19:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eVX5LB1ZQ-eIxI4EcNF3hg/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip'}, attempt #1 12:19:01 INFO - Fetch https://queue.taskcluster.net/v1/task/eVX5LB1ZQ-eIxI4EcNF3hg/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip into memory 12:19:02 INFO - Expected file size: 14403485 12:19:02 INFO - Obtained file size: 14403485 12:19: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')]} 12:19:02 INFO - trying https://queue.taskcluster.net/v1/task/eVX5LB1ZQ-eIxI4EcNF3hg/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 12:19:02 INFO - Downloading https://queue.taskcluster.net/v1/task/eVX5LB1ZQ-eIxI4EcNF3hg/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg 12:19:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eVX5LB1ZQ-eIxI4EcNF3hg/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg'}, attempt #1 12:19:04 INFO - Downloaded 73945883 bytes. 12:19:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/eVX5LB1ZQ-eIxI4EcNF3hg/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 12:19:04 INFO - mkdir: /builds/slave/test/properties 12:19:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:19:04 INFO - Writing to file /builds/slave/test/properties/build_url 12:19:04 INFO - Contents: 12:19:04 INFO - build_url:https://queue.taskcluster.net/v1/task/eVX5LB1ZQ-eIxI4EcNF3hg/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 12:19:05 INFO - Running post-action listener: _resource_record_post_action 12:19:05 INFO - Running post-action listener: set_extra_try_arguments 12:19:05 INFO - [mozharness: 2017-02-16 20:19:05.605562Z] Finished download-and-extract step (success) 12:19:05 INFO - [mozharness: 2017-02-16 20:19:05.605712Z] Running populate-webroot step. 12:19:05 INFO - Running pre-action listener: _resource_record_pre_action 12:19:05 INFO - Running main action method: populate_webroot 12:19:05 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:19:05 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:19:05 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:19:05 INFO - '--tppagecycles', 12:19:05 INFO - '7'], 12:19:05 INFO - 'tests': ['tsvgm']}}, 12:19:05 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:19:05 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:19:05 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:19:05 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:19:05 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:19:05 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:19:05 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 12:19:05 INFO - 'talos_options': ['--disable-e10s'], 12:19:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:19:05 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:19:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:19:05 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 12:19:05 INFO - 'talos_options': ['--disable-e10s'], 12:19:05 INFO - 'tests': ['damp', 'tps']}, 12:19:05 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:19:05 INFO - 'tests': ['damp', 'tps']}, 12:19:05 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:19:05 INFO - 'tests': ['dromaeo_dom']}, 12:19:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:19:05 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:19:05 INFO - 'tests': ['basic_compositor_video']}, 12:19:05 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:19:05 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:19:05 INFO - 'tests': ['a11yr', 12:19:05 INFO - 'ts_paint', 12:19:05 INFO - 'tpaint', 12:19:05 INFO - 'sessionrestore', 12:19:05 INFO - 'sessionrestore_no_auto_restore', 12:19:05 INFO - 'tabpaint']}, 12:19:05 INFO - 'other-e10s': {'tests': ['a11yr', 12:19:05 INFO - 'ts_paint', 12:19:05 INFO - 'tpaint', 12:19:05 INFO - 'sessionrestore', 12:19:05 INFO - 'sessionrestore_no_auto_restore', 12:19:05 INFO - 'tabpaint']}, 12:19:05 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:19:05 INFO - 'ts_paint', 12:19:05 INFO - 'tpaint', 12:19:05 INFO - 'sessionrestore', 12:19:05 INFO - 'sessionrestore_no_auto_restore', 12:19:05 INFO - 'tabpaint']}, 12:19:05 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:19:05 INFO - 'ts_paint', 12:19:05 INFO - 'tpaint', 12:19:05 INFO - 'sessionrestore', 12:19:05 INFO - 'sessionrestore_no_auto_restore', 12:19:05 INFO - 'tabpaint']}, 12:19:05 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:19:05 INFO - 'tests': ['a11yr', 12:19:05 INFO - 'ts_paint', 12:19:05 INFO - 'tpaint', 12:19:05 INFO - 'sessionrestore', 12:19:05 INFO - 'sessionrestore_no_auto_restore', 12:19:05 INFO - 'tabpaint']}, 12:19:05 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:19:05 INFO - 'tests': ['a11yr', 12:19:05 INFO - 'ts_paint', 12:19:05 INFO - 'tpaint', 12:19:05 INFO - 'sessionrestore', 12:19:05 INFO - 'sessionrestore_no_auto_restore', 12:19:05 INFO - 'tabpaint']}, 12:19:05 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:19:05 INFO - 'tests': ['tsvgx', 12:19:05 INFO - 'tsvgr_opacity', 12:19:05 INFO - 'tart', 12:19:05 INFO - 'tscrollx', 12:19:05 INFO - 'cart', 12:19:05 INFO - 'tsvg_static']}, 12:19:05 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:19:05 INFO - 'tsvgr_opacity', 12:19:05 INFO - 'tart', 12:19:05 INFO - 'tscrollx', 12:19:05 INFO - 'cart', 12:19:05 INFO - 'tsvg_static']}, 12:19:05 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 12:19:05 INFO - 'talos_options': ['--disable-e10s'], 12:19:05 INFO - 'tests': ['tp5o']}, 12:19:05 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:19:05 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 12:19:05 INFO - 'talos_options': ['--disable-e10s', 12:19:05 INFO - '--xperf_path', 12:19:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:19:05 INFO - 'tests': ['tp5n']}, 12:19:05 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:19:05 INFO - 'talos_options': ['--xperf_path', 12:19:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:19:05 INFO - 'tests': ['tp5n']}}, 12:19:05 INFO - 'talos.zip': {'path': '', 12:19:05 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:19:05 INFO - Running post-action listener: _resource_record_post_action 12:19:05 INFO - [mozharness: 2017-02-16 20:19:05.623928Z] Finished populate-webroot step (success) 12:19:05 INFO - [mozharness: 2017-02-16 20:19:05.624070Z] Running create-virtualenv step. 12:19:05 INFO - Running pre-action listener: _resource_record_pre_action 12:19:05 INFO - Running main action method: create_virtualenv 12:19:05 INFO - Creating virtualenv /builds/slave/test/build/venv 12:19:05 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 12:19:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:19:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:19:05 INFO - Using real prefix '/tools/python27' 12:19:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:19:06 INFO - Installing distribute.............................................................................................................................................................................................done. 12:19:09 INFO - Installing pip.................done. 12:19:09 INFO - Return code: 0 12:19:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:19:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:19:09 INFO - Reading from file tmpfile_stdout 12:19:09 INFO - Output received: 12:19:09 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) 12:19:09 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:19:09 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')]} 12:19:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:19:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:19:09 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')]} 12:19:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:19:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:19:09 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 0x10e065b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e068768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa9159a730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de43d30>, '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 0x10dd6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de38be8>, '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.gV7bdHGAYL/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.F1rQ6eraSl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 12:19:09 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 12:19:09 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 12:19:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gV7bdHGAYL/Render', 12:19:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:19:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:19:09 INFO - 'HOME': '/Users/cltbld', 12:19:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:19:09 INFO - 'LOGNAME': 'cltbld', 12:19:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:19:09 INFO - 'MOZ_NO_REMOTE': '1', 12:19:09 INFO - 'NO_EM_RESTART': '1', 12:19:09 INFO - 'PAGER': '/bin/cat', 12:19:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:19:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:19:09 INFO - 'PWD': '/builds/slave/test', 12:19:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:19:09 INFO - 'SHELL': '/bin/bash', 12:19:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F1rQ6eraSl/Listeners', 12:19:09 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 12:19:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:19:09 INFO - 'USER': 'cltbld', 12:19:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:19:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:19:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:19:09 INFO - 'XPC_FLAGS': '0x0', 12:19:09 INFO - 'XPC_SERVICE_NAME': '0', 12:19:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:19:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:19:09 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 12:19:09 INFO - Cleaning up... 12:19:09 INFO - Return code: 0 12:19:09 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:19:09 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')]} 12:19:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:19:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:19:09 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')]} 12:19:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:19:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:19:09 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 0x10e065b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e068768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa9159a730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de43d30>, '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 0x10dd6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de38be8>, '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.gV7bdHGAYL/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.F1rQ6eraSl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 12:19:09 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 12:19:09 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 12:19:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gV7bdHGAYL/Render', 12:19:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:19:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:19:09 INFO - 'HOME': '/Users/cltbld', 12:19:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:19:09 INFO - 'LOGNAME': 'cltbld', 12:19:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:19:09 INFO - 'MOZ_NO_REMOTE': '1', 12:19:09 INFO - 'NO_EM_RESTART': '1', 12:19:09 INFO - 'PAGER': '/bin/cat', 12:19:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:19:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:19:09 INFO - 'PWD': '/builds/slave/test', 12:19:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:19:09 INFO - 'SHELL': '/bin/bash', 12:19:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F1rQ6eraSl/Listeners', 12:19:09 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 12:19:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:19:09 INFO - 'USER': 'cltbld', 12:19:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:19:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:19:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:19:09 INFO - 'XPC_FLAGS': '0x0', 12:19:09 INFO - 'XPC_SERVICE_NAME': '0', 12:19:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:19:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:19:10 INFO - Downloading/unpacking psutil>=3.1.1 12:19:10 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 12:19:10 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 12:19:10 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 12:19:10 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 12:19:10 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 12:19:10 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 12:19:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:19:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:19:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:19:13 INFO - Installing collected packages: psutil 12:19:13 INFO - Running setup.py install for psutil 12:19:13 INFO - building 'psutil._psutil_osx' extension 12:19:13 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 12:19:13 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 12:19:13 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 12:19:14 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 12:19:14 INFO - building 'psutil._psutil_posix' extension 12:19:14 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 12:19:14 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:19:14 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:19:14 INFO - ^ 12:19:14 INFO - 1 warning generated. 12:19:14 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 12:19:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:19:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:19:14 INFO - Successfully installed psutil 12:19:14 INFO - Cleaning up... 12:19:14 INFO - Return code: 0 12:19:14 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:19:14 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')]} 12:19:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:19:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:19:14 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')]} 12:19:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:19:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:19:14 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 0x10e065b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e068768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa9159a730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de43d30>, '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 0x10dd6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de38be8>, '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.gV7bdHGAYL/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.F1rQ6eraSl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 12:19:14 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 12:19:14 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 12:19:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gV7bdHGAYL/Render', 12:19:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:19:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:19:14 INFO - 'HOME': '/Users/cltbld', 12:19:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:19:14 INFO - 'LOGNAME': 'cltbld', 12:19:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:19:14 INFO - 'MOZ_NO_REMOTE': '1', 12:19:14 INFO - 'NO_EM_RESTART': '1', 12:19:14 INFO - 'PAGER': '/bin/cat', 12:19:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:19:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:19:14 INFO - 'PWD': '/builds/slave/test', 12:19:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:19:14 INFO - 'SHELL': '/bin/bash', 12:19:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F1rQ6eraSl/Listeners', 12:19:14 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 12:19:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:19:14 INFO - 'USER': 'cltbld', 12:19:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:19:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:19:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:19:14 INFO - 'XPC_FLAGS': '0x0', 12:19:14 INFO - 'XPC_SERVICE_NAME': '0', 12:19:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:19:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:19:14 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:19:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:19:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:19:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:19:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:19:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:19:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:19:17 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:19:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:19:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:19:17 INFO - Installing collected packages: mozsystemmonitor 12:19:17 INFO - Running setup.py install for mozsystemmonitor 12:19:17 INFO - Successfully installed mozsystemmonitor 12:19:17 INFO - Cleaning up... 12:19:17 INFO - Return code: 0 12:19:17 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:19: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')]} 12:19:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:19:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:19: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')]} 12:19:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:19:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:19: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', '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 0x10e065b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e068768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa9159a730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de43d30>, '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 0x10dd6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de38be8>, '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.gV7bdHGAYL/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.F1rQ6eraSl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 12:19: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 12:19: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 jsonschema==2.5.1 12:19:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gV7bdHGAYL/Render', 12:19:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:19:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:19:17 INFO - 'HOME': '/Users/cltbld', 12:19:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:19:17 INFO - 'LOGNAME': 'cltbld', 12:19:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:19:17 INFO - 'MOZ_NO_REMOTE': '1', 12:19:17 INFO - 'NO_EM_RESTART': '1', 12:19:17 INFO - 'PAGER': '/bin/cat', 12:19:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:19:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:19:17 INFO - 'PWD': '/builds/slave/test', 12:19:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:19:17 INFO - 'SHELL': '/bin/bash', 12:19:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F1rQ6eraSl/Listeners', 12:19:17 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 12:19:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:19:17 INFO - 'USER': 'cltbld', 12:19:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:19:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:19:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:19:17 INFO - 'XPC_FLAGS': '0x0', 12:19:17 INFO - 'XPC_SERVICE_NAME': '0', 12:19:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:19:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:19:17 INFO - Downloading/unpacking jsonschema==2.5.1 12:19: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 12:19: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 12:19: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 12:19: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 12:19: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 12:19: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 12:19:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:19:20 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:19:20 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 12:19:20 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 12:19:20 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 12:19:20 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 12:19:20 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 12:19:20 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 12:19:20 INFO - Downloading functools32-3.2.3-2.tar.gz 12:19:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:19:21 INFO - warning: no files found matching '*.txt' 12:19:21 INFO - no previously-included directories found matching 'build' 12:19:21 INFO - no previously-included directories found matching 'dist' 12:19:21 INFO - no previously-included directories found matching '.git*' 12:19:21 INFO - Installing collected packages: jsonschema, functools32 12:19:21 INFO - Running setup.py install for functools32 12:19:21 INFO - warning: no files found matching '*.txt' 12:19:21 INFO - no previously-included directories found matching 'build' 12:19:21 INFO - no previously-included directories found matching 'dist' 12:19:21 INFO - no previously-included directories found matching '.git*' 12:19:21 INFO - Successfully installed jsonschema functools32 12:19:21 INFO - Cleaning up... 12:19:21 INFO - Return code: 0 12:19:21 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:19:21 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')]} 12:19:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:19:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:19:21 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')]} 12:19:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:19:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:19: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', '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 0x10e065b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e068768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa9159a730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de43d30>, '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 0x10dd6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de38be8>, '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.gV7bdHGAYL/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.F1rQ6eraSl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 12:19: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 12:19: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 functools32==3.2.3-2 12:19:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gV7bdHGAYL/Render', 12:19:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:19:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:19:21 INFO - 'HOME': '/Users/cltbld', 12:19:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:19:21 INFO - 'LOGNAME': 'cltbld', 12:19:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:19:21 INFO - 'MOZ_NO_REMOTE': '1', 12:19:21 INFO - 'NO_EM_RESTART': '1', 12:19:21 INFO - 'PAGER': '/bin/cat', 12:19:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:19:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:19:21 INFO - 'PWD': '/builds/slave/test', 12:19:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:19:21 INFO - 'SHELL': '/bin/bash', 12:19:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F1rQ6eraSl/Listeners', 12:19:21 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 12:19:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:19:21 INFO - 'USER': 'cltbld', 12:19:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:19:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:19:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:19:21 INFO - 'XPC_FLAGS': '0x0', 12:19:21 INFO - 'XPC_SERVICE_NAME': '0', 12:19:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:19:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:19:21 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:19:21 INFO - Cleaning up... 12:19:21 INFO - Return code: 0 12:19:21 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:19:21 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')]} 12:19:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:19:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:19:21 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')]} 12:19:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:19:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:19: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', '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 0x10e065b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e068768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa9159a730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de43d30>, '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 0x10dd6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de38be8>, '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.gV7bdHGAYL/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.F1rQ6eraSl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 12:19: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:19: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 blobuploader==1.2.4 12:19:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gV7bdHGAYL/Render', 12:19:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:19:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:19:21 INFO - 'HOME': '/Users/cltbld', 12:19:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:19:21 INFO - 'LOGNAME': 'cltbld', 12:19:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:19:21 INFO - 'MOZ_NO_REMOTE': '1', 12:19:21 INFO - 'NO_EM_RESTART': '1', 12:19:21 INFO - 'PAGER': '/bin/cat', 12:19:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:19:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:19:21 INFO - 'PWD': '/builds/slave/test', 12:19:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:19:21 INFO - 'SHELL': '/bin/bash', 12:19:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F1rQ6eraSl/Listeners', 12:19:21 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 12:19:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:19:21 INFO - 'USER': 'cltbld', 12:19:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:19:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:19:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:19:21 INFO - 'XPC_FLAGS': '0x0', 12:19:21 INFO - 'XPC_SERVICE_NAME': '0', 12:19:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:19:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:19:21 INFO - Downloading/unpacking blobuploader==1.2.4 12:19: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 12:19: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 12:19: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 12:19: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 12:19: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 12:19: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 12:19:24 INFO - Downloading blobuploader-1.2.4.tar.gz 12:19:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:19:24 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:19: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 12:19: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 12:19: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 12:19: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 12:19: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 12:19: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 12:19:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:19:25 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:19: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 12:19: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 12:19: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 12:19: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 12:19: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 12:19: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 12:19:25 INFO - Downloading docopt-0.6.1.tar.gz 12:19:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:19:25 INFO - Installing collected packages: blobuploader, requests, docopt 12:19:25 INFO - Running setup.py install for blobuploader 12:19:25 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:19:26 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:19:26 INFO - Running setup.py install for requests 12:19:26 INFO - Running setup.py install for docopt 12:19:26 INFO - Successfully installed blobuploader requests docopt 12:19:26 INFO - Cleaning up... 12:19:26 INFO - Return code: 0 12:19:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:19:26 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')]} 12:19:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:19:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:19:26 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')]} 12:19:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:19:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:19:26 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 0x10e065b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e068768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa9159a730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de43d30>, '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 0x10dd6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de38be8>, '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.gV7bdHGAYL/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.F1rQ6eraSl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 12:19:26 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 12:19:26 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 12:19:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gV7bdHGAYL/Render', 12:19:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:19:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:19:26 INFO - 'HOME': '/Users/cltbld', 12:19:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:19:26 INFO - 'LOGNAME': 'cltbld', 12:19:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:19:26 INFO - 'MOZ_NO_REMOTE': '1', 12:19:26 INFO - 'NO_EM_RESTART': '1', 12:19:26 INFO - 'PAGER': '/bin/cat', 12:19:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:19:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:19:26 INFO - 'PWD': '/builds/slave/test', 12:19:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:19:26 INFO - 'SHELL': '/bin/bash', 12:19:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F1rQ6eraSl/Listeners', 12:19:26 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 12:19:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:19:26 INFO - 'USER': 'cltbld', 12:19:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:19:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:19:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:19:26 INFO - 'XPC_FLAGS': '0x0', 12:19:26 INFO - 'XPC_SERVICE_NAME': '0', 12:19:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:19:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:19:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:19:27 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-RwdZc2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:19:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:19:27 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-8yowjz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:19:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:19:27 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-DUSOUk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:19:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:19:27 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-qKTzpT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:19:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:19:27 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-9cNOYH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:19:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:19:27 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-PuDzJt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:19:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:19:27 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-NUDBsI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:19:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:19:27 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-K0REIE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:19:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:19:27 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-hVaets-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:19:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:19:27 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-ZNVlYo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:19:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:19:28 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-xt2Few-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:19:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:19:28 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-LAwkT3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:19:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:19:28 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-VQ32pI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:19:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:19:28 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-RJjH3j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:19:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:19:28 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-bavJAz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:19:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:19:28 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-ES2j6c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:19:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:19:28 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-W_losm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:19:28 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:19:28 INFO - Running setup.py install for manifestparser 12:19:28 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:19:28 INFO - Running setup.py install for mozcrash 12:19:29 INFO - Running setup.py install for mozdebug 12:19:29 INFO - Running setup.py install for mozdevice 12:19:29 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:19:29 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:19:29 INFO - Running setup.py install for mozfile 12:19:29 INFO - Running setup.py install for mozhttpd 12:19:29 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:19:29 INFO - Running setup.py install for mozinfo 12:19:29 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:19:29 INFO - Running setup.py install for mozInstall 12:19:29 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:19:29 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:19:29 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:19:29 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:19:29 INFO - Running setup.py install for mozleak 12:19:30 INFO - Running setup.py install for mozlog 12:19:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:19:30 INFO - Running setup.py install for moznetwork 12:19:30 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:19:30 INFO - Running setup.py install for mozprocess 12:19:30 INFO - Running setup.py install for mozprofile 12:19:30 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:19:30 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:19:30 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:19:30 INFO - Running setup.py install for mozrunner 12:19:30 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:19:30 INFO - Running setup.py install for mozscreenshot 12:19:31 INFO - Running setup.py install for moztest 12:19:31 INFO - Running setup.py install for mozversion 12:19:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:19:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:19:31 INFO - Cleaning up... 12:19:31 INFO - Return code: 0 12:19:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:19:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:19:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:19:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:19:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:19:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:19:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:19:31 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 0x10e065b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e068768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa9159a730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de43d30>, '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 0x10dd6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de38be8>, '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.gV7bdHGAYL/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.F1rQ6eraSl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 12:19:31 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 12:19:31 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 12:19:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gV7bdHGAYL/Render', 12:19:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:19:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:19:31 INFO - 'HOME': '/Users/cltbld', 12:19:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:19:31 INFO - 'LOGNAME': 'cltbld', 12:19:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:19:31 INFO - 'MOZ_NO_REMOTE': '1', 12:19:31 INFO - 'NO_EM_RESTART': '1', 12:19:31 INFO - 'PAGER': '/bin/cat', 12:19:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:19:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:19:31 INFO - 'PWD': '/builds/slave/test', 12:19:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:19:31 INFO - 'SHELL': '/bin/bash', 12:19:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F1rQ6eraSl/Listeners', 12:19:31 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 12:19:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:19:31 INFO - 'USER': 'cltbld', 12:19:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:19:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:19:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:19:31 INFO - 'XPC_FLAGS': '0x0', 12:19:31 INFO - 'XPC_SERVICE_NAME': '0', 12:19:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:19:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:19:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:19:31 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-m0259j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:19:31 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)) 12:19:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:19:31 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-wBWF08-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:19:31 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)) 12:19:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:19:31 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-94_OHo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:19:31 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)) 12:19:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:19:32 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-6Htppv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:19:32 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)) 12:19:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:19:32 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-82dtxd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:19:32 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)) 12:19:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:19:32 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-Mrv284-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:19:32 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)) 12:19:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:19:32 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-ZWXZCe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:19:32 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)) 12:19:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:19:32 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-R_7ArY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:19:32 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)) 12:19:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:19:32 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-b6mV2v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:19:32 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)) 12:19:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:19:32 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-pKanW8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:19:32 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)) 12:19:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:19:32 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-etlxoD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:19:32 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)) 12:19:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:19:32 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-pY6Wwr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:19:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 12:19:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:19:32 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-Cvuisb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:19:33 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)) 12:19:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:19:33 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-v_wlPW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:19:33 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)) 12:19:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:19:33 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-Bl6AOT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:19:33 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)) 12:19:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:19:33 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-IDtnnx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:19:33 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)) 12:19:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:19:33 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-fFoSBv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:19:33 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)) 12:19:33 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)) 12:19:33 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)) 12:19:33 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)) 12:19:33 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)) 12:19:33 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)) 12:19:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:19:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:19:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:19:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:19:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:19:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:19:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:19:36 INFO - Downloading blessings-1.6.tar.gz 12:19:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:19:36 INFO - Installing collected packages: blessings 12:19:36 INFO - Running setup.py install for blessings 12:19:36 INFO - Successfully installed blessings 12:19:36 INFO - Cleaning up... 12:19:36 INFO - Return code: 0 12:19:36 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:19:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:19:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:19:36 INFO - Reading from file tmpfile_stdout 12:19:36 INFO - Current package versions: 12:19:36 INFO - blessings == 1.6 12:19:36 INFO - blobuploader == 1.2.4 12:19:36 INFO - docopt == 0.6.1 12:19:36 INFO - functools32 == 3.2.3-2 12:19:36 INFO - jsonschema == 2.5.1 12:19:36 INFO - manifestparser == 1.1 12:19:36 INFO - mozInstall == 1.12 12:19:36 INFO - mozcrash == 1.0 12:19:36 INFO - mozdebug == 0.1 12:19:36 INFO - mozdevice == 0.49 12:19:36 INFO - mozfile == 1.2 12:19:36 INFO - mozhttpd == 0.7 12:19:36 INFO - mozinfo == 0.9 12:19:36 INFO - mozleak == 0.1 12:19:36 INFO - mozlog == 3.4 12:19:36 INFO - moznetwork == 0.27 12:19:36 INFO - mozprocess == 0.24 12:19:36 INFO - mozprofile == 0.28 12:19:36 INFO - mozrunner == 6.13 12:19:36 INFO - mozscreenshot == 0.1 12:19:36 INFO - mozsystemmonitor == 0.3 12:19:36 INFO - moztest == 0.8 12:19:36 INFO - mozversion == 1.4 12:19:36 INFO - psutil == 3.1.1 12:19:36 INFO - requests == 1.2.3 12:19:36 INFO - wsgiref == 0.1.2 12:19:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:19:36 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')]} 12:19:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:19:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:19:37 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')]} 12:19:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:19:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:19:37 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 0x10e065b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e068768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa9159a730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de43d30>, '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 0x10dd6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de38be8>, '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.gV7bdHGAYL/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.F1rQ6eraSl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 12:19:37 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 12:19:37 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 12:19:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gV7bdHGAYL/Render', 12:19:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:19:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:19:37 INFO - 'HOME': '/Users/cltbld', 12:19:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:19:37 INFO - 'LOGNAME': 'cltbld', 12:19:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:19:37 INFO - 'MOZ_NO_REMOTE': '1', 12:19:37 INFO - 'NO_EM_RESTART': '1', 12:19:37 INFO - 'PAGER': '/bin/cat', 12:19:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:19:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:19:37 INFO - 'PWD': '/builds/slave/test', 12:19:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:19:37 INFO - 'SHELL': '/bin/bash', 12:19:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F1rQ6eraSl/Listeners', 12:19:37 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 12:19:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:19:37 INFO - 'USER': 'cltbld', 12:19:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:19:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:19:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:19:37 INFO - 'XPC_FLAGS': '0x0', 12:19:37 INFO - 'XPC_SERVICE_NAME': '0', 12:19:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:19:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:19:38 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)) 12:19:38 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)) 12:19:38 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)) 12:19:38 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)) 12:19:38 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)) 12:19:38 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)) 12:19:38 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)) 12:19:38 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)) 12:19:38 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)) 12:19:38 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)) 12:19:38 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)) 12:19:38 INFO - Cleaning up... 12:19:38 INFO - Return code: 0 12:19:38 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:19:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:19:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:19:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:19:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:19:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:19:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:19:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e065b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e068768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa9159a730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de43d30>, '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 0x10dd6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de38be8>, '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.gV7bdHGAYL/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.F1rQ6eraSl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 12:19:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 12:19:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 12:19:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gV7bdHGAYL/Render', 12:19:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:19:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:19:38 INFO - 'HOME': '/Users/cltbld', 12:19:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:19:38 INFO - 'LOGNAME': 'cltbld', 12:19:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:19:38 INFO - 'MOZ_NO_REMOTE': '1', 12:19:38 INFO - 'NO_EM_RESTART': '1', 12:19:38 INFO - 'PAGER': '/bin/cat', 12:19:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:19:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:19:38 INFO - 'PWD': '/builds/slave/test', 12:19:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:19:38 INFO - 'SHELL': '/bin/bash', 12:19:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F1rQ6eraSl/Listeners', 12:19:38 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 12:19:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:19:38 INFO - 'USER': 'cltbld', 12:19:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:19:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:19:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:19:38 INFO - 'XPC_FLAGS': '0x0', 12:19:38 INFO - 'XPC_SERVICE_NAME': '0', 12:19:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:19:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:19:38 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:19:38 INFO - Cleaning up... 12:19:38 INFO - Return code: 0 12:19:38 INFO - Running post-action listener: _resource_record_post_action 12:19:38 INFO - Running post-action listener: _start_resource_monitoring 12:19:38 INFO - Starting resource monitoring. 12:19:38 INFO - [mozharness: 2017-02-16 20:19:38.958354Z] Finished create-virtualenv step (success) 12:19:38 INFO - [mozharness: 2017-02-16 20:19:38.959338Z] Running install step. 12:19:38 INFO - Running pre-action listener: _resource_record_pre_action 12:19:38 INFO - Running main action method: install 12:19:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:19:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:19:39 INFO - Reading from file tmpfile_stdout 12:19:39 INFO - Detecting whether we're running mozinstall >=1.0... 12:19:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:19:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:19:39 INFO - Reading from file tmpfile_stdout 12:19:39 INFO - Output received: 12:19:39 INFO - Usage: mozinstall [options] installer 12:19:39 INFO - Options: 12:19:39 INFO - -h, --help show this help message and exit 12:19:39 INFO - -d DEST, --destination=DEST 12:19:39 INFO - Directory to install application into. [default: 12:19:39 INFO - "/builds/slave/test"] 12:19:39 INFO - --app=APP Application being installed. [default: firefox] 12:19:39 INFO - mkdir: /builds/slave/test/build/application 12:19:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 12:19:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 12:19:59 INFO - Reading from file tmpfile_stdout 12:19:59 INFO - Output received: 12:19:59 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 12:19:59 INFO - Running post-action listener: _resource_record_post_action 12:19:59 INFO - [mozharness: 2017-02-16 20:19:59.053758Z] Finished install step (success) 12:19:59 INFO - [mozharness: 2017-02-16 20:19:59.053911Z] Running run-tests step. 12:19:59 INFO - Running pre-action listener: _resource_record_pre_action 12:19:59 INFO - Running main action method: run_tests 12:19:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:19:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:19:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:19:59 INFO - 2017-02-16 12:19:59.068 screenresolution[1905:59227] starting screenresolution argv=screenresolution get 12:19:59 INFO - 2017-02-16 12:19:59.071 screenresolution[1905:59227] Display 0: 1600x1200x32@60 12:19:59 INFO - 2017-02-16 12:19:59.077 screenresolution[1906:59230] starting screenresolution argv=screenresolution list 12:19:59 INFO - Available Modes on Display 0 12:19:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:19:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:19:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:19:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:19:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:19:59 INFO - Intel Iris: 12:19:59 INFO - Chipset Model: Intel Iris 12:19:59 INFO - Type: GPU 12:19:59 INFO - Bus: Built-In 12:19:59 INFO - VRAM (Dynamic, Max): 1536 MB 12:19:59 INFO - Vendor: Intel (0x8086) 12:19:59 INFO - Device ID: 0x0a2e 12:19:59 INFO - Revision ID: 0x0009 12:19:59 INFO - Displays: 12:19:59 INFO - Display: 12:19:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:19:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:19:59 INFO - Display Serial Number: 1600x1200 60 12:19:59 INFO - Main Display: Yes 12:19:59 INFO - Mirror: Off 12:19:59 INFO - Online: Yes 12:19:59 INFO - Rotation: Supported 12:19:59 INFO - Return code: 0 12:19:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:19:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:19:59 INFO - Python 2.7.3 12:19:59 INFO - Return code: 0 12:19:59 INFO - grabbing minidump binary from tooltool 12:19:59 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')]} 12:19:59 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 0x10de43d30>, '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 0x10dd6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de38be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:19:59 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 12:19:59 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 12:19:59 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 12:19:59 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:19:59 INFO - Return code: 0 12:19:59 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:19:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:19:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:19:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:19:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:19:59 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/eVX5LB1ZQ-eIxI4EcNF3hg/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0029', '--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 12:19:59 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/eVX5LB1ZQ-eIxI4EcNF3hg/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0029 --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 12:19:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gV7bdHGAYL/Render', 12:19:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:19:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:19:59 INFO - 'HOME': '/Users/cltbld', 12:19:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:19:59 INFO - 'LOGNAME': 'cltbld', 12:19:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:19:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:19:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:19:59 INFO - 'MOZ_NO_REMOTE': '1', 12:19:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:19:59 INFO - 'NO_EM_RESTART': '1', 12:19:59 INFO - 'PAGER': '/bin/cat', 12:19:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:19:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:19:59 INFO - 'PWD': '/builds/slave/test', 12:19:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:19:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:19:59 INFO - 'SHELL': '/bin/bash', 12:19:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F1rQ6eraSl/Listeners', 12:19:59 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 12:19:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:19:59 INFO - 'USER': 'cltbld', 12:19:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:19:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:19:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:19:59 INFO - 'XPC_FLAGS': '0x0', 12:19:59 INFO - 'XPC_SERVICE_NAME': '0', 12:19:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:19:59 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/eVX5LB1ZQ-eIxI4EcNF3hg/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0029', '--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 12:19:59 INFO - mozversion application_buildid: 20170216103919 12:19:59 INFO - mozversion application_changeset: 197dde7656f8d4b8f136233a664891ba8d0b150a 12:19:59 INFO - mozversion application_display_name: FirefoxDeveloperEdition 12:19:59 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:19:59 INFO - mozversion application_name: Firefox 12:19:59 INFO - mozversion application_remotingname: firefox-dev 12:19:59 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 12:19:59 INFO - mozversion application_vendor: Mozilla 12:19:59 INFO - mozversion application_version: 53.0a2 12:19:59 INFO - mozversion platform_buildid: 20170216103919 12:19:59 INFO - mozversion platform_changeset: 197dde7656f8d4b8f136233a664891ba8d0b150a 12:19:59 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 12:19:59 INFO - mozversion platform_version: 53.0a2 12:19:59 INFO - using testdate: 1487276399 12:19:59 INFO - actual date: 1487276399 12:19:59 INFO - starting webserver on 'localhost:64206' 12:19:59 INFO - SUITE-START | Running 2 tests 12:19:59 INFO - TEST-START | dromaeo_css 12:19:59 INFO - operating with platform_type : mac_ 12:19:59 INFO - Initialising browser for dromaeo_css test... 12:19:59 INFO - TEST-INFO | started process 1916 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/tmpDK0GKF/profile http://localhost:64206/getInfo.html) 12:20:02 INFO - PROCESS | 1916 | 2017-02-16 12:20:02.395 plugin-container[1918:59401] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b4b, name = 'com.apple.tsm.portname' 12:20:02 INFO - PROCESS | 1916 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:20:02 INFO - PROCESS | 1916 | 2017-02-16 12:20:02.398 plugin-container[1918:59401] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a1f, name = 'com.apple.CFPasteboardClient' 12:20:02 INFO - PROCESS | 1916 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:20:02 INFO - PROCESS | 1916 | 2017-02-16 12:20:02.398 plugin-container[1918:59401] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:20:02 INFO - PROCESS | 1916 | __metrics Screen width/height:1600/1200 12:20:02 INFO - PROCESS | 1916 | colorDepth:24 12:20:02 INFO - PROCESS | 1916 | Browser inner width/height: 1024/663 12:20:02 INFO - PROCESS | 1916 | __metrics 12:20:02 ERROR - PROCESS | 1916 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 12:20:03 INFO - TEST-INFO | 1916: exit 0 12:20:03 INFO - Browser initialized. 12:20:03 INFO - Running cycle 1/1 for dromaeo_css test... 12:20:03 INFO - TEST-INFO | started process 1919 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/tmpDK0GKF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:20:05 INFO - PROCESS | 1919 | 2017-02-16 12:20:05.938 plugin-container[1920:59530] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 12:20:05 INFO - PROCESS | 1919 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:20:05 INFO - PROCESS | 1919 | 2017-02-16 12:20:05.939 plugin-container[1920:59530] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8947, name = 'com.apple.CFPasteboardClient' 12:20:05 INFO - PROCESS | 1919 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:20:05 INFO - PROCESS | 1919 | 2017-02-16 12:20:05.939 plugin-container[1920:59530] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:20:15 INFO - PROCESS | 1919 | RSS: Main: 229855232 12:20:15 INFO - PROCESS | 1919 | 12:23:18 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:64206/tests/dromaeo/cssquery-dojo.html (next: http://localhost:64206/tests/dromaeo/cssquery-ext.html) 12:23:20 INFO - PROCESS | 1919 | RSS: Main: 245321728 12:23:20 INFO - PROCESS | 1919 | 12:25:03 INFO - PROCESS | 1919 | ************************* 12:25:03 INFO - PROCESS | 1919 | A coding exception was thrown and uncaught in a Task. 12:25:03 INFO - PROCESS | 1919 | 12:25:03 ERROR - PROCESS | 1919 | Full message: TypeError: NetworkError when attempting to fetch resource. 12:25:03 INFO - PROCESS | 1919 | Full stack: 12:25:03 INFO - PROCESS | 1919 | ************************* 12:26:22 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:64206/tests/dromaeo/cssquery-ext.html (next: http://localhost:64206/tests/dromaeo/cssquery-jquery.html) 12:26:23 INFO - PROCESS | 1919 | RSS: Main: 252469248 12:26:23 INFO - PROCESS | 1919 | 12:29:25 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:64206/tests/dromaeo/cssquery-jquery.html (next: http://localhost:64206/tests/dromaeo/cssquery-mootools.html) 12:29:25 INFO - PROCESS | 1919 | RSS: Main: 258404352 12:29:25 INFO - PROCESS | 1919 | 12:32:27 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:64206/tests/dromaeo/cssquery-mootools.html (next: http://localhost:64206/tests/dromaeo/cssquery-prototype.html) 12:32:28 INFO - PROCESS | 1919 | RSS: Main: 254779392 12:32:28 INFO - PROCESS | 1919 | 12:35:31 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:64206/tests/dromaeo/cssquery-prototype.html (next: http://localhost:64206/tests/dromaeo/cssquery-yui.html) 12:35:31 INFO - PROCESS | 1919 | RSS: Main: 258842624 12:35:31 INFO - PROCESS | 1919 | 12:38:34 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:64206/tests/dromaeo/cssquery-yui.html (next: http://localhost:64206/tests/dromaeo/cssquery-dojo.html) 12:38:34 INFO - PROCESS | 1919 | RSS: Main: 260370432 12:38:34 INFO - PROCESS | 1919 | 12:38:34 INFO - PROCESS | 1919 | __start_tp_report 12:38:34 INFO - PROCESS | 1919 | _x_x_mozilla_page_load 12:38:34 INFO - PROCESS | 1919 | _x_x_mozilla_page_load_details 12:38:34 INFO - PROCESS | 1919 | |i|pagename|runs| 12:38:34 INFO - PROCESS | 1919 | |0;dojo.html;3327.783694059886;3405.421078416669;3419.5725534308212;3440.122768693983;3467.427874400724;5765.855853603657;5805.738741756615;5835.358110607835;5850.5612079094135;5864.677442740658;5825.8252252432385;5830.271216097988;5880.294364676245;5899.085641725539;5921.082232254007;5278.1291086970605;5282.550983166426;5307.575393968493;5355.705436201027;5364.624476286662;5258.079836028699;5264.657797243186;5328.733563321838;5368.1679339702405;5379.408265090837;5163.432022477525;5179.870503237412;5187.896242075175;5296.390915044781;5302.92045619316;5146.433892271847;5224.686518808883;5252.474752524717;5266.947330526684;5287.286216360781;5118.4625614310335;5182.119039763212;5187.870303242411;5222.4255331913455;5227.477252274742;4469.307257375112;4691.413573303337;5455.890882182334;5602.075657516505;5660.886782264372;5520.006398848206;6168;6175.536834737368;6181.629102253834;6234.657093859836;5960.612560183574;6293.590916590407;6299.842503937392;6310.274318453391;6312.715927783261;5767.8558036049435;5995.100734889801;5995.220621319244;5999;6002.8499287517725;6219.222597175353;6274.592151510137;6286.685665716695;6335.208098987627;6432.935670643327;6102.267727872595;6153.7230824613;6188.226471691039;6196.256449226122;6201.844953876189;6278.937210627927;6289.150964619809;6292.402221788922;6305.432511074026;6317.14718513004;6135.906433916272;6184.165137706442;6199.070139479114;6233.096201050913;6265.154204182377;6291.716872740738;6301.338359472281;6308.274548426898;6310.1796766420075;6336.968315158395;5030.446650868402;5039.218921067167;5042.092423363756;5048.1418158913075;5055.09008378488;4422.248217802981;4425.867223983286;4496.1906856765445;4506.616937560344;4533.9319910201375;6040.486558642477;6105.877882442326;6107.389261073857;6112.633242005494;6136.478399336017;6192.876142477216;6247.3440288769025;6281.968590157111;6284.811455656338;6328.462080723098;5960.64236145824;6030.517558595302;6073.726682299307;6107.886786452505;6116.082587611894;5529.502344788908;5533.667979921218;5537.695426751487;5539.2522009529;5560.138178582326;6535.379138981789;6545.5090868184125;6551.0500977358015;6555.410013098844;6570.704318305783;6129.786714141161;6151.231096112986;6237.656928868956;6248.781292654735;6286.874262514815;6485.221773387224;6521.282658907611;6524.380183882524;6532.41208291256;6543.312952140147;6266.530010249158;6280.183576135073;6281.748730050746;6292.779752708633;6295.244570651368;6061.72722227492;6123.557162997564;6169.5064394849205;6222.533310001859;6241.8751624966335;6195.070739389128;6230.252369715663;6238.189035425366;6245.9063114052415;6256.562040657111;6127.632342059491;6180.845478863064;6229.408206220402;6241.500679945684;6257.374262573706;5881.9705901470215;5925.911111333247;5961.046232602763;5998.49012833914;6036.0644100164545;6125.388069147199;6233.127362169391;6236.968815155895;6251.718672659832;6279.937200627935;6101.542384321105;6102.420270074336;6109.84725381869;6124.969375153096;6150.477209437248 12:38:34 INFO - PROCESS | 1919 | |1;ext.html;424.4439783883113;463.1038939651774;474.93825802645694;491.13314999026704;508.66682323078373;37713.05717357064;40537.59462405375;40581.59418405822;40582.59417405825;40598.78203653891;41793.79103104488;42164.78917605415;42584.787076064575;42725.35911961323;42773.78613106938;8448.197421244971;8665.69670061548;9135.81728365434;9527.952360238189;11258.605948791794;11213.831792523119;11468.082553395709;11479.253848499862;11498.71253218672;11501.884981150208;11499.655010349703;11545.942270288637;11547.53809847605;11550.71123221922;11571.363575003372;11438.942805286004;11439.142064345166;11489.885101148966;11496.33303171044;11515.827262591069;11342.71643208918;11403.771924561463;11422.428878556038;11427.542898284059;11440.542378304941;40653;40681.38977915334;41073.794631026954;41194.79402602998;41314.38028429576;3329.6170940341926;3956.4065390191413;3986.402039694069;4006.399040143973;4052.8378864845668;24357;28816.84732610714;30530;31117;31726.682733172605;3201.6638252983566;3221.114193596756;3224.1778346521724;3241;3256.7557433192605;442624;442694;443677;446099;446154;62357.376426236064;62419.6879015602;62624.68687656624;62864.685676571324;62960.68519657372;64884.67557662181;65212.67393663096;65606.67196664082;65630.01554976679;65682;12650.430730617145;16336.836631633769;17085.572860678334;17086.572835679206;17144.39994600183;9758.512074396253;12054.337011464278;12703.364831758376;13117.0818042738;13154;17118;17244.396446124578;17283;17314.740278895828;17333.306667733148;6548.967255163694;6556.409923106943;6603.141591593062;6622.966885165543;6645.833854153685;842.1326034184799;849.8597731374377;856.280724191682;879.9736007919772;899.6626265150569;30156.84921575422;31190.376192476022;31201.531977020364;31330.530042049388;31615.52576711351;909.3225546967551;924.6162842420455;929.1405449958896;1090.3022065877844;1102.7077824376547;15121.773173402407;16206;16288.104154271628;16615.41846035407;16811.57971050709;592066;599486;602299;603200;606653;56660.716696417076;57124.42875571191;57758.42241577614;57786.7110664444;57826;1394.5676840179576;1428.3144090836456;1433.2833583208396;1437.5759151050675;1448.9565313040625;25431.36421589475;28611.9985800496;30659.54010689886;30920.536191957588;30982.0705378839;52698.47301526936;52954;53386.46613533815;53388.46611533835;53482.19776703275;590802;592253.0387348208;594537.0273148606;597730.0113499404;598884;35878;36820.44769328516;42938.57061429346;43886.56113438825;44037.11925761403;47550.76224618855;47586.52413475821;48083.75958120187;49405.752971236354;49449.25826112683;32488.35023299568;36653.26693466169;38916.41625375678;40945.976350591474;41264.381034283906;32773.672263278015;38999.22001559896;39095.609043910335;40469.797651011555;40478.595214047484;37634.81182594179;37880.810595946845;38416.42375364316;38935.6106438932;39220.215595687354;30479.390412192075;34048.82975585106;34551.65448345484;35043.47434788531;35398.938031859085;20565.89717051405;20908.85001324942;24792.876035619705;25424.491510169322;26034.73965260399 12:38:34 INFO - PROCESS | 1919 | |2;jquery.html;377.52997518089984;392.73882867892837;392.8866832092637;402.16951994132114;404.27029212271844;16474.83525164748;20758.27346042889;21302.57394852102;21566.784332156694;21703.806290654007;20883.269085582007;21145;21881.562368752657;22202.333929982087;22245.998930048147;5608.635438696492;5922;5992.310884248323;5994.520438364921;6014.458698717136;5786.450287222707;5809.535237181015;5848.824535263949;5933.910991335133;5965.508622844289;5162.735129893163;5668.376478587373;5785.305763308409;5871.119332100197;5881.4118588141055;4468.597826195625;5686.744096515667;5820.447057529528;5850.736716847752;5876.206712093898;4442.800073996678;4576.931346029813;5167.682241028551;5195.090859099642;5203.0114278287;26005.479890402275;26417.73582264172;26552.867235663893;26753.732462675318;26773.330666733487;2635.143578337048;2675.063727695291;2676.47808677308;2735.617013618095;2738.315421144714;15552.766708499381;15893.523194304189;16017.759733604005;16168.029918204946;16295.881535400322;1811.7735283089614;1867.4397680695738;1872.1107473949764;1890.6218756248668;1891.2435025989578;136178;137646;140856;147549;157051.85896282125;11949.701257468632;12054.939725301492;12173.634790956285;12181.390930453443;12331.260124392566;15774.9211253943;15940.92029539868;15945.601359966093;15949.681006379808;15978.201089945456;8722.607482663296;8937;8987.595558199897;9090.045545217788;9115.36192466521;8742.475451472932;8817.823643527221;8819.206271435602;8936.41913275648;8962.193402593797;4804.375431193923;4829.879253018703;4868.758466590996;4874.731889746098;4949.7030178189425;1722.836330548596;1760.4771872330598;2066.442060643648;2093.842961777873;2094.884781337041;873.5937788928179;975.8728668388023;980.5685498380832;991.7272749993665;993.8918106311411;20795;21302.893485532473;21339.35981920545;21358.78641213568;21424.250151244632;981.7398389426808;982.6462473509531;1011.1354791653177;1016.7966406718685;1037.9896201038043;10847.186461015297;11094.334339939469;11102.444877756241;11121;11150.21948463616;625834;667153;707973;763010;770814;117304.65390692139;156845;159074;165063;171126.28873711103;915.6291701860757;1018.3533456255332;1023.5803320638503;1073.6510634044123;1074.3607553505651;19131.426057218305;19888.806671599752;20335.79664203339;20683.4829129273;20709.68935465939;59273;60450.395496044475;60635.69682151561;61503.692481537306;62251.688741556005;85424.57287713522;87380.5630971841;89084;90479;90979.09020909706;7017.894731579133;8175.223353781381;8196.303314218358;8425.915740842513;8528.189821966904;12116.636500904988;12165.148439609142;12831.550895718605;12993.740125197632;13382.330883455594;10932.180086493703;11049.613263535737;11234;11272.267302625303;11472.713182170513;8857.424267422597;11079.113670906147;11135.99776020162;11243.212975091665;11355.772884542428;11339;11350.375729334966;11525.308481491138;11617.477213525242;11677.824832627688;5736.512396446349;7422.331990120699;8324.958375208085;8657.177568131017;8698;9547.474888881177;10822.134229261797;10951.671449856518;11163.386013768995;11355.602553910574 12:38:34 INFO - PROCESS | 1919 | |3;mootools.html;1430.5708287513737;1431.8067060946769;1445.4940770730245;1455.541504426106;1462.4223431744465;6586.802395928118;6974.407175390098;7498.737544185955;8068.878966815502;8503.872441913376;6519.380658837415;8166.877496837537;8229.83540329193;8379.83240335193;8412.957935210316;1798.7032139696935;2010.3566858605216;2343.4258606641397;2346.4133966508375;2354.434935615457;2095.1200495791727;2173.619616567102;2328.138588722175;2370.8933098010543;2376.1327115602853;1878.4083013850661;1933.3040105561986;1940.6797878350055;2347.2371479269223;2354.023080421623;2041.397787652646;2312.4681323295654;2335.1359996801207;2341.4380548668196;2345.566070277004;2317.5133222023396;2356.091297293537;2367.863425555743;2377.09670325277;2385.8449200801892;7621.58081305528;7889;8321.916780832235;8347.747837824314;8388.11924747905;5105.361829771278;5254.500822421864;5254.500822421902;5257.421683614799;5296.894062118775;3491.127218195451;3538.681518663307;3575.856965721368;3605.4231322988194;3610.6930910872607;1210.4431961297726;1210.7881120803825;1247.731737676404;1265.5001274496615;1271.2436100520176;10470.633527826489;10591.25861189717;10634.361938283728;10698.03717665414;10743.14054875608;3767.3972164454103;4660.906781864412;4704.882377940579;4716.434027916672;4731.526847315241;9341.766455838522;9491.572879220452;9495.572699228413;9508.001659825757;9524.380915240487;5235.188545775411;5263.605229607718;5269.57843372537;5270.446603106695;5282.392524859613;4536.4102666653125;4679.508651591602;4799.088173247072;4840.661153719206;4859;3929.25344184604;3980.9402858957137;4017.738846974937;4057.39139129133;4081.673466122703;1203.9699007524882;1231.605886116452;1279.0982357437983;1311.4491913396396;1321.3393303348325;731.8353370491596;748.8614606297853;768.5772824946257;784.6469088910013;810.1614828652316;4476.306172543261;5534.640248383842;5553.527950124204;5570.164475328652;5711.485966262973;896.3322324868016;968.3802366485455;971.455984648599;1021.3565453764081;1041.5886473828004;3161.9841900790348;3577.767445116059;3801.9429708554394;3854.3062248794927;3861.5366156061455;9612.903870961201;9782.951085244527;9937.801243975224;9992.150667193224;10001.2999090063;9788.21694264457;9831.803363932824;9875.802483950425;9898;9917.75205619851;7412.332890039922;7685.807854803675;7732.536047837148;7734.187910269341;7864.056313242448;5489.094299440548;5582.385937546947;5621.19834999655;5673.177389278614;5747.683877386784;8814.823703525766;8983.730488085368;9006.369554131148;9155.13026262504;9209.815803684023;9033.548322583976;9176;9197.954010229905;9459.148676619137;9482.95258523703;7180.138383393817;7261.709531618676;7284.635768211674;7293.270672932665;7301.269873012656;7715.729949451742;7780.416468764978;7789;7819.960900195462;8485.448445851;5137.8201762938115;6262.561620686508;7106.680199390937;7146.392556632744;7163.176234733072;6144.324124346408;6808.421284190899;6878.727435424463;6926.792196234121;6962.094927659372;6303.621782693053;6429.51778616615;6467.15926929515;6477;6484.351564843478;4816.253480710496;4837.177679794373;4972.801087956441;4975.601951843916;5128.615353848401;4964.131277026579;4970.602351811919;5035.144025515744;5045.6720313180995;5082.949170508396 12:38:34 INFO - PROCESS | 1919 | |4;prototype.html;329.377476569284;350.6598599358623;354.75699146084935;366.38996071540885;372.6348178784792;4373.294415178083;4740.288956656499;5134.897302053957;5405.324334458192;5498.725063746811;4833.68581042232;4910.828121015765;5361.678299302044;5448.128299472085;5457.181422786584;4233;4289.313709806432;4295.484541854982;4301.000034983732;4326.675499337546;3541.982290088559;3731.9067023324496;4227.598378154071;4310.633596144332;4329.956700433003;4216.915661686764;4227.3447615619625;4230.74615523068;4261.914761704764;4280.935785963213;3741.1769410729594;3826.1391186982983;4016.256992456405;4218.156368726267;4291.463567054118;3184.8726050957935;3188.187012311869;3825.3496905526126;3829.329867273244;3830.2148059647707;4109.91780164398;4574.565416285481;5155.819546315861;5216.2958000669805;5275.736213189325;3879.7478163919254;4900;4920.483349248313;4969.552740253358;4996.675216110941;5018.874528136789;5720.2256905400845;5720.542356611461;5795.898779231932;5831.591788574801;3490.6334834842482;3621.4386770050683;3897.707671924589;4133.731307465006;4206.053637931472;5261.7111556473055;5364.927014597096;5639.492445679867;5974;5985.820425387245;3835.9808200959374;3909.139989202428;4153.8576891355115;4246.278132717445;4246.617804397619;3678.668919797231;3817.9618203818163;4152.771597562163;4325.416068830667;4328.350747387854;4904.068227036852;4911.45973942866;4928.6303527235605;4950.628702847301;4954.430240522386;4476.35092911526;5700.657960522382;5742.712864356766;5785.945989173867;5811.82564523065;1238.9566365177175;1312.3897387714758;1332.4470344806796;1407.6345944433883;1412.11037046662;974.4396971741277;974.9171320437842;991.6033586565432;998.3011891675856;998.9650362237287;4905.798079470481;5076.162433198481;5120.564751996048;5121.487851214849;5155.407128180233;4937.753112344369;5282.735863206901;5357.5446087082255;5620.943790562042;5677.104676614874;4156.646685031807;4428.468583769968;4650.534946505322;4773.491483759434;5113.207452844741;2528.9378461046404;2530.8481491110592;2576.600626902833;2603.622474741152;2659.4415172813538;4884.731339776346;4978.581104385233;5274.393444753826;5445.863853403696;5769.538436925036;5173.275741396207;5207.3336532309295;5513.724313784295;5653.378128405872;5703.74333155009;4787.712737235777;4818.975905120452;5412.945870541323;5575.832725018256;5900.852478688067;5139.486051394831;5585.804496842591;5596.384397716167;5607.8503906698215;5687.459691329317;4925.6305777066145;5151.6908985461;5154.61340399479;5461.361591522448;5541.251930989265;4851.781669824937;5284.41871394154;5883.676397798162;5910.906482300788;5980.19267398913;3587.1032241939515;3915.823787929493;3938.783366914847;4069;4081;5396.9730151348995;5590.385057643574;5601.29983752031;5742.707890724553;5828.679422631626;5348.5186333230195;5437.4018857926385;5509.090999984957;5605.859853503532;5783.913241301299;5101.69389836611;5241.842744717665;5389;5618.101103823368;5707.571932105026;4423.8066706611;5003.67476114066;5532.1425179097305;5552.472515111058;5667.518260947865;4992.452339774682;5230.686158830483;5436.124537034717;5690.118031705092;5692.459216374438;4658.953410465988;5548.694821784679;5641.887162256814;5672.334003018575;5676.877717255576 12:38:34 INFO - PROCESS | 1919 | |5;yui.html;570.412475150595;706.4630880530796;737.6533029476146;770.2643975003868;801.5110782422721;14764.852351476482;15596.610084747887;15616.765748513753;15665;15666.999999999985;12887.613371598844;13963.930180349083;13982.860171398283;14084.225367604779;14166.362513686883;853.32160932059;866.969656062038;879.5118709116438;881.9073997230295;882.74400423877;853.8548446764034;869.7043005378171;870.277669534285;872.9170728780756;872.9301655867515;903.2006674093442;907.3421769217298;915.5513798238849;918.7932715139074;923.3997901364133;903.1961554216753;904.6969265296101;904.7557159566913;909.6225066597354;910.3672947301628;676.2898956096079;679;680.8604236131581;681.8670359279943;684.5139950635055;13234.139780914305;13391.665208369868;13527.323633818369;13664.726705465835;13817.032807703465;11822.172447928648;11844.34856082915;11896.405179741065;12019.459124339426;12160.878391216063;8705.129487051308;9935.751606209831;10197.827249866286;10310.896891031143;10574.365538067741;3054.6945305469276;3192.233863872677;3414.9829250853836;3581.158427769472;3597.37046016946;100153.99846001592;100187.49906250495;100596.49701751444;101981;102265;7489.812754681177;8558.700445484377;8672.132786721404;8873.29013678917;8922.973858006282;19425;19520.60958780845;19670.311539096063;19683.507912302306;19748;7417.480776345604;7500.849983000309;7508.924910750932;7560.130584982688;7565.6973721050545;4031.133306339151;4117.835286588503;4192.350185721218;4227.682923780729;4289.91420171601;3469.028671971852;3538.6992105671306;3539.2036791721657;3555.235624340779;3563.6614521620404;521.262413684632;608.0484042473515;640.1198352265635;646.9870602587922;651.0689713709346;529.4070640882237;625.4746013348808;630.6279295215845;630.7256343490596;643.7939859244997;9309.953450232706;9662.405703059057;9762.853557196648;9843.409395436154;10061.490776383602;595.6455908734296;601.9668918209453;629.7669862151033;646.5538778242998;651.837040739815;4476.440444944382;4809.917768502059;4834.589059929875;4836.419629644465;4883.633727470383;65393.34606653968;94725.52637236769;109858.35212471819;110850.89149108567;111917;92232.53883730673;93505;94668;96011.51994239984;99215;65840.34159658341;66001;67985.32014679888;76167.47665046668;83903;10342.32774869661;10355.275130741076;10697.786044279226;10803.45982700846;11085.889141108808;37480.625193747714;48273.75863120662;49610.503894962036;51596;52398.21402678887;64670.67664661835;65815.670921647;68852.96720549295;69683;71016.3675685414;12758.234505929673;12854.614361569167;12919.22484650924;13212.273324967218;13395.598132056053;14011.159330440207;14348.641283967985;14645.121292722471;14731;15141.62145946316;13250.536231231861;13637.181769093886;13660.248686321951;13688.794668080185;13705.862941370857;13072.673183170496;13428.865711342762;13555.254461004739;13664.453421863014;13869.791953120914;12729.363531823557;13021.414036368196;13285.202887826761;13316.733665326832;13888.305584720521;9317.72046838596;9867.802643947225;10379.142695884055;11172.273802202832;11195.211411610928;7899.802504937423;10981.670549883516;11126.332420054823;11156.386398748147;11497.770044598894 12:38:34 INFO - PROCESS | 1919 | __end_tp_report 12:38:34 INFO - PROCESS | 1919 | __start_cc_report 12:38:34 INFO - PROCESS | 1919 | _x_x_mozilla_cycle_collect,390 12:38:34 INFO - PROCESS | 1919 | __end_cc_report 12:38:34 INFO - PROCESS | 1919 | __startTimestamp1487277514765__endTimestamp 12:38:34 INFO - PROCESS | 1919 | 12:38:34 INFO - PROCESS | 1919 | ------- Summary: start ------- 12:38:34 INFO - PROCESS | 1919 | Number of tests: 6 12:38:34 INFO - PROCESS | 1919 | 12:38:34 INFO - PROCESS | 1919 | [#0] dojo.html Cycles:165 Average:5820.09 Median:6107.89 stddev:661.60 (10.8%) stddev-sans-first:634.09 12:38:34 INFO - PROCESS | 1919 | Values: 3327.8 3405.4 3419.6 3440.1 3467.4 5765.9 5805.7 5835.4 5850.6 5864.7 5825.8 5830.3 5880.3 5899.1 5921.1 5278.1 5282.6 5307.6 5355.7 5364.6 5258.1 5264.7 5328.7 5368.2 5379.4 5163.4 5179.9 5187.9 5296.4 5302.9 5146.4 5224.7 5252.5 5266.9 5287.3 5118.5 5182.1 5187.9 5222.4 5227.5 4469.3 4691.4 5455.9 5602.1 5660.9 5520.0 6168.0 6175.5 6181.6 6234.7 5960.6 6293.6 6299.8 6310.3 6312.7 5767.9 5995.1 5995.2 5999.0 6002.8 6219.2 6274.6 6286.7 6335.2 6432.9 6102.3 6153.7 6188.2 6196.3 6201.8 6278.9 6289.2 6292.4 6305.4 6317.1 6135.9 6184.2 6199.1 6233.1 6265.2 6291.7 6301.3 6308.3 6310.2 6337.0 5030.4 5039.2 5042.1 5048.1 5055.1 4422.2 4425.9 4496.2 4506.6 4533.9 6040.5 6105.9 6107.4 6112.6 6136.5 6192.9 6247.3 6282.0 6284.8 6328.5 5960.6 6030.5 6073.7 6107.9 6116.1 5529.5 5533.7 5537.7 5539.3 5560.1 6535.4 6545.5 6551.1 6555.4 6570.7 6129.8 6151.2 6237.7 6248.8 6286.9 6485.2 6521.3 6524.4 6532.4 6543.3 6266.5 6280.2 6281.7 6292.8 6295.2 6061.7 6123.6 6169.5 6222.5 6241.9 6195.1 6230.3 6238.2 6245.9 6256.6 6127.6 6180.8 6229.4 6241.5 6257.4 5882.0 5925.9 5961.0 5998.5 6036.1 6125.4 6233.1 6237.0 6251.7 6279.9 6101.5 6102.4 6109.8 6125.0 6150.5 12:38:34 INFO - PROCESS | 1919 | 12:38:34 INFO - PROCESS | 1919 | [#1] ext.html Cycles:180 Average:69095.27 Median:39157.91 stddev:147052.84 (375.5%) stddev-sans-first:147374.98 12:38:34 INFO - PROCESS | 1919 | Values: 424.4 463.1 474.9 491.1 508.7 37713.1 40537.6 40581.6 40582.6 40598.8 41793.8 42164.8 42584.8 42725.4 42773.8 8448.2 8665.7 9135.8 9528.0 11258.6 11213.8 11468.1 11479.3 11498.7 11501.9 11499.7 11545.9 11547.5 11550.7 11571.4 11438.9 11439.1 11489.9 11496.3 11515.8 11342.7 11403.8 11422.4 11427.5 11440.5 40653.0 40681.4 41073.8 41194.8 41314.4 3329.6 3956.4 3986.4 4006.4 4052.8 24357.0 28816.8 30530.0 31117.0 31726.7 3201.7 3221.1 3224.2 3241.0 3256.8 442624.0 442694.0 443677.0 446099.0 446154.0 62357.4 62419.7 62624.7 62864.7 62960.7 64884.7 65212.7 65606.7 65630.0 65682.0 12650.4 16336.8 17085.6 17086.6 17144.4 9758.5 12054.3 12703.4 13117.1 13154.0 17118.0 17244.4 17283.0 17314.7 17333.3 6549.0 6556.4 6603.1 6623.0 6645.8 842.1 849.9 856.3 880.0 899.7 30156.8 31190.4 31201.5 31330.5 31615.5 909.3 924.6 929.1 1090.3 1102.7 15121.8 16206.0 16288.1 16615.4 16811.6 592066.0 599486.0 602299.0 603200.0 606653.0 56660.7 57124.4 57758.4 57786.7 57826.0 1394.6 1428.3 1433.3 1437.6 1449.0 25431.4 28612.0 30659.5 30920.5 30982.1 52698.5 52954.0 53386.5 53388.5 53482.2 590802.0 592253.0 594537.0 597730.0 598884.0 35878.0 36820.4 42938.6 43886.6 44037.1 47550.8 47586.5 48083.8 49405.8 49449.3 32488.4 36653.3 38916.4 40946.0 41264.4 32773.7 38999.2 39095.6 40469.8 40478.6 37634.8 37880.8 38416.4 38935.6 39220.2 30479.4 34048.8 34551.7 35043.5 35398.9 20565.9 20908.9 24792.9 25424.5 26034.7 12:38:34 INFO - PROCESS | 1919 | 12:38:34 INFO - PROCESS | 1919 | [#2] jquery.html Cycles:180 Average:40345.55 Median:14320.31 stddev:118767.89 (829.4%) stddev-sans-first:119063.15 12:38:34 INFO - PROCESS | 1919 | Values: 377.5 392.7 392.9 402.2 404.3 16474.8 20758.3 21302.6 21566.8 21703.8 20883.3 21145.0 21881.6 22202.3 22246.0 5608.6 5922.0 5992.3 5994.5 6014.5 5786.5 5809.5 5848.8 5933.9 5965.5 5162.7 5668.4 5785.3 5871.1 5881.4 4468.6 5686.7 5820.4 5850.7 5876.2 4442.8 4576.9 5167.7 5195.1 5203.0 26005.5 26417.7 26552.9 26753.7 26773.3 2635.1 2675.1 2676.5 2735.6 2738.3 15552.8 15893.5 16017.8 16168.0 16295.9 1811.8 1867.4 1872.1 1890.6 1891.2 136178.0 137646.0 140856.0 147549.0 157051.9 11949.7 12054.9 12173.6 12181.4 12331.3 15774.9 15940.9 15945.6 15949.7 15978.2 8722.6 8937.0 8987.6 9090.0 9115.4 8742.5 8817.8 8819.2 8936.4 8962.2 4804.4 4829.9 4868.8 4874.7 4949.7 1722.8 1760.5 2066.4 2093.8 2094.9 873.6 975.9 980.6 991.7 993.9 20795.0 21302.9 21339.4 21358.8 21424.3 981.7 982.6 1011.1 1016.8 1038.0 10847.2 11094.3 11102.4 11121.0 11150.2 625834.0 667153.0 707973.0 763010.0 770814.0 117304.7 156845.0 159074.0 165063.0 171126.3 915.6 1018.4 1023.6 1073.7 1074.4 19131.4 19888.8 20335.8 20683.5 20709.7 59273.0 60450.4 60635.7 61503.7 62251.7 85424.6 87380.6 89084.0 90479.0 90979.1 7017.9 8175.2 8196.3 8425.9 8528.2 12116.6 12165.1 12831.6 12993.7 13382.3 10932.2 11049.6 11234.0 11272.3 11472.7 8857.4 11079.1 11136.0 11243.2 11355.8 11339.0 11350.4 11525.3 11617.5 11677.8 5736.5 7422.3 8325.0 8657.2 8698.0 9547.5 10822.1 10951.7 11163.4 11355.6 12:38:34 INFO - PROCESS | 1919 | 12:38:34 INFO - PROCESS | 1919 | [#3] mootools.html Cycles:180 Average:5364.89 Median:5266.59 stddev:2910.64 (55.3%) stddev-sans-first:2903.79 12:38:34 INFO - PROCESS | 1919 | Values: 1430.6 1431.8 1445.5 1455.5 1462.4 6586.8 6974.4 7498.7 8068.9 8503.9 6519.4 8166.9 8229.8 8379.8 8413.0 1798.7 2010.4 2343.4 2346.4 2354.4 2095.1 2173.6 2328.1 2370.9 2376.1 1878.4 1933.3 1940.7 2347.2 2354.0 2041.4 2312.5 2335.1 2341.4 2345.6 2317.5 2356.1 2367.9 2377.1 2385.8 7621.6 7889.0 8321.9 8347.7 8388.1 5105.4 5254.5 5254.5 5257.4 5296.9 3491.1 3538.7 3575.9 3605.4 3610.7 1210.4 1210.8 1247.7 1265.5 1271.2 10470.6 10591.3 10634.4 10698.0 10743.1 3767.4 4660.9 4704.9 4716.4 4731.5 9341.8 9491.6 9495.6 9508.0 9524.4 5235.2 5263.6 5269.6 5270.4 5282.4 4536.4 4679.5 4799.1 4840.7 4859.0 3929.3 3980.9 4017.7 4057.4 4081.7 1204.0 1231.6 1279.1 1311.4 1321.3 731.8 748.9 768.6 784.6 810.2 4476.3 5534.6 5553.5 5570.2 5711.5 896.3 968.4 971.5 1021.4 1041.6 3162.0 3577.8 3801.9 3854.3 3861.5 9612.9 9783.0 9937.8 9992.2 10001.3 9788.2 9831.8 9875.8 9898.0 9917.8 7412.3 7685.8 7732.5 7734.2 7864.1 5489.1 5582.4 5621.2 5673.2 5747.7 8814.8 8983.7 9006.4 9155.1 9209.8 9033.5 9176.0 9198.0 9459.1 9483.0 7180.1 7261.7 7284.6 7293.3 7301.3 7715.7 7780.4 7789.0 7820.0 8485.4 5137.8 6262.6 7106.7 7146.4 7163.2 6144.3 6808.4 6878.7 6926.8 6962.1 6303.6 6429.5 6467.2 6477.0 6484.4 4816.3 4837.2 4972.8 4975.6 5128.6 4964.1 4970.6 5035.1 5045.7 5082.9 12:38:34 INFO - PROCESS | 1919 | 12:38:34 INFO - PROCESS | 1919 | [#4] prototype.html Cycles:180 Average:4527.31 Median:4994.56 stddev:1345.15 (26.9%) stddev-sans-first:1311.50 12:38:34 INFO - PROCESS | 1919 | Values: 329.4 350.7 354.8 366.4 372.6 4373.3 4740.3 5134.9 5405.3 5498.7 4833.7 4910.8 5361.7 5448.1 5457.2 4233.0 4289.3 4295.5 4301.0 4326.7 3542.0 3731.9 4227.6 4310.6 4330.0 4216.9 4227.3 4230.7 4261.9 4280.9 3741.2 3826.1 4016.3 4218.2 4291.5 3184.9 3188.2 3825.3 3829.3 3830.2 4109.9 4574.6 5155.8 5216.3 5275.7 3879.7 4900.0 4920.5 4969.6 4996.7 5018.9 5720.2 5720.5 5795.9 5831.6 3490.6 3621.4 3897.7 4133.7 4206.1 5261.7 5364.9 5639.5 5974.0 5985.8 3836.0 3909.1 4153.9 4246.3 4246.6 3678.7 3818.0 4152.8 4325.4 4328.4 4904.1 4911.5 4928.6 4950.6 4954.4 4476.4 5700.7 5742.7 5785.9 5811.8 1239.0 1312.4 1332.4 1407.6 1412.1 974.4 974.9 991.6 998.3 999.0 4905.8 5076.2 5120.6 5121.5 5155.4 4937.8 5282.7 5357.5 5620.9 5677.1 4156.6 4428.5 4650.5 4773.5 5113.2 2528.9 2530.8 2576.6 2603.6 2659.4 4884.7 4978.6 5274.4 5445.9 5769.5 5173.3 5207.3 5513.7 5653.4 5703.7 4787.7 4819.0 5412.9 5575.8 5900.9 5139.5 5585.8 5596.4 5607.9 5687.5 4925.6 5151.7 5154.6 5461.4 5541.3 4851.8 5284.4 5883.7 5910.9 5980.2 3587.1 3915.8 3938.8 4069.0 4081.0 5397.0 5590.4 5601.3 5742.7 5828.7 5348.5 5437.4 5509.1 5605.9 5783.9 5101.7 5241.8 5389.0 5618.1 5707.6 4423.8 5003.7 5532.1 5552.5 5667.5 4992.5 5230.7 5436.1 5690.1 5692.5 4659.0 5548.7 5641.9 5672.3 5676.9 12:38:34 INFO - PROCESS | 1919 | 12:38:34 INFO - PROCESS | 1919 | [#5] yui.html Cycles:180 Average:19855.75 Median:4643.18 stddev:28983.25 (624.2%) stddev-sans-first:29028.38 12:38:34 INFO - PROCESS | 1919 | Values: 570.4 706.5 737.7 770.3 801.5 14764.9 15596.6 15616.8 15665.0 15667.0 12887.6 13963.9 13982.9 14084.2 14166.4 853.3 867.0 879.5 881.9 882.7 853.9 869.7 870.3 872.9 872.9 903.2 907.3 915.6 918.8 923.4 903.2 904.7 904.8 909.6 910.4 676.3 679.0 680.9 681.9 684.5 13234.1 13391.7 13527.3 13664.7 13817.0 11822.2 11844.3 11896.4 12019.5 12160.9 8705.1 9935.8 10197.8 10310.9 10574.4 3054.7 3192.2 3415.0 3581.2 3597.4 100154.0 100187.5 100596.5 101981.0 102265.0 7489.8 8558.7 8672.1 8873.3 8923.0 19425.0 19520.6 19670.3 19683.5 19748.0 7417.5 7500.8 7508.9 7560.1 7565.7 4031.1 4117.8 4192.4 4227.7 4289.9 3469.0 3538.7 3539.2 3555.2 3563.7 521.3 608.0 640.1 647.0 651.1 529.4 625.5 630.6 630.7 643.8 9310.0 9662.4 9762.9 9843.4 10061.5 595.6 602.0 629.8 646.6 651.8 4476.4 4809.9 4834.6 4836.4 4883.6 65393.3 94725.5 109858.4 110850.9 111917.0 92232.5 93505.0 94668.0 96011.5 99215.0 65840.3 66001.0 67985.3 76167.5 83903.0 10342.3 10355.3 10697.8 10803.5 11085.9 37480.6 48273.8 49610.5 51596.0 52398.2 64670.7 65815.7 68853.0 69683.0 71016.4 12758.2 12854.6 12919.2 13212.3 13395.6 14011.2 14348.6 14645.1 14731.0 15141.6 13250.5 13637.2 13660.2 13688.8 13705.9 13072.7 13428.9 13555.3 13664.5 13869.8 12729.4 13021.4 13285.2 13316.7 13888.3 9317.7 9867.8 10379.1 11172.3 11195.2 7899.8 10981.7 11126.3 11156.4 11497.8 12:38:34 INFO - PROCESS | 1919 | -------- Summary: end -------- 12:38:34 INFO - PROCESS | 1919 | 12:38:35 INFO - TEST-INFO | 1919: exit 0 12:38:35 INFO - TEST-OK | dromaeo_css | took 1115755ms 12:38:35 INFO - TEST-START | kraken 12:38:35 INFO - operating with platform_type : mac_ 12:38:35 INFO - Initialising browser for kraken test... 12:38:35 INFO - TEST-INFO | started process 1924 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/tmpPL4MRh/profile http://localhost:64206/getInfo.html) 12:38:38 INFO - PROCESS | 1924 | 2017-02-16 12:38:38.184 plugin-container[1925:61450] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883b, name = 'com.apple.tsm.portname' 12:38:38 INFO - PROCESS | 1924 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:38:38 INFO - PROCESS | 1924 | 2017-02-16 12:38:38.187 plugin-container[1925:61450] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.CFPasteboardClient' 12:38:38 INFO - PROCESS | 1924 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:38:38 INFO - PROCESS | 1924 | 2017-02-16 12:38:38.187 plugin-container[1925:61450] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:38:38 INFO - PROCESS | 1924 | __metrics Screen width/height:1600/1200 12:38:38 INFO - PROCESS | 1924 | colorDepth:24 12:38:38 INFO - PROCESS | 1924 | Browser inner width/height: 1024/663 12:38:38 INFO - PROCESS | 1924 | __metrics 12:38:38 ERROR - PROCESS | 1924 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 12:38:38 INFO - TEST-INFO | 1924: exit 0 12:38:38 INFO - Browser initialized. 12:38:38 INFO - Running cycle 1/1 for kraken test... 12:38:38 INFO - TEST-INFO | started process 1926 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/tmpPL4MRh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:38:41 INFO - PROCESS | 1926 | 2017-02-16 12:38:41.539 plugin-container[1927:61576] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x863f, name = 'com.apple.tsm.portname' 12:38:41 INFO - PROCESS | 1926 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:38:41 INFO - PROCESS | 1926 | 2017-02-16 12:38:41.540 plugin-container[1927:61576] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c1b, name = 'com.apple.CFPasteboardClient' 12:38:41 INFO - PROCESS | 1926 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:38:41 INFO - PROCESS | 1926 | 2017-02-16 12:38:41.540 plugin-container[1927:61576] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:38:51 INFO - PROCESS | 1926 | RSS: Main: 228950016 12:38:51 INFO - PROCESS | 1926 | 12:38:54 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:64206/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:64206/tests/kraken/driver.html?testName=audio-beat-detection) 12:38:55 INFO - PROCESS | 1926 | RSS: Main: 241500160 12:38:55 INFO - PROCESS | 1926 | 12:38:58 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:64206/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:64206/tests/kraken/driver.html?testName=audio-dft) 12:38:58 INFO - PROCESS | 1926 | RSS: Main: 249090048 12:38:58 INFO - PROCESS | 1926 | 12:39:02 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:64206/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:64206/tests/kraken/driver.html?testName=audio-fft) 12:39:02 INFO - PROCESS | 1926 | RSS: Main: 249917440 12:39:02 INFO - PROCESS | 1926 | 12:39:04 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:64206/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:64206/tests/kraken/driver.html?testName=audio-oscillator) 12:39:05 INFO - PROCESS | 1926 | RSS: Main: 251166720 12:39:05 INFO - PROCESS | 1926 | 12:39:07 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:64206/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:64206/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:39:08 INFO - PROCESS | 1926 | RSS: Main: 251060224 12:39:08 INFO - PROCESS | 1926 | 12:39:11 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:64206/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:64206/tests/kraken/driver.html?testName=imaging-darkroom) 12:39:11 INFO - PROCESS | 1926 | RSS: Main: 254914560 12:39:11 INFO - PROCESS | 1926 | 12:39:15 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:64206/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:64206/tests/kraken/driver.html?testName=imaging-desaturate) 12:39:15 INFO - PROCESS | 1926 | RSS: Main: 254414848 12:39:15 INFO - PROCESS | 1926 | 12:39:18 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:64206/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:64206/tests/kraken/driver.html?testName=json-parse-financial) 12:39:19 INFO - PROCESS | 1926 | RSS: Main: 254492672 12:39:19 INFO - PROCESS | 1926 | 12:39:20 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:64206/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:64206/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:39:21 INFO - PROCESS | 1926 | RSS: Main: 253460480 12:39:21 INFO - PROCESS | 1926 | 12:39:23 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:64206/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:64206/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:39:24 INFO - PROCESS | 1926 | RSS: Main: 253476864 12:39:24 INFO - PROCESS | 1926 | 12:39:26 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:64206/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:64206/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:39:27 INFO - PROCESS | 1926 | RSS: Main: 253538304 12:39:27 INFO - PROCESS | 1926 | 12:39:29 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:64206/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:64206/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:39:30 INFO - PROCESS | 1926 | RSS: Main: 253882368 12:39:30 INFO - PROCESS | 1926 | 12:39:33 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:64206/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:64206/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:39:34 INFO - PROCESS | 1926 | RSS: Main: 253693952 12:39:34 INFO - PROCESS | 1926 | 12:39:36 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:64206/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:64206/tests/kraken/driver.html?testName=ai-astar) 12:39:36 INFO - PROCESS | 1926 | RSS: Main: 255655936 12:39:36 INFO - PROCESS | 1926 | 12:39:36 INFO - PROCESS | 1926 | __start_tp_report 12:39:36 INFO - PROCESS | 1926 | _x_x_mozilla_page_load 12:39:36 INFO - PROCESS | 1926 | _x_x_mozilla_page_load_details 12:39:36 INFO - PROCESS | 1926 | |i|pagename|runs| 12:39:36 INFO - PROCESS | 1926 | |0;ai-astar;106;121;162;174;123;105;172;174;131;119 12:39:36 INFO - PROCESS | 1926 | |1;audio-beat-detection;145;121;148;142;150;146;145;119;137;112 12:39:36 INFO - PROCESS | 1926 | |2;audio-dft;198;203;197;200;262;281;196;200;195;198 12:39:36 INFO - PROCESS | 1926 | |3;audio-fft;84;59;61;90;62;59;60;59;75;73 12:39:36 INFO - PROCESS | 1926 | |4;audio-oscillator;94;71;110;103;101;70;81;71;71;78 12:39:36 INFO - PROCESS | 1926 | |5;imaging-gaussian-blur;76;115;133;108;88;106;137;86;90;91 12:39:36 INFO - PROCESS | 1926 | |6;imaging-darkroom;101;102;135;107;172;119;175;152;101;136 12:39:36 INFO - PROCESS | 1926 | |7;imaging-desaturate;94;96;110;92;93;112;115;90;89;128 12:39:36 INFO - PROCESS | 1926 | |8;json-parse-financial;53;54;59;61;62;63;62;62;62;62 12:39:36 INFO - PROCESS | 1926 | |9;json-stringify-tinderbox;70;110;118;100;78;69;71;74;70;75 12:39:36 INFO - PROCESS | 1926 | |10;stanford-crypto-aes;82;77;81;72;72;101;74;73;94;94 12:39:36 INFO - PROCESS | 1926 | |11;stanford-crypto-ccm;143;159;146;140;140;143;142;145;166;177 12:39:36 INFO - PROCESS | 1926 | |12;stanford-crypto-pbkdf2;170;196;150;226;232;181;178;203;170;170 12:39:36 INFO - PROCESS | 1926 | |13;stanford-crypto-sha256-iterative;69;67;67;66;65;70;64;89;60;73 12:39:36 INFO - PROCESS | 1926 | __end_tp_report 12:39:36 INFO - PROCESS | 1926 | __start_cc_report 12:39:36 INFO - PROCESS | 1926 | _x_x_mozilla_cycle_collect,979 12:39:36 INFO - PROCESS | 1926 | __end_cc_report 12:39:36 INFO - PROCESS | 1926 | __startTimestamp1487277576380__endTimestamp 12:39:36 INFO - PROCESS | 1926 | 12:39:36 INFO - PROCESS | 1926 | ------- Summary: start ------- 12:39:36 INFO - PROCESS | 1926 | Number of tests: 14 12:39:36 INFO - PROCESS | 1926 | 12:39:36 INFO - PROCESS | 1926 | [#0] ai-astar Cycles:10 Average:138.70 Median:146.50 stddev:28.59 (19.5%) stddev-sans-first:27.77 12:39:36 INFO - PROCESS | 1926 | Values: 106.0 121.0 162.0 174.0 123.0 105.0 172.0 174.0 131.0 119.0 12:39:36 INFO - PROCESS | 1926 | 12:39:36 INFO - PROCESS | 1926 | [#1] audio-beat-detection Cycles:10 Average:136.50 Median:145.00 stddev:13.85 (9.6%) stddev-sans-first:14.34 12:39:36 INFO - PROCESS | 1926 | Values: 145.0 121.0 148.0 142.0 150.0 146.0 145.0 119.0 137.0 112.0 12:39:36 INFO - PROCESS | 1926 | 12:39:36 INFO - PROCESS | 1926 | [#2] audio-dft Cycles:10 Average:213.00 Median:200.00 stddev:31.24 (15.6%) stddev-sans-first:32.66 12:39:36 INFO - PROCESS | 1926 | Values: 198.0 203.0 197.0 200.0 262.0 281.0 196.0 200.0 195.0 198.0 12:39:36 INFO - PROCESS | 1926 | 12:39:36 INFO - PROCESS | 1926 | [#3] audio-fft Cycles:10 Average:68.20 Median:67.50 stddev:11.57 (17.1%) stddev-sans-first:10.77 12:39:36 INFO - PROCESS | 1926 | Values: 84.0 59.0 61.0 90.0 62.0 59.0 60.0 59.0 75.0 73.0 12:39:36 INFO - PROCESS | 1926 | 12:39:36 INFO - PROCESS | 1926 | [#4] audio-oscillator Cycles:10 Average:85.00 Median:71.00 stddev:15.51 (21.8%) stddev-sans-first:16.10 12:39:36 INFO - PROCESS | 1926 | Values: 94.0 71.0 110.0 103.0 101.0 70.0 81.0 71.0 71.0 78.0 12:39:36 INFO - PROCESS | 1926 | 12:39:36 INFO - PROCESS | 1926 | [#5] imaging-gaussian-blur Cycles:10 Average:103.00 Median:81.00 stddev:20.52 (25.3%) stddev-sans-first:19.30 12:39:36 INFO - PROCESS | 1926 | Values: 76.0 115.0 133.0 108.0 88.0 106.0 137.0 86.0 90.0 91.0 12:39:36 INFO - PROCESS | 1926 | 12:39:36 INFO - PROCESS | 1926 | [#6] imaging-darkroom Cycles:10 Average:130.00 Median:135.50 stddev:28.77 (21.2%) stddev-sans-first:28.54 12:39:36 INFO - PROCESS | 1926 | Values: 101.0 102.0 135.0 107.0 172.0 119.0 175.0 152.0 101.0 136.0 12:39:36 INFO - PROCESS | 1926 | 12:39:36 INFO - PROCESS | 1926 | [#7] imaging-desaturate Cycles:10 Average:101.90 Median:91.00 stddev:13.35 (14.7%) stddev-sans-first:13.85 12:39:36 INFO - PROCESS | 1926 | Values: 94.0 96.0 110.0 92.0 93.0 112.0 115.0 90.0 89.0 128.0 12:39:36 INFO - PROCESS | 1926 | 12:39:36 INFO - PROCESS | 1926 | [#8] json-parse-financial Cycles:10 Average:60.00 Median:62.00 stddev:3.59 (5.8%) stddev-sans-first:2.77 12:39:36 INFO - PROCESS | 1926 | Values: 53.0 54.0 59.0 61.0 62.0 63.0 62.0 62.0 62.0 62.0 12:39:36 INFO - PROCESS | 1926 | 12:39:36 INFO - PROCESS | 1926 | [#9] json-stringify-tinderbox Cycles:10 Average:83.50 Median:70.50 stddev:18.52 (26.3%) stddev-sans-first:18.99 12:39:36 INFO - PROCESS | 1926 | Values: 70.0 110.0 118.0 100.0 78.0 69.0 71.0 74.0 70.0 75.0 12:39:36 INFO - PROCESS | 1926 | 12:39:36 INFO - PROCESS | 1926 | [#10] stanford-crypto-aes Cycles:10 Average:82.00 Median:79.00 stddev:10.65 (13.5%) stddev-sans-first:11.29 12:39:36 INFO - PROCESS | 1926 | Values: 82.0 77.0 81.0 72.0 72.0 101.0 74.0 73.0 94.0 94.0 12:39:36 INFO - PROCESS | 1926 | 12:39:36 INFO - PROCESS | 1926 | [#11] stanford-crypto-ccm Cycles:10 Average:150.10 Median:145.50 stddev:12.78 (8.8%) stddev-sans-first:13.29 12:39:36 INFO - PROCESS | 1926 | Values: 143.0 159.0 146.0 140.0 140.0 143.0 142.0 145.0 166.0 177.0 12:39:36 INFO - PROCESS | 1926 | 12:39:36 INFO - PROCESS | 1926 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:187.60 Median:188.50 stddev:26.32 (14.0%) stddev-sans-first:27.13 12:39:36 INFO - PROCESS | 1926 | Values: 170.0 196.0 150.0 226.0 232.0 181.0 178.0 203.0 170.0 170.0 12:39:36 INFO - PROCESS | 1926 | 12:39:36 INFO - PROCESS | 1926 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.00 Median:68.00 stddev:7.86 (11.6%) stddev-sans-first:8.34 12:39:36 INFO - PROCESS | 1926 | Values: 69.0 67.0 67.0 66.0 65.0 70.0 64.0 89.0 60.0 73.0 12:39:36 INFO - PROCESS | 1926 | -------- Summary: end -------- 12:39:36 INFO - PROCESS | 1926 | 12:39:37 INFO - TEST-INFO | 1926: exit 0 12:39:37 INFO - TEST-OK | kraken | took 61849ms 12:39:37 INFO - SUITE-END | took 1177s 12:39:37 INFO - Completed test suite (00:19:37) 12:39:37 INFO - javascript benchmark 12:39:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7325.318378523228, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3327.783694059886, 3405.421078416669, 3419.5725534308212, 3440.122768693983, 3467.427874400724, 5765.855853603657, 5805.738741756615, 5835.358110607835, 5850.5612079094135, 5864.677442740658, 5825.8252252432385, 5830.271216097988, 5880.294364676245, 5899.085641725539, 5921.082232254007, 5278.1291086970605, 5282.550983166426, 5307.575393968493, 5355.705436201027, 5364.624476286662, 5258.079836028699, 5264.657797243186, 5328.733563321838, 5368.1679339702405, 5379.408265090837, 5163.432022477525, 5179.870503237412, 5187.896242075175, 5296.390915044781, 5302.92045619316, 5146.433892271847, 5224.686518808883, 5252.474752524717, 5266.947330526684, 5287.286216360781, 5118.4625614310335, 5182.119039763212, 5187.870303242411, 5222.4255331913455, 5227.477252274742, 4469.307257375112, 4691.413573303337, 5455.890882182334, 5602.075657516505, 5660.886782264372, 5520.006398848206, 6168.0, 6175.536834737368, 6181.629102253834, 6234.657093859836, 5960.612560183574, 6293.590916590407, 6299.842503937392, 6310.274318453391, 6312.715927783261, 5767.8558036049435, 5995.100734889801, 5995.220621319244, 5999.0, 6002.8499287517725, 6219.222597175353, 6274.592151510137, 6286.685665716695, 6335.208098987627, 6432.935670643327, 6102.267727872595, 6153.7230824613, 6188.226471691039, 6196.256449226122, 6201.844953876189, 6278.937210627927, 6289.150964619809, 6292.402221788922, 6305.432511074026, 6317.14718513004, 6135.906433916272, 6184.165137706442, 6199.070139479114, 6233.096201050913, 6265.154204182377, 6291.716872740738, 6301.338359472281, 6308.274548426898, 6310.1796766420075, 6336.968315158395, 5030.446650868402, 5039.218921067167, 5042.092423363756, 5048.1418158913075, 5055.09008378488, 4422.248217802981, 4425.867223983286, 4496.1906856765445, 4506.616937560344, 4533.9319910201375, 6040.486558642477, 6105.877882442326, 6107.389261073857, 6112.633242005494, 6136.478399336017, 6192.876142477216, 6247.3440288769025, 6281.968590157111, 6284.811455656338, 6328.462080723098, 5960.64236145824, 6030.517558595302, 6073.726682299307, 6107.886786452505, 6116.082587611894, 5529.502344788908, 5533.667979921218, 5537.695426751487, 5539.2522009529, 5560.138178582326, 6535.379138981789, 6545.5090868184125, 6551.0500977358015, 6555.410013098844, 6570.704318305783, 6129.786714141161, 6151.231096112986, 6237.656928868956, 6248.781292654735, 6286.874262514815, 6485.221773387224, 6521.282658907611, 6524.380183882524, 6532.41208291256, 6543.312952140147, 6266.530010249158, 6280.183576135073, 6281.748730050746, 6292.779752708633, 6295.244570651368, 6061.72722227492, 6123.557162997564, 6169.5064394849205, 6222.533310001859, 6241.8751624966335, 6195.070739389128, 6230.252369715663, 6238.189035425366, 6245.9063114052415, 6256.562040657111, 6127.632342059491, 6180.845478863064, 6229.408206220402, 6241.500679945684, 6257.374262573706, 5881.9705901470215, 5925.911111333247, 5961.046232602763, 5998.49012833914, 6036.0644100164545, 6125.388069147199, 6233.127362169391, 6236.968815155895, 6251.718672659832, 6279.937200627935, 6101.542384321105, 6102.420270074336, 6109.84725381869, 6124.969375153096, 6150.477209437248], "value": 5776.8859407832715, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [424.4439783883113, 463.1038939651774, 474.93825802645694, 491.13314999026704, 508.66682323078373, 37713.05717357064, 40537.59462405375, 40581.59418405822, 40582.59417405825, 40598.78203653891, 41793.79103104488, 42164.78917605415, 42584.787076064575, 42725.35911961323, 42773.78613106938, 8448.197421244971, 8665.69670061548, 9135.81728365434, 9527.952360238189, 11258.605948791794, 11213.831792523119, 11468.082553395709, 11479.253848499862, 11498.71253218672, 11501.884981150208, 11499.655010349703, 11545.942270288637, 11547.53809847605, 11550.71123221922, 11571.363575003372, 11438.942805286004, 11439.142064345166, 11489.885101148966, 11496.33303171044, 11515.827262591069, 11342.71643208918, 11403.771924561463, 11422.428878556038, 11427.542898284059, 11440.542378304941, 40653.0, 40681.38977915334, 41073.794631026954, 41194.79402602998, 41314.38028429576, 3329.6170940341926, 3956.4065390191413, 3986.402039694069, 4006.399040143973, 4052.8378864845668, 24357.0, 28816.84732610714, 30530.0, 31117.0, 31726.682733172605, 3201.6638252983566, 3221.114193596756, 3224.1778346521724, 3241.0, 3256.7557433192605, 442624.0, 442694.0, 443677.0, 446099.0, 446154.0, 62357.376426236064, 62419.6879015602, 62624.68687656624, 62864.685676571324, 62960.68519657372, 64884.67557662181, 65212.67393663096, 65606.67196664082, 65630.01554976679, 65682.0, 12650.430730617145, 16336.836631633769, 17085.572860678334, 17086.572835679206, 17144.39994600183, 9758.512074396253, 12054.337011464278, 12703.364831758376, 13117.0818042738, 13154.0, 17118.0, 17244.396446124578, 17283.0, 17314.740278895828, 17333.306667733148, 6548.967255163694, 6556.409923106943, 6603.141591593062, 6622.966885165543, 6645.833854153685, 842.1326034184799, 849.8597731374377, 856.280724191682, 879.9736007919772, 899.6626265150569, 30156.84921575422, 31190.376192476022, 31201.531977020364, 31330.530042049388, 31615.52576711351, 909.3225546967551, 924.6162842420455, 929.1405449958896, 1090.3022065877844, 1102.7077824376547, 15121.773173402407, 16206.0, 16288.104154271628, 16615.41846035407, 16811.57971050709, 592066.0, 599486.0, 602299.0, 603200.0, 606653.0, 56660.716696417076, 57124.42875571191, 57758.42241577614, 57786.7110664444, 57826.0, 1394.5676840179576, 1428.3144090836456, 1433.2833583208396, 1437.5759151050675, 1448.9565313040625, 25431.36421589475, 28611.9985800496, 30659.54010689886, 30920.536191957588, 30982.0705378839, 52698.47301526936, 52954.0, 53386.46613533815, 53388.46611533835, 53482.19776703275, 590802.0, 592253.0387348208, 594537.0273148606, 597730.0113499404, 598884.0, 35878.0, 36820.44769328516, 42938.57061429346, 43886.56113438825, 44037.11925761403, 47550.76224618855, 47586.52413475821, 48083.75958120187, 49405.752971236354, 49449.25826112683, 32488.35023299568, 36653.26693466169, 38916.41625375678, 40945.976350591474, 41264.381034283906, 32773.672263278015, 38999.22001559896, 39095.609043910335, 40469.797651011555, 40478.595214047484, 37634.81182594179, 37880.810595946845, 38416.42375364316, 38935.6106438932, 39220.215595687354, 30479.390412192075, 34048.82975585106, 34551.65448345484, 35043.47434788531, 35398.938031859085, 20565.89717051405, 20908.85001324942, 24792.876035619705, 25424.491510169322, 26034.73965260399], "value": 20393.52240888074, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [377.52997518089984, 392.73882867892837, 392.8866832092637, 402.16951994132114, 404.27029212271844, 16474.83525164748, 20758.27346042889, 21302.57394852102, 21566.784332156694, 21703.806290654007, 20883.269085582007, 21145.0, 21881.562368752657, 22202.333929982087, 22245.998930048147, 5608.635438696492, 5922.0, 5992.310884248323, 5994.520438364921, 6014.458698717136, 5786.450287222707, 5809.535237181015, 5848.824535263949, 5933.910991335133, 5965.508622844289, 5162.735129893163, 5668.376478587373, 5785.305763308409, 5871.119332100197, 5881.4118588141055, 4468.597826195625, 5686.744096515667, 5820.447057529528, 5850.736716847752, 5876.206712093898, 4442.800073996678, 4576.931346029813, 5167.682241028551, 5195.090859099642, 5203.0114278287, 26005.479890402275, 26417.73582264172, 26552.867235663893, 26753.732462675318, 26773.330666733487, 2635.143578337048, 2675.063727695291, 2676.47808677308, 2735.617013618095, 2738.315421144714, 15552.766708499381, 15893.523194304189, 16017.759733604005, 16168.029918204946, 16295.881535400322, 1811.7735283089614, 1867.4397680695738, 1872.1107473949764, 1890.6218756248668, 1891.2435025989578, 136178.0, 137646.0, 140856.0, 147549.0, 157051.85896282125, 11949.701257468632, 12054.939725301492, 12173.634790956285, 12181.390930453443, 12331.260124392566, 15774.9211253943, 15940.92029539868, 15945.601359966093, 15949.681006379808, 15978.201089945456, 8722.607482663296, 8937.0, 8987.595558199897, 9090.045545217788, 9115.36192466521, 8742.475451472932, 8817.823643527221, 8819.206271435602, 8936.41913275648, 8962.193402593797, 4804.375431193923, 4829.879253018703, 4868.758466590996, 4874.731889746098, 4949.7030178189425, 1722.836330548596, 1760.4771872330598, 2066.442060643648, 2093.842961777873, 2094.884781337041, 873.5937788928179, 975.8728668388023, 980.5685498380832, 991.7272749993665, 993.8918106311411, 20795.0, 21302.893485532473, 21339.35981920545, 21358.78641213568, 21424.250151244632, 981.7398389426808, 982.6462473509531, 1011.1354791653177, 1016.7966406718685, 1037.9896201038043, 10847.186461015297, 11094.334339939469, 11102.444877756241, 11121.0, 11150.21948463616, 625834.0, 667153.0, 707973.0, 763010.0, 770814.0, 117304.65390692139, 156845.0, 159074.0, 165063.0, 171126.28873711103, 915.6291701860757, 1018.3533456255332, 1023.5803320638503, 1073.6510634044123, 1074.3607553505651, 19131.426057218305, 19888.806671599752, 20335.79664203339, 20683.4829129273, 20709.68935465939, 59273.0, 60450.395496044475, 60635.69682151561, 61503.692481537306, 62251.688741556005, 85424.57287713522, 87380.5630971841, 89084.0, 90479.0, 90979.09020909706, 7017.894731579133, 8175.223353781381, 8196.303314218358, 8425.915740842513, 8528.189821966904, 12116.636500904988, 12165.148439609142, 12831.550895718605, 12993.740125197632, 13382.330883455594, 10932.180086493703, 11049.613263535737, 11234.0, 11272.267302625303, 11472.713182170513, 8857.424267422597, 11079.113670906147, 11135.99776020162, 11243.212975091665, 11355.772884542428, 11339.0, 11350.375729334966, 11525.308481491138, 11617.477213525242, 11677.824832627688, 5736.512396446349, 7422.331990120699, 8324.958375208085, 8657.177568131017, 8698.0, 9547.474888881177, 10822.134229261797, 10951.671449856518, 11163.386013768995, 11355.602553910574], "value": 10085.513458871123, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1430.5708287513737, 1431.8067060946769, 1445.4940770730245, 1455.541504426106, 1462.4223431744465, 6586.802395928118, 6974.407175390098, 7498.737544185955, 8068.878966815502, 8503.872441913376, 6519.380658837415, 8166.877496837537, 8229.83540329193, 8379.83240335193, 8412.957935210316, 1798.7032139696935, 2010.3566858605216, 2343.4258606641397, 2346.4133966508375, 2354.434935615457, 2095.1200495791727, 2173.619616567102, 2328.138588722175, 2370.8933098010543, 2376.1327115602853, 1878.4083013850661, 1933.3040105561986, 1940.6797878350055, 2347.2371479269223, 2354.023080421623, 2041.397787652646, 2312.4681323295654, 2335.1359996801207, 2341.4380548668196, 2345.566070277004, 2317.5133222023396, 2356.091297293537, 2367.863425555743, 2377.09670325277, 2385.8449200801892, 7621.58081305528, 7889.0, 8321.916780832235, 8347.747837824314, 8388.11924747905, 5105.361829771278, 5254.500822421864, 5254.500822421902, 5257.421683614799, 5296.894062118775, 3491.127218195451, 3538.681518663307, 3575.856965721368, 3605.4231322988194, 3610.6930910872607, 1210.4431961297726, 1210.7881120803825, 1247.731737676404, 1265.5001274496615, 1271.2436100520176, 10470.633527826489, 10591.25861189717, 10634.361938283728, 10698.03717665414, 10743.14054875608, 3767.3972164454103, 4660.906781864412, 4704.882377940579, 4716.434027916672, 4731.526847315241, 9341.766455838522, 9491.572879220452, 9495.572699228413, 9508.001659825757, 9524.380915240487, 5235.188545775411, 5263.605229607718, 5269.57843372537, 5270.446603106695, 5282.392524859613, 4536.4102666653125, 4679.508651591602, 4799.088173247072, 4840.661153719206, 4859.0, 3929.25344184604, 3980.9402858957137, 4017.738846974937, 4057.39139129133, 4081.673466122703, 1203.9699007524882, 1231.605886116452, 1279.0982357437983, 1311.4491913396396, 1321.3393303348325, 731.8353370491596, 748.8614606297853, 768.5772824946257, 784.6469088910013, 810.1614828652316, 4476.306172543261, 5534.640248383842, 5553.527950124204, 5570.164475328652, 5711.485966262973, 896.3322324868016, 968.3802366485455, 971.455984648599, 1021.3565453764081, 1041.5886473828004, 3161.9841900790348, 3577.767445116059, 3801.9429708554394, 3854.3062248794927, 3861.5366156061455, 9612.903870961201, 9782.951085244527, 9937.801243975224, 9992.150667193224, 10001.2999090063, 9788.21694264457, 9831.803363932824, 9875.802483950425, 9898.0, 9917.75205619851, 7412.332890039922, 7685.807854803675, 7732.536047837148, 7734.187910269341, 7864.056313242448, 5489.094299440548, 5582.385937546947, 5621.19834999655, 5673.177389278614, 5747.683877386784, 8814.823703525766, 8983.730488085368, 9006.369554131148, 9155.13026262504, 9209.815803684023, 9033.548322583976, 9176.0, 9197.954010229905, 9459.148676619137, 9482.95258523703, 7180.138383393817, 7261.709531618676, 7284.635768211674, 7293.270672932665, 7301.269873012656, 7715.729949451742, 7780.416468764978, 7789.0, 7819.960900195462, 8485.448445851, 5137.8201762938115, 6262.561620686508, 7106.680199390937, 7146.392556632744, 7163.176234733072, 6144.324124346408, 6808.421284190899, 6878.727435424463, 6926.792196234121, 6962.094927659372, 6303.621782693053, 6429.51778616615, 6467.15926929515, 6477.0, 6484.351564843478, 4816.253480710496, 4837.177679794373, 4972.801087956441, 4975.601951843916, 5128.615353848401, 4964.131277026579, 4970.602351811919, 5035.144025515744, 5045.6720313180995, 5082.949170508396], "value": 4365.621571443925, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [329.377476569284, 350.6598599358623, 354.75699146084935, 366.38996071540885, 372.6348178784792, 4373.294415178083, 4740.288956656499, 5134.897302053957, 5405.324334458192, 5498.725063746811, 4833.68581042232, 4910.828121015765, 5361.678299302044, 5448.128299472085, 5457.181422786584, 4233.0, 4289.313709806432, 4295.484541854982, 4301.000034983732, 4326.675499337546, 3541.982290088559, 3731.9067023324496, 4227.598378154071, 4310.633596144332, 4329.956700433003, 4216.915661686764, 4227.3447615619625, 4230.74615523068, 4261.914761704764, 4280.935785963213, 3741.1769410729594, 3826.1391186982983, 4016.256992456405, 4218.156368726267, 4291.463567054118, 3184.8726050957935, 3188.187012311869, 3825.3496905526126, 3829.329867273244, 3830.2148059647707, 4109.91780164398, 4574.565416285481, 5155.819546315861, 5216.2958000669805, 5275.736213189325, 3879.7478163919254, 4900.0, 4920.483349248313, 4969.552740253358, 4996.675216110941, 5018.874528136789, 5720.2256905400845, 5720.542356611461, 5795.898779231932, 5831.591788574801, 3490.6334834842482, 3621.4386770050683, 3897.707671924589, 4133.731307465006, 4206.053637931472, 5261.7111556473055, 5364.927014597096, 5639.492445679867, 5974.0, 5985.820425387245, 3835.9808200959374, 3909.139989202428, 4153.8576891355115, 4246.278132717445, 4246.617804397619, 3678.668919797231, 3817.9618203818163, 4152.771597562163, 4325.416068830667, 4328.350747387854, 4904.068227036852, 4911.45973942866, 4928.6303527235605, 4950.628702847301, 4954.430240522386, 4476.35092911526, 5700.657960522382, 5742.712864356766, 5785.945989173867, 5811.82564523065, 1238.9566365177175, 1312.3897387714758, 1332.4470344806796, 1407.6345944433883, 1412.11037046662, 974.4396971741277, 974.9171320437842, 991.6033586565432, 998.3011891675856, 998.9650362237287, 4905.798079470481, 5076.162433198481, 5120.564751996048, 5121.487851214849, 5155.407128180233, 4937.753112344369, 5282.735863206901, 5357.5446087082255, 5620.943790562042, 5677.104676614874, 4156.646685031807, 4428.468583769968, 4650.534946505322, 4773.491483759434, 5113.207452844741, 2528.9378461046404, 2530.8481491110592, 2576.600626902833, 2603.622474741152, 2659.4415172813538, 4884.731339776346, 4978.581104385233, 5274.393444753826, 5445.863853403696, 5769.538436925036, 5173.275741396207, 5207.3336532309295, 5513.724313784295, 5653.378128405872, 5703.74333155009, 4787.712737235777, 4818.975905120452, 5412.945870541323, 5575.832725018256, 5900.852478688067, 5139.486051394831, 5585.804496842591, 5596.384397716167, 5607.8503906698215, 5687.459691329317, 4925.6305777066145, 5151.6908985461, 5154.61340399479, 5461.361591522448, 5541.251930989265, 4851.781669824937, 5284.41871394154, 5883.676397798162, 5910.906482300788, 5980.19267398913, 3587.1032241939515, 3915.823787929493, 3938.783366914847, 4069.0, 4081.0, 5396.9730151348995, 5590.385057643574, 5601.29983752031, 5742.707890724553, 5828.679422631626, 5348.5186333230195, 5437.4018857926385, 5509.090999984957, 5605.859853503532, 5783.913241301299, 5101.69389836611, 5241.842744717665, 5389.0, 5618.101103823368, 5707.571932105026, 4423.8066706611, 5003.67476114066, 5532.1425179097305, 5552.472515111058, 5667.518260947865, 4992.452339774682, 5230.686158830483, 5436.124537034717, 5690.118031705092, 5692.459216374438, 4658.953410465988, 5548.694821784679, 5641.887162256814, 5672.334003018575, 5676.877717255576], "value": 4118.055088026959, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [570.412475150595, 706.4630880530796, 737.6533029476146, 770.2643975003868, 801.5110782422721, 14764.852351476482, 15596.610084747887, 15616.765748513753, 15665.0, 15666.999999999985, 12887.613371598844, 13963.930180349083, 13982.860171398283, 14084.225367604779, 14166.362513686883, 853.32160932059, 866.969656062038, 879.5118709116438, 881.9073997230295, 882.74400423877, 853.8548446764034, 869.7043005378171, 870.277669534285, 872.9170728780756, 872.9301655867515, 903.2006674093442, 907.3421769217298, 915.5513798238849, 918.7932715139074, 923.3997901364133, 903.1961554216753, 904.6969265296101, 904.7557159566913, 909.6225066597354, 910.3672947301628, 676.2898956096079, 679.0, 680.8604236131581, 681.8670359279943, 684.5139950635055, 13234.139780914305, 13391.665208369868, 13527.323633818369, 13664.726705465835, 13817.032807703465, 11822.172447928648, 11844.34856082915, 11896.405179741065, 12019.459124339426, 12160.878391216063, 8705.129487051308, 9935.751606209831, 10197.827249866286, 10310.896891031143, 10574.365538067741, 3054.6945305469276, 3192.233863872677, 3414.9829250853836, 3581.158427769472, 3597.37046016946, 100153.99846001592, 100187.49906250495, 100596.49701751444, 101981.0, 102265.0, 7489.812754681177, 8558.700445484377, 8672.132786721404, 8873.29013678917, 8922.973858006282, 19425.0, 19520.60958780845, 19670.311539096063, 19683.507912302306, 19748.0, 7417.480776345604, 7500.849983000309, 7508.924910750932, 7560.130584982688, 7565.6973721050545, 4031.133306339151, 4117.835286588503, 4192.350185721218, 4227.682923780729, 4289.91420171601, 3469.028671971852, 3538.6992105671306, 3539.2036791721657, 3555.235624340779, 3563.6614521620404, 521.262413684632, 608.0484042473515, 640.1198352265635, 646.9870602587922, 651.0689713709346, 529.4070640882237, 625.4746013348808, 630.6279295215845, 630.7256343490596, 643.7939859244997, 9309.953450232706, 9662.405703059057, 9762.853557196648, 9843.409395436154, 10061.490776383602, 595.6455908734296, 601.9668918209453, 629.7669862151033, 646.5538778242998, 651.837040739815, 4476.440444944382, 4809.917768502059, 4834.589059929875, 4836.419629644465, 4883.633727470383, 65393.34606653968, 94725.52637236769, 109858.35212471819, 110850.89149108567, 111917.0, 92232.53883730673, 93505.0, 94668.0, 96011.51994239984, 99215.0, 65840.34159658341, 66001.0, 67985.32014679888, 76167.47665046668, 83903.0, 10342.32774869661, 10355.275130741076, 10697.786044279226, 10803.45982700846, 11085.889141108808, 37480.625193747714, 48273.75863120662, 49610.503894962036, 51596.0, 52398.21402678887, 64670.67664661835, 65815.670921647, 68852.96720549295, 69683.0, 71016.3675685414, 12758.234505929673, 12854.614361569167, 12919.22484650924, 13212.273324967218, 13395.598132056053, 14011.159330440207, 14348.641283967985, 14645.121292722471, 14731.0, 15141.62145946316, 13250.536231231861, 13637.181769093886, 13660.248686321951, 13688.794668080185, 13705.862941370857, 13072.673183170496, 13428.865711342762, 13555.254461004739, 13664.453421863014, 13869.791953120914, 12729.363531823557, 13021.414036368196, 13285.202887826761, 13316.733665326832, 13888.305584720521, 9317.72046838596, 9867.802643947225, 10379.142695884055, 11172.273802202832, 11195.211411610928, 7899.802504937423, 10981.670549883516, 11126.332420054823, 11156.386398748147, 11497.770044598894], "value": 7232.491814418557, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1608.4999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 121.0, 162.0, 174.0, 123.0, 105.0, 172.0, 174.0, 131.0, 119.0], "value": 138.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 121.0, 148.0, 142.0, 150.0, 146.0, 145.0, 119.0, 137.0, 112.0], "value": 136.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 203.0, 197.0, 200.0, 262.0, 281.0, 196.0, 200.0, 195.0, 198.0], "value": 213.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 59.0, 61.0, 90.0, 62.0, 59.0, 60.0, 59.0, 75.0, 73.0], "value": 68.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 71.0, 110.0, 103.0, 101.0, 70.0, 81.0, 71.0, 71.0, 78.0], "value": 85.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 115.0, 133.0, 108.0, 88.0, 106.0, 137.0, 86.0, 90.0, 91.0], "value": 103.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 102.0, 135.0, 107.0, 172.0, 119.0, 175.0, 152.0, 101.0, 136.0], "value": 130.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 96.0, 110.0, 92.0, 93.0, 112.0, 115.0, 90.0, 89.0, 128.0], "value": 101.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 54.0, 59.0, 61.0, 62.0, 63.0, 62.0, 62.0, 62.0, 62.0], "value": 60.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 110.0, 118.0, 100.0, 78.0, 69.0, 71.0, 74.0, 70.0, 75.0], "value": 83.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 77.0, 81.0, 72.0, 72.0, 101.0, 74.0, 73.0, 94.0, 94.0], "value": 82.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 159.0, 146.0, 140.0, 140.0, 143.0, 142.0, 145.0, 166.0, 177.0], "value": 150.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 196.0, 150.0, 226.0, 232.0, 181.0, 178.0, 203.0, 170.0, 170.0], "value": 187.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 67.0, 67.0, 66.0, 65.0, 70.0, 64.0, 89.0, 60.0, 73.0], "value": 69.0, "unit": "score"}]}]} 12:39:37 INFO - Return code: 0 12:39:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:39:37 ERROR - # TBPL SUCCESS # 12:39:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:39:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:39:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:39:37 INFO - 2017-02-16 12:39:37.717 screenresolution[1931:61960] starting screenresolution argv=screenresolution get 12:39:37 INFO - 2017-02-16 12:39:37.719 screenresolution[1931:61960] Display 0: 1600x1200x32@60 12:39:37 INFO - 2017-02-16 12:39:37.725 screenresolution[1932:61963] starting screenresolution argv=screenresolution list 12:39:37 INFO - Available Modes on Display 0 12:39:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:39:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:39:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:39:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:39:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:39:37 INFO - Intel Iris: 12:39:37 INFO - Chipset Model: Intel Iris 12:39:37 INFO - Type: GPU 12:39:37 INFO - Bus: Built-In 12:39:37 INFO - VRAM (Dynamic, Max): 1536 MB 12:39:37 INFO - Vendor: Intel (0x8086) 12:39:37 INFO - Device ID: 0x0a2e 12:39:37 INFO - Revision ID: 0x0009 12:39:37 INFO - Displays: 12:39:37 INFO - Display: 12:39:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:39:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:39:37 INFO - Display Serial Number: 1600x1200 60 12:39:37 INFO - Main Display: Yes 12:39:37 INFO - Mirror: Off 12:39:37 INFO - Online: Yes 12:39:37 INFO - Rotation: Supported 12:39:37 INFO - Return code: 0 12:39:37 INFO - Running post-action listener: _resource_record_post_action 12:39:37 INFO - [mozharness: 2017-02-16 20:39:37.963566Z] Finished run-tests step (success) 12:39:37 INFO - Running post-run listener: _resource_record_post_run 12:39:38 INFO - Total resource usage - Wall time: 1198s; CPU: 54.0%; Read bytes: 1127936; Write bytes: 510105600; Read time: 13; Write time: 1841 12:39:38 INFO - TinderboxPrint: CPU usage
54.3% 12:39:38 INFO - TinderboxPrint: I/O read bytes / time
1,127,936 / 13 12:39:38 INFO - TinderboxPrint: I/O write bytes / time
510,105,600 / 1,841 12:39:38 INFO - TinderboxPrint: CPU idle
2,190.3 (45.7%) 12:39:38 INFO - TinderboxPrint: CPU system
965.7 (20.1%) 12:39:38 INFO - TinderboxPrint: CPU user
1,637.7 (34.2%) 12:39:38 INFO - TinderboxPrint: Swap in / out
208,445,440 / 0 12:39:38 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 208642560; Write bytes: 208584704; Read time: 15957; Write time: 423 12:39:38 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 984576; Write bytes: 299849728; Read time: 11; Write time: 1410 12:39:38 INFO - Running post-run listener: _upload_blobber_files 12:39:38 INFO - Blob upload gear active. 12:39:38 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:39:38 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 12:39:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:39:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:39:38 INFO - (blobuploader) - INFO - Open directory for files ... 12:39:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:39:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:39:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:39:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:39:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:39:40 INFO - (blobuploader) - INFO - Done attempting. 12:39:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:39:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:39:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:39:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:39:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:39:41 INFO - (blobuploader) - INFO - Done attempting. 12:39:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:39:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:39:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:39:42 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:39:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:39:42 INFO - (blobuploader) - INFO - Done attempting. 12:39:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:39:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:39:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:39:44 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:39:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:39:44 INFO - (blobuploader) - INFO - Done attempting. 12:39:44 INFO - (blobuploader) - INFO - Iteration through files over. 12:39:44 INFO - Return code: 0 12:39:44 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:39:44 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:39:44 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/15a0690288c16add5d81877b59ad8e02ad903f6d423b36c6787a6954460963525982907726c59eae3930bf5220bf11daeee29d7fda8cacf5dd34f68326b73825", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8add4cb4dda23aeaf948e459ee60a0c4e6659824b89981ea6ca726d510c5ee5134bb5eedd85ba900318f11c3d1c167e3f5b3a4939df980385a99074b28aa5bcf", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e2344ee59a04ed0bee9f095bfd4df806910c8cab1edc6183b669354da875dd4c00ce627f3766ecf92d637234674e13c69c2f92e7c335ec77a707f9bbf6643905", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/25d4c84628781297c2d6634689a59538e0e9b11698776a8b05e0866fcf700abf71e69abab48836730546ade46e0091464fc04f8467b3b42f981d446a835e7e41"} 12:39:44 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:39:44 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:39:44 INFO - Contents: 12:39:44 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/15a0690288c16add5d81877b59ad8e02ad903f6d423b36c6787a6954460963525982907726c59eae3930bf5220bf11daeee29d7fda8cacf5dd34f68326b73825", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8add4cb4dda23aeaf948e459ee60a0c4e6659824b89981ea6ca726d510c5ee5134bb5eedd85ba900318f11c3d1c167e3f5b3a4939df980385a99074b28aa5bcf", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e2344ee59a04ed0bee9f095bfd4df806910c8cab1edc6183b669354da875dd4c00ce627f3766ecf92d637234674e13c69c2f92e7c335ec77a707f9bbf6643905", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/25d4c84628781297c2d6634689a59538e0e9b11698776a8b05e0866fcf700abf71e69abab48836730546ade46e0091464fc04f8467b3b42f981d446a835e7e41"} 12:39:44 INFO - Running post-run listener: copy_logs_to_upload_dir 12:39:44 INFO - Copying logs to upload dir... 12:39:44 INFO - mkdir: /builds/slave/test/build/upload/logs 12:39:44 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1262.678665 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2017-02-16 12:39:44.594536) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-16 12:39:44.598177) ========= 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.gV7bdHGAYL/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.F1rQ6eraSl/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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/15a0690288c16add5d81877b59ad8e02ad903f6d423b36c6787a6954460963525982907726c59eae3930bf5220bf11daeee29d7fda8cacf5dd34f68326b73825", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8add4cb4dda23aeaf948e459ee60a0c4e6659824b89981ea6ca726d510c5ee5134bb5eedd85ba900318f11c3d1c167e3f5b3a4939df980385a99074b28aa5bcf", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e2344ee59a04ed0bee9f095bfd4df806910c8cab1edc6183b669354da875dd4c00ce627f3766ecf92d637234674e13c69c2f92e7c335ec77a707f9bbf6643905", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/25d4c84628781297c2d6634689a59538e0e9b11698776a8b05e0866fcf700abf71e69abab48836730546ade46e0091464fc04f8467b3b42f981d446a835e7e41"} build_url:https://queue.taskcluster.net/v1/task/eVX5LB1ZQ-eIxI4EcNF3hg/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011036 build_url: 'https://queue.taskcluster.net/v1/task/eVX5LB1ZQ-eIxI4EcNF3hg/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/15a0690288c16add5d81877b59ad8e02ad903f6d423b36c6787a6954460963525982907726c59eae3930bf5220bf11daeee29d7fda8cacf5dd34f68326b73825", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8add4cb4dda23aeaf948e459ee60a0c4e6659824b89981ea6ca726d510c5ee5134bb5eedd85ba900318f11c3d1c167e3f5b3a4939df980385a99074b28aa5bcf", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e2344ee59a04ed0bee9f095bfd4df806910c8cab1edc6183b669354da875dd4c00ce627f3766ecf92d637234674e13c69c2f92e7c335ec77a707f9bbf6643905", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/25d4c84628781297c2d6634689a59538e0e9b11698776a8b05e0866fcf700abf71e69abab48836730546ade46e0091464fc04f8467b3b42f981d446a835e7e41"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-16 12:39:44.632184) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-16 12:39:44.632513) ========= 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.gV7bdHGAYL/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.F1rQ6eraSl/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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.005388 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-16 12:39:44.682461) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-16 12:39:44.682931) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-16 12:39:44.683323) ========= ========= Total master_lag: 0.18 =========