Sergey Kozlov created IGNITE-10136:
--------------------------------------
Summary: NPE in PartitionUpdateCountersMessage
Key: IGNITE-10136
URL: https://issues.apache.org/jira/browse/IGNITE-10136
Project: Ignite
Issue Type: Bug
Affects Versions: 2.7
Reporter: Sergey Kozlov
Fix For: 2.7
{noformat}
[14:00:55,950][INFO][db-checkpoint-thread-#73][GridCacheDatabaseSharedManager]
Checkpoint started [checkpointId=9d5398bc-896a-469c-8686-38e2afd517c1,
startPtr=FileWALPointer [idx=0, fileOff=17828636, len=210609],
checkpointLockWait=0ms, checkpointLockHoldTime=11ms,
walCpRecordFsyncDuration=12ms, pages=636, reason='timeout']
[14:00:56,029][INFO][db-checkpoint-thread-#73][GridCacheDatabaseSharedManager]
Checkpoint finished [cpId=9d5398bc-896a-469c-8686-38e2afd517c1, pages=636,
markPos=FileWALPointer [idx=0, fileOff=17828636, len=210609],
walSegmentsCleared=0, walSegmentsCovered=[], markDuration=26ms,
pagesWrite=21ms, fsync=58ms, total=105ms]
[14:00:56,940][INFO][db-checkpoint-thread-#73][GridCacheDatabaseSharedManager]
Checkpoint started [checkpointId=5f46c89e-ead8-4c87-adad-72a50c26bd7c,
startPtr=FileWALPointer [idx=0, fileOff=20005440, len=210609],
checkpointLockWait=0ms, checkpointLockHoldTime=8ms,
walCpRecordFsyncDuration=5ms, pages=474, reason='timeout']
[14:00:57,003][INFO][db-checkpoint-thread-#73][GridCacheDatabaseSharedManager]
Checkpoint finished [cpId=5f46c89e-ead8-4c87-adad-72a50c26bd7c, pages=474,
markPos=FileWALPointer [idx=0, fileOff=20005440, len=210609],
walSegmentsCleared=0, walSegmentsCovered=[], markDuration=15ms,
pagesWrite=10ms, fsync=53ms, total=78ms]
[14:00:57,065][SEVERE][grid-nio-worker-tcp-comm-2-#42][GridDirectParser] Failed
to read message [msg=GridIoMessage [plc=0, topic=null, topicOrd=-1,
ordered=false, timeout=0, skipOnTimeout=false, msg=null],
buf=java.nio.DirectByteBuffer[pos=792 lim=885 cap=32768],
reader=RollingUpgradeMessageReader [state=StateItem
[stream=DirectByteBufferStreamImplV2 [baseOff=140703933959040, arrOff=-1,
tmpArrOff=0, valReadBytes=0, tmpArrBytes=0, msgTypeDone=true,
msg=GridCacheIdMessage [cacheId=0]GridDistributedBaseMessage
[ver=GridCacheVersion [topVer=152301622, order=1540821647376, nodeOrder=4],
committedVers=null, rolledbackVers=null, cnt=0,
super=]GridDistributedTxPrepareRequest [threadId=236, concurrency=PESSIMISTIC,
isolation=REPEATABLE_READ, writeVer=GridCacheVersion [topVer=152301622,
order=1540821647377, nodeOrder=4], timeout=0, reads=null, writes=ArrayList [],
dhtVers=null, txSize=-1, plc=2, txState=null, flags=last,
super=]GridDhtTxPrepareRequest
[nearNodeId=3800f476-beb1-46b0-8a39-faa51c91831d,
futId=f794020c661-cc8749ef-caa5-4f1e-9d89-4a9beff59798, miniId=1,
topVer=AffinityTopologyVersion [topVer=5, minorTopVer=8],
invalidateNearEntries={}, nearWrites=null, owned=null,
nearXidVer=GridCacheVersion [topVer=152301622, order=1540821647374,
nodeOrder=5], subjId=3800f476-beb1-46b0-8a39-faa51c91831d, taskNameHash=0,
preloadKeys=null, mvccSnapshot=MvccSnapshotResponse [futId=1,
crdVer=1540821617885, cntr=16, opCntr=1, txs=null, cleanupVer=15, tracking=0],
skipCompletedVers=false, super=], mapIt=null, it=null, arrPos=-1,
keyDone=false, readSize=-1, readItems=0, prim=0, primShift=0, uuidState=0,
uuidMost=0, uuidLeast=0, uuidLocId=0, lastFinished=true], state=0, fieldCnt=7,
readFieldCnt=0, curName=msg, typeRead=true, itemTypeRead=false,
keyTypeRead=false, valTypeRead=false, curType=21, curItemType=null,
curKeyType=null, curValType=null, readMsgCls=class
o.a.i.i.managers.communication.GridIoMessage]StateItem
[stream=DirectByteBufferStreamImplV2 [baseOff=140703933959040, arrOff=-1,
tmpArrOff=0, valReadBytes=0, tmpArrBytes=0, msgTypeDone=true,
msg=PartitionUpdateCountersMessage{cacheId=-553317389, size=0, cntrs=},
mapIt=null, it=null, arrPos=-1, keyDone=false, readSize=1, readItems=0, prim=0,
primShift=0, uuidState=0, uuidMost=0, uuidLeast=0, uuidLocId=0,
lastFinished=true], state=0, fieldCnt=-1, readFieldCnt=0, curName=null,
typeRead=false, itemTypeRead=false, keyTypeRead=false, valTypeRead=false,
curType=0, curItemType=null, curKeyType=null, curValType=null,
readMsgCls=null]StateItem [stream=DirectByteBufferStreamImplV2
[baseOff=140703933959040, arrOff=-1, tmpArrOff=0, valReadBytes=0,
tmpArrBytes=0, msgTypeDone=false, msg=null, mapIt=null, it=null, arrPos=-1,
keyDone=false, readSize=-1, readItems=0, prim=0, primShift=0, uuidState=0,
uuidMost=0, uuidLeast=0, uuidLocId=0, lastFinished=true], state=0, fieldCnt=-1,
readFieldCnt=0, curName=null, typeRead=false, itemTypeRead=false,
keyTypeRead=false, valTypeRead=false, curType=0, curItemType=null,
curKeyType=null, curValType=null, readMsgCls=null]StateItem
[stream=DirectByteBufferStreamImplV2 [baseOff=140703933959040, arrOff=-1,
tmpArrOff=0, valReadBytes=0, tmpArrBytes=0, msgTypeDone=false, msg=null,
mapIt=null, it=null, arrPos=-1, keyDone=false, readSize=-1, readItems=0,
prim=0, primShift=0, uuidState=0, uuidMost=0, uuidLeast=0, uuidLocId=0,
lastFinished=true], state=0, fieldCnt=-1, readFieldCnt=0, curName=null,
typeRead=false, itemTypeRead=false, keyTypeRead=false, valTypeRead=false,
curType=0, curItemType=null, curKeyType=null, curValType=null,
readMsgCls=null]StateItem [stream=DirectByteBufferStreamImplV2
[baseOff=140703933959040, arrOff=-1, tmpArrOff=0, valReadBytes=0,
tmpArrBytes=0, msgTypeDone=false, msg=null, mapIt=null, it=null, arrPos=-1,
keyDone=false, readSize=-1, readItems=0, prim=0, primShift=0, uuidState=0,
uuidMost=0, uuidLeast=0, uuidLocId=0, lastFinished=true], state=0, fieldCnt=-1,
readFieldCnt=0, curName=null, typeRead=false, itemTypeRead=false,
keyTypeRead=false, valTypeRead=false, curType=0, curItemType=null,
curKeyType=null, curValType=null, readMsgCls=null]DirectMessageState [pos=0,
stack=[, , , , , null, null, null, null, null]], lastRead=true],
ses=GridSelectorNioSessionImpl [worker=GridWorker
[name=grid-nio-worker-tcp-comm-2, igniteInstanceName=null, finished=false,
heartbeatTs=1540821657026, hashCode=1663168647, interrupted=false,
runner=grid-nio-worker-tcp-comm-2-#42]AbstractNioClientWorker [idx=2,
bytesRcvd=1489505, bytesSent=791387, bytesRcvd0=874094, bytesSent0=495009,
select=true, super=]DirectNioClientWorker [super=],
writeBuf=java.nio.DirectByteBuffer[pos=0 lim=32768 cap=32768],
readBuf=java.nio.DirectByteBuffer[pos=792 lim=885 cap=32768],
inRecovery=GridNioRecoveryDescriptor [acked=1440, resendCnt=0, rcvCnt=1559,
sentCnt=1447, reserved=true, lastAck=1536, nodeLeft=false,
node=TcpDiscoveryNode [id=06168729-530d-4318-b066-29b42762edda, addrs=ArrayList
[127.0.0.1], sockAddrs=HashSet [/127.0.0.1:47503], discPort=47503, order=4,
intOrder=4, lastExchangeTime=1540821634193, loc=false,
ver=2.7.1#20181029-sha1:83c27088, isClient=false], connected=true,
connectCnt=0, queueLimit=4096, reserveCnt=1, pairedConnections=false],
outRecovery=GridNioRecoveryDescriptor [acked=1440, resendCnt=0, rcvCnt=1559,
sentCnt=1447, reserved=true, lastAck=1536, nodeLeft=false,
node=TcpDiscoveryNode [id=06168729-530d-4318-b066-29b42762edda, addrs=ArrayList
[127.0.0.1], sockAddrs=HashSet [/127.0.0.1:47503], discPort=47503, order=4,
intOrder=4, lastExchangeTime=1540821634193, loc=false,
ver=2.7.1#20181029-sha1:83c27088, isClient=false], connected=true,
connectCnt=0, queueLimit=4096, reserveCnt=1, pairedConnections=false],
super=GridNioSessionImpl [locAddr=/127.0.0.1:47101, rmtAddr=/127.0.0.1:41260,
createTime=1540821640738, closeTime=0, bytesSent=791387, bytesRcvd=1489505,
bytesSent0=495009, bytesRcvd0=874094, sndSchedTime=1540821640738,
lastSndTime=1540821657026, lastRcvTime=1540821657036, readsPaused=false,
filterChain=GridNioCodecFilter
[parser=o.a.i.i.util.nio.GridDirectParser@4d763e1f,
directMode=true]FilterChain[filters=[, GridConnectionBytesVerifyFilter],
accepted=true, markedForClose=false]]]
java.lang.NullPointerException
at
org.apache.ignite.internal.processors.cache.distributed.dht.PartitionUpdateCountersMessage.readFrom(PartitionUpdateCountersMessage.java:215)
at
org.apache.ignite.internal.direct.stream.v2.DirectByteBufferStreamImplV2.readMessage(DirectByteBufferStreamImplV2.java:1178)
at
org.apache.ignite.internal.direct.stream.v2.DirectByteBufferStreamImplV2.read(DirectByteBufferStreamImplV2.java:1779)
at
org.apache.ignite.internal.direct.stream.v2.DirectByteBufferStreamImplV2.readCollection(DirectByteBufferStreamImplV2.java:1257)
at
org.gridgain.grid.internal.communication.RollingUpgradeMessageReader.readCollection(RollingUpgradeMessageReader.java:401)
at
org.apache.ignite.internal.processors.cache.distributed.dht.GridDhtTxPrepareRequest.readFrom(GridDhtTxPrepareRequest.java:645)
at
org.apache.ignite.internal.direct.stream.v2.DirectByteBufferStreamImplV2.readMessage(DirectByteBufferStreamImplV2.java:1178)
at
org.gridgain.grid.internal.communication.RollingUpgradeMessageReader.readMessage(RollingUpgradeMessageReader.java:365)
at
org.apache.ignite.internal.managers.communication.GridIoMessage.readFrom(GridIoMessage.java:262)
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:3553)
at
org.apache.ignite.internal.util.nio.GridNioFilterChain.onMessageReceived(GridNioFilterChain.java:175)
at
org.apache.ignite.internal.util.nio.GridNioServer$DirectNioClientWorker.processRead(GridNioServer.java:1303)
at
org.apache.ignite.internal.util.nio.GridNioServer$AbstractNioClientWorker.processSelectedKeysOptimized(GridNioServer.java:2389)
at
org.apache.ignite.internal.util.nio.GridNioServer$AbstractNioClientWorker.bodyInternal(GridNioServer.java:2156)
at
org.apache.ignite.internal.util.nio.GridNioServer$AbstractNioClientWorker.body(GridNioServer.java:1797)
at
org.apache.ignite.internal.util.worker.GridWorker.run(GridWorker.java:120)
at java.lang.Thread.run(Thread.java:748)
[14:00:57,956][INFO][db-checkpoint-thread-#73][GridCacheDatabaseSharedManager]
Checkpoint started [checkpointId=82318c66-2933-493a-8e7e-c4170420cd70,
startPtr=FileWALPointer [idx=0, fileOff=20368709, len=210609],
checkpointLockWait=0ms, checkpointLockHoldTime=20ms,
walCpRecordFsyncDuration=1ms, pages=44, reason='timeout']
[14:00:57,962][INFO][db-checkpoint-thread-#73][GridCacheDatabaseSharedManager]
Checkpoint finished [cpId=82318c66-2933-493a-8e7e-c4170420cd70, pages=44,
markPos=FileWALPointer [idx=0, fileOff=20368709, len=210609],
walSegmentsCleared=0, walSegmentsCovered=[], markDuration=23ms, pagesWrite=1ms,
fsync=4ms, total=29ms]
{noformat}
--
This message was sent by Atlassian JIRA
(v7.6.3#76005)
