newwdles opened a new issue, #621:
URL: https://github.com/apache/mina-sshd/issues/621
### Version
2.14.0
### Bug description
When trying to upload a file to a remote sftp using Spring integration, it
first tries to check if the remote directory exist.
To do so it calls lstat on the remote directory.
We have a case when the call fails because there is a buffer underflow in
the method org.apache.sshd.sftp.common.SftpHelper#readTime, here's the
stacktrace :
```
org.apache.sshd.common.util.buffer.BufferException: Underflow: requested=8,
available=4
at
org.apache.sshd.common.util.buffer.Buffer.ensureAvailable(Buffer.java:661)
at
org.apache.sshd.common.util.buffer.Buffer.getLong(Buffer.java:310)
at
org.apache.sshd.sftp.common.SftpHelper.readTime(SftpHelper.java:1192)
at
org.apache.sshd.sftp.client.impl.AbstractSftpClient.readAttributes(AbstractSftpClient.java:458)
at
org.apache.sshd.sftp.client.impl.AbstractSftpClient.checkAttributesResponse(AbstractSftpClient.java:331)
at
org.apache.sshd.sftp.client.impl.AbstractSftpClient.checkAttributes(AbstractSftpClient.java:325)
at
org.apache.sshd.sftp.client.impl.AbstractSftpClient.lstat(AbstractSftpClient.java:1010)
```
We use multiple remote sftp (that we don't own) and this is only happening
with one, so i guess it doesn't behave like it should, but is it possible to
just return a null time and log a warn ?
### Actual behavior
There is an exception and we cannot upload the file.
### Expected behavior
If the time returned by the remote sftp is incorrect, set the time to null
but don't fail with an exception.
### Relevant log output
```Shell
2024-10-09T10:12:58.560+02:00 DEBUG 240892 --- [ftp-gateway]
[or-http-epoll-7] o.s.integration.util.SimplePool : Obtained
org.springframework.integration.sftp.session.SftpSession@86e34 from pool.
2024-10-09T10:12:58.560+02:00 DEBUG 240892 --- [ftp-gateway]
[or-http-epoll-7] o.s.integration.util.SimplePool : Received a stale
item org.springframework.integration.sftp.session.SftpSession@86e34, will
attempt to get a new one.
2024-10-09T10:12:58.560+02:00 DEBUG 240892 --- [ftp-gateway]
[or-http-epoll-7] o.s.integration.util.SimplePool : Removing
org.springframework.integration.sftp.session.SftpSession@86e34 from the pool
2024-10-09T10:12:58.560+02:00 DEBUG 240892 --- [ftp-gateway]
[or-http-epoll-7] o.a.sshd.common.io.nio2.Nio2Connector : Connecting to
localhost/127.0.0.1:2102
2024-10-09T10:12:58.560+02:00 DEBUG 240892 --- [ftp-gateway]
[or-http-epoll-7] o.a.sshd.common.io.nio2.Nio2Connector :
setOption(SO_REUSEADDR)[true] from property=Property[socket-reuseaddr](Boolean]
2024-10-09T10:12:58.560+02:00 DEBUG 240892 --- [ftp-gateway]
[resume-thread-3] o.a.sshd.common.io.nio2.Nio2Connector :
connect(localhost/127.0.0.1:2102): waiting for connection (timeout=60000ms)
2024-10-09T10:12:58.560+02:00 DEBUG 240892 --- [ftp-gateway]
[resume-thread-3] o.a.sshd.common.io.nio2.Nio2Session : Creating IoSession
on /127.0.0.1:49884 from localhost/127.0.0.1:2102 via null
2024-10-09T10:12:58.560+02:00 DEBUG 240892 --- [ftp-gateway]
[resume-thread-3] o.a.s.client.session.ClientSessionImpl : Client session
created: Nio2Session[local=/127.0.0.1:49884, remote=localhost/127.0.0.1:2102]
2024-10-09T10:12:58.560+02:00 DEBUG 240892 --- [ftp-gateway]
[resume-thread-3] o.a.s.c.session.ClientUserAuthService :
ClientUserAuthService(ClientSessionImpl[null@localhost/127.0.0.1:2102]) client
methods: [publickey, keyboard-interactive, password]
2024-10-09T10:12:58.560+02:00 DEBUG 240892 --- [ftp-gateway]
[resume-thread-3] o.a.s.c.s.h.SessionTimeoutListener :
sessionCreated(ClientSessionImpl[null@localhost/127.0.0.1:2102]) tracking
2024-10-09T10:12:58.560+02:00 DEBUG 240892 --- [ftp-gateway]
[resume-thread-3] o.a.s.client.session.ClientSessionImpl :
initializeProxyConnector(ClientSessionImpl[null@localhost/127.0.0.1:2102]) no
proxy to initialize
2024-10-09T10:12:58.560+02:00 DEBUG 240892 --- [ftp-gateway]
[resume-thread-3] o.a.s.client.session.ClientSessionImpl :
sendIdentification(ClientSessionImpl[null@localhost/127.0.0.1:2102]):
SSH-2.0-APACHE-SSHD-2.12.1
2024-10-09T10:12:58.560+02:00 DEBUG 240892 --- [ftp-gateway]
[resume-thread-3] o.a.sshd.common.io.nio2.Nio2Session :
writeBuffer(Nio2Session[local=/127.0.0.1:49884,
remote=localhost/127.0.0.1:2102]) writing 28 bytes
2024-10-09T10:12:58.561+02:00 DEBUG 240892 --- [ftp-gateway]
[resume-thread-3] o.a.s.client.session.ClientSessionImpl :
sendKexInit(ClientSessionImpl[null@localhost/127.0.0.1:2102]) Send
SSH_MSG_KEXINIT
2024-10-09T10:12:58.561+02:00 DEBUG 240892 --- [ftp-gateway]
[resume-thread-3] o.a.s.client.session.ClientSessionImpl :
encode(ClientSessionImpl[null@localhost/127.0.0.1:2102]) packet #0 sending
command=20[SSH_MSG_KEXINIT] len=1263
2024-10-09T10:12:58.561+02:00 DEBUG 240892 --- [ftp-gateway]
[resume-thread-3] o.a.sshd.common.io.nio2.Nio2Session :
writeBuffer(Nio2Session[local=/127.0.0.1:49884,
remote=localhost/127.0.0.1:2102]) writing 1280 bytes
2024-10-09T10:12:58.561+02:00 DEBUG 240892 --- [ftp-gateway]
[resume-thread-3] org.apache.sshd.client.SshClient :
setupDefaultSessionIdentities(ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102])
key identity provider override in session listener
2024-10-09T10:12:58.561+02:00 DEBUG 240892 --- [ftp-gateway]
[or-http-epoll-7] o.a.s.c.future.DefaultConnectFuture : Connected to
localhost/127.0.0.1:2102 after 703652 nanos
2024-10-09T10:12:58.640+02:00 DEBUG 240892 --- [ftp-gateway]
[-nio2-thread-20] o.a.s.client.session.ClientSessionImpl :
doReadIdentification(ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102])
line='SSH-2.0-mod_sftp'
2024-10-09T10:12:58.640+02:00 DEBUG 240892 --- [ftp-gateway]
[-nio2-thread-20] o.a.s.client.session.ClientSessionImpl :
readIdentification(ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102])
Server version string: SSH-2.0-mod_sftp
2024-10-09T10:12:58.640+02:00 DEBUG 240892 --- [ftp-gateway]
[-nio2-thread-20] o.a.s.client.session.ClientSessionImpl :
doHandleMessage(ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102])
process #0 SSH_MSG_KEXINIT
2024-10-09T10:12:58.640+02:00 DEBUG 240892 --- [ftp-gateway]
[-nio2-thread-20] o.a.s.client.session.ClientSessionImpl :
handleKexInit(ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102])
SSH_MSG_KEXINIT
2024-10-09T10:12:58.640+02:00 DEBUG 240892 --- [ftp-gateway]
[-nio2-thread-20] o.a.s.client.session.ClientSessionImpl :
negotiate(ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102]) strict
KEX=false client=true server=false
2024-10-09T10:12:58.640+02:00 DEBUG 240892 --- [ftp-gateway]
[-nio2-thread-20] o.a.s.client.session.ClientSessionImpl :
setNegotiationResult(ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102])
Kex: kex algorithms = ecdh-sha2-nistp521
2024-10-09T10:12:58.640+02:00 DEBUG 240892 --- [ftp-gateway]
[-nio2-thread-20] o.a.s.client.session.ClientSessionImpl :
setNegotiationResult(ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102])
Kex: server host key algorithms = ecdsa-sha2-nistp256
2024-10-09T10:12:58.640+02:00 DEBUG 240892 --- [ftp-gateway]
[-nio2-thread-20] o.a.s.client.session.ClientSessionImpl :
setNegotiationResult(ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102])
Kex: encryption algorithms (client to server) = aes128-ctr
2024-10-09T10:12:58.640+02:00 DEBUG 240892 --- [ftp-gateway]
[-nio2-thread-20] o.a.s.client.session.ClientSessionImpl :
setNegotiationResult(ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102])
Kex: encryption algorithms (server to client) = aes128-ctr
2024-10-09T10:12:58.640+02:00 DEBUG 240892 --- [ftp-gateway]
[-nio2-thread-20] o.a.s.client.session.ClientSessionImpl :
setNegotiationResult(ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102])
Kex: mac algorithms (client to server) = hmac-sha2-256
2024-10-09T10:12:58.640+02:00 DEBUG 240892 --- [ftp-gateway]
[-nio2-thread-20] o.a.s.client.session.ClientSessionImpl :
setNegotiationResult(ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102])
Kex: mac algorithms (server to client) = hmac-sha2-256
2024-10-09T10:12:58.640+02:00 DEBUG 240892 --- [ftp-gateway]
[-nio2-thread-20] o.a.s.client.session.ClientSessionImpl :
setNegotiationResult(ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102])
Kex: compression algorithms (client to server) = none
2024-10-09T10:12:58.640+02:00 DEBUG 240892 --- [ftp-gateway]
[-nio2-thread-20] o.a.s.client.session.ClientSessionImpl :
setNegotiationResult(ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102])
Kex: compression algorithms (server to client) = none
2024-10-09T10:12:58.643+02:00 DEBUG 240892 --- [ftp-gateway]
[-nio2-thread-20] org.apache.sshd.client.kex.DHGClient :
init(DHGClient[ecdh-sha2-nistp521])[ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102]]
Send SSH_MSG_KEXDH_INIT
2024-10-09T10:12:58.643+02:00 DEBUG 240892 --- [ftp-gateway]
[-nio2-thread-20] o.a.s.client.session.ClientSessionImpl :
encode(ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102]) packet #1
sending command=30[30] len=138
2024-10-09T10:12:58.643+02:00 DEBUG 240892 --- [ftp-gateway]
[-nio2-thread-20] o.a.sshd.common.io.nio2.Nio2Session :
writeBuffer(Nio2Session[local=/127.0.0.1:49884,
remote=localhost/127.0.0.1:2102]) writing 152 bytes
2024-10-09T10:12:58.666+02:00 DEBUG 240892 --- [ftp-gateway]
[-nio2-thread-21] o.a.s.client.session.ClientSessionImpl :
doHandleMessage(ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102])
process #1 31
2024-10-09T10:12:58.666+02:00 DEBUG 240892 --- [ftp-gateway]
[-nio2-thread-21] org.apache.sshd.client.kex.DHGClient :
next(DHGClient[ecdh-sha2-nistp521])[ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102]]
process command=SSH_MSG_KEXDH_REPLY
2024-10-09T10:12:58.671+02:00 DEBUG 240892 --- [ftp-gateway]
[-nio2-thread-21] o.a.s.client.session.ClientSessionImpl :
setServerKey(ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102])
keyType=ecdsa-sha2-nistp256,
digest=SHA256:+2xkRguqrV8WH8sBms+dChhmR8zmVpjdVDkKwLMkoPU
2024-10-09T10:12:58.671+02:00 DEBUG 240892 --- [ftp-gateway]
[-nio2-thread-21] o.a.s.client.session.ClientSessionImpl :
handleKexMessage(ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102])[ecdh-sha2-nistp521]
KEX processing complete after cmd=31
2024-10-09T10:12:58.671+02:00 WARN 240892 --- [ftp-gateway]
[-nio2-thread-21] o.a.s.c.k.AcceptAllServerKeyVerifier : Server at
localhost/127.0.0.1:2102 presented unverified EC key:
SHA256:+2xkRguqrV8WH8sBms+dChhmR8zmVpjdVDkKwLMkoPU
2024-10-09T10:12:58.671+02:00 DEBUG 240892 --- [ftp-gateway]
[-nio2-thread-21] o.a.s.client.session.ClientSessionImpl :
checkKeys(ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102])
key=ecdsa-sha2-nistp256-SHA256:+2xkRguqrV8WH8sBms+dChhmR8zmVpjdVDkKwLMkoPU,
verified=true
2024-10-09T10:12:58.671+02:00 DEBUG 240892 --- [ftp-gateway]
[-nio2-thread-21] o.a.s.client.session.ClientSessionImpl :
sendNewKeys(ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102]) Send
SSH_MSG_NEWKEYS
2024-10-09T10:12:58.671+02:00 DEBUG 240892 --- [ftp-gateway]
[-nio2-thread-21] o.a.s.client.session.ClientSessionImpl :
prepareNewKeys(ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102])
session
ID=59:d1:c5:58:16:fc:29:ef:16:79:75:f2:ad:0e:e6:01:6f:3a:d7:ce:9a:7e:27:7e:e5:62:6a:6b:86:54:fe:5d:8b:14:2a:7a:e3:f3:a1:71:c0:94:a7:c2:38:2f:43:16:5c:d1:f2:db:cb:d7:08:ad:43:88:6e:7d:94:c1:fa:dd
2024-10-09T10:12:58.671+02:00 DEBUG 240892 --- [ftp-gateway]
[-nio2-thread-21] o.a.s.client.session.ClientSessionImpl :
encode(ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102]) packet #2
sending command=21[SSH_MSG_NEWKEYS] len=1
2024-10-09T10:12:58.671+02:00 DEBUG 240892 --- [ftp-gateway]
[-nio2-thread-21] o.a.sshd.common.io.nio2.Nio2Session :
writeBuffer(Nio2Session[local=/127.0.0.1:49884,
remote=localhost/127.0.0.1:2102]) writing 16 bytes
2024-10-09T10:12:58.671+02:00 DEBUG 240892 --- [ftp-gateway]
[-nio2-thread-21] o.a.s.client.session.ClientSessionImpl :
setOutputEncoding(ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102]):
cipher BaseCipher[AES, ivSize=16, kdfSize=16,AES/CTR/NoPadding, blkSize=16];
mac BaseMac[HmacSHA256] - block=32/32 bytes, encrypt-then-mac=false;
compression none[Deflater/-1]; blocks limit 134217728
2024-10-09T10:12:58.671+02:00 DEBUG 240892 --- [ftp-gateway]
[-nio2-thread-21] o.a.s.client.session.ClientSessionImpl :
handleNewKeys(ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102]) No
pending packets to flush at end of KEX
2024-10-09T10:12:58.671+02:00 DEBUG 240892 --- [ftp-gateway]
[-nio2-thread-21] o.a.s.client.session.ClientSessionImpl :
doHandleMessage(ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102])
process #2 SSH_MSG_NEWKEYS
2024-10-09T10:12:58.671+02:00 DEBUG 240892 --- [ftp-gateway]
[-nio2-thread-21] o.a.s.client.session.ClientSessionImpl :
handleNewKeys(ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102])
SSH_MSG_NEWKEYS command=SSH_MSG_NEWKEYS
2024-10-09T10:12:58.671+02:00 DEBUG 240892 --- [ftp-gateway]
[-nio2-thread-21] o.a.s.client.session.ClientSessionImpl :
setInputEncoding(ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102]):
cipher BaseCipher[AES, ivSize=16, kdfSize=16,AES/CTR/NoPadding, blkSize=16];
mac BaseMac[HmacSHA256] - block=32/32 bytes, encrypt-then-mac=false;
compression none[Inflater/-1]; blocks limit 4294967296
2024-10-09T10:12:58.671+02:00 DEBUG 240892 --- [ftp-gateway]
[-nio2-thread-21] o.a.s.client.session.ClientSessionImpl :
sendInitialServiceRequest(ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102])
Send SSH_MSG_SERVICE_REQUEST for ssh-userauth
2024-10-09T10:12:58.671+02:00 DEBUG 240892 --- [ftp-gateway]
[-nio2-thread-21] o.a.s.client.session.ClientSessionImpl :
encode(ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102]) packet #3
sending command=5[SSH_MSG_SERVICE_REQUEST] len=17
2024-10-09T10:12:58.671+02:00 DEBUG 240892 --- [ftp-gateway]
[-nio2-thread-21] o.a.sshd.common.io.nio2.Nio2Session :
writeBuffer(Nio2Session[local=/127.0.0.1:49884,
remote=localhost/127.0.0.1:2102]) writing 80 bytes
2024-10-09T10:12:58.671+02:00 DEBUG 240892 --- [ftp-gateway]
[-nio2-thread-21] o.a.s.c.session.ClientUserAuthService :
auth(ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102])[ssh-connection]
send SSH_MSG_USERAUTH_REQUEST for 'none'
2024-10-09T10:12:58.671+02:00 DEBUG 240892 --- [ftp-gateway]
[-nio2-thread-21] o.a.s.client.session.ClientSessionImpl :
encode(ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102]) packet #4
sending command=50[SSH_MSG_USERAUTH_REQUEST] len=44
2024-10-09T10:12:58.671+02:00 DEBUG 240892 --- [ftp-gateway]
[-nio2-thread-21] o.a.sshd.common.io.nio2.Nio2Session :
writeBuffer(Nio2Session[local=/127.0.0.1:49884,
remote=localhost/127.0.0.1:2102]) writing 112 bytes
2024-10-09T10:12:58.744+02:00 DEBUG 240892 --- [ftp-gateway]
[]-nio2-thread-1] o.a.s.client.session.ClientSessionImpl :
doHandleMessage(ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102])
process #3 SSH_MSG_SERVICE_ACCEPT
2024-10-09T10:12:58.744+02:00 DEBUG 240892 --- [ftp-gateway]
[]-nio2-thread-1] o.a.s.client.session.ClientSessionImpl :
handleServiceAccept(ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102])
SSH_MSG_SERVICE_ACCEPT service=ssh-userauth
2024-10-09T10:12:58.788+02:00 DEBUG 240892 --- [ftp-gateway]
[]-nio2-thread-2] o.a.s.client.session.ClientSessionImpl :
doHandleMessage(ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102])
process #4 SSH_MSG_USERAUTH_FAILURE
2024-10-09T10:12:58.789+02:00 DEBUG 240892 --- [ftp-gateway]
[]-nio2-thread-2] o.a.s.c.session.ClientUserAuthService :
processUserAuth(ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102])
Received SSH_MSG_USERAUTH_FAILURE - partial=false, methods=publickey,password
2024-10-09T10:12:58.789+02:00 DEBUG 240892 --- [ftp-gateway]
[]-nio2-thread-2] o.a.s.c.session.ClientUserAuthService :
tryNext(ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102]) starting
authentication mechanisms: client=[publickey, keyboard-interactive, password],
server=[publickey, password]
2024-10-09T10:12:58.789+02:00 DEBUG 240892 --- [ftp-gateway]
[]-nio2-thread-2] o.a.s.c.session.ClientUserAuthService :
tryNext(ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102]) attempting
method=publickey
2024-10-09T10:12:58.789+02:00 DEBUG 240892 --- [ftp-gateway]
[]-nio2-thread-2] o.a.s.c.auth.pubkey.UserAuthPublicKey :
sendAuthDataRequest(ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102])[ssh-connection]
send SSH_MSG_USERAUTH_REQUEST request publickey type=rsa-sha2-512 -
fingerprint=SHA256:ETSunKAUwMd5ByWr0OISdnWTR6c1dAJRbRVlIxjtxzg
2024-10-09T10:12:58.789+02:00 DEBUG 240892 --- [ftp-gateway]
[]-nio2-thread-2] o.a.s.client.session.ClientSessionImpl :
encode(ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102]) packet #5
sending command=50[SSH_MSG_USERAUTH_REQUEST] len=347
2024-10-09T10:12:58.789+02:00 DEBUG 240892 --- [ftp-gateway]
[]-nio2-thread-2] o.a.sshd.common.io.nio2.Nio2Session :
writeBuffer(Nio2Session[local=/127.0.0.1:49884,
remote=localhost/127.0.0.1:2102]) writing 400 bytes
2024-10-09T10:12:58.789+02:00 DEBUG 240892 --- [ftp-gateway]
[]-nio2-thread-2] o.a.s.c.session.ClientUserAuthService :
tryNext(ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102]) successfully
processed initial buffer by method=publickey
2024-10-09T10:12:58.810+02:00 DEBUG 240892 --- [ftp-gateway]
[]-nio2-thread-3] o.a.s.client.session.ClientSessionImpl :
doHandleMessage(ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102])
process #5 SSH_MSG_USERAUTH_FAILURE
2024-10-09T10:12:58.810+02:00 DEBUG 240892 --- [ftp-gateway]
[]-nio2-thread-3] o.a.s.c.session.ClientUserAuthService :
processUserAuth(ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102])
Received SSH_MSG_USERAUTH_FAILURE - partial=false, methods=publickey,password
2024-10-09T10:12:58.810+02:00 DEBUG 240892 --- [ftp-gateway]
[]-nio2-thread-3] o.a.s.c.auth.pubkey.UserAuthPublicKey :
sendAuthDataRequest(ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102])[ssh-connection]
send SSH_MSG_USERAUTH_REQUEST request publickey type=rsa-sha2-256 -
fingerprint=SHA256:ETSunKAUwMd5ByWr0OISdnWTR6c1dAJRbRVlIxjtxzg
2024-10-09T10:12:58.810+02:00 DEBUG 240892 --- [ftp-gateway]
[]-nio2-thread-3] o.a.s.client.session.ClientSessionImpl :
encode(ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102]) packet #6
sending command=50[SSH_MSG_USERAUTH_REQUEST] len=347
2024-10-09T10:12:58.810+02:00 DEBUG 240892 --- [ftp-gateway]
[]-nio2-thread-3] o.a.sshd.common.io.nio2.Nio2Session :
writeBuffer(Nio2Session[local=/127.0.0.1:49884,
remote=localhost/127.0.0.1:2102]) writing 400 bytes
2024-10-09T10:12:58.810+02:00 DEBUG 240892 --- [ftp-gateway]
[]-nio2-thread-3] o.a.s.c.session.ClientUserAuthService :
tryNext(ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102]) successfully
processed initial buffer by method=publickey
2024-10-09T10:12:58.832+02:00 DEBUG 240892 --- [ftp-gateway]
[]-nio2-thread-4] o.a.s.client.session.ClientSessionImpl :
doHandleMessage(ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102])
process #6 SSH_MSG_USERAUTH_FAILURE
2024-10-09T10:12:58.832+02:00 DEBUG 240892 --- [ftp-gateway]
[]-nio2-thread-4] o.a.s.c.session.ClientUserAuthService :
processUserAuth(ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102])
Received SSH_MSG_USERAUTH_FAILURE - partial=false, methods=publickey,password
2024-10-09T10:12:58.832+02:00 DEBUG 240892 --- [ftp-gateway]
[]-nio2-thread-4] o.a.s.c.auth.pubkey.UserAuthPublicKey :
sendAuthDataRequest(ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102])[ssh-connection]
send SSH_MSG_USERAUTH_REQUEST request publickey type=ssh-rsa -
fingerprint=SHA256:ETSunKAUwMd5ByWr0OISdnWTR6c1dAJRbRVlIxjtxzg
2024-10-09T10:12:58.832+02:00 DEBUG 240892 --- [ftp-gateway]
[]-nio2-thread-4] o.a.s.client.session.ClientSessionImpl :
encode(ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102]) packet #7
sending command=50[SSH_MSG_USERAUTH_REQUEST] len=342
2024-10-09T10:12:58.832+02:00 DEBUG 240892 --- [ftp-gateway]
[]-nio2-thread-4] o.a.sshd.common.io.nio2.Nio2Session :
writeBuffer(Nio2Session[local=/127.0.0.1:49884,
remote=localhost/127.0.0.1:2102]) writing 400 bytes
2024-10-09T10:12:58.832+02:00 DEBUG 240892 --- [ftp-gateway]
[]-nio2-thread-4] o.a.s.c.session.ClientUserAuthService :
tryNext(ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102]) successfully
processed initial buffer by method=publickey
2024-10-09T10:12:58.852+02:00 DEBUG 240892 --- [ftp-gateway]
[]-nio2-thread-5] o.a.s.client.session.ClientSessionImpl :
doHandleMessage(ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102])
process #7 60
2024-10-09T10:12:58.853+02:00 DEBUG 240892 --- [ftp-gateway]
[]-nio2-thread-5] o.a.s.c.session.ClientUserAuthService :
processUserAuth(ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102])
delegate processing of 60 to publickey
2024-10-09T10:12:58.853+02:00 DEBUG 240892 --- [ftp-gateway]
[]-nio2-thread-5] o.a.s.c.auth.pubkey.UserAuthPublicKey :
processAuthDataRequest(ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102])[ssh-connection][publickey]
SSH_MSG_USERAUTH_PK_OK type=ssh-rsa,
fingerprint=SHA256:ETSunKAUwMd5ByWr0OISdnWTR6c1dAJRbRVlIxjtxzg
2024-10-09T10:12:58.853+02:00 DEBUG 240892 --- [ftp-gateway]
[]-nio2-thread-5] o.a.s.c.auth.pubkey.UserAuthPublicKey :
processAuthDataRequest(ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102])[ssh-connection][publickey]:
signing with algorithm ssh-rsa
2024-10-09T10:12:58.861+02:00 DEBUG 240892 --- [ftp-gateway]
[]-nio2-thread-5] o.a.s.client.session.ClientSessionImpl :
encode(ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102]) packet #8
sending command=50[SSH_MSG_USERAUTH_REQUEST] len=617
2024-10-09T10:12:58.861+02:00 DEBUG 240892 --- [ftp-gateway]
[]-nio2-thread-5] o.a.sshd.common.io.nio2.Nio2Session :
writeBuffer(Nio2Session[local=/127.0.0.1:49884,
remote=localhost/127.0.0.1:2102]) writing 672 bytes
2024-10-09T10:12:58.906+02:00 DEBUG 240892 --- [ftp-gateway]
[]-nio2-thread-6] o.a.s.client.session.ClientSessionImpl :
doHandleMessage(ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102])
process #8 SSH_MSG_USERAUTH_SUCCESS
2024-10-09T10:12:58.906+02:00 DEBUG 240892 --- [ftp-gateway]
[]-nio2-thread-6] o.a.s.c.session.ClientUserAuthService :
processUserAuth(ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102])
SSH_MSG_USERAUTH_SUCCESS Succeeded with publickey
2024-10-09T10:12:58.906+02:00 DEBUG 240892 --- [ftp-gateway]
[]-nio2-thread-6] o.a.s.c.auth.pubkey.UserAuthPublicKey :
destroy(ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102])[ssh-connection]
2024-10-09T10:12:58.906+02:00 DEBUG 240892 --- [ftp-gateway]
[]-nio2-thread-6] o.a.s.c.session.ClientConnectionService :
stopHeartBeat(ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102]) no
heartbeat to stop
2024-10-09T10:12:58.906+02:00 DEBUG 240892 --- [ftp-gateway]
[]-nio2-thread-6] o.a.s.c.session.ClientConnectionService :
startHeartbeat(ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102])
heartbeat type=NONE, interval=PT0S
2024-10-09T10:12:58.906+02:00 DEBUG 240892 --- [ftp-gateway]
[or-http-epoll-7] i.DefaultSftpClient$SftpChannelSubsystem : init()
service=ClientConnectionService[ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102]]
session=ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102] id=0
2024-10-09T10:12:58.906+02:00 DEBUG 240892 --- [ftp-gateway]
[or-http-epoll-7] o.a.sshd.common.channel.LocalWindow :
init(LocalWindow[client](SftpChannelSubsystem[id=0,
recipient=-1]-ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102][sftp]))
size=2097152, max=2097152, packet=32768
2024-10-09T10:12:58.906+02:00 DEBUG 240892 --- [ftp-gateway]
[or-http-epoll-7] o.a.s.c.session.ClientConnectionService :
registerChannel(ClientConnectionService[ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102]])[id=0,
registered=true] SftpChannelSubsystem[id=0,
recipient=-1]-ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102][sftp]
2024-10-09T10:12:58.906+02:00 DEBUG 240892 --- [ftp-gateway]
[or-http-epoll-7] i.DefaultSftpClient$SftpChannelSubsystem :
open(SftpChannelSubsystem[id=0,
recipient=-1]-ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102][sftp])
Send SSH_MSG_CHANNEL_OPEN - type=session
2024-10-09T10:12:58.906+02:00 DEBUG 240892 --- [ftp-gateway]
[or-http-epoll-7] o.a.s.client.session.ClientSessionImpl :
encode(ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102]) packet #9
sending command=90[SSH_MSG_CHANNEL_OPEN] len=24
2024-10-09T10:12:58.906+02:00 DEBUG 240892 --- [ftp-gateway]
[or-http-epoll-7] o.a.sshd.common.io.nio2.Nio2Session :
writeBuffer(Nio2Session[local=/127.0.0.1:49884,
remote=localhost/127.0.0.1:2102]) writing 80 bytes
2024-10-09T10:12:58.926+02:00 DEBUG 240892 --- [ftp-gateway]
[]-nio2-thread-8] o.a.s.client.session.ClientSessionImpl :
doHandleMessage(ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102])
process #9 SSH_MSG_CHANNEL_OPEN_CONFIRMATION
2024-10-09T10:12:58.926+02:00 DEBUG 240892 --- [ftp-gateway]
[]-nio2-thread-8] o.a.s.c.session.ClientConnectionService :
channelOpenConfirmation(SftpChannelSubsystem[id=0,
recipient=-1]-ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102][sftp])
SSH_MSG_CHANNEL_OPEN_CONFIRMATION sender=0, window-size=4294967295,
packet-size=32768
2024-10-09T10:12:58.926+02:00 DEBUG 240892 --- [ftp-gateway]
[]-nio2-thread-8] i.DefaultSftpClient$SftpChannelSubsystem :
setRecipient(SftpChannelSubsystem[id=0,
recipient=-1]-ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102][sftp])
recipient=0
2024-10-09T10:12:58.926+02:00 DEBUG 240892 --- [ftp-gateway]
[]-nio2-thread-8] o.a.sshd.common.channel.RemoteWindow :
init(RemoteWindow[client](SftpChannelSubsystem[id=0,
recipient=0]-ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102][sftp]))
size=4294967295, max=4294967295, packet=32768
2024-10-09T10:12:58.926+02:00 DEBUG 240892 --- [ftp-gateway]
[]-nio2-thread-8] i.DefaultSftpClient$SftpChannelSubsystem :
addPendingRequest(SftpChannelSubsystem[id=0,
recipient=0]-ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102][sftp])
request=subsystem, pending=Wed Oct 09 10:12:58 CEST 2024
2024-10-09T10:12:58.926+02:00 DEBUG 240892 --- [ftp-gateway]
[]-nio2-thread-8] o.a.s.client.session.ClientSessionImpl :
encode(ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102]) packet #10
sending command=98[SSH_MSG_CHANNEL_REQUEST] len=27
2024-10-09T10:12:58.926+02:00 DEBUG 240892 --- [ftp-gateway]
[]-nio2-thread-8] o.a.sshd.common.io.nio2.Nio2Session :
writeBuffer(Nio2Session[local=/127.0.0.1:49884,
remote=localhost/127.0.0.1:2102]) writing 80 bytes
2024-10-09T10:12:58.926+02:00 DEBUG 240892 --- [ftp-gateway]
[or-http-epoll-7] o.a.sshd.common.channel.RemoteWindow : Consume
RemoteWindow[client](SftpChannelSubsystem[id=0,
recipient=0]-ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102][sftp])
by 9 down to 4294967286
2024-10-09T10:12:58.926+02:00 DEBUG 240892 --- [ftp-gateway]
[or-http-epoll-7] o.a.s.client.session.ClientSessionImpl :
encode(ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102]) packet #11
sending command=94[SSH_MSG_CHANNEL_DATA] len=18
2024-10-09T10:12:58.926+02:00 DEBUG 240892 --- [ftp-gateway]
[or-http-epoll-7] o.a.sshd.common.io.nio2.Nio2Session :
writeBuffer(Nio2Session[local=/127.0.0.1:49884,
remote=localhost/127.0.0.1:2102]) writing 80 bytes
2024-10-09T10:12:58.948+02:00 DEBUG 240892 --- [ftp-gateway]
[-nio2-thread-10] o.a.s.client.session.ClientSessionImpl :
doHandleMessage(ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102])
process #10 SSH_MSG_CHANNEL_SUCCESS
2024-10-09T10:12:58.948+02:00 DEBUG 240892 --- [ftp-gateway]
[-nio2-thread-10] i.DefaultSftpClient$SftpChannelSubsystem :
removePendingRequest(SftpChannelSubsystem[id=0,
recipient=0]-ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102][sftp])
request=subsystem, pending=Wed Oct 09 10:12:58 CEST 2024
2024-10-09T10:12:58.948+02:00 DEBUG 240892 --- [ftp-gateway]
[-nio2-thread-10] i.DefaultSftpClient$SftpChannelSubsystem :
handleSuccess(SftpChannelSubsystem[id=0,
recipient=0]-ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102][sftp])
subsystem=sftp, pending since=Wed Oct 09 10:12:58 CEST 2024
2024-10-09T10:12:58.967+02:00 DEBUG 240892 --- [ftp-gateway]
[-nio2-thread-11] o.a.s.client.session.ClientSessionImpl :
doHandleMessage(ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102])
process #11 SSH_MSG_CHANNEL_DATA
2024-10-09T10:12:58.967+02:00 DEBUG 240892 --- [ftp-gateway]
[-nio2-thread-11] o.a.sshd.common.channel.LocalWindow : Consume
LocalWindow[client](SftpChannelSubsystem[id=0,
recipient=0]-ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102][sftp])
by 304 down to 2096848
2024-10-09T10:12:58.967+02:00 DEBUG 240892 --- [ftp-gateway]
[-nio2-thread-11] i.DefaultSftpClient$SftpChannelSubsystem :
handleData(SftpChannelSubsystem[id=0,
recipient=0]-ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102][sftp])
SSH_MSG_CHANNEL_DATA len=304
2024-10-09T10:12:58.967+02:00 DEBUG 240892 --- [ftp-gateway]
[or-http-epoll-7] o.s.integration.util.SimplePool : Obtained new
org.springframework.integration.sftp.session.SftpSession@536756ed.
2024-10-09T10:12:58.967+02:00 INFO 240892 --- [ftp-gateway]
[or-http-epoll-7] f.i.f.configuration.sftp.SftpConfig : Uploaded file name
= test
2024-10-09T10:12:58.967+02:00 DEBUG 240892 --- [ftp-gateway]
[or-http-epoll-7] tSftpSessionFactory$ConcurrentSftpClient :
canonicalPath(SftpChannelSubsystem[id=0,
recipient=0]-ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102][sftp]):
send SSH_FXP_REALPATH ./TO_EDF/SIMM/
2024-10-09T10:12:58.967+02:00 DEBUG 240892 --- [ftp-gateway]
[or-http-epoll-7] o.a.sshd.common.channel.RemoteWindow : Consume
RemoteWindow[client](SftpChannelSubsystem[id=0,
recipient=0]-ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102][sftp])
by 27 down to 4294967259
2024-10-09T10:12:58.967+02:00 DEBUG 240892 --- [ftp-gateway]
[or-http-epoll-7] o.a.s.client.session.ClientSessionImpl :
encode(ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102]) packet #12
sending command=94[SSH_MSG_CHANNEL_DATA] len=36
2024-10-09T10:12:58.967+02:00 DEBUG 240892 --- [ftp-gateway]
[or-http-epoll-7] o.a.sshd.common.io.nio2.Nio2Session :
writeBuffer(Nio2Session[local=/127.0.0.1:49884,
remote=localhost/127.0.0.1:2102]) writing 96 bytes
2024-10-09T10:12:58.998+02:00 DEBUG 240892 --- [ftp-gateway]
[-nio2-thread-13] o.a.s.client.session.ClientSessionImpl :
doHandleMessage(ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102])
process #12 SSH_MSG_CHANNEL_DATA
2024-10-09T10:12:58.998+02:00 DEBUG 240892 --- [ftp-gateway]
[-nio2-thread-13] o.a.sshd.common.channel.LocalWindow : Consume
LocalWindow[client](SftpChannelSubsystem[id=0,
recipient=0]-ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102][sftp])
by 34 down to 2096814
2024-10-09T10:12:58.998+02:00 DEBUG 240892 --- [ftp-gateway]
[-nio2-thread-13] i.DefaultSftpClient$SftpChannelSubsystem :
handleData(SftpChannelSubsystem[id=0,
recipient=0]-ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102][sftp])
SSH_MSG_CHANNEL_DATA len=34
2024-10-09T10:12:58.998+02:00 DEBUG 240892 --- [ftp-gateway]
[or-http-epoll-7] tSftpSessionFactory$ConcurrentSftpClient :
response(SftpChannelSubsystem[id=0,
recipient=0]-ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102][sftp]):
received SSH_FXP_NAME for command SSH_FXP_REALPATH (id=101)
2024-10-09T10:13:02.098+02:00 DEBUG 240892 --- [ftp-gateway]
[or-http-epoll-7] tSftpSessionFactory$ConcurrentSftpClient :
stat(SftpChannelSubsystem[id=0,
recipient=0]-ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102][sftp]):
send SSH_FXP_LSTAT /TO_EDF/SIMM
2024-10-09T10:13:02.098+02:00 DEBUG 240892 --- [ftp-gateway]
[or-http-epoll-7] o.a.sshd.common.channel.RemoteWindow : Consume
RemoteWindow[client](SftpChannelSubsystem[id=0,
recipient=0]-ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102][sftp])
by 29 down to 4294967230
2024-10-09T10:13:02.098+02:00 DEBUG 240892 --- [ftp-gateway]
[or-http-epoll-7] o.a.s.client.session.ClientSessionImpl :
encode(ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102]) packet #13
sending command=94[SSH_MSG_CHANNEL_DATA] len=38
2024-10-09T10:13:02.098+02:00 DEBUG 240892 --- [ftp-gateway]
[or-http-epoll-7] o.a.sshd.common.io.nio2.Nio2Session :
writeBuffer(Nio2Session[local=/127.0.0.1:49884,
remote=localhost/127.0.0.1:2102]) writing 96 bytes
2024-10-09T10:13:02.118+02:00 DEBUG 240892 --- [ftp-gateway]
[-nio2-thread-15] o.a.s.client.session.ClientSessionImpl :
doHandleMessage(ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102])
process #13 SSH_MSG_CHANNEL_DATA
2024-10-09T10:13:02.118+02:00 DEBUG 240892 --- [ftp-gateway]
[-nio2-thread-15] o.a.sshd.common.channel.LocalWindow : Consume
LocalWindow[client](SftpChannelSubsystem[id=0,
recipient=0]-ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102][sftp])
by 85 down to 2096729
2024-10-09T10:13:02.118+02:00 DEBUG 240892 --- [ftp-gateway]
[-nio2-thread-15] i.DefaultSftpClient$SftpChannelSubsystem :
handleData(SftpChannelSubsystem[id=0,
recipient=0]-ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102][sftp])
SSH_MSG_CHANNEL_DATA len=85
2024-10-09T10:13:02.118+02:00 DEBUG 240892 --- [ftp-gateway]
[or-http-epoll-7] tSftpSessionFactory$ConcurrentSftpClient :
response(SftpChannelSubsystem[id=0,
recipient=0]-ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102][sftp]):
received SSH_FXP_ATTRS for command SSH_FXP_LSTAT (id=102)
2024-10-09T10:23:24.918+02:00 DEBUG 240892 --- [ftp-gateway]
[-timer-thread-1] o.a.s.client.session.ClientSessionImpl :
checkForTimeouts(ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102])
disconnect - reason=TimeoutIndicator[status=IdleTimeout, threshold=600000,
expired=622799]
2024-10-09T10:23:24.918+02:00 DEBUG 240892 --- [ftp-gateway]
[-nio2-thread-16] o.a.sshd.common.io.nio2.Nio2Session :
handleReadCycleCompletion(Nio2Session[local=/127.0.0.1:49884,
remote=localhost/127.0.0.1:2102]) Socket has been disconnected (result=-1),
closing IoSession now
2024-10-09T10:23:24.918+02:00 DEBUG 240892 --- [ftp-gateway]
[-nio2-thread-16] o.a.sshd.common.io.nio2.Nio2Session :
close(Nio2Session[local=/127.0.0.1:49884, remote=localhost/127.0.0.1:2102])
Closing immediately
2024-10-09T10:23:24.918+02:00 DEBUG 240892 --- [ftp-gateway]
[-nio2-thread-16] o.a.sshd.common.io.nio2.Nio2Session :
doCloseImmediately(Nio2Session[local=/127.0.0.1:49884,
remote=localhost/127.0.0.1:2102]) closing
socket=sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected
local=/127.0.0.1:49884 remote=localhost/127.0.0.1:2102]
2024-10-09T10:23:24.918+02:00 INFO 240892 --- [ftp-gateway]
[container-0-C-1] org.apache.kafka.clients.NetworkClient : [Consumer
clientId=consumer-ftp-gateway-2, groupId=ftp-gateway] Node -1 disconnected.
2024-10-09T10:23:24.918+02:00 DEBUG 240892 --- [ftp-gateway]
[-nio2-thread-16] o.a.sshd.common.io.nio2.Nio2Session :
doCloseImmediately(Nio2Session[local=/127.0.0.1:49884,
remote=localhost/127.0.0.1:2102])
socket=sun.nio.ch.UnixAsynchronousSocketChannelImpl[closed] closed
2024-10-09T10:23:24.918+02:00 DEBUG 240892 --- [ftp-gateway]
[-nio2-thread-16] o.a.sshd.common.io.nio2.Nio2Connector :
unmapSession(id=103): Nio2Session[local=/127.0.0.1:49884,
remote=localhost/127.0.0.1:2102]
2024-10-09T10:23:24.918+02:00 DEBUG 240892 --- [ftp-gateway]
[-nio2-thread-16] o.a.s.client.session.ClientSessionImpl :
close(ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102]) Closing
immediately
2024-10-09T10:23:24.918+02:00 DEBUG 240892 --- [ftp-gateway]
[-nio2-thread-16] o.a.s.client.session.ClientSessionImpl :
signalAuthFailure(ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102])
type=SshException, signalled=false, first=false: Session is being closed
2024-10-09T10:23:24.918+02:00 DEBUG 240892 --- [ftp-gateway]
[-nio2-thread-16] o.a.s.c.s.h.SessionTimeoutListener :
sessionClosed(ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102])
un-tracked
2024-10-09T10:23:24.918+02:00 DEBUG 240892 --- [ftp-gateway]
[-nio2-thread-16] o.a.s.c.session.ClientConnectionService :
close(ClientConnectionService[ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102]])
Closing immediately
2024-10-09T10:23:24.918+02:00 DEBUG 240892 --- [ftp-gateway]
[-nio2-thread-16] o.a.s.c.session.ClientConnectionService :
stopHeartBeat(ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102]) no
heartbeat to stop
2024-10-09T10:23:24.918+02:00 DEBUG 240892 --- [ftp-gateway]
[-nio2-thread-16] i.DefaultSftpClient$SftpChannelSubsystem :
close(SftpChannelSubsystem[id=0,
recipient=0]-ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102][sftp])
Closing immediately
2024-10-09T10:23:24.918+02:00 DEBUG 240892 --- [ftp-gateway]
[-nio2-thread-16] i.DefaultSftpClient$SftpChannelSubsystem :
close(SftpChannelSubsystem[id=0,
recipient=0]-ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102][sftp])
no EOF sent
2024-10-09T10:23:24.918+02:00 DEBUG 240892 --- [ftp-gateway]
[-nio2-thread-16] o.a.sshd.common.channel.LocalWindow : Closing
LocalWindow[client](SftpChannelSubsystem[id=0,
recipient=0]-ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102][sftp])
2024-10-09T10:23:24.918+02:00 DEBUG 240892 --- [ftp-gateway]
[-nio2-thread-16] o.a.sshd.common.channel.RemoteWindow : Closing
RemoteWindow[client](SftpChannelSubsystem[id=0,
recipient=0]-ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102][sftp])
2024-10-09T10:23:24.918+02:00 DEBUG 240892 --- [ftp-gateway]
[-nio2-thread-16] DefaultSftpClient$SftpChannelSubsystem$1 :
close([SftpChannelSubsystem[id=0,
recipient=0]-ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102][sftp]]
cmd=SSH_MSG_CHANNEL_DATA) Closing immediately
2024-10-09T10:23:24.918+02:00 DEBUG 240892 --- [ftp-gateway]
[-nio2-thread-16] DefaultSftpClient$SftpChannelSubsystem$1 :
close([SftpChannelSubsystem[id=0,
recipient=0]-ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102][sftp]]
cmd=SSH_MSG_CHANNEL_DATA)[Immediately] closed
2024-10-09T10:23:24.918+02:00 DEBUG 240892 --- [ftp-gateway]
[-nio2-thread-16] AbstractChannel$GracefulChannelCloseable :
close(SftpChannelSubsystem[id=0,
recipient=0]-ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102][sftp])[immediately=true]
processing
2024-10-09T10:23:24.918+02:00 DEBUG 240892 --- [ftp-gateway]
[-nio2-thread-16] o.a.s.c.session.ClientConnectionService :
unregisterChannel(SftpChannelSubsystem[id=0,
recipient=0]-ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102][sftp])
result=SftpChannelSubsystem[id=0,
recipient=0]-ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102][sftp]
2024-10-09T10:23:24.918+02:00 DEBUG 240892 --- [ftp-gateway]
[-nio2-thread-16] o.a.s.c.u.closeable.SequentialCloseable :
doClose(org.apache.sshd.common.util.closeable.SequentialCloseable$1@2343315c)
signal close complete immediately=true
2024-10-09T10:23:24.918+02:00 DEBUG 240892 --- [ftp-gateway]
[-nio2-thread-16] o.a.s.c.u.closeable.SequentialCloseable :
doClose(org.apache.sshd.common.util.closeable.SequentialCloseable$1@2d84db44)
signal close complete immediately=true
2024-10-09T10:23:24.918+02:00 DEBUG 240892 --- [ftp-gateway]
[-nio2-thread-16] o.a.s.c.u.closeable.SequentialCloseable :
doClose(org.apache.sshd.common.util.closeable.SequentialCloseable$1@29bb5a2c)
signal close complete immediately=true
2024-10-09T10:23:24.918+02:00 DEBUG 240892 --- [ftp-gateway]
[-nio2-thread-16] i.DefaultSftpClient$SftpChannelSubsystem :
close(SftpChannelSubsystem[id=0,
recipient=0]-ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102][sftp])[Immediately]
closed
2024-10-09T10:23:24.918+02:00 DEBUG 240892 --- [ftp-gateway]
[-nio2-thread-16] o.a.s.c.session.ClientConnectionService :
close(ClientConnectionService[ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102]])[Immediately]
closed
2024-10-09T10:23:24.918+02:00 DEBUG 240892 --- [ftp-gateway]
[-nio2-thread-16] o.a.sshd.common.io.nio2.Nio2Session :
close(Nio2Session[local=/127.0.0.1:49884,
remote=localhost/127.0.0.1:2102])[Immediately] state already Closed
2024-10-09T10:23:24.918+02:00 DEBUG 240892 --- [ftp-gateway]
[-nio2-thread-16] o.a.s.c.u.closeable.SequentialCloseable :
doClose(org.apache.sshd.common.util.closeable.SequentialCloseable$1@6cb9d17f)
signal close complete immediately=true
2024-10-09T10:23:24.918+02:00 DEBUG 240892 --- [ftp-gateway]
[-nio2-thread-16] o.a.s.client.session.ClientSessionImpl :
close(ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102])[Immediately]
closed
2024-10-09T10:23:24.918+02:00 DEBUG 240892 --- [ftp-gateway]
[-nio2-thread-16] o.a.sshd.common.io.nio2.Nio2Session :
close(Nio2Session[local=/127.0.0.1:49884,
remote=localhost/127.0.0.1:2102])[Immediately] closed
2024-10-09T10:23:24.918+02:00 INFO 240892 --- [ftp-gateway]
[pool-4-thread-1] org.apache.kafka.clients.NetworkClient : [Consumer
clientId=consumer-ftp-gateway-3, groupId=ftp-gateway] Node 1 disconnected.
2024-10-09T10:23:24.918+02:00 INFO 240892 --- [ftp-gateway]
[pool-4-thread-1] org.apache.kafka.clients.NetworkClient : [Consumer
clientId=consumer-ftp-gateway-3, groupId=ftp-gateway] Cancelled in-flight
METADATA request with correlation id 26 due to node 1 being disconnected
(elapsed time since creation: 0ms, elapsed time since send: 0ms, request
timeout: 30000ms)
2024-10-09T10:23:24.920+02:00 DEBUG 240892 --- [ftp-gateway]
[or-http-epoll-7] o.s.i.f.r.session.CachingSessionFactory : Releasing Session
org.springframework.integration.sftp.session.SftpSession@536756ed back to the
pool.
2024-10-09T10:23:24.920+02:00 INFO 240892 --- [ftp-gateway]
[pool-4-thread-1] org.apache.kafka.clients.NetworkClient : [Consumer
clientId=consumer-ftp-gateway-3, groupId=ftp-gateway] Node -1 disconnected.
2024-10-09T10:23:24.920+02:00 DEBUG 240892 --- [ftp-gateway]
[or-http-epoll-7] o.s.integration.util.SimplePool : Releasing
org.springframework.integration.sftp.session.SftpSession@536756ed back to the
pool
2024-10-09T10:23:24.920+02:00 INFO 240892 --- [ftp-gateway]
[pool-4-thread-1] org.apache.kafka.clients.NetworkClient : [Consumer
clientId=consumer-ftp-gateway-3, groupId=ftp-gateway] Node 2147483646
disconnected.
2024-10-09T10:23:24.920+02:00 INFO 240892 --- [ftp-gateway]
[-timer-thread-1] o.a.s.client.session.ClientSessionImpl :
Disconnecting(ClientSessionImpl[Pepsis_Isilis@localhost/127.0.0.1:2102]):
SSH2_DISCONNECT_PROTOCOL_ERROR - Detected IdleTimeout after 622799/600000 ms.
2024-10-09T10:23:24.920+02:00 INFO 240892 --- [ftp-gateway] [d |
ftp-gateway] org.apache.kafka.clients.NetworkClient : [Consumer
clientId=consumer-ftp-gateway-2, groupId=ftp-gateway] Node 1 disconnected.
2024-10-09T10:23:24.920+02:00 INFO 240892 --- [ftp-gateway] [d |
ftp-gateway] org.apache.kafka.clients.NetworkClient : [Consumer
clientId=consumer-ftp-gateway-2, groupId=ftp-gateway] Node 2147483646
disconnected.
2024-10-09T10:23:24.920+02:00 INFO 240892 --- [ftp-gateway] [d |
ftp-gateway] o.a.k.c.c.internals.ConsumerCoordinator : [Consumer
clientId=consumer-ftp-gateway-2, groupId=ftp-gateway] Group coordinator
localhost:9092 (id: 2147483646 rack: null) is unavailable or invalid due to
cause: coordinator unavailable. isDisconnected: true. Rediscovery will be
attempted.
2024-10-09T10:23:24.921+02:00 INFO 240892 --- [ftp-gateway]
[or-http-epoll-7] f.i.f.write.business.FileWriterImpl : Buffer is closed
```
### Other information
In the log the call goes through :
- an SSH tunnel to one of our server
- on this server an HAproxy in TCP mode that just forwards to the remote sftp
--
This is an automated message from the Apache Git Service.
To respond to the message, please log on to GitHub and use the
URL above to go to the specific comment.
To unsubscribe, e-mail: [email protected]
For queries about this service, please contact Infrastructure at:
[email protected]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
