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/tmpcR1Bb9.json --task-output-dir /b/s/w/ir/k/recipe_cleanup/5vFxkkL --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/tmpz1MZaQmerge_script_log --merge-additional-args '[]' --summary-json-file /b/s/w/ir/tmp/t/tmpXJ_TP0.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/tmpuakVcD.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/tmpcR1Bb9.json', '--task-output-dir', '/b/s/w/ir/k/recipe_cleanup/5vFxkkL', '--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/tmpz1MZaQmerge_script_log', '--merge-additional-args', '[]', '--summary-json-file', '/b/s/w/ir/tmp/t/tmpXJ_TP0.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/tmpuakVcD.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.nV5Rv9eXMw/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: 54390 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/8918560704723203264 LOGDOG_STREAM_PROJECT: chromium LOGDOG_STREAM_SERVER_PATH: unix:/b/s/w/ir/tmp/ld.sock LOGNAME: chrome-bot LUCI_CONTEXT: /b/s/w/itoT7F1A/luci_context.217473954 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.FKooqqAtlD/Listeners SWARMING_BOT_ID: build212-m9 SWARMING_HEADLESS: 1 SWARMING_SERVER: https://chromium-swarm.appspot.com SWARMING_TASK_ID: 43ae84ab3f8cf811 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 04:33:00,244 - root: [DEBUG] Using task_output_dir: '/b/s/w/ir/k/recipe_cleanup/5vFxkkL' 2019-03-19 04:33:00,244 - root: [WARNING] task_output_dir '/b/s/w/ir/k/recipe_cleanup/5vFxkkL' already exists! 2019-03-19 04:33:00,244 - root: [WARNING] task_output_dir existing content: [] 2019-03-19 04:33:00,244 - 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/tmpuakVcD.json -output-dir /b/s/w/ir/k/recipe_cleanup/5vFxkkL -task-summary-json /b/s/w/ir/tmp/t/tmpXJ_TP0.json [D2019-03-19T04:33:00.259215-07:00 52917 0 auth.go:1265] Minting a new token {"key":"luci_ctx/09d7614de2c4c7083c78a992a8deff56a5310d279257e9f3b4593ec913718288", "scopes":"https://www.googleapis.com/auth/userinfo.email"} [D2019-03-19T04:33:00.259284-07:00 52917 0 luci_ctx.go:138] POST http://127.0.0.1:54379/rpc/LuciLocalAuthService.GetOAuthToken {"key":"luci_ctx/09d7614de2c4c7083c78a992a8deff56a5310d279257e9f3b4593ec913718288", "scopes":"https://www.googleapis.com/auth/userinfo.email"} [D2019-03-19T04:33:00.260979-07:00 52917 0 auth.go:1222] Token expires in 49m18.739061s {"key":"luci_ctx/09d7614de2c4c7083c78a992a8deff56a5310d279257e9f3b4593ec913718288", "scopes":"https://www.googleapis.com/auth/userinfo.email"} [D2019-03-19T04:33:01.757221-07:00 52917 0 collect.go:344] Waiting task_id: 43ae880a2769d510 43ae880a2769d510: exit 0 [I 2019-03-19 04:29:35] About to install Xcode 10b61 in Xcode.app for ios [04:29:37:INFO] Using Xcode version 10.1 build 10B61 at /b/s/w/ir/Xcode.app/Contents/Developer Device wiped. [04:29:46:DEBUG] Setting sigterm handler. [04:29:48:INFO] User defaults from command line: [04:29:48:INFO] IDETestRunSpecificationPath = /var/folders/2j/22s2gz0s7hn48k32d47clxf80000gm/T/E69C6E3A-AE3E-419B-8BB0-DB78F078A252 [04:29:48:INFO] [04:30:18:INFO] objc[76596]: Class CRBProtocolObservers is implemented in both /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/D3A050AD-AB4E-4F9A-B6D5-637C0E890240/data/Containers/Bundle/Application/7AA697C6-A6C6-4538-9FF6-3E597F9FBB0E/cronet_test.app/Frameworks/Cronet.framework/Cronet (0x10c3b2b90) and /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/D3A050AD-AB4E-4F9A-B6D5-637C0E890240/data/Containers/Bundle/Application/7AA697C6-A6C6-4538-9FF6-3E597F9FBB0E/cronet_test.app/cronet_test (0x1020736d8). One of the two will be used. Which one is undefined. [04:30:18:INFO] objc[76596]: Class CRBWeakNSProtocolSentinel is implemented in both /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/D3A050AD-AB4E-4F9A-B6D5-637C0E890240/data/Containers/Bundle/Application/7AA697C6-A6C6-4538-9FF6-3E597F9FBB0E/cronet_test.app/Frameworks/Cronet.framework/Cronet (0x10c3b2be0) and /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/D3A050AD-AB4E-4F9A-B6D5-637C0E890240/data/Containers/Bundle/Application/7AA697C6-A6C6-4538-9FF6-3E597F9FBB0E/cronet_test.app/cronet_test (0x102073728). One of the two will be used. Which one is undefined. [04:30:18:INFO] 2019-03-19 04:30:18.394 cronet_test[76596:1275442] +[CATransaction synchronize] called within transaction [04:30:18:INFO] [==========] Running 166 tests from 14 test suites. [04:30:18:INFO] [----------] Global test environment set-up. [04:30:18:INFO] [----------] 4 tests from BufferTest [04:30:18:INFO] [ RUN ] BufferTest.TestInitWithAlloc [04:30:18:INFO] [ OK ] BufferTest.TestInitWithAlloc (16 ms) [04:30:18:INFO] [ RUN ] BufferTest.TestInitWithHugeAllocFails [04:30:18:INFO] [ OK ] BufferTest.TestInitWithHugeAllocFails (0 ms) [04:30:18:INFO] [ RUN ] BufferTest.TestInitWithDataAndCallback [04:30:18:INFO] [ OK ] BufferTest.TestInitWithDataAndCallback (0 ms) [04:30:18:INFO] [ RUN ] BufferTest.TestCronetBufferAsync [04:30:18:INFO] [ OK ] BufferTest.TestCronetBufferAsync (2 ms) [04:30:18:INFO] [----------] 4 tests from BufferTest (25 ms total) [04:30:18:INFO] [04:30:18:INFO] [----------] 8 tests from EngineTest [04:30:18:INFO] [ RUN ] EngineTest.StartCronetEngine [04:30:18:INFO] [ OK ] EngineTest.StartCronetEngine (119 ms) [04:30:18:INFO] [ RUN ] EngineTest.CronetEngineDefaultUserAgent [04:30:18:INFO] [ OK ] EngineTest.CronetEngineDefaultUserAgent (1 ms) [04:30:18:INFO] [ RUN ] EngineTest.InitDifferentEngines [04:30:18:INFO] [ OK ] EngineTest.InitDifferentEngines (7 ms) [04:30:18:INFO] [ RUN ] EngineTest.StartResults [04:30:18:INFO] [0319/043018.945088:ERROR:engine.cc(135)] Disk cache path /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/D3A050AD-AB4E-4F9A-B6D5-637C0E890240/data/Containers/Data/Application/017F853D-C6AB-4DFE-A911-3E1379D50663/tmp/.org.chromium.Chromium.7qj2zU is already used, cache disabled. [04:30:19:INFO] [ OK ] EngineTest.StartResults (150 ms) [04:30:19:INFO] [ RUN ] EngineTest.InvalidPkpParams [04:30:19:INFO] [ OK ] EngineTest.InvalidPkpParams (0 ms) [04:30:19:INFO] [ RUN ] EngineTest.ValidPkpParams [04:30:19:INFO] [ OK ] EngineTest.ValidPkpParams (7 ms) [04:30:19:INFO] [ RUN ] EngineTest.SetMockCertVerifierForTesting [04:30:19:INFO] [ OK ] EngineTest.SetMockCertVerifierForTesting (2 ms) [04:30:19:INFO] [ RUN ] EngineTest.StartNetLogToFile [04:30:19:INFO] [0319/043019.152424:ERROR:cronet_url_request_context.cc(536)] Failed to open NetLog file for writing. [04:30:19:INFO] [ OK ] EngineTest.StartNetLogToFile (43 ms) [04:30:19:INFO] [----------] 8 tests from EngineTest (343 ms total) [04:30:19:INFO] [04:30:19:INFO] [----------] 2 tests from ExecutorsTest [04:30:19:INFO] [ RUN ] ExecutorsTest.TestCustom [04:30:19:INFO] [ OK ] ExecutorsTest.TestCustom (1 ms) [04:30:19:INFO] [ RUN ] ExecutorsTest.TestTestExecutor [04:30:19:INFO] [ OK ] ExecutorsTest.TestTestExecutor (1 ms) [04:30:19:INFO] [----------] 2 tests from ExecutorsTest (4 ms total) [04:30:19:INFO] [04:30:19:INFO] [----------] 57 tests from UrlRequestTest [04:30:19:INFO] [ RUN ] UrlRequestTest.UploadWithDirectExecutor [04:30:19:INFO] [ OK ] UrlRequestTest.UploadWithDirectExecutor (67 ms) [04:30:19:INFO] [ RUN ] UrlRequestTest.InitChecks/0 [04:30:19:INFO] [ OK ] UrlRequestTest.InitChecks/0 (6 ms) [04:30:19:INFO] [ RUN ] UrlRequestTest.InitChecks/1 [04:30:19:INFO] [ OK ] UrlRequestTest.InitChecks/1 (6 ms) [04:30:19:INFO] [ RUN ] UrlRequestTest.SimpleGet/0 [04:30:19:INFO] [ OK ] UrlRequestTest.SimpleGet/0 (12 ms) [04:30:19:INFO] [ RUN ] UrlRequestTest.SimpleGet/1 [04:30:19:INFO] [ OK ] UrlRequestTest.SimpleGet/1 (11 ms) [04:30:19:INFO] [ RUN ] UrlRequestTest.UploadEmptyBodySync/0 [04:30:19:INFO] [ OK ] UrlRequestTest.UploadEmptyBodySync/0 (9 ms) [04:30:19:INFO] [ RUN ] UrlRequestTest.UploadEmptyBodySync/1 [04:30:19:INFO] [ OK ] UrlRequestTest.UploadEmptyBodySync/1 (10 ms) [04:30:19:INFO] [ RUN ] UrlRequestTest.UploadSync/0 [04:30:19:INFO] [ OK ] UrlRequestTest.UploadSync/0 (9 ms) [04:30:19:INFO] [ RUN ] UrlRequestTest.UploadSync/1 [04:30:19:INFO] [ OK ] UrlRequestTest.UploadSync/1 (13 ms) [04:30:19:INFO] [ RUN ] UrlRequestTest.SSLCertificateError/0 [04:30:19:INFO] [0319/043019.394503:ERROR:ssl_client_socket_impl.cc(947)] handshake failed; returned -1, SSL error code 1, net_error -207 [04:30:19:INFO] [76596:46143:0319/043019.394908:26117230547037:ERROR:ssl_server_socket_impl.cc(686)] handshake failed; returned -1, SSL error code 1, net_error -117 [04:30:19:INFO] [ OK ] UrlRequestTest.SSLCertificateError/0 (85 ms) [04:30:19:INFO] [ RUN ] UrlRequestTest.SSLCertificateError/1 [04:30:19:INFO] [0319/043019.439298:ERROR:ssl_client_socket_impl.cc(947)] handshake failed; returned -1, SSL error code 1, net_error -207 [04:30:19:INFO] [76596:35371:0319/043019.439505:26117275107433:ERROR:ssl_server_socket_impl.cc(686)] handshake failed; returned -1, SSL error code 1, net_error -117 [04:30:19:INFO] [ OK ] UrlRequestTest.SSLCertificateError/1 (44 ms) [04:30:19:INFO] [ RUN ] UrlRequestTest.UploadMultiplePiecesSync/0 [04:30:19:INFO] [ OK ] UrlRequestTest.UploadMultiplePiecesSync/0 (11 ms) [04:30:19:INFO] [ RUN ] UrlRequestTest.UploadMultiplePiecesSync/1 [04:30:19:INFO] [ OK ] UrlRequestTest.UploadMultiplePiecesSync/1 (11 ms) [04:30:19:INFO] [ RUN ] UrlRequestTest.UploadMultiplePiecesAsync/0 [04:30:19:INFO] [ OK ] UrlRequestTest.UploadMultiplePiecesAsync/0 (10 ms) [04:30:19:INFO] [ RUN ] UrlRequestTest.UploadMultiplePiecesAsync/1 [04:30:19:INFO] [ OK ] UrlRequestTest.UploadMultiplePiecesAsync/1 (13 ms) [04:30:19:INFO] [ RUN ] UrlRequestTest.UploadChangesDefaultMethod/0 [04:30:19:INFO] [ OK ] UrlRequestTest.UploadChangesDefaultMethod/0 (12 ms) [04:30:19:INFO] [ RUN ] UrlRequestTest.UploadChangesDefaultMethod/1 [04:30:19:INFO] [ OK ] UrlRequestTest.UploadChangesDefaultMethod/1 (12 ms) [04:30:19:INFO] [ RUN ] UrlRequestTest.UploadWithSetMethod/0 [04:30:19:INFO] [ OK ] UrlRequestTest.UploadWithSetMethod/0 (10 ms) [04:30:19:INFO] [ RUN ] UrlRequestTest.UploadWithSetMethod/1 [04:30:19:INFO] [ OK ] UrlRequestTest.UploadWithSetMethod/1 (12 ms) [04:30:19:INFO] [ RUN ] UrlRequestTest.UploadWithBigRead/0 [04:30:19:INFO] [ OK ] UrlRequestTest.UploadWithBigRead/0 (11 ms) [04:30:19:INFO] [ RUN ] UrlRequestTest.UploadWithBigRead/1 [04:30:19:INFO] [ OK ] UrlRequestTest.UploadWithBigRead/1 (13 ms) [04:30:19:INFO] [ RUN ] UrlRequestTest.UploadRedirectSync/0 [04:30:19:INFO] [ OK ] UrlRequestTest.UploadRedirectSync/0 (12 ms) [04:30:19:INFO] [ RUN ] UrlRequestTest.UploadRedirectSync/1 [04:30:19:INFO] [ OK ] UrlRequestTest.UploadRedirectSync/1 (15 ms) [04:30:19:INFO] [ RUN ] UrlRequestTest.UploadRedirectAsync/0 [04:30:19:INFO] [ OK ] UrlRequestTest.UploadRedirectAsync/0 (14 ms) [04:30:19:INFO] [ RUN ] UrlRequestTest.UploadRedirectAsync/1 [04:30:19:INFO] [ OK ] UrlRequestTest.UploadRedirectAsync/1 (19 ms) [04:30:19:INFO] [ RUN ] UrlRequestTest.UploadWithBadLength/0 [04:30:19:INFO] [ OK ] UrlRequestTest.UploadWithBadLength/0 (11 ms) [04:30:19:INFO] [ RUN ] UrlRequestTest.UploadWithBadLength/1 [04:30:19:INFO] [ OK ] UrlRequestTest.UploadWithBadLength/1 (11 ms) [04:30:19:INFO] [ RUN ] UrlRequestTest.UploadWithBadLengthBufferAligned/0 [04:30:19:INFO] [ OK ] UrlRequestTest.UploadWithBadLengthBufferAligned/0 (106 ms) [04:30:19:INFO] [ RUN ] UrlRequestTest.UploadWithBadLengthBufferAligned/1 [04:30:19:INFO] [ OK ] UrlRequestTest.UploadWithBadLengthBufferAligned/1 (176 ms) [04:30:19:INFO] [ RUN ] UrlRequestTest.UploadReadFailSync/0 [04:30:19:INFO] [ OK ] UrlRequestTest.UploadReadFailSync/0 (10 ms) [04:30:19:INFO] [ RUN ] UrlRequestTest.UploadReadFailSync/1 [04:30:19:INFO] [ OK ] UrlRequestTest.UploadReadFailSync/1 (11 ms) [04:30:19:INFO] [ RUN ] UrlRequestTest.UploadReadFailAsync/0 [04:30:19:INFO] [ OK ] UrlRequestTest.UploadReadFailAsync/0 (10 ms) [04:30:19:INFO] [ RUN ] UrlRequestTest.UploadReadFailAsync/1 [04:30:20:INFO] [ OK ] UrlRequestTest.UploadReadFailAsync/1 (11 ms) [04:30:20:INFO] [ RUN ] UrlRequestTest.UploadRewindFailSync/0 [04:30:20:INFO] [ OK ] UrlRequestTest.UploadRewindFailSync/0 (13 ms) [04:30:20:INFO] [ RUN ] UrlRequestTest.UploadRewindFailSync/1 [04:30:20:INFO] [ OK ] UrlRequestTest.UploadRewindFailSync/1 (15 ms) [04:30:20:INFO] [ RUN ] UrlRequestTest.UploadRewindFailAsync/0 [04:30:20:INFO] [ OK ] UrlRequestTest.UploadRewindFailAsync/0 (11 ms) [04:30:20:INFO] [ RUN ] UrlRequestTest.UploadRewindFailAsync/1 [04:30:20:INFO] [ OK ] UrlRequestTest.UploadRewindFailAsync/1 (15 ms) [04:30:20:INFO] [ RUN ] UrlRequestTest.UploadChunked/0 [04:30:20:INFO] [ OK ] UrlRequestTest.UploadChunked/0 (19 ms) [04:30:20:INFO] [ RUN ] UrlRequestTest.UploadChunked/1 [04:30:20:INFO] [ OK ] UrlRequestTest.UploadChunked/1 (11 ms) [04:30:20:INFO] [ RUN ] UrlRequestTest.UploadChunkedLastReadZeroLengthBody/0 [04:30:20:INFO] [ OK ] UrlRequestTest.UploadChunkedLastReadZeroLengthBody/0 (12 ms) [04:30:20:INFO] [ RUN ] UrlRequestTest.UploadChunkedLastReadZeroLengthBody/1 [04:30:20:INFO] [ OK ] UrlRequestTest.UploadChunkedLastReadZeroLengthBody/1 (12 ms) [04:30:20:INFO] [ RUN ] UrlRequestTest.UploadFailsWithoutInitializingStream/0 [04:30:20:INFO] [ OK ] UrlRequestTest.UploadFailsWithoutInitializingStream/0 (8 ms) [04:30:20:INFO] [ RUN ] UrlRequestTest.UploadFailsWithoutInitializingStream/1 [04:30:20:INFO] [ OK ] UrlRequestTest.UploadFailsWithoutInitializingStream/1 (10 ms) [04:30:20:INFO] [ RUN ] UrlRequestTest.SimpleRequest/0 [04:30:20:INFO] [ OK ] UrlRequestTest.SimpleRequest/0 (10 ms) [04:30:20:INFO] [ RUN ] UrlRequestTest.SimpleRequest/1 [04:30:20:INFO] [ OK ] UrlRequestTest.SimpleRequest/1 (12 ms) [04:30:20:INFO] [ RUN ] UrlRequestTest.MultiRedirect/0 [04:30:20:INFO] [ OK ] UrlRequestTest.MultiRedirect/0 (28 ms) [04:30:20:INFO] [ RUN ] UrlRequestTest.MultiRedirect/1 [04:30:20:INFO] [ OK ] UrlRequestTest.MultiRedirect/1 (21 ms) [04:30:20:INFO] [ RUN ] UrlRequestTest.CancelRequest/0 [04:30:20:INFO] [ OK ] UrlRequestTest.CancelRequest/0 (10 ms) [04:30:20:INFO] [ RUN ] UrlRequestTest.CancelRequest/1 [04:30:20:INFO] [ OK ] UrlRequestTest.CancelRequest/1 (10 ms) [04:30:20:INFO] [ RUN ] UrlRequestTest.FailedRequestHostNotFound/0 [04:30:20:INFO] [ OK ] UrlRequestTest.FailedRequestHostNotFound/0 (7 ms) [04:30:20:INFO] [ RUN ] UrlRequestTest.FailedRequestHostNotFound/1 [04:30:20:INFO] [ OK ] UrlRequestTest.FailedRequestHostNotFound/1 (8 ms) [04:30:20:INFO] [ RUN ] UrlRequestTest.TestCancel/0 [04:30:20:INFO] [ OK ] UrlRequestTest.TestCancel/0 (86 ms) [04:30:20:INFO] [ RUN ] UrlRequestTest.TestCancel/1 [04:30:20:INFO] [ OK ] UrlRequestTest.TestCancel/1 (101 ms) [04:30:20:INFO] [ RUN ] UrlRequestTest.PerfTest/0 [04:30:21:INFO] [76596:771:0319/043021.798483:26119634469292:INFO:url_request_test.cc(993)] Total time 1299.25 ms [04:30:21:INFO] [76596:771:0319/043021.798970:26119634700382:INFO:url_request_test.cc(994)] Single Iteration time 129.925 ms [04:30:21:INFO] [76596:771:0319/043021.799165:26119634894361:INFO:url_request_test.cc(1001)] Average Throughput: 1188.83 mbps [04:30:21:INFO] [ OK ] UrlRequestTest.PerfTest/0 (1354 ms) [04:30:21:INFO] [ RUN ] UrlRequestTest.PerfTest/1 [04:30:24:INFO] [76596:771:0319/043024.133307:26121969190388:INFO:url_request_test.cc(993)] Total time 2306.44 ms [04:30:24:INFO] [76596:771:0319/043024.133578:26121969421669:INFO:url_request_test.cc(994)] Single Iteration time 230.644 ms [04:30:24:INFO] [76596:771:0319/043024.133785:26121969624811:INFO:url_request_test.cc(1001)] Average Throughput: 669.687 mbps [04:30:24:INFO] [ OK ] UrlRequestTest.PerfTest/1 (2332 ms) [04:30:24:INFO] [ RUN ] UrlRequestTest.GetStatus/0 [04:30:24:INFO] [ OK ] UrlRequestTest.GetStatus/0 (10 ms) [04:30:24:INFO] [ RUN ] UrlRequestTest.GetStatus/1 [04:30:24:INFO] [ OK ] UrlRequestTest.GetStatus/1 (12 ms) [04:30:24:INFO] [----------] 57 tests from UrlRequestTest (5004 ms total) [04:30:24:INFO] [04:30:24:INFO] [----------] 5 tests from AcceptLangTest [04:30:24:INFO] [ RUN ] AcceptLangTest.Region [04:30:24:INFO] [ OK ] AcceptLangTest.Region (0 ms) [04:30:24:INFO] [ RUN ] AcceptLangTest.Lang [04:30:24:INFO] [ OK ] AcceptLangTest.Lang (0 ms) [04:30:24:INFO] [ RUN ] AcceptLangTest.Default [04:30:24:INFO] [ OK ] AcceptLangTest.Default (0 ms) [04:30:24:INFO] [ RUN ] AcceptLangTest.Append [04:30:24:INFO] [ OK ] AcceptLangTest.Append (0 ms) [04:30:24:INFO] [ RUN ] AcceptLangTest.NoDefaultAppend [04:30:24:INFO] 2019-03-19 04:30:24.171 cronet_test[76596:1275442] en-GB,en-US,en [04:30:24:INFO] [ OK ] AcceptLangTest.NoDefaultAppend (0 ms) [04:30:24:INFO] [----------] 5 tests from AcceptLangTest (9 ms total) [04:30:24:INFO] [04:30:24:INFO] [----------] 21 tests from HttpTest [04:30:24:INFO] [ RUN ] HttpTest.CreateSslKeyLogFile [04:30:24:INFO] 2019-03-19 04:30:24.202 cronet_test[76596:1275980] DiskCookieStorage changing policy from 2 to 0, cookie file: file:///Users/chrome-bot/Library/Developer/CoreSimulator/Devices/D3A050AD-AB4E-4F9A-B6D5-637C0E890240/data/Containers/Data/Application/017F853D-C6AB-4DFE-A911-3E1379D50663/Library/Cookies/org.chromium.gtest.cronet-test.binarycookies [04:30:24:INFO] [0319/043024.222999:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [04:30:24:INFO] [0319/043024.232389:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [04:30:24:INFO] [ OK ] HttpTest.CreateSslKeyLogFile (62 ms) [04:30:24:INFO] [ RUN ] HttpTest.NSURLSessionReceivesData [04:30:24:INFO] [0319/043024.372041:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [04:30:24:INFO] [ OK ] HttpTest.NSURLSessionReceivesData (136 ms) [04:30:24:INFO] [ RUN ] HttpTest.GetGlobalMetricsDeltas [04:30:24:INFO] [0319/043024.468520:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [04:30:24:INFO] [ OK ] HttpTest.GetGlobalMetricsDeltas (95 ms) [04:30:24:INFO] [ RUN ] HttpTest.SdchDisabledByDefault [04:30:24:INFO] [0319/043024.497679:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [04:30:24:INFO] [ OK ] HttpTest.SdchDisabledByDefault (27 ms) [04:30:24:INFO] [ RUN ] HttpTest.AcceptEncodingSdchIsAllowed [04:30:24:INFO] [0319/043024.526635:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [04:30:24:INFO] [ OK ] HttpTest.AcceptEncodingSdchIsAllowed (27 ms) [04:30:24:INFO] [ RUN ] HttpTest.AcceptEncodingFooBarIsAllowed [04:30:24:INFO] [0319/043024.550007:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [04:30:24:INFO] [ OK ] HttpTest.AcceptEncodingFooBarIsAllowed (22 ms) [04:30:24:INFO] [ RUN ] HttpTest.NSURLSessionAcceptLanguage [04:30:24:INFO] [0319/043024.573923:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [04:30:24:INFO] [ OK ] HttpTest.NSURLSessionAcceptLanguage (23 ms) [04:30:24:INFO] [ RUN ] HttpTest.SetUserAgentIsExact [04:30:24:INFO] [0319/043024.597118:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [04:30:24:INFO] [ OK ] HttpTest.SetUserAgentIsExact (22 ms) [04:30:24:INFO] [ RUN ] HttpTest.SetCookie [04:30:24:INFO] [0319/043024.632310:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [04:30:24:INFO] [ OK ] HttpTest.SetCookie (34 ms) [04:30:24:INFO] [ RUN ] HttpTest.SetSystemCookie [04:30:24:INFO] [0319/043024.659161:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [04:30:24:INFO] [ OK ] HttpTest.SetSystemCookie (26 ms) [04:30:24:INFO] [ RUN ] HttpTest.SystemCookieWithNullCreationTime [04:30:24:INFO] [0319/043024.694206:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [04:30:24:INFO] [ OK ] HttpTest.SystemCookieWithNullCreationTime (26 ms) [04:30:24:INFO] [ RUN ] HttpTest.FilterOutRequest [04:30:24:INFO] [0319/043024.728142:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [04:30:24:INFO] [ OK ] HttpTest.FilterOutRequest (32 ms) [04:30:24:INFO] [ RUN ] HttpTest.FileSchemeNotSupported [04:30:24:INFO] [0319/043024.749033:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [04:30:24:INFO] [ OK ] HttpTest.FileSchemeNotSupported (20 ms) [04:30:24:INFO] [ RUN ] HttpTest.DataSchemeNotSupported [04:30:24:INFO] [0319/043024.768368:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [04:30:24:INFO] [ OK ] HttpTest.DataSchemeNotSupported (18 ms) [04:30:24:INFO] [ RUN ] HttpTest.BrotliAdvertisedTest [04:30:24:INFO] [0319/043024.789350:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [04:30:24:INFO] [0319/043024.802423:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [04:30:24:INFO] [ OK ] HttpTest.BrotliAdvertisedTest (32 ms) [04:30:24:INFO] [ RUN ] HttpTest.BrotliNotAdvertisedTest [04:30:24:INFO] [0319/043024.821119:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [04:30:24:INFO] [0319/043024.831811:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [04:30:24:INFO] [ OK ] HttpTest.BrotliNotAdvertisedTest (28 ms) [04:30:24:INFO] [ RUN ] HttpTest.BrotliHandleDecoding [04:30:24:INFO] [0319/043024.852640:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [04:30:24:INFO] [0319/043024.865986:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [04:30:24:INFO] [ OK ] HttpTest.BrotliHandleDecoding (33 ms) [04:30:24:INFO] [ RUN ] HttpTest.PostRequest [04:30:24:INFO] [0319/043024.891821:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [04:30:24:INFO] [ OK ] HttpTest.PostRequest (24 ms) [04:30:24:INFO] [ RUN ] HttpTest.PostRequestWithBodyStream [04:30:24:INFO] [0319/043024.923236:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [04:30:24:INFO] [ OK ] HttpTest.PostRequestWithBodyStream (30 ms) [04:30:24:INFO] [ RUN ] HttpTest.PostRequestWithLargeBodyStream [04:30:24:INFO] [0319/043024.949020:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [04:30:24:INFO] [ OK ] HttpTest.PostRequestWithLargeBodyStream (25 ms) [04:30:24:INFO] [ RUN ] HttpTest.LegacyApi [04:30:25:INFO] [0319/043025.017658:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [04:30:25:INFO] [ OK ] HttpTest.LegacyApi (69 ms) [04:30:25:INFO] [----------] 21 tests from HttpTest (848 ms total) [04:30:25:INFO] [04:30:25:INFO] [----------] 8 tests from CronetEnabledMetricsTest [04:30:25:INFO] [ RUN ] CronetEnabledMetricsTest.ProtocolIsQuic [04:30:25:INFO] [0319/043025.101702:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [04:30:25:INFO] [ OK ] CronetEnabledMetricsTest.ProtocolIsQuic (84 ms) [04:30:25:INFO] [ RUN ] CronetEnabledMetricsTest.ProtocolIsNotQuic [04:30:25:INFO] [0319/043025.135374:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [04:30:25:INFO] [ OK ] CronetEnabledMetricsTest.ProtocolIsNotQuic (32 ms) [04:30:25:INFO] [ RUN ] CronetEnabledMetricsTest.PlatformComparison [04:30:25:INFO] [0319/043025.172526:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [04:30:25:INFO] [ OK ] CronetEnabledMetricsTest.PlatformComparison (36 ms) [04:30:25:INFO] [ RUN ] CronetEnabledMetricsTest.InvalidURL [04:30:25:INFO] [0319/043025.198600:ERROR:crn_http_protocol_handler.mm(845)] HttpProtocolHandlerCore - Network error: net::ERR_NAME_NOT_RESOLVED (-105) [04:30:25:INFO] [0319/043025.203794:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [04:30:25:INFO] [ OK ] CronetEnabledMetricsTest.InvalidURL (29 ms) [04:30:25:INFO] [ RUN ] CronetEnabledMetricsTest.CanceledRequest [04:30:25:INFO] [76596:771:0319/043025.223013:26123058923397:WARNING:cronet_test_base.mm(119)] The request was canceled due to timeout. [04:30:25:INFO] [0319/043025.228955:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [04:30:25:INFO] [ OK ] CronetEnabledMetricsTest.CanceledRequest (25 ms) [04:30:25:INFO] [ RUN ] CronetEnabledMetricsTest.ReusedConnection [04:30:25:INFO] [0319/043025.321354:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [04:30:25:INFO] [ OK ] CronetEnabledMetricsTest.ReusedConnection (89 ms) [04:30:25:INFO] [ RUN ] CronetEnabledMetricsTest.SessionWithoutDelegate [04:30:25:INFO] [0319/043025.401099:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [04:30:25:INFO] [ OK ] CronetEnabledMetricsTest.SessionWithoutDelegate (78 ms) [04:30:25:INFO] [ RUN ] CronetEnabledMetricsTest.LegacyApi [04:30:25:INFO] [0319/043025.474555:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [04:30:25:INFO] [ OK ] CronetEnabledMetricsTest.LegacyApi (72 ms) [04:30:25:INFO] [----------] 8 tests from CronetEnabledMetricsTest (457 ms total) [04:30:25:INFO] [04:30:25:INFO] [----------] 1 test from CronetDisabledMetricsTest [04:30:25:INFO] [ RUN ] CronetDisabledMetricsTest.MetricsDisabled [04:30:25:INFO] [0319/043025.547182:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [04:30:25:INFO] [ OK ] CronetDisabledMetricsTest.MetricsDisabled (71 ms) [04:30:25:INFO] [----------] 1 test from CronetDisabledMetricsTest (73 ms total) [04:30:25:INFO] [04:30:25:INFO] [----------] 7 tests from NetLogTest [04:30:25:INFO] [ RUN ] NetLogTest.OpenFile [04:30:25:INFO] [0319/043025.554228:WARNING:cronet_environment.mm(163)] Starting NetLog to /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/D3A050AD-AB4E-4F9A-B6D5-637C0E890240/data/Containers/Data/Application/017F853D-C6AB-4DFE-A911-3E1379D50663/Documents/cronet_netlog.json [04:30:25:INFO] [0319/043025.569091:WARNING:cronet_environment.mm(186)] Started NetLog [04:30:25:INFO] [0319/043025.569394:WARNING:cronet_environment.mm(202)] Stopped NetLog. [04:30:25:INFO] [0319/043025.576366:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [04:30:25:INFO] [ OK ] NetLogTest.OpenFile (25 ms) [04:30:25:INFO] [ RUN ] NetLogTest.CreateFile [04:30:25:INFO] [0319/043025.580158:WARNING:cronet_environment.mm(163)] Starting NetLog to /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/D3A050AD-AB4E-4F9A-B6D5-637C0E890240/data/Containers/Data/Application/017F853D-C6AB-4DFE-A911-3E1379D50663/Documents/39924F65-BACD-4A26-BA2B-B7693927E4BF-76596-000017C254E942D4_netlog.json [04:30:25:INFO] [0319/043025.597612:WARNING:cronet_environment.mm(186)] Started NetLog [04:30:25:INFO] [0319/043025.597918:WARNING:cronet_environment.mm(202)] Stopped NetLog. [04:30:25:INFO] [0319/043025.605471:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [04:30:25:INFO] [ OK ] NetLogTest.CreateFile (28 ms) [04:30:25:INFO] [ RUN ] NetLogTest.NonExistantDir [04:30:25:INFO] [0319/043025.609925:ERROR:cronet_environment.mm(158)] Can not start NetLog to /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/D3A050AD-AB4E-4F9A-B6D5-637C0E890240/data/Containers/Data/Application/017F853D-C6AB-4DFE-A911-3E1379D50663/Documents/DF8FA1FB-0AD8-406D-94E7-C6CB8A70F9F9-76596-000017C256B1ACF6/netlog.json: Undefined error: 0 [04:30:25:INFO] [0319/043025.612331:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [04:30:25:INFO] [ OK ] NetLogTest.NonExistantDir (6 ms) [04:30:25:INFO] [ RUN ] NetLogTest.ExistantDir [04:30:25:INFO] [0319/043025.616811:WARNING:cronet_environment.mm(163)] Starting NetLog to /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/D3A050AD-AB4E-4F9A-B6D5-637C0E890240/data/Containers/Data/Application/017F853D-C6AB-4DFE-A911-3E1379D50663/Documents/E210D690-F3E5-4E77-A225-27332013C635-76596-000017C257162748/netlog.json [04:30:25:INFO] [0319/043025.632544:WARNING:cronet_environment.mm(186)] Started NetLog [04:30:25:INFO] [0319/043025.632842:WARNING:cronet_environment.mm(202)] Stopped NetLog. [04:30:25:INFO] [0319/043025.640978:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [04:30:25:INFO] [ OK ] NetLogTest.ExistantDir (28 ms) [04:30:25:INFO] [ RUN ] NetLogTest.EmptyFilename [04:30:25:INFO] [0319/043025.647184:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [04:30:25:INFO] [ OK ] NetLogTest.EmptyFilename (5 ms) [04:30:25:INFO] [ RUN ] NetLogTest.AbsoluteFilename [04:30:25:INFO] [0319/043025.653608:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [04:30:25:INFO] [ OK ] NetLogTest.AbsoluteFilename (5 ms) [04:30:25:INFO] [ RUN ] NetLogTest.ExperimentalOptions [04:30:25:INFO] [0319/043025.660405:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [04:30:25:INFO] [0319/043025.663672:WARNING:cronet_environment.mm(163)] Starting NetLog to /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/D3A050AD-AB4E-4F9A-B6D5-637C0E890240/data/Containers/Data/Application/017F853D-C6AB-4DFE-A911-3E1379D50663/Documents/cronet_netlog.json [04:30:25:INFO] [0319/043025.681360:WARNING:cronet_environment.mm(186)] Started NetLog [04:30:25:INFO] [0319/043025.681640:WARNING:cronet_environment.mm(202)] Stopped NetLog. [04:30:25:INFO] [0319/043025.689292:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [04:30:25:INFO] [ OK ] NetLogTest.ExperimentalOptions (34 ms) [04:30:25:INFO] [----------] 7 tests from NetLogTest (139 ms total) [04:30:25:INFO] [04:30:25:INFO] [----------] 12 tests from PkpTest [04:30:25:INFO] [ RUN ] PkpTest.TestSuccessIfPinSetForDifferentHost [04:30:25:INFO] [0319/043025.754973:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [04:30:25:INFO] [ OK ] PkpTest.TestSuccessIfPinSetForDifferentHost (66 ms) [04:30:25:INFO] [ RUN ] PkpTest.TestErrorIfPinDoesNotMatch [04:30:25:INFO] [0319/043025.793281:WARNING:proof_verifier_chromium.cc(501)] Failed to verify certificate chain: net::ERR_SSL_PINNED_KEY_NOT_IN_CERT_CHAIN [04:30:25:INFO] [0319/043025.795725:ERROR:crn_http_protocol_handler.mm(845)] HttpProtocolHandlerCore - Network error: net::ERR_CONNECTION_REFUSED (-102) [04:30:25:INFO] [0319/043025.804584:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [04:30:25:INFO] [ OK ] PkpTest.TestErrorIfPinDoesNotMatch (49 ms) [04:30:25:INFO] [ RUN ] PkpTest.TestSuccessIfPinMatches [04:30:25:INFO] [0319/043025.870164:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [04:30:25:INFO] [ OK ] PkpTest.TestSuccessIfPinMatches (64 ms) [04:30:25:INFO] [ RUN ] PkpTest.TestBypass [04:30:25:INFO] [ OK ] PkpTest.TestBypass (11 ms) [04:30:25:INFO] [ RUN ] PkpTest.TestIncludeSubdomainsFlagEqualTrue [04:30:25:INFO] [0319/043025.919208:WARNING:proof_verifier_chromium.cc(501)] Failed to verify certificate chain: net::ERR_SSL_PINNED_KEY_NOT_IN_CERT_CHAIN [04:30:25:INFO] [0319/043025.921913:ERROR:crn_http_protocol_handler.mm(845)] HttpProtocolHandlerCore - Network error: net::ERR_CONNECTION_REFUSED (-102) [04:30:25:INFO] [0319/043025.929889:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [04:30:25:INFO] [ OK ] PkpTest.TestIncludeSubdomainsFlagEqualTrue (47 ms) [04:30:25:INFO] [ RUN ] PkpTest.TestIncludeSubdomainsFlagEqualFalse [04:30:25:INFO] [0319/043025.992731:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [04:30:25:INFO] [ OK ] PkpTest.TestIncludeSubdomainsFlagEqualFalse (62 ms) [04:30:25:INFO] [ RUN ] PkpTest.TestSoonExpiringPin [04:30:26:INFO] [0319/043026.030016:WARNING:proof_verifier_chromium.cc(501)] Failed to verify certificate chain: net::ERR_SSL_PINNED_KEY_NOT_IN_CERT_CHAIN [04:30:26:INFO] [0319/043026.032871:ERROR:crn_http_protocol_handler.mm(845)] HttpProtocolHandlerCore - Network error: net::ERR_CONNECTION_REFUSED (-102) [04:30:26:INFO] [0319/043026.040356:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [04:30:26:INFO] [ OK ] PkpTest.TestSoonExpiringPin (46 ms) [04:30:26:INFO] [ RUN ] PkpTest.TestRecentlyExpiredPin [04:30:26:INFO] [0319/043026.102995:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [04:30:26:INFO] [ OK ] PkpTest.TestRecentlyExpiredPin (61 ms) [04:30:26:INFO] [ RUN ] PkpTest.TestPinsAreNotPersisted [04:30:26:INFO] [0319/043026.139345:WARNING:proof_verifier_chromium.cc(501)] Failed to verify certificate chain: net::ERR_SSL_PINNED_KEY_NOT_IN_CERT_CHAIN [04:30:26:INFO] [0319/043026.141647:ERROR:crn_http_protocol_handler.mm(845)] HttpProtocolHandlerCore - Network error: net::ERR_CONNECTION_REFUSED (-102) [04:30:26:INFO] [0319/043026.148166:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [04:30:26:INFO] [0319/043026.197375:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [04:30:26:INFO] [ OK ] PkpTest.TestPinsAreNotPersisted (92 ms) [04:30:26:INFO] [ RUN ] PkpTest.TestHashLengthError [04:30:26:INFO] [ OK ] PkpTest.TestHashLengthError (12 ms) [04:30:26:INFO] [ RUN ] PkpTest.TestPkpOverrideNonMatchingToMatching [04:30:26:INFO] [0319/043026.279544:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [04:30:26:INFO] [ OK ] PkpTest.TestPkpOverrideNonMatchingToMatching (69 ms) [04:30:26:INFO] [ RUN ] PkpTest.TestPkpOverrideMatchingToNonMatching [04:30:26:INFO] [0319/043026.318229:WARNING:proof_verifier_chromium.cc(501)] Failed to verify certificate chain: net::ERR_SSL_PINNED_KEY_NOT_IN_CERT_CHAIN [04:30:26:INFO] [0319/043026.320682:ERROR:crn_http_protocol_handler.mm(845)] HttpProtocolHandlerCore - Network error: net::ERR_CONNECTION_REFUSED (-102) [04:30:26:INFO] [0319/043026.328096:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [04:30:26:INFO] [ OK ] PkpTest.TestPkpOverrideMatchingToNonMatching (47 ms) [04:30:26:INFO] [----------] 12 tests from PkpTest (641 ms total) [04:30:26:INFO] [04:30:26:INFO] [----------] 1 test from PrefsTest [04:30:26:INFO] [ RUN ] PrefsTest.HttpSeverProperties [04:30:26:INFO] 2019-03-19 04:30:26.386 cronet_test[76596:1275442] Waiting for file /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/D3A050AD-AB4E-4F9A-B6D5-637C0E890240/data/Containers/Data/Application/017F853D-C6AB-4DFE-A911-3E1379D50663/Library/Caches/cronet/prefs/local_prefs.json. [04:30:26:INFO] 2019-03-19 04:30:26.386 cronet_test[76596:1275442] Time left: 80 seconds [04:30:31:INFO] 2019-03-19 04:30:31.389 cronet_test[76596:1275442] Time left: 75 seconds [04:30:36:INFO] 2019-03-19 04:30:36.391 cronet_test[76596:1275442] Time left: 70 seconds [04:30:41:INFO] 2019-03-19 04:30:41.393 cronet_test[76596:1275442] Time left: 65 seconds [04:30:46:INFO] 2019-03-19 04:30:46.396 cronet_test[76596:1275442] Time left: 60 seconds [04:30:51:INFO] 2019-03-19 04:30:51.398 cronet_test[76596:1275442] Time left: 55 seconds [04:30:56:INFO] 2019-03-19 04:30:56.400 cronet_test[76596:1275442] Time left: 50 seconds [04:31:01:INFO] 2019-03-19 04:31:01.402 cronet_test[76596:1275442] Time left: 45 seconds [04:31:06:INFO] 2019-03-19 04:31:06.404 cronet_test[76596:1275442] Time left: 40 seconds [04:31:11:INFO] 2019-03-19 04:31:11.406 cronet_test[76596:1275442] Time left: 35 seconds [04:31:16:INFO] 2019-03-19 04:31:16.408 cronet_test[76596:1275442] Time left: 30 seconds [04:31:21:INFO] 2019-03-19 04:31:21.411 cronet_test[76596:1275442] Time left: 25 seconds [04:31:26:INFO] 2019-03-19 04:31:26.412 cronet_test[76596:1275442] Time left: 20 seconds [04:31:31:INFO] 2019-03-19 04:31:31.415 cronet_test[76596:1275442] Time left: 15 seconds [04:31:36:INFO] 2019-03-19 04:31:36.416 cronet_test[76596:1275442] File /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/D3A050AD-AB4E-4F9A-B6D5-637C0E890240/data/Containers/Data/Application/017F853D-C6AB-4DFE-A911-3E1379D50663/Library/Caches/cronet/prefs/local_prefs.json exists. [04:31:36:INFO] [0319/043136.425896:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [04:31:36:INFO] [ OK ] PrefsTest.HttpSeverProperties (70096 ms) [04:31:36:INFO] [----------] 1 test from PrefsTest (70097 ms total) [04:31:36:INFO] [04:31:36:INFO] [----------] 3 tests from QuicTest [04:31:36:INFO] [ RUN ] QuicTest.InvalidQuicHost [04:31:36:INFO] [0319/043136.436640:ERROR:Cronet.mm(218)] Invalid QUIC hint host: https://test.example.com/ [04:31:36:INFO] [ OK ] QuicTest.InvalidQuicHost (9 ms) [04:31:36:INFO] [ RUN ] QuicTest.ValidQuicHost [04:31:36:INFO] [ OK ] QuicTest.ValidQuicHost (8 ms) [04:31:36:INFO] [ RUN ] QuicTest.RequestWithSocketOptimizationEnabled [04:31:36:INFO] [0319/043136.516994:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [04:31:36:INFO] [ OK ] QuicTest.RequestWithSocketOptimizationEnabled (69 ms) [04:31:36:INFO] [----------] 3 tests from QuicTest (91 ms total) [04:31:36:INFO] [04:31:36:INFO] [----------] 32 tests from BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest [04:31:36:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.StartExampleBidiStream/0 [04:31:36:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.StartExampleBidiStream/0 (54 ms) [04:31:36:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.StartExampleBidiStream/1 [04:31:36:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.StartExampleBidiStream/1 (52 ms) [04:31:36:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.SimplePutWithEmptyWriteDataAtTheEnd/0 [04:31:36:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.SimplePutWithEmptyWriteDataAtTheEnd/0 (52 ms) [04:31:36:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.SimplePutWithEmptyWriteDataAtTheEnd/1 [04:31:36:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.SimplePutWithEmptyWriteDataAtTheEnd/1 (55 ms) [04:31:36:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.SimpleGetWithFlush/0 [04:31:36:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.SimpleGetWithFlush/0 (50 ms) [04:31:36:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.SimpleGetWithFlush/1 [04:31:36:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.SimpleGetWithFlush/1 (49 ms) [04:31:36:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.SimplePostWithFlush/0 [04:31:36:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.SimplePostWithFlush/0 (60 ms) [04:31:36:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.SimplePostWithFlush/1 [04:31:36:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.SimplePostWithFlush/1 (59 ms) [04:31:36:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.SimplePostWithFlushTwice/0 [04:31:37:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.SimplePostWithFlushTwice/0 (56 ms) [04:31:37:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.SimplePostWithFlushTwice/1 [04:31:37:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.SimplePostWithFlushTwice/1 (53 ms) [04:31:37:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.SimplePostWithFlushAfterOneWrite/0 [04:31:37:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.SimplePostWithFlushAfterOneWrite/0 (51 ms) [04:31:37:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.SimplePostWithFlushAfterOneWrite/1 [04:31:37:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.SimplePostWithFlushAfterOneWrite/1 (60 ms) [04:31:37:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.TestDelayedFlush/0 [04:31:37:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.TestDelayedFlush/0 (58 ms) [04:31:37:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.TestDelayedFlush/1 [04:31:37:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.TestDelayedFlush/1 (54 ms) [04:31:37:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.CancelOnRead/0 [04:31:37:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.CancelOnRead/0 (60 ms) [04:31:37:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.CancelOnRead/1 [04:31:37:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.CancelOnRead/1 (50 ms) [04:31:37:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.CancelOnResponse/0 [04:31:37:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.CancelOnResponse/0 (52 ms) [04:31:37:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.CancelOnResponse/1 [04:31:37:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.CancelOnResponse/1 (62 ms) [04:31:37:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.CancelOnSucceeded/0 [04:31:37:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.CancelOnSucceeded/0 (51 ms) [04:31:37:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.CancelOnSucceeded/1 [04:31:37:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.CancelOnSucceeded/1 (65 ms) [04:31:37:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.ReadFailsBeforeRequestStarted/0 [04:31:37:INFO] [0319/043137.666684:ERROR:bidirectional_stream.cc(286)] Unexpected Read Data in read_state 0 [04:31:37:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.ReadFailsBeforeRequestStarted/0 (15 ms) [04:31:37:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.ReadFailsBeforeRequestStarted/1 [04:31:37:INFO] [0319/043137.681466:ERROR:bidirectional_stream.cc(286)] Unexpected Read Data in read_state 0 [04:31:37:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.ReadFailsBeforeRequestStarted/1 (13 ms) [04:31:37:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.StreamFailBeforeReadIsExecutedOnNetworkThread/0 [04:31:37:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.StreamFailBeforeReadIsExecutedOnNetworkThread/0 (53 ms) [04:31:37:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.StreamFailBeforeReadIsExecutedOnNetworkThread/1 [04:31:37:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.StreamFailBeforeReadIsExecutedOnNetworkThread/1 (51 ms) [04:31:37:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.WriteFailsBeforeRequestStarted/0 [04:31:37:INFO] [0319/043137.802246:ERROR:bidirectional_stream.cc(315)] Unexpected Flush Data in write_state 0 [04:31:37:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.WriteFailsBeforeRequestStarted/0 (12 ms) [04:31:37:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.WriteFailsBeforeRequestStarted/1 [04:31:37:INFO] [0319/043137.819362:ERROR:bidirectional_stream.cc(315)] Unexpected Flush Data in write_state 0 [04:31:37:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.WriteFailsBeforeRequestStarted/1 (16 ms) [04:31:37:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.StreamFailAfterStreamReadyCallback/0 [04:31:37:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.StreamFailAfterStreamReadyCallback/0 (55 ms) [04:31:37:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.StreamFailAfterStreamReadyCallback/1 [04:31:37:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.StreamFailAfterStreamReadyCallback/1 (59 ms) [04:31:37:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.StreamFailBeforeWriteIsExecutedOnNetworkThread/0 [04:31:38:INFO] [0319/043138.002251:ERROR:bidirectional_stream_quic_impl.cc(175)] Trying to send data after stream has been closed. [04:31:38:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.StreamFailBeforeWriteIsExecutedOnNetworkThread/0 (67 ms) [04:31:38:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.StreamFailBeforeWriteIsExecutedOnNetworkThread/1 [04:31:38:INFO] [0319/043138.066496:ERROR:bidirectional_stream_quic_impl.cc(175)] Trying to send data after stream has been closed. [04:31:38:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.StreamFailBeforeWriteIsExecutedOnNetworkThread/1 (64 ms) [04:31:38:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.FailedResolution/0 [04:31:38:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.FailedResolution/0 (14 ms) [04:31:38:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.FailedResolution/1 [04:31:38:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.FailedResolution/1 (14 ms) [04:31:38:INFO] [----------] 32 tests from BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest (1583 ms total) [04:31:38:INFO] [04:31:38:INFO] [----------] 5 tests from Loops/PerfTest [04:31:38:INFO] [ RUN ] Loops/PerfTest.NSURLSessionReceivesImageLoop/0 [04:31:38:INFO] [76596:771:0319/043138.117759:26195957582324:INFO:cronet_performance_test.mm(181)] Running with parameters: QUIC: 0 HTTP2: 0 AKD4: 0 Cronet: 0 [04:31:38:INFO] [76596:771:0319/043138.118034:26195957832078:INFO:cronet_performance_test.mm(191)] Downloading 19307439 bytes per iteration [04:31:39:INFO] [76596:771:0319/043139.199107:26197038951224:INFO:cronet_performance_test.mm(253)] Elapsed Total:1058.66ms [04:31:39:INFO] [0319/043139.204031:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [04:31:39:INFO] [ OK ] Loops/PerfTest.NSURLSessionReceivesImageLoop/0 (1102 ms) [04:31:39:INFO] [ RUN ] Loops/PerfTest.NSURLSessionReceivesImageLoop/1 [04:31:39:INFO] [76596:771:0319/043139.219092:26197058941210:INFO:cronet_performance_test.mm(181)] Running with parameters: QUIC: 0 HTTP2: 0 AKD4: 0 Cronet: 1 [04:31:39:INFO] [76596:771:0319/043139.219324:26197059174136:INFO:cronet_performance_test.mm(191)] Downloading 19307439 bytes per iteration [04:31:40:INFO] [76596:771:0319/043140.112581:26197952493380:INFO:cronet_performance_test.mm(253)] Elapsed Total:870.79ms [04:31:40:INFO] [0319/043140.118608:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [04:31:40:INFO] [ OK ] Loops/PerfTest.NSURLSessionReceivesImageLoop/1 (914 ms) [04:31:40:INFO] [ RUN ] Loops/PerfTest.NSURLSessionReceivesImageLoop/2 [04:31:40:INFO] [76596:771:0319/043140.136797:26197976718213:INFO:cronet_performance_test.mm(181)] Running with parameters: QUIC: 0 HTTP2: 1 AKD4: 0 Cronet: 1 [04:31:40:INFO] [76596:771:0319/043140.137029:26197976901604:INFO:cronet_performance_test.mm(191)] Downloading 19307439 bytes per iteration [04:31:41:INFO] [76596:771:0319/043141.047603:26198887559985:INFO:cronet_performance_test.mm(253)] Elapsed Total:887.925ms [04:31:41:INFO] [0319/043141.052403:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [04:31:41:INFO] [ OK ] Loops/PerfTest.NSURLSessionReceivesImageLoop/2 (932 ms) [04:31:41:INFO] [ RUN ] Loops/PerfTest.NSURLSessionReceivesImageLoop/3 [04:31:41:INFO] [76596:771:0319/043141.066186:26198906147143:INFO:cronet_performance_test.mm(181)] Running with parameters: QUIC: 1 HTTP2: 0 AKD4: 0 Cronet: 1 [04:31:41:INFO] [76596:771:0319/043141.066415:26198906349584:INFO:cronet_performance_test.mm(191)] Downloading 19307439 bytes per iteration [04:31:41:INFO] [76596:771:0319/043141.922676:26199762681111:INFO:cronet_performance_test.mm(253)] Elapsed Total:834.923ms [04:31:41:INFO] [0319/043141.927968:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [04:31:41:INFO] [ OK ] Loops/PerfTest.NSURLSessionReceivesImageLoop/3 (875 ms) [04:31:41:INFO] [ RUN ] Loops/PerfTest.NSURLSessionReceivesImageLoop/4 [04:31:41:INFO] [76596:771:0319/043141.943136:26199783134152:INFO:cronet_performance_test.mm(181)] Running with parameters: QUIC: 1 HTTP2: 0 AKD4: 1 Cronet: 1 [04:31:41:INFO] [76596:771:0319/043141.943365:26199783350699:INFO:cronet_performance_test.mm(191)] Downloading 19307439 bytes per iteration [04:31:42:INFO] [76596:771:0319/043142.829843:26200669899673:INFO:cronet_performance_test.mm(253)] Elapsed Total:868.125ms [04:31:42:INFO] [0319/043142.836276:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [04:31:42:INFO] [ OK ] Loops/PerfTest.NSURLSessionReceivesImageLoop/4 (907 ms) [04:31:42:INFO] [76596:771:0319/043142.839253:26200679286932:INFO:cronet_performance_test.mm(71)] Performance Data: [04:31:42:INFO] [76596:771:0319/043142.839481:26200679522405:INFO:cronet_performance_test.mm(88)] Quic 0 Http2 0 AKD4 0 Cronet 0: Mean: 0.105866s (1459.010888mbps) Max: 0.127520s with 0 fails out of 10 total requests. [04:31:42:INFO] [76596:771:0319/043142.839712:26200679749408:INFO:cronet_performance_test.mm(88)] Quic 0 Http2 0 AKD4 0 Cronet 1: Mean: 0.087079s (1773.786001mbps) Max: 0.113352s with 0 fails out of 10 total requests. [04:31:42:INFO] [76596:771:0319/043142.839929:26200679959350:INFO:cronet_performance_test.mm(88)] Quic 0 Http2 1 AKD4 0 Cronet 1: Mean: 0.088793s (1739.555784mbps) Max: 0.112253s with 0 fails out of 10 total requests. [04:31:42:INFO] [76596:771:0319/043142.840138:26200680172085:INFO:cronet_performance_test.mm(88)] Quic 1 Http2 0 AKD4 0 Cronet 1: Mean: 0.083492s (1849.984784mbps) Max: 0.114135s with 0 fails out of 10 total requests. [04:31:42:INFO] [76596:771:0319/043142.840360:26200680407696:INFO:cronet_performance_test.mm(88)] Quic 1 Http2 0 AKD4 1 Cronet 1: Mean: 0.086813s (1779.230585mbps) Max: 0.102129s with 0 fails out of 10 total requests. [04:31:42:INFO] [----------] 5 tests from Loops/PerfTest (4736 ms total) [04:31:42:INFO] [04:31:42:INFO] [----------] Global test environment tear-down [04:31:42:INFO] [==========] 166 tests from 14 test suites ran. (84055 ms total) [04:31:42:INFO] [ PASSED ] 166 tests. [04:31:42:INFO] [04:31:42:INFO] YOU HAVE 2 DISABLED TESTS [04:31:42:INFO] [04:31:56:INFO] Testing started on 'iPhone X' [04:31:56:INFO] 2019-03-19 04:29:48.940 xcodebuild[76472:1273806] IDETestOperationsObserverDebug: Writing diagnostic log for test session to: [04:31:56:INFO] /Users/chrome-bot/Library/Developer/Xcode/DerivedData/temporary-dzntzzlvitkbalgpirqkflpobmom/Logs/Test/Test-Transient Testing-2019.03.19_04-29-48--0700.xcresult/1_Test/Diagnostics/cronet_test-75169BA3-D578-4EB1-99DF-E9DE006FD6E6/cronet_test-09BE55AE-E08B-44B6-9FC6-57B0A4AF49FD/Session-cronet_test-2019-03-19_042948-tEuy7x.log [04:31:56:INFO] 2019-03-19 04:29:48.941 xcodebuild[76472:1273797] [MT] IDETestOperationsObserverDebug: (68485BC4-A80F-4903-B738-CC9BE2AB7299) Beginning test session cronet_test-68485BC4-A80F-4903-B738-CC9BE2AB7299 at 2019-03-19 04:29:48.941 with Xcode 10B61 on target <DVTiPhoneSimulator: 0x7fb09cca1230> { [04:31:56:INFO] SimDevice: iPhone X (D3A050AD-AB4E-4F9A-B6D5-637C0E890240, iOS 11.4, Shutdown) [04:31:56:INFO] } (11.4 (15F79)) [04:31:56:INFO] 2019-03-19 04:31:52.385 xcodebuild[76472:1273797] [MT] IDETestOperationsObserverDebug: 123.573 elapsed -- Testing started completed. [04:31:56:INFO] 2019-03-19 04:31:52.385 xcodebuild[76472:1273797] [MT] IDETestOperationsObserverDebug: 0.000 sec, +0.000 sec -- start [04:31:56:INFO] 2019-03-19 04:31:52.385 xcodebuild[76472:1273797] [MT] IDETestOperationsObserverDebug: 123.573 sec, +123.573 sec -- end [04:31:56:INFO] [04:31:56:INFO] Testing failed: [04:31:56:DEBUG] Finished print_process_output. [04:31:56:INFO] Waiting for test process to terminate. [04:31:56:INFO] Test process terminated. [04:31:56:DEBUG] Setting sigterm handler. [04:31:56:DEBUG] Stdout flushed after test process. [04:31:56:DEBUG] Processing test results. [04:31:56:INFO] /b/s/w/ir/src/out/Debug-iphonesimulator/iossim returned 65 [04:31:56:DEBUG] Extracting test data. [04:31:56:DEBUG] Retrieving crash reports. [04:31:56:DEBUG] Retrieving derived data. [04:31:56:DEBUG] Making desktop screenshots. [04:31:57:DEBUG] Killing simulators. [04:32:02:DEBUG] Wiping simulator. Device wiped. [04:32:05:DEBUG] End of tear_down. 2019-03-19 04:33:02,602 - root: [DEBUG] Contents of task_output_dir: ['/b/s/w/ir/k/recipe_cleanup/5vFxkkL/43ae880a2769d510'] 2019-03-19 04:33:02,602 - root: [WARNING] Expected output.json file missing: set(['/b/s/w/ir/k/recipe_cleanup/5vFxkkL/43ae880a2769d510/output.json']) Found: [] Expected: ['/b/s/w/ir/k/recipe_cleanup/5vFxkkL/43ae880a2769d510/output.json'] 2019-03-19 04:33:02,603 - root: [WARNING] No shard json files found in task_output_dir: '/b/s/w/ir/k/recipe_cleanup/5vFxkkL' Found ['/b/s/w/ir/k/recipe_cleanup/5vFxkkL/43ae880a2769d510'] 2019-03-19 04:33:02,603 - root: [DEBUG] Found shard_json_files: ['/b/s/w/ir/k/recipe_cleanup/5vFxkkL/43ae880a2769d510/output.json'] 2019-03-19 04:33:02,603 - 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/tmpXJ_TP0.json --task-output-dir /b/s/w/ir/k/recipe_cleanup/5vFxkkL -o /b/s/w/ir/tmp/t/tmpcR1Bb9.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/tmpXJ_TP0.json', '--task-output-dir', '/b/s/w/ir/k/recipe_cleanup/5vFxkkL', '-o', '/b/s/w/ir/tmp/t/tmpcR1Bb9.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/tmpXJ_TP0.json', '--task-output-dir', '/b/s/w/ir/k/recipe_cleanup/5vFxkkL', '-o', '/b/s/w/ir/tmp/t/tmpcR1Bb9.json'] returned exit code 0