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/tmpYvQohE.json --task-output-dir /b/s/w/ir/k/recipe_cleanup/5n90Rtl --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/tmp1gVOGfmerge_script_log --merge-additional-args '[]' --summary-json-file /b/s/w/ir/tmp/t/tmpN6LLZ7.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/tmpCHJQ9z.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/tmpYvQohE.json', '--task-output-dir', '/b/s/w/ir/k/recipe_cleanup/5n90Rtl', '--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/tmp1gVOGfmerge_script_log', '--merge-additional-args', '[]', '--summary-json-file', '/b/s/w/ir/tmp/t/tmpN6LLZ7.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/tmpCHJQ9z.json'] env: {} env_prefixes: {'PATH': '/b/s/w/ir/cache/swarming_client'} env_suffixes: {} infra_step: False name_tokens: ('cronet_test (iPhone X iOS 11.4) 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.Ob3X3d1xVI/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: 50819 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/8918865787204792880 LOGDOG_STREAM_PROJECT: chromium LOGDOG_STREAM_SERVER_PATH: unix:/b/s/w/ir/tmp/ld.sock LOGNAME: chrome-bot LUCI_CONTEXT: /b/s/w/itxSynss/luci_context.170549014 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.8uXpdnitw2/Listeners SWARMING_BOT_ID: build212-m9 SWARMING_HEADLESS: 1 SWARMING_SERVER: https://chromium-swarm.appspot.com SWARMING_TASK_ID: 439d2d2292942211 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-15 19:44:06,827 - root: [DEBUG] Using task_output_dir: '/b/s/w/ir/k/recipe_cleanup/5n90Rtl' 2019-03-15 19:44:06,827 - root: [WARNING] task_output_dir '/b/s/w/ir/k/recipe_cleanup/5n90Rtl' already exists! 2019-03-15 19:44:06,827 - root: [WARNING] task_output_dir existing content: [] 2019-03-15 19:44:06,827 - 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/tmpCHJQ9z.json -output-dir /b/s/w/ir/k/recipe_cleanup/5n90Rtl -task-summary-json /b/s/w/ir/tmp/t/tmpN6LLZ7.json [D2019-03-15T19:44:06.841459-07:00 83064 0 auth.go:1265] Minting a new token {"key":"luci_ctx/d5431a5ae605d0bd92974bbfc86d280b765c3764f4ba1922a765d0f44b0f0314", "scopes":"https://www.googleapis.com/auth/userinfo.email"} [D2019-03-15T19:44:06.841529-07:00 83064 0 luci_ctx.go:138] POST http://127.0.0.1:50808/rpc/LuciLocalAuthService.GetOAuthToken {"key":"luci_ctx/d5431a5ae605d0bd92974bbfc86d280b765c3764f4ba1922a765d0f44b0f0314", "scopes":"https://www.googleapis.com/auth/userinfo.email"} [D2019-03-15T19:44:06.843231-07:00 83064 0 auth.go:1222] Token expires in 32m34.156799s {"key":"luci_ctx/d5431a5ae605d0bd92974bbfc86d280b765c3764f4ba1922a765d0f44b0f0314", "scopes":"https://www.googleapis.com/auth/userinfo.email"} 439d308f51157510: exit 0 [I 2019-03-15 19:40:28] About to install Xcode 10b61 in Xcode.app for ios [07:40:29:INFO] Using Xcode version 10.1 build 10B61 at /b/s/w/ir/Xcode.app/Contents/Developer Device wiped. [07:40:37:DEBUG] Setting sigterm handler. [07:40:43:INFO] User defaults from command line: [07:40:43:INFO] IDETestRunSpecificationPath = /var/folders/2j/22s2gz0s7hn48k32d47clxf80000gm/T/46FE096A-97AD-418B-87BB-EE11FDC236E0 [07:40:43:INFO] [07:41:17:INFO] objc[93509]: Class CRBProtocolObservers is implemented in both /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/2F34655F-9A6A-4B38-94FC-1FCF61CBFD93/data/Containers/Bundle/Application/82423D4D-6D36-46BC-9AF7-382EADC4AAF6/cronet_test.app/Frameworks/Cronet.framework/Cronet (0x112fd9b10) and /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/2F34655F-9A6A-4B38-94FC-1FCF61CBFD93/data/Containers/Bundle/Application/82423D4D-6D36-46BC-9AF7-382EADC4AAF6/cronet_test.app/cronet_test (0x10b663658). One of the two will be used. Which one is undefined. [07:41:17:INFO] objc[93509]: Class CRBWeakNSProtocolSentinel is implemented in both /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/2F34655F-9A6A-4B38-94FC-1FCF61CBFD93/data/Containers/Bundle/Application/82423D4D-6D36-46BC-9AF7-382EADC4AAF6/cronet_test.app/Frameworks/Cronet.framework/Cronet (0x112fd9b60) and /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/2F34655F-9A6A-4B38-94FC-1FCF61CBFD93/data/Containers/Bundle/Application/82423D4D-6D36-46BC-9AF7-382EADC4AAF6/cronet_test.app/cronet_test (0x10b6636a8). One of the two will be used. Which one is undefined. [07:41:17:INFO] 2019-03-15 19:41:17.847 cronet_test[93509:1568261] +[CATransaction synchronize] called within transaction [07:41:18:INFO] [==========] Running 166 tests from 14 test suites. [07:41:18:INFO] [----------] Global test environment set-up. [07:41:18:INFO] [----------] 4 tests from BufferTest [07:41:18:INFO] [ RUN ] BufferTest.TestInitWithAlloc [07:41:18:INFO] [ OK ] BufferTest.TestInitWithAlloc (73 ms) [07:41:18:INFO] [ RUN ] BufferTest.TestInitWithHugeAllocFails [07:41:18:INFO] [ OK ] BufferTest.TestInitWithHugeAllocFails (0 ms) [07:41:18:INFO] [ RUN ] BufferTest.TestInitWithDataAndCallback [07:41:18:INFO] [ OK ] BufferTest.TestInitWithDataAndCallback (1 ms) [07:41:18:INFO] [ RUN ] BufferTest.TestCronetBufferAsync [07:41:18:INFO] [ OK ] BufferTest.TestCronetBufferAsync (6 ms) [07:41:18:INFO] [----------] 4 tests from BufferTest (86 ms total) [07:41:18:INFO] [07:41:18:INFO] [----------] 8 tests from EngineTest [07:41:18:INFO] [ RUN ] EngineTest.StartCronetEngine [07:41:18:INFO] [ OK ] EngineTest.StartCronetEngine (528 ms) [07:41:18:INFO] [ RUN ] EngineTest.CronetEngineDefaultUserAgent [07:41:18:INFO] [ OK ] EngineTest.CronetEngineDefaultUserAgent (1 ms) [07:41:18:INFO] [ RUN ] EngineTest.InitDifferentEngines [07:41:18:INFO] [ OK ] EngineTest.InitDifferentEngines (9 ms) [07:41:18:INFO] [ RUN ] EngineTest.StartResults [07:41:18:INFO] [0315/194118.721817:ERROR:engine.cc(135)] Disk cache path /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/2F34655F-9A6A-4B38-94FC-1FCF61CBFD93/data/Containers/Data/Application/2B44146C-3DDD-4C60-B998-37F0C801EFB8/tmp/.org.chromium.Chromium.m8J5st is already used, cache disabled. [07:41:18:INFO] [ OK ] EngineTest.StartResults (251 ms) [07:41:18:INFO] [ RUN ] EngineTest.InvalidPkpParams [07:41:18:INFO] [ OK ] EngineTest.InvalidPkpParams (0 ms) [07:41:18:INFO] [ RUN ] EngineTest.ValidPkpParams [07:41:18:INFO] [ OK ] EngineTest.ValidPkpParams (16 ms) [07:41:18:INFO] [ RUN ] EngineTest.SetMockCertVerifierForTesting [07:41:18:INFO] [ OK ] EngineTest.SetMockCertVerifierForTesting (2 ms) [07:41:18:INFO] [ RUN ] EngineTest.StartNetLogToFile [07:41:19:INFO] [0315/194119.033718:ERROR:cronet_url_request_context.cc(536)] Failed to open NetLog file for writing. [07:41:19:INFO] [ OK ] EngineTest.StartNetLogToFile (42 ms) [07:41:19:INFO] [----------] 8 tests from EngineTest (858 ms total) [07:41:19:INFO] [07:41:19:INFO] [----------] 2 tests from ExecutorsTest [07:41:19:INFO] [ RUN ] ExecutorsTest.TestCustom [07:41:19:INFO] [ OK ] ExecutorsTest.TestCustom (0 ms) [07:41:19:INFO] [ RUN ] ExecutorsTest.TestTestExecutor [07:41:19:INFO] [ OK ] ExecutorsTest.TestTestExecutor (0 ms) [07:41:19:INFO] [----------] 2 tests from ExecutorsTest (3 ms total) [07:41:19:INFO] [07:41:19:INFO] [----------] 57 tests from UrlRequestTest [07:41:19:INFO] [ RUN ] UrlRequestTest.UploadWithDirectExecutor [07:41:19:INFO] [ OK ] UrlRequestTest.UploadWithDirectExecutor (195 ms) [07:41:19:INFO] [ RUN ] UrlRequestTest.InitChecks/0 [07:41:19:INFO] [ OK ] UrlRequestTest.InitChecks/0 (5 ms) [07:41:19:INFO] [ RUN ] UrlRequestTest.InitChecks/1 [07:41:19:INFO] [ OK ] UrlRequestTest.InitChecks/1 (5 ms) [07:41:19:INFO] [ RUN ] UrlRequestTest.SimpleGet/0 [07:41:19:INFO] [ OK ] UrlRequestTest.SimpleGet/0 (11 ms) [07:41:19:INFO] [ RUN ] UrlRequestTest.SimpleGet/1 [07:41:19:INFO] [ OK ] UrlRequestTest.SimpleGet/1 (9 ms) [07:41:19:INFO] [ RUN ] UrlRequestTest.UploadEmptyBodySync/0 [07:41:19:INFO] [ OK ] UrlRequestTest.UploadEmptyBodySync/0 (8 ms) [07:41:19:INFO] [ RUN ] UrlRequestTest.UploadEmptyBodySync/1 [07:41:19:INFO] [ OK ] UrlRequestTest.UploadEmptyBodySync/1 (8 ms) [07:41:19:INFO] [ RUN ] UrlRequestTest.UploadSync/0 [07:41:19:INFO] [ OK ] UrlRequestTest.UploadSync/0 (8 ms) [07:41:19:INFO] [ RUN ] UrlRequestTest.UploadSync/1 [07:41:19:INFO] [ OK ] UrlRequestTest.UploadSync/1 (8 ms) [07:41:19:INFO] [ RUN ] UrlRequestTest.SSLCertificateError/0 [07:41:19:INFO] [0315/194119.498530:ERROR:ssl_client_socket_impl.cc(947)] handshake failed; returned -1, SSL error code 1, net_error -207 [07:41:19:INFO] [93509:29739:0315/194119.498805:21792899699061:ERROR:ssl_server_socket_impl.cc(686)] handshake failed; returned -1, SSL error code 1, net_error -117 [07:41:19:INFO] [ OK ] UrlRequestTest.SSLCertificateError/0 (196 ms) [07:41:19:INFO] [ RUN ] UrlRequestTest.SSLCertificateError/1 [07:41:19:INFO] [0315/194119.534490:ERROR:ssl_client_socket_impl.cc(947)] handshake failed; returned -1, SSL error code 1, net_error -207 [07:41:19:INFO] [93509:85059:0315/194119.534652:21792935524199:ERROR:ssl_server_socket_impl.cc(686)] handshake failed; returned -1, SSL error code 1, net_error -117 [07:41:19:INFO] [ OK ] UrlRequestTest.SSLCertificateError/1 (32 ms) [07:41:19:INFO] [ RUN ] UrlRequestTest.UploadMultiplePiecesSync/0 [07:41:19:INFO] [ OK ] UrlRequestTest.UploadMultiplePiecesSync/0 (8 ms) [07:41:19:INFO] [ RUN ] UrlRequestTest.UploadMultiplePiecesSync/1 [07:41:19:INFO] [ OK ] UrlRequestTest.UploadMultiplePiecesSync/1 (9 ms) [07:41:19:INFO] [ RUN ] UrlRequestTest.UploadMultiplePiecesAsync/0 [07:41:19:INFO] [ OK ] UrlRequestTest.UploadMultiplePiecesAsync/0 (8 ms) [07:41:19:INFO] [ RUN ] UrlRequestTest.UploadMultiplePiecesAsync/1 [07:41:19:INFO] [ OK ] UrlRequestTest.UploadMultiplePiecesAsync/1 (9 ms) [07:41:19:INFO] [ RUN ] UrlRequestTest.UploadChangesDefaultMethod/0 [07:41:19:INFO] [ OK ] UrlRequestTest.UploadChangesDefaultMethod/0 (7 ms) [07:41:19:INFO] [ RUN ] UrlRequestTest.UploadChangesDefaultMethod/1 [07:41:19:INFO] [ OK ] UrlRequestTest.UploadChangesDefaultMethod/1 (9 ms) [07:41:19:INFO] [ RUN ] UrlRequestTest.UploadWithSetMethod/0 [07:41:19:INFO] [ OK ] UrlRequestTest.UploadWithSetMethod/0 (8 ms) [07:41:19:INFO] [ RUN ] UrlRequestTest.UploadWithSetMethod/1 [07:41:19:INFO] [ OK ] UrlRequestTest.UploadWithSetMethod/1 (9 ms) [07:41:19:INFO] [ RUN ] UrlRequestTest.UploadWithBigRead/0 [07:41:19:INFO] [ OK ] UrlRequestTest.UploadWithBigRead/0 (8 ms) [07:41:19:INFO] [ RUN ] UrlRequestTest.UploadWithBigRead/1 [07:41:19:INFO] [ OK ] UrlRequestTest.UploadWithBigRead/1 (9 ms) [07:41:19:INFO] [ RUN ] UrlRequestTest.UploadRedirectSync/0 [07:41:19:INFO] [ OK ] UrlRequestTest.UploadRedirectSync/0 (10 ms) [07:41:19:INFO] [ RUN ] UrlRequestTest.UploadRedirectSync/1 [07:41:19:INFO] [ OK ] UrlRequestTest.UploadRedirectSync/1 (11 ms) [07:41:19:INFO] [ RUN ] UrlRequestTest.UploadRedirectAsync/0 [07:41:19:INFO] [ OK ] UrlRequestTest.UploadRedirectAsync/0 (10 ms) [07:41:19:INFO] [ RUN ] UrlRequestTest.UploadRedirectAsync/1 [07:41:19:INFO] [ OK ] UrlRequestTest.UploadRedirectAsync/1 (11 ms) [07:41:19:INFO] [ RUN ] UrlRequestTest.UploadWithBadLength/0 [07:41:19:INFO] [ OK ] UrlRequestTest.UploadWithBadLength/0 (6 ms) [07:41:19:INFO] [ RUN ] UrlRequestTest.UploadWithBadLength/1 [07:41:19:INFO] [ OK ] UrlRequestTest.UploadWithBadLength/1 (8 ms) [07:41:19:INFO] [ RUN ] UrlRequestTest.UploadWithBadLengthBufferAligned/0 [07:41:19:INFO] [ OK ] UrlRequestTest.UploadWithBadLengthBufferAligned/0 (77 ms) [07:41:19:INFO] [ RUN ] UrlRequestTest.UploadWithBadLengthBufferAligned/1 [07:41:19:INFO] [ OK ] UrlRequestTest.UploadWithBadLengthBufferAligned/1 (131 ms) [07:41:19:INFO] [ RUN ] UrlRequestTest.UploadReadFailSync/0 [07:41:19:INFO] [ OK ] UrlRequestTest.UploadReadFailSync/0 (7 ms) [07:41:19:INFO] [ RUN ] UrlRequestTest.UploadReadFailSync/1 [07:41:19:INFO] [ OK ] UrlRequestTest.UploadReadFailSync/1 (8 ms) [07:41:19:INFO] [ RUN ] UrlRequestTest.UploadReadFailAsync/0 [07:41:19:INFO] [ OK ] UrlRequestTest.UploadReadFailAsync/0 (7 ms) [07:41:19:INFO] [ RUN ] UrlRequestTest.UploadReadFailAsync/1 [07:41:19:INFO] [ OK ] UrlRequestTest.UploadReadFailAsync/1 (8 ms) [07:41:19:INFO] [ RUN ] UrlRequestTest.UploadRewindFailSync/0 [07:41:19:INFO] [ OK ] UrlRequestTest.UploadRewindFailSync/0 (14 ms) [07:41:19:INFO] [ RUN ] UrlRequestTest.UploadRewindFailSync/1 [07:41:19:INFO] [ OK ] UrlRequestTest.UploadRewindFailSync/1 (11 ms) [07:41:19:INFO] [ RUN ] UrlRequestTest.UploadRewindFailAsync/0 [07:41:19:INFO] [ OK ] UrlRequestTest.UploadRewindFailAsync/0 (9 ms) [07:41:19:INFO] [ RUN ] UrlRequestTest.UploadRewindFailAsync/1 [07:41:19:INFO] [ OK ] UrlRequestTest.UploadRewindFailAsync/1 (10 ms) [07:41:19:INFO] [ RUN ] UrlRequestTest.UploadChunked/0 [07:41:20:INFO] [ OK ] UrlRequestTest.UploadChunked/0 (15 ms) [07:41:20:INFO] [ RUN ] UrlRequestTest.UploadChunked/1 [07:41:20:INFO] [ OK ] UrlRequestTest.UploadChunked/1 (9 ms) [07:41:20:INFO] [ RUN ] UrlRequestTest.UploadChunkedLastReadZeroLengthBody/0 [07:41:20:INFO] [ OK ] UrlRequestTest.UploadChunkedLastReadZeroLengthBody/0 (9 ms) [07:41:20:INFO] [ RUN ] UrlRequestTest.UploadChunkedLastReadZeroLengthBody/1 [07:41:20:INFO] [ OK ] UrlRequestTest.UploadChunkedLastReadZeroLengthBody/1 (12 ms) [07:41:20:INFO] [ RUN ] UrlRequestTest.UploadFailsWithoutInitializingStream/0 [07:41:20:INFO] [ OK ] UrlRequestTest.UploadFailsWithoutInitializingStream/0 (7 ms) [07:41:20:INFO] [ RUN ] UrlRequestTest.UploadFailsWithoutInitializingStream/1 [07:41:20:INFO] [ OK ] UrlRequestTest.UploadFailsWithoutInitializingStream/1 (7 ms) [07:41:20:INFO] [ RUN ] UrlRequestTest.SimpleRequest/0 [07:41:20:INFO] [ OK ] UrlRequestTest.SimpleRequest/0 (7 ms) [07:41:20:INFO] [ RUN ] UrlRequestTest.SimpleRequest/1 [07:41:20:INFO] [ OK ] UrlRequestTest.SimpleRequest/1 (8 ms) [07:41:20:INFO] [ RUN ] UrlRequestTest.MultiRedirect/0 [07:41:20:INFO] [ OK ] UrlRequestTest.MultiRedirect/0 (33 ms) [07:41:20:INFO] [ RUN ] UrlRequestTest.MultiRedirect/1 [07:41:20:INFO] [ OK ] UrlRequestTest.MultiRedirect/1 (14 ms) [07:41:20:INFO] [ RUN ] UrlRequestTest.CancelRequest/0 [07:41:20:INFO] [ OK ] UrlRequestTest.CancelRequest/0 (7 ms) [07:41:20:INFO] [ RUN ] UrlRequestTest.CancelRequest/1 [07:41:20:INFO] [ OK ] UrlRequestTest.CancelRequest/1 (7 ms) [07:41:20:INFO] [ RUN ] UrlRequestTest.FailedRequestHostNotFound/0 [07:41:20:INFO] [ OK ] UrlRequestTest.FailedRequestHostNotFound/0 (5 ms) [07:41:20:INFO] [ RUN ] UrlRequestTest.FailedRequestHostNotFound/1 [07:41:20:INFO] [ OK ] UrlRequestTest.FailedRequestHostNotFound/1 (6 ms) [07:41:20:INFO] [ RUN ] UrlRequestTest.TestCancel/0 [07:41:20:INFO] [ OK ] UrlRequestTest.TestCancel/0 (66 ms) [07:41:20:INFO] [ RUN ] UrlRequestTest.TestCancel/1 [07:41:20:INFO] [ OK ] UrlRequestTest.TestCancel/1 (77 ms) [07:41:20:INFO] [ RUN ] UrlRequestTest.PerfTest/0 [07:41:21:INFO] [93509:771:0315/194121.429848:21794830819587:INFO:url_request_test.cc(993)] Total time 1081.98 ms [07:41:21:INFO] [93509:771:0315/194121.430042:21794831017862:INFO:url_request_test.cc(994)] Single Iteration time 108.198 ms [07:41:21:INFO] [93509:771:0315/194121.430191:21794831148742:INFO:url_request_test.cc(1001)] Average Throughput: 1427.57 mbps [07:41:21:INFO] [ OK ] UrlRequestTest.PerfTest/0 (1128 ms) [07:41:21:INFO] [ RUN ] UrlRequestTest.PerfTest/1 [07:41:23:INFO] [93509:771:0315/194123.347230:21796748284356:INFO:url_request_test.cc(993)] Total time 1893.01 ms [07:41:23:INFO] [93509:771:0315/194123.347409:21796748452903:INFO:url_request_test.cc(994)] Single Iteration time 189.301 ms [07:41:23:INFO] [93509:771:0315/194123.347540:21796748589890:INFO:url_request_test.cc(1001)] Average Throughput: 815.945 mbps [07:41:23:INFO] [ OK ] UrlRequestTest.PerfTest/1 (1916 ms) [07:41:23:INFO] [ RUN ] UrlRequestTest.GetStatus/0 [07:41:23:INFO] [ OK ] UrlRequestTest.GetStatus/0 (8 ms) [07:41:23:INFO] [ RUN ] UrlRequestTest.GetStatus/1 [07:41:23:INFO] [ OK ] UrlRequestTest.GetStatus/1 (9 ms) [07:41:23:INFO] [----------] 57 tests from UrlRequestTest (4333 ms total) [07:41:23:INFO] [07:41:23:INFO] [----------] 5 tests from AcceptLangTest [07:41:23:INFO] [ RUN ] AcceptLangTest.Region [07:41:23:INFO] [ OK ] AcceptLangTest.Region (0 ms) [07:41:23:INFO] [ RUN ] AcceptLangTest.Lang [07:41:23:INFO] [ OK ] AcceptLangTest.Lang (0 ms) [07:41:23:INFO] [ RUN ] AcceptLangTest.Default [07:41:23:INFO] [ OK ] AcceptLangTest.Default (0 ms) [07:41:23:INFO] [ RUN ] AcceptLangTest.Append [07:41:23:INFO] [ OK ] AcceptLangTest.Append (0 ms) [07:41:23:INFO] [ RUN ] AcceptLangTest.NoDefaultAppend [07:41:23:INFO] 2019-03-15 19:41:23.378 cronet_test[93509:1568261] en-GB,en-US,en [07:41:23:INFO] [ OK ] AcceptLangTest.NoDefaultAppend (0 ms) [07:41:23:INFO] [----------] 5 tests from AcceptLangTest (6 ms total) [07:41:23:INFO] [07:41:23:INFO] [----------] 21 tests from HttpTest [07:41:23:INFO] [ RUN ] HttpTest.CreateSslKeyLogFile [07:41:23:INFO] 2019-03-15 19:41:23.476 cronet_test[93509:1568786] DiskCookieStorage changing policy from 2 to 0, cookie file: file:///Users/chrome-bot/Library/Developer/CoreSimulator/Devices/2F34655F-9A6A-4B38-94FC-1FCF61CBFD93/data/Containers/Data/Application/2B44146C-3DDD-4C60-B998-37F0C801EFB8/Library/Cookies/org.chromium.gtest.cronet-test.binarycookies [07:41:23:INFO] [0315/194123.519616:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:41:23:INFO] [0315/194123.525623:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:41:23:INFO] [ OK ] HttpTest.CreateSslKeyLogFile (148 ms) [07:41:23:INFO] [ RUN ] HttpTest.NSURLSessionReceivesData [07:41:23:INFO] [0315/194123.800314:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:41:23:INFO] [ OK ] HttpTest.NSURLSessionReceivesData (272 ms) [07:41:23:INFO] [ RUN ] HttpTest.GetGlobalMetricsDeltas [07:41:23:INFO] [0315/194123.898940:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:41:23:INFO] [ OK ] HttpTest.GetGlobalMetricsDeltas (97 ms) [07:41:23:INFO] [ RUN ] HttpTest.SdchDisabledByDefault [07:41:23:INFO] [0315/194123.931862:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:41:23:INFO] [ OK ] HttpTest.SdchDisabledByDefault (31 ms) [07:41:23:INFO] [ RUN ] HttpTest.AcceptEncodingSdchIsAllowed [07:41:23:INFO] [0315/194123.952640:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:41:23:INFO] [ OK ] HttpTest.AcceptEncodingSdchIsAllowed (19 ms) [07:41:23:INFO] [ RUN ] HttpTest.AcceptEncodingFooBarIsAllowed [07:41:23:INFO] [0315/194123.972099:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:41:23:INFO] [ OK ] HttpTest.AcceptEncodingFooBarIsAllowed (19 ms) [07:41:23:INFO] [ RUN ] HttpTest.NSURLSessionAcceptLanguage [07:41:23:INFO] [0315/194123.991985:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:41:23:INFO] [ OK ] HttpTest.NSURLSessionAcceptLanguage (18 ms) [07:41:23:INFO] [ RUN ] HttpTest.SetUserAgentIsExact [07:41:24:INFO] [0315/194124.011846:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:41:24:INFO] [ OK ] HttpTest.SetUserAgentIsExact (18 ms) [07:41:24:INFO] [ RUN ] HttpTest.SetCookie [07:41:24:INFO] [0315/194124.039762:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:41:24:INFO] [ OK ] HttpTest.SetCookie (26 ms) [07:41:24:INFO] [ RUN ] HttpTest.SetSystemCookie [07:41:24:INFO] [0315/194124.060077:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:41:24:INFO] [ OK ] HttpTest.SetSystemCookie (19 ms) [07:41:24:INFO] [ RUN ] HttpTest.SystemCookieWithNullCreationTime [07:41:24:INFO] [0315/194124.081052:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:41:24:INFO] [ OK ] HttpTest.SystemCookieWithNullCreationTime (20 ms) [07:41:24:INFO] [ RUN ] HttpTest.FilterOutRequest [07:41:24:INFO] [0315/194124.108552:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:41:24:INFO] [ OK ] HttpTest.FilterOutRequest (26 ms) [07:41:24:INFO] [ RUN ] HttpTest.FileSchemeNotSupported [07:41:24:INFO] [0315/194124.129932:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:41:24:INFO] [ OK ] HttpTest.FileSchemeNotSupported (20 ms) [07:41:24:INFO] [ RUN ] HttpTest.DataSchemeNotSupported [07:41:24:INFO] [0315/194124.149019:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:41:24:INFO] [ OK ] HttpTest.DataSchemeNotSupported (18 ms) [07:41:24:INFO] [ RUN ] HttpTest.BrotliAdvertisedTest [07:41:24:INFO] [0315/194124.163605:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:41:24:INFO] [0315/194124.172429:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:41:24:INFO] [ OK ] HttpTest.BrotliAdvertisedTest (22 ms) [07:41:24:INFO] [ RUN ] HttpTest.BrotliNotAdvertisedTest [07:41:24:INFO] [0315/194124.186906:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:41:24:INFO] [0315/194124.195461:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:41:24:INFO] [ OK ] HttpTest.BrotliNotAdvertisedTest (22 ms) [07:41:24:INFO] [ RUN ] HttpTest.BrotliHandleDecoding [07:41:24:INFO] [0315/194124.209913:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:41:24:INFO] [0315/194124.223130:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:41:24:INFO] [ OK ] HttpTest.BrotliHandleDecoding (27 ms) [07:41:24:INFO] [ RUN ] HttpTest.PostRequest [07:41:24:INFO] [0315/194124.244986:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:41:24:INFO] [ OK ] HttpTest.PostRequest (21 ms) [07:41:24:INFO] [ RUN ] HttpTest.PostRequestWithBodyStream [07:41:24:INFO] [0315/194124.267665:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:41:24:INFO] [ OK ] HttpTest.PostRequestWithBodyStream (21 ms) [07:41:24:INFO] [ RUN ] HttpTest.PostRequestWithLargeBodyStream [07:41:24:INFO] [0315/194124.289618:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:41:24:INFO] [ OK ] HttpTest.PostRequestWithLargeBodyStream (21 ms) [07:41:24:INFO] [ RUN ] HttpTest.LegacyApi [07:41:24:INFO] [0315/194124.360799:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:41:24:INFO] [ OK ] HttpTest.LegacyApi (70 ms) [07:41:24:INFO] [----------] 21 tests from HttpTest (983 ms total) [07:41:24:INFO] [07:41:24:INFO] [----------] 8 tests from CronetEnabledMetricsTest [07:41:24:INFO] [ RUN ] CronetEnabledMetricsTest.ProtocolIsQuic [07:41:24:INFO] [0315/194124.424526:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:41:24:INFO] [ OK ] CronetEnabledMetricsTest.ProtocolIsQuic (63 ms) [07:41:24:INFO] [ RUN ] CronetEnabledMetricsTest.ProtocolIsNotQuic [07:41:24:INFO] [0315/194124.448250:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:41:24:INFO] [ OK ] CronetEnabledMetricsTest.ProtocolIsNotQuic (22 ms) [07:41:24:INFO] [ RUN ] CronetEnabledMetricsTest.PlatformComparison [07:41:24:INFO] [0315/194124.477607:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:41:24:INFO] [ OK ] CronetEnabledMetricsTest.PlatformComparison (28 ms) [07:41:24:INFO] [ RUN ] CronetEnabledMetricsTest.InvalidURL [07:41:24:INFO] [0315/194124.495787:ERROR:crn_http_protocol_handler.mm(845)] HttpProtocolHandlerCore - Network error: net::ERR_NAME_NOT_RESOLVED (-105) [07:41:24:INFO] [0315/194124.499311:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:41:24:INFO] [ OK ] CronetEnabledMetricsTest.InvalidURL (20 ms) [07:41:24:INFO] [ RUN ] CronetEnabledMetricsTest.CanceledRequest [07:41:24:INFO] [93509:771:0315/194124.515319:21797916428382:WARNING:cronet_test_base.mm(119)] The request was canceled due to timeout. [07:41:24:INFO] [0315/194124.519349:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:41:24:INFO] [ OK ] CronetEnabledMetricsTest.CanceledRequest (18 ms) [07:41:24:INFO] [ RUN ] CronetEnabledMetricsTest.ReusedConnection [07:41:24:INFO] [0315/194124.590000:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:41:24:INFO] [ OK ] CronetEnabledMetricsTest.ReusedConnection (70 ms) [07:41:24:INFO] [ RUN ] CronetEnabledMetricsTest.SessionWithoutDelegate [07:41:24:INFO] [0315/194124.655720:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:41:24:INFO] [ OK ] CronetEnabledMetricsTest.SessionWithoutDelegate (65 ms) [07:41:24:INFO] [ RUN ] CronetEnabledMetricsTest.LegacyApi [07:41:24:INFO] [0315/194124.719095:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:41:24:INFO] [ OK ] CronetEnabledMetricsTest.LegacyApi (62 ms) [07:41:24:INFO] [----------] 8 tests from CronetEnabledMetricsTest (359 ms total) [07:41:24:INFO] [07:41:24:INFO] [----------] 1 test from CronetDisabledMetricsTest [07:41:24:INFO] [ RUN ] CronetDisabledMetricsTest.MetricsDisabled [07:41:24:INFO] [0315/194124.780243:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:41:24:INFO] [ OK ] CronetDisabledMetricsTest.MetricsDisabled (60 ms) [07:41:24:INFO] [----------] 1 test from CronetDisabledMetricsTest (62 ms total) [07:41:24:INFO] [07:41:24:INFO] [----------] 7 tests from NetLogTest [07:41:24:INFO] [ RUN ] NetLogTest.OpenFile [07:41:24:INFO] [0315/194124.786988:WARNING:cronet_environment.mm(163)] Starting NetLog to /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/2F34655F-9A6A-4B38-94FC-1FCF61CBFD93/data/Containers/Data/Application/2B44146C-3DDD-4C60-B998-37F0C801EFB8/Documents/cronet_netlog.json [07:41:24:INFO] [0315/194124.797667:WARNING:cronet_environment.mm(186)] Started NetLog [07:41:24:INFO] [0315/194124.797883:WARNING:cronet_environment.mm(202)] Stopped NetLog. [07:41:24:INFO] [0315/194124.803463:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:41:24:INFO] [ OK ] NetLogTest.OpenFile (19 ms) [07:41:24:INFO] [ RUN ] NetLogTest.CreateFile [07:41:24:INFO] [0315/194124.806957:WARNING:cronet_environment.mm(163)] Starting NetLog to /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/2F34655F-9A6A-4B38-94FC-1FCF61CBFD93/data/Containers/Data/Application/2B44146C-3DDD-4C60-B998-37F0C801EFB8/Documents/195DE78D-6AE5-42F7-AB0D-44E150842724-93509-000013D34A6D65F4_netlog.json [07:41:24:INFO] [0315/194124.818154:WARNING:cronet_environment.mm(186)] Started NetLog [07:41:24:INFO] [0315/194124.818412:WARNING:cronet_environment.mm(202)] Stopped NetLog. [07:41:24:INFO] [0315/194124.824502:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:41:24:INFO] [ OK ] NetLogTest.CreateFile (20 ms) [07:41:24:INFO] [ RUN ] NetLogTest.NonExistantDir [07:41:24:INFO] [0315/194124.827917:ERROR:cronet_environment.mm(158)] Can not start NetLog to /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/2F34655F-9A6A-4B38-94FC-1FCF61CBFD93/data/Containers/Data/Application/2B44146C-3DDD-4C60-B998-37F0C801EFB8/Documents/0EF9589D-B593-423B-95A1-475E367443CF-93509-000013D34BAF0FD9/netlog.json: Undefined error: 0 [07:41:24:INFO] [0315/194124.829661:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:41:24:INFO] [ OK ] NetLogTest.NonExistantDir (4 ms) [07:41:24:INFO] [ RUN ] NetLogTest.ExistantDir [07:41:24:INFO] [0315/194124.833359:WARNING:cronet_environment.mm(163)] Starting NetLog to /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/2F34655F-9A6A-4B38-94FC-1FCF61CBFD93/data/Containers/Data/Application/2B44146C-3DDD-4C60-B998-37F0C801EFB8/Documents/162EC74C-DAEA-4911-9A6A-3DBD83466851-93509-000013D34BFC25F4/netlog.json [07:41:24:INFO] [0315/194124.851193:WARNING:cronet_environment.mm(186)] Started NetLog [07:41:24:INFO] [0315/194124.851390:WARNING:cronet_environment.mm(202)] Stopped NetLog. [07:41:24:INFO] [0315/194124.857776:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:41:24:INFO] [ OK ] NetLogTest.ExistantDir (26 ms) [07:41:24:INFO] [ RUN ] NetLogTest.EmptyFilename [07:41:24:INFO] [0315/194124.862894:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:41:24:INFO] [ OK ] NetLogTest.EmptyFilename (4 ms) [07:41:24:INFO] [ RUN ] NetLogTest.AbsoluteFilename [07:41:24:INFO] [0315/194124.867792:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:41:24:INFO] [ OK ] NetLogTest.AbsoluteFilename (3 ms) [07:41:24:INFO] [ RUN ] NetLogTest.ExperimentalOptions [07:41:24:INFO] [0315/194124.872802:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:41:24:INFO] [0315/194124.875605:WARNING:cronet_environment.mm(163)] Starting NetLog to /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/2F34655F-9A6A-4B38-94FC-1FCF61CBFD93/data/Containers/Data/Application/2B44146C-3DDD-4C60-B998-37F0C801EFB8/Documents/cronet_netlog.json [07:41:24:INFO] [0315/194124.886010:WARNING:cronet_environment.mm(186)] Started NetLog [07:41:24:INFO] [0315/194124.886201:WARNING:cronet_environment.mm(202)] Stopped NetLog. [07:41:24:INFO] [0315/194124.892727:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:41:24:INFO] [ OK ] NetLogTest.ExperimentalOptions (23 ms) [07:41:24:INFO] [----------] 7 tests from NetLogTest (110 ms total) [07:41:24:INFO] [07:41:24:INFO] [----------] 12 tests from PkpTest [07:41:24:INFO] [ RUN ] PkpTest.TestSuccessIfPinSetForDifferentHost [07:41:24:INFO] [0315/194124.957937:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:41:24:INFO] [ OK ] PkpTest.TestSuccessIfPinSetForDifferentHost (65 ms) [07:41:24:INFO] [ RUN ] PkpTest.TestErrorIfPinDoesNotMatch [07:41:24:INFO] [0315/194124.990005:WARNING:proof_verifier_chromium.cc(501)] Failed to verify certificate chain: net::ERR_SSL_PINNED_KEY_NOT_IN_CERT_CHAIN [07:41:24:INFO] [0315/194124.991044:ERROR:crn_http_protocol_handler.mm(845)] HttpProtocolHandlerCore - Network error: net::ERR_QUIC_PROTOCOL_ERROR (-356) [07:41:24:INFO] [0315/194124.998744:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:41:25:INFO] [ OK ] PkpTest.TestErrorIfPinDoesNotMatch (39 ms) [07:41:25:INFO] [ RUN ] PkpTest.TestSuccessIfPinMatches [07:41:25:INFO] [0315/194125.058303:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:41:25:INFO] [ OK ] PkpTest.TestSuccessIfPinMatches (58 ms) [07:41:25:INFO] [ RUN ] PkpTest.TestBypass [07:41:25:INFO] [ OK ] PkpTest.TestBypass (10 ms) [07:41:25:INFO] [ RUN ] PkpTest.TestIncludeSubdomainsFlagEqualTrue [07:41:25:INFO] [0315/194125.100754:WARNING:proof_verifier_chromium.cc(501)] Failed to verify certificate chain: net::ERR_SSL_PINNED_KEY_NOT_IN_CERT_CHAIN [07:41:25:INFO] [0315/194125.102690:ERROR:crn_http_protocol_handler.mm(845)] HttpProtocolHandlerCore - Network error: net::ERR_CONNECTION_REFUSED (-102) [07:41:25:INFO] [0315/194125.111028:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:41:25:INFO] [ OK ] PkpTest.TestIncludeSubdomainsFlagEqualTrue (41 ms) [07:41:25:INFO] [ RUN ] PkpTest.TestIncludeSubdomainsFlagEqualFalse [07:41:25:INFO] [0315/194125.167994:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:41:25:INFO] [ OK ] PkpTest.TestIncludeSubdomainsFlagEqualFalse (56 ms) [07:41:25:INFO] [ RUN ] PkpTest.TestSoonExpiringPin [07:41:25:INFO] [0315/194125.202300:WARNING:proof_verifier_chromium.cc(501)] Failed to verify certificate chain: net::ERR_SSL_PINNED_KEY_NOT_IN_CERT_CHAIN [07:41:25:INFO] [0315/194125.204242:ERROR:crn_http_protocol_handler.mm(845)] HttpProtocolHandlerCore - Network error: net::ERR_CONNECTION_REFUSED (-102) [07:41:25:INFO] [0315/194125.210340:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:41:25:INFO] [ OK ] PkpTest.TestSoonExpiringPin (41 ms) [07:41:25:INFO] [ RUN ] PkpTest.TestRecentlyExpiredPin [07:41:25:INFO] [0315/194125.274123:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:41:25:INFO] [ OK ] PkpTest.TestRecentlyExpiredPin (63 ms) [07:41:25:INFO] [ RUN ] PkpTest.TestPinsAreNotPersisted [07:41:25:INFO] [0315/194125.308844:WARNING:proof_verifier_chromium.cc(501)] Failed to verify certificate chain: net::ERR_SSL_PINNED_KEY_NOT_IN_CERT_CHAIN [07:41:25:INFO] [0315/194125.311224:ERROR:crn_http_protocol_handler.mm(845)] HttpProtocolHandlerCore - Network error: net::ERR_CONNECTION_REFUSED (-102) [07:41:25:INFO] [0315/194125.317326:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:41:25:INFO] [0315/194125.359078:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:41:25:INFO] [ OK ] PkpTest.TestPinsAreNotPersisted (84 ms) [07:41:25:INFO] [ RUN ] PkpTest.TestHashLengthError [07:41:25:INFO] [ OK ] PkpTest.TestHashLengthError (9 ms) [07:41:25:INFO] [ RUN ] PkpTest.TestPkpOverrideNonMatchingToMatching [07:41:25:INFO] [0315/194125.435393:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:41:25:INFO] [ OK ] PkpTest.TestPkpOverrideNonMatchingToMatching (65 ms) [07:41:25:INFO] [ RUN ] PkpTest.TestPkpOverrideMatchingToNonMatching [07:41:25:INFO] [0315/194125.468877:WARNING:proof_verifier_chromium.cc(501)] Failed to verify certificate chain: net::ERR_SSL_PINNED_KEY_NOT_IN_CERT_CHAIN [07:41:25:INFO] [0315/194125.469545:ERROR:crn_http_protocol_handler.mm(845)] HttpProtocolHandlerCore - Network error: net::ERR_QUIC_PROTOCOL_ERROR (-356) [07:41:25:INFO] [0315/194125.475476:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:41:25:INFO] [ OK ] PkpTest.TestPkpOverrideMatchingToNonMatching (39 ms) [07:41:25:INFO] [----------] 12 tests from PkpTest (583 ms total) [07:41:25:INFO] [07:41:25:INFO] [----------] 1 test from PrefsTest [07:41:25:INFO] [ RUN ] PrefsTest.HttpSeverProperties [07:41:25:INFO] 2019-03-15 19:41:25.527 cronet_test[93509:1568261] Waiting for file /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/2F34655F-9A6A-4B38-94FC-1FCF61CBFD93/data/Containers/Data/Application/2B44146C-3DDD-4C60-B998-37F0C801EFB8/Library/Caches/cronet/prefs/local_prefs.json. [07:41:25:INFO] 2019-03-15 19:41:25.527 cronet_test[93509:1568261] Time left: 80 seconds [07:41:30:INFO] 2019-03-15 19:41:30.529 cronet_test[93509:1568261] Time left: 75 seconds [07:41:35:INFO] 2019-03-15 19:41:35.530 cronet_test[93509:1568261] Time left: 70 seconds [07:41:40:INFO] 2019-03-15 19:41:40.533 cronet_test[93509:1568261] Time left: 65 seconds [07:41:45:INFO] 2019-03-15 19:41:45.535 cronet_test[93509:1568261] Time left: 60 seconds [07:41:50:INFO] 2019-03-15 19:41:50.537 cronet_test[93509:1568261] Time left: 55 seconds [07:41:55:INFO] 2019-03-15 19:41:55.540 cronet_test[93509:1568261] Time left: 50 seconds [07:42:00:INFO] 2019-03-15 19:42:00.542 cronet_test[93509:1568261] Time left: 45 seconds [07:42:05:INFO] 2019-03-15 19:42:05.544 cronet_test[93509:1568261] Time left: 40 seconds [07:42:10:INFO] 2019-03-15 19:42:10.547 cronet_test[93509:1568261] Time left: 35 seconds [07:42:15:INFO] 2019-03-15 19:42:15.547 cronet_test[93509:1568261] Time left: 30 seconds [07:42:20:INFO] 2019-03-15 19:42:20.549 cronet_test[93509:1568261] Time left: 25 seconds [07:42:25:INFO] 2019-03-15 19:42:25.552 cronet_test[93509:1568261] Time left: 20 seconds [07:42:30:INFO] 2019-03-15 19:42:30.554 cronet_test[93509:1568261] Time left: 15 seconds [07:42:35:INFO] 2019-03-15 19:42:35.556 cronet_test[93509:1568261] File /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/2F34655F-9A6A-4B38-94FC-1FCF61CBFD93/data/Containers/Data/Application/2B44146C-3DDD-4C60-B998-37F0C801EFB8/Library/Caches/cronet/prefs/local_prefs.json exists. [07:42:35:INFO] [0315/194235.566958:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:42:35:INFO] [ OK ] PrefsTest.HttpSeverProperties (70091 ms) [07:42:35:INFO] [----------] 1 test from PrefsTest (70093 ms total) [07:42:35:INFO] [07:42:35:INFO] [----------] 3 tests from QuicTest [07:42:35:INFO] [ RUN ] QuicTest.InvalidQuicHost [07:42:35:INFO] [0315/194235.580938:ERROR:Cronet.mm(218)] Invalid QUIC hint host: https://test.example.com/ [07:42:35:INFO] [ OK ] QuicTest.InvalidQuicHost (12 ms) [07:42:35:INFO] [ RUN ] QuicTest.ValidQuicHost [07:42:35:INFO] [ OK ] QuicTest.ValidQuicHost (12 ms) [07:42:35:INFO] [ RUN ] QuicTest.RequestWithSocketOptimizationEnabled [07:42:35:INFO] [0315/194235.678606:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:42:35:INFO] [ OK ] QuicTest.RequestWithSocketOptimizationEnabled (82 ms) [07:42:35:INFO] [----------] 3 tests from QuicTest (111 ms total) [07:42:35:INFO] [07:42:35:INFO] [----------] 32 tests from BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest [07:42:35:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.StartExampleBidiStream/0 [07:42:35:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.StartExampleBidiStream/0 (71 ms) [07:42:35:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.StartExampleBidiStream/1 [07:42:35:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.StartExampleBidiStream/1 (66 ms) [07:42:35:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.SimplePutWithEmptyWriteDataAtTheEnd/0 [07:42:35:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.SimplePutWithEmptyWriteDataAtTheEnd/0 (63 ms) [07:42:35:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.SimplePutWithEmptyWriteDataAtTheEnd/1 [07:42:35:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.SimplePutWithEmptyWriteDataAtTheEnd/1 (67 ms) [07:42:35:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.SimpleGetWithFlush/0 [07:42:36:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.SimpleGetWithFlush/0 (61 ms) [07:42:36:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.SimpleGetWithFlush/1 [07:42:36:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.SimpleGetWithFlush/1 (49 ms) [07:42:36:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.SimplePostWithFlush/0 [07:42:36:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.SimplePostWithFlush/0 (55 ms) [07:42:36:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.SimplePostWithFlush/1 [07:42:36:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.SimplePostWithFlush/1 (49 ms) [07:42:36:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.SimplePostWithFlushTwice/0 [07:42:36:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.SimplePostWithFlushTwice/0 (53 ms) [07:42:36:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.SimplePostWithFlushTwice/1 [07:42:36:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.SimplePostWithFlushTwice/1 (57 ms) [07:42:36:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.SimplePostWithFlushAfterOneWrite/0 [07:42:36:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.SimplePostWithFlushAfterOneWrite/0 (59 ms) [07:42:36:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.SimplePostWithFlushAfterOneWrite/1 [07:42:36:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.SimplePostWithFlushAfterOneWrite/1 (66 ms) [07:42:36:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.TestDelayedFlush/0 [07:42:36:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.TestDelayedFlush/0 (61 ms) [07:42:36:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.TestDelayedFlush/1 [07:42:36:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.TestDelayedFlush/1 (69 ms) [07:42:36:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.CancelOnRead/0 [07:42:36:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.CancelOnRead/0 (52 ms) [07:42:36:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.CancelOnRead/1 [07:42:36:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.CancelOnRead/1 (61 ms) [07:42:36:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.CancelOnResponse/0 [07:42:36:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.CancelOnResponse/0 (59 ms) [07:42:36:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.CancelOnResponse/1 [07:42:36:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.CancelOnResponse/1 (69 ms) [07:42:36:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.CancelOnSucceeded/0 [07:42:36:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.CancelOnSucceeded/0 (66 ms) [07:42:36:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.CancelOnSucceeded/1 [07:42:36:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.CancelOnSucceeded/1 (69 ms) [07:42:36:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.ReadFailsBeforeRequestStarted/0 [07:42:36:INFO] [0315/194236.943786:ERROR:bidirectional_stream.cc(286)] Unexpected Read Data in read_state 0 [07:42:36:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.ReadFailsBeforeRequestStarted/0 (16 ms) [07:42:36:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.ReadFailsBeforeRequestStarted/1 [07:42:36:INFO] [0315/194236.958508:ERROR:bidirectional_stream.cc(286)] Unexpected Read Data in read_state 0 [07:42:36:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.ReadFailsBeforeRequestStarted/1 (13 ms) [07:42:36:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.StreamFailBeforeReadIsExecutedOnNetworkThread/0 [07:42:37:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.StreamFailBeforeReadIsExecutedOnNetworkThread/0 (71 ms) [07:42:37:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.StreamFailBeforeReadIsExecutedOnNetworkThread/1 [07:42:37:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.StreamFailBeforeReadIsExecutedOnNetworkThread/1 (61 ms) [07:42:37:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.WriteFailsBeforeRequestStarted/0 [07:42:37:INFO] [0315/194237.110928:ERROR:bidirectional_stream.cc(315)] Unexpected Flush Data in write_state 0 [07:42:37:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.WriteFailsBeforeRequestStarted/0 (16 ms) [07:42:37:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.WriteFailsBeforeRequestStarted/1 [07:42:37:INFO] [0315/194237.125561:ERROR:bidirectional_stream.cc(315)] Unexpected Flush Data in write_state 0 [07:42:37:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.WriteFailsBeforeRequestStarted/1 (14 ms) [07:42:37:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.StreamFailAfterStreamReadyCallback/0 [07:42:37:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.StreamFailAfterStreamReadyCallback/0 (65 ms) [07:42:37:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.StreamFailAfterStreamReadyCallback/1 [07:42:37:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.StreamFailAfterStreamReadyCallback/1 (53 ms) [07:42:37:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.StreamFailBeforeWriteIsExecutedOnNetworkThread/0 [07:42:37:INFO] [0315/194237.298649:ERROR:bidirectional_stream_quic_impl.cc(175)] Trying to send data after stream has been closed. [07:42:37:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.StreamFailBeforeWriteIsExecutedOnNetworkThread/0 (50 ms) [07:42:37:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.StreamFailBeforeWriteIsExecutedOnNetworkThread/1 [07:42:37:INFO] [0315/194237.350835:ERROR:bidirectional_stream_quic_impl.cc(175)] Trying to send data after stream has been closed. [07:42:37:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.StreamFailBeforeWriteIsExecutedOnNetworkThread/1 (52 ms) [07:42:37:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.FailedResolution/0 [07:42:37:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.FailedResolution/0 (17 ms) [07:42:37:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.FailedResolution/1 [07:42:37:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.FailedResolution/1 (17 ms) [07:42:37:INFO] [----------] 32 tests from BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest (1713 ms total) [07:42:37:INFO] [07:42:37:INFO] [----------] 5 tests from Loops/PerfTest [07:42:37:INFO] [ RUN ] Loops/PerfTest.NSURLSessionReceivesImageLoop/0 [07:42:37:INFO] [93509:771:0315/194237.408656:21870813117584:INFO:cronet_performance_test.mm(181)] Running with parameters: QUIC: 0 HTTP2: 0 AKD4: 0 Cronet: 0 [07:42:37:INFO] [93509:771:0315/194237.408918:21870813356222:INFO:cronet_performance_test.mm(191)] Downloading 19307439 bytes per iteration [07:42:38:INFO] [93509:771:0315/194238.539556:21871944052996:INFO:cronet_performance_test.mm(253)] Elapsed Total:1107.58ms [07:42:38:INFO] [0315/194238.546492:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:42:38:INFO] [ OK ] Loops/PerfTest.NSURLSessionReceivesImageLoop/0 (1154 ms) [07:42:38:INFO] [ RUN ] Loops/PerfTest.NSURLSessionReceivesImageLoop/1 [07:42:38:INFO] [93509:771:0315/194238.566961:21871971472638:INFO:cronet_performance_test.mm(181)] Running with parameters: QUIC: 0 HTTP2: 0 AKD4: 0 Cronet: 1 [07:42:38:INFO] [93509:771:0315/194238.567216:21871971719760:INFO:cronet_performance_test.mm(191)] Downloading 19307439 bytes per iteration [07:42:39:INFO] [93509:771:0315/194239.490043:21872894605131:INFO:cronet_performance_test.mm(253)] Elapsed Total:899.975ms [07:42:39:INFO] [0315/194239.495180:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:42:39:INFO] [ OK ] Loops/PerfTest.NSURLSessionReceivesImageLoop/1 (947 ms) [07:42:39:INFO] [ RUN ] Loops/PerfTest.NSURLSessionReceivesImageLoop/2 [07:42:39:INFO] [93509:771:0315/194239.513827:21872918352482:INFO:cronet_performance_test.mm(181)] Running with parameters: QUIC: 0 HTTP2: 1 AKD4: 0 Cronet: 1 [07:42:39:INFO] [93509:771:0315/194239.514038:21872918562986:INFO:cronet_performance_test.mm(191)] Downloading 19307439 bytes per iteration [07:42:40:INFO] [93509:771:0315/194240.341004:21873745566189:INFO:cronet_performance_test.mm(253)] Elapsed Total:806.883ms [07:42:40:INFO] [0315/194240.346699:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:42:40:INFO] [ OK ] Loops/PerfTest.NSURLSessionReceivesImageLoop/2 (850 ms) [07:42:40:INFO] [ RUN ] Loops/PerfTest.NSURLSessionReceivesImageLoop/3 [07:42:40:INFO] [93509:771:0315/194240.361768:21873766340643:INFO:cronet_performance_test.mm(181)] Running with parameters: QUIC: 1 HTTP2: 0 AKD4: 0 Cronet: 1 [07:42:40:INFO] [93509:771:0315/194240.361977:21873766522687:INFO:cronet_performance_test.mm(191)] Downloading 19307439 bytes per iteration [07:42:41:INFO] [93509:771:0315/194241.319604:21874724246183:INFO:cronet_performance_test.mm(253)] Elapsed Total:939.128ms [07:42:41:INFO] [0315/194241.325904:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:42:41:INFO] [ OK ] Loops/PerfTest.NSURLSessionReceivesImageLoop/3 (978 ms) [07:42:41:INFO] [ RUN ] Loops/PerfTest.NSURLSessionReceivesImageLoop/4 [07:42:41:INFO] [93509:771:0315/194241.345803:21874750442591:INFO:cronet_performance_test.mm(181)] Running with parameters: QUIC: 1 HTTP2: 0 AKD4: 1 Cronet: 1 [07:42:41:INFO] [93509:771:0315/194241.346040:21874750642146:INFO:cronet_performance_test.mm(191)] Downloading 19307439 bytes per iteration [07:42:42:INFO] [93509:771:0315/194242.279300:21875684008921:INFO:cronet_performance_test.mm(253)] Elapsed Total:910.899ms [07:42:42:INFO] [0315/194242.286160:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:42:42:INFO] [ OK ] Loops/PerfTest.NSURLSessionReceivesImageLoop/4 (959 ms) [07:42:42:INFO] [93509:771:0315/194242.289426:21875694099137:INFO:cronet_performance_test.mm(71)] Performance Data: [07:42:42:INFO] [93509:771:0315/194242.289675:21875694334713:INFO:cronet_performance_test.mm(88)] Quic 0 Http2 0 AKD4 0 Cronet 0: Mean: 0.110758s (1394.563558mbps) Max: 0.130252s with 0 fails out of 10 total requests. [07:42:42:INFO] [93509:771:0315/194242.289909:21875694570292:INFO:cronet_performance_test.mm(88)] Quic 0 Http2 0 AKD4 0 Cronet 1: Mean: 0.089998s (1716.264130mbps) Max: 0.104132s with 0 fails out of 10 total requests. [07:42:42:INFO] [93509:771:0315/194242.290141:21875694804214:INFO:cronet_performance_test.mm(88)] Quic 0 Http2 1 AKD4 0 Cronet 1: Mean: 0.080688s (1914.275150mbps) Max: 0.099029s with 0 fails out of 10 total requests. [07:42:42:INFO] [93509:771:0315/194242.290372:21875695030179:INFO:cronet_performance_test.mm(88)] Quic 1 Http2 0 AKD4 0 Cronet 1: Mean: 0.093913s (1644.711958mbps) Max: 0.107732s with 0 fails out of 10 total requests. [07:42:42:INFO] [93509:771:0315/194242.290606:21875695264044:INFO:cronet_performance_test.mm(88)] Quic 1 Http2 0 AKD4 1 Cronet 1: Mean: 0.091090s (1695.682232mbps) Max: 0.111110s with 0 fails out of 10 total requests. [07:42:42:INFO] [----------] 5 tests from Loops/PerfTest (4895 ms total) [07:42:42:INFO] [07:42:42:INFO] [----------] Global test environment tear-down [07:42:42:INFO] [==========] 166 tests from 14 test suites ran. (84199 ms total) [07:42:42:INFO] [ PASSED ] 166 tests. [07:42:42:INFO] [07:42:42:INFO] YOU HAVE 2 DISABLED TESTS [07:42:42:INFO] [07:42:56:INFO] Testing started on 'iPhone X' [07:42:56:INFO] 2019-03-15 19:40:44.509 xcodebuild[93384:1566620] IDETestOperationsObserverDebug: Writing diagnostic log for test session to: [07:42:56:INFO] /Users/chrome-bot/Library/Developer/Xcode/DerivedData/temporary-hezovqquerdfgycfburoxzduqnbg/Logs/Test/Test-Transient Testing-2019.03.15_19-40-43--0700.xcresult/1_Test/Diagnostics/cronet_test-2B4E6F06-E8BF-44AB-85FA-CF706B530CC5/cronet_test-9573D42B-533A-4151-B631-C6E2EF175871/Session-cronet_test-2019-03-15_194044-27Naqm.log [07:42:56:INFO] 2019-03-15 19:40:44.509 xcodebuild[93384:1566610] [MT] IDETestOperationsObserverDebug: (246593F9-52D0-449C-AB92-54591D5C3E8A) Beginning test session cronet_test-246593F9-52D0-449C-AB92-54591D5C3E8A at 2019-03-15 19:40:44.509 with Xcode 10B61 on target <DVTiPhoneSimulator: 0x7fae42090c70> { [07:42:56:INFO] SimDevice: iPhone X (2F34655F-9A6A-4B38-94FC-1FCF61CBFD93, iOS 11.4, Shutdown) [07:42:56:INFO] } (11.4 (15F79)) [07:42:56:INFO] 2019-03-15 19:42:52.002 xcodebuild[93384:1566610] [MT] IDETestOperationsObserverDebug: 128.212 elapsed -- Testing started completed. [07:42:56:INFO] 2019-03-15 19:42:52.002 xcodebuild[93384:1566610] [MT] IDETestOperationsObserverDebug: 0.000 sec, +0.000 sec -- start [07:42:56:INFO] 2019-03-15 19:42:52.002 xcodebuild[93384:1566610] [MT] IDETestOperationsObserverDebug: 128.212 sec, +128.212 sec -- end [07:42:56:INFO] [07:42:56:INFO] Testing failed: [07:42:56:INFO] cronet_test.app (93509) encountered an error (Early unexpected exit, operation never finished bootstrapping - no restart will be attempted. (Underlying error: Test runner exited before starting test execution.)) [07:42:56:DEBUG] Finished print_process_output. [07:42:56:INFO] Waiting for test process to terminate. [07:42:56:INFO] Test process terminated. [07:42:56:DEBUG] Setting sigterm handler. [07:42:56:DEBUG] Stdout flushed after test process. [07:42:56:DEBUG] Processing test results. [07:42:56:INFO] /b/s/w/ir/src/out/Debug-iphonesimulator/iossim returned 65 [07:42:56:DEBUG] Extracting test data. [07:42:56:DEBUG] Retrieving crash reports. [07:42:56:DEBUG] Retrieving derived data. [07:42:56:DEBUG] Making desktop screenshots. [07:42:56:DEBUG] Killing simulators. [07:43:01:DEBUG] Wiping simulator. Device wiped. [07:43:05:DEBUG] End of tear_down. 2019-03-15 19:44:07,862 - root: [DEBUG] Contents of task_output_dir: ['/b/s/w/ir/k/recipe_cleanup/5n90Rtl/439d308f51157510'] 2019-03-15 19:44:07,863 - root: [WARNING] Expected output.json file missing: set(['/b/s/w/ir/k/recipe_cleanup/5n90Rtl/439d308f51157510/output.json']) Found: [] Expected: ['/b/s/w/ir/k/recipe_cleanup/5n90Rtl/439d308f51157510/output.json'] 2019-03-15 19:44:07,863 - root: [WARNING] No shard json files found in task_output_dir: '/b/s/w/ir/k/recipe_cleanup/5n90Rtl' Found ['/b/s/w/ir/k/recipe_cleanup/5n90Rtl/439d308f51157510'] 2019-03-15 19:44:07,863 - root: [DEBUG] Found shard_json_files: ['/b/s/w/ir/k/recipe_cleanup/5n90Rtl/439d308f51157510/output.json'] 2019-03-15 19:44:07,863 - 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/tmpN6LLZ7.json --task-output-dir /b/s/w/ir/k/recipe_cleanup/5n90Rtl -o /b/s/w/ir/tmp/t/tmpYvQohE.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/tmpN6LLZ7.json', '--task-output-dir', '/b/s/w/ir/k/recipe_cleanup/5n90Rtl', '-o', '/b/s/w/ir/tmp/t/tmpYvQohE.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/tmpN6LLZ7.json', '--task-output-dir', '/b/s/w/ir/k/recipe_cleanup/5n90Rtl', '-o', '/b/s/w/ir/tmp/t/tmpYvQohE.json'] returned exit code 0