builder: graphics_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0108 starttime: 1483053274.55 results: success (0) buildid: 20161229135219 builduid: d103706511054fd797cc69c12b5a7936 revision: 8999cab905a4fd17d22d1d3ba687e6f1aab374f1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-29 15:14:34.546322) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-29 15:14:34.546809) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-29 15:14:34.547082) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-29 15:14:34.590855) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 15:14:34.591159) ========= 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.vw93tOwmJ3/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.NnmPiRQHAD/Listeners TMPDIR=/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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 --2016-12-29 15:14:34-- 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.3M=0.001s 2016-12-29 15:14:35 (10.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.530598 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 15:14:35.144755) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 15:14:35.145053) ========= 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.vw93tOwmJ3/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.NnmPiRQHAD/Listeners TMPDIR=/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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.019330 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 15:14:35.193810) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-29 15:14:35.194127) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev 8999cab905a4fd17d22d1d3ba687e6f1aab374f1 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/graphics --rev 8999cab905a4fd17d22d1d3ba687e6f1aab374f1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.vw93tOwmJ3/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.NnmPiRQHAD/Listeners TMPDIR=/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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 2016-12-29 15:14:35,272 truncating revision to first 12 chars 2016-12-29 15:14:35,272 Setting DEBUG logging. 2016-12-29 15:14:35,272 attempt 1/10 2016-12-29 15:14:35,272 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/8999cab905a4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-29 15:14:36,032 unpacking tar archive at: graphics-8999cab905a4/testing/mozharness/ program finished with exit code 0 elapsedTime=1.009362 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-29 15:14:36.228061) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-29 15:14:36.228411) ========= script_repo_revision: 8999cab905a4fd17d22d1d3ba687e6f1aab374f1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-29 15:14:36.228806) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-29 15:14:36.229070) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-29 15:14:36.253687) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2016-12-29 15:14:36.254141) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Graphics --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 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', '--add-option', '--webServer,localhost', '--branch-name', 'Graphics', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Graphics'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.vw93tOwmJ3/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.NnmPiRQHAD/Listeners TMPDIR=/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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 15:14:36 INFO - MultiFileLogger online at 20161229 15:14:36 in /builds/slave/test 15:14:36 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Graphics --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 15:14:36 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:14:36 INFO - {'append_to_log': False, 15:14:36 INFO - 'base_work_dir': '/builds/slave/test', 15:14:36 INFO - 'blob_upload_branch': 'Graphics', 15:14:36 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:14:36 INFO - 'branch': 'Graphics', 15:14:36 INFO - 'buildbot_json_path': 'buildprops.json', 15:14:36 INFO - 'config_files': ('talos/mac_config.py',), 15:14:36 INFO - 'default_actions': ('clobber', 15:14:36 INFO - 'read-buildbot-config', 15:14:36 INFO - 'download-and-extract', 15:14:36 INFO - 'populate-webroot', 15:14:36 INFO - 'create-virtualenv', 15:14:36 INFO - 'install', 15:14:36 INFO - 'run-tests'), 15:14:36 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:14:36 INFO - 'download_minidump_stackwalk': True, 15:14:36 INFO - 'download_symbols': 'ondemand', 15:14:36 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:14:36 INFO - 'tooltool.py': '/tools/tooltool.py', 15:14:36 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:14:36 INFO - '/tools/misc-python/virtualenv.py')}, 15:14:36 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:14:36 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:14:36 INFO - 'installer_path': 'installer.exe', 15:14:36 INFO - 'log_level': 'info', 15:14:36 INFO - 'log_name': 'talos', 15:14:36 INFO - 'log_to_console': True, 15:14:36 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:14:36 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:14:36 INFO - 'opt_config_files': (), 15:14:36 INFO - 'pip_index': False, 15:14:36 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:14:36 INFO - 'cmd': ('bash', 15:14:36 INFO - '-c', 15:14:36 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:14:36 INFO - 'enabled': True, 15:14:36 INFO - 'halt_on_failure': False, 15:14:36 INFO - 'name': 'check_screen_resolution'},), 15:14:36 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:14:36 INFO - 'cmd': ('bash', 15:14:36 INFO - '-c', 15:14:36 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:14:36 INFO - 'enabled': True, 15:14:36 INFO - 'halt_on_failure': False, 15:14:36 INFO - 'name': 'check_screen_resolution'},), 15:14:36 INFO - 'run_cmd_checks_enabled': True, 15:14:36 INFO - 'sps_profile': False, 15:14:36 INFO - 'sps_profile_interval': 0, 15:14:36 INFO - 'suite': 'dromaeojs', 15:14:36 INFO - 'system_bits': '32', 15:14:36 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:14:36 INFO - 'title': 't-yosemite-r7-0108', 15:14:36 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:14:36 INFO - 'use_talos_json': True, 15:14:36 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:14:36 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:14:36 INFO - 'work_dir': 'build'} 15:14:36 INFO - [mozharness: 2016-12-29 23:14:36.459944Z] Running clobber step. 15:14:36 INFO - Running pre-action listener: _resource_record_pre_action 15:14:36 INFO - Running main action method: clobber 15:14:36 INFO - rmtree: /builds/slave/test/build 15:14:36 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:14:37 INFO - Running post-action listener: _resource_record_post_action 15:14:37 INFO - [mozharness: 2016-12-29 23:14:37.422265Z] Finished clobber step (success) 15:14:37 INFO - [mozharness: 2016-12-29 23:14:37.422490Z] Running read-buildbot-config step. 15:14:37 INFO - Running pre-action listener: _resource_record_pre_action 15:14:37 INFO - Running main action method: read_buildbot_config 15:14:37 INFO - Using buildbot properties: 15:14:37 INFO - { 15:14:37 INFO - "project": "", 15:14:37 INFO - "product": "firefox", 15:14:37 INFO - "script_repo_revision": "default", 15:14:37 INFO - "builddir": "graphics_yosemite_r7_test-dromaeojs", 15:14:37 INFO - "repository": "", 15:14:37 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 graphics talos dromaeojs", 15:14:37 INFO - "buildid": "20161229135219", 15:14:37 INFO - "pgo_build": "False", 15:14:37 INFO - "basedir": "/builds/slave/test", 15:14:37 INFO - "buildnumber": 17, 15:14:37 INFO - "slavename": "t-yosemite-r7-0108", 15:14:37 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 15:14:37 INFO - "platform": "yosemite_r7", 15:14:37 INFO - "scheduler": "tests-graphics-macosx64-talos", 15:14:37 INFO - "branch": "graphics", 15:14:37 INFO - "repo_path": "projects/graphics", 15:14:37 INFO - "revision": "8999cab905a4fd17d22d1d3ba687e6f1aab374f1", 15:14:37 INFO - "stage_platform": "macosx64", 15:14:37 INFO - "builduid": "d103706511054fd797cc69c12b5a7936", 15:14:37 INFO - "slavebuilddir": "test" 15:14:37 INFO - } 15:14:37 INFO - Found installer url https://queue.taskcluster.net/v1/task/CsWQ6Z-zSr-ht0-CsmSleg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 15:14:37 INFO - Running post-action listener: _resource_record_post_action 15:14:37 INFO - [mozharness: 2016-12-29 23:14:37.425816Z] Finished read-buildbot-config step (success) 15:14:37 INFO - [mozharness: 2016-12-29 23:14:37.425957Z] Running download-and-extract step. 15:14:37 INFO - Running pre-action listener: _resource_record_pre_action 15:14:37 INFO - Running main action method: download_and_extract 15:14:37 INFO - mkdir: /builds/slave/test/build/tests 15:14: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')]} 15:14:37 INFO - trying https://queue.taskcluster.net/v1/task/CsWQ6Z-zSr-ht0-CsmSleg/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 15:14:37 INFO - Downloading https://queue.taskcluster.net/v1/task/CsWQ6Z-zSr-ht0-CsmSleg/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 15:14:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CsWQ6Z-zSr-ht0-CsmSleg/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 15:14:38 INFO - Downloaded 1199 bytes. 15:14:38 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 15:14:38 INFO - Using the following test package requirements: 15:14:38 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 15:14:38 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 15:14:38 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 15:14:38 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 15:14:38 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 15:14:38 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 15:14:38 INFO - u'jsshell-mac.zip'], 15:14:38 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 15:14:38 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 15:14:38 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 15:14:38 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 15:14:38 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 15:14:38 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 15:14:38 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 15:14:38 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 15:14:38 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 15:14:38 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 15:14:38 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 15:14:38 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 15:14:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CsWQ6Z-zSr-ht0-CsmSleg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 15:14:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CsWQ6Z-zSr-ht0-CsmSleg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:14:38 INFO - Fetch https://queue.taskcluster.net/v1/task/CsWQ6Z-zSr-ht0-CsmSleg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 15:14:39 INFO - Expected file size: 16298980 15:14:39 INFO - Obtained file size: 16298980 15:14:46 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 15:14:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CsWQ6Z-zSr-ht0-CsmSleg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 15:14:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CsWQ6Z-zSr-ht0-CsmSleg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:14:46 INFO - Fetch https://queue.taskcluster.net/v1/task/CsWQ6Z-zSr-ht0-CsmSleg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 15:14:47 INFO - Expected file size: 16298980 15:14:47 INFO - Obtained file size: 16298980 15:14:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CsWQ6Z-zSr-ht0-CsmSleg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 15:14:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CsWQ6Z-zSr-ht0-CsmSleg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 15:14:53 INFO - Fetch https://queue.taskcluster.net/v1/task/CsWQ6Z-zSr-ht0-CsmSleg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 15:14:55 INFO - Expected file size: 14409776 15:14:55 INFO - Obtained file size: 14409776 15:14:55 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')]} 15:14:55 INFO - trying https://queue.taskcluster.net/v1/task/CsWQ6Z-zSr-ht0-CsmSleg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 15:14:55 INFO - Downloading https://queue.taskcluster.net/v1/task/CsWQ6Z-zSr-ht0-CsmSleg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 15:14:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CsWQ6Z-zSr-ht0-CsmSleg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 15:14:57 INFO - Downloaded 69782664 bytes. 15:14:57 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CsWQ6Z-zSr-ht0-CsmSleg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 15:14:57 INFO - mkdir: /builds/slave/test/properties 15:14:57 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:14:57 INFO - Writing to file /builds/slave/test/properties/build_url 15:14:57 INFO - Contents: 15:14:57 INFO - build_url:https://queue.taskcluster.net/v1/task/CsWQ6Z-zSr-ht0-CsmSleg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 15:14:59 INFO - Running post-action listener: _resource_record_post_action 15:14:59 INFO - Running post-action listener: set_extra_try_arguments 15:14:59 INFO - [mozharness: 2016-12-29 23:14:59.093489Z] Finished download-and-extract step (success) 15:14:59 INFO - [mozharness: 2016-12-29 23:14:59.093639Z] Running populate-webroot step. 15:14:59 INFO - Running pre-action listener: _resource_record_pre_action 15:14:59 INFO - Running main action method: populate_webroot 15:14:59 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:14:59 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:14:59 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:14:59 INFO - '--tppagecycles', 15:14:59 INFO - '7'], 15:14:59 INFO - 'tests': ['tsvgm']}}, 15:14:59 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:14:59 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:14:59 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:14:59 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:14:59 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:14:59 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:14:59 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 15:14:59 INFO - 'talos_options': ['--disable-e10s'], 15:14:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:14:59 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:14:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:14:59 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 15:14:59 INFO - 'talos_options': ['--disable-e10s'], 15:14:59 INFO - 'tests': ['damp', 'tps']}, 15:14:59 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:14:59 INFO - 'tests': ['damp', 'tps']}, 15:14:59 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:14:59 INFO - 'tests': ['dromaeo_dom']}, 15:14:59 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:14:59 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:14:59 INFO - 'tests': ['basic_compositor_video']}, 15:14:59 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:14:59 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:14:59 INFO - 'tests': ['a11yr', 15:14:59 INFO - 'ts_paint', 15:14:59 INFO - 'tpaint', 15:14:59 INFO - 'sessionrestore', 15:14:59 INFO - 'sessionrestore_no_auto_restore', 15:14:59 INFO - 'tabpaint']}, 15:14:59 INFO - 'other-e10s': {'tests': ['a11yr', 15:14:59 INFO - 'ts_paint', 15:14:59 INFO - 'tpaint', 15:14:59 INFO - 'sessionrestore', 15:14:59 INFO - 'sessionrestore_no_auto_restore', 15:14:59 INFO - 'tabpaint']}, 15:14:59 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:14:59 INFO - 'ts_paint', 15:14:59 INFO - 'tpaint', 15:14:59 INFO - 'sessionrestore', 15:14:59 INFO - 'sessionrestore_no_auto_restore', 15:14:59 INFO - 'tabpaint']}, 15:14:59 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:14:59 INFO - 'ts_paint', 15:14:59 INFO - 'tpaint', 15:14:59 INFO - 'sessionrestore', 15:14:59 INFO - 'sessionrestore_no_auto_restore', 15:14:59 INFO - 'tabpaint']}, 15:14:59 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:14:59 INFO - 'tests': ['a11yr', 15:14:59 INFO - 'ts_paint', 15:14:59 INFO - 'tpaint', 15:14:59 INFO - 'sessionrestore', 15:14:59 INFO - 'sessionrestore_no_auto_restore', 15:14:59 INFO - 'tabpaint']}, 15:14:59 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:14:59 INFO - 'tests': ['a11yr', 15:14:59 INFO - 'ts_paint', 15:14:59 INFO - 'tpaint', 15:14:59 INFO - 'sessionrestore', 15:14:59 INFO - 'sessionrestore_no_auto_restore', 15:14:59 INFO - 'tabpaint']}, 15:14:59 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:14:59 INFO - 'tests': ['tsvgx', 15:14:59 INFO - 'tsvgr_opacity', 15:14:59 INFO - 'tart', 15:14:59 INFO - 'tscrollx', 15:14:59 INFO - 'cart', 15:14:59 INFO - 'tsvg_static']}, 15:14:59 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:14:59 INFO - 'tsvgr_opacity', 15:14:59 INFO - 'tart', 15:14:59 INFO - 'tscrollx', 15:14:59 INFO - 'cart', 15:14:59 INFO - 'tsvg_static']}, 15:14:59 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 15:14:59 INFO - 'talos_options': ['--disable-e10s'], 15:14:59 INFO - 'tests': ['tp5o']}, 15:14:59 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:14:59 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 15:14:59 INFO - 'talos_options': ['--disable-e10s', 15:14:59 INFO - '--xperf_path', 15:14:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:14:59 INFO - 'tests': ['tp5n']}, 15:14:59 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:14:59 INFO - 'talos_options': ['--xperf_path', 15:14:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:14:59 INFO - 'tests': ['tp5n']}}, 15:14:59 INFO - 'talos.zip': {'path': '', 15:14:59 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:14:59 INFO - Running post-action listener: _resource_record_post_action 15:14:59 INFO - [mozharness: 2016-12-29 23:14:59.111858Z] Finished populate-webroot step (success) 15:14:59 INFO - [mozharness: 2016-12-29 23:14:59.111981Z] Running create-virtualenv step. 15:14:59 INFO - Running pre-action listener: _resource_record_pre_action 15:14:59 INFO - Running main action method: create_virtualenv 15:14:59 INFO - Creating virtualenv /builds/slave/test/build/venv 15:14:59 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:14:59 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:14:59 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:14:59 INFO - Using real prefix '/tools/python27' 15:14:59 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:15:00 INFO - Installing distribute.............................................................................................................................................................................................done. 15:15:02 INFO - Installing pip.................done. 15:15:02 INFO - Return code: 0 15:15:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:15:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:15:03 INFO - Reading from file tmpfile_stdout 15:15:03 INFO - Output received: 15:15:03 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 15:15:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:15:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:15:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:15:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:15:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:15:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:15:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:15:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d394b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d397768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe648556340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d172d30>, '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 0x10d099db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d167be8>, '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.vw93tOwmJ3/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.NnmPiRQHAD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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 15:15:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:15:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:15:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vw93tOwmJ3/Render', 15:15:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:15:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:15:03 INFO - 'HOME': '/Users/cltbld', 15:15:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:15:03 INFO - 'LOGNAME': 'cltbld', 15:15:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:15:03 INFO - 'MOZ_NO_REMOTE': '1', 15:15:03 INFO - 'NO_EM_RESTART': '1', 15:15:03 INFO - 'PAGER': '/bin/cat', 15:15:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:15:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:15:03 INFO - 'PWD': '/builds/slave/test', 15:15:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:15:03 INFO - 'SHELL': '/bin/bash', 15:15:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NnmPiRQHAD/Listeners', 15:15:03 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 15:15:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:15:03 INFO - 'USER': 'cltbld', 15:15:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:15:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:15:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:15:03 INFO - 'XPC_FLAGS': '0x0', 15:15:03 INFO - 'XPC_SERVICE_NAME': '0', 15:15:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:15:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:15:03 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 15:15:03 INFO - Cleaning up... 15:15:03 INFO - Return code: 0 15:15:03 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:15:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:15:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:15:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:15:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:15:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:15:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:15:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d394b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d397768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe648556340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d172d30>, '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 0x10d099db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d167be8>, '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.vw93tOwmJ3/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.NnmPiRQHAD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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 15:15:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 15:15:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:15:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vw93tOwmJ3/Render', 15:15:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:15:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:15:03 INFO - 'HOME': '/Users/cltbld', 15:15:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:15:03 INFO - 'LOGNAME': 'cltbld', 15:15:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:15:03 INFO - 'MOZ_NO_REMOTE': '1', 15:15:03 INFO - 'NO_EM_RESTART': '1', 15:15:03 INFO - 'PAGER': '/bin/cat', 15:15:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:15:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:15:03 INFO - 'PWD': '/builds/slave/test', 15:15:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:15:03 INFO - 'SHELL': '/bin/bash', 15:15:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NnmPiRQHAD/Listeners', 15:15:03 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 15:15:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:15:03 INFO - 'USER': 'cltbld', 15:15:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:15:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:15:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:15:03 INFO - 'XPC_FLAGS': '0x0', 15:15:03 INFO - 'XPC_SERVICE_NAME': '0', 15:15:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:15:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:15:03 INFO - Downloading/unpacking psutil>=3.1.1 15:15:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:15:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:15:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:15:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:15:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:15:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:15:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:15:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:15:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:15:07 INFO - Installing collected packages: psutil 15:15:07 INFO - Running setup.py install for psutil 15:15:07 INFO - building 'psutil._psutil_osx' extension 15:15:07 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 15:15:08 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 15:15:08 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 15:15:08 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 15:15:08 INFO - building 'psutil._psutil_posix' extension 15:15:08 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 15:15:08 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:15:08 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:15:08 INFO - ^ 15:15:08 INFO - 1 warning generated. 15:15:08 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 15:15:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:15:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:15:08 INFO - Successfully installed psutil 15:15:08 INFO - Cleaning up... 15:15:08 INFO - Return code: 0 15:15:08 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:15:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:15:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:15:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:15:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:15:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:15:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:15:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d394b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d397768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe648556340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d172d30>, '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 0x10d099db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d167be8>, '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.vw93tOwmJ3/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.NnmPiRQHAD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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 15:15:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:15:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:15:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vw93tOwmJ3/Render', 15:15:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:15:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:15:08 INFO - 'HOME': '/Users/cltbld', 15:15:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:15:08 INFO - 'LOGNAME': 'cltbld', 15:15:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:15:08 INFO - 'MOZ_NO_REMOTE': '1', 15:15:08 INFO - 'NO_EM_RESTART': '1', 15:15:08 INFO - 'PAGER': '/bin/cat', 15:15:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:15:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:15:08 INFO - 'PWD': '/builds/slave/test', 15:15:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:15:08 INFO - 'SHELL': '/bin/bash', 15:15:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NnmPiRQHAD/Listeners', 15:15:08 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 15:15:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:15:08 INFO - 'USER': 'cltbld', 15:15:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:15:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:15:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:15:08 INFO - 'XPC_FLAGS': '0x0', 15:15:08 INFO - 'XPC_SERVICE_NAME': '0', 15:15:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:15:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:15:08 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:15:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:15:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:15:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:15:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:15:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:15:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:15:11 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:15:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:15:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:15:11 INFO - Installing collected packages: mozsystemmonitor 15:15:11 INFO - Running setup.py install for mozsystemmonitor 15:15:11 INFO - Successfully installed mozsystemmonitor 15:15:11 INFO - Cleaning up... 15:15:11 INFO - Return code: 0 15:15:11 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:15:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:15:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:15:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:15:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:15:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:15:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:15:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d394b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d397768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe648556340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d172d30>, '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 0x10d099db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d167be8>, '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.vw93tOwmJ3/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.NnmPiRQHAD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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 15:15:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 15:15:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 15:15:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vw93tOwmJ3/Render', 15:15:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:15:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:15:11 INFO - 'HOME': '/Users/cltbld', 15:15:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:15:11 INFO - 'LOGNAME': 'cltbld', 15:15:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:15:11 INFO - 'MOZ_NO_REMOTE': '1', 15:15:11 INFO - 'NO_EM_RESTART': '1', 15:15:11 INFO - 'PAGER': '/bin/cat', 15:15:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:15:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:15:11 INFO - 'PWD': '/builds/slave/test', 15:15:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:15:11 INFO - 'SHELL': '/bin/bash', 15:15:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NnmPiRQHAD/Listeners', 15:15:11 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 15:15:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:15:11 INFO - 'USER': 'cltbld', 15:15:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:15:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:15:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:15:11 INFO - 'XPC_FLAGS': '0x0', 15:15:11 INFO - 'XPC_SERVICE_NAME': '0', 15:15:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:15:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:15:12 INFO - Downloading/unpacking jsonschema==2.5.1 15:15:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:15:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:15:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:15:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:15:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:15:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:15:14 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:15:14 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:15: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 15:15: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 15:15: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 15:15: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 15:15: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 15:15: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 15:15:15 INFO - Downloading functools32-3.2.3-2.tar.gz 15:15:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:15:15 INFO - warning: no files found matching '*.txt' 15:15:15 INFO - no previously-included directories found matching 'build' 15:15:15 INFO - no previously-included directories found matching 'dist' 15:15:15 INFO - no previously-included directories found matching '.git*' 15:15:15 INFO - Installing collected packages: jsonschema, functools32 15:15:15 INFO - Running setup.py install for functools32 15:15:15 INFO - warning: no files found matching '*.txt' 15:15:15 INFO - no previously-included directories found matching 'build' 15:15:15 INFO - no previously-included directories found matching 'dist' 15:15:15 INFO - no previously-included directories found matching '.git*' 15:15:15 INFO - Successfully installed jsonschema functools32 15:15:15 INFO - Cleaning up... 15:15:15 INFO - Return code: 0 15:15:15 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:15:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:15:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:15:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:15:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:15:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:15:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:15:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d394b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d397768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe648556340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d172d30>, '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 0x10d099db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d167be8>, '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.vw93tOwmJ3/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.NnmPiRQHAD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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 15:15:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 15:15:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 15:15:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vw93tOwmJ3/Render', 15:15:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:15:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:15:15 INFO - 'HOME': '/Users/cltbld', 15:15:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:15:15 INFO - 'LOGNAME': 'cltbld', 15:15:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:15:15 INFO - 'MOZ_NO_REMOTE': '1', 15:15:15 INFO - 'NO_EM_RESTART': '1', 15:15:15 INFO - 'PAGER': '/bin/cat', 15:15:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:15:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:15:15 INFO - 'PWD': '/builds/slave/test', 15:15:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:15:15 INFO - 'SHELL': '/bin/bash', 15:15:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NnmPiRQHAD/Listeners', 15:15:15 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 15:15:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:15:15 INFO - 'USER': 'cltbld', 15:15:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:15:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:15:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:15:15 INFO - 'XPC_FLAGS': '0x0', 15:15:15 INFO - 'XPC_SERVICE_NAME': '0', 15:15:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:15:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:15:15 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:15:15 INFO - Cleaning up... 15:15:15 INFO - Return code: 0 15:15:15 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:15:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:15:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:15:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:15:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:15:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:15:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:15:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d394b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d397768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe648556340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d172d30>, '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 0x10d099db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d167be8>, '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.vw93tOwmJ3/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.NnmPiRQHAD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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 15:15:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:15:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:15:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vw93tOwmJ3/Render', 15:15:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:15:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:15:15 INFO - 'HOME': '/Users/cltbld', 15:15:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:15:15 INFO - 'LOGNAME': 'cltbld', 15:15:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:15:15 INFO - 'MOZ_NO_REMOTE': '1', 15:15:15 INFO - 'NO_EM_RESTART': '1', 15:15:15 INFO - 'PAGER': '/bin/cat', 15:15:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:15:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:15:15 INFO - 'PWD': '/builds/slave/test', 15:15:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:15:15 INFO - 'SHELL': '/bin/bash', 15:15:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NnmPiRQHAD/Listeners', 15:15:15 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 15:15:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:15:15 INFO - 'USER': 'cltbld', 15:15:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:15:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:15:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:15:15 INFO - 'XPC_FLAGS': '0x0', 15:15:15 INFO - 'XPC_SERVICE_NAME': '0', 15:15:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:15:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:15:16 INFO - Downloading/unpacking blobuploader==1.2.4 15:15:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:15:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:15:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:15:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:15:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:15:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:15:18 INFO - Downloading blobuploader-1.2.4.tar.gz 15:15:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:15:18 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:15:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:15:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:15:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:15:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:15:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:15:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:15:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:15:19 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:15:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:15:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:15:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:15:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:15:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:15:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:15:19 INFO - Downloading docopt-0.6.1.tar.gz 15:15:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:15:20 INFO - Installing collected packages: blobuploader, requests, docopt 15:15:20 INFO - Running setup.py install for blobuploader 15:15:20 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:15:20 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:15:20 INFO - Running setup.py install for requests 15:15:20 INFO - Running setup.py install for docopt 15:15:20 INFO - Successfully installed blobuploader requests docopt 15:15:20 INFO - Cleaning up... 15:15:20 INFO - Return code: 0 15:15:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:15:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:15:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:15:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:15:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:15:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:15:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:15:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d394b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d397768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe648556340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d172d30>, '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 0x10d099db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d167be8>, '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.vw93tOwmJ3/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.NnmPiRQHAD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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 15:15:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:15:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:15:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vw93tOwmJ3/Render', 15:15:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:15:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:15:20 INFO - 'HOME': '/Users/cltbld', 15:15:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:15:20 INFO - 'LOGNAME': 'cltbld', 15:15:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:15:20 INFO - 'MOZ_NO_REMOTE': '1', 15:15:20 INFO - 'NO_EM_RESTART': '1', 15:15:20 INFO - 'PAGER': '/bin/cat', 15:15:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:15:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:15:20 INFO - 'PWD': '/builds/slave/test', 15:15:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:15:20 INFO - 'SHELL': '/bin/bash', 15:15:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NnmPiRQHAD/Listeners', 15:15:20 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 15:15:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:15:20 INFO - 'USER': 'cltbld', 15:15:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:15:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:15:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:15:20 INFO - 'XPC_FLAGS': '0x0', 15:15:20 INFO - 'XPC_SERVICE_NAME': '0', 15:15:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:15:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:15:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:15:21 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-ZznVc6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:15:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:15:21 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-2F7ktZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:15:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:15:21 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-qe4mwa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:15:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:15:21 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-3rhWHn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:15:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:15:21 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-RinkJK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:15:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:15:21 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-6zQGqM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:15:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:15:21 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-um8GNK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:15:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:15:21 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-4RXxSp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:15:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:15:22 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-uvEIyN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:15:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:15:22 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-9bS62h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:15:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:15:22 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-byoNOv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:15:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:15:22 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-6OvA2I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:15:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:15:22 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-gY7ts9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:15:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:15:22 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-cB3tpj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:15:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:15:22 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-zsiZvZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:15:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:15:22 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-Ls8Tg6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:15:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:15:22 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-GbY8eo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:15:22 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:15:22 INFO - Running setup.py install for manifestparser 15:15:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:15:23 INFO - Running setup.py install for mozcrash 15:15:23 INFO - Running setup.py install for mozdebug 15:15:23 INFO - Running setup.py install for mozdevice 15:15:23 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:15:23 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:15:23 INFO - Running setup.py install for mozfile 15:15:23 INFO - Running setup.py install for mozhttpd 15:15:23 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:15:23 INFO - Running setup.py install for mozinfo 15:15:24 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:15:24 INFO - Running setup.py install for mozInstall 15:15:24 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:15:24 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:15:24 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:15:24 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:15:24 INFO - Running setup.py install for mozleak 15:15:24 INFO - Running setup.py install for mozlog 15:15:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:15:24 INFO - Running setup.py install for moznetwork 15:15:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:15:24 INFO - Running setup.py install for mozprocess 15:15:24 INFO - Running setup.py install for mozprofile 15:15:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:15:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:15:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:15:24 INFO - Running setup.py install for mozrunner 15:15:25 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:15:25 INFO - Running setup.py install for mozscreenshot 15:15:25 INFO - Running setup.py install for moztest 15:15:25 INFO - Running setup.py install for mozversion 15:15:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:15:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:15:25 INFO - Cleaning up... 15:15:25 INFO - Return code: 0 15:15:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:15:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:15:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:15:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:15:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:15:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:15:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:15:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d394b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d397768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe648556340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d172d30>, '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 0x10d099db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d167be8>, '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.vw93tOwmJ3/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.NnmPiRQHAD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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 15:15:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:15:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:15:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vw93tOwmJ3/Render', 15:15:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:15:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:15:25 INFO - 'HOME': '/Users/cltbld', 15:15:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:15:25 INFO - 'LOGNAME': 'cltbld', 15:15:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:15:25 INFO - 'MOZ_NO_REMOTE': '1', 15:15:25 INFO - 'NO_EM_RESTART': '1', 15:15:25 INFO - 'PAGER': '/bin/cat', 15:15:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:15:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:15:25 INFO - 'PWD': '/builds/slave/test', 15:15:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:15:25 INFO - 'SHELL': '/bin/bash', 15:15:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NnmPiRQHAD/Listeners', 15:15:25 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 15:15:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:15:25 INFO - 'USER': 'cltbld', 15:15:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:15:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:15:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:15:25 INFO - 'XPC_FLAGS': '0x0', 15:15:25 INFO - 'XPC_SERVICE_NAME': '0', 15:15:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:15:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:15:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:15:25 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-1WbAWz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:15:25 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 15:15:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:15:25 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-ObW5o8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:15:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:15:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:15:26 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-D0tK7V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:15:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:15:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:15:26 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-gDGCvK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:15:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:15:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:15:26 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-INu8Yv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:15:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 15:15:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:15:26 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-en8Qui-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:15:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 15:15:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:15:26 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-PqB5vu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:15:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 15:15:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:15:26 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-Lxwddv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:15:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 15:15:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:15:26 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-meRO21-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:15:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 15:15:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:15:26 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-W6VdzD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:15:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:15:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:15:26 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-JrKRDt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:15:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 15:15:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:15:27 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-cgDGuJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:15:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 15:15:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:15:27 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-0jcTho-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:15:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 15:15:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:15:27 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-xs_qjG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:15:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 15:15:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:15:27 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-V0lC5z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:15:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 15:15:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:15:27 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-jzbsP3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:15:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 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)) 15:15:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:15:27 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-iPM4fW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:15:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 15:15:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:15:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:15:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:15:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:15:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:15:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:15:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:15:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:15:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:15:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:15:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:15:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:15:30 INFO - Downloading blessings-1.6.tar.gz 15:15:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:15:30 INFO - Installing collected packages: blessings 15:15:30 INFO - Running setup.py install for blessings 15:15:30 INFO - Successfully installed blessings 15:15:30 INFO - Cleaning up... 15:15:30 INFO - Return code: 0 15:15:30 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:15:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:15:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:15:30 INFO - Reading from file tmpfile_stdout 15:15:30 INFO - Current package versions: 15:15:30 INFO - blessings == 1.6 15:15:30 INFO - blobuploader == 1.2.4 15:15:30 INFO - docopt == 0.6.1 15:15:30 INFO - functools32 == 3.2.3-2 15:15:30 INFO - jsonschema == 2.5.1 15:15:30 INFO - manifestparser == 1.1 15:15:30 INFO - mozInstall == 1.12 15:15:30 INFO - mozcrash == 1.0 15:15:30 INFO - mozdebug == 0.1 15:15:30 INFO - mozdevice == 0.49 15:15:30 INFO - mozfile == 1.2 15:15:30 INFO - mozhttpd == 0.7 15:15:30 INFO - mozinfo == 0.9 15:15:30 INFO - mozleak == 0.1 15:15:30 INFO - mozlog == 3.4 15:15:30 INFO - moznetwork == 0.27 15:15:30 INFO - mozprocess == 0.23 15:15:30 INFO - mozprofile == 0.28 15:15:30 INFO - mozrunner == 6.13 15:15:30 INFO - mozscreenshot == 0.1 15:15:30 INFO - mozsystemmonitor == 0.3 15:15:30 INFO - moztest == 0.7 15:15:30 INFO - mozversion == 1.4 15:15:30 INFO - psutil == 3.1.1 15:15:30 INFO - requests == 1.2.3 15:15:30 INFO - wsgiref == 0.1.2 15:15:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:15:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:15:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:15:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:15:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:15:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:15:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:15:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d394b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d397768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe648556340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d172d30>, '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 0x10d099db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d167be8>, '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.vw93tOwmJ3/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.NnmPiRQHAD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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 15:15:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 15:15:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:15:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vw93tOwmJ3/Render', 15:15:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:15:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:15:30 INFO - 'HOME': '/Users/cltbld', 15:15:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:15:30 INFO - 'LOGNAME': 'cltbld', 15:15:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:15:30 INFO - 'MOZ_NO_REMOTE': '1', 15:15:30 INFO - 'NO_EM_RESTART': '1', 15:15:30 INFO - 'PAGER': '/bin/cat', 15:15:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:15:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:15:30 INFO - 'PWD': '/builds/slave/test', 15:15:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:15:30 INFO - 'SHELL': '/bin/bash', 15:15:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NnmPiRQHAD/Listeners', 15:15:30 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 15:15:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:15:30 INFO - 'USER': 'cltbld', 15:15:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:15:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:15:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:15:30 INFO - 'XPC_FLAGS': '0x0', 15:15:30 INFO - 'XPC_SERVICE_NAME': '0', 15:15:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:15:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:15:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:15:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 15:15:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 15:15:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:15:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 15:15:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 15:15:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 15:15:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 15:15:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 15:15:31 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:15:31 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:15:31 INFO - Cleaning up... 15:15:31 INFO - Return code: 0 15:15:31 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:15: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')]} 15:15:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:15:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:15: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')]} 15:15:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:15:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:15:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d394b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d397768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe648556340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d172d30>, '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 0x10d099db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d167be8>, '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.vw93tOwmJ3/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.NnmPiRQHAD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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 15:15:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:15:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:15:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vw93tOwmJ3/Render', 15:15:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:15:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:15:31 INFO - 'HOME': '/Users/cltbld', 15:15:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:15:31 INFO - 'LOGNAME': 'cltbld', 15:15:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:15:31 INFO - 'MOZ_NO_REMOTE': '1', 15:15:31 INFO - 'NO_EM_RESTART': '1', 15:15:31 INFO - 'PAGER': '/bin/cat', 15:15:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:15:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:15:31 INFO - 'PWD': '/builds/slave/test', 15:15:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:15:31 INFO - 'SHELL': '/bin/bash', 15:15:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NnmPiRQHAD/Listeners', 15:15:31 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 15:15:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:15:31 INFO - 'USER': 'cltbld', 15:15:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:15:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:15:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:15:31 INFO - 'XPC_FLAGS': '0x0', 15:15:31 INFO - 'XPC_SERVICE_NAME': '0', 15:15:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:15:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:15:31 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:15:31 INFO - Cleaning up... 15:15:31 INFO - Return code: 0 15:15:31 INFO - Running post-action listener: _resource_record_post_action 15:15:31 INFO - Running post-action listener: _start_resource_monitoring 15:15:31 INFO - Starting resource monitoring. 15:15:31 INFO - [mozharness: 2016-12-29 23:15:31.457610Z] Finished create-virtualenv step (success) 15:15:31 INFO - [mozharness: 2016-12-29 23:15:31.458650Z] Running install step. 15:15:31 INFO - Running pre-action listener: _resource_record_pre_action 15:15:31 INFO - Running main action method: install 15:15:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:15:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:15:31 INFO - Reading from file tmpfile_stdout 15:15:31 INFO - Detecting whether we're running mozinstall >=1.0... 15:15:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:15:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:15:31 INFO - Reading from file tmpfile_stdout 15:15:31 INFO - Output received: 15:15:31 INFO - Usage: mozinstall [options] installer 15:15:31 INFO - Options: 15:15:31 INFO - -h, --help show this help message and exit 15:15:31 INFO - -d DEST, --destination=DEST 15:15:31 INFO - Directory to install application into. [default: 15:15:31 INFO - "/builds/slave/test"] 15:15:31 INFO - --app=APP Application being installed. [default: firefox] 15:15:31 INFO - mkdir: /builds/slave/test/build/application 15:15:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 15:15:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 15:15:53 INFO - Reading from file tmpfile_stdout 15:15:53 INFO - Output received: 15:15:53 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 15:15:53 INFO - Running post-action listener: _resource_record_post_action 15:15:53 INFO - [mozharness: 2016-12-29 23:15:53.754861Z] Finished install step (success) 15:15:53 INFO - [mozharness: 2016-12-29 23:15:53.754996Z] Running run-tests step. 15:15:53 INFO - Running pre-action listener: _resource_record_pre_action 15:15:53 INFO - Running main action method: run_tests 15:15:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:15:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:15:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:15:53 INFO - 2016-12-29 15:15:53.769 screenresolution[1916:13672] starting screenresolution argv=screenresolution get 15:15:53 INFO - 2016-12-29 15:15:53.772 screenresolution[1916:13672] Display 0: 1600x1200x32@60 15:15:53 INFO - 2016-12-29 15:15:53.778 screenresolution[1917:13675] starting screenresolution argv=screenresolution list 15:15:53 INFO - Available Modes on Display 0 15:15:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:15:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:15:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:15:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:15:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:15:54 INFO - Intel Iris: 15:15:54 INFO - Chipset Model: Intel Iris 15:15:54 INFO - Type: GPU 15:15:54 INFO - Bus: Built-In 15:15:54 INFO - VRAM (Dynamic, Max): 1536 MB 15:15:54 INFO - Vendor: Intel (0x8086) 15:15:54 INFO - Device ID: 0x0a2e 15:15:54 INFO - Revision ID: 0x0009 15:15:54 INFO - Displays: 15:15:54 INFO - Display: 15:15:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:15:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:15:54 INFO - Display Serial Number: 1600x1200 60 15:15:54 INFO - Main Display: Yes 15:15:54 INFO - Mirror: Off 15:15:54 INFO - Online: Yes 15:15:54 INFO - Rotation: Supported 15:15:54 INFO - Return code: 0 15:15:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:15:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:15:54 INFO - Python 2.7.3 15:15:54 INFO - Return code: 0 15:15:54 INFO - grabbing minidump binary from tooltool 15:15:54 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')]} 15:15:54 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 0x10d172d30>, '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 0x10d099db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d167be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:15:54 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 15:15:54 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 15:15:54 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 15:15:54 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:15:54 INFO - Return code: 0 15:15:54 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:15:54 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:15:54 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:15:54 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:15:54 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:15:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CsWQ6Z-zSr-ht0-CsmSleg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0108', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 15:15:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Graphics --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/CsWQ6Z-zSr-ht0-CsmSleg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0108 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 15:15:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vw93tOwmJ3/Render', 15:15:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:15:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:15:54 INFO - 'HOME': '/Users/cltbld', 15:15:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:15:54 INFO - 'LOGNAME': 'cltbld', 15:15:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:15:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:15:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:15:54 INFO - 'MOZ_NO_REMOTE': '1', 15:15:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:15:54 INFO - 'NO_EM_RESTART': '1', 15:15:54 INFO - 'PAGER': '/bin/cat', 15:15:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:15:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:15:54 INFO - 'PWD': '/builds/slave/test', 15:15:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:15:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:15:54 INFO - 'SHELL': '/bin/bash', 15:15:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NnmPiRQHAD/Listeners', 15:15:54 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 15:15:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:15:54 INFO - 'USER': 'cltbld', 15:15:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:15:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:15:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:15:54 INFO - 'XPC_FLAGS': '0x0', 15:15:54 INFO - 'XPC_SERVICE_NAME': '0', 15:15:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:15:54 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CsWQ6Z-zSr-ht0-CsmSleg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0108', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 15:15:54 INFO - mozversion application_buildid: 20161229135219 15:15:54 INFO - mozversion application_changeset: 8999cab905a4fd17d22d1d3ba687e6f1aab374f1 15:15:54 INFO - mozversion application_display_name: Nightly 15:15:54 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:15:54 INFO - mozversion application_name: Firefox 15:15:54 INFO - mozversion application_remotingname: firefox 15:15:54 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 15:15:54 INFO - mozversion application_vendor: Mozilla 15:15:54 INFO - mozversion application_version: 53.0a1 15:15:54 INFO - mozversion platform_buildid: 20161229135219 15:15:54 INFO - mozversion platform_changeset: 8999cab905a4fd17d22d1d3ba687e6f1aab374f1 15:15:54 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 15:15:54 INFO - mozversion platform_version: 53.0a1 15:15:54 INFO - using testdate: 1483053354 15:15:54 INFO - actual date: 1483053354 15:15:54 INFO - starting webserver on 'localhost:49502' 15:15:54 INFO - SUITE-START | Running 2 tests 15:15:54 INFO - TEST-START | dromaeo_css 15:15:54 INFO - operating with platform_type : mac_ 15:15:54 INFO - Initialising browser for dromaeo_css test... 15:15:54 INFO - TEST-INFO | started process 1927 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/tmpMCE12O/profile http://localhost:49502/getInfo.html) 15:15:54 INFO - PROCESS | 1927 | 1483053354875 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4986 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3827 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2831 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 15:15:56 INFO - PROCESS | 1927 | __metrics Screen width/height:1600/1200 15:15:56 INFO - PROCESS | 1927 | colorDepth:24 15:15:56 INFO - PROCESS | 1927 | Browser inner width/height: 1024/689 15:15:56 INFO - PROCESS | 1927 | __metrics 15:15:56 INFO - PROCESS | 1927 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:15:56 INFO - PROCESS | 1927 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:15:57 INFO - TEST-INFO | 1927: exit 0 15:15:57 INFO - Browser initialized. 15:15:57 INFO - Running cycle 1/1 for dromaeo_css test... 15:15:57 INFO - TEST-INFO | started process 1929 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/tmpMCE12O/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:16:09 INFO - PROCESS | 1929 | RSS: Main: 234483712 15:16:09 INFO - PROCESS | 1929 | 15:19:12 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:49502/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49502/tests/dromaeo/cssquery-ext.html) 15:19:15 INFO - PROCESS | 1929 | RSS: Main: 470777856 15:19:15 INFO - PROCESS | 1929 | 15:20:58 INFO - PROCESS | 1929 | ************************* 15:20:58 INFO - PROCESS | 1929 | A coding exception was thrown and uncaught in a Task. 15:20:58 INFO - PROCESS | 1929 | 15:20:58 INFO - PROCESS | 1929 | Full message: ReferenceError: fetch is not defined 15:20:58 INFO - PROCESS | 1929 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 15:20:58 INFO - PROCESS | 1929 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 15:20:58 INFO - PROCESS | 1929 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 15:20:58 INFO - PROCESS | 1929 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 15:20:58 INFO - PROCESS | 1929 | TaskImpl@resource://gre/modules/Task.jsm:277:3 15:20:58 INFO - PROCESS | 1929 | asyncFunction@resource://gre/modules/Task.jsm:252:14 15:20:58 INFO - PROCESS | 1929 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 15:20:58 INFO - PROCESS | 1929 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 15:20:58 INFO - PROCESS | 1929 | TaskImpl@resource://gre/modules/Task.jsm:277:3 15:20:58 INFO - PROCESS | 1929 | asyncFunction@resource://gre/modules/Task.jsm:252:14 15:20:58 INFO - PROCESS | 1929 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 15:20:58 INFO - PROCESS | 1929 | 15:20:58 INFO - PROCESS | 1929 | ************************* 15:22:18 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:49502/tests/dromaeo/cssquery-ext.html (next: http://localhost:49502/tests/dromaeo/cssquery-jquery.html) 15:22:19 INFO - PROCESS | 1929 | RSS: Main: 367652864 15:22:19 INFO - PROCESS | 1929 | 15:25:21 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:49502/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49502/tests/dromaeo/cssquery-mootools.html) 15:25:22 INFO - PROCESS | 1929 | RSS: Main: 334176256 15:25:22 INFO - PROCESS | 1929 | 15:28:24 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:49502/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49502/tests/dromaeo/cssquery-prototype.html) 15:28:24 INFO - PROCESS | 1929 | RSS: Main: 347021312 15:28:24 INFO - PROCESS | 1929 | 15:31:28 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:49502/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49502/tests/dromaeo/cssquery-yui.html) 15:31:29 INFO - PROCESS | 1929 | RSS: Main: 342466560 15:31:29 INFO - PROCESS | 1929 | 15:34:32 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:49502/tests/dromaeo/cssquery-yui.html (next: http://localhost:49502/tests/dromaeo/cssquery-dojo.html) 15:34:32 INFO - PROCESS | 1929 | RSS: Main: 398434304 15:34:32 INFO - PROCESS | 1929 | 15:34:32 INFO - PROCESS | 1929 | __start_tp_report 15:34:32 INFO - PROCESS | 1929 | _x_x_mozilla_page_load 15:34:32 INFO - PROCESS | 1929 | _x_x_mozilla_page_load_details 15:34:32 INFO - PROCESS | 1929 | |i|pagename|runs| 15:34:32 INFO - PROCESS | 1929 | |0;dojo.html;3238.1257060593634;3245.983770081149;3251.4960181171914;3262.6411094779587;3301.14170315718;5444.292242008543;5498.0653288940875;5584.329880414351;5584.8324550263505;5624.3813180550105;5543.445655434454;5562.721863906809;5606;5635;5650.63270887392;5169.741512924357;5181.766820493068;5193;5210.869728256786;5219.373675158986;5028.7485625718755;5144.768485418165;5233.319668443116;5238.79044838206;5244.685318880879;5191.0656081905245;5227.817026404076;5242.082635538784;5243.580513558925;5250.8687282817855;5066.442691303953;5087.135187018179;5173.094708426011;5182.378114626231;5227.60792940531;5046.671966322214;5092.923606145874;5126.5898728101665;5136.743162841843;5167.741612919376;5578.079616863212;5584.4136365681425;5622.297212848394;5623.0721930881355;5661.631993920382;6173.444390004921;6176.536759742992;6183.258009038944;6192.225971753531;6199;6104.519788040277;6104.786332478387;6120.908186377208;6123.750585468418;6167.074938759177;5741.3971532988435;5751.108578170307;5758.596898217168;5775.971120144414;5789.913151302691;6235.220597425322;6242.438180563771;6254.906176407358;6260.561760676709;6270.561060725797;5826.096954971986;5898.941010589925;5901.868691915676;6050.45545900871;6071.2714474263375;6121.1481385573825;6141.170941922873;6155.230596175478;6173.074038894202;6236.501079913596;4856.8300109495995;6016.608920420159;6132.703928623082;6144.3717414885095;6155.169052177988;6021.729022194012;6094.634321940698;6110.7555697771595;6111.622125543816;6171.228596425447;4871.074495845778;4880.633952453509;4881.658283920163;4901.3383193269165;4905.754712264444;4334.631556317685;4342.196693611692;4363.105563359503;4389.4513185851765;4405.229084910128;4664.253719404879;5684.857878553069;5685.232493613392;5687.630304030225;5719.799807006735;6186;6186.288576813724;6190.133381326618;6203.16257305267;6208.348123447064;5527.8894422112135;5590.301212348457;5657.6039677223025;5669.177969194444;5685.175649530877;5261.4738526147075;5315.973420132875;5350.438203988603;5360.303160589098;5413.079776438015;6199.814005579839;6208.72060757267;6217.844553886189;6219.222597175353;6245.281792593819;4864.513548645249;5062.417821950376;6160.507159427146;6204.937950620617;6235.064740288993;6159.414855588712;6188.257409110935;6211.378862113753;6212.06818977157;6228.22147231596;6044.516438684983;6047.78832740852;6048.485878700184;6087.421694938974;6090.177825993612;6142.877142457215;6144;6148.661823599745;6156;6156.415140561639;5703.5722320826935;5775.711214439178;5868.354481007001;5926.377730338252;5928.140419639042;5845.386234445322;5895.469407753323;5907.261592300963;5923.08192230205;5925;5755.1942728017275;5759.827205183851;5767.769289228384;5772.682502462237;5774.335951365479;5889.234399528034;5913.231279933581;5929.970350148222;5933;5946.108083787466;5742;5743.770249190153;5751.338596061507;5754.9424505754405;5755.827325180251 15:34:32 INFO - PROCESS | 1929 | |1;ext.html;426.70983731062887;436.3324114105419;474.33356134630856;487.0137970609515;504.9192129259317;29516.704832951666;37661.81169094154;39619.811405657805;39746.8012659937;41137.58862411375;40864.38703419451;41199.38200926989;41236.58763412365;41253.58746412535;41282.17435651293;8331.500109993389;9223;9448.763780905463;9455.385399948982;9456.479893605883;8332.83334333313;8899.822003559924;10880;11274.098072154207;11464.598739044135;11217.214794964355;11275.097992160607;11276.943615281913;11283.548658053667;11334.943325283362;8364;8593.613287402082;11238.831417528743;11324.547018119307;11344.205905586612;8448.197421244971;8508.489490630582;8517.914820851774;10806.18953578477;11097.55609775608;38212.808935955145;38238.42642360367;38677.03307417309;38860.611393885985;38962.02594935121;3942.4842340544287;4004.4994375703036;4004.7797371144575;4035.1929614077007;4105;28672;29286.560701589493;29846.70153298461;29920.700792992007;29953.400931981458;2931.384409274055;3042.863071161803;3073.2931425771976;3099.2251937015744;3109.751219902402;464022.67988659843;465576.67211664066;469203;469538;470433;55415;55541.22422860803;55584;56747.71626141843;57021.714891426105;59622;60354.69822650859;60545.69727151424;60615;60619.39380606226;12013.519459221534;15150.772738408712;15178.924105379403;15199.468018619295;15202.847971520143;10702.250842440955;11019.008289254005;11019.118470522339;11961.28232306047;12055.035597152208;14045.859541404456;14186.787198192036;14211.502597409248;15306.617334566503;15722.13528255957;5753.971230143822;6605.735770569124;6626.668666566652;6688.732450702015;6692.531522793455;716.7885473785142;851.7104184577273;854.4574195385975;858.454881150474;874.6676263020139;23613.88193059058;26734.86632566825;27207.86396068007;27329.770160342832;28139.577906331422;964.62379671928;981.1807141037314;1012.0891197921782;1023.1763430438568;1041.796849614318;13131.934340328236;13698.452061917611;14067.859321406859;14073.225972571607;14087.436502539784;611245;615385;617081;617437;618010;48430.757846210545;48575.75712121417;48631;48635.27047094297;48715.26927096096;1329.3419757220238;1370.047816767337;1374.5120482228665;1376.9586912392845;1381.3714759784275;24783.13259035925;26424.471510570067;26430.810613522823;26625.20124396271;26733.86633066822;42347.7882610585;46714;48007;48141.03717925692;50709;428525;433280;433471;433596;436018;23391.649125263473;23784.048638054977;23800.166994155123;23855.52288954246;23857.92639331269;32813.83593082019;39965;40265.59734402619;41230.38154427746;43645.781771090944;25406.745932540438;34375.82812085924;34391;35392.29215415729;36367;28628.42743145167;33618.15954601154;34288.65711342855;34711.09782825304;35495.82252088723;33087;34252;34301.313973720884;34609;34642.65357346394;27838.86080569584;29857.57581760186;30647.2338191547;31082;31130.899554260934;22549.887250563643;22987.195448159233;23079.538409232056;23587.764122359244;23611.64582531229 15:34:32 INFO - PROCESS | 1929 | |2;jquery.html;353.49979778613266;371.4947671167212;374.1040208700163;381.9789911554864;403.27611936573845;11366.545338186474;14721.969462137655;15473.69052618947;19429;20017;19098.331558395425;19343.872174060878;19629.90185049076;19870.50323741907;20019.59960800783;5581.441855814416;5825.155352473888;5825.592208545382;5827.883442331151;5834.824955251349;4479.216137175998;5661.886762264752;5698.886022279552;5705.229793977822;5726.112452569859;5533.557315414757;5667.4049224831415;5672.914906276409;5680.431956804307;5687.402822703619;5486.780528778844;5576.275084239023;5591.440855914397;5657.57568182388;5658.943410565924;4951.356323677899;4953.157963146274;4956.884700942295;4968.726720030397;4968.782648251202;12054.879451205463;12596.433160507799;12600.306983115925;12605.991520678324;12615.432305546272;2568.2808813532056;2587.3014286142634;2605.7915366770612;2912.6941671124437;2914.1985953862645;8459.323254139654;9385;9581.664641737505;9942.105210531015;10005.449700266483;1805.124514610413;1810.2741258046156;1811.5598099510921;1814.1926842555067;1816.2825683854803;103906.48046759718;107382;123037.38481307536;126463;128529;9305.53472326381;10912.618058368078;10972.725681857857;11081.002709756161;11522.76954460906;14207.431702731981;14212.928935355258;14221.00452968303;14243.64390890236;14247.501337453139;8048.637811298506;8328.541930193898;8342.082370939192;8489.830203395897;8503.23470887623;6089.969550152221;7997.400194985399;8036.316913062456;8168.305694016075;8182.7954301142945;4399.1641588098155;4403.735775853459;4420.7126536775;4461;4510;1862.3761040051463;1904.9142788574547;1911.3883557261543;1912.7513423254888;1913.0147973793507;912.6577533424966;964.9179819715403;987.7234374375042;1002.8696269485066;1020.4489575629077;19291.903540482206;19515.609687806165;19575;19734.111964961612;20062;942.5004747483845;1020.0921180149672;1025.8153532364097;1036.18659352408;1049.3598904668147;9647.951760241294;10099.020734175818;10223.846642300372;10354.275200736027;10369.481525923673;540971.9028097158;542664.5733542613;552172;553296.4368384997;554035;91186.08813911775;116328;119663;132647;133752;968.6512855372201;972.601233494264;1009.3842755918884;1011.8431643095332;1012.6050840172338;17208.65582688364;17309.39417120395;17372.65254694874;17583.903292508636;17598;54560;54609;55137;55745.44254557403;55951.88697638738;77013.45973080395;78231.60884195543;78299;78484.60757696403;80569.59715201387;7744.186860379805;7769.650365733442;7862.960685196537;7901.328387087162;8196.38527110481;10139.898601013896;11804.82292765592;12431.502739890664;12678.049146314248;12765.297908614744;10862.565497380016;10948.507317170586;11156.944215278872;11170.944145279222;11176.441177941233;10808.243422960317;10812;11120.499577519193;11211.831822522505;11365.48855301497;11296.152788541056;11315.886841131483;11440.654665793327;11552.133589980616;11556.653300401002;6566.80299591013;7255.600941948243;7363.595002274927;7828.647710852912;8215.096339402542;7516.5114267572435;7741.5742134183165;7829.530228186327;7847.607619619111;8027 15:34:32 INFO - PROCESS | 1929 | |3;mootools.html;1208.190512356721;1398.8111604933333;1423.9288035598217;1426.3937826423767;1438.381495956739;8043.356531477482;8431.325493960483;8450.577471126435;8454.873176902343;8458.534780587066;7416.592087435202;7654.617269136535;7656.846863062736;8042.034955805297;8276.131006244348;2282.3495303838367;2323.1056043423237;2325.5581439526522;2326.569584626841;2331.405491599648;2301.2635956493928;2332.486852892361;2342.3792694935855;2346.061575369857;2346.4485845826216;1966.9606607867834;2035.6742921132623;2235.7987781099696;2301.539692061586;2314.90740370385;2320.385097949045;2323.0939933425975;2328.511012687338;2333.230034088747;2335.2760644200353;2310.1683393978155;2323.303009097281;2328.5342931413616;2335.2060299498253;2337.474068334628;6421.903671444932;8353.540555269457;8441.324694024463;8548.914510854936;8570.100139485327;5086.593072554187;5124.641275110744;5259.184826351921;5259.579233661336;5270.314859068335;3216.404965081444;3336.9332613347597;3375.6961873431264;3381.1209085637665;3388.136025313554;1109.944502774858;1157.6295585412672;1194.5162209305245;1203.5607003443768;1203.8555373355166;10268.229882758824;10677.305975111592;10687.78624427507;10695.03744662984;10853.5115919783;4262;4332.978335108304;4428.8484993901175;4434.822607095744;4444.688871779009;8663.566821658891;8664.176903194313;8667.653293868301;8679.522626255617;8687.739367818975;5080.263361812516;5085.74571271435;5092.083424983539;5094.61790365724;5094.745262736848;4116;4274.145170965757;4444.822207111744;4621.2837010263465;4740.881477963078;3548.503209550665;3592.98203508986;3658.067192865802;3682.760620559655;3685.963140368562;1147.2657499200518;1156.3697784707354;1160.6053941659877;1166.9183157178916;1169.4211365374194;857.6097875466647;872.47651409154;880.3133555826465;908.2525360783682;916.7524768312518;5417.404085550586;5467.651721353205;5664.320281566238;5731.168980497804;5737.454941780524;523.3745673919706;535.4698848140391;546.3116473243741;554.5314209493052;558.793007104652;3806.5051543299196;3828.425736139601;3841.5966323536186;3890.3386424307937;3908.2769687607883;9612.741005863616;10147.797044059078;10152.746181345525;10157.746056348502;10158.593656253815;10119.898801012043;10120;10120.038596333337;10124.493775311352;10135.239857010607;7714.267144621252;7875.724349647735;7888.605569721491;7903.091144518339;7909.3277071449575;5396.163594642837;5566.487209964433;5637.126245431965;5659.801906933238;5680.68756218412;9274.768130796783;9299.116583924517;9321.533923303943;9323.114304141096;9339.439633622005;9213.585388657393;9298.907010929805;9500.809983800147;9562.28282878773;9562.760930976781;6729.798106056826;6754.3921047106;6768.289329620516;6818.420434263143;6851.109355783717;7820.648070836713;7872.409569282212;7909.8813517796125;7992.9200707994505;7995.480293780885;7001.684924178322;7223.747168849065;7229.602371869598;7334.559926404433;7366.337029667356;7018.192907815463;7037.648117594203;7131.750388736369;7166;7264.309890560388;6279.874402511833;6457.644829534421;6459.257185423548;6461.386168314003;6465.709043092978;4567.703099298667;4808.783604737725;4827.372441582572;4918.43438004634;4995.700257984533;4349.760763158057;4944.381952255968;4969.801207951641;5028.220625803006;5032.647714659939 15:34:32 INFO - PROCESS | 1929 | |4;prototype.html;352.28837747749543;357.3282229408711;362.18869731800766;373.46407904656803;373.770131369208;5308.973455132719;5354.7416357156035;5366.677999320039;5386.542143917762;5410.215518749779;4241.491021077467;4725.291206319054;5078.669886457377;5236.136037553799;5260.342457192851;3588.354096262685;3727.1986522897573;3745.232227393378;4091.0795071108987;4108.746832216173;3680.245549662326;4083.775392353419;4107.979460102711;4139.813708383115;4162.895927601804;4058.4521089652812;4134.193832202722;4142.316517774563;4142.502899652046;4143.7513749175;3598.064503229179;4018.874715079782;4171.687123465737;4179.561146079648;4184.497860256758;3302.240484688535;3415.8975230743117;3527.735419843522;3609.4946707460977;3648.4527320902075;4304.418903448026;5141.354766474729;5161.599856066947;5163.974180129076;5194.558462530689;4711.269753188261;4755.5719985201495;4780.689255198401;4802.43920725762;4812.184871849972;5183.792648294064;5513.669179849222;5578.9163162552595;5618.4662457066515;5620.494155525981;3681.44778283257;4023.941804228778;4033.0925541753163;4038.9394159087637;4039.313316736162;4887.582601045692;5297.867553311159;5490.505854473036;5545.334559852843;5647.8588035299035;3782.583915769263;4075.9388609170887;4151.73013754105;4156.9792151039055;4167.979160104241;4211.620954114083;4219.109909711796;4223.176480586256;4230.259704551691;4244.3524340846725;4362.105768317488;4686.625069994392;4783.258594917794;4786.138495070921;4826.565609095198;5208.989078409603;5572.192032155396;5590.385057643737;5596.964561556125;5605.5515558756015;1196.7726132034886;1200.6278053803348;1213.5873802906876;1349.8312710911136;1352.371147416456;772.559641004622;831.9001719793664;961.1205746741653;964.5900492290889;972.2756546372856;5018;5020.824271150565;5042.546170844642;5063.417706963746;5107.336046313955;4572.736495434715;5081.974590127025;5104.412992505835;5420.728963551806;5628.831135065954;4122;4354;4815.2054910940005;4916.050369778176;5045.672031317952;2585.974140258611;2597.6103584462103;2828.8161269517414;2852.985735071353;2869.813462124955;5284.282608152366;5389.353277606712;5512.4211957744665;5560.610757246954;5638.2670252866865;4430.379746835446;4856.732879691651;5324.707141107198;5600.263918185376;5609.775608975677;4890.657653964188;5080.593552515716;5170.353705786777;5606.803761868315;5763.250777398911;5173.560247378864;5191;5379.704116273642;5445.373782015119;5534.889302214014;5275.709835959059;5317.553925194454;5695.715214239354;5756.769729210952;5777.91333129995;5197.974010129925;5349.518543331119;5701.600887937804;5898.439648233582;5959.463648271676;3540.097275194809;3802.144517483655;3820.3696390096434;3850.903727406725;3879.3599056155417;4911.852644420673;5360.919586206132;5590.245316882331;5711.742971566373;5822.243108395882;4972.602191824573;5122.33524104675;5181.35233095863;5398.622096453368;5596.692181930033;4729.597984171384;5554.166874968625;5597.63615365;5614.494695477427;5629.4652008059165;5222.660527065728;5482.80810171642;5503.944960550343;5536.280283563111;5544.7227638617105;5536.54308281837;5601.7759289629585;5609.186667933209;5639.069553523786;5642.210090587403;5492.258545096521;5499.615026948075;5504.53211477029;5505.476979687083;5514.5864060194845 15:34:32 INFO - PROCESS | 1929 | |5;yui.html;720.6865013719032;735.528306393821;761.2577736706712;788.7340669780698;791.5775361533077;13217.669558261048;13887.236202008886;14561.490347837815;15604.453844115458;15614.609634759137;12910;13040.347982600879;13059.608211753639;13081.149725267875;13083.672908177301;668.2315337362024;691.018753370213;826.2192228344217;852.8038551133243;857.8884744983144;851.6635928808117;860.6772460327377;865.4807115730574;867.332154241237;867.8611422172454;701.1971292869662;920.1258804136064;921.9631214751402;922.7923717163652;922.8108237811233;717.8707832590131;870.1646419437348;907.4555266839909;907.5915837872983;916.3127654259306;685.7291369908858;686.2656957055952;686.2794066230438;688.1295161620504;688.2807466197835;14311.570652880326;14699.779503307458;14746.557603271918;14748.041377310548;14758.557243282612;13312.600621981355;13386.062975591713;13419.194848309133;13620.996131190997;13914.165150091027;12143.817842732366;12148.471354711433;12275.38623068843;12335.629931102081;12788.552400665933;4348.5216626171095;4349.717268377545;4366.694331396803;4528.25283828168;4535.750533720644;101118;103399.48300258526;104135.95864041339;104421;106144;10689.091427228772;10704.09015233714;10939.56241750337;10968.835467467994;10969;19567.412977610693;19848.80907145547;21508.78491215099;21511.89244053801;21516.13935442596;7451.701931922662;7504.137024242282;7578.810529736801;7709.8458030839065;7749.41879359039;4210.305299625527;4215.810288537024;4258.084511829909;4290.892727681833;4372.781360931941;3398.388290107755;3490.89527314181;3491.5984661764223;3501.0547152268746;3508.5789705235534;498.7032715534251;626.6803929995735;634.9174607301021;640.3564417760122;655.5018186178538;594.2486288484376;598.2910251352162;608.7990962982205;608.9025755879038;621.4282859769023;7981;8044.308060773801;8889.599968001456;8943.284537237005;9202.81594368109;557.2143277978113;639.114825966031;639.3287048598952;666.5167753378762;707.0454885904091;3229.5155726640724;4462.352958820953;4478.507364189936;4598.011427543028;4710.678971325207;84978.15021849703;85352;85905;87433.68849467263;87664;59093.704531477066;67626.64746705038;74541.25458745452;75354.86967695488;76875.61562192265;68702;69225;69780;70130.64934675497;71283;8861.778455538662;8953.552322383724;9000.099990000948;9072.591733371883;9299.163075323253;29181.854090729412;43864.34203486887;44911;45069.774651126536;46281.768591156826;47391.81520462016;59105;59774.70112649409;60117.69941150266;61943;10227.43749093807;10234.488275586042;10265.692029239135;10354.689359319233;10489.370637761758;12548.937255314031;12656.43046062911;12905.806412904001;13354.799678005033;13430.462781488632;10218.335808172445;11726.296422214695;12115.878841211475;12194.634160975185;12334.938325308674;11874.881251187722;12096.153269271419;12362.3818809061;12445.502179912703;12495.937520312704;11767.411629418324;12047.698807529881;12328.260304381765;12338.383080845742;12431;10101.898981010096;10295.845562316421;10513.789724205626;10549.367037977747;10753.731156721145;9456.33805633599;9937;9960.850587241051;9964.900350996688;9973 15:34:32 INFO - PROCESS | 1929 | __end_tp_report 15:34:32 INFO - PROCESS | 1929 | __start_cc_report 15:34:32 INFO - PROCESS | 1929 | _x_x_mozilla_cycle_collect,2923 15:34:32 INFO - PROCESS | 1929 | __end_cc_report 15:34:32 INFO - PROCESS | 1929 | __startTimestamp1483054472823__endTimestamp 15:34:32 INFO - PROCESS | 1929 | 15:34:32 INFO - PROCESS | 1929 | ------- Summary: start ------- 15:34:32 INFO - PROCESS | 1929 | Number of tests: 6 15:34:32 INFO - PROCESS | 1929 | 15:34:32 INFO - PROCESS | 1929 | [#0] dojo.html Cycles:165 Average:5627.53 Median:5758.60 stddev:631.64 (11.0%) stddev-sans-first:605.12 15:34:32 INFO - PROCESS | 1929 | Values: 3238.1 3246.0 3251.5 3262.6 3301.1 5444.3 5498.1 5584.3 5584.8 5624.4 5543.4 5562.7 5606.0 5635.0 5650.6 5169.7 5181.8 5193.0 5210.9 5219.4 5028.7 5144.8 5233.3 5238.8 5244.7 5191.1 5227.8 5242.1 5243.6 5250.9 5066.4 5087.1 5173.1 5182.4 5227.6 5046.7 5092.9 5126.6 5136.7 5167.7 5578.1 5584.4 5622.3 5623.1 5661.6 6173.4 6176.5 6183.3 6192.2 6199.0 6104.5 6104.8 6120.9 6123.8 6167.1 5741.4 5751.1 5758.6 5776.0 5789.9 6235.2 6242.4 6254.9 6260.6 6270.6 5826.1 5898.9 5901.9 6050.5 6071.3 6121.1 6141.2 6155.2 6173.1 6236.5 4856.8 6016.6 6132.7 6144.4 6155.2 6021.7 6094.6 6110.8 6111.6 6171.2 4871.1 4880.6 4881.7 4901.3 4905.8 4334.6 4342.2 4363.1 4389.5 4405.2 4664.3 5684.9 5685.2 5687.6 5719.8 6186.0 6186.3 6190.1 6203.2 6208.3 5527.9 5590.3 5657.6 5669.2 5685.2 5261.5 5316.0 5350.4 5360.3 5413.1 6199.8 6208.7 6217.8 6219.2 6245.3 4864.5 5062.4 6160.5 6204.9 6235.1 6159.4 6188.3 6211.4 6212.1 6228.2 6044.5 6047.8 6048.5 6087.4 6090.2 6142.9 6144.0 6148.7 6156.0 6156.4 5703.6 5775.7 5868.4 5926.4 5928.1 5845.4 5895.5 5907.3 5923.1 5925.0 5755.2 5759.8 5767.8 5772.7 5774.3 5889.2 5913.2 5930.0 5933.0 5946.1 5742.0 5743.8 5751.3 5754.9 5755.8 15:34:32 INFO - PROCESS | 1929 | 15:34:32 INFO - PROCESS | 1929 | [#1] ext.html Cycles:180 Average:62988.92 Median:35444.06 stddev:136730.12 (385.8%) stddev-sans-first:137033.00 15:34:32 INFO - PROCESS | 1929 | Values: 426.7 436.3 474.3 487.0 504.9 29516.7 37661.8 39619.8 39746.8 41137.6 40864.4 41199.4 41236.6 41253.6 41282.2 8331.5 9223.0 9448.8 9455.4 9456.5 8332.8 8899.8 10880.0 11274.1 11464.6 11217.2 11275.1 11276.9 11283.5 11334.9 8364.0 8593.6 11238.8 11324.5 11344.2 8448.2 8508.5 8517.9 10806.2 11097.6 38212.8 38238.4 38677.0 38860.6 38962.0 3942.5 4004.5 4004.8 4035.2 4105.0 28672.0 29286.6 29846.7 29920.7 29953.4 2931.4 3042.9 3073.3 3099.2 3109.8 464022.7 465576.7 469203.0 469538.0 470433.0 55415.0 55541.2 55584.0 56747.7 57021.7 59622.0 60354.7 60545.7 60615.0 60619.4 12013.5 15150.8 15178.9 15199.5 15202.8 10702.3 11019.0 11019.1 11961.3 12055.0 14045.9 14186.8 14211.5 15306.6 15722.1 5754.0 6605.7 6626.7 6688.7 6692.5 716.8 851.7 854.5 858.5 874.7 23613.9 26734.9 27207.9 27329.8 28139.6 964.6 981.2 1012.1 1023.2 1041.8 13131.9 13698.5 14067.9 14073.2 14087.4 611245.0 615385.0 617081.0 617437.0 618010.0 48430.8 48575.8 48631.0 48635.3 48715.3 1329.3 1370.0 1374.5 1377.0 1381.4 24783.1 26424.5 26430.8 26625.2 26733.9 42347.8 46714.0 48007.0 48141.0 50709.0 428525.0 433280.0 433471.0 433596.0 436018.0 23391.6 23784.0 23800.2 23855.5 23857.9 32813.8 39965.0 40265.6 41230.4 43645.8 25406.7 34375.8 34391.0 35392.3 36367.0 28628.4 33618.2 34288.7 34711.1 35495.8 33087.0 34252.0 34301.3 34609.0 34642.7 27838.9 29857.6 30647.2 31082.0 31130.9 22549.9 22987.2 23079.5 23587.8 23611.6 15:34:32 INFO - PROCESS | 1929 | 15:34:32 INFO - PROCESS | 1929 | [#2] jquery.html Cycles:180 Average:32645.21 Median:2913.45 stddev:92032.97 (3158.9%) stddev-sans-first:92259.20 15:34:32 INFO - PROCESS | 1929 | Values: 353.5 371.5 374.1 382.0 403.3 11366.5 14722.0 15473.7 19429.0 20017.0 19098.3 19343.9 19629.9 19870.5 20019.6 5581.4 5825.2 5825.6 5827.9 5834.8 4479.2 5661.9 5698.9 5705.2 5726.1 5533.6 5667.4 5672.9 5680.4 5687.4 5486.8 5576.3 5591.4 5657.6 5658.9 4951.4 4953.2 4956.9 4968.7 4968.8 12054.9 12596.4 12600.3 12606.0 12615.4 2568.3 2587.3 2605.8 2912.7 2914.2 8459.3 9385.0 9581.7 9942.1 10005.4 1805.1 1810.3 1811.6 1814.2 1816.3 103906.5 107382.0 123037.4 126463.0 128529.0 9305.5 10912.6 10972.7 11081.0 11522.8 14207.4 14212.9 14221.0 14243.6 14247.5 8048.6 8328.5 8342.1 8489.8 8503.2 6090.0 7997.4 8036.3 8168.3 8182.8 4399.2 4403.7 4420.7 4461.0 4510.0 1862.4 1904.9 1911.4 1912.8 1913.0 912.7 964.9 987.7 1002.9 1020.4 19291.9 19515.6 19575.0 19734.1 20062.0 942.5 1020.1 1025.8 1036.2 1049.4 9648.0 10099.0 10223.8 10354.3 10369.5 540971.9 542664.6 552172.0 553296.4 554035.0 91186.1 116328.0 119663.0 132647.0 133752.0 968.7 972.6 1009.4 1011.8 1012.6 17208.7 17309.4 17372.7 17583.9 17598.0 54560.0 54609.0 55137.0 55745.4 55951.9 77013.5 78231.6 78299.0 78484.6 80569.6 7744.2 7769.7 7863.0 7901.3 8196.4 10139.9 11804.8 12431.5 12678.0 12765.3 10862.6 10948.5 11156.9 11170.9 11176.4 10808.2 10812.0 11120.5 11211.8 11365.5 11296.2 11315.9 11440.7 11552.1 11556.7 6566.8 7255.6 7363.6 7828.6 8215.1 7516.5 7741.6 7829.5 7847.6 8027.0 15:34:32 INFO - PROCESS | 1929 | 15:34:32 INFO - PROCESS | 1929 | [#3] mootools.html Cycles:180 Average:5367.84 Median:5086.17 stddev:2965.32 (58.3%) stddev-sans-first:2957.16 15:34:32 INFO - PROCESS | 1929 | Values: 1208.2 1398.8 1423.9 1426.4 1438.4 8043.4 8431.3 8450.6 8454.9 8458.5 7416.6 7654.6 7656.8 8042.0 8276.1 2282.3 2323.1 2325.6 2326.6 2331.4 2301.3 2332.5 2342.4 2346.1 2346.4 1967.0 2035.7 2235.8 2301.5 2314.9 2320.4 2323.1 2328.5 2333.2 2335.3 2310.2 2323.3 2328.5 2335.2 2337.5 6421.9 8353.5 8441.3 8548.9 8570.1 5086.6 5124.6 5259.2 5259.6 5270.3 3216.4 3336.9 3375.7 3381.1 3388.1 1109.9 1157.6 1194.5 1203.6 1203.9 10268.2 10677.3 10687.8 10695.0 10853.5 4262.0 4333.0 4428.8 4434.8 4444.7 8663.6 8664.2 8667.7 8679.5 8687.7 5080.3 5085.7 5092.1 5094.6 5094.7 4116.0 4274.1 4444.8 4621.3 4740.9 3548.5 3593.0 3658.1 3682.8 3686.0 1147.3 1156.4 1160.6 1166.9 1169.4 857.6 872.5 880.3 908.3 916.8 5417.4 5467.7 5664.3 5731.2 5737.5 523.4 535.5 546.3 554.5 558.8 3806.5 3828.4 3841.6 3890.3 3908.3 9612.7 10147.8 10152.7 10157.7 10158.6 10119.9 10120.0 10120.0 10124.5 10135.2 7714.3 7875.7 7888.6 7903.1 7909.3 5396.2 5566.5 5637.1 5659.8 5680.7 9274.8 9299.1 9321.5 9323.1 9339.4 9213.6 9298.9 9500.8 9562.3 9562.8 6729.8 6754.4 6768.3 6818.4 6851.1 7820.6 7872.4 7909.9 7992.9 7995.5 7001.7 7223.7 7229.6 7334.6 7366.3 7018.2 7037.6 7131.8 7166.0 7264.3 6279.9 6457.6 6459.3 6461.4 6465.7 4567.7 4808.8 4827.4 4918.4 4995.7 4349.8 4944.4 4969.8 5028.2 5032.6 15:34:32 INFO - PROCESS | 1929 | 15:34:32 INFO - PROCESS | 1929 | [#4] prototype.html Cycles:180 Average:4500.96 Median:5079.63 stddev:1335.77 (26.3%) stddev-sans-first:1302.72 15:34:32 INFO - PROCESS | 1929 | Values: 352.3 357.3 362.2 373.5 373.8 5309.0 5354.7 5366.7 5386.5 5410.2 4241.5 4725.3 5078.7 5236.1 5260.3 3588.4 3727.2 3745.2 4091.1 4108.7 3680.2 4083.8 4108.0 4139.8 4162.9 4058.5 4134.2 4142.3 4142.5 4143.8 3598.1 4018.9 4171.7 4179.6 4184.5 3302.2 3415.9 3527.7 3609.5 3648.5 4304.4 5141.4 5161.6 5164.0 5194.6 4711.3 4755.6 4780.7 4802.4 4812.2 5183.8 5513.7 5578.9 5618.5 5620.5 3681.4 4023.9 4033.1 4038.9 4039.3 4887.6 5297.9 5490.5 5545.3 5647.9 3782.6 4075.9 4151.7 4157.0 4168.0 4211.6 4219.1 4223.2 4230.3 4244.4 4362.1 4686.6 4783.3 4786.1 4826.6 5209.0 5572.2 5590.4 5597.0 5605.6 1196.8 1200.6 1213.6 1349.8 1352.4 772.6 831.9 961.1 964.6 972.3 5018.0 5020.8 5042.5 5063.4 5107.3 4572.7 5082.0 5104.4 5420.7 5628.8 4122.0 4354.0 4815.2 4916.1 5045.7 2586.0 2597.6 2828.8 2853.0 2869.8 5284.3 5389.4 5512.4 5560.6 5638.3 4430.4 4856.7 5324.7 5600.3 5609.8 4890.7 5080.6 5170.4 5606.8 5763.3 5173.6 5191.0 5379.7 5445.4 5534.9 5275.7 5317.6 5695.7 5756.8 5777.9 5198.0 5349.5 5701.6 5898.4 5959.5 3540.1 3802.1 3820.4 3850.9 3879.4 4911.9 5360.9 5590.2 5711.7 5822.2 4972.6 5122.3 5181.4 5398.6 5596.7 4729.6 5554.2 5597.6 5614.5 5629.5 5222.7 5482.8 5503.9 5536.3 5544.7 5536.5 5601.8 5609.2 5639.1 5642.2 5492.3 5499.6 5504.5 5505.5 5514.6 15:34:32 INFO - PROCESS | 1929 | 15:34:32 INFO - PROCESS | 1929 | [#5] yui.html Cycles:180 Average:18280.48 Median:4532.00 stddev:25866.51 (570.8%) stddev-sans-first:25905.47 15:34:32 INFO - PROCESS | 1929 | Values: 720.7 735.5 761.3 788.7 791.6 13217.7 13887.2 14561.5 15604.5 15614.6 12910.0 13040.3 13059.6 13081.1 13083.7 668.2 691.0 826.2 852.8 857.9 851.7 860.7 865.5 867.3 867.9 701.2 920.1 922.0 922.8 922.8 717.9 870.2 907.5 907.6 916.3 685.7 686.3 686.3 688.1 688.3 14311.6 14699.8 14746.6 14748.0 14758.6 13312.6 13386.1 13419.2 13621.0 13914.2 12143.8 12148.5 12275.4 12335.6 12788.6 4348.5 4349.7 4366.7 4528.3 4535.8 101118.0 103399.5 104136.0 104421.0 106144.0 10689.1 10704.1 10939.6 10968.8 10969.0 19567.4 19848.8 21508.8 21511.9 21516.1 7451.7 7504.1 7578.8 7709.8 7749.4 4210.3 4215.8 4258.1 4290.9 4372.8 3398.4 3490.9 3491.6 3501.1 3508.6 498.7 626.7 634.9 640.4 655.5 594.2 598.3 608.8 608.9 621.4 7981.0 8044.3 8889.6 8943.3 9202.8 557.2 639.1 639.3 666.5 707.0 3229.5 4462.4 4478.5 4598.0 4710.7 84978.2 85352.0 85905.0 87433.7 87664.0 59093.7 67626.6 74541.3 75354.9 76875.6 68702.0 69225.0 69780.0 70130.6 71283.0 8861.8 8953.6 9000.1 9072.6 9299.2 29181.9 43864.3 44911.0 45069.8 46281.8 47391.8 59105.0 59774.7 60117.7 61943.0 10227.4 10234.5 10265.7 10354.7 10489.4 12548.9 12656.4 12905.8 13354.8 13430.5 10218.3 11726.3 12115.9 12194.6 12334.9 11874.9 12096.2 12362.4 12445.5 12495.9 11767.4 12047.7 12328.3 12338.4 12431.0 10101.9 10295.8 10513.8 10549.4 10753.7 9456.3 9937.0 9960.9 9964.9 9973.0 15:34:32 INFO - PROCESS | 1929 | -------- Summary: end -------- 15:34:32 INFO - PROCESS | 1929 | 15:34:33 INFO - TEST-INFO | 1929: exit 0 15:34:33 INFO - TEST-OK | dromaeo_css | took 1119171ms 15:34:33 INFO - TEST-START | kraken 15:34:33 INFO - operating with platform_type : mac_ 15:34:33 INFO - Initialising browser for kraken test... 15:34:33 INFO - TEST-INFO | started process 1933 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/tmpNULbV_/profile http://localhost:49502/getInfo.html) 15:34:34 INFO - PROCESS | 1933 | 1483054474033 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4986 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3827 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2831 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 15:34:35 INFO - PROCESS | 1933 | __metrics Screen width/height:1600/1200 15:34:35 INFO - PROCESS | 1933 | colorDepth:24 15:34:35 INFO - PROCESS | 1933 | Browser inner width/height: 1024/689 15:34:35 INFO - PROCESS | 1933 | __metrics 15:34:35 INFO - PROCESS | 1933 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:34:35 INFO - PROCESS | 1933 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:34:36 INFO - TEST-INFO | 1933: exit 0 15:34:36 INFO - Browser initialized. 15:34:36 INFO - Running cycle 1/1 for kraken test... 15:34:36 INFO - TEST-INFO | started process 1934 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/tmpNULbV_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:34:48 INFO - PROCESS | 1934 | RSS: Main: 234164224 15:34:48 INFO - PROCESS | 1934 | 15:34:51 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:49502/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49502/tests/kraken/driver.html?testName=audio-beat-detection) 15:34:52 INFO - PROCESS | 1934 | RSS: Main: 286007296 15:34:52 INFO - PROCESS | 1934 | 15:34:55 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:49502/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49502/tests/kraken/driver.html?testName=audio-dft) 15:34:55 INFO - PROCESS | 1934 | RSS: Main: 287535104 15:34:55 INFO - PROCESS | 1934 | 15:34:59 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:49502/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49502/tests/kraken/driver.html?testName=audio-fft) 15:34:59 INFO - PROCESS | 1934 | RSS: Main: 378437632 15:34:59 INFO - PROCESS | 1934 | 15:35:01 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:49502/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49502/tests/kraken/driver.html?testName=audio-oscillator) 15:35:02 INFO - PROCESS | 1934 | RSS: Main: 283017216 15:35:02 INFO - PROCESS | 1934 | 15:35:04 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:49502/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49502/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:35:04 INFO - PROCESS | 1934 | RSS: Main: 286564352 15:35:04 INFO - PROCESS | 1934 | 15:35:07 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:49502/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49502/tests/kraken/driver.html?testName=imaging-darkroom) 15:35:08 INFO - PROCESS | 1934 | RSS: Main: 327864320 15:35:08 INFO - PROCESS | 1934 | 15:35:11 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:49502/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49502/tests/kraken/driver.html?testName=imaging-desaturate) 15:35:11 INFO - PROCESS | 1934 | RSS: Main: 328884224 15:35:11 INFO - PROCESS | 1934 | 15:35:14 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:49502/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49502/tests/kraken/driver.html?testName=json-parse-financial) 15:35:15 INFO - PROCESS | 1934 | RSS: Main: 328024064 15:35:15 INFO - PROCESS | 1934 | 15:35:16 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:49502/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49502/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:35:17 INFO - PROCESS | 1934 | RSS: Main: 303321088 15:35:17 INFO - PROCESS | 1934 | 15:35:19 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:49502/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49502/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:35:19 INFO - PROCESS | 1934 | RSS: Main: 307707904 15:35:19 INFO - PROCESS | 1934 | 15:35:22 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:49502/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49502/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:35:22 INFO - PROCESS | 1934 | RSS: Main: 308539392 15:35:22 INFO - PROCESS | 1934 | 15:35:25 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:49502/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49502/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:35:26 INFO - PROCESS | 1934 | RSS: Main: 324808704 15:35:26 INFO - PROCESS | 1934 | 15:35:29 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:49502/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49502/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:35:30 INFO - PROCESS | 1934 | RSS: Main: 318910464 15:35:30 INFO - PROCESS | 1934 | 15:35:31 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:49502/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49502/tests/kraken/driver.html?testName=ai-astar) 15:35:32 INFO - PROCESS | 1934 | RSS: Main: 363016192 15:35:32 INFO - PROCESS | 1934 | 15:35:32 INFO - PROCESS | 1934 | __start_tp_report 15:35:32 INFO - PROCESS | 1934 | _x_x_mozilla_page_load 15:35:32 INFO - PROCESS | 1934 | _x_x_mozilla_page_load_details 15:35:32 INFO - PROCESS | 1934 | |i|pagename|runs| 15:35:32 INFO - PROCESS | 1934 | |0;ai-astar;114;113;115;112;112;112;112;112;112;112 15:35:32 INFO - PROCESS | 1934 | |1;audio-beat-detection;144;110;109;147;109;107;113;131;165;110 15:35:32 INFO - PROCESS | 1934 | |2;audio-dft;182;185;182;185;185;186;184;181;185;182 15:35:32 INFO - PROCESS | 1934 | |3;audio-fft;80;59;59;101;61;59;60;58;74;59 15:35:32 INFO - PROCESS | 1934 | |4;audio-oscillator;85;70;85;69;69;70;83;70;70;84 15:35:32 INFO - PROCESS | 1934 | |5;imaging-gaussian-blur;94;84;87;85;87;84;88;84;87;83 15:35:32 INFO - PROCESS | 1934 | |6;imaging-darkroom;95;97;95;97;97;96;96;97;95;97 15:35:32 INFO - PROCESS | 1934 | |7;imaging-desaturate;87;88;86;85;85;84;88;87;85;84 15:35:32 INFO - PROCESS | 1934 | |8;json-parse-financial;56;55;55;55;55;55;56;56;56;55 15:35:32 INFO - PROCESS | 1934 | |9;json-stringify-tinderbox;66;76;83;68;74;73;73;72;73;73 15:35:32 INFO - PROCESS | 1934 | |10;stanford-crypto-aes;87;87;87;88;89;93;107;87;87;88 15:35:32 INFO - PROCESS | 1934 | |11;stanford-crypto-ccm;146;149;153;155;139;142;136;142;208;170 15:35:32 INFO - PROCESS | 1934 | |12;stanford-crypto-pbkdf2;174;175;175;174;175;172;174;172;172;173 15:35:32 INFO - PROCESS | 1934 | |13;stanford-crypto-sha256-iterative;67;67;68;66;69;68;66;66;66;66 15:35:32 INFO - PROCESS | 1934 | __end_tp_report 15:35:32 INFO - PROCESS | 1934 | __start_cc_report 15:35:32 INFO - PROCESS | 1934 | _x_x_mozilla_cycle_collect,1942 15:35:32 INFO - PROCESS | 1934 | __end_cc_report 15:35:32 INFO - PROCESS | 1934 | __startTimestamp1483054532046__endTimestamp 15:35:32 INFO - PROCESS | 1934 | 15:35:32 INFO - PROCESS | 1934 | ------- Summary: start ------- 15:35:32 INFO - PROCESS | 1934 | Number of tests: 14 15:35:32 INFO - PROCESS | 1934 | 15:35:32 INFO - PROCESS | 1934 | [#0] ai-astar Cycles:10 Average:112.60 Median:112.00 stddev:1.07 (1.0%) stddev-sans-first:1.01 15:35:32 INFO - PROCESS | 1934 | Values: 114.0 113.0 115.0 112.0 112.0 112.0 112.0 112.0 112.0 112.0 15:35:32 INFO - PROCESS | 1934 | 15:35:32 INFO - PROCESS | 1934 | [#1] audio-beat-detection Cycles:10 Average:124.50 Median:122.00 stddev:20.84 (17.1%) stddev-sans-first:20.87 15:35:32 INFO - PROCESS | 1934 | Values: 144.0 110.0 109.0 147.0 109.0 107.0 113.0 131.0 165.0 110.0 15:35:32 INFO - PROCESS | 1934 | 15:35:32 INFO - PROCESS | 1934 | [#2] audio-dft Cycles:10 Average:183.70 Median:185.00 stddev:1.77 (1.0%) stddev-sans-first:1.76 15:35:32 INFO - PROCESS | 1934 | Values: 182.0 185.0 182.0 185.0 185.0 186.0 184.0 181.0 185.0 182.0 15:35:32 INFO - PROCESS | 1934 | 15:35:32 INFO - PROCESS | 1934 | [#3] audio-fft Cycles:10 Average:67.00 Median:59.50 stddev:14.13 (23.7%) stddev-sans-first:14.18 15:35:32 INFO - PROCESS | 1934 | Values: 80.0 59.0 59.0 101.0 61.0 59.0 60.0 58.0 74.0 59.0 15:35:32 INFO - PROCESS | 1934 | 15:35:32 INFO - PROCESS | 1934 | [#4] audio-oscillator Cycles:10 Average:75.50 Median:76.50 stddev:7.56 (9.9%) stddev-sans-first:7.20 15:35:32 INFO - PROCESS | 1934 | Values: 85.0 70.0 85.0 69.0 69.0 70.0 83.0 70.0 70.0 84.0 15:35:32 INFO - PROCESS | 1934 | 15:35:32 INFO - PROCESS | 1934 | [#5] imaging-gaussian-blur Cycles:10 Average:86.30 Median:87.00 stddev:3.20 (3.7%) stddev-sans-first:1.81 15:35:32 INFO - PROCESS | 1934 | Values: 94.0 84.0 87.0 85.0 87.0 84.0 88.0 84.0 87.0 83.0 15:35:32 INFO - PROCESS | 1934 | 15:35:32 INFO - PROCESS | 1934 | [#6] imaging-darkroom Cycles:10 Average:96.20 Median:97.00 stddev:0.92 (0.9%) stddev-sans-first:0.87 15:35:32 INFO - PROCESS | 1934 | Values: 95.0 97.0 95.0 97.0 97.0 96.0 96.0 97.0 95.0 97.0 15:35:32 INFO - PROCESS | 1934 | 15:35:32 INFO - PROCESS | 1934 | [#7] imaging-desaturate Cycles:10 Average:85.90 Median:86.50 stddev:1.52 (1.8%) stddev-sans-first:1.56 15:35:32 INFO - PROCESS | 1934 | Values: 87.0 88.0 86.0 85.0 85.0 84.0 88.0 87.0 85.0 84.0 15:35:32 INFO - PROCESS | 1934 | 15:35:32 INFO - PROCESS | 1934 | [#8] json-parse-financial Cycles:10 Average:55.40 Median:55.50 stddev:0.52 (0.9%) stddev-sans-first:0.50 15:35:32 INFO - PROCESS | 1934 | Values: 56.0 55.0 55.0 55.0 55.0 55.0 56.0 56.0 56.0 55.0 15:35:32 INFO - PROCESS | 1934 | 15:35:32 INFO - PROCESS | 1934 | [#9] json-stringify-tinderbox Cycles:10 Average:73.10 Median:73.00 stddev:4.53 (6.2%) stddev-sans-first:4.01 15:35:32 INFO - PROCESS | 1934 | Values: 66.0 76.0 83.0 68.0 74.0 73.0 73.0 72.0 73.0 73.0 15:35:32 INFO - PROCESS | 1934 | 15:35:32 INFO - PROCESS | 1934 | [#10] stanford-crypto-aes Cycles:10 Average:90.00 Median:87.50 stddev:6.25 (7.1%) stddev-sans-first:6.54 15:35:32 INFO - PROCESS | 1934 | Values: 87.0 87.0 87.0 88.0 89.0 93.0 107.0 87.0 87.0 88.0 15:35:32 INFO - PROCESS | 1934 | 15:35:32 INFO - PROCESS | 1934 | [#11] stanford-crypto-ccm Cycles:10 Average:154.00 Median:151.00 stddev:21.34 (14.1%) stddev-sans-first:22.44 15:35:32 INFO - PROCESS | 1934 | Values: 146.0 149.0 153.0 155.0 139.0 142.0 136.0 142.0 208.0 170.0 15:35:32 INFO - PROCESS | 1934 | 15:35:32 INFO - PROCESS | 1934 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.60 Median:174.00 stddev:1.26 (0.7%) stddev-sans-first:1.33 15:35:32 INFO - PROCESS | 1934 | Values: 174.0 175.0 175.0 174.0 175.0 172.0 174.0 172.0 172.0 173.0 15:35:32 INFO - PROCESS | 1934 | 15:35:32 INFO - PROCESS | 1934 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.90 Median:67.00 stddev:1.10 (1.6%) stddev-sans-first:1.17 15:35:32 INFO - PROCESS | 1934 | Values: 67.0 67.0 68.0 66.0 69.0 68.0 66.0 66.0 66.0 66.0 15:35:32 INFO - PROCESS | 1934 | -------- Summary: end -------- 15:35:32 INFO - PROCESS | 1934 | 15:35:32 INFO - TEST-INFO | 1934: exit 0 15:35:32 INFO - TEST-OK | kraken | took 59429ms 15:35:32 INFO - SUITE-END | took 1178s 15:35:33 INFO - Completed test suite (00:19:38) 15:35:33 INFO - javascript benchmark 15:35:33 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3238.1257060593634, 3245.983770081149, 3251.4960181171914, 3262.6411094779587, 3301.14170315718, 5444.292242008543, 5498.0653288940875, 5584.329880414351, 5584.8324550263505, 5624.3813180550105, 5543.445655434454, 5562.721863906809, 5606.0, 5635.0, 5650.63270887392, 5169.741512924357, 5181.766820493068, 5193.0, 5210.869728256786, 5219.373675158986, 5028.7485625718755, 5144.768485418165, 5233.319668443116, 5238.79044838206, 5244.685318880879, 5191.0656081905245, 5227.817026404076, 5242.082635538784, 5243.580513558925, 5250.8687282817855, 5066.442691303953, 5087.135187018179, 5173.094708426011, 5182.378114626231, 5227.60792940531, 5046.671966322214, 5092.923606145874, 5126.5898728101665, 5136.743162841843, 5167.741612919376, 5578.079616863212, 5584.4136365681425, 5622.297212848394, 5623.0721930881355, 5661.631993920382, 6173.444390004921, 6176.536759742992, 6183.258009038944, 6192.225971753531, 6199.0, 6104.519788040277, 6104.786332478387, 6120.908186377208, 6123.750585468418, 6167.074938759177, 5741.3971532988435, 5751.108578170307, 5758.596898217168, 5775.971120144414, 5789.913151302691, 6235.220597425322, 6242.438180563771, 6254.906176407358, 6260.561760676709, 6270.561060725797, 5826.096954971986, 5898.941010589925, 5901.868691915676, 6050.45545900871, 6071.2714474263375, 6121.1481385573825, 6141.170941922873, 6155.230596175478, 6173.074038894202, 6236.501079913596, 4856.8300109495995, 6016.608920420159, 6132.703928623082, 6144.3717414885095, 6155.169052177988, 6021.729022194012, 6094.634321940698, 6110.7555697771595, 6111.622125543816, 6171.228596425447, 4871.074495845778, 4880.633952453509, 4881.658283920163, 4901.3383193269165, 4905.754712264444, 4334.631556317685, 4342.196693611692, 4363.105563359503, 4389.4513185851765, 4405.229084910128, 4664.253719404879, 5684.857878553069, 5685.232493613392, 5687.630304030225, 5719.799807006735, 6186.0, 6186.288576813724, 6190.133381326618, 6203.16257305267, 6208.348123447064, 5527.8894422112135, 5590.301212348457, 5657.6039677223025, 5669.177969194444, 5685.175649530877, 5261.4738526147075, 5315.973420132875, 5350.438203988603, 5360.303160589098, 5413.079776438015, 6199.814005579839, 6208.72060757267, 6217.844553886189, 6219.222597175353, 6245.281792593819, 4864.513548645249, 5062.417821950376, 6160.507159427146, 6204.937950620617, 6235.064740288993, 6159.414855588712, 6188.257409110935, 6211.378862113753, 6212.06818977157, 6228.22147231596, 6044.516438684983, 6047.78832740852, 6048.485878700184, 6087.421694938974, 6090.177825993612, 6142.877142457215, 6144.0, 6148.661823599745, 6156.0, 6156.415140561639, 5703.5722320826935, 5775.711214439178, 5868.354481007001, 5926.377730338252, 5928.140419639042, 5845.386234445322, 5895.469407753323, 5907.261592300963, 5923.08192230205, 5925.0, 5755.1942728017275, 5759.827205183851, 5767.769289228384, 5772.682502462237, 5774.335951365479, 5889.234399528034, 5913.231279933581, 5929.970350148222, 5933.0, 5946.108083787466, 5742.0, 5743.770249190153, 5751.338596061507, 5754.9424505754405, 5755.827325180251], "value": 5587.883103659775, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [426.70983731062887, 436.3324114105419, 474.33356134630856, 487.0137970609515, 504.9192129259317, 29516.704832951666, 37661.81169094154, 39619.811405657805, 39746.8012659937, 41137.58862411375, 40864.38703419451, 41199.38200926989, 41236.58763412365, 41253.58746412535, 41282.17435651293, 8331.500109993389, 9223.0, 9448.763780905463, 9455.385399948982, 9456.479893605883, 8332.83334333313, 8899.822003559924, 10880.0, 11274.098072154207, 11464.598739044135, 11217.214794964355, 11275.097992160607, 11276.943615281913, 11283.548658053667, 11334.943325283362, 8364.0, 8593.613287402082, 11238.831417528743, 11324.547018119307, 11344.205905586612, 8448.197421244971, 8508.489490630582, 8517.914820851774, 10806.18953578477, 11097.55609775608, 38212.808935955145, 38238.42642360367, 38677.03307417309, 38860.611393885985, 38962.02594935121, 3942.4842340544287, 4004.4994375703036, 4004.7797371144575, 4035.1929614077007, 4105.0, 28672.0, 29286.560701589493, 29846.70153298461, 29920.700792992007, 29953.400931981458, 2931.384409274055, 3042.863071161803, 3073.2931425771976, 3099.2251937015744, 3109.751219902402, 464022.67988659843, 465576.67211664066, 469203.0, 469538.0, 470433.0, 55415.0, 55541.22422860803, 55584.0, 56747.71626141843, 57021.714891426105, 59622.0, 60354.69822650859, 60545.69727151424, 60615.0, 60619.39380606226, 12013.519459221534, 15150.772738408712, 15178.924105379403, 15199.468018619295, 15202.847971520143, 10702.250842440955, 11019.008289254005, 11019.118470522339, 11961.28232306047, 12055.035597152208, 14045.859541404456, 14186.787198192036, 14211.502597409248, 15306.617334566503, 15722.13528255957, 5753.971230143822, 6605.735770569124, 6626.668666566652, 6688.732450702015, 6692.531522793455, 716.7885473785142, 851.7104184577273, 854.4574195385975, 858.454881150474, 874.6676263020139, 23613.88193059058, 26734.86632566825, 27207.86396068007, 27329.770160342832, 28139.577906331422, 964.62379671928, 981.1807141037314, 1012.0891197921782, 1023.1763430438568, 1041.796849614318, 13131.934340328236, 13698.452061917611, 14067.859321406859, 14073.225972571607, 14087.436502539784, 611245.0, 615385.0, 617081.0, 617437.0, 618010.0, 48430.757846210545, 48575.75712121417, 48631.0, 48635.27047094297, 48715.26927096096, 1329.3419757220238, 1370.047816767337, 1374.5120482228665, 1376.9586912392845, 1381.3714759784275, 24783.13259035925, 26424.471510570067, 26430.810613522823, 26625.20124396271, 26733.86633066822, 42347.7882610585, 46714.0, 48007.0, 48141.03717925692, 50709.0, 428525.0, 433280.0, 433471.0, 433596.0, 436018.0, 23391.649125263473, 23784.048638054977, 23800.166994155123, 23855.52288954246, 23857.92639331269, 32813.83593082019, 39965.0, 40265.59734402619, 41230.38154427746, 43645.781771090944, 25406.745932540438, 34375.82812085924, 34391.0, 35392.29215415729, 36367.0, 28628.42743145167, 33618.15954601154, 34288.65711342855, 34711.09782825304, 35495.82252088723, 33087.0, 34252.0, 34301.313973720884, 34609.0, 34642.65357346394, 27838.86080569584, 29857.57581760186, 30647.2338191547, 31082.0, 31130.899554260934, 22549.887250563643, 22987.195448159233, 23079.538409232056, 23587.764122359244, 23611.64582531229], "value": 18512.128473289886, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [353.49979778613266, 371.4947671167212, 374.1040208700163, 381.9789911554864, 403.27611936573845, 11366.545338186474, 14721.969462137655, 15473.69052618947, 19429.0, 20017.0, 19098.331558395425, 19343.872174060878, 19629.90185049076, 19870.50323741907, 20019.59960800783, 5581.441855814416, 5825.155352473888, 5825.592208545382, 5827.883442331151, 5834.824955251349, 4479.216137175998, 5661.886762264752, 5698.886022279552, 5705.229793977822, 5726.112452569859, 5533.557315414757, 5667.4049224831415, 5672.914906276409, 5680.431956804307, 5687.402822703619, 5486.780528778844, 5576.275084239023, 5591.440855914397, 5657.57568182388, 5658.943410565924, 4951.356323677899, 4953.157963146274, 4956.884700942295, 4968.726720030397, 4968.782648251202, 12054.879451205463, 12596.433160507799, 12600.306983115925, 12605.991520678324, 12615.432305546272, 2568.2808813532056, 2587.3014286142634, 2605.7915366770612, 2912.6941671124437, 2914.1985953862645, 8459.323254139654, 9385.0, 9581.664641737505, 9942.105210531015, 10005.449700266483, 1805.124514610413, 1810.2741258046156, 1811.5598099510921, 1814.1926842555067, 1816.2825683854803, 103906.48046759718, 107382.0, 123037.38481307536, 126463.0, 128529.0, 9305.53472326381, 10912.618058368078, 10972.725681857857, 11081.002709756161, 11522.76954460906, 14207.431702731981, 14212.928935355258, 14221.00452968303, 14243.64390890236, 14247.501337453139, 8048.637811298506, 8328.541930193898, 8342.082370939192, 8489.830203395897, 8503.23470887623, 6089.969550152221, 7997.400194985399, 8036.316913062456, 8168.305694016075, 8182.7954301142945, 4399.1641588098155, 4403.735775853459, 4420.7126536775, 4461.0, 4510.0, 1862.3761040051463, 1904.9142788574547, 1911.3883557261543, 1912.7513423254888, 1913.0147973793507, 912.6577533424966, 964.9179819715403, 987.7234374375042, 1002.8696269485066, 1020.4489575629077, 19291.903540482206, 19515.609687806165, 19575.0, 19734.111964961612, 20062.0, 942.5004747483845, 1020.0921180149672, 1025.8153532364097, 1036.18659352408, 1049.3598904668147, 9647.951760241294, 10099.020734175818, 10223.846642300372, 10354.275200736027, 10369.481525923673, 540971.9028097158, 542664.5733542613, 552172.0, 553296.4368384997, 554035.0, 91186.08813911775, 116328.0, 119663.0, 132647.0, 133752.0, 968.6512855372201, 972.601233494264, 1009.3842755918884, 1011.8431643095332, 1012.6050840172338, 17208.65582688364, 17309.39417120395, 17372.65254694874, 17583.903292508636, 17598.0, 54560.0, 54609.0, 55137.0, 55745.44254557403, 55951.88697638738, 77013.45973080395, 78231.60884195543, 78299.0, 78484.60757696403, 80569.59715201387, 7744.186860379805, 7769.650365733442, 7862.960685196537, 7901.328387087162, 8196.38527110481, 10139.898601013896, 11804.82292765592, 12431.502739890664, 12678.049146314248, 12765.297908614744, 10862.565497380016, 10948.507317170586, 11156.944215278872, 11170.944145279222, 11176.441177941233, 10808.243422960317, 10812.0, 11120.499577519193, 11211.831822522505, 11365.48855301497, 11296.152788541056, 11315.886841131483, 11440.654665793327, 11552.133589980616, 11556.653300401002, 6566.80299591013, 7255.600941948243, 7363.595002274927, 7828.647710852912, 8215.096339402542, 7516.5114267572435, 7741.5742134183165, 7829.530228186327, 7847.607619619111, 8027.0], "value": 9026.92897353409, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1208.190512356721, 1398.8111604933333, 1423.9288035598217, 1426.3937826423767, 1438.381495956739, 8043.356531477482, 8431.325493960483, 8450.577471126435, 8454.873176902343, 8458.534780587066, 7416.592087435202, 7654.617269136535, 7656.846863062736, 8042.034955805297, 8276.131006244348, 2282.3495303838367, 2323.1056043423237, 2325.5581439526522, 2326.569584626841, 2331.405491599648, 2301.2635956493928, 2332.486852892361, 2342.3792694935855, 2346.061575369857, 2346.4485845826216, 1966.9606607867834, 2035.6742921132623, 2235.7987781099696, 2301.539692061586, 2314.90740370385, 2320.385097949045, 2323.0939933425975, 2328.511012687338, 2333.230034088747, 2335.2760644200353, 2310.1683393978155, 2323.303009097281, 2328.5342931413616, 2335.2060299498253, 2337.474068334628, 6421.903671444932, 8353.540555269457, 8441.324694024463, 8548.914510854936, 8570.100139485327, 5086.593072554187, 5124.641275110744, 5259.184826351921, 5259.579233661336, 5270.314859068335, 3216.404965081444, 3336.9332613347597, 3375.6961873431264, 3381.1209085637665, 3388.136025313554, 1109.944502774858, 1157.6295585412672, 1194.5162209305245, 1203.5607003443768, 1203.8555373355166, 10268.229882758824, 10677.305975111592, 10687.78624427507, 10695.03744662984, 10853.5115919783, 4262.0, 4332.978335108304, 4428.8484993901175, 4434.822607095744, 4444.688871779009, 8663.566821658891, 8664.176903194313, 8667.653293868301, 8679.522626255617, 8687.739367818975, 5080.263361812516, 5085.74571271435, 5092.083424983539, 5094.61790365724, 5094.745262736848, 4116.0, 4274.145170965757, 4444.822207111744, 4621.2837010263465, 4740.881477963078, 3548.503209550665, 3592.98203508986, 3658.067192865802, 3682.760620559655, 3685.963140368562, 1147.2657499200518, 1156.3697784707354, 1160.6053941659877, 1166.9183157178916, 1169.4211365374194, 857.6097875466647, 872.47651409154, 880.3133555826465, 908.2525360783682, 916.7524768312518, 5417.404085550586, 5467.651721353205, 5664.320281566238, 5731.168980497804, 5737.454941780524, 523.3745673919706, 535.4698848140391, 546.3116473243741, 554.5314209493052, 558.793007104652, 3806.5051543299196, 3828.425736139601, 3841.5966323536186, 3890.3386424307937, 3908.2769687607883, 9612.741005863616, 10147.797044059078, 10152.746181345525, 10157.746056348502, 10158.593656253815, 10119.898801012043, 10120.0, 10120.038596333337, 10124.493775311352, 10135.239857010607, 7714.267144621252, 7875.724349647735, 7888.605569721491, 7903.091144518339, 7909.3277071449575, 5396.163594642837, 5566.487209964433, 5637.126245431965, 5659.801906933238, 5680.68756218412, 9274.768130796783, 9299.116583924517, 9321.533923303943, 9323.114304141096, 9339.439633622005, 9213.585388657393, 9298.907010929805, 9500.809983800147, 9562.28282878773, 9562.760930976781, 6729.798106056826, 6754.3921047106, 6768.289329620516, 6818.420434263143, 6851.109355783717, 7820.648070836713, 7872.409569282212, 7909.8813517796125, 7992.9200707994505, 7995.480293780885, 7001.684924178322, 7223.747168849065, 7229.602371869598, 7334.559926404433, 7366.337029667356, 7018.192907815463, 7037.648117594203, 7131.750388736369, 7166.0, 7264.309890560388, 6279.874402511833, 6457.644829534421, 6459.257185423548, 6461.386168314003, 6465.709043092978, 4567.703099298667, 4808.783604737725, 4827.372441582572, 4918.43438004634, 4995.700257984533, 4349.760763158057, 4944.381952255968, 4969.801207951641, 5028.220625803006, 5032.647714659939], "value": 4296.6875201945495, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.28837747749543, 357.3282229408711, 362.18869731800766, 373.46407904656803, 373.770131369208, 5308.973455132719, 5354.7416357156035, 5366.677999320039, 5386.542143917762, 5410.215518749779, 4241.491021077467, 4725.291206319054, 5078.669886457377, 5236.136037553799, 5260.342457192851, 3588.354096262685, 3727.1986522897573, 3745.232227393378, 4091.0795071108987, 4108.746832216173, 3680.245549662326, 4083.775392353419, 4107.979460102711, 4139.813708383115, 4162.895927601804, 4058.4521089652812, 4134.193832202722, 4142.316517774563, 4142.502899652046, 4143.7513749175, 3598.064503229179, 4018.874715079782, 4171.687123465737, 4179.561146079648, 4184.497860256758, 3302.240484688535, 3415.8975230743117, 3527.735419843522, 3609.4946707460977, 3648.4527320902075, 4304.418903448026, 5141.354766474729, 5161.599856066947, 5163.974180129076, 5194.558462530689, 4711.269753188261, 4755.5719985201495, 4780.689255198401, 4802.43920725762, 4812.184871849972, 5183.792648294064, 5513.669179849222, 5578.9163162552595, 5618.4662457066515, 5620.494155525981, 3681.44778283257, 4023.941804228778, 4033.0925541753163, 4038.9394159087637, 4039.313316736162, 4887.582601045692, 5297.867553311159, 5490.505854473036, 5545.334559852843, 5647.8588035299035, 3782.583915769263, 4075.9388609170887, 4151.73013754105, 4156.9792151039055, 4167.979160104241, 4211.620954114083, 4219.109909711796, 4223.176480586256, 4230.259704551691, 4244.3524340846725, 4362.105768317488, 4686.625069994392, 4783.258594917794, 4786.138495070921, 4826.565609095198, 5208.989078409603, 5572.192032155396, 5590.385057643737, 5596.964561556125, 5605.5515558756015, 1196.7726132034886, 1200.6278053803348, 1213.5873802906876, 1349.8312710911136, 1352.371147416456, 772.559641004622, 831.9001719793664, 961.1205746741653, 964.5900492290889, 972.2756546372856, 5018.0, 5020.824271150565, 5042.546170844642, 5063.417706963746, 5107.336046313955, 4572.736495434715, 5081.974590127025, 5104.412992505835, 5420.728963551806, 5628.831135065954, 4122.0, 4354.0, 4815.2054910940005, 4916.050369778176, 5045.672031317952, 2585.974140258611, 2597.6103584462103, 2828.8161269517414, 2852.985735071353, 2869.813462124955, 5284.282608152366, 5389.353277606712, 5512.4211957744665, 5560.610757246954, 5638.2670252866865, 4430.379746835446, 4856.732879691651, 5324.707141107198, 5600.263918185376, 5609.775608975677, 4890.657653964188, 5080.593552515716, 5170.353705786777, 5606.803761868315, 5763.250777398911, 5173.560247378864, 5191.0, 5379.704116273642, 5445.373782015119, 5534.889302214014, 5275.709835959059, 5317.553925194454, 5695.715214239354, 5756.769729210952, 5777.91333129995, 5197.974010129925, 5349.518543331119, 5701.600887937804, 5898.439648233582, 5959.463648271676, 3540.097275194809, 3802.144517483655, 3820.3696390096434, 3850.903727406725, 3879.3599056155417, 4911.852644420673, 5360.919586206132, 5590.245316882331, 5711.742971566373, 5822.243108395882, 4972.602191824573, 5122.33524104675, 5181.35233095863, 5398.622096453368, 5596.692181930033, 4729.597984171384, 5554.166874968625, 5597.63615365, 5614.494695477427, 5629.4652008059165, 5222.660527065728, 5482.80810171642, 5503.944960550343, 5536.280283563111, 5544.7227638617105, 5536.54308281837, 5601.7759289629585, 5609.186667933209, 5639.069553523786, 5642.210090587403, 5492.258545096521, 5499.615026948075, 5504.53211477029, 5505.476979687083, 5514.5864060194845], "value": 4086.252302893534, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [720.6865013719032, 735.528306393821, 761.2577736706712, 788.7340669780698, 791.5775361533077, 13217.669558261048, 13887.236202008886, 14561.490347837815, 15604.453844115458, 15614.609634759137, 12910.0, 13040.347982600879, 13059.608211753639, 13081.149725267875, 13083.672908177301, 668.2315337362024, 691.018753370213, 826.2192228344217, 852.8038551133243, 857.8884744983144, 851.6635928808117, 860.6772460327377, 865.4807115730574, 867.332154241237, 867.8611422172454, 701.1971292869662, 920.1258804136064, 921.9631214751402, 922.7923717163652, 922.8108237811233, 717.8707832590131, 870.1646419437348, 907.4555266839909, 907.5915837872983, 916.3127654259306, 685.7291369908858, 686.2656957055952, 686.2794066230438, 688.1295161620504, 688.2807466197835, 14311.570652880326, 14699.779503307458, 14746.557603271918, 14748.041377310548, 14758.557243282612, 13312.600621981355, 13386.062975591713, 13419.194848309133, 13620.996131190997, 13914.165150091027, 12143.817842732366, 12148.471354711433, 12275.38623068843, 12335.629931102081, 12788.552400665933, 4348.5216626171095, 4349.717268377545, 4366.694331396803, 4528.25283828168, 4535.750533720644, 101118.0, 103399.48300258526, 104135.95864041339, 104421.0, 106144.0, 10689.091427228772, 10704.09015233714, 10939.56241750337, 10968.835467467994, 10969.0, 19567.412977610693, 19848.80907145547, 21508.78491215099, 21511.89244053801, 21516.13935442596, 7451.701931922662, 7504.137024242282, 7578.810529736801, 7709.8458030839065, 7749.41879359039, 4210.305299625527, 4215.810288537024, 4258.084511829909, 4290.892727681833, 4372.781360931941, 3398.388290107755, 3490.89527314181, 3491.5984661764223, 3501.0547152268746, 3508.5789705235534, 498.7032715534251, 626.6803929995735, 634.9174607301021, 640.3564417760122, 655.5018186178538, 594.2486288484376, 598.2910251352162, 608.7990962982205, 608.9025755879038, 621.4282859769023, 7981.0, 8044.308060773801, 8889.599968001456, 8943.284537237005, 9202.81594368109, 557.2143277978113, 639.114825966031, 639.3287048598952, 666.5167753378762, 707.0454885904091, 3229.5155726640724, 4462.352958820953, 4478.507364189936, 4598.011427543028, 4710.678971325207, 84978.15021849703, 85352.0, 85905.0, 87433.68849467263, 87664.0, 59093.704531477066, 67626.64746705038, 74541.25458745452, 75354.86967695488, 76875.61562192265, 68702.0, 69225.0, 69780.0, 70130.64934675497, 71283.0, 8861.778455538662, 8953.552322383724, 9000.099990000948, 9072.591733371883, 9299.163075323253, 29181.854090729412, 43864.34203486887, 44911.0, 45069.774651126536, 46281.768591156826, 47391.81520462016, 59105.0, 59774.70112649409, 60117.69941150266, 61943.0, 10227.43749093807, 10234.488275586042, 10265.692029239135, 10354.689359319233, 10489.370637761758, 12548.937255314031, 12656.43046062911, 12905.806412904001, 13354.799678005033, 13430.462781488632, 10218.335808172445, 11726.296422214695, 12115.878841211475, 12194.634160975185, 12334.938325308674, 11874.881251187722, 12096.153269271419, 12362.3818809061, 12445.502179912703, 12495.937520312704, 11767.411629418324, 12047.698807529881, 12328.260304381765, 12338.383080845742, 12431.0, 10101.898981010096, 10295.845562316421, 10513.789724205626, 10549.367037977747, 10753.731156721145, 9456.33805633599, 9937.0, 9960.850587241051, 9964.900350996688, 9973.0], "value": 7016.077098652615, "unit": "score"}], "name": "dromaeo_css", "value": 6973.866789163727, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 115.0, 112.0, 112.0, 112.0, 112.0, 112.0, 112.0, 112.0], "value": 112.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 110.0, 109.0, 147.0, 109.0, 107.0, 113.0, 131.0, 165.0, 110.0], "value": 124.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 185.0, 182.0, 185.0, 185.0, 186.0, 184.0, 181.0, 185.0, 182.0], "value": 183.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 59.0, 59.0, 101.0, 61.0, 59.0, 60.0, 58.0, 74.0, 59.0], "value": 67.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 70.0, 85.0, 69.0, 69.0, 70.0, 83.0, 70.0, 70.0, 84.0], "value": 75.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 84.0, 87.0, 85.0, 87.0, 84.0, 88.0, 84.0, 87.0, 83.0], "value": 86.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 97.0, 95.0, 97.0, 97.0, 96.0, 96.0, 97.0, 95.0, 97.0], "value": 96.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 88.0, 86.0, 85.0, 85.0, 84.0, 88.0, 87.0, 85.0, 84.0], "value": 85.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 55.0, 55.0, 55.0, 55.0, 56.0, 56.0, 56.0, 55.0], "value": 55.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 76.0, 83.0, 68.0, 74.0, 73.0, 73.0, 72.0, 73.0, 73.0], "value": 73.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 87.0, 87.0, 88.0, 89.0, 93.0, 107.0, 87.0, 87.0, 88.0], "value": 90.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 149.0, 153.0, 155.0, 139.0, 142.0, 136.0, 142.0, 208.0, 170.0], "value": 154.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 175.0, 175.0, 174.0, 175.0, 172.0, 174.0, 172.0, 172.0, 173.0], "value": 173.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 68.0, 66.0, 69.0, 68.0, 66.0, 66.0, 66.0, 66.0], "value": 66.9, "unit": "score"}], "name": "kraken", "value": 1444.6999999999998, "alertThreshold": 2.0}]} 15:35:33 INFO - Return code: 0 15:35:33 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:35:33 INFO - # TBPL SUCCESS # 15:35:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:35:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:35:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:35:33 INFO - 2016-12-29 15:35:33.386 screenresolution[1938:15905] starting screenresolution argv=screenresolution get 15:35:33 INFO - 2016-12-29 15:35:33.389 screenresolution[1938:15905] Display 0: 1600x1200x32@60 15:35:33 INFO - 2016-12-29 15:35:33.395 screenresolution[1939:15908] starting screenresolution argv=screenresolution list 15:35:33 INFO - Available Modes on Display 0 15:35:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:35:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:35:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:35:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:35:33 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:35:33 INFO - Intel Iris: 15:35:33 INFO - Chipset Model: Intel Iris 15:35:33 INFO - Type: GPU 15:35:33 INFO - Bus: Built-In 15:35:33 INFO - VRAM (Dynamic, Max): 1536 MB 15:35:33 INFO - Vendor: Intel (0x8086) 15:35:33 INFO - Device ID: 0x0a2e 15:35:33 INFO - Revision ID: 0x0009 15:35:33 INFO - Displays: 15:35:33 INFO - Display: 15:35:33 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:35:33 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:35:33 INFO - Display Serial Number: 1600x1200 60 15:35:33 INFO - Main Display: Yes 15:35:33 INFO - Mirror: Off 15:35:33 INFO - Online: Yes 15:35:33 INFO - Rotation: Supported 15:35:33 INFO - Return code: 0 15:35:33 INFO - Running post-action listener: _resource_record_post_action 15:35:33 INFO - [mozharness: 2016-12-29 23:35:33.629994Z] Finished run-tests step (success) 15:35:33 INFO - Running post-run listener: _resource_record_post_run 15:35:33 INFO - Total resource usage - Wall time: 1201s; CPU: 54.0%; Read bytes: 1040384; Write bytes: 551764992; Read time: 11; Write time: 1748 15:35:33 INFO - TinderboxPrint: CPU usage
53.8% 15:35:33 INFO - TinderboxPrint: I/O read bytes / time
1,040,384 / 11 15:35:33 INFO - TinderboxPrint: I/O write bytes / time
551,764,992 / 1,748 15:35:33 INFO - TinderboxPrint: CPU idle
2,219.3 (46.2%) 15:35:33 INFO - TinderboxPrint: CPU system
963.1 (20.0%) 15:35:33 INFO - TinderboxPrint: CPU user
1,623.2 (33.8%) 15:35:33 INFO - TinderboxPrint: Swap in / out
250,482,688 / 0 15:35:33 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 245748224; Write bytes: 239990272; Read time: 17994; Write time: 481 15:35:33 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 1019904; Write bytes: 298266112; Read time: 10; Write time: 1229 15:35:34 INFO - Running post-run listener: _upload_blobber_files 15:35:34 INFO - Blob upload gear active. 15:35:34 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:35:34 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 15:35:34 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', 'Graphics', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:35:34 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 Graphics -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:35:34 INFO - (blobuploader) - INFO - Open directory for files ... 15:35:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:35:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:35:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:35:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:35:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:35:35 INFO - (blobuploader) - INFO - Done attempting. 15:35:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:35:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:35:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:35:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:35:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:35:36 INFO - (blobuploader) - INFO - Done attempting. 15:35:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 15:35:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:35:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:35:37 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 15:35:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:35:37 INFO - (blobuploader) - INFO - Done attempting. 15:35:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:35:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:35:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:35:39 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:35:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:35:39 INFO - (blobuploader) - INFO - Done attempting. 15:35:39 INFO - (blobuploader) - INFO - Iteration through files over. 15:35:39 INFO - Return code: 0 15:35:39 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:35:39 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:35:39 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/b4c2d67bd79158b99e8297c374e9d5fb0a28f02dc1f11549cad081cddd9270dd348d3e75354c8ed3018643faccc69d0d406ad2beb759a8d49be4aba0397cbca6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/44c41a387f4edfada15cda0fafcb9c2a0083c628d9ca67ba76ae63df6c83401bc2f32685521ba4e7972cf548823a494fb46ed5f2486b8968fab4dcb0b2e559bf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/87d1952ad831e187b8711f02124f9c181f339b048d216d9251fc64010982414a1260fa8bd693501bd21b3ea82f2a6183a3a50220adf2a4fc8fd31bae0ac08268", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/17904b4140952662464f3ac8e18e0542ef3666fced0229a93655880dd262f0b5a4dc9d0cedce2abdaa6bc5f85a9519ddf92ce8d17a850613fd222ced620a2a8d"} 15:35:39 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:35:39 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:35:39 INFO - Contents: 15:35:39 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/b4c2d67bd79158b99e8297c374e9d5fb0a28f02dc1f11549cad081cddd9270dd348d3e75354c8ed3018643faccc69d0d406ad2beb759a8d49be4aba0397cbca6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/44c41a387f4edfada15cda0fafcb9c2a0083c628d9ca67ba76ae63df6c83401bc2f32685521ba4e7972cf548823a494fb46ed5f2486b8968fab4dcb0b2e559bf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/87d1952ad831e187b8711f02124f9c181f339b048d216d9251fc64010982414a1260fa8bd693501bd21b3ea82f2a6183a3a50220adf2a4fc8fd31bae0ac08268", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/17904b4140952662464f3ac8e18e0542ef3666fced0229a93655880dd262f0b5a4dc9d0cedce2abdaa6bc5f85a9519ddf92ce8d17a850613fd222ced620a2a8d"} 15:35:39 INFO - Running post-run listener: copy_logs_to_upload_dir 15:35:39 INFO - Copying logs to upload dir... 15:35:39 INFO - mkdir: /builds/slave/test/build/upload/logs 15:35:39 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1263.670640 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2016-12-29 15:35:39.967135) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-29 15:35:39.970926) ========= 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.vw93tOwmJ3/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.NnmPiRQHAD/Listeners TMPDIR=/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/b4c2d67bd79158b99e8297c374e9d5fb0a28f02dc1f11549cad081cddd9270dd348d3e75354c8ed3018643faccc69d0d406ad2beb759a8d49be4aba0397cbca6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/44c41a387f4edfada15cda0fafcb9c2a0083c628d9ca67ba76ae63df6c83401bc2f32685521ba4e7972cf548823a494fb46ed5f2486b8968fab4dcb0b2e559bf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/87d1952ad831e187b8711f02124f9c181f339b048d216d9251fc64010982414a1260fa8bd693501bd21b3ea82f2a6183a3a50220adf2a4fc8fd31bae0ac08268", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/17904b4140952662464f3ac8e18e0542ef3666fced0229a93655880dd262f0b5a4dc9d0cedce2abdaa6bc5f85a9519ddf92ce8d17a850613fd222ced620a2a8d"} build_url:https://queue.taskcluster.net/v1/task/CsWQ6Z-zSr-ht0-CsmSleg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011309 build_url: 'https://queue.taskcluster.net/v1/task/CsWQ6Z-zSr-ht0-CsmSleg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/b4c2d67bd79158b99e8297c374e9d5fb0a28f02dc1f11549cad081cddd9270dd348d3e75354c8ed3018643faccc69d0d406ad2beb759a8d49be4aba0397cbca6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/44c41a387f4edfada15cda0fafcb9c2a0083c628d9ca67ba76ae63df6c83401bc2f32685521ba4e7972cf548823a494fb46ed5f2486b8968fab4dcb0b2e559bf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/87d1952ad831e187b8711f02124f9c181f339b048d216d9251fc64010982414a1260fa8bd693501bd21b3ea82f2a6183a3a50220adf2a4fc8fd31bae0ac08268", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/17904b4140952662464f3ac8e18e0542ef3666fced0229a93655880dd262f0b5a4dc9d0cedce2abdaa6bc5f85a9519ddf92ce8d17a850613fd222ced620a2a8d"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-29 15:35:40.009484) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 15:35:40.010149) ========= 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.vw93tOwmJ3/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.NnmPiRQHAD/Listeners TMPDIR=/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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.005219 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 15:35:40.060487) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-29 15:35:40.060843) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-29 15:35:40.061197) ========= ========= Total master_lag: 0.19 =========