index.jar is already in classpath of server. I added it on the dbeaver
classpath ( Not sure, I dont think it is needed. )
I am on DBeaver 6.1.3.
Here is what I do :
1. Clear my data directory.
2. Start the 3 servers with 2.8.0
3. Activate the cluster
4. Add the table via dbeaver : CREATE TABLE TEST (
USERNAME VARCHAR,
APPLICATIONNAME VARCHAR,
MACHINENAME VARCHAR,
PRIMARY KEY ( USERNAME)
)
5. Query the table. This hangs for me.
Here are the logs of the three servers :
Server1 :
------------------------------------------------------------------------------------------
2020-03-11 16:37:10,548 [exchange-worker-#105] INFO
org.apache.ignite.internal.processors.cache.GridCacheProcessor [] - Started
cache [name=SQL_PUBLIC_TEST, id=-2066189417, dataRegionName=Default_Region,
mode=PARTITIONED, atomicity=ATOMIC, backups=0, mvcc=false]
2020-03-11 16:37:10,555 [exchange-worker-#105] INFO
org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionsExchangeFuture
[] - Finished waiting for partition release future
[topVer=AffinityTopologyVersion [topVer=5, minorTopVer=20], waitTime=0ms,
futInfo=NA, mode=DISTRIBUTED]
2020-03-11 16:37:10,569 [exchange-worker-#105] INFO
org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionsExchangeFuture
[] - Finished waiting for partitions release latch: ClientLatch
[coordinator=TcpDiscoveryNode [id=c04b3dfd-3ef4-40a2-9d76-68678f95618d,
consistentId=b9ec6d51-3cae-4607-b0f5-a45da0e9d3a5, addrs=ArrayList
[0:0:0:0:0:0:0:1, x.x.x.y, 127.0.0.1], sockAddrs=HashSet
[/0:0:0:0:0:0:0:1:47501, /127.0.0.1:47501,
machinename.companyname.LOCAL/x.x.x.y:47501], discPort=47501, order=1,
intOrder=1, lastExchangeTime=1583944444737, loc=false,
ver=2.8.0#20200226-sha1:341b01df, isClient=false], ackSent=true,
super=CompletableLatch [id=CompletableLatchUid [id=exchange,
topVer=AffinityTopologyVersion [topVer=5, minorTopVer=20]]]]
2020-03-11 16:37:10,569 [exchange-worker-#105] INFO
org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionsExchangeFuture
[] - Finished waiting for partition release future
[topVer=AffinityTopologyVersion [topVer=5, minorTopVer=20], waitTime=0ms,
futInfo=NA, mode=LOCAL]
2020-03-11 16:37:10,608 [exchange-worker-#105] INFO
org.apache.ignite.internal.exchange.time [] - Finished exchange init
[topVer=AffinityTopologyVersion [topVer=5, minorTopVer=20], crd=false]
2020-03-11 16:37:10,645 [sys-#228] INFO
org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionsExchangeFuture
[] - Received full message, will finish exchange
[node=c04b3dfd-3ef4-40a2-9d76-68678f95618d, resVer=AffinityTopologyVersion
[topVer=5, minorTopVer=20]]
2020-03-11 16:37:10,656 [sys-#228] INFO
org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionsExchangeFuture
[] - Finish exchange future [startVer=AffinityTopologyVersion [topVer=5,
minorTopVer=20], resVer=AffinityTopologyVersion [topVer=5, minorTopVer=20],
err=null, rebalanced=false, wasRebalanced=false]
2020-03-11 16:37:10,662 [sys-#228] INFO
org.apache.ignite.internal.processors.cache.GridCacheProcessor [] - Finish
proxy initialization, cacheName=SQL_PUBLIC_TEST,
localNodeId=6d77796a-c86f-4281-ad32-6cd70ffd7eb0
2020-03-11 16:37:10,662 [sys-#228] INFO
org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionsExchangeFuture
[] - Completed partition exchange
[localNode=6d77796a-c86f-4281-ad32-6cd70ffd7eb0,
exchange=GridDhtPartitionsExchangeFuture [topVer=AffinityTopologyVersion
[topVer=5, minorTopVer=20], evt=DISCOVERY_CUSTOM_EVT,
evtNode=TcpDiscoveryNode [id=6d77796a-c86f-4281-ad32-6cd70ffd7eb0,
consistentId=ae4a00d7-c92e-4829-a492-8d9a7757a3c9, addrs=ArrayList
[0:0:0:0:0:0:0:1, x.x.x.y, 127.0.0.1], sockAddrs=HashSet
[/0:0:0:0:0:0:0:1:47500, /127.0.0.1:47500,
machinename.companyname.LOCAL/x.x.x.y:47500], discPort=47500, order=2,
intOrder=2, lastExchangeTime=1583944630556, loc=true,
ver=2.8.0#20200226-sha1:341b01df, isClient=false], done=true,
newCrdFut=null], topVer=AffinityTopologyVersion [topVer=5, minorTopVer=20]]
2020-03-11 16:37:10,662 [sys-#228] INFO
org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionsExchangeFuture
[] - Exchange timings [startVer=AffinityTopologyVersion [topVer=5,
minorTopVer=20], resVer=AffinityTopologyVersion [topVer=5, minorTopVer=20],
stage="Waiting in exchange queue" (0 ms), stage="Exchange parameters
initialization" (0 ms), stage="Update caches registry" (5 ms), stage="Start
caches" (120 ms), stage="Affinity initialization on cache group start" (5
ms), stage="Determine exchange type" (0 ms), stage="Preloading notification"
(0 ms), stage="WAL history reservation" (0 ms), stage="Wait partitions
release" (0 ms), stage="Wait partitions release latch" (14 ms), stage="Wait
partitions release" (0 ms), stage="Restore partition states" (25 ms),
stage="After states restored callback" (9 ms), stage="Waiting for Full
message" (40 ms), stage="Affinity recalculation" (0 ms), stage="Full map
updating" (11 ms), stage="Exchange done" (5 ms), stage="Total time" (234
ms)]
2020-03-11 16:37:10,662 [sys-#228] INFO
org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionsExchangeFuture
[] - Exchange longest local stages [startVer=AffinityTopologyVersion
[topVer=5, minorTopVer=20], resVer=AffinityTopologyVersion [topVer=5,
minorTopVer=20], stage="Affinity initialization on cache group start
[grp=SQL_PUBLIC_TEST]" (5 ms) (parent=Affinity initialization on cache group
start), stage="Restore partition states [grp=SQL_PUBLIC_TEST]" (24 ms)
(parent=Restore partition states), stage="Restore partition states
[grp=transactionIdSubjectMapCache]" (0 ms) (parent=Restore partition
states), stage="Restore partition states [grp=ignite-sys-cache]" (0 ms)
(parent=Restore partition states)]
2020-03-11 16:37:10,665 [exchange-worker-#105] INFO
org.apache.ignite.internal.processors.cache.GridCachePartitionExchangeManager
[] - Skipping rebalancing (nothing scheduled) [top=AffinityTopologyVersion
[topVer=5, minorTopVer=20], force=false, evt=DISCOVERY_CUSTOM_EVT,
node=6d77796a-c86f-4281-ad32-6cd70ffd7eb0]
2020-03-11 16:37:19,989 [db-checkpoint-thread-#120] INFO
org.apache.ignite.internal.processors.cache.persistence.GridCacheDatabaseSharedManager
[] - Checkpoint started [checkpointId=cffe4e6c-364c-4f16-9d08-1aba1ff6b1e5,
startPtr=FileWALPointer [idx=0, fileOff=1245367, len=242487],
checkpointBeforeLockTime=37ms, checkpointLockWait=0ms,
checkpointListenersExecuteTime=15ms, checkpointLockHoldTime=22ms,
walCpRecordFsyncDuration=5ms, writeCheckpointEntryDuration=4ms,
splitAndSortCpPagesDuration=0ms, pages=155, reason='timeout']
2020-03-11 16:37:20,027 [db-checkpoint-thread-#120] INFO
org.apache.ignite.internal.processors.cache.persistence.GridCacheDatabaseSharedManager
[] - Checkpoint finished [cpId=cffe4e6c-364c-4f16-9d08-1aba1ff6b1e5,
pages=155, markPos=FileWALPointer [idx=0, fileOff=1245367, len=242487],
walSegmentsCleared=0, walSegmentsCovered=[], markDuration=32ms,
pagesWrite=3ms, fsync=34ms, total=106ms]
2020-03-11 16:37:25,101 [grid-nio-worker-tcp-comm-2-#74] INFO
org.apache.ignite.spi.communication.tcp.TcpCommunicationSpi [] - Established
outgoing communication connection [locAddr=/127.0.0.1:60349,
rmtAddr=/127.0.0.1:47102]
-------------------------------------------------------------------------------------
Server 2 :
020-03-11 16:37:10,424 [tcp-disco-msg-worker-[6d77796a 0:0:0:0:0:0:0:1:47500
crd]-#2] DEBUG
com.companyname.project.module.common.plugin.discoveryspi.CustomTcpDiscoverySpi
[] - Processing message [cls=TcpDiscoveryDiscardMessage,
id=82a027ac071-c04b3dfd-3ef4-40a2-9d76-68678f95618d]
2020-03-11 16:37:10,564 [exchange-worker-#105] INFO
org.apache.ignite.internal.processors.cache.GridCacheProcessor [] - Started
cache [name=SQL_PUBLIC_TEST, id=-2066189417, dataRegionName=Default_Region,
mode=PARTITIONED, atomicity=ATOMIC, backups=0, mvcc=false]
2020-03-11 16:37:10,568 [exchange-worker-#105] INFO
org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionsExchangeFuture
[] - Finished waiting for partition release future
[topVer=AffinityTopologyVersion [topVer=5, minorTopVer=20], waitTime=0ms,
futInfo=NA, mode=DISTRIBUTED]
2020-03-11 16:37:10,568 [exchange-worker-#105] INFO
org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionsExchangeFuture
[] - Finished waiting for partitions release latch: ServerLatch [permits=0,
pendingAcks=HashSet [], super=CompletableLatch [id=CompletableLatchUid
[id=exchange, topVer=AffinityTopologyVersion [topVer=5, minorTopVer=20]]]]
2020-03-11 16:37:10,568 [exchange-worker-#105] INFO
org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionsExchangeFuture
[] - Finished waiting for partition release future
[topVer=AffinityTopologyVersion [topVer=5, minorTopVer=20], waitTime=0ms,
futInfo=NA, mode=LOCAL]
2020-03-11 16:37:10,604 [exchange-worker-#105] INFO
org.apache.ignite.internal.exchange.time [] - Finished exchange init
[topVer=AffinityTopologyVersion [topVer=5, minorTopVer=20], crd=true]
2020-03-11 16:37:10,612 [sys-#207] INFO
org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionsExchangeFuture
[] - Coordinator received single message [ver=AffinityTopologyVersion
[topVer=5, minorTopVer=20], node=6d77796a-c86f-4281-ad32-6cd70ffd7eb0,
remainingNodes=1, allReceived=false]
2020-03-11 16:37:10,622 [sys-#209] INFO
org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionsExchangeFuture
[] - Coordinator received single message [ver=AffinityTopologyVersion
[topVer=5, minorTopVer=20], node=a6a987ef-89a2-47bd-bc87-fc497a3f285d,
allReceived=true]
2020-03-11 16:37:10,622 [sys-#209] INFO
org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionsExchangeFuture
[] - finishExchangeOnCoordinator [topVer=AffinityTopologyVersion [topVer=5,
minorTopVer=20], resVer=AffinityTopologyVersion [topVer=5, minorTopVer=20]]
2020-03-11 16:37:10,640 [sys-#209] INFO
org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionsExchangeFuture
[] - Finish exchange future [startVer=AffinityTopologyVersion [topVer=5,
minorTopVer=20], resVer=AffinityTopologyVersion [topVer=5, minorTopVer=20],
err=null, rebalanced=false, wasRebalanced=false]
2020-03-11 16:37:10,650 [sys-#209] INFO
org.apache.ignite.internal.processors.cache.GridCacheProcessor [] - Finish
proxy initialization, cacheName=SQL_PUBLIC_TEST,
localNodeId=c04b3dfd-3ef4-40a2-9d76-68678f95618d
2020-03-11 16:37:10,650 [sys-#209] INFO
org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionsExchangeFuture
[] - Completed partition exchange
[localNode=c04b3dfd-3ef4-40a2-9d76-68678f95618d,
exchange=GridDhtPartitionsExchangeFuture [topVer=AffinityTopologyVersion
[topVer=5, minorTopVer=20], evt=DISCOVERY_CUSTOM_EVT,
evtNode=TcpDiscoveryNode [id=6d77796a-c86f-4281-ad32-6cd70ffd7eb0,
consistentId=ae4a00d7-c92e-4829-a492-8d9a7757a3c9, addrs=ArrayList
[0:0:0:0:0:0:0:1, x.x.x.y, 127.0.0.1], sockAddrs=HashSet
[/0:0:0:0:0:0:0:1:47500, /127.0.0.1:47500,
machinename.companyname.LOCAL/x.x.x.y:47500], discPort=47500, order=2,
intOrder=2, lastExchangeTime=1583944444650, loc=false,
ver=2.8.0#20200226-sha1:341b01df, isClient=false], done=true,
newCrdFut=null], topVer=AffinityTopologyVersion [topVer=5, minorTopVer=20]]
2020-03-11 16:37:10,650 [sys-#209] INFO
org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionsExchangeFuture
[] - Exchange timings [startVer=AffinityTopologyVersion [topVer=5,
minorTopVer=20], resVer=AffinityTopologyVersion [topVer=5, minorTopVer=20],
stage="Waiting in exchange queue" (0 ms), stage="Exchange parameters
initialization" (0 ms), stage="Update caches registry" (4 ms), stage="Start
caches" (138 ms), stage="Affinity initialization on cache group start" (2
ms), stage="Determine exchange type" (0 ms), stage="Preloading notification"
(0 ms), stage="WAL history reservation" (0 ms), stage="Wait partitions
release" (0 ms), stage="Wait partitions release latch" (0 ms), stage="Wait
partitions release" (0 ms), stage="Restore partition states" (29 ms),
stage="After states restored callback" (5 ms), stage="Waiting for all single
messages" (18 ms), stage="Affinity recalculation (crd)" (0 ms),
stage="Collect update counters and create affinity messages" (0 ms),
stage="Validate partitions states" (6 ms), stage="Assign partitions states"
(2 ms), stage="Apply update counters" (1 ms), stage="Full message preparing"
(7 ms), stage="Full message sending" (0 ms), stage="Exchange done" (9 ms),
stage="Total time" (221 ms), Discovery lag=3 ms, Latest started node
id=a6a987ef-89a2-47bd-bc87-fc497a3f285d]
2020-03-11 16:37:10,655 [sys-#209] INFO
org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionsExchangeFuture
[] - Exchange longest local stages [startVer=AffinityTopologyVersion
[topVer=5, minorTopVer=20], resVer=AffinityTopologyVersion [topVer=5,
minorTopVer=20], stage="Affinity initialization on cache group start
[grp=SQL_PUBLIC_TEST]" (2 ms) (parent=Affinity initialization on cache group
start), stage="Restore partition states [grp=SQL_PUBLIC_TEST]" (29 ms)
(parent=Restore partition states), stage="Restore partition states
[grp=transactionIdSubjectMapCache]" (0 ms) (parent=Restore partition
states), stage="Restore partition states [grp=ignite-sys-cache]" (0 ms)
(parent=Restore partition states)]
2020-03-11 16:37:10,665 [exchange-worker-#105] INFO
org.apache.ignite.internal.processors.cache.GridCachePartitionExchangeManager
[] - Skipping rebalancing (nothing scheduled) [top=AffinityTopologyVersion
[topVer=5, minorTopVer=20], force=false, evt=DISCOVERY_CUSTOM_EVT,
node=6d77796a-c86f-4281-ad32-6cd70ffd7eb0]
2020-03-11 16:37:19,986 [db-checkpoint-thread-#116] INFO
org.apache.ignite.internal.processors.cache.persistence.GridCacheDatabaseSharedManager
[] - Checkpoint started [checkpointId=33ee4248-56aa-4c4f-8ba8-216350394aff,
startPtr=FileWALPointer [idx=0, fileOff=1257755, len=245584],
checkpointBeforeLockTime=37ms, checkpointLockWait=0ms,
checkpointListenersExecuteTime=21ms, checkpointLockHoldTime=28ms,
walCpRecordFsyncDuration=8ms, writeCheckpointEntryDuration=5ms,
splitAndSortCpPagesDuration=0ms, pages=155, reason='timeout']
2020-03-11 16:37:20,026 [db-checkpoint-thread-#116] INFO
org.apache.ignite.internal.processors.cache.persistence.GridCacheDatabaseSharedManager
[] - Checkpoint finished [cpId=33ee4248-56aa-4c4f-8ba8-216350394aff,
pages=155, markPos=FileWALPointer [idx=0, fileOff=1257755, len=245584],
walSegmentsCleared=0, walSegmentsCovered=[], markDuration=41ms,
pagesWrite=4ms, fsync=35ms, total=117ms]
2020-03-11 16:40:19,991 [db-checkpoint-thread-#116] INFO
org.apache.ignite.internal.processors.cache.persistence.GridCacheDatabaseSharedManager
[] - Skipping checkpoint (no pages were modified)
[checkpointBeforeLockTime=18ms, checkpointLockWait=0ms,
checkpointListenersExecuteTime=19ms, checkpointLockHoldTime=24ms,
reason='timeout']
-------------------------------------------------------------------------------------------------------
Server 3 :
2020-03-11 16:37:10,558 [exchange-worker-#105] INFO
org.apache.ignite.internal.processors.cache.GridCacheProcessor [] - Started
cache [name=SQL_PUBLIC_TEST, id=-2066189417, dataRegionName=Default_Region,
mode=PARTITIONED, atomicity=ATOMIC, backups=0, mvcc=false]
2020-03-11 16:37:10,562 [exchange-worker-#105] INFO
org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionsExchangeFuture
[] - Finished waiting for partition release future
[topVer=AffinityTopologyVersion [topVer=5, minorTopVer=20], waitTime=0ms,
futInfo=NA, mode=DISTRIBUTED]
2020-03-11 16:37:10,573 [exchange-worker-#105] INFO
org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionsExchangeFuture
[] - Finished waiting for partitions release latch: ClientLatch
[coordinator=TcpDiscoveryNode [id=c04b3dfd-3ef4-40a2-9d76-68678f95618d,
consistentId=b9ec6d51-3cae-4607-b0f5-a45da0e9d3a5, addrs=ArrayList
[0:0:0:0:0:0:0:1, x.x.x.y, 127.0.0.1], sockAddrs=HashSet
[/0:0:0:0:0:0:0:1:47501, /127.0.0.1:47501,
machinename.companyname.LOCAL/x.x.x.y:47501], discPort=47501, order=1,
intOrder=1, lastExchangeTime=1583944472211, loc=false,
ver=2.8.0#20200226-sha1:341b01df, isClient=false], ackSent=true,
super=CompletableLatch [id=CompletableLatchUid [id=exchange,
topVer=AffinityTopologyVersion [topVer=5, minorTopVer=20]]]]
2020-03-11 16:37:10,573 [exchange-worker-#105] INFO
org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionsExchangeFuture
[] - Finished waiting for partition release future
[topVer=AffinityTopologyVersion [topVer=5, minorTopVer=20], waitTime=0ms,
futInfo=NA, mode=LOCAL]
2020-03-11 16:37:10,618 [exchange-worker-#105] INFO
org.apache.ignite.internal.exchange.time [] - Finished exchange init
[topVer=AffinityTopologyVersion [topVer=5, minorTopVer=20], crd=false]
2020-03-11 16:37:10,645 [sys-#202] INFO
org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionsExchangeFuture
[] - Received full message, will finish exchange
[node=c04b3dfd-3ef4-40a2-9d76-68678f95618d, resVer=AffinityTopologyVersion
[topVer=5, minorTopVer=20]]
2020-03-11 16:37:10,660 [sys-#202] INFO
org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionsExchangeFuture
[] - Finish exchange future [startVer=AffinityTopologyVersion [topVer=5,
minorTopVer=20], resVer=AffinityTopologyVersion [topVer=5, minorTopVer=20],
err=null, rebalanced=false, wasRebalanced=false]
2020-03-11 16:37:10,668 [sys-#202] INFO
org.apache.ignite.internal.processors.cache.GridCacheProcessor [] - Finish
proxy initialization, cacheName=SQL_PUBLIC_TEST,
localNodeId=a6a987ef-89a2-47bd-bc87-fc497a3f285d
2020-03-11 16:37:10,669 [sys-#202] INFO
org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionsExchangeFuture
[] - Completed partition exchange
[localNode=a6a987ef-89a2-47bd-bc87-fc497a3f285d,
exchange=GridDhtPartitionsExchangeFuture [topVer=AffinityTopologyVersion
[topVer=5, minorTopVer=20], evt=DISCOVERY_CUSTOM_EVT,
evtNode=TcpDiscoveryNode [id=6d77796a-c86f-4281-ad32-6cd70ffd7eb0,
consistentId=ae4a00d7-c92e-4829-a492-8d9a7757a3c9, addrs=ArrayList
[0:0:0:0:0:0:0:1, x.x.x.y, 127.0.0.1], sockAddrs=HashSet
[/0:0:0:0:0:0:0:1:47500, /127.0.0.1:47500,
machinename.companyname.LOCAL/x.x.x.y:47500], discPort=47500, order=2,
intOrder=2, lastExchangeTime=1583944472211, loc=false,
ver=2.8.0#20200226-sha1:341b01df, isClient=false], done=true,
newCrdFut=null], topVer=AffinityTopologyVersion [topVer=5, minorTopVer=20]]
2020-03-11 16:37:10,669 [sys-#202] INFO
org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionsExchangeFuture
[] - Exchange timings [startVer=AffinityTopologyVersion [topVer=5,
minorTopVer=20], resVer=AffinityTopologyVersion [topVer=5, minorTopVer=20],
stage="Waiting in exchange queue" (0 ms), stage="Exchange parameters
initialization" (0 ms), stage="Update caches registry" (5 ms), stage="Start
caches" (128 ms), stage="Affinity initialization on cache group start" (2
ms), stage="Determine exchange type" (0 ms), stage="Preloading notification"
(0 ms), stage="WAL history reservation" (0 ms), stage="Wait partitions
release" (0 ms), stage="Wait partitions release latch" (10 ms), stage="Wait
partitions release" (0 ms), stage="Restore partition states" (28 ms),
stage="After states restored callback" (12 ms), stage="Waiting for Full
message" (30 ms), stage="Affinity recalculation" (0 ms), stage="Full map
updating" (14 ms), stage="Exchange done" (8 ms), stage="Total time" (237
ms)]
2020-03-11 16:37:10,669 [sys-#202] INFO
org.apache.ignite.internal.processors.cache.distributed.dht.preloader.GridDhtPartitionsExchangeFuture
[] - Exchange longest local stages [startVer=AffinityTopologyVersion
[topVer=5, minorTopVer=20], resVer=AffinityTopologyVersion [topVer=5,
minorTopVer=20], stage="Affinity initialization on cache group start
[grp=SQL_PUBLIC_TEST]" (2 ms) (parent=Affinity initialization on cache group
start), stage="Restore partition states [grp=SQL_PUBLIC_TEST]" (27 ms)
(parent=Restore partition states), stage="Restore partition states
[grp=transactionIdSubjectMapCache]" (0 ms) (parent=Restore partition
states), stage="Restore partition states [grp=ignite-sys-cache]" (0 ms)
(parent=Restore partition states)]
2020-03-11 16:37:10,674 [exchange-worker-#105] INFO
org.apache.ignite.internal.processors.cache.GridCachePartitionExchangeManager
[] - Skipping rebalancing (nothing scheduled) [top=AffinityTopologyVersion
[topVer=5, minorTopVer=20], force=false, evt=DISCOVERY_CUSTOM_EVT,
node=6d77796a-c86f-4281-ad32-6cd70ffd7eb0]
2020-03-11 16:37:25,097 [grid-nio-worker-tcp-comm-1-#73] INFO
org.apache.ignite.spi.communication.tcp.TcpCommunicationSpi [] - Accepted
incoming communication connection [locAddr=/127.0.0.1:47102,
rmtAddr=/127.0.0.1:60349]
2020-03-11 16:37:32,468 [db-checkpoint-thread-#113] INFO
org.apache.ignite.internal.processors.cache.persistence.GridCacheDatabaseSharedManager
[] - Checkpoint started [checkpointId=3fd04e70-9137-498c-9d1c-1495e9561e6a,
startPtr=FileWALPointer [idx=0, fileOff=1463055, len=237984],
checkpointBeforeLockTime=36ms, checkpointLockWait=0ms,
checkpointListenersExecuteTime=16ms, checkpointLockHoldTime=22ms,
walCpRecordFsyncDuration=7ms, writeCheckpointEntryDuration=6ms,
splitAndSortCpPagesDuration=0ms, pages=155, reason='timeout']
2020-03-11 16:37:32,497 [db-checkpoint-thread-#113] INFO
org.apache.ignite.internal.processors.cache.persistence.GridCacheDatabaseSharedManager
[] - Checkpoint finished [cpId=3fd04e70-9137-498c-9d1c-1495e9561e6a,
pages=155, markPos=FileWALPointer [idx=0, fileOff=1463055, len=237984],
walSegmentsCleared=0, walSegmentsCovered=[], markDuration=36ms,
pagesWrite=5ms, fsync=24ms, total=101ms]
2020-03-11 16:40:32,470 [db-checkpoint-thread-#113] INFO
org.apache.ignite.internal.processors.cache.persistence.GridCacheDatabaseSharedManager
[] - Skipping checkpoint (no pages were modified)
[checkpointBeforeLockTime=18ms, checkpointLockWait=0ms,
checkpointListenersExecuteTime=16ms, checkpointLockHoldTime=21ms,
reason='timeout']
--
Sent from: http://apache-ignite-users.70518.x6.nabble.com/
