geode-issues mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From "Anthony Baker (JIRA)" <j...@apache.org>
Subject [jira] [Closed] (GEODE-1562) CI Failure: PreloadedRegionTestCase.testNonblockingGetInitialImage assertion failure
Date Mon, 22 Aug 2016 16:58:27 GMT

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

Anthony Baker closed GEODE-1562.
--------------------------------

> CI Failure:  PreloadedRegionTestCase.testNonblockingGetInitialImage assertion failure
> -------------------------------------------------------------------------------------
>
>                 Key: GEODE-1562
>                 URL: https://issues.apache.org/jira/browse/GEODE-1562
>             Project: Geode
>          Issue Type: Bug
>          Components: core
>            Reporter: Scott Jewell
>             Fix For: 1.0.0-incubating.M3
>
>
> NOTE:  PreloadedRegionTestCase.testTXNonblockingGetInitialImage also failed with the same error in build #2943
> Error Message
> com.gemstone.gemfire.test.dunit.RMIException: While invoking com.gemstone.gemfire.cache30.MultiVMRegionTestCase$196.runRepeatingIfNecessary in VM 2 running on Host cc2-rh6.gemstone.com with 4 VMs
> Stacktrace
> com.gemstone.gemfire.test.dunit.RMIException: While invoking com.gemstone.gemfire.cache30.MultiVMRegionTestCase$196.runRepeatingIfNecessary in VM 2 running on Host cc2-rh6.gemstone.com with 4 VMs
> 	at com.gemstone.gemfire.test.dunit.VM.invoke(VM.java:389)
> 	at com.gemstone.gemfire.test.dunit.VM.invokeRepeatingIfNecessary(VM.java:334)
> 	at com.gemstone.gemfire.cache30.MultiVMRegionTestCase.testNonblockingGetInitialImage(MultiVMRegionTestCase.java:4514)
> 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
> 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
> 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
> 	at java.lang.reflect.Method.invoke(Method.java:497)
> 	at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:50)
> 	at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
> 	at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:47)
> 	at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
> 	at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:26)
> 	at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:27)
> 	at org.junit.rules.TestWatcher$1.evaluate(TestWatcher.java:55)
> 	at org.junit.rules.RunRules.evaluate(RunRules.java:20)
> 	at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:325)
> 	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:78)
> 	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:57)
> 	at org.junit.runners.ParentRunner$3.run(ParentRunner.java:290)
> 	at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:71)
> 	at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:288)
> 	at org.junit.runners.ParentRunner.access$000(ParentRunner.java:58)
> 	at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:268)
> 	at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:26)
> 	at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:27)
> 	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.GeneratedMethodAccessor509.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.GeneratedMethodAccessor508.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)
> Caused by: java.lang.AssertionError: expected:<1034> but was:<1200>
> 	at org.junit.Assert.fail(Assert.java:88)
> 	at org.junit.Assert.failNotEquals(Assert.java:834)
> 	at org.junit.Assert.assertEquals(Assert.java:645)
> 	at org.junit.Assert.assertEquals(Assert.java:631)
> 	at com.gemstone.gemfire.cache30.MultiVMRegionTestCase$196.run2(MultiVMRegionTestCase.java:4528)
> 	at com.gemstone.gemfire.cache30.CacheSerializableRunnable.run(CacheSerializableRunnable.java:60)
> 	at com.gemstone.gemfire.cache30.CacheSerializableRunnable.runRepeatingIfNecessary(CacheSerializableRunnable.java:83)
> 	at sun.reflect.GeneratedMethodAccessor367.invoke(Unknown Source)
> 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
> 	at java.lang.reflect.Method.invoke(Method.java:497)
> 	at hydra.MethExecutor.executeObject(MethExecutor.java:268)
> 	at com.gemstone.gemfire.test.dunit.standalone.RemoteDUnitVM.executeMethodOnObject(RemoteDUnitVM.java:82)
> 	at sun.reflect.GeneratedMethodAccessor3.invoke(Unknown Source)
> 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
> 	at java.lang.reflect.Method.invoke(Method.java:497)
> 	at sun.rmi.server.UnicastServerRef.dispatch(UnicastServerRef.java:323)
> 	at sun.rmi.transport.Transport$1.run(Transport.java:200)
> 	at sun.rmi.transport.Transport$1.run(Transport.java:197)
> 	at java.security.AccessController.doPrivileged(Native Method)
> 	at sun.rmi.transport.Transport.serviceCall(Transport.java:196)
> 	at sun.rmi.transport.tcp.TCPTransport.handleMessages(TCPTransport.java:568)
> 	at sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.run0(TCPTransport.java:826)
> 	at sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.lambda$run$95(TCPTransport.java:683)
> 	at java.security.AccessController.doPrivileged(Native Method)
> 	at sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.run(TCPTransport.java:682)
> 	... 3 more
> Standard Output
> Previously run tests: [DistributedAckPersistentRegionCCEDUnitTest, RemoveAllMultiVmDUnitTest, ClearMultiVmCallBkDUnitTest, CacheXml80DUnitTest, DiskRegionDUnitTest, RRSynchronizationDUnitTest, ConcurrentLeaveDuringGIIDUnitTest, DistributedAckOverflowRegionCCEDUnitTest, CacheRegionsReliablityStatsCheckDUnitTest, PreloadedRegionTestCase]
> [vm_0][info 2016/06/16 18:20:25.386 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(setupVM)
> [vm_0][info 2016/06/16 18:20:25.386 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Got result: null
> [vm_0] from com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(setupVM) (took 0 ms)
> [vm_1][info 2016/06/16 18:20:25.387 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(setupVM)
> [vm_1][info 2016/06/16 18:20:25.387 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] 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/06/16 18:20:25.387 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(setupVM)
> [vm_2][info 2016/06/16 18:20:25.387 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] 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/06/16 18:20:25.388 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(setupVM)
> [vm_3][info 2016/06/16 18:20:25.388 PDT <RMI TCP Connection(1)-10.118.33.92> 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 PreloadedRegionTestCase.testDistributedCreate
> [vm_0][info 2016/06/16 18:20:25.392 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.cache30.PreloadedRegionTestCase$1.run with 0 args on object: "testDistributedCreate: Create Region"
> [vm_0]distributed system properties: {locators=localhost[23244], enable-cluster-configuration=false, use-cluster-configuration=false, mcast-port=0, disable-auto-reconnect=true, log-level=info}
> [vm_0][info 2016/06/16 18:20:25.395 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Startup Configuration:
> [vm_0] ### GemFire Properties defined with api ###
> [vm_0]disable-auto-reconnect=true
> [vm_0]enable-cluster-configuration=false
> [vm_0]locators=localhost[23244]
> [vm_0]log-level=info
> [vm_0]mcast-port=0
> [vm_0]use-cluster-configuration=false
> [vm_0]### GemFire Properties using default values ###
> [vm_0]ack-severe-alert-threshold=0
> [vm_0]ack-wait-threshold=15
> [vm_0]archive-disk-space-limit=0
> [vm_0]archive-file-size-limit=0
> [vm_0]async-distribution-timeout=0
> [vm_0]async-max-queue-size=8
> [vm_0]async-queue-timeout=60000
> [vm_0]bind-address=
> [vm_0]cache-xml-file=cache.xml
> [vm_0]cluster-configuration-dir=/cc2-rh6a/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/geode-core/build/distributedTest/dunit/vm0
> [vm_0]cluster-ssl-ciphers=any
> [vm_0]cluster-ssl-enabled=false
> [vm_0]cluster-ssl-keystore=
> [vm_0]cluster-ssl-keystore-password=
> [vm_0]cluster-ssl-keystore-type=
> [vm_0]cluster-ssl-protocols=any
> [vm_0]cluster-ssl-require-authentication=true
> [vm_0]cluster-ssl-truststore=
> [vm_0]cluster-ssl-truststore-password=
> [vm_0]conflate-events=server
> [vm_0]conserve-sockets=true
> [vm_0]delta-propagation=true
> [vm_0]deploy-working-dir=.
> [vm_0]disable-tcp=false
> [vm_0]distributed-system-id=-1
> [vm_0]distributed-transactions=false
> [vm_0]durable-client-id=
> [vm_0]durable-client-timeout=300
> [vm_0]enable-network-partition-detection=false
> [vm_0]enable-time-statistics=false
> [vm_0]enforce-unique-host=false
> [vm_0]gateway-ssl-ciphers=any
> [vm_0]gateway-ssl-enabled=false
> [vm_0]gateway-ssl-keystore=
> [vm_0]gateway-ssl-keystore-password=
> [vm_0]gateway-ssl-keystore-type=
> [vm_0]gateway-ssl-protocols=any
> [vm_0]gateway-ssl-require-authentication=true
> [vm_0]gateway-ssl-truststore=
> [vm_0]gateway-ssl-truststore-password=
> [vm_0]groups=
> [vm_0]http-service-bind-address=
> [vm_0]http-service-port=7070
> [vm_0]http-service-ssl-ciphers=any
> [vm_0]http-service-ssl-enabled=false
> [vm_0]http-service-ssl-keystore=
> [vm_0]http-service-ssl-keystore-password=
> [vm_0]http-service-ssl-keystore-type=
> [vm_0]http-service-ssl-protocols=any
> [vm_0]http-service-ssl-require-authentication=false
> [vm_0]http-service-ssl-truststore=
> [vm_0]http-service-ssl-truststore-password=
> [vm_0]jmx-manager=false
> [vm_0]jmx-manager-access-file=
> [vm_0]jmx-manager-bind-address=
> [vm_0]jmx-manager-hostname-for-clients=
> [vm_0]jmx-manager-http-port=7070
> [vm_0]jmx-manager-password-file=
> [vm_0]jmx-manager-port=1099
> [vm_0]jmx-manager-ssl=false
> [vm_0]jmx-manager-ssl-ciphers=any
> [vm_0]jmx-manager-ssl-enabled=false
> [vm_0]jmx-manager-ssl-keystore=
> [vm_0]jmx-manager-ssl-keystore-password=
> [vm_0]jmx-manager-ssl-keystore-type=
> [vm_0]jmx-manager-ssl-protocols=any
> [vm_0]jmx-manager-ssl-require-authentication=true
> [vm_0]jmx-manager-ssl-truststore=
> [vm_0]jmx-manager-ssl-truststore-password=
> [vm_0]jmx-manager-start=false
> [vm_0]jmx-manager-update-rate=2000
> [vm_0]load-cluster-configuration-from-dir=false
> [vm_0]locator-wait-time=0
> [vm_0]lock-memory=false
> [vm_0]log-disk-space-limit=0
> [vm_0]log-file=
> [vm_0]log-file-size-limit=0
> [vm_0]max-num-reconnect-tries=3
> [vm_0]max-wait-time-reconnect=60000
> [vm_0]mcast-address=239.192.81.1
> [vm_0]mcast-flow-control=1048576, 0.25, 5000
> [vm_0]mcast-recv-buffer-size=1048576
> [vm_0]mcast-send-buffer-size=65535
> [vm_0]mcast-ttl=32
> [vm_0]member-timeout=5000
> [vm_0]membership-port-range=1024-65535
> [vm_0]memcached-bind-address=
> [vm_0]memcached-port=0
> [vm_0]memcached-protocol=ASCII
> [vm_0]name=
> [vm_0]off-heap-memory-size=
> [vm_0]redis-bind-address=
> [vm_0]redis-password=
> [vm_0]redis-port=0
> [vm_0]redundancy-zone=
> [vm_0]remote-locators=
> [vm_0]remove-unresponsive-client=false
> [vm_0]roles=
> [vm_0]security-client-accessor=
> [vm_0]security-client-accessor-pp=
> [vm_0]security-client-auth-init=
> [vm_0]security-client-authenticator=
> [vm_0]security-client-dhalgo=
> [vm_0]security-log-file=
> [vm_0]security-log-level=config
> [vm_0]security-peer-auth-init=
> [vm_0]security-peer-authenticator=
> [vm_0]security-peer-verifymember-timeout=1000
> [vm_0]server-bind-address=
> [vm_0]server-ssl-ciphers=any
> [vm_0]server-ssl-enabled=false
> [vm_0]server-ssl-keystore=
> [vm_0]server-ssl-keystore-password=
> [vm_0]server-ssl-keystore-type=
> [vm_0]server-ssl-protocols=any
> [vm_0]server-ssl-require-authentication=true
> [vm_0]server-ssl-truststore=
> [vm_0]server-ssl-truststore-password=
> [vm_0]socket-buffer-size=32768
> [vm_0]socket-lease-time=60000
> [vm_0]ssl-ciphers=any
> [vm_0]ssl-enabled=false
> [vm_0]ssl-protocols=any
> [vm_0]ssl-require-authentication=true
> [vm_0]start-dev-rest-api=false
> [vm_0]start-locator=
> [vm_0]statistic-archive-file=
> [vm_0]statistic-sample-rate=1000
> [vm_0]statistic-sampling-enabled=true
> [vm_0]tcp-port=0
> [vm_0]udp-fragment-size=60000
> [vm_0]udp-recv-buffer-size=1048576
> [vm_0]udp-send-buffer-size=65535
> [vm_0]user-command-packages=
> [vm_0][info 2016/06/16 18:20:25.396 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Starting membership services
> [vm_0][info 2016/06/16 18:20:25.403 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] JGroups channel created (took 6ms)
> [vm_0][info 2016/06/16 18:20:25.404 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] GemFire P2P Listener started on  null
> [vm_0][info 2016/06/16 18:20:25.405 PDT <Geode Failure Detection Server thread 0> tid=0x193f] Started failure detection server thread on cc2-rh6.gemstone.com/10.118.33.92:61727.
> [vm_0][info 2016/06/16 18:20:25.408 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Attempting to join the distributed system through coordinator cc2-rh6(14622:locator)<ec><v0>:1024 using address cc2-rh6(14627)<ec>:1025
> [locator][info 2016/06/16 18:20:25.409 PDT <unicast receiver,cc2-rh6-59330> tid=0x22] received join request from cc2-rh6(14627)<ec>:1025
> [locator][info 2016/06/16 18:20:25.509 PDT <Geode Membership View Creator> tid=0x28] View Creator is processing 5 requests for the next membership view
> [locator][info 2016/06/16 18:20:25.509 PDT <Geode Membership View Creator> tid=0x28] preparing new view View[cc2-rh6(14622:locator)<ec><v0>:1024|441] members: [cc2-rh6(14622:locator)<ec><v0>:1024, cc2-rh6(14627)<ec><v441>:1025{lead}]  shutdown: [cc2-rh6(14632)<ec><v440>:1026, cc2-rh6(14590)<ec><v439>:1025]
> [locator]failure detection ports: 7248 61727
> [vm_0][info 2016/06/16 18:20:25.510 PDT <unicast receiver,cc2-rh6-34505> tid=0x193b] received new view: View[cc2-rh6(14622:locator)<ec><v0>:1024|441] members: [cc2-rh6(14622:locator)<ec><v0>:1024, cc2-rh6(14627)<ec><v441>:1025{lead}]  shutdown: [cc2-rh6(14632)<ec><v440>:1026, cc2-rh6(14590)<ec><v439>:1025]
> [vm_0]old view is: null
> [vm_0][info 2016/06/16 18:20:25.510 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Finished joining (took 103ms).
> [vm_0][info 2016/06/16 18:20:25.510 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Starting DistributionManager cc2-rh6(14627)<ec><v441>:1025.  (took 114 ms)
> [locator][info 2016/06/16 18:20:25.510 PDT <Geode Membership View Creator> tid=0x28] finished waiting for responses to view preparation
> [locator][info 2016/06/16 18:20:25.510 PDT <Geode Membership View Creator> tid=0x28] received new view: View[cc2-rh6(14622:locator)<ec><v0>:1024|441] members: [cc2-rh6(14622:locator)<ec><v0>:1024, cc2-rh6(14627)<ec><v441>:1025{lead}]  shutdown: [cc2-rh6(14632)<ec><v440>:1026, cc2-rh6(14590)<ec><v439>:1025]
> [locator]old view is: View[cc2-rh6(14622:locator)<ec><v0>:1024|440] members: [cc2-rh6(14622:locator)<ec><v0>:1024, cc2-rh6(14590)<ec><v439>:1025{lead}, cc2-rh6(14632)<ec><v440>:1026]
> [locator][info 2016/06/16 18:20:25.510 PDT <Geode Membership View Creator> tid=0x28] Peer locator received new membership view: View[cc2-rh6(14622:locator)<ec><v0>:1024|441] members: [cc2-rh6(14622:locator)<ec><v0>:1024, cc2-rh6(14627)<ec><v441>:1025{lead}]  shutdown: [cc2-rh6(14632)<ec><v440>:1026, cc2-rh6(14590)<ec><v439>:1025]
> [locator][info 2016/06/16 18:20:25.510 PDT <Geode Membership View Creator> tid=0x28] sending new view View[cc2-rh6(14622:locator)<ec><v0>:1024|441] members: [cc2-rh6(14622:locator)<ec><v0>:1024, cc2-rh6(14627)<ec><v441>:1025{lead}]  shutdown: [cc2-rh6(14632)<ec><v440>:1026, cc2-rh6(14590)<ec><v439>:1025]
> [locator]failure detection ports: 7248 61727
> [locator][info 2016/06/16 18:20:25.511 PDT <View Message Processor> tid=0x33] Membership: Processing addition < cc2-rh6(14627)<ec><v441>:1025 >
> [locator][info 2016/06/16 18:20:25.511 PDT <View Message Processor> tid=0x33] Admitting member <cc2-rh6(14627)<ec><v441>:1025>. Now there are 2 non-admin member(s).
> [vm_0][info 2016/06/16 18:20:25.510 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Initial (distribution manager) view =  View[cc2-rh6(14622:locator)<ec><v0>:1024|441] members: [cc2-rh6(14622:locator)<ec><v0>:1024, cc2-rh6(14627)<ec><v441>:1025{lead}]  shutdown: [cc2-rh6(14632)<ec><v440>:1026, cc2-rh6(14590)<ec><v439>:1025]
> [vm_0][info 2016/06/16 18:20:25.510 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Admitting member <cc2-rh6(14622:locator)<ec><v0>:1024>. Now there are 1 non-admin member(s).
> [vm_0][info 2016/06/16 18:20:25.511 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Admitting member <cc2-rh6(14627)<ec><v441>:1025>. Now there are 2 non-admin member(s).
> [locator][info 2016/06/16 18:20:25.512 PDT <Pooled High Priority Message Processor 1> tid=0x37] Member cc2-rh6(14627)<ec><v441>:1025 is equivalent or in the same redundancy zone.
> [vm_0][info 2016/06/16 18:20:25.513 PDT <P2P message reader for cc2-rh6(14622:locator)<ec><v0>:1024 shared unordered uid=404 port=39724> tid=0x1945] Member cc2-rh6(14622:locator)<ec><v0>:1024 is equivalent or in the same redundancy zone.
> [vm_0][info 2016/06/16 18:20:25.519 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Initializing region _monitoringRegion_10.118.33.92<v441>1025
> [vm_0][info 2016/06/16 18:20:25.520 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Initialization of region _monitoringRegion_10.118.33.92<v441>1025 completed
> [vm_0][info 2016/06/16 18:20:25.522 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] The cache has been created with "use-cluster-configuration=false". It will not receive any cluster configuration
> [vm_0][info 2016/06/16 18:20:25.523 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Initializing region PdxTypes
> [vm_0][info 2016/06/16 18:20:25.523 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Initialization of region PdxTypes completed
> [vm_0][info 2016/06/16 18:20:25.524 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Initializing region root
> [vm_0][info 2016/06/16 18:20:25.524 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Initialization of region root completed
> [vm_0][info 2016/06/16 18:20:25.525 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Initializing region PreloadedRegionTestCase_testDistributedCreate
> [vm_0][info 2016/06/16 18:20:25.525 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Initialization of region PreloadedRegionTestCase_testDistributedCreate completed
> [vm_0][info 2016/06/16 18:20:25.526 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] testDistributedCreate: Created Region
> [vm_0][info 2016/06/16 18:20:25.526 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Got result: null
> [vm_0] from com.gemstone.gemfire.cache30.PreloadedRegionTestCase$1.run with 0 args on object: "testDistributedCreate: Create Region" (took 133 ms)
> distributed system properties: {locators=localhost[23244], enable-cluster-configuration=false, use-cluster-configuration=false, mcast-port=0, disable-auto-reconnect=true, log-level=info}
> [info 2016/06/16 18:20:25.528 PDT <Test worker> tid=0xa] Startup Configuration:
>  ### GemFire Properties defined with api ###
> disable-auto-reconnect=true
> enable-cluster-configuration=false
> locators=localhost[23244]
> log-level=info
> mcast-port=0
> use-cluster-configuration=false
> ### GemFire Properties using default values ###
> ack-severe-alert-threshold=0
> ack-wait-threshold=15
> archive-disk-space-limit=0
> archive-file-size-limit=0
> async-distribution-timeout=0
> async-max-queue-size=8
> async-queue-timeout=60000
> bind-address=
> cache-xml-file=cache.xml
> cluster-configuration-dir=/cc2-rh6a/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/geode-core/build/distributedTest
> cluster-ssl-ciphers=any
> cluster-ssl-enabled=false
> cluster-ssl-keystore=
> cluster-ssl-keystore-password=
> cluster-ssl-keystore-type=
> cluster-ssl-protocols=any
> cluster-ssl-require-authentication=true
> cluster-ssl-truststore=
> cluster-ssl-truststore-password=
> conflate-events=server
> conserve-sockets=true
> delta-propagation=true
> deploy-working-dir=.
> disable-tcp=false
> distributed-system-id=-1
> distributed-transactions=false
> durable-client-id=
> durable-client-timeout=300
> enable-network-partition-detection=false
> enable-time-statistics=false
> enforce-unique-host=false
> gateway-ssl-ciphers=any
> gateway-ssl-enabled=false
> gateway-ssl-keystore=
> gateway-ssl-keystore-password=
> gateway-ssl-keystore-type=
> gateway-ssl-protocols=any
> gateway-ssl-require-authentication=true
> gateway-ssl-truststore=
> gateway-ssl-truststore-password=
> groups=
> http-service-bind-address=
> http-service-port=7070
> http-service-ssl-ciphers=any
> http-service-ssl-enabled=false
> http-service-ssl-keystore=
> http-service-ssl-keystore-password=
> http-service-ssl-keystore-type=
> http-service-ssl-protocols=any
> http-service-ssl-require-authentication=false
> http-service-ssl-truststore=
> http-service-ssl-truststore-password=
> jmx-manager=false
> jmx-manager-access-file=
> jmx-manager-bind-address=
> jmx-manager-hostname-for-clients=
> jmx-manager-http-port=7070
> jmx-manager-password-file=
> jmx-manager-port=1099
> jmx-manager-ssl=false
> jmx-manager-ssl-ciphers=any
> jmx-manager-ssl-enabled=false
> jmx-manager-ssl-keystore=
> jmx-manager-ssl-keystore-password=
> jmx-manager-ssl-keystore-type=
> jmx-manager-ssl-protocols=any
> jmx-manager-ssl-require-authentication=true
> jmx-manager-ssl-truststore=
> jmx-manager-ssl-truststore-password=
> jmx-manager-start=false
> jmx-manager-update-rate=2000
> load-cluster-configuration-from-dir=false
> locator-wait-time=0
> lock-memory=false
> log-disk-space-limit=0
> log-file=
> log-file-size-limit=0
> max-num-reconnect-tries=3
> max-wait-time-reconnect=60000
> mcast-address=239.192.81.1
> mcast-flow-control=1048576, 0.25, 5000
> mcast-recv-buffer-size=1048576
> mcast-send-buffer-size=65535
> mcast-ttl=32
> member-timeout=5000
> membership-port-range=1024-65535
> memcached-bind-address=
> memcached-port=0
> memcached-protocol=ASCII
> name=
> off-heap-memory-size=
> redis-bind-address=
> redis-password=
> redis-port=0
> redundancy-zone=
> remote-locators=
> remove-unresponsive-client=false
> roles=
> security-client-accessor=
> security-client-accessor-pp=
> security-client-auth-init=
> security-client-authenticator=
> security-client-dhalgo=
> security-log-file=
> security-log-level=config
> security-peer-auth-init=
> security-peer-authenticator=
> security-peer-verifymember-timeout=1000
> server-bind-address=
> server-ssl-ciphers=any
> server-ssl-enabled=false
> server-ssl-keystore=
> server-ssl-keystore-password=
> server-ssl-keystore-type=
> server-ssl-protocols=any
> server-ssl-require-authentication=true
> server-ssl-truststore=
> server-ssl-truststore-password=
> socket-buffer-size=32768
> socket-lease-time=60000
> ssl-ciphers=any
> ssl-enabled=false
> ssl-protocols=any
> ssl-require-authentication=true
> start-dev-rest-api=false
> start-locator=
> statistic-archive-file=
> statistic-sample-rate=1000
> statistic-sampling-enabled=true
> tcp-port=0
> udp-fragment-size=60000
> udp-recv-buffer-size=1048576
> udp-send-buffer-size=65535
> user-command-packages=
> [info 2016/06/16 18:20:25.530 PDT <Test worker> tid=0xa] Starting membership services
> [info 2016/06/16 18:20:25.535 PDT <Test worker> tid=0xa] JGroups channel created (took 5ms)
> [info 2016/06/16 18:20:25.536 PDT <Test worker> tid=0xa] GemFire P2P Listener started on  null
> [info 2016/06/16 18:20:25.536 PDT <Geode Failure Detection Server thread 0> tid=0x211e] Started failure detection server thread on cc2-rh6.gemstone.com/10.118.33.92:13431.
> [info 2016/06/16 18:20:25.538 PDT <Test worker> tid=0xa] Attempting to join the distributed system through coordinator cc2-rh6(14622:locator)<ec><v0>:1024 using address cc2-rh6(14590)<ec>:1026
> [locator][info 2016/06/16 18:20:25.538 PDT <unicast receiver,cc2-rh6-59330> tid=0x22] received join request from cc2-rh6(14590)<ec>:1026
> [locator][info 2016/06/16 18:20:25.839 PDT <Geode Membership View Creator> tid=0x28] View Creator is processing 1 requests for the next membership view
> [locator][info 2016/06/16 18:20:25.839 PDT <Geode Membership View Creator> tid=0x28] preparing new view View[cc2-rh6(14622:locator)<ec><v0>:1024|442] members: [cc2-rh6(14622:locator)<ec><v0>:1024, cc2-rh6(14627)<ec><v441>:1025{lead}, cc2-rh6(14590)<ec><v442>:1026]
> [locator]failure detection ports: 7248 61727 13431
> [info 2016/06/16 18:20:25.839 PDT <unicast receiver,cc2-rh6-42089> tid=0x211a] received new view: View[cc2-rh6(14622:locator)<ec><v0>:1024|442] members: [cc2-rh6(14622:locator)<ec><v0>:1024, cc2-rh6(14627)<ec><v441>:1025{lead}, cc2-rh6(14590)<ec><v442>:1026]
> old view is: null
> [locator][info 2016/06/16 18:20:25.839 PDT <Geode Membership View Creator> tid=0x28] finished waiting for responses to view preparation
> [locator][info 2016/06/16 18:20:25.840 PDT <Geode Membership View Creator> tid=0x28] received new view: View[cc2-rh6(14622:locator)<ec><v0>:1024|442] members: [cc2-rh6(14622:locator)<ec><v0>:1024, cc2-rh6(14627)<ec><v441>:1025{lead}, cc2-rh6(14590)<ec><v442>:1026]
> [locator]old view is: View[cc2-rh6(14622:locator)<ec><v0>:1024|441] members: [cc2-rh6(14622:locator)<ec><v0>:1024, cc2-rh6(14627)<ec><v441>:1025{lead}]  shutdown: [cc2-rh6(14632)<ec><v440>:1026, cc2-rh6(14590)<ec><v439>:1025]
> [locator][info 2016/06/16 18:20:25.840 PDT <Geode Membership View Creator> tid=0x28] Peer locator received new membership view: View[cc2-rh6(14622:locator)<ec><v0>:1024|442] members: [cc2-rh6(14622:locator)<ec><v0>:1024, cc2-rh6(14627)<ec><v441>:1025{lead}, cc2-rh6(14590)<ec><v442>:1026]
> [info 2016/06/16 18:20:25.840 PDT <Test worker> tid=0xa] Finished joining (took 304ms).
> [info 2016/06/16 18:20:25.840 PDT <Test worker> tid=0xa] Starting DistributionManager cc2-rh6(14590)<ec><v442>:1026.  (took 311 ms)
> [info 2016/06/16 18:20:25.840 PDT <Test worker> tid=0xa] Initial (distribution manager) view =  View[cc2-rh6(14622:locator)<ec><v0>:1024|442] members: [cc2-rh6(14622:locator)<ec><v0>:1024, cc2-rh6(14627)<ec><v441>:1025{lead}, cc2-rh6(14590)<ec><v442>:1026]
> [info 2016/06/16 18:20:25.840 PDT <Test worker> tid=0xa] Admitting member <cc2-rh6(14622:locator)<ec><v0>:1024>. Now there are 1 non-admin member(s).
> [info 2016/06/16 18:20:25.840 PDT <Test worker> tid=0xa] Admitting member <cc2-rh6(14627)<ec><v441>:1025>. Now there are 2 non-admin member(s).
> [info 2016/06/16 18:20:25.840 PDT <Test worker> tid=0xa] Admitting member <cc2-rh6(14590)<ec><v442>:1026>. Now there are 3 non-admin member(s).
> [locator][info 2016/06/16 18:20:25.840 PDT <Geode Membership View Creator> tid=0x28] sending new view View[cc2-rh6(14622:locator)<ec><v0>:1024|442] members: [cc2-rh6(14622:locator)<ec><v0>:1024, cc2-rh6(14627)<ec><v441>:1025{lead}, cc2-rh6(14590)<ec><v442>:1026]
> [locator]failure detection ports: 7248 61727 13431
> [locator][info 2016/06/16 18:20:25.840 PDT <View Message Processor> tid=0x33] Membership: Processing addition < cc2-rh6(14590)<ec><v442>:1026 >
> [locator][info 2016/06/16 18:20:25.840 PDT <View Message Processor> tid=0x33] Admitting member <cc2-rh6(14590)<ec><v442>:1026>. Now there are 3 non-admin member(s).
> [vm_0][info 2016/06/16 18:20:25.840 PDT <unicast receiver,cc2-rh6-34505> tid=0x193b] received new view: View[cc2-rh6(14622:locator)<ec><v0>:1024|442] members: [cc2-rh6(14622:locator)<ec><v0>:1024, cc2-rh6(14627)<ec><v441>:1025{lead}, cc2-rh6(14590)<ec><v442>:1026]
> [vm_0]old view is: View[cc2-rh6(14622:locator)<ec><v0>:1024|441] members: [cc2-rh6(14622:locator)<ec><v0>:1024, cc2-rh6(14627)<ec><v441>:1025{lead}]  shutdown: [cc2-rh6(14632)<ec><v440>:1026, cc2-rh6(14590)<ec><v439>:1025]
> [vm_0][info 2016/06/16 18:20:25.841 PDT <View Message Processor> tid=0x1952] Membership: Processing addition < cc2-rh6(14590)<ec><v442>:1026 >
> [vm_0][info 2016/06/16 18:20:25.841 PDT <View Message Processor> tid=0x1952] Admitting member <cc2-rh6(14590)<ec><v442>:1026>. Now there are 3 non-admin member(s).
> [vm_0][info 2016/06/16 18:20:25.842 PDT <Pooled High Priority Message Processor 1> tid=0x1950] Member cc2-rh6(14590)<ec><v442>:1026 is equivalent or in the same redundancy zone.
> [locator][info 2016/06/16 18:20:25.842 PDT <Pooled High Priority Message Processor 1> tid=0x37] Member cc2-rh6(14590)<ec><v442>:1026 is equivalent or in the same redundancy zone.
> [info 2016/06/16 18:20:25.843 PDT <P2P message reader for cc2-rh6(14622:locator)<ec><v0>:1024 shared unordered uid=405 port=45086> tid=0x2126] Member cc2-rh6(14622:locator)<ec><v0>:1024 is equivalent or in the same redundancy zone.
> [info 2016/06/16 18:20:25.843 PDT <P2P message reader for cc2-rh6(14627)<ec><v441>:1025 shared unordered uid=614 port=45087> tid=0x2125] Member cc2-rh6(14627)<ec><v441>:1025 is equivalent or in the same redundancy zone.
> [info 2016/06/16 18:20:25.849 PDT <Test worker> tid=0xa] Initializing region _monitoringRegion_10.118.33.92<v442>1026
> [info 2016/06/16 18:20:25.850 PDT <Test worker> tid=0xa] Initialization of region _monitoringRegion_10.118.33.92<v442>1026 completed
> [info 2016/06/16 18:20:25.852 PDT <Test worker> tid=0xa] The cache has been created with "use-cluster-configuration=false". It will not receive any cluster configuration
> [info 2016/06/16 18:20:25.852 PDT <Test worker> tid=0xa] Initializing region PdxTypes
> [info 2016/06/16 18:20:25.854 PDT <Test worker> tid=0xa] Region PdxTypes requesting initial image from cc2-rh6(14627)<ec><v441>:1025
> [info 2016/06/16 18:20:25.855 PDT <Test worker> tid=0xa] PdxTypes is done getting image from cc2-rh6(14627)<ec><v441>:1025. isDeltaGII is false
> [info 2016/06/16 18:20:25.855 PDT <Test worker> tid=0xa] Initialization of region PdxTypes completed
> [info 2016/06/16 18:20:25.855 PDT <Test worker> tid=0xa] Initializing region root
> [info 2016/06/16 18:20:25.856 PDT <Test worker> tid=0xa] Region root requesting initial image from cc2-rh6(14627)<ec><v441>:1025
> [info 2016/06/16 18:20:25.857 PDT <Test worker> tid=0xa] root is done getting image from cc2-rh6(14627)<ec><v441>:1025. isDeltaGII is false
> [info 2016/06/16 18:20:25.857 PDT <Test worker> tid=0xa] Initialization of region root completed
> [info 2016/06/16 18:20:25.858 PDT <Test worker> tid=0xa] Initializing region PreloadedRegionTestCase_testDistributedCreate
> [info 2016/06/16 18:20:25.859 PDT <Test worker> tid=0xa] Region PreloadedRegionTestCase_testDistributedCreate requesting initial image from cc2-rh6(14627)<ec><v441>:1025
> [info 2016/06/16 18:20:25.859 PDT <Test worker> tid=0xa] PreloadedRegionTestCase_testDistributedCreate is done getting image from cc2-rh6(14627)<ec><v441>:1025. isDeltaGII is false
> [info 2016/06/16 18:20:25.859 PDT <Test worker> tid=0xa] Initialization of region PreloadedRegionTestCase_testDistributedCreate completed
> [vm_0][info 2016/06/16 18:20:25.862 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.cache30.PreloadedRegionTestCase$2.run with 0 args on object: "testDistributedCreate: Create Key"
> [vm_0][info 2016/06/16 18:20:25.862 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] testDistributedCReate: Created Key
> [vm_0][info 2016/06/16 18:20:25.863 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Got result: null
> [vm_0] from com.gemstone.gemfire.cache30.PreloadedRegionTestCase$2.run with 0 args on object: "testDistributedCreate: Create Key" (took 0 ms)
> [info 2016/06/16 18:20:25.865 PDT <Test worker> tid=0xa] GemFireCache[id = 1751725476; isClosing = true; isShutDownAll = false; created = Thu Jun 16 18:20:25 PDT 2016; server = false; copyOnRead = false; lockLease = 120; lockTimeout = 60]: Now closing.
> [vm_0][info 2016/06/16 18:20:25.867 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$4/27964002.run with 0 args on object: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$4/27964002@1c13bd8b
> [vm_0][info 2016/06/16 18:20:25.868 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] GemFireCache[id = 1273423760; isClosing = true; isShutDownAll = false; created = Thu Jun 16 18:20:25 PDT 2016; server = false; copyOnRead = false; lockLease = 120; lockTimeout = 60]: Now closing.
> [vm_0][info 2016/06/16 18:20:25.870 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Got result: null
> [vm_0] from com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$4/27964002.run with 0 args on object: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$4/27964002@1c13bd8b (took 2 ms)
> [vm_1][info 2016/06/16 18:20:25.871 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$4/45568174.run with 0 args on object: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$4/45568174@2297c56f
> [vm_1][info 2016/06/16 18:20:25.871 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Got result: null
> [vm_1] from com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$4/45568174.run with 0 args on object: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$4/45568174@2297c56f (took 0 ms)
> [vm_2][info 2016/06/16 18:20:25.871 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$4/1542137447.run with 0 args on object: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$4/1542137447@5b68c0a9
> [vm_2][info 2016/06/16 18:20:25.872 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Got result: null
> [vm_2] from com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$4/1542137447.run with 0 args on object: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$4/1542137447@5b68c0a9 (took 0 ms)
> [vm_3][info 2016/06/16 18:20:25.872 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$4/27964002.run with 0 args on object: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$4/27964002@1c13bd8b
> [vm_3][info 2016/06/16 18:20:25.872 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Got result: null
> [vm_3] from com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$4/27964002.run with 0 args on object: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$4/27964002@1c13bd8b (took 0 ms)
> [vm_0][info 2016/06/16 18:20:25.873 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.cache30.PreloadedRegionTestCase.cleanup with 0 args
> [vm_0][info 2016/06/16 18:20:25.873 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Got result: null
> [vm_0] from com.gemstone.gemfire.cache30.PreloadedRegionTestCase.cleanup with 0 args (took 0 ms)
> [vm_1][info 2016/06/16 18:20:25.873 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.cache30.PreloadedRegionTestCase.cleanup with 0 args
> [vm_1][info 2016/06/16 18:20:25.874 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Got result: null
> [vm_1] from com.gemstone.gemfire.cache30.PreloadedRegionTestCase.cleanup with 0 args (took 0 ms)
> [vm_2][info 2016/06/16 18:20:25.874 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.cache30.PreloadedRegionTestCase.cleanup with 0 args
> [vm_2][info 2016/06/16 18:20:25.875 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Got result: null
> [vm_2] from com.gemstone.gemfire.cache30.PreloadedRegionTestCase.cleanup with 0 args (took 0 ms)
> [vm_3][info 2016/06/16 18:20:25.875 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.cache30.PreloadedRegionTestCase.cleanup with 0 args
> [vm_3][info 2016/06/16 18:20:25.876 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Got result: null
> [vm_3] from com.gemstone.gemfire.cache30.PreloadedRegionTestCase.cleanup with 0 args (took 0 ms)
> [vm_0][info 2016/06/16 18:20:25.877 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/210810896.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/210810896@1d771b38
> [vm_0][info 2016/06/16 18:20:25.877 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Got result: null
> [vm_0] from com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/210810896.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/210810896@1d771b38 (took 0 ms)
> [vm_1][info 2016/06/16 18:20:25.877 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/1141997112.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/1141997112@31fb0322
> [vm_1][info 2016/06/16 18:20:25.877 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Got result: null
> [vm_1] from com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/1141997112.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/1141997112@31fb0322 (took 0 ms)
> [vm_2][info 2016/06/16 18:20:25.877 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/45568785.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/45568785@516b65d1
> [vm_2][info 2016/06/16 18:20:25.877 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Got result: null
> [vm_2] from com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/45568785.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/45568785@516b65d1 (took 0 ms)
> [vm_3][info 2016/06/16 18:20:25.878 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/210810896.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/210810896@1d771b38
> [vm_3][info 2016/06/16 18:20:25.878 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Got result: null
> [vm_3] from com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/210810896.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/210810896@1d771b38 (took 0 ms)
> [info 2016/06/16 18:20:25.879 PDT <Test worker> tid=0xa] Shutting down DistributionManager cc2-rh6(14590)<ec><v442>:1026. 
> [vm_0][info 2016/06/16 18:20:25.879 PDT <Pooled High Priority Message Processor 1> tid=0x1950] received leave request from cc2-rh6(14590)<ec><v442>:1026 for cc2-rh6(14590)<ec><v442>:1026
> [vm_0][info 2016/06/16 18:20:25.879 PDT <Pooled High Priority Message Processor 1> tid=0x1950] Member at cc2-rh6(14590)<ec><v442>:1026 gracefully left the distributed cache: shutdown message received
> [locator][info 2016/06/16 18:20:25.879 PDT <Pooled High Priority Message Processor 1> tid=0x37] received leave request from cc2-rh6(14590)<ec><v442>:1026 for cc2-rh6(14590)<ec><v442>:1026
> [locator][info 2016/06/16 18:20:25.879 PDT <Pooled High Priority Message Processor 1> tid=0x37] Member at cc2-rh6(14590)<ec><v442>:1026 gracefully left the distributed cache: shutdown message received
> [info 2016/06/16 18:20:25.980 PDT <Test worker> tid=0xa] Now closing distribution for cc2-rh6(14590)<ec><v442>:1026
> [info 2016/06/16 18:20:25.980 PDT <Test worker> tid=0xa] Stopping membership services
> [vm_0][info 2016/06/16 18:20:25.981 PDT <unicast receiver,cc2-rh6-34505> tid=0x193b] received leave request from cc2-rh6(14590)<ec><v442>:1026 for cc2-rh6(14590)<ec><v442>:1026
> [locator][info 2016/06/16 18:20:25.981 PDT <unicast receiver,cc2-rh6-59330> tid=0x22] received leave request from cc2-rh6(14590)<ec><v442>:1026 for cc2-rh6(14590)<ec><v442>:1026
> [info 2016/06/16 18:20:25.981 PDT <Test worker> tid=0xa] GMSHealthMonitor server socket is closed in stopServices().
> [info 2016/06/16 18:20:25.981 PDT <Geode Failure Detection Server thread 0> tid=0x211e] GMSHealthMonitor server thread exiting
> [info 2016/06/16 18:20:25.982 PDT <Test worker> tid=0xa] GMSHealthMonitor serverSocketExecutor is terminated
> [info 2016/06/16 18:20:25.984 PDT <Test worker> tid=0xa] DistributionManager stopped in 105ms.
> [info 2016/06/16 18:20:25.984 PDT <Test worker> tid=0xa] Marking DistributionManager cc2-rh6(14590)<ec><v442>:1026 as closed.
> [vm_0][info 2016/06/16 18:20:25.985 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$7/138308732.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$7/138308732@1671553
> [vm_0][info 2016/06/16 18:20:25.986 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Shutting down DistributionManager cc2-rh6(14627)<ec><v441>:1025. 
> [locator][info 2016/06/16 18:20:25.986 PDT <Pooled High Priority Message Processor 1> tid=0x37] received leave request from cc2-rh6(14627)<ec><v441>:1025 for cc2-rh6(14627)<ec><v441>:1025
> [locator][info 2016/06/16 18:20:25.986 PDT <Pooled High Priority Message Processor 1> tid=0x37] Member at cc2-rh6(14627)<ec><v441>:1025 gracefully left the distributed cache: shutdown message received
> [vm_0][info 2016/06/16 18:20:26.087 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Now closing distribution for cc2-rh6(14627)<ec><v441>:1025
> [vm_0][info 2016/06/16 18:20:26.087 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Stopping membership services
> [vm_0][info 2016/06/16 18:20:26.087 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] GMSHealthMonitor server socket is closed in stopServices().
> [vm_0][info 2016/06/16 18:20:26.087 PDT <Geode Failure Detection Server thread 0> tid=0x193f] GMSHealthMonitor server thread exiting
> [locator][info 2016/06/16 18:20:26.087 PDT <unicast receiver,cc2-rh6-59330> tid=0x22] received leave request from cc2-rh6(14627)<ec><v441>:1025 for cc2-rh6(14627)<ec><v441>:1025
> [vm_0][info 2016/06/16 18:20:26.087 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] GMSHealthMonitor serverSocketExecutor is terminated
> [vm_0][info 2016/06/16 18:20:26.089 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] DistributionManager stopped in 103ms.
> [vm_0][info 2016/06/16 18:20:26.089 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Marking DistributionManager cc2-rh6(14627)<ec><v441>:1025 as closed.
> [vm_0][info 2016/06/16 18:20:26.090 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Got result: null
> [vm_0] from com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$7/138308732.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$7/138308732@1671553 (took 105 ms)
> [vm_1][info 2016/06/16 18:20:26.091 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$7/1870846827.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$7/1870846827@20ad881d
> [vm_1][info 2016/06/16 18:20:26.091 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Got result: null
> [vm_1] from com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$7/1870846827.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$7/1870846827@20ad881d (took 0 ms)
> [vm_2][info 2016/06/16 18:20:26.091 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$7/1017410477.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$7/1017410477@4b380f3
> [vm_2][info 2016/06/16 18:20:26.091 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Got result: null
> [vm_2] from com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$7/1017410477.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$7/1017410477@4b380f3 (took 0 ms)
> [locator][info 2016/06/16 18:20:26.093 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$5/871632238.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$5/871632238@3cb7c778
> [locator][info 2016/06/16 18:20:26.093 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Got result: null
> [locator] from com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$5/871632238.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$5/871632238@3cb7c778 (took 0 ms)
> [vm_3][info 2016/06/16 18:20:26.092 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$7/138308732.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$7/138308732@1671553
> [vm_3][info 2016/06/16 18:20:26.092 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Got result: null
> [vm_3] from com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$7/138308732.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$7/138308732@1671553 (took 0 ms)
> Previously run tests: [DistributedAckPersistentRegionCCEDUnitTest, RemoveAllMultiVmDUnitTest, ClearMultiVmCallBkDUnitTest, CacheXml80DUnitTest, DiskRegionDUnitTest, RRSynchronizationDUnitTest, ConcurrentLeaveDuringGIIDUnitTest, DistributedAckOverflowRegionCCEDUnitTest, CacheRegionsReliablityStatsCheckDUnitTest, PreloadedRegionTestCase]
> [vm_0][info 2016/06/16 18:20:26.094 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(setupVM)
> [vm_0][info 2016/06/16 18:20:26.094 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Got result: null
> [vm_0] from com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(setupVM) (took 0 ms)
> [vm_1][info 2016/06/16 18:20:26.094 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(setupVM)
> [vm_1][info 2016/06/16 18:20:26.095 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] 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/06/16 18:20:26.095 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(setupVM)
> [vm_2][info 2016/06/16 18:20:26.095 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] 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/06/16 18:20:26.095 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(setupVM)
> [vm_3][info 2016/06/16 18:20:26.095 PDT <RMI TCP Connection(1)-10.118.33.92> 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 PreloadedRegionTestCase.testTXRmtMirror
> distributed system properties: {locators=localhost[23244], enable-cluster-configuration=false, use-cluster-configuration=false, mcast-port=0, disable-auto-reconnect=true, log-level=info}
> [info 2016/06/16 18:20:26.098 PDT <Test worker> tid=0xa] Startup Configuration:
>  ### GemFire Properties defined with api ###
> disable-auto-reconnect=true
> enable-cluster-configuration=false
> locators=localhost[23244]
> log-level=info
> mcast-port=0
> use-cluster-configuration=false
> ### GemFire Properties using default values ###
> ack-severe-alert-threshold=0
> ack-wait-threshold=15
> archive-disk-space-limit=0
> archive-file-size-limit=0
> async-distribution-timeout=0
> async-max-queue-size=8
> async-queue-timeout=60000
> bind-address=
> cache-xml-file=cache.xml
> cluster-configuration-dir=/cc2-rh6a/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/geode-core/build/distributedTest
> cluster-ssl-ciphers=any
> cluster-ssl-enabled=false
> cluster-ssl-keystore=
> cluster-ssl-keystore-password=
> cluster-ssl-keystore-type=
> cluster-ssl-protocols=any
> cluster-ssl-require-authentication=true
> cluster-ssl-truststore=
> cluster-ssl-truststore-password=
> conflate-events=server
> conserve-sockets=true
> delta-propagation=true
> deploy-working-dir=.
> disable-tcp=false
> distributed-system-id=-1
> distributed-transactions=false
> durable-client-id=
> durable-client-timeout=300
> enable-network-partition-detection=false
> enable-time-statistics=false
> enforce-unique-host=false
> gateway-ssl-ciphers=any
> gateway-ssl-enabled=false
> gateway-ssl-keystore=
> gateway-ssl-keystore-password=
> gateway-ssl-keystore-type=
> gateway-ssl-protocols=any
> gateway-ssl-require-authentication=true
> gateway-ssl-truststore=
> gateway-ssl-truststore-password=
> groups=
> http-service-bind-address=
> http-service-port=7070
> http-service-ssl-ciphers=any
> http-service-ssl-enabled=false
> http-service-ssl-keystore=
> http-service-ssl-keystore-password=
> http-service-ssl-keystore-type=
> http-service-ssl-protocols=any
> http-service-ssl-require-authentication=false
> http-service-ssl-truststore=
> http-service-ssl-truststore-password=
> jmx-manager=false
> jmx-manager-access-file=
> jmx-manager-bind-address=
> jmx-manager-hostname-for-clients=
> jmx-manager-http-port=7070
> jmx-manager-password-file=
> jmx-manager-port=1099
> jmx-manager-ssl=false
> jmx-manager-ssl-ciphers=any
> jmx-manager-ssl-enabled=false
> jmx-manager-ssl-keystore=
> jmx-manager-ssl-keystore-password=
> jmx-manager-ssl-keystore-type=
> jmx-manager-ssl-protocols=any
> jmx-manager-ssl-require-authentication=true
> jmx-manager-ssl-truststore=
> jmx-manager-ssl-truststore-password=
> jmx-manager-start=false
> jmx-manager-update-rate=2000
> load-cluster-configuration-from-dir=false
> locator-wait-time=0
> lock-memory=false
> log-disk-space-limit=0
> log-file=
> log-file-size-limit=0
> max-num-reconnect-tries=3
> max-wait-time-reconnect=60000
> mcast-address=239.192.81.1
> mcast-flow-control=1048576, 0.25, 5000
> mcast-recv-buffer-size=1048576
> mcast-send-buffer-size=65535
> mcast-ttl=32
> member-timeout=5000
> membership-port-range=1024-65535
> memcached-bind-address=
> memcached-port=0
> memcached-protocol=ASCII
> name=
> off-heap-memory-size=
> redis-bind-address=
> redis-password=
> redis-port=0
> redundancy-zone=
> remote-locators=
> remove-unresponsive-client=false
> roles=
> security-client-accessor=
> security-client-accessor-pp=
> security-client-auth-init=
> security-client-authenticator=
> security-client-dhalgo=
> security-log-file=
> security-log-level=config
> security-peer-auth-init=
> security-peer-authenticator=
> security-peer-verifymember-timeout=1000
> server-bind-address=
> server-ssl-ciphers=any
> server-ssl-enabled=false
> server-ssl-keystore=
> server-ssl-keystore-password=
> server-ssl-keystore-type=
> server-ssl-protocols=any
> server-ssl-require-authentication=true
> server-ssl-truststore=
> server-ssl-truststore-password=
> socket-buffer-size=32768
> socket-lease-time=60000
> ssl-ciphers=any
> ssl-enabled=false
> ssl-protocols=any
> ssl-require-authentication=true
> start-dev-rest-api=false
> start-locator=
> statistic-archive-file=
> statistic-sample-rate=1000
> statistic-sampling-enabled=true
> tcp-port=0
> udp-fragment-size=60000
> udp-recv-buffer-size=1048576
> udp-send-buffer-size=65535
> user-command-packages=
> [info 2016/06/16 18:20:26.100 PDT <Test worker> tid=0xa] Starting membership services
> [info 2016/06/16 18:20:26.106 PDT <Test worker> tid=0xa] JGroups channel created (took 5ms)
> [info 2016/06/16 18:20:26.107 PDT <Test worker> tid=0xa] GemFire P2P Listener started on  null
> [info 2016/06/16 18:20:26.107 PDT <Geode Failure Detection Server thread 0> tid=0x214d] Started failure detection server thread on cc2-rh6.gemstone.com/10.118.33.92:33446.
> [info 2016/06/16 18:20:26.110 PDT <Test worker> tid=0xa] Attempting to join the distributed system through coordinator cc2-rh6(14622:locator)<ec><v0>:1024 using address cc2-rh6(14590)<ec>:1025
> [locator][info 2016/06/16 18:20:26.110 PDT <unicast receiver,cc2-rh6-59330> tid=0x22] received join request from cc2-rh6(14590)<ec>:1025
> [locator][info 2016/06/16 18:20:26.210 PDT <Geode Membership View Creator> tid=0x28] View Creator is processing 5 requests for the next membership view
> [locator][info 2016/06/16 18:20:26.210 PDT <Geode Membership View Creator> tid=0x28] preparing new view View[cc2-rh6(14622:locator)<ec><v0>:1024|443] members: [cc2-rh6(14622:locator)<ec><v0>:1024, cc2-rh6(14590)<ec><v443>:1025{lead}]  shutdown: [cc2-rh6(14590)<ec><v442>:1026, cc2-rh6(14627)<ec><v441>:1025]
> [locator]failure detection ports: 7248 33446
> [info 2016/06/16 18:20:26.211 PDT <unicast receiver,cc2-rh6-8418> tid=0x2149] received new view: View[cc2-rh6(14622:locator)<ec><v0>:1024|443] members: [cc2-rh6(14622:locator)<ec><v0>:1024, cc2-rh6(14590)<ec><v443>:1025{lead}]  shutdown: [cc2-rh6(14590)<ec><v442>:1026, cc2-rh6(14627)<ec><v441>:1025]
> old view is: null
> [locator][info 2016/06/16 18:20:26.211 PDT <Geode Membership View Creator> tid=0x28] finished waiting for responses to view preparation
> [locator][info 2016/06/16 18:20:26.211 PDT <Geode Membership View Creator> tid=0x28] received new view: View[cc2-rh6(14622:locator)<ec><v0>:1024|443] members: [cc2-rh6(14622:locator)<ec><v0>:1024, cc2-rh6(14590)<ec><v443>:1025{lead}]  shutdown: [cc2-rh6(14590)<ec><v442>:1026, cc2-rh6(14627)<ec><v441>:1025]
> [locator]old view is: View[cc2-rh6(14622:locator)<ec><v0>:1024|442] members: [cc2-rh6(14622:locator)<ec><v0>:1024, cc2-rh6(14627)<ec><v441>:1025{lead}, cc2-rh6(14590)<ec><v442>:1026]
> [locator][info 2016/06/16 18:20:26.211 PDT <Geode Membership View Creator> tid=0x28] Peer locator received new membership view: View[cc2-rh6(14622:locator)<ec><v0>:1024|443] members: [cc2-rh6(14622:locator)<ec><v0>:1024, cc2-rh6(14590)<ec><v443>:1025{lead}]  shutdown: [cc2-rh6(14590)<ec><v442>:1026, cc2-rh6(14627)<ec><v441>:1025]
> [locator][info 2016/06/16 18:20:26.212 PDT <Geode Membership View Creator> tid=0x28] sending new view View[cc2-rh6(14622:locator)<ec><v0>:1024|443] members: [cc2-rh6(14622:locator)<ec><v0>:1024, cc2-rh6(14590)<ec><v443>:1025{lead}]  shutdown: [cc2-rh6(14590)<ec><v442>:1026, cc2-rh6(14627)<ec><v441>:1025]
> [locator]failure detection ports: 7248 33446
> [locator][info 2016/06/16 18:20:26.212 PDT <View Message Processor> tid=0x33] Membership: Processing addition < cc2-rh6(14590)<ec><v443>:1025 >
> [locator][info 2016/06/16 18:20:26.212 PDT <View Message Processor> tid=0x33] Admitting member <cc2-rh6(14590)<ec><v443>:1025>. Now there are 2 non-admin member(s).
> [info 2016/06/16 18:20:26.214 PDT <Test worker> tid=0xa] Finished joining (took 107ms).
> [info 2016/06/16 18:20:26.214 PDT <Test worker> tid=0xa] Starting DistributionManager cc2-rh6(14590)<ec><v443>:1025.  (took 114 ms)
> [info 2016/06/16 18:20:26.214 PDT <Test worker> tid=0xa] Initial (distribution manager) view
> ...[truncated 3816272 chars]...
> dunit.internal.JUnit4DistributedTestCase$$Lambda$7/138308732.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$7/138308732@1671553 (took 0 ms)
> [locator][info 2016/06/16 18:21:50.696 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$5/871632238.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$5/871632238@3cb7c778
> [locator][info 2016/06/16 18:21:50.696 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Got result: null
> [locator] from com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$5/871632238.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$5/871632238@3cb7c778 (took 0 ms)
> Previously run tests: [DistributedAckPersistentRegionCCEDUnitTest, RemoveAllMultiVmDUnitTest, ClearMultiVmCallBkDUnitTest, CacheXml80DUnitTest, DiskRegionDUnitTest, RRSynchronizationDUnitTest, ConcurrentLeaveDuringGIIDUnitTest, DistributedAckOverflowRegionCCEDUnitTest, CacheRegionsReliablityStatsCheckDUnitTest, PreloadedRegionTestCase]
> [vm_0][info 2016/06/16 18:21:50.697 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(setupVM)
> [vm_0][info 2016/06/16 18:21:50.697 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Got result: null
> [vm_0] from com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(setupVM) (took 0 ms)
> [vm_1][info 2016/06/16 18:21:50.697 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(setupVM)
> [vm_1][info 2016/06/16 18:21:50.698 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] 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/06/16 18:21:50.698 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(setupVM)
> [vm_2][info 2016/06/16 18:21:50.698 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] 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/06/16 18:21:50.698 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(setupVM)
> [vm_3][info 2016/06/16 18:21:50.698 PDT <RMI TCP Connection(1)-10.118.33.92> 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 PreloadedRegionTestCase.testCustomEntryTtl2
> distributed system properties: {locators=localhost[23244], enable-cluster-configuration=false, use-cluster-configuration=false, mcast-port=0, disable-auto-reconnect=true, log-level=info}
> [info 2016/06/16 18:21:50.701 PDT <Test worker> tid=0xa] Startup Configuration:
>  ### GemFire Properties defined with api ###
> disable-auto-reconnect=true
> enable-cluster-configuration=false
> locators=localhost[23244]
> log-level=info
> mcast-port=0
> use-cluster-configuration=false
> ### GemFire Properties using default values ###
> ack-severe-alert-threshold=0
> ack-wait-threshold=15
> archive-disk-space-limit=0
> archive-file-size-limit=0
> async-distribution-timeout=0
> async-max-queue-size=8
> async-queue-timeout=60000
> bind-address=
> cache-xml-file=cache.xml
> cluster-configuration-dir=/cc2-rh6a/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/geode-core/build/distributedTest
> cluster-ssl-ciphers=any
> cluster-ssl-enabled=false
> cluster-ssl-keystore=
> cluster-ssl-keystore-password=
> cluster-ssl-keystore-type=
> cluster-ssl-protocols=any
> cluster-ssl-require-authentication=true
> cluster-ssl-truststore=
> cluster-ssl-truststore-password=
> conflate-events=server
> conserve-sockets=true
> delta-propagation=true
> deploy-working-dir=.
> disable-tcp=false
> distributed-system-id=-1
> distributed-transactions=false
> durable-client-id=
> durable-client-timeout=300
> enable-network-partition-detection=false
> enable-time-statistics=false
> enforce-unique-host=false
> gateway-ssl-ciphers=any
> gateway-ssl-enabled=false
> gateway-ssl-keystore=
> gateway-ssl-keystore-password=
> gateway-ssl-keystore-type=
> gateway-ssl-protocols=any
> gateway-ssl-require-authentication=true
> gateway-ssl-truststore=
> gateway-ssl-truststore-password=
> groups=
> http-service-bind-address=
> http-service-port=7070
> http-service-ssl-ciphers=any
> http-service-ssl-enabled=false
> http-service-ssl-keystore=
> http-service-ssl-keystore-password=
> http-service-ssl-keystore-type=
> http-service-ssl-protocols=any
> http-service-ssl-require-authentication=false
> http-service-ssl-truststore=
> http-service-ssl-truststore-password=
> jmx-manager=false
> jmx-manager-access-file=
> jmx-manager-bind-address=
> jmx-manager-hostname-for-clients=
> jmx-manager-http-port=7070
> jmx-manager-password-file=
> jmx-manager-port=1099
> jmx-manager-ssl=false
> jmx-manager-ssl-ciphers=any
> jmx-manager-ssl-enabled=false
> jmx-manager-ssl-keystore=
> jmx-manager-ssl-keystore-password=
> jmx-manager-ssl-keystore-type=
> jmx-manager-ssl-protocols=any
> jmx-manager-ssl-require-authentication=true
> jmx-manager-ssl-truststore=
> jmx-manager-ssl-truststore-password=
> jmx-manager-start=false
> jmx-manager-update-rate=2000
> load-cluster-configuration-from-dir=false
> locator-wait-time=0
> lock-memory=false
> log-disk-space-limit=0
> log-file=
> log-file-size-limit=0
> max-num-reconnect-tries=3
> max-wait-time-reconnect=60000
> mcast-address=239.192.81.1
> mcast-flow-control=1048576, 0.25, 5000
> mcast-recv-buffer-size=1048576
> mcast-send-buffer-size=65535
> mcast-ttl=32
> member-timeout=5000
> membership-port-range=1024-65535
> memcached-bind-address=
> memcached-port=0
> memcached-protocol=ASCII
> name=
> off-heap-memory-size=
> redis-bind-address=
> redis-password=
> redis-port=0
> redundancy-zone=
> remote-locators=
> remove-unresponsive-client=false
> roles=
> security-client-accessor=
> security-client-accessor-pp=
> security-client-auth-init=
> security-client-authenticator=
> security-client-dhalgo=
> security-log-file=
> security-log-level=config
> security-peer-auth-init=
> security-peer-authenticator=
> security-peer-verifymember-timeout=1000
> server-bind-address=
> server-ssl-ciphers=any
> server-ssl-enabled=false
> server-ssl-keystore=
> server-ssl-keystore-password=
> server-ssl-keystore-type=
> server-ssl-protocols=any
> server-ssl-require-authentication=true
> server-ssl-truststore=
> server-ssl-truststore-password=
> socket-buffer-size=32768
> socket-lease-time=60000
> ssl-ciphers=any
> ssl-enabled=false
> ssl-protocols=any
> ssl-require-authentication=true
> start-dev-rest-api=false
> start-locator=
> statistic-archive-file=
> statistic-sample-rate=1000
> statistic-sampling-enabled=true
> tcp-port=0
> udp-fragment-size=60000
> udp-recv-buffer-size=1048576
> udp-send-buffer-size=65535
> user-command-packages=
> [info 2016/06/16 18:21:50.702 PDT <Test worker> tid=0xa] Starting membership services
> [info 2016/06/16 18:21:50.707 PDT <Test worker> tid=0xa] JGroups channel created (took 5ms)
> [info 2016/06/16 18:21:50.708 PDT <Test worker> tid=0xa] GemFire P2P Listener started on  null
> [info 2016/06/16 18:21:50.708 PDT <Geode Failure Detection Server thread 0> tid=0x29fa] Started failure detection server thread on cc2-rh6.gemstone.com/10.118.33.92:15653.
> [info 2016/06/16 18:21:50.710 PDT <Test worker> tid=0xa] Attempting to join the distributed system through coordinator cc2-rh6(14622:locator)<ec><v0>:1024 using address cc2-rh6(14590)<ec>:1025
> [locator][info 2016/06/16 18:21:50.711 PDT <unicast receiver,cc2-rh6-59330> tid=0x22] received join request from cc2-rh6(14590)<ec>:1025
> [locator][info 2016/06/16 18:21:50.911 PDT <Geode Membership View Creator> tid=0x28] View Creator is processing 3 requests for the next membership view
> [locator][info 2016/06/16 18:21:50.911 PDT <Geode Membership View Creator> tid=0x28] preparing new view View[cc2-rh6(14622:locator)<ec><v0>:1024|620] members: [cc2-rh6(14622:locator)<ec><v0>:1024, cc2-rh6(14590)<ec><v620>:1025{lead}]  shutdown: [cc2-rh6(14590)<ec><v619>:1025]
> [locator]failure detection ports: 7248 15653
> [info 2016/06/16 18:21:50.912 PDT <unicast receiver,cc2-rh6-48468> tid=0x29f6] received new view: View[cc2-rh6(14622:locator)<ec><v0>:1024|620] members: [cc2-rh6(14622:locator)<ec><v0>:1024, cc2-rh6(14590)<ec><v620>:1025{lead}]  shutdown: [cc2-rh6(14590)<ec><v619>:1025]
> old view is: null
> [info 2016/06/16 18:21:50.912 PDT <Test worker> tid=0xa] Finished joining (took 204ms).
> [info 2016/06/16 18:21:50.912 PDT <Test worker> tid=0xa] Starting DistributionManager cc2-rh6(14590)<ec><v620>:1025.  (took 211 ms)
> [locator][info 2016/06/16 18:21:50.912 PDT <Geode Membership View Creator> tid=0x28] finished waiting for responses to view preparation
> [locator][info 2016/06/16 18:21:50.912 PDT <Geode Membership View Creator> tid=0x28] received new view: View[cc2-rh6(14622:locator)<ec><v0>:1024|620] members: [cc2-rh6(14622:locator)<ec><v0>:1024, cc2-rh6(14590)<ec><v620>:1025{lead}]  shutdown: [cc2-rh6(14590)<ec><v619>:1025]
> [locator]old view is: View[cc2-rh6(14622:locator)<ec><v0>:1024|619] members: [cc2-rh6(14622:locator)<ec><v0>:1024, cc2-rh6(14590)<ec><v619>:1025{lead}]  shutdown: [cc2-rh6(14590)<ec><v618>:1025]
> [locator][info 2016/06/16 18:21:50.912 PDT <Geode Membership View Creator> tid=0x28] Peer locator received new membership view: View[cc2-rh6(14622:locator)<ec><v0>:1024|620] members: [cc2-rh6(14622:locator)<ec><v0>:1024, cc2-rh6(14590)<ec><v620>:1025{lead}]  shutdown: [cc2-rh6(14590)<ec><v619>:1025]
> [locator][info 2016/06/16 18:21:50.913 PDT <Geode Membership View Creator> tid=0x28] sending new view View[cc2-rh6(14622:locator)<ec><v0>:1024|620] members: [cc2-rh6(14622:locator)<ec><v0>:1024, cc2-rh6(14590)<ec><v620>:1025{lead}]  shutdown: [cc2-rh6(14590)<ec><v619>:1025]
> [locator]failure detection ports: 7248 15653
> [locator][info 2016/06/16 18:21:50.913 PDT <View Message Processor> tid=0x33] Membership: Processing addition < cc2-rh6(14590)<ec><v620>:1025 >
> [locator][info 2016/06/16 18:21:50.913 PDT <View Message Processor> tid=0x33] Admitting member <cc2-rh6(14590)<ec><v620>:1025>. Now there are 2 non-admin member(s).
> [info 2016/06/16 18:21:50.913 PDT <Test worker> tid=0xa] Initial (distribution manager) view =  View[cc2-rh6(14622:locator)<ec><v0>:1024|620] members: [cc2-rh6(14622:locator)<ec><v0>:1024, cc2-rh6(14590)<ec><v620>:1025{lead}]  shutdown: [cc2-rh6(14590)<ec><v619>:1025]
> [info 2016/06/16 18:21:50.913 PDT <Test worker> tid=0xa] Admitting member <cc2-rh6(14622:locator)<ec><v0>:1024>. Now there are 1 non-admin member(s).
> [info 2016/06/16 18:21:50.913 PDT <Test worker> tid=0xa] Admitting member <cc2-rh6(14590)<ec><v620>:1025>. Now there are 2 non-admin member(s).
> [locator][info 2016/06/16 18:21:50.915 PDT <Pooled High Priority Message Processor 2> tid=0x6a] Member cc2-rh6(14590)<ec><v620>:1025 is equivalent or in the same redundancy zone.
> [info 2016/06/16 18:21:50.916 PDT <P2P message reader for cc2-rh6(14622:locator)<ec><v0>:1024 shared unordered uid=569 port=57707> tid=0x29ff] Member cc2-rh6(14622:locator)<ec><v0>:1024 is equivalent or in the same redundancy zone.
> [info 2016/06/16 18:21:50.922 PDT <Test worker> tid=0xa] Initializing region _monitoringRegion_10.118.33.92<v620>1025
> [info 2016/06/16 18:21:50.923 PDT <Test worker> tid=0xa] Initialization of region _monitoringRegion_10.118.33.92<v620>1025 completed
> [info 2016/06/16 18:21:50.925 PDT <Test worker> tid=0xa] The cache has been created with "use-cluster-configuration=false". It will not receive any cluster configuration
> [info 2016/06/16 18:21:50.925 PDT <Test worker> tid=0xa] Initializing region PdxTypes
> [info 2016/06/16 18:21:50.926 PDT <Test worker> tid=0xa] Initialization of region PdxTypes completed
> [info 2016/06/16 18:21:50.926 PDT <Test worker> tid=0xa] Initializing region root
> [info 2016/06/16 18:21:50.926 PDT <Test worker> tid=0xa] Initialization of region root completed
> [info 2016/06/16 18:21:50.927 PDT <Test worker> tid=0xa] Initializing region PreloadedRegionTestCase_testCustomEntryTtl2
> [info 2016/06/16 18:21:50.927 PDT <Test worker> tid=0xa] Initialization of region PreloadedRegionTestCase_testCustomEntryTtl2 completed
> [info 2016/06/16 18:21:51.168 PDT <Test worker> tid=0xa] GemFireCache[id = 1457406350; isClosing = true; isShutDownAll = false; created = Thu Jun 16 18:21:50 PDT 2016; server = false; copyOnRead = false; lockLease = 120; lockTimeout = 60]: Now closing.
> [vm_0][info 2016/06/16 18:21:51.171 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$4/27964002.run with 0 args on object: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$4/27964002@1c13bd8b
> [vm_0][info 2016/06/16 18:21:51.171 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Got result: null
> [vm_0] from com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$4/27964002.run with 0 args on object: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$4/27964002@1c13bd8b (took 0 ms)
> [vm_1][info 2016/06/16 18:21:51.171 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$4/45568174.run with 0 args on object: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$4/45568174@2297c56f
> [vm_1][info 2016/06/16 18:21:51.172 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Got result: null
> [vm_1] from com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$4/45568174.run with 0 args on object: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$4/45568174@2297c56f (took 0 ms)
> [vm_2][info 2016/06/16 18:21:51.172 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$4/1542137447.run with 0 args on object: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$4/1542137447@5b68c0a9
> [vm_2][info 2016/06/16 18:21:51.172 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Got result: null
> [vm_2] from com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$4/1542137447.run with 0 args on object: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$4/1542137447@5b68c0a9 (took 0 ms)
> [vm_3][info 2016/06/16 18:21:51.173 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$4/27964002.run with 0 args on object: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$4/27964002@1c13bd8b
> [vm_3][info 2016/06/16 18:21:51.173 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Got result: null
> [vm_3] from com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$4/27964002.run with 0 args on object: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$4/27964002@1c13bd8b (took 0 ms)
> [vm_0][info 2016/06/16 18:21:51.173 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.cache30.PreloadedRegionTestCase.cleanup with 0 args
> [vm_0][info 2016/06/16 18:21:51.174 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Got result: null
> [vm_0] from com.gemstone.gemfire.cache30.PreloadedRegionTestCase.cleanup with 0 args (took 0 ms)
> [vm_1][info 2016/06/16 18:21:51.174 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.cache30.PreloadedRegionTestCase.cleanup with 0 args
> [vm_1][info 2016/06/16 18:21:51.174 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Got result: null
> [vm_1] from com.gemstone.gemfire.cache30.PreloadedRegionTestCase.cleanup with 0 args (took 0 ms)
> [vm_2][info 2016/06/16 18:21:51.174 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.cache30.PreloadedRegionTestCase.cleanup with 0 args
> [vm_2][info 2016/06/16 18:21:51.174 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Got result: null
> [vm_2] from com.gemstone.gemfire.cache30.PreloadedRegionTestCase.cleanup with 0 args (took 0 ms)
> [vm_3][info 2016/06/16 18:21:51.174 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.cache30.PreloadedRegionTestCase.cleanup with 0 args
> [vm_3][info 2016/06/16 18:21:51.174 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Got result: null
> [vm_3] from com.gemstone.gemfire.cache30.PreloadedRegionTestCase.cleanup with 0 args (took 0 ms)
> [vm_0][info 2016/06/16 18:21:51.175 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/210810896.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/210810896@1d771b38
> [vm_0][info 2016/06/16 18:21:51.175 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Got result: null
> [vm_0] from com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/210810896.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/210810896@1d771b38 (took 0 ms)
> [vm_1][info 2016/06/16 18:21:51.175 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/1141997112.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/1141997112@31fb0322
> [vm_1][info 2016/06/16 18:21:51.175 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Got result: null
> [vm_1] from com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/1141997112.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/1141997112@31fb0322 (took 0 ms)
> [vm_2][info 2016/06/16 18:21:51.175 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/45568785.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/45568785@516b65d1
> [vm_2][info 2016/06/16 18:21:51.175 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Got result: null
> [vm_2] from com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/45568785.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/45568785@516b65d1 (took 0 ms)
> [vm_3][info 2016/06/16 18:21:51.176 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/210810896.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/210810896@1d771b38
> [vm_3][info 2016/06/16 18:21:51.176 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Got result: null
> [vm_3] from com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/210810896.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/210810896@1d771b38 (took 0 ms)
> [info 2016/06/16 18:21:51.176 PDT <Test worker> tid=0xa] Shutting down DistributionManager cc2-rh6(14590)<ec><v620>:1025. 
> [locator][info 2016/06/16 18:21:51.177 PDT <Pooled High Priority Message Processor 2> tid=0x6a] received leave request from cc2-rh6(14590)<ec><v620>:1025 for cc2-rh6(14590)<ec><v620>:1025
> [locator][info 2016/06/16 18:21:51.177 PDT <Pooled High Priority Message Processor 2> tid=0x6a] Member at cc2-rh6(14590)<ec><v620>:1025 gracefully left the distributed cache: shutdown message received
> [info 2016/06/16 18:21:51.278 PDT <Test worker> tid=0xa] Now closing distribution for cc2-rh6(14590)<ec><v620>:1025
> [info 2016/06/16 18:21:51.278 PDT <Test worker> tid=0xa] Stopping membership services
> [locator][info 2016/06/16 18:21:51.278 PDT <unicast receiver,cc2-rh6-59330> tid=0x22] received leave request from cc2-rh6(14590)<ec><v620>:1025 for cc2-rh6(14590)<ec><v620>:1025
> [info 2016/06/16 18:21:51.278 PDT <Test worker> tid=0xa] GMSHealthMonitor server socket is closed in stopServices().
> [info 2016/06/16 18:21:51.278 PDT <Geode Failure Detection Server thread 0> tid=0x29fa] GMSHealthMonitor server thread exiting
> [info 2016/06/16 18:21:51.278 PDT <Test worker> tid=0xa] GMSHealthMonitor serverSocketExecutor is terminated
> [info 2016/06/16 18:21:51.280 PDT <Test worker> tid=0xa] DistributionManager stopped in 103ms.
> [info 2016/06/16 18:21:51.280 PDT <Test worker> tid=0xa] Marking DistributionManager cc2-rh6(14590)<ec><v620>:1025 as closed.
> [vm_0][info 2016/06/16 18:21:51.280 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$7/138308732.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$7/138308732@1671553
> [vm_0][info 2016/06/16 18:21:51.280 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Got result: null
> [vm_0] from com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$7/138308732.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$7/138308732@1671553 (took 0 ms)
> [vm_1][info 2016/06/16 18:21:51.281 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$7/1870846827.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$7/1870846827@20ad881d
> [vm_1][info 2016/06/16 18:21:51.281 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Got result: null
> [vm_1] from com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$7/1870846827.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$7/1870846827@20ad881d (took 0 ms)
> [vm_2][info 2016/06/16 18:21:51.281 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$7/1017410477.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$7/1017410477@4b380f3
> [vm_2][info 2016/06/16 18:21:51.281 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Got result: null
> [vm_2] from com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$7/1017410477.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$7/1017410477@4b380f3 (took 0 ms)
> [vm_3][info 2016/06/16 18:21:51.282 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$7/138308732.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$7/138308732@1671553
> [vm_3][info 2016/06/16 18:21:51.282 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Got result: null
> [vm_3] from com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$7/138308732.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$7/138308732@1671553 (took 0 ms)
> [locator][info 2016/06/16 18:21:51.282 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$5/871632238.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$5/871632238@3cb7c778
> [locator][info 2016/06/16 18:21:51.282 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Got result: null
> [locator] from com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$5/871632238.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$5/871632238@3cb7c778 (took 0 ms)
> Previously run tests: [DistributedAckPersistentRegionCCEDUnitTest, RemoveAllMultiVmDUnitTest, ClearMultiVmCallBkDUnitTest, CacheXml80DUnitTest, DiskRegionDUnitTest, RRSynchronizationDUnitTest, ConcurrentLeaveDuringGIIDUnitTest, DistributedAckOverflowRegionCCEDUnitTest, CacheRegionsReliablityStatsCheckDUnitTest, PreloadedRegionTestCase]
> [vm_0][info 2016/06/16 18:21:51.283 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(setupVM)
> [vm_0][info 2016/06/16 18:21:51.283 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Got result: null
> [vm_0] from com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(setupVM) (took 0 ms)
> [vm_1][info 2016/06/16 18:21:51.284 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(setupVM)
> [vm_1][info 2016/06/16 18:21:51.284 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] 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/06/16 18:21:51.284 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(setupVM)
> [vm_2][info 2016/06/16 18:21:51.284 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] 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/06/16 18:21:51.284 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(setupVM)
> [vm_3][info 2016/06/16 18:21:51.284 PDT <RMI TCP Connection(1)-10.118.33.92> 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 PreloadedRegionTestCase.testCustomEntryTtl3
> distributed system properties: {locators=localhost[23244], enable-cluster-configuration=false, use-cluster-configuration=false, mcast-port=0, disable-auto-reconnect=true, log-level=info}
> [info 2016/06/16 18:21:51.287 PDT <Test worker> tid=0xa] Startup Configuration:
>  ### GemFire Properties defined with api ###
> disable-auto-reconnect=true
> enable-cluster-configuration=false
> locators=localhost[23244]
> log-level=info
> mcast-port=0
> use-cluster-configuration=false
> ### GemFire Properties using default values ###
> ack-severe-alert-threshold=0
> ack-wait-threshold=15
> archive-disk-space-limit=0
> archive-file-size-limit=0
> async-distribution-timeout=0
> async-max-queue-size=8
> async-queue-timeout=60000
> bind-address=
> cache-xml-file=cache.xml
> cluster-configuration-dir=/cc2-rh6a/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/geode-core/build/distributedTest
> cluster-ssl-ciphers=any
> cluster-ssl-enabled=false
> cluster-ssl-keystore=
> cluster-ssl-keystore-password=
> cluster-ssl-keystore-type=
> cluster-ssl-protocols=any
> cluster-ssl-require-authentication=true
> cluster-ssl-truststore=
> cluster-ssl-truststore-password=
> conflate-events=server
> conserve-sockets=true
> delta-propagation=true
> deploy-working-dir=.
> disable-tcp=false
> distributed-system-id=-1
> distributed-transactions=false
> durable-client-id=
> durable-client-timeout=300
> enable-network-partition-detection=false
> enable-time-statistics=false
> enforce-unique-host=false
> gateway-ssl-ciphers=any
> gateway-ssl-enabled=false
> gateway-ssl-keystore=
> gateway-ssl-keystore-password=
> gateway-ssl-keystore-type=
> gateway-ssl-protocols=any
> gateway-ssl-require-authentication=true
> gateway-ssl-truststore=
> gateway-ssl-truststore-password=
> groups=
> http-service-bind-address=
> http-service-port=7070
> http-service-ssl-ciphers=any
> http-service-ssl-enabled=false
> http-service-ssl-keystore=
> http-service-ssl-keystore-password=
> http-service-ssl-keystore-type=
> http-service-ssl-protocols=any
> http-service-ssl-require-authentication=false
> http-service-ssl-truststore=
> http-service-ssl-truststore-password=
> jmx-manager=false
> jmx-manager-access-file=
> jmx-manager-bind-address=
> jmx-manager-hostname-for-clients=
> jmx-manager-http-port=7070
> jmx-manager-password-file=
> jmx-manager-port=1099
> jmx-manager-ssl=false
> jmx-manager-ssl-ciphers=any
> jmx-manager-ssl-enabled=false
> jmx-manager-ssl-keystore=
> jmx-manager-ssl-keystore-password=
> jmx-manager-ssl-keystore-type=
> jmx-manager-ssl-protocols=any
> jmx-manager-ssl-require-authentication=true
> jmx-manager-ssl-truststore=
> jmx-manager-ssl-truststore-password=
> jmx-manager-start=false
> jmx-manager-update-rate=2000
> load-cluster-configuration-from-dir=false
> locator-wait-time=0
> lock-memory=false
> log-disk-space-limit=0
> log-file=
> log-file-size-limit=0
> max-num-reconnect-tries=3
> max-wait-time-reconnect=60000
> mcast-address=239.192.81.1
> mcast-flow-control=1048576, 0.25, 5000
> mcast-recv-buffer-size=1048576
> mcast-send-buffer-size=65535
> mcast-ttl=32
> member-timeout=5000
> membership-port-range=1024-65535
> memcached-bind-address=
> memcached-port=0
> memcached-protocol=ASCII
> name=
> off-heap-memory-size=
> redis-bind-address=
> redis-password=
> redis-port=0
> redundancy-zone=
> remote-locators=
> remove-unresponsive-client=false
> roles=
> security-client-accessor=
> security-client-accessor-pp=
> security-client-auth-init=
> security-client-authenticator=
> security-client-dhalgo=
> security-log-file=
> security-log-level=config
> security-peer-auth-init=
> security-peer-authenticator=
> security-peer-verifymember-timeout=1000
> server-bind-address=
> server-ssl-ciphers=any
> server-ssl-enabled=false
> server-ssl-keystore=
> server-ssl-keystore-password=
> server-ssl-keystore-type=
> server-ssl-protocols=any
> server-ssl-require-authentication=true
> server-ssl-truststore=
> server-ssl-truststore-password=
> socket-buffer-size=32768
> socket-lease-time=60000
> ssl-ciphers=any
> ssl-enabled=false
> ssl-protocols=any
> ssl-require-authentication=true
> start-dev-rest-api=false
> start-locator=
> statistic-archive-file=
> statistic-sample-rate=1000
> statistic-sampling-enabled=true
> tcp-port=0
> udp-fragment-size=60000
> udp-recv-buffer-size=1048576
> udp-send-buffer-size=65535
> user-command-packages=
> [info 2016/06/16 18:21:51.288 PDT <Test worker> tid=0xa] Starting membership services
> [info 2016/06/16 18:21:51.293 PDT <Test worker> tid=0xa] JGroups channel created (took 5ms)
> [info 2016/06/16 18:21:51.294 PDT <Test worker> tid=0xa] GemFire P2P Listener started on  null
> [info 2016/06/16 18:21:51.294 PDT <Geode Failure Detection Server thread 0> tid=0x2a23] Started failure detection server thread on cc2-rh6.gemstone.com/10.118.33.92:35320.
> [info 2016/06/16 18:21:51.297 PDT <Test worker> tid=0xa] Attempting to join the distributed system through coordinator cc2-rh6(14622:locator)<ec><v0>:1024 using address cc2-rh6(14590)<ec>:1025
> [locator][info 2016/06/16 18:21:51.297 PDT <unicast receiver,cc2-rh6-59330> tid=0x22] received join request from cc2-rh6(14590)<ec>:1025
> [locator][info 2016/06/16 18:21:51.497 PDT <Geode Membership View Creator> tid=0x28] View Creator is processing 3 requests for the next membership view
> [locator][info 2016/06/16 18:21:51.497 PDT <Geode Membership View Creator> tid=0x28] preparing new view View[cc2-rh6(14622:locator)<ec><v0>:1024|621] members: [cc2-rh6(14622:locator)<ec><v0>:1024, cc2-rh6(14590)<ec><v621>:1025{lead}]  shutdown: [cc2-rh6(14590)<ec><v620>:1025]
> [locator]failure detection ports: 7248 35320
> [info 2016/06/16 18:21:51.498 PDT <unicast receiver,cc2-rh6-28001> tid=0x2a1f] received new view: View[cc2-rh6(14622:locator)<ec><v0>:1024|621] members: [cc2-rh6(14622:locator)<ec><v0>:1024, cc2-rh6(14590)<ec><v621>:1025{lead}]  shutdown: [cc2-rh6(14590)<ec><v620>:1025]
> old view is: null
> [locator][info 2016/06/16 18:21:51.498 PDT <Geode Membership View Creator> tid=0x28] finished waiting for responses to view preparation
> [info 2016/06/16 18:21:51.498 PDT <Test worker> tid=0xa] Finished joining (took 204ms).
> [locator][info 2016/06/16 18:21:51.498 PDT <Geode Membership View Creator> tid=0x28] received new view: View[cc2-rh6(14622:locator)<ec><v0>:1024|621] members: [cc2-rh6(14622:locator)<ec><v0>:1024, cc2-rh6(14590)<ec><v621>:1025{lead}]  shutdown: [cc2-rh6(14590)<ec><v620>:1025]
> [locator]old view is: View[cc2-rh6(14622:locator)<ec><v0>:1024|620] members: [cc2-rh6(14622:locator)<ec><v0>:1024, cc2-rh6(14590)<ec><v620>:1025{lead}]  shutdown: [cc2-rh6(14590)<ec><v619>:1025]
> [locator][info 2016/06/16 18:21:51.498 PDT <Geode Membership View Creator> tid=0x28] Peer locator received new membership view: View[cc2-rh6(14622:locator)<ec><v0>:1024|621] members: [cc2-rh6(14622:locator)<ec><v0>:1024, cc2-rh6(14590)<ec><v621>:1025{lead}]  shutdown: [cc2-rh6(14590)<ec><v620>:1025]
> [info 2016/06/16 18:21:51.498 PDT <Test worker> tid=0xa] Starting DistributionManager cc2-rh6(14590)<ec><v621>:1025.  (took 210 ms)
> [locator][info 2016/06/16 18:21:51.499 PDT <Geode Membership View Creator> tid=0x28] sending new view View[cc2-rh6(14622:locator)<ec><v0>:1024|621] members: [cc2-rh6(14622:locator)<ec><v0>:1024, cc2-rh6(14590)<ec><v621>:1025{lead}]  shutdown: [cc2-rh6(14590)<ec><v620>:1025]
> [locator]failure detection ports: 7248 35320
> [locator][info 2016/06/16 18:21:51.499 PDT <View Message Processor> tid=0x33] Membership: Processing addition < cc2-rh6(14590)<ec><v621>:1025 >
> [locator][info 2016/06/16 18:21:51.499 PDT <View Message Processor> tid=0x33] Admitting member <cc2-rh6(14590)<ec><v621>:1025>. Now there are 2 non-admin member(s).
> [info 2016/06/16 18:21:51.500 PDT <Test worker> tid=0xa] Initial (distribution manager) view =  View[cc2-rh6(14622:locator)<ec><v0>:1024|621] members: [cc2-rh6(14622:locator)<ec><v0>:1024, cc2-rh6(14590)<ec><v621>:1025{lead}]  shutdown: [cc2-rh6(14590)<ec><v620>:1025]
> [info 2016/06/16 18:21:51.500 PDT <Test worker> tid=0xa] Admitting member <cc2-rh6(14622:locator)<ec><v0>:1024>. Now there are 1 non-admin member(s).
> [info 2016/06/16 18:21:51.500 PDT <Test worker> tid=0xa] Admitting member <cc2-rh6(14590)<ec><v621>:1025>. Now there are 2 non-admin member(s).
> [locator][info 2016/06/16 18:21:51.501 PDT <Pooled High Priority Message Processor 2> tid=0x6a] Member cc2-rh6(14590)<ec><v621>:1025 is equivalent or in the same redundancy zone.
> [info 2016/06/16 18:21:51.502 PDT <P2P message reader for cc2-rh6(14622:locator)<ec><v0>:1024 shared unordered uid=570 port=60770> tid=0x2a28] Member cc2-rh6(14622:locator)<ec><v0>:1024 is equivalent or in the same redundancy zone.
> [info 2016/06/16 18:21:51.507 PDT <Test worker> tid=0xa] Initializing region _monitoringRegion_10.118.33.92<v621>1025
> [info 2016/06/16 18:21:51.508 PDT <Test worker> tid=0xa] Initialization of region _monitoringRegion_10.118.33.92<v621>1025 completed
> [info 2016/06/16 18:21:51.510 PDT <Test worker> tid=0xa] The cache has been created with "use-cluster-configuration=false". It will not receive any cluster configuration
> [info 2016/06/16 18:21:51.510 PDT <Test worker> tid=0xa] Initializing region PdxTypes
> [info 2016/06/16 18:21:51.511 PDT <Test worker> tid=0xa] Initialization of region PdxTypes completed
> [info 2016/06/16 18:21:51.511 PDT <Test worker> tid=0xa] Initializing region root
> [info 2016/06/16 18:21:51.512 PDT <Test worker> tid=0xa] Initialization of region root completed
> [info 2016/06/16 18:21:51.512 PDT <Test worker> tid=0xa] Initializing region PreloadedRegionTestCase_testCustomEntryTtl3
> [info 2016/06/16 18:21:51.512 PDT <Test worker> tid=0xa] Initialization of region PreloadedRegionTestCase_testCustomEntryTtl3 completed
> [info 2016/06/16 18:21:51.604 PDT <Test worker> tid=0xa] GemFireCache[id = 498118297; isClosing = true; isShutDownAll = false; created = Thu Jun 16 18:21:51 PDT 2016; server = false; copyOnRead = false; lockLease = 120; lockTimeout = 60]: Now closing.
> [vm_0][info 2016/06/16 18:21:51.606 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$4/27964002.run with 0 args on object: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$4/27964002@1c13bd8b
> [vm_0][info 2016/06/16 18:21:51.607 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Got result: null
> [vm_0] from com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$4/27964002.run with 0 args on object: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$4/27964002@1c13bd8b (took 0 ms)
> [vm_1][info 2016/06/16 18:21:51.607 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$4/45568174.run with 0 args on object: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$4/45568174@2297c56f
> [vm_1][info 2016/06/16 18:21:51.607 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Got result: null
> [vm_1] from com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$4/45568174.run with 0 args on object: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$4/45568174@2297c56f (took 0 ms)
> [vm_2][info 2016/06/16 18:21:51.608 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$4/1542137447.run with 0 args on object: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$4/1542137447@5b68c0a9
> [vm_2][info 2016/06/16 18:21:51.608 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Got result: null
> [vm_2] from com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$4/1542137447.run with 0 args on object: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$4/1542137447@5b68c0a9 (took 0 ms)
> [vm_3][info 2016/06/16 18:21:51.609 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$4/27964002.run with 0 args on object: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$4/27964002@1c13bd8b
> [vm_3][info 2016/06/16 18:21:51.609 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Got result: null
> [vm_3] from com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$4/27964002.run with 0 args on object: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$4/27964002@1c13bd8b (took 0 ms)
> [vm_0][info 2016/06/16 18:21:51.609 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.cache30.PreloadedRegionTestCase.cleanup with 0 args
> [vm_0][info 2016/06/16 18:21:51.609 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Got result: null
> [vm_0] from com.gemstone.gemfire.cache30.PreloadedRegionTestCase.cleanup with 0 args (took 0 ms)
> [vm_1][info 2016/06/16 18:21:51.610 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.cache30.PreloadedRegionTestCase.cleanup with 0 args
> [vm_1][info 2016/06/16 18:21:51.610 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Got result: null
> [vm_1] from com.gemstone.gemfire.cache30.PreloadedRegionTestCase.cleanup with 0 args (took 0 ms)
> [vm_2][info 2016/06/16 18:21:51.610 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.cache30.PreloadedRegionTestCase.cleanup with 0 args
> [vm_2][info 2016/06/16 18:21:51.610 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Got result: null
> [vm_2] from com.gemstone.gemfire.cache30.PreloadedRegionTestCase.cleanup with 0 args (took 0 ms)
> [vm_3][info 2016/06/16 18:21:51.610 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.cache30.PreloadedRegionTestCase.cleanup with 0 args
> [vm_3][info 2016/06/16 18:21:51.610 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Got result: null
> [vm_3] from com.gemstone.gemfire.cache30.PreloadedRegionTestCase.cleanup with 0 args (took 0 ms)
> [vm_0][info 2016/06/16 18:21:51.611 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/210810896.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/210810896@1d771b38
> [vm_0][info 2016/06/16 18:21:51.611 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Got result: null
> [vm_0] from com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/210810896.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/210810896@1d771b38 (took 0 ms)
> [vm_1][info 2016/06/16 18:21:51.611 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/1141997112.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/1141997112@31fb0322
> [vm_1][info 2016/06/16 18:21:51.611 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Got result: null
> [vm_1] from com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/1141997112.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/1141997112@31fb0322 (took 0 ms)
> [vm_2][info 2016/06/16 18:21:51.611 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/45568785.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/45568785@516b65d1
> [vm_2][info 2016/06/16 18:21:51.611 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Got result: null
> [vm_2] from com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/45568785.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/45568785@516b65d1 (took 0 ms)
> [vm_3][info 2016/06/16 18:21:51.612 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/210810896.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/210810896@1d771b38
> [vm_3][info 2016/06/16 18:21:51.612 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Got result: null
> [vm_3] from com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/210810896.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/210810896@1d771b38 (took 0 ms)
> [info 2016/06/16 18:21:51.612 PDT <Test worker> tid=0xa] Shutting down DistributionManager cc2-rh6(14590)<ec><v621>:1025. 
> [locator][info 2016/06/16 18:21:51.613 PDT <Pooled High Priority Message Processor 2> tid=0x6a] received leave request from cc2-rh6(14590)<ec><v621>:1025 for cc2-rh6(14590)<ec><v621>:1025
> [locator][info 2016/06/16 18:21:51.613 PDT <Pooled High Priority Message Processor 2> tid=0x6a] Member at cc2-rh6(14590)<ec><v621>:1025 gracefully left the distributed cache: shutdown message received
> [info 2016/06/16 18:21:51.714 PDT <Test worker> tid=0xa] Now closing distribution for cc2-rh6(14590)<ec><v621>:1025
> [info 2016/06/16 18:21:51.714 PDT <Test worker> tid=0xa] Stopping membership services
> [locator][info 2016/06/16 18:21:51.714 PDT <unicast receiver,cc2-rh6-59330> tid=0x22] received leave request from cc2-rh6(14590)<ec><v621>:1025 for cc2-rh6(14590)<ec><v621>:1025
> [info 2016/06/16 18:21:51.714 PDT <Test worker> tid=0xa] GMSHealthMonitor server socket is closed in stopServices().
> [info 2016/06/16 18:21:51.714 PDT <Geode Failure Detection Server thread 0> tid=0x2a23] GMSHealthMonitor server thread exiting
> [info 2016/06/16 18:21:51.714 PDT <Test worker> tid=0xa] GMSHealthMonitor serverSocketExecutor is terminated
> [info 2016/06/16 18:21:51.716 PDT <Test worker> tid=0xa] DistributionManager stopped in 104ms.
> [info 2016/06/16 18:21:51.716 PDT <Test worker> tid=0xa] Marking DistributionManager cc2-rh6(14590)<ec><v621>:1025 as closed.
> [vm_0][info 2016/06/16 18:21:51.716 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$7/138308732.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$7/138308732@1671553
> [vm_0][info 2016/06/16 18:21:51.717 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Got result: null
> [vm_0] from com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$7/138308732.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$7/138308732@1671553 (took 0 ms)
> [vm_1][info 2016/06/16 18:21:51.717 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$7/1870846827.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$7/1870846827@20ad881d
> [vm_1][info 2016/06/16 18:21:51.717 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Got result: null
> [vm_1] from com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$7/1870846827.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$7/1870846827@20ad881d (took 0 ms)
> [vm_2][info 2016/06/16 18:21:51.717 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$7/1017410477.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$7/1017410477@4b380f3
> [vm_2][info 2016/06/16 18:21:51.717 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Got result: null
> [vm_2] from com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$7/1017410477.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$7/1017410477@4b380f3 (took 0 ms)
> [vm_3][info 2016/06/16 18:21:51.718 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$7/138308732.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$7/138308732@1671553
> [vm_3][info 2016/06/16 18:21:51.718 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Got result: null
> [vm_3] from com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$7/138308732.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$7/138308732@1671553 (took 0 ms)
> [locator][info 2016/06/16 18:21:51.718 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$5/871632238.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$5/871632238@3cb7c778
> [locator][info 2016/06/16 18:21:51.719 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Got result: null
> [locator] from com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$5/871632238.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$5/871632238@3cb7c778 (took 0 ms)
> [vm_0][info 2016/06/16 18:21:51.719 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$8/453849416.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$8/453849416@50819e55
> [vm_0][info 2016/06/16 18:21:51.719 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Got result: null
> [vm_0] from com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$8/453849416.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$8/453849416@50819e55 (took 0 ms)
> [vm_1][info 2016/06/16 18:21:51.720 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$8/1096729101.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$8/1096729101@4bed3031
> [vm_1][info 2016/06/16 18:21:51.720 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Got result: null
> [vm_1] from com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$8/1096729101.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$8/1096729101@4bed3031 (took 0 ms)
> [vm_2][info 2016/06/16 18:21:51.720 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$8/1997029186.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$8/1997029186@7f7a44b2
> [vm_2][info 2016/06/16 18:21:51.720 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Got result: null
> [vm_2] from com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$8/1997029186.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$8/1997029186@7f7a44b2 (took 0 ms)
> [vm_3][info 2016/06/16 18:21:51.720 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$8/453849416.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$8/453849416@50819e55
> [vm_3][info 2016/06/16 18:21:51.720 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Got result: null
> [vm_3] from com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$8/453849416.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$8/453849416@50819e55 (took 0 ms)
> Standard Error
> testValues region name is PreloadedRegionTestCase_testValues



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

Mime
View raw message