Hi Kai, Your changes fixed the error message I was seeing. However, I now see another problem when I run a few GSS client tests in a row:
>>> EType: sun.security.krb5.internal.crypto.Aes128CtsHmacSha1EType >>> KrbAsReq creating message >>> KrbKdcReq send: kdc=localhost UDP:42665, timeout=30000, number of retries =3, #bytes=245 >>> KDCCommunication: kdc=localhost UDP:42665, timeout=30000,Attempt =1, #bytes=245 SocketTimeOutException with attempt: 1 >>> KDCCommunication: kdc=localhost UDP:42665, timeout=30000,Attempt =2, #bytes=245 >>> KrbKdcReq send: error trying localhost:42665 java.net.PortUnreachableException: ICMP Port Unreachable Do you want me to create a JIRA + attach a test-case? Colm. On Sat, May 6, 2017 at 2:01 AM, Zheng, Kai <kai.zh...@intel.com> wrote: > I haven't repeated the issue but revisited the codes again and made > improvements. Would you check it out? Thanks! > > Sent from iPhone > > > 在 2017年5月6日，上午6:28，Zheng, Kai <kai.zh...@intel.com> 写道： > > > > Thanks colm for the clarification and it sounds an issue we need to > address. I will investigate it soon. > > > > Sent from iPhone > > > >> 在 2017年5月6日，上午2:14，Colm O hEigeartaigh <cohei...@apache.org> 写道： > >> > >> Hi Kai, > >> > >> If I enable UDP with the default Transport, I can get a ticket fine > using > >> kinit. However then the following error pops up in the window I'm > running > >> Kerby in (as a test): > >> > >> Exception in thread "Thread-1" java.lang.RuntimeException: Error occured > >> while checking udp connections > >> at > >> org.apache.kerby.kerberos.kerb.transport.KdcNetwork.run( > KdcNetwork.java:105) > >> at > >> org.apache.kerby.kerberos.kerb.transport.KdcNetwork. > access$000(KdcNetwork.java:39) > >> at > >> org.apache.kerby.kerberos.kerb.transport.KdcNetwork$1. > run(KdcNetwork.java:75) > >> at java.lang.Thread.run(Thread.java:748) > >> Caused by: java.nio.channels.ClosedChannelException > >> at > >> sun.nio.ch.DatagramChannelImpl.ensureOpen(DatagramChannelImpl.java:320) > >> at sun.nio.ch.DatagramChannelImpl.receive( > DatagramChannelImpl.java:331) > >> at > >> org.apache.kerby.kerberos.kerb.transport.KdcNetwork. > checkUdpMessage(KdcNetwork.java:132) > >> at > >> org.apache.kerby.kerberos.kerb.transport.KdcNetwork.run( > KdcNetwork.java:101) > >> > >> Colm. > >> > >> > >>> On Fri, May 5, 2017 at 5:56 PM, Zheng, Kai <kai.zh...@intel.com> > wrote: > >>> > >>> Colm, did you see udp problem now instead? I'm a little confused. Udp > is > >>> sure supported but may not be enabled by default, which should be okay, > >>> imo. Thanks. > >>> > >>> Sent from iPhone > >>> > >>>> 在 2017年5月6日，上午12:02，Colm O hEigeartaigh <cohei...@apache.org> 写道： > >>>> > >>>> That's probably it. Why does the default transport not support UDP in > >>> Kerby? > >>>> > >>>> Colm. > >>>> > >>>>> On Fri, May 5, 2017 at 4:54 PM, Li, Jiajia <jiajia...@intel.com> > wrote: > >>>>> > >>>>> Are you sure add kdc_allow_udp = false in kdc.conf? > >>>>> > >>>>> Thanks > >>>>> Jiajia > >>>>> > >>>>> -----Original Message----- > >>>>> From: Colm O hEigeartaigh [mailto:cohei...@apache.org] > >>>>> Sent: Friday, May 5, 2017 11:41 PM > >>>>> To: Li, Jiajia <jiajia...@intel.com> > >>>>> Cc: kerby@directory.apache.org; Zheng, Kai <kai.zh...@intel.com>; > >>> mailto: > >>>>> m.c.delig...@xs4all.nl <m.c.delig...@xs4all.nl> > >>>>> Subject: Re: MIT Kerberos compatibility > >>>>> > >>>>> Sorry, it was my error, UDP was actually enabled there. But why am I > >>> still > >>>>> seeing that error message? > >>>>> > >>>>> Colm. > >>>>> > >>>>>> On Fri, May 5, 2017 at 4:39 PM, Li, Jiajia <jiajia...@intel.com> > >>> wrote: > >>>>>> > >>>>>> Hi Colm, > >>>>>> I also test the Kerby KDC with kerby kint and MIT kinit, and only > >>>>>> listen the tcp port(disable udp), both got ticket successfully. But > I > >>>>>> don't get the error message. Both krb.conf and kdc.conf should set > udp > >>>>>> to be false, udp is enabled in default. > >>>>>> > >>>>>> Thanks > >>>>>> Jiajia > >>>>>> > >>>>>> -----Original Message----- > >>>>>> From: Colm O hEigeartaigh [mailto:cohei...@apache.org] > >>>>>> Sent: Friday, May 5, 2017 11:34 PM > >>>>>> To: kerby@directory.apache.org > >>>>>> Cc: Zheng, Kai <kai.zh...@intel.com>; mailto:m.c.delig...@xs4all.nl > < > >>>>>> m.c.delig...@xs4all.nl> > >>>>>> Subject: Re: MIT Kerberos compatibility > >>>>>> > >>>>>> Hi Jiajia, > >>>>>> > >>>>>> If UDP is disabled and we don't use Netty, I can get a token > >>>>>> successfully via kinit. However I then see an error message in the > >>> Kerby > >>>>> console: > >>>>>> > >>>>>> Exception in thread "Thread-1" java.lang.RuntimeException: Error > >>>>>> occured while checking udp connections > >>>>>> at > >>>>>> org.apache.kerby.kerberos.kerb.transport.KdcNetwork.run( > >>>>>> KdcNetwork.java:105) > >>>>>> at > >>>>>> org.apache.kerby.kerberos.kerb.transport.KdcNetwork. > >>>>>> access$000(KdcNetwork.java:39) > >>>>>> at > >>>>>> org.apache.kerby.kerberos.kerb.transport.KdcNetwork$1. > >>>>>> run(KdcNetwork.java:75) > >>>>>> at java.lang.Thread.run(Thread.java:748) > >>>>>> Caused by: java.nio.channels.ClosedChannelException > >>>>>> at > >>>>>> sun.nio.ch.DatagramChannelImpl.ensureOpen( > >>> DatagramChannelImpl.java:320) > >>>>>> at sun.nio.ch.DatagramChannelImpl.receive( > >>>>>> DatagramChannelImpl.java:331) > >>>>>> at > >>>>>> org.apache.kerby.kerberos.kerb.transport.KdcNetwork. > >>>>>> checkUdpMessage(KdcNetwork.java:132) > >>>>>> at > >>>>>> org.apache.kerby.kerberos.kerb.transport.KdcNetwork.run( > >>>>>> KdcNetwork.java:101) > >>>>>> > >>>>>> I'm not sure why we are seeing UDP errors when it's disabled? > >>>>>> > >>>>>> Colm. > >>>>>> > >>>>>>> On Fri, May 5, 2017 at 3:57 PM, Li, Jiajia <jiajia...@intel.com> > >>> wrote: > >>>>>>> > >>>>>>> Hi Colm, > >>>>>>> The shell client can't connect to kdc if the UDP is disabled. > >>>>>>> We don't use Netty in default. > >>>>>>> What's your test-cases? The same as the Marc's? > >>>>>>> > >>>>>>> Thanks > >>>>>>> Jiajia > >>>>>>> > >>>>>>> -----Original Message----- > >>>>>>> From: Colm O hEigeartaigh [mailto:cohei...@apache.org] > >>>>>>> Sent: Friday, May 5, 2017 10:09 PM > >>>>>>> To: kerby@directory.apache.org > >>>>>>> Cc: Zheng, Kai <kai.zh...@intel.com>; mailto: > m.c.delig...@xs4all.nl > >>>>>>> < m.c.delig...@xs4all.nl> > >>>>>>> Subject: Re: MIT Kerberos compatibility > >>>>>>> > >>>>>>> Hi Jiajia, > >>>>>>> > >>>>>>> What are the issues if UDP is disabled and we don't use Netty? I > >>>>>>> tried doing this with my own test-cases and it didn't work, so it > >>>>>>> would be good to get this fixed soon. > >>>>>>> > >>>>>>> Colm. > >>>>>>> > >>>>>>> On Fri, May 5, 2017 at 2:46 PM, Li, Jiajia <jiajia...@intel.com> > >>>>> wrote: > >>>>>>> > >>>>>>>> Hi Marc, > >>>>>>>>>>> - your KRB5 tracing looks quite different. What OS and > >>>>>>>>>>> mit-kerberos > >>>>>>>> version did you use? > >>>>>>>> I use mac os and the python version is 2.7.10 > >>>>>>>> > >>>>>>>>>>> - your KRB5 tracing shows UDP comms between kerberos client and > >>>>>>>>>>> KDC, > >>>>>>>> despite the allowUDP = false setting > >>>>>>>>>>> in my test. I did this setting because I get different > >>>>>>>>>>> problems > >>>>>>>> without it, see the additional logs below. So, > >>>>>>>>>>> we must also be aware of networking problems at my side. > >>>>>>>> I enable the UDP and use netty network, there are some issues if > >>>>>>>> UDP disabled, you can create a JIRA for this and we can fix this > >>>>>>>> issue in the next release version. > >>>>>>>> > >>>>>>>> The changes in my side as following: > >>>>>>>> > >>>>>>>> protected boolean allowUdp() { > >>>>>>>> return true; > >>>>>>>> } > >>>>>>>> @Override > >>>>>>>> protected void prepareKdc() throws KrbException { > >>>>>>>> getKdcServer().setInnerKdcImpl( > >>>>>>>> new NettyKdcServerImpl(getKdcServer().getKdcSetting())); > >>>>>>>> super.prepareKdc(); > >>>>>>>> } > >>>>>>>> > >>>>>>>> Here is log of MitIssueTest: > >>>>>>>> [INFO] Running org.apache.kerby.kerberos.kerb.server.MitIssueTest > >>>>>>>> [nioEventLoopGroup-2-1] INFO > >>>>>>>> io.netty.handler.logging.LoggingHandler > >>>>>>>> - > >>>>>>>> [id: 0x2634fe6b] REGISTERED > >>>>>>>> [nioEventLoopGroup-2-1] INFO > >>>>>>>> io.netty.handler.logging.LoggingHandler > >>>>>>>> - > >>>>>>>> [id: 0x2634fe6b] BIND(0.0.0.0/0.0.0.0:53957) > >>>>>>>> [nioEventLoopGroup-2-1] INFO > >>>>>>>> io.netty.handler.logging.LoggingHandler - > >>>>>>>> [id: 0x2634fe6b, /0:0:0:0:0:0:0:0:53957] ACTIVE [main] INFO > >>>>>>>> org.apache.kerby.kerberos.kdc.impl.NettyKdcServerImpl - Netty kdc > >>>>>>>> server started. > >>>>>>>> [nioEventLoopGroup-2-1] INFO > >>>>>>>> io.netty.handler.logging.LoggingHandler > >>>>>>>> - > >>>>>>>> [id: 0x2634fe6b, /0:0:0:0:0:0:0:0:53957] RECEIVED: [id: > >>>>>>>> 0xdac7228b, / > >>>>>>>> 127.0.0.1:53961 => /127.0.0.1:53957] > >>>>>>>> [defaultEventExecutorGroup-4-1] INFO > >>>>>>>> org.apache.kerby.kerberos.kerb.server.request.AsRequest > >>>>>>>> - AS_REQ ISSUE: authtime 1493991123792,dran...@test.com for > >>>>>>>> krbtgt/ test....@test.com [main] INFO > >>>>>>>> org.apache.kerby.kerberos.kerb.client.impl. > DefaultInternalKrbClien > >>>>>>>> t > >>>>>>>> - Send to kdc success. > >>>>>>>> [main] INFO org.apache.kerby.kerberos.kerb.client.KrbClientBase - > >>>>>>>> Storing the tgt to the credential cache file. > >>>>>>>> [nioEventLoopGroup-5-1] INFO > >>>>>>>> org.apache.kerby.kerberos.kerb.server.request.KdcRequest > >>>>>>>> - The preauth data is empty. > >>>>>>>> [nioEventLoopGroup-5-1] INFO > >>>>>>>> org.apache.kerby.kerberos.kerb.server.KdcHandler > >>>>>>>> - KRB error occurred while processing request:Additional > >>>>>>>> pre-authentication required [nioEventLoopGroup-5-1] INFO > >>>>>>>> org.apache.kerby.kerberos.kerb.server.request.AsRequest > >>>>>>>> - AS_REQ ISSUE: authtime > >>>>>>>> 1493991123859,test-service/localh...@test.com > >>>>>>>> for krbtgt/test....@test.com > >>>>>>>> [nioEventLoopGroup-5-1] INFO > >>>>>>>> org.apache.kerby.kerberos.kerb.server.request.TgsRequest > >>>>>>>> - TGS_REQ ISSUE: authtime 1493991142850,drankye for test-service/ > >>>>>>>> localh...@test.com > >>>>>>>> > >>>>>>>> Thanks > >>>>>>>> Jiajia > >>>>>>>> > >>>>>>>> -----Original Message----- > >>>>>>>> From: Zheng, Kai > >>>>>>>> Sent: Friday, May 5, 2017 7:46 PM > >>>>>>>> To: kerby@directory.apache.org; Li, Jiajia <jiajia...@intel.com> > >>>>>>>> Subject: RE: MIT Kerberos compatibility > >>>>>>>> > >>>>>>>> Hi Marc, > >>>>>>>> > >>>>>>>> Looks like this is quite environment related, could you fire an > >>>>>>>> issue for this? I would suggest we target it to 1.1.0, which can > >>>>>>>> be done in > >>>>>>> June. > >>>>>>>> > >>>>>>>> Regards, > >>>>>>>> Kai > >>>>>>>> > >>>>>>>> -----Original Message----- > >>>>>>>> From: Marc de Lignie [mailto:m.c.delig...@xs4all.nl] > >>>>>>>> Sent: Friday, May 05, 2017 4:44 PM > >>>>>>>> To: Li, Jiajia <jiajia...@intel.com> > >>>>>>>> Cc: kerby@directory.apache.org > >>>>>>>> Subject: Re: MIT Kerberos compatibility > >>>>>>>> > >>>>>>>> Hi Jiajia, > >>>>>>>> > >>>>>>>> Great to read that you made progress on this issue and to see a > >>>>>>>> working config at your side. Below, I list my progress below (with > >>>>>>>> trunk merged into my MitIssue branch), but I am afraid we are not > >>>>>>>> done > >>>>>>> yet. > >>>>>>>> > >>>>>>>> Things that stand out: > >>>>>>>> > >>>>>>>> - the kdc decoding error is solved, relative to the logs without > >>>>>>>> your patch > >>>>>>>> > >>>>>>>> - your KRB5 tracing looks quite different. What OS and > >>>>>>>> mit-kerberos version did you use? > >>>>>>>> > >>>>>>>> - your KRB5 tracing shows UDP comms between kerberos client and > >>>>>>>> KDC, despite the allowUDP = false setting in my test. I did this > >>>>>>>> setting because I get different problems without it, see the > >>>>>>>> additional logs below. So, we must also be aware of networking > >>>>> problems at my side. > >>>>>>>> > >>>>>>>> - the "Response was not from master KDC" msg is not relevant; it > >>>>>>>> disappears if you manually add master_kdc to the realms section of > >>>>>>>> the krb5.conf > >>>>>>>> > >>>>>>>> I have no idea how to proceed from here, so that is why I just > >>>>>>>> document the status at my side and ask about your - apparently > >>>>>>>> working - > >>>>>>> config. > >>>>>>>> > >>>>>>>> Cheers, Marc > >>>>>>>> > >>>>>>>> > >>>>>>>> KDC logging with allowUDP = false: > >>>>>>>> > >>>>>>>> [INFO] Running org.apache.kerby.kerberos.kerb.server.MitIssueTest > >>>>>>>> [pool-1-thread-1] INFO > >>>>>>>> org.apache.kerby.kerberos.kerb.server.request.AsRequest - AS_REQ > >>>>>> ISSUE: > >>>>>>>> authtime 1493970789075,dran...@test.com for > >>>>>>>> krbtgt/test....@test.com [main] INFO > >>>>>>>> org.apache.kerby.kerberos.kerb.client.impl. > DefaultInternalKrbClien > >>>>>>>> t > >>>>>>>> - Send to kdc success. > >>>>>>>> [main] INFO org.apache.kerby.kerberos.kerb.client.KrbClientBase - > >>>>>>>> Storing the tgt to the credential cache file. > >>>>>>>> [pool-1-thread-1] INFO > >>>>>>>> org.apache.kerby.kerberos.kerb.server.request.KdcRequest - The > >>>>>>>> preauth data is empty. > >>>>>>>> [pool-1-thread-1] INFO > >>>>>>>> org.apache.kerby.kerberos.kerb.server.KdcHandler > >>>>>>>> - KRB error occurred while processing request:Additional > >>>>>>>> pre-authentication required [pool-1-thread-1] INFO > >>>>>>>> org.apache.kerby.kerberos.kerb.server.request.AsRequest - AS_REQ > >>>>>> ISSUE: > >>>>>>>> authtime 1493970789108,test-service/localh...@test.com for > krbtgt/ > >>>>>>>> test....@test.com [pool-1-thread-1] INFO > >>>>>>>> org.apache.kerby.kerberos.kerb.server.request.KdcRequest > >>>>>>>> - Found fast padata and starting to process it. > >>>>>>>> [pool-1-thread-1] INFO > >>>>>>>> org.apache.kerby.kerberos.kerb.server.request.KdcRequest - Found > >>>>>>>> fast padata and starting to process it. > >>>>>>>> > >>>>>>>> Python script KRB5 tracing (MIT Kerberos 1.13.2 of Ubuntu Xenial) > >>>>>>>> with allowUDP = false: > >>>>>>>> > >>>>>>>> $ . > >>>>>>>> kerby-kerb/kerb-kdc-test/src/test/java/org/apache/kerby/ > >>>>>>>> kerberos/kerb/server/MitIssueTest.sh > >>>>>>>> [25281] 1493970797.298753: Retrieving dran...@test.com from > >>>>>>>> FILE:/etc/krb5/user/1000/client.keytab (vno 0, enctype 0) with > >>>>> result: > >>>>>>>> 2/Key table file '/etc/krb5/user/1000/client.keytab' not found > >>>>>>>> [25281] > >>>>>>>> 1493970797.298952: Retrieving dran...@test.com from > >>>>>>>> FILE:/etc/krb5/user/1000/client.keytab (vno 0, enctype 0) with > >>>>> result: > >>>>>>>> 2/Key table file '/etc/krb5/user/1000/client.keytab' not found > >>>>>>>> [25281] > >>>>>>>> 1493970797.299106: Retrieving dran...@test.com from > >>>>>>>> FILE:/etc/krb5/user/1000/client.keytab (vno 0, enctype 0) with > >>>>> result: > >>>>>>>> 2/Key table file '/etc/krb5/user/1000/client.keytab' not found > >>>>>>>> [25281] > >>>>>>>> 1493970797.299213: Retrieving dran...@test.com from > >>>>>>>> FILE:/etc/krb5/user/1000/client.keytab (vno 0, enctype 0) with > >>>>> result: > >>>>>>>> 2/Key table file '/etc/krb5/user/1000/client.keytab' not found > >>>>>>>> [25281] > >>>>>>>> 1493970797.299323: Retrieving dran...@test.com from > >>>>>>>> FILE:/etc/krb5/user/1000/client.keytab (vno 0, enctype 0) with > >>>>> result: > >>>>>>>> 2/Key table file '/etc/krb5/user/1000/client.keytab' not found > >>>>>>>> [25281] > >>>>>>>> 1493970797.299436: Retrieving dran...@test.com from > >>>>>>>> FILE:/etc/krb5/user/1000/client.keytab (vno 0, enctype 0) with > >>>>> result: > >>>>>>>> 2/Key table file '/etc/krb5/user/1000/client.keytab' not found > >>>>>>>> [25281] > >>>>>>>> 1493970797.299545: Retrieving dran...@test.com from > >>>>>>>> FILE:/etc/krb5/user/1000/client.keytab (vno 0, enctype 0) with > >>>>> result: > >>>>>>>> 2/Key table file '/etc/krb5/user/1000/client.keytab' not found > >>>>>>>> [25281] > >>>>>>>> 1493970797.299654: Retrieving dran...@test.com from > >>>>>>>> FILE:/etc/krb5/user/1000/client.keytab (vno 0, enctype 0) with > >>>>> result: > >>>>>>>> 2/Key table file '/etc/krb5/user/1000/client.keytab' not found > >>>>>>>> kerberos.authGSSClientInit successful [25281] 1493970797.299922: > >>>>>>>> Getting credentials dran...@test.com -> test-service/localhost@ > >>>>>>>> using ccache FILE:kerby-kerb/kerb-kdc-test/target/tmp/test-tkt.cc > >>>>>>>> [25281] 1493970797.299945: Retrieving dran...@test.com -> > >>>>>>>> test-service/localhost@ from > >>>>>>>> FILE:kerby-kerb/kerb-kdc-test/target/tmp/test-tkt.cc > >>>>>>>> with result: > >>>>>>>> -1765328243/Matching credential not found [25281] > 1493970797.299959: > >>>>>>>> Retrying dran...@test.com -> test-service/localh...@test.com with > >>>>>>> result: > >>>>>>>> -1765328243/Matching credential not found [25281] > 1493970797.299962: > >>>>>>>> Server has referral realm; starting with > >>>>>>>> test-service/localh...@test.com [25281] > >>>>>>>> 1493970797.299975: Retrieving dran...@test.com -> > >>>>>>>> krbtgt/test....@test.com from > >>>>>>>> FILE:kerby-kerb/kerb-kdc-test/target/tmp/test-tkt.cc > >>>>>>> with result: > >>>>>>>> 0/Success [25281] 1493970797.299979: Starting with TGT for client > >>>>>> realm: > >>>>>>>> dran...@test.com -> krbtgt/test....@test.com [25281] > >>>>>> 1493970797.299981: > >>>>>>>> Requesting tickets for test-service/localh...@test.com, referrals > >>>>>>>> on [25281] 1493970797.299994: Generated subkey for TGS request: > >>>>>>>> aes128-cts/1B9B [25281] 1493970797.300009: etypes requested in TGS > >>>>>>> request: > >>>>>>>> aes256-cts, aes128-cts, des3-cbc-sha1, rc4-hmac, camellia128-cts, > >>>>>>>> camellia256-cts [25281] 1493970797.300054: Encoding request body > >>>>>>>> and padata into FAST request [25281] 1493970797.300080: Sending > >>>>>>>> request > >>>>>>>> (823 bytes) to TEST.COM [25281] 1493970797.300091: Resolving > >>>>>>>> hostname localhost [25281] > >>>>>>>> 1493970797.300136: Initiating TCP connection to stream > >>>>>>>> 127.0.0.1:34319 > >>>>>>>> [25281] 1493970797.300191: Sending TCP request to stream > >>>>>>>> 127.0.0.1:34319 [25281] 1493970797.303610: Received answer (125 > >>>>>>>> bytes) from stream > >>>>>>>> 127.0.0.1:34319 > >>>>>>>> [25281] 1493970797.303618: Terminating TCP connection to stream > >>>>>>>> 127.0.0.1:34319 > >>>>>>>> [25281] 1493970797.553126: Response was not from master KDC > >>>>>>>> [25281] > >>>>>>>> 1493970797.553198: TGS request result: -1765323383/Unknown code > >>>>>>>> krcM > >>>>>>>> 137 [25281] 1493970797.553234: Requesting tickets for > >>>>>>>> test-service/ localh...@test.com, referrals off [25281] > >>>>> 1493970797.553273: > >>>>>>>> Generated subkey for TGS request: aes128-cts/94C6 [25281] > >>>>>> 1493970797.553323: > >>>>>>>> etypes requested in TGS request: aes256-cts, aes128-cts, > >>>>>>>> des3-cbc-sha1, rc4-hmac, camellia128-cts, camellia256-cts [25281] > >>>>>>>> 1493970797.553436: Encoding request body and padata into FAST > >>>>>>>> request > >>>>>>> [25281] 1493970797.553532: > >>>>>>>> Sending request (823 bytes) to TEST.COM [25281] > 1493970797.553567: > >>>>>>>> Resolving hostname localhost [25281] 1493970797.553745: Initiating > >>>>>>>> TCP connection to stream > >>>>>>>> 127.0.0.1:34319 > >>>>>>>> [25281] 1493970797.553889: Sending TCP request to stream > >>>>>>>> 127.0.0.1:34319 [25281] 1493970797.558297: Received answer (125 > >>>>>>>> bytes) from stream > >>>>>>>> 127.0.0.1:34319 > >>>>>>>> [25281] 1493970797.558318: Terminating TCP connection to stream > >>>>>>>> 127.0.0.1:34319 > >>>>>>>> [25281] 1493970797.561189: Response was not from master KDC > >>>>>>>> [25281] > >>>>>>>> 1493970797.561258: TGS request result: -1765323383/Unknown code > >>>>>>>> krcM > >>>>>>>> 137 ('First kerberos.authGSSClientStep not successful', > >>>>>>>> GSSError(('Unspecified GSS failure. Minor code may provide more > >>>>>>>> information', 851968), ('Unknown code krcM 137', -1765323383))) > >>>>>>>> > >>>>>>>> > >>>>>>>> KDC logging with allowUDP = true: > >>>>>>>> > >>>>>>>> [INFO] Running org.apache.kerby.kerberos.kerb.server.MitIssueTest > >>>>>>>> [pool-1-thread-1] INFO > >>>>>>>> org.apache.kerby.kerberos.kerb.server.request.AsRequest - AS_REQ > >>>>>> ISSUE: > >>>>>>>> authtime 1493972505784,dran...@test.com for > >>>>>>>> krbtgt/test....@test.com [main] INFO > >>>>>>>> org.apache.kerby.kerberos.kerb.client.impl. > DefaultInternalKrbClien > >>>>>>>> t > >>>>>>>> - Send to kdc success. > >>>>>>>> [main] INFO org.apache.kerby.kerberos.kerb.client.KrbClientBase - > >>>>>>>> Storing the tgt to the credential cache file. > >>>>>>>> [pool-1-thread-1] INFO > >>>>>>>> org.apache.kerby.kerberos.kerb.server.request.KdcRequest - The > >>>>>>>> preauth data is empty. > >>>>>>>> [pool-1-thread-1] INFO > >>>>>>>> org.apache.kerby.kerberos.kerb.server.KdcHandler > >>>>>>>> - KRB error occurred while processing request:Additional > >>>>>>>> pre-authentication required [pool-1-thread-2] INFO > >>>>>>>> org.apache.kerby.kerberos.kerb.server.request.AsRequest - AS_REQ > >>>>>> ISSUE: > >>>>>>>> authtime 1493972505948,test-service/localh...@test.com for > krbtgt/ > >>>>>>>> test....@test.com Exception in thread "Thread-0" > >>>>>>>> java.lang.RuntimeException: Error occured while checking udp > >>>>>> connections > >>>>>>>> at > >>>>>>>> org.apache.kerby.kerberos.kerb.transport.KdcNetwork.run( > >>>>>>>> KdcNetwork.java:105) > >>>>>>>> at > >>>>>>>> org.apache.kerby.kerberos.kerb.transport.KdcNetwork. > >>>>>>>> access$000(KdcNetwork.java:39) > >>>>>>>> at > >>>>>>>> org.apache.kerby.kerberos.kerb.transport.KdcNetwork$1. > >>>>>>>> run(KdcNetwork.java:75) > >>>>>>>> at java.lang.Thread.run(Thread.java:748) > >>>>>>>> Caused by: java.nio.channels.ClosedChannelException > >>>>>>>> at > >>>>>>>> sun.nio.ch.DatagramChannelImpl.ensureOpen( > >>>>>> DatagramChannelImpl.java:320) > >>>>>>>> at sun.nio.ch.DatagramChannelImpl.receive( > >>>>>>>> DatagramChannelImpl.java:331) > >>>>>>>> at > >>>>>>>> org.apache.kerby.kerberos.kerb.transport.KdcNetwork. > >>>>>>>> checkUdpMessage(KdcNetwork.java:132) > >>>>>>>> at > >>>>>>>> org.apache.kerby.kerberos.kerb.transport.KdcNetwork.run( > >>>>>>>> KdcNetwork.java:101) > >>>>>>>> ... 3 more > >>>>>>>> > >>>>>>>> > >>>>>>>> krb5.conf: > >>>>>>>> > >>>>>>>> [libdefaults] > >>>>>>>> kdc_realm = TEST.COM > >>>>>>>> default_realm = TEST.COM > >>>>>>>> udp_preference_limit = 4096 > >>>>>>>> kdc_tcp_port = 37080 > >>>>>>>> kdc_udp_port = 36525 > >>>>>>>> > >>>>>>>> [realms] > >>>>>>>> TEST.COM = { > >>>>>>>> kdc = localhost:36525 > >>>>>>>> } > >>>>>>>> > >>>>>>>> And port 36525 does not show up in `netstat -l` (while 37080 does) > >>>>>>>> > >>>>>>>> > >>>>>>>> Op 04-05-17 om 14:55 schreef Li, Jiajia: > >>>>>>>>> Hi Marc, > >>>>>>>>> I try to run your test(through applying your patch in the trunk) > >>>>>>>>> , I > >>>>>>>> think it's success now. Could you take some time to check about > it? > >>>>>>>>> Here is the log: > >>>>>>>>> > >>>>>>>>> directory-kerby git:(trunk) ? . > >>>>>>>>> kerby-kerb/kerb-kdc-test/src/test/java/org/apache/kerby/kerberos > >>>>>>>>> /k > >>>>>>>>> er > >>>>>>>>> b/ > >>>>>>>>> server/MitIssueTest.sh > >>>>>>>>> kerberos.authGSSClientInit successful > >>>>>>>>> 2017-05-04T20:44:06 set-error: -1765328234: entypes not > >>>>>>>>> supported > >>>>>>>>> 2017-05-04T20:44:06 set-error: -1765328243: Did not find > >>>>>>>>> credential for krb5_ccache_conf_data/realm-config@X-CACHECONF: > >>>>>>>>> in cache FILE:kerby-kerb/kerb-kdc-test/target/tmp/test-tkt.cc > >>>>>>>>> 2017-05-04T20:44:06 set-error: -1765328243: Did not find > >>>>>>>>> credential for test-service/localh...@test.com in cache > >>>>>>>>> FILE:kerby-kerb/kerb-kdc-test/target/tmp/test-tkt.cc > >>>>>>>>> 2017-05-04T20:44:06 set-error: -1765328243: Did not find > >>>>>>>>> credential for > >>>>>>>>> krb5_ccache_conf_data/negative-cache/test-service\134/localhost\ > >>>>>>>>> 13 > >>>>>>>>> 4@ > >>>>>>>>> TE > >>>>>>>>> ST.COM@X-CACHECONF: in cache > >>>>>>>>> FILE:kerby-kerb/kerb-kdc-test/target/tmp/test-tkt.cc > >>>>>>>>> 2017-05-04T20:44:06 set-error: -1765328243: Did not find > >>>>>>>>> credential for krb5_ccache_conf_data/lkdc-hostname@X-CACHECONF: > >>>>>>>>> in cache FILE:kerby-kerb/kerb-kdc-test/target/tmp/test-tkt.cc > >>>>>>>>> 2017-05-04T20:44:06 set-error: -1765328243: Did not find > >>>>>>>>> credential for krb5_ccache_conf_data/sitename@X-CACHECONF: in > >>>>>>>>> cache FILE:kerby-kerb/kerb-kdc-test/target/tmp/test-tkt.cc > >>>>>>>>> 2017-05-04T20:44:06 set-error: -1765328243: Did not find > >>>>>>>>> credential for test-service/localh...@test.com in cache > >>>>>>>>> FILE:kerby-kerb/kerb-kdc-test/target/tmp/test-tkt.cc > >>>>>>>>> 2017-05-04T20:44:06 set-error: -1765328234: Encryption type > >>>>>>>>> des-cbc-md5-deprecated not supported > >>>>>>>>> 2017-05-04T20:44:06 set-error: -1765328234: Encryption type > >>>>>>>>> des-cbc-md4-deprecated not supported > >>>>>>>>> 2017-05-04T20:44:06 set-error: -1765328234: Encryption type > >>>>>>>>> des-cbc-crc-deprecated not supported > >>>>>>>>> 2017-05-04T20:44:06 Trying to find service kdc for realm > >>>>>>>>> TEST.COM flags 0 > >>>>>>>>> 2017-05-04T20:44:06 configuration file for realm TEST.COM found > >>>>>>>>> 2017-05-04T20:44:06 submissing new requests to new host > >>>>>>>>> 2017-05-04T20:44:06 host_create: setting hostname localhost > >>>>>>>>> 2017-05-04T20:44:06 connecting to host: udp ::1:52534 > >>>>>>>>> (localhost) > >>>>>> tid: > >>>>>>>>> 00000001 > >>>>>>>>> 2017-05-04T20:44:06 host_create: setting hostname localhost > >>>>>>>>> 2017-05-04T20:44:06 Queuing host in future (in 3s), its the 2 > >>>>>>>>> address on the same name: udp 127.0.0.1:52534 (localhost) tid: > >>>>>>>>> 00000002 > >>>>>>>>> 2017-05-04T20:44:06 writing packet: udp ::1:52534 (localhost) > tid: > >>>>>>>>> 00000001 > >>>>>>>>> 2017-05-04T20:44:06 reading packet: udp ::1:52534 (localhost) > tid: > >>>>>>>>> 00000001 > >>>>>>>>> 2017-05-04T20:44:06 host completed: udp ::1:52534 (localhost) > tid: > >>>>>>>>> 00000001 > >>>>>>>>> 2017-05-04T20:44:06 krb5_sendto_context TEST.COM done: 0 hosts 1 > >>>>>>>>> packets 1 wc: 0.048927 nr: 0.000932 kh: 0.000814 tid: 00000002 > >>>>>>>>> 2017-05-04T20:44:06 tkt: extract key 17/763641F3 > >>>>>>>>> 2017-05-04T20:44:06 set-error: -1765328353: Decrypt integrity > >>>>>>>>> check failed for checksum type hmac-sha1-96-aes128, key type > >>>>>>>>> aes128-cts-hmac-sha1-96 > >>>>>>>>> 2017-05-04T20:44:06 tkt: extract key 17/3084A95C > >>>>>>>>> 2017-05-04T20:44:06 krb5_get_credentials_with_flags: TEST.COM > wc: > >>>>>>>>> 0.050317 > >>>>>>>>> 2017-05-04T20:44:06 set-error: -1765328243: Did not find > >>>>>>>>> credential for krb5_ccache_conf_data/realm-config@X-CACHECONF: > >>>>>>>>> in cache FILE:kerby-kerb/kerb-kdc-test/target/tmp/test-tkt.cc > >>>>>>>>> 2017-05-04T20:44:06 set-error: -1765328243: Did not find > >>>>>>>>> credential for > >>>>>>>>> krb5_ccache_conf_data/time-offset/test-service\134/ > >>>>>> localhost\134@TEST. > >>>>>>>>> COM@X-CACHECONF: in cache > >>>>>>>>> FILE:kerby-kerb/kerb-kdc-test/target/tmp/test-tkt.cc > >>>>>>>>> 2017-05-04T20:44:06 Setting up PFS for auth context > >>>>>>>>> 2017-05-04T20:44:06 set-error: -1765328234: Encryption type > >>>>>>>>> des-cbc-md5-deprecated not supported > >>>>>>>>> 2017-05-04T20:44:06 set-error: -1765328234: Encryption type > >>>>>>>>> des-cbc-md4-deprecated not supported > >>>>>>>>> 2017-05-04T20:44:06 set-error: -1765328234: Encryption type > >>>>>>>>> des-cbc-crc-deprecated not supported First > >>>>>>>>> kerberos.authGSSClientStep successful > >>>>>>>>> > >>>>>>>>> Thanks > >>>>>>>>> Jiajia > >>>>>>>>> > >>>>>>>>> -----Original Message----- > >>>>>>>>> From: Zheng, Kai [mailto:kai.zh...@intel.com] > >>>>>>>>> Sent: Wednesday, May 3, 2017 7:29 PM > >>>>>>>>> To: kerby@directory.apache.org > >>>>>>>>> Subject: RE: MIT Kerberos compatibility > >>>>>>>>> > >>>>>>>>> Hi Marc, > >>>>>>>>> > >>>>>>>>> In case you're not aware of this, please check out the latest > >>>>>>>>> fix made > >>>>>>>> by Jiajia. We thought your case may be different, but would be > >>>>>>>> good to have a check before we can repeat/fix your case. Thanks. > >>>>>>>>> https://issues.apache.org/jira/browse/DIRKRB-625 > >>>>>>>>> > >>>>>>>>> Regards, > >>>>>>>>> Kai > >>>>>>>>> > >>>>>>>>> -----Original Message----- > >>>>>>>>> From: Marc de Lignie [mailto:m.c.delig...@xs4all.nl] > >>>>>>>>> Sent: Sunday, April 30, 2017 7:45 PM > >>>>>>>>> To: kerby@directory.apache.org > >>>>>>>>> Subject: Re: MIT Kerberos compatibility > >>>>>>>>> > >>>>>>>>> Hi Kai, > >>>>>>>>> > >>>>>>>>> The terminal output below is for the latest MIT Kerberos 1.15.1 > >>>>>>>>> (locally > >>>>>>>> built on Ubuntu Xenial). Before that, I also tested with the > >>>>>>>> default Xenial MIT Kerberos packages (1.13.2), with the same > >>>>>>>> result. I did not try earlier MIT Kerberos versions. > >>>>>>>>> > >>>>>>>>> Marc > >>>>>>>>> > >>>>>>>>> Op 29-04-17 om 21:42 schreef Marc de Lignie: > >>>>>>>>>> Hi Kai, > >>>>>>>>>> > >>>>>>>>>> Thanks for the response. I prepared a minimal config that > >>>>>>>>>> reproduces my problem. > >>>>>>>>>> > >>>>>>>>>> You can fetch the branch/commit from: > >>>>>>>>>> https://github.com/vtslab/directory-kerby/commits/MitIssue > >>>>>>>>>> > >>>>>>>>>> This is relative to RC2, but I also tried this on trunk for my > >>>>>>>>>> actual project. > >>>>>>>>>> > >>>>>>>>>> This config produces the debug and error messages below. > >>>>>>>>>> > >>>>>>>>>> 1. For the terminal with the bash + python script $ klist > >>>>>>>>>> Ticket > >>>>>>>>>> cache: FILE:kerby-kerb/kerb-kdc-test/target/tmp/test-tkt.cc > >>>>>>>>>> Default principal: dran...@test.com > >>>>>>>>>> > >>>>>>>>>> Valid starting Expires Service principal > >>>>>>>>>> 29-04-17 21:07:39 30-04-17 05:07:39 krbtgt/test....@test.com > >>>>>>>>>> renew until 29-04-17 21:07:39 > >>>>>>>>>> > >>>>>>>>>> $ . > >>>>>>>>>> kerby-kerb/kerb-kdc-test/src/test/java/org/apache/kerby/kerbero > >>>>>>>>>> s/ ke rb / server/MitIssueTest.sh [15538] 1493491231.917606: > >>>>>>>>>> Retrieving dran...@test.com from > >>>>>>>>>> FILE:/etc/krb5/user/1000/client.keytab (vno 0, enctype 0) with > >>>>>>>>>> result: > >>>>>>>>>> 2/Key table file '/etc/krb5/user/1000/client.keytab' not found > >>>>>>>>>> [15538] > >>>>>>>>>> 1493491231.917827: Retrieving dran...@test.com from > >>>>>>>>>> FILE:/etc/krb5/user/1000/client.keytab (vno 0, enctype 0) with > >>>>>>> result: > >>>>>>>>>> 2/Key table file '/etc/krb5/user/1000/client.keytab' not found > >>>>>>>>>> kerberos.authGSSClientInit successful [15538] 1493491231.918185: > >>>>>>>>>> Getting credentials dran...@test.com -> test-service/localhost@ > >>>>>>>>>> using ccache > >>>>>>>>>> FILE:kerby-kerb/kerb-kdc-test/target/tmp/test-tkt.cc > >>>>>>>>>> [15538] 1493491231.918210: Retrieving dran...@test.com -> > >>>>>>>>>> test-service/localhost@ from > >>>>>>>>>> FILE:kerby-kerb/kerb-kdc-test/target/tmp/test-tkt.cc with > result: > >>>>>>>>>> -1765328243/Matching credential not found (filename: > >>>>>>>>>> kerby-kerb/kerb-kdc-test/target/tmp/test-tkt.cc) > >>>>>>>>>> [15538] 1493491231.918226: Retrying dran...@test.com -> > >>>>>>>>>> test-service/localh...@test.com with result: > >>>>>>>>>> -1765328243/Matching credential not found (filename: > >>>>>>>>>> kerby-kerb/kerb-kdc-test/target/tmp/test-tkt.cc) > >>>>>>>>>> [15538] 1493491231.918229: Server has referral realm; starting > >>>>>>>>>> with test-service/localh...@test.com [15538] 1493491231.918278: > >>>>>>>>>> Retrieving dran...@test.com -> krbtgt/test....@test.com from > >>>>>>>>>> FILE:kerby-kerb/kerb-kdc-test/target/tmp/test-tkt.cc with > result: > >>>>>>>>>> 0/Success > >>>>>>>>>> [15538] 1493491231.918281: Starting with TGT for client realm: > >>>>>>>>>> dran...@test.com -> krbtgt/test....@test.com [15538] > >>>>>>>>>> 1493491231.918301: Requesting tickets for > >>>>>>>>>> test-service/localh...@test.com, referrals on [15538] > >>>>>>>>>> 1493491231.918326: Generated subkey for TGS request: > >>>>>>>>>> aes128-cts/FA30 > >>>>>>>>>> [15538] 1493491231.918359: etypes requested in TGS request: > >>>>>>>>>> aes256-cts, aes128-cts, aes256-sha2, aes128-sha2, > >>>>>>>>>> des3-cbc-sha1, rc4-hmac, camellia128-cts, camellia256-cts > >>>>>>>>>> [15538] > >>>>>> 1493491231.918484: > >>>>>>>>>> Encoding request body and padata into FAST request [15538] > >>>>>>>>>> 1493491231.918541: Sending request (836 bytes) to TEST.COM > >>>>>>>>>> [15538] > >>>>>>>>>> 1493491231.918597: Resolving hostname localhost [15538] > >>>>>>>>>> 1493491231.918703: Initiating TCP connection to stream > >>>>>>>>>> 127.0.0.1:44292 > >>>>>>>>>> [15538] 1493491231.918777: Sending TCP request to stream > >>>>>>>>>> 127.0.0.1:44292 [15538] 1493491231.922803: TCP error receiving > >>>>>>>>>> from stream > >>>>>>>>>> 127.0.0.1:44292: 104/Connection reset by peer [15538] > >>>>>>>>>> 1493491231.922812: Terminating TCP connection to stream > >>>>>>>>>> 127.0.0.1:44292 > >>>>>>>>>> [15538] 1493491231.922858: Sending initial UDP request to dgram > >>>>>>>>>> 127.0.0.1:44292 > >>>>>>>>>> ('First kerberos.authGSSClientStep not successful', > >>>>>>>>>> GSSError(('Unspecified GSS failure. Minor code may provide > >>>>>>>>>> more information', 851968), ("Cannot contact any KDC for realm > >>>>>>>>>> 'TEST.COM'", > >>>>>>>>>> -1765328228))) > >>>>>>>>>> > >>>>>>>>>> 2. For the terminal that runs mvn clean test > >>>>>>>>>> -Dtest=MitIssueTest Running > >>>>>>>>>> org.apache.kerby.kerberos.kerb.server.MitIssueTest > >>>>>>>>>> 2017-04-29 21:07:39,182 DEBUG [main] backend. > >>>>>> AbstractIdentityBackend: > >>>>>>>>>> initialize called > >>>>>>>>>> 2017-04-29 21:07:39,195 DEBUG [main] backend. > >>>>>> AbstractIdentityBackend: > >>>>>>>>>> getIdentity called, principalName = krbtgt/test....@test.com > >>>>>>>>>> 2017-04-29 21:07:39,195 DEBUG [main] backend. > >>>>>> AbstractIdentityBackend: > >>>>>>>>>> getIdentity failed, principalName = krbtgt/test....@test.com > >>>>>>>>>> 2017-04-29 21:07:39,212 DEBUG [main] backend. > >>>>>> AbstractIdentityBackend: > >>>>>>>>>> addIdentity successful, principalName = > >>>>>>>>>> krbtgt/test....@test.com > >>>>>>>>>> 2017-04-29 21:07:39,212 DEBUG [main] backend. > >>>>>> AbstractIdentityBackend: > >>>>>>>>>> getIdentity called, principalName = kadmin/test....@test.com > >>>>>>>>>> 2017-04-29 21:07:39,212 DEBUG [main] backend. > >>>>>> AbstractIdentityBackend: > >>>>>>>>>> getIdentity failed, principalName = kadmin/test....@test.com > >>>>>>>>>> 2017-04-29 21:07:39,213 DEBUG [main] backend. > >>>>>> AbstractIdentityBackend: > >>>>>>>>>> addIdentity successful, principalName = > >>>>>>>>>> kadmin/test....@test.com > >>>>>>>>>> 2017-04-29 21:07:39,216 DEBUG [main] backend. > >>>>>> AbstractIdentityBackend: > >>>>>>>>>> start called > >>>>>>>>>> 2017-04-29 21:07:39,232 DEBUG [main] backend. > >>>>>> AbstractIdentityBackend: > >>>>>>>>>> addIdentity successful, principalName = > >>>>>>>>>> test-service/localh...@test.com > >>>>>>>>>> 2017-04-29 21:07:39,425 DEBUG [main] backend. > >>>>>> AbstractIdentityBackend: > >>>>>>>>>> addIdentity successful, principalName = dran...@test.com > >>>>>>>>>> 2017-04-29 21:07:39,465 DEBUG [pool-1-thread-1] > >>>>>>>>>> backend.AbstractIdentityBackend: getIdentity called, > >>>>>>>>>> principalName = krbtgt/test....@test.com > >>>>>>>>>> 2017-04-29 21:07:39,465 DEBUG [pool-1-thread-1] > >>>>>>>>>> backend.AbstractIdentityBackend: getIdentity successful, > >>>>>>>>>> principalName = krbtgt/test....@test.com > >>>>>>>>>> 2017-04-29 21:07:39,465 INFO [pool-1-thread-1] > >>>>> request.KdcRequest: > >>>>>>>>>> Client entry is empty. > >>>>>>>>>> 2017-04-29 21:07:39,465 DEBUG [pool-1-thread-1] > >>>>>>>>>> backend.AbstractIdentityBackend: getIdentity called, > >>>>>>>>>> principalName = dran...@test.com > >>>>>>>>>> 2017-04-29 21:07:39,465 DEBUG [pool-1-thread-1] > >>>>>>>>>> backend.AbstractIdentityBackend: getIdentity successful, > >>>>>>>>>> principalName = dran...@test.com > >>>>>>>>>> 2017-04-29 21:07:39,465 DEBUG [pool-1-thread-1] > >>>>>>>>>> backend.AbstractIdentityBackend: getIdentity called, > >>>>>>>>>> principalName = krbtgt/test....@test.com > >>>>>>>>>> 2017-04-29 21:07:39,465 DEBUG [pool-1-thread-1] > >>>>>>>>>> backend.AbstractIdentityBackend: getIdentity successful, > >>>>>>>>>> principalName = krbtgt/test....@test.com > >>>>>>>>>> 2017-04-29 21:07:39,476 DEBUG [pool-1-thread-1] > >>>>>>>>>> impl.DefaultKdcHandler: Transport or decoding error occurred, > >>>>>>>>>> disconnecting abnormally java.io.EOFException > >>>>>>>>>> at java.io.DataInputStream.readInt(DataInputStream.java:392) > >>>>>>>>>> at > >>>>>>>>>> org.apache.kerby.kerberos.kerb.transport.KrbTcpTransport. > >>>>>>>> receiveMessage(KrbTcpTransport.java:54) > >>>>>>>>>> at > >>>>>>>>>> org.apache.kerby.kerberos.kerb.server.impl.DefaultKdcHandler.ru > >>>>>>>>>> n( > >>>>>>>> DefaultKdcHandler.java:46) > >>>>>>>>>> at > >>>>>>>>>> java.util.concurrent.ThreadPoolExecutor.runWorker( > >>>>>>>> ThreadPoolExecutor.java:1142) > >>>>>>>>>> at > >>>>>>>>>> java.util.concurrent.ThreadPoolExecutor$Worker.run( > >>>>>>>> ThreadPoolExecutor.java:617) > >>>>>>>>>> at java.lang.Thread.run(Thread.java:748) > >>>>>>>>>> 2017-04-29 21:07:39,477 INFO [main] client.KrbClientBase: > >>>>>>>>>> Storing the tgt to the credential cache file. > >>>>>>>>>> 2017-04-29 21:07:39,491 DEBUG [main] backend. > >>>>>> AbstractIdentityBackend: > >>>>>>>>>> getIdentity called, principalName = > >>>>>>>>>> test-service/localh...@test.com > >>>>>>>>>> 2017-04-29 21:07:39,491 DEBUG [main] backend. > >>>>>> AbstractIdentityBackend: > >>>>>>>>>> getIdentity successful, principalName = > >>>>>>>>>> test-service/localh...@test.com > >>>>>>>>>> 2017-04-29 21:07:39,498 DEBUG [pool-1-thread-1] > >>>>>>>>>> backend.AbstractIdentityBackend: getIdentity called, > >>>>>>>>>> principalName = krbtgt/test....@test.com > >>>>>>>>>> 2017-04-29 21:07:39,498 DEBUG [pool-1-thread-1] > >>>>>>>>>> backend.AbstractIdentityBackend: getIdentity successful, > >>>>>>>>>> principalName = krbtgt/test....@test.com > >>>>>>>>>> 2017-04-29 21:07:39,498 INFO [pool-1-thread-1] > >>>>> request.KdcRequest: > >>>>>>>>>> Client entry is empty. > >>>>>>>>>> 2017-04-29 21:07:39,499 DEBUG [pool-1-thread-1] > >>>>>>>>>> backend.AbstractIdentityBackend: getIdentity called, > >>>>>>>>>> principalName = test-service/localh...@test.com > >>>>>>>>>> 2017-04-29 21:07:39,499 DEBUG [pool-1-thread-1] > >>>>>>>>>> backend.AbstractIdentityBackend: getIdentity successful, > >>>>>>>>>> principalName = test-service/localh...@test.com > >>>>>>>>>> 2017-04-29 21:07:39,499 DEBUG [pool-1-thread-1] > >>>>>>>>>> backend.AbstractIdentityBackend: getIdentity called, > >>>>>>>>>> principalName = krbtgt/test....@test.com > >>>>>>>>>> 2017-04-29 21:07:39,499 DEBUG [pool-1-thread-1] > >>>>>>>>>> backend.AbstractIdentityBackend: getIdentity successful, > >>>>>>>>>> principalName = krbtgt/test....@test.com > >>>>>>>>>> 2017-04-29 21:07:39,499 INFO [pool-1-thread-1] > >>>>> request.KdcRequest: > >>>>>>>>>> The preauth data is empty. > >>>>>>>>>> 2017-04-29 21:07:39,501 INFO [pool-1-thread-1] > server.KdcHandler: > >>>>>>>>>> KRB error occurred while processing request:Additional > >>>>>>>>>> pre-authentication required > >>>>>>>>>> 2017-04-29 21:07:39,502 DEBUG [pool-1-thread-1] > >>>>>>>>>> impl.DefaultKdcHandler: Transport or decoding error occurred, > >>>>>>>>>> disconnecting abnormally java.io.EOFException > >>>>>>>>>> at java.io.DataInputStream.readInt(DataInputStream.java:392) > >>>>>>>>>> at > >>>>>>>>>> org.apache.kerby.kerberos.kerb.transport.KrbTcpTransport. > >>>>>>>> receiveMessage(KrbTcpTransport.java:54) > >>>>>>>>>> at > >>>>>>>>>> org.apache.kerby.kerberos.kerb.server.impl.DefaultKdcHandler.ru > >>>>>>>>>> n( > >>>>>>>> DefaultKdcHandler.java:46) > >>>>>>>>>> at > >>>>>>>>>> java.util.concurrent.ThreadPoolExecutor.runWorker( > >>>>>>>> ThreadPoolExecutor.java:1142) > >>>>>>>>>> at > >>>>>>>>>> java.util.concurrent.ThreadPoolExecutor$Worker.run( > >>>>>>>> ThreadPoolExecutor.java:617) > >>>>>>>>>> at java.lang.Thread.run(Thread.java:748) > >>>>>>>>>> 2017-04-29 21:07:39,505 DEBUG [pool-1-thread-1] > >>>>>>>>>> backend.AbstractIdentityBackend: getIdentity called, > >>>>>>>>>> principalName = krbtgt/test....@test.com > >>>>>>>>>> 2017-04-29 21:07:39,505 DEBUG [pool-1-thread-1] > >>>>>>>>>> backend.AbstractIdentityBackend: getIdentity successful, > >>>>>>>>>> principalName = krbtgt/test....@test.com > >>>>>>>>>> 2017-04-29 21:07:39,505 INFO [pool-1-thread-1] > >>>>> request.KdcRequest: > >>>>>>>>>> Client entry is empty. > >>>>>>>>>> 2017-04-29 21:07:39,506 DEBUG [pool-1-thread-1] > >>>>>>>>>> backend.AbstractIdentityBackend: getIdentity called, > >>>>>>>>>> principalName = test-service/localh...@test.com > >>>>>>>>>> 2017-04-29 21:07:39,506 DEBUG [pool-1-thread-1] > >>>>>>>>>> backend.AbstractIdentityBackend: getIdentity successful, > >>>>>>>>>> principalName = test-service/localh...@test.com > >>>>>>>>>> 2017-04-29 21:07:39,506 DEBUG [pool-1-thread-1] > >>>>>>>>>> backend.AbstractIdentityBackend: getIdentity called, > >>>>>>>>>> principalName = krbtgt/test....@test.com > >>>>>>>>>> 2017-04-29 21:07:39,506 DEBUG [pool-1-thread-1] > >>>>>>>>>> backend.AbstractIdentityBackend: getIdentity successful, > >>>>>>>>>> principalName = krbtgt/test....@test.com > >>>>>>>>>> 2017-04-29 21:07:39,510 DEBUG [pool-1-thread-1] > >>>>>>>>>> impl.DefaultKdcHandler: Transport or decoding error occurred, > >>>>>>>>>> disconnecting abnormally java.io.EOFException > >>>>>>>>>> at java.io.DataInputStream.readInt(DataInputStream.java:392) > >>>>>>>>>> at > >>>>>>>>>> org.apache.kerby.kerberos.kerb.transport.KrbTcpTransport. > >>>>>>>> receiveMessage(KrbTcpTransport.java:54) > >>>>>>>>>> at > >>>>>>>>>> org.apache.kerby.kerberos.kerb.server.impl.DefaultKdcHandler.ru > >>>>>>>>>> n( > >>>>>>>> DefaultKdcHandler.java:46) > >>>>>>>>>> at > >>>>>>>>>> java.util.concurrent.ThreadPoolExecutor.runWorker( > >>>>>>>> ThreadPoolExecutor.java:1142) > >>>>>>>>>> at > >>>>>>>>>> java.util.concurrent.ThreadPoolExecutor$Worker.run( > >>>>>>>> ThreadPoolExecutor.java:617) > >>>>>>>>>> at java.lang.Thread.run(Thread.java:748) > >>>>>>>>>> 2017-04-29 21:07:55,602 DEBUG [pool-1-thread-1] > >>>>>>>>>> backend.AbstractIdentityBackend: getIdentity called, > >>>>>>>>>> principalName = krbtgt/test....@test.com > >>>>>>>>>> 2017-04-29 21:07:55,602 DEBUG [pool-1-thread-1] > >>>>>>>>>> backend.AbstractIdentityBackend: getIdentity successful, > >>>>>>>>>> principalName = krbtgt/test....@test.com > >>>>>>>>>> 2017-04-29 21:07:55,602 INFO [pool-1-thread-1] > >>>>> request.KdcRequest: > >>>>>>>>>> Found fast padata and start to process it. > >>>>>>>>>> 2017-04-29 21:07:55,603 ERROR [pool-1-thread-1] > >>>>>>>>>> impl.DefaultKdcHandler: Error occured while processing request: > >>>>>>>>>> org.apache.kerby.kerberos.kerb.KrbException: Decoding failed > >>>>>>>>>> at org.apache.kerby.kerberos.kerb.KrbCodec.decode(KrbCodec. > >>>>>>>> java:85) > >>>>>>>>>> at org.apache.kerby.kerberos.kerb.KrbCodec.decode(KrbCodec. > >>>>>>>> java:70) > >>>>>>>>>> at > >>>>>>>>>> org.apache.kerby.kerberos.kerb.server.request.KdcRequest.kdcFin > >>>>>>>>>> dF > >>>>>>>>>> as > >>>>>>>>>> t( > >>>>>>>> KdcRequest.java:208) > >>>>>>>>>> at > >>>>>>>>>> org.apache.kerby.kerberos.kerb.server.request. > >>>>>>>> KdcRequest.process(KdcRequest.java:168) > >>>>>>>>>> at > >>>>>>>>>> org.apache.kerby.kerberos.kerb.server.KdcHandler. > >>>>>>>> handleMessage(KdcHandler.java:115) > >>>>>>>>>> at > >>>>>>>>>> org.apache.kerby.kerberos.kerb.server.impl.DefaultKdcHandler. > >>>>>>>> handleMessage(DefaultKdcHandler.java:67) > >>>>>>>>>> at > >>>>>>>>>> org.apache.kerby.kerberos.kerb.server.impl.DefaultKdcHandler.ru > >>>>>>>>>> n( > >>>>>>>> DefaultKdcHandler.java:52) > >>>>>>>>>> at > >>>>>>>>>> java.util.concurrent.ThreadPoolExecutor.runWorker( > >>>>>>>> ThreadPoolExecutor.java:1142) > >>>>>>>>>> at > >>>>>>>>>> java.util.concurrent.ThreadPoolExecutor$Worker.run( > >>>>>>>> ThreadPoolExecutor.java:617) > >>>>>>>>>> at java.lang.Thread.run(Thread.java:748) > >>>>>>>>>> Caused by: java.io.IOException: Unexpected item context [0] > >>>>>>>>>> [tag=0xA0, off=0, len=3+207], expecting 0x30 > >>>>>>>>>> at > >>>>>>>>>> org.apache.kerby.asn1.type.Asn1Encodeable.decode( > >>>>>>>> Asn1Encodeable.java:210) > >>>>>>>>>> at > >>>>>>>>>> org.apache.kerby.asn1.type.Asn1Encodeable.decode( > >>>>>>>> Asn1Encodeable.java:197) > >>>>>>>>>> at org.apache.kerby.kerberos.kerb.KrbCodec.decode(KrbCodec. > >>>>>>>> java:83) > >>>>>>>>>> ... 9 more > >>>>>>>>>> 2017-04-29 21:07:55,604 DEBUG [pool-1-thread-1] > >>>>>>>>>> impl.DefaultKdcHandler: Transport or decoding error occurred, > >>>>>>>>>> disconnecting abnormally > >>>>>>>>>> java.net.SocketException: Socket closed > >>>>>>>>>> at java.net.SocketInputStream.socketRead0(Native Method) > >>>>>>>>>> at java.net.SocketInputStream.socketRead(SocketInputStream. > >>>>>>>> java:116) > >>>>>>>>>> at java.net.SocketInputStream.read(SocketInputStream.java: > >>>>> 171) > >>>>>>>>>> at java.net.SocketInputStream.read(SocketInputStream.java: > >>>>> 141) > >>>>>>>>>> at java.net.SocketInputStream.read(SocketInputStream.java: > >>>>> 224) > >>>>>>>>>> at java.io.DataInputStream.readInt(DataInputStream.java:387) > >>>>>>>>>> at > >>>>>>>>>> org.apache.kerby.kerberos.kerb.transport.KrbTcpTransport. > >>>>>>>> receiveMessage(KrbTcpTransport.java:54) > >>>>>>>>>> at > >>>>>>>>>> org.apache.kerby.kerberos.kerb.server.impl.DefaultKdcHandler.ru > >>>>>>>>>> n( > >>>>>>>> DefaultKdcHandler.java:46) > >>>>>>>>>> at > >>>>>>>>>> java.util.concurrent.ThreadPoolExecutor.runWorker( > >>>>>>>> ThreadPoolExecutor.java:1142) > >>>>>>>>>> at > >>>>>>>>>> java.util.concurrent.ThreadPoolExecutor$Worker.run( > >>>>>>>> ThreadPoolExecutor.java:617) > >>>>>>>>>> at java.lang.Thread.run(Thread.java:748) > >>>>>>>>>> > >>>>>>>>>> In a FreeIPA environment these python lines "just" work. > >>>>>>>>>> > >>>>>>>>>> Any suggestions are welcome! > >>>>>>>>>> > >>>>>>>>>> Marc > >>>>>>>>>> > >>>>>>>>>> > >>>>>>>>> -- > >>>>>>>>> Marc de Lignie > >>>>>>>>> > >>>>>>>> > >>>>>>>> -- > >>>>>>>> Marc de Lignie > >>>>>>>> > >>>>>>>> > >>>>>>> > >>>>>>> > >>>>>>> -- > >>>>>>> Colm O hEigeartaigh > >>>>>>> > >>>>>>> Talend Community Coder > >>>>>>> http://coders.talend.com > >>>>>>> > >>>>>> > >>>>>> > >>>>>> > >>>>>> -- > >>>>>> Colm O hEigeartaigh > >>>>>> > >>>>>> Talend Community Coder > >>>>>> http://coders.talend.com > >>>>>> > >>>>> > >>>>> > >>>>> > >>>>> -- > >>>>> Colm O hEigeartaigh > >>>>> > >>>>> Talend Community Coder > >>>>> http://coders.talend.com > >>>>> > >>>> > >>>> > >>>> > >>>> -- > >>>> Colm O hEigeartaigh > >>>> > >>>> Talend Community Coder > >>>> http://coders.talend.com > >>> > >>> > >> > >> > >> -- > >> Colm O hEigeartaigh > >> > >> Talend Community Coder > >> http://coders.talend.com > -- Colm O hEigeartaigh Talend Community Coder http://coders.talend.com
