[jira] [Commented] (IGNITE-2372) GridCacheAbstractDataStructuresFailoverSelfTest.testQueueConstant* fail sometimes

["Artem Shutak (JIRA)" <ji...@apache.org>]

    [ https://issues.apache.org/jira/browse/IGNITE-2372?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=15096010#comment-15096010 ] 

Artem Shutak commented on IGNITE-2372:
--------------------------------------

Logs from TC (for GridCachePartitionedDataStructuresFailoverSelfTest.testQueueConstantMultipleTopologyChange)

{noformat}
junit.framework.AssertionFailedError: expected:<10188> but was:<10189>
    at junit.framework.Assert.fail(Assert.java:57)
    at junit.framework.Assert.failNotEquals(Assert.java:329)
    at junit.framework.Assert.assertEquals(Assert.java:78)
    at junit.framework.Assert.assertEquals(Assert.java:234)
    at junit.framework.Assert.assertEquals(Assert.java:241)
    at junit.framework.TestCase.assertEquals(TestCase.java:409)
    at org.apache.ignite.internal.processors.cache.datastructures.GridCacheAbstractDataStructuresFailoverSelfTest.doTestQueue(GridCacheAbstractDataStructuresFailoverSelfTest.java:729)
    at org.apache.ignite.internal.processors.cache.datastructures.GridCacheAbstractDataStructuresFailoverSelfTest.testQueueConstantMultipleTopologyChange(GridCacheAbstractDataStructuresFailoverSelfTest.java:683)
    at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
    at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
    at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
    at java.lang.reflect.Method.invoke(Method.java:606)
    at junit.framework.TestCase.runTest(TestCase.java:176)
    at org.apache.ignite.testframework.junits.GridAbstractTest.runTestInternal(GridAbstractTest.java:1699)
    at org.apache.ignite.testframework.junits.GridAbstractTest.access$000(GridAbstractTest.java:116)
    at org.apache.ignite.testframework.junits.GridAbstractTest$4.run(GridAbstractTest.java:1637)
    at java.lang.Thread.run(Thread.java:745)
------- Stderr: -------
[23:28:22,080][WARN ][grid-starter-testQueueConstantMultipleTopologyChange-1][GridCachePartitionedDataStructuresFailoverSelfTest1] Peer class loading is enabled (disable it in production for performance and deployment consistency reasons)
[23:28:22,084][WARN ][grid-starter-testQueueConstantMultipleTopologyChange-3][GridCachePartitionedDataStructuresFailoverSelfTest2] Peer class loading is enabled (disable it in production for performance and deployment consistency reasons)
[23:28:22,089][WARN ][grid-starter-testQueueConstantMultipleTopologyChange-2][GridCachePartitionedDataStructuresFailoverSelfTest0] Peer class loading is enabled (disable it in production for performance and deployment consistency reasons)
[23:28:23,640][WARN ][grid-starter-testQueueConstantMultipleTopologyChange-3][GridCollisionManager] Collision resolution is disabled (all jobs will be activated upon arrival).
[23:28:23,640][WARN ][grid-starter-testQueueConstantMultipleTopologyChange-3][NoopSwapSpaceSpi] Swap space is disabled. To enable use FileSwapSpaceSpi.
[23:28:23,850][WARN ][grid-starter-testQueueConstantMultipleTopologyChange-2][GridCollisionManager] Collision resolution is disabled (all jobs will be activated upon arrival).
[23:28:23,850][WARN ][grid-starter-testQueueConstantMultipleTopologyChange-2][NoopSwapSpaceSpi] Swap space is disabled. To enable use FileSwapSpaceSpi.
[23:28:23,856][WARN ][grid-starter-testQueueConstantMultipleTopologyChange-1][GridCollisionManager] Collision resolution is disabled (all jobs will be activated upon arrival).
[23:28:23,856][WARN ][grid-starter-testQueueConstantMultipleTopologyChange-1][NoopSwapSpaceSpi] Swap space is disabled. To enable use FileSwapSpaceSpi.
[23:28:37,316][WARN ][main][root] Waiting for topology map update [grid=partitioned.GridCachePartitionedDataStructuresFailoverSelfTest2, cache=ignite-atomics-sys-cache, cacheId=689859866, topVer=AffinityTopologyVersion [topVer=3, minorTopVer=0], topFut=GridDhtPartitionsExchangeFuture [dummy=false, forcePreload=false, reassign=false, discoEvt=DiscoveryEvent [evtNode=TcpDiscoveryNode [id=7515d89d-1808-4974-ae0f-20a6bce00001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47502], discPort=47502, order=3, intOrder=3, lastExchangeTime=1452670117255, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], topVer=3, nodeId8=fb3c51b7, msg=Node joined: TcpDiscoveryNode [id=7515d89d-1808-4974-ae0f-20a6bce00001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47502], discPort=47502, order=3, intOrder=3, lastExchangeTime=1452670117255, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], type=NODE_JOINED, tstamp=1452670117266], rcvdIds=GridConcurrentHashSet [elements=[]], rmtIds=[7515d89d-1808-4974-ae0f-20a6bce00001, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=3, minorTopVer=0], nodeId=7515d89d, evt=NODE_JOINED], init=true, ready=true, replied=false, added=true, initFut=GridFutureAdapter [resFlag=2, res=true, startTime=1452670117266, endTime=1452670117276, ignoreInterrupts=false, lsnr=null, state=DONE], topSnapshot=null, lastVer=null, partReleaseFut=GridCompoundFuture [rdc=null, flags=1, lsnrCalls=3, done=true, cancelled=false, err=null, futs=[true, true, true]], skipPreload=false, clientOnlyExchange=false, initTs=1452670117266, oldest=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, oldestOrder=1, evtLatch=0, remaining=[7515d89d-1808-4974-ae0f-20a6bce00001, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], super=GridFutureAdapter [resFlag=2, res=AffinityTopologyVersion [topVer=3, minorTopVer=0], startTime=1452670117266, endTime=1452670117296, ignoreInterrupts=false, lsnr=null, state=DONE]], p=1, affNodesCnt=2, ownersCnt=3, affNodes=[TcpDiscoveryNode [id=fb3c51b7-d917-477a-a552-af7d45f00002, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47500], discPort=47500, order=2, intOrder=2, lastExchangeTime=1452670117307, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=7515d89d-1808-4974-ae0f-20a6bce00001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47502], discPort=47502, order=3, intOrder=3, lastExchangeTime=1452670117255, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]], owners=[TcpDiscoveryNode [id=fb3c51b7-d917-477a-a552-af7d45f00002, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47500], discPort=47500, order=2, intOrder=2, lastExchangeTime=1452670117307, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=7515d89d-1808-4974-ae0f-20a6bce00001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47502], discPort=47502, order=3, intOrder=3, lastExchangeTime=1452670117255, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47501], discPort=47501, order=1, intOrder=1, lastExchangeTime=1452670115712, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]], locNode=TcpDiscoveryNode [id=fb3c51b7-d917-477a-a552-af7d45f00002, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47500], discPort=47500, order=2, intOrder=2, lastExchangeTime=1452670117307, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]]
[23:28:37,516][WARN ][main][root] Waiting for topology map update [grid=partitioned.GridCachePartitionedDataStructuresFailoverSelfTest2, cache=ignite-atomics-sys-cache, cacheId=689859866, topVer=AffinityTopologyVersion [topVer=3, minorTopVer=0], topFut=GridDhtPartitionsExchangeFuture [dummy=false, forcePreload=false, reassign=false, discoEvt=DiscoveryEvent [evtNode=TcpDiscoveryNode [id=7515d89d-1808-4974-ae0f-20a6bce00001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47502], discPort=47502, order=3, intOrder=3, lastExchangeTime=1452670117255, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], topVer=3, nodeId8=fb3c51b7, msg=Node joined: TcpDiscoveryNode [id=7515d89d-1808-4974-ae0f-20a6bce00001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47502], discPort=47502, order=3, intOrder=3, lastExchangeTime=1452670117255, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], type=NODE_JOINED, tstamp=1452670117266], rcvdIds=GridConcurrentHashSet [elements=[]], rmtIds=[7515d89d-1808-4974-ae0f-20a6bce00001, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=3, minorTopVer=0], nodeId=7515d89d, evt=NODE_JOINED], init=true, ready=true, replied=false, added=true, initFut=GridFutureAdapter [resFlag=2, res=true, startTime=1452670117266, endTime=1452670117276, ignoreInterrupts=false, lsnr=null, state=DONE], topSnapshot=null, lastVer=null, partReleaseFut=GridCompoundFuture [rdc=null, flags=1, lsnrCalls=3, done=true, cancelled=false, err=null, futs=[true, true, true]], skipPreload=false, clientOnlyExchange=false, initTs=1452670117266, oldest=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, oldestOrder=1, evtLatch=0, remaining=[7515d89d-1808-4974-ae0f-20a6bce00001, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], super=GridFutureAdapter [resFlag=2, res=AffinityTopologyVersion [topVer=3, minorTopVer=0], startTime=1452670117266, endTime=1452670117296, ignoreInterrupts=false, lsnr=null, state=DONE]], p=1, affNodesCnt=2, ownersCnt=3, affNodes=[TcpDiscoveryNode [id=fb3c51b7-d917-477a-a552-af7d45f00002, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47500], discPort=47500, order=2, intOrder=2, lastExchangeTime=1452670117444, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=7515d89d-1808-4974-ae0f-20a6bce00001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47502], discPort=47502, order=3, intOrder=3, lastExchangeTime=1452670117255, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]], owners=[TcpDiscoveryNode [id=fb3c51b7-d917-477a-a552-af7d45f00002, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47500], discPort=47500, order=2, intOrder=2, lastExchangeTime=1452670117444, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=7515d89d-1808-4974-ae0f-20a6bce00001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47502], discPort=47502, order=3, intOrder=3, lastExchangeTime=1452670117255, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47501], discPort=47501, order=1, intOrder=1, lastExchangeTime=1452670115712, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]], locNode=TcpDiscoveryNode [id=fb3c51b7-d917-477a-a552-af7d45f00002, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47500], discPort=47500, order=2, intOrder=2, lastExchangeTime=1452670117444, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]]
[23:28:39,117][WARN ][main][root] Waiting for topology map update [grid=partitioned.GridCachePartitionedDataStructuresFailoverSelfTest2, cache=ignite-atomics-sys-cache, cacheId=689859866, topVer=AffinityTopologyVersion [topVer=3, minorTopVer=0], topFut=GridDhtPartitionsExchangeFuture [dummy=false, forcePreload=false, reassign=false, discoEvt=DiscoveryEvent [evtNode=TcpDiscoveryNode [id=7515d89d-1808-4974-ae0f-20a6bce00001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47502], discPort=47502, order=3, intOrder=3, lastExchangeTime=1452670117255, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], topVer=3, nodeId8=fb3c51b7, msg=Node joined: TcpDiscoveryNode [id=7515d89d-1808-4974-ae0f-20a6bce00001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47502], discPort=47502, order=3, intOrder=3, lastExchangeTime=1452670117255, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], type=NODE_JOINED, tstamp=1452670117266], rcvdIds=GridConcurrentHashSet [elements=[]], rmtIds=[7515d89d-1808-4974-ae0f-20a6bce00001, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=3, minorTopVer=0], nodeId=7515d89d, evt=NODE_JOINED], init=true, ready=true, replied=false, added=true, initFut=GridFutureAdapter [resFlag=2, res=true, startTime=1452670117266, endTime=1452670117276, ignoreInterrupts=false, lsnr=null, state=DONE], topSnapshot=null, lastVer=null, partReleaseFut=GridCompoundFuture [rdc=null, flags=1, lsnrCalls=3, done=true, cancelled=false, err=null, futs=[true, true, true]], skipPreload=false, clientOnlyExchange=false, initTs=1452670117266, oldest=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, oldestOrder=1, evtLatch=0, remaining=[7515d89d-1808-4974-ae0f-20a6bce00001, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], super=GridFutureAdapter [resFlag=2, res=AffinityTopologyVersion [topVer=3, minorTopVer=0], startTime=1452670117266, endTime=1452670117296, ignoreInterrupts=false, lsnr=null, state=DONE]], p=1, affNodesCnt=2, ownersCnt=3, affNodes=[TcpDiscoveryNode [id=fb3c51b7-d917-477a-a552-af7d45f00002, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47500], discPort=47500, order=2, intOrder=2, lastExchangeTime=1452670119075, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=7515d89d-1808-4974-ae0f-20a6bce00001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47502], discPort=47502, order=3, intOrder=3, lastExchangeTime=1452670117255, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]], owners=[TcpDiscoveryNode [id=fb3c51b7-d917-477a-a552-af7d45f00002, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47500], discPort=47500, order=2, intOrder=2, lastExchangeTime=1452670119075, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=7515d89d-1808-4974-ae0f-20a6bce00001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47502], discPort=47502, order=3, intOrder=3, lastExchangeTime=1452670117255, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47501], discPort=47501, order=1, intOrder=1, lastExchangeTime=1452670115712, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]], locNode=TcpDiscoveryNode [id=fb3c51b7-d917-477a-a552-af7d45f00002, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47500], discPort=47500, order=2, intOrder=2, lastExchangeTime=1452670119075, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]]
[23:28:39,517][WARN ][main][root] Waiting for topology map update [grid=partitioned.GridCachePartitionedDataStructuresFailoverSelfTest2, cache=ignite-atomics-sys-cache, cacheId=689859866, topVer=AffinityTopologyVersion [topVer=3, minorTopVer=0], topFut=GridDhtPartitionsExchangeFuture [dummy=false, forcePreload=false, reassign=false, discoEvt=DiscoveryEvent [evtNode=TcpDiscoveryNode [id=7515d89d-1808-4974-ae0f-20a6bce00001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47502], discPort=47502, order=3, intOrder=3, lastExchangeTime=1452670117255, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], topVer=3, nodeId8=fb3c51b7, msg=Node joined: TcpDiscoveryNode [id=7515d89d-1808-4974-ae0f-20a6bce00001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47502], discPort=47502, order=3, intOrder=3, lastExchangeTime=1452670117255, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], type=NODE_JOINED, tstamp=1452670117266], rcvdIds=GridConcurrentHashSet [elements=[]], rmtIds=[7515d89d-1808-4974-ae0f-20a6bce00001, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=3, minorTopVer=0], nodeId=7515d89d, evt=NODE_JOINED], init=true, ready=true, replied=false, added=true, initFut=GridFutureAdapter [resFlag=2, res=true, startTime=1452670117266, endTime=1452670117276, ignoreInterrupts=false, lsnr=null, state=DONE], topSnapshot=null, lastVer=null, partReleaseFut=GridCompoundFuture [rdc=null, flags=1, lsnrCalls=3, done=true, cancelled=false, err=null, futs=[true, true, true]], skipPreload=false, clientOnlyExchange=false, initTs=1452670117266, oldest=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, oldestOrder=1, evtLatch=0, remaining=[7515d89d-1808-4974-ae0f-20a6bce00001, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], super=GridFutureAdapter [resFlag=2, res=AffinityTopologyVersion [topVer=3, minorTopVer=0], startTime=1452670117266, endTime=1452670117296, ignoreInterrupts=false, lsnr=null, state=DONE]], p=1, affNodesCnt=2, ownersCnt=3, affNodes=[TcpDiscoveryNode [id=fb3c51b7-d917-477a-a552-af7d45f00002, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47500], discPort=47500, order=2, intOrder=2, lastExchangeTime=1452670119452, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=7515d89d-1808-4974-ae0f-20a6bce00001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47502], discPort=47502, order=3, intOrder=3, lastExchangeTime=1452670117255, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]], owners=[TcpDiscoveryNode [id=fb3c51b7-d917-477a-a552-af7d45f00002, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47500], discPort=47500, order=2, intOrder=2, lastExchangeTime=1452670119452, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=7515d89d-1808-4974-ae0f-20a6bce00001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47502], discPort=47502, order=3, intOrder=3, lastExchangeTime=1452670117255, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47501], discPort=47501, order=1, intOrder=1, lastExchangeTime=1452670115712, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]], locNode=TcpDiscoveryNode [id=fb3c51b7-d917-477a-a552-af7d45f00002, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47500], discPort=47500, order=2, intOrder=2, lastExchangeTime=1452670119452, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]]
[23:28:40,317][WARN ][main][root] Finished waiting for topology map update [grid=partitioned.GridCachePartitionedDataStructuresFailoverSelfTest2, p=1, duration=3001ms]
[23:28:40,322][WARN ][main][root] Waiting for topology map update [grid=partitioned.GridCachePartitionedDataStructuresFailoverSelfTest1, cache=ignite-atomics-sys-cache, cacheId=689859866, topVer=AffinityTopologyVersion [topVer=3, minorTopVer=0], topFut=GridDhtPartitionsExchangeFuture [dummy=false, forcePreload=false, reassign=false, discoEvt=DiscoveryEvent [evtNode=TcpDiscoveryNode [id=7515d89d-1808-4974-ae0f-20a6bce00001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47502], discPort=47502, order=3, intOrder=3, lastExchangeTime=1452670119452, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], topVer=3, nodeId8=7515d89d, msg=null, type=NODE_JOINED, tstamp=1452670117266], rcvdIds=GridConcurrentHashSet [elements=[]], rmtIds=[fb3c51b7-d917-477a-a552-af7d45f00002, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=3, minorTopVer=0], nodeId=7515d89d, evt=NODE_JOINED], init=true, ready=true, replied=false, added=false, initFut=GridFutureAdapter [resFlag=2, res=true, startTime=1452670117276, endTime=1452670117296, ignoreInterrupts=false, lsnr=null, state=DONE], topSnapshot=null, lastVer=null, partReleaseFut=GridCompoundFuture [rdc=null, flags=1, lsnrCalls=3, done=true, cancelled=false, err=null, futs=[true, true, true]], skipPreload=false, clientOnlyExchange=false, initTs=1452670117286, oldest=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, oldestOrder=1, evtLatch=0, remaining=[fb3c51b7-d917-477a-a552-af7d45f00002, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], super=GridFutureAdapter [resFlag=2, res=AffinityTopologyVersion [topVer=3, minorTopVer=0], startTime=1452670117276, endTime=1452670117296, ignoreInterrupts=false, lsnr=null, state=DONE]], p=2, affNodesCnt=2, ownersCnt=3, affNodes=[TcpDiscoveryNode [id=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47501], discPort=47501, order=1, intOrder=1, lastExchangeTime=1452670117266, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=7515d89d-1808-4974-ae0f-20a6bce00001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47502], discPort=47502, order=3, intOrder=3, lastExchangeTime=1452670119452, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]], owners=[TcpDiscoveryNode [id=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47501], discPort=47501, order=1, intOrder=1, lastExchangeTime=1452670117266, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=7515d89d-1808-4974-ae0f-20a6bce00001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47502], discPort=47502, order=3, intOrder=3, lastExchangeTime=1452670119452, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=fb3c51b7-d917-477a-a552-af7d45f00002, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47500], discPort=47500, order=2, intOrder=2, lastExchangeTime=1452670117266, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]], locNode=TcpDiscoveryNode [id=7515d89d-1808-4974-ae0f-20a6bce00001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47502], discPort=47502, order=3, intOrder=3, lastExchangeTime=1452670119452, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]]
[23:28:40,722][WARN ][main][root] Waiting for topology map update [grid=partitioned.GridCachePartitionedDataStructuresFailoverSelfTest1, cache=ignite-atomics-sys-cache, cacheId=689859866, topVer=AffinityTopologyVersion [topVer=3, minorTopVer=0], topFut=GridDhtPartitionsExchangeFuture [dummy=false, forcePreload=false, reassign=false, discoEvt=DiscoveryEvent [evtNode=TcpDiscoveryNode [id=7515d89d-1808-4974-ae0f-20a6bce00001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47502], discPort=47502, order=3, intOrder=3, lastExchangeTime=1452670120637, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], topVer=3, nodeId8=7515d89d, msg=null, type=NODE_JOINED, tstamp=1452670117266], rcvdIds=GridConcurrentHashSet [elements=[]], rmtIds=[fb3c51b7-d917-477a-a552-af7d45f00002, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=3, minorTopVer=0], nodeId=7515d89d, evt=NODE_JOINED], init=true, ready=true, replied=false, added=false, initFut=GridFutureAdapter [resFlag=2, res=true, startTime=1452670117276, endTime=1452670117296, ignoreInterrupts=false, lsnr=null, state=DONE], topSnapshot=null, lastVer=null, partReleaseFut=GridCompoundFuture [rdc=null, flags=1, lsnrCalls=3, done=true, cancelled=false, err=null, futs=[true, true, true]], skipPreload=false, clientOnlyExchange=false, initTs=1452670117286, oldest=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, oldestOrder=1, evtLatch=0, remaining=[fb3c51b7-d917-477a-a552-af7d45f00002, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], super=GridFutureAdapter [resFlag=2, res=AffinityTopologyVersion [topVer=3, minorTopVer=0], startTime=1452670117276, endTime=1452670117296, ignoreInterrupts=false, lsnr=null, state=DONE]], p=2, affNodesCnt=2, ownersCnt=3, affNodes=[TcpDiscoveryNode [id=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47501], discPort=47501, order=1, intOrder=1, lastExchangeTime=1452670117266, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=7515d89d-1808-4974-ae0f-20a6bce00001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47502], discPort=47502, order=3, intOrder=3, lastExchangeTime=1452670120637, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]], owners=[TcpDiscoveryNode [id=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47501], discPort=47501, order=1, intOrder=1, lastExchangeTime=1452670117266, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=7515d89d-1808-4974-ae0f-20a6bce00001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47502], discPort=47502, order=3, intOrder=3, lastExchangeTime=1452670120637, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=fb3c51b7-d917-477a-a552-af7d45f00002, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47500], discPort=47500, order=2, intOrder=2, lastExchangeTime=1452670117266, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]], locNode=TcpDiscoveryNode [id=7515d89d-1808-4974-ae0f-20a6bce00001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47502], discPort=47502, order=3, intOrder=3, lastExchangeTime=1452670120637, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]]
[23:28:41,523][WARN ][main][root] Waiting for topology map update [grid=partitioned.GridCachePartitionedDataStructuresFailoverSelfTest1, cache=ignite-atomics-sys-cache, cacheId=689859866, topVer=AffinityTopologyVersion [topVer=3, minorTopVer=0], topFut=GridDhtPartitionsExchangeFuture [dummy=false, forcePreload=false, reassign=false, discoEvt=DiscoveryEvent [evtNode=TcpDiscoveryNode [id=7515d89d-1808-4974-ae0f-20a6bce00001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47502], discPort=47502, order=3, intOrder=3, lastExchangeTime=1452670121457, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], topVer=3, nodeId8=7515d89d, msg=null, type=NODE_JOINED, tstamp=1452670117266], rcvdIds=GridConcurrentHashSet [elements=[]], rmtIds=[fb3c51b7-d917-477a-a552-af7d45f00002, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=3, minorTopVer=0], nodeId=7515d89d, evt=NODE_JOINED], init=true, ready=true, replied=false, added=false, initFut=GridFutureAdapter [resFlag=2, res=true, startTime=1452670117276, endTime=1452670117296, ignoreInterrupts=false, lsnr=null, state=DONE], topSnapshot=null, lastVer=null, partReleaseFut=GridCompoundFuture [rdc=null, flags=1, lsnrCalls=3, done=true, cancelled=false, err=null, futs=[true, true, true]], skipPreload=false, clientOnlyExchange=false, initTs=1452670117286, oldest=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, oldestOrder=1, evtLatch=0, remaining=[fb3c51b7-d917-477a-a552-af7d45f00002, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], super=GridFutureAdapter [resFlag=2, res=AffinityTopologyVersion [topVer=3, minorTopVer=0], startTime=1452670117276, endTime=1452670117296, ignoreInterrupts=false, lsnr=null, state=DONE]], p=2, affNodesCnt=2, ownersCnt=3, affNodes=[TcpDiscoveryNode [id=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47501], discPort=47501, order=1, intOrder=1, lastExchangeTime=1452670117266, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=7515d89d-1808-4974-ae0f-20a6bce00001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47502], discPort=47502, order=3, intOrder=3, lastExchangeTime=1452670121457, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]], owners=[TcpDiscoveryNode [id=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47501], discPort=47501, order=1, intOrder=1, lastExchangeTime=1452670117266, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=7515d89d-1808-4974-ae0f-20a6bce00001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47502], discPort=47502, order=3, intOrder=3, lastExchangeTime=1452670121457, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=fb3c51b7-d917-477a-a552-af7d45f00002, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47500], discPort=47500, order=2, intOrder=2, lastExchangeTime=1452670117266, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]], locNode=TcpDiscoveryNode [id=7515d89d-1808-4974-ae0f-20a6bce00001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47502], discPort=47502, order=3, intOrder=3, lastExchangeTime=1452670121457, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]]
[23:28:41,923][WARN ][main][root] Finished waiting for topology map update [grid=partitioned.GridCachePartitionedDataStructuresFailoverSelfTest1, p=2, duration=1601ms]
[23:28:42,002][WARN ][topology-change-thread-1][IgniteKernal%11989b8d-c01c-4e83-862d-130ef6838ff0] Peer class loading is enabled (disable it in production for performance and deployment consistency reasons)
[23:28:42,007][WARN ][topology-change-thread-3][IgniteKernal%29506308-9332-4361-945f-4855b34ae831] Peer class loading is enabled (disable it in production for performance and deployment consistency reasons)
[23:28:42,011][WARN ][topology-change-thread-2][IgniteKernal%ac8e7911-67c9-4938-b73f-90f88ff46a95] Peer class loading is enabled (disable it in production for performance and deployment consistency reasons)
[23:28:43,562][WARN ][topology-change-thread-3][GridCollisionManager] Collision resolution is disabled (all jobs will be activated upon arrival).
[23:28:43,562][WARN ][topology-change-thread-3][NoopSwapSpaceSpi] Swap space is disabled. To enable use FileSwapSpaceSpi.
[23:28:43,760][WARN ][topology-change-thread-2][GridCollisionManager] Collision resolution is disabled (all jobs will be activated upon arrival).
[23:28:43,760][WARN ][topology-change-thread-2][NoopSwapSpaceSpi] Swap space is disabled. To enable use FileSwapSpaceSpi.
[23:28:43,767][WARN ][topology-change-thread-1][GridCollisionManager] Collision resolution is disabled (all jobs will be activated upon arrival).
[23:28:43,767][WARN ][topology-change-thread-1][NoopSwapSpaceSpi] Swap space is disabled. To enable use FileSwapSpaceSpi.
[23:28:43,852][WARN ][topology-change-thread-1][IgniteKernal%96286cfb-45b5-4f3a-acdc-d2ed566ded25] Peer class loading is enabled (disable it in production for performance and deployment consistency reasons)
[23:28:44,515][WARN ][topology-change-thread-1][GridCollisionManager] Collision resolution is disabled (all jobs will be activated upon arrival).
[23:28:44,515][WARN ][topology-change-thread-1][NoopSwapSpaceSpi] Swap space is disabled. To enable use FileSwapSpaceSpi.
[23:28:44,737][WARN ][topology-change-thread-1][IgniteKernal%239caaaa-2a7e-4301-927e-877b3b3f45d9] Peer class loading is enabled (disable it in production for performance and deployment consistency reasons)
[23:28:44,740][WARN ][topology-change-thread-3][IgniteKernal%12141f3f-9292-436e-8b3d-929a3e129ae2] Peer class loading is enabled (disable it in production for performance and deployment consistency reasons)
[23:28:44,887][WARN ][topology-change-thread-2][IgniteKernal%47221a07-6e85-4f65-8530-a48dc591d975] Peer class loading is enabled (disable it in production for performance and deployment consistency reasons)
[23:28:46,449][WARN ][topology-change-thread-2][GridCollisionManager] Collision resolution is disabled (all jobs will be activated upon arrival).
[23:28:46,450][WARN ][topology-change-thread-2][NoopSwapSpaceSpi] Swap space is disabled. To enable use FileSwapSpaceSpi.
[23:28:47,054][WARN ][topology-change-thread-2][IgniteKernal%fcc0b788-a8c6-4176-ae3e-3d53c3e5968d] Peer class loading is enabled (disable it in production for performance and deployment consistency reasons)
[23:28:47,521][WARN ][topology-change-thread-3][GridCollisionManager] Collision resolution is disabled (all jobs will be activated upon arrival).
[23:28:47,521][WARN ][topology-change-thread-3][NoopSwapSpaceSpi] Swap space is disabled. To enable use FileSwapSpaceSpi.
[23:28:47,672][WARN ][topology-change-thread-3][IgniteKernal%4af86325-1a0c-408b-bae8-cb0e96d291e7] Peer class loading is enabled (disable it in production for performance and deployment consistency reasons)
[23:28:47,749][WARN ][topology-change-thread-1][GridCollisionManager] Collision resolution is disabled (all jobs will be activated upon arrival).
[23:28:47,749][WARN ][topology-change-thread-1][NoopSwapSpaceSpi] Swap space is disabled. To enable use FileSwapSpaceSpi.
[23:28:48,477][WARN ][topology-change-thread-2][GridCollisionManager] Collision resolution is disabled (all jobs will be activated upon arrival).
[23:28:48,477][WARN ][topology-change-thread-2][NoopSwapSpaceSpi] Swap space is disabled. To enable use FileSwapSpaceSpi.
[23:28:48,490][WARN ][topology-change-thread-3][GridCollisionManager] Collision resolution is disabled (all jobs will be activated upon arrival).
[23:28:48,490][WARN ][topology-change-thread-3][NoopSwapSpaceSpi] Swap space is disabled. To enable use FileSwapSpaceSpi.
[23:28:48,829][WARN ][topology-change-thread-3][root] Waiting for topology map update [grid=96286cfb-45b5-4f3a-acdc-d2ed566ded25, cache=datastructures_0, cacheId=1278244283, topVer=AffinityTopologyVersion [topVer=13, minorTopVer=0], topFut=GridDhtPartitionsExchangeFuture [dummy=false, forcePreload=false, reassign=false, discoEvt=DiscoveryEvent [evtNode=TcpDiscoveryNode [id=00337f89-ca50-4dea-af2a-e71e3623b000, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47505], discPort=47505, order=4, intOrder=4, lastExchangeTime=1452670124526, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], topVer=13, nodeId8=502bc6c2, msg=Node left: TcpDiscoveryNode [id=00337f89-ca50-4dea-af2a-e71e3623b000, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47505], discPort=47505, order=4, intOrder=4, lastExchangeTime=1452670124526, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], type=NODE_LEFT, tstamp=1452670128815], rcvdIds=GridConcurrentHashSet [elements=[]], rmtIds=[70054b6c-f9d5-4c5e-bae2-937634d88007, 7515d89d-1808-4974-ae0f-20a6bce00001, 10a6f8bd-46d2-4f60-a75d-0f3c6ec89001, 20b86367-b604-441a-aa48-f85815e7c002, 503494bb-2425-405e-84e8-f30d411ae005, 90b1e011-a18d-43d3-a7ca-c5bf15e37009, fb3c51b7-d917-477a-a552-af7d45f00002, 50cc3202-49bb-46ac-a1b4-acf3de144005, ef4ef201-d3b3-4f29-ad18-5fcbf46cd186, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=13, minorTopVer=0], nodeId=00337f89, evt=NODE_LEFT], init=true, ready=false, replied=false, added=true, initFut=GridFutureAdapter [resFlag=0, res=null, startTime=1452670128815, endTime=0, ignoreInterrupts=false, lsnr=null, state=INIT], topSnapshot=null, lastVer=null, partReleaseFut=null, skipPreload=false, clientOnlyExchange=false, initTs=1452670128815, oldest=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, oldestOrder=1, evtLatch=0, remaining=[70054b6c-f9d5-4c5e-bae2-937634d88007, 7515d89d-1808-4974-ae0f-20a6bce00001, 10a6f8bd-46d2-4f60-a75d-0f3c6ec89001, 20b86367-b604-441a-aa48-f85815e7c002, 503494bb-2425-405e-84e8-f30d411ae005, 90b1e011-a18d-43d3-a7ca-c5bf15e37009, fb3c51b7-d917-477a-a552-af7d45f00002, 50cc3202-49bb-46ac-a1b4-acf3de144005, ef4ef201-d3b3-4f29-ad18-5fcbf46cd186, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], super=GridFutureAdapter [resFlag=0, res=null, startTime=1452670128815, endTime=0, ignoreInterrupts=false, lsnr=null, state=INIT]], p=0, affNodesCnt=2, ownersCnt=0, affNodes=[TcpDiscoveryNode [id=00337f89-ca50-4dea-af2a-e71e3623b000, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47505], discPort=47505, order=4, intOrder=4, lastExchangeTime=1452670124526, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=10a6f8bd-46d2-4f60-a75d-0f3c6ec89001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=6, intOrder=6, lastExchangeTime=1452670124611, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]], owners=[], locNode=TcpDiscoveryNode [id=502bc6c2-7095-43de-9813-e6692e19a005, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47506], discPort=47506, order=5, intOrder=5, lastExchangeTime=1452670128826, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]]
[23:28:49,029][WARN ][topology-change-thread-3][root] Finished waiting for topology map update [grid=96286cfb-45b5-4f3a-acdc-d2ed566ded25, p=0, duration=200ms]
[23:28:49,029][WARN ][topology-change-thread-3][root] Waiting for topology map update [grid=96286cfb-45b5-4f3a-acdc-d2ed566ded25, cache=datastructures_0, cacheId=1278244283, topVer=AffinityTopologyVersion [topVer=13, minorTopVer=0], topFut=GridDhtPartitionsExchangeFuture [dummy=false, forcePreload=false, reassign=false, discoEvt=DiscoveryEvent [evtNode=TcpDiscoveryNode [id=00337f89-ca50-4dea-af2a-e71e3623b000, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47505], discPort=47505, order=4, intOrder=4, lastExchangeTime=1452670124526, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], topVer=13, nodeId8=502bc6c2, msg=Node left: TcpDiscoveryNode [id=00337f89-ca50-4dea-af2a-e71e3623b000, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47505], discPort=47505, order=4, intOrder=4, lastExchangeTime=1452670124526, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], type=NODE_LEFT, tstamp=1452670128815], rcvdIds=GridConcurrentHashSet [elements=[]], rmtIds=[70054b6c-f9d5-4c5e-bae2-937634d88007, 7515d89d-1808-4974-ae0f-20a6bce00001, 10a6f8bd-46d2-4f60-a75d-0f3c6ec89001, 20b86367-b604-441a-aa48-f85815e7c002, 503494bb-2425-405e-84e8-f30d411ae005, 90b1e011-a18d-43d3-a7ca-c5bf15e37009, fb3c51b7-d917-477a-a552-af7d45f00002, 50cc3202-49bb-46ac-a1b4-acf3de144005, ef4ef201-d3b3-4f29-ad18-5fcbf46cd186, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=13, minorTopVer=0], nodeId=00337f89, evt=NODE_LEFT], init=true, ready=true, replied=false, added=true, initFut=GridFutureAdapter [resFlag=2, res=true, startTime=1452670128815, endTime=1452670128846, ignoreInterrupts=false, lsnr=null, state=DONE], topSnapshot=null, lastVer=null, partReleaseFut=GridCompoundFuture [rdc=null, flags=1, lsnrCalls=3, done=true, cancelled=false, err=null, futs=[true, true, true]], skipPreload=false, clientOnlyExchange=false, initTs=1452670128815, oldest=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, oldestOrder=1, evtLatch=0, remaining=[70054b6c-f9d5-4c5e-bae2-937634d88007, 7515d89d-1808-4974-ae0f-20a6bce00001, 10a6f8bd-46d2-4f60-a75d-0f3c6ec89001, 20b86367-b604-441a-aa48-f85815e7c002, 503494bb-2425-405e-84e8-f30d411ae005, 90b1e011-a18d-43d3-a7ca-c5bf15e37009, fb3c51b7-d917-477a-a552-af7d45f00002, 50cc3202-49bb-46ac-a1b4-acf3de144005, ef4ef201-d3b3-4f29-ad18-5fcbf46cd186, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], super=GridFutureAdapter [resFlag=2, res=AffinityTopologyVersion [topVer=13, minorTopVer=0], startTime=1452670128815, endTime=1452670128866, ignoreInterrupts=false, lsnr=null, state=DONE]], p=1, affNodesCnt=2, ownersCnt=3, affNodes=[TcpDiscoveryNode [id=70054b6c-f9d5-4c5e-bae2-937634d88007, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47511], discPort=47511, order=12, intOrder=12, lastExchangeTime=1452670128513, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=fb3c51b7-d917-477a-a552-af7d45f00002, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47500], discPort=47500, order=2, intOrder=2, lastExchangeTime=1452670124526, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]], owners=[TcpDiscoveryNode [id=70054b6c-f9d5-4c5e-bae2-937634d88007, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47511], discPort=47511, order=12, intOrder=12, lastExchangeTime=1452670128513, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=fb3c51b7-d917-477a-a552-af7d45f00002, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47500], discPort=47500, order=2, intOrder=2, lastExchangeTime=1452670124526, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=503494bb-2425-405e-84e8-f30d411ae005, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47507], discPort=47507, order=8, intOrder=8, lastExchangeTime=1452670126457, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]], locNode=TcpDiscoveryNode [id=502bc6c2-7095-43de-9813-e6692e19a005, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47506], discPort=47506, order=5, intOrder=5, lastExchangeTime=1452670128826, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]]
[23:28:49,231][WARN ][topology-change-thread-3][root] Finished waiting for topology map update [grid=96286cfb-45b5-4f3a-acdc-d2ed566ded25, p=1, duration=200ms]
[23:28:49,232][WARN ][topology-change-thread-3][root] Waiting for topology map update [grid=96286cfb-45b5-4f3a-acdc-d2ed566ded25, cache=datastructures_0, cacheId=1278244283, topVer=AffinityTopologyVersion [topVer=13, minorTopVer=0], topFut=GridDhtPartitionsExchangeFuture [dummy=false, forcePreload=false, reassign=false, discoEvt=DiscoveryEvent [evtNode=TcpDiscoveryNode [id=00337f89-ca50-4dea-af2a-e71e3623b000, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47505], discPort=47505, order=4, intOrder=4, lastExchangeTime=1452670124526, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], topVer=13, nodeId8=502bc6c2, msg=Node left: TcpDiscoveryNode [id=00337f89-ca50-4dea-af2a-e71e3623b000, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47505], discPort=47505, order=4, intOrder=4, lastExchangeTime=1452670124526, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], type=NODE_LEFT, tstamp=1452670128815], rcvdIds=GridConcurrentHashSet [elements=[]], rmtIds=[70054b6c-f9d5-4c5e-bae2-937634d88007, 7515d89d-1808-4974-ae0f-20a6bce00001, 10a6f8bd-46d2-4f60-a75d-0f3c6ec89001, 20b86367-b604-441a-aa48-f85815e7c002, 503494bb-2425-405e-84e8-f30d411ae005, 90b1e011-a18d-43d3-a7ca-c5bf15e37009, fb3c51b7-d917-477a-a552-af7d45f00002, 50cc3202-49bb-46ac-a1b4-acf3de144005, ef4ef201-d3b3-4f29-ad18-5fcbf46cd186, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=13, minorTopVer=0], nodeId=00337f89, evt=NODE_LEFT], init=true, ready=true, replied=false, added=true, initFut=GridFutureAdapter [resFlag=2, res=true, startTime=1452670128815, endTime=1452670128846, ignoreInterrupts=false, lsnr=null, state=DONE], topSnapshot=null, lastVer=null, partReleaseFut=GridCompoundFuture [rdc=null, flags=1, lsnrCalls=3, done=true, cancelled=false, err=null, futs=[true, true, true]], skipPreload=false, clientOnlyExchange=false, initTs=1452670128815, oldest=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, oldestOrder=1, evtLatch=0, remaining=[70054b6c-f9d5-4c5e-bae2-937634d88007, 7515d89d-1808-4974-ae0f-20a6bce00001, 10a6f8bd-46d2-4f60-a75d-0f3c6ec89001, 20b86367-b604-441a-aa48-f85815e7c002, 503494bb-2425-405e-84e8-f30d411ae005, 90b1e011-a18d-43d3-a7ca-c5bf15e37009, fb3c51b7-d917-477a-a552-af7d45f00002, 50cc3202-49bb-46ac-a1b4-acf3de144005, ef4ef201-d3b3-4f29-ad18-5fcbf46cd186, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], super=GridFutureAdapter [resFlag=2, res=AffinityTopologyVersion [topVer=13, minorTopVer=0], startTime=1452670128815, endTime=1452670128866, ignoreInterrupts=false, lsnr=null, state=DONE]], p=703, affNodesCnt=2, ownersCnt=3, affNodes=[TcpDiscoveryNode [id=70054b6c-f9d5-4c5e-bae2-937634d88007, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47511], discPort=47511, order=12, intOrder=12, lastExchangeTime=1452670128513, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=ef4ef201-d3b3-4f29-ad18-5fcbf46cd186, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47510], discPort=47510, order=11, intOrder=11, lastExchangeTime=1452670128491, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]], owners=[TcpDiscoveryNode [id=70054b6c-f9d5-4c5e-bae2-937634d88007, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47511], discPort=47511, order=12, intOrder=12, lastExchangeTime=1452670128513, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=ef4ef201-d3b3-4f29-ad18-5fcbf46cd186, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47510], discPort=47510, order=11, intOrder=11, lastExchangeTime=1452670128491, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=503494bb-2425-405e-84e8-f30d411ae005, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47507], discPort=47507, order=8, intOrder=8, lastExchangeTime=1452670126457, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]], locNode=TcpDiscoveryNode [id=502bc6c2-7095-43de-9813-e6692e19a005, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47506], discPort=47506, order=5, intOrder=5, lastExchangeTime=1452670128826, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]]
[23:28:49,632][WARN ][topology-change-thread-3][root] Waiting for topology map update [grid=96286cfb-45b5-4f3a-acdc-d2ed566ded25, cache=datastructures_0, cacheId=1278244283, topVer=AffinityTopologyVersion [topVer=13, minorTopVer=0], topFut=GridDhtPartitionsExchangeFuture [dummy=false, forcePreload=false, reassign=false, discoEvt=DiscoveryEvent [evtNode=TcpDiscoveryNode [id=00337f89-ca50-4dea-af2a-e71e3623b000, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47505], discPort=47505, order=4, intOrder=4, lastExchangeTime=1452670124526, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], topVer=13, nodeId8=502bc6c2, msg=Node left: TcpDiscoveryNode [id=00337f89-ca50-4dea-af2a-e71e3623b000, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47505], discPort=47505, order=4, intOrder=4, lastExchangeTime=1452670124526, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], type=NODE_LEFT, tstamp=1452670128815], rcvdIds=GridConcurrentHashSet [elements=[]], rmtIds=[70054b6c-f9d5-4c5e-bae2-937634d88007, 7515d89d-1808-4974-ae0f-20a6bce00001, 10a6f8bd-46d2-4f60-a75d-0f3c6ec89001, 20b86367-b604-441a-aa48-f85815e7c002, 503494bb-2425-405e-84e8-f30d411ae005, 90b1e011-a18d-43d3-a7ca-c5bf15e37009, fb3c51b7-d917-477a-a552-af7d45f00002, 50cc3202-49bb-46ac-a1b4-acf3de144005, ef4ef201-d3b3-4f29-ad18-5fcbf46cd186, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=13, minorTopVer=0], nodeId=00337f89, evt=NODE_LEFT], init=true, ready=true, replied=false, added=true, initFut=GridFutureAdapter [resFlag=2, res=true, startTime=1452670128815, endTime=1452670128846, ignoreInterrupts=false, lsnr=null, state=DONE], topSnapshot=null, lastVer=null, partReleaseFut=GridCompoundFuture [rdc=null, flags=1, lsnrCalls=3, done=true, cancelled=false, err=null, futs=[true, true, true]], skipPreload=false, clientOnlyExchange=false, initTs=1452670128815, oldest=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, oldestOrder=1, evtLatch=0, remaining=[70054b6c-f9d5-4c5e-bae2-937634d88007, 7515d89d-1808-4974-ae0f-20a6bce00001, 10a6f8bd-46d2-4f60-a75d-0f3c6ec89001, 20b86367-b604-441a-aa48-f85815e7c002, 503494bb-2425-405e-84e8-f30d411ae005, 90b1e011-a18d-43d3-a7ca-c5bf15e37009, fb3c51b7-d917-477a-a552-af7d45f00002, 50cc3202-49bb-46ac-a1b4-acf3de144005, ef4ef201-d3b3-4f29-ad18-5fcbf46cd186, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], super=GridFutureAdapter [resFlag=2, res=AffinityTopologyVersion [topVer=13, minorTopVer=0], startTime=1452670128815, endTime=1452670128866, ignoreInterrupts=false, lsnr=null, state=DONE]], p=703, affNodesCnt=2, ownersCnt=3, affNodes=[TcpDiscoveryNode [id=70054b6c-f9d5-4c5e-bae2-937634d88007, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47511], discPort=47511, order=12, intOrder=12, lastExchangeTime=1452670128513, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=ef4ef201-d3b3-4f29-ad18-5fcbf46cd186, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47510], discPort=47510, order=11, intOrder=11, lastExchangeTime=1452670128491, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]], owners=[TcpDiscoveryNode [id=70054b6c-f9d5-4c5e-bae2-937634d88007, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47511], discPort=47511, order=12, intOrder=12, lastExchangeTime=1452670128513, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=ef4ef201-d3b3-4f29-ad18-5fcbf46cd186, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47510], discPort=47510, order=11, intOrder=11, lastExchangeTime=1452670128491, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=503494bb-2425-405e-84e8-f30d411ae005, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47507], discPort=47507, order=8, intOrder=8, lastExchangeTime=1452670126457, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]], locNode=TcpDiscoveryNode [id=502bc6c2-7095-43de-9813-e6692e19a005, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47506], discPort=47506, order=5, intOrder=5, lastExchangeTime=1452670129492, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]]
[23:28:51,436][WARN ][topology-change-thread-3][root] Waiting for topology map update [grid=96286cfb-45b5-4f3a-acdc-d2ed566ded25, cache=datastructures_0, cacheId=1278244283, topVer=AffinityTopologyVersion [topVer=13, minorTopVer=0], topFut=GridDhtPartitionsExchangeFuture [dummy=false, forcePreload=false, reassign=false, discoEvt=DiscoveryEvent [evtNode=TcpDiscoveryNode [id=00337f89-ca50-4dea-af2a-e71e3623b000, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47505], discPort=47505, order=4, intOrder=4, lastExchangeTime=1452670124526, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], topVer=13, nodeId8=502bc6c2, msg=Node left: TcpDiscoveryNode [id=00337f89-ca50-4dea-af2a-e71e3623b000, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47505], discPort=47505, order=4, intOrder=4, lastExchangeTime=1452670124526, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], type=NODE_LEFT, tstamp=1452670128815], rcvdIds=GridConcurrentHashSet [elements=[]], rmtIds=[70054b6c-f9d5-4c5e-bae2-937634d88007, 7515d89d-1808-4974-ae0f-20a6bce00001, 10a6f8bd-46d2-4f60-a75d-0f3c6ec89001, 20b86367-b604-441a-aa48-f85815e7c002, 503494bb-2425-405e-84e8-f30d411ae005, 90b1e011-a18d-43d3-a7ca-c5bf15e37009, fb3c51b7-d917-477a-a552-af7d45f00002, 50cc3202-49bb-46ac-a1b4-acf3de144005, ef4ef201-d3b3-4f29-ad18-5fcbf46cd186, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=13, minorTopVer=0], nodeId=00337f89, evt=NODE_LEFT], init=true, ready=true, replied=false, added=true, initFut=GridFutureAdapter [resFlag=2, res=true, startTime=1452670128815, endTime=1452670128846, ignoreInterrupts=false, lsnr=null, state=DONE], topSnapshot=null, lastVer=null, partReleaseFut=GridCompoundFuture [rdc=null, flags=1, lsnrCalls=3, done=true, cancelled=false, err=null, futs=[true, true, true]], skipPreload=false, clientOnlyExchange=false, initTs=1452670128815, oldest=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, oldestOrder=1, evtLatch=0, remaining=[70054b6c-f9d5-4c5e-bae2-937634d88007, 7515d89d-1808-4974-ae0f-20a6bce00001, 10a6f8bd-46d2-4f60-a75d-0f3c6ec89001, 20b86367-b604-441a-aa48-f85815e7c002, 503494bb-2425-405e-84e8-f30d411ae005, 90b1e011-a18d-43d3-a7ca-c5bf15e37009, fb3c51b7-d917-477a-a552-af7d45f00002, 50cc3202-49bb-46ac-a1b4-acf3de144005, ef4ef201-d3b3-4f29-ad18-5fcbf46cd186, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], super=GridFutureAdapter [resFlag=2, res=AffinityTopologyVersion [topVer=13, minorTopVer=0], startTime=1452670128815, endTime=1452670128866, ignoreInterrupts=false, lsnr=null, state=DONE]], p=703, affNodesCnt=2, ownersCnt=3, affNodes=[TcpDiscoveryNode [id=70054b6c-f9d5-4c5e-bae2-937634d88007, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47511], discPort=47511, order=12, intOrder=12, lastExchangeTime=1452670128513, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=ef4ef201-d3b3-4f29-ad18-5fcbf46cd186, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47510], discPort=47510, order=11, intOrder=11, lastExchangeTime=1452670128491, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]], owners=[TcpDiscoveryNode [id=70054b6c-f9d5-4c5e-bae2-937634d88007, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47511], discPort=47511, order=12, intOrder=12, lastExchangeTime=1452670128513, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=ef4ef201-d3b3-4f29-ad18-5fcbf46cd186, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47510], discPort=47510, order=11, intOrder=11, lastExchangeTime=1452670128491, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=503494bb-2425-405e-84e8-f30d411ae005, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47507], discPort=47507, order=8, intOrder=8, lastExchangeTime=1452670126457, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]], locNode=TcpDiscoveryNode [id=502bc6c2-7095-43de-9813-e6692e19a005, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47506], discPort=47506, order=5, intOrder=5, lastExchangeTime=1452670131281, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]]
[23:28:51,636][WARN ][topology-change-thread-3][root] Waiting for topology map update [grid=96286cfb-45b5-4f3a-acdc-d2ed566ded25, cache=datastructures_0, cacheId=1278244283, topVer=AffinityTopologyVersion [topVer=13, minorTopVer=0], topFut=GridDhtPartitionsExchangeFuture [dummy=false, forcePreload=false, reassign=false, discoEvt=DiscoveryEvent [evtNode=TcpDiscoveryNode [id=00337f89-ca50-4dea-af2a-e71e3623b000, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47505], discPort=47505, order=4, intOrder=4, lastExchangeTime=1452670124526, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], topVer=13, nodeId8=502bc6c2, msg=Node left: TcpDiscoveryNode [id=00337f89-ca50-4dea-af2a-e71e3623b000, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47505], discPort=47505, order=4, intOrder=4, lastExchangeTime=1452670124526, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], type=NODE_LEFT, tstamp=1452670128815], rcvdIds=GridConcurrentHashSet [elements=[]], rmtIds=[70054b6c-f9d5-4c5e-bae2-937634d88007, 7515d89d-1808-4974-ae0f-20a6bce00001, 10a6f8bd-46d2-4f60-a75d-0f3c6ec89001, 20b86367-b604-441a-aa48-f85815e7c002, 503494bb-2425-405e-84e8-f30d411ae005, 90b1e011-a18d-43d3-a7ca-c5bf15e37009, fb3c51b7-d917-477a-a552-af7d45f00002, 50cc3202-49bb-46ac-a1b4-acf3de144005, ef4ef201-d3b3-4f29-ad18-5fcbf46cd186, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=13, minorTopVer=0], nodeId=00337f89, evt=NODE_LEFT], init=true, ready=true, replied=false, added=true, initFut=GridFutureAdapter [resFlag=2, res=true, startTime=1452670128815, endTime=1452670128846, ignoreInterrupts=false, lsnr=null, state=DONE], topSnapshot=null, lastVer=null, partReleaseFut=GridCompoundFuture [rdc=null, flags=1, lsnrCalls=3, done=true, cancelled=false, err=null, futs=[true, true, true]], skipPreload=false, clientOnlyExchange=false, initTs=1452670128815, oldest=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, oldestOrder=1, evtLatch=0, remaining=[70054b6c-f9d5-4c5e-bae2-937634d88007, 7515d89d-1808-4974-ae0f-20a6bce00001, 10a6f8bd-46d2-4f60-a75d-0f3c6ec89001, 20b86367-b604-441a-aa48-f85815e7c002, 503494bb-2425-405e-84e8-f30d411ae005, 90b1e011-a18d-43d3-a7ca-c5bf15e37009, fb3c51b7-d917-477a-a552-af7d45f00002, 50cc3202-49bb-46ac-a1b4-acf3de144005, ef4ef201-d3b3-4f29-ad18-5fcbf46cd186, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], super=GridFutureAdapter [resFlag=2, res=AffinityTopologyVersion [topVer=13, minorTopVer=0], startTime=1452670128815, endTime=1452670128866, ignoreInterrupts=false, lsnr=null, state=DONE]], p=703, affNodesCnt=2, ownersCnt=3, affNodes=[TcpDiscoveryNode [id=70054b6c-f9d5-4c5e-bae2-937634d88007, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47511], discPort=47511, order=12, intOrder=12, lastExchangeTime=1452670128513, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=ef4ef201-d3b3-4f29-ad18-5fcbf46cd186, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47510], discPort=47510, order=11, intOrder=11, lastExchangeTime=1452670128491, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]], owners=[TcpDiscoveryNode [id=70054b6c-f9d5-4c5e-bae2-937634d88007, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47511], discPort=47511, order=12, intOrder=12, lastExchangeTime=1452670128513, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=ef4ef201-d3b3-4f29-ad18-5fcbf46cd186, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47510], discPort=47510, order=11, intOrder=11, lastExchangeTime=1452670128491, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=503494bb-2425-405e-84e8-f30d411ae005, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47507], discPort=47507, order=8, intOrder=8, lastExchangeTime=1452670126457, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]], locNode=TcpDiscoveryNode [id=502bc6c2-7095-43de-9813-e6692e19a005, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47506], discPort=47506, order=5, intOrder=5, lastExchangeTime=1452670131498, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]]
[23:28:52,236][WARN ][topology-change-thread-3][root] Finished waiting for topology map update [grid=96286cfb-45b5-4f3a-acdc-d2ed566ded25, p=703, duration=3004ms]
[23:28:52,279][WARN ][topology-change-thread-3][root] Waiting for topology map update [grid=96286cfb-45b5-4f3a-acdc-d2ed566ded25, cache=datastructures_0, cacheId=1278244283, topVer=AffinityTopologyVersion [topVer=14, minorTopVer=0], topFut=GridDhtPartitionsExchangeFuture [dummy=false, forcePreload=false, reassign=false, discoEvt=DiscoveryEvent [evtNode=TcpDiscoveryNode [id=20b86367-b604-441a-aa48-f85815e7c002, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47508], discPort=47508, order=9, intOrder=9, lastExchangeTime=1452670127528, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], topVer=14, nodeId8=502bc6c2, msg=Node left: TcpDiscoveryNode [id=20b86367-b604-441a-aa48-f85815e7c002, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47508], discPort=47508, order=9, intOrder=9, lastExchangeTime=1452670127528, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], type=NODE_LEFT, tstamp=1452670132260], rcvdIds=GridConcurrentHashSet [elements=[]], rmtIds=[70054b6c-f9d5-4c5e-bae2-937634d88007, 7515d89d-1808-4974-ae0f-20a6bce00001, 10a6f8bd-46d2-4f60-a75d-0f3c6ec89001, 503494bb-2425-405e-84e8-f30d411ae005, 90b1e011-a18d-43d3-a7ca-c5bf15e37009, fb3c51b7-d917-477a-a552-af7d45f00002, 50cc3202-49bb-46ac-a1b4-acf3de144005, ef4ef201-d3b3-4f29-ad18-5fcbf46cd186, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=14, minorTopVer=0], nodeId=20b86367, evt=NODE_LEFT], init=true, ready=false, replied=false, added=true, initFut=GridFutureAdapter [resFlag=0, res=null, startTime=1452670132260, endTime=0, ignoreInterrupts=false, lsnr=null, state=INIT], topSnapshot=null, lastVer=null, partReleaseFut=null, skipPreload=false, clientOnlyExchange=false, initTs=1452670132260, oldest=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, oldestOrder=1, evtLatch=0, remaining=[70054b6c-f9d5-4c5e-bae2-937634d88007, 7515d89d-1808-4974-ae0f-20a6bce00001, 10a6f8bd-46d2-4f60-a75d-0f3c6ec89001, 503494bb-2425-405e-84e8-f30d411ae005, 90b1e011-a18d-43d3-a7ca-c5bf15e37009, fb3c51b7-d917-477a-a552-af7d45f00002, 50cc3202-49bb-46ac-a1b4-acf3de144005, ef4ef201-d3b3-4f29-ad18-5fcbf46cd186, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], super=GridFutureAdapter [resFlag=0, res=null, startTime=1452670132260, endTime=0, ignoreInterrupts=false, lsnr=null, state=INIT]], p=0, affNodesCnt=2, ownersCnt=0, affNodes=[TcpDiscoveryNode [id=10a6f8bd-46d2-4f60-a75d-0f3c6ec89001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=6, intOrder=6, lastExchangeTime=1452670124611, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47501], discPort=47501, order=1, intOrder=1, lastExchangeTime=1452670124526, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]], owners=[], locNode=TcpDiscoveryNode [id=502bc6c2-7095-43de-9813-e6692e19a005, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47506], discPort=47506, order=5, intOrder=5, lastExchangeTime=1452670132271, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]]
[23:28:52,479][WARN ][topology-change-thread-3][root] Waiting for topology map update [grid=96286cfb-45b5-4f3a-acdc-d2ed566ded25, cache=datastructures_0, cacheId=1278244283, topVer=AffinityTopologyVersion [topVer=14, minorTopVer=0], topFut=GridDhtPartitionsExchangeFuture [dummy=false, forcePreload=false, reassign=false, discoEvt=DiscoveryEvent [evtNode=TcpDiscoveryNode [id=20b86367-b604-441a-aa48-f85815e7c002, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47508], discPort=47508, order=9, intOrder=9, lastExchangeTime=1452670127528, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], topVer=14, nodeId8=502bc6c2, msg=Node left: TcpDiscoveryNode [id=20b86367-b604-441a-aa48-f85815e7c002, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47508], discPort=47508, order=9, intOrder=9, lastExchangeTime=1452670127528, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], type=NODE_LEFT, tstamp=1452670132260], rcvdIds=GridConcurrentHashSet [elements=[]], rmtIds=[70054b6c-f9d5-4c5e-bae2-937634d88007, 7515d89d-1808-4974-ae0f-20a6bce00001, 10a6f8bd-46d2-4f60-a75d-0f3c6ec89001, 503494bb-2425-405e-84e8-f30d411ae005, 90b1e011-a18d-43d3-a7ca-c5bf15e37009, fb3c51b7-d917-477a-a552-af7d45f00002, 50cc3202-49bb-46ac-a1b4-acf3de144005, ef4ef201-d3b3-4f29-ad18-5fcbf46cd186, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=14, minorTopVer=0], nodeId=20b86367, evt=NODE_LEFT], init=true, ready=true, replied=false, added=true, initFut=GridFutureAdapter [resFlag=2, res=true, startTime=1452670132260, endTime=1452670132291, ignoreInterrupts=false, lsnr=null, state=DONE], topSnapshot=null, lastVer=null, partReleaseFut=GridCompoundFuture [rdc=null, flags=1, lsnrCalls=3, done=true, cancelled=false, err=null, futs=[true, true, true]], skipPreload=false, clientOnlyExchange=false, initTs=1452670132260, oldest=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, oldestOrder=1, evtLatch=0, remaining=[70054b6c-f9d5-4c5e-bae2-937634d88007, 7515d89d-1808-4974-ae0f-20a6bce00001, 10a6f8bd-46d2-4f60-a75d-0f3c6ec89001, 503494bb-2425-405e-84e8-f30d411ae005, 90b1e011-a18d-43d3-a7ca-c5bf15e37009, fb3c51b7-d917-477a-a552-af7d45f00002, 50cc3202-49bb-46ac-a1b4-acf3de144005, ef4ef201-d3b3-4f29-ad18-5fcbf46cd186, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], super=GridFutureAdapter [resFlag=0, res=null, startTime=1452670132260, endTime=0, ignoreInterrupts=false, lsnr=null, state=INIT]], p=0, affNodesCnt=2, ownersCnt=0, affNodes=[TcpDiscoveryNode [id=10a6f8bd-46d2-4f60-a75d-0f3c6ec89001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=6, intOrder=6, lastExchangeTime=1452670124611, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47501], discPort=47501, order=1, intOrder=1, lastExchangeTime=1452670124526, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]], owners=[], locNode=TcpDiscoveryNode [id=502bc6c2-7095-43de-9813-e6692e19a005, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47506], discPort=47506, order=5, intOrder=5, lastExchangeTime=1452670132271, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]]
[23:28:53,479][WARN ][topology-change-thread-3][root] Finished waiting for topology map update [grid=96286cfb-45b5-4f3a-acdc-d2ed566ded25, p=0, duration=1200ms]
[23:28:53,528][WARN ][topology-change-thread-3][root] Waiting for topology map update [grid=96286cfb-45b5-4f3a-acdc-d2ed566ded25, cache=datastructures_0, cacheId=1278244283, topVer=AffinityTopologyVersion [topVer=15, minorTopVer=0], topFut=GridDhtPartitionsExchangeFuture [dummy=false, forcePreload=false, reassign=false, discoEvt=DiscoveryEvent [evtNode=TcpDiscoveryNode [id=90b1e011-a18d-43d3-a7ca-c5bf15e37009, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47509], discPort=47509, order=10, intOrder=10, lastExchangeTime=1452670127760, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], topVer=15, nodeId8=502bc6c2, msg=Node left: TcpDiscoveryNode [id=90b1e011-a18d-43d3-a7ca-c5bf15e37009, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47509], discPort=47509, order=10, intOrder=10, lastExchangeTime=1452670127760, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], type=NODE_LEFT, tstamp=1452670133504], rcvdIds=GridConcurrentHashSet [elements=[]], rmtIds=[70054b6c-f9d5-4c5e-bae2-937634d88007, 7515d89d-1808-4974-ae0f-20a6bce00001, 10a6f8bd-46d2-4f60-a75d-0f3c6ec89001, 503494bb-2425-405e-84e8-f30d411ae005, fb3c51b7-d917-477a-a552-af7d45f00002, 50cc3202-49bb-46ac-a1b4-acf3de144005, ef4ef201-d3b3-4f29-ad18-5fcbf46cd186, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=15, minorTopVer=0], nodeId=90b1e011, evt=NODE_LEFT], init=true, ready=false, replied=false, added=true, initFut=GridFutureAdapter [resFlag=0, res=null, startTime=1452670133504, endTime=0, ignoreInterrupts=false, lsnr=null, state=INIT], topSnapshot=null, lastVer=null, partReleaseFut=null, skipPreload=false, clientOnlyExchange=false, initTs=1452670133504, oldest=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, oldestOrder=1, evtLatch=0, remaining=[70054b6c-f9d5-4c5e-bae2-937634d88007, 7515d89d-1808-4974-ae0f-20a6bce00001, 10a6f8bd-46d2-4f60-a75d-0f3c6ec89001, 503494bb-2425-405e-84e8-f30d411ae005, fb3c51b7-d917-477a-a552-af7d45f00002, 50cc3202-49bb-46ac-a1b4-acf3de144005, ef4ef201-d3b3-4f29-ad18-5fcbf46cd186, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], super=GridFutureAdapter [resFlag=0, res=null, startTime=1452670133504, endTime=0, ignoreInterrupts=false, lsnr=null, state=INIT]], p=0, affNodesCnt=2, ownersCnt=0, affNodes=[TcpDiscoveryNode [id=10a6f8bd-46d2-4f60-a75d-0f3c6ec89001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=6, intOrder=6, lastExchangeTime=1452670124611, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47501], discPort=47501, order=1, intOrder=1, lastExchangeTime=1452670124526, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]], owners=[], locNode=TcpDiscoveryNode [id=502bc6c2-7095-43de-9813-e6692e19a005, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47506], discPort=47506, order=5, intOrder=5, lastExchangeTime=1452670133515, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]]
[23:28:53,728][WARN ][topology-change-thread-3][root] Waiting for topology map update [grid=96286cfb-45b5-4f3a-acdc-d2ed566ded25, cache=datastructures_0, cacheId=1278244283, topVer=AffinityTopologyVersion [topVer=15, minorTopVer=0], topFut=GridDhtPartitionsExchangeFuture [dummy=false, forcePreload=false, reassign=false, discoEvt=DiscoveryEvent [evtNode=TcpDiscoveryNode [id=90b1e011-a18d-43d3-a7ca-c5bf15e37009, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47509], discPort=47509, order=10, intOrder=10, lastExchangeTime=1452670127760, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], topVer=15, nodeId8=502bc6c2, msg=Node left: TcpDiscoveryNode [id=90b1e011-a18d-43d3-a7ca-c5bf15e37009, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47509], discPort=47509, order=10, intOrder=10, lastExchangeTime=1452670127760, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], type=NODE_LEFT, tstamp=1452670133504], rcvdIds=GridConcurrentHashSet [elements=[]], rmtIds=[70054b6c-f9d5-4c5e-bae2-937634d88007, 7515d89d-1808-4974-ae0f-20a6bce00001, 10a6f8bd-46d2-4f60-a75d-0f3c6ec89001, 503494bb-2425-405e-84e8-f30d411ae005, fb3c51b7-d917-477a-a552-af7d45f00002, 50cc3202-49bb-46ac-a1b4-acf3de144005, ef4ef201-d3b3-4f29-ad18-5fcbf46cd186, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=15, minorTopVer=0], nodeId=90b1e011, evt=NODE_LEFT], init=true, ready=true, replied=false, added=true, initFut=GridFutureAdapter [resFlag=2, res=true, startTime=1452670133504, endTime=1452670133544, ignoreInterrupts=false, lsnr=null, state=DONE], topSnapshot=null, lastVer=null, partReleaseFut=GridCompoundFuture [rdc=null, flags=1, lsnrCalls=3, done=true, cancelled=false, err=null, futs=[true, true, true]], skipPreload=false, clientOnlyExchange=false, initTs=1452670133504, oldest=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, oldestOrder=1, evtLatch=0, remaining=[70054b6c-f9d5-4c5e-bae2-937634d88007, 7515d89d-1808-4974-ae0f-20a6bce00001, 10a6f8bd-46d2-4f60-a75d-0f3c6ec89001, 503494bb-2425-405e-84e8-f30d411ae005, fb3c51b7-d917-477a-a552-af7d45f00002, 50cc3202-49bb-46ac-a1b4-acf3de144005, ef4ef201-d3b3-4f29-ad18-5fcbf46cd186, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], super=GridFutureAdapter [resFlag=0, res=null, startTime=1452670133504, endTime=0, ignoreInterrupts=false, lsnr=null, state=INIT]], p=0, affNodesCnt=2, ownersCnt=0, affNodes=[TcpDiscoveryNode [id=10a6f8bd-46d2-4f60-a75d-0f3c6ec89001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=6, intOrder=6, lastExchangeTime=1452670124611, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47501], discPort=47501, order=1, intOrder=1, lastExchangeTime=1452670124526, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]], owners=[], locNode=TcpDiscoveryNode [id=502bc6c2-7095-43de-9813-e6692e19a005, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47506], discPort=47506, order=5, intOrder=5, lastExchangeTime=1452670133515, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]]
[23:28:54,729][WARN ][topology-change-thread-3][root] Finished waiting for topology map update [grid=96286cfb-45b5-4f3a-acdc-d2ed566ded25, p=0, duration=1201ms]
[23:28:54,772][WARN ][topology-change-thread-3][root] Waiting for topology map update [grid=96286cfb-45b5-4f3a-acdc-d2ed566ded25, cache=datastructures_0, cacheId=1278244283, topVer=AffinityTopologyVersion [topVer=16, minorTopVer=0], topFut=GridDhtPartitionsExchangeFuture [dummy=false, forcePreload=false, reassign=false, discoEvt=DiscoveryEvent [evtNode=TcpDiscoveryNode [id=10a6f8bd-46d2-4f60-a75d-0f3c6ec89001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=6, intOrder=6, lastExchangeTime=1452670124611, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], topVer=16, nodeId8=502bc6c2, msg=Node left: TcpDiscoveryNode [id=10a6f8bd-46d2-4f60-a75d-0f3c6ec89001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=6, intOrder=6, lastExchangeTime=1452670124611, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], type=NODE_LEFT, tstamp=1452670134754], rcvdIds=GridConcurrentHashSet [elements=[]], rmtIds=[70054b6c-f9d5-4c5e-bae2-937634d88007, 7515d89d-1808-4974-ae0f-20a6bce00001, 503494bb-2425-405e-84e8-f30d411ae005, fb3c51b7-d917-477a-a552-af7d45f00002, 50cc3202-49bb-46ac-a1b4-acf3de144005, ef4ef201-d3b3-4f29-ad18-5fcbf46cd186, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=16, minorTopVer=0], nodeId=10a6f8bd, evt=NODE_LEFT], init=true, ready=false, replied=false, added=true, initFut=GridFutureAdapter [resFlag=0, res=null, startTime=1452670134754, endTime=0, ignoreInterrupts=false, lsnr=null, state=INIT], topSnapshot=null, lastVer=null, partReleaseFut=null, skipPreload=false, clientOnlyExchange=false, initTs=1452670134754, oldest=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, oldestOrder=1, evtLatch=0, remaining=[70054b6c-f9d5-4c5e-bae2-937634d88007, 7515d89d-1808-4974-ae0f-20a6bce00001, 503494bb-2425-405e-84e8-f30d411ae005, fb3c51b7-d917-477a-a552-af7d45f00002, 50cc3202-49bb-46ac-a1b4-acf3de144005, ef4ef201-d3b3-4f29-ad18-5fcbf46cd186, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], super=GridFutureAdapter [resFlag=0, res=null, startTime=1452670134754, endTime=0, ignoreInterrupts=false, lsnr=null, state=INIT]], p=0, affNodesCnt=2, ownersCnt=0, affNodes=[TcpDiscoveryNode [id=10a6f8bd-46d2-4f60-a75d-0f3c6ec89001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=6, intOrder=6, lastExchangeTime=1452670124611, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47501], discPort=47501, order=1, intOrder=1, lastExchangeTime=1452670124526, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]], owners=[], locNode=TcpDiscoveryNode [id=502bc6c2-7095-43de-9813-e6692e19a005, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47506], discPort=47506, order=5, intOrder=5, lastExchangeTime=1452670134765, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]]
[23:28:54,973][WARN ][topology-change-thread-3][root] Waiting for topology map update [grid=96286cfb-45b5-4f3a-acdc-d2ed566ded25, cache=datastructures_0, cacheId=1278244283, topVer=AffinityTopologyVersion [topVer=16, minorTopVer=0], topFut=GridDhtPartitionsExchangeFuture [dummy=false, forcePreload=false, reassign=false, discoEvt=DiscoveryEvent [evtNode=TcpDiscoveryNode [id=10a6f8bd-46d2-4f60-a75d-0f3c6ec89001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=6, intOrder=6, lastExchangeTime=1452670124611, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], topVer=16, nodeId8=502bc6c2, msg=Node left: TcpDiscoveryNode [id=10a6f8bd-46d2-4f60-a75d-0f3c6ec89001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=6, intOrder=6, lastExchangeTime=1452670124611, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], type=NODE_LEFT, tstamp=1452670134754], rcvdIds=GridConcurrentHashSet [elements=[]], rmtIds=[70054b6c-f9d5-4c5e-bae2-937634d88007, 7515d89d-1808-4974-ae0f-20a6bce00001, 503494bb-2425-405e-84e8-f30d411ae005, fb3c51b7-d917-477a-a552-af7d45f00002, 50cc3202-49bb-46ac-a1b4-acf3de144005, ef4ef201-d3b3-4f29-ad18-5fcbf46cd186, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=16, minorTopVer=0], nodeId=10a6f8bd, evt=NODE_LEFT], init=true, ready=true, replied=false, added=true, initFut=GridFutureAdapter [resFlag=2, res=true, startTime=1452670134754, endTime=1452670134775, ignoreInterrupts=false, lsnr=null, state=DONE], topSnapshot=null, lastVer=null, partReleaseFut=GridCompoundFuture [rdc=null, flags=1, lsnrCalls=3, done=true, cancelled=false, err=null, futs=[true, true, true]], skipPreload=false, clientOnlyExchange=false, initTs=1452670134754, oldest=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, oldestOrder=1, evtLatch=0, remaining=[70054b6c-f9d5-4c5e-bae2-937634d88007, 7515d89d-1808-4974-ae0f-20a6bce00001, 503494bb-2425-405e-84e8-f30d411ae005, fb3c51b7-d917-477a-a552-af7d45f00002, 50cc3202-49bb-46ac-a1b4-acf3de144005, ef4ef201-d3b3-4f29-ad18-5fcbf46cd186, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], super=GridFutureAdapter [resFlag=0, res=null, startTime=1452670134754, endTime=0, ignoreInterrupts=false, lsnr=null, state=INIT]], p=0, affNodesCnt=2, ownersCnt=0, affNodes=[TcpDiscoveryNode [id=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47501], discPort=47501, order=1, intOrder=1, lastExchangeTime=1452670124526, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=502bc6c2-7095-43de-9813-e6692e19a005, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47506], discPort=47506, order=5, intOrder=5, lastExchangeTime=1452670134765, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]], owners=[], locNode=TcpDiscoveryNode [id=502bc6c2-7095-43de-9813-e6692e19a005, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47506], discPort=47506, order=5, intOrder=5, lastExchangeTime=1452670134765, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]]
[23:28:55,573][WARN ][topology-change-thread-3][root] Waiting for topology map update [grid=96286cfb-45b5-4f3a-acdc-d2ed566ded25, cache=datastructures_0, cacheId=1278244283, topVer=AffinityTopologyVersion [topVer=16, minorTopVer=0], topFut=GridDhtPartitionsExchangeFuture [dummy=false, forcePreload=false, reassign=false, discoEvt=DiscoveryEvent [evtNode=TcpDiscoveryNode [id=10a6f8bd-46d2-4f60-a75d-0f3c6ec89001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=6, intOrder=6, lastExchangeTime=1452670124611, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], topVer=16, nodeId8=502bc6c2, msg=Node left: TcpDiscoveryNode [id=10a6f8bd-46d2-4f60-a75d-0f3c6ec89001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=6, intOrder=6, lastExchangeTime=1452670124611, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], type=NODE_LEFT, tstamp=1452670134754], rcvdIds=GridConcurrentHashSet [elements=[]], rmtIds=[70054b6c-f9d5-4c5e-bae2-937634d88007, 7515d89d-1808-4974-ae0f-20a6bce00001, 503494bb-2425-405e-84e8-f30d411ae005, fb3c51b7-d917-477a-a552-af7d45f00002, 50cc3202-49bb-46ac-a1b4-acf3de144005, ef4ef201-d3b3-4f29-ad18-5fcbf46cd186, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=16, minorTopVer=0], nodeId=10a6f8bd, evt=NODE_LEFT], init=true, ready=true, replied=false, added=true, initFut=GridFutureAdapter [resFlag=2, res=true, startTime=1452670134754, endTime=1452670134775, ignoreInterrupts=false, lsnr=null, state=DONE], topSnapshot=null, lastVer=null, partReleaseFut=GridCompoundFuture [rdc=null, flags=1, lsnrCalls=3, done=true, cancelled=false, err=null, futs=[true, true, true]], skipPreload=false, clientOnlyExchange=false, initTs=1452670134754, oldest=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, oldestOrder=1, evtLatch=0, remaining=[70054b6c-f9d5-4c5e-bae2-937634d88007, 7515d89d-1808-4974-ae0f-20a6bce00001, 503494bb-2425-405e-84e8-f30d411ae005, fb3c51b7-d917-477a-a552-af7d45f00002, 50cc3202-49bb-46ac-a1b4-acf3de144005, ef4ef201-d3b3-4f29-ad18-5fcbf46cd186, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], super=GridFutureAdapter [resFlag=0, res=null, startTime=1452670134754, endTime=0, ignoreInterrupts=false, lsnr=null, state=INIT]], p=0, affNodesCnt=2, ownersCnt=0, affNodes=[TcpDiscoveryNode [id=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47501], discPort=47501, order=1, intOrder=1, lastExchangeTime=1452670124526, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=502bc6c2-7095-43de-9813-e6692e19a005, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47506], discPort=47506, order=5, intOrder=5, lastExchangeTime=1452670135505, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]], owners=[], locNode=TcpDiscoveryNode [id=502bc6c2-7095-43de-9813-e6692e19a005, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47506], discPort=47506, order=5, intOrder=5, lastExchangeTime=1452670135505, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]]
[23:28:55,973][WARN ][topology-change-thread-3][root] Finished waiting for topology map update [grid=96286cfb-45b5-4f3a-acdc-d2ed566ded25, p=0, duration=1201ms]
[23:28:55,995][WARN ][tcp-disco-sock-reader-#39639%47221a07-6e85-4f65-8530-a48dc591d975%][TcpDiscoverySpi] Failed to read magic header (too few bytes received) [rmtAddr=/127.0.0.1:53769, locAddr=/127.0.0.1:47507]
[23:28:56,005][WARN ][topology-change-thread-3][root] Waiting for topology map update [grid=96286cfb-45b5-4f3a-acdc-d2ed566ded25, cache=datastructures_0, cacheId=1278244283, topVer=AffinityTopologyVersion [topVer=17, minorTopVer=0], topFut=GridDhtPartitionsExchangeFuture [dummy=false, forcePreload=false, reassign=false, discoEvt=DiscoveryEvent [evtNode=TcpDiscoveryNode [id=503494bb-2425-405e-84e8-f30d411ae005, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47507], discPort=47507, order=8, intOrder=8, lastExchangeTime=1452670126457, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], topVer=17, nodeId8=502bc6c2, msg=Node left: TcpDiscoveryNode [id=503494bb-2425-405e-84e8-f30d411ae005, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47507], discPort=47507, order=8, intOrder=8, lastExchangeTime=1452670126457, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], type=NODE_LEFT, tstamp=1452670135985], rcvdIds=GridConcurrentHashSet [elements=[]], rmtIds=[70054b6c-f9d5-4c5e-bae2-937634d88007, 7515d89d-1808-4974-ae0f-20a6bce00001, fb3c51b7-d917-477a-a552-af7d45f00002, 50cc3202-49bb-46ac-a1b4-acf3de144005, ef4ef201-d3b3-4f29-ad18-5fcbf46cd186, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=17, minorTopVer=0], nodeId=503494bb, evt=NODE_LEFT], init=true, ready=false, replied=false, added=true, initFut=GridFutureAdapter [resFlag=0, res=null, startTime=1452670135996, endTime=0, ignoreInterrupts=false, lsnr=null, state=INIT], topSnapshot=null, lastVer=null, partReleaseFut=null, skipPreload=false, clientOnlyExchange=false, initTs=1452670135996, oldest=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, oldestOrder=1, evtLatch=0, remaining=[70054b6c-f9d5-4c5e-bae2-937634d88007, 7515d89d-1808-4974-ae0f-20a6bce00001, fb3c51b7-d917-477a-a552-af7d45f00002, 50cc3202-49bb-46ac-a1b4-acf3de144005, ef4ef201-d3b3-4f29-ad18-5fcbf46cd186, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], super=GridFutureAdapter [resFlag=0, res=null, startTime=1452670135996, endTime=0, ignoreInterrupts=false, lsnr=null, state=INIT]], p=0, affNodesCnt=2, ownersCnt=0, affNodes=[TcpDiscoveryNode [id=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47501], discPort=47501, order=1, intOrder=1, lastExchangeTime=1452670124526, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=502bc6c2-7095-43de-9813-e6692e19a005, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47506], discPort=47506, order=5, intOrder=5, lastExchangeTime=1452670135996, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]], owners=[], locNode=TcpDiscoveryNode [id=502bc6c2-7095-43de-9813-e6692e19a005, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47506], discPort=47506, order=5, intOrder=5, lastExchangeTime=1452670135996, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]]
[23:28:56,206][WARN ][topology-change-thread-3][root] Finished waiting for topology map update [grid=96286cfb-45b5-4f3a-acdc-d2ed566ded25, p=0, duration=201ms]
[23:28:56,226][WARN ][tcp-disco-sock-reader-#39645%4af86325-1a0c-408b-bae8-cb0e96d291e7%][TcpDiscoverySpi] Failed to read magic header (too few bytes received) [rmtAddr=/127.0.0.1:53773, locAddr=/127.0.0.1:47511]
[23:28:56,236][WARN ][topology-change-thread-3][root] Waiting for topology map update [grid=96286cfb-45b5-4f3a-acdc-d2ed566ded25, cache=datastructures_0, cacheId=1278244283, topVer=AffinityTopologyVersion [topVer=18, minorTopVer=0], topFut=GridDhtPartitionsExchangeFuture [dummy=false, forcePreload=false, reassign=false, discoEvt=DiscoveryEvent [evtNode=TcpDiscoveryNode [id=70054b6c-f9d5-4c5e-bae2-937634d88007, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47511], discPort=47511, order=12, intOrder=12, lastExchangeTime=1452670128513, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], topVer=18, nodeId8=502bc6c2, msg=Node left: TcpDiscoveryNode [id=70054b6c-f9d5-4c5e-bae2-937634d88007, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47511], discPort=47511, order=12, intOrder=12, lastExchangeTime=1452670128513, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], type=NODE_LEFT, tstamp=1452670136226], rcvdIds=GridConcurrentHashSet [elements=[]], rmtIds=[7515d89d-1808-4974-ae0f-20a6bce00001, fb3c51b7-d917-477a-a552-af7d45f00002, 50cc3202-49bb-46ac-a1b4-acf3de144005, ef4ef201-d3b3-4f29-ad18-5fcbf46cd186, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=18, minorTopVer=0], nodeId=70054b6c, evt=NODE_LEFT], init=true, ready=false, replied=false, added=true, initFut=GridFutureAdapter [resFlag=0, res=null, startTime=1452670136226, endTime=0, ignoreInterrupts=false, lsnr=null, state=INIT], topSnapshot=null, lastVer=null, partReleaseFut=null, skipPreload=false, clientOnlyExchange=false, initTs=1452670136226, oldest=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, oldestOrder=1, evtLatch=0, remaining=[7515d89d-1808-4974-ae0f-20a6bce00001, fb3c51b7-d917-477a-a552-af7d45f00002, 50cc3202-49bb-46ac-a1b4-acf3de144005, ef4ef201-d3b3-4f29-ad18-5fcbf46cd186, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], super=GridFutureAdapter [resFlag=0, res=null, startTime=1452670136226, endTime=0, ignoreInterrupts=false, lsnr=null, state=INIT]], p=0, affNodesCnt=2, ownersCnt=0, affNodes=[TcpDiscoveryNode [id=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47501], discPort=47501, order=1, intOrder=1, lastExchangeTime=1452670124526, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=502bc6c2-7095-43de-9813-e6692e19a005, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47506], discPort=47506, order=5, intOrder=5, lastExchangeTime=1452670136226, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]], owners=[], locNode=TcpDiscoveryNode [id=502bc6c2-7095-43de-9813-e6692e19a005, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47506], discPort=47506, order=5, intOrder=5, lastExchangeTime=1452670136226, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]]
[23:28:56,437][WARN ][topology-change-thread-3][root] Waiting for topology map update [grid=96286cfb-45b5-4f3a-acdc-d2ed566ded25, cache=datastructures_0, cacheId=1278244283, topVer=AffinityTopologyVersion [topVer=18, minorTopVer=0], topFut=GridDhtPartitionsExchangeFuture [dummy=false, forcePreload=false, reassign=false, discoEvt=DiscoveryEvent [evtNode=TcpDiscoveryNode [id=70054b6c-f9d5-4c5e-bae2-937634d88007, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47511], discPort=47511, order=12, intOrder=12, lastExchangeTime=1452670128513, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], topVer=18, nodeId8=502bc6c2, msg=Node left: TcpDiscoveryNode [id=70054b6c-f9d5-4c5e-bae2-937634d88007, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47511], discPort=47511, order=12, intOrder=12, lastExchangeTime=1452670128513, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], type=NODE_LEFT, tstamp=1452670136226], rcvdIds=GridConcurrentHashSet [elements=[]], rmtIds=[7515d89d-1808-4974-ae0f-20a6bce00001, fb3c51b7-d917-477a-a552-af7d45f00002, 50cc3202-49bb-46ac-a1b4-acf3de144005, ef4ef201-d3b3-4f29-ad18-5fcbf46cd186, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=18, minorTopVer=0], nodeId=70054b6c, evt=NODE_LEFT], init=true, ready=false, replied=false, added=true, initFut=GridFutureAdapter [resFlag=0, res=null, startTime=1452670136226, endTime=0, ignoreInterrupts=false, lsnr=null, state=INIT], topSnapshot=null, lastVer=null, partReleaseFut=GridCompoundFuture [rdc=null, flags=1, lsnrCalls=2, done=false, cancelled=false, err=null, futs=[true, false, true]], skipPreload=false, clientOnlyExchange=false, initTs=1452670136226, oldest=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, oldestOrder=1, evtLatch=0, remaining=[7515d89d-1808-4974-ae0f-20a6bce00001, fb3c51b7-d917-477a-a552-af7d45f00002, 50cc3202-49bb-46ac-a1b4-acf3de144005, ef4ef201-d3b3-4f29-ad18-5fcbf46cd186, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], super=GridFutureAdapter [resFlag=0, res=null, startTime=1452670136226, endTime=0, ignoreInterrupts=false, lsnr=null, state=INIT]], p=0, affNodesCnt=2, ownersCnt=0, affNodes=[TcpDiscoveryNode [id=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47501], discPort=47501, order=1, intOrder=1, lastExchangeTime=1452670124526, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=502bc6c2-7095-43de-9813-e6692e19a005, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47506], discPort=47506, order=5, intOrder=5, lastExchangeTime=1452670136226, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]], owners=[], locNode=TcpDiscoveryNode [id=502bc6c2-7095-43de-9813-e6692e19a005, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47506], discPort=47506, order=5, intOrder=5, lastExchangeTime=1452670136226, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]]
[23:28:57,237][WARN ][topology-change-thread-3][root] Waiting for topology map update [grid=96286cfb-45b5-4f3a-acdc-d2ed566ded25, cache=datastructures_0, cacheId=1278244283, topVer=AffinityTopologyVersion [topVer=18, minorTopVer=0], topFut=GridDhtPartitionsExchangeFuture [dummy=false, forcePreload=false, reassign=false, discoEvt=DiscoveryEvent [evtNode=TcpDiscoveryNode [id=70054b6c-f9d5-4c5e-bae2-937634d88007, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47511], discPort=47511, order=12, intOrder=12, lastExchangeTime=1452670128513, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], topVer=18, nodeId8=502bc6c2, msg=Node left: TcpDiscoveryNode [id=70054b6c-f9d5-4c5e-bae2-937634d88007, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47511], discPort=47511, order=12, intOrder=12, lastExchangeTime=1452670128513, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], type=NODE_LEFT, tstamp=1452670136226], rcvdIds=GridConcurrentHashSet [elements=[]], rmtIds=[7515d89d-1808-4974-ae0f-20a6bce00001, fb3c51b7-d917-477a-a552-af7d45f00002, 50cc3202-49bb-46ac-a1b4-acf3de144005, ef4ef201-d3b3-4f29-ad18-5fcbf46cd186, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=18, minorTopVer=0], nodeId=70054b6c, evt=NODE_LEFT], init=true, ready=true, replied=false, added=true, initFut=GridFutureAdapter [resFlag=2, res=true, startTime=1452670136226, endTime=1452670137232, ignoreInterrupts=false, lsnr=null, state=DONE], topSnapshot=null, lastVer=null, partReleaseFut=GridCompoundFuture [rdc=null, flags=1, lsnrCalls=3, done=true, cancelled=false, err=null, futs=[true, true, true]], skipPreload=false, clientOnlyExchange=false, initTs=1452670136226, oldest=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, oldestOrder=1, evtLatch=0, remaining=[7515d89d-1808-4974-ae0f-20a6bce00001, fb3c51b7-d917-477a-a552-af7d45f00002, 50cc3202-49bb-46ac-a1b4-acf3de144005, ef4ef201-d3b3-4f29-ad18-5fcbf46cd186, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], super=GridFutureAdapter [resFlag=0, res=null, startTime=1452670136226, endTime=0, ignoreInterrupts=false, lsnr=null, state=INIT]], p=0, affNodesCnt=2, ownersCnt=0, affNodes=[TcpDiscoveryNode [id=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47501], discPort=47501, order=1, intOrder=1, lastExchangeTime=1452670124526, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=502bc6c2-7095-43de-9813-e6692e19a005, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47506], discPort=47506, order=5, intOrder=5, lastExchangeTime=1452670136226, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]], owners=[], locNode=TcpDiscoveryNode [id=502bc6c2-7095-43de-9813-e6692e19a005, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47506], discPort=47506, order=5, intOrder=5, lastExchangeTime=1452670136226, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]]
[23:28:57,437][WARN ][topology-change-thread-3][root] Finished waiting for topology map update [grid=96286cfb-45b5-4f3a-acdc-d2ed566ded25, p=0, duration=1201ms]
[23:28:57,469][WARN ][topology-change-thread-3][root] Waiting for topology map update [grid=ac8e7911-67c9-4938-b73f-90f88ff46a95, cache=datastructures_0, cacheId=1278244283, topVer=AffinityTopologyVersion [topVer=19, minorTopVer=0], topFut=GridDhtPartitionsExchangeFuture [dummy=false, forcePreload=false, reassign=false, discoEvt=DiscoveryEvent [evtNode=TcpDiscoveryNode [id=502bc6c2-7095-43de-9813-e6692e19a005, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47506], discPort=47506, order=5, intOrder=5, lastExchangeTime=1452670124775, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], topVer=19, nodeId8=50cc3202, msg=Node left: TcpDiscoveryNode [id=502bc6c2-7095-43de-9813-e6692e19a005, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47506], discPort=47506, order=5, intOrder=5, lastExchangeTime=1452670124775, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], type=NODE_LEFT, tstamp=1452670137459], rcvdIds=GridConcurrentHashSet [elements=[]], rmtIds=[7515d89d-1808-4974-ae0f-20a6bce00001, fb3c51b7-d917-477a-a552-af7d45f00002, ef4ef201-d3b3-4f29-ad18-5fcbf46cd186, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=19, minorTopVer=0], nodeId=502bc6c2, evt=NODE_LEFT], init=true, ready=false, replied=false, added=true, initFut=GridFutureAdapter [resFlag=0, res=null, startTime=1452670137459, endTime=0, ignoreInterrupts=false, lsnr=null, state=INIT], topSnapshot=null, lastVer=null, partReleaseFut=null, skipPreload=false, clientOnlyExchange=false, initTs=1452670137459, oldest=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, oldestOrder=1, evtLatch=0, remaining=[7515d89d-1808-4974-ae0f-20a6bce00001, fb3c51b7-d917-477a-a552-af7d45f00002, ef4ef201-d3b3-4f29-ad18-5fcbf46cd186, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], super=GridFutureAdapter [resFlag=0, res=null, startTime=1452670137459, endTime=0, ignoreInterrupts=false, lsnr=null, state=INIT]], p=0, affNodesCnt=2, ownersCnt=0, affNodes=[TcpDiscoveryNode [id=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47501], discPort=47501, order=1, intOrder=1, lastExchangeTime=1452670124775, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=502bc6c2-7095-43de-9813-e6692e19a005, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47506], discPort=47506, order=5, intOrder=5, lastExchangeTime=1452670124775, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]], owners=[], locNode=TcpDiscoveryNode [id=50cc3202-49bb-46ac-a1b4-acf3de144005, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47504], discPort=47504, order=7, intOrder=7, lastExchangeTime=1452670137469, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]]
[23:28:57,669][WARN ][topology-change-thread-3][root] Finished waiting for topology map update [grid=ac8e7911-67c9-4938-b73f-90f88ff46a95, p=0, duration=200ms]
[23:28:57,685][WARN ][grid-nio-worker-1-#127386%fcc0b788-a8c6-4176-ae3e-3d53c3e5968d%][TcpCommunicationSpi] Failed to process selector key (will close): GridSelectorNioSessionImpl [selectorIdx=1, queueSize=0, writeBuf=java.nio.DirectByteBuffer[pos=0 lim=32768 cap=32768], readBuf=java.nio.DirectByteBuffer[pos=0 lim=32768 cap=32768], recovery=GridNioRecoveryDescriptor [acked=3184, resendCnt=0, rcvCnt=188, reserved=true, lastAck=176, nodeLeft=false, node=TcpDiscoveryNode [id=50cc3202-49bb-46ac-a1b4-acf3de144005, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47504], discPort=47504, order=7, intOrder=7, lastExchangeTime=1452670128502, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], connected=true, connectCnt=1, queueLimit=5120], super=GridNioSessionImpl [locAddr=/127.0.0.1:53666, rmtAddr=/127.0.0.1:45444, createTime=1452670128596, closeTime=0, bytesSent=698509, bytesRcvd=36985, sndSchedTime=1452670137681, lastSndTime=1452670137681, lastRcvTime=1452670137671, readsPaused=false, filterChain=FilterChain[filters=[GridNioCodecFilter [parser=o.a.i.i.util.nio.GridDirectParser@6360892b, directMode=true], GridConnectionBytesVerifyFilter], accepted=false]]
[23:28:57,685][WARN ][grid-nio-worker-1-#127386%fcc0b788-a8c6-4176-ae3e-3d53c3e5968d%][TcpCommunicationSpi] Closing NIO session because of unhandled exception [cls=class o.a.i.i.util.nio.GridNioException, msg=An existing connection was forcibly closed by the remote host]
[23:28:57,696][WARN ][topology-change-thread-3][root] Waiting for topology map update [grid=fcc0b788-a8c6-4176-ae3e-3d53c3e5968d, cache=datastructures_0, cacheId=1278244283, topVer=AffinityTopologyVersion [topVer=20, minorTopVer=0], topFut=GridDhtPartitionsExchangeFuture [dummy=false, forcePreload=false, reassign=false, discoEvt=DiscoveryEvent [evtNode=TcpDiscoveryNode [id=50cc3202-49bb-46ac-a1b4-acf3de144005, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47504], discPort=47504, order=7, intOrder=7, lastExchangeTime=1452670128502, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], topVer=20, nodeId8=ef4ef201, msg=Node left: TcpDiscoveryNode [id=50cc3202-49bb-46ac-a1b4-acf3de144005, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47504], discPort=47504, order=7, intOrder=7, lastExchangeTime=1452670128502, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], type=NODE_LEFT, tstamp=1452670137681], rcvdIds=GridConcurrentHashSet [elements=[]], rmtIds=[7515d89d-1808-4974-ae0f-20a6bce00001, fb3c51b7-d917-477a-a552-af7d45f00002, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=20, minorTopVer=0], nodeId=50cc3202, evt=NODE_LEFT], init=true, ready=false, replied=false, added=true, initFut=GridFutureAdapter [resFlag=0, res=null, startTime=1452670137681, endTime=0, ignoreInterrupts=false, lsnr=null, state=INIT], topSnapshot=null, lastVer=null, partReleaseFut=null, skipPreload=false, clientOnlyExchange=false, initTs=1452670137681, oldest=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, oldestOrder=1, evtLatch=0, remaining=[7515d89d-1808-4974-ae0f-20a6bce00001, fb3c51b7-d917-477a-a552-af7d45f00002, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], super=GridFutureAdapter [resFlag=0, res=null, startTime=1452670137681, endTime=0, ignoreInterrupts=false, lsnr=null, state=INIT]], p=0, affNodesCnt=2, ownersCnt=0, affNodes=[TcpDiscoveryNode [id=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47501], discPort=47501, order=1, intOrder=1, lastExchangeTime=1452670128502, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=ef4ef201-d3b3-4f29-ad18-5fcbf46cd186, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47510], discPort=47510, order=11, intOrder=11, lastExchangeTime=1452670137692, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]], owners=[], locNode=TcpDiscoveryNode [id=ef4ef201-d3b3-4f29-ad18-5fcbf46cd186, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47510], discPort=47510, order=11, intOrder=11, lastExchangeTime=1452670137692, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]]
[23:28:57,896][WARN ][topology-change-thread-3][root] Finished waiting for topology map update [grid=fcc0b788-a8c6-4176-ae3e-3d53c3e5968d, p=0, duration=200ms]
[23:28:57,919][WARN ][topology-change-thread-3][root] Waiting for topology map update [grid=partitioned.GridCachePartitionedDataStructuresFailoverSelfTest2, cache=datastructures_0, cacheId=1278244283, topVer=AffinityTopologyVersion [topVer=21, minorTopVer=0], topFut=GridDhtPartitionsExchangeFuture [dummy=false, forcePreload=false, reassign=false, discoEvt=DiscoveryEvent [evtNode=TcpDiscoveryNode [id=ef4ef201-d3b3-4f29-ad18-5fcbf46cd186, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47510], discPort=47510, order=11, intOrder=11, lastExchangeTime=1452670128481, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], topVer=21, nodeId8=fb3c51b7, msg=Node left: TcpDiscoveryNode [id=ef4ef201-d3b3-4f29-ad18-5fcbf46cd186, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47510], discPort=47510, order=11, intOrder=11, lastExchangeTime=1452670128481, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], type=NODE_LEFT, tstamp=1452670137909], rcvdIds=GridConcurrentHashSet [elements=[]], rmtIds=[7515d89d-1808-4974-ae0f-20a6bce00001, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=21, minorTopVer=0], nodeId=ef4ef201, evt=NODE_LEFT], init=true, ready=false, replied=false, added=true, initFut=GridFutureAdapter [resFlag=0, res=null, startTime=1452670137909, endTime=0, ignoreInterrupts=false, lsnr=null, state=INIT], topSnapshot=null, lastVer=null, partReleaseFut=null, skipPreload=false, clientOnlyExchange=false, initTs=1452670137909, oldest=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, oldestOrder=1, evtLatch=0, remaining=[7515d89d-1808-4974-ae0f-20a6bce00001, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], super=GridFutureAdapter [resFlag=0, res=null, startTime=1452670137909, endTime=0, ignoreInterrupts=false, lsnr=null, state=INIT]], p=0, affNodesCnt=2, ownersCnt=0, affNodes=[TcpDiscoveryNode [id=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47501], discPort=47501, order=1, intOrder=1, lastExchangeTime=1452670115712, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=fb3c51b7-d917-477a-a552-af7d45f00002, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47500], discPort=47500, order=2, intOrder=2, lastExchangeTime=1452670137909, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]], owners=[], locNode=TcpDiscoveryNode [id=fb3c51b7-d917-477a-a552-af7d45f00002, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47500], discPort=47500, order=2, intOrder=2, lastExchangeTime=1452670137909, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]]
[23:28:58,119][WARN ][topology-change-thread-3][root] Finished waiting for topology map update [grid=partitioned.GridCachePartitionedDataStructuresFailoverSelfTest2, p=0, duration=200ms]
[23:28:58,146][WARN ][topology-change-thread-3][IgniteKernal%793c1fc5-5962-4a22-a3d4-1848cf9484c5] Peer class loading is enabled (disable it in production for performance and deployment consistency reasons)
[23:28:58,150][WARN ][topology-change-thread-1][IgniteKernal%672c6b37-df9a-4407-8964-abb100fd2e1a] Peer class loading is enabled (disable it in production for performance and deployment consistency reasons)
[23:28:58,154][WARN ][topology-change-thread-2][IgniteKernal%05c04980-e389-486d-9d2e-16ee4158e929] Peer class loading is enabled (disable it in production for performance and deployment consistency reasons)
[23:28:59,716][WARN ][topology-change-thread-1][GridCollisionManager] Collision resolution is disabled (all jobs will be activated upon arrival).
[23:28:59,716][WARN ][topology-change-thread-1][NoopSwapSpaceSpi] Swap space is disabled. To enable use FileSwapSpaceSpi.
[23:28:59,827][WARN ][topology-change-thread-1][IgniteKernal%0793c0ef-01f3-4987-80e5-d9dff34b3d6d] Peer class loading is enabled (disable it in production for performance and deployment consistency reasons)
[23:29:00,314][WARN ][topology-change-thread-2][GridCollisionManager] Collision resolution is disabled (all jobs will be activated upon arrival).
[23:29:00,315][WARN ][topology-change-thread-2][NoopSwapSpaceSpi] Swap space is disabled. To enable use FileSwapSpaceSpi.
[23:29:00,426][WARN ][topology-change-thread-2][IgniteKernal%4550d374-9e93-437d-b818-b6035d439f9a] Peer class loading is enabled (disable it in production for performance and deployment consistency reasons)
[23:29:00,521][WARN ][topology-change-thread-3][GridCollisionManager] Collision resolution is disabled (all jobs will be activated upon arrival).
[23:29:00,521][WARN ][topology-change-thread-3][NoopSwapSpaceSpi] Swap space is disabled. To enable use FileSwapSpaceSpi.
[23:29:00,640][WARN ][topology-change-thread-3][IgniteKernal%0030bacf-ccfb-471b-bf43-20c768c13cf4] Peer class loading is enabled (disable it in production for performance and deployment consistency reasons)
[23:29:01,582][WARN ][topology-change-thread-1][GridCollisionManager] Collision resolution is disabled (all jobs will be activated upon arrival).
[23:29:01,582][WARN ][topology-change-thread-1][NoopSwapSpaceSpi] Swap space is disabled. To enable use FileSwapSpaceSpi.
[23:29:01,803][WARN ][topology-change-thread-1][IgniteKernal%3c25b9cc-29c1-4b7f-a84c-45d4d35e3218] Peer class loading is enabled (disable it in production for performance and deployment consistency reasons)
[23:29:01,821][WARN ][topology-change-thread-2][GridCollisionManager] Collision resolution is disabled (all jobs will be activated upon arrival).
[23:29:01,821][WARN ][topology-change-thread-2][NoopSwapSpaceSpi] Swap space is disabled. To enable use FileSwapSpaceSpi.
[23:29:01,969][WARN ][topology-change-thread-2][IgniteKernal%5f175a85-dc25-4da9-9deb-22abf4fe03b5] Peer class loading is enabled (disable it in production for performance and deployment consistency reasons)
[23:29:02,962][WARN ][topology-change-thread-3][GridCollisionManager] Collision resolution is disabled (all jobs will be activated upon arrival).
[23:29:02,962][WARN ][topology-change-thread-3][NoopSwapSpaceSpi] Swap space is disabled. To enable use FileSwapSpaceSpi.
[23:29:03,179][WARN ][topology-change-thread-3][IgniteKernal%2ef7af99-c1f2-4927-82dd-3ce59ab72a42] Peer class loading is enabled (disable it in production for performance and deployment consistency reasons)
[23:29:03,243][WARN ][topology-change-thread-1][GridCollisionManager] Collision resolution is disabled (all jobs will be activated upon arrival).
[23:29:03,243][WARN ][topology-change-thread-1][NoopSwapSpaceSpi] Swap space is disabled. To enable use FileSwapSpaceSpi.
[23:29:03,939][WARN ][topology-change-thread-2][GridCollisionManager] Collision resolution is disabled (all jobs will be activated upon arrival).
[23:29:03,939][WARN ][topology-change-thread-3][GridCollisionManager] Collision resolution is disabled (all jobs will be activated upon arrival).
[23:29:03,939][WARN ][topology-change-thread-2][NoopSwapSpaceSpi] Swap space is disabled. To enable use FileSwapSpaceSpi.
[23:29:03,939][WARN ][topology-change-thread-3][NoopSwapSpaceSpi] Swap space is disabled. To enable use FileSwapSpaceSpi.
[23:29:04,273][WARN ][topology-change-thread-2][root] Waiting for topology map update [grid=5f175a85-dc25-4da9-9deb-22abf4fe03b5, cache=datastructures_0, cacheId=1278244283, topVer=AffinityTopologyVersion [topVer=31, minorTopVer=0], topFut=GridDhtPartitionsExchangeFuture [dummy=false, forcePreload=false, reassign=false, discoEvt=DiscoveryEvent [evtNode=TcpDiscoveryNode [id=40cbc83b-17f4-4418-a260-281a2844d004, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47508], discPort=47508, order=27, intOrder=18, lastExchangeTime=1452670143973, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], topVer=31, nodeId8=50f14d64, msg=Node left: TcpDiscoveryNode [id=40cbc83b-17f4-4418-a260-281a2844d004, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47508], discPort=47508, order=27, intOrder=18, lastExchangeTime=1452670143973, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], type=NODE_LEFT, tstamp=1452670144261], rcvdIds=GridConcurrentHashSet [elements=[]], rmtIds=[7515d89d-1808-4974-ae0f-20a6bce00001, 8001ce22-de1b-4ee1-a9a5-9f8033087008, 209bc679-78c9-470b-9868-aa9e389ec002, ec2bd5f5-449f-44e8-9089-1a2e520e236b, 9073c236-503b-4ca9-b7b9-f798cb70f009, 5f6e841f-c502-4df5-993b-d355da3cfa76, fb3c51b7-d917-477a-a552-af7d45f00002, d793581e-48ca-4284-aa6c-1ca0f4349f8e, 50e5fce9-f87f-4925-8ec0-b9ce27f8c005, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=31, minorTopVer=0], nodeId=40cbc83b, evt=NODE_LEFT], init=true, ready=false, replied=false, added=true, initFut=GridFutureAdapter [resFlag=0, res=null, startTime=1452670144261, endTime=0, ignoreInterrupts=false, lsnr=null, state=INIT], topSnapshot=null, lastVer=null, partReleaseFut=null, skipPreload=false, clientOnlyExchange=false, initTs=1452670144261, oldest=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, oldestOrder=1, evtLatch=0, remaining=[7515d89d-1808-4974-ae0f-20a6bce00001, 8001ce22-de1b-4ee1-a9a5-9f8033087008, 209bc679-78c9-470b-9868-aa9e389ec002, ec2bd5f5-449f-44e8-9089-1a2e520e236b, 9073c236-503b-4ca9-b7b9-f798cb70f009, 5f6e841f-c502-4df5-993b-d355da3cfa76, fb3c51b7-d917-477a-a552-af7d45f00002, d793581e-48ca-4284-aa6c-1ca0f4349f8e, 50e5fce9-f87f-4925-8ec0-b9ce27f8c005, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], super=GridFutureAdapter [resFlag=0, res=null, startTime=1452670144261, endTime=0, ignoreInterrupts=false, lsnr=null, state=INIT]], p=0, affNodesCnt=2, ownersCnt=0, affNodes=[TcpDiscoveryNode [id=50e5fce9-f87f-4925-8ec0-b9ce27f8c005, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47505], discPort=47505, order=24, intOrder=15, lastExchangeTime=1452670143973, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=5f6e841f-c502-4df5-993b-d355da3cfa76, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=22, intOrder=13, lastExchangeTime=1452670143973, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]], owners=[], locNode=TcpDiscoveryNode [id=50f14d64-8b2f-42f8-9be5-75f523d38005, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47511], discPort=47511, order=29, intOrder=20, lastExchangeTime=1452670144261, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]]
[23:29:04,473][WARN ][topology-change-thread-2][root] Waiting for topology map update [grid=5f175a85-dc25-4da9-9deb-22abf4fe03b5, cache=datastructures_0, cacheId=1278244283, topVer=AffinityTopologyVersion [topVer=31, minorTopVer=0], topFut=GridDhtPartitionsExchangeFuture [dummy=false, forcePreload=false, reassign=false, discoEvt=DiscoveryEvent [evtNode=TcpDiscoveryNode [id=40cbc83b-17f4-4418-a260-281a2844d004, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47508], discPort=47508, order=27, intOrder=18, lastExchangeTime=1452670143973, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], topVer=31, nodeId8=50f14d64, msg=Node left: TcpDiscoveryNode [id=40cbc83b-17f4-4418-a260-281a2844d004, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47508], discPort=47508, order=27, intOrder=18, lastExchangeTime=1452670143973, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], type=NODE_LEFT, tstamp=1452670144261], rcvdIds=GridConcurrentHashSet [elements=[]], rmtIds=[7515d89d-1808-4974-ae0f-20a6bce00001, 8001ce22-de1b-4ee1-a9a5-9f8033087008, 209bc679-78c9-470b-9868-aa9e389ec002, ec2bd5f5-449f-44e8-9089-1a2e520e236b, 9073c236-503b-4ca9-b7b9-f798cb70f009, 5f6e841f-c502-4df5-993b-d355da3cfa76, fb3c51b7-d917-477a-a552-af7d45f00002, d793581e-48ca-4284-aa6c-1ca0f4349f8e, 50e5fce9-f87f-4925-8ec0-b9ce27f8c005, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=31, minorTopVer=0], nodeId=40cbc83b, evt=NODE_LEFT], init=true, ready=true, replied=false, added=true, initFut=GridFutureAdapter [resFlag=2, res=true, startTime=1452670144261, endTime=1452670144291, ignoreInterrupts=false, lsnr=null, state=DONE], topSnapshot=null, lastVer=null, partReleaseFut=GridCompoundFuture [rdc=null, flags=1, lsnrCalls=3, done=true, cancelled=false, err=null, futs=[true, true, true]], skipPreload=false, clientOnlyExchange=false, initTs=1452670144261, oldest=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, oldestOrder=1, evtLatch=0, remaining=[7515d89d-1808-4974-ae0f-20a6bce00001, 8001ce22-de1b-4ee1-a9a5-9f8033087008, 209bc679-78c9-470b-9868-aa9e389ec002, ec2bd5f5-449f-44e8-9089-1a2e520e236b, 9073c236-503b-4ca9-b7b9-f798cb70f009, 5f6e841f-c502-4df5-993b-d355da3cfa76, fb3c51b7-d917-477a-a552-af7d45f00002, d793581e-48ca-4284-aa6c-1ca0f4349f8e, 50e5fce9-f87f-4925-8ec0-b9ce27f8c005, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], super=GridFutureAdapter [resFlag=0, res=null, startTime=1452670144261, endTime=0, ignoreInterrupts=false, lsnr=null, state=INIT]], p=0, affNodesCnt=2, ownersCnt=0, affNodes=[TcpDiscoveryNode [id=50e5fce9-f87f-4925-8ec0-b9ce27f8c005, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47505], discPort=47505, order=24, intOrder=15, lastExchangeTime=1452670143973, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=5f6e841f-c502-4df5-993b-d355da3cfa76, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=22, intOrder=13, lastExchangeTime=1452670143973, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]], owners=[], locNode=TcpDiscoveryNode [id=50f14d64-8b2f-42f8-9be5-75f523d38005, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47511], discPort=47511, order=29, intOrder=20, lastExchangeTime=1452670144261, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]]
[23:29:05,474][WARN ][topology-change-thread-2][root] Finished waiting for topology map update [grid=5f175a85-dc25-4da9-9deb-22abf4fe03b5, p=0, duration=1201ms]
[23:29:05,474][WARN ][topology-change-thread-2][root] Waiting for topology map update [grid=5f175a85-dc25-4da9-9deb-22abf4fe03b5, cache=datastructures_0, cacheId=1278244283, topVer=AffinityTopologyVersion [topVer=31, minorTopVer=0], topFut=GridDhtPartitionsExchangeFuture [dummy=false, forcePreload=false, reassign=false, discoEvt=DiscoveryEvent [evtNode=TcpDiscoveryNode [id=40cbc83b-17f4-4418-a260-281a2844d004, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47508], discPort=47508, order=27, intOrder=18, lastExchangeTime=1452670143973, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], topVer=31, nodeId8=50f14d64, msg=Node left: TcpDiscoveryNode [id=40cbc83b-17f4-4418-a260-281a2844d004, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47508], discPort=47508, order=27, intOrder=18, lastExchangeTime=1452670143973, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], type=NODE_LEFT, tstamp=1452670144261], rcvdIds=GridConcurrentHashSet [elements=[]], rmtIds=[7515d89d-1808-4974-ae0f-20a6bce00001, 8001ce22-de1b-4ee1-a9a5-9f8033087008, 209bc679-78c9-470b-9868-aa9e389ec002, ec2bd5f5-449f-44e8-9089-1a2e520e236b, 9073c236-503b-4ca9-b7b9-f798cb70f009, 5f6e841f-c502-4df5-993b-d355da3cfa76, fb3c51b7-d917-477a-a552-af7d45f00002, d793581e-48ca-4284-aa6c-1ca0f4349f8e, 50e5fce9-f87f-4925-8ec0-b9ce27f8c005, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=31, minorTopVer=0], nodeId=40cbc83b, evt=NODE_LEFT], init=true, ready=true, replied=false, added=true, initFut=GridFutureAdapter [resFlag=2, res=true, startTime=1452670144261, endTime=1452670144291, ignoreInterrupts=false, lsnr=null, state=DONE], topSnapshot=null, lastVer=null, partReleaseFut=GridCompoundFuture [rdc=null, flags=1, lsnrCalls=3, done=true, cancelled=false, err=null, futs=[true, true, true]], skipPreload=false, clientOnlyExchange=false, initTs=1452670144261, oldest=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, oldestOrder=1, evtLatch=0, remaining=[7515d89d-1808-4974-ae0f-20a6bce00001, 8001ce22-de1b-4ee1-a9a5-9f8033087008, 209bc679-78c9-470b-9868-aa9e389ec002, ec2bd5f5-449f-44e8-9089-1a2e520e236b, 9073c236-503b-4ca9-b7b9-f798cb70f009, 5f6e841f-c502-4df5-993b-d355da3cfa76, fb3c51b7-d917-477a-a552-af7d45f00002, d793581e-48ca-4284-aa6c-1ca0f4349f8e, 50e5fce9-f87f-4925-8ec0-b9ce27f8c005, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], super=GridFutureAdapter [resFlag=2, res=AffinityTopologyVersion [topVer=31, minorTopVer=0], startTime=1452670144261, endTime=1452670145282, ignoreInterrupts=false, lsnr=null, state=DONE]], p=1, affNodesCnt=2, ownersCnt=3, affNodes=[TcpDiscoveryNode [id=50f14d64-8b2f-42f8-9be5-75f523d38005, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47511], discPort=47511, order=29, intOrder=20, lastExchangeTime=1452670144261, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=fb3c51b7-d917-477a-a552-af7d45f00002, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47500], discPort=47500, order=2, intOrder=2, lastExchangeTime=1452670143973, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]], owners=[TcpDiscoveryNode [id=50f14d64-8b2f-42f8-9be5-75f523d38005, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47511], discPort=47511, order=29, intOrder=20, lastExchangeTime=1452670144261, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=fb3c51b7-d917-477a-a552-af7d45f00002, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47500], discPort=47500, order=2, intOrder=2, lastExchangeTime=1452670143973, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=ec2bd5f5-449f-44e8-9089-1a2e520e236b, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47507], discPort=47507, order=26, intOrder=17, lastExchangeTime=1452670143973, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]], locNode=TcpDiscoveryNode [id=50f14d64-8b2f-42f8-9be5-75f523d38005, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47511], discPort=47511, order=29, intOrder=20, lastExchangeTime=1452670144261, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]]
[23:29:05,675][WARN ][topology-change-thread-2][root] Finished waiting for topology map update [grid=5f175a85-dc25-4da9-9deb-22abf4fe03b5, p=1, duration=200ms]
[23:29:05,675][WARN ][topology-change-thread-2][root] Waiting for topology map update [grid=5f175a85-dc25-4da9-9deb-22abf4fe03b5, cache=datastructures_0, cacheId=1278244283, topVer=AffinityTopologyVersion [topVer=31, minorTopVer=0], topFut=GridDhtPartitionsExchangeFuture [dummy=false, forcePreload=false, reassign=false, discoEvt=DiscoveryEvent [evtNode=TcpDiscoveryNode [id=40cbc83b-17f4-4418-a260-281a2844d004, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47508], discPort=47508, order=27, intOrder=18, lastExchangeTime=1452670143973, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], topVer=31, nodeId8=50f14d64, msg=Node left: TcpDiscoveryNode [id=40cbc83b-17f4-4418-a260-281a2844d004, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47508], discPort=47508, order=27, intOrder=18, lastExchangeTime=1452670143973, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], type=NODE_LEFT, tstamp=1452670144261], rcvdIds=GridConcurrentHashSet [elements=[]], rmtIds=[7515d89d-1808-4974-ae0f-20a6bce00001, 8001ce22-de1b-4ee1-a9a5-9f8033087008, 209bc679-78c9-470b-9868-aa9e389ec002, ec2bd5f5-449f-44e8-9089-1a2e520e236b, 9073c236-503b-4ca9-b7b9-f798cb70f009, 5f6e841f-c502-4df5-993b-d355da3cfa76, fb3c51b7-d917-477a-a552-af7d45f00002, d793581e-48ca-4284-aa6c-1ca0f4349f8e, 50e5fce9-f87f-4925-8ec0-b9ce27f8c005, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=31, minorTopVer=0], nodeId=40cbc83b, evt=NODE_LEFT], init=true, ready=true, replied=false, added=true, initFut=GridFutureAdapter [resFlag=2, res=true, startTime=1452670144261, endTime=1452670144291, ignoreInterrupts=false, lsnr=null, state=DONE], topSnapshot=null, lastVer=null, partReleaseFut=GridCompoundFuture [rdc=null, flags=1, lsnrCalls=3, done=true, cancelled=false, err=null, futs=[true, true, true]], skipPreload=false, clientOnlyExchange=false, initTs=1452670144261, oldest=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, oldestOrder=1, evtLatch=0, remaining=[7515d89d-1808-4974-ae0f-20a6bce00001, 8001ce22-de1b-4ee1-a9a5-9f8033087008, 209bc679-78c9-470b-9868-aa9e389ec002, ec2bd5f5-449f-44e8-9089-1a2e520e236b, 9073c236-503b-4ca9-b7b9-f798cb70f009, 5f6e841f-c502-4df5-993b-d355da3cfa76, fb3c51b7-d917-477a-a552-af7d45f00002, d793581e-48ca-4284-aa6c-1ca0f4349f8e, 50e5fce9-f87f-4925-8ec0-b9ce27f8c005, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], super=GridFutureAdapter [resFlag=2, res=AffinityTopologyVersion [topVer=31, minorTopVer=0], startTime=1452670144261, endTime=1452670145282, ignoreInterrupts=false, lsnr=null, state=DONE]], p=304, affNodesCnt=2, ownersCnt=3, affNodes=[TcpDiscoveryNode [id=50f14d64-8b2f-42f8-9be5-75f523d38005, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47511], discPort=47511, order=29, intOrder=20, lastExchangeTime=1452670145575, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=209bc679-78c9-470b-9868-aa9e389ec002, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47510], discPort=47510, order=30, intOrder=21, lastExchangeTime=1452670143973, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]], owners=[TcpDiscoveryNode [id=50f14d64-8b2f-42f8-9be5-75f523d38005, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47511], discPort=47511, order=29, intOrder=20, lastExchangeTime=1452670145575, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=209bc679-78c9-470b-9868-aa9e389ec002, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47510], discPort=47510, order=30, intOrder=21, lastExchangeTime=1452670143973, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47501], discPort=47501, order=1, intOrder=1, lastExchangeTime=1452670143973, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]], locNode=TcpDiscoveryNode [id=50f14d64-8b2f-42f8-9be5-75f523d38005, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47511], discPort=47511, order=29, intOrder=20, lastExchangeTime=1452670145575, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]]
[23:29:07,277][WARN ][topology-change-thread-2][root] Finished waiting for topology map update [grid=5f175a85-dc25-4da9-9deb-22abf4fe03b5, p=304, duration=1601ms]
[23:29:07,318][WARN ][topology-change-thread-2][root] Waiting for topology map update [grid=5f175a85-dc25-4da9-9deb-22abf4fe03b5, cache=datastructures_0, cacheId=1278244283, topVer=AffinityTopologyVersion [topVer=32, minorTopVer=0], topFut=GridDhtPartitionsExchangeFuture [dummy=false, forcePreload=false, reassign=false, discoEvt=DiscoveryEvent [evtNode=TcpDiscoveryNode [id=9073c236-503b-4ca9-b7b9-f798cb70f009, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47504], discPort=47504, order=23, intOrder=14, lastExchangeTime=1452670143973, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], topVer=32, nodeId8=50f14d64, msg=Node left: TcpDiscoveryNode [id=9073c236-503b-4ca9-b7b9-f798cb70f009, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47504], discPort=47504, order=23, intOrder=14, lastExchangeTime=1452670143973, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], type=NODE_LEFT, tstamp=1452670147312], rcvdIds=GridConcurrentHashSet [elements=[]], rmtIds=[7515d89d-1808-4974-ae0f-20a6bce00001, 8001ce22-de1b-4ee1-a9a5-9f8033087008, 209bc679-78c9-470b-9868-aa9e389ec002, ec2bd5f5-449f-44e8-9089-1a2e520e236b, 5f6e841f-c502-4df5-993b-d355da3cfa76, fb3c51b7-d917-477a-a552-af7d45f00002, d793581e-48ca-4284-aa6c-1ca0f4349f8e, 50e5fce9-f87f-4925-8ec0-b9ce27f8c005, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=32, minorTopVer=0], nodeId=9073c236, evt=NODE_LEFT], init=true, ready=false, replied=false, added=true, initFut=GridFutureAdapter [resFlag=0, res=null, startTime=1452670147312, endTime=0, ignoreInterrupts=false, lsnr=null, state=INIT], topSnapshot=null, lastVer=null, partReleaseFut=null, skipPreload=false, clientOnlyExchange=false, initTs=1452670147312, oldest=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, oldestOrder=1, evtLatch=0, remaining=[7515d89d-1808-4974-ae0f-20a6bce00001, 8001ce22-de1b-4ee1-a9a5-9f8033087008, 209bc679-78c9-470b-9868-aa9e389ec002, ec2bd5f5-449f-44e8-9089-1a2e520e236b, 5f6e841f-c502-4df5-993b-d355da3cfa76, fb3c51b7-d917-477a-a552-af7d45f00002, d793581e-48ca-4284-aa6c-1ca0f4349f8e, 50e5fce9-f87f-4925-8ec0-b9ce27f8c005, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], super=GridFutureAdapter [resFlag=0, res=null, startTime=1452670147312, endTime=0, ignoreInterrupts=false, lsnr=null, state=INIT]], p=0, affNodesCnt=2, ownersCnt=0, affNodes=[TcpDiscoveryNode [id=50e5fce9-f87f-4925-8ec0-b9ce27f8c005, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47505], discPort=47505, order=24, intOrder=15, lastExchangeTime=1452670143973, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=5f6e841f-c502-4df5-993b-d355da3cfa76, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=22, intOrder=13, lastExchangeTime=1452670143973, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]], owners=[], locNode=TcpDiscoveryNode [id=50f14d64-8b2f-42f8-9be5-75f523d38005, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47511], discPort=47511, order=29, intOrder=20, lastExchangeTime=1452670147312, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]]
[23:29:07,519][WARN ][topology-change-thread-2][root] Waiting for topology map update [grid=5f175a85-dc25-4da9-9deb-22abf4fe03b5, cache=datastructures_0, cacheId=1278244283, topVer=AffinityTopologyVersion [topVer=32, minorTopVer=0], topFut=GridDhtPartitionsExchangeFuture [dummy=false, forcePreload=false, reassign=false, discoEvt=DiscoveryEvent [evtNode=TcpDiscoveryNode [id=9073c236-503b-4ca9-b7b9-f798cb70f009, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47504], discPort=47504, order=23, intOrder=14, lastExchangeTime=1452670143973, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], topVer=32, nodeId8=50f14d64, msg=Node left: TcpDiscoveryNode [id=9073c236-503b-4ca9-b7b9-f798cb70f009, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47504], discPort=47504, order=23, intOrder=14, lastExchangeTime=1452670143973, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], type=NODE_LEFT, tstamp=1452670147312], rcvdIds=GridConcurrentHashSet [elements=[]], rmtIds=[7515d89d-1808-4974-ae0f-20a6bce00001, 8001ce22-de1b-4ee1-a9a5-9f8033087008, 209bc679-78c9-470b-9868-aa9e389ec002, ec2bd5f5-449f-44e8-9089-1a2e520e236b, 5f6e841f-c502-4df5-993b-d355da3cfa76, fb3c51b7-d917-477a-a552-af7d45f00002, d793581e-48ca-4284-aa6c-1ca0f4349f8e, 50e5fce9-f87f-4925-8ec0-b9ce27f8c005, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=32, minorTopVer=0], nodeId=9073c236, evt=NODE_LEFT], init=true, ready=true, replied=false, added=true, initFut=GridFutureAdapter [resFlag=2, res=true, startTime=1452670147312, endTime=1452670147337, ignoreInterrupts=false, lsnr=null, state=DONE], topSnapshot=null, lastVer=null, partReleaseFut=GridCompoundFuture [rdc=null, flags=1, lsnrCalls=3, done=true, cancelled=false, err=null, futs=[true, true, true]], skipPreload=false, clientOnlyExchange=false, initTs=1452670147312, oldest=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, oldestOrder=1, evtLatch=0, remaining=[7515d89d-1808-4974-ae0f-20a6bce00001, 8001ce22-de1b-4ee1-a9a5-9f8033087008, 209bc679-78c9-470b-9868-aa9e389ec002, ec2bd5f5-449f-44e8-9089-1a2e520e236b, 5f6e841f-c502-4df5-993b-d355da3cfa76, fb3c51b7-d917-477a-a552-af7d45f00002, d793581e-48ca-4284-aa6c-1ca0f4349f8e, 50e5fce9-f87f-4925-8ec0-b9ce27f8c005, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], super=GridFutureAdapter [resFlag=0, res=null, startTime=1452670147312, endTime=0, ignoreInterrupts=false, lsnr=null, state=INIT]], p=0, affNodesCnt=2, ownersCnt=0, affNodes=[TcpDiscoveryNode [id=50e5fce9-f87f-4925-8ec0-b9ce27f8c005, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47505], discPort=47505, order=24, intOrder=15, lastExchangeTime=1452670143973, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=5f6e841f-c502-4df5-993b-d355da3cfa76, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=22, intOrder=13, lastExchangeTime=1452670143973, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]], owners=[], locNode=TcpDiscoveryNode [id=50f14d64-8b2f-42f8-9be5-75f523d38005, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47511], discPort=47511, order=29, intOrder=20, lastExchangeTime=1452670147374, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]]
[23:29:07,719][WARN ][topology-change-thread-2][root] Waiting for topology map update [grid=5f175a85-dc25-4da9-9deb-22abf4fe03b5, cache=datastructures_0, cacheId=1278244283, topVer=AffinityTopologyVersion [topVer=32, minorTopVer=0], topFut=GridDhtPartitionsExchangeFuture [dummy=false, forcePreload=false, reassign=false, discoEvt=DiscoveryEvent [evtNode=TcpDiscoveryNode [id=9073c236-503b-4ca9-b7b9-f798cb70f009, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47504], discPort=47504, order=23, intOrder=14, lastExchangeTime=1452670143973, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], topVer=32, nodeId8=50f14d64, msg=Node left: TcpDiscoveryNode [id=9073c236-503b-4ca9-b7b9-f798cb70f009, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47504], discPort=47504, order=23, intOrder=14, lastExchangeTime=1452670143973, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], type=NODE_LEFT, tstamp=1452670147312], rcvdIds=GridConcurrentHashSet [elements=[]], rmtIds=[7515d89d-1808-4974-ae0f-20a6bce00001, 8001ce22-de1b-4ee1-a9a5-9f8033087008, 209bc679-78c9-470b-9868-aa9e389ec002, ec2bd5f5-449f-44e8-9089-1a2e520e236b, 5f6e841f-c502-4df5-993b-d355da3cfa76, fb3c51b7-d917-477a-a552-af7d45f00002, d793581e-48ca-4284-aa6c-1ca0f4349f8e, 50e5fce9-f87f-4925-8ec0-b9ce27f8c005, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=32, minorTopVer=0], nodeId=9073c236, evt=NODE_LEFT], init=true, ready=true, replied=false, added=true, initFut=GridFutureAdapter [resFlag=2, res=true, startTime=1452670147312, endTime=1452670147337, ignoreInterrupts=false, lsnr=null, state=DONE], topSnapshot=null, lastVer=null, partReleaseFut=GridCompoundFuture [rdc=null, flags=1, lsnrCalls=3, done=true, cancelled=false, err=null, futs=[true, true, true]], skipPreload=false, clientOnlyExchange=false, initTs=1452670147312, oldest=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, oldestOrder=1, evtLatch=0, remaining=[7515d89d-1808-4974-ae0f-20a6bce00001, 8001ce22-de1b-4ee1-a9a5-9f8033087008, 209bc679-78c9-470b-9868-aa9e389ec002, ec2bd5f5-449f-44e8-9089-1a2e520e236b, 5f6e841f-c502-4df5-993b-d355da3cfa76, fb3c51b7-d917-477a-a552-af7d45f00002, d793581e-48ca-4284-aa6c-1ca0f4349f8e, 50e5fce9-f87f-4925-8ec0-b9ce27f8c005, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], super=GridFutureAdapter [resFlag=0, res=null, startTime=1452670147312, endTime=0, ignoreInterrupts=false, lsnr=null, state=INIT]], p=0, affNodesCnt=2, ownersCnt=0, affNodes=[TcpDiscoveryNode [id=50e5fce9-f87f-4925-8ec0-b9ce27f8c005, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47505], discPort=47505, order=24, intOrder=15, lastExchangeTime=1452670143973, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=5f6e841f-c502-4df5-993b-d355da3cfa76, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=22, intOrder=13, lastExchangeTime=1452670143973, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]], owners=[], locNode=TcpDiscoveryNode [id=50f14d64-8b2f-42f8-9be5-75f523d38005, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47511], discPort=47511, order=29, intOrder=20, lastExchangeTime=1452670147587, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]]
[23:29:08,519][WARN ][topology-change-thread-2][root] Finished waiting for topology map update [grid=5f175a85-dc25-4da9-9deb-22abf4fe03b5, p=0, duration=1201ms]
[23:29:08,580][WARN ][topology-change-thread-2][root] Waiting for topology map update [grid=5f175a85-dc25-4da9-9deb-22abf4fe03b5, cache=datastructures_0, cacheId=1278244283, topVer=AffinityTopologyVersion [topVer=33, minorTopVer=0], topFut=GridDhtPartitionsExchangeFuture [dummy=false, forcePreload=false, reassign=false, discoEvt=DiscoveryEvent [evtNode=TcpDiscoveryNode [id=d793581e-48ca-4284-aa6c-1ca0f4349f8e, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47506], discPort=47506, order=25, intOrder=16, lastExchangeTime=1452670143973, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], topVer=33, nodeId8=50f14d64, msg=Node left: TcpDiscoveryNode [id=d793581e-48ca-4284-aa6c-1ca0f4349f8e, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47506], discPort=47506, order=25, intOrder=16, lastExchangeTime=1452670143973, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], type=NODE_LEFT, tstamp=1452670148574], rcvdIds=GridConcurrentHashSet [elements=[]], rmtIds=[7515d89d-1808-4974-ae0f-20a6bce00001, 8001ce22-de1b-4ee1-a9a5-9f8033087008, 209bc679-78c9-470b-9868-aa9e389ec002, ec2bd5f5-449f-44e8-9089-1a2e520e236b, 5f6e841f-c502-4df5-993b-d355da3cfa76, fb3c51b7-d917-477a-a552-af7d45f00002, 50e5fce9-f87f-4925-8ec0-b9ce27f8c005, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=33, minorTopVer=0], nodeId=d793581e, evt=NODE_LEFT], init=true, ready=false, replied=false, added=true, initFut=GridFutureAdapter [resFlag=0, res=null, startTime=1452670148574, endTime=0, ignoreInterrupts=false, lsnr=null, state=INIT], topSnapshot=null, lastVer=null, partReleaseFut=null, skipPreload=false, clientOnlyExchange=false, initTs=1452670148574, oldest=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, oldestOrder=1, evtLatch=0, remaining=[7515d89d-1808-4974-ae0f-20a6bce00001, 8001ce22-de1b-4ee1-a9a5-9f8033087008, 209bc679-78c9-470b-9868-aa9e389ec002, ec2bd5f5-449f-44e8-9089-1a2e520e236b, 5f6e841f-c502-4df5-993b-d355da3cfa76, fb3c51b7-d917-477a-a552-af7d45f00002, 50e5fce9-f87f-4925-8ec0-b9ce27f8c005, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], super=GridFutureAdapter [resFlag=0, res=null, startTime=1452670148574, endTime=0, ignoreInterrupts=false, lsnr=null, state=INIT]], p=0, affNodesCnt=2, ownersCnt=0, affNodes=[TcpDiscoveryNode [id=50e5fce9-f87f-4925-8ec0-b9ce27f8c005, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47505], discPort=47505, order=24, intOrder=15, lastExchangeTime=1452670143973, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=5f6e841f-c502-4df5-993b-d355da3cfa76, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=22, intOrder=13, lastExchangeTime=1452670143973, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]], owners=[], locNode=TcpDiscoveryNode [id=50f14d64-8b2f-42f8-9be5-75f523d38005, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47511], discPort=47511, order=29, intOrder=20, lastExchangeTime=1452670148574, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]]
[23:29:08,780][WARN ][topology-change-thread-2][root] Waiting for topology map update [grid=5f175a85-dc25-4da9-9deb-22abf4fe03b5, cache=datastructures_0, cacheId=1278244283, topVer=AffinityTopologyVersion [topVer=33, minorTopVer=0], topFut=GridDhtPartitionsExchangeFuture [dummy=false, forcePreload=false, reassign=false, discoEvt=DiscoveryEvent [evtNode=TcpDiscoveryNode [id=d793581e-48ca-4284-aa6c-1ca0f4349f8e, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47506], discPort=47506, order=25, intOrder=16, lastExchangeTime=1452670143973, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], topVer=33, nodeId8=50f14d64, msg=Node left: TcpDiscoveryNode [id=d793581e-48ca-4284-aa6c-1ca0f4349f8e, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47506], discPort=47506, order=25, intOrder=16, lastExchangeTime=1452670143973, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], type=NODE_LEFT, tstamp=1452670148574], rcvdIds=GridConcurrentHashSet [elements=[]], rmtIds=[7515d89d-1808-4974-ae0f-20a6bce00001, 8001ce22-de1b-4ee1-a9a5-9f8033087008, 209bc679-78c9-470b-9868-aa9e389ec002, ec2bd5f5-449f-44e8-9089-1a2e520e236b, 5f6e841f-c502-4df5-993b-d355da3cfa76, fb3c51b7-d917-477a-a552-af7d45f00002, 50e5fce9-f87f-4925-8ec0-b9ce27f8c005, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=33, minorTopVer=0], nodeId=d793581e, evt=NODE_LEFT], init=true, ready=true, replied=false, added=true, initFut=GridFutureAdapter [resFlag=2, res=true, startTime=1452670148574, endTime=1452670148594, ignoreInterrupts=false, lsnr=null, state=DONE], topSnapshot=null, lastVer=null, partReleaseFut=GridCompoundFuture [rdc=null, flags=1, lsnrCalls=3, done=true, cancelled=false, err=null, futs=[true, true, true]], skipPreload=false, clientOnlyExchange=false, initTs=1452670148574, oldest=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, oldestOrder=1, evtLatch=0, remaining=[7515d89d-1808-4974-ae0f-20a6bce00001, 8001ce22-de1b-4ee1-a9a5-9f8033087008, 209bc679-78c9-470b-9868-aa9e389ec002, ec2bd5f5-449f-44e8-9089-1a2e520e236b, 5f6e841f-c502-4df5-993b-d355da3cfa76, fb3c51b7-d917-477a-a552-af7d45f00002, 50e5fce9-f87f-4925-8ec0-b9ce27f8c005, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], super=GridFutureAdapter [resFlag=0, res=null, startTime=1452670148574, endTime=0, ignoreInterrupts=false, lsnr=null, state=INIT]], p=0, affNodesCnt=2, ownersCnt=0, affNodes=[TcpDiscoveryNode [id=50e5fce9-f87f-4925-8ec0-b9ce27f8c005, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47505], discPort=47505, order=24, intOrder=15, lastExchangeTime=1452670143973, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=5f6e841f-c502-4df5-993b-d355da3cfa76, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=22, intOrder=13, lastExchangeTime=1452670143973, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]], owners=[], locNode=TcpDiscoveryNode [id=50f14d64-8b2f-42f8-9be5-75f523d38005, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47511], discPort=47511, order=29, intOrder=20, lastExchangeTime=1452670148574, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]]
[23:29:09,781][WARN ][topology-change-thread-2][root] Finished waiting for topology map update [grid=5f175a85-dc25-4da9-9deb-22abf4fe03b5, p=0, duration=1201ms]
[23:29:09,805][ERROR][topology-change-thread-2][IgniteTxHandler] Future execution resulted in error: GridDhtTxFinishFuture [futId=2f3a19b3251-5e4ba563-63bc-47ad-a06c-8331adf404eb, commit=true, trackable=true, xidVer=GridCacheVersion [topVer=64150144, nodeOrderDrId=30, globalTime=1452670149803, order=1452670176958], innerFuts=[[node=ec2bd5f5-449f-44e8-9089-1a2e520e236b, loc=false, done=false]], super=GridCompoundIdentityFuture [super=GridCompoundFuture [rdc=o.a.i.i.util.lang.GridFunc$27@6a7105b, flags=1, lsnrCalls=0, done=true, cancelled=false, err=class o.a.i.i.NodeStoppingException: Operation has been cancelled (node is stopping)., futs=[false]]]]
class org.apache.ignite.internal.NodeStoppingException: Operation has been cancelled (node is stopping).
    at org.apache.ignite.internal.processors.cache.GridCacheMvccManager.stopError(GridCacheMvccManager.java:373)
    at org.apache.ignite.internal.processors.cache.GridCacheMvccManager.onStop(GridCacheMvccManager.java:336)
    at org.apache.ignite.internal.processors.cache.GridCacheProcessor.cancelFutures(GridCacheProcessor.java:3182)
    at org.apache.ignite.internal.processors.cache.GridCacheProcessor.onKernalStop(GridCacheProcessor.java:910)
    at org.apache.ignite.internal.IgniteKernal.stop0(IgniteKernal.java:1848)
    at org.apache.ignite.internal.IgniteKernal.stop(IgniteKernal.java:1794)
    at org.apache.ignite.internal.IgnitionEx$IgniteNamedInstance.stop0(IgnitionEx.java:2207)
    at org.apache.ignite.internal.IgnitionEx$IgniteNamedInstance.stop(IgnitionEx.java:2170)
    at org.apache.ignite.internal.IgnitionEx.stop(IgnitionEx.java:322)
    at org.apache.ignite.Ignition.stop(Ignition.java:224)
    at org.apache.ignite.testframework.junits.GridAbstractTest.stopGrid(GridAbstractTest.java:812)
    at org.apache.ignite.internal.processors.cache.datastructures.GridCacheAbstractDataStructuresFailoverSelfTest.access$1400(GridCacheAbstractDataStructuresFailoverSelfTest.java:64)
    at org.apache.ignite.internal.processors.cache.datastructures.GridCacheAbstractDataStructuresFailoverSelfTest$PartitionedMultipleTopologyChangeWorker$1.run(GridCacheAbstractDataStructuresFailoverSelfTest.java:1045)
    at java.util.concurrent.CyclicBarrier.dowait(CyclicBarrier.java:213)
    at java.util.concurrent.CyclicBarrier.await(CyclicBarrier.java:355)
    at org.apache.ignite.internal.processors.cache.datastructures.GridCacheAbstractDataStructuresFailoverSelfTest$PartitionedMultipleTopologyChangeWorker$2.apply(GridCacheAbstractDataStructuresFailoverSelfTest.java:1093)
    at org.apache.ignite.internal.util.lang.GridAbsClosure.run(GridAbsClosure.java:50)
    at org.apache.ignite.testframework.GridTestUtils$7.call(GridTestUtils.java:1026)
    at org.apache.ignite.testframework.GridTestThread.run(GridTestThread.java:86)
[23:29:09,815][WARN ][topology-change-thread-2][root] Waiting for topology map update [grid=5f175a85-dc25-4da9-9deb-22abf4fe03b5, cache=datastructures_0, cacheId=1278244283, topVer=AffinityTopologyVersion [topVer=34, minorTopVer=0], topFut=GridDhtPartitionsExchangeFuture [dummy=false, forcePreload=false, reassign=false, discoEvt=DiscoveryEvent [evtNode=TcpDiscoveryNode [id=209bc679-78c9-470b-9868-aa9e389ec002, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47510], discPort=47510, order=30, intOrder=21, lastExchangeTime=1452670143973, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], topVer=34, nodeId8=50f14d64, msg=Node left: TcpDiscoveryNode [id=209bc679-78c9-470b-9868-aa9e389ec002, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47510], discPort=47510, order=30, intOrder=21, lastExchangeTime=1452670143973, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], type=NODE_LEFT, tstamp=1452670149811], rcvdIds=GridConcurrentHashSet [elements=[]], rmtIds=[7515d89d-1808-4974-ae0f-20a6bce00001, 8001ce22-de1b-4ee1-a9a5-9f8033087008, ec2bd5f5-449f-44e8-9089-1a2e520e236b, 5f6e841f-c502-4df5-993b-d355da3cfa76, fb3c51b7-d917-477a-a552-af7d45f00002, 50e5fce9-f87f-4925-8ec0-b9ce27f8c005, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=34, minorTopVer=0], nodeId=209bc679, evt=NODE_LEFT], init=true, ready=false, replied=false, added=true, initFut=GridFutureAdapter [resFlag=0, res=null, startTime=1452670149811, endTime=0, ignoreInterrupts=false, lsnr=null, state=INIT], topSnapshot=null, lastVer=null, partReleaseFut=null, skipPreload=false, clientOnlyExchange=false, initTs=1452670149811, oldest=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, oldestOrder=1, evtLatch=0, remaining=[7515d89d-1808-4974-ae0f-20a6bce00001, 8001ce22-de1b-4ee1-a9a5-9f8033087008, ec2bd5f5-449f-44e8-9089-1a2e520e236b, 5f6e841f-c502-4df5-993b-d355da3cfa76, fb3c51b7-d917-477a-a552-af7d45f00002, 50e5fce9-f87f-4925-8ec0-b9ce27f8c005, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], super=GridFutureAdapter [resFlag=0, res=null, startTime=1452670149811, endTime=0, ignoreInterrupts=false, lsnr=null, state=INIT]], p=0, affNodesCnt=2, ownersCnt=0, affNodes=[TcpDiscoveryNode [id=50e5fce9-f87f-4925-8ec0-b9ce27f8c005, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47505], discPort=47505, order=24, intOrder=15, lastExchangeTime=1452670143973, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=5f6e841f-c502-4df5-993b-d355da3cfa76, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=22, intOrder=13, lastExchangeTime=1452670143973, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]], owners=[], locNode=TcpDiscoveryNode [id=50f14d64-8b2f-42f8-9be5-75f523d38005, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47511], discPort=47511, order=29, intOrder=20, lastExchangeTime=1452670149811, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]]
[23:29:10,015][WARN ][topology-change-thread-2][root] Finished waiting for topology map update [grid=5f175a85-dc25-4da9-9deb-22abf4fe03b5, p=0, duration=200ms]
[23:29:10,050][WARN ][topology-change-thread-2][root] Waiting for topology map update [grid=5f175a85-dc25-4da9-9deb-22abf4fe03b5, cache=datastructures_0, cacheId=1278244283, topVer=AffinityTopologyVersion [topVer=35, minorTopVer=0], topFut=GridDhtPartitionsExchangeFuture [dummy=false, forcePreload=false, reassign=false, discoEvt=DiscoveryEvent [evtNode=TcpDiscoveryNode [id=8001ce22-de1b-4ee1-a9a5-9f8033087008, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47509], discPort=47509, order=28, intOrder=19, lastExchangeTime=1452670143973, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], topVer=35, nodeId8=50f14d64, msg=Node left: TcpDiscoveryNode [id=8001ce22-de1b-4ee1-a9a5-9f8033087008, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47509], discPort=47509, order=28, intOrder=19, lastExchangeTime=1452670143973, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], type=NODE_LEFT, tstamp=1452670150044], rcvdIds=GridConcurrentHashSet [elements=[]], rmtIds=[7515d89d-1808-4974-ae0f-20a6bce00001, ec2bd5f5-449f-44e8-9089-1a2e520e236b, 5f6e841f-c502-4df5-993b-d355da3cfa76, fb3c51b7-d917-477a-a552-af7d45f00002, 50e5fce9-f87f-4925-8ec0-b9ce27f8c005, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=35, minorTopVer=0], nodeId=8001ce22, evt=NODE_LEFT], init=true, ready=false, replied=false, added=true, initFut=GridFutureAdapter [resFlag=0, res=null, startTime=1452670150044, endTime=0, ignoreInterrupts=false, lsnr=null, state=INIT], topSnapshot=null, lastVer=null, partReleaseFut=null, skipPreload=false, clientOnlyExchange=false, initTs=1452670150044, oldest=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, oldestOrder=1, evtLatch=0, remaining=[7515d89d-1808-4974-ae0f-20a6bce00001, ec2bd5f5-449f-44e8-9089-1a2e520e236b, 5f6e841f-c502-4df5-993b-d355da3cfa76, fb3c51b7-d917-477a-a552-af7d45f00002, 50e5fce9-f87f-4925-8ec0-b9ce27f8c005, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], super=GridFutureAdapter [resFlag=0, res=null, startTime=1452670150044, endTime=0, ignoreInterrupts=false, lsnr=null, state=INIT]], p=0, affNodesCnt=2, ownersCnt=0, affNodes=[TcpDiscoveryNode [id=50e5fce9-f87f-4925-8ec0-b9ce27f8c005, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47505], discPort=47505, order=24, intOrder=15, lastExchangeTime=1452670143973, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=5f6e841f-c502-4df5-993b-d355da3cfa76, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=22, intOrder=13, lastExchangeTime=1452670143973, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]], owners=[], locNode=TcpDiscoveryNode [id=50f14d64-8b2f-42f8-9be5-75f523d38005, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47511], discPort=47511, order=29, intOrder=20, lastExchangeTime=1452670150044, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]]
[23:29:10,250][WARN ][topology-change-thread-2][root] Finished waiting for topology map update [grid=5f175a85-dc25-4da9-9deb-22abf4fe03b5, p=0, duration=200ms]
[23:29:10,280][WARN ][topology-change-thread-2][root] Waiting for topology map update [grid=5f175a85-dc25-4da9-9deb-22abf4fe03b5, cache=datastructures_0, cacheId=1278244283, topVer=AffinityTopologyVersion [topVer=36, minorTopVer=0], topFut=GridDhtPartitionsExchangeFuture [dummy=false, forcePreload=false, reassign=false, discoEvt=DiscoveryEvent [evtNode=TcpDiscoveryNode [id=ec2bd5f5-449f-44e8-9089-1a2e520e236b, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47507], discPort=47507, order=26, intOrder=17, lastExchangeTime=1452670143973, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], topVer=36, nodeId8=50f14d64, msg=Node left: TcpDiscoveryNode [id=ec2bd5f5-449f-44e8-9089-1a2e520e236b, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47507], discPort=47507, order=26, intOrder=17, lastExchangeTime=1452670143973, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], type=NODE_LEFT, tstamp=1452670150268], rcvdIds=GridConcurrentHashSet [elements=[]], rmtIds=[7515d89d-1808-4974-ae0f-20a6bce00001, 5f6e841f-c502-4df5-993b-d355da3cfa76, fb3c51b7-d917-477a-a552-af7d45f00002, 50e5fce9-f87f-4925-8ec0-b9ce27f8c005, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=36, minorTopVer=0], nodeId=ec2bd5f5, evt=NODE_LEFT], init=true, ready=false, replied=false, added=true, initFut=GridFutureAdapter [resFlag=0, res=null, startTime=1452670150268, endTime=0, ignoreInterrupts=false, lsnr=null, state=INIT], topSnapshot=null, lastVer=null, partReleaseFut=null, skipPreload=false, clientOnlyExchange=false, initTs=1452670150268, oldest=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, oldestOrder=1, evtLatch=0, remaining=[7515d89d-1808-4974-ae0f-20a6bce00001, 5f6e841f-c502-4df5-993b-d355da3cfa76, fb3c51b7-d917-477a-a552-af7d45f00002, 50e5fce9-f87f-4925-8ec0-b9ce27f8c005, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], super=GridFutureAdapter [resFlag=0, res=null, startTime=1452670150268, endTime=0, ignoreInterrupts=false, lsnr=null, state=INIT]], p=0, affNodesCnt=2, ownersCnt=0, affNodes=[TcpDiscoveryNode [id=50e5fce9-f87f-4925-8ec0-b9ce27f8c005, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47505], discPort=47505, order=24, intOrder=15, lastExchangeTime=1452670143973, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=5f6e841f-c502-4df5-993b-d355da3cfa76, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=22, intOrder=13, lastExchangeTime=1452670143973, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]], owners=[], locNode=TcpDiscoveryNode [id=50f14d64-8b2f-42f8-9be5-75f523d38005, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47511], discPort=47511, order=29, intOrder=20, lastExchangeTime=1452670150279, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]]
[23:29:10,481][WARN ][topology-change-thread-2][root] Finished waiting for topology map update [grid=5f175a85-dc25-4da9-9deb-22abf4fe03b5, p=0, duration=201ms]
[23:29:10,497][ERROR][topology-change-thread-2][IgniteTxHandler] Future execution resulted in error: GridDhtTxFinishFuture [futId=49dc19b3251-5e4ba563-63bc-47ad-a06c-8331adf404eb, commit=true, trackable=true, xidVer=GridCacheVersion [topVer=64150147, nodeOrderDrId=29, globalTime=1452670150495, order=1452670178675], innerFuts=[[node=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, loc=false, done=false], [node=7515d89d-1808-4974-ae0f-20a6bce00001, loc=false, done=false]], super=GridCompoundIdentityFuture [super=GridCompoundFuture [rdc=o.a.i.i.util.lang.GridFunc$27@3f666506, flags=1, lsnrCalls=0, done=true, cancelled=false, err=class o.a.i.i.NodeStoppingException: Operation has been cancelled (node is stopping)., futs=[false, false]]]]
class org.apache.ignite.internal.NodeStoppingException: Operation has been cancelled (node is stopping).
    at org.apache.ignite.internal.processors.cache.GridCacheMvccManager.stopError(GridCacheMvccManager.java:373)
    at org.apache.ignite.internal.processors.cache.GridCacheMvccManager.onStop(GridCacheMvccManager.java:336)
    at org.apache.ignite.internal.processors.cache.GridCacheProcessor.cancelFutures(GridCacheProcessor.java:3182)
    at org.apache.ignite.internal.processors.cache.GridCacheProcessor.onKernalStop(GridCacheProcessor.java:910)
    at org.apache.ignite.internal.IgniteKernal.stop0(IgniteKernal.java:1848)
    at org.apache.ignite.internal.IgniteKernal.stop(IgniteKernal.java:1794)
    at org.apache.ignite.internal.IgnitionEx$IgniteNamedInstance.stop0(IgnitionEx.java:2207)
    at org.apache.ignite.internal.IgnitionEx$IgniteNamedInstance.stop(IgnitionEx.java:2170)
    at org.apache.ignite.internal.IgnitionEx.stop(IgnitionEx.java:322)
    at org.apache.ignite.Ignition.stop(Ignition.java:224)
    at org.apache.ignite.testframework.junits.GridAbstractTest.stopGrid(GridAbstractTest.java:812)
    at org.apache.ignite.internal.processors.cache.datastructures.GridCacheAbstractDataStructuresFailoverSelfTest.access$1400(GridCacheAbstractDataStructuresFailoverSelfTest.java:64)
    at org.apache.ignite.internal.processors.cache.datastructures.GridCacheAbstractDataStructuresFailoverSelfTest$PartitionedMultipleTopologyChangeWorker$1.run(GridCacheAbstractDataStructuresFailoverSelfTest.java:1045)
    at java.util.concurrent.CyclicBarrier.dowait(CyclicBarrier.java:213)
    at java.util.concurrent.CyclicBarrier.await(CyclicBarrier.java:355)
    at org.apache.ignite.internal.processors.cache.datastructures.GridCacheAbstractDataStructuresFailoverSelfTest$PartitionedMultipleTopologyChangeWorker$2.apply(GridCacheAbstractDataStructuresFailoverSelfTest.java:1093)
    at org.apache.ignite.internal.util.lang.GridAbsClosure.run(GridAbsClosure.java:50)
    at org.apache.ignite.testframework.GridTestUtils$7.call(GridTestUtils.java:1026)
    at org.apache.ignite.testframework.GridTestThread.run(GridTestThread.java:86)
[23:29:10,502][WARN ][topology-change-thread-2][root] Waiting for topology map update [grid=672c6b37-df9a-4407-8964-abb100fd2e1a, cache=datastructures_0, cacheId=1278244283, topVer=AffinityTopologyVersion [topVer=37, minorTopVer=0], topFut=GridDhtPartitionsExchangeFuture [dummy=false, forcePreload=false, reassign=false, discoEvt=DiscoveryEvent [evtNode=TcpDiscoveryNode [id=50f14d64-8b2f-42f8-9be5-75f523d38005, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47511], discPort=47511, order=29, intOrder=20, lastExchangeTime=1452670143933, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], topVer=37, nodeId8=5f6e841f, msg=Node left: TcpDiscoveryNode [id=50f14d64-8b2f-42f8-9be5-75f523d38005, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47511], discPort=47511, order=29, intOrder=20, lastExchangeTime=1452670143933, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], type=NODE_LEFT, tstamp=1452670150490], rcvdIds=GridConcurrentHashSet [elements=[]], rmtIds=[7515d89d-1808-4974-ae0f-20a6bce00001, fb3c51b7-d917-477a-a552-af7d45f00002, 50e5fce9-f87f-4925-8ec0-b9ce27f8c005, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=37, minorTopVer=0], nodeId=50f14d64, evt=NODE_LEFT], init=true, ready=false, replied=false, added=true, initFut=GridFutureAdapter [resFlag=0, res=null, startTime=1452670150490, endTime=0, ignoreInterrupts=false, lsnr=null, state=INIT], topSnapshot=null, lastVer=null, partReleaseFut=null, skipPreload=false, clientOnlyExchange=false, initTs=1452670150490, oldest=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, oldestOrder=1, evtLatch=0, remaining=[7515d89d-1808-4974-ae0f-20a6bce00001, fb3c51b7-d917-477a-a552-af7d45f00002, 50e5fce9-f87f-4925-8ec0-b9ce27f8c005, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], super=GridFutureAdapter [resFlag=0, res=null, startTime=1452670150490, endTime=0, ignoreInterrupts=false, lsnr=null, state=INIT]], p=0, affNodesCnt=2, ownersCnt=0, affNodes=[TcpDiscoveryNode [id=50e5fce9-f87f-4925-8ec0-b9ce27f8c005, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47505], discPort=47505, order=24, intOrder=15, lastExchangeTime=1452670140528, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=5f6e841f-c502-4df5-993b-d355da3cfa76, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=22, intOrder=13, lastExchangeTime=1452670150501, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]], owners=[], locNode=TcpDiscoveryNode [id=5f6e841f-c502-4df5-993b-d355da3cfa76, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=22, intOrder=13, lastExchangeTime=1452670150501, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]]
[23:29:10,702][WARN ][topology-change-thread-2][root] Finished waiting for topology map update [grid=672c6b37-df9a-4407-8964-abb100fd2e1a, p=0, duration=200ms]
[23:29:10,726][WARN ][topology-change-thread-2][root] Waiting for topology map update [grid=793c1fc5-5962-4a22-a3d4-1848cf9484c5, cache=datastructures_0, cacheId=1278244283, topVer=AffinityTopologyVersion [topVer=38, minorTopVer=0], topFut=GridDhtPartitionsExchangeFuture [dummy=false, forcePreload=false, reassign=false, discoEvt=DiscoveryEvent [evtNode=TcpDiscoveryNode [id=5f6e841f-c502-4df5-993b-d355da3cfa76, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=22, intOrder=13, lastExchangeTime=1452670140539, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], topVer=38, nodeId8=50e5fce9, msg=Node left: TcpDiscoveryNode [id=5f6e841f-c502-4df5-993b-d355da3cfa76, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=22, intOrder=13, lastExchangeTime=1452670140539, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], type=NODE_LEFT, tstamp=1452670150721], rcvdIds=GridConcurrentHashSet [elements=[]], rmtIds=[7515d89d-1808-4974-ae0f-20a6bce00001, fb3c51b7-d917-477a-a552-af7d45f00002, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=38, minorTopVer=0], nodeId=5f6e841f, evt=NODE_LEFT], init=true, ready=false, replied=false, added=true, initFut=GridFutureAdapter [resFlag=0, res=null, startTime=1452670150721, endTime=0, ignoreInterrupts=false, lsnr=null, state=INIT], topSnapshot=null, lastVer=null, partReleaseFut=null, skipPreload=false, clientOnlyExchange=false, initTs=1452670150721, oldest=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, oldestOrder=1, evtLatch=0, remaining=[7515d89d-1808-4974-ae0f-20a6bce00001, fb3c51b7-d917-477a-a552-af7d45f00002, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], super=GridFutureAdapter [resFlag=0, res=null, startTime=1452670150721, endTime=0, ignoreInterrupts=false, lsnr=null, state=INIT]], p=169, affNodesCnt=2, ownersCnt=0, affNodes=[TcpDiscoveryNode [id=7515d89d-1808-4974-ae0f-20a6bce00001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47502], discPort=47502, order=3, intOrder=3, lastExchangeTime=1452670140539, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=50e5fce9-f87f-4925-8ec0-b9ce27f8c005, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47505], discPort=47505, order=24, intOrder=15, lastExchangeTime=1452670150721, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]], owners=[], locNode=TcpDiscoveryNode [id=50e5fce9-f87f-4925-8ec0-b9ce27f8c005, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47505], discPort=47505, order=24, intOrder=15, lastExchangeTime=1452670150721, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]]
[23:29:10,926][WARN ][topology-change-thread-2][root] Finished waiting for topology map update [grid=793c1fc5-5962-4a22-a3d4-1848cf9484c5, p=169, duration=200ms]
[23:29:10,949][WARN ][topology-change-thread-2][root] Waiting for topology map update [grid=partitioned.GridCachePartitionedDataStructuresFailoverSelfTest2, cache=datastructures_0, cacheId=1278244283, topVer=AffinityTopologyVersion [topVer=39, minorTopVer=0], topFut=GridDhtPartitionsExchangeFuture [dummy=false, forcePreload=false, reassign=false, discoEvt=DiscoveryEvent [evtNode=TcpDiscoveryNode [id=50e5fce9-f87f-4925-8ec0-b9ce27f8c005, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47505], discPort=47505, order=24, intOrder=15, lastExchangeTime=1452670140528, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], topVer=39, nodeId8=fb3c51b7, msg=Node left: TcpDiscoveryNode [id=50e5fce9-f87f-4925-8ec0-b9ce27f8c005, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47505], discPort=47505, order=24, intOrder=15, lastExchangeTime=1452670140528, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], type=NODE_LEFT, tstamp=1452670150934], rcvdIds=GridConcurrentHashSet [elements=[]], rmtIds=[7515d89d-1808-4974-ae0f-20a6bce00001, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=39, minorTopVer=0], nodeId=50e5fce9, evt=NODE_LEFT], init=true, ready=false, replied=false, added=true, initFut=GridFutureAdapter [resFlag=0, res=null, startTime=1452670150934, endTime=0, ignoreInterrupts=false, lsnr=null, state=INIT], topSnapshot=null, lastVer=null, partReleaseFut=null, skipPreload=false, clientOnlyExchange=false, initTs=1452670150934, oldest=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, oldestOrder=1, evtLatch=0, remaining=[7515d89d-1808-4974-ae0f-20a6bce00001, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], super=GridFutureAdapter [resFlag=0, res=null, startTime=1452670150934, endTime=0, ignoreInterrupts=false, lsnr=null, state=INIT]], p=0, affNodesCnt=2, ownersCnt=0, affNodes=[TcpDiscoveryNode [id=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47501], discPort=47501, order=1, intOrder=1, lastExchangeTime=1452670115712, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=fb3c51b7-d917-477a-a552-af7d45f00002, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47500], discPort=47500, order=2, intOrder=2, lastExchangeTime=1452670150945, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]], owners=[], locNode=TcpDiscoveryNode [id=fb3c51b7-d917-477a-a552-af7d45f00002, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47500], discPort=47500, order=2, intOrder=2, lastExchangeTime=1452670150945, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]]
[23:29:11,149][WARN ][topology-change-thread-2][root] Finished waiting for topology map update [grid=partitioned.GridCachePartitionedDataStructuresFailoverSelfTest2, p=0, duration=200ms]
[23:29:11,170][WARN ][topology-change-thread-2][IgniteKernal%7b7ca90f-ab15-4340-b320-e09d0998b805] Peer class loading is enabled (disable it in production for performance and deployment consistency reasons)
[23:29:11,174][WARN ][topology-change-thread-1][IgniteKernal%ec3142d1-90fc-4b17-87a0-3452f38a526d] Peer class loading is enabled (disable it in production for performance and deployment consistency reasons)
[23:29:11,174][WARN ][topology-change-thread-3][IgniteKernal%706d4036-487f-4935-999f-841999e4c76d] Peer class loading is enabled (disable it in production for performance and deployment consistency reasons)
[23:29:12,667][WARN ][topology-change-thread-3][GridCollisionManager] Collision resolution is disabled (all jobs will be activated upon arrival).
[23:29:12,667][WARN ][topology-change-thread-3][NoopSwapSpaceSpi] Swap space is disabled. To enable use FileSwapSpaceSpi.
[23:29:12,852][WARN ][topology-change-thread-1][GridCollisionManager] Collision resolution is disabled (all jobs will be activated upon arrival).
[23:29:12,852][WARN ][topology-change-thread-1][NoopSwapSpaceSpi] Swap space is disabled. To enable use FileSwapSpaceSpi.
[23:29:12,859][WARN ][topology-change-thread-2][GridCollisionManager] Collision resolution is disabled (all jobs will be activated upon arrival).
[23:29:12,859][WARN ][topology-change-thread-2][NoopSwapSpaceSpi] Swap space is disabled. To enable use FileSwapSpaceSpi.
[23:29:13,322][WARN ][topology-change-thread-3][IgniteKernal%15d1f447-f77a-41c3-8fb8-87b0ff194138] Peer class loading is enabled (disable it in production for performance and deployment consistency reasons)
[23:29:13,947][WARN ][topology-change-thread-3][GridCollisionManager] Collision resolution is disabled (all jobs will be activated upon arrival).
[23:29:13,947][WARN ][topology-change-thread-3][NoopSwapSpaceSpi] Swap space is disabled. To enable use FileSwapSpaceSpi.
[23:29:14,089][WARN ][topology-change-thread-2][IgniteKernal%950f4ada-9436-41aa-9088-432a04dc2273] Peer class loading is enabled (disable it in production for performance and deployment consistency reasons)
[23:29:14,096][WARN ][topology-change-thread-1][IgniteKernal%37950ac0-884d-41c6-b9e5-b57d6685989a] Peer class loading is enabled (disable it in production for performance and deployment consistency reasons)
[23:29:14,118][WARN ][topology-change-thread-3][IgniteKernal%a86d1090-ccea-4768-b568-54432c1809fe] Peer class loading is enabled (disable it in production for performance and deployment consistency reasons)
[23:29:15,926][WARN ][topology-change-thread-1][GridCollisionManager] Collision resolution is disabled (all jobs will be activated upon arrival).
[23:29:15,926][WARN ][topology-change-thread-3][GridCollisionManager] Collision resolution is disabled (all jobs will be activated upon arrival).
[23:29:15,926][WARN ][topology-change-thread-3][NoopSwapSpaceSpi] Swap space is disabled. To enable use FileSwapSpaceSpi.
[23:29:15,926][WARN ][topology-change-thread-1][NoopSwapSpaceSpi] Swap space is disabled. To enable use FileSwapSpaceSpi.
[23:29:16,157][WARN ][topology-change-thread-1][IgniteKernal%817510b0-950f-47e7-95f8-0ffe5e3cba34] Peer class loading is enabled (disable it in production for performance and deployment consistency reasons)
[23:29:16,910][WARN ][topology-change-thread-2][GridCollisionManager] Collision resolution is disabled (all jobs will be activated upon arrival).
[23:29:16,910][WARN ][topology-change-thread-2][NoopSwapSpaceSpi] Swap space is disabled. To enable use FileSwapSpaceSpi.
[23:29:16,921][WARN ][topology-change-thread-1][GridCollisionManager] Collision resolution is disabled (all jobs will be activated upon arrival).
[23:29:16,921][WARN ][topology-change-thread-1][NoopSwapSpaceSpi] Swap space is disabled. To enable use FileSwapSpaceSpi.
[23:29:17,081][WARN ][topology-change-thread-2][IgniteKernal%df903f52-4d5f-4bd8-b8a6-2ac7ef35351e] Peer class loading is enabled (disable it in production for performance and deployment consistency reasons)
[23:29:17,799][WARN ][topology-change-thread-2][GridCollisionManager] Collision resolution is disabled (all jobs will be activated upon arrival).
[23:29:17,799][WARN ][topology-change-thread-2][NoopSwapSpaceSpi] Swap space is disabled. To enable use FileSwapSpaceSpi.
[23:29:18,730][WARN ][topology-change-thread-1][root] Waiting for topology map update [grid=706d4036-487f-4935-999f-841999e4c76d, cache=datastructures_0, cacheId=1278244283, topVer=AffinityTopologyVersion [topVer=49, minorTopVer=0], topFut=GridDhtPartitionsExchangeFuture [dummy=false, forcePreload=false, reassign=false, discoEvt=DiscoveryEvent [evtNode=TcpDiscoveryNode [id=8086518b-05ec-41d3-91cd-098be3b48008, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47506], discPort=47506, order=43, intOrder=25, lastExchangeTime=1452670153955, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], topVer=49, nodeId8=76594d7b, msg=Node left: TcpDiscoveryNode [id=8086518b-05ec-41d3-91cd-098be3b48008, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47506], discPort=47506, order=43, intOrder=25, lastExchangeTime=1452670153955, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], type=NODE_LEFT, tstamp=1452670158715], rcvdIds=GridConcurrentHashSet [elements=[]], rmtIds=[7515d89d-1808-4974-ae0f-20a6bce00001, c437a5bd-f252-4915-bf26-fb77bc918e1d, 4055df00-7251-461c-b448-5de7e3e2e004, 0a54fb9f-c699-42ba-bace-441f2125c3b5, 50c9b29e-68d2-41dc-9a77-c061360ad005, 30292413-a835-4f76-a95d-2551de4ae003, 29d8291e-083e-4f49-8dab-47247af56a8d, 795a3839-e9e7-4554-9063-19ea31124db1, fb3c51b7-d917-477a-a552-af7d45f00002, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=49, minorTopVer=0], nodeId=8086518b, evt=NODE_LEFT], init=true, ready=false, replied=false, added=true, initFut=GridFutureAdapter [resFlag=0, res=null, startTime=1452670158715, endTime=0, ignoreInterrupts=false, lsnr=null, state=INIT], topSnapshot=null, lastVer=null, partReleaseFut=null, skipPreload=false, clientOnlyExchange=false, initTs=1452670158715, oldest=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, oldestOrder=1, evtLatch=0, remaining=[7515d89d-1808-4974-ae0f-20a6bce00001, c437a5bd-f252-4915-bf26-fb77bc918e1d, 4055df00-7251-461c-b448-5de7e3e2e004, 0a54fb9f-c699-42ba-bace-441f2125c3b5, 50c9b29e-68d2-41dc-9a77-c061360ad005, 30292413-a835-4f76-a95d-2551de4ae003, 29d8291e-083e-4f49-8dab-47247af56a8d, 795a3839-e9e7-4554-9063-19ea31124db1, fb3c51b7-d917-477a-a552-af7d45f00002, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], super=GridFutureAdapter [resFlag=0, res=null, startTime=1452670158715, endTime=0, ignoreInterrupts=false, lsnr=null, state=INIT]], p=0, affNodesCnt=2, ownersCnt=0, affNodes=[TcpDiscoveryNode [id=50c9b29e-68d2-41dc-9a77-c061360ad005, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47505], discPort=47505, order=42, intOrder=24, lastExchangeTime=1452670153891, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=76594d7b-ae45-4f05-bc25-f49a77c31e39, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=40, intOrder=22, lastExchangeTime=1452670158725, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]], owners=[], locNode=TcpDiscoveryNode [id=76594d7b-ae45-4f05-bc25-f49a77c31e39, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=40, intOrder=22, lastExchangeTime=1452670158725, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]]
[23:29:18,930][WARN ][topology-change-thread-1][root] Waiting for topology map update [grid=706d4036-487f-4935-999f-841999e4c76d, cache=datastructures_0, cacheId=1278244283, topVer=AffinityTopologyVersion [topVer=49, minorTopVer=0], topFut=GridDhtPartitionsExchangeFuture [dummy=false, forcePreload=false, reassign=false, discoEvt=DiscoveryEvent [evtNode=TcpDiscoveryNode [id=8086518b-05ec-41d3-91cd-098be3b48008, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47506], discPort=47506, order=43, intOrder=25, lastExchangeTime=1452670153955, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], topVer=49, nodeId8=76594d7b, msg=Node left: TcpDiscoveryNode [id=8086518b-05ec-41d3-91cd-098be3b48008, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47506], discPort=47506, order=43, intOrder=25, lastExchangeTime=1452670153955, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], type=NODE_LEFT, tstamp=1452670158715], rcvdIds=GridConcurrentHashSet [elements=[]], rmtIds=[7515d89d-1808-4974-ae0f-20a6bce00001, c437a5bd-f252-4915-bf26-fb77bc918e1d, 4055df00-7251-461c-b448-5de7e3e2e004, 0a54fb9f-c699-42ba-bace-441f2125c3b5, 50c9b29e-68d2-41dc-9a77-c061360ad005, 30292413-a835-4f76-a95d-2551de4ae003, 29d8291e-083e-4f49-8dab-47247af56a8d, 795a3839-e9e7-4554-9063-19ea31124db1, fb3c51b7-d917-477a-a552-af7d45f00002, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=49, minorTopVer=0], nodeId=8086518b, evt=NODE_LEFT], init=true, ready=true, replied=false, added=true, initFut=GridFutureAdapter [resFlag=2, res=true, startTime=1452670158715, endTime=1452670158745, ignoreInterrupts=false, lsnr=null, state=DONE], topSnapshot=null, lastVer=null, partReleaseFut=GridCompoundFuture [rdc=null, flags=1, lsnrCalls=3, done=true, cancelled=false, err=null, futs=[true, true, true]], skipPreload=false, clientOnlyExchange=false, initTs=1452670158715, oldest=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, oldestOrder=1, evtLatch=0, remaining=[7515d89d-1808-4974-ae0f-20a6bce00001, c437a5bd-f252-4915-bf26-fb77bc918e1d, 4055df00-7251-461c-b448-5de7e3e2e004, 0a54fb9f-c699-42ba-bace-441f2125c3b5, 50c9b29e-68d2-41dc-9a77-c061360ad005, 30292413-a835-4f76-a95d-2551de4ae003, 29d8291e-083e-4f49-8dab-47247af56a8d, 795a3839-e9e7-4554-9063-19ea31124db1, fb3c51b7-d917-477a-a552-af7d45f00002, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], super=GridFutureAdapter [resFlag=0, res=null, startTime=1452670158715, endTime=0, ignoreInterrupts=false, lsnr=null, state=INIT]], p=0, affNodesCnt=2, ownersCnt=0, affNodes=[TcpDiscoveryNode [id=50c9b29e-68d2-41dc-9a77-c061360ad005, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47505], discPort=47505, order=42, intOrder=24, lastExchangeTime=1452670153891, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=76594d7b-ae45-4f05-bc25-f49a77c31e39, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=40, intOrder=22, lastExchangeTime=1452670158725, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]], owners=[], locNode=TcpDiscoveryNode [id=76594d7b-ae45-4f05-bc25-f49a77c31e39, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=40, intOrder=22, lastExchangeTime=1452670158725, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]]
[23:29:19,130][WARN ][topology-change-thread-1][root] Waiting for topology map update [grid=706d4036-487f-4935-999f-841999e4c76d, cache=datastructures_0, cacheId=1278244283, topVer=AffinityTopologyVersion [topVer=49, minorTopVer=0], topFut=GridDhtPartitionsExchangeFuture [dummy=false, forcePreload=false, reassign=false, discoEvt=DiscoveryEvent [evtNode=TcpDiscoveryNode [id=8086518b-05ec-41d3-91cd-098be3b48008, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47506], discPort=47506, order=43, intOrder=25, lastExchangeTime=1452670153955, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], topVer=49, nodeId8=76594d7b, msg=Node left: TcpDiscoveryNode [id=8086518b-05ec-41d3-91cd-098be3b48008, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47506], discPort=47506, order=43, intOrder=25, lastExchangeTime=1452670153955, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], type=NODE_LEFT, tstamp=1452670158715], rcvdIds=GridConcurrentHashSet [elements=[]], rmtIds=[7515d89d-1808-4974-ae0f-20a6bce00001, c437a5bd-f252-4915-bf26-fb77bc918e1d, 4055df00-7251-461c-b448-5de7e3e2e004, 0a54fb9f-c699-42ba-bace-441f2125c3b5, 50c9b29e-68d2-41dc-9a77-c061360ad005, 30292413-a835-4f76-a95d-2551de4ae003, 29d8291e-083e-4f49-8dab-47247af56a8d, 795a3839-e9e7-4554-9063-19ea31124db1, fb3c51b7-d917-477a-a552-af7d45f00002, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=49, minorTopVer=0], nodeId=8086518b, evt=NODE_LEFT], init=true, ready=true, replied=false, added=true, initFut=GridFutureAdapter [resFlag=2, res=true, startTime=1452670158715, endTime=1452670158745, ignoreInterrupts=false, lsnr=null, state=DONE], topSnapshot=null, lastVer=null, partReleaseFut=GridCompoundFuture [rdc=null, flags=1, lsnrCalls=3, done=true, cancelled=false, err=null, futs=[true, true, true]], skipPreload=false, clientOnlyExchange=false, initTs=1452670158715, oldest=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, oldestOrder=1, evtLatch=0, remaining=[7515d89d-1808-4974-ae0f-20a6bce00001, c437a5bd-f252-4915-bf26-fb77bc918e1d, 4055df00-7251-461c-b448-5de7e3e2e004, 0a54fb9f-c699-42ba-bace-441f2125c3b5, 50c9b29e-68d2-41dc-9a77-c061360ad005, 30292413-a835-4f76-a95d-2551de4ae003, 29d8291e-083e-4f49-8dab-47247af56a8d, 795a3839-e9e7-4554-9063-19ea31124db1, fb3c51b7-d917-477a-a552-af7d45f00002, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], super=GridFutureAdapter [resFlag=0, res=null, startTime=1452670158715, endTime=0, ignoreInterrupts=false, lsnr=null, state=INIT]], p=0, affNodesCnt=2, ownersCnt=0, affNodes=[TcpDiscoveryNode [id=50c9b29e-68d2-41dc-9a77-c061360ad005, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47505], discPort=47505, order=42, intOrder=24, lastExchangeTime=1452670153891, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=76594d7b-ae45-4f05-bc25-f49a77c31e39, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=40, intOrder=22, lastExchangeTime=1452670158990, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]], owners=[], locNode=TcpDiscoveryNode [id=76594d7b-ae45-4f05-bc25-f49a77c31e39, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=40, intOrder=22, lastExchangeTime=1452670158990, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]]
[23:29:19,732][WARN ][topology-change-thread-1][root] Waiting for topology map update [grid=706d4036-487f-4935-999f-841999e4c76d, cache=datastructures_0, cacheId=1278244283, topVer=AffinityTopologyVersion [topVer=49, minorTopVer=0], topFut=GridDhtPartitionsExchangeFuture [dummy=false, forcePreload=false, reassign=false, discoEvt=DiscoveryEvent [evtNode=TcpDiscoveryNode [id=8086518b-05ec-41d3-91cd-098be3b48008, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47506], discPort=47506, order=43, intOrder=25, lastExchangeTime=1452670153955, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], topVer=49, nodeId8=76594d7b, msg=Node left: TcpDiscoveryNode [id=8086518b-05ec-41d3-91cd-098be3b48008, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47506], discPort=47506, order=43, intOrder=25, lastExchangeTime=1452670153955, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], type=NODE_LEFT, tstamp=1452670158715], rcvdIds=GridConcurrentHashSet [elements=[]], rmtIds=[7515d89d-1808-4974-ae0f-20a6bce00001, c437a5bd-f252-4915-bf26-fb77bc918e1d, 4055df00-7251-461c-b448-5de7e3e2e004, 0a54fb9f-c699-42ba-bace-441f2125c3b5, 50c9b29e-68d2-41dc-9a77-c061360ad005, 30292413-a835-4f76-a95d-2551de4ae003, 29d8291e-083e-4f49-8dab-47247af56a8d, 795a3839-e9e7-4554-9063-19ea31124db1, fb3c51b7-d917-477a-a552-af7d45f00002, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=49, minorTopVer=0], nodeId=8086518b, evt=NODE_LEFT], init=true, ready=true, replied=false, added=true, initFut=GridFutureAdapter [resFlag=2, res=true, startTime=1452670158715, endTime=1452670158745, ignoreInterrupts=false, lsnr=null, state=DONE], topSnapshot=null, lastVer=null, partReleaseFut=GridCompoundFuture [rdc=null, flags=1, lsnrCalls=3, done=true, cancelled=false, err=null, futs=[true, true, true]], skipPreload=false, clientOnlyExchange=false, initTs=1452670158715, oldest=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, oldestOrder=1, evtLatch=0, remaining=[7515d89d-1808-4974-ae0f-20a6bce00001, c437a5bd-f252-4915-bf26-fb77bc918e1d, 4055df00-7251-461c-b448-5de7e3e2e004, 0a54fb9f-c699-42ba-bace-441f2125c3b5, 50c9b29e-68d2-41dc-9a77-c061360ad005, 30292413-a835-4f76-a95d-2551de4ae003, 29d8291e-083e-4f49-8dab-47247af56a8d, 795a3839-e9e7-4554-9063-19ea31124db1, fb3c51b7-d917-477a-a552-af7d45f00002, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], super=GridFutureAdapter [resFlag=0, res=null, startTime=1452670158715, endTime=0, ignoreInterrupts=false, lsnr=null, state=INIT]], p=0, affNodesCnt=2, ownersCnt=0, affNodes=[TcpDiscoveryNode [id=50c9b29e-68d2-41dc-9a77-c061360ad005, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47505], discPort=47505, order=42, intOrder=24, lastExchangeTime=1452670153891, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=76594d7b-ae45-4f05-bc25-f49a77c31e39, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=40, intOrder=22, lastExchangeTime=1452670159588, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]], owners=[], locNode=TcpDiscoveryNode [id=76594d7b-ae45-4f05-bc25-f49a77c31e39, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=40, intOrder=22, lastExchangeTime=1452670159588, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]]
[23:29:19,933][WARN ][topology-change-thread-1][root] Finished waiting for topology map update [grid=706d4036-487f-4935-999f-841999e4c76d, p=0, duration=1203ms]
[23:29:19,933][WARN ][topology-change-thread-1][root] Waiting for topology map update [grid=706d4036-487f-4935-999f-841999e4c76d, cache=datastructures_0, cacheId=1278244283, topVer=AffinityTopologyVersion [topVer=49, minorTopVer=0], topFut=GridDhtPartitionsExchangeFuture [dummy=false, forcePreload=false, reassign=false, discoEvt=DiscoveryEvent [evtNode=TcpDiscoveryNode [id=8086518b-05ec-41d3-91cd-098be3b48008, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47506], discPort=47506, order=43, intOrder=25, lastExchangeTime=1452670153955, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], topVer=49, nodeId8=76594d7b, msg=Node left: TcpDiscoveryNode [id=8086518b-05ec-41d3-91cd-098be3b48008, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47506], discPort=47506, order=43, intOrder=25, lastExchangeTime=1452670153955, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], type=NODE_LEFT, tstamp=1452670158715], rcvdIds=GridConcurrentHashSet [elements=[]], rmtIds=[7515d89d-1808-4974-ae0f-20a6bce00001, c437a5bd-f252-4915-bf26-fb77bc918e1d, 4055df00-7251-461c-b448-5de7e3e2e004, 0a54fb9f-c699-42ba-bace-441f2125c3b5, 50c9b29e-68d2-41dc-9a77-c061360ad005, 30292413-a835-4f76-a95d-2551de4ae003, 29d8291e-083e-4f49-8dab-47247af56a8d, 795a3839-e9e7-4554-9063-19ea31124db1, fb3c51b7-d917-477a-a552-af7d45f00002, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=49, minorTopVer=0], nodeId=8086518b, evt=NODE_LEFT], init=true, ready=true, replied=false, added=true, initFut=GridFutureAdapter [resFlag=2, res=true, startTime=1452670158715, endTime=1452670158745, ignoreInterrupts=false, lsnr=null, state=DONE], topSnapshot=null, lastVer=null, partReleaseFut=GridCompoundFuture [rdc=null, flags=1, lsnrCalls=3, done=true, cancelled=false, err=null, futs=[true, true, true]], skipPreload=false, clientOnlyExchange=false, initTs=1452670158715, oldest=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, oldestOrder=1, evtLatch=0, remaining=[7515d89d-1808-4974-ae0f-20a6bce00001, c437a5bd-f252-4915-bf26-fb77bc918e1d, 4055df00-7251-461c-b448-5de7e3e2e004, 0a54fb9f-c699-42ba-bace-441f2125c3b5, 50c9b29e-68d2-41dc-9a77-c061360ad005, 30292413-a835-4f76-a95d-2551de4ae003, 29d8291e-083e-4f49-8dab-47247af56a8d, 795a3839-e9e7-4554-9063-19ea31124db1, fb3c51b7-d917-477a-a552-af7d45f00002, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], super=GridFutureAdapter [resFlag=2, res=AffinityTopologyVersion [topVer=49, minorTopVer=0], startTime=1452670158715, endTime=1452670159752, ignoreInterrupts=false, lsnr=null, state=DONE]], p=3, affNodesCnt=2, ownersCnt=3, affNodes=[TcpDiscoveryNode [id=7515d89d-1808-4974-ae0f-20a6bce00001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47502], discPort=47502, order=3, intOrder=3, lastExchangeTime=1452670153240, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=4055df00-7251-461c-b448-5de7e3e2e004, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47510], discPort=47510, order=48, intOrder=30, lastExchangeTime=1452670158504, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]], owners=[TcpDiscoveryNode [id=7515d89d-1808-4974-ae0f-20a6bce00001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47502], discPort=47502, order=3, intOrder=3, lastExchangeTime=1452670153240, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=4055df00-7251-461c-b448-5de7e3e2e004, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47510], discPort=47510, order=48, intOrder=30, lastExchangeTime=1452670158504, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=30292413-a835-4f76-a95d-2551de4ae003, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47509], discPort=47509, order=46, intOrder=28, lastExchangeTime=1452670156915, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]], locNode=TcpDiscoveryNode [id=76594d7b-ae45-4f05-bc25-f49a77c31e39, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=40, intOrder=22, lastExchangeTime=1452670159588, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]]
[23:29:20,141][WARN ][topology-change-thread-1][root] Waiting for topology map update [grid=706d4036-487f-4935-999f-841999e4c76d, cache=datastructures_0, cacheId=1278244283, topVer=AffinityTopologyVersion [topVer=49, minorTopVer=0], topFut=GridDhtPartitionsExchangeFuture [dummy=false, forcePreload=false, reassign=false, discoEvt=DiscoveryEvent [evtNode=TcpDiscoveryNode [id=8086518b-05ec-41d3-91cd-098be3b48008, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47506], discPort=47506, order=43, intOrder=25, lastExchangeTime=1452670153955, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], topVer=49, nodeId8=76594d7b, msg=Node left: TcpDiscoveryNode [id=8086518b-05ec-41d3-91cd-098be3b48008, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47506], discPort=47506, order=43, intOrder=25, lastExchangeTime=1452670153955, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], type=NODE_LEFT, tstamp=1452670158715], rcvdIds=GridConcurrentHashSet [elements=[]], rmtIds=[7515d89d-1808-4974-ae0f-20a6bce00001, c437a5bd-f252-4915-bf26-fb77bc918e1d, 4055df00-7251-461c-b448-5de7e3e2e004, 0a54fb9f-c699-42ba-bace-441f2125c3b5, 50c9b29e-68d2-41dc-9a77-c061360ad005, 30292413-a835-4f76-a95d-2551de4ae003, 29d8291e-083e-4f49-8dab-47247af56a8d, 795a3839-e9e7-4554-9063-19ea31124db1, fb3c51b7-d917-477a-a552-af7d45f00002, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=49, minorTopVer=0], nodeId=8086518b, evt=NODE_LEFT], init=true, ready=true, replied=false, added=true, initFut=GridFutureAdapter [resFlag=2, res=true, startTime=1452670158715, endTime=1452670158745, ignoreInterrupts=false, lsnr=null, state=DONE], topSnapshot=null, lastVer=null, partReleaseFut=GridCompoundFuture [rdc=null, flags=1, lsnrCalls=3, done=true, cancelled=false, err=null, futs=[true, true, true]], skipPreload=false, clientOnlyExchange=false, initTs=1452670158715, oldest=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, oldestOrder=1, evtLatch=0, remaining=[7515d89d-1808-4974-ae0f-20a6bce00001, c437a5bd-f252-4915-bf26-fb77bc918e1d, 4055df00-7251-461c-b448-5de7e3e2e004, 0a54fb9f-c699-42ba-bace-441f2125c3b5, 50c9b29e-68d2-41dc-9a77-c061360ad005, 30292413-a835-4f76-a95d-2551de4ae003, 29d8291e-083e-4f49-8dab-47247af56a8d, 795a3839-e9e7-4554-9063-19ea31124db1, fb3c51b7-d917-477a-a552-af7d45f00002, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], super=GridFutureAdapter [resFlag=2, res=AffinityTopologyVersion [topVer=49, minorTopVer=0], startTime=1452670158715, endTime=1452670159752, ignoreInterrupts=false, lsnr=null, state=DONE]], p=3, affNodesCnt=2, ownersCnt=3, affNodes=[TcpDiscoveryNode [id=7515d89d-1808-4974-ae0f-20a6bce00001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47502], discPort=47502, order=3, intOrder=3, lastExchangeTime=1452670153240, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=4055df00-7251-461c-b448-5de7e3e2e004, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47510], discPort=47510, order=48, intOrder=30, lastExchangeTime=1452670158504, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]], owners=[TcpDiscoveryNode [id=7515d89d-1808-4974-ae0f-20a6bce00001, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47502], discPort=47502, order=3, intOrder=3, lastExchangeTime=1452670153240, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=4055df00-7251-461c-b448-5de7e3e2e004, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47510], discPort=47510, order=48, intOrder=30, lastExchangeTime=1452670158504, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=30292413-a835-4f76-a95d-2551de4ae003, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47509], discPort=47509, order=46, intOrder=28, lastExchangeTime=1452670156915, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]], locNode=TcpDiscoveryNode [id=76594d7b-ae45-4f05-bc25-f49a77c31e39, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=40, intOrder=22, lastExchangeTime=1452670159952, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]]
[23:29:20,341][WARN ][topology-change-thread-1][root] Finished waiting for topology map update [grid=706d4036-487f-4935-999f-841999e4c76d, p=3, duration=406ms]
[23:29:20,388][WARN ][topology-change-thread-1][root] Waiting for topology map update [grid=706d4036-487f-4935-999f-841999e4c76d, cache=datastructures_0, cacheId=1278244283, topVer=AffinityTopologyVersion [topVer=50, minorTopVer=0], topFut=GridDhtPartitionsExchangeFuture [dummy=false, forcePreload=false, reassign=false, discoEvt=DiscoveryEvent [evtNode=TcpDiscoveryNode [id=29d8291e-083e-4f49-8dab-47247af56a8d, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47507], discPort=47507, order=45, intOrder=27, lastExchangeTime=1452670155940, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], topVer=50, nodeId8=76594d7b, msg=Node left: TcpDiscoveryNode [id=29d8291e-083e-4f49-8dab-47247af56a8d, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47507], discPort=47507, order=45, intOrder=27, lastExchangeTime=1452670155940, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], type=NODE_LEFT, tstamp=1452670160370], rcvdIds=GridConcurrentHashSet [elements=[]], rmtIds=[7515d89d-1808-4974-ae0f-20a6bce00001, c437a5bd-f252-4915-bf26-fb77bc918e1d, 4055df00-7251-461c-b448-5de7e3e2e004, 0a54fb9f-c699-42ba-bace-441f2125c3b5, 50c9b29e-68d2-41dc-9a77-c061360ad005, 30292413-a835-4f76-a95d-2551de4ae003, 795a3839-e9e7-4554-9063-19ea31124db1, fb3c51b7-d917-477a-a552-af7d45f00002, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=50, minorTopVer=0], nodeId=29d8291e, evt=NODE_LEFT], init=true, ready=false, replied=false, added=true, initFut=GridFutureAdapter [resFlag=0, res=null, startTime=1452670160370, endTime=0, ignoreInterrupts=false, lsnr=null, state=INIT], topSnapshot=null, lastVer=null, partReleaseFut=null, skipPreload=false, clientOnlyExchange=false, initTs=1452670160370, oldest=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, oldestOrder=1, evtLatch=0, remaining=[7515d89d-1808-4974-ae0f-20a6bce00001, c437a5bd-f252-4915-bf26-fb77bc918e1d, 4055df00-7251-461c-b448-5de7e3e2e004, 0a54fb9f-c699-42ba-bace-441f2125c3b5, 50c9b29e-68d2-41dc-9a77-c061360ad005, 30292413-a835-4f76-a95d-2551de4ae003, 795a3839-e9e7-4554-9063-19ea31124db1, fb3c51b7-d917-477a-a552-af7d45f00002, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], super=GridFutureAdapter [resFlag=0, res=null, startTime=1452670160370, endTime=0, ignoreInterrupts=false, lsnr=null, state=INIT]], p=0, affNodesCnt=2, ownersCnt=0, affNodes=[TcpDiscoveryNode [id=50c9b29e-68d2-41dc-9a77-c061360ad005, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47505], discPort=47505, order=42, intOrder=24, lastExchangeTime=1452670153891, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=76594d7b-ae45-4f05-bc25-f49a77c31e39, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=40, intOrder=22, lastExchangeTime=1452670160380, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]], owners=[], locNode=TcpDiscoveryNode [id=76594d7b-ae45-4f05-bc25-f49a77c31e39, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=40, intOrder=22, lastExchangeTime=1452670160380, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]]
[23:29:20,588][WARN ][topology-change-thread-1][root] Finished waiting for topology map update [grid=706d4036-487f-4935-999f-841999e4c76d, p=0, duration=200ms]
[23:29:20,633][WARN ][topology-change-thread-1][root] Waiting for topology map update [grid=df903f52-4d5f-4bd8-b8a6-2ac7ef35351e, cache=datastructures_0, cacheId=1278244283, topVer=AffinityTopologyVersion [topVer=51, minorTopVer=0], topFut=GridDhtPartitionsExchangeFuture [dummy=false, forcePreload=false, reassign=false, discoEvt=DiscoveryEvent [evtNode=TcpDiscoveryNode [id=76594d7b-ae45-4f05-bc25-f49a77c31e39, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=40, intOrder=22, lastExchangeTime=1452670157835, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], topVer=51, nodeId8=0a54fb9f, msg=Node left: TcpDiscoveryNode [id=76594d7b-ae45-4f05-bc25-f49a77c31e39, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=40, intOrder=22, lastExchangeTime=1452670157835, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], type=NODE_LEFT, tstamp=1452670160626], rcvdIds=GridConcurrentHashSet [elements=[]], rmtIds=[7515d89d-1808-4974-ae0f-20a6bce00001, c437a5bd-f252-4915-bf26-fb77bc918e1d, 4055df00-7251-461c-b448-5de7e3e2e004, 50c9b29e-68d2-41dc-9a77-c061360ad005, 30292413-a835-4f76-a95d-2551de4ae003, 795a3839-e9e7-4554-9063-19ea31124db1, fb3c51b7-d917-477a-a552-af7d45f00002, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=51, minorTopVer=0], nodeId=76594d7b, evt=NODE_LEFT], init=true, ready=false, replied=false, added=true, initFut=GridFutureAdapter [resFlag=0, res=null, startTime=1452670160626, endTime=0, ignoreInterrupts=false, lsnr=null, state=INIT], topSnapshot=null, lastVer=null, partReleaseFut=null, skipPreload=false, clientOnlyExchange=false, initTs=1452670160626, oldest=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, oldestOrder=1, evtLatch=0, remaining=[7515d89d-1808-4974-ae0f-20a6bce00001, c437a5bd-f252-4915-bf26-fb77bc918e1d, 4055df00-7251-461c-b448-5de7e3e2e004, 50c9b29e-68d2-41dc-9a77-c061360ad005, 30292413-a835-4f76-a95d-2551de4ae003, 795a3839-e9e7-4554-9063-19ea31124db1, fb3c51b7-d917-477a-a552-af7d45f00002, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], super=GridFutureAdapter [resFlag=0, res=null, startTime=1452670160626, endTime=0, ignoreInterrupts=false, lsnr=null, state=INIT]], p=0, affNodesCnt=2, ownersCnt=0, affNodes=[TcpDiscoveryNode [id=50c9b29e-68d2-41dc-9a77-c061360ad005, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47505], discPort=47505, order=42, intOrder=24, lastExchangeTime=1452670157835, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=76594d7b-ae45-4f05-bc25-f49a77c31e39, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=40, intOrder=22, lastExchangeTime=1452670157835, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]], owners=[], locNode=TcpDiscoveryNode [id=0a54fb9f-c699-42ba-bace-441f2125c3b5, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47511], discPort=47511, order=47, intOrder=29, lastExchangeTime=1452670160626, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]]
[23:29:20,833][WARN ][topology-change-thread-1][root] Waiting for topology map update [grid=df903f52-4d5f-4bd8-b8a6-2ac7ef35351e, cache=datastructures_0, cacheId=1278244283, topVer=AffinityTopologyVersion [topVer=51, minorTopVer=0], topFut=GridDhtPartitionsExchangeFuture [dummy=false, forcePreload=false, reassign=false, discoEvt=DiscoveryEvent [evtNode=TcpDiscoveryNode [id=76594d7b-ae45-4f05-bc25-f49a77c31e39, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=40, intOrder=22, lastExchangeTime=1452670157835, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], topVer=51, nodeId8=0a54fb9f, msg=Node left: TcpDiscoveryNode [id=76594d7b-ae45-4f05-bc25-f49a77c31e39, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=40, intOrder=22, lastExchangeTime=1452670157835, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], type=NODE_LEFT, tstamp=1452670160626], rcvdIds=GridConcurrentHashSet [elements=[]], rmtIds=[7515d89d-1808-4974-ae0f-20a6bce00001, c437a5bd-f252-4915-bf26-fb77bc918e1d, 4055df00-7251-461c-b448-5de7e3e2e004, 50c9b29e-68d2-41dc-9a77-c061360ad005, 30292413-a835-4f76-a95d-2551de4ae003, 795a3839-e9e7-4554-9063-19ea31124db1, fb3c51b7-d917-477a-a552-af7d45f00002, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=51, minorTopVer=0], nodeId=76594d7b, evt=NODE_LEFT], init=true, ready=true, replied=false, added=true, initFut=GridFutureAdapter [resFlag=2, res=true, startTime=1452670160626, endTime=1452670160656, ignoreInterrupts=false, lsnr=null, state=DONE], topSnapshot=null, lastVer=null, partReleaseFut=GridCompoundFuture [rdc=null, flags=1, lsnrCalls=3, done=true, cancelled=false, err=null, futs=[true, true, true]], skipPreload=false, clientOnlyExchange=false, initTs=1452670160626, oldest=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, oldestOrder=1, evtLatch=0, remaining=[7515d89d-1808-4974-ae0f-20a6bce00001, c437a5bd-f252-4915-bf26-fb77bc918e1d, 4055df00-7251-461c-b448-5de7e3e2e004, 50c9b29e-68d2-41dc-9a77-c061360ad005, 30292413-a835-4f76-a95d-2551de4ae003, 795a3839-e9e7-4554-9063-19ea31124db1, fb3c51b7-d917-477a-a552-af7d45f00002, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], super=GridFutureAdapter [resFlag=0, res=null, startTime=1452670160626, endTime=0, ignoreInterrupts=false, lsnr=null, state=INIT]], p=0, affNodesCnt=2, ownersCnt=0, affNodes=[TcpDiscoveryNode [id=50c9b29e-68d2-41dc-9a77-c061360ad005, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47505], discPort=47505, order=42, intOrder=24, lastExchangeTime=1452670157835, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47501], discPort=47501, order=1, intOrder=1, lastExchangeTime=1452670157835, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]], owners=[], locNode=TcpDiscoveryNode [id=0a54fb9f-c699-42ba-bace-441f2125c3b5, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47511], discPort=47511, order=47, intOrder=29, lastExchangeTime=1452670160626, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]]
[23:29:21,241][WARN ][topology-change-thread-1][root] Waiting for topology map update [grid=df903f52-4d5f-4bd8-b8a6-2ac7ef35351e, cache=datastructures_0, cacheId=1278244283, topVer=AffinityTopologyVersion [topVer=51, minorTopVer=0], topFut=GridDhtPartitionsExchangeFuture [dummy=false, forcePreload=false, reassign=false, discoEvt=DiscoveryEvent [evtNode=TcpDiscoveryNode [id=76594d7b-ae45-4f05-bc25-f49a77c31e39, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=40, intOrder=22, lastExchangeTime=1452670157835, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], topVer=51, nodeId8=0a54fb9f, msg=Node left: TcpDiscoveryNode [id=76594d7b-ae45-4f05-bc25-f49a77c31e39, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47503], discPort=47503, order=40, intOrder=22, lastExchangeTime=1452670157835, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], type=NODE_LEFT, tstamp=1452670160626], rcvdIds=GridConcurrentHashSet [elements=[]], rmtIds=[7515d89d-1808-4974-ae0f-20a6bce00001, c437a5bd-f252-4915-bf26-fb77bc918e1d, 4055df00-7251-461c-b448-5de7e3e2e004, 50c9b29e-68d2-41dc-9a77-c061360ad005, 30292413-a835-4f76-a95d-2551de4ae003, 795a3839-e9e7-4554-9063-19ea31124db1, fb3c51b7-d917-477a-a552-af7d45f00002, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=51, minorTopVer=0], nodeId=76594d7b, evt=NODE_LEFT], init=true, ready=true, replied=false, added=true, initFut=GridFutureAdapter [resFlag=2, res=true, startTime=1452670160626, endTime=1452670160656, ignoreInterrupts=false, lsnr=null, state=DONE], topSnapshot=null, lastVer=null, partReleaseFut=GridCompoundFuture [rdc=null, flags=1, lsnrCalls=3, done=true, cancelled=false, err=null, futs=[true, true, true]], skipPreload=false, clientOnlyExchange=false, initTs=1452670160626, oldest=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, oldestOrder=1, evtLatch=0, remaining=[7515d89d-1808-4974-ae0f-20a6bce00001, c437a5bd-f252-4915-bf26-fb77bc918e1d, 4055df00-7251-461c-b448-5de7e3e2e004, 50c9b29e-68d2-41dc-9a77-c061360ad005, 30292413-a835-4f76-a95d-2551de4ae003, 795a3839-e9e7-4554-9063-19ea31124db1, fb3c51b7-d917-477a-a552-af7d45f00002, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], super=GridFutureAdapter [resFlag=0, res=null, startTime=1452670160626, endTime=0, ignoreInterrupts=false, lsnr=null, state=INIT]], p=0, affNodesCnt=2, ownersCnt=0, affNodes=[TcpDiscoveryNode [id=50c9b29e-68d2-41dc-9a77-c061360ad005, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47505], discPort=47505, order=42, intOrder=24, lastExchangeTime=1452670157835, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47501], discPort=47501, order=1, intOrder=1, lastExchangeTime=1452670157835, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]], owners=[], locNode=TcpDiscoveryNode [id=0a54fb9f-c699-42ba-bace-441f2125c3b5, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47511], discPort=47511, order=47, intOrder=29, lastExchangeTime=1452670161216, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]]
[23:29:21,642][WARN ][topology-change-thread-1][root] Finished waiting for topology map update [grid=df903f52-4d5f-4bd8-b8a6-2ac7ef35351e, p=0, duration=1009ms]
[23:29:21,764][ERROR][main][root] Test failed.
junit.framework.AssertionFailedError: expected:<10188> but was:<10189>
    at junit.framework.Assert.fail(Assert.java:57)
    at junit.framework.Assert.failNotEquals(Assert.java:329)
    at junit.framework.Assert.assertEquals(Assert.java:78)
    at junit.framework.Assert.assertEquals(Assert.java:234)
    at junit.framework.Assert.assertEquals(Assert.java:241)
    at junit.framework.TestCase.assertEquals(TestCase.java:409)
    at org.apache.ignite.internal.processors.cache.datastructures.GridCacheAbstractDataStructuresFailoverSelfTest.doTestQueue(GridCacheAbstractDataStructuresFailoverSelfTest.java:729)
    at org.apache.ignite.internal.processors.cache.datastructures.GridCacheAbstractDataStructuresFailoverSelfTest.testQueueConstantMultipleTopologyChange(GridCacheAbstractDataStructuresFailoverSelfTest.java:683)
    at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
    at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
    at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
    at java.lang.reflect.Method.invoke(Method.java:606)
    at junit.framework.TestCase.runTest(TestCase.java:176)
    at org.apache.ignite.testframework.junits.GridAbstractTest.runTestInternal(GridAbstractTest.java:1699)
    at org.apache.ignite.testframework.junits.GridAbstractTest.access$000(GridAbstractTest.java:116)
    at org.apache.ignite.testframework.junits.GridAbstractTest$4.run(GridAbstractTest.java:1637)
    at java.lang.Thread.run(Thread.java:745)
[23:29:21,769][WARN ][grid-nio-worker-0-#126471%partitioned.GridCachePartitionedDataStructuresFailoverSelfTest2%][TcpCommunicationSpi] Failed to process selector key (will close): GridSelectorNioSessionImpl [selectorIdx=0, queueSize=0, writeBuf=java.nio.DirectByteBuffer[pos=0 lim=32768 cap=32768], readBuf=java.nio.DirectByteBuffer[pos=0 lim=32768 cap=32768], recovery=GridNioRecoveryDescriptor [acked=48, resendCnt=0, rcvCnt=101, reserved=true, lastAck=96, nodeLeft=false, node=TcpDiscoveryNode [id=4055df00-7251-461c-b448-5de7e3e2e004, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47510], discPort=47510, order=48, intOrder=30, lastExchangeTime=1452670158504, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], connected=true, connectCnt=0, queueLimit=5120], super=GridNioSessionImpl [locAddr=/127.0.0.1:45441, rmtAddr=/127.0.0.1:54672, createTime=1452670158631, closeTime=0, bytesSent=12460, bytesRcvd=9490, sndSchedTime=1452670161769, lastSndTime=1452670161769, lastRcvTime=1452670161769, readsPaused=false, filterChain=FilterChain[filters=[GridNioCodecFilter [parser=o.a.i.i.util.nio.GridDirectParser@212f6eb3, directMode=true], GridConnectionBytesVerifyFilter], accepted=true]]
[23:29:21,769][WARN ][grid-nio-worker-0-#126471%partitioned.GridCachePartitionedDataStructuresFailoverSelfTest2%][TcpCommunicationSpi] Closing NIO session because of unhandled exception [cls=class o.a.i.i.util.nio.GridNioException, msg=An existing connection was forcibly closed by the remote host]
[23:29:21,840][WARN ][topology-change-thread-1][root] Waiting for topology map update [grid=950f4ada-9436-41aa-9088-432a04dc2273, cache=datastructures_0, cacheId=1278244283, topVer=AffinityTopologyVersion [topVer=53, minorTopVer=0], topFut=GridDhtPartitionsExchangeFuture [dummy=false, forcePreload=false, reassign=false, discoEvt=DiscoveryEvent [evtNode=TcpDiscoveryNode [id=0a54fb9f-c699-42ba-bace-441f2125c3b5, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47511], discPort=47511, order=47, intOrder=29, lastExchangeTime=1452670157815, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], topVer=53, nodeId8=30292413, msg=Node left: TcpDiscoveryNode [id=0a54fb9f-c699-42ba-bace-441f2125c3b5, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47511], discPort=47511, order=47, intOrder=29, lastExchangeTime=1452670157815, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], type=NODE_LEFT, tstamp=1452670161769], rcvdIds=GridConcurrentHashSet [elements=[]], rmtIds=[7515d89d-1808-4974-ae0f-20a6bce00001, c437a5bd-f252-4915-bf26-fb77bc918e1d, 795a3839-e9e7-4554-9063-19ea31124db1, fb3c51b7-d917-477a-a552-af7d45f00002, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], exchId=GridDhtPartitionExchangeId [topVer=AffinityTopologyVersion [topVer=53, minorTopVer=0], nodeId=0a54fb9f, evt=NODE_LEFT], init=true, ready=false, replied=false, added=true, initFut=GridFutureAdapter [resFlag=0, res=null, startTime=1452670161769, endTime=0, ignoreInterrupts=false, lsnr=o.a.i.i.processors.cache.distributed.dht.preloader.GridDhtPartitionsExchangeFuture$5@60ff5b6, state=INIT], topSnapshot=null, lastVer=null, partReleaseFut=null, skipPreload=false, clientOnlyExchange=false, initTs=1452670161769, oldest=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, oldestOrder=1, evtLatch=0, remaining=[7515d89d-1808-4974-ae0f-20a6bce00001, c437a5bd-f252-4915-bf26-fb77bc918e1d, 795a3839-e9e7-4554-9063-19ea31124db1, fb3c51b7-d917-477a-a552-af7d45f00002, be963c7b-1c93-43b1-9b8d-bb9ea5c00000], super=GridFutureAdapter [resFlag=0, res=null, startTime=1452670161769, endTime=0, ignoreInterrupts=false, lsnr=null, state=INIT]], p=0, affNodesCnt=2, ownersCnt=0, affNodes=[TcpDiscoveryNode [id=be963c7b-1c93-43b1-9b8d-bb9ea5c00000, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47501], discPort=47501, order=1, intOrder=1, lastExchangeTime=1452670156947, loc=false, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false], TcpDiscoveryNode [id=30292413-a835-4f76-a95d-2551de4ae003, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47509], discPort=47509, order=46, intOrder=28, lastExchangeTime=1452670161833, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]], owners=[], locNode=TcpDiscoveryNode [id=30292413-a835-4f76-a95d-2551de4ae003, addrs=[127.0.0.1], sockAddrs=[/127.0.0.1:47509], discPort=47509, order=46, intOrder=28, lastExchangeTime=1452670161833, loc=true, ver=1.5.1#20160112-sha1:1d8c4e25, isClient=false]]
[23:29:21,942][WARN ][main][root] Interrupting threads started so far: 4
[23:29:21] (err) Failed to execute compound future reducer: Compound future listener []class org.apache.ignite.IgniteCheckedException: null
    at org.apache.ignite.internal.util.IgniteUtils.cast(IgniteUtils.java:7015)
    at org.apache.ignite.internal.util.future.GridFutureAdapter.get0(GridFutureAdapter.java:166)
    at org.apache.ignite.internal.util.future.GridFutureAdapter.get(GridFutureAdapter.java:115)
    at org.apache.ignite.internal.util.future.GridCompoundFuture$Listener.apply(GridCompoundFuture.java:372)
    at org.apache.ignite.internal.util.future.GridCompoundFuture$Listener.apply(GridCompoundFuture.java:365)
    at org.apache.ignite.internal.util.future.GridFutureAdapter.notifyListener(GridFutureAdapter.java:262)
    at org.apache.ignite.internal.util.future.GridFutureAdapter.notifyListeners(GridFutureAdapter.java:250)
    at org.apache.ignite.internal.util.future.GridFutureAdapter.onDone(GridFutureAdapter.java:380)
    at org.apache.ignite.internal.util.future.GridFutureAdapter.onDone(GridFutureAdapter.java:346)
    at org.apache.ignite.internal.util.future.GridFutureAdapter.onDone(GridFutureAdapter.java:334)
    at org.apache.ignite.testframework.GridTestUtils$5.run(GridTestUtils.java:735)
    at org.apache.ignite.testframework.GridTestUtils$7.call(GridTestUtils.java:1026)
    at org.apache.ignite.testframework.GridTestThread.run(GridTestThread.java:86)
Caused by: java.lang.InterruptedException
    at java.lang.Object.wait(Native Method)
    at java.lang.Thread.join(Thread.java:1281)
    at java.lang.Thread.join(Thread.java:1355)
    at org.apache.ignite.testframework.GridTestUtils.runMultiThreaded(GridTestUtils.java:678)
    at org.apache.ignite.testframework.GridTestUtils$3.call(GridTestUtils.java:619)
    at org.apache.ignite.testframework.GridTestUtils$3.call(GridTestUtils.java:617)
    at org.apache.ignite.testframework.GridTestUtils$5.run(GridTestUtils.java:730)
    ... 2 more
[23:29:21,944][ERROR][main][root] Finished interrupting threads, but some threads are still alive [size=1, threads=[Thread[topology-change-thread-3,5,]]]
***** Text was not loaded fully because its' size exceeds 2 MB, see full log for the whole text *****
{noformat}

> GridCacheAbstractDataStructuresFailoverSelfTest.testQueueConstant* fail sometimes
> ---------------------------------------------------------------------------------
>
>                 Key: IGNITE-2372
>                 URL: https://issues.apache.org/jira/browse/IGNITE-2372
>             Project: Ignite
>          Issue Type: Bug
>            Reporter: Artem Shutak
>            Assignee: Denis Magda
>             Fix For: 1.6
>
>
> The following tests fail on TC sometimes:
> - GridCachePartitionedDataStructuresFailoverSelfTest.testQueueConstantMultipleTopologyChange	
> - GridCachePartitionedOffheapDataStructuresFailoverSelfTest.testQueueConstantMultipleTopologyChange	
> TC build:
> http://ci.ignite.apache.org/viewLog.html?buildId=95910&tab=buildResultsDiv&buildTypeId=IgniteTests_IgniteDataStrucutures
> Test testQueueConstantMultipleTopologyChange failed on my laptop too.



--
This message was sent by Atlassian JIRA
(v6.3.4#6332)