[==========] Running 42 tests from 3 test suites.
[----------] Global test environment set-up.
[----------] 4 tests from TestNegotiation
[ RUN      ] TestNegotiation.TestGSSAPIInvalidNegotiation
Loading random data
Initializing database '/tmp/dist-test-taskndtFVT/test-tmp/negotiation-test.0.TestNegotiation.TestGSSAPIInvalidNegotiation.1786516598832285-2559-0/krb5kdc/principal' for realm 'KRBTEST.COM',
master key name 'K/M@KRBTEST.COM'
Aug 12 06:36:38 dist-test-slave-1zqn krb5kdc[2564](info): setting up network...
krb5kdc: setsockopt(10,IPV6_V6ONLY,1) worked
Aug 12 06:36:38 dist-test-slave-1zqn krb5kdc[2564](info): set up 2 sockets
Aug 12 06:36:38 dist-test-slave-1zqn krb5kdc[2564](info): commencing operation
krb5kdc: starting...
WARNING: Logging before InitGoogleLogging() is written to STDERR
W20260812 06:36:40.931799  2559 mini_kdc.cc:121] Time spent starting KDC: real 2.092s	user 0.000s	sys 0.006s
I20260812 06:36:40.932484  2559 negotiation-test.cc:1359] Waiting for test threads to terminate...
I20260812 06:36:40.936302  2559 negotiation-test.cc:1361] Client thread terminated.
I20260812 06:36:40.936359  2559 negotiation-test.cc:1364] Server thread terminated.
WARNING: no policy specified for kudu/127.0.0.1@KRBTEST.COM; defaulting to no policy
Authenticating as principal slave/admin@KRBTEST.COM with password.
Principal "kudu/127.0.0.1@KRBTEST.COM" created.
Authenticating as principal slave/admin@KRBTEST.COM with password.
Entry for principal kudu/127.0.0.1 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskndtFVT/test-tmp/negotiation-test.0.TestNegotiation.TestGSSAPIInvalidNegotiation.1786516598832285-2559-0/krb5kdc/kudu_127.0.0.1.keytab.
Entry for principal kudu/127.0.0.1 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskndtFVT/test-tmp/negotiation-test.0.TestNegotiation.TestGSSAPIInvalidNegotiation.1786516598832285-2559-0/krb5kdc/kudu_127.0.0.1.keytab.
I20260812 06:36:40.964470  2559 negotiation-test.cc:1359] Waiting for test threads to terminate...
I20260812 06:36:40.965759  2559 negotiation-test.cc:1361] Client thread terminated.
I20260812 06:36:40.965816  2559 negotiation-test.cc:1364] Server thread terminated.
WARNING: no policy specified for testuser@KRBTEST.COM; defaulting to no policy
Authenticating as principal slave/admin@KRBTEST.COM with password.
Principal "testuser@KRBTEST.COM" created.
Aug 12 06:36:40 dist-test-slave-1zqn krb5kdc[2564](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786516600, etypes {rep=17 tkt=17 ses=17}, testuser@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
Password for testuser@KRBTEST.COM: 
WARNING: no policy specified for otherservice/127.0.0.1@KRBTEST.COM; defaulting to no policy
Authenticating as principal testuser/admin@KRBTEST.COM with password.
Principal "otherservice/127.0.0.1@KRBTEST.COM" created.
Authenticating as principal testuser/admin@KRBTEST.COM with password.
Entry for principal otherservice/127.0.0.1 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskndtFVT/test-tmp/negotiation-test.0.TestNegotiation.TestGSSAPIInvalidNegotiation.1786516598832285-2559-0/krb5kdc/otherservice_127.0.0.1.keytab.
Entry for principal otherservice/127.0.0.1 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskndtFVT/test-tmp/negotiation-test.0.TestNegotiation.TestGSSAPIInvalidNegotiation.1786516598832285-2559-0/krb5kdc/otherservice_127.0.0.1.keytab.
I20260812 06:36:41.023710  2559 negotiation-test.cc:1359] Waiting for test threads to terminate...
Aug 12 06:36:41 dist-test-slave-1zqn krb5kdc[2564](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786516600, etypes {rep=17 tkt=17 ses=17}, testuser@KRBTEST.COM for kudu/127.0.0.1@KRBTEST.COM
I20260812 06:36:41.030164  2559 negotiation-test.cc:1361] Client thread terminated.
I20260812 06:36:41.030228  2559 negotiation-test.cc:1364] Server thread terminated.
[       OK ] TestNegotiation.TestGSSAPIInvalidNegotiation (2196 ms)
[ RUN      ] TestNegotiation.TestPreflight
Loading random data
Initializing database '/tmp/dist-test-taskndtFVT/test-tmp/negotiation-test.0.TestNegotiation.TestPreflight.1786516598832285-2559-0/krb5kdc/principal' for realm 'KRBTEST.COM',
master key name 'K/M@KRBTEST.COM'
Aug 12 06:36:41 dist-test-slave-1zqn krb5kdc[2584](info): setting up network...
krb5kdc: setsockopt(10,IPV6_V6ONLY,1) worked
Aug 12 06:36:41 dist-test-slave-1zqn krb5kdc[2584](info): set up 2 sockets
Aug 12 06:36:41 dist-test-slave-1zqn krb5kdc[2584](info): commencing operation
krb5kdc: starting...
W20260812 06:36:43.180359  2559 mini_kdc.cc:121] Time spent starting KDC: real 2.144s	user 0.001s	sys 0.005s
WARNING: no policy specified for kudu/127.0.0.1@KRBTEST.COM; defaulting to no policy
Authenticating as principal slave/admin@KRBTEST.COM with password.
Principal "kudu/127.0.0.1@KRBTEST.COM" created.
Authenticating as principal slave/admin@KRBTEST.COM with password.
Entry for principal kudu/127.0.0.1 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskndtFVT/test-tmp/negotiation-test.0.TestNegotiation.TestPreflight.1786516598832285-2559-0/krb5kdc/kudu_127.0.0.1.keytab.
Entry for principal kudu/127.0.0.1 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskndtFVT/test-tmp/negotiation-test.0.TestNegotiation.TestPreflight.1786516598832285-2559-0/krb5kdc/kudu_127.0.0.1.keytab.
WARNING: no policy specified for wrong-service/127.0.0.1@KRBTEST.COM; defaulting to no policy
Authenticating as principal slave/admin@KRBTEST.COM with password.
Principal "wrong-service/127.0.0.1@KRBTEST.COM" created.
Authenticating as principal slave/admin@KRBTEST.COM with password.
Entry for principal wrong-service/127.0.0.1 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskndtFVT/test-tmp/negotiation-test.0.TestNegotiation.TestPreflight.1786516598832285-2559-0/krb5kdc/wrong-service_127.0.0.1.keytab.
Entry for principal wrong-service/127.0.0.1 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskndtFVT/test-tmp/negotiation-test.0.TestNegotiation.TestPreflight.1786516598832285-2559-0/krb5kdc/wrong-service_127.0.0.1.keytab.
[       OK ] TestNegotiation.TestPreflight (2214 ms)
[ RUN      ] TestNegotiation.TestClientTimeout
I20260812 06:36:43.246549  2559 negotiation-test.cc:1359] Waiting for test threads to terminate...
I20260812 06:36:43.246977  2559 negotiation-test.cc:1361] Client thread terminated.
I20260812 06:36:43.250732  2559 negotiation-test.cc:1364] Server thread terminated.
[       OK ] TestNegotiation.TestClientTimeout (5 ms)
[ RUN      ] TestNegotiation.TestServerTimeout
I20260812 06:36:43.251662  2559 negotiation-test.cc:1359] Waiting for test threads to terminate...
I20260812 06:36:43.254091  2559 negotiation-test.cc:1361] Client thread terminated.
I20260812 06:36:43.254148  2559 negotiation-test.cc:1364] Server thread terminated.
[       OK ] TestNegotiation.TestServerTimeout (3 ms)
[----------] 4 tests from TestNegotiation (4418 ms total)

[----------] 5 tests from TestDisableInit
[ RUN      ] TestDisableInit.TestDisableSasl_NotInitialized
[       OK ] TestDisableInit.TestDisableSasl_NotInitialized (28 ms)
[ RUN      ] TestDisableInit.TestDisableSasl_Good
[       OK ] TestDisableInit.TestDisableSasl_Good (30 ms)
[ RUN      ] TestDisableInit.TestMultipleSaslInit
[       OK ] TestDisableInit.TestMultipleSaslInit (29 ms)
[ RUN      ] TestDisableInit.TestDisableSasl_NoMutexImpl
[       OK ] TestDisableInit.TestDisableSasl_NoMutexImpl (30 ms)
[ RUN      ] TestDisableInit.TestMultipleSaslInit_NoMutexImpl
[       OK ] TestDisableInit.TestMultipleSaslInit_NoMutexImpl (30 ms)
[----------] 5 tests from TestDisableInit (150 ms total)

[----------] 33 tests from NegotiationCombinations/TestNegotiation
[ RUN      ] NegotiationCombinations/TestNegotiation.TestNegotiation/0
W20260812 06:36:43.453162  2604 negotiation-test.cc:357] Failed client RPC negotiation. Client trace:
0812 06:36:43.452717 (+     0us) client_negotiation.cc:175] Beginning negotiation
Metrics: {}
W20260812 06:36:43.453164  2607 negotiation-test.cc:373] Failed server RPC negotiation. Server trace:
0812 06:36:43.452717 (+     0us) server_negotiation.cc:207] Beginning negotiation
0812 06:36:43.452728 (+    11us) server_negotiation.cc:400] Waiting for connection header
0812 06:36:43.452848 (+   120us) server_negotiation.cc:408] Connection header received
Metrics: {}
[       OK ] NegotiationCombinations/TestNegotiation.TestNegotiation/0 (49 ms)
[ RUN      ] NegotiationCombinations/TestNegotiation.TestNegotiation/1
E20260812 06:36:43.487051  2609 server_negotiation.cc:598] Not authorized: SASL server mechanism list is empty!
W20260812 06:36:43.487372  2609 negotiation-test.cc:373] Failed server RPC negotiation. Server trace:
0812 06:36:43.486615 (+     0us) server_negotiation.cc:207] Beginning negotiation
0812 06:36:43.486625 (+    10us) server_negotiation.cc:400] Waiting for connection header
0812 06:36:43.486736 (+   111us) server_negotiation.cc:408] Connection header received
0812 06:36:43.486997 (+   261us) server_negotiation.cc:366] Received NEGOTIATE NegotiatePB request
0812 06:36:43.487000 (+     3us) server_negotiation.cc:462] Received NEGOTIATE request from client
0812 06:36:43.487134 (+   134us) server_negotiation.cc:599] Sending FATAL_UNAUTHORIZED response to client
0812 06:36:43.487141 (+     7us) server_negotiation.cc:395] Sending RPC error: FATAL_UNAUTHORIZED: Not authorized: SASL server mechanism list is empty!
Metrics: {}
W20260812 06:36:43.487372  2608 negotiation-test.cc:357] Failed client RPC negotiation. Client trace:
0812 06:36:43.486615 (+     0us) client_negotiation.cc:175] Beginning negotiation
0812 06:36:43.486832 (+   217us) client_negotiation.cc:262] Sending NEGOTIATE NegotiatePB request
0812 06:36:43.487281 (+   449us) client_negotiation.cc:293] Received error response from server: Not authorized: FATAL_UNAUTHORIZED: Not authorized: SASL server mechanism list is empty!
Metrics: {}
[       OK ] NegotiationCombinations/TestNegotiation.TestNegotiation/1 (34 ms)
[ RUN      ] NegotiationCombinations/TestNegotiation.TestNegotiation/2
[       OK ] NegotiationCombinations/TestNegotiation.TestNegotiation/2 (41 ms)
[ RUN      ] NegotiationCombinations/TestNegotiation.TestNegotiation/3
Loading random data
Initializing database '/tmp/dist-test-taskndtFVT/test-tmp/negotiation-test.0.NegotiationCombinations_TestNegotiation.TestNegotiation_3.1786516598832285-2559-0/krb5kdc/principal' for realm 'KRBTEST.COM',
master key name 'K/M@KRBTEST.COM'
Aug 12 06:36:43 dist-test-slave-1zqn krb5kdc[2615](info): setting up network...
krb5kdc: setsockopt(10,IPV6_V6ONLY,1) worked
Aug 12 06:36:43 dist-test-slave-1zqn krb5kdc[2615](info): set up 2 sockets
Aug 12 06:36:43 dist-test-slave-1zqn krb5kdc[2615](info): commencing operation
krb5kdc: starting...
W20260812 06:36:45.668927  2559 mini_kdc.cc:121] Time spent starting KDC: real 2.121s	user 0.004s	sys 0.005s
WARNING: no policy specified for client-gssapi@KRBTEST.COM; defaulting to no policy
Authenticating as principal slave/admin@KRBTEST.COM with password.
Principal "client-gssapi@KRBTEST.COM" created.
Aug 12 06:36:45 dist-test-slave-1zqn krb5kdc[2615](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786516605, etypes {rep=17 tkt=17 ses=17}, client-gssapi@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
Password for client-gssapi@KRBTEST.COM: 
WARNING: no policy specified for kudu/127.0.0.1@KRBTEST.COM; defaulting to no policy
Authenticating as principal client-gssapi/admin@KRBTEST.COM with password.
Principal "kudu/127.0.0.1@KRBTEST.COM" created.
Authenticating as principal client-gssapi/admin@KRBTEST.COM with password.
Entry for principal kudu/127.0.0.1 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskndtFVT/test-tmp/negotiation-test.0.NegotiationCombinations_TestNegotiation.TestNegotiation_3.1786516598832285-2559-0/krb5kdc/kudu_127.0.0.1.keytab.
Entry for principal kudu/127.0.0.1 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskndtFVT/test-tmp/negotiation-test.0.NegotiationCombinations_TestNegotiation.TestNegotiation_3.1786516598832285-2559-0/krb5kdc/kudu_127.0.0.1.keytab.
Aug 12 06:36:45 dist-test-slave-1zqn krb5kdc[2615](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786516605, etypes {rep=17 tkt=17 ses=17}, client-gssapi@KRBTEST.COM for kudu/127.0.0.1@KRBTEST.COM
[       OK ] NegotiationCombinations/TestNegotiation.TestNegotiation/3 (2207 ms)
[ RUN      ] NegotiationCombinations/TestNegotiation.TestNegotiation/4
Loading random data
Initializing database '/tmp/dist-test-taskndtFVT/test-tmp/negotiation-test.0.NegotiationCombinations_TestNegotiation.TestNegotiation_4.1786516598832285-2559-0/krb5kdc/principal' for realm 'KRBTEST.COM',
master key name 'K/M@KRBTEST.COM'
Aug 12 06:36:45 dist-test-slave-1zqn krb5kdc[2628](info): setting up network...
krb5kdc: setsockopt(10,IPV6_V6ONLY,1) worked
Aug 12 06:36:45 dist-test-slave-1zqn krb5kdc[2628](info): set up 2 sockets
Aug 12 06:36:45 dist-test-slave-1zqn krb5kdc[2628](info): commencing operation
krb5kdc: starting...
W20260812 06:36:47.870029  2559 mini_kdc.cc:121] Time spent starting KDC: real 2.089s	user 0.004s	sys 0.002s
WARNING: no policy specified for client-gssapi@KRBTEST.COM; defaulting to no policy
Authenticating as principal slave/admin@KRBTEST.COM with password.
Principal "client-gssapi@KRBTEST.COM" created.
Aug 12 06:36:47 dist-test-slave-1zqn krb5kdc[2628](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786516607, etypes {rep=17 tkt=17 ses=17}, client-gssapi@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
Password for client-gssapi@KRBTEST.COM: 
WARNING: no policy specified for kudu/127.0.0.1@KRBTEST.COM; defaulting to no policy
Authenticating as principal client-gssapi/admin@KRBTEST.COM with password.
Principal "kudu/127.0.0.1@KRBTEST.COM" created.
Authenticating as principal client-gssapi/admin@KRBTEST.COM with password.
Entry for principal kudu/127.0.0.1 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskndtFVT/test-tmp/negotiation-test.0.NegotiationCombinations_TestNegotiation.TestNegotiation_4.1786516598832285-2559-0/krb5kdc/kudu_127.0.0.1.keytab.
Entry for principal kudu/127.0.0.1 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskndtFVT/test-tmp/negotiation-test.0.NegotiationCombinations_TestNegotiation.TestNegotiation_4.1786516598832285-2559-0/krb5kdc/kudu_127.0.0.1.keytab.
Aug 12 06:36:47 dist-test-slave-1zqn krb5kdc[2628](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786516607, etypes {rep=17 tkt=17 ses=17}, client-gssapi@KRBTEST.COM for kudu/127.0.0.1@KRBTEST.COM
[       OK ] NegotiationCombinations/TestNegotiation.TestNegotiation/4 (2211 ms)
[ RUN      ] NegotiationCombinations/TestNegotiation.TestNegotiation/5
Loading random data
Initializing database '/tmp/dist-test-taskndtFVT/test-tmp/negotiation-test.0.NegotiationCombinations_TestNegotiation.TestNegotiation_5.1786516598832285-2559-0/krb5kdc/principal' for realm 'KRBTEST.COM',
master key name 'K/M@KRBTEST.COM'
Aug 12 06:36:48 dist-test-slave-1zqn krb5kdc[2641](info): setting up network...
krb5kdc: setsockopt(10,IPV6_V6ONLY,1) worked
Aug 12 06:36:48 dist-test-slave-1zqn krb5kdc[2641](info): set up 2 sockets
Aug 12 06:36:48 dist-test-slave-1zqn krb5kdc[2641](info): commencing operation
krb5kdc: starting...
W20260812 06:36:50.132943  2559 mini_kdc.cc:121] Time spent starting KDC: real 2.142s	user 0.002s	sys 0.007s
WARNING: no policy specified for client-gssapi@KRBTEST.COM; defaulting to no policy
Authenticating as principal slave/admin@KRBTEST.COM with password.
Principal "client-gssapi@KRBTEST.COM" created.
Aug 12 06:36:50 dist-test-slave-1zqn krb5kdc[2641](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786516610, etypes {rep=17 tkt=17 ses=17}, client-gssapi@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
Password for client-gssapi@KRBTEST.COM: 
WARNING: no policy specified for kudu/127.0.0.1@KRBTEST.COM; defaulting to no policy
Authenticating as principal client-gssapi/admin@KRBTEST.COM with password.
Principal "kudu/127.0.0.1@KRBTEST.COM" created.
Authenticating as principal client-gssapi/admin@KRBTEST.COM with password.
Entry for principal kudu/127.0.0.1 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskndtFVT/test-tmp/negotiation-test.0.NegotiationCombinations_TestNegotiation.TestNegotiation_5.1786516598832285-2559-0/krb5kdc/kudu_127.0.0.1.keytab.
Entry for principal kudu/127.0.0.1 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskndtFVT/test-tmp/negotiation-test.0.NegotiationCombinations_TestNegotiation.TestNegotiation_5.1786516598832285-2559-0/krb5kdc/kudu_127.0.0.1.keytab.
Aug 12 06:36:50 dist-test-slave-1zqn krb5kdc[2641](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786516610, etypes {rep=17 tkt=17 ses=17}, client-gssapi@KRBTEST.COM for kudu/127.0.0.1@KRBTEST.COM
[       OK ] NegotiationCombinations/TestNegotiation.TestNegotiation/5 (2259 ms)
[ RUN      ] NegotiationCombinations/TestNegotiation.TestNegotiation/6
Loading random data
Initializing database '/tmp/dist-test-taskndtFVT/test-tmp/negotiation-test.0.NegotiationCombinations_TestNegotiation.TestNegotiation_6.1786516598832285-2559-0/krb5kdc/principal' for realm 'KRBTEST.COM',
master key name 'K/M@KRBTEST.COM'
Aug 12 06:36:50 dist-test-slave-1zqn krb5kdc[2653](info): setting up network...
krb5kdc: setsockopt(10,IPV6_V6ONLY,1) worked
Aug 12 06:36:50 dist-test-slave-1zqn krb5kdc[2653](info): set up 2 sockets
Aug 12 06:36:50 dist-test-slave-1zqn krb5kdc[2653](info): commencing operation
krb5kdc: starting...
W20260812 06:36:52.332257  2559 mini_kdc.cc:121] Time spent starting KDC: real 2.087s	user 0.003s	sys 0.004s
WARNING: no policy specified for kudu/127.0.0.1@KRBTEST.COM; defaulting to no policy
Authenticating as principal slave/admin@KRBTEST.COM with password.
Principal "kudu/127.0.0.1@KRBTEST.COM" created.
Authenticating as principal slave/admin@KRBTEST.COM with password.
Entry for principal kudu/127.0.0.1 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskndtFVT/test-tmp/negotiation-test.0.NegotiationCombinations_TestNegotiation.TestNegotiation_6.1786516598832285-2559-0/krb5kdc/kudu_127.0.0.1.keytab.
Entry for principal kudu/127.0.0.1 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskndtFVT/test-tmp/negotiation-test.0.NegotiationCombinations_TestNegotiation.TestNegotiation_6.1786516598832285-2559-0/krb5kdc/kudu_127.0.0.1.keytab.
W20260812 06:36:52.359321  2660 negotiation-test.cc:357] Failed client RPC negotiation. Client trace:
0812 06:36:52.358744 (+     0us) client_negotiation.cc:175] Beginning negotiation
0812 06:36:52.358913 (+   169us) client_negotiation.cc:262] Sending NEGOTIATE NegotiatePB request
0812 06:36:52.359176 (+   263us) client_negotiation.cc:281] Received NEGOTIATE NegotiatePB response
0812 06:36:52.359178 (+     2us) client_negotiation.cc:404] Received NEGOTIATE response from server
Metrics: {}
W20260812 06:36:52.359405  2661 negotiation-test.cc:373] Failed server RPC negotiation. Server trace:
0812 06:36:52.358743 (+     0us) server_negotiation.cc:207] Beginning negotiation
0812 06:36:52.358751 (+     8us) server_negotiation.cc:400] Waiting for connection header
0812 06:36:52.358858 (+   107us) server_negotiation.cc:408] Connection header received
0812 06:36:52.359008 (+   150us) server_negotiation.cc:366] Received NEGOTIATE NegotiatePB request
0812 06:36:52.359011 (+     3us) server_negotiation.cc:462] Received NEGOTIATE request from client
0812 06:36:52.359046 (+    35us) server_negotiation.cc:378] Sending NEGOTIATE NegotiatePB response
0812 06:36:52.359146 (+   100us) server_negotiation.cc:227] Negotiated authn=SASL
Metrics: {}
[       OK ] NegotiationCombinations/TestNegotiation.TestNegotiation/6 (2152 ms)
[ RUN      ] NegotiationCombinations/TestNegotiation.TestNegotiation/7
Loading random data
Initializing database '/tmp/dist-test-taskndtFVT/test-tmp/negotiation-test.0.NegotiationCombinations_TestNegotiation.TestNegotiation_7.1786516598832285-2559-0/krb5kdc/principal' for realm 'KRBTEST.COM',
master key name 'K/M@KRBTEST.COM'
Aug 12 06:36:52 dist-test-slave-1zqn krb5kdc[2663](info): setting up network...
krb5kdc: setsockopt(10,IPV6_V6ONLY,1) worked
Aug 12 06:36:52 dist-test-slave-1zqn krb5kdc[2663](info): set up 2 sockets
Aug 12 06:36:52 dist-test-slave-1zqn krb5kdc[2663](info): commencing operation
krb5kdc: starting...
W20260812 06:36:54.559290  2559 mini_kdc.cc:121] Time spent starting KDC: real 2.164s	user 0.001s	sys 0.007s
WARNING: no policy specified for client-gssapi@KRBTEST.COM; defaulting to no policy
Authenticating as principal slave/admin@KRBTEST.COM with password.
Principal "client-gssapi@KRBTEST.COM" created.
Aug 12 06:36:54 dist-test-slave-1zqn krb5kdc[2663](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786516614, etypes {rep=17 tkt=17 ses=17}, client-gssapi@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
Password for client-gssapi@KRBTEST.COM: 
WARNING: no policy specified for kudu/127.0.0.1@KRBTEST.COM; defaulting to no policy
Authenticating as principal client-gssapi/admin@KRBTEST.COM with password.
Principal "kudu/127.0.0.1@KRBTEST.COM" created.
Authenticating as principal client-gssapi/admin@KRBTEST.COM with password.
Entry for principal kudu/127.0.0.1 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskndtFVT/test-tmp/negotiation-test.0.NegotiationCombinations_TestNegotiation.TestNegotiation_7.1786516598832285-2559-0/krb5kdc/kudu_127.0.0.1.keytab.
Entry for principal kudu/127.0.0.1 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskndtFVT/test-tmp/negotiation-test.0.NegotiationCombinations_TestNegotiation.TestNegotiation_7.1786516598832285-2559-0/krb5kdc/kudu_127.0.0.1.keytab.
Aug 12 06:36:54 dist-test-slave-1zqn krb5kdc[2663](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786516614, etypes {rep=17 tkt=17 ses=17}, client-gssapi@KRBTEST.COM for kudu/127.0.0.1@KRBTEST.COM
[       OK ] NegotiationCombinations/TestNegotiation.TestNegotiation/7 (2318 ms)
[ RUN      ] NegotiationCombinations/TestNegotiation.TestNegotiation/8
Loading random data
Initializing database '/tmp/dist-test-taskndtFVT/test-tmp/negotiation-test.0.NegotiationCombinations_TestNegotiation.TestNegotiation_8.1786516598832285-2559-0/krb5kdc/principal' for realm 'KRBTEST.COM',
master key name 'K/M@KRBTEST.COM'
Aug 12 06:36:54 dist-test-slave-1zqn krb5kdc[2677](info): setting up network...
krb5kdc: setsockopt(10,IPV6_V6ONLY,1) worked
Aug 12 06:36:54 dist-test-slave-1zqn krb5kdc[2677](info): set up 2 sockets
Aug 12 06:36:54 dist-test-slave-1zqn krb5kdc[2677](info): commencing operation
krb5kdc: starting...
W20260812 06:36:56.855818  2559 mini_kdc.cc:121] Time spent starting KDC: real 2.095s	user 0.005s	sys 0.001s
WARNING: no policy specified for client-gssapi@KRBTEST.COM; defaulting to no policy
Authenticating as principal slave/admin@KRBTEST.COM with password.
Principal "client-gssapi@KRBTEST.COM" created.
Aug 12 06:36:56 dist-test-slave-1zqn krb5kdc[2677](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786516616, etypes {rep=17 tkt=17 ses=17}, client-gssapi@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
Password for client-gssapi@KRBTEST.COM: 
WARNING: no policy specified for kudu/127.0.0.1@KRBTEST.COM; defaulting to no policy
Authenticating as principal client-gssapi/admin@KRBTEST.COM with password.
Principal "kudu/127.0.0.1@KRBTEST.COM" created.
Authenticating as principal client-gssapi/admin@KRBTEST.COM with password.
Entry for principal kudu/127.0.0.1 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskndtFVT/test-tmp/negotiation-test.0.NegotiationCombinations_TestNegotiation.TestNegotiation_8.1786516598832285-2559-0/krb5kdc/kudu_127.0.0.1.keytab.
Entry for principal kudu/127.0.0.1 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskndtFVT/test-tmp/negotiation-test.0.NegotiationCombinations_TestNegotiation.TestNegotiation_8.1786516598832285-2559-0/krb5kdc/kudu_127.0.0.1.keytab.
Aug 12 06:36:56 dist-test-slave-1zqn krb5kdc[2677](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786516616, etypes {rep=17 tkt=17 ses=17}, client-gssapi@KRBTEST.COM for kudu/127.0.0.1@KRBTEST.COM
[       OK ] NegotiationCombinations/TestNegotiation.TestNegotiation/8 (2295 ms)
[ RUN      ] NegotiationCombinations/TestNegotiation.TestNegotiation/9
[       OK ] NegotiationCombinations/TestNegotiation.TestNegotiation/9 (43 ms)
[ RUN      ] NegotiationCombinations/TestNegotiation.TestNegotiation/10
Loading random data
Initializing database '/tmp/dist-test-taskndtFVT/test-tmp/negotiation-test.0.NegotiationCombinations_TestNegotiation.TestNegotiation_10.1786516598832285-2559-0/krb5kdc/principal' for realm 'KRBTEST.COM',
master key name 'K/M@KRBTEST.COM'
Aug 12 06:36:57 dist-test-slave-1zqn krb5kdc[2698](info): setting up network...
krb5kdc: setsockopt(10,IPV6_V6ONLY,1) worked
Aug 12 06:36:57 dist-test-slave-1zqn krb5kdc[2698](info): set up 2 sockets
Aug 12 06:36:57 dist-test-slave-1zqn krb5kdc[2698](info): commencing operation
krb5kdc: starting...
W20260812 06:36:59.228349  2559 mini_kdc.cc:121] Time spent starting KDC: real 2.122s	user 0.000s	sys 0.008s
WARNING: no policy specified for client-gssapi@KRBTEST.COM; defaulting to no policy
Authenticating as principal slave/admin@KRBTEST.COM with password.
Principal "client-gssapi@KRBTEST.COM" created.
Aug 12 06:36:59 dist-test-slave-1zqn krb5kdc[2698](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786516619, etypes {rep=17 tkt=17 ses=17}, client-gssapi@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
Password for client-gssapi@KRBTEST.COM: 
WARNING: no policy specified for kudu/127.0.0.1@KRBTEST.COM; defaulting to no policy
Authenticating as principal client-gssapi/admin@KRBTEST.COM with password.
Principal "kudu/127.0.0.1@KRBTEST.COM" created.
Authenticating as principal client-gssapi/admin@KRBTEST.COM with password.
Entry for principal kudu/127.0.0.1 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskndtFVT/test-tmp/negotiation-test.0.NegotiationCombinations_TestNegotiation.TestNegotiation_10.1786516598832285-2559-0/krb5kdc/kudu_127.0.0.1.keytab.
Entry for principal kudu/127.0.0.1 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskndtFVT/test-tmp/negotiation-test.0.NegotiationCombinations_TestNegotiation.TestNegotiation_10.1786516598832285-2559-0/krb5kdc/kudu_127.0.0.1.keytab.
[       OK ] NegotiationCombinations/TestNegotiation.TestNegotiation/10 (2272 ms)
[ RUN      ] NegotiationCombinations/TestNegotiation.TestNegotiation/11
[       OK ] NegotiationCombinations/TestNegotiation.TestNegotiation/11 (62 ms)
[ RUN      ] NegotiationCombinations/TestNegotiation.TestNegotiation/12
[       OK ] NegotiationCombinations/TestNegotiation.TestNegotiation/12 (38 ms)
[ RUN      ] NegotiationCombinations/TestNegotiation.TestNegotiation/13
Loading random data
Initializing database '/tmp/dist-test-taskndtFVT/test-tmp/negotiation-test.0.NegotiationCombinations_TestNegotiation.TestNegotiation_13.1786516598832285-2559-0/krb5kdc/principal' for realm 'KRBTEST.COM',
master key name 'K/M@KRBTEST.COM'
Aug 12 06:36:59 dist-test-slave-1zqn krb5kdc[2721](info): setting up network...
krb5kdc: setsockopt(10,IPV6_V6ONLY,1) worked
Aug 12 06:36:59 dist-test-slave-1zqn krb5kdc[2721](info): set up 2 sockets
Aug 12 06:36:59 dist-test-slave-1zqn krb5kdc[2721](info): commencing operation
krb5kdc: starting...
W20260812 06:37:01.548655  2559 mini_kdc.cc:121] Time spent starting KDC: real 2.098s	user 0.003s	sys 0.004s
WARNING: no policy specified for client-gssapi@KRBTEST.COM; defaulting to no policy
Authenticating as principal slave/admin@KRBTEST.COM with password.
Principal "client-gssapi@KRBTEST.COM" created.
Aug 12 06:37:01 dist-test-slave-1zqn krb5kdc[2721](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786516621, etypes {rep=17 tkt=17 ses=17}, client-gssapi@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
Password for client-gssapi@KRBTEST.COM: 
WARNING: no policy specified for kudu/127.0.0.1@KRBTEST.COM; defaulting to no policy
Authenticating as principal client-gssapi/admin@KRBTEST.COM with password.
Principal "kudu/127.0.0.1@KRBTEST.COM" created.
Authenticating as principal client-gssapi/admin@KRBTEST.COM with password.
Entry for principal kudu/127.0.0.1 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskndtFVT/test-tmp/negotiation-test.0.NegotiationCombinations_TestNegotiation.TestNegotiation_13.1786516598832285-2559-0/krb5kdc/kudu_127.0.0.1.keytab.
Entry for principal kudu/127.0.0.1 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskndtFVT/test-tmp/negotiation-test.0.NegotiationCombinations_TestNegotiation.TestNegotiation_13.1786516598832285-2559-0/krb5kdc/kudu_127.0.0.1.keytab.
[       OK ] NegotiationCombinations/TestNegotiation.TestNegotiation/13 (2224 ms)
[ RUN      ] NegotiationCombinations/TestNegotiation.TestNegotiation/14
W20260812 06:37:01.650823  2736 negotiation-test.cc:373] Failed server RPC negotiation. Server trace:
0812 06:37:01.650336 (+     0us) server_negotiation.cc:207] Beginning negotiation
0812 06:37:01.650346 (+    10us) server_negotiation.cc:400] Waiting for connection header
0812 06:37:01.650446 (+   100us) server_negotiation.cc:408] Connection header received
0812 06:37:01.650602 (+   156us) server_negotiation.cc:366] Received NEGOTIATE NegotiatePB request
0812 06:37:01.650605 (+     3us) server_negotiation.cc:462] Received NEGOTIATE request from client
0812 06:37:01.650619 (+    14us) server_negotiation.cc:395] Sending RPC error: FATAL_UNAUTHORIZED: Not authorized: client does not support required TLS encryption
Metrics: {}
W20260812 06:37:01.650933  2735 negotiation-test.cc:357] Failed client RPC negotiation. Client trace:
0812 06:37:01.650333 (+     0us) client_negotiation.cc:175] Beginning negotiation
0812 06:37:01.650481 (+   148us) client_negotiation.cc:262] Sending NEGOTIATE NegotiatePB request
0812 06:37:01.650757 (+   276us) client_negotiation.cc:293] Received error response from server: Not authorized: FATAL_UNAUTHORIZED: Not authorized: client does not support required TLS encryption
Metrics: {}
[       OK ] NegotiationCombinations/TestNegotiation.TestNegotiation/14 (35 ms)
[ RUN      ] NegotiationCombinations/TestNegotiation.TestNegotiation/15
W20260812 06:37:01.691654  2737 negotiation-test.cc:357] Failed client RPC negotiation. Client trace:
0812 06:37:01.691076 (+     0us) client_negotiation.cc:175] Beginning negotiation
0812 06:37:01.691262 (+   186us) client_negotiation.cc:262] Sending NEGOTIATE NegotiatePB request
0812 06:37:01.691523 (+   261us) client_negotiation.cc:281] Received NEGOTIATE NegotiatePB response
0812 06:37:01.691526 (+     3us) client_negotiation.cc:404] Received NEGOTIATE response from server
Metrics: {}
W20260812 06:37:01.692255  2738 negotiation-test.cc:373] Failed server RPC negotiation. Server trace:
0812 06:37:01.691076 (+     0us) server_negotiation.cc:207] Beginning negotiation
0812 06:37:01.691086 (+    10us) server_negotiation.cc:400] Waiting for connection header
0812 06:37:01.691192 (+   106us) server_negotiation.cc:408] Connection header received
0812 06:37:01.691368 (+   176us) server_negotiation.cc:366] Received NEGOTIATE NegotiatePB request
0812 06:37:01.691372 (+     4us) server_negotiation.cc:462] Received NEGOTIATE request from client
0812 06:37:01.691407 (+    35us) server_negotiation.cc:378] Sending NEGOTIATE NegotiatePB response
0812 06:37:01.691511 (+   104us) server_negotiation.cc:227] Negotiated authn=SASL
Metrics: {}
[       OK ] NegotiationCombinations/TestNegotiation.TestNegotiation/15 (41 ms)
[ RUN      ] NegotiationCombinations/TestNegotiation.TestNegotiation/16
Loading random data
Initializing database '/tmp/dist-test-taskndtFVT/test-tmp/negotiation-test.0.NegotiationCombinations_TestNegotiation.TestNegotiation_16.1786516598832285-2559-0/krb5kdc/principal' for realm 'KRBTEST.COM',
master key name 'K/M@KRBTEST.COM'
Aug 12 06:37:01 dist-test-slave-1zqn krb5kdc[2740](info): setting up network...
krb5kdc: setsockopt(10,IPV6_V6ONLY,1) worked
Aug 12 06:37:01 dist-test-slave-1zqn krb5kdc[2740](info): set up 2 sockets
Aug 12 06:37:01 dist-test-slave-1zqn krb5kdc[2740](info): commencing operation
krb5kdc: starting...
W20260812 06:37:03.814324  2559 mini_kdc.cc:121] Time spent starting KDC: real 2.095s	user 0.001s	sys 0.008s
WARNING: no policy specified for client-gssapi@KRBTEST.COM; defaulting to no policy
Authenticating as principal slave/admin@KRBTEST.COM with password.
Principal "client-gssapi@KRBTEST.COM" created.
Aug 12 06:37:03 dist-test-slave-1zqn krb5kdc[2740](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786516623, etypes {rep=17 tkt=17 ses=17}, client-gssapi@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
Password for client-gssapi@KRBTEST.COM: 
WARNING: no policy specified for kudu/127.0.0.1@KRBTEST.COM; defaulting to no policy
Authenticating as principal client-gssapi/admin@KRBTEST.COM with password.
Principal "kudu/127.0.0.1@KRBTEST.COM" created.
Authenticating as principal client-gssapi/admin@KRBTEST.COM with password.
Entry for principal kudu/127.0.0.1 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskndtFVT/test-tmp/negotiation-test.0.NegotiationCombinations_TestNegotiation.TestNegotiation_16.1786516598832285-2559-0/krb5kdc/kudu_127.0.0.1.keytab.
Entry for principal kudu/127.0.0.1 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskndtFVT/test-tmp/negotiation-test.0.NegotiationCombinations_TestNegotiation.TestNegotiation_16.1786516598832285-2559-0/krb5kdc/kudu_127.0.0.1.keytab.
Aug 12 06:37:03 dist-test-slave-1zqn krb5kdc[2740](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786516623, etypes {rep=17 tkt=17 ses=17}, client-gssapi@KRBTEST.COM for kudu/127.0.0.1@KRBTEST.COM
[       OK ] NegotiationCombinations/TestNegotiation.TestNegotiation/16 (2246 ms)
[ RUN      ] NegotiationCombinations/TestNegotiation.TestNegotiation/17
Loading random data
Initializing database '/tmp/dist-test-taskndtFVT/test-tmp/negotiation-test.0.NegotiationCombinations_TestNegotiation.TestNegotiation_17.1786516598832285-2559-0/krb5kdc/principal' for realm 'KRBTEST.COM',
master key name 'K/M@KRBTEST.COM'
Aug 12 06:37:04 dist-test-slave-1zqn krb5kdc[2755](info): setting up network...
krb5kdc: setsockopt(10,IPV6_V6ONLY,1) worked
Aug 12 06:37:04 dist-test-slave-1zqn krb5kdc[2755](info): set up 2 sockets
Aug 12 06:37:04 dist-test-slave-1zqn krb5kdc[2755](info): commencing operation
krb5kdc: starting...
W20260812 06:37:06.050872  2559 mini_kdc.cc:121] Time spent starting KDC: real 2.073s	user 0.007s	sys 0.000s
WARNING: no policy specified for client-gssapi@KRBTEST.COM; defaulting to no policy
Authenticating as principal slave/admin@KRBTEST.COM with password.
Principal "client-gssapi@KRBTEST.COM" created.
Aug 12 06:37:06 dist-test-slave-1zqn krb5kdc[2755](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786516626, etypes {rep=17 tkt=17 ses=17}, client-gssapi@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
Password for client-gssapi@KRBTEST.COM: 
WARNING: no policy specified for kudu/127.0.0.1@KRBTEST.COM; defaulting to no policy
Authenticating as principal client-gssapi/admin@KRBTEST.COM with password.
Principal "kudu/127.0.0.1@KRBTEST.COM" created.
Authenticating as principal client-gssapi/admin@KRBTEST.COM with password.
Entry for principal kudu/127.0.0.1 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskndtFVT/test-tmp/negotiation-test.0.NegotiationCombinations_TestNegotiation.TestNegotiation_17.1786516598832285-2559-0/krb5kdc/kudu_127.0.0.1.keytab.
Entry for principal kudu/127.0.0.1 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskndtFVT/test-tmp/negotiation-test.0.NegotiationCombinations_TestNegotiation.TestNegotiation_17.1786516598832285-2559-0/krb5kdc/kudu_127.0.0.1.keytab.
Aug 12 06:37:06 dist-test-slave-1zqn krb5kdc[2755](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786516626, etypes {rep=17 tkt=17 ses=17}, client-gssapi@KRBTEST.COM for kudu/127.0.0.1@KRBTEST.COM
[       OK ] NegotiationCombinations/TestNegotiation.TestNegotiation/17 (2184 ms)
[ RUN      ] NegotiationCombinations/TestNegotiation.TestNegotiation/18
Loading random data
Initializing database '/tmp/dist-test-taskndtFVT/test-tmp/negotiation-test.0.NegotiationCombinations_TestNegotiation.TestNegotiation_18.1786516598832285-2559-0/krb5kdc/principal' for realm 'KRBTEST.COM',
master key name 'K/M@KRBTEST.COM'
Aug 12 06:37:06 dist-test-slave-1zqn krb5kdc[2769](info): setting up network...
krb5kdc: setsockopt(10,IPV6_V6ONLY,1) worked
Aug 12 06:37:06 dist-test-slave-1zqn krb5kdc[2769](info): set up 2 sockets
Aug 12 06:37:06 dist-test-slave-1zqn krb5kdc[2769](info): commencing operation
krb5kdc: starting...
W20260812 06:37:08.306792  2559 mini_kdc.cc:121] Time spent starting KDC: real 2.120s	user 0.003s	sys 0.005s
WARNING: no policy specified for client-gssapi@KRBTEST.COM; defaulting to no policy
Authenticating as principal slave/admin@KRBTEST.COM with password.
Principal "client-gssapi@KRBTEST.COM" created.
Aug 12 06:37:08 dist-test-slave-1zqn krb5kdc[2769](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786516628, etypes {rep=17 tkt=17 ses=17}, client-gssapi@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
Password for client-gssapi@KRBTEST.COM: 
WARNING: no policy specified for kudu/127.0.0.1@KRBTEST.COM; defaulting to no policy
Authenticating as principal client-gssapi/admin@KRBTEST.COM with password.
Principal "kudu/127.0.0.1@KRBTEST.COM" created.
Authenticating as principal client-gssapi/admin@KRBTEST.COM with password.
Entry for principal kudu/127.0.0.1 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskndtFVT/test-tmp/negotiation-test.0.NegotiationCombinations_TestNegotiation.TestNegotiation_18.1786516598832285-2559-0/krb5kdc/kudu_127.0.0.1.keytab.
Entry for principal kudu/127.0.0.1 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskndtFVT/test-tmp/negotiation-test.0.NegotiationCombinations_TestNegotiation.TestNegotiation_18.1786516598832285-2559-0/krb5kdc/kudu_127.0.0.1.keytab.
Aug 12 06:37:08 dist-test-slave-1zqn krb5kdc[2769](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786516628, etypes {rep=17 tkt=17 ses=17}, client-gssapi@KRBTEST.COM for kudu/127.0.0.1@KRBTEST.COM
[       OK ] NegotiationCombinations/TestNegotiation.TestNegotiation/18 (2254 ms)
[ RUN      ] NegotiationCombinations/TestNegotiation.TestNegotiation/19
Loading random data
Initializing database '/tmp/dist-test-taskndtFVT/test-tmp/negotiation-test.0.NegotiationCombinations_TestNegotiation.TestNegotiation_19.1786516598832285-2559-0/krb5kdc/principal' for realm 'KRBTEST.COM',
master key name 'K/M@KRBTEST.COM'
Aug 12 06:37:08 dist-test-slave-1zqn krb5kdc[2784](info): setting up network...
krb5kdc: setsockopt(10,IPV6_V6ONLY,1) worked
Aug 12 06:37:08 dist-test-slave-1zqn krb5kdc[2784](info): set up 2 sockets
Aug 12 06:37:08 dist-test-slave-1zqn krb5kdc[2784](info): commencing operation
krb5kdc: starting...
W20260812 06:37:10.575987  2559 mini_kdc.cc:121] Time spent starting KDC: real 2.126s	user 0.006s	sys 0.001s
WARNING: no policy specified for client-gssapi@KRBTEST.COM; defaulting to no policy
Authenticating as principal slave/admin@KRBTEST.COM with password.
Principal "client-gssapi@KRBTEST.COM" created.
Aug 12 06:37:10 dist-test-slave-1zqn krb5kdc[2784](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786516630, etypes {rep=17 tkt=17 ses=17}, client-gssapi@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
Password for client-gssapi@KRBTEST.COM: 
[       OK ] NegotiationCombinations/TestNegotiation.TestNegotiation/19 (2243 ms)
[ RUN      ] NegotiationCombinations/TestNegotiation.TestNegotiation/20
Loading random data
Initializing database '/tmp/dist-test-taskndtFVT/test-tmp/negotiation-test.0.NegotiationCombinations_TestNegotiation.TestNegotiation_20.1786516598832285-2559-0/krb5kdc/principal' for realm 'KRBTEST.COM',
master key name 'K/M@KRBTEST.COM'
Aug 12 06:37:10 dist-test-slave-1zqn krb5kdc[2797](info): setting up network...
krb5kdc: setsockopt(10,IPV6_V6ONLY,1) worked
Aug 12 06:37:10 dist-test-slave-1zqn krb5kdc[2797](info): set up 2 sockets
Aug 12 06:37:10 dist-test-slave-1zqn krb5kdc[2797](info): commencing operation
krb5kdc: starting...
W20260812 06:37:12.773432  2559 mini_kdc.cc:121] Time spent starting KDC: real 2.075s	user 0.004s	sys 0.004s
WARNING: no policy specified for client-gssapi@KRBTEST.COM; defaulting to no policy
Authenticating as principal slave/admin@KRBTEST.COM with password.
Principal "client-gssapi@KRBTEST.COM" created.
Aug 12 06:37:12 dist-test-slave-1zqn krb5kdc[2797](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786516632, etypes {rep=17 tkt=17 ses=17}, client-gssapi@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
Password for client-gssapi@KRBTEST.COM: 
WARNING: no policy specified for kudu/127.0.0.1@KRBTEST.COM; defaulting to no policy
Authenticating as principal client-gssapi/admin@KRBTEST.COM with password.
Principal "kudu/127.0.0.1@KRBTEST.COM" created.
Authenticating as principal client-gssapi/admin@KRBTEST.COM with password.
Entry for principal kudu/127.0.0.1 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskndtFVT/test-tmp/negotiation-test.0.NegotiationCombinations_TestNegotiation.TestNegotiation_20.1786516598832285-2559-0/krb5kdc/kudu_127.0.0.1.keytab.
Entry for principal kudu/127.0.0.1 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskndtFVT/test-tmp/negotiation-test.0.NegotiationCombinations_TestNegotiation.TestNegotiation_20.1786516598832285-2559-0/krb5kdc/kudu_127.0.0.1.keytab.
W20260812 06:37:12.835608  2810 negotiation-test.cc:373] Failed server RPC negotiation. Server trace:
0812 06:37:12.835133 (+     0us) server_negotiation.cc:207] Beginning negotiation
0812 06:37:12.835141 (+     8us) server_negotiation.cc:400] Waiting for connection header
0812 06:37:12.835216 (+    75us) server_negotiation.cc:408] Connection header received
0812 06:37:12.835365 (+   149us) server_negotiation.cc:366] Received NEGOTIATE NegotiatePB request
0812 06:37:12.835368 (+     3us) server_negotiation.cc:462] Received NEGOTIATE request from client
0812 06:37:12.835388 (+    20us) server_negotiation.cc:395] Sending RPC error: FATAL_UNAUTHORIZED: Not authorized: client does not support required TLS encryption
Metrics: {}
W20260812 06:37:12.835705  2808 negotiation-test.cc:357] Failed client RPC negotiation. Client trace:
0812 06:37:12.835109 (+     0us) client_negotiation.cc:175] Beginning negotiation
0812 06:37:12.835267 (+   158us) client_negotiation.cc:262] Sending NEGOTIATE NegotiatePB request
0812 06:37:12.835530 (+   263us) client_negotiation.cc:293] Received error response from server: Not authorized: FATAL_UNAUTHORIZED: Not authorized: client does not support required TLS encryption
Metrics: {}
[       OK ] NegotiationCombinations/TestNegotiation.TestNegotiation/20 (2215 ms)
[ RUN      ] NegotiationCombinations/TestNegotiation.TestNegotiation/21
Loading random data
Initializing database '/tmp/dist-test-taskndtFVT/test-tmp/negotiation-test.0.NegotiationCombinations_TestNegotiation.TestNegotiation_21.1786516598832285-2559-0/krb5kdc/principal' for realm 'KRBTEST.COM',
master key name 'K/M@KRBTEST.COM'
Aug 12 06:37:12 dist-test-slave-1zqn krb5kdc[2812](info): setting up network...
krb5kdc: setsockopt(10,IPV6_V6ONLY,1) worked
Aug 12 06:37:12 dist-test-slave-1zqn krb5kdc[2812](info): set up 2 sockets
Aug 12 06:37:12 dist-test-slave-1zqn krb5kdc[2812](info): commencing operation
krb5kdc: starting...
W20260812 06:37:14.965219  2559 mini_kdc.cc:121] Time spent starting KDC: real 2.072s	user 0.003s	sys 0.003s
WARNING: no policy specified for client-gssapi@KRBTEST.COM; defaulting to no policy
Authenticating as principal slave/admin@KRBTEST.COM with password.
Principal "client-gssapi@KRBTEST.COM" created.
Aug 12 06:37:14 dist-test-slave-1zqn krb5kdc[2812](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786516634, etypes {rep=17 tkt=17 ses=17}, client-gssapi@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
Password for client-gssapi@KRBTEST.COM: 
WARNING: no policy specified for kudu/127.0.0.1@KRBTEST.COM; defaulting to no policy
Authenticating as principal client-gssapi/admin@KRBTEST.COM with password.
Principal "kudu/127.0.0.1@KRBTEST.COM" created.
Authenticating as principal client-gssapi/admin@KRBTEST.COM with password.
Entry for principal kudu/127.0.0.1 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskndtFVT/test-tmp/negotiation-test.0.NegotiationCombinations_TestNegotiation.TestNegotiation_21.1786516598832285-2559-0/krb5kdc/kudu_127.0.0.1.keytab.
Entry for principal kudu/127.0.0.1 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskndtFVT/test-tmp/negotiation-test.0.NegotiationCombinations_TestNegotiation.TestNegotiation_21.1786516598832285-2559-0/krb5kdc/kudu_127.0.0.1.keytab.
Aug 12 06:37:15 dist-test-slave-1zqn krb5kdc[2812](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786516634, etypes {rep=17 tkt=17 ses=17}, client-gssapi@KRBTEST.COM for kudu/127.0.0.1@KRBTEST.COM
[       OK ] NegotiationCombinations/TestNegotiation.TestNegotiation/21 (2199 ms)
[ RUN      ] NegotiationCombinations/TestNegotiation.TestNegotiation/22
W20260812 06:37:15.061385  2824 negotiation-test.cc:373] Failed server RPC negotiation. Server trace:
0812 06:37:15.060219 (+     0us) server_negotiation.cc:207] Beginning negotiation
0812 06:37:15.060225 (+     6us) server_negotiation.cc:400] Waiting for connection header
0812 06:37:15.060247 (+    22us) server_negotiation.cc:408] Connection header received
0812 06:37:15.060396 (+   149us) server_negotiation.cc:366] Received NEGOTIATE NegotiatePB request
0812 06:37:15.060399 (+     3us) server_negotiation.cc:462] Received NEGOTIATE request from client
0812 06:37:15.060447 (+    48us) server_negotiation.cc:378] Sending NEGOTIATE NegotiatePB response
0812 06:37:15.060529 (+    82us) server_negotiation.cc:227] Negotiated authn=SASL
0812 06:37:15.061190 (+   661us) server_negotiation.cc:366] Received SASL_INITIATE NegotiatePB request
0812 06:37:15.061224 (+    34us) server_negotiation.cc:395] Sending RPC error: FATAL_UNAUTHORIZED: Not authorized: unencrypted connections from publicly routable IPs are prohibited. See --trusted_subnets flag for more information.: 8.8.8.8:12345
Metrics: {}
W20260812 06:37:15.061509  2823 negotiation-test.cc:357] Failed client RPC negotiation. Client trace:
0812 06:37:15.060184 (+     0us) client_negotiation.cc:175] Beginning negotiation
0812 06:37:15.060300 (+   116us) client_negotiation.cc:262] Sending NEGOTIATE NegotiatePB request
0812 06:37:15.060570 (+   270us) client_negotiation.cc:281] Received NEGOTIATE NegotiatePB response
0812 06:37:15.060573 (+     3us) client_negotiation.cc:404] Received NEGOTIATE response from server
0812 06:37:15.060587 (+    14us) client_negotiation.cc:190] Negotiated authn=SASL
0812 06:37:15.061052 (+   465us) client_negotiation.cc:639] Initiating SASL PLAIN handshake
0812 06:37:15.061054 (+     2us) client_negotiation.cc:672] calling sasl_client_start()
0812 06:37:15.061065 (+    11us) client_negotiation.cc:830] callback for SASL_CB_AUTHNAME
0812 06:37:15.061087 (+    22us) client_negotiation.cc:262] Sending SASL_INITIATE NegotiatePB request
0812 06:37:15.061314 (+   227us) client_negotiation.cc:293] Received error response from server: Not authorized: FATAL_UNAUTHORIZED: Not authorized: unencrypted connections from publicly routable IPs are prohibited. See --trusted_subnets flag for more information.: 8.8.8.8:12345
Metrics: {}
[       OK ] NegotiationCombinations/TestNegotiation.TestNegotiation/22 (25 ms)
[ RUN      ] NegotiationCombinations/TestNegotiation.TestNegotiation/23
Loading random data
Initializing database '/tmp/dist-test-taskndtFVT/test-tmp/negotiation-test.0.NegotiationCombinations_TestNegotiation.TestNegotiation_23.1786516598832285-2559-0/krb5kdc/principal' for realm 'KRBTEST.COM',
master key name 'K/M@KRBTEST.COM'
Aug 12 06:37:15 dist-test-slave-1zqn krb5kdc[2826](info): setting up network...
krb5kdc: setsockopt(10,IPV6_V6ONLY,1) worked
Aug 12 06:37:15 dist-test-slave-1zqn krb5kdc[2826](info): set up 2 sockets
Aug 12 06:37:15 dist-test-slave-1zqn krb5kdc[2826](info): commencing operation
krb5kdc: starting...
W20260812 06:37:17.193698  2559 mini_kdc.cc:121] Time spent starting KDC: real 2.108s	user 0.003s	sys 0.004s
WARNING: no policy specified for client-gssapi@KRBTEST.COM; defaulting to no policy
Authenticating as principal slave/admin@KRBTEST.COM with password.
Principal "client-gssapi@KRBTEST.COM" created.
Aug 12 06:37:17 dist-test-slave-1zqn krb5kdc[2826](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786516637, etypes {rep=17 tkt=17 ses=17}, client-gssapi@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
Password for client-gssapi@KRBTEST.COM: 
WARNING: no policy specified for kudu/127.0.0.1@KRBTEST.COM; defaulting to no policy
Authenticating as principal client-gssapi/admin@KRBTEST.COM with password.
Principal "kudu/127.0.0.1@KRBTEST.COM" created.
Authenticating as principal client-gssapi/admin@KRBTEST.COM with password.
Entry for principal kudu/127.0.0.1 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskndtFVT/test-tmp/negotiation-test.0.NegotiationCombinations_TestNegotiation.TestNegotiation_23.1786516598832285-2559-0/krb5kdc/kudu_127.0.0.1.keytab.
Entry for principal kudu/127.0.0.1 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskndtFVT/test-tmp/negotiation-test.0.NegotiationCombinations_TestNegotiation.TestNegotiation_23.1786516598832285-2559-0/krb5kdc/kudu_127.0.0.1.keytab.
Aug 12 06:37:17 dist-test-slave-1zqn krb5kdc[2826](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786516637, etypes {rep=17 tkt=17 ses=17}, client-gssapi@KRBTEST.COM for kudu/127.0.0.1@KRBTEST.COM
[       OK ] NegotiationCombinations/TestNegotiation.TestNegotiation/23 (2248 ms)
[ RUN      ] NegotiationCombinations/TestNegotiation.TestNegotiation/24
[       OK ] NegotiationCombinations/TestNegotiation.TestNegotiation/24 (18 ms)
[ RUN      ] NegotiationCombinations/TestNegotiation.TestNegotiation/25
W20260812 06:37:17.380882  2842 client_negotiation.cc:385] the client has a JWT but it isn't advertising its JWT-based authn capability since it doesn't trust the certificate of the server at 127.0.0.1:44883
W20260812 06:37:17.381078  2842 negotiation-test.cc:357] Failed client RPC negotiation. Client trace:
0812 06:37:17.380670 (+     0us) client_negotiation.cc:175] Beginning negotiation
Metrics: {}
W20260812 06:37:17.381172  2843 negotiation-test.cc:373] Failed server RPC negotiation. Server trace:
0812 06:37:17.380670 (+     0us) server_negotiation.cc:207] Beginning negotiation
0812 06:37:17.380680 (+    10us) server_negotiation.cc:400] Waiting for connection header
0812 06:37:17.380779 (+    99us) server_negotiation.cc:408] Connection header received
Metrics: {}
[       OK ] NegotiationCombinations/TestNegotiation.TestNegotiation/25 (51 ms)
[ RUN      ] NegotiationCombinations/TestNegotiation.TestNegotiation/26
Loading random data
Initializing database '/tmp/dist-test-taskndtFVT/test-tmp/negotiation-test.0.NegotiationCombinations_TestNegotiation.TestNegotiation_26.1786516598832285-2559-0/krb5kdc/principal' for realm 'KRBTEST.COM',
master key name 'K/M@KRBTEST.COM'
Aug 12 06:37:17 dist-test-slave-1zqn krb5kdc[2845](info): setting up network...
krb5kdc: setsockopt(10,IPV6_V6ONLY,1) worked
Aug 12 06:37:17 dist-test-slave-1zqn krb5kdc[2845](info): set up 2 sockets
Aug 12 06:37:17 dist-test-slave-1zqn krb5kdc[2845](info): commencing operation
krb5kdc: starting...
W20260812 06:37:19.529450  2559 mini_kdc.cc:121] Time spent starting KDC: real 2.100s	user 0.003s	sys 0.005s
WARNING: no policy specified for client-gssapi@KRBTEST.COM; defaulting to no policy
Authenticating as principal slave/admin@KRBTEST.COM with password.
Principal "client-gssapi@KRBTEST.COM" created.
Aug 12 06:37:19 dist-test-slave-1zqn krb5kdc[2845](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786516639, etypes {rep=17 tkt=17 ses=17}, client-gssapi@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
Password for client-gssapi@KRBTEST.COM: 
[       OK ] NegotiationCombinations/TestNegotiation.TestNegotiation/26 (2189 ms)
[ RUN      ] NegotiationCombinations/TestNegotiation.TestNegotiation/27
W20260812 06:37:19.602737  2862 negotiation-test.cc:373] Failed server RPC negotiation. Server trace:
0812 06:37:19.602318 (+     0us) server_negotiation.cc:207] Beginning negotiation
0812 06:37:19.602325 (+     7us) server_negotiation.cc:400] Waiting for connection header
0812 06:37:19.602385 (+    60us) server_negotiation.cc:408] Connection header received
0812 06:37:19.602513 (+   128us) server_negotiation.cc:366] Received NEGOTIATE NegotiatePB request
0812 06:37:19.602516 (+     3us) server_negotiation.cc:462] Received NEGOTIATE request from client
0812 06:37:19.602532 (+    16us) server_negotiation.cc:395] Sending RPC error: FATAL_UNAUTHORIZED: Not authorized: client does not support required TLS encryption
Metrics: {}
W20260812 06:37:19.602751  2861 negotiation-test.cc:357] Failed client RPC negotiation. Client trace:
0812 06:37:19.602287 (+     0us) client_negotiation.cc:175] Beginning negotiation
0812 06:37:19.602416 (+   129us) client_negotiation.cc:262] Sending NEGOTIATE NegotiatePB request
0812 06:37:19.602646 (+   230us) client_negotiation.cc:293] Received error response from server: Not authorized: FATAL_UNAUTHORIZED: Not authorized: client does not support required TLS encryption
Metrics: {}
[       OK ] NegotiationCombinations/TestNegotiation.TestNegotiation/27 (32 ms)
[ RUN      ] NegotiationCombinations/TestNegotiation.TestNegotiation/28
Loading random data
Initializing database '/tmp/dist-test-taskndtFVT/test-tmp/negotiation-test.0.NegotiationCombinations_TestNegotiation.TestNegotiation_28.1786516598832285-2559-0/krb5kdc/principal' for realm 'KRBTEST.COM',
master key name 'K/M@KRBTEST.COM'
Aug 12 06:37:19 dist-test-slave-1zqn krb5kdc[2864](info): setting up network...
krb5kdc: setsockopt(10,IPV6_V6ONLY,1) worked
Aug 12 06:37:19 dist-test-slave-1zqn krb5kdc[2864](info): set up 2 sockets
Aug 12 06:37:19 dist-test-slave-1zqn krb5kdc[2864](info): commencing operation
krb5kdc: starting...
W20260812 06:37:21.739228  2559 mini_kdc.cc:121] Time spent starting KDC: real 2.107s	user 0.001s	sys 0.004s
WARNING: no policy specified for client-gssapi@KRBTEST.COM; defaulting to no policy
Authenticating as principal slave/admin@KRBTEST.COM with password.
Principal "client-gssapi@KRBTEST.COM" created.
Aug 12 06:37:21 dist-test-slave-1zqn krb5kdc[2864](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786516641, etypes {rep=17 tkt=17 ses=17}, client-gssapi@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
Password for client-gssapi@KRBTEST.COM: 
W20260812 06:37:21.774322  2872 negotiation-test.cc:357] Failed client RPC negotiation. Client trace:
0812 06:37:21.773739 (+     0us) client_negotiation.cc:175] Beginning negotiation
0812 06:37:21.773900 (+   161us) client_negotiation.cc:262] Sending NEGOTIATE NegotiatePB request
0812 06:37:21.774191 (+   291us) client_negotiation.cc:281] Received NEGOTIATE NegotiatePB response
0812 06:37:21.774193 (+     2us) client_negotiation.cc:404] Received NEGOTIATE response from server
Metrics: {}
W20260812 06:37:21.774403  2873 negotiation-test.cc:373] Failed server RPC negotiation. Server trace:
0812 06:37:21.773758 (+     0us) server_negotiation.cc:207] Beginning negotiation
0812 06:37:21.773766 (+     8us) server_negotiation.cc:400] Waiting for connection header
0812 06:37:21.773834 (+    68us) server_negotiation.cc:408] Connection header received
0812 06:37:21.774026 (+   192us) server_negotiation.cc:366] Received NEGOTIATE NegotiatePB request
0812 06:37:21.774029 (+     3us) server_negotiation.cc:462] Received NEGOTIATE request from client
0812 06:37:21.774069 (+    40us) server_negotiation.cc:378] Sending NEGOTIATE NegotiatePB response
0812 06:37:21.774162 (+    93us) server_negotiation.cc:227] Negotiated authn=SASL
Metrics: {}
[       OK ] NegotiationCombinations/TestNegotiation.TestNegotiation/28 (2172 ms)
[ RUN      ] NegotiationCombinations/TestNegotiation.TestNegotiation/29
[       OK ] NegotiationCombinations/TestNegotiation.TestNegotiation/29 (58 ms)
[ RUN      ] NegotiationCombinations/TestNegotiation.TestNegotiation/30
Loading random data
Initializing database '/tmp/dist-test-taskndtFVT/test-tmp/negotiation-test.0.NegotiationCombinations_TestNegotiation.TestNegotiation_30.1786516598832285-2559-0/krb5kdc/principal' for realm 'KRBTEST.COM',
master key name 'K/M@KRBTEST.COM'
Aug 12 06:37:21 dist-test-slave-1zqn krb5kdc[2877](info): setting up network...
krb5kdc: setsockopt(10,IPV6_V6ONLY,1) worked
Aug 12 06:37:21 dist-test-slave-1zqn krb5kdc[2877](info): set up 2 sockets
Aug 12 06:37:21 dist-test-slave-1zqn krb5kdc[2877](info): commencing operation
krb5kdc: starting...
W20260812 06:37:23.943373  2559 mini_kdc.cc:121] Time spent starting KDC: real 2.080s	user 0.001s	sys 0.008s
WARNING: no policy specified for client-gssapi@KRBTEST.COM; defaulting to no policy
Authenticating as principal slave/admin@KRBTEST.COM with password.
Principal "client-gssapi@KRBTEST.COM" created.
Aug 12 06:37:23 dist-test-slave-1zqn krb5kdc[2877](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786516643, etypes {rep=17 tkt=17 ses=17}, client-gssapi@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
Password for client-gssapi@KRBTEST.COM: 
[       OK ] NegotiationCombinations/TestNegotiation.TestNegotiation/30 (2149 ms)
[ RUN      ] NegotiationCombinations/TestNegotiation.TestNegotiation/31
Loading random data
Initializing database '/tmp/dist-test-taskndtFVT/test-tmp/negotiation-test.0.NegotiationCombinations_TestNegotiation.TestNegotiation_31.1786516598832285-2559-0/krb5kdc/principal' for realm 'KRBTEST.COM',
master key name 'K/M@KRBTEST.COM'
Aug 12 06:37:24 dist-test-slave-1zqn krb5kdc[2888](info): setting up network...
krb5kdc: setsockopt(10,IPV6_V6ONLY,1) worked
Aug 12 06:37:24 dist-test-slave-1zqn krb5kdc[2888](info): set up 2 sockets
Aug 12 06:37:24 dist-test-slave-1zqn krb5kdc[2888](info): commencing operation
krb5kdc: starting...
W20260812 06:37:26.088176  2559 mini_kdc.cc:121] Time spent starting KDC: real 2.073s	user 0.000s	sys 0.009s
WARNING: no policy specified for client-gssapi@KRBTEST.COM; defaulting to no policy
Authenticating as principal slave/admin@KRBTEST.COM with password.
Principal "client-gssapi@KRBTEST.COM" created.
Aug 12 06:37:26 dist-test-slave-1zqn krb5kdc[2888](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786516646, etypes {rep=17 tkt=17 ses=17}, client-gssapi@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
Password for client-gssapi@KRBTEST.COM: 
WARNING: no policy specified for kudu/127.0.0.1@KRBTEST.COM; defaulting to no policy
Authenticating as principal client-gssapi/admin@KRBTEST.COM with password.
Principal "kudu/127.0.0.1@KRBTEST.COM" created.
Authenticating as principal client-gssapi/admin@KRBTEST.COM with password.
Entry for principal kudu/127.0.0.1 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskndtFVT/test-tmp/negotiation-test.0.NegotiationCombinations_TestNegotiation.TestNegotiation_31.1786516598832285-2559-0/krb5kdc/kudu_127.0.0.1.keytab.
Entry for principal kudu/127.0.0.1 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskndtFVT/test-tmp/negotiation-test.0.NegotiationCombinations_TestNegotiation.TestNegotiation_31.1786516598832285-2559-0/krb5kdc/kudu_127.0.0.1.keytab.
Aug 12 06:37:26 dist-test-slave-1zqn krb5kdc[2888](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786516646, etypes {rep=17 tkt=17 ses=17}, client-gssapi@KRBTEST.COM for kudu/127.0.0.1@KRBTEST.COM
[       OK ] NegotiationCombinations/TestNegotiation.TestNegotiation/31 (2177 ms)
[ RUN      ] NegotiationCombinations/TestNegotiation.TestNegotiation/32
Loading random data
Initializing database '/tmp/dist-test-taskndtFVT/test-tmp/negotiation-test.0.NegotiationCombinations_TestNegotiation.TestNegotiation_32.1786516598832285-2559-0/krb5kdc/principal' for realm 'KRBTEST.COM',
master key name 'K/M@KRBTEST.COM'
Aug 12 06:37:26 dist-test-slave-1zqn krb5kdc[2901](info): setting up network...
krb5kdc: setsockopt(10,IPV6_V6ONLY,1) worked
Aug 12 06:37:26 dist-test-slave-1zqn krb5kdc[2901](info): set up 2 sockets
Aug 12 06:37:26 dist-test-slave-1zqn krb5kdc[2901](info): commencing operation
krb5kdc: starting...
W20260812 06:37:28.327714  2559 mini_kdc.cc:121] Time spent starting KDC: real 2.146s	user 0.002s	sys 0.004s
WARNING: no policy specified for client-gssapi@KRBTEST.COM; defaulting to no policy
Authenticating as principal slave/admin@KRBTEST.COM with password.
Principal "client-gssapi@KRBTEST.COM" created.
Aug 12 06:37:28 dist-test-slave-1zqn krb5kdc[2901](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786516648, etypes {rep=17 tkt=17 ses=17}, client-gssapi@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
Password for client-gssapi@KRBTEST.COM: 
WARNING: no policy specified for kudu/127.0.0.1@KRBTEST.COM; defaulting to no policy
Authenticating as principal client-gssapi/admin@KRBTEST.COM with password.
Principal "kudu/127.0.0.1@KRBTEST.COM" created.
Authenticating as principal client-gssapi/admin@KRBTEST.COM with password.
Entry for principal kudu/127.0.0.1 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskndtFVT/test-tmp/negotiation-test.0.NegotiationCombinations_TestNegotiation.TestNegotiation_32.1786516598832285-2559-0/krb5kdc/kudu_127.0.0.1.keytab.
Entry for principal kudu/127.0.0.1 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskndtFVT/test-tmp/negotiation-test.0.NegotiationCombinations_TestNegotiation.TestNegotiation_32.1786516598832285-2559-0/krb5kdc/kudu_127.0.0.1.keytab.
Aug 12 06:37:28 dist-test-slave-1zqn krb5kdc[2901](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786516648, etypes {rep=17 tkt=17 ses=17}, client-gssapi@KRBTEST.COM for kudu/127.0.0.1@KRBTEST.COM
[       OK ] NegotiationCombinations/TestNegotiation.TestNegotiation/32 (2281 ms)
[----------] 33 tests from NegotiationCombinations/TestNegotiation (45038 ms total)

[----------] Global test environment tear-down
[==========] 42 tests from 3 test suites ran. (49608 ms total)
[  PASSED  ] 42 tests.
