raghamadhu opened a new issue, #578:
URL: https://github.com/apache/mina-sshd/issues/578
### Version
2.13.1
### Bug description
We are using Apache Mina to connect to an RHEL server, and intermittently we
are getting IOException - "The semaphore timeout period has expired" while
using Apache Mina SshClient.
This error only occurs when connecting from a Windows client.
Error only occurs 3 in 10 times. We are trying to execute commands on the
remote server using ChannelExec::open method.
### Actual behavior
We are using ClientBuilder to build the client as below
'client =
ClientBuilder.builder().hostConfigEntryResolver(hostConfigEntryResolver)
' .serverKeyVerifier(AcceptAllServerKeyVerifier.INSTANCE)
'
.forwardingFilter(AcceptAllForwardingFilter.INSTANCE).build();
Session heartbeat is set to 10 seconds and we are starting the client.
' client.start();
Using the session object created, we are opening an ExecChannel, to execute
linux commands.
` channelExec = session.createExecChannel("<command to execute>")
` channelExec.open()
This closes the complete session in the client. which interrupts command
execution in other channels too.
### Expected behavior
while a connection is open, and no network disturbances, this should not
happen.
### Relevant log output
```Shell
2024-08-07 13:09:25,424 DEBUG [sshd-SshClient[569b3009]-timer-thread-1]
o.a.s.c.i.n.Nio2Session writeBuffer(Nio2Session[local=/0:0:0:0:0:0:0:0:64688,
remote=vmr-iod-1001.ete.ka.sw.ericsson.se/10.223.58.242:22]) writing 60 bytes
2024-08-07 13:09:25,574 DEBUG [sshd-SshClient[569b3009]-nio2-thread-5]
o.a.s.c.i.n.Nio2Session
handleReadCycleFailure(Nio2Session[local=/0:0:0:0:0:0:0:0:64688,
remote=vmr-iod-1001.ete.ka.sw.ericsson.se/10.223.58.242:22]) IOException after
19180462400 nanos at read cycle=34: The semaphore timeout period has expired.
2024-08-07 13:09:25,574 DEBUG [sshd-SshClient[569b3009]-nio2-thread-5]
o.a.s.c.i.n.Nio2Session
exceptionCaught(Nio2Session[local=/0:0:0:0:0:0:0:0:64688,
remote=vmr-iod-1001.ete.ka.sw.ericsson.se/10.223.58.242:22]) caught
IOException[The semaphore timeout period has expired.
] - calling handler
2024-08-07 13:09:25,574 DEBUG [sshd-SshClient[569b3009]-nio2-thread-5]
o.a.s.c.s.ClientSessionImpl
signalAuthFailure(ClientSessionImpl[installad...@vmr-iod-1001.ete.ka.sw.ericsson.se/10.223.58.242:22])
type=IOException, signalled=false, first=false: The semaphore timeout period
has expired.
2024-08-07 13:09:25,574 WARN [sshd-SshClient[569b3009]-nio2-thread-5]
o.a.s.c.s.ClientSessionImpl
exceptionCaught(ClientSessionImpl[installad...@vmr-iod-1001.ete.ka.sw.ericsson.se/10.223.58.242:22])[state=Opened]
IOException: The semaphore timeout period has expired.
java.io.IOException: The semaphore timeout period has expired.
at sun.nio.ch.Iocp.translateErrorToIOException(Iocp.java:309)
at sun.nio.ch.Iocp.access$700(Iocp.java:46)
at sun.nio.ch.Iocp$EventHandlerTask.run(Iocp.java:399)
at java.lang.Thread.run(Thread.java:750)
2024-08-07 13:09:25,574 DEBUG [sshd-SshClient[569b3009]-nio2-thread-5]
o.a.s.c.s.h.SessionTimeoutListener
sessionException(ClientSessionImpl[installad...@vmr-iod-1001.ete.ka.sw.ericsson.se/10.223.58.242:22])
IOException: The semaphore timeout period has expired.
2024-08-07 13:09:25,574 DEBUG [sshd-SshClient[569b3009]-nio2-thread-5]
o.a.s.c.s.h.SessionTimeoutListener
sessionClosed(ClientSessionImpl[installad...@vmr-iod-1001.ete.ka.sw.ericsson.se/10.223.58.242:22])
un-tracked
2024-08-07 13:09:25,574 DEBUG [sshd-SshClient[569b3009]-nio2-thread-5]
o.a.s.c.s.ClientSessionImpl
close(ClientSessionImpl[installad...@vmr-iod-1001.ete.ka.sw.ericsson.se/10.223.58.242:22])
Closing immediately
2024-08-07 13:09:25,589 DEBUG [sshd-SshClient[569b3009]-nio2-thread-5]
o.a.s.c.s.ClientSessionImpl
signalAuthFailure(ClientSessionImpl[installad...@vmr-iod-1001.ete.ka.sw.ericsson.se/10.223.58.242:22])
type=SshException, signalled=false, first=false: Session is being closed
2024-08-07 13:09:25,589 DEBUG [sshd-SshClient[569b3009]-nio2-thread-5]
o.a.s.c.s.ClientConnectionService
close(ClientConnectionService[ClientSessionImpl[installad...@vmr-iod-1001.ete.ka.sw.ericsson.se/10.223.58.242:22]])
Closing immediately
2024-08-07 13:09:25,589 DEBUG [sshd-SshClient[569b3009]-nio2-thread-5]
o.a.s.c.s.ClientConnectionService
stopHeartBeat(ClientSessionImpl[installad...@vmr-iod-1001.ete.ka.sw.ericsson.se/10.223.58.242:22])
stopping
2024-08-07 13:09:25,589 DEBUG [sshd-SshClient[569b3009]-nio2-thread-5]
o.a.s.c.s.ClientConnectionService
stopHeartBeat(ClientSessionImpl[installad...@vmr-iod-1001.ete.ka.sw.ericsson.se/10.223.58.242:22])
stopped
2024-08-07 13:09:25,589 DEBUG [sshd-SshClient[569b3009]-nio2-thread-5]
o.a.s.c.c.ChannelExec close(ChannelExec[id=0,
recipient=1]-ClientSessionImpl[installad...@vmr-iod-1001.ete.ka.sw.ericsson.se/10.223.58.242:22])
Closing immediately
2024-08-07 13:09:25,589 DEBUG [sshd-SshClient[569b3009]-nio2-thread-5]
o.a.s.c.c.ChannelExec close(ChannelExec[id=0,
recipient=1]-ClientSessionImpl[installad...@vmr-iod-1001.ete.ka.sw.ericsson.se/10.223.58.242:22])
no EOF sent
2024-08-07 13:09:25,589 DEBUG [sshd-SshClient[569b3009]-nio2-thread-5]
o.a.s.c.c.LocalWindow Closing LocalWindow[client](ChannelExec[id=0,
recipient=1]-ClientSessionImpl[installad...@vmr-iod-1001.ete.ka.sw.ericsson.se/10.223.58.242:22])
2024-08-07 13:09:25,589 DEBUG [sshd-SshClient[569b3009]-nio2-thread-5]
o.a.s.c.c.RemoteWindow Closing RemoteWindow[client](ChannelExec[id=0,
recipient=1]-ClientSessionImpl[installad...@vmr-iod-1001.ete.ka.sw.ericsson.se/10.223.58.242:22])
2024-08-07 13:09:25,589 DEBUG [sshd-SshClient[569b3009]-nio2-thread-5]
o.a.s.c.c.ChannelExec sendEof(ChannelExec[id=0,
recipient=1]-ClientSessionImpl[installad...@vmr-iod-1001.ete.ka.sw.ericsson.se/10.223.58.242:22])
already closing or closed - state=Immediate
2024-08-07 13:09:25,589 DEBUG [sshd-SshClient[569b3009]-nio2-thread-5]
o.a.s.c.c.AbstractChannel$GracefulChannelCloseable close(ChannelExec[id=0,
recipient=1]-ClientSessionImpl[installad...@vmr-iod-1001.ete.ka.sw.ericsson.se/10.223.58.242:22])[immediately=true]
processing
2024-08-07 13:09:25,589 DEBUG [sshd-SshClient[569b3009]-nio2-thread-5]
o.a.s.c.s.ClientConnectionService unregisterChannel(ChannelExec[id=0,
recipient=1]-ClientSessionImpl[installad...@vmr-iod-1001.ete.ka.sw.ericsson.se/10.223.58.242:22])
result=ChannelExec[id=0,
recipient=1]-ClientSessionImpl[installad...@vmr-iod-1001.ete.ka.sw.ericsson.se/10.223.58.242:22]
2024-08-07 13:09:25,589 DEBUG [sshd-SshClient[569b3009]-nio2-thread-5]
o.a.s.c.u.c.SequentialCloseable
doClose(org.apache.sshd.common.util.closeable.SequentialCloseable$1@1b3126aa)
signal close complete immediately=true
```
### Other information
Local Windows 10 with Apache Mina SSHD 2.13.1
Server - RHEL 9.2 - openssh-8.7p1-30.el9_2.3.x86_64 -
openssh-server-8.7p1-30.el9_2.3.x86_64
Java 8 Runtime Environment (for the client)
--
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: dev-unsubscr...@mina.apache.org.apache.org
For queries about this service, please contact Infrastructure at:
us...@infra.apache.org
---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscr...@mina.apache.org
For additional commands, e-mail: dev-h...@mina.apache.org
