Kevin Duling created GEODE-1710: ----------------------------------- Summary: CompiledInDUnitTest.whenMultipleTypeBindParameterIsUsedWithInQueryAndMapIndexIsPresentReturnCorrectResults Key: GEODE-1710 URL: https://issues.apache.org/jira/browse/GEODE-1710 Project: Geode Issue Type: Bug Reporter: Kevin Duling
In Geode_develop_DistributedTests/3430: {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 kuwait.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 kuwait.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.whenMultipleTypeBindParameterIsUsedWithInQueryAndMapIndexIsPresentReturnCorrectResults(CompiledInDUnitTest.java:236) 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.GeneratedMethodAccessor15.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:497) at org.gradle.messaging.dispatch.ReflectionDispatch.dispatch(ReflectionDispatch.java:35) at org.gradle.messaging.dispatch.ReflectionDispatch.dispatch(ReflectionDispatch.java:24) at org.gradle.messaging.dispatch.ContextClassLoaderDispatch.dispatch(ContextClassLoaderDispatch.java:32) at org.gradle.messaging.dispatch.ProxyDispatchAdapter$DispatchingInvocationHandler.invoke(ProxyDispatchAdapter.java:93) at com.sun.proxy.$Proxy2.processTestClass(Unknown Source) at org.gradle.api.internal.tasks.testing.worker.TestWorker.processTestClass(TestWorker.java:109) at sun.reflect.GeneratedMethodAccessor14.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:497) at org.gradle.messaging.dispatch.ReflectionDispatch.dispatch(ReflectionDispatch.java:35) at org.gradle.messaging.dispatch.ReflectionDispatch.dispatch(ReflectionDispatch.java:24) at org.gradle.messaging.remote.internal.hub.MessageHub$Handler.run(MessageHub.java:360) at org.gradle.internal.concurrent.ExecutorPolicy$CatchAndRecordFailures.onExecute(ExecutorPolicy.java:54) at org.gradle.internal.concurrent.StoppableExecutorImpl$1.run(StoppableExecutorImpl.java:40) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) at java.lang.Thread.run(Thread.java:745) Caused by: java.lang.AssertionError: expected:<5> but was:<0> 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.GeneratedMethodAccessor158.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.GeneratedMethodAccessor8.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: [PRBasicQueryDUnitTest, PRBasicMultiIndexCreationDUnitTest, PRColocatedEquiJoinDUnitTest, PRBasicIndexCreationDUnitTest, PRQueryRegionCloseDUnitTest, PRQueryDUnitTest, PRBasicRemoveIndexDUnitTest, PRQueryRegionDestroyedDUnitTest, PRQueryRemoteNodeExceptionDUnitTest, PRInvalidQueryDUnitTest, PRQueryCacheCloseDUnitTest, CompiledInDUnitTest] [vm_0][info 2016/07/28 02:50:23.303 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(setupVM) [vm_0][info 2016/07/28 02:50:23.303 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Got result: null [vm_0] from com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(setupVM) (took 0 ms) [vm_1][info 2016/07/28 02:50:23.304 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(setupVM) [vm_1][info 2016/07/28 02:50:23.304 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Got result: null [vm_1] from com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(setupVM) (took 0 ms) [vm_2][info 2016/07/28 02:50:23.304 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(setupVM) [vm_2][info 2016/07/28 02:50:23.304 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Got result: null [vm_2] from com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(setupVM) (took 0 ms) [vm_3][info 2016/07/28 02:50:23.305 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(setupVM) [vm_3][info 2016/07/28 02:50:23.305 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Got result: null [vm_3] from com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(setupVM) (took 0 ms) [setup] START TEST CompiledInDUnitTest.whenUsingAccessorMultipleEnumBindParametersAreUsedWithInQueryInPartitionRegionReturnCorrectResults [vm_0][info 2016/07/28 02:50:23.328 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] 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[59948], enable-cluster-configuration=false, use-cluster-configuration=false, mcast-port=0, disable-auto-reconnect=true, log-level=info} [vm_0][info 2016/07/28 02:50:23.331 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Startup Configuration: [vm_0] ### GemFire Properties defined with api ### [vm_0]disable-auto-reconnect=true [vm_0]enable-cluster-configuration=false [vm_0]locators=localhost[59948] [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/28 02:50:23.333 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Starting membership services [vm_0][info 2016/07/28 02:50:23.340 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] JGroups channel created (took 7ms) [vm_0][info 2016/07/28 02:50:23.341 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] GemFire P2P Listener started on null [vm_0][info 2016/07/28 02:50:23.342 PDT <Geode Failure Detection Server thread 0> tid=0x11f6] Started failure detection server thread on kuwait.gemstone.com/10.118.32.93:23162. [vm_0][info 2016/07/28 02:50:23.343 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Attempting to join the distributed system through coordinator kuwait(9316:locator)<ec><v0>:1024 using address kuwait(9394)<ec>:1025 [locator][info 2016/07/28 02:50:23.343 PDT <unicast receiver,kuwait-49731> tid=0x2a] received join request from kuwait(9394)<ec>:1025 [locator][info 2016/07/28 02:50:23.644 PDT <Geode Membership View Creator> tid=0x30] View Creator is processing 1 requests for the next membership view [locator][info 2016/07/28 02:50:23.644 PDT <Geode Membership View Creator> tid=0x30] preparing new view View[kuwait(9316:locator)<ec><v0>:1024|265] members: [kuwait(9316:locator)<ec><v0>:1024, kuwait(9394)<ec><v265>:1025{lead}] [locator]failure detection ports: 29523 23162 [vm_0][info 2016/07/28 02:50:23.645 PDT <unicast receiver,kuwait-61886> tid=0x11f2] received new view: View[kuwait(9316:locator)<ec><v0>:1024|265] members: [kuwait(9316:locator)<ec><v0>:1024, kuwait(9394)<ec><v265>:1025{lead}] [vm_0]old view is: null [locator][info 2016/07/28 02:50:23.645 PDT <Geode Membership View Creator> tid=0x30] finished waiting for responses to view preparation [locator][info 2016/07/28 02:50:23.645 PDT <Geode Membership View Creator> tid=0x30] received new view: View[kuwait(9316:locator)<ec><v0>:1024|265] members: [kuwait(9316:locator)<ec><v0>:1024, kuwait(9394)<ec><v265>:1025{lead}] [locator]old view is: View[kuwait(9316:locator)<ec><v0>:1024|264] members: [kuwait(9316:locator)<ec><v0>:1024] shutdown: [kuwait(9403)<ec><v263>:1027, kuwait(9394)<ec><v261>:1025, kuwait(9398)<ec><v262>:1026] [locator][info 2016/07/28 02:50:23.645 PDT <Geode Membership View Creator> tid=0x30] Peer locator received new membership view: View[kuwait(9316:locator)<ec><v0>:1024|265] members: [kuwait(9316:locator)<ec><v0>:1024, kuwait(9394)<ec><v265>:1025{lead}] [vm_0][info 2016/07/28 02:50:23.645 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Finished joining (took 303ms). [vm_0][info 2016/07/28 02:50:23.645 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Starting DistributionManager kuwait(9394)<ec><v265>:1025. (took 313 ms) [locator][info 2016/07/28 02:50:23.645 PDT <Geode Membership View Creator> tid=0x30] sending new view View[kuwait(9316:locator)<ec><v0>:1024|265] members: [kuwait(9316:locator)<ec><v0>:1024, kuwait(9394)<ec><v265>:1025{lead}] [locator]failure detection ports: 29523 23162 [locator][info 2016/07/28 02:50:23.645 PDT <View Message Processor> tid=0x3c] Membership: Processing addition < kuwait(9394)<ec><v265>:1025 > [vm_0][info 2016/07/28 02:50:23.645 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Initial (distribution manager) view = View[kuwait(9316:locator)<ec><v0>:1024|265] members: [kuwait(9316:locator)<ec><v0>:1024, kuwait(9394)<ec><v265>:1025{lead}] [vm_0][info 2016/07/28 02:50:23.645 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Admitting member <kuwait(9316:locator)<ec><v0>:1024>. Now there are 1 non-admin member(s). [locator][info 2016/07/28 02:50:23.645 PDT <View Message Processor> tid=0x3c] Admitting member <kuwait(9394)<ec><v265>:1025>. Now there are 2 non-admin member(s). [vm_0][info 2016/07/28 02:50:23.646 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Admitting member <kuwait(9394)<ec><v265>:1025>. Now there are 2 non-admin member(s). [locator][info 2016/07/28 02:50:23.648 PDT <Pooled High Priority Message Processor 1> tid=0x40] Member kuwait(9394)<ec><v265>:1025 is equivalent or in the same redundancy zone. [vm_0][info 2016/07/28 02:50:23.650 PDT <P2P message reader for kuwait(9316:locator)<ec><v0>:1024 shared unordered uid=218 port=45562> tid=0x11fc] Member kuwait(9316:locator)<ec><v0>:1024 is equivalent or in the same redundancy zone. [vm_0][info 2016/07/28 02:50:23.659 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Initializing region _monitoringRegion_10.118.32.93<v265>1025 [vm_0][info 2016/07/28 02:50:23.659 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Initialization of region _monitoringRegion_10.118.32.93<v265>1025 completed [vm_0][info 2016/07/28 02:50:23.662 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] The cache has been created with "use-cluster-configuration=false". It will not receive any cluster configuration [vm_0][info 2016/07/28 02:50:23.663 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Initializing region PdxTypes [vm_0][info 2016/07/28 02:50:23.663 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Initialization of region PdxTypes completed [vm_0][info 2016/07/28 02:50:23.677 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Cache server connection listener bound to address 0.0.0.0/0.0.0.0:57192 with backlog 1,000. [vm_0][info 2016/07/28 02:50:23.686 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] ClientHealthMonitorThread maximum allowed time between pings: 60,000 [vm_0][info 2016/07/28 02:50:23.693 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] CacheServer Configuration: port=57192 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/28 02:50:23.702 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Initializing region __PR [vm_0][info 2016/07/28 02:50:23.703 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Initialization of region __PR completed [vm_0][info 2016/07/28 02:50:23.705 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Partitioned Region /root/PdxTest is born with prId=80 ident:#root#PdxTest [vm_0][info 2016/07/28 02:50:23.708 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Initializing region _B__root_PdxTest_17 [vm_0][info 2016/07/28 02:50:23.708 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Initialization of region _B__root_PdxTest_17 completed [vm_0][info 2016/07/28 02:50:23.712 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Initializing region _B__root_PdxTest_18 [vm_0][info 2016/07/28 02:50:23.712 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Initialization of region _B__root_PdxTest_18 completed [vm_0][info 2016/07/28 02:50:23.713 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Initializing region _B__root_PdxTest_19 [vm_0][info 2016/07/28 02:50:23.713 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Initialization of region _B__root_PdxTest_19 completed [vm_0][info 2016/07/28 02:50:23.714 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Initializing region _B__root_PdxTest_0 [vm_0][info 2016/07/28 02:50:23.714 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Initialization of region _B__root_PdxTest_0 completed [vm_0][info 2016/07/28 02:50:23.714 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Initializing region _B__root_PdxTest_1 [vm_0][info 2016/07/28 02:50:23.714 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Initialization of region _B__root_PdxTest_1 completed [vm_0][info 2016/07/28 02:50:23.715 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Initializing region _B__root_PdxTest_2 [vm_0][info 2016/07/28 02:50:23.715 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Initialization of region _B__root_PdxTest_2 completed [vm_0][info 2016/07/28 02:50:23.716 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Initializing region _B__root_PdxTest_3 [vm_0][info 2016/07/28 02:50:23.716 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Initialization of region _B__root_PdxTest_3 completed [vm_0][info 2016/07/28 02:50:23.716 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Initializing region _B__root_PdxTest_4 [vm_0][info 2016/07/28 02:50:23.716 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Initialization of region _B__root_PdxTest_4 completed [vm_0][info 2016/07/28 02:50:23.717 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Initializing region _B__root_PdxTest_5 [vm_0][info 2016/07/28 02:50:23.717 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Initialization of region _B__root_PdxTest_5 completed [vm_0][info 2016/07/28 02:50:23.717 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Initializing region _B__root_PdxTest_6 [vm_0][info 2016/07/28 02:50:23.717 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Initialization of region _B__root_PdxTest_6 completed [vm_0][info 2016/07/28 02:50:23.718 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Got result: null [vm_0] from com.gemstone.gemfire.cache.query.dunit.CompiledInDUnitTest$20.run with 0 args on object: "Create Bridge Server" (took 389 ms) [vm_1][info 2016/07/28 02:50:23.739 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] 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[59948], enable-cluster-configuration=false, use-cluster-configuration=false, mcast-port=0, disable-auto-reconnect=true, log-level=info} [vm_1][info 2016/07/28 02:50:23.743 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Startup Configuration: [vm_1] ### GemFire Properties defined with api ### [vm_1]disable-auto-reconnect=true [vm_1]enable-cluster-configuration=false [vm_1]locators=localhost[59948] [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/28 02:50:23.745 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Starting membership services [vm_1][info 2016/07/28 02:50:23.756 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] JGroups channel created (took 11ms) [vm_1][info 2016/07/28 02:50:23.757 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] GemFire P2P Listener started on null [vm_1][info 2016/07/28 02:50:23.758 PDT <Geode Failure Detection Server thread 0> tid=0x1032] Started failure detection server thread on kuwait.gemstone.com/10.118.32.93:64446. [vm_1][info 2016/07/28 02:50:23.759 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Attempting to join the distributed system through coordinator kuwait(9316:locator)<ec><v0>:1024 using address kuwait(9398)<ec>:1026 [locator][info 2016/07/28 02:50:23.759 PDT <unicast receiver,kuwait-49731> tid=0x2a] received join request from kuwait(9398)<ec>:1026 [locator][info 2016/07/28 02:50:24.060 PDT <Geode Membership View Creator> tid=0x30] View Creator is processing 1 requests for the next membership view [locator][info 2016/07/28 02:50:24.060 PDT <Geode Membership View Creator> tid=0x30] preparing new view View[kuwait(9316:locator)<ec><v0>:1024|266] members: [kuwait(9316:locator)<ec><v0>:1024, kuwait(9394)<ec><v265>:1025{lead}, kuwait(9398)<ec><v266>:1026] [locator]failure detection ports: 29523 23162 64446 [vm_1][info 2016/07/28 02:50:24.061 PDT <unicast receiver,kuwait-53260> tid=0x102e] received new view: View[kuwait(9316:locator)<ec><v0>:1024|266] members: [kuwait(9316:locator)<ec><v0>:1024, kuwait(9394)<ec><v265>:1025{lead}, kuwait(9398)<ec><v266>:1026] [vm_1]old view is: null [locator][info 2016/07/28 02:50:24.061 PDT <Geode Membership View Creator> tid=0x30] finished waiting for responses to view preparation [locator][info 2016/07/28 02:50:24.061 PDT <Geode Membership View Creator> tid=0x30] received new view: View[kuwait(9316:locator)<ec><v0>:1024|266] members: [kuwait(9316:locator)<ec><v0>:1024, kuwait(9394)<ec><v265>:1025{lead}, kuwait(9398)<ec><v266>:1026] [locator]old view is: View[kuwait(9316:locator)<ec><v0>:1024|265] members: [kuwait(9316:locator)<ec><v0>:1024, kuwait(9394)<ec><v265>:1025{lead}] [locator][info 2016/07/28 02:50:24.061 PDT <Geode Membership View Creator> tid=0x30] Peer locator received new membership view: View[kuwait(9316:locator)<ec><v0>:1024|266] members: [kuwait(9316:locator)<ec><v0>:1024, kuwait(9394)<ec><v265>:1025{lead}, kuwait(9398)<ec><v266>:1026] [vm_1][info 2016/07/28 02:50:24.061 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Finished joining (took 303ms). [vm_1][info 2016/07/28 02:50:24.061 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Starting DistributionManager kuwait(9398)<ec><v266>:1026. (took 317 ms) [locator][info 2016/07/28 02:50:24.061 PDT <Geode Membership View Creator> tid=0x30] sending new view View[kuwait(9316:locator)<ec><v0>:1024|266] members: [kuwait(9316:locator)<ec><v0>:1024, kuwait(9394)<ec><v265>:1025{lead}, kuwait(9398)<ec><v266>:1026] [locator]failure detection ports: 29523 23162 64446 [vm_1][info 2016/07/28 02:50:24.061 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Initial (distribution manager) view = View[kuwait(9316:locator)<ec><v0>:1024|266] members: [kuwait(9316:locator)<ec><v0>:1024, kuwait(9394)<ec><v265>:1025{lead}, kuwait(9398)<ec><v266>:1026] [vm_1][info 2016/07/28 02:50:24.062 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Admitting member <kuwait(9316:locator)<ec><v0>:1024>. Now there are 1 non-admin member(s). [locator][info 2016/07/28 02:50:24.062 PDT <View Message Processor> tid=0x3c] Membership: Processing addition < kuwait(9398)<ec><v266>:1026 > [vm_1][info 2016/07/28 02:50:24.062 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Admitting member <kuwait(9394)<ec><v265>:1025>. Now there are 2 non-admin member(s). [vm_1][info 2016/07/28 02:50:24.062 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Admitting member <kuwait(9398)<ec><v266>:1026>. Now there are 3 non-admin member(s). [vm_0][info 2016/07/28 02:50:24.062 PDT <unicast receiver,kuwait-61886> tid=0x11f2] received new view: View[kuwait(9316:locator)<ec><v0>:1024|266] members: [kuwait(9316:locator)<ec><v0>:1024, kuwait(9394)<ec><v265>:1025{lead}, kuwait(9398)<ec><v266>:1026] [vm_0]old view is: View[kuwait(9316:locator)<ec><v0>:1024|265] members: [kuwait(9316:locator)<ec><v0>:1024, kuwait(9394)<ec><v265>:1025{lead}] [locator][info 2016/07/28 02:50:24.062 PDT <View Message Processor> tid=0x3c] Admitting member <kuwait(9398)<ec><v266>:1026>. Now there are 3 non-admin member(s). [vm_0][info 2016/07/28 02:50:24.062 PDT <View Message Processor> tid=0x1210] Membership: Processing addition < kuwait(9398)<ec><v266>:1026 > [vm_0][info 2016/07/28 02:50:24.062 PDT <View Message Processor> tid=0x1210] Admitting member <kuwait(9398)<ec><v266>:1026>. Now there are 3 non-admin member(s). [vm_0][info 2016/07/28 02:50:24.065 PDT <Pooled High Priority Message Processor 1> tid=0x1207] Member kuwait(9398)<ec><v266>:1026 is equivalent or in the same redundancy zone. [locator][info 2016/07/28 02:50:24.065 PDT <Pooled High Priority Message Processor 1> tid=0x40] Member kuwait(9398)<ec><v266>:1026 is equivalent or in the same redundancy zone. [vm_1][info 2016/07/28 02:50:24.067 PDT <P2P message reader for kuwait(9394)<ec><v265>:1025 shared unordered uid=339 port=55947> tid=0x103a] Member kuwait(9394)<ec><v265>:1025 is equivalent or in the same redundancy zone. [vm_1][info 2016/07/28 02:50:24.068 PDT <P2P message reader for kuwait(9316:locator)<ec><v0>:1024 shared unordered uid=219 port=55948> tid=0x103b] Member kuwait(9316:locator)<ec><v0>:1024 is equivalent or in the same redundancy zone. [vm_1][info 2016/07/28 02:50:24.077 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Initializing region _monitoringRegion_10.118.32.93<v266>1026 [vm_1][info 2016/07/28 02:50:24.078 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Initialization of region _monitoringRegion_10.118.32.93<v266>1026 completed [vm_1][info 2016/07/28 02:50:24.081 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] The cache has been created with "use-cluster-configuration=false". It will not receive any cluster configuration [vm_1][info 2016/07/28 02:50:24.081 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Initializing region PdxTypes [vm_1][info 2016/07/28 02:50:24.083 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Region PdxTypes requesting initial image from kuwait(9394)<ec><v265>:1025 [vm_1][info 2016/07/28 02:50:24.083 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] PdxTypes is done getting image from kuwait(9394)<ec><v265>:1025. isDeltaGII is false [vm_1][info 2016/07/28 02:50:24.083 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Initialization of region PdxTypes completed [vm_1][info 2016/07/28 02:50:24.098 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Cache server connection listener bound to address 0.0.0.0/0.0.0.0:33410 with backlog 1,000. [vm_1][info 2016/07/28 02:50:24.107 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] ClientHealthMonitorThread maximum allowed time between pings: 60,000 [vm_1][info 2016/07/28 02:50:24.113 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] CacheServer Configuration: port=33410 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/28 02:50:24.123 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Initializing region __PR [vm_1][info 2016/07/28 02:50:24.124 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Region __PR requesting initial image from kuwait(9394)<ec><v265>:1025 [vm_1][info 2016/07/28 02:50:24.126 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] __PR is done getting image from kuwait(9394)<ec><v265>:1025. isDeltaGII is false [vm_1][info 2016/07/28 02:50:24.126 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Initialization of region __PR completed [vm_1][info 2016/07/28 02:50:24.128 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Partitioned Region /root/PdxTest is created with prId=80 [vm_0][info 2016/07/28 02:50:24.136 PDT <Pooled Waiting Message Processor 0> tid=0x11f9] Initializing region _B__root_PdxTest_7 [vm_0][info 2016/07/28 02:50:24.136 PDT <Pooled Waiting Message Processor 0> tid=0x11f9] Initialization of region _B__root_PdxTest_7 completed [vm_0][info 2016/07/28 02:50:24.139 PDT <Pooled Waiting Message Processor 0> tid=0x11f9] Initializing region _B__root_PdxTest_8 [vm_0][info 2016/07/28 02:50:24.139 PDT <Pooled Waiting Message Processor 0> tid=0x11f9] Initialization of region _B__root_PdxTest_8 completed [vm_0][info 2016/07/28 02:50:24.140 PDT <Pooled Waiting Message Processor 0> tid=0x11f9] Initializing region _B__root_PdxTest_9 [vm_0][info 2016/07/28 02:50:24.140 PDT <Pooled Waiting Message Processor 0> tid=0x11f9] Initialization of region _B__root_PdxTest_9 completed [vm_0][info 2016/07/28 02:50:24.141 PDT <Pooled Waiting Message Processor 0> tid=0x11f9] Initializing region _B__root_PdxTest_10 [vm_0][info 2016/07/28 02:50:24.141 PDT <Pooled Waiting Message Processor 0> tid=0x11f9] Initialization of region _B__root_PdxTest_10 completed [vm_0][info 2016/07/28 02:50:24.142 PDT <Pooled Waiting Message Processor 0> tid=0x11f9] Initializing region _B__root_PdxTest_11 [vm_0][info 2016/07/28 02:50:24.142 PDT <Pooled Waiting Message Processor 0> tid=0x11f9] Initialization of region _B__root_PdxTest_11 completed [vm_0][info 2016/07/28 02:50:24.143 PDT <Pooled Waiting Message Processor 0> tid=0x11f9] Initializing region _B__root_PdxTest_12 [vm_0][info 2016/07/28 02:50:24.143 PDT <Pooled Waiting Message Processor 0> tid=0x11f9] Initialization of region _B__root_PdxTest_12 completed [vm_0][info 2016/07/28 02:50:24.144 PDT <Pooled Waiting Message Processor 0> tid=0x11f9] Initializing region _B__root_PdxTest_13 [vm_0][info 2016/07/28 02:50:24.144 PDT <Pooled Waiting Message Processor 0> tid=0x11f9] Initialization of region _B__root_PdxTest_13 completed [vm_0][info 2016/07/28 02:50:24.145 PDT <Pooled Waiting Message Processor 0> tid=0x11f9] Initializing region _B__root_PdxTest_14 [vm_0][info 2016/07/28 02:50:24.145 PDT <Pooled Waiting Message Processor 0> tid=0x11f9] Initialization of region _B__root_PdxTest_14 completed [vm_0][info 2016/07/28 02:50:24.146 PDT <Pooled Waiting Message Processor 0> tid=0x11f9] Initializing region _B__root_PdxTest_15 [vm_0][info 2016/07/28 02:50:24.146 PDT <Pooled Waiting Message Processor 0> tid=0x11f9] Initialization of region _B__root_PdxTest_15 completed [vm_0][info 2016/07/28 02:50:24.147 PDT <Pooled Waiting Message Processor 0> tid=0x11f9] Initializing region _B__root_PdxTest_16 [vm_0][info 2016/07/28 02:50:24.147 PDT <Pooled Waiting Message Processor 0> tid=0x11f9] Initialization of region _B__root_PdxTest_16 completed [vm_1][info 2016/07/28 02:50:24.161 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] 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 421 ms) [vm_0][info 2016/07/28 02:50:24.166 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Received method: com.gemstone.gemfire.cache.query.dunit.CompiledInDUnitTest$1.run with 0 args on object: "Close Client" [vm_0][info 2016/07/28 02:50:24.166 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] ### Close Client. ### [vm_0][info 2016/07/28 02:50:24.166 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] GemFireCache[id = 803231187; isClosing = true; isShutDownAll = false; created = Thu Jul 28 02:50:23 PDT 2016; server = false; copyOnRead = false; lockLease = 120; lockTimeout = 60]: Now closing. [vm_0][info 2016/07/28 02:50:24.169 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Cache server on port 57,192 is shutting down. [vm_0]238.327: [GC (Allocation Failure) [PSYoungGen: 145279K->4463K(148480K)] 237168K->96360K(498176K), 0.0075062 secs] [Times: user=0.03 sys=0.00, real=0.01 secs] [vm_0][info 2016/07/28 02:50:24.188 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Shutting down DistributionManager kuwait(9394)<ec><v265>:1025. [vm_1][info 2016/07/28 02:50:24.189 PDT <Pooled High Priority Message Processor 1> tid=0x1046] received leave request from kuwait(9394)<ec><v265>:1025 for kuwait(9394)<ec><v265>:1025 [locator][info 2016/07/28 02:50:24.189 PDT <Pooled High Priority Message Processor 1> tid=0x40] received leave request from kuwait(9394)<ec><v265>:1025 for kuwait(9394)<ec><v265>:1025 [vm_1][info 2016/07/28 02:50:24.189 PDT <Pooled High Priority Message Processor 1> tid=0x1046] Member at kuwait(9394)<ec><v265>:1025 gracefully left the distributed cache: shutdown message received [locator][info 2016/07/28 02:50:24.189 PDT <Pooled High Priority Message Processor 1> tid=0x40] Member at kuwait(9394)<ec><v265>:1025 gracefully left the distributed cache: shutdown message received [vm_0][info 2016/07/28 02:50:24.289 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Now closing distribution for kuwait(9394)<ec><v265>:1025 [vm_0][info 2016/07/28 02:50:24.290 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Stopping membership services [locator][info 2016/07/28 02:50:24.290 PDT <unicast receiver,kuwait-49731> tid=0x2a] received leave request from kuwait(9394)<ec><v265>:1025 for kuwait(9394)<ec><v265>:1025 [vm_1][info 2016/07/28 02:50:24.290 PDT <unicast receiver,kuwait-53260> tid=0x102e] received leave request from kuwait(9394)<ec><v265>:1025 for kuwait(9394)<ec><v265>:1025 [vm_0][info 2016/07/28 02:50:24.290 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] GMSHealthMonitor server socket is closed in stopServices(). [vm_0][info 2016/07/28 02:50:24.291 PDT <Geode Failure Detection Server thread 0> tid=0x11f6] GMSHealthMonitor server thread exiting [vm_0][info 2016/07/28 02:50:24.291 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] GMSHealthMonitor serverSocketExecutor is terminated [vm_0][info 2016/07/28 02:50:24.295 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] DistributionManager stopped in 106ms. [vm_0][info 2016/07/28 02:50:24.295 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Marking DistributionManager kuwait(9394)<ec><v265>:1025 as closed. [vm_0][info 2016/07/28 02:50:24.295 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Got result: null [vm_0] from com.gemstone.gemfire.cache.query.dunit.CompiledInDUnitTest$1.run with 0 args on object: "Close Client" (took 129 ms) [vm_1][info 2016/07/28 02:50:24.299 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Received method: com.gemstone.gemfire.cache.query.dunit.CompiledInDUnitTest$1.run with 0 args on object: "Close Client" [vm_1][info 2016/07/28 02:50:24.299 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] ### Close Client. ### [vm_1][info 2016/07/28 02:50:24.300 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] GemFireCache[id = 1247209337; isClosing = true; isShutDownAll = false; created = Thu Jul 28 02:50:24 PDT 2016; server = false; copyOnRead = false; lockLease = 120; lockTimeout = 60]: Now closing. [vm_1][info 2016/07/28 02:50:24.301 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Cache server on port 33,410 is shutting down. [vm_1][info 2016/07/28 02:50:24.306 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Shutting down DistributionManager kuwait(9398)<ec><v266>:1026. [locator][info 2016/07/28 02:50:24.307 PDT <Pooled High Priority Message Processor 1> tid=0x40] received leave request from kuwait(9398)<ec><v266>:1026 for kuwait(9398)<ec><v266>:1026 [locator][info 2016/07/28 02:50:24.307 PDT <Pooled High Priority Message Processor 1> tid=0x40] Member at kuwait(9398)<ec><v266>:1026 gracefully left the distributed cache: shutdown message received [vm_1][info 2016/07/28 02:50:24.407 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Now closing distribution for kuwait(9398)<ec><v266>:1026 [vm_1][info 2016/07/28 02:50:24.407 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Stopping membership services [locator][info 2016/07/28 02:50:24.408 PDT <unicast receiver,kuwait-49731> tid=0x2a] received leave request from kuwait(9398)<ec><v266>:1026 for kuwait(9398)<ec><v266>:1026 [vm_1][info 2016/07/28 02:50:24.408 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] GMSHealthMonitor server socket is closed in stopServices(). [vm_1][info 2016/07/28 02:50:24.408 PDT <Geode Failure Detection Server thread 0> tid=0x1032] GMSHealthMonitor server thread exiting [vm_1][info 2016/07/28 02:50:24.408 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] GMSHealthMonitor serverSocketExecutor is terminated [vm_1][info 2016/07/28 02:50:24.411 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] DistributionManager stopped in 105ms. [vm_1][info 2016/07/28 02:50:24.411 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Marking DistributionManager kuwait(9398)<ec><v266>:1026 as closed. [vm_1][info 2016/07/28 02:50:24.412 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Got result: null [vm_1] from com.gemstone.gemfire.cache.query.dunit.CompiledInDUnitTest$1.run with 0 args on object: "Close Client" (took 113 ms) [vm_3][info 2016/07/28 02:50:24.431 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Received method: com.gemstone.gemfire.cache.query.dunit.CompiledInDUnitTest$1.run with 0 args on object: "Close Client" [vm_3][info 2016/07/28 02:50:24.432 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] ### Close Client. ### [vm_3][info 2016/07/28 02:50:24.432 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] 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/28 02:50:24.433 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Received method: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$9/1993756657.run with 0 args on object: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$9/1993756657@15e89968 [vm_0][info 2016/07/28 02:50:24.433 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Got result: null [vm_0] from com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$9/1993756657.run with 0 args on object: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$9/1993756657@15e89968 (took 0 ms) [vm_1][info 2016/07/28 02:50:24.434 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Received method: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$10/9633645.run with 0 args on object: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$10/9633645@7359ff91 [vm_1][info 2016/07/28 02:50:24.434 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Got result: null [vm_1] from com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$10/9633645.run with 0 args on object: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$10/9633645@7359ff91 (took 0 ms) [vm_2][info 2016/07/28 02:50:24.435 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Received method: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$10/1327170274.run with 0 args on object: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$10/1327170274@10e1b131 [vm_2][info 2016/07/28 02:50:24.435 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Got result: null [vm_2] from com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$10/1327170274.run with 0 args on object: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$10/1327170274@10e1b131 (took 0 ms) [vm_3][info 2016/07/28 02:50:24.435 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Received method: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$5/1477154395.run with 0 args on object: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$5/1477154395@5f79c56b [vm_3][info 2016/07/28 02:50:24.436 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Got result: null [vm_3] from com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$5/1477154395.run with 0 args on object: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$5/1477154395@5f79c56b (took 0 ms) [vm_0][info 2016/07/28 02:50:24.436 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(tearDownCreationStackGenerator) [vm_0][info 2016/07/28 02:50:24.436 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Got result: null [vm_0] from com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(tearDownCreationStackGenerator) (took 0 ms) [vm_1][info 2016/07/28 02:50:24.437 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(tearDownCreationStackGenerator) [vm_1][info 2016/07/28 02:50:24.437 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Got result: null [vm_1] from com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(tearDownCreationStackGenerator) (took 0 ms) [vm_2][info 2016/07/28 02:50:24.437 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(tearDownCreationStackGenerator) [vm_2][info 2016/07/28 02:50:24.437 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Got result: null [vm_2] from com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(tearDownCreationStackGenerator) (took 0 ms) [vm_3][info 2016/07/28 02:50:24.438 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(tearDownCreationStackGenerator) [vm_3][info 2016/07/28 02:50:24.438 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Got result: null [vm_3] from com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(tearDownCreationStackGenerator) (took 0 ms) [vm_0][info 2016/07/28 02:50:24.438 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(tearDownVM) [vm_0][info 2016/07/28 02:50:24.438 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Got result: null [vm_0] from com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(tearDownVM) (took 0 ms) [vm_1][info 2016/07/28 02:50:24.439 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(tearDownVM) [vm_1][info 2016/07/28 02:50:24.439 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Got result: null [vm_1] from com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(tearDownVM) (took 0 ms) [vm_2][info 2016/07/28 02:50:24.439 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(tearDownVM) [vm_2][info 2016/07/28 02:50:24.440 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Got result: null [vm_2] from com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(tearDownVM) (took 0 ms) [vm_3][info 2016/07/28 02:50:24.440 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(tearDownVM) [vm_3][info 2016/07/28 02:50:24.440 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Got result: null [vm_3] from com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(tearDownVM) (took 0 ms) [locator][info 2016/07/28 02:50:24.441 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Received method: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/1814869608.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/1814869608@fe46a45 [locator][info 2016/07/28 02:50:24.441 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Got result: null [locator] from com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/1814869608.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/1814869608@fe46a45 (took 0 ms) Previously run tests: [PRBasicQueryDUnitTest, PRBasicMultiIndexCreationDUnitTest, PRColocatedEquiJoinDUnitTest, PRBasicIndexCreationDUnitTest, PRQueryRegionCloseDUnitTest, PRQueryDUnitTest, PRBasicRemoveIndexDUnitTest, PRQueryRegionDestroyedDUnitTest, PRQueryRemoteNodeExceptionDUnitTest, PRInvalidQueryDUnitTest, PRQueryCacheCloseDUnitTest, CompiledInDUnitTest] [vm_0][info 2016/07/28 02:50:24.442 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(setupVM) [vm_0][info 2016/07/28 02:50:24.443 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Got result: null [vm_0] from com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(setupVM) (took 0 ms) [vm_1][info 2016/07/28 02:50:24.443 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(setupVM) [vm_1][info 2016/07/28 02:50:24.443 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Got result: null [vm_1] from com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(setupVM) (took 0 ms) [vm_2][info 2016/07/28 02:50:24.443 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(setupVM) [vm_2][info 2016/07/28 02:50:24.443 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Got result: null [vm_2] from com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(setupVM) (took 0 ms) [vm_3][info 2016/07/28 02:50:24.444 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(setupVM) [vm_3][info 2016/07/28 02:50:24.444 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Got result: null [vm_3] from com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(setupVM) (took 0 ms) [setup] START TEST CompiledInDUnitTest.whenMultipleTypeBindParameterIsUsedWithInQueryAndMapIndexIsPresentReturnCorrectResults [vm_0][info 2016/07/28 02:50:24.447 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] 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[59948], enable-cluster-configuration=false, use-cluster-configuration=false, mcast-port=0, disable-auto-reconnect=true, log-level=info} [vm_0][info 2016/07/28 02:50:24.450 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Startup Configuration: [vm_0] ### GemFire Properties defined with api ### [vm_0]disable-auto-reconnect=true [vm_0]enable-cluster-configuration=false [vm_0]locators=localhost[59948] [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]distri ...[truncated 916333 chars]... tionManager kuwait(9398)<ec><v307>:1026 as closed. [vm_1][info 2016/07/28 02:50:40.141 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Got result: null [vm_1] from com.gemstone.gemfire.cache.query.dunit.CompiledInDUnitTest$1.run with 0 args on object: "Close Client" (took 110 ms) [vm_3][info 2016/07/28 02:50:40.142 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Received method: com.gemstone.gemfire.cache.query.dunit.CompiledInDUnitTest$1.run with 0 args on object: "Close Client" [vm_3][info 2016/07/28 02:50:40.142 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] ### Close Client. ### [vm_3][info 2016/07/28 02:50:40.142 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] 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/28 02:50:40.143 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Received method: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$9/1993756657.run with 0 args on object: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$9/1993756657@15e89968 [vm_0][info 2016/07/28 02:50:40.143 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Got result: null [vm_0] from com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$9/1993756657.run with 0 args on object: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$9/1993756657@15e89968 (took 0 ms) [vm_1][info 2016/07/28 02:50:40.144 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Received method: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$10/9633645.run with 0 args on object: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$10/9633645@7359ff91 [vm_1][info 2016/07/28 02:50:40.144 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Got result: null [vm_1] from com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$10/9633645.run with 0 args on object: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$10/9633645@7359ff91 (took 0 ms) [vm_2][info 2016/07/28 02:50:40.145 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Received method: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$10/1327170274.run with 0 args on object: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$10/1327170274@10e1b131 [vm_2][info 2016/07/28 02:50:40.145 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Got result: null [vm_2] from com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$10/1327170274.run with 0 args on object: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$10/1327170274@10e1b131 (took 0 ms) [vm_3][info 2016/07/28 02:50:40.146 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Received method: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$5/1477154395.run with 0 args on object: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$5/1477154395@5f79c56b [vm_3][info 2016/07/28 02:50:40.146 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Got result: null [vm_3] from com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$5/1477154395.run with 0 args on object: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$5/1477154395@5f79c56b (took 0 ms) [vm_0][info 2016/07/28 02:50:40.146 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(tearDownCreationStackGenerator) [vm_0][info 2016/07/28 02:50:40.146 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Got result: null [vm_0] from com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(tearDownCreationStackGenerator) (took 0 ms) [vm_1][info 2016/07/28 02:50:40.147 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(tearDownCreationStackGenerator) [vm_1][info 2016/07/28 02:50:40.147 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Got result: null [vm_1] from com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(tearDownCreationStackGenerator) (took 0 ms) [vm_2][info 2016/07/28 02:50:40.147 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(tearDownCreationStackGenerator) [vm_2][info 2016/07/28 02:50:40.147 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Got result: null [vm_2] from com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(tearDownCreationStackGenerator) (took 0 ms) [vm_3][info 2016/07/28 02:50:40.147 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(tearDownCreationStackGenerator) [vm_3][info 2016/07/28 02:50:40.147 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Got result: null [vm_3] from com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(tearDownCreationStackGenerator) (took 0 ms) [vm_0][info 2016/07/28 02:50:40.148 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(tearDownVM) [vm_0][info 2016/07/28 02:50:40.148 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Got result: null [vm_0] from com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(tearDownVM) (took 0 ms) [vm_1][info 2016/07/28 02:50:40.148 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(tearDownVM) [vm_1][info 2016/07/28 02:50:40.148 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Got result: null [vm_1] from com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(tearDownVM) (took 0 ms) [vm_2][info 2016/07/28 02:50:40.149 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(tearDownVM) [vm_2][info 2016/07/28 02:50:40.149 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Got result: null [vm_2] from com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(tearDownVM) (took 0 ms) [vm_3][info 2016/07/28 02:50:40.149 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(tearDownVM) [vm_3][info 2016/07/28 02:50:40.149 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Got result: null [vm_3] from com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(tearDownVM) (took 0 ms) [locator][info 2016/07/28 02:50:40.150 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Received method: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/1814869608.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/1814869608@fe46a45 [locator][info 2016/07/28 02:50:40.150 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Got result: null [locator] from com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/1814869608.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/1814869608@fe46a45 (took 0 ms) Previously run tests: [PRBasicQueryDUnitTest, PRBasicMultiIndexCreationDUnitTest, PRColocatedEquiJoinDUnitTest, PRBasicIndexCreationDUnitTest, PRQueryRegionCloseDUnitTest, PRQueryDUnitTest, PRBasicRemoveIndexDUnitTest, PRQueryRegionDestroyedDUnitTest, PRQueryRemoteNodeExceptionDUnitTest, PRInvalidQueryDUnitTest, PRQueryCacheCloseDUnitTest, CompiledInDUnitTest] [vm_0][info 2016/07/28 02:50:40.151 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(setupVM) [vm_0][info 2016/07/28 02:50:40.151 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Got result: null [vm_0] from com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(setupVM) (took 0 ms) [vm_1][info 2016/07/28 02:50:40.152 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(setupVM) [vm_1][info 2016/07/28 02:50:40.152 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Got result: null [vm_1] from com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(setupVM) (took 0 ms) [vm_2][info 2016/07/28 02:50:40.152 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(setupVM) [vm_2][info 2016/07/28 02:50:40.152 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Got result: null [vm_2] from com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(setupVM) (took 0 ms) [vm_3][info 2016/07/28 02:50:40.152 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(setupVM) [vm_3][info 2016/07/28 02:50:40.152 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Got result: null [vm_3] from com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(setupVM) (took 0 ms) [setup] START TEST CompiledInDUnitTest.whenEnumBindArgumentIsMatchedInSetWithIteratingFieldShouldReturnResults [vm_0][info 2016/07/28 02:50:40.156 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] 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[59948], enable-cluster-configuration=false, use-cluster-configuration=false, mcast-port=0, disable-auto-reconnect=true, log-level=info} [vm_0][info 2016/07/28 02:50:40.158 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Startup Configuration: [vm_0] ### GemFire Properties defined with api ### [vm_0]disable-auto-reconnect=true [vm_0]enable-cluster-configuration=false [vm_0]locators=localhost[59948] [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/28 02:50:40.160 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Starting membership services [vm_0][info 2016/07/28 02:50:40.167 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] JGroups channel created (took 7ms) [vm_0][info 2016/07/28 02:50:40.169 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] GemFire P2P Listener started on null [vm_0][info 2016/07/28 02:50:40.169 PDT <Geode Failure Detection Server thread 0> tid=0x16a3] Started failure detection server thread on kuwait.gemstone.com/10.118.32.93:29082. [vm_0][info 2016/07/28 02:50:40.170 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Attempting to join the distributed system through coordinator kuwait(9316:locator)<ec><v0>:1024 using address kuwait(9394)<ec>:1025 [locator][info 2016/07/28 02:50:40.171 PDT <unicast receiver,kuwait-49731> tid=0x2a] received join request from kuwait(9394)<ec>:1025 [locator][info 2016/07/28 02:50:40.271 PDT <Geode Membership View Creator> tid=0x30] View Creator is processing 5 requests for the next membership view [locator][info 2016/07/28 02:50:40.271 PDT <Geode Membership View Creator> tid=0x30] preparing new view View[kuwait(9316:locator)<ec><v0>:1024|308] members: [kuwait(9316:locator)<ec><v0>:1024, kuwait(9394)<ec><v308>:1025{lead}] shutdown: [kuwait(9394)<ec><v306>:1025, kuwait(9398)<ec><v307>:1026] [locator]failure detection ports: 29523 29082 [vm_0][info 2016/07/28 02:50:40.272 PDT <unicast receiver,kuwait-6440> tid=0x169f] received new view: View[kuwait(9316:locator)<ec><v0>:1024|308] members: [kuwait(9316:locator)<ec><v0>:1024, kuwait(9394)<ec><v308>:1025{lead}] shutdown: [kuwait(9394)<ec><v306>:1025, kuwait(9398)<ec><v307>:1026] [vm_0]old view is: null [locator][info 2016/07/28 02:50:40.272 PDT <Geode Membership View Creator> tid=0x30] finished waiting for responses to view preparation [locator][info 2016/07/28 02:50:40.272 PDT <Geode Membership View Creator> tid=0x30] received new view: View[kuwait(9316:locator)<ec><v0>:1024|308] members: [kuwait(9316:locator)<ec><v0>:1024, kuwait(9394)<ec><v308>:1025{lead}] shutdown: [kuwait(9394)<ec><v306>:1025, kuwait(9398)<ec><v307>:1026] [locator]old view is: View[kuwait(9316:locator)<ec><v0>:1024|307] members: [kuwait(9316:locator)<ec><v0>:1024, kuwait(9394)<ec><v306>:1025{lead}, kuwait(9398)<ec><v307>:1026] [locator][info 2016/07/28 02:50:40.272 PDT <Geode Membership View Creator> tid=0x30] Peer locator received new membership view: View[kuwait(9316:locator)<ec><v0>:1024|308] members: [kuwait(9316:locator)<ec><v0>:1024, kuwait(9394)<ec><v308>:1025{lead}] shutdown: [kuwait(9394)<ec><v306>:1025, kuwait(9398)<ec><v307>:1026] [vm_0][info 2016/07/28 02:50:40.272 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Finished joining (took 103ms). [vm_0][info 2016/07/28 02:50:40.272 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Starting DistributionManager kuwait(9394)<ec><v308>:1025. (took 113 ms) [vm_0][info 2016/07/28 02:50:40.272 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Initial (distribution manager) view = View[kuwait(9316:locator)<ec><v0>:1024|308] members: [kuwait(9316:locator)<ec><v0>:1024, kuwait(9394)<ec><v308>:1025{lead}] shutdown: [kuwait(9394)<ec><v306>:1025, kuwait(9398)<ec><v307>:1026] [locator][info 2016/07/28 02:50:40.272 PDT <Geode Membership View Creator> tid=0x30] sending new view View[kuwait(9316:locator)<ec><v0>:1024|308] members: [kuwait(9316:locator)<ec><v0>:1024, kuwait(9394)<ec><v308>:1025{lead}] shutdown: [kuwait(9394)<ec><v306>:1025, kuwait(9398)<ec><v307>:1026] [locator]failure detection ports: 29523 29082 [locator][info 2016/07/28 02:50:40.272 PDT <View Message Processor> tid=0x3c] Membership: Processing addition < kuwait(9394)<ec><v308>:1025 > [vm_0][info 2016/07/28 02:50:40.272 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Admitting member <kuwait(9316:locator)<ec><v0>:1024>. Now there are 1 non-admin member(s). [vm_0][info 2016/07/28 02:50:40.272 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Admitting member <kuwait(9394)<ec><v308>:1025>. Now there are 2 non-admin member(s). [locator][info 2016/07/28 02:50:40.272 PDT <View Message Processor> tid=0x3c] Admitting member <kuwait(9394)<ec><v308>:1025>. Now there are 2 non-admin member(s). [locator][info 2016/07/28 02:50:40.274 PDT <Pooled High Priority Message Processor 2> tid=0x11a] Member kuwait(9394)<ec><v308>:1025 is equivalent or in the same redundancy zone. [vm_0][info 2016/07/28 02:50:40.276 PDT <P2P message reader for kuwait(9316:locator)<ec><v0>:1024 shared unordered uid=260 port=45099> tid=0x16a8] Member kuwait(9316:locator)<ec><v0>:1024 is equivalent or in the same redundancy zone. [vm_0][info 2016/07/28 02:50:40.284 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Initializing region _monitoringRegion_10.118.32.93<v308>1025 [vm_0][info 2016/07/28 02:50:40.285 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Initialization of region _monitoringRegion_10.118.32.93<v308>1025 completed [vm_0][info 2016/07/28 02:50:40.288 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] The cache has been created with "use-cluster-configuration=false". It will not receive any cluster configuration [vm_0][info 2016/07/28 02:50:40.289 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Initializing region PdxTypes [vm_0][info 2016/07/28 02:50:40.289 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Initialization of region PdxTypes completed [vm_0][info 2016/07/28 02:50:40.291 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Cache server connection listener bound to address 0.0.0.0/0.0.0.0:58560 with backlog 1,000. [vm_0][info 2016/07/28 02:50:40.291 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] ClientHealthMonitorThread maximum allowed time between pings: 60,000 [vm_0][info 2016/07/28 02:50:40.292 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] CacheServer Configuration: port=58560 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/28 02:50:40.293 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Initializing region root [vm_0][info 2016/07/28 02:50:40.293 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Initialization of region root completed [vm_0][info 2016/07/28 02:50:40.294 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Initializing region PdxTest [vm_0][info 2016/07/28 02:50:40.294 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Initialization of region PdxTest completed [vm_0][info 2016/07/28 02:50:40.295 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Using Compact Map Range index implementation for 'myIndex' on region /root/PdxTest [vm_0][info 2016/07/28 02:50:40.295 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Initialized and loaded entries into the index [vm_0][info 2016/07/28 02:50:40.296 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Got result: null [vm_0] from com.gemstone.gemfire.cache.query.dunit.CompiledInDUnitTest$12.run with 0 args on object: "Create Bridge Server" (took 139 ms) [vm_1][info 2016/07/28 02:50:40.297 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] 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[59948], enable-cluster-configuration=false, use-cluster-configuration=false, mcast-port=0, disable-auto-reconnect=true, log-level=info} [vm_1][info 2016/07/28 02:50:40.300 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Startup Configuration: [vm_1] ### GemFire Properties defined with api ### [vm_1]disable-auto-reconnect=true [vm_1]enable-cluster-configuration=false [vm_1]locators=localhost[59948] [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/28 02:50:40.301 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Starting membership services [vm_1][info 2016/07/28 02:50:40.309 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] JGroups channel created (took 8ms) [vm_1][info 2016/07/28 02:50:40.310 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] GemFire P2P Listener started on null [vm_1][info 2016/07/28 02:50:40.311 PDT <Geode Failure Detection Server thread 0> tid=0x1520] Started failure detection server thread on kuwait.gemstone.com/10.118.32.93:50073. [vm_1][info 2016/07/28 02:50:40.312 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Attempting to join the distributed system through coordinator kuwait(9316:locator)<ec><v0>:1024 using address kuwait(9398)<ec>:1026 [locator][info 2016/07/28 02:50:40.312 PDT <unicast receiver,kuwait-49731> tid=0x2a] received join request from kuwait(9398)<ec>:1026 [locator][info 2016/07/28 02:50:40.613 PDT <Geode Membership View Creator> tid=0x30] View Creator is processing 1 requests for the next membership view [locator][info 2016/07/28 02:50:40.613 PDT <Geode Membership View Creator> tid=0x30] preparing new view View[kuwait(9316:locator)<ec><v0>:1024|309] members: [kuwait(9316:locator)<ec><v0>:1024, kuwait(9394)<ec><v308>:1025{lead}, kuwait(9398)<ec><v309>:1026] [locator]failure detection ports: 29523 29082 50073 [locator][info 2016/07/28 02:50:40.614 PDT <Geode Membership View Creator> tid=0x30] finished waiting for responses to view preparation [vm_1][info 2016/07/28 02:50:40.614 PDT <unicast receiver,kuwait-64167> tid=0x151c] received new view: View[kuwait(9316:locator)<ec><v0>:1024|309] members: [kuwait(9316:locator)<ec><v0>:1024, kuwait(9394)<ec><v308>:1025{lead}, kuwait(9398)<ec><v309>:1026] [vm_1]old view is: null [vm_1][info 2016/07/28 02:50:40.614 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Finished joining (took 303ms). [vm_1][info 2016/07/28 02:50:40.614 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Starting DistributionManager kuwait(9398)<ec><v309>:1026. (took 313 ms) [locator][info 2016/07/28 02:50:40.614 PDT <Geode Membership View Creator> tid=0x30] received new view: View[kuwait(9316:locator)<ec><v0>:1024|309] members: [kuwait(9316:locator)<ec><v0>:1024, kuwait(9394)<ec><v308>:1025{lead}, kuwait(9398)<ec><v309>:1026] [locator]old view is: View[kuwait(9316:locator)<ec><v0>:1024|308] members: [kuwait(9316:locator)<ec><v0>:1024, kuwait(9394)<ec><v308>:1025{lead}] shutdown: [kuwait(9394)<ec><v306>:1025, kuwait(9398)<ec><v307>:1026] [locator][info 2016/07/28 02:50:40.614 PDT <Geode Membership View Creator> tid=0x30] Peer locator received new membership view: View[kuwait(9316:locator)<ec><v0>:1024|309] members: [kuwait(9316:locator)<ec><v0>:1024, kuwait(9394)<ec><v308>:1025{lead}, kuwait(9398)<ec><v309>:1026] [locator][info 2016/07/28 02:50:40.614 PDT <Geode Membership View Creator> tid=0x30] sending new view View[kuwait(9316:locator)<ec><v0>:1024|309] members: [kuwait(9316:locator)<ec><v0>:1024, kuwait(9394)<ec><v308>:1025{lead}, kuwait(9398)<ec><v309>:1026] [locator]failure detection ports: 29523 29082 50073 [locator][info 2016/07/28 02:50:40.614 PDT <View Message Processor> tid=0x3c] Membership: Processing addition < kuwait(9398)<ec><v309>:1026 > [vm_1][info 2016/07/28 02:50:40.615 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Initial (distribution manager) view = View[kuwait(9316:locator)<ec><v0>:1024|309] members: [kuwait(9316:locator)<ec><v0>:1024, kuwait(9394)<ec><v308>:1025{lead}, kuwait(9398)<ec><v309>:1026] [locator][info 2016/07/28 02:50:40.615 PDT <View Message Processor> tid=0x3c] Admitting member <kuwait(9398)<ec><v309>:1026>. Now there are 3 non-admin member(s). [vm_0][info 2016/07/28 02:50:40.615 PDT <unicast receiver,kuwait-6440> tid=0x169f] received new view: View[kuwait(9316:locator)<ec><v0>:1024|309] members: [kuwait(9316:locator)<ec><v0>:1024, kuwait(9394)<ec><v308>:1025{lead}, kuwait(9398)<ec><v309>:1026] [vm_0]old view is: View[kuwait(9316:locator)<ec><v0>:1024|308] members: [kuwait(9316:locator)<ec><v0>:1024, kuwait(9394)<ec><v308>:1025{lead}] shutdown: [kuwait(9394)<ec><v306>:1025, kuwait(9398)<ec><v307>:1026] [vm_1][info 2016/07/28 02:50:40.615 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Admitting member <kuwait(9316:locator)<ec><v0>:1024>. Now there are 1 non-admin member(s). [vm_1][info 2016/07/28 02:50:40.615 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Admitting member <kuwait(9394)<ec><v308>:1025>. Now there are 2 non-admin member(s). [vm_1][info 2016/07/28 02:50:40.615 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Admitting member <kuwait(9398)<ec><v309>:1026>. Now there are 3 non-admin member(s). [vm_0][info 2016/07/28 02:50:40.615 PDT <View Message Processor> tid=0x16ba] Membership: Processing addition < kuwait(9398)<ec><v309>:1026 > [vm_0][info 2016/07/28 02:50:40.615 PDT <View Message Processor> tid=0x16ba] Admitting member <kuwait(9398)<ec><v309>:1026>. Now there are 3 non-admin member(s). [vm_0][info 2016/07/28 02:50:40.617 PDT <Pooled High Priority Message Processor 1> tid=0x16b3] Member kuwait(9398)<ec><v309>:1026 is equivalent or in the same redundancy zone. [locator][info 2016/07/28 02:50:40.617 PDT <Pooled High Priority Message Processor 2> tid=0x11a] Member kuwait(9398)<ec><v309>:1026 is equivalent or in the same redundancy zone. [vm_1][info 2016/07/28 02:50:40.618 PDT <P2P message reader for kuwait(9394)<ec><v308>:1025 shared unordered uid=423 port=57958> tid=0x1528] Member kuwait(9394)<ec><v308>:1025 is equivalent or in the same redundancy zone. [vm_1][info 2016/07/28 02:50:40.619 PDT <P2P message reader for kuwait(9316:locator)<ec><v0>:1024 shared unordered uid=261 port=57959> tid=0x1529] Member kuwait(9316:locator)<ec><v0>:1024 is equivalent or in the same redundancy zone. [vm_1][info 2016/07/28 02:50:40.627 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Initializing region _monitoringRegion_10.118.32.93<v309>1026 [vm_1][info 2016/07/28 02:50:40.628 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Initialization of region _monitoringRegion_10.118.32.93<v309>1026 completed [vm_1][info 2016/07/28 02:50:40.631 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] The cache has been created with "use-cluster-configuration=false". It will not receive any cluster configuration [vm_1][info 2016/07/28 02:50:40.632 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Initializing region PdxTypes [vm_1][info 2016/07/28 02:50:40.633 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Region PdxTypes requesting initial image from kuwait(9394)<ec><v308>:1025 [vm_1][info 2016/07/28 02:50:40.634 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] PdxTypes is done getting image from kuwait(9394)<ec><v308>:1025. isDeltaGII is false [vm_1][info 2016/07/28 02:50:40.634 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Initialization of region PdxTypes completed [vm_1][info 2016/07/28 02:50:40.636 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Cache server connection listener bound to address 0.0.0.0/0.0.0.0:42027 with backlog 1,000. [vm_1][info 2016/07/28 02:50:40.637 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] ClientHealthMonitorThread maximum allowed time between pings: 60,000 [vm_1][info 2016/07/28 02:50:40.638 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] CacheServer Configuration: port=42027 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/28 02:50:40.639 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Initializing region root [vm_1][info 2016/07/28 02:50:40.640 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Region root requesting initial image from kuwait(9394)<ec><v308>:1025 [vm_1][info 2016/07/28 02:50:40.641 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] root is done getting image from kuwait(9394)<ec><v308>:1025. isDeltaGII is false [vm_1][info 2016/07/28 02:50:40.641 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Initialization of region root completed [vm_1][info 2016/07/28 02:50:40.641 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Initializing region PdxTest [vm_1][info 2016/07/28 02:50:40.642 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Region PdxTest requesting initial image from kuwait(9394)<ec><v308>:1025 [vm_1][info 2016/07/28 02:50:40.643 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] PdxTest is done getting image from kuwait(9394)<ec><v308>:1025. isDeltaGII is false [vm_1][info 2016/07/28 02:50:40.643 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Initialization of region PdxTest completed [vm_1][info 2016/07/28 02:50:40.644 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Using Compact Map Range index implementation for 'myIndex' on region /root/PdxTest [vm_1][info 2016/07/28 02:50:40.645 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Initialized and loaded entries into the index [vm_1][info 2016/07/28 02:50:40.646 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] 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 348 ms) [vm_0][info 2016/07/28 02:50:40.647 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Received method: com.gemstone.gemfire.cache.query.dunit.CompiledInDUnitTest$1.run with 0 args on object: "Close Client" [vm_0][info 2016/07/28 02:50:40.647 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] ### Close Client. ### [vm_0][info 2016/07/28 02:50:40.648 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] GemFireCache[id = 1412466484; isClosing = true; isShutDownAll = false; created = Thu Jul 28 02:50:40 PDT 2016; server = false; copyOnRead = false; lockLease = 120; lockTimeout = 60]: Now closing. [vm_0][info 2016/07/28 02:50:40.649 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Cache server on port 58,560 is shutting down. [vm_0][info 2016/07/28 02:50:40.653 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Shutting down DistributionManager kuwait(9394)<ec><v308>:1025. [vm_1][info 2016/07/28 02:50:40.654 PDT <Pooled High Priority Message Processor 2> tid=0x1535] received leave request from kuwait(9394)<ec><v308>:1025 for kuwait(9394)<ec><v308>:1025 [locator][info 2016/07/28 02:50:40.654 PDT <Pooled High Priority Message Processor 2> tid=0x11a] received leave request from kuwait(9394)<ec><v308>:1025 for kuwait(9394)<ec><v308>:1025 [vm_1][info 2016/07/28 02:50:40.654 PDT <Pooled High Priority Message Processor 2> tid=0x1535] Member at kuwait(9394)<ec><v308>:1025 gracefully left the distributed cache: shutdown message received [locator][info 2016/07/28 02:50:40.654 PDT <Pooled High Priority Message Processor 2> tid=0x11a] Member at kuwait(9394)<ec><v308>:1025 gracefully left the distributed cache: shutdown message received [vm_0][info 2016/07/28 02:50:40.754 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Now closing distribution for kuwait(9394)<ec><v308>:1025 [vm_0][info 2016/07/28 02:50:40.754 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Stopping membership services [vm_0][info 2016/07/28 02:50:40.754 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] GMSHealthMonitor server socket is closed in stopServices(). [locator][info 2016/07/28 02:50:40.754 PDT <unicast receiver,kuwait-49731> tid=0x2a] received leave request from kuwait(9394)<ec><v308>:1025 for kuwait(9394)<ec><v308>:1025 [vm_0][info 2016/07/28 02:50:40.754 PDT <Geode Failure Detection Server thread 0> tid=0x16a3] GMSHealthMonitor server thread exiting [vm_0][info 2016/07/28 02:50:40.755 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] GMSHealthMonitor serverSocketExecutor is terminated [vm_1][info 2016/07/28 02:50:40.755 PDT <unicast receiver,kuwait-64167> tid=0x151c] received leave request from kuwait(9394)<ec><v308>:1025 for kuwait(9394)<ec><v308>:1025 [vm_0][info 2016/07/28 02:50:40.758 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] DistributionManager stopped in 105ms. [vm_0][info 2016/07/28 02:50:40.758 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Marking DistributionManager kuwait(9394)<ec><v308>:1025 as closed. [vm_0][info 2016/07/28 02:50:40.759 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Got result: null [vm_0] from com.gemstone.gemfire.cache.query.dunit.CompiledInDUnitTest$1.run with 0 args on object: "Close Client" (took 111 ms) [vm_1][info 2016/07/28 02:50:40.760 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Received method: com.gemstone.gemfire.cache.query.dunit.CompiledInDUnitTest$1.run with 0 args on object: "Close Client" [vm_1][info 2016/07/28 02:50:40.760 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] ### Close Client. ### [vm_1][info 2016/07/28 02:50:40.761 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] GemFireCache[id = 2046044872; isClosing = true; isShutDownAll = false; created = Thu Jul 28 02:50:40 PDT 2016; server = false; copyOnRead = false; lockLease = 120; lockTimeout = 60]: Now closing. [vm_1][info 2016/07/28 02:50:40.762 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Cache server on port 42,027 is shutting down. [vm_1][info 2016/07/28 02:50:40.765 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Shutting down DistributionManager kuwait(9398)<ec><v309>:1026. [locator][info 2016/07/28 02:50:40.766 PDT <Pooled High Priority Message Processor 2> tid=0x11a] received leave request from kuwait(9398)<ec><v309>:1026 for kuwait(9398)<ec><v309>:1026 [locator][info 2016/07/28 02:50:40.766 PDT <Pooled High Priority Message Processor 2> tid=0x11a] Member at kuwait(9398)<ec><v309>:1026 gracefully left the distributed cache: shutdown message received [vm_1][info 2016/07/28 02:50:40.866 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Now closing distribution for kuwait(9398)<ec><v309>:1026 [vm_1][info 2016/07/28 02:50:40.866 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Stopping membership services [vm_1][info 2016/07/28 02:50:40.867 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] GMSHealthMonitor server socket is closed in stopServices(). [vm_1][info 2016/07/28 02:50:40.867 PDT <Geode Failure Detection Server thread 0> tid=0x1520] GMSHealthMonitor server thread exiting [locator][info 2016/07/28 02:50:40.867 PDT <unicast receiver,kuwait-49731> tid=0x2a] received leave request from kuwait(9398)<ec><v309>:1026 for kuwait(9398)<ec><v309>:1026 [vm_1][info 2016/07/28 02:50:40.867 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] GMSHealthMonitor serverSocketExecutor is terminated [vm_1][info 2016/07/28 02:50:40.870 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] DistributionManager stopped in 105ms. [vm_1][info 2016/07/28 02:50:40.870 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Marking DistributionManager kuwait(9398)<ec><v309>:1026 as closed. [vm_1][info 2016/07/28 02:50:40.871 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Got result: null [vm_1] from com.gemstone.gemfire.cache.query.dunit.CompiledInDUnitTest$1.run with 0 args on object: "Close Client" (took 110 ms) [vm_3][info 2016/07/28 02:50:40.872 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Received method: com.gemstone.gemfire.cache.query.dunit.CompiledInDUnitTest$1.run with 0 args on object: "Close Client" [vm_3][info 2016/07/28 02:50:40.872 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] ### Close Client. ### [vm_3][info 2016/07/28 02:50:40.872 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] 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/28 02:50:40.881 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Received method: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$9/1993756657.run with 0 args on object: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$9/1993756657@15e89968 [vm_0][info 2016/07/28 02:50:40.882 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Got result: null [vm_0] from com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$9/1993756657.run with 0 args on object: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$9/1993756657@15e89968 (took 0 ms) [vm_1][info 2016/07/28 02:50:40.882 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Received method: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$10/9633645.run with 0 args on object: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$10/9633645@7359ff91 [vm_1][info 2016/07/28 02:50:40.882 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Got result: null [vm_1] from com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$10/9633645.run with 0 args on object: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$10/9633645@7359ff91 (took 0 ms) [vm_2][info 2016/07/28 02:50:40.883 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Received method: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$10/1327170274.run with 0 args on object: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$10/1327170274@10e1b131 [vm_2][info 2016/07/28 02:50:40.883 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Got result: null [vm_2] from com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$10/1327170274.run with 0 args on object: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$10/1327170274@10e1b131 (took 0 ms) [vm_3][info 2016/07/28 02:50:40.884 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Received method: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$5/1477154395.run with 0 args on object: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$5/1477154395@5f79c56b [vm_3][info 2016/07/28 02:50:40.884 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Got result: null [vm_3] from com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$5/1477154395.run with 0 args on object: com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$5/1477154395@5f79c56b (took 0 ms) [vm_0][info 2016/07/28 02:50:40.884 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(tearDownCreationStackGenerator) [vm_0][info 2016/07/28 02:50:40.885 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Got result: null [vm_0] from com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(tearDownCreationStackGenerator) (took 0 ms) [vm_1][info 2016/07/28 02:50:40.885 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(tearDownCreationStackGenerator) [vm_1][info 2016/07/28 02:50:40.885 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Got result: null [vm_1] from com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(tearDownCreationStackGenerator) (took 0 ms) [vm_2][info 2016/07/28 02:50:40.885 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(tearDownCreationStackGenerator) [vm_2][info 2016/07/28 02:50:40.885 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Got result: null [vm_2] from com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(tearDownCreationStackGenerator) (took 0 ms) [vm_3][info 2016/07/28 02:50:40.886 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(tearDownCreationStackGenerator) [vm_3][info 2016/07/28 02:50:40.886 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Got result: null [vm_3] from com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(tearDownCreationStackGenerator) (took 0 ms) [vm_0][info 2016/07/28 02:50:40.886 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(tearDownVM) [vm_0][info 2016/07/28 02:50:40.886 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Got result: null [vm_0] from com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(tearDownVM) (took 0 ms) [vm_1][info 2016/07/28 02:50:40.886 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(tearDownVM) [vm_1][info 2016/07/28 02:50:40.887 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Got result: null [vm_1] from com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(tearDownVM) (took 0 ms) [vm_2][info 2016/07/28 02:50:40.887 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(tearDownVM) [vm_2][info 2016/07/28 02:50:40.887 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Got result: null [vm_2] from com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(tearDownVM) (took 0 ms) [vm_3][info 2016/07/28 02:50:40.887 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(tearDownVM) [vm_3][info 2016/07/28 02:50:40.887 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Got result: null [vm_3] from com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on object: runnable(tearDownVM) (took 0 ms) [locator][info 2016/07/28 02:50:40.888 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Received method: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/1814869608.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/1814869608@fe46a45 [locator][info 2016/07/28 02:50:40.888 PDT <RMI TCP Connection(1)-10.118.32.93> tid=0x1b] Got result: null [locator] from com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/1814869608.run with 0 args on object: com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/1814869608@fe46a45 (took 0 ms) {noformat} -- This message was sent by Atlassian JIRA (v6.3.4#6332)