[==========] Running 8 tests from 1 test suite.
[----------] Global test environment set-up.
[----------] 8 tests from MultiThreadedRpcTest
[ RUN      ] MultiThreadedRpcTest.TestShutdownDuringService
WARNING: Logging before InitGoogleLogging() is written to STDERR
I20260812 08:03:51.864913 32061 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 0.0.0.0:41173 every 8 connection(s)
I20260812 08:03:51.864916 32062 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 0.0.0.0:41173 every 8 connection(s)
I20260812 08:03:51.867491 32068 mt-rpc-test.cc:111] Connecting to 0.0.0.0:41173
I20260812 08:03:51.867535 32067 mt-rpc-test.cc:111] Connecting to 0.0.0.0:41173
I20260812 08:03:51.867514 32071 mt-rpc-test.cc:111] Connecting to 0.0.0.0:41173
I20260812 08:03:51.867515 32076 mt-rpc-test.cc:111] Connecting to 0.0.0.0:41173
I20260812 08:03:51.916571 32076 mt-rpc-test.cc:121] Call failed. Shutting down client thread. Ran 99 calls: Remote error: Service unavailable: service kudu.rpc.GenericCalculatorService not registered on TestServer
I20260812 08:03:51.916643 32068 mt-rpc-test.cc:121] Call failed. Shutting down client thread. Ran 97 calls: Remote error: Service unavailable: service kudu.rpc.GenericCalculatorService not registered on TestServer
I20260812 08:03:51.916615 32067 mt-rpc-test.cc:121] Call failed. Shutting down client thread. Ran 86 calls: Remote error: Service unavailable: service kudu.rpc.GenericCalculatorService not registered on TestServer
I20260812 08:03:51.917303 32071 mt-rpc-test.cc:121] Call failed. Shutting down client thread. Ran 85 calls: Remote error: Service unavailable: service kudu.rpc.GenericCalculatorService not registered on TestServer
W20260812 08:03:51.919267 32036 rpc-test-base.h:477] error unregistering service: Service unavailable: service kudu.rpc.GenericCalculatorService not registered on TestServer
[       OK ] MultiThreadedRpcTest.TestShutdownDuringService (63 ms)
[ RUN      ] MultiThreadedRpcTest.TestShutdownClientWhileCallsPending
I20260812 08:03:51.921814 32148 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 0.0.0.0:45975 every 8 connection(s)
I20260812 08:03:51.921883 32149 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 0.0.0.0:45975 every 8 connection(s)
I20260812 08:03:51.923346 32169 mt-rpc-test.cc:111] Connecting to 0.0.0.0:45975
I20260812 08:03:51.923923 32169 mt-rpc-test.cc:121] Call failed. Shutting down client thread. Ran 1 calls: Service unavailable: Unable to start connection negotiation thread: The pool has been shut down.
W20260812 08:03:51.924542 32141 negotiation.cc:336] Failed RPC negotiation. Trace:
0812 08:03:51.923848 (+     0us) reactor.cc:730] Submitting negotiation task for server connection from 127.0.0.1:48268 (local address 127.0.0.1:45975)
0812 08:03:51.923977 (+   129us) server_negotiation.cc:207] Beginning negotiation
0812 08:03:51.923982 (+     5us) server_negotiation.cc:400] Waiting for connection header
0812 08:03:51.924096 (+   114us) negotiation.cc:326] Negotiation complete: Network error: Server connection negotiation failed: server connection from 127.0.0.1:48268: BlockingRecv error: recv got EOF from 127.0.0.1:48268 (error 108)
Metrics: {"server-negotiator.queue_time_us":30}
[       OK ] MultiThreadedRpcTest.TestShutdownClientWhileCallsPending (5 ms)
[ RUN      ] MultiThreadedRpcTest.TestBlowOutServiceQueue
I20260812 08:03:51.926489 32173 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 0.0.0.0:42415 every 8 connection(s)
I20260812 08:03:51.926528 32174 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 0.0.0.0:42415 every 8 connection(s)
I20260812 08:03:51.926671 32175 mt-rpc-test.cc:91] Connecting to 0.0.0.0:42415
I20260812 08:03:51.926718 32176 mt-rpc-test.cc:91] Connecting to 0.0.0.0:42415
I20260812 08:03:51.926748 32177 mt-rpc-test.cc:91] Connecting to 0.0.0.0:42415
W20260812 08:03:51.931090 32172 service_pool.cc:136] Add request on kudu.rpc.GenericCalculatorService from 127.0.0.1:43474 dropped due to backpressure: service queue is full with 2 items
I20260812 08:03:51.931139 32172 service_pool.cc:137] Add request on kudu.rpc.GenericCalculatorService from 127.0.0.1:43474 dropped due to backpressure: service queue is full with 2 items Contents of service queue:
Call kudu.rpc.GenericCalculatorService.Add from 127.0.0.1:43476 (request call id 0)
Call kudu.rpc.GenericCalculatorService.Add from 127.0.0.1:43492 (request call id 0)
W20260812 08:03:51.933696 32036 rpc-test-base.h:477] error unregistering service: Service unavailable: service kudu.rpc.GenericCalculatorService not registered on messenger1
[       OK ] MultiThreadedRpcTest.TestBlowOutServiceQueue (8 ms)
[ RUN      ] MultiThreadedRpcTest.ReactorMaxIOLatencyStatsDisabled
I20260812 08:03:51.935829 32247 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 0.0.0.0:37729 every 8 connection(s)
I20260812 08:03:51.935889 32249 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 0.0.0.0:37729 every 8 connection(s)
[       OK ] MultiThreadedRpcTest.ReactorMaxIOLatencyStatsDisabled (1044 ms)
[ RUN      ] MultiThreadedRpcTest.ReactorMaxIOLatencyStatsEnabled
I20260812 08:03:52.980973 32283 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 0.0.0.0:37833 every 8 connection(s)
I20260812 08:03:52.981056 32284 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 0.0.0.0:37833 every 8 connection(s)
[       OK ] MultiThreadedRpcTest.ReactorMaxIOLatencyStatsEnabled (726 ms)
[ RUN      ] MultiThreadedRpcTest.MediumSizeSidecars
I20260812 08:03:53.707994 32382 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 0.0.0.0:46219 every 8 connection(s)
I20260812 08:03:53.708091 32383 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 0.0.0.0:46219 every 8 connection(s)
I20260812 08:03:58.943449 32036 mt-rpc-test.cc:501] 16 threads completed 1024 iterations each in 5231.326 ms
I20260812 08:03:58.943554 32036 mt-rpc-test.cc:503] CPU system:  0.000 ms
I20260812 08:03:58.943576 32036 mt-rpc-test.cc:504] CPU user  :  0.011 ms
[       OK ] MultiThreadedRpcTest.MediumSizeSidecars (5240 ms)
[ RUN      ] MultiThreadedRpcTest.PerMethodQueueOverflowRejectionCounter
I20260812 08:03:58.948318 32663 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 0.0.0.0:46063 every 8 connection(s)
W20260812 08:03:58.963584 32660 service_pool.cc:136] Sleep request on kudu.rpc_test.CalculatorService from 127.0.0.1:57414 dropped due to backpressure: service queue is full with 1 items
I20260812 08:03:58.963649 32660 service_pool.cc:137] Sleep request on kudu.rpc_test.CalculatorService from 127.0.0.1:57414 dropped due to backpressure: service queue is full with 1 items Contents of service queue:
Call kudu.rpc_test.CalculatorService.Sleep from 127.0.0.1:57402 (request call id 0)
I20260812 08:03:58.964376 32660 service_pool.cc:137] Sleep request on kudu.rpc_test.CalculatorService from 127.0.0.1:57448 dropped due to backpressure: service queue is full with 1 items Contents of service queue:
Call kudu.rpc_test.CalculatorService.Sleep from 127.0.0.1:57402 (request call id 0)
I20260812 08:03:58.964670 32660 service_pool.cc:137] Sleep request on kudu.rpc_test.CalculatorService from 127.0.0.1:57428 dropped due to backpressure: service queue is full with 1 items Contents of service queue:
Call kudu.rpc_test.CalculatorService.Sleep from 127.0.0.1:57402 (request call id 0)
I20260812 08:03:58.965025 32660 service_pool.cc:137] Sleep request on kudu.rpc_test.CalculatorService from 127.0.0.1:57434 dropped due to backpressure: service queue is full with 1 items Contents of service queue:
Call kudu.rpc_test.CalculatorService.Sleep from 127.0.0.1:57402 (request call id 0)
I20260812 08:03:58.969429 32660 service_pool.cc:137] Sleep request on kudu.rpc_test.CalculatorService from 127.0.0.1:57518 dropped due to backpressure: service queue is full with 1 items Contents of service queue:
Call kudu.rpc_test.CalculatorService.Sleep from 127.0.0.1:57402 (request call id 0)
I20260812 08:03:58.969604 32660 service_pool.cc:137] Sleep request on kudu.rpc_test.CalculatorService from 127.0.0.1:57534 dropped due to backpressure: service queue is full with 1 items Contents of service queue:
Call kudu.rpc_test.CalculatorService.Sleep from 127.0.0.1:57402 (request call id 0)
I20260812 08:03:58.969781 32660 service_pool.cc:137] Sleep request on kudu.rpc_test.CalculatorService from 127.0.0.1:57506 dropped due to backpressure: service queue is full with 1 items Contents of service queue:
Call kudu.rpc_test.CalculatorService.Sleep from 127.0.0.1:57402 (request call id 0)
I20260812 08:03:58.969960 32660 service_pool.cc:137] Sleep request on kudu.rpc_test.CalculatorService from 127.0.0.1:57496 dropped due to backpressure: service queue is full with 1 items Contents of service queue:
Call kudu.rpc_test.CalculatorService.Sleep from 127.0.0.1:57402 (request call id 0)
I20260812 08:03:58.970118 32660 service_pool.cc:137] Sleep request on kudu.rpc_test.CalculatorService from 127.0.0.1:57486 dropped due to backpressure: service queue is full with 1 items Contents of service queue:
Call kudu.rpc_test.CalculatorService.Sleep from 127.0.0.1:57402 (request call id 0)
I20260812 08:03:58.970281 32660 service_pool.cc:137] Sleep request on kudu.rpc_test.CalculatorService from 127.0.0.1:57460 dropped due to backpressure: service queue is full with 1 items Contents of service queue:
Call kudu.rpc_test.CalculatorService.Sleep from 127.0.0.1:57402 (request call id 0)
I20260812 08:03:58.970436 32660 service_pool.cc:137] Sleep request on kudu.rpc_test.CalculatorService from 127.0.0.1:57472 dropped due to backpressure: service queue is full with 1 items Contents of service queue:
Call kudu.rpc_test.CalculatorService.Sleep from 127.0.0.1:57402 (request call id 0)
I20260812 08:03:58.972628 32660 service_pool.cc:137] Sleep request on kudu.rpc_test.CalculatorService from 127.0.0.1:57488 dropped due to backpressure: service queue is full with 1 items Contents of service queue:
Call kudu.rpc_test.CalculatorService.Sleep from 127.0.0.1:57402 (request call id 0)
I20260812 08:03:58.973897 32660 service_pool.cc:137] Sleep request on kudu.rpc_test.CalculatorService from 127.0.0.1:57396 dropped due to backpressure: service queue is full with 1 items Contents of service queue:
Call kudu.rpc_test.CalculatorService.Sleep from 127.0.0.1:57402 (request call id 0)
I20260812 08:03:58.974462 32660 service_pool.cc:137] Sleep request on kudu.rpc_test.CalculatorService from 127.0.0.1:57498 dropped due to backpressure: service queue is full with 1 items Contents of service queue:
Call kudu.rpc_test.CalculatorService.Sleep from 127.0.0.1:57402 (request call id 0)
[       OK ] MultiThreadedRpcTest.PerMethodQueueOverflowRejectionCounter (221 ms)
[ RUN      ] MultiThreadedRpcTest.TestShutdownWithIncomingConnections
I20260812 08:03:59.169765   481 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 0.0.0.0:46203 every 8 connection(s)
I20260812 08:03:59.169813   484 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 0.0.0.0:46203 every 8 connection(s)
W20260812 08:03:59.170852   471 negotiation.cc:336] Failed RPC negotiation. Trace:
0812 08:03:59.170498 (+     0us) reactor.cc:730] Submitting negotiation task for server connection from 127.0.0.1:52434 (local address 127.0.0.1:46203)
0812 08:03:59.170665 (+   167us) server_negotiation.cc:207] Beginning negotiation
0812 08:03:59.170670 (+     5us) server_negotiation.cc:400] Waiting for connection header
0812 08:03:59.170763 (+    93us) negotiation.cc:326] Negotiation complete: Network error: Server connection negotiation failed: server connection from 127.0.0.1:52434: BlockingRecv error: recv got EOF from 127.0.0.1:52434 (error 108)
Metrics: {"server-negotiator.queue_time_us":82}
W20260812 08:03:59.171123   472 negotiation.cc:336] Failed RPC negotiation. Trace:
0812 08:03:59.170619 (+     0us) reactor.cc:730] Submitting negotiation task for server connection from 127.0.0.1:52464 (local address 127.0.0.1:46203)
0812 08:03:59.170965 (+   346us) server_negotiation.cc:207] Beginning negotiation
0812 08:03:59.170968 (+     3us) server_negotiation.cc:400] Waiting for connection header
0812 08:03:59.171048 (+    80us) negotiation.cc:326] Negotiation complete: Network error: Server connection negotiation failed: server connection from 127.0.0.1:52464: BlockingRecv error: recv got EOF from 127.0.0.1:52464 (error 108)
Metrics: {"server-negotiator.queue_time_us":253}
W20260812 08:03:59.171433   472 negotiation.cc:336] Failed RPC negotiation. Trace:
0812 08:03:59.170851 (+     0us) reactor.cc:730] Submitting negotiation task for server connection from 127.0.0.1:52480 (local address 127.0.0.1:46203)
0812 08:03:59.171279 (+   428us) server_negotiation.cc:207] Beginning negotiation
0812 08:03:59.171284 (+     5us) server_negotiation.cc:400] Waiting for connection header
0812 08:03:59.171367 (+    83us) negotiation.cc:326] Negotiation complete: Network error: Server connection negotiation failed: server connection from 127.0.0.1:52480: BlockingRecv error: recv got EOF from 127.0.0.1:52480 (error 108)
Metrics: {"server-negotiator.queue_time_us":360}
W20260812 08:03:59.171484   471 negotiation.cc:336] Failed RPC negotiation. Trace:
0812 08:03:59.170671 (+     0us) reactor.cc:730] Submitting negotiation task for server connection from 127.0.0.1:52438 (local address 127.0.0.1:46203)
0812 08:03:59.171134 (+   463us) server_negotiation.cc:207] Beginning negotiation
0812 08:03:59.171137 (+     3us) server_negotiation.cc:400] Waiting for connection header
0812 08:03:59.171329 (+   192us) negotiation.cc:326] Negotiation complete: Network error: Server connection negotiation failed: server connection from 127.0.0.1:52438: BlockingRecv error: recv got EOF from 127.0.0.1:52438 (error 108)
Metrics: {"server-negotiator.queue_time_us":398}
W20260812 08:03:59.171747   472 negotiation.cc:336] Failed RPC negotiation. Trace:
0812 08:03:59.171060 (+     0us) reactor.cc:730] Submitting negotiation task for server connection from 127.0.0.1:52482 (local address 127.0.0.1:46203)
0812 08:03:59.171578 (+   518us) server_negotiation.cc:207] Beginning negotiation
0812 08:03:59.171584 (+     6us) server_negotiation.cc:400] Waiting for connection header
0812 08:03:59.171664 (+    80us) negotiation.cc:326] Negotiation complete: Network error: Server connection negotiation failed: server connection from 127.0.0.1:52482: BlockingRecv error: recv got EOF from 127.0.0.1:52482 (error 108)
Metrics: {"mutex_wait_us":31,"server-negotiator.queue_time_us":453}
W20260812 08:03:59.172041   472 negotiation.cc:336] Failed RPC negotiation. Trace:
0812 08:03:59.171149 (+     0us) reactor.cc:730] Submitting negotiation task for server connection from 127.0.0.1:52494 (local address 127.0.0.1:46203)
0812 08:03:59.171892 (+   743us) server_negotiation.cc:207] Beginning negotiation
0812 08:03:59.171897 (+     5us) server_negotiation.cc:400] Waiting for connection header
0812 08:03:59.171972 (+    75us) negotiation.cc:326] Negotiation complete: Network error: Server connection negotiation failed: server connection from 127.0.0.1:52494: BlockingRecv error: recv got EOF from 127.0.0.1:52494 (error 108)
Metrics: {"server-negotiator.queue_time_us":667}
W20260812 08:03:59.172067   471 negotiation.cc:336] Failed RPC negotiation. Trace:
0812 08:03:59.171134 (+     0us) reactor.cc:730] Submitting negotiation task for server connection from 127.0.0.1:52484 (local address 127.0.0.1:46203)
0812 08:03:59.171755 (+   621us) server_negotiation.cc:207] Beginning negotiation
0812 08:03:59.171762 (+     7us) server_negotiation.cc:400] Waiting for connection header
0812 08:03:59.172008 (+   246us) negotiation.cc:326] Negotiation complete: Network error: Server connection negotiation failed: server connection from 127.0.0.1:52484: BlockingRecv error: recv got EOF from 127.0.0.1:52484 (error 108)
Metrics: {"server-negotiator.queue_time_us":495}
W20260812 08:03:59.172524   472 negotiation.cc:336] Failed RPC negotiation. Trace:
0812 08:03:59.171326 (+     0us) reactor.cc:730] Submitting negotiation task for server connection from 127.0.0.1:52512 (local address 127.0.0.1:46203)
0812 08:03:59.172358 (+  1032us) server_negotiation.cc:207] Beginning negotiation
0812 08:03:59.172374 (+    16us) server_negotiation.cc:400] Waiting for connection header
0812 08:03:59.172452 (+    78us) negotiation.cc:326] Negotiation complete: Network error: Server connection negotiation failed: server connection from 127.0.0.1:52512: BlockingRecv error: recv got EOF from 127.0.0.1:52512 (error 108)
Metrics: {"server-negotiator.queue_time_us":974}
W20260812 08:03:59.172768   473 negotiation.cc:336] Failed RPC negotiation. Trace:
0812 08:03:59.170517 (+     0us) reactor.cc:730] Submitting negotiation task for server connection from 127.0.0.1:52450 (local address 127.0.0.1:46203)
0812 08:03:59.170745 (+   228us) server_negotiation.cc:207] Beginning negotiation
0812 08:03:59.170751 (+     6us) server_negotiation.cc:400] Waiting for connection header
0812 08:03:59.170854 (+   103us) negotiation.cc:326] Negotiation complete: Network error: Server connection negotiation failed: server connection from 127.0.0.1:52450: BlockingRecv error: recv got EOF from 127.0.0.1:52450 (error 108)
Metrics: {"server-negotiator.queue_time_us":128}
W20260812 08:03:59.173122   473 negotiation.cc:336] Failed RPC negotiation. Trace:
0812 08:03:59.171459 (+     0us) reactor.cc:730] Submitting negotiation task for server connection from 127.0.0.1:52520 (local address 127.0.0.1:46203)
0812 08:03:59.172957 (+  1498us) server_negotiation.cc:207] Beginning negotiation
0812 08:03:59.172963 (+     6us) server_negotiation.cc:400] Waiting for connection header
0812 08:03:59.173045 (+    82us) negotiation.cc:326] Negotiation complete: Network error: Server connection negotiation failed: server connection from 127.0.0.1:52520: BlockingRecv error: recv got EOF from 127.0.0.1:52520 (error 108)
Metrics: {"server-negotiator.queue_time_us":1410}
W20260812 08:03:59.173231   472 negotiation.cc:336] Failed RPC negotiation. Trace:
0812 08:03:59.171478 (+     0us) reactor.cc:730] Submitting negotiation task for server connection from 127.0.0.1:52534 (local address 127.0.0.1:46203)
0812 08:03:59.173039 (+  1561us) server_negotiation.cc:207] Beginning negotiation
0812 08:03:59.173043 (+     4us) server_negotiation.cc:400] Waiting for connection header
0812 08:03:59.173114 (+    71us) negotiation.cc:326] Negotiation complete: Network error: Server connection negotiation failed: server connection from 127.0.0.1:52534: BlockingRecv error: recv got EOF from 127.0.0.1:52534 (error 108)
Metrics: {"server-negotiator.queue_time_us":1477}
W20260812 08:03:59.173470   473 negotiation.cc:336] Failed RPC negotiation. Trace:
0812 08:03:59.171578 (+     0us) reactor.cc:730] Submitting negotiation task for server connection from 127.0.0.1:52550 (local address 127.0.0.1:46203)
0812 08:03:59.173313 (+  1735us) server_negotiation.cc:207] Beginning negotiation
0812 08:03:59.173316 (+     3us) server_negotiation.cc:400] Waiting for connection header
0812 08:03:59.173393 (+    77us) negotiation.cc:326] Negotiation complete: Network error: Server connection negotiation failed: server connection from 127.0.0.1:52550: BlockingRecv error: recv got EOF from 127.0.0.1:52550 (error 108)
Metrics: {"server-negotiator.queue_time_us":1676}
W20260812 08:03:59.173482   472 negotiation.cc:336] Failed RPC negotiation. Trace:
0812 08:03:59.171718 (+     0us) reactor.cc:730] Submitting negotiation task for server connection from 127.0.0.1:52558 (local address 127.0.0.1:46203)
0812 08:03:59.173364 (+  1646us) server_negotiation.cc:207] Beginning negotiation
0812 08:03:59.173368 (+     4us) server_negotiation.cc:400] Waiting for connection header
0812 08:03:59.173441 (+    73us) negotiation.cc:326] Negotiation complete: Network error: Server connection negotiation failed: server connection from 127.0.0.1:52558: BlockingRecv error: recv got EOF from 127.0.0.1:52558 (error 108)
Metrics: {"server-negotiator.queue_time_us":1599}
W20260812 08:03:59.173723   470 negotiation.cc:336] Failed RPC negotiation. Trace:
0812 08:03:59.170600 (+     0us) reactor.cc:730] Submitting negotiation task for server connection from 127.0.0.1:52460 (local address 127.0.0.1:46203)
0812 08:03:59.170763 (+   163us) server_negotiation.cc:207] Beginning negotiation
0812 08:03:59.170766 (+     3us) server_negotiation.cc:400] Waiting for connection header
0812 08:03:59.173648 (+  2882us) negotiation.cc:326] Negotiation complete: Network error: Server connection negotiation failed: server connection from 127.0.0.1:52460: BlockingRecv error: recv got EOF from 127.0.0.1:52460 (error 108)
Metrics: {"server-negotiator.queue_time_us":92}
W20260812 08:03:59.173825   473 negotiation.cc:336] Failed RPC negotiation. Trace:
0812 08:03:59.171867 (+     0us) reactor.cc:730] Submitting negotiation task for server connection from 127.0.0.1:52590 (local address 127.0.0.1:46203)
0812 08:03:59.173637 (+  1770us) server_negotiation.cc:207] Beginning negotiation
0812 08:03:59.173644 (+     7us) server_negotiation.cc:400] Waiting for connection header
0812 08:03:59.173717 (+    73us) negotiation.cc:326] Negotiation complete: Network error: Server connection negotiation failed: server connection from 127.0.0.1:52590: BlockingRecv error: recv got EOF from 127.0.0.1:52590 (error 108)
Metrics: {"server-negotiator.queue_time_us":1713}
W20260812 08:03:59.173734   472 negotiation.cc:336] Failed RPC negotiation. Trace:
0812 08:03:59.171828 (+     0us) reactor.cc:730] Submitting negotiation task for server connection from 127.0.0.1:52586 (local address 127.0.0.1:46203)
0812 08:03:59.173589 (+  1761us) server_negotiation.cc:207] Beginning negotiation
0812 08:03:59.173593 (+     4us) server_negotiation.cc:400] Waiting for connection header
0812 08:03:59.173670 (+    77us) negotiation.cc:326] Negotiation complete: Network error: Server connection negotiation failed: server connection from 127.0.0.1:52586: BlockingRecv error: recv got EOF from 127.0.0.1:52586 (error 108)
Metrics: {"server-negotiator.queue_time_us":1704}
W20260812 08:03:59.173403   471 negotiation.cc:336] Failed RPC negotiation. Trace:
0812 08:03:59.171293 (+     0us) reactor.cc:730] Submitting negotiation task for server connection from 127.0.0.1:52502 (local address 127.0.0.1:46203)
0812 08:03:59.173204 (+  1911us) server_negotiation.cc:207] Beginning negotiation
0812 08:03:59.173208 (+     4us) server_negotiation.cc:400] Waiting for connection header
0812 08:03:59.173321 (+   113us) negotiation.cc:326] Negotiation complete: Network error: Server connection negotiation failed: server connection from 127.0.0.1:52502: BlockingRecv error: recv got EOF from 127.0.0.1:52502 (error 108)
Metrics: {"server-negotiator.queue_time_us":981}
W20260812 08:03:59.174021   470 negotiation.cc:336] Failed RPC negotiation. Trace:
0812 08:03:59.171962 (+     0us) reactor.cc:730] Submitting negotiation task for server connection from 127.0.0.1:52592 (local address 127.0.0.1:46203)
0812 08:03:59.173881 (+  1919us) server_negotiation.cc:207] Beginning negotiation
0812 08:03:59.173887 (+     6us) server_negotiation.cc:400] Waiting for connection header
0812 08:03:59.173970 (+    83us) negotiation.cc:326] Negotiation complete: Network error: Server connection negotiation failed: server connection from 127.0.0.1:52592: BlockingRecv error: recv got EOF from 127.0.0.1:52592 (error 108)
Metrics: {"server-negotiator.queue_time_us":1866}
W20260812 08:03:59.174111   471 negotiation.cc:336] Failed RPC negotiation. Trace:
0812 08:03:59.172374 (+     0us) reactor.cc:730] Submitting negotiation task for server connection from 127.0.0.1:52602 (local address 127.0.0.1:46203)
0812 08:03:59.173996 (+  1622us) server_negotiation.cc:207] Beginning negotiation
0812 08:03:59.174000 (+     4us) server_negotiation.cc:400] Waiting for connection header
0812 08:03:59.174066 (+    66us) negotiation.cc:326] Negotiation complete: Network error: Server connection negotiation failed: server connection from 127.0.0.1:52602: BlockingRecv error: recv got EOF from 127.0.0.1:52602 (error 108)
Metrics: {"server-negotiator.queue_time_us":1576}
W20260812 08:03:59.174093   473 negotiation.cc:336] Failed RPC negotiation. Trace:
0812 08:03:59.172089 (+     0us) reactor.cc:730] Submitting negotiation task for server connection from 127.0.0.1:52606 (local address 127.0.0.1:46203)
0812 08:03:59.173956 (+  1867us) server_negotiation.cc:207] Beginning negotiation
0812 08:03:59.173960 (+     4us) server_negotiation.cc:400] Waiting for connection header
0812 08:03:59.174041 (+    81us) negotiation.cc:326] Negotiation complete: Network error: Server connection negotiation failed: server connection from 127.0.0.1:52606: BlockingRecv error: recv got EOF from 127.0.0.1:52606 (error 108)
Metrics: {"server-negotiator.queue_time_us":1814}
W20260812 08:03:59.174265   470 negotiation.cc:336] Failed RPC negotiation. Trace:
0812 08:03:59.172401 (+     0us) reactor.cc:730] Submitting negotiation task for server connection from 127.0.0.1:52636 (local address 127.0.0.1:46203)
0812 08:03:59.174130 (+  1729us) server_negotiation.cc:207] Beginning negotiation
0812 08:03:59.174136 (+     6us) server_negotiation.cc:400] Waiting for connection header
0812 08:03:59.174216 (+    80us) negotiation.cc:326] Negotiation complete: Network error: Server connection negotiation failed: server connection from 127.0.0.1:52636: BlockingRecv error: recv got EOF from 127.0.0.1:52636 (error 108)
Metrics: {"server-negotiator.queue_time_us":1678}
W20260812 08:03:59.174361   471 negotiation.cc:336] Failed RPC negotiation. Trace:
0812 08:03:59.172420 (+     0us) reactor.cc:730] Submitting negotiation task for server connection from 127.0.0.1:52610 (local address 127.0.0.1:46203)
0812 08:03:59.174237 (+  1817us) server_negotiation.cc:207] Beginning negotiation
0812 08:03:59.174241 (+     4us) server_negotiation.cc:400] Waiting for connection header
0812 08:03:59.174304 (+    63us) negotiation.cc:326] Negotiation complete: Network error: Server connection negotiation failed: server connection from 127.0.0.1:52610: BlockingRecv error: recv got EOF from 127.0.0.1:52610 (error 108)
Metrics: {"server-negotiator.queue_time_us":1770}
W20260812 08:03:59.174479   470 negotiation.cc:336] Failed RPC negotiation. Trace:
0812 08:03:59.172578 (+     0us) reactor.cc:730] Submitting negotiation task for server connection from 127.0.0.1:52684 (local address 127.0.0.1:46203)
0812 08:03:59.174362 (+  1784us) server_negotiation.cc:207] Beginning negotiation
0812 08:03:59.174366 (+     4us) server_negotiation.cc:400] Waiting for connection header
0812 08:03:59.174435 (+    69us) negotiation.cc:326] Negotiation complete: Network error: Server connection negotiation failed: server connection from 127.0.0.1:52684: BlockingRecv error: recv got EOF from 127.0.0.1:52684 (error 108)
Metrics: {"server-negotiator.queue_time_us":1738}
W20260812 08:03:59.174578   473 negotiation.cc:336] Failed RPC negotiation. Trace:
0812 08:03:59.172492 (+     0us) reactor.cc:730] Submitting negotiation task for server connection from 127.0.0.1:52652 (local address 127.0.0.1:46203)
0812 08:03:59.174370 (+  1878us) server_negotiation.cc:207] Beginning negotiation
0812 08:03:59.174377 (+     7us) server_negotiation.cc:400] Waiting for connection header
0812 08:03:59.174459 (+    82us) negotiation.cc:326] Negotiation complete: Network error: Server connection negotiation failed: server connection from 127.0.0.1:52652: BlockingRecv error: recv got EOF from 127.0.0.1:52652 (error 108)
Metrics: {"server-negotiator.queue_time_us":1812}
W20260812 08:03:59.174093   472 negotiation.cc:336] Failed RPC negotiation. Trace:
0812 08:03:59.172328 (+     0us) reactor.cc:730] Submitting negotiation task for server connection from 127.0.0.1:52572 (local address 127.0.0.1:46203)
0812 08:03:59.173982 (+  1654us) server_negotiation.cc:207] Beginning negotiation
0812 08:03:59.173985 (+     3us) server_negotiation.cc:400] Waiting for connection header
0812 08:03:59.174051 (+    66us) negotiation.cc:326] Negotiation complete: Network error: Server connection negotiation failed: server connection from 127.0.0.1:52572: BlockingRecv error: recv got EOF from 127.0.0.1:52572 (error 108)
Metrics: {"server-negotiator.queue_time_us":1607}
W20260812 08:03:59.174789   470 negotiation.cc:336] Failed RPC negotiation. Trace:
0812 08:03:59.172664 (+     0us) reactor.cc:730] Submitting negotiation task for server connection from 127.0.0.1:52686 (local address 127.0.0.1:46203)
0812 08:03:59.174613 (+  1949us) server_negotiation.cc:207] Beginning negotiation
0812 08:03:59.174617 (+     4us) server_negotiation.cc:400] Waiting for connection header
0812 08:03:59.174692 (+    75us) negotiation.cc:326] Negotiation complete: Network error: Server connection negotiation failed: server connection from 127.0.0.1:52686: BlockingRecv error: recv got EOF from 127.0.0.1:52686 (error 108)
Metrics: {"mutex_wait_us":21,"server-negotiator.queue_time_us":1902}
W20260812 08:03:59.174872   472 negotiation.cc:336] Failed RPC negotiation. Trace:
0812 08:03:59.172796 (+     0us) reactor.cc:730] Submitting negotiation task for server connection from 127.0.0.1:52626 (local address 127.0.0.1:46203)
0812 08:03:59.174727 (+  1931us) server_negotiation.cc:207] Beginning negotiation
0812 08:03:59.174732 (+     5us) server_negotiation.cc:400] Waiting for connection header
0812 08:03:59.174802 (+    70us) negotiation.cc:326] Negotiation complete: Network error: Server connection negotiation failed: server connection from 127.0.0.1:52626: BlockingRecv error: recv got EOF from 127.0.0.1:52626 (error 108)
Metrics: {"server-negotiator.queue_time_us":1887}
W20260812 08:03:59.174875   473 negotiation.cc:336] Failed RPC negotiation. Trace:
0812 08:03:59.172755 (+     0us) reactor.cc:730] Submitting negotiation task for server connection from 127.0.0.1:52622 (local address 127.0.0.1:46203)
0812 08:03:59.174719 (+  1964us) server_negotiation.cc:207] Beginning negotiation
0812 08:03:59.174723 (+     4us) server_negotiation.cc:400] Waiting for connection header
0812 08:03:59.174802 (+    79us) negotiation.cc:326] Negotiation complete: Network error: Server connection negotiation failed: server connection from 127.0.0.1:52622: BlockingRecv error: recv got EOF from 127.0.0.1:52622 (error 108)
Metrics: {"server-negotiator.queue_time_us":1902}
W20260812 08:03:59.174595   471 negotiation.cc:336] Failed RPC negotiation. Trace:
0812 08:03:59.172213 (+     0us) reactor.cc:730] Submitting negotiation task for server connection from 127.0.0.1:52618 (local address 127.0.0.1:46203)
0812 08:03:59.174469 (+  2256us) server_negotiation.cc:207] Beginning negotiation
0812 08:03:59.174472 (+     3us) server_negotiation.cc:400] Waiting for connection header
0812 08:03:59.174546 (+    74us) negotiation.cc:326] Negotiation complete: Network error: Server connection negotiation failed: server connection from 127.0.0.1:52618: BlockingRecv error: recv got EOF from 127.0.0.1:52618 (error 108)
Metrics: {"mutex_wait_us":442,"server-negotiator.queue_time_us":2209}
W20260812 08:03:59.175747 32036 rpc-test-base.h:477] error unregistering service: Service unavailable: service kudu.rpc.GenericCalculatorService not registered on TestServer
[       OK ] MultiThreadedRpcTest.TestShutdownWithIncomingConnections (8 ms)
[----------] 8 tests from MultiThreadedRpcTest (7319 ms total)

[----------] Global test environment tear-down
[==========] 8 tests from 1 test suite ran. (7320 ms total)
[  PASSED  ] 8 tests.
I20260812 08:03:59.176800 32036 logging.cc:436] LogThrottler /workspace/apache/dev/local/kudu/src/kudu/rpc/service_pool.cc:136: suppressed but not reported on 13 messages since previous log ~0 seconds ago
