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)
