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-1708) CompiledInDUnitTest.whenASingleEnumBindParameterIsUsedWithInQueryAndMapIndexIsPresentReturnCorrectResults
Date Sat, 01 Oct 2016 19:15:20 GMT

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

Anthony Baker closed GEODE-1708.
--------------------------------

> CompiledInDUnitTest.whenASingleEnumBindParameterIsUsedWithInQueryAndMapIndexIsPresentReturnCorrectResults
> ---------------------------------------------------------------------------------------------------------
>
>                 Key: GEODE-1708
>                 URL: https://issues.apache.org/jira/browse/GEODE-1708
>             Project: Geode
>          Issue Type: Bug
>            Reporter: Kevin Duling
>              Labels: CI
>
> In Geode_develop_DistributedTests/3423:
> {noformat}
> Error Message
> com.gemstone.gemfire.test.dunit.RMIException: While invoking com.gemstone.gemfire.cache.query.dunit.CompiledInDUnitTest$25.run in VM 1 running on Host cc2-rh6.gemstone.com with 4 VMs
> Stacktrace
> com.gemstone.gemfire.test.dunit.RMIException: While invoking com.gemstone.gemfire.cache.query.dunit.CompiledInDUnitTest$25.run in VM 1 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.invoke(VM.java:355)
> 	at com.gemstone.gemfire.test.dunit.VM.invoke(VM.java:293)
> 	at com.gemstone.gemfire.cache.query.dunit.CompiledInDUnitTest.whenASingleEnumBindParameterIsUsedWithInQueryAndMapIndexIsPresentReturnCorrectResults(CompiledInDUnitTest.java:197)
> 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
> 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
> 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
> 	at java.lang.reflect.Method.invoke(Method.java:497)
> 	at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:50)
> 	at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
> 	at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:47)
> 	at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
> 	at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:26)
> 	at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:27)
> 	at org.junit.rules.TestWatcher$1.evaluate(TestWatcher.java:55)
> 	at org.junit.rules.RunRules.evaluate(RunRules.java:20)
> 	at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:325)
> 	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:78)
> 	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:57)
> 	at org.junit.runners.ParentRunner$3.run(ParentRunner.java:290)
> 	at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:71)
> 	at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:288)
> 	at org.junit.runners.ParentRunner.access$000(ParentRunner.java:58)
> 	at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:268)
> 	at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:26)
> 	at org.junit.runners.ParentRunner.run(ParentRunner.java:363)
> 	at org.gradle.api.internal.tasks.testing.junit.JUnitTestClassExecuter.runTestClass(JUnitTestClassExecuter.java:112)
> 	at org.gradle.api.internal.tasks.testing.junit.JUnitTestClassExecuter.execute(JUnitTestClassExecuter.java:56)
> 	at org.gradle.api.internal.tasks.testing.junit.JUnitTestClassProcessor.processTestClass(JUnitTestClassProcessor.java:66)
> 	at org.gradle.api.internal.tasks.testing.SuiteTestClassProcessor.processTestClass(SuiteTestClassProcessor.java:51)
> 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
> 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
> 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
> 	at java.lang.reflect.Method.invoke(Method.java:497)
> 	at org.gradle.messaging.dispatch.ReflectionDispatch.dispatch(ReflectionDispatch.java:35)
> 	at org.gradle.messaging.dispatch.ReflectionDispatch.dispatch(ReflectionDispatch.java:24)
> 	at org.gradle.messaging.dispatch.ContextClassLoaderDispatch.dispatch(ContextClassLoaderDispatch.java:32)
> 	at org.gradle.messaging.dispatch.ProxyDispatchAdapter$DispatchingInvocationHandler.invoke(ProxyDispatchAdapter.java:93)
> 	at com.sun.proxy.$Proxy2.processTestClass(Unknown Source)
> 	at org.gradle.api.internal.tasks.testing.worker.TestWorker.processTestClass(TestWorker.java:109)
> 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
> 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
> 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
> 	at java.lang.reflect.Method.invoke(Method.java:497)
> 	at org.gradle.messaging.dispatch.ReflectionDispatch.dispatch(ReflectionDispatch.java:35)
> 	at org.gradle.messaging.dispatch.ReflectionDispatch.dispatch(ReflectionDispatch.java:24)
> 	at org.gradle.messaging.remote.internal.hub.MessageHub$Handler.run(MessageHub.java:360)
> 	at org.gradle.internal.concurrent.ExecutorPolicy$CatchAndRecordFailures.onExecute(ExecutorPolicy.java:54)
> 	at org.gradle.internal.concurrent.StoppableExecutorImpl$1.run(StoppableExecutorImpl.java:40)
> 	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
> 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
> 	at java.lang.Thread.run(Thread.java:745)
> Caused by: java.lang.AssertionError: expected:<5> but was:<4>
> 	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.cache.query.dunit.CompiledInDUnitTest.verifyQuery(CompiledInDUnitTest.java:653)
> 	at com.gemstone.gemfire.cache.query.dunit.CompiledInDUnitTest$25.run2(CompiledInDUnitTest.java:607)
> 	at com.gemstone.gemfire.cache30.CacheSerializableRunnable.run(CacheSerializableRunnable.java:60)
> 	at sun.reflect.GeneratedMethodAccessor369.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.GeneratedMethodAccessor10.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: [GroupByPartitionedQueryDUnitTest, CompiledInDUnitTest]
> [vm_0][info 2016/07/27 11:57:47.793 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/07/27 11:57:47.793 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/07/27 11:57:47.794 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/07/27 11:57:47.794 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/07/27 11:57:47.795 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/07/27 11:57:47.795 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/07/27 11:57:47.796 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/07/27 11:57:47.796 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 CompiledInDUnitTest.whenUsingAccessorMultipleEnumBindParametersAreUsedWithInQueryInPartitionRegionReturnCorrectResults
> [vm_0][info 2016/07/27 11:57:47.835 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.cache.query.dunit.CompiledInDUnitTest$20.run with 0 args on object: "Create Bridge Server"
> [vm_0]distributed system properties: {locators=localhost[59664], enable-cluster-configuration=false, use-cluster-configuration=false, mcast-port=0, disable-auto-reconnect=true, log-level=info}
> [vm_0][info 2016/07/27 11:57:47.838 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[59664]
> [vm_0]log-level=info
> [vm_0]mcast-port=0
> [vm_0]use-cluster-configuration=false
> [vm_0]### GemFire Properties using default values ###
> [vm_0]ack-severe-alert-threshold=0
> [vm_0]ack-wait-threshold=15
> [vm_0]archive-disk-space-limit=0
> [vm_0]archive-file-size-limit=0
> [vm_0]async-distribution-timeout=0
> [vm_0]async-max-queue-size=8
> [vm_0]async-queue-timeout=60000
> [vm_0]bind-address=
> [vm_0]cache-xml-file=cache.xml
> [vm_0]cluster-configuration-dir=
> [vm_0]cluster-ssl-ciphers=any
> [vm_0]cluster-ssl-enabled=false
> [vm_0]cluster-ssl-keystore=
> [vm_0]cluster-ssl-keystore-password=
> [vm_0]cluster-ssl-keystore-type=
> [vm_0]cluster-ssl-protocols=any
> [vm_0]cluster-ssl-require-authentication=true
> [vm_0]cluster-ssl-truststore=
> [vm_0]cluster-ssl-truststore-password=
> [vm_0]conflate-events=server
> [vm_0]conserve-sockets=true
> [vm_0]delta-propagation=true
> [vm_0]deploy-working-dir=.
> [vm_0]disable-tcp=false
> [vm_0]distributed-system-id=-1
> [vm_0]distributed-transactions=false
> [vm_0]durable-client-id=
> [vm_0]durable-client-timeout=300
> [vm_0]enable-network-partition-detection=false
> [vm_0]enable-time-statistics=false
> [vm_0]enforce-unique-host=false
> [vm_0]gateway-ssl-ciphers=any
> [vm_0]gateway-ssl-enabled=false
> [vm_0]gateway-ssl-keystore=
> [vm_0]gateway-ssl-keystore-password=
> [vm_0]gateway-ssl-keystore-type=
> [vm_0]gateway-ssl-protocols=any
> [vm_0]gateway-ssl-require-authentication=true
> [vm_0]gateway-ssl-truststore=
> [vm_0]gateway-ssl-truststore-password=
> [vm_0]groups=
> [vm_0]http-service-bind-address=
> [vm_0]http-service-port=7070
> [vm_0]http-service-ssl-ciphers=any
> [vm_0]http-service-ssl-enabled=false
> [vm_0]http-service-ssl-keystore=
> [vm_0]http-service-ssl-keystore-password=
> [vm_0]http-service-ssl-keystore-type=
> [vm_0]http-service-ssl-protocols=any
> [vm_0]http-service-ssl-require-authentication=false
> [vm_0]http-service-ssl-truststore=
> [vm_0]http-service-ssl-truststore-password=
> [vm_0]jmx-manager=false
> [vm_0]jmx-manager-access-file=
> [vm_0]jmx-manager-bind-address=
> [vm_0]jmx-manager-hostname-for-clients=
> [vm_0]jmx-manager-http-port=7070
> [vm_0]jmx-manager-password-file=
> [vm_0]jmx-manager-port=1099
> [vm_0]jmx-manager-ssl=false
> [vm_0]jmx-manager-ssl-ciphers=any
> [vm_0]jmx-manager-ssl-enabled=false
> [vm_0]jmx-manager-ssl-keystore=
> [vm_0]jmx-manager-ssl-keystore-password=
> [vm_0]jmx-manager-ssl-keystore-type=
> [vm_0]jmx-manager-ssl-protocols=any
> [vm_0]jmx-manager-ssl-require-authentication=true
> [vm_0]jmx-manager-ssl-truststore=
> [vm_0]jmx-manager-ssl-truststore-password=
> [vm_0]jmx-manager-start=false
> [vm_0]jmx-manager-update-rate=2000
> [vm_0]load-cluster-configuration-from-dir=false
> [vm_0]locator-wait-time=0
> [vm_0]lock-memory=false
> [vm_0]log-disk-space-limit=0
> [vm_0]log-file=
> [vm_0]log-file-size-limit=0
> [vm_0]max-num-reconnect-tries=3
> [vm_0]max-wait-time-reconnect=60000
> [vm_0]mcast-address=239.192.81.1
> [vm_0]mcast-flow-control=1048576, 0.25, 5000
> [vm_0]mcast-recv-buffer-size=1048576
> [vm_0]mcast-send-buffer-size=65535
> [vm_0]mcast-ttl=32
> [vm_0]member-timeout=5000
> [vm_0]membership-port-range=1024-65535
> [vm_0]memcached-bind-address=
> [vm_0]memcached-port=0
> [vm_0]memcached-protocol=ASCII
> [vm_0]name=
> [vm_0]off-heap-memory-size=
> [vm_0]redis-bind-address=
> [vm_0]redis-password=
> [vm_0]redis-port=0
> [vm_0]redundancy-zone=
> [vm_0]remote-locators=
> [vm_0]remove-unresponsive-client=false
> [vm_0]roles=
> [vm_0]security-client-accessor=
> [vm_0]security-client-accessor-pp=
> [vm_0]security-client-auth-init=
> [vm_0]security-client-authenticator=
> [vm_0]security-client-dhalgo=
> [vm_0]security-log-file=
> [vm_0]security-log-level=config
> [vm_0]security-manager=
> [vm_0]security-peer-auth-init=
> [vm_0]security-peer-authenticator=
> [vm_0]security-peer-verifymember-timeout=1000
> [vm_0]security-post-processor=
> [vm_0]server-bind-address=
> [vm_0]server-ssl-ciphers=any
> [vm_0]server-ssl-enabled=false
> [vm_0]server-ssl-keystore=
> [vm_0]server-ssl-keystore-password=
> [vm_0]server-ssl-keystore-type=
> [vm_0]server-ssl-protocols=any
> [vm_0]server-ssl-require-authentication=true
> [vm_0]server-ssl-truststore=
> [vm_0]server-ssl-truststore-password=
> [vm_0]socket-buffer-size=32768
> [vm_0]socket-lease-time=60000
> [vm_0]ssl-ciphers=any
> [vm_0]ssl-enabled=false
> [vm_0]ssl-protocols=any
> [vm_0]ssl-require-authentication=true
> [vm_0]start-dev-rest-api=false
> [vm_0]start-locator=
> [vm_0]statistic-archive-file=
> [vm_0]statistic-sample-rate=1000
> [vm_0]statistic-sampling-enabled=true
> [vm_0]tcp-port=0
> [vm_0]udp-fragment-size=60000
> [vm_0]udp-recv-buffer-size=1048576
> [vm_0]udp-send-buffer-size=65535
> [vm_0]user-command-packages=
> [vm_0][info 2016/07/27 11:57:47.840 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Starting membership services
> [vm_0][info 2016/07/27 11:57:47.849 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] JGroups channel created (took 9ms)
> [vm_0][info 2016/07/27 11:57:47.850 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] GemFire P2P Listener started on  null
> [vm_0][info 2016/07/27 11:57:47.852 PDT <Geode Failure Detection Server thread 0> tid=0x551] Started failure detection server thread on cc2-rh6.gemstone.com/10.118.33.92:51777.
> [vm_0][info 2016/07/27 11:57:47.853 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Attempting to join the distributed system through coordinator cc2-rh6(8979:locator)<ec><v0>:1024 using address cc2-rh6(9046)<ec>:1025
> [vm_0][info 2016/07/27 11:57:47.854 PDT <unicast receiver,cc2-rh6-20757> tid=0x54d] received new view: View[cc2-rh6(8979:locator)<ec><v0>:1024|118] members: [cc2-rh6(8979:locator)<ec><v0>:1024, cc2-rh6(9046)<ec><v118>:1025{lead}]  shutdown: [cc2-rh6(9055)<ec><v114>:1027, cc2-rh6(9060)<ec><v115>:1028]
> [vm_0]old view is: null
> [locator][info 2016/07/27 11:57:47.853 PDT <unicast receiver,cc2-rh6-63288> tid=0x21] received join request from cc2-rh6(9046)<ec>:1025
> [locator][info 2016/07/27 11:57:47.853 PDT <Geode Membership View Creator> tid=0x27] View Creator is processing 6 requests for the next membership view
> [locator][info 2016/07/27 11:57:47.854 PDT <Geode Membership View Creator> tid=0x27] preparing new view View[cc2-rh6(8979:locator)<ec><v0>:1024|118] members: [cc2-rh6(8979:locator)<ec><v0>:1024, cc2-rh6(9046)<ec><v118>:1025{lead}]  shutdown: [cc2-rh6(9060)<ec><v115>:1028, cc2-rh6(9055)<ec><v114>:1027]
> [locator]failure detection ports: 17096 51777
> [locator][info 2016/07/27 11:57:47.855 PDT <Geode Membership View Creator> tid=0x27] finished waiting for responses to view preparation
> [locator][info 2016/07/27 11:57:47.855 PDT <Geode Membership View Creator> tid=0x27] received new view: View[cc2-rh6(8979:locator)<ec><v0>:1024|118] members: [cc2-rh6(8979:locator)<ec><v0>:1024, cc2-rh6(9046)<ec><v118>:1025{lead}]  shutdown: [cc2-rh6(9060)<ec><v115>:1028, cc2-rh6(9055)<ec><v114>:1027]
> [locator]old view is: View[cc2-rh6(8979:locator)<ec><v0>:1024|117] members: [cc2-rh6(8979:locator)<ec><v0>:1024, cc2-rh6(9055)<ec><v114>:1027{lead}, cc2-rh6(9060)<ec><v115>:1028]  shutdown: [cc2-rh6(9046)<ec><v116>:1029, cc2-rh6(9050)<ec><v113>:1026, cc2-rh6(8950)<ec><v112>:1025]
> [locator][info 2016/07/27 11:57:47.855 PDT <Geode Membership View Creator> tid=0x27] Peer locator received new membership view: View[cc2-rh6(8979:locator)<ec><v0>:1024|118] members: [cc2-rh6(8979:locator)<ec><v0>:1024, cc2-rh6(9046)<ec><v118>:1025{lead}]  shutdown: [cc2-rh6(9060)<ec><v115>:1028, cc2-rh6(9055)<ec><v114>:1027]
> [locator][info 2016/07/27 11:57:47.855 PDT <Geode Membership View Creator> tid=0x27] sending new view View[cc2-rh6(8979:locator)<ec><v0>:1024|118] members: [cc2-rh6(8979:locator)<ec><v0>:1024, cc2-rh6(9046)<ec><v118>:1025{lead}]  shutdown: [cc2-rh6(9060)<ec><v115>:1028, cc2-rh6(9055)<ec><v114>:1027]
> [locator]failure detection ports: 17096 51777
> [vm_0][info 2016/07/27 11:57:47.856 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Finished joining (took 6ms).
> [vm_0][info 2016/07/27 11:57:47.856 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Starting DistributionManager cc2-rh6(9046)<ec><v118>:1025.  (took 17 ms)
> [locator][info 2016/07/27 11:57:47.856 PDT <View Message Processor> tid=0x33] Membership: Processing addition < cc2-rh6(9046)<ec><v118>:1025 >
> [locator][info 2016/07/27 11:57:47.856 PDT <View Message Processor> tid=0x33] Admitting member <cc2-rh6(9046)<ec><v118>:1025>. Now there are 2 non-admin member(s).
> [vm_0][info 2016/07/27 11:57:47.858 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Initial (distribution manager) view =  View[cc2-rh6(8979:locator)<ec><v0>:1024|118] members: [cc2-rh6(8979:locator)<ec><v0>:1024, cc2-rh6(9046)<ec><v118>:1025{lead}]  shutdown: [cc2-rh6(9060)<ec><v115>:1028, cc2-rh6(9055)<ec><v114>:1027]
> [vm_0][info 2016/07/27 11:57:47.858 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Admitting member <cc2-rh6(8979:locator)<ec><v0>:1024>. Now there are 1 non-admin member(s).
> [vm_0][info 2016/07/27 11:57:47.858 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Admitting member <cc2-rh6(9046)<ec><v118>:1025>. Now there are 2 non-admin member(s).
> [locator][info 2016/07/27 11:57:47.865 PDT <Pooled High Priority Message Processor 2> tid=0x5f] Member cc2-rh6(9046)<ec><v118>:1025 is equivalent or in the same redundancy zone.
> [vm_0][info 2016/07/27 11:57:47.868 PDT <P2P message reader for cc2-rh6(8979:locator)<ec><v0>:1024 shared unordered uid=96 port=34732> tid=0x556] Member cc2-rh6(8979:locator)<ec><v0>:1024 is equivalent or in the same redundancy zone.
> [vm_0][info 2016/07/27 11:57:47.884 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Initializing region _monitoringRegion_10.118.33.92<v118>1025
> [vm_0][info 2016/07/27 11:57:47.890 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Initialization of region _monitoringRegion_10.118.33.92<v118>1025 completed
> [vm_0][info 2016/07/27 11:57:47.898 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/07/27 11:57:47.900 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Initializing region PdxTypes
> [vm_0][info 2016/07/27 11:57:47.901 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Initialization of region PdxTypes completed
> [vm_0][info 2016/07/27 11:57:47.921 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Cache server connection listener bound to address 0.0.0.0/0.0.0.0:58363 with backlog 1,000.
> [vm_0][info 2016/07/27 11:57:47.936 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] ClientHealthMonitorThread maximum allowed time between pings: 60,000
> [vm_0][info 2016/07/27 11:57:47.945 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] CacheServer Configuration:   port=58363 max-connections=800 max-threads=0 notify-by-subscription=true socket-buffer-size=32768 maximum-time-between-pings=60000 maximum-message-count=230000 message-time-to-live=180 eviction-policy=none capacity=1 overflow directory=. groups=[] loadProbe=ConnectionCountProbe loadPollInterval=5000 tcpNoDelay=true
> [vm_0][info 2016/07/27 11:57:47.994 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Initializing region __PR
> [vm_0][info 2016/07/27 11:57:47.995 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Initialization of region __PR completed
> [vm_0][info 2016/07/27 11:57:48.040 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Partitioned Region /root/PdxTest is born with prId=20 ident:#root#PdxTest
> [vm_0][info 2016/07/27 11:57:48.094 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Initializing region _B__root_PdxTest_17
> [vm_0][info 2016/07/27 11:57:48.094 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Initialization of region _B__root_PdxTest_17 completed
> [vm_0][info 2016/07/27 11:57:48.111 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Initializing region _B__root_PdxTest_18
> [vm_0][info 2016/07/27 11:57:48.112 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Initialization of region _B__root_PdxTest_18 completed
> [vm_0][info 2016/07/27 11:57:48.114 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Initializing region _B__root_PdxTest_19
> [vm_0][info 2016/07/27 11:57:48.115 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Initialization of region _B__root_PdxTest_19 completed
> [vm_0][info 2016/07/27 11:57:48.117 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Initializing region _B__root_PdxTest_0
> [vm_0][info 2016/07/27 11:57:48.117 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Initialization of region _B__root_PdxTest_0 completed
> [vm_0][info 2016/07/27 11:57:48.119 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Initializing region _B__root_PdxTest_1
> [vm_0][info 2016/07/27 11:57:48.119 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Initialization of region _B__root_PdxTest_1 completed
> [vm_0][info 2016/07/27 11:57:48.122 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Initializing region _B__root_PdxTest_2
> [vm_0][info 2016/07/27 11:57:48.122 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Initialization of region _B__root_PdxTest_2 completed
> [vm_0][info 2016/07/27 11:57:48.126 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Initializing region _B__root_PdxTest_3
> [vm_0][info 2016/07/27 11:57:48.126 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Initialization of region _B__root_PdxTest_3 completed
> [vm_0][info 2016/07/27 11:57:48.129 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Initializing region _B__root_PdxTest_4
> [vm_0][info 2016/07/27 11:57:48.129 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Initialization of region _B__root_PdxTest_4 completed
> [vm_0][info 2016/07/27 11:57:48.132 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Initializing region _B__root_PdxTest_5
> [vm_0][info 2016/07/27 11:57:48.132 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Initialization of region _B__root_PdxTest_5 completed
> [vm_0][info 2016/07/27 11:57:48.135 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Initializing region _B__root_PdxTest_6
> [vm_0][info 2016/07/27 11:57:48.135 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Initialization of region _B__root_PdxTest_6 completed
> [vm_0][info 2016/07/27 11:57:48.136 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Got result: null
> [vm_0] from com.gemstone.gemfire.cache.query.dunit.CompiledInDUnitTest$20.run with 0 args on object: "Create Bridge Server" (took 301 ms)
> [vm_1][info 2016/07/27 11:57:48.182 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.cache.query.dunit.CompiledInDUnitTest$27.run with 0 args on object: "Execute Query with Accessor"
> [vm_1]distributed system properties: {locators=localhost[59664], enable-cluster-configuration=false, use-cluster-configuration=false, mcast-port=0, disable-auto-reconnect=true, log-level=info}
> [vm_1][info 2016/07/27 11:57:48.185 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Startup Configuration:
> [vm_1] ### GemFire Properties defined with api ###
> [vm_1]disable-auto-reconnect=true
> [vm_1]enable-cluster-configuration=false
> [vm_1]locators=localhost[59664]
> [vm_1]log-level=info
> [vm_1]mcast-port=0
> [vm_1]use-cluster-configuration=false
> [vm_1]### GemFire Properties using default values ###
> [vm_1]ack-severe-alert-threshold=0
> [vm_1]ack-wait-threshold=15
> [vm_1]archive-disk-space-limit=0
> [vm_1]archive-file-size-limit=0
> [vm_1]async-distribution-timeout=0
> [vm_1]async-max-queue-size=8
> [vm_1]async-queue-timeout=60000
> [vm_1]bind-address=
> [vm_1]cache-xml-file=cache.xml
> [vm_1]cluster-configuration-dir=
> [vm_1]cluster-ssl-ciphers=any
> [vm_1]cluster-ssl-enabled=false
> [vm_1]cluster-ssl-keystore=
> [vm_1]cluster-ssl-keystore-password=
> [vm_1]cluster-ssl-keystore-type=
> [vm_1]cluster-ssl-protocols=any
> [vm_1]cluster-ssl-require-authentication=true
> [vm_1]cluster-ssl-truststore=
> [vm_1]cluster-ssl-truststore-password=
> [vm_1]conflate-events=server
> [vm_1]conserve-sockets=true
> [vm_1]delta-propagation=true
> [vm_1]deploy-working-dir=.
> [vm_1]disable-tcp=false
> [vm_1]distributed-system-id=-1
> [vm_1]distributed-transactions=false
> [vm_1]durable-client-id=
> [vm_1]durable-client-timeout=300
> [vm_1]enable-network-partition-detection=false
> [vm_1]enable-time-statistics=false
> [vm_1]enforce-unique-host=false
> [vm_1]gateway-ssl-ciphers=any
> [vm_1]gateway-ssl-enabled=false
> [vm_1]gateway-ssl-keystore=
> [vm_1]gateway-ssl-keystore-password=
> [vm_1]gateway-ssl-keystore-type=
> [vm_1]gateway-ssl-protocols=any
> [vm_1]gateway-ssl-require-authentication=true
> [vm_1]gateway-ssl-truststore=
> [vm_1]gateway-ssl-truststore-password=
> [vm_1]groups=
> [vm_1]http-service-bind-address=
> [vm_1]http-service-port=7070
> [vm_1]http-service-ssl-ciphers=any
> [vm_1]http-service-ssl-enabled=false
> [vm_1]http-service-ssl-keystore=
> [vm_1]http-service-ssl-keystore-password=
> [vm_1]http-service-ssl-keystore-type=
> [vm_1]http-service-ssl-protocols=any
> [vm_1]http-service-ssl-require-authentication=false
> [vm_1]http-service-ssl-truststore=
> [vm_1]http-service-ssl-truststore-password=
> [vm_1]jmx-manager=false
> [vm_1]jmx-manager-access-file=
> [vm_1]jmx-manager-bind-address=
> [vm_1]jmx-manager-hostname-for-clients=
> [vm_1]jmx-manager-http-port=7070
> [vm_1]jmx-manager-password-file=
> [vm_1]jmx-manager-port=1099
> [vm_1]jmx-manager-ssl=false
> [vm_1]jmx-manager-ssl-ciphers=any
> [vm_1]jmx-manager-ssl-enabled=false
> [vm_1]jmx-manager-ssl-keystore=
> [vm_1]jmx-manager-ssl-keystore-password=
> [vm_1]jmx-manager-ssl-keystore-type=
> [vm_1]jmx-manager-ssl-protocols=any
> [vm_1]jmx-manager-ssl-require-authentication=true
> [vm_1]jmx-manager-ssl-truststore=
> [vm_1]jmx-manager-ssl-truststore-password=
> [vm_1]jmx-manager-start=false
> [vm_1]jmx-manager-update-rate=2000
> [vm_1]load-cluster-configuration-from-dir=false
> [vm_1]locator-wait-time=0
> [vm_1]lock-memory=false
> [vm_1]log-disk-space-limit=0
> [vm_1]log-file=
> [vm_1]log-file-size-limit=0
> [vm_1]max-num-reconnect-tries=3
> [vm_1]max-wait-time-reconnect=60000
> [vm_1]mcast-address=239.192.81.1
> [vm_1]mcast-flow-control=1048576, 0.25, 5000
> [vm_1]mcast-recv-buffer-size=1048576
> [vm_1]mcast-send-buffer-size=65535
> [vm_1]mcast-ttl=32
> [vm_1]member-timeout=5000
> [vm_1]membership-port-range=1024-65535
> [vm_1]memcached-bind-address=
> [vm_1]memcached-port=0
> [vm_1]memcached-protocol=ASCII
> [vm_1]name=
> [vm_1]off-heap-memory-size=
> [vm_1]redis-bind-address=
> [vm_1]redis-password=
> [vm_1]redis-port=0
> [vm_1]redundancy-zone=
> [vm_1]remote-locators=
> [vm_1]remove-unresponsive-client=false
> [vm_1]roles=
> [vm_1]security-client-accessor=
> [vm_1]security-client-accessor-pp=
> [vm_1]security-client-auth-init=
> [vm_1]security-client-authenticator=
> [vm_1]security-client-dhalgo=
> [vm_1]security-log-file=
> [vm_1]security-log-level=config
> [vm_1]security-manager=
> [vm_1]security-peer-auth-init=
> [vm_1]security-peer-authenticator=
> [vm_1]security-peer-verifymember-timeout=1000
> [vm_1]security-post-processor=
> [vm_1]server-bind-address=
> [vm_1]server-ssl-ciphers=any
> [vm_1]server-ssl-enabled=false
> [vm_1]server-ssl-keystore=
> [vm_1]server-ssl-keystore-password=
> [vm_1]server-ssl-keystore-type=
> [vm_1]server-ssl-protocols=any
> [vm_1]server-ssl-require-authentication=true
> [vm_1]server-ssl-truststore=
> [vm_1]server-ssl-truststore-password=
> [vm_1]socket-buffer-size=32768
> [vm_1]socket-lease-time=60000
> [vm_1]ssl-ciphers=any
> [vm_1]ssl-enabled=false
> [vm_1]ssl-protocols=any
> [vm_1]ssl-require-authentication=true
> [vm_1]start-dev-rest-api=false
> [vm_1]start-locator=
> [vm_1]statistic-archive-file=
> [vm_1]statistic-sample-rate=1000
> [vm_1]statistic-sampling-enabled=true
> [vm_1]tcp-port=0
> [vm_1]udp-fragment-size=60000
> [vm_1]udp-recv-buffer-size=1048576
> [vm_1]udp-send-buffer-size=65535
> [vm_1]user-command-packages=
> [vm_1][info 2016/07/27 11:57:48.188 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Starting membership services
> [vm_1][info 2016/07/27 11:57:48.205 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] JGroups channel created (took 17ms)
> [vm_1][info 2016/07/27 11:57:48.206 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] GemFire P2P Listener started on  null
> [vm_1][info 2016/07/27 11:57:48.206 PDT <Geode Failure Detection Server thread 0> tid=0x5c4] Started failure detection server thread on cc2-rh6.gemstone.com/10.118.33.92:58690.
> [vm_1][info 2016/07/27 11:57:48.213 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Attempting to join the distributed system through coordinator cc2-rh6(8979:locator)<ec><v0>:1024 using address cc2-rh6(9050)<ec>:1026
> [locator][info 2016/07/27 11:57:48.213 PDT <unicast receiver,cc2-rh6-63288> tid=0x21] received join request from cc2-rh6(9050)<ec>:1026
> [locator][info 2016/07/27 11:57:48.514 PDT <Geode Membership View Creator> tid=0x27] View Creator is processing 1 requests for the next membership view
> [locator][info 2016/07/27 11:57:48.514 PDT <Geode Membership View Creator> tid=0x27] preparing new view View[cc2-rh6(8979:locator)<ec><v0>:1024|119] members: [cc2-rh6(8979:locator)<ec><v0>:1024, cc2-rh6(9046)<ec><v118>:1025{lead}, cc2-rh6(9050)<ec><v119>:1026]
> [locator]failure detection ports: 17096 51777 58690
> [vm_1][info 2016/07/27 11:57:48.516 PDT <unicast receiver,cc2-rh6-2608> tid=0x5c0] received new view: View[cc2-rh6(8979:locator)<ec><v0>:1024|119] members: [cc2-rh6(8979:locator)<ec><v0>:1024, cc2-rh6(9046)<ec><v118>:1025{lead}, cc2-rh6(9050)<ec><v119>:1026]
> [vm_1]old view is: null
> [locator][info 2016/07/27 11:57:48.516 PDT <Geode Membership View Creator> tid=0x27] finished waiting for responses to view preparation
> [locator][info 2016/07/27 11:57:48.516 PDT <Geode Membership View Creator> tid=0x27] received new view: View[cc2-rh6(8979:locator)<ec><v0>:1024|119] members: [cc2-rh6(8979:locator)<ec><v0>:1024, cc2-rh6(9046)<ec><v118>:1025{lead}, cc2-rh6(9050)<ec><v119>:1026]
> [locator]old view is: View[cc2-rh6(8979:locator)<ec><v0>:1024|118] members: [cc2-rh6(8979:locator)<ec><v0>:1024, cc2-rh6(9046)<ec><v118>:1025{lead}]  shutdown: [cc2-rh6(9060)<ec><v115>:1028, cc2-rh6(9055)<ec><v114>:1027]
> [locator][info 2016/07/27 11:57:48.516 PDT <Geode Membership View Creator> tid=0x27] Peer locator received new membership view: View[cc2-rh6(8979:locator)<ec><v0>:1024|119] members: [cc2-rh6(8979:locator)<ec><v0>:1024, cc2-rh6(9046)<ec><v118>:1025{lead}, cc2-rh6(9050)<ec><v119>:1026]
> [locator][info 2016/07/27 11:57:48.517 PDT <Geode Membership View Creator> tid=0x27] sending new view View[cc2-rh6(8979:locator)<ec><v0>:1024|119] members: [cc2-rh6(8979:locator)<ec><v0>:1024, cc2-rh6(9046)<ec><v118>:1025{lead}, cc2-rh6(9050)<ec><v119>:1026]
> [locator]failure detection ports: 17096 51777 58690
> [locator][info 2016/07/27 11:57:48.517 PDT <View Message Processor> tid=0x33] Membership: Processing addition < cc2-rh6(9050)<ec><v119>:1026 >
> [locator][info 2016/07/27 11:57:48.517 PDT <View Message Processor> tid=0x33] Admitting member <cc2-rh6(9050)<ec><v119>:1026>. Now there are 3 non-admin member(s).
> [vm_0][info 2016/07/27 11:57:48.517 PDT <unicast receiver,cc2-rh6-20757> tid=0x54d] received new view: View[cc2-rh6(8979:locator)<ec><v0>:1024|119] members: [cc2-rh6(8979:locator)<ec><v0>:1024, cc2-rh6(9046)<ec><v118>:1025{lead}, cc2-rh6(9050)<ec><v119>:1026]
> [vm_0]old view is: View[cc2-rh6(8979:locator)<ec><v0>:1024|118] members: [cc2-rh6(8979:locator)<ec><v0>:1024, cc2-rh6(9046)<ec><v118>:1025{lead}]  shutdown: [cc2-rh6(9055)<ec><v114>:1027, cc2-rh6(9060)<ec><v115>:1028]
> [vm_1][info 2016/07/27 11:57:48.519 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Finished joining (took 313ms).
> [vm_1][info 2016/07/27 11:57:48.519 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Starting DistributionManager cc2-rh6(9050)<ec><v119>:1026.  (took 332 ms)
> [vm_0][info 2016/07/27 11:57:48.520 PDT <View Message Processor> tid=0x56a] Membership: Processing addition < cc2-rh6(9050)<ec><v119>:1026 >
> [vm_1][info 2016/07/27 11:57:48.520 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Initial (distribution manager) view =  View[cc2-rh6(8979:locator)<ec><v0>:1024|119] members: [cc2-rh6(8979:locator)<ec><v0>:1024, cc2-rh6(9046)<ec><v118>:1025{lead}, cc2-rh6(9050)<ec><v119>:1026]
> [vm_1][info 2016/07/27 11:57:48.520 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Admitting member <cc2-rh6(8979:locator)<ec><v0>:1024>. Now there are 1 non-admin member(s).
> [vm_1][info 2016/07/27 11:57:48.520 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Admitting member <cc2-rh6(9046)<ec><v118>:1025>. Now there are 2 non-admin member(s).
> [vm_1][info 2016/07/27 11:57:48.520 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Admitting member <cc2-rh6(9050)<ec><v119>:1026>. Now there are 3 non-admin member(s).
> [vm_0][info 2016/07/27 11:57:48.521 PDT <View Message Processor> tid=0x56a] Admitting member <cc2-rh6(9050)<ec><v119>:1026>. Now there are 3 non-admin member(s).
> [vm_0][info 2016/07/27 11:57:48.527 PDT <Pooled High Priority Message Processor 1> tid=0x561] Member cc2-rh6(9050)<ec><v119>:1026 is equivalent or in the same redundancy zone.
> [locator][info 2016/07/27 11:57:48.526 PDT <Pooled High Priority Message Processor 2> tid=0x5f] Member cc2-rh6(9050)<ec><v119>:1026 is equivalent or in the same redundancy zone.
> [vm_1][info 2016/07/27 11:57:48.531 PDT <P2P message reader for cc2-rh6(8979:locator)<ec><v0>:1024 shared unordered uid=97 port=36870> tid=0x5cb] Member cc2-rh6(8979:locator)<ec><v0>:1024 is equivalent or in the same redundancy zone.
> [vm_1][info 2016/07/27 11:57:48.541 PDT <P2P message reader for cc2-rh6(9046)<ec><v118>:1025 shared unordered uid=174 port=36871> tid=0x5cc] Member cc2-rh6(9046)<ec><v118>:1025 is equivalent or in the same redundancy zone.
> [vm_1][info 2016/07/27 11:57:48.558 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Initializing region _monitoringRegion_10.118.33.92<v119>1026
> [vm_1][info 2016/07/27 11:57:48.559 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Initialization of region _monitoringRegion_10.118.33.92<v119>1026 completed
> [vm_1][info 2016/07/27 11:57:48.568 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_1][info 2016/07/27 11:57:48.574 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Initializing region PdxTypes
> [vm_1][info 2016/07/27 11:57:48.580 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Region PdxTypes requesting initial image from cc2-rh6(9046)<ec><v118>:1025
> [vm_1][info 2016/07/27 11:57:48.584 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] PdxTypes is done getting image from cc2-rh6(9046)<ec><v118>:1025. isDeltaGII is false
> [vm_1][info 2016/07/27 11:57:48.584 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Initialization of region PdxTypes completed
> [vm_1][info 2016/07/27 11:57:48.602 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Cache server connection listener bound to address 0.0.0.0/0.0.0.0:43638 with backlog 1,000.
> [vm_1][info 2016/07/27 11:57:48.613 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] ClientHealthMonitorThread maximum allowed time between pings: 60,000
> [vm_1][info 2016/07/27 11:57:48.621 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] CacheServer Configuration:   port=43638 max-connections=800 max-threads=0 notify-by-subscription=true socket-buffer-size=32768 maximum-time-between-pings=60000 maximum-message-count=230000 message-time-to-live=180 eviction-policy=none capacity=1 overflow directory=. groups=[] loadProbe=ConnectionCountProbe loadPollInterval=5000 tcpNoDelay=true
> [vm_1][info 2016/07/27 11:57:48.634 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Initializing region __PR
> [vm_1][info 2016/07/27 11:57:48.638 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Region __PR requesting initial image from cc2-rh6(9046)<ec><v118>:1025
> [vm_1][info 2016/07/27 11:57:48.645 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] __PR is done getting image from cc2-rh6(9046)<ec><v118>:1025. isDeltaGII is false
> [vm_1][info 2016/07/27 11:57:48.645 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Initialization of region __PR completed
> [vm_1][info 2016/07/27 11:57:48.648 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Partitioned Region /root/PdxTest is created with prId=20
> [vm_0][info 2016/07/27 11:57:48.675 PDT <Pooled Waiting Message Processor 0> tid=0x553] Initializing region _B__root_PdxTest_7
> [vm_0][info 2016/07/27 11:57:48.675 PDT <Pooled Waiting Message Processor 0> tid=0x553] Initialization of region _B__root_PdxTest_7 completed
> [vm_0][info 2016/07/27 11:57:48.685 PDT <Pooled Waiting Message Processor 0> tid=0x553] Initializing region _B__root_PdxTest_8
> [vm_0][info 2016/07/27 11:57:48.685 PDT <Pooled Waiting Message Processor 0> tid=0x553] Initialization of region _B__root_PdxTest_8 completed
> [vm_0][info 2016/07/27 11:57:48.688 PDT <Pooled Waiting Message Processor 0> tid=0x553] Initializing region _B__root_PdxTest_9
> [vm_0][info 2016/07/27 11:57:48.689 PDT <Pooled Waiting Message Processor 0> tid=0x553] Initialization of region _B__root_PdxTest_9 completed
> [vm_0][info 2016/07/27 11:57:48.694 PDT <Pooled Waiting Message Processor 0> tid=0x553] Initializing region _B__root_PdxTest_10
> [vm_0][info 2016/07/27 11:57:48.694 PDT <Pooled Waiting Message Processor 0> tid=0x553] Initialization of region _B__root_PdxTest_10 completed
> [vm_0][info 2016/07/27 11:57:48.698 PDT <Pooled Waiting Message Processor 0> tid=0x553] Initializing region _B__root_PdxTest_11
> [vm_0][info 2016/07/27 11:57:48.698 PDT <Pooled Waiting Message Processor 0> tid=0x553] Initialization of region _B__root_PdxTest_11 completed
> [vm_0][info 2016/07/27 11:57:48.700 PDT <Pooled Waiting Message Processor 0> tid=0x553] Initializing region _B__root_PdxTest_12
> [vm_0][info 2016/07/27 11:57:48.701 PDT <Pooled Waiting Message Processor 0> tid=0x553] Initialization of region _B__root_PdxTest_12 completed
> [vm_0][info 2016/07/27 11:57:48.705 PDT <Pooled Waiting Message Processor 2> tid=0x570] Initializing region _B__root_PdxTest_13
> [vm_0][info 2016/07/27 11:57:48.705 PDT <Pooled Waiting Message Processor 2> tid=0x570] Initialization of region _B__root_PdxTest_13 completed
> [vm_0][info 2016/07/27 11:57:48.708 PDT <Pooled Waiting Message Processor 1> tid=0x56f] Initializing region _B__root_PdxTest_14
> [vm_0][info 2016/07/27 11:57:48.709 PDT <Pooled Waiting Message Processor 1> tid=0x56f] Initialization of region _B__root_PdxTest_14 completed
> [vm_0][info 2016/07/27 11:57:48.713 PDT <Pooled Waiting Message Processor 1> tid=0x56f] Initializing region _B__root_PdxTest_15
> [vm_0][info 2016/07/27 11:57:48.714 PDT <Pooled Waiting Message Processor 1> tid=0x56f] Initialization of region _B__root_PdxTest_15 completed
> [vm_0][info 2016/07/27 11:57:48.718 PDT <Pooled Waiting Message Processor 2> tid=0x570] Initializing region _B__root_PdxTest_16
> [vm_0][info 2016/07/27 11:57:48.718 PDT <Pooled Waiting Message Processor 2> tid=0x570] Initialization of region _B__root_PdxTest_16 completed
> [vm_1][info 2016/07/27 11:57:48.841 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Got result: null
> [vm_1] from com.gemstone.gemfire.cache.query.dunit.CompiledInDUnitTest$27.run with 0 args on object: "Execute Query with Accessor" (took 658 ms)
> [vm_0][info 2016/07/27 11:57:48.847 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.cache.query.dunit.CompiledInDUnitTest$1.run with 0 args on object: "Close Client"
> [vm_0][info 2016/07/27 11:57:48.848 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] ### Close Client. ###
> [vm_0][info 2016/07/27 11:57:48.850 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] GemFireCache[id = 2027575581; isClosing = true; isShutDownAll = false; created = Wed Jul 27 11:57:47 PDT 2016; server = false; copyOnRead = false; lockLease = 120; lockTimeout = 60]: Now closing.
> [vm_0][info 2016/07/27 11:57:48.854 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Cache server on port 58,363 is shutting down.
> [vm_0][info 2016/07/27 11:57:48.903 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Shutting down DistributionManager cc2-rh6(9046)<ec><v118>:1025. 
> [vm_1][info 2016/07/27 11:57:48.904 PDT <Pooled High Priority Message Processor 2> tid=0x5d8] received leave request from cc2-rh6(9046)<ec><v118>:1025 for cc2-rh6(9046)<ec><v118>:1025
> [locator][info 2016/07/27 11:57:48.904 PDT <Pooled High Priority Message Processor 2> tid=0x5f] received leave request from cc2-rh6(9046)<ec><v118>:1025 for cc2-rh6(9046)<ec><v118>:1025
> [locator][info 2016/07/27 11:57:48.904 PDT <Pooled High Priority Message Processor 2> tid=0x5f] Member at cc2-rh6(9046)<ec><v118>:1025 gracefully left the distributed cache: shutdown message received
> [vm_1][info 2016/07/27 11:57:48.904 PDT <Pooled High Priority Message Processor 2> tid=0x5d8] Member at cc2-rh6(9046)<ec><v118>:1025 gracefully left the distributed cache: shutdown message received
> [vm_0][info 2016/07/27 11:57:49.005 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Now closing distribution for cc2-rh6(9046)<ec><v118>:1025
> [vm_0][info 2016/07/27 11:57:49.005 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Stopping membership services
> [vm_0][info 2016/07/27 11:57:49.006 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] GMSHealthMonitor server socket is closed in stopServices().
> [vm_0][info 2016/07/27 11:57:49.006 PDT <Geode Failure Detection Server thread 0> tid=0x551] GMSHealthMonitor server thread exiting
> [locator][info 2016/07/27 11:57:49.006 PDT <unicast receiver,cc2-rh6-63288> tid=0x21] received leave request from cc2-rh6(9046)<ec><v118>:1025 for cc2-rh6(9046)<ec><v118>:1025
> [vm_0][info 2016/07/27 11:57:49.007 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] GMSHealthMonitor serverSocketExecutor is terminated
> [vm_1][info 2016/07/27 11:57:49.007 PDT <unicast receiver,cc2-rh6-2608> tid=0x5c0] received leave request from cc2-rh6(9046)<ec><v118>:1025 for cc2-rh6(9046)<ec><v118>:1025
> [vm_0][info 2016/07/27 11:57:49.010 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] DistributionManager stopped in 107ms.
> [vm_0][info 2016/07/27 11:57:49.010 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Marking DistributionManager cc2-rh6(9046)<ec><v118>:1025 as closed.
> [vm_0][info 2016/07/27 11:57:49.011 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Got result: null
> [vm_0] from com.gemstone.gemfire.cache.query.dunit.CompiledInDUnitTest$1.run with 0 args on object: "Close Client" (took 163 ms)
> [vm_1][info 2016/07/27 11:57:49.015 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.cache.query.dunit.CompiledInDUnitTest$1.run with 0 args on object: "Close Client"
> [vm_1][info 2016/07/27 11:57:49.018 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] ### Close Client. ###
> [vm_1][info 2016/07/27 11:57:49.019 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] GemFireCache[id = 1802916914; isClosing = true; isShutDownAll = false; created = Wed Jul 27 11:57:48 PDT 2016; server = false; copyOnRead = false; lockLease = 120; lockTimeout = 60]: Now closing.
> [vm_1][info 2016/07/27 11:57:49.020 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Cache server on port 43,638 is shutting down.
> [vm_1][info 2016/07/27 11:57:49.026 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Shutting down DistributionManager cc2-rh6(9050)<ec><v119>:1026. 
> [locator][info 2016/07/27 11:57:49.027 PDT <Pooled High Priority Message Processor 2> tid=0x5f] received leave request from cc2-rh6(9050)<ec><v119>:1026 for cc2-rh6(9050)<ec><v119>:1026
> [locator][info 2016/07/27 11:57:49.028 PDT <Pooled High Priority Message Processor 2> tid=0x5f] Member at cc2-rh6(9050)<ec><v119>:1026 gracefully left the distributed cache: shutdown message received
> [vm_1][info 2016/07/27 11:57:49.129 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Now closing distribution for cc2-rh6(9050)<ec><v119>:1026
> [vm_1][info 2016/07/27 11:57:49.129 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Stopping membership services
> [locator][info 2016/07/27 11:57:49.130 PDT <unicast receiver,cc2-rh6-63288> tid=0x21] received leave request from cc2-rh6(9050)<ec><v119>:1026 for cc2-rh6(9050)<ec><v119>:1026
> [vm_1][info 2016/07/27 11:57:49.131 PDT <Geode Failure Detection Server thread 0> tid=0x5c4] GMSHealthMonitor server thread exiting
> [vm_1][info 2016/07/27 11:57:49.131 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] GMSHealthMonitor server socket is closed in stopServices().
> [vm_1][info 2016/07/27 11:57:49.131 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] GMSHealthMonitor serverSocketExecutor is terminated
> [vm_1][info 2016/07/27 11:57:49.136 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] DistributionManager stopped in 109ms.
> [vm_1][info 2016/07/27 11:57:49.136 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Marking DistributionManager cc2-rh6(9050)<ec><v119>:1026 as closed.
> [vm_1][info 2016/07/27 11:57:49.139 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Got result: null
> [vm_1] from com.gemstone.gemfire.cache.query.dunit.CompiledInDUnitTest$1.run with 0 args on object: "Close Client" (took 123 ms)
> [vm_3][info 2016/07/27 11:57:49.170 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.cache.query.dunit.CompiledInDUnitTest$1.run with 0 args on object: "Close Client"
> [vm_3][info 2016/07/27 11:57:49.171 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] ### Close Client. ###
> [vm_3][info 2016/07/27 11:57:49.172 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Got result: null
> [vm_3] from com.gemstone.gemfire.cache.query.dunit.CompiledInDUnitTest$1.run with 0 args on object: "Close Client" (took 0 ms)
> [vm_0][info 2016/07/27 11:57:49.173 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$8/1605590543.run with 0 args on object: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$8/1605590543@7a351a87
> [vm_0][info 2016/07/27 11:57:49.174 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$8/1605590543.run with 0 args on object: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$8/1605590543@7a351a87 (took 0 ms)
> [vm_1][info 2016/07/27 11:57:49.174 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$8/1222678784.run with 0 args on object: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$8/1222678784@768bcd7d
> [vm_1][info 2016/07/27 11:57:49.175 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$8/1222678784.run with 0 args on object: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$8/1222678784@768bcd7d (took 0 ms)
> [vm_2][info 2016/07/27 11:57:49.176 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$8/1560828044.run with 0 args on object: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$8/1560828044@2308915f
> [vm_2][info 2016/07/27 11:57:49.176 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$8/1560828044.run with 0 args on object: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$8/1560828044@2308915f (took 0 ms)
> [vm_3][info 2016/07/27 11:57:49.177 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$8/1036292184.run with 0 args on object: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$8/1036292184@11d2b9f7
> [vm_3][info 2016/07/27 11:57:49.177 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$8/1036292184.run with 0 args on object: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$8/1036292184@11d2b9f7 (took 0 ms)
> [vm_0][info 2016/07/27 11:57:49.178 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(tearDownCreationStackGenerator)
> [vm_0][info 2016/07/27 11:57:49.178 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(tearDownCreationStackGenerator) (took 0 ms)
> [vm_1][info 2016/07/27 11:57:49.179 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(tearDownCreationStackGenerator)
> [vm_1][info 2016/07/27 11:57:49.179 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(tearDownCreationStackGenerator) (took 0 ms)
> [vm_2][info 2016/07/27 11:57:49.180 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(tearDownCreationStackGenerator)
> [vm_2][info 2016/07/27 11:57:49.180 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(tearDownCreationStackGenerator) (took 0 ms)
> [vm_3][info 2016/07/27 11:57:49.181 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(tearDownCreationStackGenerator)
> [vm_3][info 2016/07/27 11:57:49.181 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(tearDownCreationStackGenerator) (took 0 ms)
> [vm_0][info 2016/07/27 11:57:49.181 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(tearDownVM)
> [vm_0][info 2016/07/27 11:57:49.182 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(tearDownVM) (took 0 ms)
> [vm_1][info 2016/07/27 11:57:49.182 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(tearDownVM)
> [vm_1][info 2016/07/27 11:57:49.182 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(tearDownVM) (took 0 ms)
> [vm_2][info 2016/07/27 11:57:49.183 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(tearDownVM)
> [vm_2][info 2016/07/27 11:57:49.183 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(tearDownVM) (took 0 ms)
> [vm_3][info 2016/07/27 11:57:49.184 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(tearDownVM)
> [vm_3][info 2016/07/27 11:57:49.184 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(tearDownVM) (took 0 ms)
> [locator][info 2016/07/27 11:57:49.185 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/2127478927.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/2127478927@5e324cf0
> [locator][info 2016/07/27 11:57:49.185 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Got result: null
> [locator] from com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/2127478927.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/2127478927@5e324cf0 (took 0 ms)
> Previously run tests: [GroupByPartitionedQueryDUnitTest, CompiledInDUnitTest]
> [vm_0][info 2016/07/27 11:57:49.187 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/07/27 11:57:49.187 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/07/27 11:57:49.188 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/07/27 11:57:49.188 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/07/27 11:57:49.189 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/07/27 11:57:49.189 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/07/27 11:57:49.190 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/07/27 11:57:49.190 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 CompiledInDUnitTest.whenMultipleTypeBindParameterIsUsedWithInQueryAndMapIndexIsPresentReturnCorrectResults
> [vm_0][info 2016/07/27 11:57:49.194 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.cache.query.dunit.CompiledInDUnitTest$6.run with 0 args on object: "Create Bridge Server"
> [vm_0]distributed system properties: {locators=localhost[59664], enable-cluster-configuration=false, use-cluster-configuration=false, mcast-port=0, disable-auto-reconnect=true, log-level=info}
> [vm_0][info 2016/07/27 11:57:49.198 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[59664]
> [vm_0]log-level=info
> [vm_0]mcast-port=0
> [vm_0]use-cluster-configuration=false
> [vm_0]### GemFire Properties using default values ###
> [vm_0]ack-severe-alert-threshold=0
> [vm_0]ack-wait-threshold=15
> [vm_0]archive-disk-space-limit=0
> [vm_0]archive-file-size-limit=0
> [vm_0]async-distribution-timeout=0
> [vm_0]async-max-queue-size=8
> [vm_0]async-queue-timeout=60000
> [vm_0]bind-address=
> [vm_0]cache-xml-file=cache.xml
> [vm_0]cluster-configuration-dir=
> [vm_0]cluster-ssl-ciphers=any
> [vm_0]cluster-ssl-enabled=false
> [vm_0]cluster-ssl-keystore=
> [vm_0]cluster-ssl-keystore-password=
> [vm_0]cluster-ssl-keystore-type=
> [vm_0]cluster-ssl-protocols=any
> [vm_0]cluster-ssl-require-authentication=true
> [vm_0]cluster-ssl-truststore=
> [vm_0]cluster-ssl-truststore-password=
> [vm_0]conflate-events=server
> [vm_0]conserve-sockets=true
> [vm_0]delta-propagation=true
> [vm_0]deploy-working-dir=.
> [vm_0]disable-tcp=false
> [vm_0]distributed-sys
> ...[truncated 913753 chars]...
> 92> tid=0x12] DistributionManager stopped in 105ms.
> [vm_1][info 2016/07/27 11:58:07.057 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Marking DistributionManager cc2-rh6(9050)<ec><v161>:1026 as closed.
> [vm_1][info 2016/07/27 11:58:07.058 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Got result: null
> [vm_1] from com.gemstone.gemfire.cache.query.dunit.CompiledInDUnitTest$1.run with 0 args on object: "Close Client" (took 111 ms)
> [vm_3][info 2016/07/27 11:58:07.060 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.cache.query.dunit.CompiledInDUnitTest$1.run with 0 args on object: "Close Client"
> [vm_3][info 2016/07/27 11:58:07.060 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] ### Close Client. ###
> [vm_3][info 2016/07/27 11:58:07.060 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Got result: null
> [vm_3] from com.gemstone.gemfire.cache.query.dunit.CompiledInDUnitTest$1.run with 0 args on object: "Close Client" (took 0 ms)
> [vm_0][info 2016/07/27 11:58:07.062 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$8/1605590543.run with 0 args on object: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$8/1605590543@7a351a87
> [vm_0][info 2016/07/27 11:58:07.063 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$8/1605590543.run with 0 args on object: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$8/1605590543@7a351a87 (took 0 ms)
> [vm_1][info 2016/07/27 11:58:07.064 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$8/1222678784.run with 0 args on object: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$8/1222678784@768bcd7d
> [vm_1][info 2016/07/27 11:58:07.064 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$8/1222678784.run with 0 args on object: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$8/1222678784@768bcd7d (took 0 ms)
> [vm_2][info 2016/07/27 11:58:07.065 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$8/1560828044.run with 0 args on object: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$8/1560828044@2308915f
> [vm_2][info 2016/07/27 11:58:07.065 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$8/1560828044.run with 0 args on object: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$8/1560828044@2308915f (took 0 ms)
> [vm_3][info 2016/07/27 11:58:07.066 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$8/1036292184.run with 0 args on object: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$8/1036292184@11d2b9f7
> [vm_3][info 2016/07/27 11:58:07.066 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$8/1036292184.run with 0 args on object: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$8/1036292184@11d2b9f7 (took 0 ms)
> [vm_0][info 2016/07/27 11:58:07.067 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(tearDownCreationStackGenerator)
> [vm_0][info 2016/07/27 11:58:07.067 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(tearDownCreationStackGenerator) (took 0 ms)
> [vm_1][info 2016/07/27 11:58:07.068 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(tearDownCreationStackGenerator)
> [vm_1][info 2016/07/27 11:58:07.068 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(tearDownCreationStackGenerator) (took 0 ms)
> [vm_2][info 2016/07/27 11:58:07.068 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(tearDownCreationStackGenerator)
> [vm_2][info 2016/07/27 11:58:07.068 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(tearDownCreationStackGenerator) (took 0 ms)
> [vm_3][info 2016/07/27 11:58:07.069 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(tearDownCreationStackGenerator)
> [vm_3][info 2016/07/27 11:58:07.069 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(tearDownCreationStackGenerator) (took 0 ms)
> [vm_0][info 2016/07/27 11:58:07.070 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(tearDownVM)
> [vm_0][info 2016/07/27 11:58:07.070 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(tearDownVM) (took 0 ms)
> [vm_1][info 2016/07/27 11:58:07.070 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(tearDownVM)
> [vm_1][info 2016/07/27 11:58:07.070 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(tearDownVM) (took 0 ms)
> [vm_2][info 2016/07/27 11:58:07.071 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(tearDownVM)
> [vm_2][info 2016/07/27 11:58:07.071 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(tearDownVM) (took 0 ms)
> [vm_3][info 2016/07/27 11:58:07.072 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(tearDownVM)
> [vm_3][info 2016/07/27 11:58:07.072 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(tearDownVM) (took 0 ms)
> [locator][info 2016/07/27 11:58:07.073 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/2127478927.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/2127478927@5e324cf0
> [locator][info 2016/07/27 11:58:07.073 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Got result: null
> [locator] from com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/2127478927.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/2127478927@5e324cf0 (took 0 ms)
> Previously run tests: [GroupByPartitionedQueryDUnitTest, CompiledInDUnitTest]
> [vm_0][info 2016/07/27 11:58:07.075 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/07/27 11:58:07.075 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/07/27 11:58:07.076 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/07/27 11:58:07.076 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/07/27 11:58:07.077 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/07/27 11:58:07.077 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/07/27 11:58:07.078 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/07/27 11:58:07.078 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 CompiledInDUnitTest.whenEnumBindArgumentIsMatchedInSetWithIteratingFieldShouldReturnResults
> [vm_0][info 2016/07/27 11:58:07.083 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.cache.query.dunit.CompiledInDUnitTest$12.run with 0 args on object: "Create Bridge Server"
> [vm_0]distributed system properties: {locators=localhost[59664], enable-cluster-configuration=false, use-cluster-configuration=false, mcast-port=0, disable-auto-reconnect=true, log-level=info}
> [vm_0][info 2016/07/27 11:58:07.085 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[59664]
> [vm_0]log-level=info
> [vm_0]mcast-port=0
> [vm_0]use-cluster-configuration=false
> [vm_0]### GemFire Properties using default values ###
> [vm_0]ack-severe-alert-threshold=0
> [vm_0]ack-wait-threshold=15
> [vm_0]archive-disk-space-limit=0
> [vm_0]archive-file-size-limit=0
> [vm_0]async-distribution-timeout=0
> [vm_0]async-max-queue-size=8
> [vm_0]async-queue-timeout=60000
> [vm_0]bind-address=
> [vm_0]cache-xml-file=cache.xml
> [vm_0]cluster-configuration-dir=
> [vm_0]cluster-ssl-ciphers=any
> [vm_0]cluster-ssl-enabled=false
> [vm_0]cluster-ssl-keystore=
> [vm_0]cluster-ssl-keystore-password=
> [vm_0]cluster-ssl-keystore-type=
> [vm_0]cluster-ssl-protocols=any
> [vm_0]cluster-ssl-require-authentication=true
> [vm_0]cluster-ssl-truststore=
> [vm_0]cluster-ssl-truststore-password=
> [vm_0]conflate-events=server
> [vm_0]conserve-sockets=true
> [vm_0]delta-propagation=true
> [vm_0]deploy-working-dir=.
> [vm_0]disable-tcp=false
> [vm_0]distributed-system-id=-1
> [vm_0]distributed-transactions=false
> [vm_0]durable-client-id=
> [vm_0]durable-client-timeout=300
> [vm_0]enable-network-partition-detection=false
> [vm_0]enable-time-statistics=false
> [vm_0]enforce-unique-host=false
> [vm_0]gateway-ssl-ciphers=any
> [vm_0]gateway-ssl-enabled=false
> [vm_0]gateway-ssl-keystore=
> [vm_0]gateway-ssl-keystore-password=
> [vm_0]gateway-ssl-keystore-type=
> [vm_0]gateway-ssl-protocols=any
> [vm_0]gateway-ssl-require-authentication=true
> [vm_0]gateway-ssl-truststore=
> [vm_0]gateway-ssl-truststore-password=
> [vm_0]groups=
> [vm_0]http-service-bind-address=
> [vm_0]http-service-port=7070
> [vm_0]http-service-ssl-ciphers=any
> [vm_0]http-service-ssl-enabled=false
> [vm_0]http-service-ssl-keystore=
> [vm_0]http-service-ssl-keystore-password=
> [vm_0]http-service-ssl-keystore-type=
> [vm_0]http-service-ssl-protocols=any
> [vm_0]http-service-ssl-require-authentication=false
> [vm_0]http-service-ssl-truststore=
> [vm_0]http-service-ssl-truststore-password=
> [vm_0]jmx-manager=false
> [vm_0]jmx-manager-access-file=
> [vm_0]jmx-manager-bind-address=
> [vm_0]jmx-manager-hostname-for-clients=
> [vm_0]jmx-manager-http-port=7070
> [vm_0]jmx-manager-password-file=
> [vm_0]jmx-manager-port=1099
> [vm_0]jmx-manager-ssl=false
> [vm_0]jmx-manager-ssl-ciphers=any
> [vm_0]jmx-manager-ssl-enabled=false
> [vm_0]jmx-manager-ssl-keystore=
> [vm_0]jmx-manager-ssl-keystore-password=
> [vm_0]jmx-manager-ssl-keystore-type=
> [vm_0]jmx-manager-ssl-protocols=any
> [vm_0]jmx-manager-ssl-require-authentication=true
> [vm_0]jmx-manager-ssl-truststore=
> [vm_0]jmx-manager-ssl-truststore-password=
> [vm_0]jmx-manager-start=false
> [vm_0]jmx-manager-update-rate=2000
> [vm_0]load-cluster-configuration-from-dir=false
> [vm_0]locator-wait-time=0
> [vm_0]lock-memory=false
> [vm_0]log-disk-space-limit=0
> [vm_0]log-file=
> [vm_0]log-file-size-limit=0
> [vm_0]max-num-reconnect-tries=3
> [vm_0]max-wait-time-reconnect=60000
> [vm_0]mcast-address=239.192.81.1
> [vm_0]mcast-flow-control=1048576, 0.25, 5000
> [vm_0]mcast-recv-buffer-size=1048576
> [vm_0]mcast-send-buffer-size=65535
> [vm_0]mcast-ttl=32
> [vm_0]member-timeout=5000
> [vm_0]membership-port-range=1024-65535
> [vm_0]memcached-bind-address=
> [vm_0]memcached-port=0
> [vm_0]memcached-protocol=ASCII
> [vm_0]name=
> [vm_0]off-heap-memory-size=
> [vm_0]redis-bind-address=
> [vm_0]redis-password=
> [vm_0]redis-port=0
> [vm_0]redundancy-zone=
> [vm_0]remote-locators=
> [vm_0]remove-unresponsive-client=false
> [vm_0]roles=
> [vm_0]security-client-accessor=
> [vm_0]security-client-accessor-pp=
> [vm_0]security-client-auth-init=
> [vm_0]security-client-authenticator=
> [vm_0]security-client-dhalgo=
> [vm_0]security-log-file=
> [vm_0]security-log-level=config
> [vm_0]security-manager=
> [vm_0]security-peer-auth-init=
> [vm_0]security-peer-authenticator=
> [vm_0]security-peer-verifymember-timeout=1000
> [vm_0]security-post-processor=
> [vm_0]server-bind-address=
> [vm_0]server-ssl-ciphers=any
> [vm_0]server-ssl-enabled=false
> [vm_0]server-ssl-keystore=
> [vm_0]server-ssl-keystore-password=
> [vm_0]server-ssl-keystore-type=
> [vm_0]server-ssl-protocols=any
> [vm_0]server-ssl-require-authentication=true
> [vm_0]server-ssl-truststore=
> [vm_0]server-ssl-truststore-password=
> [vm_0]socket-buffer-size=32768
> [vm_0]socket-lease-time=60000
> [vm_0]ssl-ciphers=any
> [vm_0]ssl-enabled=false
> [vm_0]ssl-protocols=any
> [vm_0]ssl-require-authentication=true
> [vm_0]start-dev-rest-api=false
> [vm_0]start-locator=
> [vm_0]statistic-archive-file=
> [vm_0]statistic-sample-rate=1000
> [vm_0]statistic-sampling-enabled=true
> [vm_0]tcp-port=0
> [vm_0]udp-fragment-size=60000
> [vm_0]udp-recv-buffer-size=1048576
> [vm_0]udp-send-buffer-size=65535
> [vm_0]user-command-packages=
> [vm_0][info 2016/07/27 11:58:07.088 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Starting membership services
> [vm_0][info 2016/07/27 11:58:07.096 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] JGroups channel created (took 8ms)
> [vm_0][info 2016/07/27 11:58:07.096 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] GemFire P2P Listener started on  null
> [vm_0][info 2016/07/27 11:58:07.100 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Attempting to join the distributed system through coordinator cc2-rh6(8979:locator)<ec><v0>:1024 using address cc2-rh6(9046)<ec>:1025
> [locator][info 2016/07/27 11:58:07.100 PDT <unicast receiver,cc2-rh6-63288> tid=0x21] received join request from cc2-rh6(9046)<ec>:1025
> [vm_0][info 2016/07/27 11:58:07.097 PDT <Geode Failure Detection Server thread 0> tid=0x9fb] Started failure detection server thread on cc2-rh6.gemstone.com/10.118.33.92:13452.
> [locator][info 2016/07/27 11:58:07.201 PDT <Geode Membership View Creator> tid=0x27] View Creator is processing 5 requests for the next membership view
> [locator][info 2016/07/27 11:58:07.201 PDT <Geode Membership View Creator> tid=0x27] preparing new view View[cc2-rh6(8979:locator)<ec><v0>:1024|162] members: [cc2-rh6(8979:locator)<ec><v0>:1024, cc2-rh6(9046)<ec><v162>:1025{lead}]  shutdown: [cc2-rh6(9050)<ec><v161>:1026, cc2-rh6(9046)<ec><v160>:1025]
> [locator]failure detection ports: 17096 13452
> [vm_0][info 2016/07/27 11:58:07.202 PDT <unicast receiver,cc2-rh6-48186> tid=0x9f7] received new view: View[cc2-rh6(8979:locator)<ec><v0>:1024|162] members: [cc2-rh6(8979:locator)<ec><v0>:1024, cc2-rh6(9046)<ec><v162>:1025{lead}]  shutdown: [cc2-rh6(9050)<ec><v161>:1026, cc2-rh6(9046)<ec><v160>:1025]
> [vm_0]old view is: null
> [vm_0][info 2016/07/27 11:58:07.203 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Finished joining (took 105ms).
> [vm_0][info 2016/07/27 11:58:07.203 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Starting DistributionManager cc2-rh6(9046)<ec><v162>:1025.  (took 116 ms)
> [locator][info 2016/07/27 11:58:07.202 PDT <Geode Membership View Creator> tid=0x27] finished waiting for responses to view preparation
> [locator][info 2016/07/27 11:58:07.202 PDT <Geode Membership View Creator> tid=0x27] received new view: View[cc2-rh6(8979:locator)<ec><v0>:1024|162] members: [cc2-rh6(8979:locator)<ec><v0>:1024, cc2-rh6(9046)<ec><v162>:1025{lead}]  shutdown: [cc2-rh6(9050)<ec><v161>:1026, cc2-rh6(9046)<ec><v160>:1025]
> [locator]old view is: View[cc2-rh6(8979:locator)<ec><v0>:1024|161] members: [cc2-rh6(8979:locator)<ec><v0>:1024, cc2-rh6(9046)<ec><v160>:1025{lead}, cc2-rh6(9050)<ec><v161>:1026]
> [locator][info 2016/07/27 11:58:07.202 PDT <Geode Membership View Creator> tid=0x27] Peer locator received new membership view: View[cc2-rh6(8979:locator)<ec><v0>:1024|162] members: [cc2-rh6(8979:locator)<ec><v0>:1024, cc2-rh6(9046)<ec><v162>:1025{lead}]  shutdown: [cc2-rh6(9050)<ec><v161>:1026, cc2-rh6(9046)<ec><v160>:1025]
> [locator][info 2016/07/27 11:58:07.203 PDT <Geode Membership View Creator> tid=0x27] sending new view View[cc2-rh6(8979:locator)<ec><v0>:1024|162] members: [cc2-rh6(8979:locator)<ec><v0>:1024, cc2-rh6(9046)<ec><v162>:1025{lead}]  shutdown: [cc2-rh6(9050)<ec><v161>:1026, cc2-rh6(9046)<ec><v160>:1025]
> [locator]failure detection ports: 17096 13452
> [vm_0][info 2016/07/27 11:58:07.203 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Initial (distribution manager) view =  View[cc2-rh6(8979:locator)<ec><v0>:1024|162] members: [cc2-rh6(8979:locator)<ec><v0>:1024, cc2-rh6(9046)<ec><v162>:1025{lead}]  shutdown: [cc2-rh6(9050)<ec><v161>:1026, cc2-rh6(9046)<ec><v160>:1025]
> [vm_0][info 2016/07/27 11:58:07.203 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Admitting member <cc2-rh6(8979:locator)<ec><v0>:1024>. Now there are 1 non-admin member(s).
> [vm_0][info 2016/07/27 11:58:07.203 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Admitting member <cc2-rh6(9046)<ec><v162>:1025>. Now there are 2 non-admin member(s).
> [locator][info 2016/07/27 11:58:07.203 PDT <View Message Processor> tid=0x33] Membership: Processing addition < cc2-rh6(9046)<ec><v162>:1025 >
> [locator][info 2016/07/27 11:58:07.203 PDT <View Message Processor> tid=0x33] Admitting member <cc2-rh6(9046)<ec><v162>:1025>. Now there are 2 non-admin member(s).
> [locator][info 2016/07/27 11:58:07.206 PDT <Pooled High Priority Message Processor 1> tid=0x37] Member cc2-rh6(9046)<ec><v162>:1025 is equivalent or in the same redundancy zone.
> [vm_0][info 2016/07/27 11:58:07.208 PDT <P2P message reader for cc2-rh6(8979:locator)<ec><v0>:1024 shared unordered uid=138 port=59772> tid=0xa00] Member cc2-rh6(8979:locator)<ec><v0>:1024 is equivalent or in the same redundancy zone.
> [vm_0][info 2016/07/27 11:58:07.218 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Initializing region _monitoringRegion_10.118.33.92<v162>1025
> [vm_0][info 2016/07/27 11:58:07.219 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Initialization of region _monitoringRegion_10.118.33.92<v162>1025 completed
> [vm_0][info 2016/07/27 11:58:07.222 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/07/27 11:58:07.223 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Initializing region PdxTypes
> [vm_0][info 2016/07/27 11:58:07.223 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Initialization of region PdxTypes completed
> [vm_0][info 2016/07/27 11:58:07.225 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Cache server connection listener bound to address 0.0.0.0/0.0.0.0:37986 with backlog 1,000.
> [vm_0][info 2016/07/27 11:58:07.225 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] ClientHealthMonitorThread maximum allowed time between pings: 60,000
> [vm_0][info 2016/07/27 11:58:07.227 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] CacheServer Configuration:   port=37986 max-connections=800 max-threads=0 notify-by-subscription=true socket-buffer-size=32768 maximum-time-between-pings=60000 maximum-message-count=230000 message-time-to-live=180 eviction-policy=none capacity=1 overflow directory=. groups=[] loadProbe=ConnectionCountProbe loadPollInterval=5000 tcpNoDelay=true
> [vm_0][info 2016/07/27 11:58:07.228 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Initializing region root
> [vm_0][info 2016/07/27 11:58:07.228 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Initialization of region root completed
> [vm_0][info 2016/07/27 11:58:07.229 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Initializing region PdxTest
> [vm_0][info 2016/07/27 11:58:07.229 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Initialization of region PdxTest completed
> [vm_0][info 2016/07/27 11:58:07.231 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Using Compact Map Range index implementation for 'myIndex' on region /root/PdxTest
> [vm_0][info 2016/07/27 11:58:07.231 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Initialized and loaded entries into the index 
> [vm_0][info 2016/07/27 11:58:07.235 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Got result: null
> [vm_0] from com.gemstone.gemfire.cache.query.dunit.CompiledInDUnitTest$12.run with 0 args on object: "Create Bridge Server" (took 151 ms)
> [vm_1][info 2016/07/27 11:58:07.236 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.cache.query.dunit.CompiledInDUnitTest$25.run with 0 args on object: "Execute Query with Index in Replicated Region"
> [vm_1]distributed system properties: {locators=localhost[59664], enable-cluster-configuration=false, use-cluster-configuration=false, mcast-port=0, disable-auto-reconnect=true, log-level=info}
> [vm_1][info 2016/07/27 11:58:07.239 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Startup Configuration:
> [vm_1] ### GemFire Properties defined with api ###
> [vm_1]disable-auto-reconnect=true
> [vm_1]enable-cluster-configuration=false
> [vm_1]locators=localhost[59664]
> [vm_1]log-level=info
> [vm_1]mcast-port=0
> [vm_1]use-cluster-configuration=false
> [vm_1]### GemFire Properties using default values ###
> [vm_1]ack-severe-alert-threshold=0
> [vm_1]ack-wait-threshold=15
> [vm_1]archive-disk-space-limit=0
> [vm_1]archive-file-size-limit=0
> [vm_1]async-distribution-timeout=0
> [vm_1]async-max-queue-size=8
> [vm_1]async-queue-timeout=60000
> [vm_1]bind-address=
> [vm_1]cache-xml-file=cache.xml
> [vm_1]cluster-configuration-dir=
> [vm_1]cluster-ssl-ciphers=any
> [vm_1]cluster-ssl-enabled=false
> [vm_1]cluster-ssl-keystore=
> [vm_1]cluster-ssl-keystore-password=
> [vm_1]cluster-ssl-keystore-type=
> [vm_1]cluster-ssl-protocols=any
> [vm_1]cluster-ssl-require-authentication=true
> [vm_1]cluster-ssl-truststore=
> [vm_1]cluster-ssl-truststore-password=
> [vm_1]conflate-events=server
> [vm_1]conserve-sockets=true
> [vm_1]delta-propagation=true
> [vm_1]deploy-working-dir=.
> [vm_1]disable-tcp=false
> [vm_1]distributed-system-id=-1
> [vm_1]distributed-transactions=false
> [vm_1]durable-client-id=
> [vm_1]durable-client-timeout=300
> [vm_1]enable-network-partition-detection=false
> [vm_1]enable-time-statistics=false
> [vm_1]enforce-unique-host=false
> [vm_1]gateway-ssl-ciphers=any
> [vm_1]gateway-ssl-enabled=false
> [vm_1]gateway-ssl-keystore=
> [vm_1]gateway-ssl-keystore-password=
> [vm_1]gateway-ssl-keystore-type=
> [vm_1]gateway-ssl-protocols=any
> [vm_1]gateway-ssl-require-authentication=true
> [vm_1]gateway-ssl-truststore=
> [vm_1]gateway-ssl-truststore-password=
> [vm_1]groups=
> [vm_1]http-service-bind-address=
> [vm_1]http-service-port=7070
> [vm_1]http-service-ssl-ciphers=any
> [vm_1]http-service-ssl-enabled=false
> [vm_1]http-service-ssl-keystore=
> [vm_1]http-service-ssl-keystore-password=
> [vm_1]http-service-ssl-keystore-type=
> [vm_1]http-service-ssl-protocols=any
> [vm_1]http-service-ssl-require-authentication=false
> [vm_1]http-service-ssl-truststore=
> [vm_1]http-service-ssl-truststore-password=
> [vm_1]jmx-manager=false
> [vm_1]jmx-manager-access-file=
> [vm_1]jmx-manager-bind-address=
> [vm_1]jmx-manager-hostname-for-clients=
> [vm_1]jmx-manager-http-port=7070
> [vm_1]jmx-manager-password-file=
> [vm_1]jmx-manager-port=1099
> [vm_1]jmx-manager-ssl=false
> [vm_1]jmx-manager-ssl-ciphers=any
> [vm_1]jmx-manager-ssl-enabled=false
> [vm_1]jmx-manager-ssl-keystore=
> [vm_1]jmx-manager-ssl-keystore-password=
> [vm_1]jmx-manager-ssl-keystore-type=
> [vm_1]jmx-manager-ssl-protocols=any
> [vm_1]jmx-manager-ssl-require-authentication=true
> [vm_1]jmx-manager-ssl-truststore=
> [vm_1]jmx-manager-ssl-truststore-password=
> [vm_1]jmx-manager-start=false
> [vm_1]jmx-manager-update-rate=2000
> [vm_1]load-cluster-configuration-from-dir=false
> [vm_1]locator-wait-time=0
> [vm_1]lock-memory=false
> [vm_1]log-disk-space-limit=0
> [vm_1]log-file=
> [vm_1]log-file-size-limit=0
> [vm_1]max-num-reconnect-tries=3
> [vm_1]max-wait-time-reconnect=60000
> [vm_1]mcast-address=239.192.81.1
> [vm_1]mcast-flow-control=1048576, 0.25, 5000
> [vm_1]mcast-recv-buffer-size=1048576
> [vm_1]mcast-send-buffer-size=65535
> [vm_1]mcast-ttl=32
> [vm_1]member-timeout=5000
> [vm_1]membership-port-range=1024-65535
> [vm_1]memcached-bind-address=
> [vm_1]memcached-port=0
> [vm_1]memcached-protocol=ASCII
> [vm_1]name=
> [vm_1]off-heap-memory-size=
> [vm_1]redis-bind-address=
> [vm_1]redis-password=
> [vm_1]redis-port=0
> [vm_1]redundancy-zone=
> [vm_1]remote-locators=
> [vm_1]remove-unresponsive-client=false
> [vm_1]roles=
> [vm_1]security-client-accessor=
> [vm_1]security-client-accessor-pp=
> [vm_1]security-client-auth-init=
> [vm_1]security-client-authenticator=
> [vm_1]security-client-dhalgo=
> [vm_1]security-log-file=
> [vm_1]security-log-level=config
> [vm_1]security-manager=
> [vm_1]security-peer-auth-init=
> [vm_1]security-peer-authenticator=
> [vm_1]security-peer-verifymember-timeout=1000
> [vm_1]security-post-processor=
> [vm_1]server-bind-address=
> [vm_1]server-ssl-ciphers=any
> [vm_1]server-ssl-enabled=false
> [vm_1]server-ssl-keystore=
> [vm_1]server-ssl-keystore-password=
> [vm_1]server-ssl-keystore-type=
> [vm_1]server-ssl-protocols=any
> [vm_1]server-ssl-require-authentication=true
> [vm_1]server-ssl-truststore=
> [vm_1]server-ssl-truststore-password=
> [vm_1]socket-buffer-size=32768
> [vm_1]socket-lease-time=60000
> [vm_1]ssl-ciphers=any
> [vm_1]ssl-enabled=false
> [vm_1]ssl-protocols=any
> [vm_1]ssl-require-authentication=true
> [vm_1]start-dev-rest-api=false
> [vm_1]start-locator=
> [vm_1]statistic-archive-file=
> [vm_1]statistic-sample-rate=1000
> [vm_1]statistic-sampling-enabled=true
> [vm_1]tcp-port=0
> [vm_1]udp-fragment-size=60000
> [vm_1]udp-recv-buffer-size=1048576
> [vm_1]udp-send-buffer-size=65535
> [vm_1]user-command-packages=
> [vm_1][info 2016/07/27 11:58:07.241 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Starting membership services
> [vm_1][info 2016/07/27 11:58:07.249 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] JGroups channel created (took 8ms)
> [vm_1][info 2016/07/27 11:58:07.250 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] GemFire P2P Listener started on  null
> [vm_1][info 2016/07/27 11:58:07.251 PDT <Geode Failure Detection Server thread 0> tid=0xac0] Started failure detection server thread on cc2-rh6.gemstone.com/10.118.33.92:16061.
> [vm_1][info 2016/07/27 11:58:07.254 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Attempting to join the distributed system through coordinator cc2-rh6(8979:locator)<ec><v0>:1024 using address cc2-rh6(9050)<ec>:1026
> [locator][info 2016/07/27 11:58:07.254 PDT <unicast receiver,cc2-rh6-63288> tid=0x21] received join request from cc2-rh6(9050)<ec>:1026
> [locator][info 2016/07/27 11:58:07.555 PDT <Geode Membership View Creator> tid=0x27] View Creator is processing 1 requests for the next membership view
> [locator][info 2016/07/27 11:58:07.555 PDT <Geode Membership View Creator> tid=0x27] preparing new view View[cc2-rh6(8979:locator)<ec><v0>:1024|163] members: [cc2-rh6(8979:locator)<ec><v0>:1024, cc2-rh6(9046)<ec><v162>:1025{lead}, cc2-rh6(9050)<ec><v163>:1026]
> [locator]failure detection ports: 17096 13452 16061
> [vm_1][info 2016/07/27 11:58:07.556 PDT <unicast receiver,cc2-rh6-22791> tid=0xabc] received new view: View[cc2-rh6(8979:locator)<ec><v0>:1024|163] members: [cc2-rh6(8979:locator)<ec><v0>:1024, cc2-rh6(9046)<ec><v162>:1025{lead}, cc2-rh6(9050)<ec><v163>:1026]
> [vm_1]old view is: null
> [locator][info 2016/07/27 11:58:07.556 PDT <Geode Membership View Creator> tid=0x27] finished waiting for responses to view preparation
> [locator][info 2016/07/27 11:58:07.556 PDT <Geode Membership View Creator> tid=0x27] received new view: View[cc2-rh6(8979:locator)<ec><v0>:1024|163] members: [cc2-rh6(8979:locator)<ec><v0>:1024, cc2-rh6(9046)<ec><v162>:1025{lead}, cc2-rh6(9050)<ec><v163>:1026]
> [locator]old view is: View[cc2-rh6(8979:locator)<ec><v0>:1024|162] members: [cc2-rh6(8979:locator)<ec><v0>:1024, cc2-rh6(9046)<ec><v162>:1025{lead}]  shutdown: [cc2-rh6(9050)<ec><v161>:1026, cc2-rh6(9046)<ec><v160>:1025]
> [locator][info 2016/07/27 11:58:07.556 PDT <Geode Membership View Creator> tid=0x27] Peer locator received new membership view: View[cc2-rh6(8979:locator)<ec><v0>:1024|163] members: [cc2-rh6(8979:locator)<ec><v0>:1024, cc2-rh6(9046)<ec><v162>:1025{lead}, cc2-rh6(9050)<ec><v163>:1026]
> [vm_1][info 2016/07/27 11:58:07.556 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Finished joining (took 305ms).
> [vm_1][info 2016/07/27 11:58:07.556 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Starting DistributionManager cc2-rh6(9050)<ec><v163>:1026.  (took 316 ms)
> [locator][info 2016/07/27 11:58:07.556 PDT <Geode Membership View Creator> tid=0x27] sending new view View[cc2-rh6(8979:locator)<ec><v0>:1024|163] members: [cc2-rh6(8979:locator)<ec><v0>:1024, cc2-rh6(9046)<ec><v162>:1025{lead}, cc2-rh6(9050)<ec><v163>:1026]
> [locator]failure detection ports: 17096 13452 16061
> [vm_1][info 2016/07/27 11:58:07.556 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Initial (distribution manager) view =  View[cc2-rh6(8979:locator)<ec><v0>:1024|163] members: [cc2-rh6(8979:locator)<ec><v0>:1024, cc2-rh6(9046)<ec><v162>:1025{lead}, cc2-rh6(9050)<ec><v163>:1026]
> [vm_1][info 2016/07/27 11:58:07.556 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Admitting member <cc2-rh6(8979:locator)<ec><v0>:1024>. Now there are 1 non-admin member(s).
> [vm_1][info 2016/07/27 11:58:07.557 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Admitting member <cc2-rh6(9046)<ec><v162>:1025>. Now there are 2 non-admin member(s).
> [vm_1][info 2016/07/27 11:58:07.557 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Admitting member <cc2-rh6(9050)<ec><v163>:1026>. Now there are 3 non-admin member(s).
> [vm_0][info 2016/07/27 11:58:07.557 PDT <unicast receiver,cc2-rh6-48186> tid=0x9f7] received new view: View[cc2-rh6(8979:locator)<ec><v0>:1024|163] members: [cc2-rh6(8979:locator)<ec><v0>:1024, cc2-rh6(9046)<ec><v162>:1025{lead}, cc2-rh6(9050)<ec><v163>:1026]
> [vm_0]old view is: View[cc2-rh6(8979:locator)<ec><v0>:1024|162] members: [cc2-rh6(8979:locator)<ec><v0>:1024, cc2-rh6(9046)<ec><v162>:1025{lead}]  shutdown: [cc2-rh6(9050)<ec><v161>:1026, cc2-rh6(9046)<ec><v160>:1025]
> [locator][info 2016/07/27 11:58:07.557 PDT <View Message Processor> tid=0x33] Membership: Processing addition < cc2-rh6(9050)<ec><v163>:1026 >
> [locator][info 2016/07/27 11:58:07.557 PDT <View Message Processor> tid=0x33] Admitting member <cc2-rh6(9050)<ec><v163>:1026>. Now there are 3 non-admin member(s).
> [vm_0][info 2016/07/27 11:58:07.558 PDT <View Message Processor> tid=0xa13] Membership: Processing addition < cc2-rh6(9050)<ec><v163>:1026 >
> [vm_0][info 2016/07/27 11:58:07.558 PDT <View Message Processor> tid=0xa13] Admitting member <cc2-rh6(9050)<ec><v163>:1026>. Now there are 3 non-admin member(s).
> [locator][info 2016/07/27 11:58:07.559 PDT <Pooled High Priority Message Processor 1> tid=0x37] Member cc2-rh6(9050)<ec><v163>:1026 is equivalent or in the same redundancy zone.
> [vm_0][info 2016/07/27 11:58:07.560 PDT <Pooled High Priority Message Processor 2> tid=0xa0c] Member cc2-rh6(9050)<ec><v163>:1026 is equivalent or in the same redundancy zone.
> [vm_1][info 2016/07/27 11:58:07.561 PDT <P2P message reader for cc2-rh6(9046)<ec><v162>:1025 shared unordered uid=258 port=44453> tid=0xac8] Member cc2-rh6(9046)<ec><v162>:1025 is equivalent or in the same redundancy zone.
> [vm_1][info 2016/07/27 11:58:07.562 PDT <P2P message reader for cc2-rh6(8979:locator)<ec><v0>:1024 shared unordered uid=139 port=44452> tid=0xac7] Member cc2-rh6(8979:locator)<ec><v0>:1024 is equivalent or in the same redundancy zone.
> [vm_1][info 2016/07/27 11:58:07.568 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Initializing region _monitoringRegion_10.118.33.92<v163>1026
> [vm_1][info 2016/07/27 11:58:07.569 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Initialization of region _monitoringRegion_10.118.33.92<v163>1026 completed
> [vm_1][info 2016/07/27 11:58:07.572 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_1][info 2016/07/27 11:58:07.573 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Initializing region PdxTypes
> [vm_1][info 2016/07/27 11:58:07.574 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Region PdxTypes requesting initial image from cc2-rh6(9046)<ec><v162>:1025
> [vm_1][info 2016/07/27 11:58:07.575 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] PdxTypes is done getting image from cc2-rh6(9046)<ec><v162>:1025. isDeltaGII is false
> [vm_1][info 2016/07/27 11:58:07.576 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Initialization of region PdxTypes completed
> [vm_1][info 2016/07/27 11:58:07.578 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Cache server connection listener bound to address 0.0.0.0/0.0.0.0:49708 with backlog 1,000.
> [vm_1][info 2016/07/27 11:58:07.578 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] ClientHealthMonitorThread maximum allowed time between pings: 60,000
> [vm_1][info 2016/07/27 11:58:07.580 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] CacheServer Configuration:   port=49708 max-connections=800 max-threads=0 notify-by-subscription=true socket-buffer-size=32768 maximum-time-between-pings=60000 maximum-message-count=230000 message-time-to-live=180 eviction-policy=none capacity=1 overflow directory=. groups=[] loadProbe=ConnectionCountProbe loadPollInterval=5000 tcpNoDelay=true
> [vm_1][info 2016/07/27 11:58:07.581 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Initializing region root
> [vm_1][info 2016/07/27 11:58:07.582 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Region root requesting initial image from cc2-rh6(9046)<ec><v162>:1025
> [vm_1][info 2016/07/27 11:58:07.582 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] root is done getting image from cc2-rh6(9046)<ec><v162>:1025. isDeltaGII is false
> [vm_1][info 2016/07/27 11:58:07.583 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Initialization of region root completed
> [vm_1][info 2016/07/27 11:58:07.584 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Initializing region PdxTest
> [vm_1][info 2016/07/27 11:58:07.585 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Region PdxTest requesting initial image from cc2-rh6(9046)<ec><v162>:1025
> [vm_1][info 2016/07/27 11:58:07.587 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] PdxTest is done getting image from cc2-rh6(9046)<ec><v162>:1025. isDeltaGII is false
> [vm_1][info 2016/07/27 11:58:07.587 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Initialization of region PdxTest completed
> [vm_1][info 2016/07/27 11:58:07.589 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Using Compact Map Range index implementation for 'myIndex' on region /root/PdxTest
> [vm_1][info 2016/07/27 11:58:07.590 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Initialized and loaded entries into the index 
> [vm_1][info 2016/07/27 11:58:07.591 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Got result: null
> [vm_1] from com.gemstone.gemfire.cache.query.dunit.CompiledInDUnitTest$25.run with 0 args on object: "Execute Query with Index in Replicated Region" (took 354 ms)
> [vm_0][info 2016/07/27 11:58:07.592 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.cache.query.dunit.CompiledInDUnitTest$1.run with 0 args on object: "Close Client"
> [vm_0][info 2016/07/27 11:58:07.593 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] ### Close Client. ###
> [vm_0][info 2016/07/27 11:58:07.593 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] GemFireCache[id = 1929180943; isClosing = true; isShutDownAll = false; created = Wed Jul 27 11:58:07 PDT 2016; server = false; copyOnRead = false; lockLease = 120; lockTimeout = 60]: Now closing.
> [vm_0][info 2016/07/27 11:58:07.596 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Cache server on port 37,986 is shutting down.
> [vm_0][info 2016/07/27 11:58:07.601 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Shutting down DistributionManager cc2-rh6(9046)<ec><v162>:1025. 
> [vm_1][info 2016/07/27 11:58:07.602 PDT <Pooled High Priority Message Processor 1> tid=0xad3] received leave request from cc2-rh6(9046)<ec><v162>:1025 for cc2-rh6(9046)<ec><v162>:1025
> [vm_1][info 2016/07/27 11:58:07.602 PDT <Pooled High Priority Message Processor 1> tid=0xad3] Member at cc2-rh6(9046)<ec><v162>:1025 gracefully left the distributed cache: shutdown message received
> [locator][info 2016/07/27 11:58:07.602 PDT <Pooled High Priority Message Processor 1> tid=0x37] received leave request from cc2-rh6(9046)<ec><v162>:1025 for cc2-rh6(9046)<ec><v162>:1025
> [locator][info 2016/07/27 11:58:07.602 PDT <Pooled High Priority Message Processor 1> tid=0x37] Member at cc2-rh6(9046)<ec><v162>:1025 gracefully left the distributed cache: shutdown message received
> [vm_0][info 2016/07/27 11:58:07.702 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Now closing distribution for cc2-rh6(9046)<ec><v162>:1025
> [vm_0][info 2016/07/27 11:58:07.703 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Stopping membership services
> [vm_0][info 2016/07/27 11:58:07.703 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] GMSHealthMonitor server socket is closed in stopServices().
> [vm_1][info 2016/07/27 11:58:07.704 PDT <unicast receiver,cc2-rh6-22791> tid=0xabc] received leave request from cc2-rh6(9046)<ec><v162>:1025 for cc2-rh6(9046)<ec><v162>:1025
> [vm_0][info 2016/07/27 11:58:07.704 PDT <Geode Failure Detection Server thread 0> tid=0x9fb] GMSHealthMonitor server thread exiting
> [locator][info 2016/07/27 11:58:07.704 PDT <unicast receiver,cc2-rh6-63288> tid=0x21] received leave request from cc2-rh6(9046)<ec><v162>:1025 for cc2-rh6(9046)<ec><v162>:1025
> [vm_0][info 2016/07/27 11:58:07.737 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] GMSHealthMonitor serverSocketExecutor is terminated
> [vm_0][info 2016/07/27 11:58:07.740 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] DistributionManager stopped in 139ms.
> [vm_0][info 2016/07/27 11:58:07.740 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Marking DistributionManager cc2-rh6(9046)<ec><v162>:1025 as closed.
> [vm_0][info 2016/07/27 11:58:07.740 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Got result: null
> [vm_0] from com.gemstone.gemfire.cache.query.dunit.CompiledInDUnitTest$1.run with 0 args on object: "Close Client" (took 147 ms)
> [vm_1][info 2016/07/27 11:58:07.742 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.cache.query.dunit.CompiledInDUnitTest$1.run with 0 args on object: "Close Client"
> [vm_1][info 2016/07/27 11:58:07.742 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] ### Close Client. ###
> [vm_1][info 2016/07/27 11:58:07.742 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] GemFireCache[id = 589813762; isClosing = true; isShutDownAll = false; created = Wed Jul 27 11:58:07 PDT 2016; server = false; copyOnRead = false; lockLease = 120; lockTimeout = 60]: Now closing.
> [vm_1][info 2016/07/27 11:58:07.744 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Cache server on port 49,708 is shutting down.
> [vm_1][info 2016/07/27 11:58:07.747 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Shutting down DistributionManager cc2-rh6(9050)<ec><v163>:1026. 
> [locator][info 2016/07/27 11:58:07.748 PDT <Pooled High Priority Message Processor 1> tid=0x37] received leave request from cc2-rh6(9050)<ec><v163>:1026 for cc2-rh6(9050)<ec><v163>:1026
> [locator][info 2016/07/27 11:58:07.748 PDT <Pooled High Priority Message Processor 1> tid=0x37] Member at cc2-rh6(9050)<ec><v163>:1026 gracefully left the distributed cache: shutdown message received
> [vm_1][info 2016/07/27 11:58:07.849 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Now closing distribution for cc2-rh6(9050)<ec><v163>:1026
> [vm_1][info 2016/07/27 11:58:07.849 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Stopping membership services
> [vm_1][info 2016/07/27 11:58:07.850 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] GMSHealthMonitor server socket is closed in stopServices().
> [locator][info 2016/07/27 11:58:07.850 PDT <unicast receiver,cc2-rh6-63288> tid=0x21] received leave request from cc2-rh6(9050)<ec><v163>:1026 for cc2-rh6(9050)<ec><v163>:1026
> [vm_1][info 2016/07/27 11:58:07.850 PDT <Geode Failure Detection Server thread 0> tid=0xac0] GMSHealthMonitor server thread exiting
> [vm_1][info 2016/07/27 11:58:07.850 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] GMSHealthMonitor serverSocketExecutor is terminated
> [vm_1][info 2016/07/27 11:58:07.853 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] DistributionManager stopped in 106ms.
> [vm_1][info 2016/07/27 11:58:07.853 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Marking DistributionManager cc2-rh6(9050)<ec><v163>:1026 as closed.
> [vm_1][info 2016/07/27 11:58:07.853 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Got result: null
> [vm_1] from com.gemstone.gemfire.cache.query.dunit.CompiledInDUnitTest$1.run with 0 args on object: "Close Client" (took 111 ms)
> [vm_3][info 2016/07/27 11:58:07.855 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.cache.query.dunit.CompiledInDUnitTest$1.run with 0 args on object: "Close Client"
> [vm_3][info 2016/07/27 11:58:07.855 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] ### Close Client. ###
> [vm_3][info 2016/07/27 11:58:07.855 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Got result: null
> [vm_3] from com.gemstone.gemfire.cache.query.dunit.CompiledInDUnitTest$1.run with 0 args on object: "Close Client" (took 0 ms)
> [vm_0][info 2016/07/27 11:58:07.856 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$8/1605590543.run with 0 args on object: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$8/1605590543@7a351a87
> [vm_0][info 2016/07/27 11:58:07.857 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$8/1605590543.run with 0 args on object: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$8/1605590543@7a351a87 (took 0 ms)
> [vm_1][info 2016/07/27 11:58:07.857 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$8/1222678784.run with 0 args on object: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$8/1222678784@768bcd7d
> [vm_1][info 2016/07/27 11:58:07.858 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$8/1222678784.run with 0 args on object: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$8/1222678784@768bcd7d (took 0 ms)
> [vm_2][info 2016/07/27 11:58:07.859 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$8/1560828044.run with 0 args on object: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$8/1560828044@2308915f
> [vm_2][info 2016/07/27 11:58:07.859 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$8/1560828044.run with 0 args on object: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$8/1560828044@2308915f (took 0 ms)
> [vm_3][info 2016/07/27 11:58:07.860 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$8/1036292184.run with 0 args on object: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$8/1036292184@11d2b9f7
> [vm_3][info 2016/07/27 11:58:07.860 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$8/1036292184.run with 0 args on object: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$8/1036292184@11d2b9f7 (took 0 ms)
> [vm_0][info 2016/07/27 11:58:07.861 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(tearDownCreationStackGenerator)
> [vm_0][info 2016/07/27 11:58:07.861 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(tearDownCreationStackGenerator) (took 0 ms)
> [vm_1][info 2016/07/27 11:58:07.861 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(tearDownCreationStackGenerator)
> [vm_1][info 2016/07/27 11:58:07.862 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(tearDownCreationStackGenerator) (took 0 ms)
> [vm_2][info 2016/07/27 11:58:07.862 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(tearDownCreationStackGenerator)
> [vm_2][info 2016/07/27 11:58:07.862 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(tearDownCreationStackGenerator) (took 0 ms)
> [vm_3][info 2016/07/27 11:58:07.863 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(tearDownCreationStackGenerator)
> [vm_3][info 2016/07/27 11:58:07.863 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(tearDownCreationStackGenerator) (took 0 ms)
> [vm_0][info 2016/07/27 11:58:07.864 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(tearDownVM)
> [vm_0][info 2016/07/27 11:58:07.864 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(tearDownVM) (took 0 ms)
> [vm_1][info 2016/07/27 11:58:07.864 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(tearDownVM)
> [vm_1][info 2016/07/27 11:58:07.864 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(tearDownVM) (took 0 ms)
> [vm_2][info 2016/07/27 11:58:07.865 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(tearDownVM)
> [vm_2][info 2016/07/27 11:58:07.865 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(tearDownVM) (took 0 ms)
> [vm_3][info 2016/07/27 11:58:07.865 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(tearDownVM)
> [vm_3][info 2016/07/27 11:58:07.866 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(tearDownVM) (took 0 ms)
> [locator][info 2016/07/27 11:58:07.866 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/2127478927.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/2127478927@5e324cf0
> [locator][info 2016/07/27 11:58:07.867 PDT <RMI TCP Connection(1)-10.118.33.92> tid=0x12] Got result: null
> [locator] from com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/2127478927.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/2127478927@5e324cf0 (took 0 ms)
> {noformat}



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

Mime
View raw message