ignite-issues mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From "Ilya Suntsov (JIRA)" <j...@apache.org>
Subject [jira] [Updated] (IGNITE-5538) NPE (PersistentStoreExample)
Date Mon, 19 Jun 2017 10:26:00 GMT

     [ https://issues.apache.org/jira/browse/IGNITE-5538?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel
]

Ilya Suntsov updated IGNITE-5538:
---------------------------------
    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:
{noformat}
[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
{noformat}

Full logs in attachment.

  was:
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:
{noformat}
[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
{noformat}


> NPE (PersistentStoreExample)
> ----------------------------
>
>                 Key: IGNITE-5538
>                 URL: https://issues.apache.org/jira/browse/IGNITE-5538
>             Project: Ignite
>          Issue Type: Bug
>          Components: cache
>    Affects Versions: 2.1
>            Reporter: Ilya Suntsov
>             Fix For: 2.1
>
>         Attachments: PersistentStoreExampleNode.txt, PersistentStoreExample.txt
>
>
> 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:
> {noformat}
> [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
> {noformat}
> Full logs in attachment.



--
This message was sent by Atlassian JIRA
(v6.4.14#64029)

Mime
View raw message