[IGNITE-5538] NPE (PersistentStoreExample)

• Type: Bug
• Status: Closed
• Priority: Major
• Resolution: Fixed
• Affects Version/s: 2.1
• Fix Version/s: 2.1
• Component/s: cache
• Assignee: Alexey Goncharuk
• Reporter: Ilya Suntsov
• Created: 19/Jun/17 10:14
• Updated: 13/Jul/17 10:46
• Resolved: 11/Jul/17 08:47

Description

Steps to reproduce:
1. Start PersistentStoreExampleNodeStartup
2. Start PersistentStoreExample (UPLOAD=true)
Result:
1. Topology snapshot [ver=2, servers=1, clients=1, CPUs=8, heap=7.1GB]
2. Started preloading
3. On ExampleNode got exception:

[2017-06-19 13:11:28,545][WARN ][grid-nio-worker-tcp-comm-3-#20%null%][TcpCommunicationSpi] Failed to process selector key (will close): GridSelectorNioSessionImpl [worker=DirectNioClientWorker [super=AbstractNioClientWorker [idx=3, bytesRcvd=2052, bytesSent=252, bytesRcvd0=228, bytesSent0=28, select=true, super=GridWorker [name=grid-nio-worker-tcp-comm-3, igniteInstanceName=null, finished=false, hashCode=1279096191, interrupted=false, runner=grid-nio-worker-tcp-comm-3-#20%null%]]], writeBuf=java.nio.DirectByteBuffer[pos=0 lim=32768 cap=32768], readBuf=java.nio.DirectByteBuffer[pos=4 lim=186 cap=32768], inRecovery=GridNioRecoveryDescriptor [acked=103, resendCnt=0, rcvCnt=104, sentCnt=103, reserved=true, lastAck=96, nodeLeft=false, node=TcpDiscoveryNode [id=be66eae2-3986-4772-b02b-bf2813370a15, addrs=[0:0:0:0:0:0:0:1, 127.0.0.1, 172.25.4.115, 172.25.4.116, 2001:db8:85a3:0:0:8a2e:370:7334], sockAddrs=[/0:0:0:0:0:0:0:1:0, /127.0.0.1:0, /172.25.4.115:0, /172.25.4.116:0, /2001:db8:85a3:0:0:8a2e:370:7334:0], discPort=0, order=2, intOrder=2, lastExchangeTime=1497867042970, loc=false, ver=2.1.1#20170618-sha1:09ce29e0, isClient=true], connected=true, connectCnt=1, queueLimit=4096, reserveCnt=35, pairedConnections=false], outRecovery=GridNioRecoveryDescriptor [acked=103, resendCnt=0, rcvCnt=104, sentCnt=103, reserved=true, lastAck=96, nodeLeft=false, node=TcpDiscoveryNode [id=be66eae2-3986-4772-b02b-bf2813370a15, addrs=[0:0:0:0:0:0:0:1, 127.0.0.1, 172.25.4.115, 172.25.4.116, 2001:db8:85a3:0:0:8a2e:370:7334], sockAddrs=[/0:0:0:0:0:0:0:1:0, /127.0.0.1:0, /172.25.4.115:0, /172.25.4.116:0, /2001:db8:85a3:0:0:8a2e:370:7334:0], discPort=0, order=2, intOrder=2, lastExchangeTime=1497867042970, loc=false, ver=2.1.1#20170618-sha1:09ce29e0, isClient=true], connected=true, connectCnt=1, queueLimit=4096, reserveCnt=35, pairedConnections=false], super=GridNioSessionImpl [locAddr=/0:0:0:0:0:0:0:1:47100, rmtAddr=/0:0:0:0:0:0:0:1:60813, createTime=1497867087529, closeTime=0, bytesSent=28, bytesRcvd=228, bytesSent0=28, bytesRcvd0=228, sndSchedTime=1497867087529, lastSndTime=1497867087529, lastRcvTime=1497867087541, readsPaused=false, filterChain=FilterChain[filters=[GridNioCodecFilter [parser=o.a.i.i.util.nio.GridDirectParser@7a94a8f6, directMode=true], GridConnectionBytesVerifyFilter], accepted=true]]
[2017-06-19 13:11:28,545][ERROR][grid-nio-worker-tcp-comm-3-#20%null%][TcpCommunicationSpi] Closing NIO session because of unhandled exception.
class org.apache.ignite.internal.util.nio.GridNioException: null
	at org.apache.ignite.internal.util.nio.GridNioServer$AbstractNioClientWorker.processSelectedKeysOptimized(GridNioServer.java:2199)
	at org.apache.ignite.internal.util.nio.GridNioServer$AbstractNioClientWorker.bodyInternal(GridNioServer.java:1968)
	at org.apache.ignite.internal.util.nio.GridNioServer$AbstractNioClientWorker.body(GridNioServer.java:1669)
	at org.apache.ignite.internal.util.worker.GridWorker.run(GridWorker.java:110)
	at java.lang.Thread.run(Thread.java:745)
Caused by: java.lang.NullPointerException
	at org.apache.ignite.internal.managers.communication.GridIoMessageFactory.create(GridIoMessageFactory.java:879)
	at org.apache.ignite.spi.communication.tcp.TcpCommunicationSpi$9.create(TcpCommunicationSpi.java:2134)
	at org.apache.ignite.internal.direct.stream.v2.DirectByteBufferStreamImplV2.readMessage(DirectByteBufferStreamImplV2.java:1154)
	at org.apache.ignite.internal.direct.DirectMessageReader.readMessage(DirectMessageReader.java:311)
	at org.apache.ignite.internal.managers.communication.GridIoMessage.readFrom(GridIoMessage.java:261)
	at org.apache.ignite.internal.util.nio.GridDirectParser.decode(GridDirectParser.java:90)
	at org.apache.ignite.internal.util.nio.GridNioCodecFilter.onMessageReceived(GridNioCodecFilter.java:114)
	at org.apache.ignite.internal.util.nio.GridNioFilterAdapter.proceedMessageReceived(GridNioFilterAdapter.java:109)
	at org.apache.ignite.internal.util.nio.GridConnectionBytesVerifyFilter.onMessageReceived(GridConnectionBytesVerifyFilter.java:133)
	at org.apache.ignite.internal.util.nio.GridNioFilterAdapter.proceedMessageReceived(GridNioFilterAdapter.java:109)
	at org.apache.ignite.internal.util.nio.GridNioServer$HeadFilter.onMessageReceived(GridNioServer.java:3222)
	at org.apache.ignite.internal.util.nio.GridNioFilterChain.onMessageReceived(GridNioFilterChain.java:175)
	at org.apache.ignite.internal.util.nio.GridNioServer$DirectNioClientWorker.processRead(GridNioServer.java:1213)
	at org.apache.ignite.internal.util.nio.GridNioServer$AbstractNioClientWorker.processSelectedKeysOptimized(GridNioServer.java:2180)
	... 4 more

Full logs in attachment.

Issue Links

• links to: GitHub Pull Request #2273