Open pranaysharmamanulife opened 3 years ago
Connecting to this DFS using Mac Finder or Windows machine works fine with same credentials, but looks like smbj is trying to authenticate user at root level of the target server.
Error occurs in connection.authenticate(ac)
SmbConfig config = SmbConfig.builder().withDfsEnabled(true).withReadTimeout(timeoutMinutes, TimeUnit.MINUTES) .withTimeout(timeoutMinutes, TimeUnit.MINUTES) // Timeout sets Read, Write, and Transact timeouts (default is 60 seconds) .withSoTimeout(timeoutMinutes, TimeUnit.MINUTES) // Socket Timeout (default is 0 seconds, blocks forever) .build(); SMBClient client = new SMBClient(config); Connection connection = client.connect(namedHost); return connection.authenticate(ac);
Stack Trace -
2020-11-26 13:31:30.096 INFO 41819 --- [nio-8442-exec-4] c.m.g.s.c.SambaDirectoryClientController : readDirectory: \\mfc.com\AZNADFS\can\G-Dev\MDOM Unc Directory does not end with / Share cleaned: //mfc.com/AZNADFS/can/G-Dev/MDOM/ 2020-11-26 13:31:30.101 DEBUG 41819 --- [nio-8442-exec-4] c.h.p.commons.socket.ProxySocketFactory : Connecting to mfc.com/10.201.142.23:445 2020-11-26 13:31:30.219 DEBUG 41819 --- [nio-8442-exec-4] c.h.s.t.t.direct.DirectTcpPacketReader : Starting PacketReader on thread: Packet Reader for mfc.com 2020-11-26 13:31:30.219 DEBUG 41819 --- [nio-8442-exec-4] c.h.s.connection.SMBProtocolNegotiator : Negotiating dialects [SMB_2_0_2, SMB_2_1] 2020-11-26 13:31:30.219 DEBUG 41819 --- [nio-8442-exec-4] c.hierynomus.smbj.connection.Connection : Granted 1 (out of 1) credits to SMB2_NEGOTIATE with message id << 0 >> 2020-11-26 13:31:30.219 DEBUG 41819 --- [nio-8442-exec-4] c.h.s.t.tcp.direct.DirectTcpTransport : Writing packet SMB2_NEGOTIATE with message id << 0 >> 2020-11-26 13:31:30.219 DEBUG 41819 --- [nio-8442-exec-4] c.h.protocol.commons.concurrent.Promise : Awaiting << 0 >> 2020-11-26 13:31:30.230 DEBUG 41819 --- [r for mfc.com] c.h.s.t.t.direct.DirectTcpPacketReader : Received packet SMB2_NEGOTIATE with message id << 0 >> 2020-11-26 13:31:30.231 DEBUG 41819 --- [r for mfc.com] .h.s.c.p.SMB2CreditGrantingPacketHandler : Server granted us 1 credits for SMB2_NEGOTIATE with message id << 0 >>, now available: 1 credits 2020-11-26 13:31:30.231 DEBUG 41819 --- [r for mfc.com] c.h.protocol.commons.concurrent.Promise : Setting << 0 >> to `SMB2_NEGOTIATE with message id << 0 >>` 2020-11-26 13:31:30.231 DEBUG 41819 --- [nio-8442-exec-4] c.h.s.connection.SMBProtocolNegotiator : Negotiated the following connection settings: ConnectionInfo{ serverGuid=31120fa6-6388-4c86-892c-7708e3277a12, serverName='mfc.com', negotiatedProtocol=NegotiatedProtocol{dialect=SMB_2_1, maxTransactSize=8388608, maxReadSize=8388608, maxWriteSize=8388608}, clientGuid=24476b5c-27bd-40d5-a63a-ebec24b1b09c, clientCapabilities=[], serverCapabilities=[SMB2_GLOBAL_CAP_DFS, SMB2_GLOBAL_CAP_LEASING, SMB2_GLOBAL_CAP_LARGE_MTU], clientSecurityMode=0, serverSecurityMode=1, server='com.hierynomus.smbj.server.Server@3e1f8eca' } 2020-11-26 13:31:30.231 INFO 41819 --- [nio-8442-exec-4] c.hierynomus.smbj.connection.Connection : Successfully connected to: mfc.com 2020-11-26 13:31:30.231 DEBUG 41819 --- [nio-8442-exec-4] c.h.smbj.auth.NtlmAuthenticator : Initialized Authentication of dev using NTLM 2020-11-26 13:31:30.231 DEBUG 41819 --- [nio-8442-exec-4] c.hierynomus.smbj.connection.Connection : Granted 1 (out of 1) credits to SMB2_SESSION_SETUP with message id << 1 >> 2020-11-26 13:31:30.231 DEBUG 41819 --- [nio-8442-exec-4] c.h.s.t.tcp.direct.DirectTcpTransport : Writing packet SMB2_SESSION_SETUP with message id << 1 >> 2020-11-26 13:31:30.231 DEBUG 41819 --- [nio-8442-exec-4] c.h.protocol.commons.concurrent.Promise : Awaiting << 1 >> 2020-11-26 13:31:30.242 DEBUG 41819 --- [r for mfc.com] c.h.s.t.t.direct.DirectTcpPacketReader : Received packet SMB2_SESSION_SETUP with message id << 1 >> 2020-11-26 13:31:30.242 DEBUG 41819 --- [r for mfc.com] .h.s.c.p.SMB2CreditGrantingPacketHandler : Server granted us 1 credits for SMB2_SESSION_SETUP with message id << 1 >>, now available: 1 credits 2020-11-26 13:31:30.242 DEBUG 41819 --- [r for mfc.com] c.h.protocol.commons.concurrent.Promise : Setting << 1 >> to `SMB2_SESSION_SETUP with message id << 1 >>` 2020-11-26 13:31:30.242 DEBUG 41819 --- [nio-8442-exec-4] c.h.smbj.connection.SMBSessionBuilder : More processing required for authentication of dev using com.hierynomus.smbj.auth.NtlmAuthenticator@7b1d076d 2020-11-26 13:31:30.243 DEBUG 41819 --- [nio-8442-exec-4] c.h.smbj.auth.NtlmAuthenticator : Received token: a1 81 fc 30 81 f9 a0 03 0a 01 01 a1 0c 06 0a 2b 06 01 04 01 82 37 02 02 0a a2 81 e3 04 81 e0 4e 54 4c 4d 53 53 50 00 02 00 00 00 0a 00 0a 00 38 00 00 00 15 82 89 e2 48 ee dd 4d 50 5a 56 86 00 00 00 00 00 00 00 00 9e 00 9e 00 42 00 00 00 0a 00 39 38 00 00 00 0f 4d 00 46 00 43 00 47 00 44 00 02 00 0a 00 4d 00 46 00 43 00 47 00 44 00 01 00 1c 00 43 00 4c 00 53 00 57 00 56 00 47 00 49 00 53 00 41 00 44 00 53 00 50 00 30 00 31 00 04 00 12 00 4d 00 46 00 43 00 47 00 44 00 2e 00 63 00 6f 00 6d 00 03 00 30 00 43 00 4c 00 53 00 57 00 56 00 47 00 49 00 53 00 41 00 44 00 53 00 50 00 30 00 31 00 2e 00 4d 00 46 00 43 00 47 00 44 00 2e 00 63 00 6f 00 6d 00 05 00 12 00 4d 00 46 00 43 00 47 00 44 00 2e 00 63 00 6f 00 6d 00 07 00 08 00 8c 0d d5 5b 22 c4 d6 01 00 00 00 00 2020-11-26 13:31:30.243 DEBUG 41819 --- [nio-8442-exec-4] com.hierynomus.asn1.ASN1InputStream : Read ASN.1 object: ASN1TaggedObject[ASN1Tag[CONTEXT_SPECIFIC,CONSTRUCTED,1],<unknown>] 2020-11-26 13:31:30.243 DEBUG 41819 --- [nio-8442-exec-4] com.hierynomus.asn1.ASN1InputStream : Read ASN.1 object: ASN1TaggedObject[ASN1Tag[CONTEXT_SPECIFIC,CONSTRUCTED,0],<unknown>] 2020-11-26 13:31:30.243 DEBUG 41819 --- [nio-8442-exec-4] com.hierynomus.asn1.ASN1InputStream : Read ASN.1 object: ASN1TaggedObject[ASN1Tag[CONTEXT_SPECIFIC,CONSTRUCTED,1],<unknown>] 2020-11-26 13:31:30.243 DEBUG 41819 --- [nio-8442-exec-4] com.hierynomus.asn1.ASN1InputStream : Read ASN.1 object: ASN1TaggedObject[ASN1Tag[CONTEXT_SPECIFIC,CONSTRUCTED,2],<unknown>] 2020-11-26 13:31:30.243 DEBUG 41819 --- [nio-8442-exec-4] com.hierynomus.asn1.ASN1InputStream : Read ASN.1 object: ASN1Sequence[[ASN1TaggedObject[ASN1Tag[CONTEXT_SPECIFIC,CONSTRUCTED,0],<unknown>], ASN1TaggedObject[ASN1Tag[CONTEXT_SPECIFIC,CONSTRUCTED,1],<unknown>], ASN1TaggedObject[ASN1Tag[CONTEXT_SPECIFIC,CONSTRUCTED,2],<unknown>]]] 2020-11-26 13:31:30.243 DEBUG 41819 --- [nio-8442-exec-4] com.hierynomus.asn1.ASN1InputStream : Read ASN.1 object: ASN1Enumerated[1] 2020-11-26 13:31:30.243 DEBUG 41819 --- [nio-8442-exec-4] com.hierynomus.asn1.ASN1InputStream : Read ASN.1 object: ASN1ObjectIdentifier[1.3.6.1.4.1.311.2.2.10] 2020-11-26 13:31:30.243 DEBUG 41819 --- [nio-8442-exec-4] com.hierynomus.asn1.ASN1InputStream : Read ASN.1 object: ASN1OctetString[[78, 84, 76, 77, 83, 83, 80, 0, 2, 0, 0, 0, 10, 0, 10, 0, 56, 0, 0, 0, 21, -126, -119, -30, 72, -18, -35, 77, 80, 90, 86, -122, 0, 0, 0, 0, 0, 0, 0, 0, -98, 0, -98, 0, 66, 0, 0, 0, 10, 0, 57, 56, 0, 0, 0, 15, 77, 0, 70, 0, 67, 0, 71, 0, 68, 0, 2, 0, 10, 0, 77, 0, 70, 0, 67, 0, 71, 0, 68, 0, 1, 0, 28, 0, 67, 0, 76, 0, 83, 0, 87, 0, 86, 0, 71, 0, 73, 0, 83, 0, 65, 0, 68, 0, 83, 0, 80, 0, 48, 0, 49, 0, 4, 0, 18, 0, 77, 0, 70, 0, 67, 0, 71, 0, 68, 0, 46, 0, 99, 0, 111, 0, 109, 0, 3, 0, 48, 0, 67, 0, 76, 0, 83, 0, 87, 0, 86, 0, 71, 0, 73, 0, 83, 0, 65, 0, 68, 0, 83, 0, 80, 0, 48, 0, 49, 0, 46, 0, 77, 0, 70, 0, 67, 0, 71, 0, 68, 0, 46, 0, 99, 0, 111, 0, 109, 0, 5, 0, 18, 0, 77, 0, 70, 0, 67, 0, 71, 0, 68, 0, 46, 0, 99, 0, 111, 0, 109, 0, 7, 0, 8, 0, -116, 13, -43, 91, 34, -60, -42, 1, 0, 0, 0, 0]] 2020-11-26 13:31:30.243 DEBUG 41819 --- [nio-8442-exec-4] c.h.ntlm.messages.NtlmChallenge : Windows version = WindowsVersion[WINDOWS_MAJOR_VERSION_10, WINDOWS_MINOR_VERSION_0, 14393, NTLMSSP_REVISION_W2K3] 2020-11-26 13:31:30.243 DEBUG 41819 --- [nio-8442-exec-4] c.h.smbj.auth.NtlmAuthenticator : Received NTLM challenge from: MFC 2020-11-26 13:31:30.244 DEBUG 41819 --- [nio-8442-exec-4] c.hierynomus.smbj.connection.Connection : Granted 1 (out of 1) credits to SMB2_SESSION_SETUP with message id << 2 >> 2020-11-26 13:31:30.244 DEBUG 41819 --- [nio-8442-exec-4] c.h.s.t.tcp.direct.DirectTcpTransport : Writing packet SMB2_SESSION_SETUP with message id << 2 >> 2020-11-26 13:31:30.245 DEBUG 41819 --- [nio-8442-exec-4] c.h.protocol.commons.concurrent.Promise : Awaiting << 2 >> 2020-11-26 13:31:30.300 DEBUG 41819 --- [r for mfc.com] c.h.s.t.t.direct.DirectTcpPacketReader : Received packet SMB2_SESSION_SETUP with message id << 2 >> 2020-11-26 13:31:30.300 DEBUG 41819 --- [r for mfc.com] .h.s.c.p.SMB2CreditGrantingPacketHandler : Server granted us 511 credits for SMB2_SESSION_SETUP with message id << 2 >>, now available: 511 credits 2020-11-26 13:31:30.300 DEBUG 41819 --- [r for mfc.com] c.h.protocol.commons.concurrent.Promise : Setting << 2 >> to `SMB2_SESSION_SETUP with message id << 2 >>` 2020-11-26 13:31:30.300 INFO 41819 --- [nio-8442-exec-4] c.h.smbj.connection.SMBSessionBuilder : Successfully authenticated dev on mfc.com, session is 453501100491569 Unc Directory does not end with / Share cleaned: //mfc.com/AZNADFS/can/G-Dev/MDOM/ AZNADFS 2020-11-26 13:31:30.301 INFO 41819 --- [nio-8442-exec-4] com.hierynomus.smbj.session.Session : Connecting to \\mfc.com\AZNADFS on session 453501100491569 2020-11-26 13:31:30.301 DEBUG 41819 --- [nio-8442-exec-4] c.hierynomus.smbj.connection.Connection : Granted 1 (out of 511) credits to Signed(SMB2_TREE_CONNECT with message id << 3 >>) 2020-11-26 13:31:30.301 DEBUG 41819 --- [nio-8442-exec-4] c.h.s.t.tcp.direct.DirectTcpTransport : Writing packet Signed(SMB2_TREE_CONNECT with message id << 3 >>) 2020-11-26 13:31:30.301 DEBUG 41819 --- [nio-8442-exec-4] c.h.protocol.commons.concurrent.Promise : Awaiting << 3 >> 2020-11-26 13:31:30.317 DEBUG 41819 --- [r for mfc.com] c.h.s.t.t.direct.DirectTcpPacketReader : Received packet SMB2_TREE_CONNECT with message id << 3 >> 2020-11-26 13:31:30.317 DEBUG 41819 --- [r for mfc.com] p.SMB2SignatureVerificationPacketHandler : Signature for packet SMB2_TREE_CONNECT with message id << 3 >> verified. 2020-11-26 13:31:30.317 DEBUG 41819 --- [r for mfc.com] .h.s.c.p.SMB2CreditGrantingPacketHandler : Server granted us 2 credits for SMB2_TREE_CONNECT with message id << 3 >>, now available: 512 credits 2020-11-26 13:31:30.317 DEBUG 41819 --- [r for mfc.com] c.h.protocol.commons.concurrent.Promise : Setting << 3 >> to `SMB2_TREE_CONNECT with message id << 3 >>` 2020-11-26 13:31:30.317 INFO 41819 --- [nio-8442-exec-4] c.hierynomus.smbj.paths.DFSPathResolver : Attempting to resolve \\mfc.com\AZNADFS through DFS 2020-11-26 13:31:30.317 INFO 41819 --- [nio-8442-exec-4] c.hierynomus.smbj.paths.DFSPathResolver : Starting DFS resolution for \\mfc.com\AZNADFS 2020-11-26 13:31:30.317 INFO 41819 --- [nio-8442-exec-4] com.hierynomus.smbj.session.Session : Connecting to \\mfc.com\IPC$ on session 453501100491569 2020-11-26 13:31:30.317 DEBUG 41819 --- [nio-8442-exec-4] c.hierynomus.smbj.connection.Connection : Granted 1 (out of 512) credits to Signed(SMB2_TREE_CONNECT with message id << 4 >>) 2020-11-26 13:31:30.317 DEBUG 41819 --- [nio-8442-exec-4] c.h.s.t.tcp.direct.DirectTcpTransport : Writing packet Signed(SMB2_TREE_CONNECT with message id << 4 >>) 2020-11-26 13:31:30.317 DEBUG 41819 --- [nio-8442-exec-4] c.h.protocol.commons.concurrent.Promise : Awaiting << 4 >> 2020-11-26 13:31:30.483 DEBUG 41819 --- [r for mfc.com] c.h.s.t.t.direct.DirectTcpPacketReader : Received packet SMB2_TREE_CONNECT with message id << 4 >> 2020-11-26 13:31:30.483 DEBUG 41819 --- [r for mfc.com] p.SMB2SignatureVerificationPacketHandler : Signature for packet SMB2_TREE_CONNECT with message id << 4 >> verified. 2020-11-26 13:31:30.483 DEBUG 41819 --- [r for mfc.com] .h.s.c.p.SMB2CreditGrantingPacketHandler : Server granted us 2 credits for SMB2_TREE_CONNECT with message id << 4 >>, now available: 513 credits 2020-11-26 13:31:30.483 DEBUG 41819 --- [r for mfc.com] c.h.protocol.commons.concurrent.Promise : Setting << 4 >> to `SMB2_TREE_CONNECT with message id << 4 >>` 2020-11-26 13:31:30.483 DEBUG 41819 --- [nio-8442-exec-4] c.hierynomus.smbj.connection.Connection : Granted 16 (out of 513) credits to Signed(SMB2_IOCTL with message id << 5 >>) 2020-11-26 13:31:30.483 DEBUG 41819 --- [nio-8442-exec-4] c.h.s.t.tcp.direct.DirectTcpTransport : Writing packet Signed(SMB2_IOCTL with message id << 5 >>) 2020-11-26 13:31:30.483 DEBUG 41819 --- [nio-8442-exec-4] c.h.protocol.commons.concurrent.Promise : Awaiting << 5 >> 2020-11-26 13:31:30.494 DEBUG 41819 --- [r for mfc.com] c.h.s.t.t.direct.DirectTcpPacketReader : Received packet SMB2_IOCTL with message id << 5 >> 2020-11-26 13:31:30.495 DEBUG 41819 --- [r for mfc.com] p.SMB2SignatureVerificationPacketHandler : Signature for packet SMB2_IOCTL with message id << 5 >> verified. 2020-11-26 13:31:30.495 DEBUG 41819 --- [r for mfc.com] .h.s.c.p.SMB2CreditGrantingPacketHandler : Server granted us 32 credits for SMB2_IOCTL with message id << 5 >>, now available: 529 credits 2020-11-26 13:31:30.495 DEBUG 41819 --- [r for mfc.com] c.h.protocol.commons.concurrent.Promise : Setting << 5 >> to `SMB2_IOCTL with message id << 5 >>` 2020-11-26 13:31:30.495 INFO 41819 --- [nio-8442-exec-4] c.hierynomus.smbj.paths.DFSPathResolver : Got DFS Referral result: \mfc.com\AZNADFS->\AZCDFS02.MFC.com\AZNADFS(ROOT), [TargetSetEntry[\AZCDFS02.MFC.com\AZNADFS,targetSetBoundary=false], TargetSetEntry[\AZWDFS2.MFC.com\AZNADFS,targetSetBoundary=false], TargetSetEntry[\AZCWVCAETSDFS01.MFC.com\AZNADFS,targetSetBoundary=false], TargetSetEntry[\AZWAPPUSETSDFS1.MFC.com\AZNADFS,targetSetBoundary=false]] 2020-11-26 13:31:30.495 DEBUG 41819 --- [nio-8442-exec-4] c.hierynomus.smbj.connection.Connection : Granted 1 (out of 529) credits to Signed(SMB2_TREE_DISCONNECT with message id << 21 >>) 2020-11-26 13:31:30.495 DEBUG 41819 --- [nio-8442-exec-4] c.h.s.t.tcp.direct.DirectTcpTransport : Writing packet Signed(SMB2_TREE_DISCONNECT with message id << 21 >>) 2020-11-26 13:31:30.495 DEBUG 41819 --- [nio-8442-exec-4] c.h.protocol.commons.concurrent.Promise : Awaiting << 21 >> 2020-11-26 13:31:30.505 DEBUG 41819 --- [r for mfc.com] c.h.s.t.t.direct.DirectTcpPacketReader : Received packet SMB2_TREE_DISCONNECT with message id << 21 >> 2020-11-26 13:31:30.505 DEBUG 41819 --- [r for mfc.com] p.SMB2SignatureVerificationPacketHandler : Signature for packet SMB2_TREE_DISCONNECT with message id << 21 >> verified. 2020-11-26 13:31:30.505 DEBUG 41819 --- [r for mfc.com] .h.s.c.p.SMB2CreditGrantingPacketHandler : Server granted us 2 credits for SMB2_TREE_DISCONNECT with message id << 21 >>, now available: 530 credits 2020-11-26 13:31:30.505 DEBUG 41819 --- [r for mfc.com] c.h.protocol.commons.concurrent.Promise : Setting << 21 >> to `SMB2_TREE_DISCONNECT with message id << 21 >>` 2020-11-26 13:31:30.505 DEBUG 41819 --- [nio-8442-exec-4] com.hierynomus.smbj.session.Session : Notified of TreeDisconnected <<1>> 2020-11-26 13:31:30.505 INFO 41819 --- [nio-8442-exec-4] com.hierynomus.smbj.session.Session : Re-routing the connection to host AZCDFS02.MFC.com 2020-11-26 13:31:30.518 DEBUG 41819 --- [nio-8442-exec-4] c.h.p.commons.socket.ProxySocketFactory : Connecting to AZCDFS02.MFC.com/10.239.250.7:445 2020-11-26 13:31:30.560 DEBUG 41819 --- [nio-8442-exec-4] c.h.s.t.t.direct.DirectTcpPacketReader : Starting PacketReader on thread: Packet Reader for AZCDFS02.MFC.com 2020-11-26 13:31:30.560 DEBUG 41819 --- [nio-8442-exec-4] c.h.s.connection.SMBProtocolNegotiator : Negotiating dialects [SMB_2_0_2, SMB_2_1] 2020-11-26 13:31:30.560 DEBUG 41819 --- [nio-8442-exec-4] c.hierynomus.smbj.connection.Connection : Granted 1 (out of 1) credits to SMB2_NEGOTIATE with message id << 0 >> 2020-11-26 13:31:30.561 DEBUG 41819 --- [nio-8442-exec-4] c.h.s.t.tcp.direct.DirectTcpTransport : Writing packet SMB2_NEGOTIATE with message id << 0 >> 2020-11-26 13:31:30.561 DEBUG 41819 --- [nio-8442-exec-4] c.h.protocol.commons.concurrent.Promise : Awaiting << 0 >> 2020-11-26 13:31:30.651 DEBUG 41819 --- [DFS02.MFC.com] c.h.s.t.t.direct.DirectTcpPacketReader : Received packet SMB2_NEGOTIATE with message id << 0 >> 2020-11-26 13:31:30.651 DEBUG 41819 --- [DFS02.MFC.com] .h.s.c.p.SMB2CreditGrantingPacketHandler : Server granted us 1 credits for SMB2_NEGOTIATE with message id << 0 >>, now available: 1 credits 2020-11-26 13:31:30.651 DEBUG 41819 --- [DFS02.MFC.com] c.h.protocol.commons.concurrent.Promise : Setting << 0 >> to `SMB2_NEGOTIATE with message id << 0 >>` 2020-11-26 13:31:30.651 DEBUG 41819 --- [nio-8442-exec-4] c.h.s.connection.SMBProtocolNegotiator : Negotiated the following connection settings: ConnectionInfo{ serverGuid=5753d5d3-87b2-4db5-a7f0-19971ef965ae, serverName='AZCDFS02.MFC.com', negotiatedProtocol=NegotiatedProtocol{dialect=SMB_2_1, maxTransactSize=8388608, maxReadSize=8388608, maxWriteSize=8388608}, clientGuid=24476b5c-27bd-40d5-a63a-ebec24b1b09c, clientCapabilities=[], serverCapabilities=[SMB2_GLOBAL_CAP_DFS, SMB2_GLOBAL_CAP_LEASING, SMB2_GLOBAL_CAP_LARGE_MTU], clientSecurityMode=0, serverSecurityMode=3, server='com.hierynomus.smbj.server.Server@cc5f43a' } 2020-11-26 13:31:30.651 INFO 41819 --- [nio-8442-exec-4] c.hierynomus.smbj.connection.Connection : Successfully connected to: AZCDFS02.MFC.com 2020-11-26 13:31:30.652 DEBUG 41819 --- [nio-8442-exec-4] c.h.smbj.auth.NtlmAuthenticator : Initialized Authentication of dev using NTLM 2020-11-26 13:31:30.652 DEBUG 41819 --- [nio-8442-exec-4] c.hierynomus.smbj.connection.Connection : Granted 1 (out of 1) credits to SMB2_SESSION_SETUP with message id << 1 >> 2020-11-26 13:31:30.652 DEBUG 41819 --- [nio-8442-exec-4] c.h.s.t.tcp.direct.DirectTcpTransport : Writing packet SMB2_SESSION_SETUP with message id << 1 >> 2020-11-26 13:31:30.652 DEBUG 41819 --- [nio-8442-exec-4] c.h.protocol.commons.concurrent.Promise : Awaiting << 1 >> 2020-11-26 13:31:30.680 DEBUG 41819 --- [DFS02.MFC.com] c.h.s.t.t.direct.DirectTcpPacketReader : Received packet SMB2_SESSION_SETUP with message id << 1 >> 2020-11-26 13:31:30.680 DEBUG 41819 --- [DFS02.MFC.com] .h.s.c.p.SMB2CreditGrantingPacketHandler : Server granted us 1 credits for SMB2_SESSION_SETUP with message id << 1 >>, now available: 1 credits 2020-11-26 13:31:30.680 DEBUG 41819 --- [DFS02.MFC.com] c.h.protocol.commons.concurrent.Promise : Setting << 1 >> to `SMB2_SESSION_SETUP with message id << 1 >>` 2020-11-26 13:31:30.680 DEBUG 41819 --- [nio-8442-exec-4] c.h.smbj.connection.SMBSessionBuilder : More processing required for authentication of dev using com.hierynomus.smbj.auth.NtlmAuthenticator@26b02cd5 2020-11-26 13:31:30.680 DEBUG 41819 --- [nio-8442-exec-4] c.h.smbj.auth.NtlmAuthenticator : Received token: a1 82 01 00 30 81 fd a0 03 0a 01 01 a1 0c 06 0a 2b 06 01 04 01 82 37 02 02 0a a2 81 e7 04 81 e4 4e 54 4c 4d 53 53 50 00 02 00 00 00 0a 00 0a 00 38 00 00 00 15 82 89 e2 3f e4 7b 7d 48 3a 38 4c 00 00 00 00 00 00 00 00 a2 00 a2 00 42 00 00 00 0a 00 39 38 00 00 00 0f 4d 00 46 00 43 00 47 00 44 00 02 00 0a 00 4d 00 46 00 43 00 47 00 44 00 01 00 1e 00 41 00 5a 00 43 00 57 00 56 00 43 00 41 00 45 00 54 00 53 00 44 00 46 00 53 00 30 00 32 00 04 00 12 00 4d 00 46 00 43 00 47 00 44 00 2e 00 63 00 6f 00 6d 00 03 00 32 00 41 00 5a 00 43 00 57 00 56 00 43 00 41 00 45 00 54 00 53 00 44 00 46 00 53 00 30 00 32 00 2e 00 4d 00 46 00 43 00 47 00 44 00 2e 00 63 00 6f 00 6d 00 05 00 12 00 4d 00 46 00 43 00 47 00 44 00 2e 00 63 00 6f 00 6d 00 07 00 08 00 83 55 15 5c 22 c4 d6 01 00 00 00 00 2020-11-26 13:31:30.680 DEBUG 41819 --- [nio-8442-exec-4] com.hierynomus.asn1.ASN1InputStream : Read ASN.1 object: ASN1TaggedObject[ASN1Tag[CONTEXT_SPECIFIC,CONSTRUCTED,1],<unknown>] 2020-11-26 13:31:30.680 DEBUG 41819 --- [nio-8442-exec-4] com.hierynomus.asn1.ASN1InputStream : Read ASN.1 object: ASN1TaggedObject[ASN1Tag[CONTEXT_SPECIFIC,CONSTRUCTED,0],<unknown>] 2020-11-26 13:31:30.680 DEBUG 41819 --- [nio-8442-exec-4] com.hierynomus.asn1.ASN1InputStream : Read ASN.1 object: ASN1TaggedObject[ASN1Tag[CONTEXT_SPECIFIC,CONSTRUCTED,1],<unknown>] 2020-11-26 13:31:30.680 DEBUG 41819 --- [nio-8442-exec-4] com.hierynomus.asn1.ASN1InputStream : Read ASN.1 object: ASN1TaggedObject[ASN1Tag[CONTEXT_SPECIFIC,CONSTRUCTED,2],<unknown>] 2020-11-26 13:31:30.680 DEBUG 41819 --- [nio-8442-exec-4] com.hierynomus.asn1.ASN1InputStream : Read ASN.1 object: ASN1Sequence[[ASN1TaggedObject[ASN1Tag[CONTEXT_SPECIFIC,CONSTRUCTED,0],<unknown>], ASN1TaggedObject[ASN1Tag[CONTEXT_SPECIFIC,CONSTRUCTED,1],<unknown>], ASN1TaggedObject[ASN1Tag[CONTEXT_SPECIFIC,CONSTRUCTED,2],<unknown>]]] 2020-11-26 13:31:30.681 DEBUG 41819 --- [nio-8442-exec-4] com.hierynomus.asn1.ASN1InputStream : Read ASN.1 object: ASN1Enumerated[1] 2020-11-26 13:31:30.681 DEBUG 41819 --- [nio-8442-exec-4] com.hierynomus.asn1.ASN1InputStream : Read ASN.1 object: ASN1ObjectIdentifier[1.3.6.1.4.1.311.2.2.10] 2020-11-26 13:31:30.681 DEBUG 41819 --- [nio-8442-exec-4] com.hierynomus.asn1.ASN1InputStream : Read ASN.1 object: ASN1OctetString[[78, 84, 76, 77, 83, 83, 80, 0, 2, 0, 0, 0, 10, 0, 10, 0, 56, 0, 0, 0, 21, -126, -119, -30, 63, -28, 123, 125, 72, 58, 56, 76, 0, 0, 0, 0, 0, 0, 0, 0, -94, 0, -94, 0, 66, 0, 0, 0, 10, 0, 57, 56, 0, 0, 0, 15, 77, 0, 70, 0, 67, 0, 71, 0, 68, 0, 2, 0, 10, 0, 77, 0, 70, 0, 67, 0, 71, 0, 68, 0, 1, 0, 30, 0, 65, 0, 90, 0, 67, 0, 87, 0, 86, 0, 67, 0, 65, 0, 69, 0, 84, 0, 83, 0, 68, 0, 70, 0, 83, 0, 48, 0, 50, 0, 4, 0, 18, 0, 77, 0, 70, 0, 67, 0, 71, 0, 68, 0, 46, 0, 99, 0, 111, 0, 109, 0, 3, 0, 50, 0, 65, 0, 90, 0, 67, 0, 87, 0, 86, 0, 67, 0, 65, 0, 69, 0, 84, 0, 83, 0, 68, 0, 70, 0, 83, 0, 48, 0, 50, 0, 46, 0, 77, 0, 70, 0, 67, 0, 71, 0, 68, 0, 46, 0, 99, 0, 111, 0, 109, 0, 5, 0, 18, 0, 77, 0, 70, 0, 67, 0, 71, 0, 68, 0, 46, 0, 99, 0, 111, 0, 109, 0, 7, 0, 8, 0, -125, 85, 21, 92, 34, -60, -42, 1, 0, 0, 0, 0]] 2020-11-26 13:31:30.681 DEBUG 41819 --- [nio-8442-exec-4] c.h.ntlm.messages.NtlmChallenge : Windows version = WindowsVersion[WINDOWS_MAJOR_VERSION_10, WINDOWS_MINOR_VERSION_0, 14393, NTLMSSP_REVISION_W2K3] 2020-11-26 13:31:30.681 DEBUG 41819 --- [nio-8442-exec-4] c.h.smbj.auth.NtlmAuthenticator : Received NTLM challenge from: MFC 2020-11-26 13:31:30.682 DEBUG 41819 --- [nio-8442-exec-4] c.hierynomus.smbj.connection.Connection : Granted 1 (out of 1) credits to SMB2_SESSION_SETUP with message id << 2 >> 2020-11-26 13:31:30.682 DEBUG 41819 --- [nio-8442-exec-4] c.h.s.t.tcp.direct.DirectTcpTransport : Writing packet SMB2_SESSION_SETUP with message id << 2 >> 2020-11-26 13:31:30.683 DEBUG 41819 --- [nio-8442-exec-4] c.h.protocol.commons.concurrent.Promise : Awaiting << 2 >> 2020-11-26 13:31:30.832 DEBUG 41819 --- [DFS02.MFC.com] c.h.s.t.t.direct.DirectTcpPacketReader : Received packet SMB2_SESSION_SETUP with message id << 2 >> 2020-11-26 13:31:30.833 DEBUG 41819 --- [DFS02.MFC.com] .h.s.c.p.SMB2CreditGrantingPacketHandler : Server granted us 511 credits for SMB2_SESSION_SETUP with message id << 2 >>, now available: 511 credits 2020-11-26 13:31:30.833 DEBUG 41819 --- [DFS02.MFC.com] c.h.protocol.commons.concurrent.Promise : Setting << 2 >> to `SMB2_SESSION_SETUP with message id << 2 >>` 2020-11-26 13:31:30.833 INFO 41819 --- [nio-8442-exec-4] c.h.smbj.connection.SMBSessionBuilder : Successfully authenticated dev on AZCDFS02.MFC.com, session is 145177880559653 2020-11-26 13:31:30.833 INFO 41819 --- [nio-8442-exec-4] com.hierynomus.smbj.session.Session : Connecting to \\AZCDFS02.MFC.com\AZNADFS on session 145177880559653 2020-11-26 13:31:30.833 DEBUG 41819 --- [nio-8442-exec-4] c.hierynomus.smbj.connection.Connection : Granted 1 (out of 511) credits to Signed(SMB2_TREE_CONNECT with message id << 3 >>) 2020-11-26 13:31:30.833 DEBUG 41819 --- [nio-8442-exec-4] c.h.s.t.tcp.direct.DirectTcpTransport : Writing packet Signed(SMB2_TREE_CONNECT with message id << 3 >>) 2020-11-26 13:31:30.834 DEBUG 41819 --- [nio-8442-exec-4] c.h.protocol.commons.concurrent.Promise : Awaiting << 3 >> 2020-11-26 13:31:30.861 DEBUG 41819 --- [DFS02.MFC.com] c.h.s.t.t.direct.DirectTcpPacketReader : Received packet SMB2_TREE_CONNECT with message id << 3 >> 2020-11-26 13:31:30.861 DEBUG 41819 --- [DFS02.MFC.com] p.SMB2SignatureVerificationPacketHandler : Signature for packet SMB2_TREE_CONNECT with message id << 3 >> verified. 2020-11-26 13:31:30.861 DEBUG 41819 --- [DFS02.MFC.com] .h.s.c.p.SMB2CreditGrantingPacketHandler : Server granted us 2 credits for SMB2_TREE_CONNECT with message id << 3 >>, now available: 512 credits 2020-11-26 13:31:30.861 DEBUG 41819 --- [DFS02.MFC.com] c.h.protocol.commons.concurrent.Promise : Setting << 3 >> to `SMB2_TREE_CONNECT with message id << 3 >>` 2020-11-26 13:31:30.861 INFO 41819 --- [nio-8442-exec-4] c.hierynomus.smbj.paths.DFSPathResolver : Starting DFS resolution for \\AZCDFS02.MFC.com\AZNADFS\can\G-Dev\MDOM 2020-11-26 13:31:30.861 INFO 41819 --- [nio-8442-exec-4] com.hierynomus.smbj.session.Session : Connecting to \\AZCDFS02.MFC.com\IPC$ on session 145177880559653 2020-11-26 13:31:30.861 DEBUG 41819 --- [nio-8442-exec-4] c.hierynomus.smbj.connection.Connection : Granted 1 (out of 512) credits to Signed(SMB2_TREE_CONNECT with message id << 4 >>) 2020-11-26 13:31:30.861 DEBUG 41819 --- [nio-8442-exec-4] c.h.s.t.tcp.direct.DirectTcpTransport : Writing packet Signed(SMB2_TREE_CONNECT with message id << 4 >>) 2020-11-26 13:31:30.862 DEBUG 41819 --- [nio-8442-exec-4] c.h.protocol.commons.concurrent.Promise : Awaiting << 4 >> 2020-11-26 13:31:31.014 DEBUG 41819 --- [DFS02.MFC.com] c.h.s.t.t.direct.DirectTcpPacketReader : Received packet SMB2_TREE_CONNECT with message id << 4 >> 2020-11-26 13:31:31.015 DEBUG 41819 --- [DFS02.MFC.com] p.SMB2SignatureVerificationPacketHandler : Signature for packet SMB2_TREE_CONNECT with message id << 4 >> verified. 2020-11-26 13:31:31.015 DEBUG 41819 --- [DFS02.MFC.com] .h.s.c.p.SMB2CreditGrantingPacketHandler : Server granted us 2 credits for SMB2_TREE_CONNECT with message id << 4 >>, now available: 513 credits 2020-11-26 13:31:31.015 DEBUG 41819 --- [DFS02.MFC.com] c.h.protocol.commons.concurrent.Promise : Setting << 4 >> to `SMB2_TREE_CONNECT with message id << 4 >>` 2020-11-26 13:31:31.015 DEBUG 41819 --- [nio-8442-exec-4] c.hierynomus.smbj.connection.Connection : Granted 16 (out of 513) credits to Signed(SMB2_IOCTL with message id << 5 >>) 2020-11-26 13:31:31.015 DEBUG 41819 --- [nio-8442-exec-4] c.h.s.t.tcp.direct.DirectTcpTransport : Writing packet Signed(SMB2_IOCTL with message id << 5 >>) 2020-11-26 13:31:31.015 DEBUG 41819 --- [nio-8442-exec-4] c.h.protocol.commons.concurrent.Promise : Awaiting << 5 >> 2020-11-26 13:31:31.057 DEBUG 41819 --- [DFS02.MFC.com] c.h.s.t.t.direct.DirectTcpPacketReader : Received packet SMB2_IOCTL with message id << 5 >> 2020-11-26 13:31:31.057 DEBUG 41819 --- [DFS02.MFC.com] p.SMB2SignatureVerificationPacketHandler : Signature for packet SMB2_IOCTL with message id << 5 >> verified. 2020-11-26 13:31:31.057 DEBUG 41819 --- [DFS02.MFC.com] .h.s.c.p.SMB2CreditGrantingPacketHandler : Server granted us 32 credits for SMB2_IOCTL with message id << 5 >>, now available: 529 credits 2020-11-26 13:31:31.057 DEBUG 41819 --- [DFS02.MFC.com] c.h.protocol.commons.concurrent.Promise : Setting << 5 >> to `SMB2_IOCTL with message id << 5 >>` 2020-11-26 13:31:31.057 INFO 41819 --- [nio-8442-exec-4] c.hierynomus.smbj.paths.DFSPathResolver : Got DFS Referral result: \AZCDFS02.MFC.com\AZNADFS\can\G-Dev->\AZCNAS01.mfc.com\G-Dev(LINK), [TargetSetEntry[\AZCNAS01.mfc.com\G-Dev,targetSetBoundary=false]] 2020-11-26 13:31:31.057 DEBUG 41819 --- [nio-8442-exec-4] c.hierynomus.smbj.connection.Connection : Granted 1 (out of 529) credits to Signed(SMB2_TREE_DISCONNECT with message id << 21 >>) 2020-11-26 13:31:31.057 DEBUG 41819 --- [nio-8442-exec-4] c.h.s.t.tcp.direct.DirectTcpTransport : Writing packet Signed(SMB2_TREE_DISCONNECT with message id << 21 >>) 2020-11-26 13:31:31.057 DEBUG 41819 --- [nio-8442-exec-4] c.h.protocol.commons.concurrent.Promise : Awaiting << 21 >> 2020-11-26 13:31:31.196 DEBUG 41819 --- [DFS02.MFC.com] c.h.s.t.t.direct.DirectTcpPacketReader : Received packet SMB2_TREE_DISCONNECT with message id << 21 >> 2020-11-26 13:31:31.196 DEBUG 41819 --- [DFS02.MFC.com] p.SMB2SignatureVerificationPacketHandler : Signature for packet SMB2_TREE_DISCONNECT with message id << 21 >> verified. 2020-11-26 13:31:31.196 DEBUG 41819 --- [DFS02.MFC.com] .h.s.c.p.SMB2CreditGrantingPacketHandler : Server granted us 2 credits for SMB2_TREE_DISCONNECT with message id << 21 >>, now available: 530 credits 2020-11-26 13:31:31.196 DEBUG 41819 --- [DFS02.MFC.com] c.h.protocol.commons.concurrent.Promise : Setting << 21 >> to `SMB2_TREE_DISCONNECT with message id << 21 >>` 2020-11-26 13:31:31.197 DEBUG 41819 --- [nio-8442-exec-4] com.hierynomus.smbj.session.Session : Notified of TreeDisconnected <<5>> 2020-11-26 13:31:31.199 DEBUG 41819 --- [nio-8442-exec-4] c.h.p.commons.socket.ProxySocketFactory : Connecting to AZCNAS01.mfc.com/10.237.56.17:445 2020-11-26 13:31:31.218 DEBUG 41819 --- [nio-8442-exec-4] c.h.s.t.t.direct.DirectTcpPacketReader : Starting PacketReader on thread: Packet Reader for AZCNAS01.mfc.com 2020-11-26 13:31:31.219 DEBUG 41819 --- [nio-8442-exec-4] c.h.s.connection.SMBProtocolNegotiator : Negotiating dialects [SMB_2_0_2, SMB_2_1] 2020-11-26 13:31:31.219 DEBUG 41819 --- [nio-8442-exec-4] c.hierynomus.smbj.connection.Connection : Granted 1 (out of 1) credits to SMB2_NEGOTIATE with message id << 0 >> 2020-11-26 13:31:31.219 DEBUG 41819 --- [nio-8442-exec-4] c.h.s.t.tcp.direct.DirectTcpTransport : Writing packet SMB2_NEGOTIATE with message id << 0 >> 2020-11-26 13:31:31.219 DEBUG 41819 --- [nio-8442-exec-4] c.h.protocol.commons.concurrent.Promise : Awaiting << 0 >> 2020-11-26 13:31:31.237 DEBUG 41819 --- [NAS01.mfc.com] c.h.s.t.t.direct.DirectTcpPacketReader : Received packet SMB2_NEGOTIATE with message id << 0 >> 2020-11-26 13:31:31.237 DEBUG 41819 --- [NAS01.mfc.com] .h.s.c.p.SMB2CreditGrantingPacketHandler : Server granted us 1 credits for SMB2_NEGOTIATE with message id << 0 >>, now available: 1 credits 2020-11-26 13:31:31.237 DEBUG 41819 --- [NAS01.mfc.com] c.h.protocol.commons.concurrent.Promise : Setting << 0 >> to `SMB2_NEGOTIATE with message id << 0 >>` 2020-11-26 13:31:31.237 DEBUG 41819 --- [nio-8442-exec-4] c.h.s.connection.SMBProtocolNegotiator : Negotiated the following connection settings: ConnectionInfo{ serverGuid=b1081fb2-81a8-470d-a7dc-e29a677b0ea9, serverName='AZCNAS01.mfc.com', negotiatedProtocol=NegotiatedProtocol{dialect=SMB_2_1, maxTransactSize=65536, maxReadSize=65536, maxWriteSize=65536}, clientGuid=24476b5c-27bd-40d5-a63a-ebec24b1b09c, clientCapabilities=[], serverCapabilities=[SMB2_GLOBAL_CAP_DFS, SMB2_GLOBAL_CAP_LEASING], clientSecurityMode=0, serverSecurityMode=1, server='com.hierynomus.smbj.server.Server@10b277f2' } 2020-11-26 13:31:31.237 INFO 41819 --- [nio-8442-exec-4] c.hierynomus.smbj.connection.Connection : Successfully connected to: AZCNAS01.mfc.com 2020-11-26 13:31:31.237 DEBUG 41819 --- [nio-8442-exec-4] c.h.smbj.auth.NtlmAuthenticator : Initialized Authentication of dev using NTLM 2020-11-26 13:31:31.238 DEBUG 41819 --- [nio-8442-exec-4] c.hierynomus.smbj.connection.Connection : Granted 1 (out of 1) credits to SMB2_SESSION_SETUP with message id << 1 >> 2020-11-26 13:31:31.238 DEBUG 41819 --- [nio-8442-exec-4] c.h.s.t.tcp.direct.DirectTcpTransport : Writing packet SMB2_SESSION_SETUP with message id << 1 >> 2020-11-26 13:31:31.238 DEBUG 41819 --- [nio-8442-exec-4] c.h.protocol.commons.concurrent.Promise : Awaiting << 1 >> 2020-11-26 13:31:31.384 DEBUG 41819 --- [NAS01.mfc.com] c.h.s.t.t.direct.DirectTcpPacketReader : Received packet SMB2_SESSION_SETUP with message id << 1 >> 2020-11-26 13:31:31.385 DEBUG 41819 --- [NAS01.mfc.com] .h.s.c.p.SMB2CreditGrantingPacketHandler : Server granted us 128 credits for SMB2_SESSION_SETUP with message id << 1 >>, now available: 128 credits 2020-11-26 13:31:31.385 DEBUG 41819 --- [NAS01.mfc.com] c.h.protocol.commons.concurrent.Promise : Setting << 1 >> to `SMB2_SESSION_SETUP with message id << 1 >>` 2020-11-26 13:31:31.385 DEBUG 41819 --- [nio-8442-exec-4] c.h.smbj.connection.SMBSessionBuilder : More processing required for authentication of dev using com.hierynomus.smbj.auth.NtlmAuthenticator@6f0cfb92 2020-11-26 13:31:31.385 DEBUG 41819 --- [nio-8442-exec-4] c.h.smbj.auth.NtlmAuthenticator : Received token: a1 82 00 d3 30 82 00 cf a0 82 00 03 0a 01 01 a1 82 00 0e 06 82 00 0a 2b 06 01 04 01 82 37 02 02 0a a2 82 00 b2 04 82 00 ae 4e 54 4c 4d 53 53 50 00 02 00 00 00 0a 00 0a 00 30 00 00 00 15 82 89 60 ef 53 cf a0 e3 be c9 e3 00 00 00 00 00 00 00 00 74 00 74 00 3a 00 00 00 4d 00 46 00 43 00 47 00 44 00 01 00 18 00 41 00 5a 00 43 00 57 00 56 00 43 00 41 00 4e 00 41 00 53 00 30 00 31 00 02 00 0a 00 4d 00 46 00 43 00 47 00 44 00 03 00 2c 00 41 00 5a 00 43 00 57 00 56 00 43 00 41 00 4e 00 41 00 53 00 30 00 31 00 2e 00 4d 00 46 00 43 00 47 00 44 00 2e 00 43 00 4f 00 4d 00 04 00 12 00 4d 00 46 00 43 00 47 00 44 00 2e 00 43 00 4f 00 4d 00 00 00 00 00 2020-11-26 13:31:31.385 DEBUG 41819 --- [nio-8442-exec-4] com.hierynomus.asn1.ASN1InputStream : Read ASN.1 object: ASN1TaggedObject[ASN1Tag[CONTEXT_SPECIFIC,CONSTRUCTED,1],<unknown>] 2020-11-26 13:31:31.385 DEBUG 41819 --- [nio-8442-exec-4] com.hierynomus.asn1.ASN1InputStream : Read ASN.1 object: ASN1TaggedObject[ASN1Tag[CONTEXT_SPECIFIC,CONSTRUCTED,0],<unknown>] 2020-11-26 13:31:31.385 DEBUG 41819 --- [nio-8442-exec-4] com.hierynomus.asn1.ASN1InputStream : Read ASN.1 object: ASN1TaggedObject[ASN1Tag[CONTEXT_SPECIFIC,CONSTRUCTED,1],<unknown>] 2020-11-26 13:31:31.385 DEBUG 41819 --- [nio-8442-exec-4] com.hierynomus.asn1.ASN1InputStream : Read ASN.1 object: ASN1TaggedObject[ASN1Tag[CONTEXT_SPECIFIC,CONSTRUCTED,2],<unknown>] 2020-11-26 13:31:31.385 DEBUG 41819 --- [nio-8442-exec-4] com.hierynomus.asn1.ASN1InputStream : Read ASN.1 object: ASN1Sequence[[ASN1TaggedObject[ASN1Tag[CONTEXT_SPECIFIC,CONSTRUCTED,0],<unknown>], ASN1TaggedObject[ASN1Tag[CONTEXT_SPECIFIC,CONSTRUCTED,1],<unknown>], ASN1TaggedObject[ASN1Tag[CONTEXT_SPECIFIC,CONSTRUCTED,2],<unknown>]]] 2020-11-26 13:31:31.385 DEBUG 41819 --- [nio-8442-exec-4] com.hierynomus.asn1.ASN1InputStream : Read ASN.1 object: ASN1Enumerated[1] 2020-11-26 13:31:31.385 DEBUG 41819 --- [nio-8442-exec-4] com.hierynomus.asn1.ASN1InputStream : Read ASN.1 object: ASN1ObjectIdentifier[1.3.6.1.4.1.311.2.2.10] 2020-11-26 13:31:31.385 DEBUG 41819 --- [nio-8442-exec-4] com.hierynomus.asn1.ASN1InputStream : Read ASN.1 object: ASN1OctetString[[78, 84, 76, 77, 83, 83, 80, 0, 2, 0, 0, 0, 10, 0, 10, 0, 48, 0, 0, 0, 21, -126, -119, 96, -17, 83, -49, -96, -29, -66, -55, -29, 0, 0, 0, 0, 0, 0, 0, 0, 116, 0, 116, 0, 58, 0, 0, 0, 77, 0, 70, 0, 67, 0, 71, 0, 68, 0, 1, 0, 24, 0, 65, 0, 90, 0, 67, 0, 87, 0, 86, 0, 67, 0, 65, 0, 78, 0, 65, 0, 83, 0, 48, 0, 49, 0, 2, 0, 10, 0, 77, 0, 70, 0, 67, 0, 71, 0, 68, 0, 3, 0, 44, 0, 65, 0, 90, 0, 67, 0, 87, 0, 86, 0, 67, 0, 65, 0, 78, 0, 65, 0, 83, 0, 48, 0, 49, 0, 46, 0, 77, 0, 70, 0, 67, 0, 71, 0, 68, 0, 46, 0, 67, 0, 79, 0, 77, 0, 4, 0, 18, 0, 77, 0, 70, 0, 67, 0, 71, 0, 68, 0, 46, 0, 67, 0, 79, 0, 77, 0, 0, 0, 0, 0]] 2020-11-26 13:31:31.385 DEBUG 41819 --- [nio-8442-exec-4] c.h.smbj.auth.NtlmAuthenticator : Received NTLM challenge from: MFC 2020-11-26 13:31:31.386 DEBUG 41819 --- [nio-8442-exec-4] c.hierynomus.smbj.connection.Connection : Granted 1 (out of 128) credits to SMB2_SESSION_SETUP with message id << 2 >> 2020-11-26 13:31:31.386 DEBUG 41819 --- [nio-8442-exec-4] c.h.s.t.tcp.direct.DirectTcpTransport : Writing packet SMB2_SESSION_SETUP with message id << 2 >> 2020-11-26 13:31:31.387 DEBUG 41819 --- [nio-8442-exec-4] c.h.protocol.commons.concurrent.Promise : Awaiting << 2 >> 2020-11-26 13:31:31.528 DEBUG 41819 --- [NAS01.mfc.com] c.h.s.t.t.direct.DirectTcpPacketReader : Received packet SMB2_SESSION_SETUP with message id << 2 >> 2020-11-26 13:31:31.529 DEBUG 41819 --- [NAS01.mfc.com] .h.s.c.p.SMB2CreditGrantingPacketHandler : Server granted us 1 credits for SMB2_SESSION_SETUP with message id << 2 >>, now available: 128 credits 2020-11-26 13:31:31.529 DEBUG 41819 --- [NAS01.mfc.com] c.h.protocol.commons.concurrent.Promise : Setting << 2 >> to `SMB2_SESSION_SETUP with message id << 2 >>` 2020-11-26 13:31:31.529 INFO 41819 --- [nio-8442-exec-4] c.h.smbj.connection.SMBSessionBuilder : Successfully authenticated dev on AZCNAS01.mfc.com, session is -797700083994450341 2020-11-26 13:31:31.529 INFO 41819 --- [nio-8442-exec-4] com.hierynomus.smbj.session.Session : Connecting to \\AZCNAS01.mfc.com\G-Dev on session -797700083994450341 2020-11-26 13:31:31.529 DEBUG 41819 --- [nio-8442-exec-4] c.hierynomus.smbj.connection.Connection : Granted 1 (out of 128) credits to Signed(SMB2_TREE_CONNECT with message id << 3 >>) 2020-11-26 13:31:31.529 DEBUG 41819 --- [nio-8442-exec-4] c.h.s.t.tcp.direct.DirectTcpTransport : Writing packet Signed(SMB2_TREE_CONNECT with message id << 3 >>) 2020-11-26 13:31:31.530 DEBUG 41819 --- [nio-8442-exec-4] c.h.protocol.commons.concurrent.Promise : Awaiting << 3 >> 2020-11-26 13:31:31.546 DEBUG 41819 --- [NAS01.mfc.com] c.h.s.t.t.direct.DirectTcpPacketReader : Received packet SMB2_TREE_CONNECT with message id << 3 >> 2020-11-26 13:31:31.546 DEBUG 41819 --- [NAS01.mfc.com] p.SMB2SignatureVerificationPacketHandler : Signature for packet SMB2_TREE_CONNECT with message id << 3 >> verified. 2020-11-26 13:31:31.547 DEBUG 41819 --- [NAS01.mfc.com] .h.s.c.p.SMB2CreditGrantingPacketHandler : Server granted us 1 credits for SMB2_TREE_CONNECT with message id << 3 >>, now available: 128 credits 2020-11-26 13:31:31.547 DEBUG 41819 --- [NAS01.mfc.com] c.h.protocol.commons.concurrent.Promise : Setting << 3 >> to `SMB2_TREE_CONNECT with message id << 3 >>` 2020-11-26 13:31:31.547 INFO 41819 --- [nio-8442-exec-4] c.hierynomus.smbj.paths.DFSPathResolver : Attempting to resolve \\AZCNAS01.mfc.com\G-Dev through DFS 2020-11-26 13:31:31.547 INFO 41819 --- [nio-8442-exec-4] c.hierynomus.smbj.paths.DFSPathResolver : Starting DFS resolution for \\AZCNAS01.mfc.com\G-Dev 2020-11-26 13:31:31.547 INFO 41819 --- [nio-8442-exec-4] com.hierynomus.smbj.session.Session : Connecting to \\AZCNAS01.mfc.com\IPC$ on session -797700083994450341 2020-11-26 13:31:31.547 DEBUG 41819 --- [nio-8442-exec-4] c.hierynomus.smbj.connection.Connection : Granted 1 (out of 128) credits to Signed(SMB2_TREE_CONNECT with message id << 4 >>) 2020-11-26 13:31:31.547 DEBUG 41819 --- [nio-8442-exec-4] c.h.s.t.tcp.direct.DirectTcpTransport : Writing packet Signed(SMB2_TREE_CONNECT with message id << 4 >>) 2020-11-26 13:31:31.547 DEBUG 41819 --- [nio-8442-exec-4] c.h.protocol.commons.concurrent.Promise : Awaiting << 4 >> 2020-11-26 13:31:31.564 DEBUG 41819 --- [NAS01.mfc.com] c.h.s.t.t.direct.DirectTcpPacketReader : Received packet SMB2_TREE_CONNECT with message id << 4 >> 2020-11-26 13:31:31.564 DEBUG 41819 --- [NAS01.mfc.com] p.SMB2SignatureVerificationPacketHandler : Signature for packet SMB2_TREE_CONNECT with message id << 4 >> verified. 2020-11-26 13:31:31.564 DEBUG 41819 --- [NAS01.mfc.com] .h.s.c.p.SMB2CreditGrantingPacketHandler : Server granted us 1 credits for SMB2_TREE_CONNECT with message id << 4 >>, now available: 128 credits 2020-11-26 13:31:31.564 DEBUG 41819 --- [NAS01.mfc.com] c.h.protocol.commons.concurrent.Promise : Setting << 4 >> to `SMB2_TREE_CONNECT with message id << 4 >>` 2020-11-26 13:31:31.565 DEBUG 41819 --- [nio-8442-exec-4] c.hierynomus.smbj.connection.Connection : Granted 1 (out of 128) credits to Signed(SMB2_IOCTL with message id << 5 >>) 2020-11-26 13:31:31.565 DEBUG 41819 --- [nio-8442-exec-4] c.h.s.t.tcp.direct.DirectTcpTransport : Writing packet Signed(SMB2_IOCTL with message id << 5 >>) 2020-11-26 13:31:31.565 DEBUG 41819 --- [nio-8442-exec-4] c.h.protocol.commons.concurrent.Promise : Awaiting << 5 >> 2020-11-26 13:31:31.707 DEBUG 41819 --- [NAS01.mfc.com] c.h.s.t.t.direct.DirectTcpPacketReader : Received packet SMB2_IOCTL with message id << 5 >> 2020-11-26 13:31:31.707 DEBUG 41819 --- [NAS01.mfc.com] p.SMB2SignatureVerificationPacketHandler : Signature for packet SMB2_IOCTL with message id << 5 >> verified. 2020-11-26 13:31:31.707 DEBUG 41819 --- [NAS01.mfc.com] .h.s.c.p.SMB2CreditGrantingPacketHandler : Server granted us 1 credits for SMB2_IOCTL with message id << 5 >>, now available: 128 credits 2020-11-26 13:31:31.707 DEBUG 41819 --- [NAS01.mfc.com] c.h.protocol.commons.concurrent.Promise : Setting << 5 >> to `SMB2_IOCTL with message id << 5 >>` 2020-11-26 13:31:31.707 DEBUG 41819 --- [nio-8442-exec-4] c.hierynomus.smbj.connection.Connection : Granted 1 (out of 128) credits to Signed(SMB2_TREE_DISCONNECT with message id << 6 >>) 2020-11-26 13:31:31.707 DEBUG 41819 --- [nio-8442-exec-4] c.h.s.t.tcp.direct.DirectTcpTransport : Writing packet Signed(SMB2_TREE_DISCONNECT with message id << 6 >>) 2020-11-26 13:31:31.707 DEBUG 41819 --- [nio-8442-exec-4] c.h.protocol.commons.concurrent.Promise : Awaiting << 6 >> 2020-11-26 13:31:31.726 DEBUG 41819 --- [NAS01.mfc.com] c.h.s.t.t.direct.DirectTcpPacketReader : Received packet SMB2_TREE_DISCONNECT with message id << 6 >> 2020-11-26 13:31:31.726 DEBUG 41819 --- [NAS01.mfc.com] p.SMB2SignatureVerificationPacketHandler : Signature for packet SMB2_TREE_DISCONNECT with message id << 6 >> verified. 2020-11-26 13:31:31.726 DEBUG 41819 --- [NAS01.mfc.com] .h.s.c.p.SMB2CreditGrantingPacketHandler : Server granted us 1 credits for SMB2_TREE_DISCONNECT with message id << 6 >>, now available: 128 credits 2020-11-26 13:31:31.726 DEBUG 41819 --- [NAS01.mfc.com] c.h.protocol.commons.concurrent.Promise : Setting << 6 >> to `SMB2_TREE_DISCONNECT with message id << 6 >>` 2020-11-26 13:31:31.726 DEBUG 41819 --- [nio-8442-exec-4] com.hierynomus.smbj.session.Session : Notified of TreeDisconnected <<1>> 2020-11-26 13:31:31.727 DEBUG 41819 --- [nio-8442-exec-4] com.hierynomus.smbj.session.Session : dialect=null, creditCharge=1, creditRequest=0, creditResponse=1, message=SMB2_TREE_CONNECT, messageId=3, asyncId=0, sessionId=-797700083994450341, treeId=0, status=0xc0000022, flags=9, nextCommandOffset=0 Error in directoryReader 2020-11-26 13:31:31.729 INFO 41819 --- [nio-8442-exec-4] com.hierynomus.smbj.session.Session : Logging off session 453501100491569 from host mfc.com 2020-11-26 13:31:31.729 INFO 41819 --- [nio-8442-exec-4] com.hierynomus.smbj.session.Session : Logging off nested session 145177880559653 for session 453501100491569 2020-11-26 13:31:31.729 INFO 41819 --- [nio-8442-exec-4] com.hierynomus.smbj.session.Session : Logging off session 145177880559653 from host AZCDFS02.MFC.com 2020-11-26 13:31:31.729 DEBUG 41819 --- [nio-8442-exec-4] c.hierynomus.smbj.connection.Connection : Granted 1 (out of 530) credits to Signed(SMB2_TREE_DISCONNECT with message id << 22 >>) 2020-11-26 13:31:31.729 DEBUG 41819 --- [nio-8442-exec-4] c.h.s.t.tcp.direct.DirectTcpTransport : Writing packet Signed(SMB2_TREE_DISCONNECT with message id << 22 >>) 2020-11-26 13:31:31.729 DEBUG 41819 --- [nio-8442-exec-4] c.h.protocol.commons.concurrent.Promise : Awaiting << 22 >> com.hierynomus.mssmb2.SMBApiException: STATUS_ACCESS_DENIED (0xc0000022): Could not connect to \\AZCNAS01.mfc.com\G-Dev at com.hierynomus.smbj.session.Session.connectTree(Session.java:150) at com.hierynomus.smbj.session.Session.connectShare(Session.java:112) at com.hierynomus.smbj.share.DiskShare.rerouteIfNeeded(DiskShare.java:126) at com.hierynomus.smbj.share.DiskShare.access$000(DiskShare.java:55) at com.hierynomus.smbj.share.DiskShare$2.apply(DiskShare.java:108) at com.hierynomus.smbj.share.DiskShare$2.apply(DiskShare.java:105) at com.hierynomus.smbj.paths.DFSPathResolver.step8(DFSPathResolver.java:305) at com.hierynomus.smbj.paths.DFSPathResolver.step3(DFSPathResolver.java:168) at com.hierynomus.smbj.paths.DFSPathResolver.step4(DFSPathResolver.java:200) at com.hierynomus.smbj.paths.DFSPathResolver.step7(DFSPathResolver.java:285) at com.hierynomus.smbj.paths.DFSPathResolver.step6(DFSPathResolver.java:263) at com.hierynomus.smbj.paths.DFSPathResolver.step5(DFSPathResolver.java:222) at com.hierynomus.smbj.paths.DFSPathResolver.step2(DFSPathResolver.java:141) at com.hierynomus.smbj.paths.DFSPathResolver.step1(DFSPathResolver.java:124) at com.hierynomus.smbj.paths.DFSPathResolver.start(DFSPathResolver.java:112) at com.hierynomus.smbj.paths.DFSPathResolver.resolve(DFSPathResolver.java:100) at com.hierynomus.smbj.share.DiskShare.resolveAndCreateFile(DiskShare.java:105) at com.hierynomus.smbj.share.DiskShare.open(DiskShare.java:65) at com.hierynomus.smbj.share.DiskShare.openDirectory(DiskShare.java:151) at com.hierynomus.smbj.share.DiskShare.list(DiskShare.java:258) at com.hierynomus.smbj.share.DiskShare.list(DiskShare.java:231) at com.m.gees.smb.library.services.UNCService.directoryReader(UNCService.java:147) at com.m.gees.smb.controllers.SambaDirectoryClientController.readDirectory(SambaDirectoryClientController.java:72) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at org.springframework.web.method.support.InvocableHandlerMethod.doInvoke(InvocableHandlerMethod.java:205) at org.springframework.web.method.support.InvocableHandlerMethod.invokeForRequest(InvocableHandlerMethod.java:133) at org.springframework.web.servlet.mvc.method.annotation.ServletInvocableHandlerMethod.invokeAndHandle(ServletInvocableHandlerMethod.java:97) at org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerAdapter.invokeHandlerMethod(RequestMappingHandlerAdapter.java:827) at org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerAdapter.handleInternal(RequestMappingHandlerAdapter.java:738) at org.springframework.web.servlet.mvc.method.AbstractHandlerMethodAdapter.handle(AbstractHandlerMethodAdapter.java:85) at org.springframework.web.servlet.DispatcherServlet.doDispatch(DispatcherServlet.java:967) at org.springframework.web.servlet.DispatcherServlet.doService(DispatcherServlet.java:901) at org.springframework.web.servlet.FrameworkServlet.processRequest(FrameworkServlet.java:970) at org.springframework.web.servlet.FrameworkServlet.doPost(FrameworkServlet.java:872) at javax.servlet.http.HttpServlet.service(HttpServlet.java:661) at org.springframework.web.servlet.FrameworkServlet.service(FrameworkServlet.java:846) at javax.servlet.http.HttpServlet.service(HttpServlet.java:742) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:231) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:166) at org.apache.tomcat.websocket.server.WsFilter.doFilter(WsFilter.java:52) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:193) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:166) at org.springframework.boot.web.filter.ApplicationContextHeaderFilter.doFilterInternal(ApplicationContextHeaderFilter.java:55) at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:107) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:193) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:166) at org.springframework.boot.actuate.trace.WebRequestTraceFilter.doFilterInternal(WebRequestTraceFilter.java:110) at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:107) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:193) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:166) at org.springframework.security.web.FilterChainProxy.doFilterInternal(FilterChainProxy.java:208) at org.springframework.security.web.FilterChainProxy.doFilter(FilterChainProxy.java:177) at org.springframework.web.filter.DelegatingFilterProxy.invokeDelegate(DelegatingFilterProxy.java:347) at org.springframework.web.filter.DelegatingFilterProxy.doFilter(DelegatingFilterProxy.java:263) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:193) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:166) at org.springframework.web.filter.RequestContextFilter.doFilterInternal(RequestContextFilter.java:99) at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:107) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:193) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:166) at org.springframework.web.filter.HttpPutFormContentFilter.doFilterInternal(HttpPutFormContentFilter.java:108) at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:107) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:193) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:166) at org.springframework.web.filter.HiddenHttpMethodFilter.doFilterInternal(HiddenHttpMethodFilter.java:81) at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:107) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:193) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:166) at org.springframework.web.filter.CharacterEncodingFilter.doFilterInternal(CharacterEncodingFilter.java:197) at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:107) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:193) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:166) at org.springframework.boot.actuate.autoconfigure.MetricsFilter.doFilterInternal(MetricsFilter.java:106) at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:107) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:193) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:166) at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:199) at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:96) at org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:504) at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:140) at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:81) at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:87) at org.apache.catalina.valves.RemoteIpValve.invoke(RemoteIpValve.java:677) at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:342) at org.apache.coyote.http11.Http11Processor.service(Http11Processor.java:803) at org.apache.coyote.AbstractProcessorLight.process(AbstractProcessorLight.java:66) at org.apache.coyote.AbstractProtocol$ConnectionHandler.process(AbstractProtocol.java:790) at org.apache.tomcat.util.net.NioEndpoint$SocketProcessor.doRun(NioEndpoint.java:1459) at org.apache.tomcat.util.net.SocketProcessorBase.run(SocketProcessorBase.java:49) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61) at java.lang.Thread.run(Thread.java:748) 2020-11-26 13:31:31.888 DEBUG 41819 --- [DFS02.MFC.com] c.h.s.t.t.direct.DirectTcpPacketReader : Received packet SMB2_TREE_DISCONNECT with message id << 22 >> 2020-11-26 13:31:31.888 DEBUG 41819 --- [DFS02.MFC.com] p.SMB2SignatureVerificationPacketHandler : Signature for packet SMB2_TREE_DISCONNECT with message id << 22 >> verified. 2020-11-26 13:31:31.888 DEBUG 41819 --- [DFS02.MFC.com] .h.s.c.p.SMB2CreditGrantingPacketHandler : Server granted us 2 credits for SMB2_TREE_DISCONNECT with message id << 22 >>, now available: 531 credits 2020-11-26 13:31:31.888 DEBUG 41819 --- [DFS02.MFC.com] c.h.protocol.commons.concurrent.Promise : Setting << 22 >> to `SMB2_TREE_DISCONNECT with message id << 22 >>` 2020-11-26 13:31:31.888 DEBUG 41819 --- [nio-8442-exec-4] com.hierynomus.smbj.session.Session : Notified of TreeDisconnected <<1>> 2020-11-26 13:31:31.888 INFO 41819 --- [nio-8442-exec-4] com.hierynomus.smbj.session.Session : Logging off nested session -797700083994450341 for session 145177880559653 2020-11-26 13:31:31.888 INFO 41819 --- [nio-8442-exec-4] com.hierynomus.smbj.session.Session : Logging off session -797700083994450341 from host AZCNAS01.mfc.com 2020-11-26 13:31:31.888 DEBUG 41819 --- [nio-8442-exec-4] c.hierynomus.smbj.connection.Connection : Granted 1 (out of 128) credits to Signed(SMB2_LOGOFF with message id << 7 >>) 2020-11-26 13:31:31.888 DEBUG 41819 --- [nio-8442-exec-4] c.h.s.t.tcp.direct.DirectTcpTransport : Writing packet Signed(SMB2_LOGOFF with message id << 7 >>) 2020-11-26 13:31:31.888 DEBUG 41819 --- [nio-8442-exec-4] c.h.protocol.commons.concurrent.Promise : Awaiting << 7 >> 2020-11-26 13:31:31.911 DEBUG 41819 --- [NAS01.mfc.com] c.h.s.t.t.direct.DirectTcpPacketReader : Received packet SMB2_LOGOFF with message id << 7 >> 2020-11-26 13:31:31.911 DEBUG 41819 --- [NAS01.mfc.com] p.SMB2SignatureVerificationPacketHandler : Signature for packet SMB2_LOGOFF with message id << 7 >> verified. 2020-11-26 13:31:31.912 DEBUG 41819 --- [NAS01.mfc.com] .h.s.c.p.SMB2CreditGrantingPacketHandler : Server granted us 1 credits for SMB2_LOGOFF with message id << 7 >>, now available: 128 credits 2020-11-26 13:31:31.912 DEBUG 41819 --- [NAS01.mfc.com] c.h.protocol.commons.concurrent.Promise : Setting << 7 >> to `SMB2_LOGOFF with message id << 7 >>` 2020-11-26 13:31:31.912 DEBUG 41819 --- [nio-8442-exec-4] c.hierynomus.smbj.connection.Connection : Session << -797700083994450341 >> logged off 2020-11-26 13:31:31.912 DEBUG 41819 --- [nio-8442-exec-4] c.hierynomus.smbj.connection.Connection : Session << -797700083994450341 >> logged off 2020-11-26 13:31:31.912 DEBUG 41819 --- [nio-8442-exec-4] c.hierynomus.smbj.connection.Connection : Session << -797700083994450341 >> logged off 2020-11-26 13:31:31.912 DEBUG 41819 --- [nio-8442-exec-4] c.hierynomus.smbj.connection.Connection : Granted 1 (out of 531) credits to Signed(SMB2_LOGOFF with message id << 23 >>) 2020-11-26 13:31:31.912 DEBUG 41819 --- [nio-8442-exec-4] c.h.s.t.tcp.direct.DirectTcpTransport : Writing packet Signed(SMB2_LOGOFF with message id << 23 >>) 2020-11-26 13:31:31.912 DEBUG 41819 --- [nio-8442-exec-4] c.h.protocol.commons.concurrent.Promise : Awaiting << 23 >> 2020-11-26 13:31:32.066 DEBUG 41819 --- [DFS02.MFC.com] c.h.s.t.t.direct.DirectTcpPacketReader : Received packet SMB2_LOGOFF with message id << 23 >> 2020-11-26 13:31:32.066 DEBUG 41819 --- [DFS02.MFC.com] p.SMB2SignatureVerificationPacketHandler : Signature for packet SMB2_LOGOFF with message id << 23 >> verified. 2020-11-26 13:31:32.066 DEBUG 41819 --- [DFS02.MFC.com] .h.s.c.p.SMB2CreditGrantingPacketHandler : Server granted us 2 credits for SMB2_LOGOFF with message id << 23 >>, now available: 532 credits 2020-11-26 13:31:32.066 DEBUG 41819 --- [DFS02.MFC.com] c.h.protocol.commons.concurrent.Promise : Setting << 23 >> to `SMB2_LOGOFF with message id << 23 >>` 2020-11-26 13:31:32.066 DEBUG 41819 --- [nio-8442-exec-4] c.hierynomus.smbj.connection.Connection : Session << 145177880559653 >> logged off 2020-11-26 13:31:32.066 DEBUG 41819 --- [nio-8442-exec-4] c.hierynomus.smbj.connection.Connection : Session << 145177880559653 >> logged off 2020-11-26 13:31:32.066 DEBUG 41819 --- [nio-8442-exec-4] c.hierynomus.smbj.connection.Connection : Session << 145177880559653 >> logged off 2020-11-26 13:31:32.066 DEBUG 41819 --- [nio-8442-exec-4] c.hierynomus.smbj.connection.Connection : Granted 1 (out of 530) credits to Signed(SMB2_LOGOFF with message id << 22 >>) 2020-11-26 13:31:32.066 DEBUG 41819 --- [nio-8442-exec-4] c.h.s.t.tcp.direct.DirectTcpTransport : Writing packet Signed(SMB2_LOGOFF with message id << 22 >>) 2020-11-26 13:31:32.067 DEBUG 41819 --- [nio-8442-exec-4] c.h.protocol.commons.concurrent.Promise : Awaiting << 22 >> 2020-11-26 13:31:32.088 DEBUG 41819 --- [r for mfc.com] c.h.s.t.t.direct.DirectTcpPacketReader : Received packet SMB2_LOGOFF with message id << 22 >> 2020-11-26 13:31:32.089 DEBUG 41819 --- [r for mfc.com] p.SMB2SignatureVerificationPacketHandler : Signature for packet SMB2_LOGOFF with message id << 22 >> verified. 2020-11-26 13:31:32.089 DEBUG 41819 --- [r for mfc.com] .h.s.c.p.SMB2CreditGrantingPacketHandler : Server granted us 2 credits for SMB2_LOGOFF with message id << 22 >>, now available: 531 credits 2020-11-26 13:31:32.089 DEBUG 41819 --- [r for mfc.com] c.h.protocol.commons.concurrent.Promise : Setting << 22 >> to `SMB2_LOGOFF with message id << 22 >>` 2020-11-26 13:31:32.089 DEBUG 41819 --- [nio-8442-exec-4] c.hierynomus.smbj.connection.Connection : Session << 453501100491569 >> logged off 2020-11-26 13:31:32.089 DEBUG 41819 --- [nio-8442-exec-4] c.hierynomus.smbj.connection.Connection : Session << 453501100491569 >> logged off 2020-11-26 13:31:32.089 DEBUG 41819 --- [nio-8442-exec-4] c.hierynomus.smbj.connection.Connection : Session << 453501100491569 >> logged off 2020-11-26 13:31:32.089 DEBUG 41819 --- [nio-8442-exec-4] c.h.s.t.t.direct.DirectTcpPacketReader : Stopping PacketReader... 2020-11-26 13:31:32.089 INFO 41819 --- [nio-8442-exec-4] c.hierynomus.smbj.connection.Connection : Closed connection to mfc.com 2020-11-26 13:31:32.089 DEBUG 41819 --- [nio-8442-exec-4] com.hierynomus.smbj.SMBClient : Connection to << mfc.com:445 >> closed Closing! 2020-11-26 13:31:32.089 INFO 41819 --- [r for mfc.com] c.h.s.t.t.direct.DirectTcpPacketReader : Thread[Packet Reader for mfc.com,5,main] stopped. 2020-11-26 13:31:32.089 ERROR 41819 --- [nio-8442-exec-4] c.m.g.s.c.SambaDirectoryClientController : Error reading directory: \\mfc.com\AZNADFS\can\G-Dev\MDOM. com.hierynomus.mssmb2.SMBApiException: STATUS_ACCESS_DENIED (0xc0000022): Could not connect to \\AZCNAS01.mfc.com\G-Dev 2020-11-26 13:31:32.090 DEBUG 41819 --- [nio-8442-exec-4] o.s.w.s.m.m.a.HttpEntityMethodProcessor : Written [STATUS_ACCESS_DENIED (0xc0000022): Could not connect to \\AZCNAS01.mfc.com\G-Dev] as "application/json" using [org.springframework.http.converter.StringHttpMessageConverter@21ea996f] 2020-11-26 13:31:32.091 DEBUG 41819 --- [nio-8442-exec-4] o.s.web.servlet.DispatcherServlet : Null ModelAndView returned to DispatcherServlet with name 'dispatcherServlet': assuming HandlerAdapter completed request handling 2020-11-26 13:31:32.091 DEBUG 41819 --- [nio-8442-exec-4] o.s.web.servlet.DispatcherServlet : Successfully completed request 2020-11-26 13:31:32.091 DEBUG 41819 --- [nio-8442-exec-4] o.s.b.w.f.OrderedRequestContextFilter : Cleared thread-bound request context: org.apache.catalina.connector.RequestFacade@22d9663e 2020-11-26 13:31:32.091 DEBUG 41819 --- [nio-8442-exec-4] o.a.tomcat.util.net.SocketWrapperBase : Socket: [org.apache.tomcat.util.net.NioEndpoint$NioSocketWrapper@5f012e42:org.apache.tomcat.util.net.NioChannel@331a8910:java.nio.channels.SocketChannel[connected local=/0:0:0:0:0:0:0:1:8442 remote=/0:0:0:0:0:0:0:1:53459]], Read from buffer: [0] 2020-11-26 13:31:32.091 DEBUG 41819 --- [nio-8442-exec-4] o.apache.coyote.http11.Http11Processor : Socket: [org.apache.tomcat.util.net.NioEndpoint$NioSocketWrapper@5f012e42:org.apache.tomcat.util.net.NioChannel@331a8910:java.nio.channels.SocketChannel[connected local=/0:0:0:0:0:0:0:1:8442 remote=/0:0:0:0:0:0:0:1:53459]], Status in: [OPEN_READ], State out: [OPEN] 2020-11-26 13:32:08.909 INFO 41819 --- [SDFS2.MFC.com] c.h.s.t.t.direct.DirectTcpPacketReader : PacketReader error, got exception. com.hierynomus.protocol.transport.TransportException: java.net.SocketException: Connection reset at com.hierynomus.smbj.transport.tcp.direct.DirectTcpPacketReader.doRead(DirectTcpPacketReader.java:53) ~[smbj-0.1.0-SNAPSHOT.jar:0.1.0-SNAPSHOT] at com.hierynomus.smbj.transport.PacketReader.readPacket(PacketReader.java:70) ~[smbj-0.1.0-SNAPSHOT.jar:0.1.0-SNAPSHOT] at com.hierynomus.smbj.transport.PacketReader.run(PacketReader.java:48) ~[smbj-0.1.0-SNAPSHOT.jar:0.1.0-SNAPSHOT] at java.lang.Thread.run(Thread.java:748) [na:1.8.0_241], Caused by: java.net.SocketException: Connection reset at java.net.SocketInputStream.read(SocketInputStream.java:210) ~[na:1.8.0_241] at java.net.SocketInputStream.read(SocketInputStream.java:141) ~[na:1.8.0_241] at com.hierynomus.smbj.transport.tcp.direct.DirectTcpPacketReader.readFully(DirectTcpPacketReader.java:70) ~[smbj-0.1.0-SNAPSHOT.jar:0.1.0-SNAPSHOT] at com.hierynomus.smbj.transport.tcp.direct.DirectTcpPacketReader.readTcpHeader(DirectTcpPacketReader.java:59) ~[smbj-0.1.0-SNAPSHOT.jar:0.1.0-SNAPSHOT] at com.hierynomus.smbj.transport.tcp.direct.DirectTcpPacketReader.doRead(DirectTcpPacketReader.java:48) ~[smbj-0.1.0-SNAPSHOT.jar:0.1.0-SNAPSHOT] ... 3 common frames omitted 2020-11-26 13:32:08.909 DEBUG 41819 --- [SDFS2.MFC.com] c.h.s.t.t.direct.DirectTcpPacketReader : Stopping PacketReader... 2020-11-26 13:32:08.910 INFO 41819 --- [SDFS2.MFC.com] c.hierynomus.smbj.connection.Connection : Closed connection to AZWDFS2.MFC.com 2020-11-26 13:32:08.910 DEBUG 41819 --- [SDFS2.MFC.com] com.hierynomus.smbj.SMBClient : Connection to << AZWDFS2.MFC.com:445 >> closed 2020-11-26 13:32:09.799 DEBUG 41819 --- [Engine[Tomcat]]] org.apache.catalina.session.ManagerBase : Start expire sessions StandardManager at 1606415529798 sessioncount 0 2020-11-26 13:32:09.799 DEBUG 41819 --- [Engine[Tomcat]]] org.apache.catalina.session.ManagerBase : End expire sessions StandardManager processingTime 1 expired sessions: 0
@hierynomus : Bump^^
Connecting to this DFS using Mac Finder or Windows machine works fine with same credentials, but looks like smbj is trying to authenticate user at root level of the target server.
Error occurs in connection.authenticate(ac)
Stack Trace -