In the terminal where I executed:
user@PTFAssaultMachine:~/apache-ignite-fabric-2.6.0-bin/examples$ sudo mvn
exec:java
-Dexec.mainClass="org.apache.ignite.examples.ml.genetic.helloworld.HelloWorldGAExample"
The loop displays this strange recurring output:
]
[2018-11-30 02:00:23,494][INFO ][grid-timeout-worker-#23][IgniteKernal]
Metrics for local node (to disable set 'metricsLogFrequency' to 0)
^-- Node [id=99fe1608, uptime=00:11:00.704]
^-- H/N/C [hosts=1, nodes=2, CPUs=2]
^-- CPU [cur=0.83%, avg=1.3%, GC=0%]
^-- PageMemory [pages=3286]
^-- Heap [used=107MB, free=83.81%, comm=218MB]
^-- Non heap [used=70MB, free=-1%, comm=71MB]
^-- Outbound messages queue [size=0]
^-- Public thread pool [active=0, idle=0, qSize=0]
^-- System thread pool [active=0, idle=8, qSize=0]
[2018-11-30 02:01:23,493][INFO ][grid-timeout-worker-#23][IgniteKernal]
Metrics for local node (to disable set 'metricsLogFrequency' to 0)
^-- Node [id=99fe1608, uptime=00:12:00.710]
^-- H/N/C [hosts=1, nodes=2, CPUs=2]
^-- CPU [cur=0.67%, avg=1.27%, GC=0%]
^-- PageMemory [pages=3286]
^-- Heap [used=114MB, free=82.84%, comm=218MB]
^-- Non heap [used=70MB, free=-1%, comm=71MB]
^-- Outbound messages queue [size=0]
^-- Public thread pool [active=0, idle=0, qSize=0]
^-- System thread pool [active=0, idle=6, qSize=0]
[2018-11-30 02:02:24,490][INFO ][tcp-disco-srvr-#3][TcpDiscoverySpi] TCP
discovery accepted incoming connection [rmtAddr=/127.0.0.1, rmtPort=51869]
[2018-11-30 02:02:24,824][INFO ][tcp-disco-srvr-#3][TcpDiscoverySpi] TCP
discovery spawning a new thread for connection [rmtAddr=/127.0.0.1,
rmtPort=51869]
[2018-11-30 02:02:24,664][INFO ][tcp-disco-sock-reader-#5][TcpDiscoverySpi]
Finished serving remote node connection [rmtAddr=/0:0:0:0:0:0:0:1:36991,
rmtPort=36991
[2018-11-30 02:02:24,829][INFO ][tcp-disco-sock-reader-#7][TcpDiscoverySpi]
Started serving remote node connection [rmtAddr=/127.0.0.1:51869,
rmtPort=51869]
[2018-11-30 02:02:25,154][INFO ][grid-timeout-worker-#23][IgniteKernal]
Metrics for local node (to disable set 'metricsLogFrequency' to 0)
^-- Node [id=99fe1608, uptime=00:13:02.368]
^-- H/N/C [hosts=1, nodes=2, CPUs=2]
^-- CPU [cur=1.17%, avg=1.25%, GC=0%]
^-- PageMemory [pages=3286]
^-- Heap [used=122MB, free=81.69%, comm=218MB]
^-- Non heap [used=70MB, free=-1%, comm=71MB]
^-- Outbound messages queue [size=0]
^-- Public thread pool [active=0, idle=0, qSize=0]
^-- System thread pool [active=0, idle=4, qSize=0]
Nov 30, 2018 2:02:25 AM java.util.logging.LogManager$RootLogger log
WARNING: Possible too long JVM pause: 3807 milliseconds.
Nov 30, 2018 2:02:25 AM java.util.logging.LogManager$RootLogger log
WARNING: Possible too long JVM pause: 723 milliseconds.
Nov 30, 2018 2:02:29 AM java.util.logging.LogManager$RootLogger log
WARNING: Possible too long JVM pause: 1166 milliseconds.
[2018-11-30 02:03:25,151][INFO ][grid-timeout-worker-#23][IgniteKernal]
Metrics for local node (to disable set 'metricsLogFrequency' to 0)
^-- Node [id=99fe1608, uptime=00:14:02.368]
^-- H/N/C [hosts=1, nodes=2, CPUs=2]
^-- CPU [cur=1.17%, avg=1.22%, GC=0%]
^-- PageMemory [pages=3286]
^-- Heap [used=128MB, free=80.72%, comm=218MB]
^-- Non heap [used=70MB, free=-1%, comm=71MB]
^-- Outbound messages queue [size=0]
^-- Public thread pool [active=0, idle=0, qSize=0]
^-- System thread pool [active=0, idle=6, qSize=0]
[2018-11-30 02:04:25,212][INFO ][grid-timeout-worker-#23][IgniteKernal]
Metrics for local node (to disable set 'metricsLogFrequency' to 0)
^-- Node [id=99fe1608, uptime=00:15:02.429]
^-- H/N/C [hosts=1, nodes=2, CPUs=2]
^-- CPU [cur=0.67%, avg=1.19%, GC=0%]
^-- PageMemory [pages=3286]
^-- Heap [used=134MB, free=79.79%, comm=218MB]
^-- Non heap [used=70MB, free=-1%, comm=71MB]
^-- Outbound messages queue [size=0]
^-- Public thread pool [active=0, idle=0, qSize=0]
^-- System thread pool [active=0, idle=6, qSize=0]
[2018-11-30 02:05:25,355][INFO ][grid-timeout-worker-#23][IgniteKernal]
Metrics for local node (to disable set 'metricsLogFrequency' to 0)
^-- Node [id=99fe1608, uptime=00:16:02.571]
^-- H/N/C [hosts=1, nodes=2, CPUs=2]
^-- CPU [cur=0.67%, avg=1.16%, GC=0%]
^-- PageMemory [pages=3286]
^-- Heap [used=140MB, free=78.91%, comm=218MB]
^-- Non heap [used=70MB, free=-1%, comm=71MB]
^-- Outbound messages queue [size=0]
^-- Public thread pool [active=0, idle=0, qSize=0]
^-- System thread pool [active=0, idle=6, qSize=0]
[2018-11-30 02:06:34,817][INFO ][grid-timeout-worker-#23][IgniteKernal]
Metrics for local node (to disable set 'metricsLogFrequency' to 0)
^-- Node [id=99fe1608, uptime=00:17:12.033]
^-- H/N/C [hosts=1, nodes=2, CPUs=2]
^-- CPU [cur=0.67%, avg=1.15%, GC=0%]
^-- PageMemory [pages=3286]
^-- Heap [used=146MB, free=77.98%, comm=218MB]
^-- Non heap [used=70MB, free=-1%, comm=71MB]
^-- Outbound messages queue [size=0]
^-- Public thread pool [active=0, idle=0, qSize=0]
^-- System thread pool [active=0, idle=5, qSize=0]
[2018-11-30 02:07:34,823][INFO ][grid-timeout-worker-#23][IgniteKernal]
Metrics for local node (to disable set 'metricsLogFrequency' to 0)
^-- Node [id=99fe1608, uptime=00:18:12.038]
^-- H/N/C [hosts=1, nodes=2, CPUs=2]
^-- CPU [cur=0.67%, avg=1.13%, GC=0%]
^-- PageMemory [pages=3286]
^-- Heap [used=153MB, free=76.94%, comm=218MB]
^-- Non heap [used=70MB, free=-1%, comm=72MB]
^-- Outbound messages queue [size=0]
^-- Public thread pool [active=0, idle=0, qSize=0]
^-- System thread pool [active=0, idle=6, qSize=0]
[2018-11-30 02:08:34,850][INFO ][grid-timeout-worker-#23][IgniteKernal]
Metrics for local node (to disable set 'metricsLogFrequency' to 0)
^-- Node [id=99fe1608, uptime=00:19:12.067]
^-- H/N/C [hosts=1, nodes=2, CPUs=2]
^-- CPU [cur=0.33%, avg=1.11%, GC=0%]
^-- PageMemory [pages=3286]
^-- Heap [used=159MB, free=76.03%, comm=218MB]
^-- Non heap [used=70MB, free=-1%, comm=72MB]
^-- Outbound messages queue [size=0]
^-- Public thread pool [active=0, idle=0, qSize=0]
^-- System thread pool [active=0, idle=6, qSize=0]
[2018-11-30 02:09:34,851][INFO ][grid-timeout-worker-#23][IgniteKernal]
Metrics for local node (to disable set 'metricsLogFrequency' to 0)
^-- Node [id=99fe1608, uptime=00:20:12.067]
^-- H/N/C [hosts=1, nodes=2, CPUs=2]
^-- CPU [cur=0.67%, avg=1.1%, GC=0%]
^-- PageMemory [pages=3286]
^-- Heap [used=166MB, free=75.07%, comm=218MB]
^-- Non heap [used=70MB, free=-1%, comm=72MB]
^-- Outbound messages queue [size=0]
^-- Public thread pool [active=0, idle=0, qSize=0]
^-- System thread pool [active=0, idle=6, qSize=0]
[2018-11-30 02:10:34,881][INFO ][grid-timeout-worker-#23][IgniteKernal]
Metrics for local node (to disable set 'metricsLogFrequency' to 0)
^-- Node [id=99fe1608, uptime=00:21:12.096]
^-- H/N/C [hosts=1, nodes=2, CPUs=2]
^-- CPU [cur=0.67%, avg=1.08%, GC=0%]
^-- PageMemory [pages=3286]
^-- Heap [used=172MB, free=74.16%, comm=218MB]
^-- Non heap [used=70MB, free=-1%, comm=72MB]
^-- Outbound messages queue [size=0]
^-- Public thread pool [active=0, idle=0, qSize=0]
^-- System thread pool [active=0, idle=6, qSize=0]
[2018-11-30 02:11:04,874][WARN ][exchange-worker-#38][diagnostic] Failed to
wait for partition map exchange [topVer=AffinityTopologyVersion [topVer=2,
minorTopVer=2], node=99fe1608-8efa-4178-80fd-9d5b0de53ece]. Consider
changing TransactionConfiguration.txTimeoutOnPartitionMapSynchronization to
non default value to avoid this message. Dumping pending objects that might
be the cause:
[2018-11-30 02:11:04,879][WARN ][exchange-worker-#38][diagnostic] Ready
affinity version: AffinityTopologyVersion [topVer=2, minorTopVer=1]
[2018-11-30 02:11:04,881][WARN ][exchange-worker-#38][diagnostic] Last
exchange future: GridDhtPartitionsExchangeFuture
[firstDiscoEvt=DiscoveryCustomEvent [customMsg=DynamicCacheChangeBatch
[id=43ce0636761-58387080-b5c7-4d95-a6f6-6ebeae6361be,
reqs=[DynamicCacheChangeRequest [cacheName=geneCache, hasCfg=true,
nodeId=99fe1608-8efa-4178-80fd-9d5b0de53ece, clientStartOnly=false,
stop=false, destroy=false, disabledAfterStartfalse]],
exchangeActions=ExchangeActions [startCaches=[geneCache], stopCaches=null,
startGrps=[geneCache], stopGrps=[], resetParts=null,
stateChangeRequest=null], startCaches=false],
affTopVer=AffinityTopologyVersion [topVer=2, minorTopVer=2],
super=DiscoveryEvent [evtNode=TcpDiscoveryNode
[id=99fe1608-8efa-4178-80fd-9d5b0de53ece, addrs=[0:0:0:0:0:0:0:1%lo,
10.137.0.17, 127.0.0.1], sockAddrs=[/0:0:0:0:0:0:0:1%lo:47501,
/127.0.0.1:47501, /10.137.0.17:47501], discPort=47501, order=2, intOrder=2,
lastExchangeTime=1543561864637, loc=true, ver=2.6.0#20180710-sha1:669feacc,
isClient=false], topVer=2, nodeId8=99fe1608, msg=null,
type=DISCOVERY_CUSTOM_EVT, tstamp=1543560563837]], crd=TcpDiscoveryNode
[id=b9aef4b4-07c4-4bf3-b108-4b6907e4359f, addrs=[0:0:0:0:0:0:0:1%lo,
10.137.0.17, 127.0.0.1], sockAddrs=[/10.137.0.17:47500,
/0:0:0:0:0:0:0:1%lo:47500, /127.0.0.1:47500], discPort=47500, order=1,
intOrder=1, lastExchangeTime=1543560561394, loc=false,
ver=2.6.0#20180710-sha1:669feacc, isClient=false],
exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=2,
minorTopVer=2], discoEvt=DiscoveryCustomEvent
[customMsg=DynamicCacheChangeBatch
[id=43ce0636761-58387080-b5c7-4d95-a6f6-6ebeae6361be,
reqs=[DynamicCacheChangeRequest [cacheName=geneCache, hasCfg=true,
nodeId=99fe1608-8efa-4178-80fd-9d5b0de53ece, clientStartOnly=false,
stop=false, destroy=false, disabledAfterStartfalse]],
exchangeActions=ExchangeActions [startCaches=[geneCache], stopCaches=null,
startGrps=[geneCache], stopGrps=[], resetParts=null,
stateChangeRequest=null], startCaches=false],
affTopVer=AffinityTopologyVersion [topVer=2, minorTopVer=2],
super=DiscoveryEvent [evtNode=TcpDiscoveryNode
[id=99fe1608-8efa-4178-80fd-9d5b0de53ece, addrs=[0:0:0:0:0:0:0:1%lo,
10.137.0.17, 127.0.0.1], sockAddrs=[/0:0:0:0:0:0:0:1%lo:47501,
/127.0.0.1:47501, /10.137.0.17:47501], discPort=47501, order=2, intOrder=2,
lastExchangeTime=1543561864637, loc=true, ver=2.6.0#20180710-sha1:669feacc,
isClient=false], topVer=2, nodeId8=99fe1608, msg=null,
type=DISCOVERY_CUSTOM_EVT, tstamp=1543560563837]], nodeId=99fe1608,
evt=DISCOVERY_CUSTOM_EVT], added=true, initFut=GridFutureAdapter
[ignoreInterrupts=false, state=DONE, res=true, hash=948889681], init=true,
lastVer=null, partReleaseFut=PartitionReleaseFuture
[topVer=AffinityTopologyVersion [topVer=2, minorTopVer=2],
futures=[ExplicitLockReleaseFuture [topVer=AffinityTopologyVersion
[topVer=2, minorTopVer=2], futures=[]], AtomicUpdateReleaseFuture
[topVer=AffinityTopologyVersion [topVer=2, minorTopVer=2], futures=[]],
DataStreamerReleaseFuture [topVer=AffinityTopologyVersion [topVer=2,
minorTopVer=2], futures=[]], LocalTxReleaseFuture
[topVer=AffinityTopologyVersion [topVer=2, minorTopVer=2], futures=[]],
AllTxReleaseFuture [topVer=AffinityTopologyVersion [topVer=2,
minorTopVer=2], futures=[RemoteTxReleaseFuture
[topVer=AffinityTopologyVersion [topVer=2, minorTopVer=2], futures=[]]]]]],
exchActions=ExchangeActions [startCaches=[geneCache], stopCaches=null,
startGrps=[geneCache], stopGrps=[], resetParts=null,
stateChangeRequest=null], affChangeMsg=null, initTs=1543560563837,
centralizedAff=false, forceAffReassignment=false, changeGlobalStateE=null,
done=false, state=SRV, evtLatch=0,
remaining=[b9aef4b4-07c4-4bf3-b108-4b6907e4359f], super=GridFutureAdapter
[ignoreInterrupts=false, state=INIT, res=null, hash=960914331]]
[2018-11-30 02:11:04,881][WARN
][exchange-worker-#38][GridCachePartitionExchangeManager] First 10 pending
exchange futures [total=1]
[2018-11-30 02:11:04,882][WARN
][exchange-worker-#38][GridCachePartitionExchangeManager] >>>
GridDhtPartitionsExchangeFuture [topVer=AffinityTopologyVersion [topVer=2,
minorTopVer=3], evt=DISCOVERY_CUSTOM_EVT, evtNode=TcpDiscoveryNode
[id=b9aef4b4-07c4-4bf3-b108-4b6907e4359f, addrs=[0:0:0:0:0:0:0:1%lo,
10.137.0.17, 127.0.0.1], sockAddrs=[/10.137.0.17:47500,
/0:0:0:0:0:0:0:1%lo:47500, /127.0.0.1:47500], discPort=47500, order=1,
intOrder=1, lastExchangeTime=1543560561394, loc=false,
ver=2.6.0#20180710-sha1:669feacc, isClient=false], done=false]
[2018-11-30 02:11:04,882][WARN ][exchange-worker-#38][diagnostic] Last 10
exchange futures (total: 4):
[2018-11-30 02:11:04,883][WARN ][exchange-worker-#38][diagnostic] >>>
GridDhtPartitionsExchangeFuture [topVer=AffinityTopologyVersion [topVer=2,
minorTopVer=3], evt=DISCOVERY_CUSTOM_EVT, evtNode=TcpDiscoveryNode
[id=b9aef4b4-07c4-4bf3-b108-4b6907e4359f, addrs=[0:0:0:0:0:0:0:1%lo,
10.137.0.17, 127.0.0.1], sockAddrs=[/10.137.0.17:47500,
/0:0:0:0:0:0:0:1%lo:47500, /127.0.0.1:47500], discPort=47500, order=1,
intOrder=1, lastExchangeTime=1543560561394, loc=false,
ver=2.6.0#20180710-sha1:669feacc, isClient=false], done=false]
[2018-11-30 02:11:04,890][WARN ][exchange-worker-#38][diagnostic] >>>
GridDhtPartitionsExchangeFuture [topVer=AffinityTopologyVersion [topVer=2,
minorTopVer=2], evt=DISCOVERY_CUSTOM_EVT, evtNode=TcpDiscoveryNode
[id=99fe1608-8efa-4178-80fd-9d5b0de53ece, addrs=[0:0:0:0:0:0:0:1%lo,
10.137.0.17, 127.0.0.1], sockAddrs=[/0:0:0:0:0:0:0:1%lo:47501,
/127.0.0.1:47501, /10.137.0.17:47501], discPort=47501, order=2, intOrder=2,
lastExchangeTime=1543561864637, loc=true, ver=2.6.0#20180710-sha1:669feacc,
isClient=false], done=false]
[2018-11-30 02:11:04,890][WARN ][exchange-worker-#38][diagnostic] >>>
GridDhtPartitionsExchangeFuture [topVer=AffinityTopologyVersion [topVer=2,
minorTopVer=1], evt=DISCOVERY_CUSTOM_EVT, evtNode=TcpDiscoveryNode
[id=99fe1608-8efa-4178-80fd-9d5b0de53ece, addrs=[0:0:0:0:0:0:0:1%lo,
10.137.0.17, 127.0.0.1], sockAddrs=[/0:0:0:0:0:0:0:1%lo:47501,
/127.0.0.1:47501, /10.137.0.17:47501], discPort=47501, order=2, intOrder=2,
lastExchangeTime=1543561864637, loc=true, ver=2.6.0#20180710-sha1:669feacc,
isClient=false], done=true]
[2018-11-30 02:11:04,890][WARN ][exchange-worker-#38][diagnostic] >>>
GridDhtPartitionsExchangeFuture [topVer=AffinityTopologyVersion [topVer=2,
minorTopVer=0], evt=NODE_JOINED, evtNode=TcpDiscoveryNode
[id=99fe1608-8efa-4178-80fd-9d5b0de53ece, addrs=[0:0:0:0:0:0:0:1%lo,
10.137.0.17, 127.0.0.1], sockAddrs=[/0:0:0:0:0:0:0:1%lo:47501,
/127.0.0.1:47501, /10.137.0.17:47501], discPort=47501, order=2, intOrder=2,
lastExchangeTime=1543561864637, loc=true, ver=2.6.0#20180710-sha1:669feacc,
isClient=false], done=true]
[2018-11-30 02:11:04,890][WARN ][exchange-worker-#38][diagnostic] Latch
manager state: ExchangeLatchManager [serverLatches={}, clientLatches={}]
[2018-11-30 02:11:04,890][WARN ][exchange-worker-#38][diagnostic] Pending
transactions:
[2018-11-30 02:11:04,890][WARN ][exchange-worker-#38][diagnostic] Pending
explicit locks:
[2018-11-30 02:11:04,890][WARN ][exchange-worker-#38][diagnostic] Pending
cache futures:
[2018-11-30 02:11:04,890][WARN ][exchange-worker-#38][diagnostic] Pending
atomic cache futures:
[2018-11-30 02:11:04,890][WARN ][exchange-worker-#38][diagnostic] Pending
data streamer futures:
[2018-11-30 02:11:04,890][WARN ][exchange-worker-#38][diagnostic] Pending
transaction deadlock detection futures:
[2018-11-30 02:11:04,910][INFO ][grid-nio-worker-tcp-comm-3-#28][diagnostic]
Exchange future waiting for coordinator response
[crd=b9aef4b4-07c4-4bf3-b108-4b6907e4359f, topVer=AffinityTopologyVersion
[topVer=2, minorTopVer=2]]
Remote node information:
General node info [id=b9aef4b4-07c4-4bf3-b108-4b6907e4359f, client=false,
discoTopVer=AffinityTopologyVersion [topVer=2, minorTopVer=2],
time=02:11:04.889]
Partitions exchange info [readyVer=AffinityTopologyVersion [topVer=2,
minorTopVer=1]]
Last initialized exchange future: GridDhtPartitionsExchangeFuture
[firstDiscoEvt=DiscoveryCustomEvent [customMsg=CacheAffinityChangeMessage
[id=656d8536761-8b0c73f9-623c-4d9b-8bf0-b301599e3885,
topVer=AffinityTopologyVersion [topVer=2, minorTopVer=0], exchId=null,
partsMsg=null, exchangeNeeded=true], affTopVer=AffinityTopologyVersion
[topVer=2, minorTopVer=2], super=DiscoveryEvent [evtNode=TcpDiscoveryNode
[id=b9aef4b4-07c4-4bf3-b108-4b6907e4359f, addrs=[0:0:0:0:0:0:0:1%lo,
10.137.0.17, 127.0.0.1], sockAddrs=[/10.137.0.17:47500,
/0:0:0:0:0:0:0:1%lo:47500, /127.0.0.1:47500], discPort=47500, order=1,
intOrder=1, lastExchangeTime=1543561864666, loc=true,
ver=2.6.0#20180710-sha1:669feacc, isClient=false], topVer=2,
nodeId8=b9aef4b4, msg=null, type=DISCOVERY_CUSTOM_EVT,
tstamp=1543560564317]], crd=TcpDiscoveryNode
[id=b9aef4b4-07c4-4bf3-b108-4b6907e4359f, addrs=[0:0:0:0:0:0:0:1%lo,
10.137.0.17, 127.0.0.1], sockAddrs=[/10.137.0.17:47500,
/0:0:0:0:0:0:0:1%lo:47500, /127.0.0.1:47500], discPort=47500, order=1,
intOrder=1, lastExchangeTime=1543561864666, loc=true,
ver=2.6.0#20180710-sha1:669feacc, isClient=false],
exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=2,
minorTopVer=2], discoEvt=DiscoveryCustomEvent
[customMsg=CacheAffinityChangeMessage
[id=656d8536761-8b0c73f9-623c-4d9b-8bf0-b301599e3885,
topVer=AffinityTopologyVersion [topVer=2, minorTopVer=0], exchId=null,
partsMsg=null, exchangeNeeded=true], affTopVer=AffinityTopologyVersion
[topVer=2, minorTopVer=2], super=DiscoveryEvent [evtNode=TcpDiscoveryNode
[id=b9aef4b4-07c4-4bf3-b108-4b6907e4359f, addrs=[0:0:0:0:0:0:0:1%lo,
10.137.0.17, 127.0.0.1], sockAddrs=[/10.137.0.17:47500,
/0:0:0:0:0:0:0:1%lo:47500, /127.0.0.1:47500], discPort=47500, order=1,
intOrder=1, lastExchangeTime=1543561864666, loc=true,
ver=2.6.0#20180710-sha1:669feacc, isClient=false], topVer=2,
nodeId8=b9aef4b4, msg=null, type=DISCOVERY_CUSTOM_EVT,
tstamp=1543560564317]], nodeId=b9aef4b4, evt=DISCOVERY_CUSTOM_EVT],
added=true, initFut=GridFutureAdapter [ignoreInterrupts=false, state=DONE,
res=true, hash=517169392], init=true, lastVer=null,
partReleaseFut=PartitionReleaseFuture [topVer=AffinityTopologyVersion
[topVer=2, minorTopVer=2], futures=[ExplicitLockReleaseFuture
[topVer=AffinityTopologyVersion [topVer=2, minorTopVer=2], futures=[]],
AtomicUpdateReleaseFuture [topVer=AffinityTopologyVersion [topVer=2,
minorTopVer=2], futures=[]], DataStreamerReleaseFuture
[topVer=AffinityTopologyVersion [topVer=2, minorTopVer=2], futures=[]],
LocalTxReleaseFuture [topVer=AffinityTopologyVersion [topVer=2,
minorTopVer=2], futures=[]], AllTxReleaseFuture
[topVer=AffinityTopologyVersion [topVer=2, minorTopVer=2],
futures=[RemoteTxReleaseFuture [topVer=AffinityTopologyVersion [topVer=2,
minorTopVer=2], futures=[]]]]]], exchActions=null,
affChangeMsg=CacheAffinityChangeMessage
[id=656d8536761-8b0c73f9-623c-4d9b-8bf0-b301599e3885,
topVer=AffinityTopologyVersion [topVer=2, minorTopVer=0], exchId=null,
partsMsg=null, exchangeNeeded=true], initTs=1543560564317,
centralizedAff=false, forceAffReassignment=false, changeGlobalStateE=null,
done=false, state=CRD, evtLatch=0,
remaining=[99fe1608-8efa-4178-80fd-9d5b0de53ece], super=GridFutureAdapter
[ignoreInterrupts=false, state=INIT, res=null, hash=1999521151]]
Communication SPI statistics [rmtNode=99fe1608-8efa-4178-80fd-9d5b0de53ece]
Communication SPI recovery descriptors:
[key=ConnectionKey [nodeId=99fe1608-8efa-4178-80fd-9d5b0de53ece, idx=0,
connCnt=0, dummy=false], msgsSent=23, msgsAckedByRmt=22, msgsRcvd=25,
lastAcked=23, reserveCnt=1, descIdHash=1344719508]
Communication SPI clients:
[node=99fe1608-8efa-4178-80fd-9d5b0de53ece,
client=GridTcpNioCommunicationClient [ses=GridSelectorNioSessionImpl
[worker=DirectNioClientWorker [super=AbstractNioClientWorker [idx=0,
bytesRcvd=102117, bytesSent=85540, bytesRcvd0=853, bytesSent0=0,
select=true, super=GridWorker [name=grid-nio-worker-tcp-comm-0,
igniteInstanceName=null, finished=false, hashCode=1357696285,
interrupted=false, runner=grid-nio-worker-tcp-comm-0-#25]]],
writeBuf=java.nio.DirectByteBuffer[pos=0 lim=32768 cap=32768],
readBuf=java.nio.DirectByteBuffer[pos=0 lim=32768 cap=32768],
inRecovery=GridNioRecoveryDescriptor [acked=22, resendCnt=0, rcvCnt=25,
sentCnt=23, reserved=true, lastAck=23, nodeLeft=false, node=TcpDiscoveryNode
[id=99fe1608-8efa-4178-80fd-9d5b0de53ece, addrs=[0:0:0:0:0:0:0:1%lo,
10.137.0.17, 127.0.0.1], sockAddrs=[/0:0:0:0:0:0:0:1%lo:47501,
/127.0.0.1:47501, /10.137.0.17:47501], discPort=47501, order=2, intOrder=2,
lastExchangeTime=1543560561163, loc=false, ver=2.6.0#20180710-sha1:669feacc,
isClient=false], connected=true, connectCnt=0, queueLimit=4096,
reserveCnt=1, pairedConnections=false],
outRecovery=GridNioRecoveryDescriptor [acked=22, resendCnt=0, rcvCnt=25,
sentCnt=23, reserved=true, lastAck=23, nodeLeft=false, node=TcpDiscoveryNode
[id=99fe1608-8efa-4178-80fd-9d5b0de53ece, addrs=[0:0:0:0:0:0:0:1%lo,
10.137.0.17, 127.0.0.1], sockAddrs=[/0:0:0:0:0:0:0:1%lo:47501,
/127.0.0.1:47501, /10.137.0.17:47501], discPort=47501, order=2, intOrder=2,
lastExchangeTime=1543560561163, loc=false, ver=2.6.0#20180710-sha1:669feacc,
isClient=false], connected=true, connectCnt=0, queueLimit=4096,
reserveCnt=1, pairedConnections=false], super=GridNioSessionImpl
[locAddr=/0:0:0:0:0:0:0:1:47100, rmtAddr=/0:0:0:0:0:0:0:1:52566,
createTime=1543560562182, closeTime=0, bytesSent=85540, bytesRcvd=102117,
bytesSent0=0, bytesRcvd0=853, sndSchedTime=1543560562182,
lastSndTime=1543561852988, lastRcvTime=1543561864889, readsPaused=false,
filterChain=FilterChain[filters=[GridNioCodecFilter
[parser=org.apache.ignite.internal.util.nio.GridDirectParser@26faa1a9,
directMode=true], GridConnectionBytesVerifyFilter], accepted=true]],
super=GridAbstractCommunicationClient [lastUsed=1543560562223, closed=false,
connIdx=0]]]
NIO sessions statistics:
>> Selector info [idx=0, keysCnt=1, bytesRcvd=102117, bytesRcvd0=853,
>> bytesSent=85540, bytesSent0=0]
Connection info [in=true, rmtAddr=/0:0:0:0:0:0:0:1:52566,
locAddr=/0:0:0:0:0:0:0:1:47100, msgsSent=23, msgsAckedByRmt=22,
descIdHash=1344719508, unackedMsgs=[IgniteDiagnosticMessage], msgsRcvd=25,
lastAcked=23, descIdHash=1344719508, bytesRcvd=102117, bytesRcvd0=853,
bytesSent=85540, bytesSent0=0, opQueueSize=0]
Exchange future: GridDhtPartitionsExchangeFuture
[firstDiscoEvt=DiscoveryCustomEvent [customMsg=CacheAffinityChangeMessage
[id=656d8536761-8b0c73f9-623c-4d9b-8bf0-b301599e3885,
topVer=AffinityTopologyVersion [topVer=2, minorTopVer=0], exchId=null,
partsMsg=null, exchangeNeeded=true], affTopVer=AffinityTopologyVersion
[topVer=2, minorTopVer=2], super=DiscoveryEvent [evtNode=TcpDiscoveryNode
[id=b9aef4b4-07c4-4bf3-b108-4b6907e4359f, addrs=[0:0:0:0:0:0:0:1%lo,
10.137.0.17, 127.0.0.1], sockAddrs=[/10.137.0.17:47500,
/0:0:0:0:0:0:0:1%lo:47500, /127.0.0.1:47500], discPort=47500, order=1,
intOrder=1, lastExchangeTime=1543561864666, loc=true,
ver=2.6.0#20180710-sha1:669feacc, isClient=false], topVer=2,
nodeId8=b9aef4b4, msg=null, type=DISCOVERY_CUSTOM_EVT,
tstamp=1543560564317]], crd=TcpDiscoveryNode
[id=b9aef4b4-07c4-4bf3-b108-4b6907e4359f, addrs=[0:0:0:0:0:0:0:1%lo,
10.137.0.17, 127.0.0.1], sockAddrs=[/10.137.0.17:47500,
/0:0:0:0:0:0:0:1%lo:47500, /127.0.0.1:47500], discPort=47500, order=1,
intOrder=1, lastExchangeTime=1543561864666, loc=true,
ver=2.6.0#20180710-sha1:669feacc, isClient=false],
exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=2,
minorTopVer=2], discoEvt=DiscoveryCustomEvent
[customMsg=CacheAffinityChangeMessage
[id=656d8536761-8b0c73f9-623c-4d9b-8bf0-b301599e3885,
topVer=AffinityTopologyVersion [topVer=2, minorTopVer=0], exchId=null,
partsMsg=null, exchangeNeeded=true], affTopVer=AffinityTopologyVersion
[topVer=2, minorTopVer=2], super=DiscoveryEvent [evtNode=TcpDiscoveryNode
[id=b9aef4b4-07c4-4bf3-b108-4b6907e4359f, addrs=[0:0:0:0:0:0:0:1%lo,
10.137.0.17, 127.0.0.1], sockAddrs=[/10.137.0.17:47500,
/0:0:0:0:0:0:0:1%lo:47500, /127.0.0.1:47500], discPort=47500, order=1,
intOrder=1, lastExchangeTime=1543561864666, loc=true,
ver=2.6.0#20180710-sha1:669feacc, isClient=false], topVer=2,
nodeId8=b9aef4b4, msg=null, type=DISCOVERY_CUSTOM_EVT,
tstamp=1543560564317]], nodeId=b9aef4b4, evt=DISCOVERY_CUSTOM_EVT],
added=true, initFut=GridFutureAdapter [ignoreInterrupts=false, state=DONE,
res=true, hash=517169392], init=true, lastVer=null,
partReleaseFut=PartitionReleaseFuture [topVer=AffinityTopologyVersion
[topVer=2, minorTopVer=2], futures=[ExplicitLockReleaseFuture
[topVer=AffinityTopologyVersion [topVer=2, minorTopVer=2], futures=[]],
AtomicUpdateReleaseFuture [topVer=AffinityTopologyVersion [topVer=2,
minorTopVer=2], futures=[]], DataStreamerReleaseFuture
[topVer=AffinityTopologyVersion [topVer=2, minorTopVer=2], futures=[]],
LocalTxReleaseFuture [topVer=AffinityTopologyVersion [topVer=2,
minorTopVer=2], futures=[]], AllTxReleaseFuture
[topVer=AffinityTopologyVersion [topVer=2, minorTopVer=2],
futures=[RemoteTxReleaseFuture [topVer=AffinityTopologyVersion [topVer=2,
minorTopVer=2], futures=[]]]]]], exchActions=null,
affChangeMsg=CacheAffinityChangeMessage
[id=656d8536761-8b0c73f9-623c-4d9b-8bf0-b301599e3885,
topVer=AffinityTopologyVersion [topVer=2, minorTopVer=0], exchId=null,
partsMsg=null, exchangeNeeded=true], initTs=1543560564317,
centralizedAff=false, forceAffReassignment=false, changeGlobalStateE=null,
done=false, state=CRD, evtLatch=0,
remaining=[99fe1608-8efa-4178-80fd-9d5b0de53ece], super=GridFutureAdapter
[ignoreInterrupts=false, state=INIT, res=null, hash=1999521151]]
Local communication statistics:
Communication SPI statistics [rmtNode=b9aef4b4-07c4-4bf3-b108-4b6907e4359f]
Communication SPI recovery descriptors:
[key=ConnectionKey [nodeId=b9aef4b4-07c4-4bf3-b108-4b6907e4359f, idx=0,
connCnt=-1, dummy=false], msgsSent=25, msgsAckedByRmt=23, msgsRcvd=24,
lastAcked=22, reserveCnt=1, descIdHash=579223629]
Communication SPI clients:
[node=b9aef4b4-07c4-4bf3-b108-4b6907e4359f,
client=GridTcpNioCommunicationClient [ses=GridSelectorNioSessionImpl
[worker=DirectNioClientWorker [super=AbstractNioClientWorker [idx=0,
bytesRcvd=95417, bytesSent=102075, bytesRcvd0=9905, bytesSent0=853,
select=true, super=GridWorker [name=grid-nio-worker-tcp-comm-0,
igniteInstanceName=null, finished=false, hashCode=1331534584,
interrupted=false, runner=grid-nio-worker-tcp-comm-0-#25]]],
writeBuf=java.nio.DirectByteBuffer[pos=0 lim=32768 cap=32768],
readBuf=java.nio.DirectByteBuffer[pos=0 lim=32768 cap=32768],
inRecovery=GridNioRecoveryDescriptor [acked=23, resendCnt=0, rcvCnt=24,
sentCnt=25, reserved=true, lastAck=22, nodeLeft=false, node=TcpDiscoveryNode
[id=b9aef4b4-07c4-4bf3-b108-4b6907e4359f, addrs=[0:0:0:0:0:0:0:1%lo,
10.137.0.17, 127.0.0.1], sockAddrs=[/10.137.0.17:47500,
/0:0:0:0:0:0:0:1%lo:47500, /127.0.0.1:47500], discPort=47500, order=1,
intOrder=1, lastExchangeTime=1543560561394, loc=false,
ver=2.6.0#20180710-sha1:669feacc, isClient=false], connected=false,
connectCnt=1, queueLimit=4096, reserveCnt=1, pairedConnections=false],
outRecovery=GridNioRecoveryDescriptor [acked=23, resendCnt=0, rcvCnt=24,
sentCnt=25, reserved=true, lastAck=22, nodeLeft=false, node=TcpDiscoveryNode
[id=b9aef4b4-07c4-4bf3-b108-4b6907e4359f, addrs=[0:0:0:0:0:0:0:1%lo,
10.137.0.17, 127.0.0.1], sockAddrs=[/10.137.0.17:47500,
/0:0:0:0:0:0:0:1%lo:47500, /127.0.0.1:47500], discPort=47500, order=1,
intOrder=1, lastExchangeTime=1543560561394, loc=false,
ver=2.6.0#20180710-sha1:669feacc, isClient=false], connected=false,
connectCnt=1, queueLimit=4096, reserveCnt=1, pairedConnections=false],
super=GridNioSessionImpl [locAddr=/0:0:0:0:0:0:0:1:52566,
rmtAddr=/0:0:0:0:0:0:0:1%lo:47100, createTime=1543560562231, closeTime=0,
bytesSent=102075, bytesRcvd=95417, bytesSent0=853, bytesRcvd0=9905,
sndSchedTime=1543561828642, lastSndTime=1543561864880,
lastRcvTime=1543561864891, readsPaused=false,
filterChain=FilterChain[filters=[GridNioCodecFilter
[parser=org.apache.ignite.internal.util.nio.GridDirectParser@66ac6f01,
directMode=true], GridConnectionBytesVerifyFilter], accepted=false]],
super=GridAbstractCommunicationClient [lastUsed=1543560562241, closed=false,
connIdx=0]]]
NIO sessions statistics:
>> Selector info [idx=0, keysCnt=1, bytesRcvd=95417, bytesRcvd0=9905,
>> bytesSent=102075, bytesSent0=853]
Connection info [in=false, rmtAddr=/0:0:0:0:0:0:0:1%lo:47100,
locAddr=/0:0:0:0:0:0:0:1:52566, msgsSent=25, msgsAckedByRmt=23,
descIdHash=579223629, unackedMsgs=[IgniteDiagnosticMessage,
IgniteDiagnosticMessage], msgsRcvd=24, lastAcked=22, descIdHash=579223629,
bytesRcvd=95417, bytesRcvd0=9905, bytesSent=102075, bytesSent0=853,
opQueueSize=0]
[2018-11-30 02:11:34,888][INFO ][grid-timeout-worker-#23][IgniteKernal]
Metrics for local node (to disable set 'metricsLogFrequency' to 0)
^-- Node [id=99fe1608, uptime=00:22:12.104]
^-- H/N/C [hosts=1, nodes=2, CPUs=2]
^-- CPU [cur=0.67%, avg=1.06%, GC=0%]
^-- PageMemory [pages=3286]
^-- Heap [used=180MB, free=72.98%, comm=218MB]
^-- Non heap [used=70MB, free=-1%, comm=72MB]
^-- Outbound messages queue [size=0]
^-- Public thread pool [active=0, idle=0, qSize=0]
^-- System thread pool [active=0, idle=8, qSize=0]
[2018-11-30 02:12:34,894][INFO ][grid-timeout-worker-#23][IgniteKernal]
Metrics for local node (to disable set 'metricsLogFrequency' to 0)
^-- Node [id=99fe1608, uptime=00:23:12.105]
^-- H/N/C [hosts=1, nodes=2, CPUs=2]
^-- CPU [cur=1%, avg=1.04%, GC=0%]
^-- PageMemory [pages=3286]
^-- Heap [used=186MB, free=72.05%, comm=218MB]
^-- Non heap [used=70MB, free=-1%, comm=72MB]
^-- Outbound messages queue [size=0]
^-- Public thread pool [active=0, idle=0, qSize=0]
^-- System thread pool [active=0, idle=6, qSize=0]
[2018-11-30 02:13:34,898][INFO ][grid-timeout-worker-#23][IgniteKernal]
Metrics for local node (to disable set 'metricsLogFrequency' to 0)
^-- Node [id=99fe1608, uptime=00:24:12.110]
^-- H/N/C [hosts=1, nodes=2, CPUs=2]
^-- CPU [cur=0.5%, avg=1.02%, GC=0%]
^-- PageMemory [pages=3286]
^-- Heap [used=48MB, free=92.67%, comm=211MB]
^-- Non heap [used=70MB, free=-1%, comm=72MB]
^-- Outbound messages queue [size=0]
^-- Public thread pool [active=0, idle=0, qSize=0]
^-- System thread pool [active=0, idle=6, qSize=0]
[2018-11-30 02:14:34,903][INFO ][grid-timeout-worker-#23][IgniteKernal]
Metrics for local node (to disable set 'metricsLogFrequency' to 0)
^-- Node [id=99fe1608, uptime=00:25:12.119]
^-- H/N/C [hosts=1, nodes=2, CPUs=2]
^-- CPU [cur=0.83%, avg=1.01%, GC=0%]
^-- PageMemory [pages=3286]
^-- Heap [used=55MB, free=91.74%, comm=211MB]
^-- Non heap [used=70MB, free=-1%, comm=72MB]
^-- Outbound messages queue [size=0]
^-- Public thread pool [active=0, idle=0, qSize=0]
^-- System thread pool [active=0, idle=6, qSize=0]
[2018-11-30 02:15:34,912][INFO ][grid-timeout-worker-#23][IgniteKernal]
Metrics for local node (to disable set 'metricsLogFrequency' to 0)
^-- Node [id=99fe1608, uptime=00:26:12.123]
^-- H/N/C [hosts=1, nodes=2, CPUs=2]
^-- CPU [cur=0.83%, avg=1%, GC=0%]
^-- PageMemory [pages=3286]
^-- Heap [used=61MB, free=90.81%, comm=211MB]
^-- Non heap [used=70MB, free=-1%, comm=72MB]
^-- Outbound messages queue [size=0]
^-- Public thread pool [active=0, idle=0, qSize=0]
^-- System thread pool [active=0, idle=6, qSize=0]
[2018-11-30 02:16:34,914][INFO ][grid-timeout-worker-#23][IgniteKernal]
Metrics for local node (to disable set 'metricsLogFrequency' to 0)
^-- Node [id=99fe1608, uptime=00:27:12.130]
^-- H/N/C [hosts=1, nodes=2, CPUs=2]
^-- CPU [cur=1%, avg=1%, GC=0%]
^-- PageMemory [pages=3286]
^-- Heap [used=67MB, free=89.89%, comm=211MB]
^-- Non heap [used=70MB, free=-1%, comm=72MB]
^-- Outbound messages queue [size=0]
^-- Public thread pool [active=0, idle=0, qSize=0]
^-- System thread pool [active=0, idle=6, qSize=0]
^[[B^[[B[2018-11-30 02:17:35,024][INFO
][grid-timeout-worker-#23][IgniteKernal]
Metrics for local node (to disable set 'metricsLogFrequency' to 0)
^-- Node [id=99fe1608, uptime=00:28:12.235]
^-- H/N/C [hosts=1, nodes=2, CPUs=2]
^-- CPU [cur=0.67%, avg=1%, GC=0%]
^-- PageMemory [pages=3286]
^-- Heap [used=73MB, free=88.96%, comm=211MB]
^-- Non heap [used=70MB, free=-1%, comm=72MB]
^-- Outbound messages queue [size=0]
^-- Public thread pool [active=0, idle=0, qSize=0]
^-- System thread pool [active=0, idle=6, qSize=0]
[2018-11-30 02:18:35,039][INFO ][grid-timeout-worker-#23][IgniteKernal]
Metrics for local node (to disable set 'metricsLogFrequency' to 0)
^-- Node [id=99fe1608, uptime=00:29:12.252]
^-- H/N/C [hosts=1, nodes=2, CPUs=2]
^-- CPU [cur=0.67%, avg=0.99%, GC=0%]
^-- PageMemory [pages=3286]
^-- Heap [used=79MB, free=88.03%, comm=211MB]
^-- Non heap [used=70MB, free=-1%, comm=72MB]
^-- Outbound messages queue [size=0]
^-- Public thread pool [active=0, idle=0, qSize=0]
^-- System thread pool [active=0, idle=6, qSize=0]
[2018-11-30 02:19:35,042][INFO ][grid-timeout-worker-#23][IgniteKernal]
Metrics for local node (to disable set 'metricsLogFrequency' to 0)
^-- Node [id=99fe1608, uptime=00:30:12.255]
^-- H/N/C [hosts=1, nodes=2, CPUs=2]
^-- CPU [cur=0.83%, avg=0.98%, GC=0%]
^-- PageMemory [pages=3286]
^-- Heap [used=86MB, free=87.05%, comm=211MB]
^-- Non heap [used=70MB, free=-1%, comm=72MB]
^-- Outbound messages queue [size=0]
^-- Public thread pool [active=0, idle=0, qSize=0]
^-- System thread pool [active=0, idle=6, qSize=0]
[2018-11-30 02:20:35,045][INFO ][grid-timeout-worker-#23][IgniteKernal]
Metrics for local node (to disable set 'metricsLogFrequency' to 0)
^-- Node [id=99fe1608, uptime=00:31:12.259]
^-- H/N/C [hosts=1, nodes=2, CPUs=2]
^-- CPU [cur=0.67%, avg=0.97%, GC=0%]
^-- PageMemory [pages=3286]
^-- Heap [used=92MB, free=86.16%, comm=211MB]
^-- Non heap [used=70MB, free=-1%, comm=72MB]
^-- Outbound messages queue [size=0]
^-- Public thread pool [active=0, idle=0, qSize=0]
^-- System thread pool [active=0, idle=6, qSize=0]
[2018-11-30 02:21:35,059][INFO ][grid-timeout-worker-#23][IgniteKernal]
Metrics for local node (to disable set 'metricsLogFrequency' to 0)
^-- Node [id=99fe1608, uptime=00:32:12.271]
^-- H/N/C [hosts=1, nodes=2, CPUs=2]
^-- CPU [cur=0.5%, avg=0.96%, GC=0%]
^-- PageMemory [pages=3286]
^-- Heap [used=98MB, free=85.23%, comm=211MB]
^-- Non heap [used=70MB, free=-1%, comm=72MB]
^-- Outbound messages queue [size=0]
^-- Public thread pool [active=0, idle=0, qSize=0]
^-- System thread pool [active=0, idle=6, qSize=0]
[2018-11-30 02:22:35,163][INFO ][grid-timeout-worker-#23][IgniteKernal]
Metrics for local node (to disable set 'metricsLogFrequency' to 0)
^-- Node [id=99fe1608, uptime=00:33:12.377]
^-- H/N/C [hosts=1, nodes=2, CPUs=2]
^-- CPU [cur=0.33%, avg=0.95%, GC=0%]
^-- PageMemory [pages=3286]
^-- Heap [used=104MB, free=84.3%, comm=211MB]
^-- Non heap [used=70MB, free=-1%, comm=72MB]
^-- Outbound messages queue [size=0]
^-- Public thread pool [active=0, idle=0, qSize=0]
^-- System thread pool [active=0, idle=6, qSize=0]
[2018-11-30 02:23:35,161][INFO ][grid-timeout-worker-#23][IgniteKernal]
Metrics for local node (to disable set 'metricsLogFrequency' to 0)
^-- Node [id=99fe1608, uptime=00:34:12.377]
^-- H/N/C [hosts=1, nodes=2, CPUs=2]
^-- CPU [cur=0.5%, avg=0.94%, GC=0%]
^-- PageMemory [pages=3286]
^-- Heap [used=110MB, free=83.41%, comm=211MB]
^-- Non heap [used=70MB, free=-1%, comm=72MB]
^-- Outbound messages queue [size=0]
^-- Public thread pool [active=0, idle=0, qSize=0]
^-- System thread pool [active=0, idle=6, qSize=0]
--
Sent from: http://apache-ignite-users.70518.x6.nabble.com/
