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 of over 48,000 articles and solutions.
Welcome! Check out the Getting Started with Red Hat page for quick tours and guides for common tasks.
