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 (2 ms)
[----------] 1 test from TestRpc (2 ms total)

[----------] 31 tests from Parameters/TestRpc
[ RUN      ] Parameters/TestRpc.TestMessengerCreateDestroy/UnixSocket_NoSSL
WARNING: Logging before InitGoogleLogging() is written to STDERR
I20260812 08:02:45.725756  6369 rpc-test.cc:236] started messenger TestCreateDestroy
[       OK ] Parameters/TestRpc.TestMessengerCreateDestroy/UnixSocket_NoSSL (10 ms)
[ RUN      ] Parameters/TestRpc.TestNegotiationDeadlock/TCP_IPv4_NoSSL
I20260812 08:02:45.729781  6395 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 0.0.0.0:42517 every 8 connection(s)
I20260812 08:02:45.729851  6396 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 0.0.0.0:42517 every 8 connection(s)
[       OK ] Parameters/TestRpc.TestNegotiationDeadlock/TCP_IPv4_NoSSL (12 ms)
[ RUN      ] Parameters/TestRpc.TestCall/TCP_IPv6_NoSSL
I20260812 08:02:45.742408  6419 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket [::]:43523 every 8 connection(s)
I20260812 08:02:45.742496  6420 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket [::]:43523 every 8 connection(s)
I20260812 08:02:45.742811  6369 rpc-test.cc:301] Connecting to [::]:43523
[       OK ] Parameters/TestRpc.TestCall/TCP_IPv6_NoSSL (12 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 08:02:45.757017  6369 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 08:02:45.757700  6369 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 08:02:45.758200  6369 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 08:02:45.758635  6369 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 08:02:45.759058  6369 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 (5 ms)
[ RUN      ] Parameters/TestRpc.TestWrongService/TCP_IPv4_NoSSL
I20260812 08:02:45.762198  6468 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 0.0.0.0:42247 every 8 connection(s)
I20260812 08:02:45.762233  6469 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 0.0.0.0:42247 every 8 connection(s)
I20260812 08:02:45.766079  6465 messenger.cc:364] service WrongServiceName not registered on TestServer
[       OK ] Parameters/TestRpc.TestWrongService/TCP_IPv4_NoSSL (8 ms)
[ RUN      ] Parameters/TestRpc.TestHighFDs/TCP_IPv6_NoSSL
I20260812 08:02:45.867296  6507 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket [::]:40273 every 8 connection(s)
I20260812 08:02:45.867379  6508 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket [::]:40273 every 8 connection(s)
[       OK ] Parameters/TestRpc.TestHighFDs/TCP_IPv6_NoSSL (112 ms)
[ RUN      ] Parameters/TestRpc.TestConnectionKeepalive/UnixSocket_NoSSL
I20260812 08:02:45.883136  6538 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket unix:/tmp/dist-test-task5Mq6Kq/test-tmp/rpc-test-a0bda9bc03ab425a9f871e6454f29208.sock every 8 connection(s)
I20260812 08:02:45.883160  6537 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket unix:/tmp/dist-test-task5Mq6Kq/test-tmp/rpc-test-a0bda9bc03ab425a9f871e6454f29208.sock every 8 connection(s)
I20260812 08:02:45.883392  6369 rpc-test.cc:555] Connecting to unix:/tmp/dist-test-task5Mq6Kq/test-tmp/rpc-test-a0bda9bc03ab425a9f871e6454f29208.sock
[       OK ] Parameters/TestRpc.TestConnectionKeepalive/UnixSocket_NoSSL (1014 ms)
[ RUN      ] Parameters/TestRpc.TestClientConnectionMetrics/TCP_IPv4_NoSSL
I20260812 08:02:46.897379  6569 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 0.0.0.0:45865 every 8 connection(s)
I20260812 08:02:46.897406  6570 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 0.0.0.0:45865 every 8 connection(s)
I20260812 08:02:46.897634  6369 rpc-test.cc:636] Connecting to 0.0.0.0:45865
[       OK ] Parameters/TestRpc.TestClientConnectionMetrics/TCP_IPv4_NoSSL (3489 ms)
[ RUN      ] Parameters/TestRpc.TestReopenOutboundConnections/TCP_IPv6_NoSSL
I20260812 08:02:50.386864  6610 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket [::]:46461 every 8 connection(s)
I20260812 08:02:50.386963  6613 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket [::]:46461 every 8 connection(s)
I20260812 08:02:50.387252  6369 rpc-test.cc:724] Connecting to [::]:46461
[       OK ] Parameters/TestRpc.TestReopenOutboundConnections/TCP_IPv6_NoSSL (82 ms)
[ RUN      ] Parameters/TestRpc.TestCredentialsPolicy/UnixSocket_NoSSL
I20260812 08:02:50.469327  6646 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket unix:/tmp/dist-test-task5Mq6Kq/test-tmp/rpc-test-a7bd3c802bc24724abc7c809a1625448.sock every 8 connection(s)
I20260812 08:02:50.469336  6648 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket unix:/tmp/dist-test-task5Mq6Kq/test-tmp/rpc-test-a7bd3c802bc24724abc7c809a1625448.sock every 8 connection(s)
I20260812 08:02:50.469544  6369 rpc-test.cc:766] Connecting to unix:/tmp/dist-test-task5Mq6Kq/test-tmp/rpc-test-a7bd3c802bc24724abc7c809a1625448.sock
[       OK ] Parameters/TestRpc.TestCredentialsPolicy/UnixSocket_NoSSL (14 ms)
[ RUN      ] Parameters/TestRpc.TestCallLongerThanKeepalive/TCP_IPv4_NoSSL
I20260812 08:02:50.484632  6680 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 0.0.0.0:43009 every 8 connection(s)
I20260812 08:02:50.484692  6683 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 0.0.0.0:43009 every 8 connection(s)
I20260812 08:02:50.488873  6686 rpc-test-base.h:261] got call: sleep_micros: 3000000 deferred: true
I20260812 08:02:53.489713  6686 rpcz_store.cc:275] Call kudu.rpc.GenericCalculatorService.Sleep from 127.0.0.1:58338 (request call id 0) took 3000 ms. Trace:
I20260812 08:02:53.489847  6686 rpcz_store.cc:276] 0812 08:02:50.488777 (+     0us) service_pool.cc:167] Inserting onto call queue
0812 08:02:50.488845 (+    68us) service_pool.cc:224] Handling call
0812 08:02:53.489688 (+3000843us) inbound_call.cc:177] Queueing success response
Metrics: {}
[       OK ] Parameters/TestRpc.TestCallLongerThanKeepalive/TCP_IPv4_NoSSL (3011 ms)
[ RUN      ] Parameters/TestRpc.TestTCPKeepalive/TCP_IPv6_NoSSL
I20260812 08:02:53.495601  6716 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket [::]:32793 every 8 connection(s)
I20260812 08:02:53.495641  6717 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket [::]:32793 every 8 connection(s)
I20260812 08:02:53.499164  6721 rpc-test-base.h:261] got call: sleep_micros: 8000000 deferred: true
I20260812 08:03:01.499497  6721 rpcz_store.cc:275] Call kudu.rpc.GenericCalculatorService.Sleep from [::1]:34858 (request call id 0) took 8000 ms. Trace:
I20260812 08:03:01.499624  6721 rpcz_store.cc:276] 0812 08:02:53.499057 (+     0us) service_pool.cc:167] Inserting onto call queue
0812 08:02:53.499118 (+    61us) service_pool.cc:224] Handling call
0812 08:03:01.499473 (+8000355us) inbound_call.cc:177] Queueing success response
Metrics: {}
[       OK ] Parameters/TestRpc.TestTCPKeepalive/TCP_IPv6_NoSSL (8008 ms)
[ RUN      ] Parameters/TestRpc.TestRpcSidecarWithSizeLimits/UnixSocket_NoSSL
I20260812 08:03:01.503984  6753 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket unix:/tmp/dist-test-task5Mq6Kq/test-tmp/rpc-test-5bfaff0c737d426786c2c7bf0d05e0bc.sock every 8 connection(s)
I20260812 08:03:01.504474  6751 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket unix:/tmp/dist-test-task5Mq6Kq/test-tmp/rpc-test-5bfaff0c737d426786c2c7bf0d05e0bc.sock every 8 connection(s)
W20260812 08:03:01.692117  6756 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 08:03:01.692665  6769 connection.cc:573] client connection to unix:/tmp/dist-test-task5Mq6Kq/test-tmp/rpc-test-5bfaff0c737d426786c2c7bf0d05e0bc.sock recv error: Network error: RPC frame had a length of 62914584, but we only support messages up to 20971520 bytes long.
W20260812 08:03:01.692761  6769 connection.cc:169] shutting down client connection to unix:/tmp/dist-test-task5Mq6Kq/test-tmp/rpc-test-5bfaff0c737d426786c2c7bf0d05e0bc.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 08:03:01.693068  6745 connection.cc:573] server connection from unix:<unnamed> recv error: Network error: recv error from unix:<unnamed>: Connection reset by peer (error 104)
W20260812 08:03:01.693146  6745 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 08:03:01.694960  6779 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket unix:/tmp/dist-test-task5Mq6Kq/test-tmp/rpc-test-5bfaff0c737d426786c2c7bf0d05e0bc.sock every 8 connection(s)
I20260812 08:03:01.695039  6778 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket unix:/tmp/dist-test-task5Mq6Kq/test-tmp/rpc-test-5bfaff0c737d426786c2c7bf0d05e0bc.sock every 8 connection(s)
W20260812 08:03:01.820950  6780 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 (502 ms)
[ RUN      ] Parameters/TestRpc.TestMaxSmallSidecars/TCP_IPv4_NoSSL
I20260812 08:03:02.006873  6813 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 0.0.0.0:34605 every 8 connection(s)
I20260812 08:03:02.006907  6816 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 0.0.0.0:34605 every 8 connection(s)
[       OK ] Parameters/TestRpc.TestMaxSmallSidecars/TCP_IPv4_NoSSL (27 ms)
[ RUN      ] Parameters/TestRpc.TestRpcSidecarLimits/TCP_IPv6_NoSSL
I20260812 08:03:05.072675  6849 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket [::]:36293 every 8 connection(s)
I20260812 08:03:05.072745  6850 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket [::]:36293 every 8 connection(s)
W20260812 08:03:05.075621  6369 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 08:03:05.078090  6847 connection.cc:573] server connection from [::1]:42894 recv error: Network error: RPC frame had a length of 2147483714, but we only support messages up to 52428800 bytes long.
W20260812 08:03:05.078188  6847 connection.cc:169] shutting down server connection from [::1]:42894 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 08:03:05.078583  6869 connection.cc:769] client connection to [::]:36293 send error: Network error: sendmsg error: Connection reset by peer (error 104)
I20260812 08:03:05.083457  6887 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket [::]:37365 every 8 connection(s)
I20260812 08:03:05.084280  6886 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket [::]:37365 every 8 connection(s)
W20260812 08:03:11.436856  6884 connection.cc:615] server connection from [::1]:42826: 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 (9407 ms)
[ RUN      ] Parameters/TestRpc.TestCallTimeout/UnixSocket_NoSSL
I20260812 08:03:11.443131  6925 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket unix:/tmp/dist-test-task5Mq6Kq/test-tmp/rpc-test-f74255dc0b2542038bd12c7276a2140c.sock every 8 connection(s)
I20260812 08:03:11.443295  6928 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket unix:/tmp/dist-test-task5Mq6Kq/test-tmp/rpc-test-f74255dc0b2542038bd12c7276a2140c.sock every 8 connection(s)
I20260812 08:03:11.446295  6369 rpc-test-base.h:702] status: Timed out: connection negotiation to unix:/tmp/dist-test-task5Mq6Kq/test-tmp/rpc-test-f74255dc0b2542038bd12c7276a2140c.sock for RPC Sleep timed out after 0.000s (ON_OUTBOUND_QUEUE), seconds elapsed: 0.000452491
I20260812 08:03:11.451785  6932 rpc-test-base.h:261] got call: sleep_micros: 700000
I20260812 08:03:11.647257  6369 rpc-test-base.h:702] status: Timed out: Sleep RPC to unix:/tmp/dist-test-task5Mq6Kq/test-tmp/rpc-test-f74255dc0b2542038bd12c7276a2140c.sock timed out after 0.200s (SENT), seconds elapsed: 0.200818
I20260812 08:03:11.648020  6931 rpc-test-base.h:261] got call: sleep_micros: 2000000
W20260812 08:03:12.152117  6932 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 08:03:12.152244  6932 rpcz_store.cc:269] 0812 08:03:11.451723 (+     0us) service_pool.cc:167] Inserting onto call queue
0812 08:03:11.451766 (+    43us) service_pool.cc:224] Handling call
0812 08:03:12.152092 (+700326us) inbound_call.cc:177] Queueing success response
Metrics: {}
I20260812 08:03:13.149091  6369 rpc-test-base.h:702] status: Timed out: Sleep RPC to unix:/tmp/dist-test-task5Mq6Kq/test-tmp/rpc-test-f74255dc0b2542038bd12c7276a2140c.sock timed out after 1.500s (SENT), seconds elapsed: 1.50165
W20260812 08:03:13.648365  6931 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 08:03:13.648490  6931 rpcz_store.cc:269] 0812 08:03:11.647822 (+     0us) service_pool.cc:167] Inserting onto call queue
0812 08:03:11.647881 (+    59us) service_pool.cc:224] Handling call
0812 08:03:13.648330 (+2000449us) inbound_call.cc:177] Queueing success response
Metrics: {}
W20260812 08:03:13.648736  6922 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 (2210 ms)
[ RUN      ] Parameters/TestRpc.TestServerShutsDown/TCP_IPv4_NoSSL
I20260812 08:03:13.650764  6369 rpc-test.cc:1246] Connecting to 0.0.0.0:41421
W20260812 08:03:13.653096  6949 negotiation.cc:336] Failed RPC negotiation. Trace:
0812 08:03:13.652588 (+     0us) reactor.cc:730] Submitting negotiation task for client connection to 0.0.0.0:41421 (local address 127.0.0.1:51896)
0812 08:03:13.652693 (+   105us) negotiation.cc:107] Waiting for socket to connect
0812 08:03:13.652705 (+    12us) client_negotiation.cc:175] Beginning negotiation
0812 08:03:13.652798 (+    93us) client_negotiation.cc:262] Sending NEGOTIATE NegotiatePB request
0812 08:03:13.652862 (+    64us) negotiation.cc:326] Negotiation complete: Network error: Client connection negotiation failed: client connection to 0.0.0.0:41421: BlockingWrite error: write error: Broken pipe (error 32)
Metrics: {"client-negotiator.queue_time_us":23}
[       OK ] Parameters/TestRpc.TestServerShutsDown/TCP_IPv4_NoSSL (3 ms)
[ RUN      ] Parameters/TestRpc.TestRpcHandlerLatencyMetric/TCP_IPv6_NoSSL
I20260812 08:03:13.656227  6974 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket [::]:43731 every 8 connection(s)
I20260812 08:03:13.656297  6975 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket [::]:43731 every 8 connection(s)
I20260812 08:03:13.681967  6369 rpc-test.cc:1357] Sleep() min lat: 20479
I20260812 08:03:13.682050  6369 rpc-test.cc:1358] Sleep() mean lat: 20479
I20260812 08:03:13.682089  6369 rpc-test.cc:1359] Sleep() max lat: 20479
I20260812 08:03:13.682106  6369 rpc-test.cc:1360] Sleep() #calls: 1
[       OK ] Parameters/TestRpc.TestRpcHandlerLatencyMetric/TCP_IPv6_NoSSL (30 ms)
[ RUN      ] Parameters/TestRpc.TimedOutOnResponseMetric/UnixSocket_NoSSL
I20260812 08:03:13.687043  7014 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket unix:/tmp/dist-test-task5Mq6Kq/test-tmp/rpc-test-4d8eb283ac644b2986900f66cf2cfa42.sock every 8 connection(s)
I20260812 08:03:13.686995  7015 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket unix:/tmp/dist-test-task5Mq6Kq/test-tmp/rpc-test-4d8eb283ac644b2986900f66cf2cfa42.sock every 8 connection(s)
W20260812 08:03:13.894413  7021 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 08:03:13.894536  7021 rpcz_store.cc:269] 0812 08:03:13.844012 (+     0us) service_pool.cc:167] Inserting onto call queue
0812 08:03:13.844090 (+    78us) service_pool.cc:224] Handling call
0812 08:03:13.894342 (+ 50252us) 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 08:03:13.952203  7021 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 08:03:13.952317  7021 rpcz_store.cc:269] 0812 08:03:13.901861 (+     0us) service_pool.cc:167] Inserting onto call queue
0812 08:03:13.901950 (+    89us) service_pool.cc:224] Handling call
0812 08:03:13.952177 (+ 50227us) 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 (271 ms)
[ RUN      ] Parameters/TestRpc.AcceptorDispatchingTimesMetric/TCP_IPv4_NoSSL
I20260812 08:03:13.957777  7060 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 0.0.0.0:36225 every 8 connection(s)
I20260812 08:03:13.957739  7059 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 0.0.0.0:36225 every 8 connection(s)
W20260812 08:03:13.958494  7046 negotiation.cc:336] Failed RPC negotiation. Trace:
0812 08:03:13.958263 (+     0us) reactor.cc:730] Submitting negotiation task for server connection from 127.0.0.1:39232 (local address 127.0.0.1:36225)
0812 08:03:13.958358 (+    95us) server_negotiation.cc:207] Beginning negotiation
0812 08:03:13.958361 (+     3us) server_negotiation.cc:400] Waiting for connection header
0812 08:03:13.958430 (+    69us) negotiation.cc:326] Negotiation complete: Network error: Server connection negotiation failed: server connection from 127.0.0.1:39232: BlockingRecv error: recv got EOF from 127.0.0.1:39232 (error 108)
Metrics: {"server-negotiator.queue_time_us":23}
[       OK ] Parameters/TestRpc.AcceptorDispatchingTimesMetric/TCP_IPv4_NoSSL (4 ms)
[ RUN      ] Parameters/TestRpc.RpcPendingConnectionsMetric/TCP_IPv6_NoSSL
I20260812 08:03:13.963027  7087 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 0.0.0.0:39773 every 8 connection(s)
I20260812 08:03:13.963106  7088 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 0.0.0.0:39773 every 8 connection(s)
W20260812 08:03:13.964051  7079 negotiation.cc:336] Failed RPC negotiation. Trace:
0812 08:03:13.963750 (+     0us) reactor.cc:730] Submitting negotiation task for server connection from 127.0.0.1:56430 (local address 127.0.0.1:39773)
0812 08:03:13.963868 (+   118us) server_negotiation.cc:207] Beginning negotiation
0812 08:03:13.963872 (+     4us) server_negotiation.cc:400] Waiting for connection header
0812 08:03:13.963963 (+    91us) negotiation.cc:326] Negotiation complete: Network error: Server connection negotiation failed: server connection from 127.0.0.1:56430: BlockingRecv error: recv got EOF from 127.0.0.1:56430 (error 108)
Metrics: {"server-negotiator.queue_time_us":33}
[       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 08:03:13.969498  7128 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 0.0.0.0:45883 every 8 connection(s)
I20260812 08:03:13.969547  7129 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 0.0.0.0:45883 every 8 connection(s)
W20260812 08:03:13.973982  7126 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 08:03:13.978312  7167 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket [::]:37751 every 8 connection(s)
I20260812 08:03:13.978366  7170 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket [::]:37751 every 8 connection(s)
[       OK ] Parameters/TestRpc.TestApplicationFeatureFlagUnsupportedServer/TCP_IPv6_NoSSL (7 ms)
[ RUN      ] Parameters/TestRpc.TestCancellation/UnixSocket_NoSSL
I20260812 08:03:13.986246  7204 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket unix:/tmp/dist-test-task5Mq6Kq/test-tmp/rpc-test-6517d9bfda094d60bf09e77905fc1f3d.sock every 8 connection(s)
I20260812 08:03:13.986318  7205 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket unix:/tmp/dist-test-task5Mq6Kq/test-tmp/rpc-test-6517d9bfda094d60bf09e77905fc1f3d.sock every 8 connection(s)
I20260812 08:03:13.986780  6369 rpc-test.cc:1779] Connecting to unix:/tmp/dist-test-task5Mq6Kq/test-tmp/rpc-test-6517d9bfda094d60bf09e77905fc1f3d.sock
I20260812 08:03:14.036350  6369 rpc-test-base.h:702] status: Aborted: Sleep RPC to unix:/tmp/dist-test-task5Mq6Kq/test-tmp/rpc-test-6517d9bfda094d60bf09e77905fc1f3d.sock is cancelled in state READY, seconds elapsed: 0.000160856
I20260812 08:03:14.067037  6369 rpc-test-base.h:702] status: Aborted: Sleep RPC to unix:/tmp/dist-test-task5Mq6Kq/test-tmp/rpc-test-6517d9bfda094d60bf09e77905fc1f3d.sock is cancelled in state SENT, seconds elapsed: 0.000302737
I20260812 08:03:14.067871  7210 rpc-test-base.h:261] got call: sleep_micros: 510000
I20260812 08:03:14.083577  6369 rpc-test-base.h:702] status: Aborted: Sleep RPC to unix:/tmp/dist-test-task5Mq6Kq/test-tmp/rpc-test-6517d9bfda094d60bf09e77905fc1f3d.sock is cancelled in state SENT, seconds elapsed: 0.000272238
I20260812 08:03:14.083688  7206 rpc-test-base.h:261] got call: sleep_micros: 510000
I20260812 08:03:14.088256  6369 rpc-test-base.h:702] status: Aborted: Sleep RPC to unix:/tmp/dist-test-task5Mq6Kq/test-tmp/rpc-test-6517d9bfda094d60bf09e77905fc1f3d.sock is cancelled in state SENT, seconds elapsed: 0.000203027
I20260812 08:03:14.088845  7211 rpc-test-base.h:261] got call: sleep_micros: 510000
W20260812 08:03:14.578223  7210 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 08:03:14.578379  7210 rpcz_store.cc:269] 0812 08:03:14.067753 (+     0us) service_pool.cc:167] Inserting onto call queue
0812 08:03:14.067826 (+    73us) service_pool.cc:224] Handling call
0812 08:03:14.578195 (+510369us) inbound_call.cc:177] Queueing success response
Metrics: {}
I20260812 08:03:14.578548  7210 rpc-test-base.h:261] got call: sleep_micros: 1500000
W20260812 08:03:14.593990  7206 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 08:03:14.594107  7206 rpcz_store.cc:269] 0812 08:03:14.083555 (+     0us) service_pool.cc:167] Inserting onto call queue
0812 08:03:14.083624 (+    69us) service_pool.cc:224] Handling call
0812 08:03:14.593964 (+510340us) inbound_call.cc:177] Queueing success response
Metrics: {}
W20260812 08:03:14.599107  7211 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 08:03:14.599232  7211 rpcz_store.cc:269] 0812 08:03:14.088313 (+     0us) service_pool.cc:167] Inserting onto call queue
0812 08:03:14.088709 (+   396us) service_pool.cc:224] Handling call
0812 08:03:14.599077 (+510368us) inbound_call.cc:177] Queueing success response
Metrics: {}
I20260812 08:03:15.089993  6369 rpc-test-base.h:702] status: Timed out: Sleep RPC to unix:/tmp/dist-test-task5Mq6Kq/test-tmp/rpc-test-6517d9bfda094d60bf09e77905fc1f3d.sock timed out after 1.000s (SENT), seconds elapsed: 1.0016
I20260812 08:03:15.090634  7211 rpc-test-base.h:261] got call: sleep_micros: 1500000
W20260812 08:03:16.078851  7210 rpcz_store.cc:267] Call kudu.rpc.GenericCalculatorService.Sleep from unix:<unnamed> (request call id 12) took 1990 ms (client timeout 1000 ms). Trace:
W20260812 08:03:16.078975  7210 rpcz_store.cc:269] 0812 08:03:14.088576 (+     0us) service_pool.cc:167] Inserting onto call queue
0812 08:03:14.578536 (+489960us) service_pool.cc:224] Handling call
0812 08:03:16.078824 (+1500288us) inbound_call.cc:177] Queueing success response
Metrics: {}
I20260812 08:03:16.091835  6369 rpc-test-base.h:702] status: Timed out: Sleep RPC to unix:/tmp/dist-test-task5Mq6Kq/test-tmp/rpc-test-6517d9bfda094d60bf09e77905fc1f3d.sock timed out after 1.000s (SENT), seconds elapsed: 1.00167
W20260812 08:03:16.092532  7199 messenger.cc:376] Unable to handle RPC call: Not implemented: call requires unsupported application feature flags: 99, 1
W20260812 08:03:16.093163  7199 messenger.cc:376] Unable to handle RPC call: Not implemented: call requires unsupported application feature flags: 99, 1
W20260812 08:03:16.590948  7211 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 08:03:16.591069  7211 rpcz_store.cc:269] 0812 08:03:15.090537 (+     0us) service_pool.cc:167] Inserting onto call queue
0812 08:03:15.090619 (+    82us) service_pool.cc:224] Handling call
0812 08:03:16.590924 (+1500305us) inbound_call.cc:177] Queueing success response
Metrics: {}
W20260812 08:03:16.591281  7199 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 (2608 ms)
[ RUN      ] Parameters/TestRpc.TestCancellationMultiThreads/TCP_IPv4_NoSSL
I20260812 08:03:16.595502  7248 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 0.0.0.0:37303 every 8 connection(s)
I20260812 08:03:16.595531  7249 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 0.0.0.0:37303 every 8 connection(s)
I20260812 08:03:16.595767  6369 rpc-test.cc:1939] Connecting to 0.0.0.0:37303
W20260812 08:03:32.247519  7245 connection.cc:466] server connection from 127.0.0.1:50624 torn down before Call kudu.rpc.GenericCalculatorService.PushStrings from 127.0.0.1:50624 (request call id 1673) could send its response
[       OK ] Parameters/TestRpc.TestCancellationMultiThreads/TCP_IPv4_NoSSL (15655 ms)
[ RUN      ] Parameters/TestRpc.TestPerformanceBySocketType/TCP_IPv6_NoSSL
I20260812 08:03:32.258423  7330 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket [::]:35799 every 8 connection(s)
I20260812 08:03:32.258473  7334 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket [::]:35799 every 8 connection(s)
I20260812 08:03:32.258792  6369 rpc-test.cc:1977] Connecting to [::]:35799
I20260812 08:03:33.042629  6369 rpc-test.cc:1990] Sending 1024MB via tcp socket: real 0.782s	user 0.380s	sys 0.810s
I20260812 08:03:33.787151  6369 rpc-test.cc:1990] Sending 1024MB via tcp socket: real 0.744s	user 0.392s	sys 0.751s
I20260812 08:03:34.521315  6369 rpc-test.cc:1990] Sending 1024MB via tcp socket: real 0.734s	user 0.397s	sys 0.728s
I20260812 08:03:35.278007  6369 rpc-test.cc:1990] Sending 1024MB via tcp socket: real 0.757s	user 0.393s	sys 0.762s
I20260812 08:03:36.003153  6369 rpc-test.cc:1990] Sending 1024MB via tcp socket: real 0.725s	user 0.366s	sys 0.762s
[       OK ] Parameters/TestRpc.TestPerformanceBySocketType/TCP_IPv6_NoSSL (3756 ms)
[ RUN      ] Parameters/TestRpc.TestCallId/UnixSocket_NoSSL
I20260812 08:03:36.007788  7373 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket unix:/tmp/dist-test-task5Mq6Kq/test-tmp/rpc-test-e967be0ea0214bc5b9d6836879e40868.sock every 8 connection(s)
I20260812 08:03:36.007853  7374 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket unix:/tmp/dist-test-task5Mq6Kq/test-tmp/rpc-test-e967be0ea0214bc5b9d6836879e40868.sock every 8 connection(s)
[       OK ] Parameters/TestRpc.TestCallId/UnixSocket_NoSSL (12 ms)
[----------] 31 tests from Parameters/TestRpc (50300 ms total)

[----------] 1 test from Parameters/TestRpcSocketTxRxQueue
[ RUN      ] Parameters/TestRpcSocketTxRxQueue.AcceptorRxQueueSizeMetric/TCP_IPv6_NoSSL
I20260812 08:03:36.020085  7409 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 0.0.0.0:39877 every 1 connection(s)
I20260812 08:03:36.020140  7415 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 0.0.0.0:39877 every 1 connection(s)
W20260812 08:03:36.021075  7402 negotiation.cc:336] Failed RPC negotiation. Trace:
0812 08:03:36.020760 (+     0us) reactor.cc:730] Submitting negotiation task for server connection from 127.0.0.1:51128 (local address 127.0.0.1:39877)
0812 08:03:36.020887 (+   127us) server_negotiation.cc:207] Beginning negotiation
0812 08:03:36.020890 (+     3us) server_negotiation.cc:400] Waiting for connection header
0812 08:03:36.020986 (+    96us) negotiation.cc:326] Negotiation complete: Network error: Server connection negotiation failed: server connection from 127.0.0.1:51128: BlockingRecv error: recv got EOF from 127.0.0.1:51128 (error 108)
Metrics: {"server-negotiator.queue_time_us":37}
[       OK ] Parameters/TestRpcSocketTxRxQueue.AcceptorRxQueueSizeMetric/TCP_IPv6_NoSSL (5 ms)
[----------] 1 test from Parameters/TestRpcSocketTxRxQueue (5 ms total)

[----------] Global test environment tear-down
[==========] 33 tests from 3 test suites ran. (50309 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
