Note: This is test shard 1 of 8.
[==========] Running 33 tests from 3 test suites.
[----------] Global test environment set-up.
[----------] 1 test from TestRpc
[ RUN      ] TestRpc.TestConnHeaderValidation
[       OK ] TestRpc.TestConnHeaderValidation (1 ms)
[----------] 1 test from TestRpc (1 ms total)

[----------] 31 tests from Parameters/TestRpc
[ RUN      ] Parameters/TestRpc.TestMessengerCreateDestroy/UnixSocket_NoSSL
WARNING: Logging before InitGoogleLogging() is written to STDERR
I20260812 06:36:13.675122 11720 rpc-test.cc:236] started messenger TestCreateDestroy
[       OK ] Parameters/TestRpc.TestMessengerCreateDestroy/UnixSocket_NoSSL (8 ms)
[ RUN      ] Parameters/TestRpc.TestNegotiationDeadlock/TCP_IPv4_NoSSL
I20260812 06:36:13.678278 11772 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 0.0.0.0:44211 every 8 connection(s)
I20260812 06:36:13.678344 11773 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 0.0.0.0:44211 every 8 connection(s)
[       OK ] Parameters/TestRpc.TestNegotiationDeadlock/TCP_IPv4_NoSSL (12 ms)
[ RUN      ] Parameters/TestRpc.TestCall/TCP_IPv6_NoSSL
I20260812 06:36:13.691385 11798 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket [::]:32913 every 8 connection(s)
I20260812 06:36:13.691458 11799 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket [::]:32913 every 8 connection(s)
I20260812 06:36:13.691797 11720 rpc-test.cc:301] Connecting to [::]:32913
[       OK ] Parameters/TestRpc.TestCall/TCP_IPv6_NoSSL (10 ms)
[ RUN      ] Parameters/TestRpc.TestCallWithChainCertAndChainCA/UnixSocket_NoSSL
/workspace/apache/dev/local/kudu/src/kudu/rpc/rpc-test.cc:319: Skipped

[  SKIPPED ] Parameters/TestRpc.TestCallWithChainCertAndChainCA/UnixSocket_NoSSL (0 ms)
[ RUN      ] Parameters/TestRpc.TestCallWithPasswordProtectedKey/TCP_IPv4_NoSSL
/workspace/apache/dev/local/kudu/src/kudu/rpc/rpc-test.cc:386: Skipped

[  SKIPPED ] Parameters/TestRpc.TestCallWithPasswordProtectedKey/TCP_IPv4_NoSSL (0 ms)
[ RUN      ] Parameters/TestRpc.TestCallWithBadPasswordProtectedKey/TCP_IPv6_NoSSL
/workspace/apache/dev/local/kudu/src/kudu/rpc/rpc-test.cc:424: Skipped

[  SKIPPED ] Parameters/TestRpc.TestCallWithBadPasswordProtectedKey/TCP_IPv6_NoSSL (0 ms)
[ RUN      ] Parameters/TestRpc.TestCallToBadServer/UnixSocket_NoSSL
I20260812 06:36:13.703635 11720 rpc-test.cc:461] Status: Network error: Client connection negotiation failed: client connection to 0.0.0.0:0: connect: Connection refused (error 111)
I20260812 06:36:13.704125 11720 rpc-test.cc:461] Status: Network error: Client connection negotiation failed: client connection to 0.0.0.0:0: connect: Connection refused (error 111)
I20260812 06:36:13.704640 11720 rpc-test.cc:461] Status: Network error: Client connection negotiation failed: client connection to 0.0.0.0:0: connect: Connection refused (error 111)
I20260812 06:36:13.705116 11720 rpc-test.cc:461] Status: Network error: Client connection negotiation failed: client connection to 0.0.0.0:0: connect: Connection refused (error 111)
I20260812 06:36:13.705582 11720 rpc-test.cc:461] Status: Network error: Client connection negotiation failed: client connection to 0.0.0.0:0: connect: Connection refused (error 111)
[       OK ] Parameters/TestRpc.TestCallToBadServer/UnixSocket_NoSSL (4 ms)
[ RUN      ] Parameters/TestRpc.TestWrongService/TCP_IPv4_NoSSL
I20260812 06:36:13.707938 11856 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 0.0.0.0:43049 every 8 connection(s)
I20260812 06:36:13.708015 11857 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 0.0.0.0:43049 every 8 connection(s)
I20260812 06:36:13.711441 11854 messenger.cc:364] service WrongServiceName not registered on TestServer
[       OK ] Parameters/TestRpc.TestWrongService/TCP_IPv4_NoSSL (7 ms)
[ RUN      ] Parameters/TestRpc.TestHighFDs/TCP_IPv6_NoSSL
I20260812 06:36:13.808910 11894 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket [::]:46097 every 8 connection(s)
I20260812 06:36:13.808980 11895 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket [::]:46097 every 8 connection(s)
[       OK ] Parameters/TestRpc.TestHighFDs/TCP_IPv6_NoSSL (107 ms)
[ RUN      ] Parameters/TestRpc.TestConnectionKeepalive/UnixSocket_NoSSL
I20260812 06:36:13.822794 11938 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket unix:/tmp/dist-test-task8KL8au/test-tmp/rpc-test-dbb5d9e5911d4b0f901c95aed3764287.sock every 8 connection(s)
I20260812 06:36:13.822824 11940 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket unix:/tmp/dist-test-task8KL8au/test-tmp/rpc-test-dbb5d9e5911d4b0f901c95aed3764287.sock every 8 connection(s)
I20260812 06:36:13.823045 11720 rpc-test.cc:555] Connecting to unix:/tmp/dist-test-task8KL8au/test-tmp/rpc-test-dbb5d9e5911d4b0f901c95aed3764287.sock
[       OK ] Parameters/TestRpc.TestConnectionKeepalive/UnixSocket_NoSSL (1012 ms)
[ RUN      ] Parameters/TestRpc.TestClientConnectionMetrics/TCP_IPv4_NoSSL
I20260812 06:36:14.836181 11993 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 0.0.0.0:34965 every 8 connection(s)
I20260812 06:36:14.836308 11994 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 0.0.0.0:34965 every 8 connection(s)
I20260812 06:36:14.836580 11720 rpc-test.cc:636] Connecting to 0.0.0.0:34965
[       OK ] Parameters/TestRpc.TestClientConnectionMetrics/TCP_IPv4_NoSSL (2617 ms)
[ RUN      ] Parameters/TestRpc.TestReopenOutboundConnections/TCP_IPv6_NoSSL
I20260812 06:36:17.453496 12036 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket [::]:42713 every 8 connection(s)
I20260812 06:36:17.453612 12037 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket [::]:42713 every 8 connection(s)
I20260812 06:36:17.453933 11720 rpc-test.cc:724] Connecting to [::]:42713
[       OK ] Parameters/TestRpc.TestReopenOutboundConnections/TCP_IPv6_NoSSL (66 ms)
[ RUN      ] Parameters/TestRpc.TestCredentialsPolicy/UnixSocket_NoSSL
I20260812 06:36:17.519896 12081 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket unix:/tmp/dist-test-task8KL8au/test-tmp/rpc-test-533364f4fe194d1e9619bc8109580f36.sock every 8 connection(s)
I20260812 06:36:17.519970 12082 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket unix:/tmp/dist-test-task8KL8au/test-tmp/rpc-test-533364f4fe194d1e9619bc8109580f36.sock every 8 connection(s)
I20260812 06:36:17.520247 11720 rpc-test.cc:766] Connecting to unix:/tmp/dist-test-task8KL8au/test-tmp/rpc-test-533364f4fe194d1e9619bc8109580f36.sock
[       OK ] Parameters/TestRpc.TestCredentialsPolicy/UnixSocket_NoSSL (15 ms)
[ RUN      ] Parameters/TestRpc.TestCallLongerThanKeepalive/TCP_IPv4_NoSSL
I20260812 06:36:17.535298 12130 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 0.0.0.0:38505 every 8 connection(s)
I20260812 06:36:17.535388 12131 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 0.0.0.0:38505 every 8 connection(s)
I20260812 06:36:17.538750 12135 rpc-test-base.h:261] got call: sleep_micros: 3000000 deferred: true
I20260812 06:36:20.539409 12135 rpcz_store.cc:275] Call kudu.rpc.GenericCalculatorService.Sleep from 127.0.0.1:52670 (request call id 0) took 3000 ms. Trace:
I20260812 06:36:20.539557 12135 rpcz_store.cc:276] 0812 06:36:17.538687 (+     0us) service_pool.cc:167] Inserting onto call queue
0812 06:36:17.538735 (+    48us) service_pool.cc:224] Handling call
0812 06:36:20.539386 (+3000651us) inbound_call.cc:177] Queueing success response
Metrics: {}
[       OK ] Parameters/TestRpc.TestCallLongerThanKeepalive/TCP_IPv4_NoSSL (3009 ms)
[ RUN      ] Parameters/TestRpc.TestTCPKeepalive/TCP_IPv6_NoSSL
I20260812 06:36:20.545863 12176 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket [::]:38809 every 8 connection(s)
I20260812 06:36:20.545951 12177 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket [::]:38809 every 8 connection(s)
I20260812 06:36:20.553790 12181 rpc-test-base.h:261] got call: sleep_micros: 8000000 deferred: true
I20260812 06:36:28.554077 12181 rpcz_store.cc:275] Call kudu.rpc.GenericCalculatorService.Sleep from [::1]:51800 (request call id 0) took 8000 ms. Trace:
I20260812 06:36:28.554200 12181 rpcz_store.cc:276] 0812 06:36:20.553723 (+     0us) service_pool.cc:167] Inserting onto call queue
0812 06:36:20.553777 (+    54us) service_pool.cc:224] Handling call
0812 06:36:28.554051 (+8000274us) inbound_call.cc:177] Queueing success response
Metrics: {}
[       OK ] Parameters/TestRpc.TestTCPKeepalive/TCP_IPv6_NoSSL (8013 ms)
[ RUN      ] Parameters/TestRpc.TestRpcSidecarWithSizeLimits/UnixSocket_NoSSL
I20260812 06:36:28.558061 12205 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket unix:/tmp/dist-test-task8KL8au/test-tmp/rpc-test-63190a564e7f491980693c197364de53.sock every 8 connection(s)
I20260812 06:36:28.558139 12206 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket unix:/tmp/dist-test-task8KL8au/test-tmp/rpc-test-63190a564e7f491980693c197364de53.sock every 8 connection(s)
W20260812 06:36:28.735357 12214 serialization.cc:72] Serialized kudu.rpc_test.SendTwoStringsResponsePB (62914568 bytes) is larger than the maximum configured RPC message size (52428800 bytes). Sending anyway, but peer may reject the data.
W20260812 06:36:28.735862 12227 connection.cc:573] client connection to unix:/tmp/dist-test-task8KL8au/test-tmp/rpc-test-63190a564e7f491980693c197364de53.sock recv error: Network error: RPC frame had a length of 62914584, but we only support messages up to 20971520 bytes long.
W20260812 06:36:28.735930 12227 connection.cc:169] shutting down client connection to unix:/tmp/dist-test-task8KL8au/test-tmp/rpc-test-63190a564e7f491980693c197364de53.sock with pending inbound data: 4/62914584 bytes received; last active 0 ns ago: status Network error: RPC frame had a length of 62914584, but we only support messages up to 20971520 bytes long.
W20260812 06:36:28.736136 12202 connection.cc:573] server connection from unix:<unnamed> recv error: Network error: recv error from unix:<unnamed>: Connection reset by peer (error 104)
W20260812 06:36:28.736198 12202 connection.cc:466] server connection from unix:<unnamed> torn down before Call kudu.rpc.GenericCalculatorService.SendTwoStrings from unix:<unnamed> (request call id 0) could send its response
I20260812 06:36:28.738469 12237 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket unix:/tmp/dist-test-task8KL8au/test-tmp/rpc-test-63190a564e7f491980693c197364de53.sock every 8 connection(s)
I20260812 06:36:28.738512 12238 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket unix:/tmp/dist-test-task8KL8au/test-tmp/rpc-test-63190a564e7f491980693c197364de53.sock every 8 connection(s)
W20260812 06:36:28.857858 12244 serialization.cc:72] Serialized kudu.rpc_test.SendTwoStringsResponsePB (62914568 bytes) is larger than the maximum configured RPC message size (52428800 bytes). Sending anyway, but peer may reject the data.
[       OK ] Parameters/TestRpc.TestRpcSidecarWithSizeLimits/UnixSocket_NoSSL (464 ms)
[ RUN      ] Parameters/TestRpc.TestMaxSmallSidecars/TCP_IPv4_NoSSL
I20260812 06:36:29.023918 12281 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 0.0.0.0:35745 every 8 connection(s)
I20260812 06:36:29.023932 12283 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 0.0.0.0:35745 every 8 connection(s)
[       OK ] Parameters/TestRpc.TestMaxSmallSidecars/TCP_IPv4_NoSSL (27 ms)
[ RUN      ] Parameters/TestRpc.TestRpcSidecarLimits/TCP_IPv6_NoSSL
I20260812 06:36:30.838124 12341 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket [::]:37819 every 8 connection(s)
I20260812 06:36:30.838109 12340 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket [::]:37819 every 8 connection(s)
W20260812 06:36:30.839937 11720 serialization.cc:72] Serialized kudu.rpc_test.PushStringsRequestPB (2147483654 bytes) is larger than the maximum configured RPC message size (52428800 bytes). Sending anyway, but peer may reject the data.
W20260812 06:36:30.841907 12330 connection.cc:573] server connection from [::1]:48202 recv error: Network error: RPC frame had a length of 2147483714, but we only support messages up to 52428800 bytes long.
W20260812 06:36:30.841982 12330 connection.cc:169] shutting down server connection from [::1]:48202 with pending inbound data: 4/2147483714 bytes received; last active 0 ns ago: status Network error: RPC frame had a length of 2147483714, but we only support messages up to 52428800 bytes long.
W20260812 06:36:30.842188 12358 connection.cc:769] client connection to [::]:37819 send error: Network error: sendmsg error: Connection reset by peer (error 104)
I20260812 06:36:30.845319 12379 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket [::]:35537 every 8 connection(s)
I20260812 06:36:30.845350 12380 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket [::]:35537 every 8 connection(s)
W20260812 06:36:36.365672 12376 connection.cc:615] server connection from [::1]:36622: received bad data: 'Corruption: Invalid packet: message had a length of 2147483710, but we only support messages up to 2147483647 bytes
'
[       OK ] Parameters/TestRpc.TestRpcSidecarLimits/TCP_IPv6_NoSSL (7319 ms)
[ RUN      ] Parameters/TestRpc.TestCallTimeout/UnixSocket_NoSSL
I20260812 06:36:36.371701 12434 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket unix:/tmp/dist-test-task8KL8au/test-tmp/rpc-test-004302a5d7894def916c4f6bd340e8c1.sock every 8 connection(s)
I20260812 06:36:36.371841 12435 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket unix:/tmp/dist-test-task8KL8au/test-tmp/rpc-test-004302a5d7894def916c4f6bd340e8c1.sock every 8 connection(s)
I20260812 06:36:36.374550 11720 rpc-test-base.h:702] status: Timed out: connection negotiation to unix:/tmp/dist-test-task8KL8au/test-tmp/rpc-test-004302a5d7894def916c4f6bd340e8c1.sock for RPC Sleep timed out after 0.000s (ON_OUTBOUND_QUEUE), seconds elapsed: 0.000496925
I20260812 06:36:36.381407 12443 rpc-test-base.h:261] got call: sleep_micros: 700000
I20260812 06:36:36.575716 11720 rpc-test-base.h:702] status: Timed out: Sleep RPC to unix:/tmp/dist-test-task8KL8au/test-tmp/rpc-test-004302a5d7894def916c4f6bd340e8c1.sock timed out after 0.200s (SENT), seconds elapsed: 0.201014
I20260812 06:36:36.576481 12442 rpc-test-base.h:261] got call: sleep_micros: 2000000
W20260812 06:36:37.081679 12443 rpcz_store.cc:267] Call kudu.rpc.GenericCalculatorService.Sleep from unix:<unnamed> (request call id 1) took 700 ms (client timeout 200 ms). Trace:
W20260812 06:36:37.081797 12443 rpcz_store.cc:269] 0812 06:36:36.381290 (+     0us) service_pool.cc:167] Inserting onto call queue
0812 06:36:36.381348 (+    58us) service_pool.cc:224] Handling call
0812 06:36:37.081657 (+700309us) inbound_call.cc:177] Queueing success response
Metrics: {}
I20260812 06:36:38.077251 11720 rpc-test-base.h:702] status: Timed out: Sleep RPC to unix:/tmp/dist-test-task8KL8au/test-tmp/rpc-test-004302a5d7894def916c4f6bd340e8c1.sock timed out after 1.500s (SENT), seconds elapsed: 1.50137
W20260812 06:36:38.576773 12442 rpcz_store.cc:267] Call kudu.rpc.GenericCalculatorService.Sleep from unix:<unnamed> (request call id 2) took 2000 ms (client timeout 1500 ms). Trace:
W20260812 06:36:38.576905 12442 rpcz_store.cc:269] 0812 06:36:36.576281 (+     0us) service_pool.cc:167] Inserting onto call queue
0812 06:36:36.576353 (+    72us) service_pool.cc:224] Handling call
0812 06:36:38.576738 (+2000385us) inbound_call.cc:177] Queueing success response
Metrics: {}
W20260812 06:36:38.577105 12428 connection.cc:466] server connection from unix:<unnamed> torn down before Call kudu.rpc.GenericCalculatorService.Sleep from unix:<unnamed> (request call id 2) could send its response
[       OK ] Parameters/TestRpc.TestCallTimeout/UnixSocket_NoSSL (2209 ms)
[ RUN      ] Parameters/TestRpc.TestServerShutsDown/TCP_IPv4_NoSSL
I20260812 06:36:38.579249 11720 rpc-test.cc:1246] Connecting to 0.0.0.0:38519
W20260812 06:36:38.581501 12470 negotiation.cc:336] Failed RPC negotiation. Trace:
0812 06:36:38.581009 (+     0us) reactor.cc:730] Submitting negotiation task for client connection to 0.0.0.0:38519 (local address 127.0.0.1:39870)
0812 06:36:38.581143 (+   134us) negotiation.cc:107] Waiting for socket to connect
0812 06:36:38.581157 (+    14us) client_negotiation.cc:175] Beginning negotiation
0812 06:36:38.581252 (+    95us) client_negotiation.cc:262] Sending NEGOTIATE NegotiatePB request
0812 06:36:38.581313 (+    61us) negotiation.cc:326] Negotiation complete: Network error: Client connection negotiation failed: client connection to 0.0.0.0:38519: BlockingWrite error: write error: Broken pipe (error 32)
Metrics: {"client-negotiator.queue_time_us":53}
[       OK ] Parameters/TestRpc.TestServerShutsDown/TCP_IPv4_NoSSL (3 ms)
[ RUN      ] Parameters/TestRpc.TestRpcHandlerLatencyMetric/TCP_IPv6_NoSSL
I20260812 06:36:38.584595 12502 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket [::]:37299 every 8 connection(s)
I20260812 06:36:38.584748 12503 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket [::]:37299 every 8 connection(s)
I20260812 06:36:38.610105 11720 rpc-test.cc:1357] Sleep() min lat: 20316
I20260812 06:36:38.610168 11720 rpc-test.cc:1358] Sleep() mean lat: 20316
I20260812 06:36:38.610208 11720 rpc-test.cc:1359] Sleep() max lat: 20316
I20260812 06:36:38.610235 11720 rpc-test.cc:1360] Sleep() #calls: 1
[       OK ] Parameters/TestRpc.TestRpcHandlerLatencyMetric/TCP_IPv6_NoSSL (30 ms)
[ RUN      ] Parameters/TestRpc.TimedOutOnResponseMetric/UnixSocket_NoSSL
I20260812 06:36:38.615115 12547 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket unix:/tmp/dist-test-task8KL8au/test-tmp/rpc-test-219b32a2ad224899b4c84f7afe82b6f9.sock every 8 connection(s)
I20260812 06:36:38.615197 12549 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket unix:/tmp/dist-test-task8KL8au/test-tmp/rpc-test-219b32a2ad224899b4c84f7afe82b6f9.sock every 8 connection(s)
W20260812 06:36:38.821981 12555 rpcz_store.cc:267] Call kudu.rpc_test.CalculatorService.Sleep from unix:<unnamed> (request call id 3) took 50 ms (client timeout 25 ms). Trace:
W20260812 06:36:38.822081 12555 rpcz_store.cc:269] 0812 06:36:38.771680 (+     0us) service_pool.cc:167] Inserting onto call queue
0812 06:36:38.771757 (+    77us) service_pool.cc:224] Handling call
0812 06:36:38.821952 (+ 50195us) inbound_call.cc:177] Queueing success response
Related trace 'test_child':
Metrics: {"test_sleep_us":50000,"child_traces":[["test_child",{"related_trace_metric":1}]]}
W20260812 06:36:38.879473 12555 rpcz_store.cc:267] Call kudu.rpc_test.CalculatorService.Sleep from unix:<unnamed> (request call id 4) took 50 ms (client timeout 50 ms). Trace:
W20260812 06:36:38.879567 12555 rpcz_store.cc:269] 0812 06:36:38.829176 (+     0us) service_pool.cc:167] Inserting onto call queue
0812 06:36:38.829245 (+    69us) service_pool.cc:224] Handling call
0812 06:36:38.879450 (+ 50205us) inbound_call.cc:177] Queueing success response
Related trace 'test_child':
Metrics: {"test_sleep_us":50000,"child_traces":[["test_child",{"related_trace_metric":1}]]}
[       OK ] Parameters/TestRpc.TimedOutOnResponseMetric/UnixSocket_NoSSL (269 ms)
[ RUN      ] Parameters/TestRpc.AcceptorDispatchingTimesMetric/TCP_IPv4_NoSSL
I20260812 06:36:38.885128 12594 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 0.0.0.0:39817 every 8 connection(s)
I20260812 06:36:38.885221 12596 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 0.0.0.0:39817 every 8 connection(s)
W20260812 06:36:38.886163 12582 negotiation.cc:336] Failed RPC negotiation. Trace:
0812 06:36:38.885874 (+     0us) reactor.cc:730] Submitting negotiation task for server connection from 127.0.0.1:52900 (local address 127.0.0.1:39817)
0812 06:36:38.885989 (+   115us) server_negotiation.cc:207] Beginning negotiation
0812 06:36:38.885992 (+     3us) server_negotiation.cc:400] Waiting for connection header
0812 06:36:38.886064 (+    72us) negotiation.cc:326] Negotiation complete: Network error: Server connection negotiation failed: server connection from 127.0.0.1:52900: BlockingRecv error: recv got EOF from 127.0.0.1:52900 (error 108)
Metrics: {"server-negotiator.queue_time_us":21}
[       OK ] Parameters/TestRpc.AcceptorDispatchingTimesMetric/TCP_IPv4_NoSSL (5 ms)
[ RUN      ] Parameters/TestRpc.RpcPendingConnectionsMetric/TCP_IPv6_NoSSL
I20260812 06:36:38.890435 12625 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 0.0.0.0:46279 every 8 connection(s)
I20260812 06:36:38.890561 12626 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 0.0.0.0:46279 every 8 connection(s)
W20260812 06:36:38.891544 12613 negotiation.cc:336] Failed RPC negotiation. Trace:
0812 06:36:38.891261 (+     0us) reactor.cc:730] Submitting negotiation task for server connection from 127.0.0.1:33014 (local address 127.0.0.1:46279)
0812 06:36:38.891398 (+   137us) server_negotiation.cc:207] Beginning negotiation
0812 06:36:38.891402 (+     4us) server_negotiation.cc:400] Waiting for connection header
0812 06:36:38.891491 (+    89us) negotiation.cc:326] Negotiation complete: Network error: Server connection negotiation failed: server connection from 127.0.0.1:33014: BlockingRecv error: recv got EOF from 127.0.0.1:33014 (error 108)
Metrics: {"server-negotiator.queue_time_us":55}
[       OK ] Parameters/TestRpc.RpcPendingConnectionsMetric/TCP_IPv6_NoSSL (4 ms)
[ RUN      ] Parameters/TestRpc.TestRpcCallbackDestroysMessenger/UnixSocket_NoSSL
[       OK ] Parameters/TestRpc.TestRpcCallbackDestroysMessenger/UnixSocket_NoSSL (2 ms)
[ RUN      ] Parameters/TestRpc.TestApplicationFeatureFlag/TCP_IPv4_NoSSL
I20260812 06:36:38.897559 12667 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 0.0.0.0:44215 every 8 connection(s)
I20260812 06:36:38.897646 12668 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 0.0.0.0:44215 every 8 connection(s)
W20260812 06:36:38.902107 12666 messenger.cc:376] Unable to handle RPC call: Not implemented: call requires unsupported application feature flags: 99
[       OK ] Parameters/TestRpc.TestApplicationFeatureFlag/TCP_IPv4_NoSSL (8 ms)
[ RUN      ] Parameters/TestRpc.TestApplicationFeatureFlagUnsupportedServer/TCP_IPv6_NoSSL
I20260812 06:36:38.906234 12706 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket [::]:41219 every 8 connection(s)
I20260812 06:36:38.906306 12708 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket [::]:41219 every 8 connection(s)
[       OK ] Parameters/TestRpc.TestApplicationFeatureFlagUnsupportedServer/TCP_IPv6_NoSSL (7 ms)
[ RUN      ] Parameters/TestRpc.TestCancellation/UnixSocket_NoSSL
I20260812 06:36:38.913936 12753 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket unix:/tmp/dist-test-task8KL8au/test-tmp/rpc-test-343b5c9ca8a24527b8df2f13068499ec.sock every 8 connection(s)
I20260812 06:36:38.914018 12755 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket unix:/tmp/dist-test-task8KL8au/test-tmp/rpc-test-343b5c9ca8a24527b8df2f13068499ec.sock every 8 connection(s)
I20260812 06:36:38.914294 11720 rpc-test.cc:1779] Connecting to unix:/tmp/dist-test-task8KL8au/test-tmp/rpc-test-343b5c9ca8a24527b8df2f13068499ec.sock
I20260812 06:36:38.940573 11720 rpc-test-base.h:702] status: Aborted: Sleep RPC to unix:/tmp/dist-test-task8KL8au/test-tmp/rpc-test-343b5c9ca8a24527b8df2f13068499ec.sock is cancelled in state READY, seconds elapsed: 9.4474e-05
I20260812 06:36:38.961470 11720 rpc-test-base.h:702] status: Aborted: Sleep RPC to unix:/tmp/dist-test-task8KL8au/test-tmp/rpc-test-343b5c9ca8a24527b8df2f13068499ec.sock is cancelled in state SENT, seconds elapsed: 0.000270432
I20260812 06:36:38.961583 12759 rpc-test-base.h:261] got call: sleep_micros: 510000
I20260812 06:36:38.965497 11720 rpc-test-base.h:702] status: Aborted: Sleep RPC to unix:/tmp/dist-test-task8KL8au/test-tmp/rpc-test-343b5c9ca8a24527b8df2f13068499ec.sock is cancelled in state SENT, seconds elapsed: 0.000253547
I20260812 06:36:38.965602 12757 rpc-test-base.h:261] got call: sleep_micros: 510000
I20260812 06:36:38.968997 11720 rpc-test-base.h:702] status: Aborted: Sleep RPC to unix:/tmp/dist-test-task8KL8au/test-tmp/rpc-test-343b5c9ca8a24527b8df2f13068499ec.sock is cancelled in state SENT, seconds elapsed: 0.000209478
I20260812 06:36:38.969298 12760 rpc-test-base.h:261] got call: sleep_micros: 510000
W20260812 06:36:39.471913 12759 rpcz_store.cc:267] Call kudu.rpc.GenericCalculatorService.Sleep from unix:<unnamed> (request call id 3) took 510 ms (client timeout 10 ms). Trace:
W20260812 06:36:39.472036 12759 rpcz_store.cc:269] 0812 06:36:38.961474 (+     0us) service_pool.cc:167] Inserting onto call queue
0812 06:36:38.961549 (+    75us) service_pool.cc:224] Handling call
0812 06:36:39.471889 (+510340us) inbound_call.cc:177] Queueing success response
Metrics: {}
I20260812 06:36:39.472230 12759 rpc-test-base.h:261] got call: sleep_micros: 1500000
W20260812 06:36:39.475828 12757 rpcz_store.cc:267] Call kudu.rpc.GenericCalculatorService.Sleep from unix:<unnamed> (request call id 7) took 510 ms (client timeout 10 ms). Trace:
W20260812 06:36:39.475889 12757 rpcz_store.cc:269] 0812 06:36:38.965508 (+     0us) service_pool.cc:167] Inserting onto call queue
0812 06:36:38.965587 (+    79us) service_pool.cc:224] Handling call
0812 06:36:39.475820 (+510233us) inbound_call.cc:177] Queueing success response
Metrics: {}
W20260812 06:36:39.479581 12760 rpcz_store.cc:267] Call kudu.rpc.GenericCalculatorService.Sleep from unix:<unnamed> (request call id 11) took 510 ms (client timeout 10 ms). Trace:
W20260812 06:36:39.479645 12760 rpcz_store.cc:269] 0812 06:36:38.969054 (+     0us) service_pool.cc:167] Inserting onto call queue
0812 06:36:38.969271 (+   217us) service_pool.cc:224] Handling call
0812 06:36:39.479573 (+510302us) inbound_call.cc:177] Queueing success response
Metrics: {}
I20260812 06:36:39.970098 11720 rpc-test-base.h:702] status: Timed out: Sleep RPC to unix:/tmp/dist-test-task8KL8au/test-tmp/rpc-test-343b5c9ca8a24527b8df2f13068499ec.sock timed out after 1.000s (SENT), seconds elapsed: 1.00096
I20260812 06:36:39.970739 12760 rpc-test-base.h:261] got call: sleep_micros: 1500000
I20260812 06:36:40.971439 11720 rpc-test-base.h:702] status: Timed out: Sleep RPC to unix:/tmp/dist-test-task8KL8au/test-tmp/rpc-test-343b5c9ca8a24527b8df2f13068499ec.sock timed out after 1.000s (SENT), seconds elapsed: 1.00119
W20260812 06:36:40.972077 12750 messenger.cc:376] Unable to handle RPC call: Not implemented: call requires unsupported application feature flags: 99, 1
W20260812 06:36:40.972503 12759 rpcz_store.cc:267] Call kudu.rpc.GenericCalculatorService.Sleep from unix:<unnamed> (request call id 12) took 2003 ms (client timeout 1000 ms). Trace:
W20260812 06:36:40.972584 12759 rpcz_store.cc:269] 0812 06:36:38.969380 (+     0us) service_pool.cc:167] Inserting onto call queue
0812 06:36:39.472220 (+502840us) service_pool.cc:224] Handling call
0812 06:36:40.972493 (+1500273us) inbound_call.cc:177] Queueing success response
Metrics: {}
W20260812 06:36:40.972749 12750 messenger.cc:376] Unable to handle RPC call: Not implemented: call requires unsupported application feature flags: 99, 1
W20260812 06:36:41.471001 12760 rpcz_store.cc:267] Call kudu.rpc.GenericCalculatorService.Sleep from unix:<unnamed> (request call id 13) took 1500 ms (client timeout 1000 ms). Trace:
W20260812 06:36:41.471138 12760 rpcz_store.cc:269] 0812 06:36:39.970651 (+     0us) service_pool.cc:167] Inserting onto call queue
0812 06:36:39.970724 (+    73us) service_pool.cc:224] Handling call
0812 06:36:41.470973 (+1500249us) inbound_call.cc:177] Queueing success response
Metrics: {}
W20260812 06:36:41.471393 12750 connection.cc:466] server connection from unix:<unnamed> torn down before Call kudu.rpc.GenericCalculatorService.Sleep from unix:<unnamed> (request call id 13) could send its response
[       OK ] Parameters/TestRpc.TestCancellation/UnixSocket_NoSSL (2561 ms)
[ RUN      ] Parameters/TestRpc.TestCancellationMultiThreads/TCP_IPv4_NoSSL
I20260812 06:36:41.476202 12805 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 0.0.0.0:45347 every 8 connection(s)
I20260812 06:36:41.476241 12807 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 0.0.0.0:45347 every 8 connection(s)
I20260812 06:36:41.476557 11720 rpc-test.cc:1939] Connecting to 0.0.0.0:45347
W20260812 06:36:56.793892 12801 connection.cc:466] server connection from 127.0.0.1:56554 torn down before Call kudu.rpc.GenericCalculatorService.PushStrings from 127.0.0.1:56554 (request call id 2089) could send its response
[       OK ] Parameters/TestRpc.TestCancellationMultiThreads/TCP_IPv4_NoSSL (15322 ms)
[ RUN      ] Parameters/TestRpc.TestPerformanceBySocketType/TCP_IPv6_NoSSL
I20260812 06:36:56.804008 12906 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket [::]:37877 every 8 connection(s)
I20260812 06:36:56.804108 12907 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket [::]:37877 every 8 connection(s)
I20260812 06:36:56.804441 11720 rpc-test.cc:1977] Connecting to [::]:37877
I20260812 06:36:57.378490 11720 rpc-test.cc:1990] Sending 1024MB via tcp socket: real 0.573s	user 0.313s	sys 0.596s
I20260812 06:36:57.917270 11720 rpc-test.cc:1990] Sending 1024MB via tcp socket: real 0.539s	user 0.296s	sys 0.561s
I20260812 06:36:58.478325 11720 rpc-test.cc:1990] Sending 1024MB via tcp socket: real 0.561s	user 0.273s	sys 0.621s
I20260812 06:36:59.028160 11720 rpc-test.cc:1990] Sending 1024MB via tcp socket: real 0.550s	user 0.316s	sys 0.553s
I20260812 06:36:59.571976 11720 rpc-test.cc:1990] Sending 1024MB via tcp socket: real 0.544s	user 0.310s	sys 0.552s
[       OK ] Parameters/TestRpc.TestPerformanceBySocketType/TCP_IPv6_NoSSL (2778 ms)
[ RUN      ] Parameters/TestRpc.TestCallId/UnixSocket_NoSSL
I20260812 06:36:59.576458 12949 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket unix:/tmp/dist-test-task8KL8au/test-tmp/rpc-test-e937c63c68f249df9e20e6ddc9cc5312.sock every 8 connection(s)
I20260812 06:36:59.576574 12951 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket unix:/tmp/dist-test-task8KL8au/test-tmp/rpc-test-e937c63c68f249df9e20e6ddc9cc5312.sock every 8 connection(s)
[       OK ] Parameters/TestRpc.TestCallId/UnixSocket_NoSSL (10 ms)
[----------] 31 tests from Parameters/TestRpc (45916 ms total)

[----------] 1 test from Parameters/TestRpcSocketTxRxQueue
[ RUN      ] Parameters/TestRpcSocketTxRxQueue.AcceptorRxQueueSizeMetric/TCP_IPv6_NoSSL
I20260812 06:36:59.586838 12995 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 0.0.0.0:35563 every 1 connection(s)
I20260812 06:36:59.586987 13000 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 0.0.0.0:35563 every 1 connection(s)
W20260812 06:36:59.587705 12980 negotiation.cc:336] Failed RPC negotiation. Trace:
0812 06:36:59.587486 (+     0us) reactor.cc:730] Submitting negotiation task for server connection from 127.0.0.1:44692 (local address 127.0.0.1:35563)
0812 06:36:59.587564 (+    78us) server_negotiation.cc:207] Beginning negotiation
0812 06:36:59.587568 (+     4us) server_negotiation.cc:400] Waiting for connection header
0812 06:36:59.587640 (+    72us) negotiation.cc:326] Negotiation complete: Network error: Server connection negotiation failed: server connection from 127.0.0.1:44692: BlockingRecv error: recv got EOF from 127.0.0.1:44692 (error 108)
Metrics: {"server-negotiator.queue_time_us":21}
[       OK ] Parameters/TestRpcSocketTxRxQueue.AcceptorRxQueueSizeMetric/TCP_IPv6_NoSSL (4 ms)
[----------] 1 test from Parameters/TestRpcSocketTxRxQueue (4 ms total)

[----------] Global test environment tear-down
[==========] 33 tests from 3 test suites ran. (45923 ms total)
[  PASSED  ] 30 tests.
[  SKIPPED ] 3 tests, listed below:
[  SKIPPED ] Parameters/TestRpc.TestCallWithChainCertAndChainCA/UnixSocket_NoSSL
[  SKIPPED ] Parameters/TestRpc.TestCallWithPasswordProtectedKey/TCP_IPv4_NoSSL
[  SKIPPED ] Parameters/TestRpc.TestCallWithBadPasswordProtectedKey/TCP_IPv6_NoSSL
