Back to build
Raw log | Switch to full mode | Login

python -u /b/s/w/ir/kitchen-checkout/build/scripts/tools/runit.py --show-path --with-third-party-lib -- python /b/s/w/ir/kitchen-checkout/build/scripts/slave/recipe_modules/chromium_swarming/resources/collect_task.py --verbose -o /b/s/w/ir/tmp/t/tmp1RJ3cJ.json --task-output-dir /b/s/w/ir/k/recipe_cleanup/17XFa9L --merge-script /b/s/w/ir/kitchen-checkout/build/scripts/slave/recipe_modules/chromium_swarming/resources/noop_merge.py --merge-script-stdout-file /b/s/w/ir/tmp/t/tmpipDfrLmerge_script_log --merge-additional-args '[]' --summary-json-file /b/s/w/ir/tmp/t/tmpSE7rry.json -- swarming collect -server https://chromium-swarm.appspot.com -worker 50 -task-summary-python -task-output-stdout all -verbose -requests-json /b/s/w/ir/tmp/t/tmpKFQk3t.json in dir /b/s/w/ir/k: allow_subannotations: False cmd: ['python', '-u', '/b/s/w/ir/kitchen-checkout/build/scripts/tools/runit.py', '--show-path', '--with-third-party-lib', '--', 'python', '/b/s/w/ir/kitchen-checkout/build/scripts/slave/recipe_modules/chromium_swarming/resources/collect_task.py', '--verbose', '-o', '/b/s/w/ir/tmp/t/tmp1RJ3cJ.json', '--task-output-dir', '/b/s/w/ir/k/recipe_cleanup/17XFa9L', '--merge-script', '/b/s/w/ir/kitchen-checkout/build/scripts/slave/recipe_modules/chromium_swarming/resources/noop_merge.py', '--merge-script-stdout-file', '/b/s/w/ir/tmp/t/tmpipDfrLmerge_script_log', '--merge-additional-args', '[]', '--summary-json-file', '/b/s/w/ir/tmp/t/tmpSE7rry.json', '--', 'swarming', 'collect', '-server', 'https://chromium-swarm.appspot.com', '-worker', '50', '-task-summary-python', '-task-output-stdout', 'all', '-verbose', '-requests-json', '/b/s/w/ir/tmp/t/tmpKFQk3t.json'] env: {} env_prefixes: {'PATH': '/b/s/w/ir/cache/swarming_client'} env_suffixes: {} infra_step: False name_tokens: ('cronet_test (iPhone 5s iOS 9.3) on Mac-10.13.6',) ok_ret: frozenset([0]) step_test_data: <lambda>(...) trigger_specs: () full environment: Apple_PubSub_Socket_Render: /private/tmp/com.apple.launchd.6o2BplXA3j/Render BOTO_CONFIG: /b/s/w/ir/tmp/gsutil_task/.boto BUILDBUCKET_EXPERIMENTAL: FALSE CIPD_CACHE_DIR: /b/s/cipd_cache/cache CIPD_PROTOCOL: v2 DEVSHELL_CLIENT_PORT: 49811 DOCKER_CONFIG: /b/s/w/ir/tmp/docker_cfg_task DOCKER_TMPDIR: /b/s/w/ir/tmp/docker_tmp_task GIT_CONFIG_NOSYSTEM: 1 GIT_TERMINAL_PROMPT: 0 HOME: /Users/chrome-bot INFRA_GIT_WRAPPER_HOME: /b/s/w/ir/tmp/git_home_task LOGDOG_COORDINATOR_HOST: logs.chromium.org LOGDOG_STREAM_PREFIX: buildbucket/cr-buildbucket.appspot.com/8918492426809411824 LOGDOG_STREAM_PROJECT: chromium LOGDOG_STREAM_SERVER_PATH: unix:/b/s/w/ir/tmp/ld.sock LOGNAME: chrome-bot LUCI_CONTEXT: /b/s/w/iteH3Zev/luci_context.847933795 MAC_CHROMIUM_TMPDIR: /b/s/w/ir/tmp/t NO_GCE_CHECK: False PATH: /b/s/w/ir/cache/swarming_client:/b/s/w/ir/cipd_bin_packages:/b/s/w/ir/cipd_bin_packages/bin:/b/s/cipd_cache/bin:/opt/local/bin:/opt/local/sbin:/usr/local/sbin:/usr/local/git/bin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin PWD: /b/s/w/ir/k PYTHONIOENCODING: UTF-8 PYTHONUNBUFFERED: 1 SHELL: /bin/bash SHLVL: 1 SSH_AUTH_SOCK: /private/tmp/com.apple.launchd.2tgymD3K9v/Listeners SWARMING_BOT_ID: build212-m9 SWARMING_HEADLESS: 1 SWARMING_SERVER: https://chromium-swarm.appspot.com SWARMING_TASK_ID: 43b2663e743c7511 TEMP: /b/s/w/ir/tmp/t TEMPDIR: /b/s/w/ir/tmp/t TMP: /b/s/w/ir/tmp/t TMPDIR: /b/s/w/ir/tmp/t USER: chrome-bot VERSIONER_PYTHON_PREFER_32_BIT: no VERSIONER_PYTHON_VERSION: 2.7 VPYTHON_VIRTUALENV_ROOT: /b/s/w/ir/cache/vpython XPC_FLAGS: 0x0 XPC_SERVICE_NAME: 0 _: /b/s/w/ir/cipd_bin_packages/vpython __CF_USER_TEXT_ENCODING: 0x1F4:0x0:0x0 Set PYTHONPATH: /b/s/w/ir/kitchen-checkout/build/scripts:/b/s/w/ir/kitchen-checkout/build/site_config:/b/s/w/ir/kitchen-checkout/build/third_party:/b/s/w/ir/kitchen-checkout/build/third_party/buildbot_8_4p1:/b/s/w/ir/kitchen-checkout/build/third_party/buildbot_slave_8_4:/b/s/w/ir/kitchen-checkout/build/third_party/coverage-3.7.1:/b/s/w/ir/kitchen-checkout/build/third_party/decorator_3_3_1:/b/s/w/ir/kitchen-checkout/build/third_party/google_api_python_client:/b/s/w/ir/kitchen-checkout/build/third_party/httplib2/python2:/b/s/w/ir/kitchen-checkout/build/third_party/infra_libs:/b/s/w/ir/kitchen-checkout/build/third_party/jinja2:/b/s/w/ir/kitchen-checkout/build/third_party/markupsafe:/b/s/w/ir/kitchen-checkout/build/third_party/oauth2client:/b/s/w/ir/kitchen-checkout/build/third_party/pyasn1:/b/s/w/ir/kitchen-checkout/build/third_party/pyasn1-modules:/b/s/w/ir/kitchen-checkout/build/third_party/python-rsa:/b/s/w/ir/kitchen-checkout/build/third_party/requests_2_10_0:/b/s/w/ir/kitchen-checkout/build/third_party/setuptools-0.6c11:/b/s/w/ir/kitchen-checkout/build/third_party/sqlalchemy_0_7_1:/b/s/w/ir/kitchen-checkout/build/third_party/sqlalchemy_migrate_0_7_1:/b/s/w/ir/kitchen-checkout/build/third_party/tempita_0_5:/b/s/w/ir/kitchen-checkout/build/third_party/twisted_10_2:/b/s/w/ir/kitchen-checkout/build/third_party/uritemplate:/b/s/w/ir/kitchen-checkout/build/third_party/site-packages 2019-03-19 22:36:46,656 - root: [DEBUG] Using task_output_dir: '/b/s/w/ir/k/recipe_cleanup/17XFa9L' 2019-03-19 22:36:46,656 - root: [WARNING] task_output_dir '/b/s/w/ir/k/recipe_cleanup/17XFa9L' already exists! 2019-03-19 22:36:46,656 - root: [WARNING] task_output_dir existing content: [] 2019-03-19 22:36:46,656 - root: [INFO] collect_cmd: swarming collect -server https://chromium-swarm.appspot.com -worker 50 -task-summary-python -task-output-stdout all -verbose -requests-json /b/s/w/ir/tmp/t/tmpKFQk3t.json -output-dir /b/s/w/ir/k/recipe_cleanup/17XFa9L -task-summary-json /b/s/w/ir/tmp/t/tmpSE7rry.json [D2019-03-19T22:36:46.670634-07:00 36984 0 auth.go:1265] Minting a new token {"key":"luci_ctx/3994a082bca37d1e09db01707e342b7104950a92cd576ac71ae53387f58553a4", "scopes":"https://www.googleapis.com/auth/userinfo.email"} [D2019-03-19T22:36:46.670709-07:00 36984 0 luci_ctx.go:138] POST http://127.0.0.1:49800/rpc/LuciLocalAuthService.GetOAuthToken {"key":"luci_ctx/3994a082bca37d1e09db01707e342b7104950a92cd576ac71ae53387f58553a4", "scopes":"https://www.googleapis.com/auth/userinfo.email"} [D2019-03-19T22:36:46.672187-07:00 36984 0 auth.go:1222] Token expires in 15m35.32783s {"key":"luci_ctx/3994a082bca37d1e09db01707e342b7104950a92cd576ac71ae53387f58553a4", "scopes":"https://www.googleapis.com/auth/userinfo.email"} [D2019-03-19T22:36:48.262893-07:00 36984 0 collect.go:344] Waiting task_id: 43b269a053992310 [D2019-03-19T22:36:49.692052-07:00 36984 0 collect.go:344] Waiting task_id: 43b269a053992310 [D2019-03-19T22:36:51.149922-07:00 36984 0 collect.go:344] Waiting task_id: 43b269a053992310 [D2019-03-19T22:36:52.684192-07:00 36984 0 collect.go:344] Waiting task_id: 43b269a053992310 [D2019-03-19T22:36:54.267144-07:00 36984 0 collect.go:344] Waiting task_id: 43b269a053992310 [D2019-03-19T22:36:55.870184-07:00 36984 0 collect.go:344] Waiting task_id: 43b269a053992310 [D2019-03-19T22:36:57.393012-07:00 36984 0 collect.go:344] Waiting task_id: 43b269a053992310 [D2019-03-19T22:36:59.099858-07:00 36984 0 collect.go:344] Waiting task_id: 43b269a053992310 [D2019-03-19T22:37:00.809268-07:00 36984 0 collect.go:344] Waiting task_id: 43b269a053992310 [D2019-03-19T22:37:02.630693-07:00 36984 0 collect.go:344] Waiting task_id: 43b269a053992310 [D2019-03-19T22:37:04.574674-07:00 36984 0 collect.go:344] Waiting task_id: 43b269a053992310 [D2019-03-19T22:37:06.524396-07:00 36984 0 collect.go:344] Waiting task_id: 43b269a053992310 [D2019-03-19T22:37:08.512627-07:00 36984 0 collect.go:344] Waiting task_id: 43b269a053992310 [D2019-03-19T22:37:10.586146-07:00 36984 0 collect.go:344] Waiting task_id: 43b269a053992310 [D2019-03-19T22:37:12.747138-07:00 36984 0 collect.go:344] Waiting task_id: 43b269a053992310 [D2019-03-19T22:37:14.955737-07:00 36984 0 collect.go:344] Waiting task_id: 43b269a053992310 [D2019-03-19T22:37:17.101877-07:00 36984 0 collect.go:344] Waiting task_id: 43b269a053992310 [D2019-03-19T22:37:19.545494-07:00 36984 0 collect.go:344] Waiting task_id: 43b269a053992310 [D2019-03-19T22:37:21.994501-07:00 36984 0 collect.go:344] Waiting task_id: 43b269a053992310 43b269a053992310: exit 0 [I 2019-03-19 22:34:45] About to install Xcode 10b61 in Xcode.app for ios [10:34:46:INFO] Using Xcode version 10.1 build 10B61 at /b/s/w/ir/Xcode.app/Contents/Developer Device wiped. [10:34:54:DEBUG] Setting sigterm handler. [10:34:55:INFO] User defaults from command line: [10:34:55:INFO] IDETestRunSpecificationPath = /var/folders/2j/22s2gz0s7hn48k32d47clxf80000gm/T/49E0D51B-5503-4416-BE5E-80FBC46FB050 [10:34:55:INFO] [10:35:15:INFO] objc[19368]: Class CRBProtocolObservers is implemented in both /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/90615D4D-AC23-4D78-A523-690BF0801639/data/Containers/Bundle/Application/DB6340C3-E674-4833-9079-82BDC8F4D6D2/cronet_test.app/Frameworks/Cronet.framework/Cronet and /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/90615D4D-AC23-4D78-A523-690BF0801639/data/Containers/Bundle/Application/DB6340C3-E674-4833-9079-82BDC8F4D6D2/cronet_test.app/cronet_test. One of the two will be used. Which one is undefined. [10:35:15:INFO] objc[19368]: Class CRBWeakNSProtocolSentinel is implemented in both /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/90615D4D-AC23-4D78-A523-690BF0801639/data/Containers/Bundle/Application/DB6340C3-E674-4833-9079-82BDC8F4D6D2/cronet_test.app/Frameworks/Cronet.framework/Cronet and /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/90615D4D-AC23-4D78-A523-690BF0801639/data/Containers/Bundle/Application/DB6340C3-E674-4833-9079-82BDC8F4D6D2/cronet_test.app/cronet_test. One of the two will be used. Which one is undefined. [10:35:16:INFO] [==========] Running 166 tests from 14 test suites. [10:35:16:INFO] [----------] Global test environment set-up. [10:35:16:INFO] [----------] 4 tests from BufferTest [10:35:16:INFO] [ RUN ] BufferTest.TestInitWithAlloc [10:35:16:INFO] [ OK ] BufferTest.TestInitWithAlloc (11 ms) [10:35:16:INFO] [ RUN ] BufferTest.TestInitWithHugeAllocFails [10:35:16:INFO] [ OK ] BufferTest.TestInitWithHugeAllocFails (1 ms) [10:35:16:INFO] [ RUN ] BufferTest.TestInitWithDataAndCallback [10:35:16:INFO] [ OK ] BufferTest.TestInitWithDataAndCallback (1 ms) [10:35:16:INFO] [ RUN ] BufferTest.TestCronetBufferAsync [10:35:16:INFO] [ OK ] BufferTest.TestCronetBufferAsync (2 ms) [10:35:16:INFO] [----------] 4 tests from BufferTest (21 ms total) [10:35:16:INFO] [10:35:16:INFO] [----------] 8 tests from EngineTest [10:35:16:INFO] [ RUN ] EngineTest.StartCronetEngine [10:35:16:INFO] [ OK ] EngineTest.StartCronetEngine (76 ms) [10:35:16:INFO] [ RUN ] EngineTest.CronetEngineDefaultUserAgent [10:35:16:INFO] [ OK ] EngineTest.CronetEngineDefaultUserAgent (0 ms) [10:35:16:INFO] [ RUN ] EngineTest.InitDifferentEngines [10:35:16:INFO] [ OK ] EngineTest.InitDifferentEngines (6 ms) [10:35:16:INFO] [ RUN ] EngineTest.StartResults [10:35:16:INFO] [0319/223516.306197:ERROR:engine.cc(135)] Disk cache path /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/90615D4D-AC23-4D78-A523-690BF0801639/data/Containers/Data/Application/A8B0A8E3-80AB-4183-A2A3-9592AE2FFAB4/tmp/.org.chromium.Chromium.f8x6do is already used, cache disabled. [10:35:16:INFO] [ OK ] EngineTest.StartResults (114 ms) [10:35:16:INFO] [ RUN ] EngineTest.InvalidPkpParams [10:35:16:INFO] [ OK ] EngineTest.InvalidPkpParams (0 ms) [10:35:16:INFO] [ RUN ] EngineTest.ValidPkpParams [10:35:16:INFO] [ OK ] EngineTest.ValidPkpParams (3 ms) [10:35:16:INFO] [ RUN ] EngineTest.SetMockCertVerifierForTesting [10:35:16:INFO] [ OK ] EngineTest.SetMockCertVerifierForTesting (1 ms) [10:35:16:INFO] [ RUN ] EngineTest.StartNetLogToFile [10:35:16:INFO] [0319/223516.468908:ERROR:cronet_url_request_context.cc(536)] Failed to open NetLog file for writing. [10:35:16:INFO] [ OK ] EngineTest.StartNetLogToFile (40 ms) [10:35:16:INFO] [----------] 8 tests from EngineTest (254 ms total) [10:35:16:INFO] [10:35:16:INFO] [----------] 2 tests from ExecutorsTest [10:35:16:INFO] [ RUN ] ExecutorsTest.TestCustom [10:35:16:INFO] [ OK ] ExecutorsTest.TestCustom (1 ms) [10:35:16:INFO] [ RUN ] ExecutorsTest.TestTestExecutor [10:35:16:INFO] [ OK ] ExecutorsTest.TestTestExecutor (0 ms) [10:35:16:INFO] [----------] 2 tests from ExecutorsTest (4 ms total) [10:35:16:INFO] [10:35:16:INFO] [----------] 57 tests from UrlRequestTest [10:35:16:INFO] [ RUN ] UrlRequestTest.UploadWithDirectExecutor [10:35:16:INFO] [ OK ] UrlRequestTest.UploadWithDirectExecutor (37 ms) [10:35:16:INFO] [ RUN ] UrlRequestTest.InitChecks/0 [10:35:16:INFO] [ OK ] UrlRequestTest.InitChecks/0 (4 ms) [10:35:16:INFO] [ RUN ] UrlRequestTest.InitChecks/1 [10:35:16:INFO] [ OK ] UrlRequestTest.InitChecks/1 (4 ms) [10:35:16:INFO] [ RUN ] UrlRequestTest.SimpleGet/0 [10:35:16:INFO] [ OK ] UrlRequestTest.SimpleGet/0 (6 ms) [10:35:16:INFO] [ RUN ] UrlRequestTest.SimpleGet/1 [10:35:16:INFO] [ OK ] UrlRequestTest.SimpleGet/1 (6 ms) [10:35:16:INFO] [ RUN ] UrlRequestTest.UploadEmptyBodySync/0 [10:35:16:INFO] [ OK ] UrlRequestTest.UploadEmptyBodySync/0 (6 ms) [10:35:16:INFO] [ RUN ] UrlRequestTest.UploadEmptyBodySync/1 [10:35:16:INFO] [ OK ] UrlRequestTest.UploadEmptyBodySync/1 (7 ms) [10:35:16:INFO] [ RUN ] UrlRequestTest.UploadSync/0 [10:35:16:INFO] [ OK ] UrlRequestTest.UploadSync/0 (6 ms) [10:35:16:INFO] [ RUN ] UrlRequestTest.UploadSync/1 [10:35:16:INFO] [ OK ] UrlRequestTest.UploadSync/1 (7 ms) [10:35:16:INFO] [ RUN ] UrlRequestTest.SSLCertificateError/0 [10:35:16:INFO] [0319/223516.620586:ERROR:ssl_client_socket_impl.cc(947)] handshake failed; returned -1, SSL error code 1, net_error -207 [10:35:16:INFO] [19368:24367:0319/223516.620980:8141451059560:ERROR:ssl_server_socket_impl.cc(686)] handshake failed; returned -1, SSL error code 1, net_error -117 [10:35:16:INFO] [ OK ] UrlRequestTest.SSLCertificateError/0 (53 ms) [10:35:16:INFO] [ RUN ] UrlRequestTest.SSLCertificateError/1 [10:35:16:INFO] [0319/223516.647977:ERROR:ssl_client_socket_impl.cc(947)] handshake failed; returned -1, SSL error code 1, net_error -207 [10:35:16:INFO] [19368:25611:0319/223516.648109:8141478151981:ERROR:ssl_server_socket_impl.cc(686)] handshake failed; returned -1, SSL error code 1, net_error -117 [10:35:16:INFO] [ OK ] UrlRequestTest.SSLCertificateError/1 (26 ms) [10:35:16:INFO] [ RUN ] UrlRequestTest.UploadMultiplePiecesSync/0 [10:35:16:INFO] [ OK ] UrlRequestTest.UploadMultiplePiecesSync/0 (7 ms) [10:35:16:INFO] [ RUN ] UrlRequestTest.UploadMultiplePiecesSync/1 [10:35:16:INFO] [ OK ] UrlRequestTest.UploadMultiplePiecesSync/1 (8 ms) [10:35:16:INFO] [ RUN ] UrlRequestTest.UploadMultiplePiecesAsync/0 [10:35:16:INFO] [ OK ] UrlRequestTest.UploadMultiplePiecesAsync/0 (6 ms) [10:35:16:INFO] [ RUN ] UrlRequestTest.UploadMultiplePiecesAsync/1 [10:35:16:INFO] [ OK ] UrlRequestTest.UploadMultiplePiecesAsync/1 (8 ms) [10:35:16:INFO] [ RUN ] UrlRequestTest.UploadChangesDefaultMethod/0 [10:35:16:INFO] [ OK ] UrlRequestTest.UploadChangesDefaultMethod/0 (6 ms) [10:35:16:INFO] [ RUN ] UrlRequestTest.UploadChangesDefaultMethod/1 [10:35:16:INFO] [ OK ] UrlRequestTest.UploadChangesDefaultMethod/1 (7 ms) [10:35:16:INFO] [ RUN ] UrlRequestTest.UploadWithSetMethod/0 [10:35:16:INFO] [ OK ] UrlRequestTest.UploadWithSetMethod/0 (6 ms) [10:35:16:INFO] [ RUN ] UrlRequestTest.UploadWithSetMethod/1 [10:35:16:INFO] [ OK ] UrlRequestTest.UploadWithSetMethod/1 (7 ms) [10:35:16:INFO] [ RUN ] UrlRequestTest.UploadWithBigRead/0 [10:35:16:INFO] [ OK ] UrlRequestTest.UploadWithBigRead/0 (7 ms) [10:35:16:INFO] [ RUN ] UrlRequestTest.UploadWithBigRead/1 [10:35:16:INFO] [ OK ] UrlRequestTest.UploadWithBigRead/1 (8 ms) [10:35:16:INFO] [ RUN ] UrlRequestTest.UploadRedirectSync/0 [10:35:16:INFO] [ OK ] UrlRequestTest.UploadRedirectSync/0 (9 ms) [10:35:16:INFO] [ RUN ] UrlRequestTest.UploadRedirectSync/1 [10:35:16:INFO] [ OK ] UrlRequestTest.UploadRedirectSync/1 (9 ms) [10:35:16:INFO] [ RUN ] UrlRequestTest.UploadRedirectAsync/0 [10:35:16:INFO] [ OK ] UrlRequestTest.UploadRedirectAsync/0 (8 ms) [10:35:16:INFO] [ RUN ] UrlRequestTest.UploadRedirectAsync/1 [10:35:16:INFO] [ OK ] UrlRequestTest.UploadRedirectAsync/1 (9 ms) [10:35:16:INFO] [ RUN ] UrlRequestTest.UploadWithBadLength/0 [10:35:16:INFO] [ OK ] UrlRequestTest.UploadWithBadLength/0 (5 ms) [10:35:16:INFO] [ RUN ] UrlRequestTest.UploadWithBadLength/1 [10:35:16:INFO] [ OK ] UrlRequestTest.UploadWithBadLength/1 (6 ms) [10:35:16:INFO] [ RUN ] UrlRequestTest.UploadWithBadLengthBufferAligned/0 [10:35:16:INFO] [ OK ] UrlRequestTest.UploadWithBadLengthBufferAligned/0 (79 ms) [10:35:16:INFO] [ RUN ] UrlRequestTest.UploadWithBadLengthBufferAligned/1 [10:35:16:INFO] [ OK ] UrlRequestTest.UploadWithBadLengthBufferAligned/1 (119 ms) [10:35:16:INFO] [ RUN ] UrlRequestTest.UploadReadFailSync/0 [10:35:17:INFO] [ OK ] UrlRequestTest.UploadReadFailSync/0 (7 ms) [10:35:17:INFO] [ RUN ] UrlRequestTest.UploadReadFailSync/1 [10:35:17:INFO] [ OK ] UrlRequestTest.UploadReadFailSync/1 (7 ms) [10:35:17:INFO] [ RUN ] UrlRequestTest.UploadReadFailAsync/0 [10:35:17:INFO] [ OK ] UrlRequestTest.UploadReadFailAsync/0 (6 ms) [10:35:17:INFO] [ RUN ] UrlRequestTest.UploadReadFailAsync/1 [10:35:17:INFO] [ OK ] UrlRequestTest.UploadReadFailAsync/1 (8 ms) [10:35:17:INFO] [ RUN ] UrlRequestTest.UploadRewindFailSync/0 [10:35:17:INFO] [ OK ] UrlRequestTest.UploadRewindFailSync/0 (9 ms) [10:35:17:INFO] [ RUN ] UrlRequestTest.UploadRewindFailSync/1 [10:35:17:INFO] [ OK ] UrlRequestTest.UploadRewindFailSync/1 (10 ms) [10:35:17:INFO] [ RUN ] UrlRequestTest.UploadRewindFailAsync/0 [10:35:17:INFO] [ OK ] UrlRequestTest.UploadRewindFailAsync/0 (10 ms) [10:35:17:INFO] [ RUN ] UrlRequestTest.UploadRewindFailAsync/1 [10:35:17:INFO] [ OK ] UrlRequestTest.UploadRewindFailAsync/1 (10 ms) [10:35:17:INFO] [ RUN ] UrlRequestTest.UploadChunked/0 [10:35:17:INFO] [ OK ] UrlRequestTest.UploadChunked/0 (9 ms) [10:35:17:INFO] [ RUN ] UrlRequestTest.UploadChunked/1 [10:35:17:INFO] [ OK ] UrlRequestTest.UploadChunked/1 (7 ms) [10:35:17:INFO] [ RUN ] UrlRequestTest.UploadChunkedLastReadZeroLengthBody/0 [10:35:17:INFO] [ OK ] UrlRequestTest.UploadChunkedLastReadZeroLengthBody/0 (6 ms) [10:35:17:INFO] [ RUN ] UrlRequestTest.UploadChunkedLastReadZeroLengthBody/1 [10:35:17:INFO] [ OK ] UrlRequestTest.UploadChunkedLastReadZeroLengthBody/1 (8 ms) [10:35:17:INFO] [ RUN ] UrlRequestTest.UploadFailsWithoutInitializingStream/0 [10:35:17:INFO] [ OK ] UrlRequestTest.UploadFailsWithoutInitializingStream/0 (5 ms) [10:35:17:INFO] [ RUN ] UrlRequestTest.UploadFailsWithoutInitializingStream/1 [10:35:17:INFO] [ OK ] UrlRequestTest.UploadFailsWithoutInitializingStream/1 (5 ms) [10:35:17:INFO] [ RUN ] UrlRequestTest.SimpleRequest/0 [10:35:17:INFO] [ OK ] UrlRequestTest.SimpleRequest/0 (6 ms) [10:35:17:INFO] [ RUN ] UrlRequestTest.SimpleRequest/1 [10:35:17:INFO] [ OK ] UrlRequestTest.SimpleRequest/1 (7 ms) [10:35:17:INFO] [ RUN ] UrlRequestTest.MultiRedirect/0 [10:35:17:INFO] [ OK ] UrlRequestTest.MultiRedirect/0 (12 ms) [10:35:17:INFO] [ RUN ] UrlRequestTest.MultiRedirect/1 [10:35:17:INFO] [ OK ] UrlRequestTest.MultiRedirect/1 (10 ms) [10:35:17:INFO] [ RUN ] UrlRequestTest.CancelRequest/0 [10:35:17:INFO] [ OK ] UrlRequestTest.CancelRequest/0 (6 ms) [10:35:17:INFO] [ RUN ] UrlRequestTest.CancelRequest/1 [10:35:17:INFO] [ OK ] UrlRequestTest.CancelRequest/1 (6 ms) [10:35:17:INFO] [ RUN ] UrlRequestTest.FailedRequestHostNotFound/0 [10:35:17:INFO] [ OK ] UrlRequestTest.FailedRequestHostNotFound/0 (5 ms) [10:35:17:INFO] [ RUN ] UrlRequestTest.FailedRequestHostNotFound/1 [10:35:17:INFO] [ OK ] UrlRequestTest.FailedRequestHostNotFound/1 (6 ms) [10:35:17:INFO] [ RUN ] UrlRequestTest.TestCancel/0 [10:35:17:INFO] [ OK ] UrlRequestTest.TestCancel/0 (50 ms) [10:35:17:INFO] [ RUN ] UrlRequestTest.TestCancel/1 [10:35:17:INFO] [ OK ] UrlRequestTest.TestCancel/1 (70 ms) [10:35:17:INFO] [ RUN ] UrlRequestTest.PerfTest/0 [10:35:18:INFO] [19368:771:0319/223518.114485:8142944490768:INFO:url_request_test.cc(993)] Total time 770.048 ms [10:35:18:INFO] [19368:771:0319/223518.114619:8142944604950:INFO:url_request_test.cc(994)] Single Iteration time 77.0048 ms [10:35:18:INFO] [19368:771:0319/223518.114690:8142944679741:INFO:url_request_test.cc(1001)] Average Throughput: 2005.84 mbps [10:35:18:INFO] [ OK ] UrlRequestTest.PerfTest/0 (800 ms) [10:35:18:INFO] [ RUN ] UrlRequestTest.PerfTest/1 [10:35:19:INFO] [19368:771:0319/223519.464490:8144294468891:INFO:url_request_test.cc(993)] Total time 1329.93 ms [10:35:19:INFO] [19368:771:0319/223519.464637:8144294579557:INFO:url_request_test.cc(994)] Single Iteration time 132.993 ms [10:35:19:INFO] [19368:771:0319/223519.464701:8144294644820:INFO:url_request_test.cc(1001)] Average Throughput: 1161.41 mbps [10:35:19:INFO] [ OK ] UrlRequestTest.PerfTest/1 (1349 ms) [10:35:19:INFO] [ RUN ] UrlRequestTest.GetStatus/0 [10:35:19:INFO] [ OK ] UrlRequestTest.GetStatus/0 (7 ms) [10:35:19:INFO] [ RUN ] UrlRequestTest.GetStatus/1 [10:35:19:INFO] [ OK ] UrlRequestTest.GetStatus/1 (7 ms) [10:35:19:INFO] [----------] 57 tests from UrlRequestTest (3012 ms total) [10:35:19:INFO] [10:35:19:INFO] [----------] 5 tests from AcceptLangTest [10:35:19:INFO] [ RUN ] AcceptLangTest.Region [10:35:19:INFO] [ OK ] AcceptLangTest.Region (0 ms) [10:35:19:INFO] [ RUN ] AcceptLangTest.Lang [10:35:19:INFO] [ OK ] AcceptLangTest.Lang (0 ms) [10:35:19:INFO] [ RUN ] AcceptLangTest.Default [10:35:19:INFO] [ OK ] AcceptLangTest.Default (0 ms) [10:35:19:INFO] [ RUN ] AcceptLangTest.Append [10:35:19:INFO] [ OK ] AcceptLangTest.Append (1 ms) [10:35:19:INFO] [ RUN ] AcceptLangTest.NoDefaultAppend [10:35:19:INFO] 2019-03-19 22:35:19.493 cronet_test[19368:335690] en-GB,en-US,en [10:35:19:INFO] [ OK ] AcceptLangTest.NoDefaultAppend (1 ms) [10:35:19:INFO] [----------] 5 tests from AcceptLangTest (8 ms total) [10:35:19:INFO] [10:35:19:INFO] [----------] 21 tests from HttpTest [10:35:19:INFO] [ RUN ] HttpTest.CreateSslKeyLogFile [10:35:19:INFO] 2019-03-19 22:35:19.515 cronet_test[19368:336184] DiskCookieStorage changing policy from 2 to 0, cookie file: file:///Users/chrome-bot/Library/Developer/CoreSimulator/Devices/90615D4D-AC23-4D78-A523-690BF0801639/data/Containers/Data/Application/A8B0A8E3-80AB-4183-A2A3-9592AE2FFAB4/Library/Cookies/org.chromium.gtest.cronet-test.binarycookies [10:35:19:INFO] [0319/223519.573332:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [10:35:19:INFO] [0319/223519.577460:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [10:35:19:INFO] [ OK ] HttpTest.CreateSslKeyLogFile (84 ms) [10:35:19:INFO] [ RUN ] HttpTest.NSURLSessionReceivesData [10:35:19:INFO] [0319/223519.675485:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [10:35:19:INFO] [ OK ] HttpTest.NSURLSessionReceivesData (95 ms) [10:35:19:INFO] [ RUN ] HttpTest.GetGlobalMetricsDeltas [10:35:19:INFO] [0319/223519.740608:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [10:35:19:INFO] [ OK ] HttpTest.GetGlobalMetricsDeltas (63 ms) [10:35:19:INFO] [ RUN ] HttpTest.SdchDisabledByDefault [10:35:19:INFO] [0319/223519.758093:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [10:35:19:INFO] [ OK ] HttpTest.SdchDisabledByDefault (15 ms) [10:35:19:INFO] [ RUN ] HttpTest.AcceptEncodingSdchIsAllowed [10:35:19:INFO] [0319/223519.774656:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [10:35:19:INFO] [ OK ] HttpTest.AcceptEncodingSdchIsAllowed (15 ms) [10:35:19:INFO] [ RUN ] HttpTest.AcceptEncodingFooBarIsAllowed [10:35:19:INFO] [0319/223519.791178:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [10:35:19:INFO] [ OK ] HttpTest.AcceptEncodingFooBarIsAllowed (15 ms) [10:35:19:INFO] [ RUN ] HttpTest.NSURLSessionAcceptLanguage [10:35:19:INFO] [0319/223519.807603:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [10:35:19:INFO] [ OK ] HttpTest.NSURLSessionAcceptLanguage (15 ms) [10:35:19:INFO] [ RUN ] HttpTest.SetUserAgentIsExact [10:35:19:INFO] [0319/223519.824097:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [10:35:19:INFO] [ OK ] HttpTest.SetUserAgentIsExact (14 ms) [10:35:19:INFO] [ RUN ] HttpTest.SetCookie [10:35:19:INFO] [0319/223519.846308:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [10:35:19:INFO] [ OK ] HttpTest.SetCookie (21 ms) [10:35:19:INFO] [ RUN ] HttpTest.SetSystemCookie [10:35:19:INFO] [0319/223519.863054:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [10:35:19:INFO] [ OK ] HttpTest.SetSystemCookie (16 ms) [10:35:19:INFO] [ RUN ] HttpTest.SystemCookieWithNullCreationTime [10:35:19:INFO] [0319/223519.880258:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [10:35:19:INFO] [ OK ] HttpTest.SystemCookieWithNullCreationTime (16 ms) [10:35:19:INFO] [ RUN ] HttpTest.FilterOutRequest [10:35:19:INFO] [0319/223519.919624:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [10:35:19:INFO] [ OK ] HttpTest.FilterOutRequest (38 ms) [10:35:19:INFO] [ RUN ] HttpTest.FileSchemeNotSupported [10:35:19:INFO] [0319/223519.939139:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [10:35:19:INFO] [ OK ] HttpTest.FileSchemeNotSupported (18 ms) [10:35:19:INFO] [ RUN ] HttpTest.DataSchemeNotSupported [10:35:19:INFO] [0319/223519.955713:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [10:35:19:INFO] [ OK ] HttpTest.DataSchemeNotSupported (15 ms) [10:35:19:INFO] [ RUN ] HttpTest.BrotliAdvertisedTest [10:35:19:INFO] [0319/223519.968549:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [10:35:19:INFO] [0319/223519.976935:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [10:35:19:INFO] [ OK ] HttpTest.BrotliAdvertisedTest (19 ms) [10:35:19:INFO] [ RUN ] HttpTest.BrotliNotAdvertisedTest [10:35:19:INFO] [0319/223519.989923:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [10:35:19:INFO] [0319/223519.996870:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [10:35:19:INFO] [ OK ] HttpTest.BrotliNotAdvertisedTest (18 ms) [10:35:20:INFO] [ RUN ] HttpTest.BrotliHandleDecoding [10:35:20:INFO] [0319/223520.009073:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [10:35:20:INFO] [0319/223520.017278:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [10:35:20:INFO] [ OK ] HttpTest.BrotliHandleDecoding (19 ms) [10:35:20:INFO] [ RUN ] HttpTest.PostRequest [10:35:20:INFO] [0319/223520.034462:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [10:35:20:INFO] [ OK ] HttpTest.PostRequest (16 ms) [10:35:20:INFO] [ RUN ] HttpTest.PostRequestWithBodyStream [10:35:20:INFO] [0319/223520.052993:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [10:35:20:INFO] [ OK ] HttpTest.PostRequestWithBodyStream (16 ms) [10:35:20:INFO] [ RUN ] HttpTest.PostRequestWithLargeBodyStream [10:35:20:INFO] [0319/223520.070666:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [10:35:20:INFO] [ OK ] HttpTest.PostRequestWithLargeBodyStream (16 ms) [10:35:20:INFO] [ RUN ] HttpTest.LegacyApi [10:35:20:INFO] [0319/223520.126766:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [10:35:20:INFO] [ OK ] HttpTest.LegacyApi (54 ms) [10:35:20:INFO] [----------] 21 tests from HttpTest (634 ms total) [10:35:20:INFO] [10:35:20:INFO] [----------] 8 tests from CronetEnabledMetricsTest [10:35:20:INFO] [ RUN ] CronetEnabledMetricsTest.ProtocolIsQuic [10:35:20:INFO] [0319/223520.139618:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [10:35:20:INFO] [ OK ] CronetEnabledMetricsTest.ProtocolIsQuic (12 ms) [10:35:20:INFO] [ RUN ] CronetEnabledMetricsTest.ProtocolIsNotQuic [10:35:20:INFO] [0319/223520.152768:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [10:35:20:INFO] [ OK ] CronetEnabledMetricsTest.ProtocolIsNotQuic (11 ms) [10:35:20:INFO] [ RUN ] CronetEnabledMetricsTest.PlatformComparison [10:35:20:INFO] [0319/223520.165941:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [10:35:20:INFO] [ OK ] CronetEnabledMetricsTest.PlatformComparison (11 ms) [10:35:20:INFO] [ RUN ] CronetEnabledMetricsTest.InvalidURL [10:35:20:INFO] [0319/223520.179073:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [10:35:20:INFO] [ OK ] CronetEnabledMetricsTest.InvalidURL (11 ms) [10:35:20:INFO] [ RUN ] CronetEnabledMetricsTest.CanceledRequest [10:35:20:INFO] [0319/223520.192356:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [10:35:20:INFO] [ OK ] CronetEnabledMetricsTest.CanceledRequest (12 ms) [10:35:20:INFO] [ RUN ] CronetEnabledMetricsTest.ReusedConnection [10:35:20:INFO] [0319/223520.205332:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [10:35:20:INFO] [ OK ] CronetEnabledMetricsTest.ReusedConnection (12 ms) [10:35:20:INFO] [ RUN ] CronetEnabledMetricsTest.SessionWithoutDelegate [10:35:20:INFO] [0319/223520.218461:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [10:35:20:INFO] [ OK ] CronetEnabledMetricsTest.SessionWithoutDelegate (12 ms) [10:35:20:INFO] [ RUN ] CronetEnabledMetricsTest.LegacyApi [10:35:20:INFO] [0319/223520.274177:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [10:35:20:INFO] [ OK ] CronetEnabledMetricsTest.LegacyApi (54 ms) [10:35:20:INFO] [----------] 8 tests from CronetEnabledMetricsTest (148 ms total) [10:35:20:INFO] [10:35:20:INFO] [----------] 1 test from CronetDisabledMetricsTest [10:35:20:INFO] [ RUN ] CronetDisabledMetricsTest.MetricsDisabled [10:35:20:INFO] [0319/223520.287648:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [10:35:20:INFO] [ OK ] CronetDisabledMetricsTest.MetricsDisabled (12 ms) [10:35:20:INFO] [----------] 1 test from CronetDisabledMetricsTest (14 ms total) [10:35:20:INFO] [10:35:20:INFO] [----------] 7 tests from NetLogTest [10:35:20:INFO] [ RUN ] NetLogTest.OpenFile [10:35:20:INFO] [0319/223520.293253:WARNING:cronet_environment.mm(163)] Starting NetLog to /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/90615D4D-AC23-4D78-A523-690BF0801639/data/Containers/Data/Application/A8B0A8E3-80AB-4183-A2A3-9592AE2FFAB4/Documents/cronet_netlog.json [10:35:20:INFO] [0319/223520.302278:WARNING:cronet_environment.mm(186)] Started NetLog [10:35:20:INFO] [0319/223520.302495:WARNING:cronet_environment.mm(202)] Stopped NetLog. [10:35:20:INFO] [0319/223520.308409:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [10:35:20:INFO] [ OK ] NetLogTest.OpenFile (17 ms) [10:35:20:INFO] [ RUN ] NetLogTest.CreateFile [10:35:20:INFO] [0319/223520.312231:WARNING:cronet_environment.mm(163)] Starting NetLog to /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/90615D4D-AC23-4D78-A523-690BF0801639/data/Containers/Data/Application/A8B0A8E3-80AB-4183-A2A3-9592AE2FFAB4/Documents/1E1E5F9A-0BAE-4D32-BFCD-391002A194F4-19368-00000768704A4C47_netlog.json [10:35:20:INFO] [0319/223520.321262:WARNING:cronet_environment.mm(186)] Started NetLog [10:35:20:INFO] [0319/223520.321480:WARNING:cronet_environment.mm(202)] Stopped NetLog. [10:35:20:INFO] [0319/223520.327523:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [10:35:20:INFO] [ OK ] NetLogTest.CreateFile (17 ms) [10:35:20:INFO] [ RUN ] NetLogTest.NonExistantDir [10:35:20:INFO] [0319/223520.331193:ERROR:cronet_environment.mm(158)] Can not start NetLog to /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/90615D4D-AC23-4D78-A523-690BF0801639/data/Containers/Data/Application/A8B0A8E3-80AB-4183-A2A3-9592AE2FFAB4/Documents/6371501F-2E79-42D2-BDDF-3624B4B1805B-19368-00000768716CD4A0/netlog.json: Undefined error: 0 [10:35:20:INFO] [0319/223520.332688:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [10:35:20:INFO] [ OK ] NetLogTest.NonExistantDir (3 ms) [10:35:20:INFO] [ RUN ] NetLogTest.ExistantDir [10:35:20:INFO] [0319/223520.336604:WARNING:cronet_environment.mm(163)] Starting NetLog to /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/90615D4D-AC23-4D78-A523-690BF0801639/data/Containers/Data/Application/A8B0A8E3-80AB-4183-A2A3-9592AE2FFAB4/Documents/943E6EB0-111C-41DA-A496-36E94B37AA79-19368-0000076871BC8319/netlog.json [10:35:20:INFO] [0319/223520.345645:WARNING:cronet_environment.mm(186)] Started NetLog [10:35:20:INFO] [0319/223520.345894:WARNING:cronet_environment.mm(202)] Stopped NetLog. [10:35:20:INFO] [0319/223520.352262:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [10:35:20:INFO] [ OK ] NetLogTest.ExistantDir (18 ms) [10:35:20:INFO] [ RUN ] NetLogTest.EmptyFilename [10:35:20:INFO] [0319/223520.357122:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [10:35:20:INFO] [ OK ] NetLogTest.EmptyFilename (3 ms) [10:35:20:INFO] [ RUN ] NetLogTest.AbsoluteFilename [10:35:20:INFO] [0319/223520.362110:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [10:35:20:INFO] [ OK ] NetLogTest.AbsoluteFilename (3 ms) [10:35:20:INFO] [ RUN ] NetLogTest.ExperimentalOptions [10:35:20:INFO] [0319/223520.366855:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [10:35:20:INFO] [0319/223520.368807:WARNING:cronet_environment.mm(163)] Starting NetLog to /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/90615D4D-AC23-4D78-A523-690BF0801639/data/Containers/Data/Application/A8B0A8E3-80AB-4183-A2A3-9592AE2FFAB4/Documents/cronet_netlog.json [10:35:20:INFO] [0319/223520.377796:WARNING:cronet_environment.mm(186)] Started NetLog [10:35:20:INFO] [0319/223520.378167:WARNING:cronet_environment.mm(202)] Stopped NetLog. [10:35:20:INFO] [0319/223520.384471:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [10:35:20:INFO] [ OK ] NetLogTest.ExperimentalOptions (20 ms) [10:35:20:INFO] [----------] 7 tests from NetLogTest (95 ms total) [10:35:20:INFO] [10:35:20:INFO] [----------] 12 tests from PkpTest [10:35:20:INFO] [ RUN ] PkpTest.TestSuccessIfPinSetForDifferentHost [10:35:20:INFO] [0319/223520.442652:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [10:35:20:INFO] [ OK ] PkpTest.TestSuccessIfPinSetForDifferentHost (57 ms) [10:35:20:INFO] [ RUN ] PkpTest.TestErrorIfPinDoesNotMatch [10:35:20:INFO] [0319/223520.468612:WARNING:proof_verifier_chromium.cc(501)] Failed to verify certificate chain: net::ERR_SSL_PINNED_KEY_NOT_IN_CERT_CHAIN [10:35:20:INFO] [0319/223520.470681:ERROR:crn_http_protocol_handler.mm(845)] HttpProtocolHandlerCore - Network error: net::ERR_CONNECTION_REFUSED (-102) [10:35:20:INFO] [0319/223520.485037:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [10:35:20:INFO] [ OK ] PkpTest.TestErrorIfPinDoesNotMatch (41 ms) [10:35:20:INFO] [ RUN ] PkpTest.TestSuccessIfPinMatches [10:35:20:INFO] [0319/223520.540983:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [10:35:20:INFO] [ OK ] PkpTest.TestSuccessIfPinMatches (54 ms) [10:35:20:INFO] [ RUN ] PkpTest.TestBypass [10:35:20:INFO] [ OK ] PkpTest.TestBypass (7 ms) [10:35:20:INFO] [ RUN ] PkpTest.TestIncludeSubdomainsFlagEqualTrue [10:35:20:INFO] [0319/223520.575585:WARNING:proof_verifier_chromium.cc(501)] Failed to verify certificate chain: net::ERR_SSL_PINNED_KEY_NOT_IN_CERT_CHAIN [10:35:20:INFO] [0319/223520.577517:ERROR:crn_http_protocol_handler.mm(845)] HttpProtocolHandlerCore - Network error: net::ERR_CONNECTION_REFUSED (-102) [10:35:20:INFO] [0319/223520.592548:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [10:35:20:INFO] [ OK ] PkpTest.TestIncludeSubdomainsFlagEqualTrue (41 ms) [10:35:20:INFO] [ RUN ] PkpTest.TestIncludeSubdomainsFlagEqualFalse [10:35:20:INFO] [0319/223520.649969:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [10:35:20:INFO] [ OK ] PkpTest.TestIncludeSubdomainsFlagEqualFalse (55 ms) [10:35:20:INFO] [ RUN ] PkpTest.TestSoonExpiringPin [10:35:20:INFO] [0319/223520.675915:WARNING:proof_verifier_chromium.cc(501)] Failed to verify certificate chain: net::ERR_SSL_PINNED_KEY_NOT_IN_CERT_CHAIN [10:35:20:INFO] [0319/223520.677840:ERROR:crn_http_protocol_handler.mm(845)] HttpProtocolHandlerCore - Network error: net::ERR_CONNECTION_REFUSED (-102) [10:35:20:INFO] [0319/223520.691847:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [10:35:20:INFO] [ OK ] PkpTest.TestSoonExpiringPin (40 ms) [10:35:20:INFO] [ RUN ] PkpTest.TestRecentlyExpiredPin [10:35:20:INFO] [0319/223520.749574:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [10:35:20:INFO] [ OK ] PkpTest.TestRecentlyExpiredPin (56 ms) [10:35:20:INFO] [ RUN ] PkpTest.TestPinsAreNotPersisted [10:35:20:INFO] [0319/223520.774906:WARNING:proof_verifier_chromium.cc(501)] Failed to verify certificate chain: net::ERR_SSL_PINNED_KEY_NOT_IN_CERT_CHAIN [10:35:20:INFO] [0319/223520.776761:ERROR:crn_http_protocol_handler.mm(845)] HttpProtocolHandlerCore - Network error: net::ERR_CONNECTION_REFUSED (-102) [10:35:20:INFO] [0319/223520.795239:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [10:35:20:INFO] [0319/223520.841678:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [10:35:20:INFO] [ OK ] PkpTest.TestPinsAreNotPersisted (91 ms) [10:35:20:INFO] [ RUN ] PkpTest.TestHashLengthError [10:35:20:INFO] [ OK ] PkpTest.TestHashLengthError (8 ms) [10:35:20:INFO] [ RUN ] PkpTest.TestPkpOverrideNonMatchingToMatching [10:35:20:INFO] [0319/223520.907957:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [10:35:20:INFO] [ OK ] PkpTest.TestPkpOverrideNonMatchingToMatching (56 ms) [10:35:20:INFO] [ RUN ] PkpTest.TestPkpOverrideMatchingToNonMatching [10:35:20:INFO] [0319/223520.933811:WARNING:proof_verifier_chromium.cc(501)] Failed to verify certificate chain: net::ERR_SSL_PINNED_KEY_NOT_IN_CERT_CHAIN [10:35:20:INFO] [0319/223520.935714:ERROR:crn_http_protocol_handler.mm(845)] HttpProtocolHandlerCore - Network error: net::ERR_CONNECTION_REFUSED (-102) [10:35:20:INFO] [0319/223520.952657:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [10:35:20:INFO] [ OK ] PkpTest.TestPkpOverrideMatchingToNonMatching (43 ms) [10:35:20:INFO] [----------] 12 tests from PkpTest (568 ms total) [10:35:20:INFO] [10:35:20:INFO] [----------] 1 test from PrefsTest [10:35:20:INFO] [ RUN ] PrefsTest.HttpSeverProperties [10:35:20:INFO] 2019-03-19 22:35:20.992 cronet_test[19368:335690] Waiting for file /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/90615D4D-AC23-4D78-A523-690BF0801639/data/Containers/Data/Application/A8B0A8E3-80AB-4183-A2A3-9592AE2FFAB4/Library/Caches/cronet/prefs/local_prefs.json. [10:35:20:INFO] 2019-03-19 22:35:20.992 cronet_test[19368:335690] Time left: 80 seconds [10:35:25:INFO] 2019-03-19 22:35:25.995 cronet_test[19368:335690] Time left: 75 seconds [10:35:30:INFO] 2019-03-19 22:35:30.997 cronet_test[19368:335690] Time left: 70 seconds [10:35:36:INFO] 2019-03-19 22:35:36.000 cronet_test[19368:335690] Time left: 65 seconds [10:35:41:INFO] 2019-03-19 22:35:41.003 cronet_test[19368:335690] Time left: 60 seconds [10:35:46:INFO] 2019-03-19 22:35:46.005 cronet_test[19368:335690] Time left: 55 seconds [10:35:51:INFO] 2019-03-19 22:35:51.008 cronet_test[19368:335690] Time left: 50 seconds [10:35:56:INFO] 2019-03-19 22:35:56.011 cronet_test[19368:335690] Time left: 45 seconds [10:36:01:INFO] 2019-03-19 22:36:01.013 cronet_test[19368:335690] Time left: 40 seconds [10:36:06:INFO] 2019-03-19 22:36:06.016 cronet_test[19368:335690] Time left: 35 seconds [10:36:11:INFO] 2019-03-19 22:36:11.018 cronet_test[19368:335690] Time left: 30 seconds [10:36:16:INFO] 2019-03-19 22:36:16.020 cronet_test[19368:335690] Time left: 25 seconds [10:36:21:INFO] 2019-03-19 22:36:21.022 cronet_test[19368:335690] Time left: 20 seconds [10:36:26:INFO] 2019-03-19 22:36:26.025 cronet_test[19368:335690] Time left: 15 seconds [10:36:31:INFO] 2019-03-19 22:36:31.028 cronet_test[19368:335690] File /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/90615D4D-AC23-4D78-A523-690BF0801639/data/Containers/Data/Application/A8B0A8E3-80AB-4183-A2A3-9592AE2FFAB4/Library/Caches/cronet/prefs/local_prefs.json exists. [10:36:31:INFO] [0319/223631.033821:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [10:36:31:INFO] [ OK ] PrefsTest.HttpSeverProperties (70079 ms) [10:36:31:INFO] [----------] 1 test from PrefsTest (70081 ms total) [10:36:31:INFO] [10:36:31:INFO] [----------] 3 tests from QuicTest [10:36:31:INFO] [ RUN ] QuicTest.InvalidQuicHost [10:36:31:INFO] [0319/223631.041857:ERROR:Cronet.mm(218)] Invalid QUIC hint host: https://test.example.com/ [10:36:31:INFO] [ OK ] QuicTest.InvalidQuicHost (6 ms) [10:36:31:INFO] [ RUN ] QuicTest.ValidQuicHost [10:36:31:INFO] [ OK ] QuicTest.ValidQuicHost (6 ms) [10:36:31:INFO] [ RUN ] QuicTest.RequestWithSocketOptimizationEnabled [10:36:31:INFO] [0319/223631.102988:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [10:36:31:INFO] [ OK ] QuicTest.RequestWithSocketOptimizationEnabled (51 ms) [10:36:31:INFO] [----------] 3 tests from QuicTest (69 ms total) [10:36:31:INFO] [10:36:31:INFO] [----------] 32 tests from BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest [10:36:31:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.StartExampleBidiStream/0 [10:36:31:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.StartExampleBidiStream/0 (41 ms) [10:36:31:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.StartExampleBidiStream/1 [10:36:31:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.StartExampleBidiStream/1 (40 ms) [10:36:31:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.SimplePutWithEmptyWriteDataAtTheEnd/0 [10:36:31:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.SimplePutWithEmptyWriteDataAtTheEnd/0 (40 ms) [10:36:31:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.SimplePutWithEmptyWriteDataAtTheEnd/1 [10:36:31:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.SimplePutWithEmptyWriteDataAtTheEnd/1 (40 ms) [10:36:31:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.SimpleGetWithFlush/0 [10:36:31:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.SimpleGetWithFlush/0 (38 ms) [10:36:31:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.SimpleGetWithFlush/1 [10:36:31:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.SimpleGetWithFlush/1 (38 ms) [10:36:31:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.SimplePostWithFlush/0 [10:36:31:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.SimplePostWithFlush/0 (39 ms) [10:36:31:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.SimplePostWithFlush/1 [10:36:31:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.SimplePostWithFlush/1 (39 ms) [10:36:31:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.SimplePostWithFlushTwice/0 [10:36:31:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.SimplePostWithFlushTwice/0 (39 ms) [10:36:31:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.SimplePostWithFlushTwice/1 [10:36:31:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.SimplePostWithFlushTwice/1 (40 ms) [10:36:31:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.SimplePostWithFlushAfterOneWrite/0 [10:36:31:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.SimplePostWithFlushAfterOneWrite/0 (39 ms) [10:36:31:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.SimplePostWithFlushAfterOneWrite/1 [10:36:31:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.SimplePostWithFlushAfterOneWrite/1 (39 ms) [10:36:31:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.TestDelayedFlush/0 [10:36:31:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.TestDelayedFlush/0 (40 ms) [10:36:31:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.TestDelayedFlush/1 [10:36:31:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.TestDelayedFlush/1 (40 ms) [10:36:31:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.CancelOnRead/0 [10:36:31:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.CancelOnRead/0 (39 ms) [10:36:31:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.CancelOnRead/1 [10:36:31:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.CancelOnRead/1 (38 ms) [10:36:31:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.CancelOnResponse/0 [10:36:31:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.CancelOnResponse/0 (39 ms) [10:36:31:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.CancelOnResponse/1 [10:36:31:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.CancelOnResponse/1 (38 ms) [10:36:31:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.CancelOnSucceeded/0 [10:36:31:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.CancelOnSucceeded/0 (38 ms) [10:36:31:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.CancelOnSucceeded/1 [10:36:31:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.CancelOnSucceeded/1 (39 ms) [10:36:31:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.ReadFailsBeforeRequestStarted/0 [10:36:31:INFO] [0319/223631.928441:ERROR:bidirectional_stream.cc(286)] Unexpected Read Data in read_state 0 [10:36:31:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.ReadFailsBeforeRequestStarted/0 (9 ms) [10:36:31:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.ReadFailsBeforeRequestStarted/1 [10:36:31:INFO] [0319/223631.938731:ERROR:bidirectional_stream.cc(286)] Unexpected Read Data in read_state 0 [10:36:31:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.ReadFailsBeforeRequestStarted/1 (8 ms) [10:36:31:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.StreamFailBeforeReadIsExecutedOnNetworkThread/0 [10:36:31:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.StreamFailBeforeReadIsExecutedOnNetworkThread/0 (39 ms) [10:36:31:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.StreamFailBeforeReadIsExecutedOnNetworkThread/1 [10:36:32:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.StreamFailBeforeReadIsExecutedOnNetworkThread/1 (40 ms) [10:36:32:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.WriteFailsBeforeRequestStarted/0 [10:36:32:INFO] [0319/223632.031507:ERROR:bidirectional_stream.cc(315)] Unexpected Flush Data in write_state 0 [10:36:32:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.WriteFailsBeforeRequestStarted/0 (8 ms) [10:36:32:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.WriteFailsBeforeRequestStarted/1 [10:36:32:INFO] [0319/223632.041837:ERROR:bidirectional_stream.cc(315)] Unexpected Flush Data in write_state 0 [10:36:32:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.WriteFailsBeforeRequestStarted/1 (8 ms) [10:36:32:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.StreamFailAfterStreamReadyCallback/0 [10:36:32:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.StreamFailAfterStreamReadyCallback/0 (37 ms) [10:36:32:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.StreamFailAfterStreamReadyCallback/1 [10:36:32:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.StreamFailAfterStreamReadyCallback/1 (38 ms) [10:36:32:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.StreamFailBeforeWriteIsExecutedOnNetworkThread/0 [10:36:32:INFO] [0319/223632.159767:ERROR:bidirectional_stream_quic_impl.cc(175)] Trying to send data after stream has been closed. [10:36:32:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.StreamFailBeforeWriteIsExecutedOnNetworkThread/0 (38 ms) [10:36:32:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.StreamFailBeforeWriteIsExecutedOnNetworkThread/1 [10:36:32:INFO] [0319/223632.200416:ERROR:bidirectional_stream_quic_impl.cc(175)] Trying to send data after stream has been closed. [10:36:32:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.StreamFailBeforeWriteIsExecutedOnNetworkThread/1 (39 ms) [10:36:32:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.FailedResolution/0 [10:36:32:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.FailedResolution/0 (10 ms) [10:36:32:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.FailedResolution/1 [10:36:32:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.FailedResolution/1 (10 ms) [10:36:32:INFO] [----------] 32 tests from BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest (1121 ms total) [10:36:32:INFO] [10:36:32:INFO] [----------] 5 tests from Loops/PerfTest [10:36:32:INFO] [ RUN ] Loops/PerfTest.NSURLSessionReceivesImageLoop/0 [10:36:32:INFO] [19368:771:0319/223632.234480:8217062277585:INFO:cronet_performance_test.mm(181)] Running with parameters: QUIC: 0 HTTP2: 0 AKD4: 0 Cronet: 0 [10:36:32:INFO] [19368:771:0319/223632.234608:8217062373265:INFO:cronet_performance_test.mm(191)] Downloading 19307439 bytes per iteration [10:36:32:INFO] [19368:771:0319/223632.816995:8217644768618:INFO:cronet_performance_test.mm(253)] Elapsed Total:568.185ms [10:36:32:INFO] [0319/223632.820659:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [10:36:32:INFO] [ OK ] Loops/PerfTest.NSURLSessionReceivesImageLoop/0 (595 ms) [10:36:32:INFO] [ RUN ] Loops/PerfTest.NSURLSessionReceivesImageLoop/1 [10:36:32:INFO] [19368:771:0319/223632.831734:8217659510946:INFO:cronet_performance_test.mm(181)] Running with parameters: QUIC: 0 HTTP2: 0 AKD4: 0 Cronet: 1 [10:36:32:INFO] [19368:771:0319/223632.831871:8217659626752:INFO:cronet_performance_test.mm(191)] Downloading 19307439 bytes per iteration [10:36:33:INFO] [19368:771:0319/223633.235795:8218063546445:INFO:cronet_performance_test.mm(253)] Elapsed Total:389.531ms [10:36:33:INFO] [0319/223633.239481:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [10:36:33:INFO] [ OK ] Loops/PerfTest.NSURLSessionReceivesImageLoop/1 (417 ms) [10:36:33:INFO] [ RUN ] Loops/PerfTest.NSURLSessionReceivesImageLoop/2 [10:36:33:INFO] [19368:771:0319/223633.250761:8218078526714:INFO:cronet_performance_test.mm(181)] Running with parameters: QUIC: 0 HTTP2: 1 AKD4: 0 Cronet: 1 [10:36:33:INFO] [19368:771:0319/223633.250901:8218078633197:INFO:cronet_performance_test.mm(191)] Downloading 19307439 bytes per iteration [10:36:33:INFO] [19368:771:0319/223633.656928:8218484663945:INFO:cronet_performance_test.mm(253)] Elapsed Total:391.721ms [10:36:33:INFO] [0319/223633.660641:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [10:36:33:INFO] [ OK ] Loops/PerfTest.NSURLSessionReceivesImageLoop/2 (419 ms) [10:36:33:INFO] [ RUN ] Loops/PerfTest.NSURLSessionReceivesImageLoop/3 [10:36:33:INFO] [19368:771:0319/223633.671637:8218499415626:INFO:cronet_performance_test.mm(181)] Running with parameters: QUIC: 1 HTTP2: 0 AKD4: 0 Cronet: 1 [10:36:33:INFO] [19368:771:0319/223633.671803:8218499530173:INFO:cronet_performance_test.mm(191)] Downloading 19307439 bytes per iteration [10:36:34:INFO] [19368:771:0319/223634.076145:8218903867289:INFO:cronet_performance_test.mm(253)] Elapsed Total:390.076ms [10:36:34:INFO] [0319/223634.079857:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [10:36:34:INFO] [ OK ] Loops/PerfTest.NSURLSessionReceivesImageLoop/3 (417 ms) [10:36:34:INFO] [ RUN ] Loops/PerfTest.NSURLSessionReceivesImageLoop/4 [10:36:34:INFO] [19368:771:0319/223634.091077:8218918818715:INFO:cronet_performance_test.mm(181)] Running with parameters: QUIC: 1 HTTP2: 0 AKD4: 1 Cronet: 1 [10:36:34:INFO] [19368:771:0319/223634.091218:8218918920487:INFO:cronet_performance_test.mm(191)] Downloading 19307439 bytes per iteration [10:36:34:INFO] [19368:771:0319/223634.495686:8219323397870:INFO:cronet_performance_test.mm(253)] Elapsed Total:390.312ms [10:36:34:INFO] [0319/223634.499379:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [10:36:34:INFO] [ OK ] Loops/PerfTest.NSURLSessionReceivesImageLoop/4 (418 ms) [10:36:34:INFO] [19368:771:0319/223634.502095:8219329812596:INFO:cronet_performance_test.mm(71)] Performance Data: [10:36:34:INFO] [19368:771:0319/223634.502240:8219329929812:INFO:cronet_performance_test.mm(88)] Quic 0 Http2 0 AKD4 0 Cronet 0: Mean: 0.056819s (2718.471752mbps) Max: 0.059530s with 0 fails out of 10 total requests. [10:36:34:INFO] [19368:771:0319/223634.502313:8219329999924:INFO:cronet_performance_test.mm(88)] Quic 0 Http2 0 AKD4 0 Cronet 1: Mean: 0.038953s (3965.266204mbps) Max: 0.040598s with 0 fails out of 10 total requests. [10:36:34:INFO] [19368:771:0319/223634.502377:8219330067544:INFO:cronet_performance_test.mm(88)] Quic 0 Http2 1 AKD4 0 Cronet 1: Mean: 0.039172s (3943.100012mbps) Max: 0.041116s with 0 fails out of 10 total requests. [10:36:34:INFO] [19368:771:0319/223634.502460:8219330152026:INFO:cronet_performance_test.mm(88)] Quic 1 Http2 0 AKD4 0 Cronet 1: Mean: 0.039008s (3959.728250mbps) Max: 0.040337s with 0 fails out of 10 total requests. [10:36:34:INFO] [19368:771:0319/223634.502540:8219330237287:INFO:cronet_performance_test.mm(88)] Quic 1 Http2 0 AKD4 1 Cronet 1: Mean: 0.039031s (3957.331256mbps) Max: 0.040298s with 0 fails out of 10 total requests. [10:36:34:INFO] [----------] 5 tests from Loops/PerfTest (2276 ms total) [10:36:34:INFO] [10:36:34:INFO] [----------] Global test environment tear-down [10:36:34:INFO] [==========] 166 tests from 14 test suites ran. (78308 ms total) [10:36:34:INFO] [ PASSED ] 166 tests. [10:36:34:INFO] [10:36:34:INFO] YOU HAVE 2 DISABLED TESTS [10:36:34:INFO] [10:36:45:INFO] Testing started on 'iPhone 5s' [10:36:45:INFO] 2019-03-19 22:34:56.127 xcodebuild[19272:334416] IDETestOperationsObserverDebug: Writing diagnostic log for test session to: [10:36:45:INFO] /Users/chrome-bot/Library/Developer/Xcode/DerivedData/temporary-bzvseivgmrlmpadojjzwriiilsec/Logs/Test/Test-Transient Testing-2019.03.19_22-34-56--0700.xcresult/1_Test/Diagnostics/cronet_test-76773AE5-D186-4BEB-B5CF-4F5DC5127FF1/cronet_test-92A636DC-447F-410B-BE63-F2B3782E0610/Session-cronet_test-2019-03-19_223456-hCYnSW.log [10:36:45:INFO] 2019-03-19 22:34:56.128 xcodebuild[19272:334398] [MT] IDETestOperationsObserverDebug: (310FB86C-1197-4B76-958C-D3FF04755B40) Beginning test session cronet_test-310FB86C-1197-4B76-958C-D3FF04755B40 at 2019-03-19 22:34:56.128 with Xcode 10B61 on target <DVTiPhoneSimulator: 0x7fa6377b1860> { [10:36:45:INFO] SimDevice: iPhone 5s (90615D4D-AC23-4D78-A523-690BF0801639, iOS 9.3, Shutdown) [10:36:45:INFO] } (9.3 (13E233)) [10:36:45:INFO] 2019-03-19 22:36:44.230 xcodebuild[19272:334398] [MT] IDETestOperationsObserverDebug: 108.178 elapsed -- Testing started completed. [10:36:45:INFO] 2019-03-19 22:36:44.230 xcodebuild[19272:334398] [MT] IDETestOperationsObserverDebug: 0.000 sec, +0.000 sec -- start [10:36:45:INFO] 2019-03-19 22:36:44.230 xcodebuild[19272:334398] [MT] IDETestOperationsObserverDebug: 108.178 sec, +108.178 sec -- end [10:36:45:INFO] [10:36:45:INFO] Testing failed: [10:36:45:DEBUG] Finished print_process_output. [10:36:45:INFO] Waiting for test process to terminate. [10:36:45:INFO] Test process terminated. [10:36:45:DEBUG] Setting sigterm handler. [10:36:45:DEBUG] Stdout flushed after test process. [10:36:45:DEBUG] Processing test results. [10:36:45:INFO] /b/s/w/ir/src/out/Debug-iphonesimulator/iossim returned 65 [10:36:45:DEBUG] Extracting test data. [10:36:45:DEBUG] Retrieving crash reports. [10:36:45:DEBUG] Retrieving derived data. [10:36:45:DEBUG] Making desktop screenshots. [10:36:45:DEBUG] Killing simulators. [10:36:50:DEBUG] Wiping simulator. Device wiped. [10:36:52:DEBUG] End of tear_down. 2019-03-19 22:37:24,125 - root: [DEBUG] Contents of task_output_dir: ['/b/s/w/ir/k/recipe_cleanup/17XFa9L/43b269a053992310'] 2019-03-19 22:37:24,126 - root: [WARNING] Expected output.json file missing: set(['/b/s/w/ir/k/recipe_cleanup/17XFa9L/43b269a053992310/output.json']) Found: [] Expected: ['/b/s/w/ir/k/recipe_cleanup/17XFa9L/43b269a053992310/output.json'] 2019-03-19 22:37:24,126 - root: [WARNING] No shard json files found in task_output_dir: '/b/s/w/ir/k/recipe_cleanup/17XFa9L' Found ['/b/s/w/ir/k/recipe_cleanup/17XFa9L/43b269a053992310'] 2019-03-19 22:37:24,126 - root: [DEBUG] Found shard_json_files: ['/b/s/w/ir/k/recipe_cleanup/17XFa9L/43b269a053992310/output.json'] 2019-03-19 22:37:24,126 - root: [INFO] merge_cmd: /b/s/w/ir/cache/vpython/5b0713/bin/python /b/s/w/ir/kitchen-checkout/build/scripts/slave/recipe_modules/chromium_swarming/resources/noop_merge.py --summary-json /b/s/w/ir/tmp/t/tmpSE7rry.json --task-output-dir /b/s/w/ir/k/recipe_cleanup/17XFa9L -o /b/s/w/ir/tmp/t/tmp1RJ3cJ.json Running ['/b/s/w/ir/cache/vpython/5b0713/bin/python', '/b/s/w/ir/kitchen-checkout/build/scripts/slave/recipe_modules/chromium_swarming/resources/noop_merge.py', '--summary-json', '/b/s/w/ir/tmp/t/tmpSE7rry.json', '--task-output-dir', '/b/s/w/ir/k/recipe_cleanup/17XFa9L', '-o', '/b/s/w/ir/tmp/t/tmp1RJ3cJ.json'] in None (env: None) Command ['/b/s/w/ir/cache/vpython/5b0713/bin/python', '/b/s/w/ir/kitchen-checkout/build/scripts/slave/recipe_modules/chromium_swarming/resources/noop_merge.py', '--summary-json', '/b/s/w/ir/tmp/t/tmpSE7rry.json', '--task-output-dir', '/b/s/w/ir/k/recipe_cleanup/17XFa9L', '-o', '/b/s/w/ir/tmp/t/tmp1RJ3cJ.json'] returned exit code 0