Admin status command generates excessive logs
Issue
Running an out-of-the-box A-MQ 6.2.0+P2 patch, when launching the below status command, a large series of logs is generated. This is particularly bad when using this admin command as a recurring health-monitoring entry point (let's say: every minute).
./admin status root
2015-11-06 16:43:24,295 | INFO | d]-nio2-thread-1 | Nio2Session | .sshd.common.io.nio2.Nio2Session 128 | 39 -
org.apache.sshd.core - 0.12.0.redhat-002 | Exception handler threw exception, closing the session
java.lang.IllegalStateException: No session available
at org.apache.sshd.common.AbstractSessionIoHandler.exceptionCaught(AbstractSessionIoHandler.java:49)[39:org.apache.sshd.core:0.12.0.redhat-002]
at org.apache.sshd.common.io.nio2.Nio2Session.exceptionCaught(Nio2Session.java:126)[39:org.apache.sshd.core:0.12.0.redhat-002]
at org.apache.sshd.common.io.nio2.Nio2Session.access$500(Nio2Session.java:47)[39:org.apache.sshd.core:0.12.0.redhat-002]
at org.apache.sshd.common.io.nio2.Nio2Session$2.onFailed(Nio2Session.java:230)
at org.apache.sshd.common.io.nio2.Nio2CompletionHandler$2.run(Nio2CompletionHandler.java:41)
at java.security.AccessController.doPrivileged(Native Method)[:1.8.0_45]
at org.apache.sshd.common.io.nio2.Nio2CompletionHandler.failed(Nio2CompletionHandler.java:39)[39:org.apache.sshd.core:0.12.0.redhat-002]
at sun.nio.ch.Invoker.invokeUnchecked(Invoker.java:128)[:1.8.0_45]
at sun.nio.ch.Invoker.invokeDirect(Invoker.java:157)[:1.8.0_45]
at sun.nio.ch.UnixAsynchronousSocketChannelImpl.implWrite(UnixAsynchronousSocketChannelImpl.java:736)[:1.8.0_45]
at sun.nio.ch.AsynchronousSocketChannelImpl.write(AsynchronousSocketChannelImpl.java:382)[:1.8.0_45]
at sun.nio.ch.AsynchronousSocketChannelImpl.write(AsynchronousSocketChannelImpl.java:399)[:1.8.0_45]
at java.nio.channels.AsynchronousSocketChannel.write(AsynchronousSocketChannel.java:577)[:1.8.0_45]
at org.apache.sshd.common.io.nio2.Nio2Session.startWriting(Nio2Session.java:212)[39:org.apache.sshd.core:0.12.0.redhat-002]
at org.apache.sshd.common.io.nio2.Nio2Session.write(Nio2Session.java:115)[39:org.apache.sshd.core:0.12.0.redhat-002]
at org.apache.sshd.common.session.AbstractSession.doWritePacket(AbstractSession.java:508)[39:org.apache.sshd.core:0.12.0.redhat-002]
at org.apache.sshd.common.session.AbstractSession.writePacket(AbstractSession.java:495)[39:org.apache.sshd.core:0.12.0.redhat-002]
at org.apache.sshd.common.session.AbstractSession.sendKexInit(AbstractSession.java:856)[39:org.apache.sshd.core:0.12.0.redhat-002]
at org.apache.sshd.server.session.ServerSession.sendKexInit(ServerSession.java:128)[39:org.apache.sshd.core:0.12.0.redhat-002]
at org.apache.sshd.server.session.ServerSession.<init>(ServerSession.java:60)[39:org.apache.sshd.core:0.12.0.redhat-002]
at org.apache.sshd.server.session.SessionFactory.doCreateSession(SessionFactory.java:43)[39:org.apache.sshd.core:0.12.0.redhat-002]
at org.apache.sshd.common.session.AbstractSessionFactory.createSession(AbstractSessionFactory.java:38)[39:org.apache.sshd.core:0.12.0.redhat-002]
at org.apache.sshd.common.AbstractSessionIoHandler.sessionCreated(AbstractSessionIoHandler.java:36)[39:org.apache.sshd.core:0.12.0.redhat-002]
at org.apache.sshd.common.io.nio2.Nio2Acceptor$AcceptCompletionHandler.onCompleted(Nio2Acceptor.java:127)[39:org.apache.sshd.core:0.12.0.redhat-002]
at org.apache.sshd.common.io.nio2.Nio2Acceptor$AcceptCompletionHandler.onCompleted(Nio2Acceptor.java:108)[39:org.apache.sshd.core:0.12.0.redhat-002]
at org.apache.sshd.common.io.nio2.Nio2CompletionHandler$1.run(Nio2CompletionHandler.java:32)
at java.security.AccessController.doPrivileged(Native Method)[:1.8.0_45]
at org.apache.sshd.common.io.nio2.Nio2CompletionHandler.completed(Nio2CompletionHandler.java:30)[39:org.apache.sshd.core:0.12.0.redhat-002]
at sun.nio.ch.Invoker.invokeUnchecked(Invoker.java:126)[:1.8.0_45]
at sun.nio.ch.Invoker$2.run(Invoker.java:218)[:1.8.0_45]
at sun.nio.ch.AsynchronousChannelGroupImpl$1.run(AsynchronousChannelGroupImpl.java:112)[:1.8.0_45]
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)[:1.8.0_45]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)[:1.8.0_45]
at java.lang.Thread.run(Thread.java:745)[:1.8.0_45]
Caused by: java.io.IOException: Broken pipe
at sun.nio.ch.FileDispatcherImpl.write0(Native Method)[:1.8.0_45]
at sun.nio.ch.SocketDispatcher.write(SocketDispatcher.java:47)[:1.8.0_45]
at sun.nio.ch.IOUtil.writeFromNativeBuffer(IOUtil.java:93)[:1.8.0_45]
at sun.nio.ch.IOUtil.write(IOUtil.java:65)[:1.8.0_45]
at sun.nio.ch.UnixAsynchronousSocketChannelImpl.implWrite(UnixAsynchronousSocketChannelImpl.java:691)[:1.8.0_45]
... 24 more
2015-11-06 16:45:24,532 | INFO | ]-timer-thread-1 | ServerSession | d.common.session.AbstractSession 1052 | 39 -
org.apache.sshd.core - 0.12.0.redhat-002 | Disconnecting: Session has timed out waiting for authentication after 120000 ms.
Occurs every minute as the command is used for automatic health monitoring
Environment
- Red Hat JBoss A-MQ
- 6.2.0, 6.1.0
Subscriber exclusive content
A Red Hat subscription provides unlimited access to our knowledgebase, tools, and much more.