Kevin Duling created GEODE-1698: ----------------------------------- Summary: PRColocationDUnitTest.testColocationPartitionedRegionWithNullColocationSpecifiedOnOneNode Key: GEODE-1698 URL: https://issues.apache.org/jira/browse/GEODE-1698 Project: Geode Issue Type: Bug Reporter: Kevin Duling
In Geode_develop_DistributedTests/3398: {noformat} Error Message com.gemstone.gemfire.distributed.DistributedSystemDisconnectedException: This connection to a distributed system has been disconnected., caused by com.gemstone.gemfire.ForcedDisconnectException: Member isn't responding to heartbeat requests Stacktrace com.gemstone.gemfire.distributed.DistributedSystemDisconnectedException: This connection to a distributed system has been disconnected., caused by com.gemstone.gemfire.ForcedDisconnectException: Member isn't responding to heartbeat requests at com.gemstone.gemfire.distributed.internal.InternalDistributedSystem.checkConnected(InternalDistributedSystem.java:790) at com.gemstone.gemfire.distributed.internal.InternalDistributedSystem.getDistributionManager(InternalDistributedSystem.java:1384) at com.gemstone.gemfire.distributed.internal.ReplyProcessor21.getDistributionManager(ReplyProcessor21.java:369) at com.gemstone.gemfire.distributed.internal.ReplyProcessor21.postWait(ReplyProcessor21.java:603) at com.gemstone.gemfire.distributed.internal.ReplyProcessor21.waitForRepliesUninterruptibly(ReplyProcessor21.java:844) at com.gemstone.gemfire.distributed.internal.ReplyProcessor21.waitForRepliesUninterruptibly(ReplyProcessor21.java:796) at com.gemstone.gemfire.distributed.internal.ReplyProcessor21.waitForRepliesUninterruptibly(ReplyProcessor21.java:886) at com.gemstone.gemfire.internal.cache.UpdateAttributesProcessor.waitForProfileResponse(UpdateAttributesProcessor.java:111) at com.gemstone.gemfire.internal.cache.UpdateAttributesProcessor.distribute(UpdateAttributesProcessor.java:99) at com.gemstone.gemfire.distributed.internal.DistributionAdvisor.exchangeProfiles(DistributionAdvisor.java:1131) at com.gemstone.gemfire.distributed.internal.DistributionAdvisor.initializationGate(DistributionAdvisor.java:458) at com.gemstone.gemfire.internal.cache.GemFireCacheImpl.initialize(GemFireCacheImpl.java:1096) at com.gemstone.gemfire.internal.cache.GemFireCacheImpl.basicCreate(GemFireCacheImpl.java:763) at com.gemstone.gemfire.internal.cache.GemFireCacheImpl.create(GemFireCacheImpl.java:751) at com.gemstone.gemfire.cache.CacheFactory.create(CacheFactory.java:181) at com.gemstone.gemfire.cache.CacheFactory.create(CacheFactory.java:172) at com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase.createCache(JUnit4CacheTestCase.java:120) at com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase.getCache(JUnit4CacheTestCase.java:263) at com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase.getCache(JUnit4CacheTestCase.java:242) at com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase.getCache(JUnit4CacheTestCase.java:234) at com.gemstone.gemfire.internal.cache.execute.PRColocationDUnitTest.testColocationPartitionedRegionWithNullColocationSpecifiedOnOneNode(PRColocationDUnitTest.java:1309) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:497) at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:50) at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12) at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:47) at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17) at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:26) at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:27) at org.junit.rules.TestWatcher$1.evaluate(TestWatcher.java:55) at org.junit.rules.RunRules.evaluate(RunRules.java:20) at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:325) at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:78) at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:57) at org.junit.runners.ParentRunner$3.run(ParentRunner.java:290) at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:71) at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:288) at org.junit.runners.ParentRunner.access$000(ParentRunner.java:58) at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:268) at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:26) at org.junit.runners.ParentRunner.run(ParentRunner.java:363) at org.gradle.api.internal.tasks.testing.junit.JUnitTestClassExecuter.runTestClass(JUnitTestClassExecuter.java:112) at org.gradle.api.internal.tasks.testing.junit.JUnitTestClassExecuter.execute(JUnitTestClassExecuter.java:56) at org.gradle.api.internal.tasks.testing.junit.JUnitTestClassProcessor.processTestClass(JUnitTestClassProcessor.java:66) at org.gradle.api.internal.tasks.testing.SuiteTestClassProcessor.processTestClass(SuiteTestClassProcessor.java:51) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:497) at org.gradle.messaging.dispatch.ReflectionDispatch.dispatch(ReflectionDispatch.java:35) at org.gradle.messaging.dispatch.ReflectionDispatch.dispatch(ReflectionDispatch.java:24) at org.gradle.messaging.dispatch.ContextClassLoaderDispatch.dispatch(ContextClassLoaderDispatch.java:32) at org.gradle.messaging.dispatch.ProxyDispatchAdapter$DispatchingInvocationHandler.invoke(ProxyDispatchAdapter.java:93) at com.sun.proxy.$Proxy2.processTestClass(Unknown Source) at org.gradle.api.internal.tasks.testing.worker.TestWorker.processTestClass(TestWorker.java:109) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:497) at org.gradle.messaging.dispatch.ReflectionDispatch.dispatch(ReflectionDispatch.java:35) at org.gradle.messaging.dispatch.ReflectionDispatch.dispatch(ReflectionDispatch.java:24) at org.gradle.messaging.remote.internal.hub.MessageHub$Handler.run(MessageHub.java:360) at org.gradle.internal.concurrent.ExecutorPolicy$CatchAndRecordFailures.onExecute(ExecutorPolicy.java:54) at org.gradle.internal.concurrent.StoppableExecutorImpl$1.run(StoppableExecutorImpl.java:40) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) at java.lang.Thread.run(Thread.java:745) Caused by: com.gemstone.gemfire.ForcedDisconnectException: Member isn't responding to heartbeat requests at com.gemstone.gemfire.distributed.internal.membership.gms.mgr.GMSMembershipManager.forceDisconnect(GMSMembershipManager.java:2606) at com.gemstone.gemfire.distributed.internal.membership.gms.membership.GMSJoinLeave.forceDisconnect(GMSJoinLeave.java:918) at com.gemstone.gemfire.distributed.internal.membership.gms.membership.GMSJoinLeave.processRemoveRequest(GMSJoinLeave.java:593) at com.gemstone.gemfire.distributed.internal.membership.gms.membership.GMSJoinLeave.processMessage(GMSJoinLeave.java:1555) at com.gemstone.gemfire.distributed.internal.membership.gms.messenger.JGroupsMessenger$JGroupsReceiver.receive(JGroupsMessenger.java:1065) at org.jgroups.JChannel.invokeCallback(JChannel.java:816) at org.jgroups.JChannel.up(JChannel.java:741) at org.jgroups.stack.ProtocolStack.up(ProtocolStack.java:1030) at org.jgroups.protocols.FRAG2.up(FRAG2.java:165) at org.jgroups.protocols.FlowControl.up(FlowControl.java:390) at org.jgroups.protocols.UNICAST3.deliverMessage(UNICAST3.java:1070) at org.jgroups.protocols.UNICAST3.handleDataReceived(UNICAST3.java:785) at org.jgroups.protocols.UNICAST3.up(UNICAST3.java:426) at com.gemstone.gemfire.distributed.internal.membership.gms.messenger.StatRecorder.up(StatRecorder.java:75) at com.gemstone.gemfire.distributed.internal.membership.gms.messenger.AddressManager.up(AddressManager.java:75) at org.jgroups.protocols.TP.passMessageUp(TP.java:1601) at org.jgroups.protocols.TP$SingleMessageHandler.run(TP.java:1817) at org.jgroups.util.DirectExecutor.execute(DirectExecutor.java:10) at org.jgroups.protocols.TP.handleSingleMessage(TP.java:1729) at org.jgroups.protocols.TP.receive(TP.java:1654) at com.gemstone.gemfire.distributed.internal.membership.gms.messenger.Transport.receive(Transport.java:162) at org.jgroups.protocols.UDP$PacketReceiver.run(UDP.java:701) ... 1 more Standard Output Previously run tests: [PRClientServerFunctionExecutionNoAckDUnitTest, PRFunctionExecutionTimeOutDUnitTest, PRClientServerRegionFunctionExecutionSelectorNoSingleHopDUnitTest, LocalDataSetDUnitTest, PRColocationDUnitTest] [vm_0][info 2016/07/25 12:43:17.113 PDT <RMI TCP Connection(5)-10.118.32.93> tid=0x1b] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(setupVM) [vm_0][info 2016/07/25 12:43:17.113 PDT <RMI TCP Connection(5)-10.118.32.93> tid=0x1b] Got result: null [vm_0] from com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(setupVM) (took 0 ms) [vm_1][info 2016/07/25 12:43:17.114 PDT <RMI TCP Connection(5)-10.118.32.93> tid=0x1b] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(setupVM) [vm_1][info 2016/07/25 12:43:17.114 PDT <RMI TCP Connection(5)-10.118.32.93> tid=0x1b] Got result: null [vm_1] from com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(setupVM) (took 0 ms) [vm_2][info 2016/07/25 12:43:17.115 PDT <RMI TCP Connection(5)-10.118.32.93> tid=0x1b] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(setupVM) [vm_2][info 2016/07/25 12:43:17.115 PDT <RMI TCP Connection(5)-10.118.32.93> tid=0x1b] Got result: null [vm_2] from com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(setupVM) (took 0 ms) [vm_3][info 2016/07/25 12:43:17.116 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(setupVM) [vm_3][info 2016/07/25 12:43:17.116 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Got result: null [vm_3] from com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(setupVM) (took 0 ms) [setup] START TEST PRColocationDUnitTest.testColocationPartitionedRegion [vm_0][info 2016/07/25 12:43:17.117 PDT <RMI TCP Connection(5)-10.118.32.93> tid=0x1b] Received method: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$14/119712919.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$14/119712919@2a87f435 [vm_0][info 2016/07/25 12:43:17.117 PDT <RMI TCP Connection(5)-10.118.32.93> tid=0x1b] Got result: null [vm_0] from com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$14/119712919.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$14/119712919@2a87f435 (took 0 ms) [vm_1][info 2016/07/25 12:43:17.117 PDT <RMI TCP Connection(5)-10.118.32.93> tid=0x1b] Received method: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$15/1694237478.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$15/1694237478@60813d32 [vm_1][info 2016/07/25 12:43:17.118 PDT <RMI TCP Connection(5)-10.118.32.93> tid=0x1b] Got result: null [vm_1] from com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$15/1694237478.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$15/1694237478@60813d32 (took 0 ms) [vm_2][info 2016/07/25 12:43:17.118 PDT <RMI TCP Connection(5)-10.118.32.93> tid=0x1b] Received method: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$15/1756751533.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$15/1756751533@32c07daf [vm_2][info 2016/07/25 12:43:17.119 PDT <RMI TCP Connection(5)-10.118.32.93> tid=0x1b] Got result: null [vm_2] from com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$15/1756751533.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$15/1756751533@32c07daf (took 0 ms) [vm_3][info 2016/07/25 12:43:17.119 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Received method: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$17/1943817067.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$17/1943817067@1c755dca [vm_3][info 2016/07/25 12:43:17.119 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Got result: null [vm_3] from com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$17/1943817067.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$17/1943817067@1c755dca (took 0 ms) [vm_0][info 2016/07/25 12:43:17.130 PDT <RMI TCP Connection(5)-10.118.32.93> tid=0x1b] Received method: com.gemstone.gemfire.internal.cache.execute.PRColocationDUnitTest$$Lambda$28/1063736539.run with 0 args on object: com.gemstone.gemfire.internal.cache.execute.PRColocationDUnitTest$$Lambda$28/1063736539@4a6474c4 [vm_0]distributed system properties: {locators=localhost[50213], enable-cluster-configuration=false, use-cluster-configuration=false, mcast-port=0, disable-auto-reconnect=true, log-level=info} [vm_0][info 2016/07/25 12:43:17.133 PDT <RMI TCP Connection(5)-10.118.32.93> tid=0x1b] Startup Configuration: [vm_0] ### GemFire Properties defined with api ### [vm_0]disable-auto-reconnect=true [vm_0]enable-cluster-configuration=false [vm_0]locators=localhost[50213] [vm_0]log-level=info [vm_0]mcast-port=0 [vm_0]use-cluster-configuration=false [vm_0]### GemFire Properties using default values ### [vm_0]ack-severe-alert-threshold=0 [vm_0]ack-wait-threshold=15 [vm_0]archive-disk-space-limit=0 [vm_0]archive-file-size-limit=0 [vm_0]async-distribution-timeout=0 [vm_0]async-max-queue-size=8 [vm_0]async-queue-timeout=60000 [vm_0]bind-address= [vm_0]cache-xml-file=cache.xml [vm_0]cluster-configuration-dir= [vm_0]cluster-ssl-ciphers=any [vm_0]cluster-ssl-enabled=false [vm_0]cluster-ssl-keystore= [vm_0]cluster-ssl-keystore-password= [vm_0]cluster-ssl-keystore-type= [vm_0]cluster-ssl-protocols=any [vm_0]cluster-ssl-require-authentication=true [vm_0]cluster-ssl-truststore= [vm_0]cluster-ssl-truststore-password= [vm_0]conflate-events=server [vm_0]conserve-sockets=true [vm_0]delta-propagation=true [vm_0]deploy-working-dir=. [vm_0]disable-tcp=false [vm_0]distributed-system-id=-1 [vm_0]distributed-transactions=false [vm_0]durable-client-id= [vm_0]durable-client-timeout=300 [vm_0]enable-network-partition-detection=false [vm_0]enable-time-statistics=false [vm_0]enforce-unique-host=false [vm_0]gateway-ssl-ciphers=any [vm_0]gateway-ssl-enabled=false [vm_0]gateway-ssl-keystore= [vm_0]gateway-ssl-keystore-password= [vm_0]gateway-ssl-keystore-type= [vm_0]gateway-ssl-protocols=any [vm_0]gateway-ssl-require-authentication=true [vm_0]gateway-ssl-truststore= [vm_0]gateway-ssl-truststore-password= [vm_0]groups= [vm_0]http-service-bind-address= [vm_0]http-service-port=7070 [vm_0]http-service-ssl-ciphers=any [vm_0]http-service-ssl-enabled=false [vm_0]http-service-ssl-keystore= [vm_0]http-service-ssl-keystore-password= [vm_0]http-service-ssl-keystore-type= [vm_0]http-service-ssl-protocols=any [vm_0]http-service-ssl-require-authentication=false [vm_0]http-service-ssl-truststore= [vm_0]http-service-ssl-truststore-password= [vm_0]jmx-manager=false [vm_0]jmx-manager-access-file= [vm_0]jmx-manager-bind-address= [vm_0]jmx-manager-hostname-for-clients= [vm_0]jmx-manager-http-port=7070 [vm_0]jmx-manager-password-file= [vm_0]jmx-manager-port=1099 [vm_0]jmx-manager-ssl=false [vm_0]jmx-manager-ssl-ciphers=any [vm_0]jmx-manager-ssl-enabled=false [vm_0]jmx-manager-ssl-keystore= [vm_0]jmx-manager-ssl-keystore-password= [vm_0]jmx-manager-ssl-keystore-type= [vm_0]jmx-manager-ssl-protocols=any [vm_0]jmx-manager-ssl-require-authentication=true [vm_0]jmx-manager-ssl-truststore= [vm_0]jmx-manager-ssl-truststore-password= [vm_0]jmx-manager-start=false [vm_0]jmx-manager-update-rate=2000 [vm_0]load-cluster-configuration-from-dir=false [vm_0]locator-wait-time=0 [vm_0]lock-memory=false [vm_0]log-disk-space-limit=0 [vm_0]log-file= [vm_0]log-file-size-limit=0 [vm_0]max-num-reconnect-tries=3 [vm_0]max-wait-time-reconnect=60000 [vm_0]mcast-address=239.192.81.1 [vm_0]mcast-flow-control=1048576, 0.25, 5000 [vm_0]mcast-recv-buffer-size=1048576 [vm_0]mcast-send-buffer-size=65535 [vm_0]mcast-ttl=32 [vm_0]member-timeout=5000 [vm_0]membership-port-range=1024-65535 [vm_0]memcached-bind-address= [vm_0]memcached-port=0 [vm_0]memcached-protocol=ASCII [vm_0]name= [vm_0]off-heap-memory-size= [vm_0]redis-bind-address= [vm_0]redis-password= [vm_0]redis-port=0 [vm_0]redundancy-zone= [vm_0]remote-locators= [vm_0]remove-unresponsive-client=false [vm_0]roles= [vm_0]security-client-accessor= [vm_0]security-client-accessor-pp= [vm_0]security-client-auth-init= [vm_0]security-client-authenticator= [vm_0]security-client-dhalgo= [vm_0]security-log-file= [vm_0]security-log-level=config [vm_0]security-manager= [vm_0]security-peer-auth-init= [vm_0]security-peer-authenticator= [vm_0]security-peer-verifymember-timeout=1000 [vm_0]security-post-processor= [vm_0]server-bind-address= [vm_0]server-ssl-ciphers=any [vm_0]server-ssl-enabled=false [vm_0]server-ssl-keystore= [vm_0]server-ssl-keystore-password= [vm_0]server-ssl-keystore-type= [vm_0]server-ssl-protocols=any [vm_0]server-ssl-require-authentication=true [vm_0]server-ssl-truststore= [vm_0]server-ssl-truststore-password= [vm_0]socket-buffer-size=32768 [vm_0]socket-lease-time=60000 [vm_0]ssl-ciphers=any [vm_0]ssl-enabled=false [vm_0]ssl-protocols=any [vm_0]ssl-require-authentication=true [vm_0]start-dev-rest-api=false [vm_0]start-locator= [vm_0]statistic-archive-file= [vm_0]statistic-sample-rate=1000 [vm_0]statistic-sampling-enabled=true [vm_0]tcp-port=0 [vm_0]udp-fragment-size=60000 [vm_0]udp-recv-buffer-size=1048576 [vm_0]udp-send-buffer-size=65535 [vm_0]user-command-packages= [vm_0][info 2016/07/25 12:43:17.134 PDT <RMI TCP Connection(5)-10.118.32.93> tid=0x1b] Starting membership services [vm_0][info 2016/07/25 12:43:17.142 PDT <RMI TCP Connection(5)-10.118.32.93> tid=0x1b] JGroups channel created (took 8ms) [vm_0][info 2016/07/25 12:43:17.144 PDT <RMI TCP Connection(5)-10.118.32.93> tid=0x1b] GemFire P2P Listener started on null [vm_0][info 2016/07/25 12:43:17.144 PDT <Geode Failure Detection Server thread 0> tid=0xc21] Started failure detection server thread on kuwait.gemstone.com/10.118.32.93:51006. [vm_0][info 2016/07/25 12:43:17.146 PDT <RMI TCP Connection(5)-10.118.32.93> tid=0x1b] Attempting to join the distributed system through coordinator kuwait(10426:locator)<ec><v0>:1024 using address kuwait(10504)<ec>:1025 [locator][info 2016/07/25 12:43:17.146 PDT <unicast receiver,kuwait-22220> tid=0x2a] received join request from kuwait(10504)<ec>:1025 [locator][info 2016/07/25 12:43:17.347 PDT <Geode Membership View Creator> tid=0x30] View Creator is processing 4 requests for the next membership view [locator][info 2016/07/25 12:43:17.347 PDT <Geode Membership View Creator> tid=0x30] preparing new view View[kuwait(10426:locator)<ec><v0>:1024|193] members: [kuwait(10426:locator)<ec><v0>:1024, kuwait(10504)<ec><v193>:1025{lead}] shutdown: [kuwait(10518)<ec><v191>:1028] [locator]failure detection ports: 26403 51006 [vm_0][info 2016/07/25 12:43:17.347 PDT <unicast receiver,kuwait-52318> tid=0xc1d] received new view: View[kuwait(10426:locator)<ec><v0>:1024|193] members: [kuwait(10426:locator)<ec><v0>:1024, kuwait(10504)<ec><v193>:1025{lead}] shutdown: [kuwait(10518)<ec><v191>:1028] [vm_0]old view is: null [locator][info 2016/07/25 12:43:17.348 PDT <Geode Membership View Creator> tid=0x30] finished waiting for responses to view preparation [locator][info 2016/07/25 12:43:17.348 PDT <Geode Membership View Creator> tid=0x30] received new view: View[kuwait(10426:locator)<ec><v0>:1024|193] members: [kuwait(10426:locator)<ec><v0>:1024, kuwait(10504)<ec><v193>:1025{lead}] shutdown: [kuwait(10518)<ec><v191>:1028] [locator]old view is: View[kuwait(10426:locator)<ec><v0>:1024|192] members: [kuwait(10426:locator)<ec><v0>:1024, kuwait(10518)<ec><v191>:1028{lead}] shutdown: [kuwait(10513)<ec><v190>:1027, kuwait(10504)<ec><v188>:1025, kuwait(10508)<ec><v189>:1026] [locator][info 2016/07/25 12:43:17.348 PDT <Geode Membership View Creator> tid=0x30] Peer locator received new membership view: View[kuwait(10426:locator)<ec><v0>:1024|193] members: [kuwait(10426:locator)<ec><v0>:1024, kuwait(10504)<ec><v193>:1025{lead}] shutdown: [kuwait(10518)<ec><v191>:1028] [vm_0][info 2016/07/25 12:43:17.348 PDT <RMI TCP Connection(5)-10.118.32.93> tid=0x1b] Finished joining (took 204ms). [vm_0][info 2016/07/25 12:43:17.348 PDT <RMI TCP Connection(5)-10.118.32.93> tid=0x1b] Starting DistributionManager kuwait(10504)<ec><v193>:1025. (took 214 ms) [locator][info 2016/07/25 12:43:17.348 PDT <Geode Membership View Creator> tid=0x30] sending new view View[kuwait(10426:locator)<ec><v0>:1024|193] members: [kuwait(10426:locator)<ec><v0>:1024, kuwait(10504)<ec><v193>:1025{lead}] shutdown: [kuwait(10518)<ec><v191>:1028] [locator]failure detection ports: 26403 51006 [vm_0][info 2016/07/25 12:43:17.348 PDT <RMI TCP Connection(5)-10.118.32.93> tid=0x1b] Initial (distribution manager) view = View[kuwait(10426:locator)<ec><v0>:1024|193] members: [kuwait(10426:locator)<ec><v0>:1024, kuwait(10504)<ec><v193>:1025{lead}] shutdown: [kuwait(10518)<ec><v191>:1028] [locator][info 2016/07/25 12:43:17.348 PDT <View Message Processor> tid=0x3c] Membership: Processing addition < kuwait(10504)<ec><v193>:1025 > [vm_0][info 2016/07/25 12:43:17.348 PDT <RMI TCP Connection(5)-10.118.32.93> tid=0x1b] Admitting member <kuwait(10426:locator)<ec><v0>:1024>. Now there are 1 non-admin member(s). [vm_0][info 2016/07/25 12:43:17.349 PDT <RMI TCP Connection(5)-10.118.32.93> tid=0x1b] Admitting member <kuwait(10504)<ec><v193>:1025>. Now there are 2 non-admin member(s). [locator][info 2016/07/25 12:43:17.349 PDT <View Message Processor> tid=0x3c] Admitting member <kuwait(10504)<ec><v193>:1025>. Now there are 2 non-admin member(s). [locator][info 2016/07/25 12:43:17.351 PDT <Pooled High Priority Message Processor 1> tid=0x40] Member kuwait(10504)<ec><v193>:1025 is equivalent or in the same redundancy zone. [vm_0][info 2016/07/25 12:43:17.353 PDT <P2P message reader for kuwait(10426:locator)<ec><v0>:1024 shared unordered uid=180 port=44371> tid=0xc26] Member kuwait(10426:locator)<ec><v0>:1024 is equivalent or in the same redundancy zone. [vm_0][info 2016/07/25 12:43:17.363 PDT <RMI TCP Connection(5)-10.118.32.93> tid=0x1b] Initializing region _monitoringRegion_10.118.32.93<v193>1025 [vm_0][info 2016/07/25 12:43:17.364 PDT <RMI TCP Connection(5)-10.118.32.93> tid=0x1b] Initialization of region _monitoringRegion_10.118.32.93<v193>1025 completed [vm_0][info 2016/07/25 12:43:17.368 PDT <RMI TCP Connection(5)-10.118.32.93> tid=0x1b] The cache has been created with "use-cluster-configuration=false". It will not receive any cluster configuration [vm_0][info 2016/07/25 12:43:17.368 PDT <RMI TCP Connection(5)-10.118.32.93> tid=0x1b] Initializing region PdxTypes [vm_0][info 2016/07/25 12:43:17.369 PDT <RMI TCP Connection(5)-10.118.32.93> tid=0x1b] Initialization of region PdxTypes completed [vm_0][info 2016/07/25 12:43:17.369 PDT <RMI TCP Connection(5)-10.118.32.93> tid=0x1b] Got result: null [vm_0] from com.gemstone.gemfire.internal.cache.execute.PRColocationDUnitTest$$Lambda$28/1063736539.run with 0 args on object: com.gemstone.gemfire.internal.cache.execute.PRColocationDUnitTest$$Lambda$28/1063736539@4a6474c4 (took 239 ms) [vm_1][info 2016/07/25 12:43:17.380 PDT <RMI TCP Connection(5)-10.118.32.93> tid=0x1b] Received method: com.gemstone.gemfire.internal.cache.execute.PRColocationDUnitTest$$Lambda$26/1563698689.run with 0 args on object: com.gemstone.gemfire.internal.cache.execute.PRColocationDUnitTest$$Lambda$26/1563698689@341fab37 [vm_1]distributed system properties: {locators=localhost[50213], enable-cluster-configuration=false, use-cluster-configuration=false, mcast-port=0, disable-auto-reconnect=true, log-level=info} [vm_1][info 2016/07/25 12:43:17.383 PDT <RMI TCP Connection(5)-10.118.32.93> tid=0x1b] Startup Configuration: [vm_1] ### GemFire Properties defined with api ### [vm_1]disable-auto-reconnect=true [vm_1]enable-cluster-configuration=false [vm_1]locators=localhost[50213] [vm_1]log-level=info [vm_1]mcast-port=0 [vm_1]use-cluster-configuration=false [vm_1]### GemFire Properties using default values ### [vm_1]ack-severe-alert-threshold=0 [vm_1]ack-wait-threshold=15 [vm_1]archive-disk-space-limit=0 [vm_1]archive-file-size-limit=0 [vm_1]async-distribution-timeout=0 [vm_1]async-max-queue-size=8 [vm_1]async-queue-timeout=60000 [vm_1]bind-address= [vm_1]cache-xml-file=cache.xml [vm_1]cluster-configuration-dir= [vm_1]cluster-ssl-ciphers=any [vm_1]cluster-ssl-enabled=false [vm_1]cluster-ssl-keystore= [vm_1]cluster-ssl-keystore-password= [vm_1]cluster-ssl-keystore-type= [vm_1]cluster-ssl-protocols=any [vm_1]cluster-ssl-require-authentication=true [vm_1]cluster-ssl-truststore= [vm_1]cluster-ssl-truststore-password= [vm_1]conflate-events=server [vm_1]conserve-sockets=true [vm_1]delta-propagation=true [vm_1]deploy-working-dir=. [vm_1]disable-tcp=false [vm_1]distributed-system-id=-1 [vm_1]distributed-transactions=false [vm_1]durable-client-id= [vm_1]durable-client-timeout=300 [vm_1]enable-network-partition-detection=false [vm_1]enable-time-statistics=false [vm_1]enforce-unique-host=false [vm_1]gateway-ssl-ciphers=any [vm_1]gateway-ssl-enabled=false [vm_1]gateway-ssl-keystore= [vm_1]gateway-ssl-keystore-password= [vm_1]gateway-ssl-keystore-type= [vm_1]gateway-ssl-protocols=any [vm_1]gateway-ssl-require-authentication=true [vm_1]gateway-ssl-truststore= [vm_1]gateway-ssl-truststore-password= [vm_1]groups= [vm_1]http-service-bind-address= [vm_1]http-service-port=7070 [vm_1]http-service-ssl-ciphers=any [vm_1]http-service-ssl-enabled=false [vm_1]http-service-ssl-keystore= [vm_1]http-service-ssl-keystore-password= [vm_1]http-service-ssl-keystore-type= [vm_1]http-service-ssl-protocols=any [vm_1]http-service-ssl-require-authentication=false [vm_1]http-service-ssl-truststore= [vm_1]http-service-ssl-truststore-password= [vm_1]jmx-manager=false [vm_1]jmx-manager-access-file= [vm_1]jmx-manager-bind-address= [vm_1]jmx-manager-hostname-for-clients= [vm_1]jmx-manager-http-port=7070 [vm_1]jmx-manager-password-file= [vm_1]jmx-manager-port=1099 [vm_1]jmx-manager-ssl=false [vm_1]jmx-manager-ssl-ciphers=any [vm_1]jmx-manager-ssl-enabled=false [vm_1]jmx-manager-ssl-keystore= [vm_1]jmx-manager-ssl-keystore-password= [vm_1]jmx-manager-ssl-keystore-type= [vm_1]jmx-manager-ssl-protocols=any [vm_1]jmx-manager-ssl-require-authentication=true [vm_1]jmx-manager-ssl-truststore= [vm_1]jmx-manager-ssl-truststore-password= [vm_1]jmx-manager-start=false [vm_1]jmx-manager-update-rate=2000 [vm_1]load-cluster-configuration-from-dir=false [vm_1]locator-wait-time=0 [vm_1]lock-memory=false [vm_1]log-disk-space-limit=0 [vm_1]log-file= [vm_1]log-file-size-limit=0 [vm_1]max-num-reconnect-tries=3 [vm_1]max-wait-time-reconnect=60000 [vm_1]mcast-address=239.192.81.1 [vm_1]mcast-flow-control=1048576, 0.25, 5000 [vm_1]mcast-recv-buffer-size=1048576 [vm_1]mcast-send-buffer-size=65535 [vm_1]mcast-ttl=32 [vm_1]member-timeout=5000 [vm_1]membership-port-range=1024-65535 [vm_1]memcached-bind-address= [vm_1]memcached-port=0 [vm_1]memcached-protocol=ASCII [vm_1]name= [vm_1]off-heap-memory-size= [vm_1]redis-bind-address= [vm_1]redis-password= [vm_1]redis-port=0 [vm_1]redundancy-zone= [vm_1]remote-locators= [vm_1]remove-unresponsive-client=false [vm_1]roles= [vm_1]security-client-accessor= [vm_1]security-client-accessor-pp= [vm_1]security-client-auth-init= [vm_1]security-client-authenticator= [vm_1]security-client-dhalgo= [vm_1]security-log-file= [vm_1]security-log-level=config [vm_1]security-manager= [vm_1]security-peer-auth-init= [vm_1]security-peer-authenticator= [vm_1]security-peer-verifymember-timeout=1000 [vm_1]security-post-processor= [vm_1]server-bind-address= [vm_1]server-ssl-ciphers=any [vm_1]server-ssl-enabled=false [vm_1]server-ssl-keystore= [vm_1]server-ssl-keystore-password= [vm_1]server-ssl-keystore-type= [vm_1]server-ssl-protocols=any [vm_1]server-ssl-require-authentication=true [vm_1]server-ssl-truststore= [vm_1]server-ssl-truststore-password= [vm_1]socket-buffer-size=32768 [vm_1]socket-lease-time=60000 [vm_1]ssl-ciphers=any [vm_1]ssl-enabled=false [vm_1]ssl-protocols=any [vm_1]ssl-require-authentication=true [vm_1]start-dev-rest-api=false [vm_1]start-locator= [vm_1]statistic-archive-file= [vm_1]statistic-sample-rate=1000 [vm_1]statistic-sampling-enabled=true [vm_1]tcp-port=0 [vm_1]udp-fragment-size=60000 [vm_1]udp-recv-buffer-size=1048576 [vm_1]udp-send-buffer-size=65535 [vm_1]user-command-packages= [vm_1][info 2016/07/25 12:43:17.385 PDT <RMI TCP Connection(5)-10.118.32.93> tid=0x1b] Starting membership services [vm_1][info 2016/07/25 12:43:17.394 PDT <RMI TCP Connection(5)-10.118.32.93> tid=0x1b] JGroups channel created (took 9ms) [vm_1][info 2016/07/25 12:43:17.396 PDT <RMI TCP Connection(5)-10.118.32.93> tid=0x1b] GemFire P2P Listener started on null [vm_1][info 2016/07/25 12:43:17.396 PDT <Geode Failure Detection Server thread 0> tid=0xcc1] Started failure detection server thread on kuwait.gemstone.com/10.118.32.93:56242. [vm_1][info 2016/07/25 12:43:17.398 PDT <RMI TCP Connection(5)-10.118.32.93> tid=0x1b] Attempting to join the distributed system through coordinator kuwait(10426:locator)<ec><v0>:1024 using address kuwait(10508)<ec>:1026 [locator][info 2016/07/25 12:43:17.398 PDT <unicast receiver,kuwait-22220> tid=0x2a] received join request from kuwait(10508)<ec>:1026 [locator][info 2016/07/25 12:43:17.699 PDT <Geode Membership View Creator> tid=0x30] View Creator is processing 1 requests for the next membership view [locator][info 2016/07/25 12:43:17.699 PDT <Geode Membership View Creator> tid=0x30] preparing new view View[kuwait(10426:locator)<ec><v0>:1024|194] members: [kuwait(10426:locator)<ec><v0>:1024, kuwait(10504)<ec><v193>:1025{lead}, kuwait(10508)<ec><v194>:1026] [locator]failure detection ports: 26403 51006 56242 [vm_1][info 2016/07/25 12:43:17.700 PDT <unicast receiver,kuwait-28418> tid=0xcbd] received new view: View[kuwait(10426:locator)<ec><v0>:1024|194] members: [kuwait(10426:locator)<ec><v0>:1024, kuwait(10504)<ec><v193>:1025{lead}, kuwait(10508)<ec><v194>:1026] [vm_1]old view is: null [locator][info 2016/07/25 12:43:17.700 PDT <Geode Membership View Creator> tid=0x30] finished waiting for responses to view preparation [locator][info 2016/07/25 12:43:17.700 PDT <Geode Membership View Creator> tid=0x30] received new view: View[kuwait(10426:locator)<ec><v0>:1024|194] members: [kuwait(10426:locator)<ec><v0>:1024, kuwait(10504)<ec><v193>:1025{lead}, kuwait(10508)<ec><v194>:1026] [locator]old view is: View[kuwait(10426:locator)<ec><v0>:1024|193] members: [kuwait(10426:locator)<ec><v0>:1024, kuwait(10504)<ec><v193>:1025{lead}] shutdown: [kuwait(10518)<ec><v191>:1028] [vm_1][info 2016/07/25 12:43:17.700 PDT <RMI TCP Connection(5)-10.118.32.93> tid=0x1b] Finished joining (took 304ms). [vm_1][info 2016/07/25 12:43:17.700 PDT <RMI TCP Connection(5)-10.118.32.93> tid=0x1b] Starting DistributionManager kuwait(10508)<ec><v194>:1026. (took 315 ms) [locator][info 2016/07/25 12:43:17.700 PDT <Geode Membership View Creator> tid=0x30] Peer locator received new membership view: View[kuwait(10426:locator)<ec><v0>:1024|194] members: [kuwait(10426:locator)<ec><v0>:1024, kuwait(10504)<ec><v193>:1025{lead}, kuwait(10508)<ec><v194>:1026] [locator][info 2016/07/25 12:43:17.700 PDT <Geode Membership View Creator> tid=0x30] sending new view View[kuwait(10426:locator)<ec><v0>:1024|194] members: [kuwait(10426:locator)<ec><v0>:1024, kuwait(10504)<ec><v193>:1025{lead}, kuwait(10508)<ec><v194>:1026] [locator]failure detection ports: 26403 51006 56242 [vm_1][info 2016/07/25 12:43:17.700 PDT <RMI TCP Connection(5)-10.118.32.93> tid=0x1b] Initial (distribution manager) view = View[kuwait(10426:locator)<ec><v0>:1024|194] members: [kuwait(10426:locator)<ec><v0>:1024, kuwait(10504)<ec><v193>:1025{lead}, kuwait(10508)<ec><v194>:1026] [vm_1][info 2016/07/25 12:43:17.700 PDT <RMI TCP Connection(5)-10.118.32.93> tid=0x1b] Admitting member <kuwait(10426:locator)<ec><v0>:1024>. Now there are 1 non-admin member(s). [locator][info 2016/07/25 12:43:17.700 PDT <View Message Processor> tid=0x3c] Membership: Processing addition < kuwait(10508)<ec><v194>:1026 > [vm_1][info 2016/07/25 12:43:17.701 PDT <RMI TCP Connection(5)-10.118.32.93> tid=0x1b] Admitting member <kuwait(10504)<ec><v193>:1025>. Now there are 2 non-admin member(s). [vm_1][info 2016/07/25 12:43:17.701 PDT <RMI TCP Connection(5)-10.118.32.93> tid=0x1b] Admitting member <kuwait(10508)<ec><v194>:1026>. Now there are 3 non-admin member(s). [locator][info 2016/07/25 12:43:17.701 PDT <View Message Processor> tid=0x3c] Admitting member <kuwait(10508)<ec><v194>:1026>. Now there are 3 non-admin member(s). [vm_0][info 2016/07/25 12:43:17.701 PDT <unicast receiver,kuwait-52318> tid=0xc1d] received new view: View[kuwait(10426:locator)<ec><v0>:1024|194] members: [kuwait(10426:locator)<ec><v0>:1024, kuwait(10504)<ec><v193>:1025{lead}, kuwait(10508)<ec><v194>:1026] [vm_0]old view is: View[kuwait(10426:locator)<ec><v0>:1024|193] members: [kuwait(10426:locator)<ec><v0>:1024, kuwait(10504)<ec><v193>:1025{lead}] shutdown: [kuwait(10518)<ec><v191>:1028] [vm_0][info 2016/07/25 12:43:17.701 PDT <View Message Processor> tid=0xc34] Membership: Processing addition < kuwait(10508)<ec><v194>:1026 > [vm_0][info 2016/07/25 12:43:17.702 PDT <View Message Processor> tid=0xc34] Admitting member <kuwait(10508)<ec><v194>:1026>. Now there are 3 non-admin member(s). [vm_0][info 2016/07/25 12:43:17.703 PDT <Pooled High Priority Message Processor 1> tid=0xc31] Member kuwait(10508)<ec><v194>:1026 is equivalent or in the same redundancy zone. [locator][info 2016/07/25 12:43:17.703 PDT <Pooled High Priority Message Processor 1> tid=0x40] Member kuwait(10508)<ec><v194>:1026 is equivalent or in the same redundancy zone. [vm_1][info 2016/07/25 12:43:17.705 PDT <P2P message reader for kuwait(10504)<ec><v193>:1025 shared unordered uid=267 port=33618> tid=0xcc9] Member kuwait(10504)<ec><v193>:1025 is equivalent or in the same redundancy zone. [vm_1][info 2016/07/25 12:43:17.706 PDT <P2P message reader for kuwait(10426:locator)<ec><v0>:1024 shared unordered uid=181 port=33619> tid=0xcca] Member kuwait(10426:locator)<ec><v0>:1024 is equivalent or in the same redundancy zone. [vm_1][info 2016/07/25 12:43:17.714 PDT <RMI TCP Connection(5)-10.118.32.93> tid=0x1b] Initializing region _monitoringRegion_10.118.32.93<v194>1026 [vm_1][info 2016/07/25 12:43:17.716 PDT <RMI TCP Connection(5)-10.118.32.93> tid=0x1b] Initialization of region _monitoringRegion_10.118.32.93<v194>1026 completed [vm_1][info 2016/07/25 12:43:17.719 PDT <RMI TCP Connection(5)-10.118.32.93> tid=0x1b] The cache has been created with "use-cluster-configuration=false". It will not receive any cluster configuration [vm_1][info 2016/07/25 12:43:17.719 PDT <RMI TCP Connection(5)-10.118.32.93> tid=0x1b] Initializing region PdxTypes [vm_1][info 2016/07/25 12:43:17.722 PDT <RMI TCP Connection(5)-10.118.32.93> tid=0x1b] Region PdxTypes requesting initial image from kuwait(10504)<ec><v193>:1025 [vm_1][info 2016/07/25 12:43:17.724 PDT <RMI TCP Connection(5)-10.118.32.93> tid=0x1b] PdxTypes is done getting image from kuwait(10504)<ec><v193>:1025. isDeltaGII is false [vm_1][info 2016/07/25 12:43:17.724 PDT <RMI TCP Connection(5)-10.118.32.93> tid=0x1b] Initialization of region PdxTypes completed [vm_1][info 2016/07/25 12:43:17.724 PDT <RMI TCP Connection(5)-10.118.32.93> tid=0x1b] Got result: null [vm_1] from com.gemstone.gemfire.internal.cache.execute.PRColocationDUnitTest$$Lambda$26/1563698689.run with 0 args on object: com.gemstone.gemfire.internal.cache.execute.PRColocationDUnitTest$$Lambda$26/1563698689@341fab37 (took 344 ms) [vm_2][info 2016/07/25 12:43:17.734 PDT <RMI TCP Connection(5)-10.118.32.93> tid=0x1b] Received method: com.gemstone.gemfire.internal.cache.execute.PRColocationDUnitTest$$Lambda$26/288578415.run with 0 args on object: com.gemstone.gemfire.internal.cache.execute.PRColocationDUnitTest$$Lambda$26/288578415@33bf154a [vm_2]distributed system properties: {locators=localhost[50213], enable-cluster-configuration=false, use-cluster-configuration=false, mcast-port=0, disable-auto-reconnect=true, log-level=info} [vm_2][info 2016/07/25 12:43:17.737 PDT <RMI TCP Connection(5)-10.118.32.93> tid=0x1b] Startup Configuration: [vm_2] ### GemFire Properties defined with api ### [vm_2]disable-auto-reconnect=true [vm_2]enable-cluster-configuration=false [vm_2]locators=localhost[50213] [vm_2]log-level=info [vm_2]mcast-port=0 [vm_2]use-cluster-configuration=false [vm_2]### GemFire Properties using default values ### [vm_2]ack-severe-alert-threshold=0 [vm_2]ack-wait-threshold=15 [vm_2]archive-disk-space-limit=0 [vm_2]archive-file-size-limit=0 [vm_2]async-distribution-timeout=0 [vm_2]async-max-queue-size=8 [vm_2]async-queue-timeout=60000 [vm_2]bind-address= [vm_2]cache-xml-file=cache.xml [vm_2]cluster-configuration-dir= [vm_2]cluster-ssl-ciphers=any [vm_2]cluster-ssl-enabled=false [vm_2]cluster-ssl-keystore= [vm_2]cluster-ssl-keystore-password= [vm_2]cluster-ssl-keystore-type= [vm_2]cluster-ssl-protocols=any [vm_2]cluster-ssl-require-authentication=true [vm_2]cluster-ssl-truststore= [vm_2]cluster-ssl-truststore-password= [vm_2]conflate-events=server [vm_2]conserve-sockets=true [vm_2]delta-propagation=true [vm_2]deploy-working-dir=. [vm_2]disable-tcp=false [vm_2]distributed-system-id=-1 [vm_2]distributed-transactions=false [vm_2]durable-client-id= [vm_2]durable-client-timeout=300 [vm_2]enable-network-partition-detection=false [vm_2]enable-time-statistics=false [vm_2]enforce-unique-host=false [vm_2]gateway-ssl-ciphers=any [vm_2]gateway-ssl-enabled=false [vm_2]gateway-ssl-keystore= [vm_2]gateway-ssl-keystore-password= [vm_2]gateway-ssl-keystore-type= [vm_2]gateway-ssl-protocols=any [vm_2]gateway-ssl-require-authentication=true [vm_2]gateway-ssl-truststore= [vm_2]gateway-ssl-truststore-password= [vm_2]groups= [vm_2]http-service-bind-address= [vm_2]http-service-port=7070 [vm_2]http-service-ssl-ciphers=any [vm_2]http-service-ssl-enabled=false [vm_2]http-service-ssl-keystore= [vm_2]http-service-ssl-keystore-password= [vm_2]http-service-ssl-keystore-type= [vm_2]http-service-ssl-protocols=any [vm_2]http-service-ssl-require-authentication=false [vm_2]http-service-ssl-truststore= [vm_2]http-service-ssl-truststore-password= [vm_2]jmx-manager=false [vm_2]jmx-manager-access-file= [vm_2]jmx-manager-bind-address= [vm_2]jmx-manager-hostname-for-clients= [vm_2]jmx-manager-http-port=7070 [vm_2]jmx-manager-password-file= [vm_2]jmx-manager-port=1099 [vm_2]jmx-manager-ssl=false [vm_2]jmx-manager-ssl-ciphers=any [vm_2]jmx-manager-ssl-enabled=false [vm_2]jmx-manager-ssl-keystore= [vm_2]jmx-manager-ssl-keystore-password= [vm_2]jmx-manager-ssl-keystore-type= [vm_2]jmx-manager-ssl-protocols=any [vm_2]jmx-manager-ssl-require-authentication=true [vm_2]jmx-manager-ssl-truststore= [vm_2]jmx-manager-ssl-truststore-password= [vm_2]jmx-manager-start=false [vm_2]jmx-manager-update-rate=2000 [vm_2]load-cluster-configuration-from-dir=false [vm_2]locator-wait-time=0 [vm_2]lock-memory=false [vm_2]log-disk-space-limit=0 [vm_2]log-file= [vm_2]log-file-size-limit=0 [vm_2]max-num-reconnect-tries=3 [vm_2]max-wait-time-reconnect=60000 [vm_2]mcast-address=239.192.81.1 [vm_2]mcast-flow-control=1048576, 0.25, 5000 [vm_2]mcast-recv-buffer-size=1048576 [vm_2]mcast-send-buffer-size=65535 [vm_2]mcast-ttl=32 [vm_2]member-timeout=5000 [vm_2]membership-port-range=1024-65535 [vm_2]memcached-bind-address= [vm_2]memcached-port=0 [vm_2]memcached-protocol=ASCII [vm_2]name= [vm_2]off-heap-memory-size= [vm_2]redis-bind-address= [vm_2]redis-password= [vm_2]redis-port=0 [vm_2]redundancy-zone= [vm_2]remote-locators= [vm_2]remove-unresponsive-client=false [vm_2]roles= [vm_2]security-client-accessor= [vm_2]security-client-accessor-pp= [vm_2]security-client-auth-init= [vm_2]security-client-authenticator= [vm_2]security-client-dhalgo= [vm_2]security-log-file= [vm_2]security-log-level=config [vm_2]security-manager= [vm_2]security-peer-auth-init= [vm_2]security-peer-authenticator= [vm_2]security-peer-verifymember-timeout=1000 [vm_2]security-post-processor= [vm_2]server-bind-address= [vm_2]server-ssl-ciphers=any [vm_2]server-ssl-enabled=false [vm_2]server-ssl-keystore= [vm_2]server-ssl-keystore-password= [vm_2]server-ssl-keystore-type= [vm_2]server-ssl-protocols=any [vm_2]server-ssl-require-authentication=true [vm_2]server-ssl-truststore= [vm_2]server-ssl-truststore-password= [vm_2]socket-buffer-size=32768 [vm_2]socket-lease-time=60000 [vm_2]ssl-ciphers=any [vm_2]ssl-enabled=false [vm_2]ssl-protocols=any [vm_2]ssl-require-authentication=true [vm_2]start-dev-rest-api=false [vm_2]start-locator= [vm_2]statistic-archive-file= [vm_2]statistic-sample-rate=1000 [vm_2]statistic-sampling-enabled=true [vm_2]tcp-port=0 [vm_2]udp-fragment-size=60000 [vm_2]udp-recv-buffer-size=1048576 [vm_2]udp-send-buffer-size=65535 [vm_2]user-command-packages= [vm_2][info 2016/07/25 12:43:17.739 PDT <RMI TCP Connection(5)-10.118.32.93> tid=0x1b] Starting membership services [vm_2][info 2016/07/25 12:43:17.747 PDT <RMI TCP Connection(5)-10.118.32.93> tid=0x1b] JGroups channel created (took 8ms) [vm_2][info 2016/07/25 12:43:17.748 PDT <RMI TCP Connection(5)-10.118.32.93> tid=0x1b] GemFire P2P Listener started on null [vm_2][info 2016/07/25 12:43:17.748 PDT <Geode Failure Detection Server thread 0> tid=0xd9c] Started failure detection server thread on kuwait.gemstone.com/10.118.32.93:3452. [vm_2][info 2016/07/25 12:43:17.750 PDT <RMI TCP Connection(5)-10.118.32.93> tid=0x1b] Attempting to join the distributed system through coordinator kuwait(10426:locator)<ec><v0>:1024 using address kuwait(10513)<ec>:1027 [locator][info 2016/07/25 12:43:17.751 PDT <unicast receiver,kuwait-22220> tid=0x2a] received join request from kuwait(10513)<ec>:1027 [locator][info 2016/07/25 12:43:18.051 PDT <Geode Membership View Creator> tid=0x30] View Creator is processing 1 requests for the next membership view [locator][info 2016/07/25 12:43:18.051 PDT <Geode Membership View Creator> tid=0x30] preparing new view View[kuwait(10426:locator)<ec><v0>:1024|195] members: [kuwait(10426:locator)<ec><v0>:1024, kuwait(10504)<ec><v193>:1025{lead}, kuwait(10508)<ec><v194>:1026, kuwait(10513)<ec><v195>:1027] [locator]failure detection ports: 26403 51006 56242 3452 [vm_2][info 2016/07/25 12:43:18.052 PDT <unicast receiver,kuwait-57287> tid=0xd98] received new view: View[kuwait(10426:locator)<ec><v0>:1024|195] members: [kuwait(10426:locator)<ec><v0>:1024, kuwait(10504)<ec><v193>:1025{lead}, kuwait(10508)<ec><v194>:1026, kuwait(10513)<ec><v195>:1027] [vm_2]old view is: null [locator][info 2016/07/25 12:43:18.052 PDT <Geode Membership View Creator> tid=0x30] finished waiting for responses to view preparation [locator][info 2016/07/25 12:43:18.052 PDT <Geode Membership View Creator> tid=0x30] received new view: View[kuwait(10426:locator)<ec><v0>:1024|195] members: [kuwait(10426:locator)<ec><v0>:1024, kuwait(10504)<ec><v193>:1025{lead}, kuwait(10508)<ec><v194>:1026, kuwait(10513)<ec><v195>:1027] [locator]old view is: View[kuwait(10426:locator)<ec><v0>:1024|194] members: [kuwait(10426:locator)<ec><v0>:1024, kuwait(10504)<ec><v193>:1025{lead}, kuwait(10508)<ec><v194>:1026] [locator][info 2016/07/25 12:43:18.052 PDT <Geode Membership View Creator> tid=0x30] Peer locator received new membership view: View[kuwait(10426:locator)<ec><v0>:1024|195] members: [kuwait(10426:locator)<ec><v0>:1024, kuwait(10504)<ec><v193>:1025{lead}, kuwait(10508)<ec><v194>:1026, kuwait(10513)<ec><v195>:1027] [vm_2][info 2016/07/25 12:43:18.052 PDT <RMI TCP Connection(5)-10.118.32.93> tid=0x1b] Finished joining (took 304ms). [vm_2][info 2016/07/25 12:43:18.052 PDT <RMI TCP Connection(5)-10.118.32.93> tid=0x1b] Starting DistributionManager kuwait(10513)<ec><v195>:1027. (took 314 ms) [locator][info 2016/07/25 12:43:18.052 PDT <Geode Membership View Creator> tid=0x30] sending new view View[kuwait(10426:locator)<ec><v0>:1024|195] members: [kuwait(10426:locator)<ec><v0>:1024, kuwait(10504)<ec><v193>:1025{lead}, kuwait(10508)<ec><v194>:1026, kuwait(10513)<ec><v195>:1027] [locator]failure detection ports: 26403 51006 56242 3452 [vm_2][info 2016/07/25 12:43:18.053 PDT <RMI TCP Connection(5)-10.118.32.93> tid=0x1b] Initial (distribution manager) view = View[kuwait(10426:locator)<ec><v0>:1024|195] members: [kuwait(10426:locator)<ec><v0>:1024, kuwait(10504)<ec><v193>:1025{lead}, kuwait(10508)<ec><v194>:1026, kuwait(10513)<ec><v195>:1027] [locator][info 2016/07/25 12:43:18.053 PDT <View Message Processor> tid=0x3c] Membership: Processing addition < kuwait(10513)<ec><v195>:1027 > [vm_2][info 2016/07/25 12:43:18.053 PDT <RMI TCP Connection(5)-10.118.32.93> tid=0x1b] Admitting member <kuwait(10426:locator)<ec><v0>:1024>. Now there are 1 non-admin member(s). [vm_0][info 2016/07/25 12:43:18.053 PDT <unicast receiver,kuwait-52318> tid=0xc1d] received new view: View[kuwait(10426:locator)<ec><v0>:1024|195] members: [kuwait(10426:locator)<ec><v0>:1024, kuwait(10504)<ec><v193>:1025{lead}, kuwait(10508)<ec><v194>:1026, kuwait(10513)<ec><v195>:1027] [vm_0]old view is: View[kuwait(10426:locator)<ec><v0>:1024|194] members: [kuwait(10426:locator)<ec><v0>:1024, kuwait(10504)<ec><v193>:1025{lead}, kuwait(10508)<ec><v194>:1026] [vm_2][info 2016/07/25 12:43:18.053 PDT <RMI TCP Connection(5)-10.118.32.93> tid=0x1b] Admitting member <kuwait(10504)<ec><v193>:1025>. Now there are 2 non-admin member(s). [locator][info 2016/07/25 12:43:18.053 PDT <View Message Processor> tid=0x3c] Admitting member <kuwait(10513)<ec><v195>:1027>. Now there are 4 non-admin member(s). [vm_1][info 2016/07/25 12:43:18.053 PDT <unicast receiver,kuwait-28418> tid=0xcbd] received new view: View[kuwait(10426:locator)<ec><v0>:1024|195] members: [kuwait(10426:locator)<ec><v0>:1024, kuwait(10504)<ec><v193>:1025{lead}, kuwait(10508)<ec><v194>:1026, kuwait(10513)<ec><v195>:1027] [vm_1]old view is: View[kuwait(10426:locator)<ec><v0>:1024|194] members: [kuwait(10426:locator)<ec><v0>:1024, kuwait(10504)<ec><v193>:1025{lead}, kuwait(10508)<ec><v194>:1026] [vm_2][info 2016/07/25 12:43:18.053 PDT <RMI TCP Connection(5)-10.118.32.93> tid=0x1b] Admitting member <kuwait(10508)<ec><v194>:1026>. Now there are 3 non-admin member(s). [vm_0][info 2016/07/25 12:43:18.053 PDT <View Message Processor> tid=0xc34] Membership: Processing addition < kuwait(10513)<ec><v195>:1027 > [vm_2][info 2016/07/25 12:43:18.053 PDT <RMI TCP Connection(5)-10.118.32.93> tid=0x1b] Admitting member <kuwait(10513)<ec><v195>:1027>. Now there are 4 non-admin member(s). [vm_0][info 2016/07/25 12:43:18.053 PDT <View Message Processor> tid=0xc34] Admitting member <kuwait(10513)<ec><v195>:1027>. Now there are 4 non-admin member(s). [vm_1][info 2016/07/25 12:43:18.054 PDT <View Message Processor> tid=0xcd9] Membership: Processing addition < kuwait(10513)<ec><v195>:1027 > [vm_1][info 2016/07/25 12:43:18.054 PDT <View Message Processor> tid=0xcd9] Admitting member <kuwait(10513)<ec><v195>:1027>. Now there are 4 non-admin member(s). [locator][info 2016/07/25 12:43:18.057 PDT <Pooled High Priority Message Processor 1> tid=0x40] Member kuwait(10513)<ec><v195>:1027 is equivalent or in the same redundancy zone. [vm_0][info 2016/07/25 12:43:18.057 PDT <Pooled High Priority Message Processor 1> tid=0xc31] Member kuwait(10513)<ec><v195>:1027 is equivalent or in the same redundancy zone. [vm_1][info 2016/07/25 12:43:18.057 PDT <Pooled High Priority Message Processor 1> tid=0xcd5] Member kuwait(10513)<ec><v195>:1027 is equivalent or in the same redundancy zone. [vm_2][info 2016/07/25 12:43:18.059 PDT <P2P message reader for kuwait(10426:locator)<ec><v0>:1024 shared unordered uid=182 port=59472> tid=0xda7] Member kuwait(10426:locator)<ec><v0>:1024 is equivalent or in the same redundancy zone. [vm_2][info 2016/07/25 12:43:18.059 PDT <P2P message reader for kuwait(10504)<ec><v193>:1025 shared unordered uid=268 port=59471> tid=0xda5] Member kuwait(10504)<ec><v193>:1025 is equivalent or in the same redundancy zone. [vm_2][info 2016/07/25 12:43:18.059 PDT <P2P message reader for kuwait(10508)<ec><v194>:1026 shared unordered uid=268 port=59473> tid=0xda8] Member kuwait(10508)<ec><v194>:1026 is equivalent or in the same redundancy zone. [vm_2][info 2016/07/25 12:43:18.071 PDT <RMI TCP Connection(5)-10.118.32.93> tid=0x1b] Initializing region _monitoringRegion_10.118.32.93<v195>1027 [vm_2][info 2016/07/25 12:43:18.072 PDT <RMI TCP Connection(5)-10.118.32.93> tid=0x1b] Initialization of region _monitoringRegion_10.118.32.93<v195>1027 completed [vm_2][info 2016/07/25 12:43:18.076 PDT <RMI TCP Connection(5)-10.118.32.93> tid=0x1b] The cache has been created with "use-cluster-configuration=false". It will not receive any cluster configuration [vm_2][info 2016/07/25 12:43:18.076 PDT <RMI TCP Connection(5)-10.118.32.93> tid=0x1b] Initializing region PdxTypes [vm_2][info 2016/07/25 12:43:18.080 PDT <RMI TCP Connection(5)-10.118.32.93> tid=0x1b] Region PdxTypes requesting initial image from kuwait(10508)<ec><v194>:1026 [vm_2][info 2016/07/25 12:43:18.080 PDT <RMI TCP Connection(5)-10.118.32.93> tid=0x1b] PdxTypes is done getting image from kuwait(10508)<ec><v194>:1026. isDeltaGII is false [vm_2][info 2016/07/25 12:43:18.080 PDT <RMI TCP Connection(5)-10.118.32.93> tid=0x1b] Initialization of region PdxTypes completed [vm_2][info 2016/07/25 12:43:18.081 PDT <RMI TCP Connection(5)-10.118.32.93> tid=0x1b] Got result: null [vm_2] from com.gemstone.gemfire.internal.cache.execute.PRColocationDUnitTest$$Lambda$26/288578415.run with 0 args on object: com.gemstone.gemfire.internal.cache.execute.PRColocationDUnitTest$$Lambda$26/288578415@33bf154a (took 346 ms) [vm_3][info 2016/07/25 12:43:18.090 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Received method: com.gemstone.gemfire.internal.cache.execute.PRColocationDUnitTest$$Lambda$49/861240409.run with 0 args on object: com.gemstone.gemfire.internal.cache.execute.PRColocationDUnitTest$$Lambda$49/861240409@370428ee [vm_3]distributed system properties: {locators=localhost[50213], enable-cluster-configuration=false, use-cluster-configuration=false, mcast-port=0, disable-auto-reconnect=true, log-level=info} [vm_3][info 2016/07/25 12:43:18.093 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Startup Configuration: [vm_3] ### GemFire Properties defined with api ### [vm_3]disable-auto-reconnect=true [vm_3]enable-cluster-configuration=false [vm_3]locators=localhost[50213] [vm_3]log-level=info [vm_3]mcast-port=0 [vm_3]use-cluster-configuration=false [vm_3]### GemFire Properties using default values ### [vm_3]ack-severe-alert-threshold=0 [vm_3]ack-wait-threshold=15 [vm_3]archive-disk-space-limit=0 [vm_3]archive-file-size-limit=0 [vm_3]async-distribution-timeout=0 [vm_3]async-max-queue-size=8 [vm_3]async-queue-timeout=60000 [vm_3]bind-address= [vm_3]cache-xml-file=cache.xml [vm_3]cluster-configuration-dir= [vm_3]cluster-ssl-ciphers=any [vm_3]cluster-ssl-enabled=false [vm_3]cluster-ssl-keystore= [vm_3]cluster-ssl-keystore-password= [vm_3]cluster-ssl-keystore-type= [vm_3]cluster-ssl-protocols=any [vm_3]cluster-ssl-require-authentication=true [vm_3]cluster-ssl-truststore= [vm_3]cluster-ssl-truststore-password= [vm_3]conflate-events=server [vm_3]conserve-sockets=true [vm_3]delta-propagation=true [vm_3]deploy-working-dir=. [vm_3]disable-tcp=false [vm_3]distributed-system-id=-1 [vm_3]distributed-transactions=false [vm_3]durable-client-id= [vm_3]durable-client-timeout=300 [vm_3]enable-network-partition-detection=false [vm_3]enable-time-statistics=false [vm_3]enforce-unique-host=false [vm_3]gateway-ssl-ciphers=any [vm_3]gateway-ssl-enabled=false [vm_3]gateway-ssl-keystore= [vm_3]gateway-ssl-keystore-password= [vm_3]gateway-ssl-keystore-type= [vm_3]gateway-ssl-protocols=any [vm_3]gateway-ssl-require-authentication=true [vm_3]gateway-ssl-truststore= [vm_3]gateway-ssl-truststore-password= [vm_3]groups= [vm_3]http-service-bind-address= [vm_3]http-service-port=7070 [vm_3]http-service-ssl-ciphers=any [vm_3]http-service-ssl-enabled=false [vm_3]http-service-ssl-keystore= [vm_3]http-service-ssl-keystore-password= [vm_3]http-service-ssl-keystore-type= [vm_3]http-service-ssl-protocols=any [vm_3]http-service-ssl-require-authentication=false [vm_3]http-service-ssl-truststore= [vm_3]http-service-ssl-truststore-password= [vm_3]jmx-manager=false [vm_3]jmx-manager-access-file= [vm_3]jmx-manager-bind-address= [vm_3]jmx-manager-hostname-for-clients= [vm_3]jmx-manager-http-port=7070 [vm_3]jmx-manager-password-file= [vm_3]jmx-manager-port=1099 [vm_3]jmx-manager-ssl=false [vm_3]jmx-manager-ssl-ciphers=any [vm_3]jmx-manager-ssl-enabled=false [vm_3]jmx-manager-ssl-keystore= [vm_3]jmx-manager-ssl-keystore-password= [vm_3]jmx-manager-ssl-keystore-type= [vm_3]jmx-manager-ssl-protocols=any [vm_3]jmx-manager-ssl-require-authentication=true [vm_3]jmx-manager-ssl-truststore= [vm_3]jmx-manager-ssl-truststore-password= [vm_3]jmx-manager-start=false [vm_3]jmx-manager-update-rate=2000 [vm_3]load-cluster-configuration-from-dir=false [vm_3]locator-wait-time=0 [vm_3]lock-memory=false [vm_3]log-disk-space-limit=0 [vm_3]log-file= [vm_3]log-file-size-limit=0 [vm_3]max-num-reconnect-tries=3 [vm_3]max-wait-time-reconnect=60000 [vm_3]mcast-address=239.192.81.1 [vm_3]mcast-flow-control=1048576, 0.25, 5000 [vm_3]mcast-recv-buffer-size=1048576 [vm_3]mcast-send-buffer-size=65535 [vm_3]mcast-ttl=32 [vm_3]member-timeout=5000 [vm_3]membership-port-range=1024-65535 [vm_3]memcached-bind-address= [vm_3]memcached-port=0 [vm_3]memcached-protocol=ASCII [vm_3]name= [vm_3]off-heap-memory-size= [vm_3]redis-bind-address= [vm_3]redis-password= [vm_3]redis-port=0 [vm_3]redundancy-zone= [vm_3]remote-locators= [vm_3]remove-unresponsive-client=false [vm_3]roles= [vm_3]security-client-accessor= [vm_3]security-client-accessor-pp= [vm_3]security-client-auth-init= [vm_3]security-client-authenticator= [vm_3]security-client-dhalgo= [vm_3]security-log-file= [vm_3]security-log-level=config [vm_3]security-manager= [vm_3]security-peer-auth-init= [vm_3]security-peer-authenticator= [vm_3]security-peer-verifymember-timeout=1000 [vm_3]security-post-processor= [vm_3]server-bind-address= [vm_3]server-ssl-ciphers=any [vm_3]server-ssl-enabled=false [vm_3]server-ssl-keystore= [vm_3]server-ssl-keystore-password= [vm_3]server-ssl-keystore-type= [vm_3]server-ssl-protocols=any [vm_3]server-ssl-require-authentication=true [vm_3]server-ssl-truststore= [vm_3]server-ssl-truststore-password= [vm_3]socket-buffer-size=32768 [vm_3]socket-lease-time=60000 [vm_3]ssl-ciphers=any [vm_3]ssl-enabled=false [vm_3]ssl-protocols=any [vm_3]ssl-require-authentication=true [vm_3]start-dev-rest-api=false [vm_3]start-locator= [vm_3]statistic-archive-file= [vm_3]statistic-sample-rate=1000 [vm_3]statistic-sampling-enabled=true [vm_3]tcp-port=0 [vm_3]udp-fragment-size=60000 [vm_3]udp-recv-buffer-size=1048576 [vm_3]udp-send-buffer-size=65535 [vm_3]user-command-packages= [vm_3][info 2016/07/25 12:43:18.095 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Starting membership services [vm_3][info 2016/07/25 12:43:18.106 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] JGroups channel created (took 11ms) [vm_3][info 2016/07/25 12:43:18.107 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] GemFire P2P Listener started on null [vm_3][info 2016/07/25 12:43:18.108 PDT <Geode Failure Detection Server thread 0> tid=0x58b] Started failure detection server thread on kuwait.gemstone.com/10.118.32.93:53201. [vm_3][info 2016/07/25 12:43:18.109 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Attempting to join the distributed system through coordinator kuwait(10426:locator)<ec><v0>:1024 using address kuwait(10518)<ec>:1028 [locator][info 2016/07/25 12:43:18.110 PDT <unicast receiver,kuwait-22220> tid=0x2a] received join request from kuwait(10518)<ec>:1028 [locator][info 2016/07/25 12:43:18.411 PDT <Geode Membership View Creator> tid=0x30] View Creator is processing 1 requests for the next membership view [locator][info 2016/07/25 12:43:18.411 PDT <Geode Membership View Creator> tid=0x30] preparing new view View[kuwait(10426:locator)<ec><v0>:1024|196] members: [kuwait(10426:locator)<ec><v0>:1024, kuwait(10504)<ec><v193>:1025{lead}, kuwait(10508)<ec><v194>:1026, kuwait(10513)<ec><v195>:1027, kuwait(10518)<ec><v196>:1028] [locator]failure detection ports: 26403 51006 56242 3452 53201 [vm_3][info 2016/07/25 12:43:18.412 PDT <unicast receiver,kuwait-33038> tid=0x587] received new view: View[kuwait(10426:locator)<ec><v0>:1024|196] members: [kuwait(10426:locator)<ec><v0>:1024, kuwait(10504)<ec><v193>:1025{lead}, kuwait(10508)<ec><v194>:1026, kuwait(10513)<ec><v195>:1027, kuwait(10518)<ec><v196>:1028] [vm_3]old view is: null [locator][info 2016/07/25 12:43:18.412 PDT <Geode Membership View Creator> tid=0x30] finished waiting for responses to view preparation [locator][info 2016/07/25 12:43:18.412 PDT <Geode Membership View Creator> tid=0x30] received new view: View[kuwait(10426:locator)<ec><v0>:1024|196] members: [kuwait(10426:locator)<ec><v0>:1024, kuwait(10504)<ec><v193>:1025{lead}, kuwait(10508)<ec><v194>:1026, kuwait(10513)<ec><v195>:1027, kuwait(10518)<ec><v196>:1028] [locator]old view is: View[kuwait(10426:locator)<ec><v0>:1024|195] members: [kuwait(10426:locator)<ec><v0>:1024, kuwait(10504)<ec><v193>:1025{lead}, kuwait(10508)<ec><v194>:1026, kuwait(10513)<ec><v195>:1027] [locator][info 2016/07/25 12:43:18.412 PDT <Geode Membership View Creator> tid=0x30] Peer locator received new membership view: View[kuwait(10426:locator)<ec><v0>:1024|196] members: [kuwait(10426:locator)<ec><v0>:1024, kuwait(1 ...[truncated 3424450 chars]... estCase$$Lambda$9/601811905@168dbc72 [vm_0][info 2016/07/25 12:49:26.824 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xc96] Partitioned Region /rootI/I with prId=116 closing. [vm_1][warn 2016/07/25 12:49:26.826 PDT <PartitionedRegion Message Processor1> tid=0x125e] Redundancy has dropped below 1 configured copies to 0 actual copies for /rootI/I [vm_2][warn 2016/07/25 12:49:26.826 PDT <PartitionedRegion Message Processor1> tid=0x1367] Redundancy has dropped below 1 configured copies to 0 actual copies for /rootI/I [vm_3][warn 2016/07/25 12:49:26.826 PDT <PartitionedRegion Message Processor1> tid=0xb93] Redundancy has dropped below 1 configured copies to 0 actual copies for /rootI/I [vm_0][warn 2016/07/25 12:49:26.827 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xc96] Redundancy has dropped below 1 configured copies to 0 actual copies for /rootI/I [vm_0][info 2016/07/25 12:49:26.830 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xc96] Partitioned Region /rootI/I with prId=116 closed. [vm_0][info 2016/07/25 12:49:26.831 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xc96] Partitioned Region /rootN/N with prId=121 closing. [vm_3][warn 2016/07/25 12:49:26.832 PDT <PartitionedRegion Message Processor1> tid=0xb93] Redundancy has dropped below 1 configured copies to 0 actual copies for /rootN/N [vm_2][warn 2016/07/25 12:49:26.832 PDT <PartitionedRegion Message Processor1> tid=0x1367] Redundancy has dropped below 1 configured copies to 0 actual copies for /rootN/N [vm_1][warn 2016/07/25 12:49:26.832 PDT <PartitionedRegion Message Processor1> tid=0x125e] Redundancy has dropped below 1 configured copies to 0 actual copies for /rootN/N [vm_0][warn 2016/07/25 12:49:26.833 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xc96] Redundancy has dropped below 1 configured copies to 0 actual copies for /rootN/N [vm_0][info 2016/07/25 12:49:26.843 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xc96] Partitioned Region /rootN/N with prId=121 closed. [vm_0][info 2016/07/25 12:49:26.844 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xc96] Partitioned Region /rootF/F with prId=113 closing. [vm_1][warn 2016/07/25 12:49:26.845 PDT <PartitionedRegion Message Processor1> tid=0x125e] Redundancy has dropped below 1 configured copies to 0 actual copies for /rootF/F [vm_2][warn 2016/07/25 12:49:26.845 PDT <PartitionedRegion Message Processor1> tid=0x1367] Redundancy has dropped below 1 configured copies to 0 actual copies for /rootF/F [vm_3][warn 2016/07/25 12:49:26.845 PDT <PartitionedRegion Message Processor1> tid=0xb93] Redundancy has dropped below 1 configured copies to 0 actual copies for /rootF/F [vm_0][warn 2016/07/25 12:49:26.845 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xc96] Redundancy has dropped below 1 configured copies to 0 actual copies for /rootF/F [vm_0][info 2016/07/25 12:49:26.848 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xc96] Partitioned Region /rootF/F with prId=113 closed. [vm_0][info 2016/07/25 12:49:26.849 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xc96] Partitioned Region /rootL/L with prId=119 closing. [vm_2][warn 2016/07/25 12:49:26.850 PDT <PartitionedRegion Message Processor1> tid=0x1367] Redundancy has dropped below 1 configured copies to 0 actual copies for /rootL/L [vm_1][warn 2016/07/25 12:49:26.850 PDT <PartitionedRegion Message Processor1> tid=0x125e] Redundancy has dropped below 1 configured copies to 0 actual copies for /rootL/L [vm_3][warn 2016/07/25 12:49:26.850 PDT <PartitionedRegion Message Processor1> tid=0xb93] Redundancy has dropped below 1 configured copies to 0 actual copies for /rootL/L [vm_0][warn 2016/07/25 12:49:26.850 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xc96] Redundancy has dropped below 1 configured copies to 0 actual copies for /rootL/L [vm_0][info 2016/07/25 12:49:26.853 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xc96] Partitioned Region /rootL/L with prId=119 closed. [vm_0][info 2016/07/25 12:49:26.854 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xc96] Partitioned Region /rootC/C with prId=110 closing. [vm_2][warn 2016/07/25 12:49:26.855 PDT <PartitionedRegion Message Processor1> tid=0x1367] Redundancy has dropped below 1 configured copies to 0 actual copies for /rootC/C [vm_1][warn 2016/07/25 12:49:26.855 PDT <PartitionedRegion Message Processor1> tid=0x125e] Redundancy has dropped below 1 configured copies to 0 actual copies for /rootC/C [vm_3][warn 2016/07/25 12:49:26.855 PDT <PartitionedRegion Message Processor1> tid=0xb93] Redundancy has dropped below 1 configured copies to 0 actual copies for /rootC/C [vm_0][warn 2016/07/25 12:49:26.855 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xc96] Redundancy has dropped below 1 configured copies to 0 actual copies for /rootC/C [vm_0][info 2016/07/25 12:49:26.857 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xc96] Partitioned Region /rootC/C with prId=110 closed. [vm_0][info 2016/07/25 12:49:26.859 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xc96] Partitioned Region /rootK/K with prId=118 closing. [vm_2][warn 2016/07/25 12:49:26.860 PDT <PartitionedRegion Message Processor1> tid=0x1367] Redundancy has dropped below 1 configured copies to 0 actual copies for /rootK/K [vm_1][warn 2016/07/25 12:49:26.860 PDT <PartitionedRegion Message Processor1> tid=0x125e] Redundancy has dropped below 1 configured copies to 0 actual copies for /rootK/K [vm_3][warn 2016/07/25 12:49:26.860 PDT <PartitionedRegion Message Processor1> tid=0xb93] Redundancy has dropped below 1 configured copies to 0 actual copies for /rootK/K [vm_0][warn 2016/07/25 12:49:26.860 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xc96] Redundancy has dropped below 1 configured copies to 0 actual copies for /rootK/K [vm_0][info 2016/07/25 12:49:26.862 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xc96] Partitioned Region /rootK/K with prId=118 closed. [vm_0][info 2016/07/25 12:49:26.863 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xc96] Partitioned Region /rootA/A with prId=108 closing. [vm_1][warn 2016/07/25 12:49:26.865 PDT <PartitionedRegion Message Processor1> tid=0x125e] Redundancy has dropped below 1 configured copies to 0 actual copies for /rootA/A [vm_2][warn 2016/07/25 12:49:26.865 PDT <PartitionedRegion Message Processor1> tid=0x1367] Redundancy has dropped below 1 configured copies to 0 actual copies for /rootA/A [vm_3][warn 2016/07/25 12:49:26.865 PDT <PartitionedRegion Message Processor1> tid=0xb93] Redundancy has dropped below 1 configured copies to 0 actual copies for /rootA/A [vm_0][warn 2016/07/25 12:49:26.865 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xc96] Redundancy has dropped below 1 configured copies to 0 actual copies for /rootA/A [vm_0][info 2016/07/25 12:49:26.867 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xc96] Partitioned Region /rootA/A with prId=108 closed. [vm_0][info 2016/07/25 12:49:26.869 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xc96] Partitioned Region /rootG/G with prId=114 closing. [vm_2][warn 2016/07/25 12:49:26.870 PDT <PartitionedRegion Message Processor1> tid=0x1367] Redundancy has dropped below 1 configured copies to 0 actual copies for /rootG/G [vm_3][warn 2016/07/25 12:49:26.870 PDT <PartitionedRegion Message Processor1> tid=0xb93] Redundancy has dropped below 1 configured copies to 0 actual copies for /rootG/G [vm_1][warn 2016/07/25 12:49:26.870 PDT <PartitionedRegion Message Processor1> tid=0x125e] Redundancy has dropped below 1 configured copies to 0 actual copies for /rootG/G [vm_0][warn 2016/07/25 12:49:26.870 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xc96] Redundancy has dropped below 1 configured copies to 0 actual copies for /rootG/G [vm_0][info 2016/07/25 12:49:26.874 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xc96] Partitioned Region /rootG/G with prId=114 closed. [vm_0][info 2016/07/25 12:49:26.877 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xc96] Partitioned Region /rootJ/J with prId=117 closing. [vm_2][warn 2016/07/25 12:49:26.878 PDT <PartitionedRegion Message Processor1> tid=0x1367] Redundancy has dropped below 1 configured copies to 0 actual copies for /rootJ/J [vm_1][warn 2016/07/25 12:49:26.878 PDT <PartitionedRegion Message Processor1> tid=0x125e] Redundancy has dropped below 1 configured copies to 0 actual copies for /rootJ/J [vm_3][warn 2016/07/25 12:49:26.878 PDT <PartitionedRegion Message Processor1> tid=0xb93] Redundancy has dropped below 1 configured copies to 0 actual copies for /rootJ/J [vm_0][warn 2016/07/25 12:49:26.879 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xc96] Redundancy has dropped below 1 configured copies to 0 actual copies for /rootJ/J [vm_0][info 2016/07/25 12:49:26.881 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xc96] Partitioned Region /rootJ/J with prId=117 closed. [vm_0][info 2016/07/25 12:49:26.882 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xc96] Partitioned Region /rootB/B with prId=109 closing. [vm_2][warn 2016/07/25 12:49:26.883 PDT <PartitionedRegion Message Processor1> tid=0x1367] Redundancy has dropped below 1 configured copies to 0 actual copies for /rootB/B [vm_1][warn 2016/07/25 12:49:26.884 PDT <PartitionedRegion Message Processor1> tid=0x125e] Redundancy has dropped below 1 configured copies to 0 actual copies for /rootB/B [vm_3][warn 2016/07/25 12:49:26.884 PDT <PartitionedRegion Message Processor1> tid=0xb93] Redundancy has dropped below 1 configured copies to 0 actual copies for /rootB/B [vm_0][warn 2016/07/25 12:49:26.884 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xc96] Redundancy has dropped below 1 configured copies to 0 actual copies for /rootB/B [vm_0][info 2016/07/25 12:49:26.886 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xc96] Partitioned Region /rootB/B with prId=109 closed. [vm_0][info 2016/07/25 12:49:26.888 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xc96] Partitioned Region /rootH/H with prId=115 closing. [vm_2][warn 2016/07/25 12:49:26.888 PDT <PartitionedRegion Message Processor1> tid=0x1367] Redundancy has dropped below 1 configured copies to 0 actual copies for /rootH/H [vm_3][warn 2016/07/25 12:49:26.888 PDT <PartitionedRegion Message Processor1> tid=0xb93] Redundancy has dropped below 1 configured copies to 0 actual copies for /rootH/H [vm_1][warn 2016/07/25 12:49:26.888 PDT <PartitionedRegion Message Processor1> tid=0x125e] Redundancy has dropped below 1 configured copies to 0 actual copies for /rootH/H [vm_0][warn 2016/07/25 12:49:26.889 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xc96] Redundancy has dropped below 1 configured copies to 0 actual copies for /rootH/H [vm_0][info 2016/07/25 12:49:26.891 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xc96] Partitioned Region /rootH/H with prId=115 closed. [vm_0][info 2016/07/25 12:49:26.892 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xc96] Partitioned Region /rootD/D with prId=111 closing. [vm_1][warn 2016/07/25 12:49:26.893 PDT <PartitionedRegion Message Processor1> tid=0x125e] Redundancy has dropped below 1 configured copies to 0 actual copies for /rootD/D [vm_2][warn 2016/07/25 12:49:26.893 PDT <PartitionedRegion Message Processor1> tid=0x1367] Redundancy has dropped below 1 configured copies to 0 actual copies for /rootD/D [vm_3][warn 2016/07/25 12:49:26.893 PDT <PartitionedRegion Message Processor1> tid=0xb93] Redundancy has dropped below 1 configured copies to 0 actual copies for /rootD/D [vm_0][warn 2016/07/25 12:49:26.894 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xc96] Redundancy has dropped below 1 configured copies to 0 actual copies for /rootD/D [vm_0][info 2016/07/25 12:49:26.896 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xc96] Partitioned Region /rootD/D with prId=111 closed. [vm_0][info 2016/07/25 12:49:26.897 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xc96] Partitioned Region /rootO/O with prId=122 closing. [vm_1][warn 2016/07/25 12:49:26.898 PDT <PartitionedRegion Message Processor1> tid=0x125e] Redundancy has dropped below 1 configured copies to 0 actual copies for /rootO/O [vm_2][warn 2016/07/25 12:49:26.898 PDT <PartitionedRegion Message Processor1> tid=0x1367] Redundancy has dropped below 1 configured copies to 0 actual copies for /rootO/O [vm_3][warn 2016/07/25 12:49:26.898 PDT <PartitionedRegion Message Processor1> tid=0xb93] Redundancy has dropped below 1 configured copies to 0 actual copies for /rootO/O [vm_0][warn 2016/07/25 12:49:26.898 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xc96] Redundancy has dropped below 1 configured copies to 0 actual copies for /rootO/O [vm_0][info 2016/07/25 12:49:26.900 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xc96] Partitioned Region /rootO/O with prId=122 closed. [vm_0][info 2016/07/25 12:49:26.902 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xc96] Partitioned Region /rootE/E with prId=112 closing. [vm_2][warn 2016/07/25 12:49:26.903 PDT <PartitionedRegion Message Processor1> tid=0x1367] Redundancy has dropped below 1 configured copies to 0 actual copies for /rootE/E [vm_1][warn 2016/07/25 12:49:26.903 PDT <PartitionedRegion Message Processor1> tid=0x125e] Redundancy has dropped below 1 configured copies to 0 actual copies for /rootE/E [vm_3][warn 2016/07/25 12:49:26.903 PDT <PartitionedRegion Message Processor1> tid=0xb93] Redundancy has dropped below 1 configured copies to 0 actual copies for /rootE/E [vm_0][warn 2016/07/25 12:49:26.904 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xc96] Redundancy has dropped below 1 configured copies to 0 actual copies for /rootE/E [vm_0][info 2016/07/25 12:49:26.906 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xc96] Partitioned Region /rootE/E with prId=112 closed. [vm_0][info 2016/07/25 12:49:26.907 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xc96] Partitioned Region /rootM/M with prId=120 closing. [vm_2][warn 2016/07/25 12:49:26.909 PDT <PartitionedRegion Message Processor1> tid=0x1367] Redundancy has dropped below 1 configured copies to 0 actual copies for /rootM/M [vm_1][warn 2016/07/25 12:49:26.909 PDT <PartitionedRegion Message Processor1> tid=0x125e] Redundancy has dropped below 1 configured copies to 0 actual copies for /rootM/M [vm_3][warn 2016/07/25 12:49:26.909 PDT <PartitionedRegion Message Processor1> tid=0xb93] Redundancy has dropped below 1 configured copies to 0 actual copies for /rootM/M [vm_0][warn 2016/07/25 12:49:26.909 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xc96] Redundancy has dropped below 1 configured copies to 0 actual copies for /rootM/M [vm_0][info 2016/07/25 12:49:26.911 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xc96] Partitioned Region /rootM/M with prId=120 closed. [vm_0][info 2016/07/25 12:49:26.913 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xc96] GemFireCache[id = 478477746; isClosing = true; isShutDownAll = false; created = Mon Jul 25 12:49:24 PDT 2016; server = false; copyOnRead = false; lockLease = 120; lockTimeout = 60]: Now closing. [vm_0][info 2016/07/25 12:49:26.918 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xc96] Got result: null [vm_0] from com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$9/601811905.run with 0 args on object: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$9/601811905@168dbc72 (took 94 ms) [vm_1][info 2016/07/25 12:49:26.919 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xe67] Received method: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$10/2146990354.run with 0 args on object: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$10/2146990354@8e19481 [vm_1][info 2016/07/25 12:49:26.922 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xe67] Partitioned Region /rootJ/J with prId=117 closing. [vm_1][info 2016/07/25 12:49:26.926 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xe67] Partitioned Region /rootJ/J with prId=117 closed. [vm_1][info 2016/07/25 12:49:26.928 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xe67] Partitioned Region /rootD/D with prId=111 closing. [vm_1][info 2016/07/25 12:49:26.932 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xe67] Partitioned Region /rootD/D with prId=111 closed. [vm_1][info 2016/07/25 12:49:26.934 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xe67] Partitioned Region /rootL/L with prId=119 closing. [vm_1][info 2016/07/25 12:49:26.938 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xe67] Partitioned Region /rootL/L with prId=119 closed. [vm_1][info 2016/07/25 12:49:26.941 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xe67] Partitioned Region /rootI/I with prId=116 closing. [vm_1][info 2016/07/25 12:49:26.945 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xe67] Partitioned Region /rootI/I with prId=116 closed. [vm_1][info 2016/07/25 12:49:26.947 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xe67] Partitioned Region /rootK/K with prId=118 closing. [vm_1][info 2016/07/25 12:49:26.951 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xe67] Partitioned Region /rootK/K with prId=118 closed. [vm_1][info 2016/07/25 12:49:26.953 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xe67] Partitioned Region /rootH/H with prId=115 closing. [vm_1][info 2016/07/25 12:49:26.957 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xe67] Partitioned Region /rootH/H with prId=115 closed. [vm_1][info 2016/07/25 12:49:26.958 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xe67] Partitioned Region /rootE/E with prId=112 closing. [vm_1][info 2016/07/25 12:49:26.962 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xe67] Partitioned Region /rootE/E with prId=112 closed. [vm_1][info 2016/07/25 12:49:26.964 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xe67] Partitioned Region /rootO/O with prId=122 closing. [vm_1][info 2016/07/25 12:49:26.967 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xe67] Partitioned Region /rootO/O with prId=122 closed. [vm_1][info 2016/07/25 12:49:26.968 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xe67] Partitioned Region /rootG/G with prId=114 closing. [vm_1][info 2016/07/25 12:49:26.972 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xe67] Partitioned Region /rootG/G with prId=114 closed. [vm_1][info 2016/07/25 12:49:26.972 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xe67] Partitioned Region /rootM/M with prId=120 closing. [vm_1][info 2016/07/25 12:49:26.975 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xe67] Partitioned Region /rootM/M with prId=120 closed. [vm_1][info 2016/07/25 12:49:26.976 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xe67] Partitioned Region /rootB/B with prId=109 closing. [vm_1][info 2016/07/25 12:49:26.979 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xe67] Partitioned Region /rootB/B with prId=109 closed. [vm_1][info 2016/07/25 12:49:26.980 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xe67] Partitioned Region /rootF/F with prId=113 closing. [vm_1][info 2016/07/25 12:49:26.983 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xe67] Partitioned Region /rootF/F with prId=113 closed. [vm_1][info 2016/07/25 12:49:26.984 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xe67] Partitioned Region /rootC/C with prId=110 closing. [vm_1][info 2016/07/25 12:49:26.987 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xe67] Partitioned Region /rootC/C with prId=110 closed. [vm_1][info 2016/07/25 12:49:26.988 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xe67] Partitioned Region /rootA/A with prId=108 closing. [vm_1][info 2016/07/25 12:49:26.992 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xe67] Partitioned Region /rootA/A with prId=108 closed. [vm_1][info 2016/07/25 12:49:26.992 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xe67] Partitioned Region /rootN/N with prId=121 closing. [vm_1][info 2016/07/25 12:49:26.995 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xe67] Partitioned Region /rootN/N with prId=121 closed. [vm_1][info 2016/07/25 12:49:26.997 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xe67] GemFireCache[id = 1176676677; isClosing = true; isShutDownAll = false; created = Mon Jul 25 12:49:24 PDT 2016; server = false; copyOnRead = false; lockLease = 120; lockTimeout = 60]: Now closing. [vm_1][info 2016/07/25 12:49:27.000 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xe67] Got result: null [vm_1] from com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$10/2146990354.run with 0 args on object: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$10/2146990354@8e19481 (took 81 ms) [vm_2][info 2016/07/25 12:49:27.001 PDT <RMI TCP Connection(25)-10.118.32.93> tid=0xf56] Received method: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$10/1875724691.run with 0 args on object: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$10/1875724691@4d2c32e0 [vm_2][info 2016/07/25 12:49:27.003 PDT <RMI TCP Connection(25)-10.118.32.93> tid=0xf56] Partitioned Region /rootA/A with prId=108 closing. [vm_2][info 2016/07/25 12:49:27.008 PDT <RMI TCP Connection(25)-10.118.32.93> tid=0xf56] Partitioned Region /rootA/A with prId=108 closed. [vm_2][info 2016/07/25 12:49:27.009 PDT <RMI TCP Connection(25)-10.118.32.93> tid=0xf56] Partitioned Region /rootC/C with prId=110 closing. [vm_2][info 2016/07/25 12:49:27.014 PDT <RMI TCP Connection(25)-10.118.32.93> tid=0xf56] Partitioned Region /rootC/C with prId=110 closed. [vm_2][info 2016/07/25 12:49:27.015 PDT <RMI TCP Connection(25)-10.118.32.93> tid=0xf56] Partitioned Region /rootG/G with prId=114 closing. [vm_2][info 2016/07/25 12:49:27.019 PDT <RMI TCP Connection(25)-10.118.32.93> tid=0xf56] Partitioned Region /rootG/G with prId=114 closed. [vm_2][info 2016/07/25 12:49:27.020 PDT <RMI TCP Connection(25)-10.118.32.93> tid=0xf56] Partitioned Region /rootE/E with prId=112 closing. [vm_2][info 2016/07/25 12:49:27.023 PDT <RMI TCP Connection(25)-10.118.32.93> tid=0xf56] Partitioned Region /rootE/E with prId=112 closed. [vm_2][info 2016/07/25 12:49:27.025 PDT <RMI TCP Connection(25)-10.118.32.93> tid=0xf56] Partitioned Region /rootL/L with prId=119 closing. [vm_2][info 2016/07/25 12:49:27.029 PDT <RMI TCP Connection(25)-10.118.32.93> tid=0xf56] Partitioned Region /rootL/L with prId=119 closed. [vm_2][info 2016/07/25 12:49:27.030 PDT <RMI TCP Connection(25)-10.118.32.93> tid=0xf56] Partitioned Region /rootN/N with prId=121 closing. [vm_2][info 2016/07/25 12:49:27.032 PDT <RMI TCP Connection(25)-10.118.32.93> tid=0xf56] Partitioned Region /rootN/N with prId=121 closed. [vm_2][info 2016/07/25 12:49:27.033 PDT <RMI TCP Connection(25)-10.118.32.93> tid=0xf56] Partitioned Region /rootB/B with prId=109 closing. [vm_2][info 2016/07/25 12:49:27.035 PDT <RMI TCP Connection(25)-10.118.32.93> tid=0xf56] Partitioned Region /rootB/B with prId=109 closed. [vm_2][info 2016/07/25 12:49:27.037 PDT <RMI TCP Connection(25)-10.118.32.93> tid=0xf56] Partitioned Region /rootH/H with prId=115 closing. [vm_2][info 2016/07/25 12:49:27.040 PDT <RMI TCP Connection(25)-10.118.32.93> tid=0xf56] Partitioned Region /rootH/H with prId=115 closed. [vm_2][info 2016/07/25 12:49:27.041 PDT <RMI TCP Connection(25)-10.118.32.93> tid=0xf56] Partitioned Region /rootI/I with prId=116 closing. [vm_2][info 2016/07/25 12:49:27.043 PDT <RMI TCP Connection(25)-10.118.32.93> tid=0xf56] Partitioned Region /rootI/I with prId=116 closed. [vm_2][info 2016/07/25 12:49:27.044 PDT <RMI TCP Connection(25)-10.118.32.93> tid=0xf56] Partitioned Region /rootO/O with prId=122 closing. [vm_2][info 2016/07/25 12:49:27.046 PDT <RMI TCP Connection(25)-10.118.32.93> tid=0xf56] Partitioned Region /rootO/O with prId=122 closed. [vm_2][info 2016/07/25 12:49:27.047 PDT <RMI TCP Connection(25)-10.118.32.93> tid=0xf56] Partitioned Region /rootJ/J with prId=117 closing. [vm_2][info 2016/07/25 12:49:27.049 PDT <RMI TCP Connection(25)-10.118.32.93> tid=0xf56] Partitioned Region /rootJ/J with prId=117 closed. [vm_2][info 2016/07/25 12:49:27.050 PDT <RMI TCP Connection(25)-10.118.32.93> tid=0xf56] Partitioned Region /rootD/D with prId=111 closing. [vm_2][info 2016/07/25 12:49:27.053 PDT <RMI TCP Connection(25)-10.118.32.93> tid=0xf56] Partitioned Region /rootD/D with prId=111 closed. [vm_2][info 2016/07/25 12:49:27.054 PDT <RMI TCP Connection(25)-10.118.32.93> tid=0xf56] Partitioned Region /rootM/M with prId=120 closing. [vm_2][info 2016/07/25 12:49:27.056 PDT <RMI TCP Connection(25)-10.118.32.93> tid=0xf56] Partitioned Region /rootM/M with prId=120 closed. [vm_2][info 2016/07/25 12:49:27.057 PDT <RMI TCP Connection(25)-10.118.32.93> tid=0xf56] Partitioned Region /rootK/K with prId=118 closing. [vm_2][info 2016/07/25 12:49:27.060 PDT <RMI TCP Connection(25)-10.118.32.93> tid=0xf56] Partitioned Region /rootK/K with prId=118 closed. [vm_2][info 2016/07/25 12:49:27.060 PDT <RMI TCP Connection(25)-10.118.32.93> tid=0xf56] Partitioned Region /rootF/F with prId=113 closing. [vm_2][info 2016/07/25 12:49:27.063 PDT <RMI TCP Connection(25)-10.118.32.93> tid=0xf56] Partitioned Region /rootF/F with prId=113 closed. [vm_2][info 2016/07/25 12:49:27.064 PDT <RMI TCP Connection(25)-10.118.32.93> tid=0xf56] GemFireCache[id = 2134521106; isClosing = true; isShutDownAll = false; created = Mon Jul 25 12:49:25 PDT 2016; server = false; copyOnRead = false; lockLease = 120; lockTimeout = 60]: Now closing. [vm_2][info 2016/07/25 12:49:27.067 PDT <RMI TCP Connection(25)-10.118.32.93> tid=0xf56] Got result: null [vm_2] from com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$10/1875724691.run with 0 args on object: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$10/1875724691@4d2c32e0 (took 66 ms) [vm_3][info 2016/07/25 12:49:27.068 PDT <RMI TCP Connection(26)-10.118.32.93> tid=0x75a] Received method: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$10/1737651794.run with 0 args on object: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$10/1737651794@2afef1a8 [vm_3][info 2016/07/25 12:49:27.071 PDT <RMI TCP Connection(26)-10.118.32.93> tid=0x75a] Partitioned Region /rootI/I with prId=116 closing. [vm_3][info 2016/07/25 12:49:27.071 PDT <RMI TCP Connection(26)-10.118.32.93> tid=0x75a] Partitioned Region /rootI/I with prId=116 closed. [vm_3][info 2016/07/25 12:49:27.072 PDT <RMI TCP Connection(26)-10.118.32.93> tid=0x75a] Partitioned Region /rootG/G with prId=114 closing. [vm_3][info 2016/07/25 12:49:27.073 PDT <RMI TCP Connection(26)-10.118.32.93> tid=0x75a] Partitioned Region /rootG/G with prId=114 closed. [vm_3][info 2016/07/25 12:49:27.073 PDT <RMI TCP Connection(26)-10.118.32.93> tid=0x75a] Partitioned Region /rootK/K with prId=118 closing. [vm_3][info 2016/07/25 12:49:27.074 PDT <RMI TCP Connection(26)-10.118.32.93> tid=0x75a] Partitioned Region /rootK/K with prId=118 closed. [vm_3][info 2016/07/25 12:49:27.075 PDT <RMI TCP Connection(26)-10.118.32.93> tid=0x75a] Partitioned Region /rootH/H with prId=115 closing. [vm_3][info 2016/07/25 12:49:27.075 PDT <RMI TCP Connection(26)-10.118.32.93> tid=0x75a] Partitioned Region /rootH/H with prId=115 closed. [vm_3][info 2016/07/25 12:49:27.076 PDT <RMI TCP Connection(26)-10.118.32.93> tid=0x75a] Partitioned Region /rootD/D with prId=111 closing. [vm_3][info 2016/07/25 12:49:27.076 PDT <RMI TCP Connection(26)-10.118.32.93> tid=0x75a] Partitioned Region /rootD/D with prId=111 closed. [vm_3][info 2016/07/25 12:49:27.078 PDT <RMI TCP Connection(26)-10.118.32.93> tid=0x75a] Partitioned Region /rootE/E with prId=112 closing. [vm_3][info 2016/07/25 12:49:27.078 PDT <RMI TCP Connection(26)-10.118.32.93> tid=0x75a] Partitioned Region /rootE/E with prId=112 closed. [vm_3][info 2016/07/25 12:49:27.079 PDT <RMI TCP Connection(26)-10.118.32.93> tid=0x75a] Partitioned Region /rootM/M with prId=120 closing. [vm_3][info 2016/07/25 12:49:27.079 PDT <RMI TCP Connection(26)-10.118.32.93> tid=0x75a] Partitioned Region /rootM/M with prId=120 closed. [vm_3][info 2016/07/25 12:49:27.079 PDT <RMI TCP Connection(26)-10.118.32.93> tid=0x75a] Partitioned Region /rootN/N with prId=121 closing. [vm_3][info 2016/07/25 12:49:27.080 PDT <RMI TCP Connection(26)-10.118.32.93> tid=0x75a] Partitioned Region /rootN/N with prId=121 closed. [vm_3][info 2016/07/25 12:49:27.080 PDT <RMI TCP Connection(26)-10.118.32.93> tid=0x75a] Partitioned Region /rootA/A with prId=108 closing. [vm_3][info 2016/07/25 12:49:27.081 PDT <RMI TCP Connection(26)-10.118.32.93> tid=0x75a] Partitioned Region /rootA/A with prId=108 closed. [vm_3][info 2016/07/25 12:49:27.081 PDT <RMI TCP Connection(26)-10.118.32.93> tid=0x75a] Partitioned Region /rootJ/J with prId=117 closing. [vm_3][info 2016/07/25 12:49:27.082 PDT <RMI TCP Connection(26)-10.118.32.93> tid=0x75a] Partitioned Region /rootJ/J with prId=117 closed. [vm_3][info 2016/07/25 12:49:27.082 PDT <RMI TCP Connection(26)-10.118.32.93> tid=0x75a] Partitioned Region /rootB/B with prId=109 closing. [vm_3][info 2016/07/25 12:49:27.083 PDT <RMI TCP Connection(26)-10.118.32.93> tid=0x75a] Partitioned Region /rootB/B with prId=109 closed. [vm_3][info 2016/07/25 12:49:27.083 PDT <RMI TCP Connection(26)-10.118.32.93> tid=0x75a] Partitioned Region /rootO/O with prId=122 closing. [vm_3][info 2016/07/25 12:49:27.083 PDT <RMI TCP Connection(26)-10.118.32.93> tid=0x75a] Partitioned Region /rootO/O with prId=122 closed. [vm_3][info 2016/07/25 12:49:27.084 PDT <RMI TCP Connection(26)-10.118.32.93> tid=0x75a] Partitioned Region /rootC/C with prId=110 closing. [vm_3][info 2016/07/25 12:49:27.084 PDT <RMI TCP Connection(26)-10.118.32.93> tid=0x75a] Partitioned Region /rootC/C with prId=110 closed. [vm_3][info 2016/07/25 12:49:27.085 PDT <RMI TCP Connection(26)-10.118.32.93> tid=0x75a] Partitioned Region /rootF/F with prId=113 closing. [vm_3][info 2016/07/25 12:49:27.085 PDT <RMI TCP Connection(26)-10.118.32.93> tid=0x75a] Partitioned Region /rootF/F with prId=113 closed. [vm_3][info 2016/07/25 12:49:27.086 PDT <RMI TCP Connection(26)-10.118.32.93> tid=0x75a] Partitioned Region /rootL/L with prId=119 closing. [vm_3][info 2016/07/25 12:49:27.086 PDT <RMI TCP Connection(26)-10.118.32.93> tid=0x75a] Partitioned Region /rootL/L with prId=119 closed. [vm_3][info 2016/07/25 12:49:27.087 PDT <RMI TCP Connection(26)-10.118.32.93> tid=0x75a] GemFireCache[id = 2026606935; isClosing = true; isShutDownAll = false; created = Mon Jul 25 12:49:25 PDT 2016; server = false; copyOnRead = false; lockLease = 120; lockTimeout = 60]: Now closing. [vm_3][info 2016/07/25 12:49:27.090 PDT <RMI TCP Connection(26)-10.118.32.93> tid=0x75a] Got result: null [vm_3] from com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$10/1737651794.run with 0 args on object: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$10/1737651794@2afef1a8 (took 21 ms) [vm_0][info 2016/07/25 12:49:27.098 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xc96] Received method: com.gemstone.gemfire.internal.cache.execute.PRColocationDUnitTest$35.run with 0 args on object: com.gemstone.gemfire.internal.cache.execute.PRColocationDUnitTest$35@5babcee8 [vm_0][info 2016/07/25 12:49:27.098 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xc96] Got result: null [vm_0] from com.gemstone.gemfire.internal.cache.execute.PRColocationDUnitTest$35.run with 0 args on object: com.gemstone.gemfire.internal.cache.execute.PRColocationDUnitTest$35@5babcee8 (took 0 ms) [vm_1][info 2016/07/25 12:49:27.108 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xe67] Received method: com.gemstone.gemfire.internal.cache.execute.PRColocationDUnitTest$35.run with 0 args on object: com.gemstone.gemfire.internal.cache.execute.PRColocationDUnitTest$35@19fd140 [vm_1][info 2016/07/25 12:49:27.108 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xe67] Got result: null [vm_1] from com.gemstone.gemfire.internal.cache.execute.PRColocationDUnitTest$35.run with 0 args on object: com.gemstone.gemfire.internal.cache.execute.PRColocationDUnitTest$35@19fd140 (took 0 ms) [vm_2][info 2016/07/25 12:49:27.109 PDT <RMI TCP Connection(25)-10.118.32.93> tid=0xf56] Received method: com.gemstone.gemfire.internal.cache.execute.PRColocationDUnitTest$35.run with 0 args on object: com.gemstone.gemfire.internal.cache.execute.PRColocationDUnitTest$35@2c7d9099 [vm_2][info 2016/07/25 12:49:27.109 PDT <RMI TCP Connection(25)-10.118.32.93> tid=0xf56] Got result: null [vm_2] from com.gemstone.gemfire.internal.cache.execute.PRColocationDUnitTest$35.run with 0 args on object: com.gemstone.gemfire.internal.cache.execute.PRColocationDUnitTest$35@2c7d9099 (took 0 ms) [vm_3][info 2016/07/25 12:49:27.110 PDT <RMI TCP Connection(26)-10.118.32.93> tid=0x75a] Received method: com.gemstone.gemfire.internal.cache.execute.PRColocationDUnitTest$35.run with 0 args on object: com.gemstone.gemfire.internal.cache.execute.PRColocationDUnitTest$35@6c1af0f9 [vm_3][info 2016/07/25 12:49:27.110 PDT <RMI TCP Connection(26)-10.118.32.93> tid=0x75a] Got result: null [vm_3] from com.gemstone.gemfire.internal.cache.execute.PRColocationDUnitTest$35.run with 0 args on object: com.gemstone.gemfire.internal.cache.execute.PRColocationDUnitTest$35@6c1af0f9 (took 0 ms) [vm_0][info 2016/07/25 12:49:27.111 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xc96] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(tearDownCreationStackGenerator) [vm_0][info 2016/07/25 12:49:27.111 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xc96] Got result: null [vm_0] from com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(tearDownCreationStackGenerator) (took 0 ms) [vm_1][info 2016/07/25 12:49:27.112 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xe67] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(tearDownCreationStackGenerator) [vm_1][info 2016/07/25 12:49:27.112 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xe67] Got result: null [vm_1] from com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(tearDownCreationStackGenerator) (took 0 ms) [vm_2][info 2016/07/25 12:49:27.112 PDT <RMI TCP Connection(25)-10.118.32.93> tid=0xf56] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(tearDownCreationStackGenerator) [vm_2][info 2016/07/25 12:49:27.112 PDT <RMI TCP Connection(25)-10.118.32.93> tid=0xf56] Got result: null [vm_2] from com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(tearDownCreationStackGenerator) (took 0 ms) [vm_3][info 2016/07/25 12:49:27.113 PDT <RMI TCP Connection(26)-10.118.32.93> tid=0x75a] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(tearDownCreationStackGenerator) [vm_3][info 2016/07/25 12:49:27.113 PDT <RMI TCP Connection(26)-10.118.32.93> tid=0x75a] Got result: null [vm_3] from com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(tearDownCreationStackGenerator) (took 0 ms) [vm_0][info 2016/07/25 12:49:27.113 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xc96] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(tearDownVM) [vm_0][info 2016/07/25 12:49:27.114 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xc96] Shutting down DistributionManager kuwait(10504)<ec><v288>:1025. [vm_3][info 2016/07/25 12:49:27.115 PDT <Pooled High Priority Message Processor 3> tid=0xb88] received leave request from kuwait(10504)<ec><v288>:1025 for kuwait(10504)<ec><v288>:1025 [vm_1][info 2016/07/25 12:49:27.115 PDT <Pooled High Priority Message Processor 3> tid=0x1260] received leave request from kuwait(10504)<ec><v288>:1025 for kuwait(10504)<ec><v288>:1025 [vm_3][info 2016/07/25 12:49:27.115 PDT <Pooled High Priority Message Processor 3> tid=0xb88] Member at kuwait(10504)<ec><v288>:1025 gracefully left the distributed cache: shutdown message received [vm_2][info 2016/07/25 12:49:27.115 PDT <Pooled High Priority Message Processor 2> tid=0x135a] received leave request from kuwait(10504)<ec><v288>:1025 for kuwait(10504)<ec><v288>:1025 [vm_1][info 2016/07/25 12:49:27.115 PDT <Pooled High Priority Message Processor 3> tid=0x1260] Member at kuwait(10504)<ec><v288>:1025 gracefully left the distributed cache: shutdown message received [locator][info 2016/07/25 12:49:27.115 PDT <Pooled High Priority Message Processor 2> tid=0x5ac] received leave request from kuwait(10504)<ec><v288>:1025 for kuwait(10504)<ec><v288>:1025 [vm_2][info 2016/07/25 12:49:27.115 PDT <Pooled High Priority Message Processor 2> tid=0x135a] Member at kuwait(10504)<ec><v288>:1025 gracefully left the distributed cache: shutdown message received [locator][info 2016/07/25 12:49:27.115 PDT <Pooled High Priority Message Processor 2> tid=0x5ac] Member at kuwait(10504)<ec><v288>:1025 gracefully left the distributed cache: shutdown message received [vm_0][info 2016/07/25 12:49:27.215 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xc96] Now closing distribution for kuwait(10504)<ec><v288>:1025 [vm_0][info 2016/07/25 12:49:27.216 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xc96] Stopping membership services [vm_0][info 2016/07/25 12:49:27.216 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xc96] GMSHealthMonitor server socket is closed in stopServices(). [locator][info 2016/07/25 12:49:27.216 PDT <unicast receiver,kuwait-22220> tid=0x2a] received leave request from kuwait(10504)<ec><v288>:1025 for kuwait(10504)<ec><v288>:1025 [vm_3][info 2016/07/25 12:49:27.216 PDT <unicast receiver,kuwait-61394> tid=0xb68] received leave request from kuwait(10504)<ec><v288>:1025 for kuwait(10504)<ec><v288>:1025 [vm_1][info 2016/07/25 12:49:27.216 PDT <unicast receiver,kuwait-4891> tid=0x1236] received leave request from kuwait(10504)<ec><v288>:1025 for kuwait(10504)<ec><v288>:1025 [vm_0][info 2016/07/25 12:49:27.216 PDT <Geode Failure Detection Server thread 0> tid=0x114d] GMSHealthMonitor server thread exiting [vm_2][info 2016/07/25 12:49:27.216 PDT <unicast receiver,kuwait-42915> tid=0x133f] received leave request from kuwait(10504)<ec><v288>:1025 for kuwait(10504)<ec><v288>:1025 [vm_0][info 2016/07/25 12:49:27.216 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xc96] GMSHealthMonitor serverSocketExecutor is terminated [vm_0][info 2016/07/25 12:49:27.221 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xc96] DistributionManager stopped in 107ms. [vm_0][info 2016/07/25 12:49:27.221 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xc96] Marking DistributionManager kuwait(10504)<ec><v288>:1025 as closed. [vm_0][info 2016/07/25 12:49:27.222 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xc96] Got result: null [vm_0] from com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(tearDownVM) (took 108 ms) [vm_1][info 2016/07/25 12:49:27.222 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xe67] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(tearDownVM) [vm_1][info 2016/07/25 12:49:27.223 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xe67] Shutting down DistributionManager kuwait(10508)<ec><v289>:1026. [vm_2][info 2016/07/25 12:49:27.224 PDT <Pooled High Priority Message Processor 2> tid=0x135a] received leave request from kuwait(10508)<ec><v289>:1026 for kuwait(10508)<ec><v289>:1026 [vm_2][info 2016/07/25 12:49:27.224 PDT <Pooled High Priority Message Processor 2> tid=0x135a] Member at kuwait(10508)<ec><v289>:1026 gracefully left the distributed cache: shutdown message received [vm_3][info 2016/07/25 12:49:27.224 PDT <Pooled High Priority Message Processor 3> tid=0xb88] received leave request from kuwait(10508)<ec><v289>:1026 for kuwait(10508)<ec><v289>:1026 [locator][info 2016/07/25 12:49:27.224 PDT <Pooled High Priority Message Processor 2> tid=0x5ac] received leave request from kuwait(10508)<ec><v289>:1026 for kuwait(10508)<ec><v289>:1026 [vm_3][info 2016/07/25 12:49:27.224 PDT <Pooled High Priority Message Processor 3> tid=0xb88] Member at kuwait(10508)<ec><v289>:1026 gracefully left the distributed cache: shutdown message received [locator][info 2016/07/25 12:49:27.224 PDT <Pooled High Priority Message Processor 2> tid=0x5ac] Member at kuwait(10508)<ec><v289>:1026 gracefully left the distributed cache: shutdown message received [vm_1][info 2016/07/25 12:49:27.325 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xe67] Now closing distribution for kuwait(10508)<ec><v289>:1026 [vm_1][info 2016/07/25 12:49:27.325 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xe67] Stopping membership services [vm_1][info 2016/07/25 12:49:27.325 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xe67] GMSHealthMonitor server socket is closed in stopServices(). [vm_3][info 2016/07/25 12:49:27.326 PDT <unicast receiver,kuwait-61394> tid=0xb68] received leave request from kuwait(10508)<ec><v289>:1026 for kuwait(10508)<ec><v289>:1026 [locator][info 2016/07/25 12:49:27.326 PDT <unicast receiver,kuwait-22220> tid=0x2a] received leave request from kuwait(10508)<ec><v289>:1026 for kuwait(10508)<ec><v289>:1026 [vm_1][info 2016/07/25 12:49:27.326 PDT <Geode Failure Detection Server thread 0> tid=0x123a] GMSHealthMonitor server thread exiting [vm_1][info 2016/07/25 12:49:27.326 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xe67] GMSHealthMonitor serverSocketExecutor is terminated [vm_2][info 2016/07/25 12:49:27.326 PDT <unicast receiver,kuwait-42915> tid=0x133f] received leave request from kuwait(10508)<ec><v289>:1026 for kuwait(10508)<ec><v289>:1026 [vm_1][info 2016/07/25 12:49:27.331 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xe67] DistributionManager stopped in 108ms. [vm_1][info 2016/07/25 12:49:27.331 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xe67] Marking DistributionManager kuwait(10508)<ec><v289>:1026 as closed. [vm_1][info 2016/07/25 12:49:27.332 PDT <RMI TCP Connection(24)-10.118.32.93> tid=0xe67] Got result: null [vm_1] from com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(tearDownVM) (took 109 ms) [vm_2][info 2016/07/25 12:49:27.332 PDT <RMI TCP Connection(25)-10.118.32.93> tid=0xf56] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(tearDownVM) [vm_2][info 2016/07/25 12:49:27.333 PDT <RMI TCP Connection(25)-10.118.32.93> tid=0xf56] Shutting down DistributionManager kuwait(10513)<ec><v290>:1027. [vm_3][info 2016/07/25 12:49:27.334 PDT <Pooled High Priority Message Processor 3> tid=0xb88] received leave request from kuwait(10513)<ec><v290>:1027 for kuwait(10513)<ec><v290>:1027 [vm_3][info 2016/07/25 12:49:27.334 PDT <Pooled High Priority Message Processor 3> tid=0xb88] Member at kuwait(10513)<ec><v290>:1027 gracefully left the distributed cache: shutdown message received [locator][info 2016/07/25 12:49:27.334 PDT <Pooled High Priority Message Processor 2> tid=0x5ac] received leave request from kuwait(10513)<ec><v290>:1027 for kuwait(10513)<ec><v290>:1027 [locator][info 2016/07/25 12:49:27.334 PDT <Pooled High Priority Message Processor 2> tid=0x5ac] Member at kuwait(10513)<ec><v290>:1027 gracefully left the distributed cache: shutdown message received [vm_2][info 2016/07/25 12:49:27.434 PDT <RMI TCP Connection(25)-10.118.32.93> tid=0xf56] Now closing distribution for kuwait(10513)<ec><v290>:1027 [vm_2][info 2016/07/25 12:49:27.434 PDT <RMI TCP Connection(25)-10.118.32.93> tid=0xf56] Stopping membership services [locator][info 2016/07/25 12:49:27.434 PDT <Geode Membership View Creator> tid=0x30] View Creator is processing 5 requests for the next membership view [locator][info 2016/07/25 12:49:27.434 PDT <Geode Membership View Creator> tid=0x30] preparing new view View[kuwait(10426:locator)<ec><v0>:1024|292] members: [kuwait(10426:locator)<ec><v0>:1024, kuwait(10518)<ec><v291>:1028{lead}] shutdown: [kuwait(10513)<ec><v290>:1027, kuwait(10504)<ec><v288>:1025, kuwait(10508)<ec><v289>:1026] [locator]failure detection ports: 26403 51023 [vm_2][info 2016/07/25 12:49:27.435 PDT <RMI TCP Connection(25)-10.118.32.93> tid=0xf56] GMSHealthMonitor server socket is closed in stopServices(). [vm_2][info 2016/07/25 12:49:27.435 PDT <Geode Failure Detection Server thread 0> tid=0x1343] GMSHealthMonitor server thread exiting [locator][info 2016/07/25 12:49:27.435 PDT <unicast receiver,kuwait-22220> tid=0x2a] received leave request from kuwait(10513)<ec><v290>:1027 for kuwait(10513)<ec><v290>:1027 [locator][info 2016/07/25 12:49:27.435 PDT <Geode Membership View Creator> tid=0x30] finished waiting for responses to view preparation [vm_3][info 2016/07/25 12:49:27.435 PDT <unicast receiver,kuwait-61394> tid=0xb68] received leave request from kuwait(10513)<ec><v290>:1027 for kuwait(10513)<ec><v290>:1027 [locator][info 2016/07/25 12:49:27.435 PDT <Geode Membership View Creator> tid=0x30] received new view: View[kuwait(10426:locator)<ec><v0>:1024|292] members: [kuwait(10426:locator)<ec><v0>:1024, kuwait(10518)<ec><v291>:1028{lead}] shutdown: [kuwait(10513)<ec><v290>:1027, kuwait(10504)<ec><v288>:1025, kuwait(10508)<ec><v289>:1026] [locator]old view is: View[kuwait(10426:locator)<ec><v0>:1024|291] members: [kuwait(10426:locator)<ec><v0>:1024, kuwait(10504)<ec><v288>:1025{lead}, kuwait(10508)<ec><v289>:1026, kuwait(10513)<ec><v290>:1027, kuwait(10518)<ec><v291>:1028] [vm_2][info 2016/07/25 12:49:27.435 PDT <RMI TCP Connection(25)-10.118.32.93> tid=0xf56] GMSHealthMonitor serverSocketExecutor is terminated [locator][info 2016/07/25 12:49:27.435 PDT <Geode Membership View Creator> tid=0x30] Peer locator received new membership view: View[kuwait(10426:locator)<ec><v0>:1024|292] members: [kuwait(10426:locator)<ec><v0>:1024, kuwait(10518)<ec><v291>:1028{lead}] shutdown: [kuwait(10513)<ec><v290>:1027, kuwait(10504)<ec><v288>:1025, kuwait(10508)<ec><v289>:1026] [locator][info 2016/07/25 12:49:27.436 PDT <Geode Membership View Creator> tid=0x30] sending new view View[kuwait(10426:locator)<ec><v0>:1024|292] members: [kuwait(10426:locator)<ec><v0>:1024, kuwait(10518)<ec><v291>:1028{lead}] shutdown: [kuwait(10513)<ec><v290>:1027, kuwait(10504)<ec><v288>:1025, kuwait(10508)<ec><v289>:1026] [locator]failure detection ports: 26403 51023 [vm_3][info 2016/07/25 12:49:27.436 PDT <unicast receiver,kuwait-61394> tid=0xb68] received new view: View[kuwait(10426:locator)<ec><v0>:1024|292] members: [kuwait(10426:locator)<ec><v0>:1024, kuwait(10518)<ec><v291>:1028{lead}] shutdown: [kuwait(10504)<ec><v288>:1025, kuwait(10508)<ec><v289>:1026, kuwait(10513)<ec><v290>:1027] [vm_3]old view is: View[kuwait(10426:locator)<ec><v0>:1024|291] members: [kuwait(10426:locator)<ec><v0>:1024, kuwait(10504)<ec><v288>:1025{lead}, kuwait(10508)<ec><v289>:1026, kuwait(10513)<ec><v290>:1027, kuwait(10518)<ec><v291>:1028] [vm_2][info 2016/07/25 12:49:27.439 PDT <RMI TCP Connection(25)-10.118.32.93> tid=0xf56] DistributionManager stopped in 106ms. [vm_2][info 2016/07/25 12:49:27.439 PDT <RMI TCP Connection(25)-10.118.32.93> tid=0xf56] Marking DistributionManager kuwait(10513)<ec><v290>:1027 as closed. [vm_2][info 2016/07/25 12:49:27.439 PDT <RMI TCP Connection(25)-10.118.32.93> tid=0xf56] Got result: null [vm_2] from com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(tearDownVM) (took 106 ms) [vm_3][info 2016/07/25 12:49:27.440 PDT <RMI TCP Connection(26)-10.118.32.93> tid=0x75a] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(tearDownVM) [vm_3][info 2016/07/25 12:49:27.441 PDT <RMI TCP Connection(26)-10.118.32.93> tid=0x75a] Shutting down DistributionManager kuwait(10518)<ec><v291>:1028. [locator][info 2016/07/25 12:49:27.441 PDT <Pooled High Priority Message Processor 2> tid=0x5ac] received leave request from kuwait(10518)<ec><v291>:1028 for kuwait(10518)<ec><v291>:1028 [locator][info 2016/07/25 12:49:27.441 PDT <Pooled High Priority Message Processor 2> tid=0x5ac] Member at kuwait(10518)<ec><v291>:1028 gracefully left the distributed cache: shutdown message received [vm_3][info 2016/07/25 12:49:27.542 PDT <RMI TCP Connection(26)-10.118.32.93> tid=0x75a] Now closing distribution for kuwait(10518)<ec><v291>:1028 [vm_3][info 2016/07/25 12:49:27.542 PDT <RMI TCP Connection(26)-10.118.32.93> tid=0x75a] Stopping membership services [vm_3][info 2016/07/25 12:49:27.542 PDT <RMI TCP Connection(26)-10.118.32.93> tid=0x75a] GMSHealthMonitor server socket is closed in stopServices(). [locator][info 2016/07/25 12:49:27.542 PDT <unicast receiver,kuwait-22220> tid=0x2a] received leave request from kuwait(10518)<ec><v291>:1028 for kuwait(10518)<ec><v291>:1028 [vm_3][info 2016/07/25 12:49:27.542 PDT <Geode Failure Detection Server thread 0> tid=0xb6c] GMSHealthMonitor server thread exiting [vm_3][info 2016/07/25 12:49:27.542 PDT <RMI TCP Connection(26)-10.118.32.93> tid=0x75a] GMSHealthMonitor serverSocketExecutor is terminated [vm_3][info 2016/07/25 12:49:27.549 PDT <RMI TCP Connection(26)-10.118.32.93> tid=0x75a] DistributionManager stopped in 108ms. [vm_3][info 2016/07/25 12:49:27.549 PDT <RMI TCP Connection(26)-10.118.32.93> tid=0x75a] Marking DistributionManager kuwait(10518)<ec><v291>:1028 as closed. [vm_3][info 2016/07/25 12:49:27.550 PDT <RMI TCP Connection(26)-10.118.32.93> tid=0x75a] Got result: null [vm_3] from com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(tearDownVM) (took 109 ms) [locator][info 2016/07/25 12:49:27.550 PDT <RMI TCP Connection(7)-10.118.32.93> tid=0x4bc] Received method: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/214457188.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/214457188@ab55e59 [locator][info 2016/07/25 12:49:27.551 PDT <RMI TCP Connection(7)-10.118.32.93> tid=0x4bc] Got result: null [locator] from com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/214457188.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/214457188@ab55e59 (took 0 ms) Standard Error [vm_0]java.lang.IllegalStateException: Colocated regions should have accessors at the same node [vm_0] at com.gemstone.gemfire.internal.cache.PartitionRegionConfigValidator.validateColocation(PartitionRegionConfigValidator.java:334) [vm_0] at com.gemstone.gemfire.internal.cache.PartitionedRegion.initPRInternals(PartitionedRegion.java:810) [vm_0] at com.gemstone.gemfire.internal.cache.PartitionedRegion.initialize(PartitionedRegion.java:1007) [vm_0] at com.gemstone.gemfire.internal.cache.GemFireCacheImpl.createVMRegion(GemFireCacheImpl.java:3123) [vm_0] at com.gemstone.gemfire.internal.cache.GemFireCacheImpl.basicCreateRegion(GemFireCacheImpl.java:3017) [vm_0] at com.gemstone.gemfire.internal.cache.GemFireCacheImpl.createRegion(GemFireCacheImpl.java:3006) [vm_0] at com.gemstone.gemfire.internal.cache.execute.PRColocationDUnitTest$2.run2(PRColocationDUnitTest.java:725) [vm_0] at com.gemstone.gemfire.cache30.CacheSerializableRunnable.run(CacheSerializableRunnable.java:60) [vm_0] at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [vm_0] at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) [vm_0] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [vm_0] at java.lang.reflect.Method.invoke(Method.java:497) [vm_0] at hydra.MethExecutor.executeObject(MethExecutor.java:268) [vm_0] at com.gemstone.gemfire.test.dunit.standalone.RemoteDUnitVM.executeMethodOnObject(RemoteDUnitVM.java:82) [vm_0] at sun.reflect.GeneratedMethodAccessor7.invoke(Unknown Source) [vm_0] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [vm_0] at java.lang.reflect.Method.invoke(Method.java:497) [vm_0] at sun.rmi.server.UnicastServerRef.dispatch(UnicastServerRef.java:323) [vm_0] at sun.rmi.transport.Transport$1.run(Transport.java:200) [vm_0] at sun.rmi.transport.Transport$1.run(Transport.java:197) [vm_0] at java.security.AccessController.doPrivileged(Native Method) [vm_0] at sun.rmi.transport.Transport.serviceCall(Transport.java:196) [vm_0] at sun.rmi.transport.tcp.TCPTransport.handleMessages(TCPTransport.java:568) [vm_0] at sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.run0(TCPTransport.java:826) [vm_0] at sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.lambda$run$95(TCPTransport.java:683) [vm_0] at java.security.AccessController.doPrivileged(Native Method) [vm_0] at sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.run(TCPTransport.java:682) [vm_0] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) [vm_0] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) [vm_0] at java.lang.Thread.run(Thread.java:745) Suspicious strings were written to the log during this run. Fix the strings or use IgnoredException.addIgnoredException to ignore. ----------------------------------------------------------------------- Found suspect string in log4j at line 1289 [fatal 2016/07/25 12:48:55.930 PDT <unicast receiver,kuwait-35881> tid=0x14d] Membership service failure: Member isn't responding to heartbeat requests com.gemstone.gemfire.ForcedDisconnectException: Member isn't responding to heartbeat requests at com.gemstone.gemfire.distributed.internal.membership.gms.mgr.GMSMembershipManager.forceDisconnect(GMSMembershipManager.java:2606) at com.gemstone.gemfire.distributed.internal.membership.gms.membership.GMSJoinLeave.forceDisconnect(GMSJoinLeave.java:918) at com.gemstone.gemfire.distributed.internal.membership.gms.membership.GMSJoinLeave.processRemoveRequest(GMSJoinLeave.java:593) at com.gemstone.gemfire.distributed.internal.membership.gms.membership.GMSJoinLeave.processMessage(GMSJoinLeave.java:1555) at com.gemstone.gemfire.distributed.internal.membership.gms.messenger.JGroupsMessenger$JGroupsReceiver.receive(JGroupsMessenger.java:1065) at org.jgroups.JChannel.invokeCallback(JChannel.java:816) at org.jgroups.JChannel.up(JChannel.java:741) at org.jgroups.stack.ProtocolStack.up(ProtocolStack.java:1030) at org.jgroups.protocols.FRAG2.up(FRAG2.java:165) at org.jgroups.protocols.FlowControl.up(FlowControl.java:390) at org.jgroups.protocols.UNICAST3.deliverMessage(UNICAST3.java:1070) at org.jgroups.protocols.UNICAST3.handleDataReceived(UNICAST3.java:785) at org.jgroups.protocols.UNICAST3.up(UNICAST3.java:426) at com.gemstone.gemfire.distributed.internal.membership.gms.messenger.StatRecorder.up(StatRecorder.java:75) at com.gemstone.gemfire.distributed.internal.membership.gms.messenger.AddressManager.up(AddressManager.java:75) at org.jgroups.protocols.TP.passMessageUp(TP.java:1601) at org.jgroups.protocols.TP$SingleMessageHandler.run(TP.java:1817) at org.jgroups.util.DirectExecutor.execute(DirectExecutor.java:10) at org.jgroups.protocols.TP.handleSingleMessage(TP.java:1729) at org.jgroups.protocols.TP.receive(TP.java:1654) at com.gemstone.gemfire.distributed.internal.membership.gms.messenger.Transport.receive(Transport.java:162) at org.jgroups.protocols.UDP$PacketReceiver.run(UDP.java:701) at java.lang.Thread.run(Thread.java:745) ----------------------------------------------------------------------- Found suspect string in log4j at line 1325 [error 2016/07/25 12:48:57.938 PDT <Test worker> tid=0x13] com.gemstone.gemfire.distributed.DistributedSystemDisconnectedException: This connection to a distributed system has been disconnected., caused by com.gemstone.gemfire.ForcedDisconnectException: Member isn't responding to heartbeat requests {noformat} -- This message was sent by Atlassian JIRA (v6.3.4#6332)