[jira] [Assigned] (IGNITE-9227) CacheExchangeMergeTest.testStartCacheOnJoinAndCoordinatorFailed1() hangs sometimes

["Pavel Kovalenko (JIRA)" <ji...@apache.org>]

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

Pavel Kovalenko reassigned IGNITE-9227:
---------------------------------------

    Assignee: Pavel Kovalenko

> CacheExchangeMergeTest.testStartCacheOnJoinAndCoordinatorFailed1() hangs sometimes
> ----------------------------------------------------------------------------------
>
>                 Key: IGNITE-9227
>                 URL: https://issues.apache.org/jira/browse/IGNITE-9227
>             Project: Ignite
>          Issue Type: Bug
>            Reporter: Ilya Lantukh
>            Assignee: Pavel Kovalenko
>            Priority: Major
>             Fix For: 2.7
>
>
> Probability is < 1%. To me it looks like a problem with ExchangeLatch when coordinator is changed:
> {code}
> [08:59:07] :	 [Step 3/4] Thread [name="exchange-worker-#26174%distributed.CacheExchangeMergeTest1%", id=34956, state=TIMED_WAITING, blockCnt=10, waitCnt=20]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:338)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.future.GridFutureAdapter.get0(GridFutureAdapter.java:217)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.future.GridFutureAdapter.get(GridFutureAdapter.java:159)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.processors.cache.distributed.dht.preloader.latch.ExchangeLatchManager$CompletableLatch.await(ExchangeLatchManager.java:657)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.processors.cache.distributed.dht.preloader.GridDhtPartitionsExchangeFuture.waitPartitionRelease(GridDhtPartitionsExchangeFuture.java:1450)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.processors.cache.distributed.dht.preloader.GridDhtPartitionsExchangeFuture.distributedExchange(GridDhtPartitionsExchangeFuture.java:1208)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.processors.cache.distributed.dht.preloader.GridDhtPartitionsExchangeFuture.init(GridDhtPartitionsExchangeFuture.java:749)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.processors.cache.GridCachePartitionExchangeManager$ExchangeWorker.body0(GridCachePartitionExchangeManager.java:2497)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.processors.cache.GridCachePartitionExchangeManager$ExchangeWorker.body(GridCachePartitionExchangeManager.java:2377)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> {code}
> Full thread dump:
> {code}
> [08:59:07] :	 [Step 3/4] [05:59:07] (wrn) Thread dump at 2018/08/07 05:59:07 UTC
> [08:59:07] :	 [Step 3/4] Thread [name="sys-#26318%distributed.CacheExchangeMergeTest1%", id=35122, state=TIMED_WAITING, blockCnt=0, waitCnt=1]
> [08:59:07] :	 [Step 3/4]     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@1115433e, ownerName=null, ownerId=-1]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.LinkedBlockingQueue.poll(LinkedBlockingQueue.java:467)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="sys-#26317%distributed.CacheExchangeMergeTest1%", id=35121, state=TIMED_WAITING, blockCnt=0, waitCnt=1]
> [08:59:07] :	 [Step 3/4]     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@1115433e, ownerName=null, ownerId=-1]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.LinkedBlockingQueue.poll(LinkedBlockingQueue.java:467)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="sys-#26316%distributed.CacheExchangeMergeTest1%", id=35120, state=TIMED_WAITING, blockCnt=0, waitCnt=1]
> [08:59:07] :	 [Step 3/4]     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@1115433e, ownerName=null, ownerId=-1]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.LinkedBlockingQueue.poll(LinkedBlockingQueue.java:467)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="sys-#26315%distributed.CacheExchangeMergeTest1%", id=35119, state=TIMED_WAITING, blockCnt=0, waitCnt=1]
> [08:59:07] :	 [Step 3/4]     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@1115433e, ownerName=null, ownerId=-1]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.LinkedBlockingQueue.poll(LinkedBlockingQueue.java:467)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="sys-#26314%distributed.CacheExchangeMergeTest1%", id=35118, state=TIMED_WAITING, blockCnt=0, waitCnt=1]
> [08:59:07] :	 [Step 3/4]     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@1115433e, ownerName=null, ownerId=-1]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.LinkedBlockingQueue.poll(LinkedBlockingQueue.java:467)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="sys-#26313%distributed.CacheExchangeMergeTest1%", id=35117, state=TIMED_WAITING, blockCnt=0, waitCnt=1]
> [08:59:07] :	 [Step 3/4]     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@1115433e, ownerName=null, ownerId=-1]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.LinkedBlockingQueue.poll(LinkedBlockingQueue.java:467)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="tcp-disco-sock-reader-#4329%distributed.CacheExchangeMergeTest1%", id=35072, state=RUNNABLE, blockCnt=1, waitCnt=0]
> [08:59:07] :	 [Step 3/4]         at java.net.SocketInputStream.socketRead0(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.net.SocketInputStream.socketRead(SocketInputStream.java:116)
> [08:59:07] :	 [Step 3/4]         at java.net.SocketInputStream.read(SocketInputStream.java:171)
> [08:59:07] :	 [Step 3/4]         at java.net.SocketInputStream.read(SocketInputStream.java:141)
> [08:59:07] :	 [Step 3/4]         at java.io.BufferedInputStream.fill(BufferedInputStream.java:246)
> [08:59:07] :	 [Step 3/4]         at java.io.BufferedInputStream.read1(BufferedInputStream.java:286)
> [08:59:07] :	 [Step 3/4]         at java.io.BufferedInputStream.read(BufferedInputStream.java:345)
> [08:59:07] :	 [Step 3/4]         - locked java.io.BufferedInputStream@4fdca00a
> [08:59:07] :	 [Step 3/4]         at o.a.i.marshaller.jdk.JdkMarshallerInputStreamWrapper.read(JdkMarshallerInputStreamWrapper.java:53)
> [08:59:07] :	 [Step 3/4]         at java.io.ObjectInputStream$PeekInputStream.read(ObjectInputStream.java:2661)
> [08:59:07] :	 [Step 3/4]         at java.io.ObjectInputStream$PeekInputStream.readFully(ObjectInputStream.java:2677)
> [08:59:07] :	 [Step 3/4]         at java.io.ObjectInputStream$BlockDataInputStream.readShort(ObjectInputStream.java:3154)
> [08:59:07] :	 [Step 3/4]         at java.io.ObjectInputStream.readStreamHeader(ObjectInputStream.java:862)
> [08:59:07] :	 [Step 3/4]         at java.io.ObjectInputStream.<init>(ObjectInputStream.java:358)
> [08:59:07] :	 [Step 3/4]         at o.a.i.marshaller.jdk.JdkMarshallerObjectInputStream.<init>(JdkMarshallerObjectInputStream.java:43)
> [08:59:07] :	 [Step 3/4]         at o.a.i.marshaller.jdk.JdkMarshaller.unmarshal0(JdkMarshaller.java:137)
> [08:59:07] :	 [Step 3/4]         at o.a.i.marshaller.AbstractNodeNameAwareMarshaller.unmarshal(AbstractNodeNameAwareMarshaller.java:94)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.IgniteUtils.unmarshal(IgniteUtils.java:9959)
> [08:59:07] :	 [Step 3/4]         at o.a.i.spi.discovery.tcp.ServerImpl$SocketReader.body(ServerImpl.java:6220)
> [08:59:07] :	 [Step 3/4]         at o.a.i.spi.IgniteSpiThread.run(IgniteSpiThread.java:62)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="ttl-cleanup-worker-#26266%distributed.CacheExchangeMergeTest3%", id=35069, state=TIMED_WAITING, blockCnt=0, waitCnt=600]
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.sleep(Native Method)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.IgniteUtils.sleep(IgniteUtils.java:7678)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.processors.cache.GridCacheSharedTtlCleanupManager$CleanupWorker.body(GridCacheSharedTtlCleanupManager.java:145)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="exchange-worker-#26265%distributed.CacheExchangeMergeTest3%", id=35068, state=TIMED_WAITING, blockCnt=9, waitCnt=17]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:338)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.future.GridFutureAdapter.get0(GridFutureAdapter.java:217)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.future.GridFutureAdapter.get(GridFutureAdapter.java:159)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.processors.cache.GridCachePartitionExchangeManager$ExchangeWorker.body0(GridCachePartitionExchangeManager.java:2518)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.processors.cache.GridCachePartitionExchangeManager$ExchangeWorker.body(GridCachePartitionExchangeManager.java:2377)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="disco-event-worker-#26264%distributed.CacheExchangeMergeTest3%", id=35067, state=WAITING, blockCnt=3, waitCnt=612]
> [08:59:07] :	 [Step 3/4]     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@513b52af, ownerName=null, ownerId=-1]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.managers.discovery.GridDiscoveryManager$DiscoveryWorker.body0(GridDiscoveryManager.java:2767)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.managers.discovery.GridDiscoveryManager$DiscoveryWorker.body(GridDiscoveryManager.java:2741)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="tcp-disco-ip-finder-cleaner-#4328%distributed.CacheExchangeMergeTest3%", id=35066, state=TIMED_WAITING, blockCnt=0, waitCnt=5]
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.sleep(Native Method)
> [08:59:07] :	 [Step 3/4]         at o.a.i.spi.discovery.tcp.ServerImpl$IpFinderCleaner.body(ServerImpl.java:1958)
> [08:59:07] :	 [Step 3/4]         at o.a.i.spi.IgniteSpiThread.run(IgniteSpiThread.java:62)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="tcp-disco-sock-reader-#4326%distributed.CacheExchangeMergeTest3%", id=35064, state=RUNNABLE, blockCnt=0, waitCnt=0]
> [08:59:07] :	 [Step 3/4]         at java.net.SocketInputStream.socketRead0(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.net.SocketInputStream.socketRead(SocketInputStream.java:116)
> [08:59:07] :	 [Step 3/4]         at java.net.SocketInputStream.read(SocketInputStream.java:171)
> [08:59:07] :	 [Step 3/4]         at java.net.SocketInputStream.read(SocketInputStream.java:141)
> [08:59:07] :	 [Step 3/4]         at java.io.BufferedInputStream.fill(BufferedInputStream.java:246)
> [08:59:07] :	 [Step 3/4]         at java.io.BufferedInputStream.read1(BufferedInputStream.java:286)
> [08:59:07] :	 [Step 3/4]         at java.io.BufferedInputStream.read(BufferedInputStream.java:345)
> [08:59:07] :	 [Step 3/4]         - locked java.io.BufferedInputStream@5a8c93
> [08:59:07] :	 [Step 3/4]         at o.a.i.marshaller.jdk.JdkMarshallerInputStreamWrapper.read(JdkMarshallerInputStreamWrapper.java:53)
> [08:59:07] :	 [Step 3/4]         at java.io.ObjectInputStream$PeekInputStream.read(ObjectInputStream.java:2661)
> [08:59:07] :	 [Step 3/4]         at java.io.ObjectInputStream$PeekInputStream.readFully(ObjectInputStream.java:2677)
> [08:59:07] :	 [Step 3/4]         at java.io.ObjectInputStream$BlockDataInputStream.readShort(ObjectInputStream.java:3154)
> [08:59:07] :	 [Step 3/4]         at java.io.ObjectInputStream.readStreamHeader(ObjectInputStream.java:862)
> [08:59:07] :	 [Step 3/4]         at java.io.ObjectInputStream.<init>(ObjectInputStream.java:358)
> [08:59:07] :	 [Step 3/4]         at o.a.i.marshaller.jdk.JdkMarshallerObjectInputStream.<init>(JdkMarshallerObjectInputStream.java:43)
> [08:59:07] :	 [Step 3/4]         at o.a.i.marshaller.jdk.JdkMarshaller.unmarshal0(JdkMarshaller.java:137)
> [08:59:07] :	 [Step 3/4]         at o.a.i.marshaller.AbstractNodeNameAwareMarshaller.unmarshal(AbstractNodeNameAwareMarshaller.java:94)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.IgniteUtils.unmarshal(IgniteUtils.java:9959)
> [08:59:07] :	 [Step 3/4]         at o.a.i.spi.discovery.tcp.ServerImpl$SocketReader.body(ServerImpl.java:6220)
> [08:59:07] :	 [Step 3/4]         at o.a.i.spi.IgniteSpiThread.run(IgniteSpiThread.java:62)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="tcp-disco-srvr-#4324%distributed.CacheExchangeMergeTest3%", id=35061, state=RUNNABLE, blockCnt=1, waitCnt=0]
> [08:59:07] :	 [Step 3/4]         at java.net.PlainSocketImpl.socketAccept(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.net.AbstractPlainSocketImpl.accept(AbstractPlainSocketImpl.java:409)
> [08:59:07] :	 [Step 3/4]         at java.net.ServerSocket.implAccept(ServerSocket.java:545)
> [08:59:07] :	 [Step 3/4]         at java.net.ServerSocket.accept(ServerSocket.java:513)
> [08:59:07] :	 [Step 3/4]         at o.a.i.spi.discovery.tcp.ServerImpl$TcpServer.body(ServerImpl.java:5825)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at o.a.i.spi.discovery.tcp.ServerImpl$TcpServerThread.body(ServerImpl.java:5747)
> [08:59:07] :	 [Step 3/4]         at o.a.i.spi.IgniteSpiThread.run(IgniteSpiThread.java:62)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="tcp-disco-msg-worker-#4323%distributed.CacheExchangeMergeTest3%", id=35059, state=TIMED_WAITING, blockCnt=2, waitCnt=30281]
> [08:59:07] :	 [Step 3/4]     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@3bed3315, ownerName=null, ownerId=-1]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.LinkedBlockingDeque.pollFirst(LinkedBlockingDeque.java:522)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.LinkedBlockingDeque.poll(LinkedBlockingDeque.java:684)
> [08:59:07] :	 [Step 3/4]         at o.a.i.spi.discovery.tcp.ServerImpl$MessageWorker.body(ServerImpl.java:7110)
> [08:59:07] :	 [Step 3/4]         at o.a.i.spi.discovery.tcp.ServerImpl$RingMessageWorker.body(ServerImpl.java:2688)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at o.a.i.spi.discovery.tcp.ServerImpl$MessageWorkerThread.body(ServerImpl.java:7059)
> [08:59:07] :	 [Step 3/4]         at o.a.i.spi.IgniteSpiThread.run(IgniteSpiThread.java:62)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="grid-data-loader-flusher-#26261%distributed.CacheExchangeMergeTest3%", id=35058, state=WAITING, blockCnt=0, waitCnt=2]
> [08:59:07] :	 [Step 3/4]     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@119b0892, ownerName=null, ownerId=-1]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.DelayQueue.take(DelayQueue.java:211)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.processors.datastreamer.DataStreamProcessor$2.body(DataStreamProcessor.java:109)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="grid-nio-worker-client-listener-3-#26258%distributed.CacheExchangeMergeTest3%", id=35055, state=RUNNABLE, blockCnt=0, waitCnt=0]
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:93)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86)
> [08:59:07] :	 [Step 3/4]         - locked o.a.i.i.util.nio.SelectedSelectionKeySet@1a3e8e24
> [08:59:07] :	 [Step 3/4]         - locked java.util.Collections$UnmodifiableSet@4ed4a7e4
> [08:59:07] :	 [Step 3/4]         - locked sun.nio.ch.EPollSelectorImpl@1c7350b0
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.nio.GridNioServer$AbstractNioClientWorker.bodyInternal(GridNioServer.java:2133)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.nio.GridNioServer$AbstractNioClientWorker.body(GridNioServer.java:1792)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="grid-nio-worker-client-listener-2-#26257%distributed.CacheExchangeMergeTest3%", id=35054, state=RUNNABLE, blockCnt=0, waitCnt=0]
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:93)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86)
> [08:59:07] :	 [Step 3/4]         - locked o.a.i.i.util.nio.SelectedSelectionKeySet@55651434
> [08:59:07] :	 [Step 3/4]         - locked java.util.Collections$UnmodifiableSet@3bc4ef12
> [08:59:07] :	 [Step 3/4]         - locked sun.nio.ch.EPollSelectorImpl@50448409
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.nio.GridNioServer$AbstractNioClientWorker.bodyInternal(GridNioServer.java:2133)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.nio.GridNioServer$AbstractNioClientWorker.body(GridNioServer.java:1792)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="grid-nio-worker-client-listener-1-#26256%distributed.CacheExchangeMergeTest3%", id=35053, state=RUNNABLE, blockCnt=0, waitCnt=0]
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:93)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86)
> [08:59:07] :	 [Step 3/4]         - locked o.a.i.i.util.nio.SelectedSelectionKeySet@5634a861
> [08:59:07] :	 [Step 3/4]         - locked java.util.Collections$UnmodifiableSet@2b0dc227
> [08:59:07] :	 [Step 3/4]         - locked sun.nio.ch.EPollSelectorImpl@37ad042b
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.nio.GridNioServer$AbstractNioClientWorker.bodyInternal(GridNioServer.java:2133)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.nio.GridNioServer$AbstractNioClientWorker.body(GridNioServer.java:1792)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="grid-nio-worker-client-listener-0-#26255%distributed.CacheExchangeMergeTest3%", id=35052, state=RUNNABLE, blockCnt=0, waitCnt=0]
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:93)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86)
> [08:59:07] :	 [Step 3/4]         - locked o.a.i.i.util.nio.SelectedSelectionKeySet@43bdaa1b
> [08:59:07] :	 [Step 3/4]         - locked java.util.Collections$UnmodifiableSet@30b975ad
> [08:59:07] :	 [Step 3/4]         - locked sun.nio.ch.EPollSelectorImpl@75e09567
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.nio.GridNioServer$AbstractNioClientWorker.bodyInternal(GridNioServer.java:2133)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.nio.GridNioServer$AbstractNioClientWorker.body(GridNioServer.java:1792)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="nio-acceptor-client-listener-#26259%distributed.CacheExchangeMergeTest3%", id=35056, state=RUNNABLE, blockCnt=0, waitCnt=0]
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:93)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86)
> [08:59:07] :	 [Step 3/4]         - locked sun.nio.ch.Util$3@2a334bac
> [08:59:07] :	 [Step 3/4]         - locked java.util.Collections$UnmodifiableSet@470d183
> [08:59:07] :	 [Step 3/4]         - locked sun.nio.ch.EPollSelectorImpl@7f9fc8bd
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.nio.GridNioServer$GridNioAcceptWorker.accept(GridNioServer.java:2907)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.nio.GridNioServer$GridNioAcceptWorker.body(GridNioServer.java:2859)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="grid-sharedfs-timeout-worker-#4322%distributed.CacheExchangeMergeTest3%", id=35051, state=TIMED_WAITING, blockCnt=0, waitCnt=60]
> [08:59:07] :	 [Step 3/4]     Lock [object=java.lang.Object@ea52184, ownerName=null, ownerId=-1]
> [08:59:07] :	 [Step 3/4]         at java.lang.Object.wait(Native Method)
> [08:59:07] :	 [Step 3/4]         at o.a.i.spi.checkpoint.sharedfs.SharedFsTimeoutTask.body(SharedFsTimeoutTask.java:99)
> [08:59:07] :	 [Step 3/4]         at o.a.i.spi.IgniteSpiThread.run(IgniteSpiThread.java:62)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="tcp-comm-worker-#4321%distributed.CacheExchangeMergeTest3%", id=35050, state=TIMED_WAITING, blockCnt=0, waitCnt=2]
> [08:59:07] :	 [Step 3/4]     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@3e7b65d7, ownerName=null, ownerId=-1]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.LinkedBlockingQueue.poll(LinkedBlockingQueue.java:467)
> [08:59:07] :	 [Step 3/4]         at o.a.i.spi.communication.tcp.TcpCommunicationSpi$CommunicationWorker.body(TcpCommunicationSpi.java:4257)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at o.a.i.spi.communication.tcp.TcpCommunicationSpi$5.body(TcpCommunicationSpi.java:2217)
> [08:59:07] :	 [Step 3/4]         at o.a.i.spi.IgniteSpiThread.run(IgniteSpiThread.java:62)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="grid-nio-worker-tcp-comm-3-#26253%distributed.CacheExchangeMergeTest3%", id=35048, state=RUNNABLE, blockCnt=2, waitCnt=0]
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:93)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86)
> [08:59:07] :	 [Step 3/4]         - locked o.a.i.i.util.nio.SelectedSelectionKeySet@3c854752
> [08:59:07] :	 [Step 3/4]         - locked java.util.Collections$UnmodifiableSet@3ddeaa5f
> [08:59:07] :	 [Step 3/4]         - locked sun.nio.ch.EPollSelectorImpl@1a500561
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.nio.GridNioServer$AbstractNioClientWorker.bodyInternal(GridNioServer.java:2133)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.nio.GridNioServer$AbstractNioClientWorker.body(GridNioServer.java:1792)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="grid-nio-worker-tcp-comm-2-#26252%distributed.CacheExchangeMergeTest3%", id=35047, state=RUNNABLE, blockCnt=0, waitCnt=0]
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:93)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86)
> [08:59:07] :	 [Step 3/4]         - locked o.a.i.i.util.nio.SelectedSelectionKeySet@7f608e21
> [08:59:07] :	 [Step 3/4]         - locked java.util.Collections$UnmodifiableSet@6949e948
> [08:59:07] :	 [Step 3/4]         - locked sun.nio.ch.EPollSelectorImpl@210d2a6c
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.nio.GridNioServer$AbstractNioClientWorker.bodyInternal(GridNioServer.java:2133)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.nio.GridNioServer$AbstractNioClientWorker.body(GridNioServer.java:1792)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="grid-nio-worker-tcp-comm-1-#26251%distributed.CacheExchangeMergeTest3%", id=35046, state=RUNNABLE, blockCnt=1, waitCnt=0]
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:93)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86)
> [08:59:07] :	 [Step 3/4]         - locked o.a.i.i.util.nio.SelectedSelectionKeySet@3bec5821
> [08:59:07] :	 [Step 3/4]         - locked java.util.Collections$UnmodifiableSet@4086d8fb
> [08:59:07] :	 [Step 3/4]         - locked sun.nio.ch.EPollSelectorImpl@66236a0a
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.nio.GridNioServer$AbstractNioClientWorker.bodyInternal(GridNioServer.java:2133)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.nio.GridNioServer$AbstractNioClientWorker.body(GridNioServer.java:1792)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="grid-nio-worker-tcp-comm-0-#26250%distributed.CacheExchangeMergeTest3%", id=35045, state=RUNNABLE, blockCnt=1, waitCnt=0]
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:93)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86)
> [08:59:07] :	 [Step 3/4]         - locked o.a.i.i.util.nio.SelectedSelectionKeySet@2e3572e8
> [08:59:07] :	 [Step 3/4]         - locked java.util.Collections$UnmodifiableSet@77c10a5f
> [08:59:07] :	 [Step 3/4]         - locked sun.nio.ch.EPollSelectorImpl@176555c
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.nio.GridNioServer$AbstractNioClientWorker.bodyInternal(GridNioServer.java:2133)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.nio.GridNioServer$AbstractNioClientWorker.body(GridNioServer.java:1792)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="nio-acceptor-tcp-comm-#26254%distributed.CacheExchangeMergeTest3%", id=35049, state=RUNNABLE, blockCnt=0, waitCnt=0]
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:93)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86)
> [08:59:07] :	 [Step 3/4]         - locked sun.nio.ch.Util$3@7c781c42
> [08:59:07] :	 [Step 3/4]         - locked java.util.Collections$UnmodifiableSet@795f8317
> [08:59:07] :	 [Step 3/4]         - locked sun.nio.ch.EPollSelectorImpl@633fd91
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.nio.GridNioServer$GridNioAcceptWorker.accept(GridNioServer.java:2907)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.nio.GridNioServer$GridNioAcceptWorker.body(GridNioServer.java:2859)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="grid-timeout-worker-#26249%distributed.CacheExchangeMergeTest3%", id=35044, state=TIMED_WAITING, blockCnt=2, waitCnt=854]
> [08:59:07] :	 [Step 3/4]     Lock [object=java.lang.Object@355c94be, ownerName=null, ownerId=-1]
> [08:59:07] :	 [Step 3/4]         at java.lang.Object.wait(Native Method)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.processors.timeout.GridTimeoutProcessor$TimeoutWorker.body(GridTimeoutProcessor.java:203)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="jvm-pause-detector-worker", id=35034, state=TIMED_WAITING, blockCnt=0, waitCnt=5989]
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.sleep(Native Method)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.LongJVMPauseDetector$1.run(LongJVMPauseDetector.java:100)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="data-streamer-stripe-7-#26238%distributed.CacheExchangeMergeTest3%", id=35032, state=WAITING, blockCnt=0, waitCnt=1]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:304)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$StripeConcurrentQueue.take(StripedExecutor.java:658)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$Stripe.body(StripedExecutor.java:490)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="data-streamer-stripe-6-#26237%distributed.CacheExchangeMergeTest3%", id=35031, state=WAITING, blockCnt=0, waitCnt=1]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:304)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$StripeConcurrentQueue.take(StripedExecutor.java:658)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$Stripe.body(StripedExecutor.java:490)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="data-streamer-stripe-5-#26236%distributed.CacheExchangeMergeTest3%", id=35030, state=WAITING, blockCnt=0, waitCnt=1]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:304)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$StripeConcurrentQueue.take(StripedExecutor.java:658)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$Stripe.body(StripedExecutor.java:490)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="data-streamer-stripe-4-#26235%distributed.CacheExchangeMergeTest3%", id=35029, state=WAITING, blockCnt=0, waitCnt=1]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:304)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$StripeConcurrentQueue.take(StripedExecutor.java:658)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$Stripe.body(StripedExecutor.java:490)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="data-streamer-stripe-3-#26234%distributed.CacheExchangeMergeTest3%", id=35028, state=WAITING, blockCnt=0, waitCnt=1]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:304)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$StripeConcurrentQueue.take(StripedExecutor.java:658)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$Stripe.body(StripedExecutor.java:490)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="data-streamer-stripe-2-#26233%distributed.CacheExchangeMergeTest3%", id=35027, state=WAITING, blockCnt=0, waitCnt=1]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:304)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$StripeConcurrentQueue.take(StripedExecutor.java:658)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$Stripe.body(StripedExecutor.java:490)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="data-streamer-stripe-1-#26232%distributed.CacheExchangeMergeTest3%", id=35026, state=WAITING, blockCnt=0, waitCnt=1]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:304)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$StripeConcurrentQueue.take(StripedExecutor.java:658)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$Stripe.body(StripedExecutor.java:490)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="data-streamer-stripe-0-#26231%distributed.CacheExchangeMergeTest3%", id=35025, state=WAITING, blockCnt=0, waitCnt=1]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:304)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$StripeConcurrentQueue.take(StripedExecutor.java:658)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$Stripe.body(StripedExecutor.java:490)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="sys-stripe-7-#26230%distributed.CacheExchangeMergeTest3%", id=35024, state=WAITING, blockCnt=0, waitCnt=1]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:304)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$StripeConcurrentQueue.take(StripedExecutor.java:658)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$Stripe.body(StripedExecutor.java:490)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="sys-stripe-6-#26229%distributed.CacheExchangeMergeTest3%", id=35023, state=WAITING, blockCnt=0, waitCnt=1]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:304)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$StripeConcurrentQueue.take(StripedExecutor.java:658)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$Stripe.body(StripedExecutor.java:490)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="sys-stripe-5-#26228%distributed.CacheExchangeMergeTest3%", id=35022, state=WAITING, blockCnt=0, waitCnt=1]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:304)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$StripeConcurrentQueue.take(StripedExecutor.java:658)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$Stripe.body(StripedExecutor.java:490)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="sys-stripe-4-#26227%distributed.CacheExchangeMergeTest3%", id=35021, state=WAITING, blockCnt=0, waitCnt=2]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:304)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$StripeConcurrentQueue.take(StripedExecutor.java:658)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$Stripe.body(StripedExecutor.java:490)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="sys-stripe-3-#26226%distributed.CacheExchangeMergeTest3%", id=35020, state=WAITING, blockCnt=0, waitCnt=2]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:304)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$StripeConcurrentQueue.take(StripedExecutor.java:658)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$Stripe.body(StripedExecutor.java:490)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="sys-stripe-2-#26225%distributed.CacheExchangeMergeTest3%", id=35019, state=WAITING, blockCnt=0, waitCnt=2]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:304)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$StripeConcurrentQueue.take(StripedExecutor.java:658)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$Stripe.body(StripedExecutor.java:490)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="sys-stripe-1-#26224%distributed.CacheExchangeMergeTest3%", id=35018, state=WAITING, blockCnt=0, waitCnt=2]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:304)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$StripeConcurrentQueue.take(StripedExecutor.java:658)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$Stripe.body(StripedExecutor.java:490)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="sys-stripe-0-#26223%distributed.CacheExchangeMergeTest3%", id=35017, state=WAITING, blockCnt=0, waitCnt=2]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:304)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$StripeConcurrentQueue.take(StripedExecutor.java:658)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$Stripe.body(StripedExecutor.java:490)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="ttl-cleanup-worker-#26222%distributed.CacheExchangeMergeTest2%", id=35016, state=TIMED_WAITING, blockCnt=0, waitCnt=600]
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.sleep(Native Method)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.IgniteUtils.sleep(IgniteUtils.java:7678)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.processors.cache.GridCacheSharedTtlCleanupManager$CleanupWorker.body(GridCacheSharedTtlCleanupManager.java:145)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="start-node-3-1", id=35015, state=TIMED_WAITING, blockCnt=7, waitCnt=5]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:338)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.future.GridFutureAdapter.get0(GridFutureAdapter.java:217)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.future.GridFutureAdapter.get(GridFutureAdapter.java:159)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.future.GridFutureAdapter.get(GridFutureAdapter.java:151)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.processors.cache.GridCachePartitionExchangeManager.onKernalStart(GridCachePartitionExchangeManager.java:652)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.processors.cache.GridCacheProcessor.onKernalStart(GridCacheProcessor.java:856)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.IgniteKernal.start(IgniteKernal.java:1108)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.IgnitionEx$IgniteNamedInstance.start0(IgnitionEx.java:2020)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.IgnitionEx$IgniteNamedInstance.start(IgnitionEx.java:1725)
> [08:59:07] :	 [Step 3/4]         - locked o.a.i.i.IgnitionEx$IgniteNamedInstance@97a145b
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.IgnitionEx.start0(IgnitionEx.java:1153)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.IgnitionEx.start(IgnitionEx.java:651)
> [08:59:07] :	 [Step 3/4]         at o.a.i.testframework.junits.GridAbstractTest.startGrid(GridAbstractTest.java:920)
> [08:59:07] :	 [Step 3/4]         at o.a.i.testframework.junits.GridAbstractTest.startGrid(GridAbstractTest.java:858)
> [08:59:07] :	 [Step 3/4]         at o.a.i.testframework.junits.GridAbstractTest.startGrid(GridAbstractTest.java:846)
> [08:59:07] :	 [Step 3/4]         at o.a.i.testframework.junits.GridAbstractTest.startGrid(GridAbstractTest.java:812)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.processors.cache.distributed.CacheExchangeMergeTest.access$2800(CacheExchangeMergeTest.java:91)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.processors.cache.distributed.CacheExchangeMergeTest$19.call(CacheExchangeMergeTest.java:1528)
> [08:59:07] :	 [Step 3/4]         at o.a.i.testframework.GridTestUtils.lambda$runAsync$2(GridTestUtils.java:956)
> [08:59:07] :	 [Step 3/4]         at o.a.i.testframework.GridTestUtils$$Lambda$48/2035225043.run(Unknown Source)
> [08:59:07] :	 [Step 3/4]         at o.a.i.testframework.GridTestUtils$6.call(GridTestUtils.java:1254)
> [08:59:07] :	 [Step 3/4]         at o.a.i.testframework.GridTestThread.run(GridTestThread.java:86)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="exchange-worker-#26220%distributed.CacheExchangeMergeTest2%", id=35013, state=TIMED_WAITING, blockCnt=6, waitCnt=17]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:338)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.future.GridFutureAdapter.get0(GridFutureAdapter.java:217)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.future.GridFutureAdapter.get(GridFutureAdapter.java:159)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.processors.cache.GridCachePartitionExchangeManager$ExchangeWorker.body0(GridCachePartitionExchangeManager.java:2518)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.processors.cache.GridCachePartitionExchangeManager$ExchangeWorker.body(GridCachePartitionExchangeManager.java:2377)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="ttl-cleanup-worker-#26219%distributed.CacheExchangeMergeTest1%", id=35012, state=TIMED_WAITING, blockCnt=0, waitCnt=600]
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.sleep(Native Method)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.IgniteUtils.sleep(IgniteUtils.java:7678)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.processors.cache.GridCacheSharedTtlCleanupManager$CleanupWorker.body(GridCacheSharedTtlCleanupManager.java:145)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="disco-event-worker-#26218%distributed.CacheExchangeMergeTest2%", id=35011, state=WAITING, blockCnt=6, waitCnt=615]
> [08:59:07] :	 [Step 3/4]     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@c386958, ownerName=null, ownerId=-1]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.managers.discovery.GridDiscoveryManager$DiscoveryWorker.body0(GridDiscoveryManager.java:2767)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.managers.discovery.GridDiscoveryManager$DiscoveryWorker.body(GridDiscoveryManager.java:2741)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="tcp-disco-ip-finder-cleaner-#4320%distributed.CacheExchangeMergeTest2%", id=35010, state=TIMED_WAITING, blockCnt=0, waitCnt=5]
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.sleep(Native Method)
> [08:59:07] :	 [Step 3/4]         at o.a.i.spi.discovery.tcp.ServerImpl$IpFinderCleaner.body(ServerImpl.java:1958)
> [08:59:07] :	 [Step 3/4]         at o.a.i.spi.IgniteSpiThread.run(IgniteSpiThread.java:62)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="tcp-disco-sock-reader-#4318%distributed.CacheExchangeMergeTest2%", id=35008, state=RUNNABLE, blockCnt=0, waitCnt=0]
> [08:59:07] :	 [Step 3/4]         at java.net.SocketInputStream.socketRead0(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.net.SocketInputStream.socketRead(SocketInputStream.java:116)
> [08:59:07] :	 [Step 3/4]         at java.net.SocketInputStream.read(SocketInputStream.java:171)
> [08:59:07] :	 [Step 3/4]         at java.net.SocketInputStream.read(SocketInputStream.java:141)
> [08:59:07] :	 [Step 3/4]         at java.io.BufferedInputStream.fill(BufferedInputStream.java:246)
> [08:59:07] :	 [Step 3/4]         at java.io.BufferedInputStream.read1(BufferedInputStream.java:286)
> [08:59:07] :	 [Step 3/4]         at java.io.BufferedInputStream.read(BufferedInputStream.java:345)
> [08:59:07] :	 [Step 3/4]         - locked java.io.BufferedInputStream@157ec23b
> [08:59:07] :	 [Step 3/4]         at o.a.i.marshaller.jdk.JdkMarshallerInputStreamWrapper.read(JdkMarshallerInputStreamWrapper.java:53)
> [08:59:07] :	 [Step 3/4]         at java.io.ObjectInputStream$PeekInputStream.read(ObjectInputStream.java:2661)
> [08:59:07] :	 [Step 3/4]         at java.io.ObjectInputStream$PeekInputStream.readFully(ObjectInputStream.java:2677)
> [08:59:07] :	 [Step 3/4]         at java.io.ObjectInputStream$BlockDataInputStream.readShort(ObjectInputStream.java:3154)
> [08:59:07] :	 [Step 3/4]         at java.io.ObjectInputStream.readStreamHeader(ObjectInputStream.java:862)
> [08:59:07] :	 [Step 3/4]         at java.io.ObjectInputStream.<init>(ObjectInputStream.java:358)
> [08:59:07] :	 [Step 3/4]         at o.a.i.marshaller.jdk.JdkMarshallerObjectInputStream.<init>(JdkMarshallerObjectInputStream.java:43)
> [08:59:07] :	 [Step 3/4]         at o.a.i.marshaller.jdk.JdkMarshaller.unmarshal0(JdkMarshaller.java:137)
> [08:59:07] :	 [Step 3/4]         at o.a.i.marshaller.AbstractNodeNameAwareMarshaller.unmarshal(AbstractNodeNameAwareMarshaller.java:94)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.IgniteUtils.unmarshal(IgniteUtils.java:9959)
> [08:59:07] :	 [Step 3/4]         at o.a.i.spi.discovery.tcp.ServerImpl$SocketReader.body(ServerImpl.java:6220)
> [08:59:07] :	 [Step 3/4]         at o.a.i.spi.IgniteSpiThread.run(IgniteSpiThread.java:62)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="tcp-disco-srvr-#4316%distributed.CacheExchangeMergeTest2%", id=35006, state=RUNNABLE, blockCnt=1, waitCnt=0]
> [08:59:07] :	 [Step 3/4]         at java.net.PlainSocketImpl.socketAccept(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.net.AbstractPlainSocketImpl.accept(AbstractPlainSocketImpl.java:409)
> [08:59:07] :	 [Step 3/4]         at java.net.ServerSocket.implAccept(ServerSocket.java:545)
> [08:59:07] :	 [Step 3/4]         at java.net.ServerSocket.accept(ServerSocket.java:513)
> [08:59:07] :	 [Step 3/4]         at o.a.i.spi.discovery.tcp.ServerImpl$TcpServer.body(ServerImpl.java:5825)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at o.a.i.spi.discovery.tcp.ServerImpl$TcpServerThread.body(ServerImpl.java:5747)
> [08:59:07] :	 [Step 3/4]         at o.a.i.spi.IgniteSpiThread.run(IgniteSpiThread.java:62)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="tcp-disco-msg-worker-#4315%distributed.CacheExchangeMergeTest2%", id=35005, state=TIMED_WAITING, blockCnt=0, waitCnt=30288]
> [08:59:07] :	 [Step 3/4]     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@44d64d4e, ownerName=null, ownerId=-1]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.LinkedBlockingDeque.pollFirst(LinkedBlockingDeque.java:522)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.LinkedBlockingDeque.poll(LinkedBlockingDeque.java:684)
> [08:59:07] :	 [Step 3/4]         at o.a.i.spi.discovery.tcp.ServerImpl$MessageWorker.body(ServerImpl.java:7110)
> [08:59:07] :	 [Step 3/4]         at o.a.i.spi.discovery.tcp.ServerImpl$RingMessageWorker.body(ServerImpl.java:2688)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at o.a.i.spi.discovery.tcp.ServerImpl$MessageWorkerThread.body(ServerImpl.java:7059)
> [08:59:07] :	 [Step 3/4]         at o.a.i.spi.IgniteSpiThread.run(IgniteSpiThread.java:62)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="grid-data-loader-flusher-#26217%distributed.CacheExchangeMergeTest2%", id=35004, state=WAITING, blockCnt=0, waitCnt=2]
> [08:59:07] :	 [Step 3/4]     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@1dd74143, ownerName=null, ownerId=-1]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.DelayQueue.take(DelayQueue.java:211)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.processors.datastreamer.DataStreamProcessor$2.body(DataStreamProcessor.java:109)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="grid-nio-worker-client-listener-3-#26214%distributed.CacheExchangeMergeTest2%", id=35001, state=RUNNABLE, blockCnt=0, waitCnt=0]
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:93)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86)
> [08:59:07] :	 [Step 3/4]         - locked o.a.i.i.util.nio.SelectedSelectionKeySet@526a9908
> [08:59:07] :	 [Step 3/4]         - locked java.util.Collections$UnmodifiableSet@3166f664
> [08:59:07] :	 [Step 3/4]         - locked sun.nio.ch.EPollSelectorImpl@47ac613b
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.nio.GridNioServer$AbstractNioClientWorker.bodyInternal(GridNioServer.java:2133)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.nio.GridNioServer$AbstractNioClientWorker.body(GridNioServer.java:1792)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="grid-nio-worker-client-listener-2-#26213%distributed.CacheExchangeMergeTest2%", id=35000, state=RUNNABLE, blockCnt=0, waitCnt=0]
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:93)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86)
> [08:59:07] :	 [Step 3/4]         - locked o.a.i.i.util.nio.SelectedSelectionKeySet@60d6fdd4
> [08:59:07] :	 [Step 3/4]         - locked java.util.Collections$UnmodifiableSet@66f28a1f
> [08:59:07] :	 [Step 3/4]         - locked sun.nio.ch.EPollSelectorImpl@60a19573
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.nio.GridNioServer$AbstractNioClientWorker.bodyInternal(GridNioServer.java:2133)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.nio.GridNioServer$AbstractNioClientWorker.body(GridNioServer.java:1792)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="grid-nio-worker-client-listener-1-#26212%distributed.CacheExchangeMergeTest2%", id=34999, state=RUNNABLE, blockCnt=0, waitCnt=0]
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:93)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86)
> [08:59:07] :	 [Step 3/4]         - locked o.a.i.i.util.nio.SelectedSelectionKeySet@44a085e5
> [08:59:07] :	 [Step 3/4]         - locked java.util.Collections$UnmodifiableSet@134ff8f8
> [08:59:07] :	 [Step 3/4]         - locked sun.nio.ch.EPollSelectorImpl@619f2afc
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.nio.GridNioServer$AbstractNioClientWorker.bodyInternal(GridNioServer.java:2133)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.nio.GridNioServer$AbstractNioClientWorker.body(GridNioServer.java:1792)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="grid-nio-worker-client-listener-0-#26211%distributed.CacheExchangeMergeTest2%", id=34998, state=RUNNABLE, blockCnt=0, waitCnt=0]
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:93)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86)
> [08:59:07] :	 [Step 3/4]         - locked o.a.i.i.util.nio.SelectedSelectionKeySet@df921b1
> [08:59:07] :	 [Step 3/4]         - locked java.util.Collections$UnmodifiableSet@4db60246
> [08:59:07] :	 [Step 3/4]         - locked sun.nio.ch.EPollSelectorImpl@2152ab30
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.nio.GridNioServer$AbstractNioClientWorker.bodyInternal(GridNioServer.java:2133)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.nio.GridNioServer$AbstractNioClientWorker.body(GridNioServer.java:1792)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="nio-acceptor-client-listener-#26215%distributed.CacheExchangeMergeTest2%", id=35002, state=RUNNABLE, blockCnt=0, waitCnt=0]
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:93)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86)
> [08:59:07] :	 [Step 3/4]         - locked sun.nio.ch.Util$3@3902bd2c
> [08:59:07] :	 [Step 3/4]         - locked java.util.Collections$UnmodifiableSet@7d32e714
> [08:59:07] :	 [Step 3/4]         - locked sun.nio.ch.EPollSelectorImpl@eb6ec6
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.nio.GridNioServer$GridNioAcceptWorker.accept(GridNioServer.java:2907)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.nio.GridNioServer$GridNioAcceptWorker.body(GridNioServer.java:2859)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="grid-sharedfs-timeout-worker-#4314%distributed.CacheExchangeMergeTest2%", id=34997, state=TIMED_WAITING, blockCnt=0, waitCnt=60]
> [08:59:07] :	 [Step 3/4]     Lock [object=java.lang.Object@34d45ec0, ownerName=null, ownerId=-1]
> [08:59:07] :	 [Step 3/4]         at java.lang.Object.wait(Native Method)
> [08:59:07] :	 [Step 3/4]         at o.a.i.spi.checkpoint.sharedfs.SharedFsTimeoutTask.body(SharedFsTimeoutTask.java:99)
> [08:59:07] :	 [Step 3/4]         at o.a.i.spi.IgniteSpiThread.run(IgniteSpiThread.java:62)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="tcp-comm-worker-#4313%distributed.CacheExchangeMergeTest2%", id=34996, state=TIMED_WAITING, blockCnt=0, waitCnt=2]
> [08:59:07] :	 [Step 3/4]     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@18137eab, ownerName=null, ownerId=-1]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.LinkedBlockingQueue.poll(LinkedBlockingQueue.java:467)
> [08:59:07] :	 [Step 3/4]         at o.a.i.spi.communication.tcp.TcpCommunicationSpi$CommunicationWorker.body(TcpCommunicationSpi.java:4257)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at o.a.i.spi.communication.tcp.TcpCommunicationSpi$5.body(TcpCommunicationSpi.java:2217)
> [08:59:07] :	 [Step 3/4]         at o.a.i.spi.IgniteSpiThread.run(IgniteSpiThread.java:62)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="grid-nio-worker-tcp-comm-3-#26209%distributed.CacheExchangeMergeTest2%", id=34994, state=RUNNABLE, blockCnt=3, waitCnt=0]
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:93)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86)
> [08:59:07] :	 [Step 3/4]         - locked o.a.i.i.util.nio.SelectedSelectionKeySet@727320fa
> [08:59:07] :	 [Step 3/4]         - locked java.util.Collections$UnmodifiableSet@2935fd2c
> [08:59:07] :	 [Step 3/4]         - locked sun.nio.ch.EPollSelectorImpl@3f018494
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.nio.GridNioServer$AbstractNioClientWorker.bodyInternal(GridNioServer.java:2133)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.nio.GridNioServer$AbstractNioClientWorker.body(GridNioServer.java:1792)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="grid-nio-worker-tcp-comm-2-#26208%distributed.CacheExchangeMergeTest2%", id=34993, state=RUNNABLE, blockCnt=2, waitCnt=0]
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:93)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86)
> [08:59:07] :	 [Step 3/4]         - locked o.a.i.i.util.nio.SelectedSelectionKeySet@3ce443f9
> [08:59:07] :	 [Step 3/4]         - locked java.util.Collections$UnmodifiableSet@57fbc06f
> [08:59:07] :	 [Step 3/4]         - locked sun.nio.ch.EPollSelectorImpl@51a18b21
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.nio.GridNioServer$AbstractNioClientWorker.bodyInternal(GridNioServer.java:2133)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.nio.GridNioServer$AbstractNioClientWorker.body(GridNioServer.java:1792)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="grid-nio-worker-tcp-comm-1-#26207%distributed.CacheExchangeMergeTest2%", id=34992, state=RUNNABLE, blockCnt=2, waitCnt=0]
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:93)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86)
> [08:59:07] :	 [Step 3/4]         - locked o.a.i.i.util.nio.SelectedSelectionKeySet@62b790a5
> [08:59:07] :	 [Step 3/4]         - locked java.util.Collections$UnmodifiableSet@7c52fc81
> [08:59:07] :	 [Step 3/4]         - locked sun.nio.ch.EPollSelectorImpl@7523a3dc
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.nio.GridNioServer$AbstractNioClientWorker.bodyInternal(GridNioServer.java:2133)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.nio.GridNioServer$AbstractNioClientWorker.body(GridNioServer.java:1792)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="grid-nio-worker-tcp-comm-0-#26206%distributed.CacheExchangeMergeTest2%", id=34991, state=RUNNABLE, blockCnt=3, waitCnt=0]
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:93)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86)
> [08:59:07] :	 [Step 3/4]         - locked o.a.i.i.util.nio.SelectedSelectionKeySet@2806d6da
> [08:59:07] :	 [Step 3/4]         - locked java.util.Collections$UnmodifiableSet@766a49c7
> [08:59:07] :	 [Step 3/4]         - locked sun.nio.ch.EPollSelectorImpl@1db7157f
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.nio.GridNioServer$AbstractNioClientWorker.bodyInternal(GridNioServer.java:2133)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.nio.GridNioServer$AbstractNioClientWorker.body(GridNioServer.java:1792)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="nio-acceptor-tcp-comm-#26210%distributed.CacheExchangeMergeTest2%", id=34995, state=RUNNABLE, blockCnt=0, waitCnt=0]
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:93)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86)
> [08:59:07] :	 [Step 3/4]         - locked sun.nio.ch.Util$3@75e27856
> [08:59:07] :	 [Step 3/4]         - locked java.util.Collections$UnmodifiableSet@6bccd036
> [08:59:07] :	 [Step 3/4]         - locked sun.nio.ch.EPollSelectorImpl@4f4c88f9
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.nio.GridNioServer$GridNioAcceptWorker.accept(GridNioServer.java:2907)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.nio.GridNioServer$GridNioAcceptWorker.body(GridNioServer.java:2859)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="grid-timeout-worker-#26205%distributed.CacheExchangeMergeTest2%", id=34990, state=TIMED_WAITING, blockCnt=2, waitCnt=458]
> [08:59:07] :	 [Step 3/4]     Lock [object=java.lang.Object@6a756082, ownerName=null, ownerId=-1]
> [08:59:07] :	 [Step 3/4]         at java.lang.Object.wait(Native Method)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.processors.timeout.GridTimeoutProcessor$TimeoutWorker.body(GridTimeoutProcessor.java:203)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="jvm-pause-detector-worker", id=34983, state=TIMED_WAITING, blockCnt=0, waitCnt=5991]
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.sleep(Native Method)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.LongJVMPauseDetector$1.run(LongJVMPauseDetector.java:100)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="data-streamer-stripe-7-#26198%distributed.CacheExchangeMergeTest2%", id=34982, state=WAITING, blockCnt=0, waitCnt=2]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:304)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$StripeConcurrentQueue.take(StripedExecutor.java:658)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$Stripe.body(StripedExecutor.java:490)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="data-streamer-stripe-6-#26197%distributed.CacheExchangeMergeTest2%", id=34981, state=WAITING, blockCnt=0, waitCnt=2]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:304)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$StripeConcurrentQueue.take(StripedExecutor.java:658)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$Stripe.body(StripedExecutor.java:490)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="data-streamer-stripe-5-#26196%distributed.CacheExchangeMergeTest2%", id=34980, state=WAITING, blockCnt=0, waitCnt=2]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:304)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$StripeConcurrentQueue.take(StripedExecutor.java:658)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$Stripe.body(StripedExecutor.java:490)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="data-streamer-stripe-4-#26195%distributed.CacheExchangeMergeTest2%", id=34979, state=WAITING, blockCnt=0, waitCnt=2]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:304)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$StripeConcurrentQueue.take(StripedExecutor.java:658)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$Stripe.body(StripedExecutor.java:490)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="data-streamer-stripe-3-#26194%distributed.CacheExchangeMergeTest2%", id=34978, state=WAITING, blockCnt=0, waitCnt=2]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:304)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$StripeConcurrentQueue.take(StripedExecutor.java:658)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$Stripe.body(StripedExecutor.java:490)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="data-streamer-stripe-2-#26193%distributed.CacheExchangeMergeTest2%", id=34977, state=WAITING, blockCnt=0, waitCnt=2]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:304)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$StripeConcurrentQueue.take(StripedExecutor.java:658)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$Stripe.body(StripedExecutor.java:490)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="data-streamer-stripe-1-#26192%distributed.CacheExchangeMergeTest2%", id=34976, state=WAITING, blockCnt=0, waitCnt=2]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:304)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$StripeConcurrentQueue.take(StripedExecutor.java:658)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$Stripe.body(StripedExecutor.java:490)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="data-streamer-stripe-0-#26191%distributed.CacheExchangeMergeTest2%", id=34975, state=WAITING, blockCnt=0, waitCnt=2]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:304)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$StripeConcurrentQueue.take(StripedExecutor.java:658)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$Stripe.body(StripedExecutor.java:490)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="sys-stripe-7-#26190%distributed.CacheExchangeMergeTest2%", id=34974, state=WAITING, blockCnt=0, waitCnt=2]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:304)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$StripeConcurrentQueue.take(StripedExecutor.java:658)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$Stripe.body(StripedExecutor.java:490)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="sys-stripe-6-#26189%distributed.CacheExchangeMergeTest2%", id=34973, state=WAITING, blockCnt=0, waitCnt=2]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:304)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$StripeConcurrentQueue.take(StripedExecutor.java:658)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$Stripe.body(StripedExecutor.java:490)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="sys-stripe-5-#26188%distributed.CacheExchangeMergeTest2%", id=34972, state=WAITING, blockCnt=0, waitCnt=1]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:304)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$StripeConcurrentQueue.take(StripedExecutor.java:658)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$Stripe.body(StripedExecutor.java:490)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="sys-stripe-4-#26187%distributed.CacheExchangeMergeTest2%", id=34971, state=WAITING, blockCnt=0, waitCnt=2]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:304)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$StripeConcurrentQueue.take(StripedExecutor.java:658)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$Stripe.body(StripedExecutor.java:490)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="sys-stripe-3-#26186%distributed.CacheExchangeMergeTest2%", id=34970, state=WAITING, blockCnt=0, waitCnt=1]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:304)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$StripeConcurrentQueue.take(StripedExecutor.java:658)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$Stripe.body(StripedExecutor.java:490)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="sys-stripe-2-#26185%distributed.CacheExchangeMergeTest2%", id=34969, state=WAITING, blockCnt=0, waitCnt=1]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:304)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$StripeConcurrentQueue.take(StripedExecutor.java:658)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$Stripe.body(StripedExecutor.java:490)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="sys-stripe-1-#26184%distributed.CacheExchangeMergeTest2%", id=34968, state=WAITING, blockCnt=0, waitCnt=1]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:304)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$StripeConcurrentQueue.take(StripedExecutor.java:658)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$Stripe.body(StripedExecutor.java:490)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="sys-stripe-0-#26183%distributed.CacheExchangeMergeTest2%", id=34967, state=WAITING, blockCnt=0, waitCnt=1]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:304)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$StripeConcurrentQueue.take(StripedExecutor.java:658)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$Stripe.body(StripedExecutor.java:490)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="start-node-2-1", id=34966, state=TIMED_WAITING, blockCnt=2, waitCnt=3]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:338)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.future.GridFutureAdapter.get0(GridFutureAdapter.java:217)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.future.GridFutureAdapter.get(GridFutureAdapter.java:159)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.future.GridFutureAdapter.get(GridFutureAdapter.java:151)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.processors.cache.GridCachePartitionExchangeManager.onKernalStart(GridCachePartitionExchangeManager.java:652)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.processors.cache.GridCacheProcessor.onKernalStart(GridCacheProcessor.java:856)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.IgniteKernal.start(IgniteKernal.java:1108)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.IgnitionEx$IgniteNamedInstance.start0(IgnitionEx.java:2020)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.IgnitionEx$IgniteNamedInstance.start(IgnitionEx.java:1725)
> [08:59:07] :	 [Step 3/4]         - locked o.a.i.i.IgnitionEx$IgniteNamedInstance@cb39552
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.IgnitionEx.start0(IgnitionEx.java:1153)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.IgnitionEx.start(IgnitionEx.java:651)
> [08:59:07] :	 [Step 3/4]         at o.a.i.testframework.junits.GridAbstractTest.startGrid(GridAbstractTest.java:920)
> [08:59:07] :	 [Step 3/4]         at o.a.i.testframework.junits.GridAbstractTest.startGrid(GridAbstractTest.java:858)
> [08:59:07] :	 [Step 3/4]         at o.a.i.testframework.junits.GridAbstractTest.startGrid(GridAbstractTest.java:846)
> [08:59:07] :	 [Step 3/4]         at o.a.i.testframework.junits.GridAbstractTest.startGrid(GridAbstractTest.java:812)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.processors.cache.distributed.CacheExchangeMergeTest.access$2800(CacheExchangeMergeTest.java:91)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.processors.cache.distributed.CacheExchangeMergeTest$19.call(CacheExchangeMergeTest.java:1528)
> [08:59:07] :	 [Step 3/4]         at o.a.i.testframework.GridTestUtils.lambda$runAsync$2(GridTestUtils.java:956)
> [08:59:07] :	 [Step 3/4]         at o.a.i.testframework.GridTestUtils$$Lambda$48/2035225043.run(Unknown Source)
> [08:59:07] :	 [Step 3/4]         at o.a.i.testframework.GridTestUtils$6.call(GridTestUtils.java:1254)
> [08:59:07] :	 [Step 3/4]         at o.a.i.testframework.GridTestThread.run(GridTestThread.java:86)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="srvc-deploy-#26182%distributed.CacheExchangeMergeTest1%", id=34964, state=WAITING, blockCnt=0, waitCnt=4]
> [08:59:07] :	 [Step 3/4]     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@1f3b992, ownerName=null, ownerId=-1]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="exchange-worker-#26174%distributed.CacheExchangeMergeTest1%", id=34956, state=TIMED_WAITING, blockCnt=10, waitCnt=20]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:338)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.future.GridFutureAdapter.get0(GridFutureAdapter.java:217)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.future.GridFutureAdapter.get(GridFutureAdapter.java:159)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.processors.cache.distributed.dht.preloader.latch.ExchangeLatchManager$CompletableLatch.await(ExchangeLatchManager.java:657)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.processors.cache.distributed.dht.preloader.GridDhtPartitionsExchangeFuture.waitPartitionRelease(GridDhtPartitionsExchangeFuture.java:1450)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.processors.cache.distributed.dht.preloader.GridDhtPartitionsExchangeFuture.distributedExchange(GridDhtPartitionsExchangeFuture.java:1208)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.processors.cache.distributed.dht.preloader.GridDhtPartitionsExchangeFuture.init(GridDhtPartitionsExchangeFuture.java:749)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.processors.cache.GridCachePartitionExchangeManager$ExchangeWorker.body0(GridCachePartitionExchangeManager.java:2497)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.processors.cache.GridCachePartitionExchangeManager$ExchangeWorker.body(GridCachePartitionExchangeManager.java:2377)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="disco-event-worker-#26173%distributed.CacheExchangeMergeTest1%", id=34955, state=WAITING, blockCnt=5, waitCnt=318]
> [08:59:07] :	 [Step 3/4]     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@2a53f215, ownerName=null, ownerId=-1]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.managers.discovery.GridDiscoveryManager$DiscoveryWorker.body0(GridDiscoveryManager.java:2767)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.managers.discovery.GridDiscoveryManager$DiscoveryWorker.body(GridDiscoveryManager.java:2741)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="tcp-disco-ip-finder-cleaner-#4312%distributed.CacheExchangeMergeTest1%", id=34954, state=TIMED_WAITING, blockCnt=0, waitCnt=5]
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.sleep(Native Method)
> [08:59:07] :	 [Step 3/4]         at o.a.i.spi.discovery.tcp.ServerImpl$IpFinderCleaner.body(ServerImpl.java:1958)
> [08:59:07] :	 [Step 3/4]         at o.a.i.spi.IgniteSpiThread.run(IgniteSpiThread.java:62)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="tcp-disco-sock-reader-#4310%distributed.CacheExchangeMergeTest1%", id=34952, state=RUNNABLE, blockCnt=0, waitCnt=0]
> [08:59:07] :	 [Step 3/4]         at java.net.SocketInputStream.socketRead0(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.net.SocketInputStream.socketRead(SocketInputStream.java:116)
> [08:59:07] :	 [Step 3/4]         at java.net.SocketInputStream.read(SocketInputStream.java:171)
> [08:59:07] :	 [Step 3/4]         at java.net.SocketInputStream.read(SocketInputStream.java:141)
> [08:59:07] :	 [Step 3/4]         at java.io.BufferedInputStream.fill(BufferedInputStream.java:246)
> [08:59:07] :	 [Step 3/4]         at java.io.BufferedInputStream.read1(BufferedInputStream.java:286)
> [08:59:07] :	 [Step 3/4]         at java.io.BufferedInputStream.read(BufferedInputStream.java:345)
> [08:59:07] :	 [Step 3/4]         - locked java.io.BufferedInputStream@6b63e6ad
> [08:59:07] :	 [Step 3/4]         at o.a.i.marshaller.jdk.JdkMarshallerInputStreamWrapper.read(JdkMarshallerInputStreamWrapper.java:53)
> [08:59:07] :	 [Step 3/4]         at java.io.ObjectInputStream$PeekInputStream.read(ObjectInputStream.java:2661)
> [08:59:07] :	 [Step 3/4]         at java.io.ObjectInputStream$PeekInputStream.readFully(ObjectInputStream.java:2677)
> [08:59:07] :	 [Step 3/4]         at java.io.ObjectInputStream$BlockDataInputStream.readShort(ObjectInputStream.java:3154)
> [08:59:07] :	 [Step 3/4]         at java.io.ObjectInputStream.readStreamHeader(ObjectInputStream.java:862)
> [08:59:07] :	 [Step 3/4]         at java.io.ObjectInputStream.<init>(ObjectInputStream.java:358)
> [08:59:07] :	 [Step 3/4]         at o.a.i.marshaller.jdk.JdkMarshallerObjectInputStream.<init>(JdkMarshallerObjectInputStream.java:43)
> [08:59:07] :	 [Step 3/4]         at o.a.i.marshaller.jdk.JdkMarshaller.unmarshal0(JdkMarshaller.java:137)
> [08:59:07] :	 [Step 3/4]         at o.a.i.marshaller.AbstractNodeNameAwareMarshaller.unmarshal(AbstractNodeNameAwareMarshaller.java:94)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.IgniteUtils.unmarshal(IgniteUtils.java:9959)
> [08:59:07] :	 [Step 3/4]         at o.a.i.spi.discovery.tcp.ServerImpl$SocketReader.body(ServerImpl.java:6220)
> [08:59:07] :	 [Step 3/4]         at o.a.i.spi.IgniteSpiThread.run(IgniteSpiThread.java:62)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="tcp-disco-srvr-#4308%distributed.CacheExchangeMergeTest1%", id=34950, state=RUNNABLE, blockCnt=0, waitCnt=0]
> [08:59:07] :	 [Step 3/4]         at java.net.PlainSocketImpl.socketAccept(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.net.AbstractPlainSocketImpl.accept(AbstractPlainSocketImpl.java:409)
> [08:59:07] :	 [Step 3/4]         at java.net.ServerSocket.implAccept(ServerSocket.java:545)
> [08:59:07] :	 [Step 3/4]         at java.net.ServerSocket.accept(ServerSocket.java:513)
> [08:59:07] :	 [Step 3/4]         at o.a.i.spi.discovery.tcp.ServerImpl$TcpServer.body(ServerImpl.java:5825)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at o.a.i.spi.discovery.tcp.ServerImpl$TcpServerThread.body(ServerImpl.java:5747)
> [08:59:07] :	 [Step 3/4]         at o.a.i.spi.IgniteSpiThread.run(IgniteSpiThread.java:62)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="tcp-disco-msg-worker-#4307%distributed.CacheExchangeMergeTest1%", id=34949, state=TIMED_WAITING, blockCnt=0, waitCnt=30315]
> [08:59:07] :	 [Step 3/4]     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@7beae796, ownerName=null, ownerId=-1]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.LinkedBlockingDeque.pollFirst(LinkedBlockingDeque.java:522)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.LinkedBlockingDeque.poll(LinkedBlockingDeque.java:684)
> [08:59:07] :	 [Step 3/4]         at o.a.i.spi.discovery.tcp.ServerImpl$MessageWorker.body(ServerImpl.java:7110)
> [08:59:07] :	 [Step 3/4]         at o.a.i.spi.discovery.tcp.ServerImpl$RingMessageWorker.body(ServerImpl.java:2688)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at o.a.i.spi.discovery.tcp.ServerImpl$MessageWorkerThread.body(ServerImpl.java:7059)
> [08:59:07] :	 [Step 3/4]         at o.a.i.spi.IgniteSpiThread.run(IgniteSpiThread.java:62)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="grid-data-loader-flusher-#26172%distributed.CacheExchangeMergeTest1%", id=34948, state=WAITING, blockCnt=0, waitCnt=2]
> [08:59:07] :	 [Step 3/4]     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@6749fe50, ownerName=null, ownerId=-1]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.DelayQueue.take(DelayQueue.java:211)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.processors.datastreamer.DataStreamProcessor$2.body(DataStreamProcessor.java:109)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="grid-nio-worker-client-listener-3-#26169%distributed.CacheExchangeMergeTest1%", id=34945, state=RUNNABLE, blockCnt=0, waitCnt=0]
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:93)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86)
> [08:59:07] :	 [Step 3/4]         - locked o.a.i.i.util.nio.SelectedSelectionKeySet@52bf7bf6
> [08:59:07] :	 [Step 3/4]         - locked java.util.Collections$UnmodifiableSet@261db982
> [08:59:07] :	 [Step 3/4]         - locked sun.nio.ch.EPollSelectorImpl@ae73c80
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.nio.GridNioServer$AbstractNioClientWorker.bodyInternal(GridNioServer.java:2133)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.nio.GridNioServer$AbstractNioClientWorker.body(GridNioServer.java:1792)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="grid-nio-worker-client-listener-2-#26168%distributed.CacheExchangeMergeTest1%", id=34944, state=RUNNABLE, blockCnt=0, waitCnt=0]
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:93)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86)
> [08:59:07] :	 [Step 3/4]         - locked o.a.i.i.util.nio.SelectedSelectionKeySet@22f4f8f2
> [08:59:07] :	 [Step 3/4]         - locked java.util.Collections$UnmodifiableSet@bbd4791
> [08:59:07] :	 [Step 3/4]         - locked sun.nio.ch.EPollSelectorImpl@40a72ecd
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.nio.GridNioServer$AbstractNioClientWorker.bodyInternal(GridNioServer.java:2133)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.nio.GridNioServer$AbstractNioClientWorker.body(GridNioServer.java:1792)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="grid-nio-worker-client-listener-1-#26167%distributed.CacheExchangeMergeTest1%", id=34943, state=RUNNABLE, blockCnt=0, waitCnt=0]
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:93)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86)
> [08:59:07] :	 [Step 3/4]         - locked o.a.i.i.util.nio.SelectedSelectionKeySet@73971965
> [08:59:07] :	 [Step 3/4]         - locked java.util.Collections$UnmodifiableSet@76a14c8d
> [08:59:07] :	 [Step 3/4]         - locked sun.nio.ch.EPollSelectorImpl@17410c07
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.nio.GridNioServer$AbstractNioClientWorker.bodyInternal(GridNioServer.java:2133)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.nio.GridNioServer$AbstractNioClientWorker.body(GridNioServer.java:1792)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="grid-nio-worker-client-listener-0-#26166%distributed.CacheExchangeMergeTest1%", id=34942, state=RUNNABLE, blockCnt=0, waitCnt=0]
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:93)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86)
> [08:59:07] :	 [Step 3/4]         - locked o.a.i.i.util.nio.SelectedSelectionKeySet@6ee99964
> [08:59:07] :	 [Step 3/4]         - locked java.util.Collections$UnmodifiableSet@257ef9ed
> [08:59:07] :	 [Step 3/4]         - locked sun.nio.ch.EPollSelectorImpl@59c70ceb
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.nio.GridNioServer$AbstractNioClientWorker.bodyInternal(GridNioServer.java:2133)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.nio.GridNioServer$AbstractNioClientWorker.body(GridNioServer.java:1792)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="nio-acceptor-client-listener-#26170%distributed.CacheExchangeMergeTest1%", id=34946, state=RUNNABLE, blockCnt=0, waitCnt=0]
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:93)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86)
> [08:59:07] :	 [Step 3/4]         - locked sun.nio.ch.Util$3@46f902e0
> [08:59:07] :	 [Step 3/4]         - locked java.util.Collections$UnmodifiableSet@64f981e2
> [08:59:07] :	 [Step 3/4]         - locked sun.nio.ch.EPollSelectorImpl@361abd01
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.nio.GridNioServer$GridNioAcceptWorker.accept(GridNioServer.java:2907)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.nio.GridNioServer$GridNioAcceptWorker.body(GridNioServer.java:2859)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="grid-sharedfs-timeout-worker-#4306%distributed.CacheExchangeMergeTest1%", id=34941, state=TIMED_WAITING, blockCnt=0, waitCnt=60]
> [08:59:07] :	 [Step 3/4]     Lock [object=java.lang.Object@575b5f7d, ownerName=null, ownerId=-1]
> [08:59:07] :	 [Step 3/4]         at java.lang.Object.wait(Native Method)
> [08:59:07] :	 [Step 3/4]         at o.a.i.spi.checkpoint.sharedfs.SharedFsTimeoutTask.body(SharedFsTimeoutTask.java:99)
> [08:59:07] :	 [Step 3/4]         at o.a.i.spi.IgniteSpiThread.run(IgniteSpiThread.java:62)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="tcp-comm-worker-#4305%distributed.CacheExchangeMergeTest1%", id=34940, state=TIMED_WAITING, blockCnt=0, waitCnt=2]
> [08:59:07] :	 [Step 3/4]     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@59bbb974, ownerName=null, ownerId=-1]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.LinkedBlockingQueue.poll(LinkedBlockingQueue.java:467)
> [08:59:07] :	 [Step 3/4]         at o.a.i.spi.communication.tcp.TcpCommunicationSpi$CommunicationWorker.body(TcpCommunicationSpi.java:4257)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at o.a.i.spi.communication.tcp.TcpCommunicationSpi$5.body(TcpCommunicationSpi.java:2217)
> [08:59:07] :	 [Step 3/4]         at o.a.i.spi.IgniteSpiThread.run(IgniteSpiThread.java:62)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="grid-nio-worker-tcp-comm-3-#26164%distributed.CacheExchangeMergeTest1%", id=34938, state=RUNNABLE, blockCnt=2, waitCnt=0]
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:93)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86)
> [08:59:07] :	 [Step 3/4]         - locked o.a.i.i.util.nio.SelectedSelectionKeySet@7165d530
> [08:59:07] :	 [Step 3/4]         - locked java.util.Collections$UnmodifiableSet@12f49ca8
> [08:59:07] :	 [Step 3/4]         - locked sun.nio.ch.EPollSelectorImpl@1fd9893c
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.nio.GridNioServer$AbstractNioClientWorker.bodyInternal(GridNioServer.java:2133)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.nio.GridNioServer$AbstractNioClientWorker.body(GridNioServer.java:1792)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="grid-nio-worker-tcp-comm-2-#26163%distributed.CacheExchangeMergeTest1%", id=34937, state=RUNNABLE, blockCnt=3, waitCnt=0]
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:93)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86)
> [08:59:07] :	 [Step 3/4]         - locked o.a.i.i.util.nio.SelectedSelectionKeySet@1b2df3aa
> [08:59:07] :	 [Step 3/4]         - locked java.util.Collections$UnmodifiableSet@44be69aa
> [08:59:07] :	 [Step 3/4]         - locked sun.nio.ch.EPollSelectorImpl@7219ac49
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.nio.GridNioServer$AbstractNioClientWorker.bodyInternal(GridNioServer.java:2133)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.nio.GridNioServer$AbstractNioClientWorker.body(GridNioServer.java:1792)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="grid-nio-worker-tcp-comm-1-#26162%distributed.CacheExchangeMergeTest1%", id=34936, state=RUNNABLE, blockCnt=3, waitCnt=0]
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:93)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86)
> [08:59:07] :	 [Step 3/4]         - locked o.a.i.i.util.nio.SelectedSelectionKeySet@21da4b5f
> [08:59:07] :	 [Step 3/4]         - locked java.util.Collections$UnmodifiableSet@551e4c6d
> [08:59:07] :	 [Step 3/4]         - locked sun.nio.ch.EPollSelectorImpl@625a9c5d
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.nio.GridNioServer$AbstractNioClientWorker.bodyInternal(GridNioServer.java:2133)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.nio.GridNioServer$AbstractNioClientWorker.body(GridNioServer.java:1792)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="grid-nio-worker-tcp-comm-0-#26161%distributed.CacheExchangeMergeTest1%", id=34935, state=RUNNABLE, blockCnt=3, waitCnt=0]
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:93)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86)
> [08:59:07] :	 [Step 3/4]         - locked o.a.i.i.util.nio.SelectedSelectionKeySet@5d1e0fbb
> [08:59:07] :	 [Step 3/4]         - locked java.util.Collections$UnmodifiableSet@2eed37f4
> [08:59:07] :	 [Step 3/4]         - locked sun.nio.ch.EPollSelectorImpl@9b76b60
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.nio.GridNioServer$AbstractNioClientWorker.bodyInternal(GridNioServer.java:2133)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.nio.GridNioServer$AbstractNioClientWorker.body(GridNioServer.java:1792)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="nio-acceptor-tcp-comm-#26165%distributed.CacheExchangeMergeTest1%", id=34939, state=RUNNABLE, blockCnt=0, waitCnt=0]
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:93)
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86)
> [08:59:07] :	 [Step 3/4]         - locked sun.nio.ch.Util$3@3fb9a67f
> [08:59:07] :	 [Step 3/4]         - locked java.util.Collections$UnmodifiableSet@127705e4
> [08:59:07] :	 [Step 3/4]         - locked sun.nio.ch.EPollSelectorImpl@5562c2c9
> [08:59:07] :	 [Step 3/4]         at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.nio.GridNioServer$GridNioAcceptWorker.accept(GridNioServer.java:2907)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.nio.GridNioServer$GridNioAcceptWorker.body(GridNioServer.java:2859)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="grid-timeout-worker-#26160%distributed.CacheExchangeMergeTest1%", id=34934, state=TIMED_WAITING, blockCnt=3, waitCnt=778]
> [08:59:07] :	 [Step 3/4]     Lock [object=java.lang.Object@673c4f6e, ownerName=null, ownerId=-1]
> [08:59:07] :	 [Step 3/4]         at java.lang.Object.wait(Native Method)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.processors.timeout.GridTimeoutProcessor$TimeoutWorker.body(GridTimeoutProcessor.java:203)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="jvm-pause-detector-worker", id=34927, state=TIMED_WAITING, blockCnt=0, waitCnt=5992]
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.sleep(Native Method)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.LongJVMPauseDetector$1.run(LongJVMPauseDetector.java:100)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="data-streamer-stripe-7-#26153%distributed.CacheExchangeMergeTest1%", id=34926, state=WAITING, blockCnt=0, waitCnt=2]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:304)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$StripeConcurrentQueue.take(StripedExecutor.java:658)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$Stripe.body(StripedExecutor.java:490)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="data-streamer-stripe-6-#26152%distributed.CacheExchangeMergeTest1%", id=34925, state=WAITING, blockCnt=0, waitCnt=2]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:304)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$StripeConcurrentQueue.take(StripedExecutor.java:658)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$Stripe.body(StripedExecutor.java:490)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="data-streamer-stripe-5-#26151%distributed.CacheExchangeMergeTest1%", id=34924, state=WAITING, blockCnt=0, waitCnt=2]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:304)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$StripeConcurrentQueue.take(StripedExecutor.java:658)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$Stripe.body(StripedExecutor.java:490)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="data-streamer-stripe-4-#26150%distributed.CacheExchangeMergeTest1%", id=34923, state=WAITING, blockCnt=0, waitCnt=2]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:304)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$StripeConcurrentQueue.take(StripedExecutor.java:658)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$Stripe.body(StripedExecutor.java:490)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="data-streamer-stripe-3-#26149%distributed.CacheExchangeMergeTest1%", id=34922, state=WAITING, blockCnt=0, waitCnt=2]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:304)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$StripeConcurrentQueue.take(StripedExecutor.java:658)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$Stripe.body(StripedExecutor.java:490)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="data-streamer-stripe-2-#26148%distributed.CacheExchangeMergeTest1%", id=34921, state=WAITING, blockCnt=0, waitCnt=2]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:304)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$StripeConcurrentQueue.take(StripedExecutor.java:658)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$Stripe.body(StripedExecutor.java:490)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="data-streamer-stripe-1-#26147%distributed.CacheExchangeMergeTest1%", id=34920, state=WAITING, blockCnt=0, waitCnt=2]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:304)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$StripeConcurrentQueue.take(StripedExecutor.java:658)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$Stripe.body(StripedExecutor.java:490)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="data-streamer-stripe-0-#26146%distributed.CacheExchangeMergeTest1%", id=34919, state=WAITING, blockCnt=0, waitCnt=2]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:304)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$StripeConcurrentQueue.take(StripedExecutor.java:658)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$Stripe.body(StripedExecutor.java:490)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="sys-stripe-7-#26145%distributed.CacheExchangeMergeTest1%", id=34918, state=WAITING, blockCnt=0, waitCnt=2]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:304)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$StripeConcurrentQueue.take(StripedExecutor.java:658)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$Stripe.body(StripedExecutor.java:490)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="sys-stripe-6-#26144%distributed.CacheExchangeMergeTest1%", id=34917, state=WAITING, blockCnt=0, waitCnt=2]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:304)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$StripeConcurrentQueue.take(StripedExecutor.java:658)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$Stripe.body(StripedExecutor.java:490)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="sys-stripe-5-#26143%distributed.CacheExchangeMergeTest1%", id=34916, state=WAITING, blockCnt=0, waitCnt=2]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:304)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$StripeConcurrentQueue.take(StripedExecutor.java:658)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$Stripe.body(StripedExecutor.java:490)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="sys-stripe-4-#26142%distributed.CacheExchangeMergeTest1%", id=34915, state=WAITING, blockCnt=0, waitCnt=2]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:304)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$StripeConcurrentQueue.take(StripedExecutor.java:658)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$Stripe.body(StripedExecutor.java:490)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="sys-stripe-3-#26141%distributed.CacheExchangeMergeTest1%", id=34914, state=WAITING, blockCnt=0, waitCnt=2]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:304)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$StripeConcurrentQueue.take(StripedExecutor.java:658)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$Stripe.body(StripedExecutor.java:490)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="sys-stripe-2-#26140%distributed.CacheExchangeMergeTest1%", id=34913, state=WAITING, blockCnt=0, waitCnt=2]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:304)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$StripeConcurrentQueue.take(StripedExecutor.java:658)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$Stripe.body(StripedExecutor.java:490)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="sys-stripe-1-#26139%distributed.CacheExchangeMergeTest1%", id=34912, state=WAITING, blockCnt=0, waitCnt=2]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:304)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$StripeConcurrentQueue.take(StripedExecutor.java:658)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$Stripe.body(StripedExecutor.java:490)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="sys-stripe-0-#26138%distributed.CacheExchangeMergeTest1%", id=34911, state=WAITING, blockCnt=0, waitCnt=2]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:304)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$StripeConcurrentQueue.take(StripedExecutor.java:658)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.StripedExecutor$Stripe.body(StripedExecutor.java:490)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.worker.GridWorker.run(GridWorker.java:110)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="test-runner-#26099%distributed.CacheExchangeMergeTest%", id=34865, state=WAITING, blockCnt=26, waitCnt=29]
> [08:59:07] :	 [Step 3/4]     Lock [object=java.util.concurrent.CountDownLatch$Sync@7c011174, ownerName=null, ownerId=-1]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.AbstractQueuedSynchronizer.parkAndCheckInterrupt(AbstractQueuedSynchronizer.java:836)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.AbstractQueuedSynchronizer.doAcquireSharedInterruptibly(AbstractQueuedSynchronizer.java:997)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.AbstractQueuedSynchronizer.acquireSharedInterruptibly(AbstractQueuedSynchronizer.java:1304)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.CountDownLatch.await(CountDownLatch.java:231)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.IgniteUtils.awaitQuiet(IgniteUtils.java:7584)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.IgnitionEx$IgniteNamedInstance.grid(IgnitionEx.java:1666)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.IgnitionEx.allGrids(IgnitionEx.java:1284)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.IgnitionEx.allGrids(IgnitionEx.java:1262)
> [08:59:07] :	 [Step 3/4]         at o.a.i.Ignition.allGrids(Ignition.java:502)
> [08:59:07] :	 [Step 3/4]         at o.a.i.testframework.junits.GridAbstractTest.awaitTopologyChange(GridAbstractTest.java:2258)
> [08:59:07] :	 [Step 3/4]         at o.a.i.testframework.junits.GridAbstractTest.stopGrid(GridAbstractTest.java:1158)
> [08:59:07] :	 [Step 3/4]         at o.a.i.testframework.junits.GridAbstractTest.stopGrid(GridAbstractTest.java:1133)
> [08:59:07] :	 [Step 3/4]         at o.a.i.testframework.junits.GridAbstractTest.stopGrid(GridAbstractTest.java:1433)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.processors.cache.distributed.CacheExchangeMergeTest.testStartCacheOnJoinAndCoordinatorFailed1(CacheExchangeMergeTest.java:647)
> [08:59:07] :	 [Step 3/4]         at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
> [08:59:07] :	 [Step 3/4]         at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
> [08:59:07] :	 [Step 3/4]         at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
> [08:59:07] :	 [Step 3/4]         at java.lang.reflect.Method.invoke(Method.java:498)
> [08:59:07] :	 [Step 3/4]         at junit.framework.TestCase.runTest(TestCase.java:176)
> [08:59:07] :	 [Step 3/4]         at o.a.i.testframework.junits.GridAbstractTest.runTestInternal(GridAbstractTest.java:2156)
> [08:59:07] :	 [Step 3/4]         at o.a.i.testframework.junits.GridAbstractTest.access$000(GridAbstractTest.java:143)
> [08:59:07] :	 [Step 3/4]         at o.a.i.testframework.junits.GridAbstractTest$5.run(GridAbstractTest.java:2071)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4]     Locked synchronizers:
> [08:59:07] :	 [Step 3/4]         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@15c487a8
> [08:59:07] :	 [Step 3/4]         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@3f36e8d1
> [08:59:07] :	 [Step 3/4] Thread [name="pool-1-thread-5", id=12698, state=WAITING, blockCnt=359, waitCnt=56643]
> [08:59:07] :	 [Step 3/4]     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@794366a5, ownerName=null, ownerId=-1]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="pool-1-thread-4", id=12697, state=WAITING, blockCnt=320, waitCnt=57592]
> [08:59:07] :	 [Step 3/4]     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@794366a5, ownerName=null, ownerId=-1]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="pool-1-thread-3", id=12696, state=WAITING, blockCnt=314, waitCnt=57756]
> [08:59:07] :	 [Step 3/4]     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@794366a5, ownerName=null, ownerId=-1]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="pool-1-thread-2", id=12695, state=WAITING, blockCnt=304, waitCnt=57654]
> [08:59:07] :	 [Step 3/4]     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@794366a5, ownerName=null, ownerId=-1]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="pool-1-thread-1", id=12694, state=WAITING, blockCnt=311, waitCnt=57197]
> [08:59:07] :	 [Step 3/4]     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@794366a5, ownerName=null, ownerId=-1]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="ignite-clock-for-tests", id=16, state=TIMED_WAITING, blockCnt=0, waitCnt=91718]
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.sleep(Native Method)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.GridTestClockTimer.run(GridTestClockTimer.java:50)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="resolver-3", id=15, state=WAITING, blockCnt=0, waitCnt=1]
> [08:59:07] :	 [Step 3/4]     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@480b57e2, ownerName=null, ownerId=-1]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="resolver-2", id=14, state=WAITING, blockCnt=0, waitCnt=1]
> [08:59:07] :	 [Step 3/4]     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@480b57e2, ownerName=null, ownerId=-1]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="resolver-1", id=13, state=WAITING, blockCnt=0, waitCnt=1]
> [08:59:07] :	 [Step 3/4]     Lock [object=java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@480b57e2, ownerName=null, ownerId=-1]
> [08:59:07] :	 [Step 3/4]         at sun.misc.Unsafe.park(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
> [08:59:07] :	 [Step 3/4]         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
> [08:59:07] :	 [Step 3/4]         at java.lang.Thread.run(Thread.java:748)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="Signal Dispatcher", id=4, state=RUNNABLE, blockCnt=0, waitCnt=0]
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="Finalizer", id=3, state=WAITING, blockCnt=508, waitCnt=54]
> [08:59:07] :	 [Step 3/4]     Lock [object=java.lang.ref.ReferenceQueue$Lock@40f35e52, ownerName=null, ownerId=-1]
> [08:59:07] :	 [Step 3/4]         at java.lang.Object.wait(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:143)
> [08:59:07] :	 [Step 3/4]         at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:164)
> [08:59:07] :	 [Step 3/4]         at java.lang.ref.Finalizer$FinalizerThread.run(Finalizer.java:212)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="Reference Handler", id=2, state=WAITING, blockCnt=62, waitCnt=53]
> [08:59:07] :	 [Step 3/4]     Lock [object=java.lang.ref.Reference$Lock@340d6d89, ownerName=null, ownerId=-1]
> [08:59:07] :	 [Step 3/4]         at java.lang.Object.wait(Native Method)
> [08:59:07] :	 [Step 3/4]         at java.lang.Object.wait(Object.java:502)
> [08:59:07] :	 [Step 3/4]         at java.lang.ref.Reference.tryHandlePending(Reference.java:191)
> [08:59:07] :	 [Step 3/4]         at java.lang.ref.Reference$ReferenceHandler.run(Reference.java:153)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4] Thread [name="main", id=1, state=RUNNABLE, blockCnt=2050, waitCnt=2083]
> [08:59:07] :	 [Step 3/4]         at sun.management.ThreadImpl.dumpThreads0(Native Method)
> [08:59:07] :	 [Step 3/4]         at sun.management.ThreadImpl.dumpAllThreads(ThreadImpl.java:454)
> [08:59:07] :	 [Step 3/4]         at o.a.i.i.util.IgniteUtils.dumpThreads(IgniteUtils.java:1329)
> [08:59:07] :	 [Step 3/4]         at o.a.i.testframework.junits.GridAbstractTest.runTest(GridAbstractTest.java:2097)
> [08:59:07] :	 [Step 3/4]         at junit.framework.TestCase.runBare(TestCase.java:141)
> [08:59:07] :	 [Step 3/4]         at junit.framework.TestResult$1.protect(TestResult.java:122)
> [08:59:07] :	 [Step 3/4]         at junit.framework.TestResult.runProtected(TestResult.java:142)
> [08:59:07] :	 [Step 3/4]         at junit.framework.TestResult.run(TestResult.java:125)
> [08:59:07] :	 [Step 3/4]         at junit.framework.TestCase.run(TestCase.java:129)
> [08:59:07] :	 [Step 3/4]         at junit.framework.TestSuite.runTest(TestSuite.java:255)
> [08:59:07] :	 [Step 3/4]         at junit.framework.TestSuite.run(TestSuite.java:250)
> [08:59:07] :	 [Step 3/4]         at junit.framework.TestSuite.runTest(TestSuite.java:255)
> [08:59:07] :	 [Step 3/4]         at junit.framework.TestSuite.run(TestSuite.java:250)
> [08:59:07] :	 [Step 3/4]         at org.junit.internal.runners.JUnit38ClassRunner.run(JUnit38ClassRunner.java:84)
> [08:59:07] :	 [Step 3/4]         at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:369)
> [08:59:07] :	 [Step 3/4]         at org.apache.maven.surefire.junit4.JUnit4Provider.executeWithRerun(JUnit4Provider.java:275)
> [08:59:07] :	 [Step 3/4]         at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:239)
> [08:59:07] :	 [Step 3/4]         at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:160)
> [08:59:07] :	 [Step 3/4]         at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
> [08:59:07] :	 [Step 3/4]         at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
> [08:59:07] :	 [Step 3/4]         at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
> [08:59:07] :	 [Step 3/4]         at java.lang.reflect.Method.invoke(Method.java:498)
> [08:59:07] :	 [Step 3/4]         at org.apache.maven.surefire.util.ReflectionUtils.invokeMethodWithArray2(ReflectionUtils.java:206)
> [08:59:07] :	 [Step 3/4]         at org.apache.maven.surefire.booter.ProviderFactory$ProviderProxy.invoke(ProviderFactory.java:160)
> [08:59:07] :	 [Step 3/4]         at org.apache.maven.surefire.booter.ProviderFactory.invokeProvider(ProviderFactory.java:83)
> [08:59:07] :	 [Step 3/4]         at org.apache.maven.plugin.surefire.InPluginVMSurefireStarter.runSuitesInProcess(InPluginVMSurefireStarter.java:84)
> [08:59:07] :	 [Step 3/4]         at org.apache.maven.plugin.surefire.AbstractSurefireMojo.executeProvider(AbstractSurefireMojo.java:1107)
> [08:59:07] :	 [Step 3/4]         at org.apache.maven.plugin.surefire.AbstractSurefireMojo.executeAfterPreconditionsChecked(AbstractSurefireMojo.java:954)
> [08:59:07] :	 [Step 3/4]         at org.apache.maven.plugin.surefire.AbstractSurefireMojo.execute(AbstractSurefireMojo.java:832)
> [08:59:07] :	 [Step 3/4]         at org.apache.maven.plugin.DefaultBuildPluginManager.executeMojo(DefaultBuildPluginManager.java:137)
> [08:59:07] :	 [Step 3/4]         at org.apache.maven.lifecycle.internal.MojoExecutor.execute(MojoExecutor.java:208)
> [08:59:07] :	 [Step 3/4]         at org.apache.maven.lifecycle.internal.MojoExecutor.execute(MojoExecutor.java:154)
> [08:59:07] :	 [Step 3/4]         at org.apache.maven.lifecycle.internal.MojoExecutor.execute(MojoExecutor.java:146)
> [08:59:07] :	 [Step 3/4]         at org.apache.maven.lifecycle.internal.LifecycleModuleBuilder.buildProject(LifecycleModuleBuilder.java:117)
> [08:59:07] :	 [Step 3/4]         at org.apache.maven.lifecycle.internal.LifecycleModuleBuilder.buildProject(LifecycleModuleBuilder.java:81)
> [08:59:07] :	 [Step 3/4]         at org.apache.maven.lifecycle.internal.builder.singlethreaded.SingleThreadedBuilder.build(SingleThreadedBuilder.java:56)
> [08:59:07] :	 [Step 3/4]         at org.apache.maven.lifecycle.internal.LifecycleStarter.execute(LifecycleStarter.java:128)
> [08:59:07] :	 [Step 3/4]         at org.apache.maven.DefaultMaven.doExecute(DefaultMaven.java:305)
> [08:59:07] :	 [Step 3/4]         at org.apache.maven.DefaultMaven.doExecute(DefaultMaven.java:192)
> [08:59:07] :	 [Step 3/4]         at org.apache.maven.DefaultMaven.execute(DefaultMaven.java:105)
> [08:59:07] :	 [Step 3/4]         at org.apache.maven.cli.MavenCli.execute(MavenCli.java:956)
> [08:59:07] :	 [Step 3/4]         at org.apache.maven.cli.MavenCli.doMain(MavenCli.java:290)
> [08:59:07] :	 [Step 3/4]         at org.apache.maven.cli.MavenCli.main(MavenCli.java:194)
> [08:59:07] :	 [Step 3/4]         at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
> [08:59:07] :	 [Step 3/4]         at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
> [08:59:07] :	 [Step 3/4]         at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
> [08:59:07] :	 [Step 3/4]         at java.lang.reflect.Method.invoke(Method.java:498)
> [08:59:07] :	 [Step 3/4]         at org.codehaus.plexus.classworlds.launcher.Launcher.launchEnhanced(Launcher.java:289)
> [08:59:07] :	 [Step 3/4]         at org.codehaus.plexus.classworlds.launcher.Launcher.launch(Launcher.java:229)
> [08:59:07] :	 [Step 3/4]         at org.codehaus.plexus.classworlds.launcher.Launcher.mainWithExitCode(Launcher.java:415)
> [08:59:07] :	 [Step 3/4]         at org.codehaus.plexus.classworlds.launcher.Launcher.main(Launcher.java:356)
> [08:59:07] :	 [Step 3/4] 
> [08:59:07] :	 [Step 3/4]     Locked synchronizers:
> [08:59:07] :	 [Step 3/4]         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@561953e3
> [08:59:07] :	 [Step 3/4]         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@443faa85
> [08:59:07] :	 [Step 3/4]         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@1ee47d9e
> [08:59:07] :	 [Step 3/4]         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@41da3aee
> [08:59:07] :	 [Step 3/4]         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@5dc0ff7d
> [08:59:07] :	 [Step 3/4]         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@64920dc2
> [08:59:07] :	 [Step 3/4]         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@59a79443
> [08:59:07] :	 [Step 3/4]         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@493ac8d3
> [08:59:07] :	 [Step 3/4]         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@13dbed9e
> [08:59:07] :	 [Step 3/4]         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@67531e3a
> [08:59:07] :	 [Step 3/4]         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@2b38b1f
> [08:59:07] :	 [Step 3/4]         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@1d50a7ca
> [08:59:07] :	 [Step 3/4]         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@51ab1ee3
> [08:59:07] :	 [Step 3/4]         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@2e766822
> [08:59:07] :	 [Step 3/4]         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@3003e580
> [08:59:07] :	 [Step 3/4]         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@28757abd
> [08:59:07] :	 [Step 3/4]         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@21a02556
> [08:59:07] :	 [Step 3/4]         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@3c8a7e38
> [08:59:07] :	 [Step 3/4]         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@138aa3cc
> [08:59:07] :	 [Step 3/4]         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@10a98392
> [08:59:07] :	 [Step 3/4]         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@30839e44
> [08:59:07] :	 [Step 3/4]         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@5f174dd2
> [08:59:07] :	 [Step 3/4]         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@1945113f
> [08:59:07] :	 [Step 3/4]         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@45aca496
> [08:59:07] :	 [Step 3/4]         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@697173d9
> [08:59:07] :	 [Step 3/4]         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@ceddaf8
> [08:59:07] :	 [Step 3/4]         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@3d90eeb3
> [08:59:07] :	 [Step 3/4]         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@1db87583
> [08:59:07] :	 [Step 3/4]         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@7fb53256
> [08:59:07] :	 [Step 3/4]         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@1d6d1d42
> [08:59:07] :	 [Step 3/4]         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@27329d2a
> [08:59:07] :	 [Step 3/4]         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@7978e022
> [08:59:07] :	 [Step 3/4]         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@2f894ad9
> [08:59:07] :	 [Step 3/4]         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@5afbd567
> [08:59:07] :	 [Step 3/4]         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@6993c8df
> [08:59:07] :	 [Step 3/4]         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@57545c3f
> [08:59:07] :	 [Step 3/4]         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@326e0b8e
> [08:59:07] :	 [Step 3/4]         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@36baa049
> [08:59:07] :	 [Step 3/4]         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@41184371
> [08:59:07] :	 [Step 3/4]         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@34585ac9
> [08:59:07] :	 [Step 3/4]         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@76a805b7
> [08:59:07] :	 [Step 3/4]         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@15f35bc3
> [08:59:07] :	 [Step 3/4]         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@167381c7
> [08:59:07] :	 [Step 3/4]         java.util.concurrent.locks.ReentrantReadWriteLock$NonfairSync@608b1fd2
> [08:59:07] :	 [Step 3/4] 
> {code}
> Currenty it's impossible to finish exchange on the node, which hasn't received FullMessage because of coordinator fail, while new coordinator already done this exchange future. This is the cause of the problem.



--
This message was sent by Atlassian JIRA
(v7.6.3#76005)