Uploaded image for project: 'Red Hat Fuse'
  1. Red Hat Fuse
  2. ENTESB-3138

SSHD exceptions logged at INFO level

XMLWordPrintable

    • Icon: Bug Bug
    • Resolution: Done
    • Icon: Major Major
    • jboss-fuse-6.2.1
    • jboss-fuse-6.2
    • Fabric8 v1
    • None
    • % %

      Many fabric operations trhow INFO level exceptions related to SSHD.

      ex.

      2015-05-04 15:15:01,380 | INFO  | 4]-nio2-thread-2 | Nio2Session                      | 58 - 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)[58:org.apache.sshd.core:0.12.0.redhat-002]
      	at org.apache.sshd.common.io.nio2.Nio2Session.exceptionCaught(Nio2Session.java:126)[58:org.apache.sshd.core:0.12.0.redhat-002]
      	at org.apache.sshd.common.io.nio2.Nio2Session.access$500(Nio2Session.java:47)[58: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.7.0_76]
      	at org.apache.sshd.common.io.nio2.Nio2CompletionHandler.failed(Nio2CompletionHandler.java:39)[58:org.apache.sshd.core:0.12.0.redhat-002]
      	at sun.nio.ch.Invoker.invokeUnchecked(Invoker.java:128)[:1.7.0_76]
      	at sun.nio.ch.Invoker.invokeDirect(Invoker.java:157)[:1.7.0_76]
      	at sun.nio.ch.UnixAsynchronousSocketChannelImpl.implWrite(UnixAsynchronousSocketChannelImpl.java:736)[:1.7.0_76]
      	at sun.nio.ch.AsynchronousSocketChannelImpl.write(AsynchronousSocketChannelImpl.java:381)[:1.7.0_76]
      	at sun.nio.ch.AsynchronousSocketChannelImpl.write(AsynchronousSocketChannelImpl.java:398)[:1.7.0_76]
      	at java.nio.channels.AsynchronousSocketChannel.write(AsynchronousSocketChannel.java:560)[:1.7.0_76]
      	at org.apache.sshd.common.io.nio2.Nio2Session.startWriting(Nio2Session.java:212)[58:org.apache.sshd.core:0.12.0.redhat-002]
      	at org.apache.sshd.common.io.nio2.Nio2Session.write(Nio2Session.java:115)[58:org.apache.sshd.core:0.12.0.redhat-002]
      	at org.apache.sshd.common.session.AbstractSession.doWritePacket(AbstractSession.java:508)[58:org.apache.sshd.core:0.12.0.redhat-002]
      	at org.apache.sshd.common.session.AbstractSession.writePacket(AbstractSession.java:495)[58:org.apache.sshd.core:0.12.0.redhat-002]
      	at org.apache.sshd.common.session.AbstractSession.sendKexInit(AbstractSession.java:856)[58:org.apache.sshd.core:0.12.0.redhat-002]
      	at org.apache.sshd.server.session.ServerSession.sendKexInit(ServerSession.java:128)[58:org.apache.sshd.core:0.12.0.redhat-002]
      	at org.apache.sshd.server.session.ServerSession.<init>(ServerSession.java:60)[58:org.apache.sshd.core:0.12.0.redhat-002]
      	at org.apache.sshd.server.session.SessionFactory.doCreateSession(SessionFactory.java:43)[58:org.apache.sshd.core:0.12.0.redhat-002]
      	at org.apache.sshd.common.session.AbstractSessionFactory.createSession(AbstractSessionFactory.java:38)[58:org.apache.sshd.core:0.12.0.redhat-002]
      	at org.apache.sshd.common.AbstractSessionIoHandler.sessionCreated(AbstractSessionIoHandler.java:36)[58:org.apache.sshd.core:0.12.0.redhat-002]
      	at org.apache.sshd.common.io.nio2.Nio2Acceptor$AcceptCompletionHandler.onCompleted(Nio2Acceptor.java:127)[58:org.apache.sshd.core:0.12.0.redhat-002]
      	at org.apache.sshd.common.io.nio2.Nio2Acceptor$AcceptCompletionHandler.onCompleted(Nio2Acceptor.java:108)[58: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.7.0_76]
      	at org.apache.sshd.common.io.nio2.Nio2CompletionHandler.completed(Nio2CompletionHandler.java:30)[58:org.apache.sshd.core:0.12.0.redhat-002]
      	at sun.nio.ch.Invoker.invokeUnchecked(Invoker.java:126)[:1.7.0_76]
      	at sun.nio.ch.Invoker$2.run(Invoker.java:218)[:1.7.0_76]
      	at sun.nio.ch.AsynchronousChannelGroupImpl$1.run(AsynchronousChannelGroupImpl.java:112)[:1.7.0_76]
      	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)[:1.7.0_76]
      	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)[:1.7.0_76]
      	at java.lang.Thread.run(Thread.java:745)[:1.7.0_76]
      Caused by: java.io.IOException: Broken pipe
      	at sun.nio.ch.FileDispatcherImpl.write0(Native Method)[:1.7.0_76]
      	at sun.nio.ch.SocketDispatcher.write(SocketDispatcher.java:47)[:1.7.0_76]
      	at sun.nio.ch.IOUtil.writeFromNativeBuffer(IOUtil.java:93)[:1.7.0_76]
      	at sun.nio.ch.IOUtil.write(IOUtil.java:65)[:1.7.0_76]
      	at sun.nio.ch.UnixAsynchronousSocketChannelImpl.implWrite(UnixAsynchronousSocketChannelImpl.java:691)[:1.7.0_76]
      	... 24 more
      

      They happens at start/stop time of containers.

      Can we just change the default log level for Nio2Session?

      equivalent of:

      profile-edit --pid org.ops4j.pax.logging/log4j.logger.org.apache.sshd.common.io.nio2.Nio2Session=WARN karaf
      

      ?

            yfang@redhat.com Freeman(Yue) Fang
            pantinor@redhat.com Paolo Antinori
            Votes:
            0 Vote for this issue
            Watchers:
            3 Start watching this issue

              Created:
              Updated:
              Resolved: