channel not registered to an event loop

[chraj...@gmail.com]

Hi Netty Team,

I'm working on a project, during hand shake i'm seeing the following exception.

We are trying to implement the RFC (https://tools.ietf.org/html/rfc8071), as part of this we are using 3rd party library called ODL (opendaylight), which is internally using apache sshd/netty api.

During the handshake, after authentication, and during data exchange we are seeing the "channel not registered to an event loop" exception.

Could you pl. help me in resolving this issue?

2017-08-04 18:47:21.323 [NioProcessor-2] DEBUG org.opendaylight.netconf.callhome.protocol.NetconfCallHomeServer - Authorize success

2017-08-04 18:47:22.306 [NioProcessor-2] DEBUG org.apache.sshd.client.session.ClientConnectionService - Received SSH_MSG_CHANNEL_OPEN_CONFIRMATION on channel 0

2017-08-04 18:47:22.306 [NioProcessor-2] DEBUG org.apache.sshd.client.channel.ChannelSubsystem - Send SSH_MSG_CHANNEL_REQUEST exec

2017-08-04 18:47:22.376 [NioProcessor-2] DEBUG io.netty.channel.DefaultChannelId - -Dio.netty.processId: 16360 (auto-detected)

2017-08-04 18:47:22.380 [NioProcessor-2] DEBUG io.netty.channel.DefaultChannelId - -Dio.netty.machineId: 08:00:27:ff:fe:41:57:ce (auto-detected)

2017-08-04 18:47:22.440 [NioProcessor-2] DEBUG io.netty.buffer.PooledByteBufAllocator - -Dio.netty.allocator.numHeapArenas: 4

2017-08-04 18:47:22.440 [NioProcessor-2] DEBUG io.netty.buffer.PooledByteBufAllocator - -Dio.netty.allocator.numDirectArenas: 4

2017-08-04 18:47:22.440 [NioProcessor-2] DEBUG io.netty.buffer.PooledByteBufAllocator - -Dio.netty.allocator.pageSize: 8192

2017-08-04 18:47:22.440 [NioProcessor-2] DEBUG io.netty.buffer.PooledByteBufAllocator - -Dio.netty.allocator.maxOrder: 11

2017-08-04 18:47:22.441 [NioProcessor-2] DEBUG io.netty.buffer.PooledByteBufAllocator - -Dio.netty.allocator.chunkSize: 16777216

2017-08-04 18:47:22.442 [NioProcessor-2] DEBUG io.netty.buffer.PooledByteBufAllocator - -Dio.netty.allocator.tinyCacheSize: 512

2017-08-04 18:47:22.442 [NioProcessor-2] DEBUG io.netty.buffer.PooledByteBufAllocator - -Dio.netty.allocator.smallCacheSize: 256

2017-08-04 18:47:22.442 [NioProcessor-2] DEBUG io.netty.buffer.PooledByteBufAllocator - -Dio.netty.allocator.normalCacheSize: 64

2017-08-04 18:47:22.442 [NioProcessor-2] DEBUG io.netty.buffer.PooledByteBufAllocator - -Dio.netty.allocator.maxCachedBufferCapacity: 32768

2017-08-04 18:47:22.443 [NioProcessor-2] DEBUG io.netty.buffer.PooledByteBufAllocator - -Dio.netty.allocator.cacheTrimInterval: 8192

2017-08-04 18:47:22.443 [NioProcessor-2] DEBUG io.netty.buffer.PooledByteBufAllocator - -Dio.netty.allocator.useCacheForAllThreads: true

2017-08-04 18:47:22.453 [NioProcessor-2] DEBUG io.netty.buffer.ByteBufUtil - -Dio.netty.allocator.type: pooled

2017-08-04 18:47:22.453 [NioProcessor-2] DEBUG io.netty.buffer.ByteBufUtil - -Dio.netty.threadLocalDirectBufferSize: 65536

2017-08-04 18:47:22.453 [NioProcessor-2] DEBUG io.netty.buffer.ByteBufUtil - -Dio.netty.maxThreadLocalCharBufferSize: 16384

2017-08-04 18:47:23.938 [NioProcessor-2] DEBUG org.apache.sshd.client.channel.ChannelSubsystem - Received SSH_MSG_CHANNEL_DATA on channel ChannelSubsystem[id=0, recipient=43]

2017-08-04 18:47:23.957 [NioProcessor-2] DEBUG io.netty.buffer.AbstractByteBuf - -Dio.netty.buffer.bytebuf.checkAccessible: true

2017-08-04 18:47:23.957 [NioProcessor-2] DEBUG io.netty.util.ResourceLeakDetectorFactory - Loaded default ResourceLeakDetector: io.netty.util.ResourceLeakDetector@64b0db7a

2017-08-04 18:47:23.958 [NioProcessor-2] WARN  org.apache.sshd.common.channel.ChannelAsyncInputStream$IoReadFutureImpl - Listener threw an exception

java.lang.IllegalStateException: channel not registered to an event loop

at io.netty.channel.AbstractChannel.eventLoop(AbstractChannel.java:158) ~[netty-transport-4.1.13.Final.jar:4.1.13.Final]

at io.netty.channel.AbstractChannelHandlerContext.executor(AbstractChannelHandlerContext.java:115) ~[netty-transport-4.1.13.Final.jar:4.1.13.Final]

at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:346) ~[netty-transport-4.1.13.Final.jar:4.1.13.Final]

at io.netty.channel.DefaultChannelPipeline.fireChannelRead(DefaultChannelPipeline.java:926) ~[netty-transport-4.1.13.Final.jar:4.1.13.Final]

at org.opendaylight.netconf.callhome.protocol.MinaSshNettyChannel$FireReadMessage.onMessageRead(MinaSshNettyChannel.java:159) ~[callhome-protocol-1.2.1-Carbon.jar:?]

at org.opendaylight.netconf.nettyutil.handler.ssh.client.AsyncSshHandlerReader.operationComplete(AsyncSshHandlerReader.java:71) ~[netconf-netty-util-1.2.1-Carbon.jar:?]

at org.opendaylight.netconf.nettyutil.handler.ssh.client.AsyncSshHandlerReader.operationComplete(AsyncSshHandlerReader.java:24) ~[netconf-netty-util-1.2.1-Carbon.jar:?]

at org.apache.sshd.common.future.DefaultSshFuture.notifyListener(DefaultSshFuture.java:269) [sshd-core-0.14.0.jar:0.14.0]

at org.apache.sshd.common.future.DefaultSshFuture.notifyListeners(DefaultSshFuture.java:254) [sshd-core-0.14.0.jar:0.14.0]

at org.apache.sshd.common.future.DefaultSshFuture.setValue(DefaultSshFuture.java:173) [sshd-core-0.14.0.jar:0.14.0]

at org.apache.sshd.common.channel.ChannelAsyncInputStream.doRead(ChannelAsyncInputStream.java:112) [sshd-core-0.14.0.jar:0.14.0]

at org.apache.sshd.common.channel.ChannelAsyncInputStream.write(ChannelAsyncInputStream.java:49) [sshd-core-0.14.0.jar:0.14.0]

at org.apache.sshd.client.channel.AbstractClientChannel.doWriteData(AbstractClientChannel.java:263) [sshd-core-0.14.0.jar:0.14.0]

at org.apache.sshd.common.channel.AbstractChannel.handleData(AbstractChannel.java:233) [sshd-core-0.14.0.jar:0.14.0]

at org.apache.sshd.common.session.AbstractConnectionService.channelData(AbstractConnectionService.java:215) [sshd-core-0.14.0.jar:0.14.0]

at org.apache.sshd.common.session.AbstractConnectionService.process(AbstractConnectionService.java:156) [sshd-core-0.14.0.jar:0.14.0]

at org.apache.sshd.common.session.AbstractSession.doHandleMessage(AbstractSession.java:431) [sshd-core-0.14.0.jar:0.14.0]

at org.apache.sshd.common.session.AbstractSession.handleMessage(AbstractSession.java:326) [sshd-core-0.14.0.jar:0.14.0]

at org.apache.sshd.client.session.ClientSessionImpl.handleMessage(ClientSessionImpl.java:306) [sshd-core-0.14.0.jar:0.14.0]

at org.apache.sshd.common.session.AbstractSession.decode(AbstractSession.java:780) [sshd-core-0.14.0.jar:0.14.0]

at org.apache.sshd.common.session.AbstractSession.messageReceived(AbstractSession.java:308) [sshd-core-0.14.0.jar:0.14.0]

at org.apache.sshd.common.AbstractSessionIoHandler.messageReceived(AbstractSessionIoHandler.java:54) [sshd-core-0.14.0.jar:0.14.0]

at org.apache.sshd.common.io.mina.MinaService.messageReceived(MinaService.java:105) [sshd-core-0.14.0.jar:0.14.0]

at org.apache.mina.core.filterchain.DefaultIoFilterChain$TailFilter.messageReceived(DefaultIoFilterChain.java:858) [mina-core-2.0.16.jar:?]

at org.apache.mina.core.filterchain.DefaultIoFilterChain.callNextMessageReceived(DefaultIoFilterChain.java:542) [mina-core-2.0.16.jar:?]

at org.apache.mina.core.filterchain.DefaultIoFilterChain.access$1300(DefaultIoFilterChain.java:48) [mina-core-2.0.16.jar:?]

at org.apache.mina.core.filterchain.DefaultIoFilterChain$EntryImpl$1.messageReceived(DefaultIoFilterChain.java:947) [mina-core-2.0.16.jar:?]

at org.apache.mina.core.filterchain.IoFilterAdapter.messageReceived(IoFilterAdapter.java:109) [mina-core-2.0.16.jar:?]

at org.apache.mina.core.filterchain.DefaultIoFilterChain.callNextMessageReceived(DefaultIoFilterChain.java:542) [mina-core-2.0.16.jar:?]

at org.apache.mina.core.filterchain.DefaultIoFilterChain.fireMessageReceived(DefaultIoFilterChain.java:535) [mina-core-2.0.16.jar:?]

at org.apache.mina.core.polling.AbstractPollingIoProcessor.read(AbstractPollingIoProcessor.java:703) [mina-core-2.0.16.jar:?]

at org.apache.mina.core.polling.AbstractPollingIoProcessor.process(AbstractPollingIoProcessor.java:659) [mina-core-2.0.16.jar:?]

at org.apache.mina.core.polling.AbstractPollingIoProcessor.process(AbstractPollingIoProcessor.java:648) [mina-core-2.0.16.jar:?]

at org.apache.mina.core.polling.AbstractPollingIoProcessor.access$600(AbstractPollingIoProcessor.java:68) [mina-core-2.0.16.jar:?]

at org.apache.mina.core.polling.AbstractPollingIoProcessor$Processor.run(AbstractPollingIoProcessor.java:1120) [mina-core-2.0.16.jar:?]

at org.apache.mina.util.NamePreservingRunnable.run(NamePreservingRunnable.java:64) [mina-core-2.0.16.jar:?]

at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) [?:1.8.0_131]

at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) [?:1.8.0_131]

at java.lang.Thread.run(Thread.java:748) [?:1.8.0_131]

2017-08-04 18:47:23.966 [NioProcessor-2] DEBUG org.apache.sshd.client.channel.ChannelSubsystem - Received SSH_MSG_CHANNEL_DATA on channel ChannelSubsystem[id=0, recipient=43]

2017-08-04 18:47:23.970 [NioProcessor-2] DEBUG org.apache.sshd.client.channel.ChannelSubsystem - Received SSH_MSG_CHANNEL_DATA on channel ChannelSubsystem[id=0, recipient=43]

Thanks in advance,

Raja Reddy

[Norman Maurer]

This means the Channel was not registered on the EventLoop yet which is most likely means they did not correctly bootstrap the Channel via the Bootstrap class.

You will need t report this back to the ODL project.

--
You received this message because you are subscribed to the Google Groups "Netty discussions" group.
To unsubscribe from this group and stop receiving emails from it, send an email to netty+un...@googlegroups.com.
To view this discussion on the web visit https://groups.google.com/d/msgid/netty/48700404-8661-41de-a7d8-9465aa3023a9%40googlegroups.com.
For more options, visit https://groups.google.com/d/optout.