[
https://issues.apache.org/jira/browse/GEODE-1705?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel
]
Kirk Lund updated GEODE-1705:
-----------------------------
Component/s: tests
management
> DurableClientCommandsDUnitTest.testCloseDurableCQ
> -------------------------------------------------
>
> Key: GEODE-1705
> URL: https://issues.apache.org/jira/browse/GEODE-1705
> Project: Geode
> Issue Type: Bug
> Components: client queues, management, tests
> Reporter: Kevin Duling
> Labels: DistributedTest, Flaky, ci
>
> In Geode_develop_DistributedTests/3414:
> {noformat}
> Error Message
> java.lang.AssertionError
> Stacktrace
> java.lang.AssertionError
> at org.junit.Assert.fail(Assert.java:86)
> at org.junit.Assert.assertTrue(Assert.java:41)
> at org.junit.Assert.assertTrue(Assert.java:52)
> at
> com.gemstone.gemfire.management.internal.cli.commands.DurableClientCommandsDUnitTest.testCloseDurableCQ(DurableClientCommandsDUnitTest.java:138)
> 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.ExternalResource$1.evaluate(ExternalResource.java:48)
> at org.junit.rules.ExternalResource$1.evaluate(ExternalResource.java:48)
> 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.GeneratedMethodAccessor377.invoke(Unknown Source)
> 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.GeneratedMethodAccessor376.invoke(Unknown Source)
> 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)
> Standard Output
> [vm_1][info 2016/07/26 22:12:01.616 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Received method:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$1.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$1@3ccb7f52
> [vm_1][info 2016/07/26 22:12:01.616 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Got result: null
> [vm_1] from
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$1.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$1@3ccb7f52
> (took 0 ms)
> [vm_2][info 2016/07/26 22:12:01.621 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] Received method:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$1.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$1@500eef01
> [vm_2][info 2016/07/26 22:12:01.621 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] Got result: null
> [vm_2] from
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$1.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$1@500eef01
> (took 0 ms)
> [vm_3][info 2016/07/26 22:12:01.629 PDT <RMI TCP Connection(49)-10.118.32.37>
> tid=0x12] Received method:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$1.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$1@48d31a8a
> [vm_3][info 2016/07/26 22:12:01.629 PDT <RMI TCP Connection(49)-10.118.32.37>
> tid=0x12] Got result: null
> [vm_3] from
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$1.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$1@48d31a8a
> (took 0 ms)
> [locator][info 2016/07/26 22:12:01.635 PDT <RMI TCP
> Connection(21)-10.118.32.37> tid=0x9c4] Received method:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$1.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$1@9fdc078
> [locator][info 2016/07/26 22:12:01.635 PDT <RMI TCP
> Connection(21)-10.118.32.37> tid=0x9c4] Got result: null
> [locator] from
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$1.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$1@9fdc078
> (took 0 ms)
> Previously run tests: [PRDeltaPropagationDUnitTest,
> RemoteCQTransactionDUnitTest, HADispatcherDUnitTest, CQListGIIDUnitTest,
> DurableClientSimpleDUnitTest, DeltaToRegionRelationCQRegistrationDUnitTest,
> DeltaPropagationWithCQDUnitTest, DurableClientNetDownDUnitTest,
> ClientToServerDeltaDUnitTest, DurableClientCrashDUnitTest,
> DurableClientTestCase, PutAllCSDUnitTest,
> IntegratedClientQueryAuthDistributedTest, ClientAuthorizationTwoDUnitTest,
> MultiUserAPIDUnitTest, CQPostProcessorDunitTest,
> ClientAuthzObjectModDUnitTest, ClientCQPostAuthorizationDUnitTest,
> MultiUserDurableCQAuthzDUnitTest, CacheServerManagementDUnitTest,
> DurableClientCommandsDUnitTest]
> [vm_1][info 2016/07/26 22:12:01.636 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Received method:
> com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object:
> runnable(setupVM)
> [vm_1][info 2016/07/26 22:12:01.637 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] 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/26 22:12:01.637 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] Received method:
> com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object:
> runnable(setupVM)
> [vm_2][info 2016/07/26 22:12:01.637 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] 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/26 22:12:01.637 PDT <RMI TCP Connection(49)-10.118.32.37>
> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run
> with 0 args on object: runnable(setupVM)
> [vm_3][info 2016/07/26 22:12:01.638 PDT <RMI TCP Connection(49)-10.118.32.37>
> tid=0x12] 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 DurableClientCommandsDUnitTest.testListDurableClientCqs
> [vm_1][info 2016/07/26 22:12:01.639 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Received method:
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$43/226580143.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$43/226580143@59c2134
> [vm_1][info 2016/07/26 22:12:01.639 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Got result: null
> [vm_1] from
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$43/226580143.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$43/226580143@59c2134
> (took 0 ms)
> [vm_2][info 2016/07/26 22:12:01.639 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] Received method:
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$61/90828831.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$61/90828831@79cbde16
> [vm_2][info 2016/07/26 22:12:01.639 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] Got result: null
> [vm_2] from
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$61/90828831.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$61/90828831@79cbde16
> (took 0 ms)
> [vm_3][info 2016/07/26 22:12:01.639 PDT <RMI TCP Connection(49)-10.118.32.37>
> tid=0x12] Received method:
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$22/1440013754.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$22/1440013754@25433c1e
> [vm_3][info 2016/07/26 22:12:01.640 PDT <RMI TCP Connection(49)-10.118.32.37>
> tid=0x12] Got result: null
> [vm_3] from
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$22/1440013754.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$22/1440013754@25433c1e
> (took 0 ms)
> [vm_0]distributed system properties: {name=Manager, http-service-port=28693,
> jmx-manager=true, jmx-manager-bind-address=japan, jmx-manager-port=26427,
> mcast-port=0, log-level=info, disable-auto-reconnect=true,
> enable-cluster-configuration=false, jmx-manager-start=true,
> locators=localhost[48279], use-cluster-configuration=false}
> [locator][info 2016/07/26 22:12:01.680 PDT <unicast receiver,japan-14750>
> tid=0x21] received join request from japan(Manager:31630)<ec>:1025
> [locator][info 2016/07/26 22:12:01.980 PDT <Geode Membership View Creator>
> tid=0x27] View Creator is processing 1 requests for the next membership view
> [locator][info 2016/07/26 22:12:01.981 PDT <Geode Membership View Creator>
> tid=0x27] preparing new view View[japan(31570:locator)<ec><v0>:1024|555]
> members: [japan(31570:locator)<ec><v0>:1024,
> japan(Manager:31630)<ec><v555>:1025{lead}]
> [locator]failure detection ports: 15292 24426
> [locator][info 2016/07/26 22:12:01.981 PDT <Geode Membership View Creator>
> tid=0x27] finished waiting for responses to view preparation
> [locator][info 2016/07/26 22:12:01.982 PDT <Geode Membership View Creator>
> tid=0x27] received new view: View[japan(31570:locator)<ec><v0>:1024|555]
> members: [japan(31570:locator)<ec><v0>:1024,
> japan(Manager:31630)<ec><v555>:1025{lead}]
> [locator]old view is: View[japan(31570:locator)<ec><v0>:1024|554] members:
> [japan(31570:locator)<ec><v0>:1024] shutdown: [japan(31639)<ec><v551>:1025,
> japan(31630)<ec><v552>:1026, japan(31634)<ec><v553>:1027]
> [locator][info 2016/07/26 22:12:01.982 PDT <Geode Membership View Creator>
> tid=0x27] Peer locator received new membership view:
> View[japan(31570:locator)<ec><v0>:1024|555] members:
> [japan(31570:locator)<ec><v0>:1024, japan(Manager:31630)<ec><v555>:1025{lead}]
> [locator][info 2016/07/26 22:12:01.982 PDT <Geode Membership View Creator>
> tid=0x27] sending new view View[japan(31570:locator)<ec><v0>:1024|555]
> members: [japan(31570:locator)<ec><v0>:1024,
> japan(Manager:31630)<ec><v555>:1025{lead}]
> [locator]failure detection ports: 15292 24426
> [locator][info 2016/07/26 22:12:01.982 PDT <View Message Processor> tid=0x33]
> Membership: Processing addition < japan(Manager:31630)<ec><v555>:1025 >
> [locator][info 2016/07/26 22:12:01.982 PDT <View Message Processor> tid=0x33]
> Admitting member <japan(Manager:31630)<ec><v555>:1025>. Now there are 2
> non-admin member(s).
> [locator][info 2016/07/26 22:12:01.985 PDT <Pooled High Priority Message
> Processor 3> tid=0x9a2] Member japan(Manager:31630)<ec><v555>:1025 is
> equivalent or in the same redundancy zone.
> [vm_0]1900.008: [GC (Metadata GC Threshold) [PSYoungGen:
> 21320K->5210K(59904K)] 40251K->24173K(170496K), 0.0074584 secs] [Times:
> user=0.01 sys=0.00, real=0.01 secs]
> [vm_0]1900.016: [Full GC (Metadata GC Threshold) [PSYoungGen:
> 5210K->0K(59904K)] [ParOldGen: 18962K->17631K(111616K)]
> 24173K->17631K(171520K), [Metaspace: 55979K->54860K(1101824K)], 0.1519021
> secs] [Times: user=0.29 sys=0.00, real=0.15 secs]
> [info 2016/07/26 22:12:02.357 PDT <Test worker> tid=0xa] Started testable
> shell: com.gemstone.gemfire.management.internal.cli.HeadlessGfsh@129b33c5
> DurableClientCommandsDUnitTest using endpoint: japan[26427]
> [info 2016/07/26 22:12:02.357 PDT <Test worker> tid=0xa] Executing command
> connect --jmx-manager=japan[26427] with command Mgr
> com.gemstone.gemfire.management.internal.cli.CommandManager@6007de98
> [info 2016/07/26 22:12:02.440 PDT <Test worker> tid=0xa] Successfully
> connected to managing node using JMX
> [vm_1][info 2016/07/26 22:12:02.448 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Received method:
> com.gemstone.gemfire.management.internal.cli.commands.DurableClientCommandsDUnitTest$3.call
> with 0 args on object:
> com.gemstone.gemfire.management.internal.cli.commands.DurableClientCommandsDUnitTest$3@5c35ca7b
> [vm_1]distributed system properties: {locators=localhost[48279],
> enable-cluster-configuration=false, use-cluster-configuration=false,
> mcast-port=0, disable-auto-reconnect=true, log-level=info}
> [vm_1][info 2016/07/26 22:12:02.451 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] 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[48279]
> [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/26 22:12:02.453 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Starting membership services
> [vm_1][info 2016/07/26 22:12:02.463 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] JGroups channel created (took 10ms)
> [vm_1][info 2016/07/26 22:12:02.463 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] GemFire P2P Listener started on null
> [vm_1][info 2016/07/26 22:12:02.464 PDT <Geode Failure Detection Server
> thread 0> tid=0x21b8] Started failure detection server thread on
> japan.gemstone.com/10.118.32.37:36923.
> [vm_1][info 2016/07/26 22:12:02.465 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Attempting to join the distributed system through coordinator
> japan(31570:locator)<ec><v0>:1024 using address japan(31634)<ec>:1026
> [locator][info 2016/07/26 22:12:02.465 PDT <unicast receiver,japan-14750>
> tid=0x21] received join request from japan(31634)<ec>:1026
> [locator][info 2016/07/26 22:12:02.765 PDT <Geode Membership View Creator>
> tid=0x27] View Creator is processing 1 requests for the next membership view
> [locator][info 2016/07/26 22:12:02.766 PDT <Geode Membership View Creator>
> tid=0x27] preparing new view View[japan(31570:locator)<ec><v0>:1024|556]
> members: [japan(31570:locator)<ec><v0>:1024,
> japan(Manager:31630)<ec><v555>:1025{lead}, japan(31634)<ec><v556>:1026]
> [locator]failure detection ports: 15292 24426 36923
> [vm_1][info 2016/07/26 22:12:02.767 PDT <unicast receiver,japan-56094>
> tid=0x21b4] received new view: View[japan(31570:locator)<ec><v0>:1024|556]
> members: [japan(31570:locator)<ec><v0>:1024,
> japan(Manager:31630)<ec><v555>:1025{lead}, japan(31634)<ec><v556>:1026]
> [vm_1]old view is: null
> [locator][info 2016/07/26 22:12:02.767 PDT <Geode Membership View Creator>
> tid=0x27] finished waiting for responses to view preparation
> [locator][info 2016/07/26 22:12:02.767 PDT <Geode Membership View Creator>
> tid=0x27] received new view: View[japan(31570:locator)<ec><v0>:1024|556]
> members: [japan(31570:locator)<ec><v0>:1024,
> japan(Manager:31630)<ec><v555>:1025{lead}, japan(31634)<ec><v556>:1026]
> [locator]old view is: View[japan(31570:locator)<ec><v0>:1024|555] members:
> [japan(31570:locator)<ec><v0>:1024, japan(Manager:31630)<ec><v555>:1025{lead}]
> [locator][info 2016/07/26 22:12:02.767 PDT <Geode Membership View Creator>
> tid=0x27] Peer locator received new membership view:
> View[japan(31570:locator)<ec><v0>:1024|556] members:
> [japan(31570:locator)<ec><v0>:1024,
> japan(Manager:31630)<ec><v555>:1025{lead}, japan(31634)<ec><v556>:1026]
> [vm_1][info 2016/07/26 22:12:02.767 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Finished joining (took 303ms).
> [vm_1][info 2016/07/26 22:12:02.767 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Starting DistributionManager japan(31634)<ec><v556>:1026. (took
> 315 ms)
> [locator][info 2016/07/26 22:12:02.767 PDT <Geode Membership View Creator>
> tid=0x27] sending new view View[japan(31570:locator)<ec><v0>:1024|556]
> members: [japan(31570:locator)<ec><v0>:1024,
> japan(Manager:31630)<ec><v555>:1025{lead}, japan(31634)<ec><v556>:1026]
> [locator]failure detection ports: 15292 24426 36923
> [locator][info 2016/07/26 22:12:02.767 PDT <View Message Processor> tid=0x33]
> Membership: Processing addition < japan(31634)<ec><v556>:1026 >
> [locator][info 2016/07/26 22:12:02.767 PDT <View Message Processor> tid=0x33]
> Admitting member <japan(31634)<ec><v556>:1026>. Now there are 3 non-admin
> member(s).
> [vm_1][info 2016/07/26 22:12:02.767 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Initial (distribution manager) view =
> View[japan(31570:locator)<ec><v0>:1024|556] members:
> [japan(31570:locator)<ec><v0>:1024,
> japan(Manager:31630)<ec><v555>:1025{lead}, japan(31634)<ec><v556>:1026]
> [vm_1][info 2016/07/26 22:12:02.767 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Admitting member <japan(31570:locator)<ec><v0>:1024>. Now there
> are 1 non-admin member(s).
> [vm_1][info 2016/07/26 22:12:02.768 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Admitting member <japan(Manager:31630)<ec><v555>:1025>. Now there
> are 2 non-admin member(s).
> [vm_1][info 2016/07/26 22:12:02.768 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Admitting member <japan(31634)<ec><v556>:1026>. Now there are 3
> non-admin member(s).
> [locator][info 2016/07/26 22:12:02.770 PDT <Pooled High Priority Message
> Processor 3> tid=0x9a2] Member japan(31634)<ec><v556>:1026 is equivalent or
> in the same redundancy zone.
> [vm_1][info 2016/07/26 22:12:02.771 PDT <P2P message reader for
> japan(Manager:31630)<ec><v555>:1025 shared unordered uid=781 port=33850>
> tid=0x21be] Member japan(Manager:31630)<ec><v555>:1025 is equivalent or in
> the same redundancy zone.
> [vm_1][info 2016/07/26 22:12:02.772 PDT <P2P message reader for
> japan(31570:locator)<ec><v0>:1024 shared unordered uid=463 port=33851>
> tid=0x21bf] Member japan(31570:locator)<ec><v0>:1024 is equivalent or in the
> same redundancy zone.
> [vm_1][info 2016/07/26 22:12:02.778 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Initializing region _monitoringRegion_10.118.32.37<v556>1026
> [vm_1][info 2016/07/26 22:12:02.779 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Region _monitoringRegion_10.118.32.37<v556>1026 requesting
> initial image from japan(Manager:31630)<ec><v555>:1025
> [vm_1][info 2016/07/26 22:12:02.779 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] _monitoringRegion_10.118.32.37<v556>1026 is done getting image
> from japan(Manager:31630)<ec><v555>:1025. isDeltaGII is false
> [vm_1][info 2016/07/26 22:12:02.779 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Initialization of region _monitoringRegion_10.118.32.37<v556>1026
> completed
> [vm_1][info 2016/07/26 22:12:02.782 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] The cache has been created with
> "use-cluster-configuration=false". It will not receive any cluster
> configuration
> [vm_1][info 2016/07/26 22:12:02.782 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Initializing region PdxTypes
> [vm_1][info 2016/07/26 22:12:02.783 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Region PdxTypes requesting initial image from
> japan(Manager:31630)<ec><v555>:1025
> [vm_1][info 2016/07/26 22:12:02.783 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] PdxTypes is done getting image from
> japan(Manager:31630)<ec><v555>:1025. isDeltaGII is false
> [vm_1][info 2016/07/26 22:12:02.783 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Initialization of region PdxTypes completed
> [vm_1][info 2016/07/26 22:12:02.784 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Initializing region stocks
> [vm_1][info 2016/07/26 22:12:02.784 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Initialization of region stocks completed
> [vm_1][info 2016/07/26 22:12:02.785 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Cache server connection listener bound to address
> 0.0.0.0/0.0.0.0:56307 with backlog 1,000.
> [vm_1][info 2016/07/26 22:12:02.786 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] ClientHealthMonitorThread maximum allowed time between pings:
> 60,000
> [vm_1][info 2016/07/26 22:12:02.786 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] CacheServer Configuration: port=56307 max-connections=800
> max-threads=0 notify-by-subscription=true socket-buffer-size=32768
> maximum-time-between-pings=60000 maximum-message-count=230000
> message-time-to-live=180 eviction-policy=none capacity=1 overflow directory=.
> groups=[] loadProbe=ConnectionCountProbe loadPollInterval=5000 tcpNoDelay=true
> [vm_1][info 2016/07/26 22:12:02.787 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Got result: 56307
> [vm_1] from
> com.gemstone.gemfire.management.internal.cli.commands.DurableClientCommandsDUnitTest$3.call
> with 0 args on object:
> com.gemstone.gemfire.management.internal.cli.commands.DurableClientCommandsDUnitTest$3@5c35ca7b
> (took 338 ms)
> [vm_2][info 2016/07/26 22:12:02.794 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] Received method:
> com.gemstone.gemfire.management.internal.cli.commands.DurableClientCommandsDUnitTest$4.run
> with 0 args on object: "Start client"
> [vm_2]distributed system properties: {locators=, durable-client-timeout=300,
> durable-client-id=dc1, enable-cluster-configuration=false,
> use-cluster-configuration=false, mcast-port=0, disable-auto-reconnect=true,
> log-level=info}
> [vm_2][info 2016/07/26 22:12:02.803 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] Running in client mode
> [vm_2][info 2016/07/26 22:12:02.805 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] Updating membership port. Port changed from 0 to 54,881. ID is
> now japan(31639:loner):0:f04bc42a
> [vm_1][info 2016/07/26 22:12:02.808 PDT <Handshaker 0.0.0.0/0.0.0.0:56307
> Thread 0> tid=0x21d3] Initializing region
> _gfe_durable_client_with_id_dc1_1_queue
> [vm_1][info 2016/07/26 22:12:02.808 PDT <Handshaker 0.0.0.0/0.0.0.0:56307
> Thread 0> tid=0x21d3] Initialization of region
> _gfe_durable_client_with_id_dc1_1_queue completed
> [vm_2][info 2016/07/26 22:12:02.809 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] Pool DEFAULT started with multiuser-authentication=false
> [vm_2][info 2016/07/26 22:12:02.809 PDT <Cache Client Updater Thread on
> japan(31634)<ec><v556>:1026 port 56307> tid=0x185b] Cache Client Updater
> Thread on japan(31634)<ec><v556>:1026 port 56307 (japan.gemstone.com:56307)
> : ready to process messages.
> [vm_2][info 2016/07/26 22:12:02.809 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] <ExpectedException
> action=add>java.net.ConnectException</ExpectedException>
> [vm_2][info 2016/07/26 22:12:02.809 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] <ExpectedException
> action=add>java.net.ConnectException</ExpectedException>
> [vm_2][info 2016/07/26 22:12:02.809 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] <ExpectedException
> action=add>java.net.ConnectException</ExpectedException>
> [vm_2][info 2016/07/26 22:12:02.809 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] Got result: null
> [vm_2] from
> com.gemstone.gemfire.management.internal.cli.commands.DurableClientCommandsDUnitTest$4.run
> with 0 args on object: "Start client" (took 15 ms)
> [vm_2][info 2016/07/26 22:12:02.812 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] Received method:
> com.gemstone.gemfire.management.internal.cli.commands.DurableClientCommandsDUnitTest$2.call
> with 0 args on object:
> com.gemstone.gemfire.management.internal.cli.commands.DurableClientCommandsDUnitTest$2@7eed4c9a
> [vm_2][info 2016/07/26 22:12:02.816 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] Got result: true
> [vm_2] from
> com.gemstone.gemfire.management.internal.cli.commands.DurableClientCommandsDUnitTest$2.call
> with 0 args on object:
> com.gemstone.gemfire.management.internal.cli.commands.DurableClientCommandsDUnitTest$2@7eed4c9a
> (took 3 ms)
> [info 2016/07/26 22:12:02.816 PDT <Test worker> tid=0xa]
> DurableClientCommandsDUnitTest_testListDurableClientCqs: Command String :
>
> list durable-cqs --durable-client-id=dc1
> [info 2016/07/26 22:12:02.816 PDT <Test worker> tid=0xa] Executing command
> list durable-cqs --durable-client-id=dc1 with command Mgr
> com.gemstone.gemfire.management.internal.cli.CommandManager@6007de98
> [info 2016/07/26 22:12:02.882 PDT <Test worker> tid=0xa]
> DurableClientCommandsDUnitTest_testListDurableClientCqs: Command Result :
> member | durable-cq-name
> --------------------------- | ---------------
> japan(31634)<ec><v556>:1026 | cq2
> | cq1
> | cq3
> [vm_2][info 2016/07/26 22:12:02.885 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] Received method:
> com.gemstone.gemfire.management.internal.cli.commands.DurableClientCommandsDUnitTest$1.call
> with 0 args on object:
> com.gemstone.gemfire.management.internal.cli.commands.DurableClientCommandsDUnitTest$1@759d212b
> [vm_2][info 2016/07/26 22:12:02.888 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] Got result: true
> [vm_2] from
> com.gemstone.gemfire.management.internal.cli.commands.DurableClientCommandsDUnitTest$1.call
> with 0 args on object:
> com.gemstone.gemfire.management.internal.cli.commands.DurableClientCommandsDUnitTest$1@759d212b
> (took 2 ms)
> [vm_2][info 2016/07/26 22:12:02.889 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] Received method:
> com.gemstone.gemfire.management.internal.cli.commands.DurableClientCommandsDUnitTest$1.call
> with 0 args on object:
> com.gemstone.gemfire.management.internal.cli.commands.DurableClientCommandsDUnitTest$1@3a387b36
> [vm_2][info 2016/07/26 22:12:02.889 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] Got result: true
> [vm_2] from
> com.gemstone.gemfire.management.internal.cli.commands.DurableClientCommandsDUnitTest$1.call
> with 0 args on object:
> com.gemstone.gemfire.management.internal.cli.commands.DurableClientCommandsDUnitTest$1@3a387b36
> (took 0 ms)
> [vm_2][info 2016/07/26 22:12:02.890 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] Received method:
> com.gemstone.gemfire.management.internal.cli.commands.DurableClientCommandsDUnitTest$1.call
> with 0 args on object:
> com.gemstone.gemfire.management.internal.cli.commands.DurableClientCommandsDUnitTest$1@447b1ed2
> [vm_2][info 2016/07/26 22:12:02.890 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] Got result: true
> [vm_2] from
> com.gemstone.gemfire.management.internal.cli.commands.DurableClientCommandsDUnitTest$1.call
> with 0 args on object:
> com.gemstone.gemfire.management.internal.cli.commands.DurableClientCommandsDUnitTest$1@447b1ed2
> (took 0 ms)
> [info 2016/07/26 22:12:02.890 PDT <Test worker> tid=0xa]
> DurableClientCommandsDUnitTest_testListDurableClientCqs: Command String :
>
> list durable-cqs --durable-client-id=dc1
> [info 2016/07/26 22:12:02.890 PDT <Test worker> tid=0xa] Executing command
> list durable-cqs --durable-client-id=dc1 with command Mgr
> com.gemstone.gemfire.management.internal.cli.CommandManager@6007de98
> [info 2016/07/26 22:12:02.973 PDT <Test worker> tid=0xa]
> DurableClientCommandsDUnitTest_testListDurableClientCqs: Command Result :
> Unable to list durable-cqs for durable-client-id : "dc1" due to following
> reasons.
> No durable cqs found for durable-client-id : "dc1".
> Occurred on members
> 1.japan(31634)<ec><v556>:1026
> No client found on this server
> Occurred on members
> 1.Manager
> [info 2016/07/26 22:12:02.973 PDT <Test worker> tid=0xa] Executing command
> exit with command Mgr
> com.gemstone.gemfire.management.internal.cli.CommandManager@6007de98
> [vm_1][info 2016/07/26 22:12:03.004 PDT <Pooled High Priority Message
> Processor 1> tid=0x21c0] received leave request from
> japan(Manager:31630)<ec><v555>:1025 for japan(Manager:31630)<ec><v555>:1025
> [vm_1][info 2016/07/26 22:12:03.004 PDT <Pooled High Priority Message
> Processor 1> tid=0x21c0] Member at japan(Manager:31630)<ec><v555>:1025
> gracefully left the distributed cache: shutdown message received
> [locator][info 2016/07/26 22:12:03.004 PDT <Pooled High Priority Message
> Processor 3> tid=0x9a2] received leave request from
> japan(Manager:31630)<ec><v555>:1025 for japan(Manager:31630)<ec><v555>:1025
> [locator][info 2016/07/26 22:12:03.004 PDT <Pooled High Priority Message
> Processor 3> tid=0x9a2] Member at japan(Manager:31630)<ec><v555>:1025
> gracefully left the distributed cache: shutdown message received
> [vm_1][info 2016/07/26 22:12:03.106 PDT <unicast receiver,japan-56094>
> tid=0x21b4] received leave request from japan(Manager:31630)<ec><v555>:1025
> for japan(Manager:31630)<ec><v555>:1025
> [locator][info 2016/07/26 22:12:03.106 PDT <unicast receiver,japan-14750>
> tid=0x21] received leave request from japan(Manager:31630)<ec><v555>:1025 for
> japan(Manager:31630)<ec><v555>:1025
> [vm_1][info 2016/07/26 22:12:03.112 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Received method:
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$43/226580143.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$43/226580143@59c2134
> [vm_1][info 2016/07/26 22:12:03.112 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] GemFireCache[id = 1910709280; isClosing = true; isShutDownAll =
> false; created = Tue Jul 26 22:12:02 PDT 2016; server = false; copyOnRead =
> false; lockLease = 120; lockTimeout = 60]: Now closing.
> [vm_1][info 2016/07/26 22:12:03.114 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Cache server on port 56,307 is shutting down.
> [vm_1][info 2016/07/26 22:12:03.115 PDT <Queue Removal Thread> tid=0x21d8]
> The QueueRemovalThread is done.
> [vm_2][info 2016/07/26 22:12:03.116 PDT <Cache Client Updater Thread on
> japan(31634)<ec><v556>:1026 port 56307> tid=0x185b] Primary subscription
> endpoint japan.gemstone.com:56307 crashed. Scheduling recovery.
> [vm_2][info 2016/07/26 22:12:03.117 PDT <Cache Client Updater Thread on
> japan(31634)<ec><v556>:1026 port 56307> tid=0x185b] Cache client updater for
> Queue on endpoint japan.gemstone.com:56307 exiting. Scheduling recovery.
> [vm_2][info 2016/07/26 22:12:03.117 PDT <queueTimer-DEFAULT> tid=0x185c]
> SubscriptionManager redundancy satisfier - primary endpoint has been lost.
> Attempting to recover.
> [vm_1][info 2016/07/26 22:12:03.117 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Shutting down DistributionManager japan(31634)<ec><v556>:1026.
> [locator][info 2016/07/26 22:12:03.118 PDT <Pooled High Priority Message
> Processor 1> tid=0x37] received leave request from
> japan(31634)<ec><v556>:1026 for japan(31634)<ec><v556>:1026
> [locator][info 2016/07/26 22:12:03.118 PDT <Pooled High Priority Message
> Processor 1> tid=0x37] Member at japan(31634)<ec><v556>:1026 gracefully left
> the distributed cache: shutdown message received
> [vm_1][info 2016/07/26 22:12:03.219 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Now closing distribution for japan(31634)<ec><v556>:1026
> [vm_1][info 2016/07/26 22:12:03.219 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Stopping membership services
> [locator][info 2016/07/26 22:12:03.219 PDT <unicast receiver,japan-14750>
> tid=0x21] received leave request from japan(31634)<ec><v556>:1026 for
> japan(31634)<ec><v556>:1026
> [vm_1][info 2016/07/26 22:12:03.219 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] GMSHealthMonitor server socket is closed in stopServices().
> [vm_1][info 2016/07/26 22:12:03.219 PDT <Geode Failure Detection Server
> thread 0> tid=0x21b8] GMSHealthMonitor server thread exiting
> [vm_1][info 2016/07/26 22:12:03.219 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] GMSHealthMonitor serverSocketExecutor is terminated
> [vm_1][info 2016/07/26 22:12:03.221 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] DistributionManager stopped in 104ms.
> [vm_1][info 2016/07/26 22:12:03.221 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Marking DistributionManager japan(31634)<ec><v556>:1026 as closed.
> [vm_1][info 2016/07/26 22:12:03.222 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Got result: null
> [vm_1] from
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$43/226580143.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$43/226580143@59c2134
> (took 110 ms)
> [vm_2][info 2016/07/26 22:12:03.223 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] Received method:
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$61/90828831.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$61/90828831@79cbde16
> [vm_2][info 2016/07/26 22:12:03.223 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] GemFireCache[id = 1847885149; isClosing = true; isShutDownAll =
> false; created = Tue Jul 26 22:12:02 PDT 2016; server = false; copyOnRead =
> false; lockLease = 120; lockTimeout = 60]: Now closing.
> [vm_2][info 2016/07/26 22:12:03.223 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] Destroying connection pool DEFAULT
> [vm_2][info 2016/07/26 22:12:03.229 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] Got result: null
> [vm_2] from
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$61/90828831.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$61/90828831@79cbde16
> (took 5 ms)
> [vm_3][info 2016/07/26 22:12:03.229 PDT <RMI TCP Connection(49)-10.118.32.37>
> tid=0x12] Received method:
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$22/1440013754.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$22/1440013754@25433c1e
> [vm_3][info 2016/07/26 22:12:03.229 PDT <RMI TCP Connection(49)-10.118.32.37>
> tid=0x12] Got result: null
> [vm_3] from
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$22/1440013754.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$22/1440013754@25433c1e
> (took 0 ms)
> [vm_1][info 2016/07/26 22:12:03.233 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Received method:
> com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$34/1151432988.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$34/1151432988@4f3252cb
> [vm_1][info 2016/07/26 22:12:03.233 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Got result: null
> [vm_1] from
> com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$34/1151432988.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$34/1151432988@4f3252cb
> (took 0 ms)
> [vm_2][info 2016/07/26 22:12:03.234 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] Received method:
> com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$51/1017652523.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$51/1017652523@20ba592b
> [vm_2][info 2016/07/26 22:12:03.234 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] Got result: null
> [vm_2] from
> com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$51/1017652523.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$51/1017652523@20ba592b
> (took 0 ms)
> [vm_3][info 2016/07/26 22:12:03.235 PDT <RMI TCP Connection(49)-10.118.32.37>
> tid=0x12] Received method:
> com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$14/327108742.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$14/327108742@a22da2d
> [vm_3][info 2016/07/26 22:12:03.235 PDT <RMI TCP Connection(49)-10.118.32.37>
> tid=0x12] Got result: null
> [vm_3] from
> com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$14/327108742.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$14/327108742@a22da2d
> (took 0 ms)
> [vm_1][info 2016/07/26 22:12:03.239 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Received method:
> com.gemstone.gemfire.management.internal.cli.commands.DurableClientCommandsDUnitTest$$Lambda$137/381285965.run
> with 0 args on object:
> com.gemstone.gemfire.management.internal.cli.commands.DurableClientCommandsDUnitTest$$Lambda$137/381285965@23ba5ada
> [vm_1][info 2016/07/26 22:12:03.239 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Got result: null
> [vm_1] from
> com.gemstone.gemfire.management.internal.cli.commands.DurableClientCommandsDUnitTest$$Lambda$137/381285965.run
> with 0 args on object:
> com.gemstone.gemfire.management.internal.cli.commands.DurableClientCommandsDUnitTest$$Lambda$137/381285965@23ba5ada
> (took 0 ms)
> [vm_2][info 2016/07/26 22:12:03.242 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] Received method:
> com.gemstone.gemfire.management.internal.cli.commands.DurableClientCommandsDUnitTest$$Lambda$226/867285789.run
> with 0 args on object:
> com.gemstone.gemfire.management.internal.cli.commands.DurableClientCommandsDUnitTest$$Lambda$226/867285789@631695c9
> [vm_2][info 2016/07/26 22:12:03.242 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] Got result: null
> [vm_2] from
> com.gemstone.gemfire.management.internal.cli.commands.DurableClientCommandsDUnitTest$$Lambda$226/867285789.run
> with 0 args on object:
> com.gemstone.gemfire.management.internal.cli.commands.DurableClientCommandsDUnitTest$$Lambda$226/867285789@631695c9
> (took 0 ms)
> [vm_1][info 2016/07/26 22:12:03.243 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Received method:
> com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object:
> runnable(tearDownCreationStackGenerator)
> [vm_1][info 2016/07/26 22:12:03.243 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] 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/26 22:12:03.244 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] Received method:
> com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object:
> runnable(tearDownCreationStackGenerator)
> [vm_2][info 2016/07/26 22:12:03.244 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] 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/26 22:12:03.244 PDT <RMI TCP Connection(49)-10.118.32.37>
> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run
> with 0 args on object: runnable(tearDownCreationStackGenerator)
> [vm_3][info 2016/07/26 22:12:03.245 PDT <RMI TCP Connection(49)-10.118.32.37>
> tid=0x12] Got result: null
> [vm_3] from com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on
> object: runnable(tearDownCreationStackGenerator) (took 0 ms)
> [vm_1][info 2016/07/26 22:12:03.246 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Received method:
> com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object:
> runnable(tearDownVM)
> [vm_1][info 2016/07/26 22:12:03.246 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Got result: null
> [vm_1] from com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on
> object: runnable(tearDownVM) (took 0 ms)
> [vm_2][info 2016/07/26 22:12:03.247 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] Received method:
> com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object:
> runnable(tearDownVM)
> [vm_2][info 2016/07/26 22:12:03.247 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] <ExpectedException
> action=remove>java.net.ConnectException</ExpectedException>
> [vm_2][info 2016/07/26 22:12:03.247 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] <ExpectedException
> action=remove>java.net.ConnectException</ExpectedException>
> [vm_2][info 2016/07/26 22:12:03.247 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] Got result: null
> [vm_2] from com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on
> object: runnable(tearDownVM) (took 0 ms)
> [vm_3][info 2016/07/26 22:12:03.248 PDT <RMI TCP Connection(49)-10.118.32.37>
> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run
> with 0 args on object: runnable(tearDownVM)
> [vm_3][info 2016/07/26 22:12:03.248 PDT <RMI TCP Connection(49)-10.118.32.37>
> tid=0x12] Got result: null
> [vm_3] from com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on
> object: runnable(tearDownVM) (took 0 ms)
> [locator][info 2016/07/26 22:12:03.249 PDT <RMI TCP
> Connection(21)-10.118.32.37> tid=0x9c4] Received method:
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/2122565141.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/2122565141@7dc872b1
> [locator][info 2016/07/26 22:12:03.249 PDT <RMI TCP
> Connection(21)-10.118.32.37> tid=0x9c4] Got result: null
> [locator] from
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/2122565141.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/2122565141@7dc872b1
> (took 0 ms)
> [vm_1][info 2016/07/26 22:12:03.254 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Received method:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$2.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$2@74687483
> [vm_1][info 2016/07/26 22:12:03.254 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Got result: null
> [vm_1] from
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$2.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$2@74687483
> (took 0 ms)
> [vm_2][info 2016/07/26 22:12:03.256 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] Received method:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$2.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$2@7ff6138e
> [vm_2][info 2016/07/26 22:12:03.256 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] Got result: null
> [vm_2] from
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$2.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$2@7ff6138e
> (took 0 ms)
> [vm_3][info 2016/07/26 22:12:03.257 PDT <RMI TCP Connection(49)-10.118.32.37>
> tid=0x12] Received method:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$2.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$2@27ceb336
> [vm_3][info 2016/07/26 22:12:03.257 PDT <RMI TCP Connection(49)-10.118.32.37>
> tid=0x12] Got result: null
> [vm_3] from
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$2.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$2@27ceb336
> (took 0 ms)
> [locator][info 2016/07/26 22:12:03.259 PDT <RMI TCP
> Connection(21)-10.118.32.37> tid=0x9c4] Received method:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$2.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$2@5aed8ef8
> [locator][info 2016/07/26 22:12:03.259 PDT <RMI TCP
> Connection(21)-10.118.32.37> tid=0x9c4] Got result: null
> [locator] from
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$2.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$2@5aed8ef8
> (took 0 ms)
> [vm_1][info 2016/07/26 22:12:03.266 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Received method:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$1.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$1@4b1138a7
> [vm_1][info 2016/07/26 22:12:03.266 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Got result: null
> [vm_1] from
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$1.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$1@4b1138a7
> (took 0 ms)
> [vm_2][info 2016/07/26 22:12:03.267 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] Received method:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$1.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$1@262a6480
> [vm_2][info 2016/07/26 22:12:03.267 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] Got result: null
> [vm_2] from
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$1.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$1@262a6480
> (took 0 ms)
> [vm_3][info 2016/07/26 22:12:03.267 PDT <RMI TCP Connection(49)-10.118.32.37>
> tid=0x12] Received method:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$1.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$1@2e2fecbb
> [vm_3][info 2016/07/26 22:12:03.267 PDT <RMI TCP Connection(49)-10.118.32.37>
> tid=0x12] Got result: null
> [vm_3] from
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$1.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$1@2e2fecbb
> (took 0 ms)
> [locator][info 2016/07/26 22:12:03.268 PDT <RMI TCP
> Connection(21)-10.118.32.37> tid=0x9c4] Received method:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$1.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$1@7596eab1
> [locator][info 2016/07/26 22:12:03.268 PDT <RMI TCP
> Connection(21)-10.118.32.37> tid=0x9c4] Got result: null
> [locator] from
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$1.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$1@7596eab1
> (took 0 ms)
> Previously run tests: [PRDeltaPropagationDUnitTest,
> RemoteCQTransactionDUnitTest, HADispatcherDUnitTest, CQListGIIDUnitTest,
> DurableClientSimpleDUnitTest, DeltaToRegionRelationCQRegistrationDUnitTest,
> DeltaPropagationWithCQDUnitTest, DurableClientNetDownDUnitTest,
> ClientToServerDeltaDUnitTest, DurableClientCrashDUnitTest,
> DurableClientTestCase, PutAllCSDUnitTest,
> IntegratedClientQueryAuthDistributedTest, ClientAuthorizationTwoDUnitTest,
> MultiUserAPIDUnitTest, CQPostProcessorDunitTest,
> ClientAuthzObjectModDUnitTest, ClientCQPostAuthorizationDUnitTest,
> MultiUserDurableCQAuthzDUnitTest, CacheServerManagementDUnitTest,
> DurableClientCommandsDUnitTest]
> [vm_1][info 2016/07/26 22:12:03.269 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Received method:
> com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object:
> runnable(setupVM)
> [vm_1][info 2016/07/26 22:12:03.269 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] 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/26 22:12:03.270 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] Received method:
> com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object:
> runnable(setupVM)
> [vm_2][info 2016/07/26 22:12:03.270 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] 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/26 22:12:03.270 PDT <RMI TCP Connection(49)-10.118.32.37>
> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run
> with 0 args on object: runnable(setupVM)
> [vm_3][info 2016/07/26 22:12:03.270 PDT <RMI TCP Connection(49)-10.118.32.37>
> tid=0x12] 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 DurableClientCommandsDUnitTest.t
> ...[truncated 83038 chars]...
> ith 0 args on object: runnable(tearDownCreationStackGenerator)
> [vm_1][info 2016/07/26 22:12:04.923 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] 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/26 22:12:04.924 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] Received method:
> com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object:
> runnable(tearDownCreationStackGenerator)
> [vm_2][info 2016/07/26 22:12:04.924 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] 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/26 22:12:04.924 PDT <RMI TCP Connection(49)-10.118.32.37>
> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run
> with 0 args on object: runnable(tearDownCreationStackGenerator)
> [vm_3][info 2016/07/26 22:12:04.924 PDT <RMI TCP Connection(49)-10.118.32.37>
> tid=0x12] Got result: null
> [vm_3] from com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on
> object: runnable(tearDownCreationStackGenerator) (took 0 ms)
> [vm_1][info 2016/07/26 22:12:04.925 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Received method:
> com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object:
> runnable(tearDownVM)
> [vm_1][info 2016/07/26 22:12:04.925 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Got result: null
> [vm_1] from com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on
> object: runnable(tearDownVM) (took 0 ms)
> [vm_2][info 2016/07/26 22:12:04.925 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] Received method:
> com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object:
> runnable(tearDownVM)
> [vm_2][info 2016/07/26 22:12:04.925 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] <ExpectedException
> action=remove>java.net.ConnectException</ExpectedException>
> [vm_2][info 2016/07/26 22:12:04.925 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] <ExpectedException
> action=remove>java.net.ConnectException</ExpectedException>
> [vm_2][info 2016/07/26 22:12:04.925 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] Got result: null
> [vm_2] from com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on
> object: runnable(tearDownVM) (took 0 ms)
> [vm_3][info 2016/07/26 22:12:04.926 PDT <RMI TCP Connection(49)-10.118.32.37>
> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run
> with 0 args on object: runnable(tearDownVM)
> [vm_3][info 2016/07/26 22:12:04.926 PDT <RMI TCP Connection(49)-10.118.32.37>
> tid=0x12] Got result: null
> [vm_3] from com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on
> object: runnable(tearDownVM) (took 0 ms)
> [locator][info 2016/07/26 22:12:04.926 PDT <RMI TCP
> Connection(21)-10.118.32.37> tid=0x9c4] Received method:
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/2122565141.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/2122565141@7dc872b1
> [locator][info 2016/07/26 22:12:04.926 PDT <RMI TCP
> Connection(21)-10.118.32.37> tid=0x9c4] Got result: null
> [locator] from
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/2122565141.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/2122565141@7dc872b1
> (took 0 ms)
> [vm_1][info 2016/07/26 22:12:04.927 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Received method:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$2.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$2@3791c31f
> [vm_1][info 2016/07/26 22:12:04.928 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Got result: null
> [vm_1] from
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$2.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$2@3791c31f
> (took 0 ms)
> [vm_2][info 2016/07/26 22:12:04.928 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] Received method:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$2.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$2@1889fcfd
> [vm_2][info 2016/07/26 22:12:04.928 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] Got result: null
> [vm_2] from
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$2.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$2@1889fcfd
> (took 0 ms)
> [vm_3][info 2016/07/26 22:12:04.928 PDT <RMI TCP Connection(49)-10.118.32.37>
> tid=0x12] Received method:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$2.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$2@5421f890
> [vm_3][info 2016/07/26 22:12:04.928 PDT <RMI TCP Connection(49)-10.118.32.37>
> tid=0x12] Got result: null
> [vm_3] from
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$2.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$2@5421f890
> (took 0 ms)
> [locator][info 2016/07/26 22:12:04.928 PDT <RMI TCP
> Connection(21)-10.118.32.37> tid=0x9c4] Received method:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$2.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$2@517a9271
> [locator][info 2016/07/26 22:12:04.929 PDT <RMI TCP
> Connection(21)-10.118.32.37> tid=0x9c4] Got result: null
> [locator] from
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$2.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$2@517a9271
> (took 0 ms)
> [vm_1][info 2016/07/26 22:12:04.930 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Received method:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$1.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$1@17f4e3c6
> [vm_1][info 2016/07/26 22:12:04.930 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Got result: null
> [vm_1] from
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$1.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$1@17f4e3c6
> (took 0 ms)
> [vm_2][info 2016/07/26 22:12:04.930 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] Received method:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$1.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$1@770091c6
> [vm_2][info 2016/07/26 22:12:04.930 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] Got result: null
> [vm_2] from
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$1.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$1@770091c6
> (took 0 ms)
> [vm_3][info 2016/07/26 22:12:04.930 PDT <RMI TCP Connection(49)-10.118.32.37>
> tid=0x12] Received method:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$1.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$1@26b31712
> [vm_3][info 2016/07/26 22:12:04.930 PDT <RMI TCP Connection(49)-10.118.32.37>
> tid=0x12] Got result: null
> [vm_3] from
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$1.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$1@26b31712
> (took 0 ms)
> [locator][info 2016/07/26 22:12:04.930 PDT <RMI TCP
> Connection(21)-10.118.32.37> tid=0x9c4] Received method:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$1.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$1@7f07bd55
> [locator][info 2016/07/26 22:12:04.930 PDT <RMI TCP
> Connection(21)-10.118.32.37> tid=0x9c4] Got result: null
> [locator] from
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$1.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$1@7f07bd55
> (took 0 ms)
> Previously run tests: [PRDeltaPropagationDUnitTest,
> RemoteCQTransactionDUnitTest, HADispatcherDUnitTest, CQListGIIDUnitTest,
> DurableClientSimpleDUnitTest, DeltaToRegionRelationCQRegistrationDUnitTest,
> DeltaPropagationWithCQDUnitTest, DurableClientNetDownDUnitTest,
> ClientToServerDeltaDUnitTest, DurableClientCrashDUnitTest,
> DurableClientTestCase, PutAllCSDUnitTest,
> IntegratedClientQueryAuthDistributedTest, ClientAuthorizationTwoDUnitTest,
> MultiUserAPIDUnitTest, CQPostProcessorDunitTest,
> ClientAuthzObjectModDUnitTest, ClientCQPostAuthorizationDUnitTest,
> MultiUserDurableCQAuthzDUnitTest, CacheServerManagementDUnitTest,
> DurableClientCommandsDUnitTest]
> [vm_1][info 2016/07/26 22:12:04.931 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Received method:
> com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object:
> runnable(setupVM)
> [vm_1][info 2016/07/26 22:12:04.931 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] 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/26 22:12:04.931 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] Received method:
> com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object:
> runnable(setupVM)
> [vm_2][info 2016/07/26 22:12:04.931 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] 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/26 22:12:04.932 PDT <RMI TCP Connection(49)-10.118.32.37>
> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run
> with 0 args on object: runnable(setupVM)
> [vm_3][info 2016/07/26 22:12:04.932 PDT <RMI TCP Connection(49)-10.118.32.37>
> tid=0x12] 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 DurableClientCommandsDUnitTest.testCloseDurableCQ
> [vm_1][info 2016/07/26 22:12:04.932 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Received method:
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$43/226580143.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$43/226580143@59c2134
> [vm_1][info 2016/07/26 22:12:04.932 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Got result: null
> [vm_1] from
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$43/226580143.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$43/226580143@59c2134
> (took 0 ms)
> [vm_2][info 2016/07/26 22:12:04.933 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] Received method:
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$61/90828831.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$61/90828831@79cbde16
> [vm_2][info 2016/07/26 22:12:04.933 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] Got result: null
> [vm_2] from
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$61/90828831.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$61/90828831@79cbde16
> (took 0 ms)
> [vm_3][info 2016/07/26 22:12:04.933 PDT <RMI TCP Connection(49)-10.118.32.37>
> tid=0x12] Received method:
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$22/1440013754.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$22/1440013754@25433c1e
> [vm_3][info 2016/07/26 22:12:04.933 PDT <RMI TCP Connection(49)-10.118.32.37>
> tid=0x12] Got result: null
> [vm_3] from
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$22/1440013754.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$22/1440013754@25433c1e
> (took 0 ms)
> [vm_0]distributed system properties: {name=Manager, http-service-port=20575,
> jmx-manager=true, jmx-manager-bind-address=japan, jmx-manager-port=20500,
> mcast-port=0, log-level=info, disable-auto-reconnect=true,
> enable-cluster-configuration=false, jmx-manager-start=true,
> locators=localhost[48279], use-cluster-configuration=false}
> [locator][info 2016/07/26 22:12:04.945 PDT <unicast receiver,japan-14750>
> tid=0x21] received join request from japan(Manager:31630)<ec>:1025
> [locator][info 2016/07/26 22:12:05.046 PDT <Geode Membership View Creator>
> tid=0x27] View Creator is processing 5 requests for the next membership view
> [locator][info 2016/07/26 22:12:05.046 PDT <Geode Membership View Creator>
> tid=0x27] preparing new view View[japan(31570:locator)<ec><v0>:1024|561]
> members: [japan(31570:locator)<ec><v0>:1024,
> japan(Manager:31630)<ec><v561>:1025{lead}] shutdown:
> [japan(Manager:31630)<ec><v559>:1025, japan(31634)<ec><v560>:1026]
> [locator]failure detection ports: 15292 16628
> [locator][info 2016/07/26 22:12:05.046 PDT <Geode Membership View Creator>
> tid=0x27] finished waiting for responses to view preparation
> [locator][info 2016/07/26 22:12:05.046 PDT <Geode Membership View Creator>
> tid=0x27] received new view: View[japan(31570:locator)<ec><v0>:1024|561]
> members: [japan(31570:locator)<ec><v0>:1024,
> japan(Manager:31630)<ec><v561>:1025{lead}] shutdown:
> [japan(Manager:31630)<ec><v559>:1025, japan(31634)<ec><v560>:1026]
> [locator]old view is: View[japan(31570:locator)<ec><v0>:1024|560] members:
> [japan(31570:locator)<ec><v0>:1024,
> japan(Manager:31630)<ec><v559>:1025{lead}, japan(31634)<ec><v560>:1026]
> [locator][info 2016/07/26 22:12:05.046 PDT <Geode Membership View Creator>
> tid=0x27] Peer locator received new membership view:
> View[japan(31570:locator)<ec><v0>:1024|561] members:
> [japan(31570:locator)<ec><v0>:1024,
> japan(Manager:31630)<ec><v561>:1025{lead}] shutdown:
> [japan(Manager:31630)<ec><v559>:1025, japan(31634)<ec><v560>:1026]
> [locator][info 2016/07/26 22:12:05.047 PDT <Geode Membership View Creator>
> tid=0x27] sending new view View[japan(31570:locator)<ec><v0>:1024|561]
> members: [japan(31570:locator)<ec><v0>:1024,
> japan(Manager:31630)<ec><v561>:1025{lead}] shutdown:
> [japan(Manager:31630)<ec><v559>:1025, japan(31634)<ec><v560>:1026]
> [locator]failure detection ports: 15292 16628
> [locator][info 2016/07/26 22:12:05.047 PDT <View Message Processor> tid=0x33]
> Membership: Processing addition < japan(Manager:31630)<ec><v561>:1025 >
> [locator][info 2016/07/26 22:12:05.047 PDT <View Message Processor> tid=0x33]
> Admitting member <japan(Manager:31630)<ec><v561>:1025>. Now there are 2
> non-admin member(s).
> [locator][info 2016/07/26 22:12:05.048 PDT <Pooled High Priority Message
> Processor 1> tid=0x37] Member japan(Manager:31630)<ec><v561>:1025 is
> equivalent or in the same redundancy zone.
> [info 2016/07/26 22:12:05.075 PDT <Test worker> tid=0xa] Started testable
> shell: com.gemstone.gemfire.management.internal.cli.HeadlessGfsh@30ee0857
> DurableClientCommandsDUnitTest using endpoint: japan[20500]
> [info 2016/07/26 22:12:05.075 PDT <Test worker> tid=0xa] Executing command
> connect --jmx-manager=japan[20500] with command Mgr
> com.gemstone.gemfire.management.internal.cli.CommandManager@6007de98
> [info 2016/07/26 22:12:05.084 PDT <Test worker> tid=0xa] Successfully
> connected to managing node using JMX
> [vm_1][info 2016/07/26 22:12:05.084 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Received method:
> com.gemstone.gemfire.management.internal.cli.commands.DurableClientCommandsDUnitTest$3.call
> with 0 args on object:
> com.gemstone.gemfire.management.internal.cli.commands.DurableClientCommandsDUnitTest$3@6851b415
> [vm_1]distributed system properties: {locators=localhost[48279],
> enable-cluster-configuration=false, use-cluster-configuration=false,
> mcast-port=0, disable-auto-reconnect=true, log-level=info}
> [vm_1][info 2016/07/26 22:12:05.086 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] 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[48279]
> [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/26 22:12:05.087 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Starting membership services
> [vm_1][info 2016/07/26 22:12:05.093 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] JGroups channel created (took 6ms)
> [vm_1][info 2016/07/26 22:12:05.093 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] GemFire P2P Listener started on null
> [vm_1][info 2016/07/26 22:12:05.093 PDT <Geode Failure Detection Server
> thread 0> tid=0x227d] Started failure detection server thread on
> japan.gemstone.com/10.118.32.37:2123.
> [vm_1][info 2016/07/26 22:12:05.094 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Attempting to join the distributed system through coordinator
> japan(31570:locator)<ec><v0>:1024 using address japan(31634)<ec>:1026
> [locator][info 2016/07/26 22:12:05.094 PDT <unicast receiver,japan-14750>
> tid=0x21] received join request from japan(31634)<ec>:1026
> [locator][info 2016/07/26 22:12:05.395 PDT <Geode Membership View Creator>
> tid=0x27] View Creator is processing 1 requests for the next membership view
> [locator][info 2016/07/26 22:12:05.395 PDT <Geode Membership View Creator>
> tid=0x27] preparing new view View[japan(31570:locator)<ec><v0>:1024|562]
> members: [japan(31570:locator)<ec><v0>:1024,
> japan(Manager:31630)<ec><v561>:1025{lead}, japan(31634)<ec><v562>:1026]
> [locator]failure detection ports: 15292 16628 2123
> [vm_1][info 2016/07/26 22:12:05.396 PDT <unicast receiver,japan-3344>
> tid=0x2279] received new view: View[japan(31570:locator)<ec><v0>:1024|562]
> members: [japan(31570:locator)<ec><v0>:1024,
> japan(Manager:31630)<ec><v561>:1025{lead}, japan(31634)<ec><v562>:1026]
> [vm_1]old view is: null
> [locator][info 2016/07/26 22:12:05.396 PDT <Geode Membership View Creator>
> tid=0x27] finished waiting for responses to view preparation
> [locator][info 2016/07/26 22:12:05.396 PDT <Geode Membership View Creator>
> tid=0x27] received new view: View[japan(31570:locator)<ec><v0>:1024|562]
> members: [japan(31570:locator)<ec><v0>:1024,
> japan(Manager:31630)<ec><v561>:1025{lead}, japan(31634)<ec><v562>:1026]
> [locator]old view is: View[japan(31570:locator)<ec><v0>:1024|561] members:
> [japan(31570:locator)<ec><v0>:1024,
> japan(Manager:31630)<ec><v561>:1025{lead}] shutdown:
> [japan(Manager:31630)<ec><v559>:1025, japan(31634)<ec><v560>:1026]
> [locator][info 2016/07/26 22:12:05.396 PDT <Geode Membership View Creator>
> tid=0x27] Peer locator received new membership view:
> View[japan(31570:locator)<ec><v0>:1024|562] members:
> [japan(31570:locator)<ec><v0>:1024,
> japan(Manager:31630)<ec><v561>:1025{lead}, japan(31634)<ec><v562>:1026]
> [vm_1][info 2016/07/26 22:12:05.396 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Finished joining (took 303ms).
> [vm_1][info 2016/07/26 22:12:05.396 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Starting DistributionManager japan(31634)<ec><v562>:1026. (took
> 309 ms)
> [locator][info 2016/07/26 22:12:05.397 PDT <Geode Membership View Creator>
> tid=0x27] sending new view View[japan(31570:locator)<ec><v0>:1024|562]
> members: [japan(31570:locator)<ec><v0>:1024,
> japan(Manager:31630)<ec><v561>:1025{lead}, japan(31634)<ec><v562>:1026]
> [locator]failure detection ports: 15292 16628 2123
> [locator][info 2016/07/26 22:12:05.397 PDT <View Message Processor> tid=0x33]
> Membership: Processing addition < japan(31634)<ec><v562>:1026 >
> [locator][info 2016/07/26 22:12:05.397 PDT <View Message Processor> tid=0x33]
> Admitting member <japan(31634)<ec><v562>:1026>. Now there are 3 non-admin
> member(s).
> [vm_1][info 2016/07/26 22:12:05.397 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Initial (distribution manager) view =
> View[japan(31570:locator)<ec><v0>:1024|562] members:
> [japan(31570:locator)<ec><v0>:1024,
> japan(Manager:31630)<ec><v561>:1025{lead}, japan(31634)<ec><v562>:1026]
> [vm_1][info 2016/07/26 22:12:05.397 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Admitting member <japan(31570:locator)<ec><v0>:1024>. Now there
> are 1 non-admin member(s).
> [vm_1][info 2016/07/26 22:12:05.397 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Admitting member <japan(Manager:31630)<ec><v561>:1025>. Now there
> are 2 non-admin member(s).
> [vm_1][info 2016/07/26 22:12:05.397 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Admitting member <japan(31634)<ec><v562>:1026>. Now there are 3
> non-admin member(s).
> [vm_1][info 2016/07/26 22:12:05.401 PDT <P2P message reader for
> japan(Manager:31630)<ec><v561>:1025 shared unordered uid=793 port=56505>
> tid=0x2283] Member japan(Manager:31630)<ec><v561>:1025 is equivalent or in
> the same redundancy zone.
> [locator][info 2016/07/26 22:12:05.401 PDT <Pooled High Priority Message
> Processor 1> tid=0x37] Member japan(31634)<ec><v562>:1026 is equivalent or in
> the same redundancy zone.
> [vm_1][info 2016/07/26 22:12:05.406 PDT <P2P message reader for
> japan(31570:locator)<ec><v0>:1024 shared unordered uid=470 port=56507>
> tid=0x2288] Member japan(31570:locator)<ec><v0>:1024 is equivalent or in the
> same redundancy zone.
> [vm_1][info 2016/07/26 22:12:05.413 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Initializing region _monitoringRegion_10.118.32.37<v562>1026
> [vm_1][info 2016/07/26 22:12:05.415 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Region _monitoringRegion_10.118.32.37<v562>1026 requesting
> initial image from japan(Manager:31630)<ec><v561>:1025
> [vm_1][info 2016/07/26 22:12:05.415 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] _monitoringRegion_10.118.32.37<v562>1026 is done getting image
> from japan(Manager:31630)<ec><v561>:1025. isDeltaGII is false
> [vm_1][info 2016/07/26 22:12:05.415 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Initialization of region _monitoringRegion_10.118.32.37<v562>1026
> completed
> [vm_1][info 2016/07/26 22:12:05.418 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] The cache has been created with
> "use-cluster-configuration=false". It will not receive any cluster
> configuration
> [vm_1][info 2016/07/26 22:12:05.419 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Initializing region PdxTypes
> [vm_1][info 2016/07/26 22:12:05.420 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Region PdxTypes requesting initial image from
> japan(Manager:31630)<ec><v561>:1025
> [vm_1][info 2016/07/26 22:12:05.420 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] PdxTypes is done getting image from
> japan(Manager:31630)<ec><v561>:1025. isDeltaGII is false
> [vm_1][info 2016/07/26 22:12:05.420 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Initialization of region PdxTypes completed
> [vm_1][info 2016/07/26 22:12:05.421 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Initializing region stocks
> [vm_1][info 2016/07/26 22:12:05.421 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Initialization of region stocks completed
> [vm_1][info 2016/07/26 22:12:05.430 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Cache server connection listener bound to address
> 0.0.0.0/0.0.0.0:52346 with backlog 1,000.
> [vm_1][info 2016/07/26 22:12:05.430 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] ClientHealthMonitorThread maximum allowed time between pings:
> 60,000
> [vm_1][info 2016/07/26 22:12:05.431 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] CacheServer Configuration: port=52346 max-connections=800
> max-threads=0 notify-by-subscription=true socket-buffer-size=32768
> maximum-time-between-pings=60000 maximum-message-count=230000
> message-time-to-live=180 eviction-policy=none capacity=1 overflow directory=.
> groups=[] loadProbe=ConnectionCountProbe loadPollInterval=5000 tcpNoDelay=true
> [vm_1][info 2016/07/26 22:12:05.432 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Got result: 52346
> [vm_1] from
> com.gemstone.gemfire.management.internal.cli.commands.DurableClientCommandsDUnitTest$3.call
> with 0 args on object:
> com.gemstone.gemfire.management.internal.cli.commands.DurableClientCommandsDUnitTest$3@6851b415
> (took 347 ms)
> [vm_2][info 2016/07/26 22:12:05.433 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] Received method:
> com.gemstone.gemfire.management.internal.cli.commands.DurableClientCommandsDUnitTest$4.run
> with 0 args on object: "Start client"
> [vm_2]distributed system properties: {locators=, durable-client-timeout=300,
> durable-client-id=dc1, enable-cluster-configuration=false,
> use-cluster-configuration=false, mcast-port=0, disable-auto-reconnect=true,
> log-level=info}
> [vm_2][info 2016/07/26 22:12:05.448 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] Running in client mode
> [vm_2][info 2016/07/26 22:12:05.452 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] Updating membership port. Port changed from 0 to 58,974. ID is
> now japan(31639:loner):0:4156c42a
> [vm_1][info 2016/07/26 22:12:05.461 PDT <Handshaker 0.0.0.0/0.0.0.0:52346
> Thread 0> tid=0x2299] Initializing region
> _gfe_durable_client_with_id_dc1_1_queue
> [vm_1][info 2016/07/26 22:12:05.462 PDT <Handshaker 0.0.0.0/0.0.0.0:52346
> Thread 0> tid=0x2299] Initialization of region
> _gfe_durable_client_with_id_dc1_1_queue completed
> [vm_2][info 2016/07/26 22:12:05.463 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] Pool DEFAULT started with multiuser-authentication=false
> [vm_2][info 2016/07/26 22:12:05.463 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] <ExpectedException
> action=add>java.net.ConnectException</ExpectedException>
> [vm_2][info 2016/07/26 22:12:05.463 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] <ExpectedException
> action=add>java.net.ConnectException</ExpectedException>
> [vm_2][info 2016/07/26 22:12:05.463 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] <ExpectedException
> action=add>java.net.ConnectException</ExpectedException>
> [vm_2][info 2016/07/26 22:12:05.463 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] Got result: null
> [vm_2] from
> com.gemstone.gemfire.management.internal.cli.commands.DurableClientCommandsDUnitTest$4.run
> with 0 args on object: "Start client" (took 29 ms)
> [vm_2][info 2016/07/26 22:12:05.463 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] Received method:
> com.gemstone.gemfire.management.internal.cli.commands.DurableClientCommandsDUnitTest$2.call
> with 0 args on object:
> com.gemstone.gemfire.management.internal.cli.commands.DurableClientCommandsDUnitTest$2@42e22a06
> [vm_2][info 2016/07/26 22:12:05.468 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] Got result: true
> [vm_2] from
> com.gemstone.gemfire.management.internal.cli.commands.DurableClientCommandsDUnitTest$2.call
> with 0 args on object:
> com.gemstone.gemfire.management.internal.cli.commands.DurableClientCommandsDUnitTest$2@42e22a06
> (took 5 ms)
> [vm_2][info 2016/07/26 22:12:05.469 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] Received method:
> com.gemstone.gemfire.management.internal.cli.commands.DurableClientCommandsDUnitTest$6.run
> with 0 args on object: "Stop client"
> [vm_2][info 2016/07/26 22:12:05.469 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] GemFireCache[id = 1920140688; isClosing = true; isShutDownAll =
> false; created = Tue Jul 26 22:12:05 PDT 2016; server = false; copyOnRead =
> false; lockLease = 120; lockTimeout = 60]: Now closing.
> [vm_2][warn 2016/07/26 22:12:05.470 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] Not sending CQ close to the server as it is a durable CQ
> [vm_2][warn 2016/07/26 22:12:05.470 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] Not sending CQ close to the server as it is a durable CQ
> [vm_2][warn 2016/07/26 22:12:05.470 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] Not sending CQ close to the server as it is a durable CQ
> [vm_2][info 2016/07/26 22:12:05.470 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] Destroying connection pool DEFAULT
> [vm_2][info 2016/07/26 22:12:05.472 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] Got result: null
> [vm_2] from
> com.gemstone.gemfire.management.internal.cli.commands.DurableClientCommandsDUnitTest$6.run
> with 0 args on object: "Stop client" (took 2 ms)
> [info 2016/07/26 22:12:05.472 PDT <Test worker> tid=0xa]
> DurableClientCommandsDUnitTest_testCloseDurableCQ: Command String :
> close durable-cq --durable-client-id=dc1 --durable-cq-name=cq1
> [info 2016/07/26 22:12:05.472 PDT <Test worker> tid=0xa] Executing command
> close durable-cq --durable-client-id=dc1 --durable-cq-name=cq1 with command
> Mgr com.gemstone.gemfire.management.internal.cli.CommandManager@6007de98
> [vm_2][warn 2016/07/26 22:12:05.476 PDT <Cache Client Updater Thread on
> japan(31634)<ec><v562>:1026 port 52346> tid=0x18a7] Cache Client Updater
> Thread on japan(31634)<ec><v562>:1026 port 52346 (japan.gemstone.com:52346):
> abandoned wait due to cancellation.
> [vm_2][warn 2016/07/26 22:12:05.476 PDT <Cache Client Updater Thread on
> japan(31634)<ec><v562>:1026 port 52346> tid=0x18a7] Cache Client Updater
> Thread on japan(31634)<ec><v562>:1026 port 52346 (japan.gemstone.com:52346):
> no cache (exiting)
> [vm_1][info 2016/07/26 22:12:05.479 PDT <Function Execution Processor1>
> tid=0x22a0] CacheClientProxy: Could not drain cq cq1 because client proxy id
> dc1 is connected.
> [info 2016/07/26 22:12:05.482 PDT <Test worker> tid=0xa]
> DurableClientCommandsDUnitTest_testCloseDurableCQ: Command Result :
> Could not close the durable-cq : "cq1" for the durable-client-id : "dc1" due
> to following reasons.
> CacheClientProxy: Could not drain cq cq1 because client proxy id dc1 is
> connected.
> Occurred on members
> 1.japan(31634)<ec><v562>:1026
> No client found on this server
> Occurred on members
> 1.Manager
> [info 2016/07/26 22:12:05.482 PDT <Test worker> tid=0xa] Executing command
> exit with command Mgr
> com.gemstone.gemfire.management.internal.cli.CommandManager@6007de98
> [vm_1][info 2016/07/26 22:12:05.483 PDT <ServerConnection on port 52346
> Thread 0> tid=0x229a] CacheClientNotifier: Keeping proxy for durable client
> named dc1 for 300 seconds
> CacheClientProxy[identity(japan(31639:loner):58974:4156c42a,connection=1,durableAttributes=DurableClientAttributes[id=dc1;
> timeout=300]); port=58976; primary=true; version=GFE 9.0].
> [vm_1][info 2016/07/26 22:12:05.506 PDT <Pooled High Priority Message
> Processor 2> tid=0x2291] received leave request from
> japan(Manager:31630)<ec><v561>:1025 for japan(Manager:31630)<ec><v561>:1025
> [vm_1][info 2016/07/26 22:12:05.506 PDT <Pooled High Priority Message
> Processor 2> tid=0x2291] Member at japan(Manager:31630)<ec><v561>:1025
> gracefully left the distributed cache: shutdown message received
> [locator][info 2016/07/26 22:12:05.507 PDT <Pooled High Priority Message
> Processor 1> tid=0x37] received leave request from
> japan(Manager:31630)<ec><v561>:1025 for japan(Manager:31630)<ec><v561>:1025
> [locator][info 2016/07/26 22:12:05.507 PDT <Pooled High Priority Message
> Processor 1> tid=0x37] Member at japan(Manager:31630)<ec><v561>:1025
> gracefully left the distributed cache: shutdown message received
> [locator][info 2016/07/26 22:12:05.608 PDT <unicast receiver,japan-14750>
> tid=0x21] received leave request from japan(Manager:31630)<ec><v561>:1025 for
> japan(Manager:31630)<ec><v561>:1025
> [vm_1][info 2016/07/26 22:12:05.608 PDT <unicast receiver,japan-3344>
> tid=0x2279] received leave request from japan(Manager:31630)<ec><v561>:1025
> for japan(Manager:31630)<ec><v561>:1025
> [vm_1][info 2016/07/26 22:12:05.615 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Received method:
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$43/226580143.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$43/226580143@59c2134
> [vm_1][info 2016/07/26 22:12:05.615 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] GemFireCache[id = 1026293048; isClosing = true; isShutDownAll =
> false; created = Tue Jul 26 22:12:05 PDT 2016; server = false; copyOnRead =
> false; lockLease = 120; lockTimeout = 60]: Now closing.
> [vm_1][info 2016/07/26 22:12:05.617 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Cache server on port 52,346 is shutting down.
> [vm_1][info 2016/07/26 22:12:05.618 PDT <Queue Removal Thread> tid=0x229e]
> The QueueRemovalThread is done.
> [vm_1][info 2016/07/26 22:12:05.622 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Shutting down DistributionManager japan(31634)<ec><v562>:1026.
> [locator][info 2016/07/26 22:12:05.622 PDT <Pooled High Priority Message
> Processor 1> tid=0x37] received leave request from
> japan(31634)<ec><v562>:1026 for japan(31634)<ec><v562>:1026
> [locator][info 2016/07/26 22:12:05.622 PDT <Pooled High Priority Message
> Processor 1> tid=0x37] Member at japan(31634)<ec><v562>:1026 gracefully left
> the distributed cache: shutdown message received
> [vm_1][info 2016/07/26 22:12:05.723 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Now closing distribution for japan(31634)<ec><v562>:1026
> [vm_1][info 2016/07/26 22:12:05.723 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Stopping membership services
> [locator][info 2016/07/26 22:12:05.723 PDT <unicast receiver,japan-14750>
> tid=0x21] received leave request from japan(31634)<ec><v562>:1026 for
> japan(31634)<ec><v562>:1026
> [vm_1][info 2016/07/26 22:12:05.723 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] GMSHealthMonitor server socket is closed in stopServices().
> [vm_1][info 2016/07/26 22:12:05.723 PDT <Geode Failure Detection Server
> thread 0> tid=0x227d] GMSHealthMonitor server thread exiting
> [vm_1][info 2016/07/26 22:12:05.723 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] GMSHealthMonitor serverSocketExecutor is terminated
> [vm_1][info 2016/07/26 22:12:05.725 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] DistributionManager stopped in 103ms.
> [vm_1][info 2016/07/26 22:12:05.725 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Marking DistributionManager japan(31634)<ec><v562>:1026 as closed.
> [vm_1][info 2016/07/26 22:12:05.726 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Got result: null
> [vm_1] from
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$43/226580143.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$43/226580143@59c2134
> (took 110 ms)
> [vm_2][info 2016/07/26 22:12:05.726 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] Received method:
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$61/90828831.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$61/90828831@79cbde16
> [vm_2][info 2016/07/26 22:12:05.726 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] Got result: null
> [vm_2] from
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$61/90828831.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$61/90828831@79cbde16
> (took 0 ms)
> [vm_3][info 2016/07/26 22:12:05.726 PDT <RMI TCP Connection(49)-10.118.32.37>
> tid=0x12] Received method:
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$22/1440013754.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$22/1440013754@25433c1e
> [vm_3][info 2016/07/26 22:12:05.727 PDT <RMI TCP Connection(49)-10.118.32.37>
> tid=0x12] Got result: null
> [vm_3] from
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$22/1440013754.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$22/1440013754@25433c1e
> (took 0 ms)
> [vm_1][info 2016/07/26 22:12:05.728 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Received method:
> com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$34/1151432988.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$34/1151432988@4f3252cb
> [vm_1][info 2016/07/26 22:12:05.729 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Got result: null
> [vm_1] from
> com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$34/1151432988.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$34/1151432988@4f3252cb
> (took 0 ms)
> [vm_2][info 2016/07/26 22:12:05.729 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] Received method:
> com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$51/1017652523.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$51/1017652523@20ba592b
> [vm_2][info 2016/07/26 22:12:05.729 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] Got result: null
> [vm_2] from
> com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$51/1017652523.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$51/1017652523@20ba592b
> (took 0 ms)
> [vm_3][info 2016/07/26 22:12:05.729 PDT <RMI TCP Connection(49)-10.118.32.37>
> tid=0x12] Received method:
> com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$14/327108742.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$14/327108742@a22da2d
> [vm_3][info 2016/07/26 22:12:05.730 PDT <RMI TCP Connection(49)-10.118.32.37>
> tid=0x12] Got result: null
> [vm_3] from
> com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$14/327108742.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$14/327108742@a22da2d
> (took 0 ms)
> [vm_1][info 2016/07/26 22:12:05.730 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Received method:
> com.gemstone.gemfire.management.internal.cli.commands.DurableClientCommandsDUnitTest$$Lambda$137/381285965.run
> with 0 args on object:
> com.gemstone.gemfire.management.internal.cli.commands.DurableClientCommandsDUnitTest$$Lambda$137/381285965@23ba5ada
> [vm_1][info 2016/07/26 22:12:05.730 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Got result: null
> [vm_1] from
> com.gemstone.gemfire.management.internal.cli.commands.DurableClientCommandsDUnitTest$$Lambda$137/381285965.run
> with 0 args on object:
> com.gemstone.gemfire.management.internal.cli.commands.DurableClientCommandsDUnitTest$$Lambda$137/381285965@23ba5ada
> (took 0 ms)
> [vm_2][info 2016/07/26 22:12:05.731 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] Received method:
> com.gemstone.gemfire.management.internal.cli.commands.DurableClientCommandsDUnitTest$$Lambda$226/867285789.run
> with 0 args on object:
> com.gemstone.gemfire.management.internal.cli.commands.DurableClientCommandsDUnitTest$$Lambda$226/867285789@631695c9
> [vm_2][info 2016/07/26 22:12:05.731 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] Got result: null
> [vm_2] from
> com.gemstone.gemfire.management.internal.cli.commands.DurableClientCommandsDUnitTest$$Lambda$226/867285789.run
> with 0 args on object:
> com.gemstone.gemfire.management.internal.cli.commands.DurableClientCommandsDUnitTest$$Lambda$226/867285789@631695c9
> (took 0 ms)
> [vm_1][info 2016/07/26 22:12:05.731 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Received method:
> com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object:
> runnable(tearDownCreationStackGenerator)
> [vm_1][info 2016/07/26 22:12:05.731 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] 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/26 22:12:05.731 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] Received method:
> com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object:
> runnable(tearDownCreationStackGenerator)
> [vm_2][info 2016/07/26 22:12:05.732 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] 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/26 22:12:05.732 PDT <RMI TCP Connection(49)-10.118.32.37>
> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run
> with 0 args on object: runnable(tearDownCreationStackGenerator)
> [vm_3][info 2016/07/26 22:12:05.732 PDT <RMI TCP Connection(49)-10.118.32.37>
> tid=0x12] Got result: null
> [vm_3] from com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on
> object: runnable(tearDownCreationStackGenerator) (took 0 ms)
> [vm_1][info 2016/07/26 22:12:05.733 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Received method:
> com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object:
> runnable(tearDownVM)
> [vm_1][info 2016/07/26 22:12:05.733 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Got result: null
> [vm_1] from com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on
> object: runnable(tearDownVM) (took 0 ms)
> [vm_2][info 2016/07/26 22:12:05.733 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] Received method:
> com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object:
> runnable(tearDownVM)
> [vm_2][info 2016/07/26 22:12:05.733 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] <ExpectedException
> action=remove>java.net.ConnectException</ExpectedException>
> [vm_2][info 2016/07/26 22:12:05.733 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] <ExpectedException
> action=remove>java.net.ConnectException</ExpectedException>
> [vm_2][info 2016/07/26 22:12:05.733 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] Got result: null
> [vm_2] from com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on
> object: runnable(tearDownVM) (took 0 ms)
> [vm_3][info 2016/07/26 22:12:05.734 PDT <RMI TCP Connection(49)-10.118.32.37>
> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run
> with 0 args on object: runnable(tearDownVM)
> [vm_3][info 2016/07/26 22:12:05.734 PDT <RMI TCP Connection(49)-10.118.32.37>
> tid=0x12] Got result: null
> [vm_3] from com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on
> object: runnable(tearDownVM) (took 0 ms)
> [locator][info 2016/07/26 22:12:05.734 PDT <RMI TCP
> Connection(21)-10.118.32.37> tid=0x9c4] Received method:
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/2122565141.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/2122565141@7dc872b1
> [locator][info 2016/07/26 22:12:05.734 PDT <RMI TCP
> Connection(21)-10.118.32.37> tid=0x9c4] Got result: null
> [locator] from
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/2122565141.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/2122565141@7dc872b1
> (took 0 ms)
> [vm_1][info 2016/07/26 22:12:05.736 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Received method:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$2.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$2@1901f14e
> [vm_1][info 2016/07/26 22:12:05.736 PDT <RMI TCP Connection(71)-10.118.32.37>
> tid=0x1e10] Got result: null
> [vm_1] from
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$2.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$2@1901f14e
> (took 0 ms)
> [vm_2][info 2016/07/26 22:12:05.736 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] Received method:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$2.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$2@c8ee743
> [vm_2][info 2016/07/26 22:12:05.736 PDT <RMI TCP Connection(60)-10.118.32.37>
> tid=0x139c] Got result: null
> [vm_2] from
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$2.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$2@c8ee743
> (took 0 ms)
> [vm_3][info 2016/07/26 22:12:05.737 PDT <RMI TCP Connection(49)-10.118.32.37>
> tid=0x12] Received method:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$2.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$2@7fb854ac
> [vm_3][info 2016/07/26 22:12:05.737 PDT <RMI TCP Connection(49)-10.118.32.37>
> tid=0x12] Got result: null
> [vm_3] from
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$2.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$2@7fb854ac
> (took 0 ms)
> [locator][info 2016/07/26 22:12:05.737 PDT <RMI TCP
> Connection(21)-10.118.32.37> tid=0x9c4] Received method:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$2.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$2@2c158a65
> [locator][info 2016/07/26 22:12:05.737 PDT <RMI TCP
> Connection(21)-10.118.32.37> tid=0x9c4] Got result: null
> [locator] from
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$2.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$2@2c158a65
> (took 0 ms)
> {noformat}
--
This message was sent by Atlassian JIRA
(v6.4.14#64029)
