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/tmpxEh_1W.json --task-output-dir /b/s/w/ir/k/recipe_cleanup/1hE6JLt --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/tmpZAuuGUmerge_script_log --merge-additional-args '[]' --summary-json-file /b/s/w/ir/tmp/t/tmprTVhQm.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/tmpXlwMcB.json in dir /b/s/w/ir/k: allow_subannotations: False base_name: 'cronet_test (iPhone 5s iOS 9.3) on Mac-10.13.6' 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/tmpxEh_1W.json', '--task-output-dir', '/b/s/w/ir/k/recipe_cleanup/1hE6JLt', '--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/tmpZAuuGUmerge_script_log', '--merge-additional-args', '[]', '--summary-json-file', '/b/s/w/ir/tmp/t/tmprTVhQm.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/tmpXlwMcB.json'] env: {} env_prefixes: {'PATH': '/b/s/w/ir/cache/swarming_client'} env_suffixes: {} infra_step: False name: 'cronet_test (iPhone 5s iOS 9.3) on Mac-10.13.6' nest_level: 0 ok_ret: frozenset([0]) step_test_data: <lambda>(...) trigger_specs: () full environment: Apple_PubSub_Socket_Render: /private/tmp/com.apple.launchd.Ouk2lMXbI4/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: 49491 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/8919452466703475696 LOGDOG_STREAM_PROJECT: chromium LOGDOG_STREAM_SERVER_PATH: unix:/b/s/w/ir/tmp/ld.sock LOGNAME: chrome-bot LUCI_CONTEXT: /b/s/w/itnm69gP/luci_context.852913737 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.vNrSRn5FHT/Listeners SWARMING_BOT_ID: build212-m9 SWARMING_HEADLESS: 1 SWARMING_SERVER: https://chromium-swarm.appspot.com SWARMING_TASK_ID: 437bd3d686d5df11 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-09 07:16:33,194 - root: [DEBUG] Using task_output_dir: '/b/s/w/ir/k/recipe_cleanup/1hE6JLt' 2019-03-09 07:16:33,194 - root: [WARNING] task_output_dir '/b/s/w/ir/k/recipe_cleanup/1hE6JLt' already exists! 2019-03-09 07:16:33,194 - root: [WARNING] task_output_dir existing content: [] 2019-03-09 07:16:33,194 - 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/tmpXlwMcB.json -output-dir /b/s/w/ir/k/recipe_cleanup/1hE6JLt -task-summary-json /b/s/w/ir/tmp/t/tmprTVhQm.json [D2019-03-09T07:16:33.209704-08:00 20750 0 auth.go:1265] Minting a new token {"key":"luci_ctx/36b04b225bc245bd391b3d4153dd59831c3d6e20f00cb38b36704c0df4a01b6c", "scopes":"https://www.googleapis.com/auth/userinfo.email"} [D2019-03-09T07:16:33.209770-08:00 20750 0 luci_ctx.go:138] POST http://127.0.0.1:49480/rpc/LuciLocalAuthService.GetOAuthToken {"key":"luci_ctx/36b04b225bc245bd391b3d4153dd59831c3d6e20f00cb38b36704c0df4a01b6c", "scopes":"https://www.googleapis.com/auth/userinfo.email"} [D2019-03-09T07:16:33.211253-08:00 20750 0 auth.go:1222] Token expires in 55m12.788798s {"key":"luci_ctx/36b04b225bc245bd391b3d4153dd59831c3d6e20f00cb38b36704c0df4a01b6c", "scopes":"https://www.googleapis.com/auth/userinfo.email"} [D2019-03-09T07:16:34.809025-08:00 20750 0 collect.go:344] Waiting task_id: 437bd6fec30d4a10 [D2019-03-09T07:16:36.306025-08:00 20750 0 collect.go:344] Waiting task_id: 437bd6fec30d4a10 [D2019-03-09T07:16:37.828818-08:00 20750 0 collect.go:344] Waiting task_id: 437bd6fec30d4a10 [D2019-03-09T07:16:39.276772-08:00 20750 0 collect.go:344] Waiting task_id: 437bd6fec30d4a10 [D2019-03-09T07:16:40.743435-08:00 20750 0 collect.go:344] Waiting task_id: 437bd6fec30d4a10 [D2019-03-09T07:16:42.345812-08:00 20750 0 collect.go:344] Waiting task_id: 437bd6fec30d4a10 [D2019-03-09T07:16:44.009012-08:00 20750 0 collect.go:344] Waiting task_id: 437bd6fec30d4a10 [D2019-03-09T07:16:45.783209-08:00 20750 0 collect.go:344] Waiting task_id: 437bd6fec30d4a10 [D2019-03-09T07:16:47.623108-08:00 20750 0 collect.go:344] Waiting task_id: 437bd6fec30d4a10 [D2019-03-09T07:16:49.408550-08:00 20750 0 collect.go:344] Waiting task_id: 437bd6fec30d4a10 [D2019-03-09T07:16:51.292844-08:00 20750 0 collect.go:344] Waiting task_id: 437bd6fec30d4a10 [D2019-03-09T07:16:53.378166-08:00 20750 0 collect.go:344] Waiting task_id: 437bd6fec30d4a10 [D2019-03-09T07:16:55.404622-08:00 20750 0 collect.go:344] Waiting task_id: 437bd6fec30d4a10 [D2019-03-09T07:16:57.439597-08:00 20750 0 collect.go:344] Waiting task_id: 437bd6fec30d4a10 [D2019-03-09T07:16:59.598842-08:00 20750 0 collect.go:344] Waiting task_id: 437bd6fec30d4a10 [D2019-03-09T07:17:01.871309-08:00 20750 0 collect.go:344] Waiting task_id: 437bd6fec30d4a10 [D2019-03-09T07:17:04.230705-08:00 20750 0 collect.go:344] Waiting task_id: 437bd6fec30d4a10 [D2019-03-09T07:17:06.709661-08:00 20750 0 collect.go:344] Waiting task_id: 437bd6fec30d4a10 [D2019-03-09T07:17:09.261603-08:00 20750 0 collect.go:344] Waiting task_id: 437bd6fec30d4a10 [D2019-03-09T07:17:11.880493-08:00 20750 0 collect.go:344] Waiting task_id: 437bd6fec30d4a10 [D2019-03-09T07:17:14.575989-08:00 20750 0 collect.go:344] Waiting task_id: 437bd6fec30d4a10 [D2019-03-09T07:17:17.510223-08:00 20750 0 collect.go:344] Waiting task_id: 437bd6fec30d4a10 [D2019-03-09T07:17:20.433904-08:00 20750 0 collect.go:344] Waiting task_id: 437bd6fec30d4a10 [D2019-03-09T07:17:23.443263-08:00 20750 0 collect.go:344] Waiting task_id: 437bd6fec30d4a10 [D2019-03-09T07:17:26.415075-08:00 20750 0 collect.go:344] Waiting task_id: 437bd6fec30d4a10 [D2019-03-09T07:17:29.595187-08:00 20750 0 collect.go:344] Waiting task_id: 437bd6fec30d4a10 [D2019-03-09T07:17:32.751846-08:00 20750 0 collect.go:344] Waiting task_id: 437bd6fec30d4a10 [D2019-03-09T07:17:36.099886-08:00 20750 0 collect.go:344] Waiting task_id: 437bd6fec30d4a10 [D2019-03-09T07:17:39.605681-08:00 20750 0 collect.go:344] Waiting task_id: 437bd6fec30d4a10 [D2019-03-09T07:17:43.165366-08:00 20750 0 collect.go:344] Waiting task_id: 437bd6fec30d4a10 [D2019-03-09T07:17:46.764604-08:00 20750 0 collect.go:344] Waiting task_id: 437bd6fec30d4a10 [D2019-03-09T07:17:50.595157-08:00 20750 0 collect.go:344] Waiting task_id: 437bd6fec30d4a10 [D2019-03-09T07:17:54.616077-08:00 20750 0 collect.go:344] Waiting task_id: 437bd6fec30d4a10 [D2019-03-09T07:17:58.697935-08:00 20750 0 collect.go:344] Waiting task_id: 437bd6fec30d4a10 [D2019-03-09T07:18:02.768846-08:00 20750 0 collect.go:344] Waiting task_id: 437bd6fec30d4a10 [D2019-03-09T07:18:07.262102-08:00 20750 0 collect.go:344] Waiting task_id: 437bd6fec30d4a10 [D2019-03-09T07:18:11.734222-08:00 20750 0 collect.go:344] Waiting task_id: 437bd6fec30d4a10 437bd6fec30d4a10: exit 0 [I 2019-03-09 07:15:09] About to install Xcode 10b61 in Xcode.app for ios [07:15:10:INFO] Using Xcode version 10.1 build 10B61 at /b/s/w/ir/Xcode.app/Contents/Developer Device wiped. [07:15:20:DEBUG] Setting sigterm handler. [07:15:22:INFO] User defaults from command line: [07:15:22:INFO] IDETestRunSpecificationPath = /var/folders/2j/22s2gz0s7hn48k32d47clxf80000gm/T/0F57254D-BBD4-4F18-B0C2-792E18BDCF9D [07:15:22:INFO] [07:15:42:INFO] objc[32707]: Class CRBProtocolObservers is implemented in both /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/2813EAA7-3822-4FCB-8BE9-A876B16AAFFD/data/Containers/Bundle/Application/3B31D339-3B86-4D25-8F04-D522EDFD9310/cronet_test.app/Frameworks/Cronet.framework/Cronet and /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/2813EAA7-3822-4FCB-8BE9-A876B16AAFFD/data/Containers/Bundle/Application/3B31D339-3B86-4D25-8F04-D522EDFD9310/cronet_test.app/cronet_test. One of the two will be used. Which one is undefined. [07:15:42:INFO] objc[32707]: Class CRBWeakNSProtocolSentinel is implemented in both /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/2813EAA7-3822-4FCB-8BE9-A876B16AAFFD/data/Containers/Bundle/Application/3B31D339-3B86-4D25-8F04-D522EDFD9310/cronet_test.app/Frameworks/Cronet.framework/Cronet and /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/2813EAA7-3822-4FCB-8BE9-A876B16AAFFD/data/Containers/Bundle/Application/3B31D339-3B86-4D25-8F04-D522EDFD9310/cronet_test.app/cronet_test. One of the two will be used. Which one is undefined. [07:15:43:INFO] [==========] Running 166 tests from 14 test suites. [07:15:43:INFO] [----------] Global test environment set-up. [07:15:43:INFO] [----------] 4 tests from BufferTest [07:15:43:INFO] [ RUN ] BufferTest.TestInitWithAlloc [07:15:43:INFO] [ OK ] BufferTest.TestInitWithAlloc (10 ms) [07:15:43:INFO] [ RUN ] BufferTest.TestInitWithHugeAllocFails [07:15:43:INFO] [ OK ] BufferTest.TestInitWithHugeAllocFails (1 ms) [07:15:43:INFO] [ RUN ] BufferTest.TestInitWithDataAndCallback [07:15:43:INFO] [ OK ] BufferTest.TestInitWithDataAndCallback (1 ms) [07:15:43:INFO] [ RUN ] BufferTest.TestCronetBufferAsync [07:15:43:INFO] [ OK ] BufferTest.TestCronetBufferAsync (3 ms) [07:15:43:INFO] [----------] 4 tests from BufferTest (19 ms total) [07:15:43:INFO] [07:15:43:INFO] [----------] 8 tests from EngineTest [07:15:43:INFO] [ RUN ] EngineTest.StartCronetEngine [07:15:43:INFO] [ OK ] EngineTest.StartCronetEngine (80 ms) [07:15:43:INFO] [ RUN ] EngineTest.CronetEngineDefaultUserAgent [07:15:43:INFO] [ OK ] EngineTest.CronetEngineDefaultUserAgent (0 ms) [07:15:43:INFO] [ RUN ] EngineTest.InitDifferentEngines [07:15:43:INFO] [ OK ] EngineTest.InitDifferentEngines (5 ms) [07:15:43:INFO] [ RUN ] EngineTest.StartResults [07:15:43:INFO] [0309/071543.188113:ERROR:engine.cc(135)] Disk cache path /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/2813EAA7-3822-4FCB-8BE9-A876B16AAFFD/data/Containers/Data/Application/3994A110-9EE4-4C4A-9FD2-B936C75C252C/tmp/.org.chromium.Chromium.lx2vrz is already used, cache disabled. [07:15:43:INFO] [ OK ] EngineTest.StartResults (114 ms) [07:15:43:INFO] [ RUN ] EngineTest.InvalidPkpParams [07:15:43:INFO] [ OK ] EngineTest.InvalidPkpParams (0 ms) [07:15:43:INFO] [ RUN ] EngineTest.ValidPkpParams [07:15:43:INFO] [ OK ] EngineTest.ValidPkpParams (3 ms) [07:15:43:INFO] [ RUN ] EngineTest.SetMockCertVerifierForTesting [07:15:43:INFO] [ OK ] EngineTest.SetMockCertVerifierForTesting (2 ms) [07:15:43:INFO] [ RUN ] EngineTest.StartNetLogToFile [07:15:43:INFO] [0309/071543.344526:ERROR:cronet_url_request_context.cc(536)] Failed to open NetLog file for writing. [07:15:43:INFO] [ OK ] EngineTest.StartNetLogToFile (36 ms) [07:15:43:INFO] [----------] 8 tests from EngineTest (252 ms total) [07:15:43:INFO] [07:15:43:INFO] [----------] 2 tests from ExecutorsTest [07:15:43:INFO] [ RUN ] ExecutorsTest.TestCustom [07:15:43:INFO] [ OK ] ExecutorsTest.TestCustom (1 ms) [07:15:43:INFO] [ RUN ] ExecutorsTest.TestTestExecutor [07:15:43:INFO] [ OK ] ExecutorsTest.TestTestExecutor (0 ms) [07:15:43:INFO] [----------] 2 tests from ExecutorsTest (5 ms total) [07:15:43:INFO] [07:15:43:INFO] [----------] 57 tests from UrlRequestTest [07:15:43:INFO] [ RUN ] UrlRequestTest.UploadWithDirectExecutor [07:15:43:INFO] [ OK ] UrlRequestTest.UploadWithDirectExecutor (36 ms) [07:15:43:INFO] [ RUN ] UrlRequestTest.InitChecks/0 [07:15:43:INFO] [ OK ] UrlRequestTest.InitChecks/0 (4 ms) [07:15:43:INFO] [ RUN ] UrlRequestTest.InitChecks/1 [07:15:43:INFO] [ OK ] UrlRequestTest.InitChecks/1 (4 ms) [07:15:43:INFO] [ RUN ] UrlRequestTest.SimpleGet/0 [07:15:43:INFO] [ OK ] UrlRequestTest.SimpleGet/0 (7 ms) [07:15:43:INFO] [ RUN ] UrlRequestTest.SimpleGet/1 [07:15:43:INFO] [ OK ] UrlRequestTest.SimpleGet/1 (8 ms) [07:15:43:INFO] [ RUN ] UrlRequestTest.UploadEmptyBodySync/0 [07:15:43:INFO] [ OK ] UrlRequestTest.UploadEmptyBodySync/0 (7 ms) [07:15:43:INFO] [ RUN ] UrlRequestTest.UploadEmptyBodySync/1 [07:15:43:INFO] [ OK ] UrlRequestTest.UploadEmptyBodySync/1 (8 ms) [07:15:43:INFO] [ RUN ] UrlRequestTest.UploadSync/0 [07:15:43:INFO] [ OK ] UrlRequestTest.UploadSync/0 (7 ms) [07:15:43:INFO] [ RUN ] UrlRequestTest.UploadSync/1 [07:15:43:INFO] [ OK ] UrlRequestTest.UploadSync/1 (8 ms) [07:15:43:INFO] [ RUN ] UrlRequestTest.SSLCertificateError/0 [07:15:43:INFO] [0309/071543.510487:ERROR:ssl_client_socket_impl.cc(946)] handshake failed; returned -1, SSL error code 1, net_error -207 [07:15:43:INFO] [32707:25391:0309/071543.510798:25343782249311:ERROR:ssl_server_socket_impl.cc(685)] handshake failed; returned -1, SSL error code 1, net_error -117 [07:15:43:INFO] [ OK ] UrlRequestTest.SSLCertificateError/0 (58 ms) [07:15:43:INFO] [ RUN ] UrlRequestTest.SSLCertificateError/1 [07:15:43:INFO] [0309/071543.542727:ERROR:ssl_client_socket_impl.cc(946)] handshake failed; returned -1, SSL error code 1, net_error -207 [07:15:43:INFO] [32707:31751:0309/071543.542909:25343814346114:ERROR:ssl_server_socket_impl.cc(685)] handshake failed; returned -1, SSL error code 1, net_error -117 [07:15:43:INFO] [ OK ] UrlRequestTest.SSLCertificateError/1 (31 ms) [07:15:43:INFO] [ RUN ] UrlRequestTest.UploadMultiplePiecesSync/0 [07:15:43:INFO] [ OK ] UrlRequestTest.UploadMultiplePiecesSync/0 (8 ms) [07:15:43:INFO] [ RUN ] UrlRequestTest.UploadMultiplePiecesSync/1 [07:15:43:INFO] [ OK ] UrlRequestTest.UploadMultiplePiecesSync/1 (9 ms) [07:15:43:INFO] [ RUN ] UrlRequestTest.UploadMultiplePiecesAsync/0 [07:15:43:INFO] [ OK ] UrlRequestTest.UploadMultiplePiecesAsync/0 (8 ms) [07:15:43:INFO] [ RUN ] UrlRequestTest.UploadMultiplePiecesAsync/1 [07:15:43:INFO] [ OK ] UrlRequestTest.UploadMultiplePiecesAsync/1 (10 ms) [07:15:43:INFO] [ RUN ] UrlRequestTest.UploadChangesDefaultMethod/0 [07:15:43:INFO] [ OK ] UrlRequestTest.UploadChangesDefaultMethod/0 (7 ms) [07:15:43:INFO] [ RUN ] UrlRequestTest.UploadChangesDefaultMethod/1 [07:15:43:INFO] [ OK ] UrlRequestTest.UploadChangesDefaultMethod/1 (8 ms) [07:15:43:INFO] [ RUN ] UrlRequestTest.UploadWithSetMethod/0 [07:15:43:INFO] [ OK ] UrlRequestTest.UploadWithSetMethod/0 (7 ms) [07:15:43:INFO] [ RUN ] UrlRequestTest.UploadWithSetMethod/1 [07:15:43:INFO] [ OK ] UrlRequestTest.UploadWithSetMethod/1 (8 ms) [07:15:43:INFO] [ RUN ] UrlRequestTest.UploadWithBigRead/0 [07:15:43:INFO] [ OK ] UrlRequestTest.UploadWithBigRead/0 (8 ms) [07:15:43:INFO] [ RUN ] UrlRequestTest.UploadWithBigRead/1 [07:15:43:INFO] [ OK ] UrlRequestTest.UploadWithBigRead/1 (8 ms) [07:15:43:INFO] [ RUN ] UrlRequestTest.UploadRedirectSync/0 [07:15:43:INFO] [ OK ] UrlRequestTest.UploadRedirectSync/0 (10 ms) [07:15:43:INFO] [ RUN ] UrlRequestTest.UploadRedirectSync/1 [07:15:43:INFO] [ OK ] UrlRequestTest.UploadRedirectSync/1 (11 ms) [07:15:43:INFO] [ RUN ] UrlRequestTest.UploadRedirectAsync/0 [07:15:43:INFO] [ OK ] UrlRequestTest.UploadRedirectAsync/0 (9 ms) [07:15:43:INFO] [ RUN ] UrlRequestTest.UploadRedirectAsync/1 [07:15:43:INFO] [ OK ] UrlRequestTest.UploadRedirectAsync/1 (11 ms) [07:15:43:INFO] [ RUN ] UrlRequestTest.UploadWithBadLength/0 [07:15:43:INFO] [ OK ] UrlRequestTest.UploadWithBadLength/0 (6 ms) [07:15:43:INFO] [ RUN ] UrlRequestTest.UploadWithBadLength/1 [07:15:43:INFO] [ OK ] UrlRequestTest.UploadWithBadLength/1 (7 ms) [07:15:43:INFO] [ RUN ] UrlRequestTest.UploadWithBadLengthBufferAligned/0 [07:15:43:INFO] [ OK ] UrlRequestTest.UploadWithBadLengthBufferAligned/0 (81 ms) [07:15:43:INFO] [ RUN ] UrlRequestTest.UploadWithBadLengthBufferAligned/1 [07:15:43:INFO] [ OK ] UrlRequestTest.UploadWithBadLengthBufferAligned/1 (135 ms) [07:15:43:INFO] [ RUN ] UrlRequestTest.UploadReadFailSync/0 [07:15:43:INFO] [ OK ] UrlRequestTest.UploadReadFailSync/0 (6 ms) [07:15:43:INFO] [ RUN ] UrlRequestTest.UploadReadFailSync/1 [07:15:43:INFO] [ OK ] UrlRequestTest.UploadReadFailSync/1 (7 ms) [07:15:43:INFO] [ RUN ] UrlRequestTest.UploadReadFailAsync/0 [07:15:43:INFO] [ OK ] UrlRequestTest.UploadReadFailAsync/0 (7 ms) [07:15:43:INFO] [ RUN ] UrlRequestTest.UploadReadFailAsync/1 [07:15:43:INFO] [ OK ] UrlRequestTest.UploadReadFailAsync/1 (8 ms) [07:15:43:INFO] [ RUN ] UrlRequestTest.UploadRewindFailSync/0 [07:15:43:INFO] [ OK ] UrlRequestTest.UploadRewindFailSync/0 (8 ms) [07:15:43:INFO] [ RUN ] UrlRequestTest.UploadRewindFailSync/1 [07:15:43:INFO] [ OK ] UrlRequestTest.UploadRewindFailSync/1 (9 ms) [07:15:43:INFO] [ RUN ] UrlRequestTest.UploadRewindFailAsync/0 [07:15:43:INFO] [ OK ] UrlRequestTest.UploadRewindFailAsync/0 (9 ms) [07:15:43:INFO] [ RUN ] UrlRequestTest.UploadRewindFailAsync/1 [07:15:44:INFO] [ OK ] UrlRequestTest.UploadRewindFailAsync/1 (9 ms) [07:15:44:INFO] [ RUN ] UrlRequestTest.UploadChunked/0 [07:15:44:INFO] [ OK ] UrlRequestTest.UploadChunked/0 (8 ms) [07:15:44:INFO] [ RUN ] UrlRequestTest.UploadChunked/1 [07:15:44:INFO] [ OK ] UrlRequestTest.UploadChunked/1 (8 ms) [07:15:44:INFO] [ RUN ] UrlRequestTest.UploadChunkedLastReadZeroLengthBody/0 [07:15:44:INFO] [ OK ] UrlRequestTest.UploadChunkedLastReadZeroLengthBody/0 (8 ms) [07:15:44:INFO] [ RUN ] UrlRequestTest.UploadChunkedLastReadZeroLengthBody/1 [07:15:44:INFO] [ OK ] UrlRequestTest.UploadChunkedLastReadZeroLengthBody/1 (9 ms) [07:15:44:INFO] [ RUN ] UrlRequestTest.UploadFailsWithoutInitializingStream/0 [07:15:44:INFO] [ OK ] UrlRequestTest.UploadFailsWithoutInitializingStream/0 (5 ms) [07:15:44:INFO] [ RUN ] UrlRequestTest.UploadFailsWithoutInitializingStream/1 [07:15:44:INFO] [ OK ] UrlRequestTest.UploadFailsWithoutInitializingStream/1 (6 ms) [07:15:44:INFO] [ RUN ] UrlRequestTest.SimpleRequest/0 [07:15:44:INFO] [ OK ] UrlRequestTest.SimpleRequest/0 (6 ms) [07:15:44:INFO] [ RUN ] UrlRequestTest.SimpleRequest/1 [07:15:44:INFO] [ OK ] UrlRequestTest.SimpleRequest/1 (7 ms) [07:15:44:INFO] [ RUN ] UrlRequestTest.MultiRedirect/0 [07:15:44:INFO] [ OK ] UrlRequestTest.MultiRedirect/0 (13 ms) [07:15:44:INFO] [ RUN ] UrlRequestTest.MultiRedirect/1 [07:15:44:INFO] [ OK ] UrlRequestTest.MultiRedirect/1 (13 ms) [07:15:44:INFO] [ RUN ] UrlRequestTest.CancelRequest/0 [07:15:44:INFO] [ OK ] UrlRequestTest.CancelRequest/0 (7 ms) [07:15:44:INFO] [ RUN ] UrlRequestTest.CancelRequest/1 [07:15:44:INFO] [ OK ] UrlRequestTest.CancelRequest/1 (7 ms) [07:15:44:INFO] [ RUN ] UrlRequestTest.FailedRequestHostNotFound/0 [07:15:44:INFO] [ OK ] UrlRequestTest.FailedRequestHostNotFound/0 (5 ms) [07:15:44:INFO] [ RUN ] UrlRequestTest.FailedRequestHostNotFound/1 [07:15:44:INFO] [ OK ] UrlRequestTest.FailedRequestHostNotFound/1 (6 ms) [07:15:44:INFO] [ RUN ] UrlRequestTest.TestCancel/0 [07:15:44:INFO] [ OK ] UrlRequestTest.TestCancel/0 (64 ms) [07:15:44:INFO] [ RUN ] UrlRequestTest.TestCancel/1 [07:15:44:INFO] [ OK ] UrlRequestTest.TestCancel/1 (71 ms) [07:15:44:INFO] [ RUN ] UrlRequestTest.PerfTest/0 [07:15:45:INFO] [32707:771:0309/071545.405043:25345676574571:INFO:url_request_test.cc(993)] Total time 1094.41 ms [07:15:45:INFO] [32707:771:0309/071545.405258:25345676766653:INFO:url_request_test.cc(994)] Single Iteration time 109.441 ms [07:15:45:INFO] [32707:771:0309/071545.405397:25345676908956:INFO:url_request_test.cc(1001)] Average Throughput: 1411.35 mbps [07:15:45:INFO] [ OK ] UrlRequestTest.PerfTest/0 (1138 ms) [07:15:45:INFO] [ RUN ] UrlRequestTest.PerfTest/1 [07:15:47:INFO] [32707:771:0309/071547.171337:25347442921690:INFO:url_request_test.cc(993)] Total time 1743.7 ms [07:15:47:INFO] [32707:771:0309/071547.171503:25347443079546:INFO:url_request_test.cc(994)] Single Iteration time 174.37 ms [07:15:47:INFO] [32707:771:0309/071547.171638:25347443213000:INFO:url_request_test.cc(1001)] Average Throughput: 885.814 mbps [07:15:47:INFO] [ OK ] UrlRequestTest.PerfTest/1 (1764 ms) [07:15:47:INFO] [ RUN ] UrlRequestTest.GetStatus/0 [07:15:47:INFO] [ OK ] UrlRequestTest.GetStatus/0 (8 ms) [07:15:47:INFO] [ RUN ] UrlRequestTest.GetStatus/1 [07:15:47:INFO] [ OK ] UrlRequestTest.GetStatus/1 (9 ms) [07:15:47:INFO] [----------] 57 tests from UrlRequestTest (3843 ms total) [07:15:47:INFO] [07:15:47:INFO] [----------] 5 tests from AcceptLangTest [07:15:47:INFO] [ RUN ] AcceptLangTest.Region [07:15:47:INFO] [ OK ] AcceptLangTest.Region (0 ms) [07:15:47:INFO] [ RUN ] AcceptLangTest.Lang [07:15:47:INFO] [ OK ] AcceptLangTest.Lang (0 ms) [07:15:47:INFO] [ RUN ] AcceptLangTest.Default [07:15:47:INFO] [ OK ] AcceptLangTest.Default (0 ms) [07:15:47:INFO] [ RUN ] AcceptLangTest.Append [07:15:47:INFO] [ OK ] AcceptLangTest.Append (0 ms) [07:15:47:INFO] [ RUN ] AcceptLangTest.NoDefaultAppend [07:15:47:INFO] 2019-03-09 07:15:47.204 cronet_test[32707:471581] en-GB,en-US,en [07:15:47:INFO] [ OK ] AcceptLangTest.NoDefaultAppend (0 ms) [07:15:47:INFO] [----------] 5 tests from AcceptLangTest (9 ms total) [07:15:47:INFO] [07:15:47:INFO] [----------] 21 tests from HttpTest [07:15:47:INFO] [ RUN ] HttpTest.CreateSslKeyLogFile [07:15:47:INFO] 2019-03-09 07:15:47.230 cronet_test[32707:471907] DiskCookieStorage changing policy from 2 to 0, cookie file: file:///Users/chrome-bot/Library/Developer/CoreSimulator/Devices/2813EAA7-3822-4FCB-8BE9-A876B16AAFFD/data/Containers/Data/Application/3994A110-9EE4-4C4A-9FD2-B936C75C252C/Library/Cookies/org.chromium.gtest.cronet-test.binarycookies [07:15:47:INFO] [0309/071547.256173:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:15:47:INFO] [0309/071547.261787:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:15:47:INFO] [ OK ] HttpTest.CreateSslKeyLogFile (58 ms) [07:15:47:INFO] [ RUN ] HttpTest.NSURLSessionReceivesData [07:15:47:INFO] [0309/071547.364252:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:15:47:INFO] [ OK ] HttpTest.NSURLSessionReceivesData (100 ms) [07:15:47:INFO] [ RUN ] HttpTest.GetGlobalMetricsDeltas [07:15:47:INFO] [0309/071547.434679:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:15:47:INFO] [ OK ] HttpTest.GetGlobalMetricsDeltas (69 ms) [07:15:47:INFO] [ RUN ] HttpTest.SdchDisabledByDefault [07:15:47:INFO] [0309/071547.455246:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:15:47:INFO] [ OK ] HttpTest.SdchDisabledByDefault (19 ms) [07:15:47:INFO] [ RUN ] HttpTest.AcceptEncodingSdchIsAllowed [07:15:47:INFO] [0309/071547.474496:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:15:47:INFO] [ OK ] HttpTest.AcceptEncodingSdchIsAllowed (18 ms) [07:15:47:INFO] [ RUN ] HttpTest.AcceptEncodingFooBarIsAllowed [07:15:47:INFO] [0309/071547.493653:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:15:47:INFO] [ OK ] HttpTest.AcceptEncodingFooBarIsAllowed (17 ms) [07:15:47:INFO] [ RUN ] HttpTest.NSURLSessionAcceptLanguage [07:15:47:INFO] [0309/071547.512834:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:15:47:INFO] [ OK ] HttpTest.NSURLSessionAcceptLanguage (17 ms) [07:15:47:INFO] [ RUN ] HttpTest.SetUserAgentIsExact [07:15:47:INFO] [0309/071547.531921:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:15:47:INFO] [ OK ] HttpTest.SetUserAgentIsExact (17 ms) [07:15:47:INFO] [ RUN ] HttpTest.SetCookie [07:15:47:INFO] [0309/071547.559175:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:15:47:INFO] [ OK ] HttpTest.SetCookie (26 ms) [07:15:47:INFO] [ RUN ] HttpTest.SetSystemCookie [07:15:47:INFO] [0309/071547.579137:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:15:47:INFO] [ OK ] HttpTest.SetSystemCookie (19 ms) [07:15:47:INFO] [ RUN ] HttpTest.SystemCookieWithNullCreationTime [07:15:47:INFO] [0309/071547.598981:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:15:47:INFO] [ OK ] HttpTest.SystemCookieWithNullCreationTime (19 ms) [07:15:47:INFO] [ RUN ] HttpTest.FilterOutRequest [07:15:47:INFO] [0309/071547.625661:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:15:47:INFO] [ OK ] HttpTest.FilterOutRequest (25 ms) [07:15:47:INFO] [ RUN ] HttpTest.FileSchemeNotSupported [07:15:47:INFO] [0309/071547.646349:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:15:47:INFO] [ OK ] HttpTest.FileSchemeNotSupported (19 ms) [07:15:47:INFO] [ RUN ] HttpTest.DataSchemeNotSupported [07:15:47:INFO] [0309/071547.662419:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:15:47:INFO] [ OK ] HttpTest.DataSchemeNotSupported (15 ms) [07:15:47:INFO] [ RUN ] HttpTest.BrotliAdvertisedTest [07:15:47:INFO] [0309/071547.676463:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:15:47:INFO] [0309/071547.685022:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:15:47:INFO] [ OK ] HttpTest.BrotliAdvertisedTest (21 ms) [07:15:47:INFO] [ RUN ] HttpTest.BrotliNotAdvertisedTest [07:15:47:INFO] [0309/071547.699222:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:15:47:INFO] [0309/071547.707571:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:15:47:INFO] [ OK ] HttpTest.BrotliNotAdvertisedTest (21 ms) [07:15:47:INFO] [ RUN ] HttpTest.BrotliHandleDecoding [07:15:47:INFO] [0309/071547.721559:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:15:47:INFO] [0309/071547.732734:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:15:47:INFO] [ OK ] HttpTest.BrotliHandleDecoding (23 ms) [07:15:47:INFO] [ RUN ] HttpTest.PostRequest [07:15:47:INFO] [0309/071547.752411:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:15:47:INFO] [ OK ] HttpTest.PostRequest (18 ms) [07:15:47:INFO] [ RUN ] HttpTest.PostRequestWithBodyStream [07:15:47:INFO] [0309/071547.773861:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:15:47:INFO] [ OK ] HttpTest.PostRequestWithBodyStream (19 ms) [07:15:47:INFO] [ RUN ] HttpTest.PostRequestWithLargeBodyStream [07:15:47:INFO] [0309/071547.794700:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:15:47:INFO] [ OK ] HttpTest.PostRequestWithLargeBodyStream (19 ms) [07:15:47:INFO] [ RUN ] HttpTest.LegacyApi [07:15:47:INFO] [0309/071547.855206:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:15:47:INFO] [ OK ] HttpTest.LegacyApi (59 ms) [07:15:47:INFO] [----------] 21 tests from HttpTest (651 ms total) [07:15:47:INFO] [07:15:47:INFO] [----------] 8 tests from CronetEnabledMetricsTest [07:15:47:INFO] [ RUN ] CronetEnabledMetricsTest.ProtocolIsQuic [07:15:47:INFO] [0309/071547.869572:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:15:47:INFO] [ OK ] CronetEnabledMetricsTest.ProtocolIsQuic (14 ms) [07:15:47:INFO] [ RUN ] CronetEnabledMetricsTest.ProtocolIsNotQuic [07:15:47:INFO] [0309/071547.884676:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:15:47:INFO] [ OK ] CronetEnabledMetricsTest.ProtocolIsNotQuic (13 ms) [07:15:47:INFO] [ RUN ] CronetEnabledMetricsTest.PlatformComparison [07:15:47:INFO] [0309/071547.899628:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:15:47:INFO] [ OK ] CronetEnabledMetricsTest.PlatformComparison (13 ms) [07:15:47:INFO] [ RUN ] CronetEnabledMetricsTest.InvalidURL [07:15:47:INFO] [0309/071547.914672:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:15:47:INFO] [ OK ] CronetEnabledMetricsTest.InvalidURL (13 ms) [07:15:47:INFO] [ RUN ] CronetEnabledMetricsTest.CanceledRequest [07:15:47:INFO] [0309/071547.929650:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:15:47:INFO] [ OK ] CronetEnabledMetricsTest.CanceledRequest (13 ms) [07:15:47:INFO] [ RUN ] CronetEnabledMetricsTest.ReusedConnection [07:15:47:INFO] [0309/071547.945101:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:15:47:INFO] [ OK ] CronetEnabledMetricsTest.ReusedConnection (14 ms) [07:15:47:INFO] [ RUN ] CronetEnabledMetricsTest.SessionWithoutDelegate [07:15:47:INFO] [0309/071547.962545:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:15:47:INFO] [ OK ] CronetEnabledMetricsTest.SessionWithoutDelegate (15 ms) [07:15:47:INFO] [ RUN ] CronetEnabledMetricsTest.LegacyApi [07:15:48:INFO] [0309/071548.017592:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:15:48:INFO] [ OK ] CronetEnabledMetricsTest.LegacyApi (53 ms) [07:15:48:INFO] [----------] 8 tests from CronetEnabledMetricsTest (164 ms total) [07:15:48:INFO] [07:15:48:INFO] [----------] 1 test from CronetDisabledMetricsTest [07:15:48:INFO] [ RUN ] CronetDisabledMetricsTest.MetricsDisabled [07:15:48:INFO] [0309/071548.033374:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:15:48:INFO] [ OK ] CronetDisabledMetricsTest.MetricsDisabled (14 ms) [07:15:48:INFO] [----------] 1 test from CronetDisabledMetricsTest (16 ms total) [07:15:48:INFO] [07:15:48:INFO] [----------] 7 tests from NetLogTest [07:15:48:INFO] [ RUN ] NetLogTest.OpenFile [07:15:48:INFO] [0309/071548.039009:WARNING:cronet_environment.mm(163)] Starting NetLog to /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/2813EAA7-3822-4FCB-8BE9-A876B16AAFFD/data/Containers/Data/Application/3994A110-9EE4-4C4A-9FD2-B936C75C252C/Documents/cronet_netlog.json [07:15:48:INFO] [0309/071548.049563:WARNING:cronet_environment.mm(186)] Started NetLog [07:15:48:INFO] [0309/071548.049766:WARNING:cronet_environment.mm(202)] Stopped NetLog. [07:15:48:INFO] [0309/071548.054535:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:15:48:INFO] [ OK ] NetLogTest.OpenFile (17 ms) [07:15:48:INFO] [ RUN ] NetLogTest.CreateFile [07:15:48:INFO] [0309/071548.058229:WARNING:cronet_environment.mm(163)] Starting NetLog to /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/2813EAA7-3822-4FCB-8BE9-A876B16AAFFD/data/Containers/Data/Application/3994A110-9EE4-4C4A-9FD2-B936C75C252C/Documents/97AD3565-F065-4EB0-875A-1FC4E9196B86-32707-0000170DDE2D8540_netlog.json [07:15:48:INFO] [0309/071548.068475:WARNING:cronet_environment.mm(186)] Started NetLog [07:15:48:INFO] [0309/071548.068672:WARNING:cronet_environment.mm(202)] Stopped NetLog. [07:15:48:INFO] [0309/071548.073631:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:15:48:INFO] [ OK ] NetLogTest.CreateFile (17 ms) [07:15:48:INFO] [ RUN ] NetLogTest.NonExistantDir [07:15:48:INFO] [0309/071548.077263:ERROR:cronet_environment.mm(158)] Can not start NetLog to /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/2813EAA7-3822-4FCB-8BE9-A876B16AAFFD/data/Containers/Data/Application/3994A110-9EE4-4C4A-9FD2-B936C75C252C/Documents/B35C542E-CCB4-46EE-BB59-DCE7DBDF9BC0-32707-0000170DDF516737/netlog.json: Undefined error: 0 [07:15:48:INFO] [0309/071548.078873:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:15:48:INFO] [ OK ] NetLogTest.NonExistantDir (3 ms) [07:15:48:INFO] [ RUN ] NetLogTest.ExistantDir [07:15:48:INFO] [0309/071548.082660:WARNING:cronet_environment.mm(163)] Starting NetLog to /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/2813EAA7-3822-4FCB-8BE9-A876B16AAFFD/data/Containers/Data/Application/3994A110-9EE4-4C4A-9FD2-B936C75C252C/Documents/2256B97F-E98D-49A4-A009-03E241F29310-32707-0000170DDFA00FF1/netlog.json [07:15:48:INFO] [0309/071548.092776:WARNING:cronet_environment.mm(186)] Started NetLog [07:15:48:INFO] [0309/071548.093015:WARNING:cronet_environment.mm(202)] Stopped NetLog. [07:15:48:INFO] [0309/071548.098298:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:15:48:INFO] [ OK ] NetLogTest.ExistantDir (18 ms) [07:15:48:INFO] [ RUN ] NetLogTest.EmptyFilename [07:15:48:INFO] [0309/071548.103369:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:15:48:INFO] [ OK ] NetLogTest.EmptyFilename (3 ms) [07:15:48:INFO] [ RUN ] NetLogTest.AbsoluteFilename [07:15:48:INFO] [0309/071548.108379:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:15:48:INFO] [ OK ] NetLogTest.AbsoluteFilename (3 ms) [07:15:48:INFO] [ RUN ] NetLogTest.ExperimentalOptions [07:15:48:INFO] [0309/071548.113213:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:15:48:INFO] [0309/071548.115422:WARNING:cronet_environment.mm(163)] Starting NetLog to /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/2813EAA7-3822-4FCB-8BE9-A876B16AAFFD/data/Containers/Data/Application/3994A110-9EE4-4C4A-9FD2-B936C75C252C/Documents/cronet_netlog.json [07:15:48:INFO] [0309/071548.125302:WARNING:cronet_environment.mm(186)] Started NetLog [07:15:48:INFO] [0309/071548.125542:WARNING:cronet_environment.mm(202)] Stopped NetLog. [07:15:48:INFO] [0309/071548.131043:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:15:48:INFO] [ OK ] NetLogTest.ExperimentalOptions (21 ms) [07:15:48:INFO] [----------] 7 tests from NetLogTest (95 ms total) [07:15:48:INFO] [07:15:48:INFO] [----------] 12 tests from PkpTest [07:15:48:INFO] [ RUN ] PkpTest.TestSuccessIfPinSetForDifferentHost [07:15:48:INFO] [0309/071548.186474:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:15:48:INFO] [ OK ] PkpTest.TestSuccessIfPinSetForDifferentHost (55 ms) [07:15:48:INFO] [ RUN ] PkpTest.TestErrorIfPinDoesNotMatch [07:15:48:INFO] [0309/071548.217802:WARNING:proof_verifier_chromium.cc(501)] Failed to verify certificate chain: net::ERR_SSL_PINNED_KEY_NOT_IN_CERT_CHAIN [07:15:48:INFO] [0309/071548.219899:ERROR:crn_http_protocol_handler.mm(845)] HttpProtocolHandlerCore - Network error: net::ERR_CONNECTION_REFUSED (-102) [07:15:48:INFO] [0309/071548.226085:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:15:48:INFO] [ OK ] PkpTest.TestErrorIfPinDoesNotMatch (38 ms) [07:15:48:INFO] [ RUN ] PkpTest.TestSuccessIfPinMatches [07:15:48:INFO] [0309/071548.284642:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:15:48:INFO] [ OK ] PkpTest.TestSuccessIfPinMatches (57 ms) [07:15:48:INFO] [ RUN ] PkpTest.TestBypass [07:15:48:INFO] [ OK ] PkpTest.TestBypass (8 ms) [07:15:48:INFO] [ RUN ] PkpTest.TestIncludeSubdomainsFlagEqualTrue [07:15:48:INFO] [0309/071548.326056:WARNING:proof_verifier_chromium.cc(501)] Failed to verify certificate chain: net::ERR_SSL_PINNED_KEY_NOT_IN_CERT_CHAIN [07:15:48:INFO] [0309/071548.328003:ERROR:crn_http_protocol_handler.mm(845)] HttpProtocolHandlerCore - Network error: net::ERR_CONNECTION_REFUSED (-102) [07:15:48:INFO] [0309/071548.335455:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:15:48:INFO] [ OK ] PkpTest.TestIncludeSubdomainsFlagEqualTrue (39 ms) [07:15:48:INFO] [ RUN ] PkpTest.TestIncludeSubdomainsFlagEqualFalse [07:15:48:INFO] [0309/071548.392360:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:15:48:INFO] [ OK ] PkpTest.TestIncludeSubdomainsFlagEqualFalse (55 ms) [07:15:48:INFO] [ RUN ] PkpTest.TestSoonExpiringPin [07:15:48:INFO] [0309/071548.423212:WARNING:proof_verifier_chromium.cc(501)] Failed to verify certificate chain: net::ERR_SSL_PINNED_KEY_NOT_IN_CERT_CHAIN [07:15:48:INFO] [0309/071548.425216:ERROR:crn_http_protocol_handler.mm(845)] HttpProtocolHandlerCore - Network error: net::ERR_CONNECTION_REFUSED (-102) [07:15:48:INFO] [0309/071548.430155:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:15:48:INFO] [ OK ] PkpTest.TestSoonExpiringPin (36 ms) [07:15:48:INFO] [ RUN ] PkpTest.TestRecentlyExpiredPin [07:15:48:INFO] [0309/071548.486121:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:15:48:INFO] [ OK ] PkpTest.TestRecentlyExpiredPin (55 ms) [07:15:48:INFO] [ RUN ] PkpTest.TestPinsAreNotPersisted [07:15:48:INFO] [0309/071548.517309:WARNING:proof_verifier_chromium.cc(501)] Failed to verify certificate chain: net::ERR_SSL_PINNED_KEY_NOT_IN_CERT_CHAIN [07:15:48:INFO] [0309/071548.519336:ERROR:crn_http_protocol_handler.mm(845)] HttpProtocolHandlerCore - Network error: net::ERR_CONNECTION_REFUSED (-102) [07:15:48:INFO] [0309/071548.524573:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:15:48:INFO] [0309/071548.564824:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:15:48:INFO] [ OK ] PkpTest.TestPinsAreNotPersisted (77 ms) [07:15:48:INFO] [ RUN ] PkpTest.TestHashLengthError [07:15:48:INFO] [ OK ] PkpTest.TestHashLengthError (9 ms) [07:15:48:INFO] [ RUN ] PkpTest.TestPkpOverrideNonMatchingToMatching [07:15:48:INFO] [0309/071548.633737:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:15:48:INFO] [ OK ] PkpTest.TestPkpOverrideNonMatchingToMatching (56 ms) [07:15:48:INFO] [ RUN ] PkpTest.TestPkpOverrideMatchingToNonMatching [07:15:48:INFO] [0309/071548.665652:WARNING:proof_verifier_chromium.cc(501)] Failed to verify certificate chain: net::ERR_SSL_PINNED_KEY_NOT_IN_CERT_CHAIN [07:15:48:INFO] [0309/071548.667636:ERROR:crn_http_protocol_handler.mm(845)] HttpProtocolHandlerCore - Network error: net::ERR_CONNECTION_REFUSED (-102) [07:15:48:INFO] [0309/071548.672890:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:15:48:INFO] [ OK ] PkpTest.TestPkpOverrideMatchingToNonMatching (37 ms) [07:15:48:INFO] [----------] 12 tests from PkpTest (543 ms total) [07:15:48:INFO] [07:15:48:INFO] [----------] 1 test from PrefsTest [07:15:48:INFO] [ RUN ] PrefsTest.HttpSeverProperties [07:15:48:INFO] 2019-03-09 07:15:48.720 cronet_test[32707:471581] Waiting for file /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/2813EAA7-3822-4FCB-8BE9-A876B16AAFFD/data/Containers/Data/Application/3994A110-9EE4-4C4A-9FD2-B936C75C252C/Library/Caches/cronet/prefs/local_prefs.json. [07:15:48:INFO] 2019-03-09 07:15:48.721 cronet_test[32707:471581] Time left: 80 seconds [07:15:53:INFO] 2019-03-09 07:15:53.723 cronet_test[32707:471581] Time left: 75 seconds [07:15:58:INFO] 2019-03-09 07:15:58.725 cronet_test[32707:471581] Time left: 70 seconds [07:16:03:INFO] 2019-03-09 07:16:03.728 cronet_test[32707:471581] Time left: 65 seconds [07:16:08:INFO] 2019-03-09 07:16:08.730 cronet_test[32707:471581] Time left: 60 seconds [07:16:13:INFO] 2019-03-09 07:16:13.732 cronet_test[32707:471581] Time left: 55 seconds [07:16:18:INFO] 2019-03-09 07:16:18.734 cronet_test[32707:471581] Time left: 50 seconds [07:16:23:INFO] 2019-03-09 07:16:23.736 cronet_test[32707:471581] Time left: 45 seconds [07:16:28:INFO] 2019-03-09 07:16:28.739 cronet_test[32707:471581] Time left: 40 seconds [07:16:33:INFO] 2019-03-09 07:16:33.741 cronet_test[32707:471581] Time left: 35 seconds [07:16:38:INFO] 2019-03-09 07:16:38.744 cronet_test[32707:471581] Time left: 30 seconds [07:16:43:INFO] 2019-03-09 07:16:43.746 cronet_test[32707:471581] Time left: 25 seconds [07:16:48:INFO] 2019-03-09 07:16:48.749 cronet_test[32707:471581] Time left: 20 seconds [07:16:53:INFO] 2019-03-09 07:16:53.751 cronet_test[32707:471581] Time left: 15 seconds [07:16:58:INFO] 2019-03-09 07:16:58.754 cronet_test[32707:471581] File /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/2813EAA7-3822-4FCB-8BE9-A876B16AAFFD/data/Containers/Data/Application/3994A110-9EE4-4C4A-9FD2-B936C75C252C/Library/Caches/cronet/prefs/local_prefs.json exists. [07:16:58:INFO] [0309/071658.761358:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:16:58:INFO] [ OK ] PrefsTest.HttpSeverProperties (70087 ms) [07:16:58:INFO] [----------] 1 test from PrefsTest (70089 ms total) [07:16:58:INFO] [07:16:58:INFO] [----------] 3 tests from QuicTest [07:16:58:INFO] [ RUN ] QuicTest.InvalidQuicHost [07:16:58:INFO] [0309/071658.772382:ERROR:Cronet.mm(218)] Invalid QUIC hint host: https://test.example.com/ [07:16:58:INFO] [ OK ] QuicTest.InvalidQuicHost (9 ms) [07:16:58:INFO] [ RUN ] QuicTest.ValidQuicHost [07:16:58:INFO] [ OK ] QuicTest.ValidQuicHost (8 ms) [07:16:58:INFO] [ RUN ] QuicTest.RequestWithSocketOptimizationEnabled [07:16:58:INFO] [0309/071658.848403:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:16:58:INFO] [ OK ] QuicTest.RequestWithSocketOptimizationEnabled (64 ms) [07:16:58:INFO] [----------] 3 tests from QuicTest (87 ms total) [07:16:58:INFO] [07:16:58:INFO] [----------] 32 tests from BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest [07:16:58:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.StartExampleBidiStream/0 [07:16:58:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.StartExampleBidiStream/0 (61 ms) [07:16:58:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.StartExampleBidiStream/1 [07:16:58:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.StartExampleBidiStream/1 (62 ms) [07:16:58:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.SimplePutWithEmptyWriteDataAtTheEnd/0 [07:16:59:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.SimplePutWithEmptyWriteDataAtTheEnd/0 (57 ms) [07:16:59:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.SimplePutWithEmptyWriteDataAtTheEnd/1 [07:16:59:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.SimplePutWithEmptyWriteDataAtTheEnd/1 (60 ms) [07:16:59:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.SimpleGetWithFlush/0 [07:16:59:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.SimpleGetWithFlush/0 (58 ms) [07:16:59:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.SimpleGetWithFlush/1 [07:16:59:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.SimpleGetWithFlush/1 (61 ms) [07:16:59:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.SimplePostWithFlush/0 [07:16:59:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.SimplePostWithFlush/0 (57 ms) [07:16:59:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.SimplePostWithFlush/1 [07:16:59:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.SimplePostWithFlush/1 (64 ms) [07:16:59:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.SimplePostWithFlushTwice/0 [07:16:59:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.SimplePostWithFlushTwice/0 (62 ms) [07:16:59:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.SimplePostWithFlushTwice/1 [07:16:59:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.SimplePostWithFlushTwice/1 (60 ms) [07:16:59:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.SimplePostWithFlushAfterOneWrite/0 [07:16:59:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.SimplePostWithFlushAfterOneWrite/0 (58 ms) [07:16:59:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.SimplePostWithFlushAfterOneWrite/1 [07:16:59:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.SimplePostWithFlushAfterOneWrite/1 (56 ms) [07:16:59:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.TestDelayedFlush/0 [07:16:59:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.TestDelayedFlush/0 (64 ms) [07:16:59:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.TestDelayedFlush/1 [07:16:59:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.TestDelayedFlush/1 (57 ms) [07:16:59:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.CancelOnRead/0 [07:16:59:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.CancelOnRead/0 (55 ms) [07:16:59:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.CancelOnRead/1 [07:16:59:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.CancelOnRead/1 (62 ms) [07:16:59:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.CancelOnResponse/0 [07:16:59:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.CancelOnResponse/0 (57 ms) [07:16:59:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.CancelOnResponse/1 [07:16:59:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.CancelOnResponse/1 (62 ms) [07:16:59:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.CancelOnSucceeded/0 [07:17:00:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.CancelOnSucceeded/0 (60 ms) [07:17:00:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.CancelOnSucceeded/1 [07:17:00:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.CancelOnSucceeded/1 (60 ms) [07:17:00:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.ReadFailsBeforeRequestStarted/0 [07:17:00:INFO] [0309/071700.088017:ERROR:bidirectional_stream.cc(286)] Unexpected Read Data in read_state 0 [07:17:00:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.ReadFailsBeforeRequestStarted/0 (12 ms) [07:17:00:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.ReadFailsBeforeRequestStarted/1 [07:17:00:INFO] [0309/071700.105445:ERROR:bidirectional_stream.cc(286)] Unexpected Read Data in read_state 0 [07:17:00:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.ReadFailsBeforeRequestStarted/1 (15 ms) [07:17:00:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.StreamFailBeforeReadIsExecutedOnNetworkThread/0 [07:17:00:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.StreamFailBeforeReadIsExecutedOnNetworkThread/0 (62 ms) [07:17:00:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.StreamFailBeforeReadIsExecutedOnNetworkThread/1 [07:17:00:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.StreamFailBeforeReadIsExecutedOnNetworkThread/1 (59 ms) [07:17:00:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.WriteFailsBeforeRequestStarted/0 [07:17:00:INFO] [0309/071700.243380:ERROR:bidirectional_stream.cc(315)] Unexpected Flush Data in write_state 0 [07:17:00:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.WriteFailsBeforeRequestStarted/0 (12 ms) [07:17:00:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.WriteFailsBeforeRequestStarted/1 [07:17:00:INFO] [0309/071700.256926:ERROR:bidirectional_stream.cc(315)] Unexpected Flush Data in write_state 0 [07:17:00:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.WriteFailsBeforeRequestStarted/1 (11 ms) [07:17:00:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.StreamFailAfterStreamReadyCallback/0 [07:17:00:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.StreamFailAfterStreamReadyCallback/0 (56 ms) [07:17:00:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.StreamFailAfterStreamReadyCallback/1 [07:17:00:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.StreamFailAfterStreamReadyCallback/1 (57 ms) [07:17:00:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.StreamFailBeforeWriteIsExecutedOnNetworkThread/0 [07:17:00:INFO] [0309/071700.429030:ERROR:bidirectional_stream_quic_impl.cc(175)] Trying to send data after stream has been closed. [07:17:00:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.StreamFailBeforeWriteIsExecutedOnNetworkThread/0 (54 ms) [07:17:00:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.StreamFailBeforeWriteIsExecutedOnNetworkThread/1 [07:17:00:INFO] [0309/071700.488369:ERROR:bidirectional_stream_quic_impl.cc(175)] Trying to send data after stream has been closed. [07:17:00:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.StreamFailBeforeWriteIsExecutedOnNetworkThread/1 (58 ms) [07:17:00:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.FailedResolution/0 [07:17:00:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.FailedResolution/0 (14 ms) [07:17:00:INFO] [ RUN ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.FailedResolution/1 [07:17:00:INFO] [ OK ] BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest.FailedResolution/1 (13 ms) [07:17:00:INFO] [----------] 32 tests from BidirectionalStreamDelayRequestHeadersUntilFlush/BidirectionalStreamTest (1673 ms total) [07:17:00:INFO] [07:17:00:INFO] [----------] 5 tests from Loops/PerfTest [07:17:00:INFO] [ RUN ] Loops/PerfTest.NSURLSessionReceivesImageLoop/0 [07:17:00:INFO] [32707:771:0309/071700.534407:25420808824591:INFO:cronet_performance_test.mm(181)] Running with parameters: QUIC: 0 HTTP2: 0 AKD4: 0 Cronet: 0 [07:17:00:INFO] [32707:771:0309/071700.534624:25420809020879:INFO:cronet_performance_test.mm(191)] Downloading 19307439 bytes per iteration [07:17:01:INFO] [32707:771:0309/071701.349993:25421624427520:INFO:cronet_performance_test.mm(253)] Elapsed Total:795.85ms [07:17:01:INFO] [0309/071701.354354:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:17:01:INFO] [ OK ] Loops/PerfTest.NSURLSessionReceivesImageLoop/0 (831 ms) [07:17:01:INFO] [ RUN ] Loops/PerfTest.NSURLSessionReceivesImageLoop/1 [07:17:01:INFO] [32707:771:0309/071701.367668:25421642093183:INFO:cronet_performance_test.mm(181)] Running with parameters: QUIC: 0 HTTP2: 0 AKD4: 0 Cronet: 1 [07:17:01:INFO] [32707:771:0309/071701.367814:25421642228813:INFO:cronet_performance_test.mm(191)] Downloading 19307439 bytes per iteration [07:17:01:INFO] [32707:771:0309/071701.875336:25422149791624:INFO:cronet_performance_test.mm(253)] Elapsed Total:491.292ms [07:17:01:INFO] [0309/071701.879683:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:17:01:INFO] [ OK ] Loops/PerfTest.NSURLSessionReceivesImageLoop/1 (523 ms) [07:17:01:INFO] [ RUN ] Loops/PerfTest.NSURLSessionReceivesImageLoop/2 [07:17:01:INFO] [32707:771:0309/071701.893000:25422167437973:INFO:cronet_performance_test.mm(181)] Running with parameters: QUIC: 0 HTTP2: 1 AKD4: 0 Cronet: 1 [07:17:01:INFO] [32707:771:0309/071701.893144:25422167580533:INFO:cronet_performance_test.mm(191)] Downloading 19307439 bytes per iteration [07:17:02:INFO] [32707:771:0309/071702.396259:25422670731088:INFO:cronet_performance_test.mm(253)] Elapsed Total:486.805ms [07:17:02:INFO] [0309/071702.400575:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:17:02:INFO] [ OK ] Loops/PerfTest.NSURLSessionReceivesImageLoop/2 (519 ms) [07:17:02:INFO] [ RUN ] Loops/PerfTest.NSURLSessionReceivesImageLoop/3 [07:17:02:INFO] [32707:771:0309/071702.413776:25422688237650:INFO:cronet_performance_test.mm(181)] Running with parameters: QUIC: 1 HTTP2: 0 AKD4: 0 Cronet: 1 [07:17:02:INFO] [32707:771:0309/071702.413923:25422688382420:INFO:cronet_performance_test.mm(191)] Downloading 19307439 bytes per iteration [07:17:02:INFO] [32707:771:0309/071702.927509:25423202003044:INFO:cronet_performance_test.mm(253)] Elapsed Total:497.017ms [07:17:02:INFO] [0309/071702.931899:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:17:02:INFO] [ OK ] Loops/PerfTest.NSURLSessionReceivesImageLoop/3 (529 ms) [07:17:02:INFO] [ RUN ] Loops/PerfTest.NSURLSessionReceivesImageLoop/4 [07:17:02:INFO] [32707:771:0309/071702.945103:25423219587482:INFO:cronet_performance_test.mm(181)] Running with parameters: QUIC: 1 HTTP2: 0 AKD4: 1 Cronet: 1 [07:17:02:INFO] [32707:771:0309/071702.945249:25423219724824:INFO:cronet_performance_test.mm(191)] Downloading 19307439 bytes per iteration [07:17:03:INFO] [32707:771:0309/071703.456508:25423731021343:INFO:cronet_performance_test.mm(253)] Elapsed Total:494.995ms [07:17:03:INFO] [0309/071703.460886:WARNING:url_request_context_getter.cc(43)] URLRequestContextGetter leaking due to no owning thread. [07:17:03:INFO] [ OK ] Loops/PerfTest.NSURLSessionReceivesImageLoop/4 (527 ms) [07:17:03:INFO] [32707:771:0309/071703.463601:25423738097160:INFO:cronet_performance_test.mm(71)] Performance Data: [07:17:03:INFO] [32707:771:0309/071703.463764:25423738262950:INFO:cronet_performance_test.mm(88)] Quic 0 Http2 0 AKD4 0 Cronet 0: Mean: 0.079585s (1940.811768mbps) Max: 0.091758s with 0 fails out of 10 total requests. [07:17:03:INFO] [32707:771:0309/071703.463919:25423738416124:INFO:cronet_performance_test.mm(88)] Quic 0 Http2 0 AKD4 0 Cronet 1: Mean: 0.049129s (3143.945191mbps) Max: 0.051708s with 0 fails out of 10 total requests. [07:17:03:INFO] [32707:771:0309/071703.464071:25423738571518:INFO:cronet_performance_test.mm(88)] Quic 0 Http2 1 AKD4 0 Cronet 1: Mean: 0.048680s (3172.923943mbps) Max: 0.050269s with 0 fails out of 10 total requests. [07:17:03:INFO] [32707:771:0309/071703.464219:25423738713683:INFO:cronet_performance_test.mm(88)] Quic 1 Http2 0 AKD4 0 Cronet 1: Mean: 0.049702s (3107.731546mbps) Max: 0.052444s with 0 fails out of 10 total requests. [07:17:03:INFO] [32707:771:0309/071703.464361:25423738863595:INFO:cronet_performance_test.mm(88)] Quic 1 Http2 0 AKD4 1 Cronet 1: Mean: 0.049499s (3120.426466mbps) Max: 0.053556s with 0 fails out of 10 total requests. [07:17:03:INFO] [----------] 5 tests from Loops/PerfTest (2939 ms total) [07:17:03:INFO] [07:17:03:INFO] [----------] Global test environment tear-down [07:17:03:INFO] [==========] 166 tests from 14 test suites ran. (80389 ms total) [07:17:03:INFO] [ PASSED ] 166 tests. [07:17:03:INFO] [07:17:03:INFO] YOU HAVE 2 DISABLED TESTS [07:17:03:INFO] [07:17:14:INFO] Testing started on 'iPhone 5s' [07:17:14:INFO] 2019-03-09 07:15:22.538 xcodebuild[32613:470289] IDETestOperationsObserverDebug: Writing diagnostic log for test session to: [07:17:14:INFO] /Users/chrome-bot/Library/Developer/Xcode/DerivedData/temporary-ddzuyiffrphvamexmmcxtpcwlnkh/Logs/Test/Test-Transient Testing-2019.03.09_07-15-22--0800.xcresult/1_Test/Diagnostics/cronet_test-96003681-19D1-4461-8063-21B6A58993C3/cronet_test-C14E3151-7E84-428C-B9A0-335A18935BE5/Session-cronet_test-2019-03-09_071522-O3I9Dv.log [07:17:14:INFO] 2019-03-09 07:15:22.539 xcodebuild[32613:470276] [MT] IDETestOperationsObserverDebug: (C1E4603A-FFD5-4FCD-816D-22FA0D25D16C) Beginning test session cronet_test-C1E4603A-FFD5-4FCD-816D-22FA0D25D16C at 2019-03-09 07:15:22.539 with Xcode 10B61 on target <DVTiPhoneSimulator: 0x7fdbcde625a0> { [07:17:14:INFO] SimDevice: iPhone 5s (2813EAA7-3822-4FCB-8BE9-A876B16AAFFD, iOS 9.3, Shutdown) [07:17:14:INFO] } (9.3 (13E233)) [07:17:14:INFO] 2019-03-09 07:17:13.000 xcodebuild[32613:470276] [MT] IDETestOperationsObserverDebug: 110.560 elapsed -- Testing started completed. [07:17:14:INFO] 2019-03-09 07:17:13.000 xcodebuild[32613:470276] [MT] IDETestOperationsObserverDebug: 0.000 sec, +0.000 sec -- start [07:17:14:INFO] 2019-03-09 07:17:13.001 xcodebuild[32613:470276] [MT] IDETestOperationsObserverDebug: 110.560 sec, +110.559 sec -- end [07:17:14:INFO] [07:17:14:INFO] Testing failed: [07:17:14:INFO] cronet_test.app (32707) 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:17:14:INFO] Waiting for test process to terminate. [07:17:14:INFO] Test process terminated. [07:17:14:DEBUG] Setting sigterm handler. [07:17:14:DEBUG] Stdout flushed after test process. [07:17:14:DEBUG] Processing test results. [07:17:14:INFO] /b/s/w/ir/src/out/Debug-iphonesimulator/iossim returned 65 [07:17:14:DEBUG] Extracting test data. [07:17:14:DEBUG] Retrieving crash reports. [07:17:14:DEBUG] Retrieving derived data. [07:17:14:DEBUG] Making desktop screenshots. [07:17:14:DEBUG] Killing simulators. [07:17:19:DEBUG] Wiping simulator. Device wiped. [07:17:21:DEBUG] End of tear_down. 2019-03-09 07:18:12,497 - root: [DEBUG] Contents of task_output_dir: ['/b/s/w/ir/k/recipe_cleanup/1hE6JLt/437bd6fec30d4a10'] 2019-03-09 07:18:12,497 - root: [WARNING] Expected output.json file missing: set(['/b/s/w/ir/k/recipe_cleanup/1hE6JLt/437bd6fec30d4a10/output.json']) Found: [] Expected: ['/b/s/w/ir/k/recipe_cleanup/1hE6JLt/437bd6fec30d4a10/output.json'] 2019-03-09 07:18:12,497 - root: [WARNING] No shard json files found in task_output_dir: '/b/s/w/ir/k/recipe_cleanup/1hE6JLt' Found ['/b/s/w/ir/k/recipe_cleanup/1hE6JLt/437bd6fec30d4a10'] 2019-03-09 07:18:12,497 - root: [DEBUG] Found shard_json_files: ['/b/s/w/ir/k/recipe_cleanup/1hE6JLt/437bd6fec30d4a10/output.json'] 2019-03-09 07:18:12,497 - 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/tmprTVhQm.json --task-output-dir /b/s/w/ir/k/recipe_cleanup/1hE6JLt -o /b/s/w/ir/tmp/t/tmpxEh_1W.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/tmprTVhQm.json', '--task-output-dir', '/b/s/w/ir/k/recipe_cleanup/1hE6JLt', '-o', '/b/s/w/ir/tmp/t/tmpxEh_1W.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/tmprTVhQm.json', '--task-output-dir', '/b/s/w/ir/k/recipe_cleanup/1hE6JLt', '-o', '/b/s/w/ir/tmp/t/tmpxEh_1W.json'] returned exit code 0