[
https://issues.apache.org/jira/browse/GEODE-1709?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel
]
Kevin Duling updated GEODE-1709:
--------------------------------
Labels: CI (was: )
> LuceneIndexCommandsDUnitTest.describeIndexShouldNotReturnResultWhenIndexNotFound
> --------------------------------------------------------------------------------
>
> Key: GEODE-1709
> URL: https://issues.apache.org/jira/browse/GEODE-1709
> Project: Geode
> Issue Type: Bug
> Reporter: Kevin Duling
> Labels: CI
>
> In Geode_develop_DistributedTests/3421:
> {noformat}
> Error Message
> com.gemstone.gemfire.test.dunit.RMIException: While invoking
> com.gemstone.gemfire.test.dunit.NamedCallable.call in VM 0 running on Host
> venezuela.gemstone.com with 4 VMs
> Stacktrace
> com.gemstone.gemfire.test.dunit.RMIException: While invoking
> com.gemstone.gemfire.test.dunit.NamedCallable.call in VM 0 running on Host
> venezuela.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:308)
> at
> com.gemstone.gemfire.management.internal.cli.commands.CliCommandTestBase.setUpJMXManagerOnVM(CliCommandTestBase.java:125)
> at
> com.gemstone.gemfire.management.internal.cli.commands.CliCommandTestBase.setUpJMXManagerOnVM(CliCommandTestBase.java:118)
> at
> com.gemstone.gemfire.management.internal.cli.commands.CliCommandTestBase.setUpJmxManagerOnVm0ThenConnect(CliCommandTestBase.java:109)
> at
> com.gemstone.gemfire.cache.lucene.internal.cli.LuceneIndexCommandsDUnitTest.createJMXManager(LuceneIndexCommandsDUnitTest.java:65)
> 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.RunBefores.evaluate(RunBefores.java:24)
> at
> org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:27)
> at org.junit.rules.ExternalResource$1.evaluate(ExternalResource.java:48)
> at org.junit.rules.ExternalResource$1.evaluate(ExternalResource.java:48)
> at org.junit.rules.TestWatcher$1.evaluate(TestWatcher.java:55)
> at org.junit.rules.RunRules.evaluate(RunRules.java:20)
> at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:325)
> at
> org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:78)
> at
> org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:57)
> at org.junit.runners.ParentRunner$3.run(ParentRunner.java:290)
> at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:71)
> at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:288)
> at org.junit.runners.ParentRunner.access$000(ParentRunner.java:58)
> at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:268)
> at
> org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:26)
> at org.junit.runners.ParentRunner.run(ParentRunner.java:363)
> at
> org.gradle.api.internal.tasks.testing.junit.JUnitTestClassExecuter.runTestClass(JUnitTestClassExecuter.java:112)
> at
> org.gradle.api.internal.tasks.testing.junit.JUnitTestClassExecuter.execute(JUnitTestClassExecuter.java:56)
> at
> org.gradle.api.internal.tasks.testing.junit.JUnitTestClassProcessor.processTestClass(JUnitTestClassProcessor.java:66)
> at
> org.gradle.api.internal.tasks.testing.SuiteTestClassProcessor.processTestClass(SuiteTestClassProcessor.java:51)
> at sun.reflect.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.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.GeneratedMethodAccessor13.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: com.gemstone.gemfire.management.ManagementException:
> java.rmi.server.ExportException: Port already in use: 23729; nested exception
> is:
> java.net.BindException: Failed to create server socket on
> venezuela/10.118.32.90[23,729]
> at
> com.gemstone.gemfire.management.internal.ManagementAgent.startAgent(ManagementAgent.java:147)
> at
> com.gemstone.gemfire.management.internal.SystemManagementService.startManager(SystemManagementService.java:479)
> at
> com.gemstone.gemfire.management.internal.beans.ManagementAdapter.handleCacheCreation(ManagementAdapter.java:197)
> at
> com.gemstone.gemfire.management.internal.beans.ManagementListener.handleEvent(ManagementListener.java:119)
> at
> com.gemstone.gemfire.distributed.internal.InternalDistributedSystem.notifyResourceEventListeners(InternalDistributedSystem.java:2085)
> at
> com.gemstone.gemfire.distributed.internal.InternalDistributedSystem.handleResourceEvent(InternalDistributedSystem.java:478)
> at
> com.gemstone.gemfire.internal.cache.GemFireCacheImpl.initialize(GemFireCacheImpl.java:1110)
> at
> com.gemstone.gemfire.internal.cache.GemFireCacheImpl.basicCreate(GemFireCacheImpl.java:763)
> at
> com.gemstone.gemfire.internal.cache.GemFireCacheImpl.create(GemFireCacheImpl.java:751)
> at com.gemstone.gemfire.cache.CacheFactory.create(CacheFactory.java:181)
> at com.gemstone.gemfire.cache.CacheFactory.create(CacheFactory.java:172)
> at
> com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase.createCache(JUnit4CacheTestCase.java:120)
> at
> com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase.getCache(JUnit4CacheTestCase.java:263)
> at
> com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase.getCache(JUnit4CacheTestCase.java:242)
> at
> com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase.getCache(JUnit4CacheTestCase.java:234)
> at
> com.gemstone.gemfire.management.internal.cli.commands.CliCommandTestBase.lambda$setUpJMXManagerOnVM$d0384528$1(CliCommandTestBase.java:156)
> at
> com.gemstone.gemfire.test.dunit.NamedCallable.call(NamedCallable.java:33)
> 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 hydra.MethExecutor.executeObject(MethExecutor.java:268)
> at
> com.gemstone.gemfire.test.dunit.standalone.RemoteDUnitVM.executeMethodOnObject(RemoteDUnitVM.java:82)
> at sun.reflect.GeneratedMethodAccessor50.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
> Caused by: java.rmi.server.ExportException: Port already in use: 23729;
> nested exception is:
> java.net.BindException: Failed to create server socket on
> venezuela/10.118.32.90[23,729]
> at sun.rmi.transport.tcp.TCPTransport.listen(TCPTransport.java:341)
> at
> sun.rmi.transport.tcp.TCPTransport.exportObject(TCPTransport.java:249)
> at sun.rmi.transport.tcp.TCPEndpoint.exportObject(TCPEndpoint.java:411)
> at sun.rmi.transport.LiveRef.exportObject(LiveRef.java:147)
> at
> sun.rmi.server.UnicastServerRef.exportObject(UnicastServerRef.java:208)
> at sun.rmi.registry.RegistryImpl.setup(RegistryImpl.java:152)
> at sun.rmi.registry.RegistryImpl.<init>(RegistryImpl.java:112)
> at
> java.rmi.registry.LocateRegistry.createRegistry(LocateRegistry.java:239)
> at
> com.gemstone.gemfire.management.internal.ManagementAgent.configureAndStart(ManagementAgent.java:389)
> at
> com.gemstone.gemfire.management.internal.ManagementAgent.startAgent(ManagementAgent.java:145)
> ... 38 more
> Caused by: java.net.BindException: Failed to create server socket on
> venezuela/10.118.32.90[23,729]
> at
> com.gemstone.gemfire.internal.SocketCreator.createServerSocket(SocketCreator.java:814)
> at
> com.gemstone.gemfire.internal.SocketCreator.createServerSocket(SocketCreator.java:768)
> at
> com.gemstone.gemfire.management.internal.ManagementAgent$GemFireRMIServerSocketFactory.createServerSocket(ManagementAgent.java:545)
> at
> sun.rmi.transport.tcp.TCPEndpoint.newServerSocket(TCPEndpoint.java:666)
> at sun.rmi.transport.tcp.TCPTransport.listen(TCPTransport.java:330)
> ... 47 more
> Caused by: java.net.BindException: Address already in use
> at java.net.PlainSocketImpl.socketBind(Native Method)
> at
> java.net.AbstractPlainSocketImpl.bind(AbstractPlainSocketImpl.java:387)
> at java.net.ServerSocket.bind(ServerSocket.java:375)
> at
> com.gemstone.gemfire.internal.SocketCreator.createServerSocket(SocketCreator.java:811)
> ... 51 more
> Standard Output
> [vm_0][info 2016/07/27 13:23:23.257 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Received method:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$1.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$1@2a4c2c1e
> [vm_0][info 2016/07/27 13:23:23.257 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Got result: null
> [vm_0] from
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$1.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$1@2a4c2c1e
> (took 0 ms)
> [vm_1][info 2016/07/27 13:23:23.263 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Received method:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$1.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$1@775706
> [vm_1][info 2016/07/27 13:23:23.263 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Got result: null
> [vm_1] from
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$1.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$1@775706
> (took 0 ms)
> [vm_2][info 2016/07/27 13:23:23.270 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Received method:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$1.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$1@4e076a4b
> [vm_2][info 2016/07/27 13:23:23.270 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Got result: null
> [vm_2] from
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$1.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$1@4e076a4b
> (took 0 ms)
> [vm_3][info 2016/07/27 13:23:23.277 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Received method:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$1.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$1@6d8a0fb1
> [vm_3][info 2016/07/27 13:23:23.277 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Got result: null
> [vm_3] from
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$1.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$1@6d8a0fb1
> (took 0 ms)
> [locator][info 2016/07/27 13:23:23.284 PDT <RMI TCP
> Connection(1)-10.118.32.90> tid=0x12] Received method:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$1.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$1@6feab807
> [locator][info 2016/07/27 13:23:23.284 PDT <RMI TCP
> Connection(1)-10.118.32.90> tid=0x12] Got result: null
> [locator] from
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$1.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$1@6feab807
> (took 0 ms)
> Previously run tests: [LuceneIndexCreationDUnitTest,
> LuceneIndexCommandsDUnitTest]
> [vm_1][info 2016/07/27 13:23:23.287 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run
> with 0 args on object: runnable(setupVM)
> [vm_1][info 2016/07/27 13:23:23.287 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Got result: null
> [vm_1] from com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on
> object: runnable(setupVM) (took 0 ms)
> [vm_0][info 2016/07/27 13:23:23.286 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run
> with 0 args on object: runnable(setupVM)
> [vm_0][info 2016/07/27 13:23:23.286 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Got result: null
> [vm_0] from com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on
> object: runnable(setupVM) (took 0 ms)
> [vm_2][info 2016/07/27 13:23:23.288 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run
> with 0 args on object: runnable(setupVM)
> [vm_2][info 2016/07/27 13:23:23.288 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Got result: null
> [vm_2] from com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on
> object: runnable(setupVM) (took 0 ms)
> [setup] START TEST
> LuceneIndexCommandsDUnitTest.searchShouldReturnCorrectResults
> [vm_3][info 2016/07/27 13:23:23.289 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run
> with 0 args on object: runnable(setupVM)
> [vm_3][info 2016/07/27 13:23:23.289 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Got result: null
> [vm_3] from com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on
> object: runnable(setupVM) (took 0 ms)
> [vm_0][info 2016/07/27 13:23:23.292 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Received method:
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$52/620387750.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$52/620387750@6f9db9fb
> [vm_0][info 2016/07/27 13:23:23.292 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Got result: null
> [vm_0] from
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$52/620387750.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$52/620387750@6f9db9fb
> (took 0 ms)
> [vm_1][info 2016/07/27 13:23:23.293 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Received method:
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$55/508096654.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$55/508096654@243d5b26
> [vm_1][info 2016/07/27 13:23:23.293 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Got result: null
> [vm_1] from
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$55/508096654.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$55/508096654@243d5b26
> (took 0 ms)
> [vm_2][info 2016/07/27 13:23:23.294 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Received method:
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$8/391722993.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$8/391722993@62f95263
> [vm_2][info 2016/07/27 13:23:23.295 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Got result: null
> [vm_2] from
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$8/391722993.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$8/391722993@62f95263
> (took 0 ms)
> [vm_3][info 2016/07/27 13:23:23.296 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Received method:
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$8/2126059778.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$8/2126059778@43f366e3
> [vm_3][info 2016/07/27 13:23:23.296 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Got result: null
> [vm_3] from
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$8/2126059778.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$8/2126059778@43f366e3
> (took 0 ms)
> [vm_0][info 2016/07/27 13:23:23.311 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.NamedCallable.call
> with 0 args on object: callable(setUpJmxManagerOnVm0)
> [vm_0]distributed system properties: {name=Manager, http-service-port=22200,
> jmx-manager=true, jmx-manager-bind-address=venezuela, jmx-manager-port=20742,
> mcast-port=0, log-level=info, disable-auto-reconnect=true,
> enable-cluster-configuration=false, jmx-manager-start=true,
> locators=localhost[50679], use-cluster-configuration=false}
> [vm_0][info 2016/07/27 13:23:23.316 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Startup Configuration:
> [vm_0] ### GemFire Properties defined with api ###
> [vm_0]disable-auto-reconnect=true
> [vm_0]enable-cluster-configuration=false
> [vm_0]http-service-port=22200
> [vm_0]jmx-manager=true
> [vm_0]jmx-manager-bind-address=venezuela
> [vm_0]jmx-manager-port=20742
> [vm_0]jmx-manager-start=true
> [vm_0]locators=localhost[50679]
> [vm_0]log-level=info
> [vm_0]mcast-port=0
> [vm_0]name=Manager
> [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-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-access-file=
> [vm_0]jmx-manager-hostname-for-clients=
> [vm_0]jmx-manager-http-port=22200
> [vm_0]jmx-manager-password-file=
> [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-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]off-heap-memory-size=
> [vm_0]redis-bind-address=
> [vm_0]redis-password=
> [vm_0]redis-port=0
> [vm_0]redundancy-zone=
> [vm_0]remote-locators=
> [vm_0]remove-unresponsive-client=false
> [vm_0]roles=
> [vm_0]security-client-accessor=
> [vm_0]security-client-accessor-pp=
> [vm_0]security-client-auth-init=
> [vm_0]security-client-authenticator=
> [vm_0]security-client-dhalgo=
> [vm_0]security-log-file=
> [vm_0]security-log-level=config
> [vm_0]security-manager=
> [vm_0]security-peer-auth-init=
> [vm_0]security-peer-authenticator=
> [vm_0]security-peer-verifymember-timeout=1000
> [vm_0]security-post-processor=
> [vm_0]server-bind-address=
> [vm_0]server-ssl-ciphers=any
> [vm_0]server-ssl-enabled=false
> [vm_0]server-ssl-keystore=
> [vm_0]server-ssl-keystore-password=
> [vm_0]server-ssl-keystore-type=
> [vm_0]server-ssl-protocols=any
> [vm_0]server-ssl-require-authentication=true
> [vm_0]server-ssl-truststore=
> [vm_0]server-ssl-truststore-password=
> [vm_0]socket-buffer-size=32768
> [vm_0]socket-lease-time=60000
> [vm_0]ssl-ciphers=any
> [vm_0]ssl-enabled=false
> [vm_0]ssl-protocols=any
> [vm_0]ssl-require-authentication=true
> [vm_0]start-dev-rest-api=false
> [vm_0]start-locator=
> [vm_0]statistic-archive-file=
> [vm_0]statistic-sample-rate=1000
> [vm_0]statistic-sampling-enabled=true
> [vm_0]tcp-port=0
> [vm_0]udp-fragment-size=60000
> [vm_0]udp-recv-buffer-size=1048576
> [vm_0]udp-send-buffer-size=65535
> [vm_0]user-command-packages=
> [vm_0][info 2016/07/27 13:23:23.318 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Starting membership services
> [vm_0][info 2016/07/27 13:23:23.324 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] JGroups channel created (took 6ms)
> [vm_0][info 2016/07/27 13:23:23.326 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] GemFire P2P Listener started on null
> [vm_0][info 2016/07/27 13:23:23.327 PDT <Geode Failure Detection Server
> thread 0> tid=0x897] Started failure detection server thread on
> venezuela.gemstone.com/10.118.32.90:6393.
> [vm_0][info 2016/07/27 13:23:23.328 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Attempting to join the distributed system through coordinator
> venezuela(31106:locator)<ec><v0>:1025 using address
> venezuela(Manager:31167)<ec>:1026
> [locator][info 2016/07/27 13:23:23.328 PDT <unicast receiver,venezuela-20155>
> tid=0x21] received join request from venezuela(Manager:31167)<ec>:1026
> [locator][info 2016/07/27 13:23:23.635 PDT <Geode Membership View Creator>
> tid=0x27] View Creator is processing 1 requests for the next membership view
> [locator][info 2016/07/27 13:23:23.635 PDT <Geode Membership View Creator>
> tid=0x27] preparing new view View[venezuela(31106:locator)<ec><v0>:1025|71]
> members: [venezuela(31106:locator)<ec><v0>:1025,
> venezuela(Manager:31167)<ec><v71>:1026{lead}]
> [locator]failure detection ports: 4834 6393
> [vm_0][info 2016/07/27 13:23:23.635 PDT <unicast receiver,venezuela-40120>
> tid=0x893] received new view: View[venezuela(31106:locator)<ec><v0>:1025|71]
> members: [venezuela(31106:locator)<ec><v0>:1025,
> venezuela(Manager:31167)<ec><v71>:1026{lead}]
> [locator][info 2016/07/27 13:23:23.636 PDT <Geode Membership View Creator>
> tid=0x27] finished waiting for responses to view preparation
> [vm_0]old view is: null
> [locator][info 2016/07/27 13:23:23.636 PDT <Geode Membership View Creator>
> tid=0x27] received new view: View[venezuela(31106:locator)<ec><v0>:1025|71]
> members: [venezuela(31106:locator)<ec><v0>:1025,
> venezuela(Manager:31167)<ec><v71>:1026{lead}]
> [locator]old view is: View[venezuela(31106:locator)<ec><v0>:1025|70] members:
> [venezuela(31106:locator)<ec><v0>:1025] shutdown:
> [venezuela(31171)<ec><v69>:1028, venezuela(31167)<ec><v68>:1026]
> [locator][info 2016/07/27 13:23:23.636 PDT <Geode Membership View Creator>
> tid=0x27] Peer locator received new membership view:
> View[venezuela(31106:locator)<ec><v0>:1025|71] members:
> [venezuela(31106:locator)<ec><v0>:1025,
> venezuela(Manager:31167)<ec><v71>:1026{lead}]
> [locator][info 2016/07/27 13:23:23.636 PDT <Geode Membership View Creator>
> tid=0x27] sending new view View[venezuela(31106:locator)<ec><v0>:1025|71]
> members: [venezuela(31106:locator)<ec><v0>:1025,
> venezuela(Manager:31167)<ec><v71>:1026{lead}]
> [locator]failure detection ports: 4834 6393
> [locator][info 2016/07/27 13:23:23.636 PDT <View Message Processor> tid=0x33]
> Membership: Processing addition < venezuela(Manager:31167)<ec><v71>:1026 >
> [locator][info 2016/07/27 13:23:23.636 PDT <View Message Processor> tid=0x33]
> Admitting member <venezuela(Manager:31167)<ec><v71>:1026>. Now there are 2
> non-admin member(s).
> [vm_0][info 2016/07/27 13:23:23.638 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Finished joining (took 311ms).
> [vm_0][info 2016/07/27 13:23:23.638 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Starting DistributionManager
> venezuela(Manager:31167)<ec><v71>:1026. (took 321 ms)
> [vm_0][info 2016/07/27 13:23:23.638 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Initial (distribution manager) view =
> View[venezuela(31106:locator)<ec><v0>:1025|71] members:
> [venezuela(31106:locator)<ec><v0>:1025,
> venezuela(Manager:31167)<ec><v71>:1026{lead}]
> [vm_0][info 2016/07/27 13:23:23.638 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Admitting member <venezuela(31106:locator)<ec><v0>:1025>. Now there
> are 1 non-admin member(s).
> [vm_0][info 2016/07/27 13:23:23.638 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Admitting member <venezuela(Manager:31167)<ec><v71>:1026>. Now
> there are 2 non-admin member(s).
> [locator][info 2016/07/27 13:23:23.641 PDT <Pooled High Priority Message
> Processor 1> tid=0x37] Member venezuela(Manager:31167)<ec><v71>:1026 is
> equivalent or in the same redundancy zone.
> [vm_0][info 2016/07/27 13:23:23.643 PDT <P2P message reader for
> venezuela(31106:locator)<ec><v0>:1025 shared unordered uid=69 port=58530>
> tid=0x89d] Member venezuela(31106:locator)<ec><v0>:1025 is equivalent or in
> the same redundancy zone.
> [vm_0][info 2016/07/27 13:23:23.654 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Initializing region _monitoringRegion_10.118.32.90<v71>1026
> [vm_0][info 2016/07/27 13:23:23.654 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Initialization of region _monitoringRegion_10.118.32.90<v71>1026
> completed
> [vm_0][info 2016/07/27 13:23:23.686 PDT <pool-37-thread-1> tid=0x8aa]
> Initializing region _monitoringRegion_10.118.32.90<v0>1025
> [vm_0][info 2016/07/27 13:23:23.694 PDT <pool-37-thread-1> tid=0x8aa] Region
> _monitoringRegion_10.118.32.90<v0>1025 requesting initial image from
> venezuela(31106:locator)<ec><v0>:1025
> [vm_0][info 2016/07/27 13:23:23.700 PDT <pool-37-thread-1> tid=0x8aa]
> _monitoringRegion_10.118.32.90<v0>1025 is done getting image from
> venezuela(31106:locator)<ec><v0>:1025. isDeltaGII is false
> [vm_0][info 2016/07/27 13:23:23.701 PDT <pool-37-thread-1> tid=0x8aa]
> Initialization of region _monitoringRegion_10.118.32.90<v0>1025 completed
> [vm_0][info 2016/07/27 13:23:23.702 PDT <pool-37-thread-1> tid=0x8aa]
> Initializing region _notificationRegion_10.118.32.90<v0>1025
> [vm_0][info 2016/07/27 13:23:23.703 PDT <pool-37-thread-1> tid=0x8aa]
> Initialization of region _notificationRegion_10.118.32.90<v0>1025 completed
> [locator]93.462: [GC (Metadata GC Threshold) [PSYoungGen:
> 26270K->5043K(139264K)] 37656K->16437K(305664K), 0.0085050 secs] [Times:
> user=0.01 sys=0.01, real=0.01 secs]
> [locator]93.470: [Full GC (Metadata GC Threshold) [PSYoungGen:
> 5043K->0K(139264K)] [ParOldGen: 11393K->10563K(266240K)]
> 16437K->10563K(405504K), [Metaspace: 34733K->34733K(1081344K)], 0.0596540
> secs] [Times: user=0.16 sys=0.01, real=0.06 secs]
> [vm_0][info 2016/07/27 13:23:23.810 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] GEMFIRE HOME:null
> [vm_0][info 2016/07/27 13:23:23.810 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Reading gemfire.home System Property -> null
> [vm_0][info 2016/07/27 13:23:23.811 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] GEMFIRE environment variable not set; HTTP service will not start.
> [vm_0][warn 2016/07/27 13:23:23.811 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] geode-web war file was not found
> [vm_0][info 2016/07/27 13:23:23.812 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] GEMFIRE HOME:null
> [vm_0][info 2016/07/27 13:23:23.812 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Reading gemfire.home System Property -> null
> [vm_0][info 2016/07/27 13:23:23.812 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] GEMFIRE environment variable not set; HTTP service will not start.
> [vm_0][warn 2016/07/27 13:23:23.812 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] geode-pulse war file was not found
> [vm_0][info 2016/07/27 13:23:23.813 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] GEMFIRE HOME:null
> [vm_0][info 2016/07/27 13:23:23.813 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Reading gemfire.home System Property -> null
> [vm_0][info 2016/07/27 13:23:23.813 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] GEMFIRE environment variable not set; HTTP service will not start.
> [vm_0][warn 2016/07/27 13:23:23.813 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] geode-web-api war file was not found
> [vm_0][info 2016/07/27 13:23:23.826 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] The cache has been created with "use-cluster-configuration=false".
> It will not receive any cluster configuration
> [vm_0][info 2016/07/27 13:23:23.826 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Initializing region PdxTypes
> [vm_0][info 2016/07/27 13:23:23.827 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Initialization of region PdxTypes completed
> [vm_0][info 2016/07/27 13:23:23.828 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] <ExpectedException
> action=add>org.eclipse.jetty.io.EofException</ExpectedException>
> [vm_0][info 2016/07/27 13:23:23.828 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] <ExpectedException
> action=add>org.eclipse.jetty.io.EofException</ExpectedException>
> [vm_0][info 2016/07/27 13:23:23.828 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] <ExpectedException
> action=add>org.eclipse.jetty.io.EofException</ExpectedException>
> [vm_0][info 2016/07/27 13:23:23.829 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] <ExpectedException
> action=add>java.nio.channels.ClosedChannelException</ExpectedException>
> [vm_0][info 2016/07/27 13:23:23.829 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] <ExpectedException
> action=add>java.nio.channels.ClosedChannelException</ExpectedException>
> [vm_0][info 2016/07/27 13:23:23.829 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] <ExpectedException
> action=add>java.nio.channels.ClosedChannelException</ExpectedException>
> [vm_0][info 2016/07/27 13:23:23.829 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Got result: [Ljava.lang.Object;@59a02567
> [vm_0] from com.gemstone.gemfire.test.dunit.NamedCallable.call with 0 args on
> object: callable(setUpJmxManagerOnVm0) (took 517 ms)
> [info 2016/07/27 13:23:25.196 PDT <Test worker> tid=0xa] Started testable
> shell: com.gemstone.gemfire.management.internal.cli.HeadlessGfsh@5ced5813
> LuceneIndexCommandsDUnitTest using endpoint: venezuela[20742]
> [info 2016/07/27 13:23:25.198 PDT <Test worker> tid=0xa] Executing command
> connect --jmx-manager=venezuela[20742] with command Mgr
> com.gemstone.gemfire.management.internal.cli.CommandManager@7d297cc4
> [info 2016/07/27 13:23:25.375 PDT <Test worker> tid=0xa] Successfully
> connected to managing node using JMX
> [vm_1][info 2016/07/27 13:23:25.385 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Received method:
> com.gemstone.gemfire.cache.lucene.internal.cli.LuceneIndexCommandsDUnitTest$$Lambda$56/255229839.run
> with 0 args on object:
> com.gemstone.gemfire.cache.lucene.internal.cli.LuceneIndexCommandsDUnitTest$$Lambda$56/255229839@7b0cbf50
> [vm_1]distributed system properties: {locators=localhost[50679],
> enable-cluster-configuration=false, use-cluster-configuration=false,
> mcast-port=0, disable-auto-reconnect=true, log-level=info}
> [vm_1][info 2016/07/27 13:23:25.388 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Startup Configuration:
> [vm_1] ### GemFire Properties defined with api ###
> [vm_1]disable-auto-reconnect=true
> [vm_1]enable-cluster-configuration=false
> [vm_1]locators=localhost[50679]
> [vm_1]log-level=info
> [vm_1]mcast-port=0
> [vm_1]use-cluster-configuration=false
> [vm_1]### GemFire Properties using default values ###
> [vm_1]ack-severe-alert-threshold=0
> [vm_1]ack-wait-threshold=15
> [vm_1]archive-disk-space-limit=0
> [vm_1]archive-file-size-limit=0
> [vm_1]async-distribution-timeout=0
> [vm_1]async-max-queue-size=8
> [vm_1]async-queue-timeout=60000
> [vm_1]bind-address=
> [vm_1]cache-xml-file=cache.xml
> [vm_1]cluster-configuration-dir=
> [vm_1]cluster-ssl-ciphers=any
> [vm_1]cluster-ssl-enabled=false
> [vm_1]cluster-ssl-keystore=
> [vm_1]cluster-ssl-keystore-password=
> [vm_1]cluster-ssl-keystore-type=
> [vm_1]cluster-ssl-protocols=any
> [vm_1]cluster-ssl-require-authentication=true
> [vm_1]cluster-ssl-truststore=
> [vm_1]cluster-ssl-truststore-password=
> [vm_1]conflate-events=server
> [vm_1]conserve-sockets=true
> [vm_1]delta-propagation=true
> [vm_1]deploy-working-dir=.
> [vm_1]disable-tcp=false
> [vm_1]distributed-system-id=-1
> [vm_1]distributed-transactions=false
> [vm_1]durable-client-id=
> [vm_1]durable-client-timeout=300
> [vm_1]enable-network-partition-detection=false
> [vm_1]enable-time-statistics=false
> [vm_1]enforce-unique-host=false
> [vm_1]gateway-ssl-ciphers=any
> [vm_1]gateway-ssl-enabled=false
> [vm_1]gateway-ssl-keystore=
> [vm_1]gateway-ssl-keystore-password=
> [vm_1]gateway-ssl-keystore-type=
> [vm_1]gateway-ssl-protocols=any
> [vm_1]gateway-ssl-require-authentication=true
> [vm_1]gateway-ssl-truststore=
> [vm_1]gateway-ssl-truststore-password=
> [vm_1]groups=
> [vm_1]http-service-bind-address=
> [vm_1]http-service-port=7070
> [vm_1]http-service-ssl-ciphers=any
> [vm_1]http-service-ssl-enabled=false
> [vm_1]http-service-ssl-keystore=
> [vm_1]http-service-ssl-keystore-password=
> [vm_1]http-service-ssl-keystore-type=
> [vm_1]http-service-ssl-protocols=any
> [vm_1]http-service-ssl-require-authentication=false
> [vm_1]http-service-ssl-truststore=
> [vm_1]http-service-ssl-truststore-password=
> [vm_1]jmx-manager=false
> [vm_1]jmx-manager-access-file=
> [vm_1]jmx-manager-bind-address=
> [vm_1]jmx-manager-hostname-for-clients=
> [vm_1]jmx-manager-http-port=7070
> [vm_1]jmx-manager-password-file=
> [vm_1]jmx-manager-port=1099
> [vm_1]jmx-manager-ssl=false
> [vm_1]jmx-manager-ssl-ciphers=any
> [vm_1]jmx-manager-ssl-enabled=false
> [vm_1]jmx-manager-ssl-keystore=
> [vm_1]jmx-manager-ssl-keystore-password=
> [vm_1]jmx-manager-ssl-keystore-type=
> [vm_1]jmx-manager-ssl-protocols=any
> [vm_1]jmx-manager-ssl-require-authentication=true
> [vm_1]jmx-manager-ssl-truststore=
> [vm_1]jmx-manager-ssl-truststore-password=
> [vm_1]jmx-manager-start=false
> [vm_1]jmx-manager-update-rate=2000
> [vm_1]load-cluster-configuration-from-dir=false
> [vm_1]locator-wait-time=0
> [vm_1]lock-memory=false
> [vm_1]log-disk-space-limit=0
> [vm_1]log-file=
> [vm_1]log-file-size-limit=0
> [vm_1]max-num-reconnect-tries=3
> [vm_1]max-wait-time-reconnect=60000
> [vm_1]mcast-address=239.192.81.1
> [vm_1]mcast-flow-control=1048576, 0.25, 5000
> [vm_1]mcast-recv-buffer-size=1048576
> [vm_1]mcast-send-buffer-size=65535
> [vm_1]mcast-ttl=32
> [vm_1]member-timeout=5000
> [vm_1]membership-port-range=1024-65535
> [vm_1]memcached-bind-address=
> [vm_1]memcached-port=0
> [vm_1]memcached-protocol=ASCII
> [vm_1]name=
> [vm_1]off-heap-memory-size=
> [vm_1]redis-bind-address=
> [vm_1]redis-password=
> [vm_1]redis-port=0
> [vm_1]redundancy-zone=
> [vm_1]remote-locators=
> [vm_1]remove-unresponsive-client=false
> [vm_1]roles=
> [vm_1]security-client-accessor=
> [vm_1]security-client-accessor-pp=
> [vm_1]security-client-auth-init=
> [vm_1]security-client-authenticator=
> [vm_1]security-client-dhalgo=
> [vm_1]security-log-file=
> [vm_1]security-log-level=config
> [vm_1]security-manager=
> [vm_1]security-peer-auth-init=
> [vm_1]security-peer-authenticator=
> [vm_1]security-peer-verifymember-timeout=1000
> [vm_1]security-post-processor=
> [vm_1]server-bind-address=
> [vm_1]server-ssl-ciphers=any
> [vm_1]server-ssl-enabled=false
> [vm_1]server-ssl-keystore=
> [vm_1]server-ssl-keystore-password=
> [vm_1]server-ssl-keystore-type=
> [vm_1]server-ssl-protocols=any
> [vm_1]server-ssl-require-authentication=true
> [vm_1]server-ssl-truststore=
> [vm_1]server-ssl-truststore-password=
> [vm_1]socket-buffer-size=32768
> [vm_1]socket-lease-time=60000
> [vm_1]ssl-ciphers=any
> [vm_1]ssl-enabled=false
> [vm_1]ssl-protocols=any
> [vm_1]ssl-require-authentication=true
> [vm_1]start-dev-rest-api=false
> [vm_1]start-locator=
> [vm_1]statistic-archive-file=
> [vm_1]statistic-sample-rate=1000
> [vm_1]statistic-sampling-enabled=true
> [vm_1]tcp-port=0
> [vm_1]udp-fragment-size=60000
> [vm_1]udp-recv-buffer-size=1048576
> [vm_1]udp-send-buffer-size=65535
> [vm_1]user-command-packages=
> [vm_1][info 2016/07/27 13:23:25.389 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Starting membership services
> [vm_1][info 2016/07/27 13:23:25.399 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] JGroups channel created (took 10ms)
> [vm_1][info 2016/07/27 13:23:25.401 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] GemFire P2P Listener started on null
> [vm_1][info 2016/07/27 13:23:25.403 PDT <Geode Failure Detection Server
> thread 0> tid=0x8f1] Started failure detection server thread on
> venezuela.gemstone.com/10.118.32.90:28702.
> [locator][info 2016/07/27 13:23:25.405 PDT <unicast receiver,venezuela-20155>
> tid=0x21] received join request from venezuela(31171)<ec>:1028
> [vm_1][info 2016/07/27 13:23:25.405 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Attempting to join the distributed system through coordinator
> venezuela(31106:locator)<ec><v0>:1025 using address venezuela(31171)<ec>:1028
> [locator][info 2016/07/27 13:23:25.712 PDT <Geode Membership View Creator>
> tid=0x27] View Creator is processing 1 requests for the next membership view
> [locator][info 2016/07/27 13:23:25.712 PDT <Geode Membership View Creator>
> tid=0x27] preparing new view View[venezuela(31106:locator)<ec><v0>:1025|72]
> members: [venezuela(31106:locator)<ec><v0>:1025,
> venezuela(Manager:31167)<ec><v71>:1026{lead}, venezuela(31171)<ec><v72>:1028]
> [locator]failure detection ports: 4834 6393 28702
> [vm_1][info 2016/07/27 13:23:25.713 PDT <unicast receiver,venezuela-21775>
> tid=0x8ed] received new view: View[venezuela(31106:locator)<ec><v0>:1025|72]
> members: [venezuela(31106:locator)<ec><v0>:1025,
> venezuela(Manager:31167)<ec><v71>:1026{lead}, venezuela(31171)<ec><v72>:1028]
> [vm_1]old view is: null
> [vm_1][info 2016/07/27 13:23:25.713 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Finished joining (took 310ms).
> [vm_1][info 2016/07/27 13:23:25.714 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Starting DistributionManager venezuela(31171)<ec><v72>:1028. (took
> 325 ms)
> [locator][info 2016/07/27 13:23:25.714 PDT <Geode Membership View Creator>
> tid=0x27] finished waiting for responses to view preparation
> [locator][info 2016/07/27 13:23:25.714 PDT <Geode Membership View Creator>
> tid=0x27] received new view: View[venezuela(31106:locator)<ec><v0>:1025|72]
> members: [venezuela(31106:locator)<ec><v0>:1025,
> venezuela(Manager:31167)<ec><v71>:1026{lead}, venezuela(31171)<ec><v72>:1028]
> [locator]old view is: View[venezuela(31106:locator)<ec><v0>:1025|71] members:
> [venezuela(31106:locator)<ec><v0>:1025,
> venezuela(Manager:31167)<ec><v71>:1026{lead}]
> [locator][info 2016/07/27 13:23:25.714 PDT <Geode Membership View Creator>
> tid=0x27] Peer locator received new membership view:
> View[venezuela(31106:locator)<ec><v0>:1025|72] members:
> [venezuela(31106:locator)<ec><v0>:1025,
> venezuela(Manager:31167)<ec><v71>:1026{lead}, venezuela(31171)<ec><v72>:1028]
> [locator][info 2016/07/27 13:23:25.714 PDT <Geode Membership View Creator>
> tid=0x27] sending new view View[venezuela(31106:locator)<ec><v0>:1025|72]
> members: [venezuela(31106:locator)<ec><v0>:1025,
> venezuela(Manager:31167)<ec><v71>:1026{lead}, venezuela(31171)<ec><v72>:1028]
> [vm_1][info 2016/07/27 13:23:25.715 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Initial (distribution manager) view =
> View[venezuela(31106:locator)<ec><v0>:1025|72] members:
> [venezuela(31106:locator)<ec><v0>:1025,
> venezuela(Manager:31167)<ec><v71>:1026{lead}, venezuela(31171)<ec><v72>:1028]
> [locator]failure detection ports: 4834 6393 28702
> [vm_1][info 2016/07/27 13:23:25.715 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Admitting member <venezuela(31106:locator)<ec><v0>:1025>. Now there
> are 1 non-admin member(s).
> [vm_1][info 2016/07/27 13:23:25.715 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Admitting member <venezuela(Manager:31167)<ec><v71>:1026>. Now
> there are 2 non-admin member(s).
> [vm_1][info 2016/07/27 13:23:25.715 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Admitting member <venezuela(31171)<ec><v72>:1028>. Now there are 3
> non-admin member(s).
> [vm_0][info 2016/07/27 13:23:25.717 PDT <P2P message reader@5659efdf>
> tid=0x8b1] Admitting member <venezuela(31171)<ec><v72>:1028>. Now there are 3
> non-admin member(s).
> [vm_0][info 2016/07/27 13:23:25.717 PDT <unicast receiver,venezuela-40120>
> tid=0x893] received new view: View[venezuela(31106:locator)<ec><v0>:1025|72]
> members: [venezuela(31106:locator)<ec><v0>:1025,
> venezuela(Manager:31167)<ec><v71>:1026{lead}, venezuela(31171)<ec><v72>:1028]
> [vm_0]old view is: View[venezuela(31106:locator)<ec><v0>:1025|71] members:
> [venezuela(31106:locator)<ec><v0>:1025,
> venezuela(Manager:31167)<ec><v71>:1026{lead}]
> [locator][info 2016/07/27 13:23:25.719 PDT <View Message Processor> tid=0x33]
> Membership: Processing addition < venezuela(31171)<ec><v72>:1028 >
> [locator][info 2016/07/27 13:23:25.719 PDT <View Message Processor> tid=0x33]
> Admitting member <venezuela(31171)<ec><v72>:1028>. Now there are 3 non-admin
> member(s).
> [vm_0][info 2016/07/27 13:23:25.719 PDT <pool-37-thread-2> tid=0x8b4]
> Initializing region _monitoringRegion_10.118.32.90<v72>1028
> [locator][info 2016/07/27 13:23:25.722 PDT <Pooled High Priority Message
> Processor 1> tid=0x37] Member venezuela(31171)<ec><v72>:1028 is equivalent or
> in the same redundancy zone.
> [vm_0][info 2016/07/27 13:23:25.722 PDT <Pooled High Priority Message
> Processor 1> tid=0x8a8] Member venezuela(31171)<ec><v72>:1028 is equivalent
> or in the same redundancy zone.
> [vm_1][info 2016/07/27 13:23:25.724 PDT <P2P message reader for
> venezuela(Manager:31167)<ec><v71>:1026 shared unordered uid=104 port=56235>
> tid=0x8f8] Member venezuela(Manager:31167)<ec><v71>:1026 is equivalent or in
> the same redundancy zone.
> [vm_0][info 2016/07/27 13:23:25.725 PDT <pool-37-thread-2> tid=0x8b4]
> Initialization of region _monitoringRegion_10.118.32.90<v72>1028 completed
> [vm_0][info 2016/07/27 13:23:25.725 PDT <pool-37-thread-2> tid=0x8b4]
> Initializing region _notificationRegion_10.118.32.90<v72>1028
> [vm_0][info 2016/07/27 13:23:25.727 PDT <pool-37-thread-2> tid=0x8b4]
> Initialization of region _notificationRegion_10.118.32.90<v72>1028 completed
> [vm_1][info 2016/07/27 13:23:25.729 PDT <P2P message reader for
> venezuela(31106:locator)<ec><v0>:1025 shared unordered uid=71 port=56236>
> tid=0x8fa] Member venezuela(31106:locator)<ec><v0>:1025 is equivalent or in
> the same redundancy zone.
> [vm_1][info 2016/07/27 13:23:25.735 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Initializing region _monitoringRegion_10.118.32.90<v72>1028
> [vm_1][info 2016/07/27 13:23:25.738 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Region _monitoringRegion_10.118.32.90<v72>1028 requesting initial
> image from venezuela(Manager:31167)<ec><v71>:1026
> [vm_1][info 2016/07/27 13:23:25.738 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] _monitoringRegion_10.118.32.90<v72>1028 is done getting image from
> venezuela(Manager:31167)<ec><v71>:1026. isDeltaGII is false
> [vm_1][info 2016/07/27 13:23:25.739 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Initialization of region _monitoringRegion_10.118.32.90<v72>1028
> completed
> [vm_1][info 2016/07/27 13:23:25.744 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] The cache has been created with "use-cluster-configuration=false".
> It will not receive any cluster configuration
> [vm_1][info 2016/07/27 13:23:25.745 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Initializing region PdxTypes
> [vm_1][info 2016/07/27 13:23:25.747 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Region PdxTypes requesting initial image from
> venezuela(Manager:31167)<ec><v71>:1026
> [vm_1][info 2016/07/27 13:23:25.747 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] PdxTypes is done getting image from
> venezuela(Manager:31167)<ec><v71>:1026. isDeltaGII is false
> [vm_1][info 2016/07/27 13:23:25.747 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Initialization of region PdxTypes completed
> [vm_1][info 2016/07/27 13:23:25.749 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Initializing region __PR
> [vm_1][info 2016/07/27 13:23:25.750 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Initialization of region __PR completed
> [vm_1][info 2016/07/27 13:23:25.754 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Partitioned Region /region is born with prId=191 ident:#region
> [vm_1][info 2016/07/27 13:23:25.758 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Partitioned Region /index#_region.files is born with prId=192
> ident:#index#_region.files
> [vm_1][info 2016/07/27 13:23:25.766 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Partitioned Region /index#_region.chunks is born with prId=193
> ident:#index#_region.chunks
> [vm_1][info 2016/07/27 13:23:25.770 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Initializing region gatewayEventIdIndexMetaData
> [vm_1][info 2016/07/27 13:23:25.771 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Initialization of region gatewayEventIdIndexMetaData completed
> [vm_1][info 2016/07/27 13:23:25.772 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] ConcurrentParallelGatewaySenderEventProcessor: dispatcher threads 1
> [vm_1][info 2016/07/27 13:23:25.777 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Created disk store
> DiskStore-0-1-com.gemstone.gemfire.cache.lucene.internal.cli.LuceneIndexCommandsDUnitTest.searchShouldReturnCorrectResults
> with unique id 2f6949d83c614713-82bfa37319fb982f
> [vm_1][info 2016/07/27 13:23:25.778 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] recovery region initialization took 0 ms
> [vm_1][info 2016/07/27 13:23:25.780 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Partitioned Region
> /AsyncEventQueue_index#_region_PARALLEL_GATEWAY_SENDER_QUEUE is born with
> prId=194 ident:#AsyncEventQueue_index#_region_PARALLEL_GATEWAY_SENDER_QUEUE
> [vm_1][info 2016/07/27 13:23:25.789 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Started
> ParallelGatewaySender{id=AsyncEventQueue_index#_region,remoteDsId=-1,isRunning
> =true}
> [vm_1][info 2016/07/27 13:23:25.798 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Got result: null
> [vm_1] from
> com.gemstone.gemfire.cache.lucene.internal.cli.LuceneIndexCommandsDUnitTest$$Lambda$56/255229839.run
> with 0 args on object:
> com.gemstone.gemfire.cache.lucene.internal.cli.LuceneIndexCommandsDUnitTest$$Lambda$56/255229839@7b0cbf50
> (took 412 ms)
> distributed system properties: {locators=localhost[50679],
> enable-cluster-configuration=false, use-cluster-configuration=false,
> mcast-port=0, disable-auto-reconnect=true, log-level=info}
> [info 2016/07/27 13:23:26.236 PDT <Test worker> tid=0xa] Startup
> Configuration:
> ### GemFire Properties defined with api ###
> disable-auto-reconnect=true
> enable-cluster-configuration=false
> locators=localhost[50679]
> log-level=info
> mcast-port=0
> use-cluster-configuration=false
> ### GemFire Properties using default values ###
> ack-severe-alert-threshold=0
> ack-wait-threshold=15
> archive-disk-space-limit=0
> archive-file-size-limit=0
> async-distribution-timeout=0
> async-max-queue-size=8
> async-queue-timeout=60000
> bind-address=
> cache-xml-file=cache.xml
> cluster-configuration-dir=
> cluster-ssl-ciphers=any
> cluster-ssl-enabled=false
> cluster-ssl-keystore=
> cluster-ssl-keystore-password=
> cluster-ssl-keystore-type=
> cluster-ssl-protocols=any
> cluster-ssl-require-authentication=true
> cluster-ssl-truststore=
> cluster-ssl-truststore-password=
> conflate-events=server
> conserve-sockets=true
> delta-propagation=true
> deploy-working-dir=.
> disable-tcp=false
> distributed-system-id=-1
> distributed-transactions=false
> durable-client-id=
> durable-client-timeout=300
> enable-network-partition-detection=false
> enable-time-statistics=false
> enforce-unique-host=false
> gateway-ssl-ciphers=any
> gateway-ssl-enabled=false
> gateway-ssl-keystore=
> gateway-ssl-keystore-password=
> gateway-ssl-keystore-type=
> gateway-ssl-protocols=any
> gateway-ssl-require-authentication=true
> gateway-ssl-truststore=
> gateway-ssl-truststore-password=
> groups=
> http-service-bind-address=
> http-service-port=7070
> http-service-ssl-ciphers=any
> http-service-ssl-enabled=false
> http-service-ssl-keystore=
> http-service-ssl-keystore-password=
> http-service-ssl-keystore-type=
> http-service-ssl-protocols=any
> http-service-ssl-require-authentication=false
> http-service-ssl-truststore=
> http-service-ssl-truststore-password=
> jmx-manager=false
> jmx-manager-access-file=
> jmx-manager-bind-address=
> jmx-manager-hostname-for-clients=
> jmx-manager-http-port=7070
> jmx-manager-password-file=
> jmx-manager-port=1099
> jmx-manager-ssl=false
> jmx-manager-ssl-ciphers=any
> jmx-manager-ssl-enabled=false
> jmx-manager-ssl-keystore=
> jmx-manager-ssl-keystore-password=
> jmx-manager-ssl-keystore-type=
> jmx-manager-ssl-protocols=any
> jmx-manager-ssl-require-authentication=true
> jmx-manager-ssl-truststore=
> jmx-manager-ssl-truststore-password=
> jmx-manager-start=false
> jmx-manager-update-rate=2000
> load-cluster-configuration-from-dir=false
> locator-wait-time=0
> lock-memory=false
> log-disk-space-limit=0
> log-file=
> log-file-size-limit=0
> max-num-reconnect-tries=3
> max-wait-time-reconnect=60000
> mcast-address=239.192.81.1
> mcast-flow-control=1048576, 0.25, 5000
> mcast-recv-buffer-size=1048576
> mcast-send-buffer-size=65535
> mcast-ttl=32
> member-timeout=5000
> membership-port-range=1024-65535
> memcached-bind-address=
> memcached-port=0
> memcached-protocol=ASCII
> name=
> off-heap-memory-size=
> redis-bind-address=
> redis-password=
> redis-port=0
> redundancy-zone=
> remote-locators=
> remove-unresponsive-client=false
> roles=
> security-client-accessor=
> security-client-accessor-pp=
> security-client-auth-init=
> security-client-authenticator=
> security-client-dhalgo=
> security-log-file=
> security-log-level=config
> security-manager=
> security-peer-auth-init=
> security-peer-authenticator=
> security-peer-verifymember-timeout=1000
> security-post-processor=
> server-bind-address=
> server-ssl-ciphers=any
> server-ssl-enabled=false
> server-ssl-keystore=
> server-ssl-keystore-password=
> server-ssl-keystore-type=
> server-ssl-protocols=any
> server-ssl-require-authentication=true
> server-ssl-truststore=
> server-ssl-truststore-password=
> socket-buffer-size=32768
> socket-lease-time=60000
> ssl-ciphers=any
> ssl-enabled=false
> ssl-protocols=any
> ssl-require-authentication=true
> start-dev-rest-api=false
> start-locator=
> statistic-archive-file=
> statistic-sample-rate=1000
> statistic-sampling-enabled=true
> tcp-port=0
> udp-fragment-size=60000
> udp-recv-buffer-size=1048576
> udp-send-buffer-size=65535
> user-command-packages=
> [info 2016/07/27 13:23:26.417 PDT <Test worker> tid=0xa] Starting membership
> services
> [info 2016/07/27 13:23:26.521 PDT <Test worker> tid=0xa] JGroups channel
> created (took 104ms)
> [info 2016/07/27 13:23:26.530 PDT <Test worker> tid=0xa] GemFire P2P Listener
> started on null
> [info 2016/07/27 13:23:26.533 PDT <Geode Failure Detection Server thread 0>
> tid=0x4a] Started failure detection server thread on
> venezuela.gemstone.com/10.118.32.90:4123.
> [info 2016/07/27 13:23:26.547 PDT <Test worker> tid=0xa] Attempting to join
> the distributed system through coordinator
> venezuela(31106:locator)<ec><v0>:1025 using address venezuela(31077)<ec>:1029
> [locator][info 2016/07/27 13:23:26.556 PDT <unicast receiver,venezuela-20155>
> tid=0x21] received join request from venezuela(31077)<ec>:1029
> [locator][info 2016/07/27 13:23:26.862 PDT <Geode Membership View Creator>
> tid=0x27] View Creator is processing 1 requests for the next membership view
> [locator][info 2016/07/27 13:23:26.862 PDT <Geode Membership View Creator>
> tid=0x27] preparing new view View[venezuela(31106:locator)<ec><v0>:1025|73]
> members: [venezuela(31106:locator)<ec><v0>:1025,
> venezuela(Manager:31167)<ec><v71>:1026{lead}, venezuela(31171)<ec><v72>:1028,
> venezuela(31077)<ec><v73>:1029]
> [locator]failure detection ports: 4834 6393 28702 4123
> [locator][info 2016/07/27 13:23:26.866 PDT <Geode Membership View Creator>
> tid=0x27] finished waiting for responses to view preparation
> [locator][info 2016/07/27 13:23:26.867 PDT <Geode Membership View Creator>
> tid=0x27] received new view: View[venezuela(31106:locator)<ec><v0>:1025|73]
> members: [venezuela(31106:locator)<ec><v0>:1025,
> venezuela(Manager:31167)<ec><v71>:1026{lead}, venezuela(31171)<ec><v72>:1028,
> venezuela(31077)<ec><v73>:1029]
> [locator]old view is: View[venezuela(31106:locator)<ec><v0>:1025|72] members:
> [venezuela(31106:locator)<ec><v0>:1025,
> venezuela(Manager:31167)<ec><v71>:1026{lead}, venezuela(31171)<ec><v72>:1028]
> [locator][info 2016/07/27 13:23:26.867 PDT <Geode Membership View Creator>
> tid=0x27] Peer locator received new membership view:
> View[venezuela(31106:locator)<ec><v0>:1025|73] members:
> [venezuela(31106:locator)<ec><v0>:1025,
> venezuela(Manager:31167)<ec><v71>:1026{lead}, venezuela(31171)<ec><v72>:1028,
> venezuela(31077)<ec><v73>:1029]
> [locator][info 2016/07/27 13:23:26.867 PDT <Geode Membership View Creator>
> tid=0x27] sending new view View[venezuela(31106:locator)<ec><v0>:1025|73]
> members: [venezuela(31106:locator)<ec><v0>:1025,
> venezuela(Manager:31167)<ec><v71>:1026{lead}, venezuela(31171)<ec><v72>:1028,
> venezuela(31077)<ec><v73>:1029]
> [locator]failure detection ports: 4834 6393 28702 4123
> [locator][info 2016/07/27 13:23:26.867 PDT <View Message Processor> tid=0x33]
> Membership: Processing addition < venezuela(31077)<ec><v73>:1029 >
> [locator][info 2016/07/27 13:23:26.867 PDT <View Message Processor> tid=0x33]
> Admitting member <venezuela(31077)<ec><v73>:1029>. Now there are 4 non-admin
> member(s).
> [info 2016/07/27 13:23:26.866 PDT <unicast receiver,venezuela-51611>
> tid=0x46] received new view: View[venezuela(31106:locator)<ec><v0>:1025|73]
> members: [venezuela(31106:locator)<ec><v0>:1025,
> venezuela(Manager:31167)<ec><v71>:1026{lead}, venezuela(31171)<ec><v72>:1028,
> venezuela(31077)<ec><v73>:1029]
> old view is: null
> [vm_0][info 2016/07/27 13:23:26.870 PDT <unicast receiver,venezuela-40120>
> tid=0x893] received new view: View[venezuela(31106:locator)<ec><v0>:1025|73]
> members: [venezuela(31106:locator)<ec><v0>:1025,
> venezuela(Manager:31167)<ec><v71>:1026{lead}, venezuela(31171)<ec><v72>:1028,
> venezuela(31077)<ec><v73>:1029]
> [vm_0]old view is: View[venezuela(31106:locator)<ec><v0>:1025|72] members:
> [venezuela(31106:locator)<ec><v0>:1025,
> venezuela(Manager:31167)<ec><v71>:1026{lead}, venezuela(31171)<ec><v72>:1028]
> [vm_0][info 2016/07/27 13:23:26.871 PDT <View Message Processor> tid=0x8b3]
> Membership: Processing addition < venezuela(31077)<ec><v73>:1029 >
> [vm_0][info 2016/07/27 13:23:26.871 PDT <View Message Processor> tid=0x8b3]
> Admitting member <venezuela(31077)<ec><v73>:1029>. Now there are 4 non-admin
> member(s).
> [vm_1][info 2016/07/27 13:23:26.868 PDT <unicast receiver,venezuela-21775>
> tid=0x8ed] received new view: View[venezuela(31106:locator)<ec><v0>:1025|73]
> members: [venezuela(31106:locator)<ec><v0>:1025,
> venezuela(Manager:31167)<ec><v71>:1026{lead}, venezuela(31171)<ec><v72>:1028,
> venezuela(31077)<ec><v73>:1029]
> [vm_1]old view is: View[venezuela(31106:locator)<ec><v0>:1025|72] members:
> [venezuela(31106:locator)<ec><v0>:1025,
> venezuela(Manager:31167)<ec><v71>:1026{lead}, venezuela(31171)<ec><v72>:1028]
> [vm_0][info 2016/07/27 13:23:26.872 PDT <pool-37-thread-3> tid=0x8b8]
> Initializing region _monitoringRegion_10.118.32.90<v73>1029
> [info 2016/07/27 13:23:26.873 PDT <Test worker> tid=0xa] Finished joining
> (took 339ms).
> [vm_1][info 2016/07/27 13:23:26.874 PDT <View Message Processor> tid=0x913]
> Membership: Processing addition < venezuela(31077)<ec><v73>:1029 >
> [vm_1][info 2016/07/27 13:23:26.874 PDT <View Message Processor> tid=0x913]
> Admitting member <venezuela(31077)<ec><v73>:1029>. Now there are 4 non-admin
> member(s).
> [info 2016/07/27 13:23:26.875 PDT <Test worker> tid=0xa] Starting
> DistributionManager venezuela(31077)<ec><v73>:1029. (took 594 ms)
> [info 2016/07/27 13:23:26.875 PDT <Test worker> tid=0xa] Initial
> (distribution manager) view = View[venezuela(31106:locator)<ec><v0>:1025|73]
> members: [venezuela(31106:locator)<ec><v0>:1025,
> venezuela(Manager:31167)<ec><v71>:1026{lead}, venezuela(31171)<ec><v72>:1028,
> venezuela(31077)<ec><v73>:1029]
> [info 2016/07/27 13:23:26.876 PDT <Test worker> tid=0xa] Admitting member
> <venezuela(31106:locator)<ec><v0>:1025>. Now there are 1 non-admin member(s).
> [info 2016/07/27 13:23:26.876 PDT <Test worker> tid=0xa] Admitting member
> <venezuela(Manager:31167)<ec><v71>:1026>. Now there are 2 non-admin member(s).
> [info 2016/07/27 13:23:26.877 PDT <Test worker> tid=0xa] Admitting member
> <venezuela(31171)<ec><v72>:1028>. Now there are 3 non-admin member(s).
> [info 2016/07/27 13:23:26.877 PDT <Test worker> tid=0xa] Admitting member
> <venezuela(31077)<ec><v73>:1029>. Now there are 4 non-admin member(s).
> [locator][info 2016/07/27 13:23:26.903 PDT <Pooled High Priority Message
> Processor 1> tid=0x37] Member venezuela(31077)<ec><v73>:1029 is equivalent or
> in the same redundancy zone.
> [vm_1][info 2016/07/27 13:23:26.906 PDT <Pooled High Priority Message
> Processor 1> tid=0x8fc] Member venezuela(31077)<ec><v73>:1029 is equivalent
> or in the same redundancy zone.
> [vm_0][info 2016/07/27 13:23:26.904 PDT <Pooled High Priority Message
> Processor 2> tid=0x8b6] Member venezuela(31077)<ec><v73>:1029 is equivalent
> or in the same redundancy zone.
> [info 2016/07/27 13:23:26.909 PDT <P2P message reader for
> venezuela(Manager:31167)<ec><v71>:1026 shared unordered uid=105 port=48550>
> tid=0x4d] Member venezuela(Manager:31167)<ec><v71>:1026 is equivalent or in
> the same redundancy zone.
> [info 2016/07/27 13:23:26.913 PDT <P2P message reader for
> venezuela(31171)<ec><v72>:1028 shared unordered uid=102 port=48555> tid=0x54]
> Member venezuela(31171)<ec><v72>:1028 is equivalent or in the same redundancy
> zone.
> [info 2016/07/27 13:23:26.913 PDT <P2P message reader for
> venezuela(31106:locator)<ec><v0>:1025 shared unordered uid=72 port=48554>
> tid=0x55] Member venezuela(31106:locator)<ec><v0>:1025 is equivalent or in
> the same redundancy zone.
> [vm_0][info 2016/07/27 13:23:26.928 PDT <pool-37-thread-3> tid=0x8b8]
> Initialization of region _monitoringRegion_10.118.32.90<v73>1029 completed
> [vm_0][info 2016/07/27 13:23:26.928 PDT <pool-37-thread-3> tid=0x8b8]
> Initializing region _notificationRegion_10.
> ...[truncated 1010930 chars]...
> tore-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-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-access-file=
> [vm_0]jmx-manager-hostname-for-clients=
> [vm_0]jmx-manager-http-port=22548
> [vm_0]jmx-manager-password-file=
> [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-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]off-heap-memory-size=
> [vm_0]redis-bind-address=
> [vm_0]redis-password=
> [vm_0]redis-port=0
> [vm_0]redundancy-zone=
> [vm_0]remote-locators=
> [vm_0]remove-unresponsive-client=false
> [vm_0]roles=
> [vm_0]security-client-accessor=
> [vm_0]security-client-accessor-pp=
> [vm_0]security-client-auth-init=
> [vm_0]security-client-authenticator=
> [vm_0]security-client-dhalgo=
> [vm_0]security-log-file=
> [vm_0]security-log-level=config
> [vm_0]security-manager=
> [vm_0]security-peer-auth-init=
> [vm_0]security-peer-authenticator=
> [vm_0]security-peer-verifymember-timeout=1000
> [vm_0]security-post-processor=
> [vm_0]server-bind-address=
> [vm_0]server-ssl-ciphers=any
> [vm_0]server-ssl-enabled=false
> [vm_0]server-ssl-keystore=
> [vm_0]server-ssl-keystore-password=
> [vm_0]server-ssl-keystore-type=
> [vm_0]server-ssl-protocols=any
> [vm_0]server-ssl-require-authentication=true
> [vm_0]server-ssl-truststore=
> [vm_0]server-ssl-truststore-password=
> [vm_0]socket-buffer-size=32768
> [vm_0]socket-lease-time=60000
> [vm_0]ssl-ciphers=any
> [vm_0]ssl-enabled=false
> [vm_0]ssl-protocols=any
> [vm_0]ssl-require-authentication=true
> [vm_0]start-dev-rest-api=false
> [vm_0]start-locator=
> [vm_0]statistic-archive-file=
> [vm_0]statistic-sample-rate=1000
> [vm_0]statistic-sampling-enabled=true
> [vm_0]tcp-port=0
> [vm_0]udp-fragment-size=60000
> [vm_0]udp-recv-buffer-size=1048576
> [vm_0]udp-send-buffer-size=65535
> [vm_0]user-command-packages=
> [vm_0][info 2016/07/27 13:23:50.365 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Starting membership services
> [vm_0][info 2016/07/27 13:23:50.372 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] JGroups channel created (took 7ms)
> [vm_0][info 2016/07/27 13:23:50.373 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] GemFire P2P Listener started on null
> [vm_0][info 2016/07/27 13:23:50.373 PDT <Geode Failure Detection Server
> thread 0> tid=0xae4] Started failure detection server thread on
> venezuela.gemstone.com/10.118.32.90:35158.
> [vm_0][info 2016/07/27 13:23:50.375 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Attempting to join the distributed system through coordinator
> venezuela(31106:locator)<ec><v0>:1025 using address
> venezuela(Manager:31167)<ec>:1026
> [locator][info 2016/07/27 13:23:50.376 PDT <unicast receiver,venezuela-20155>
> tid=0x21] received join request from venezuela(Manager:31167)<ec>:1026
> [locator][info 2016/07/27 13:23:50.580 PDT <Geode Membership View Creator>
> tid=0x27] View Creator is processing 3 requests for the next membership view
> [locator][info 2016/07/27 13:23:50.580 PDT <Geode Membership View Creator>
> tid=0x27] preparing new view View[venezuela(31106:locator)<ec><v0>:1025|101]
> members: [venezuela(31106:locator)<ec><v0>:1025,
> venezuela(Manager:31167)<ec><v101>:1026{lead}] shutdown:
> [venezuela(31171)<ec><v98>:1028]
> [locator]failure detection ports: 4834 35158
> [locator][info 2016/07/27 13:23:50.581 PDT <Geode Membership View Creator>
> tid=0x27] finished waiting for responses to view preparation
> [locator][info 2016/07/27 13:23:50.581 PDT <Geode Membership View Creator>
> tid=0x27] received new view: View[venezuela(31106:locator)<ec><v0>:1025|101]
> members: [venezuela(31106:locator)<ec><v0>:1025,
> venezuela(Manager:31167)<ec><v101>:1026{lead}] shutdown:
> [venezuela(31171)<ec><v98>:1028]
> [locator]old view is: View[venezuela(31106:locator)<ec><v0>:1025|100]
> members: [venezuela(31106:locator)<ec><v0>:1025,
> venezuela(31171)<ec><v98>:1028{lead}] shutdown:
> [venezuela(31077)<ec><v99>:1029, venezuela(Manager:31167)<ec><v97>:1026]
> [locator][info 2016/07/27 13:23:50.581 PDT <Geode Membership View Creator>
> tid=0x27] Peer locator received new membership view:
> View[venezuela(31106:locator)<ec><v0>:1025|101] members:
> [venezuela(31106:locator)<ec><v0>:1025,
> venezuela(Manager:31167)<ec><v101>:1026{lead}] shutdown:
> [venezuela(31171)<ec><v98>:1028]
> [vm_0][info 2016/07/27 13:23:50.581 PDT <unicast receiver,venezuela-16744>
> tid=0xae0] received new view: View[venezuela(31106:locator)<ec><v0>:1025|101]
> members: [venezuela(31106:locator)<ec><v0>:1025,
> venezuela(Manager:31167)<ec><v101>:1026{lead}] shutdown:
> [venezuela(31171)<ec><v98>:1028]
> [locator][info 2016/07/27 13:23:50.582 PDT <Geode Membership View Creator>
> tid=0x27] sending new view View[venezuela(31106:locator)<ec><v0>:1025|101]
> members: [venezuela(31106:locator)<ec><v0>:1025,
> venezuela(Manager:31167)<ec><v101>:1026{lead}] shutdown:
> [venezuela(31171)<ec><v98>:1028]
> [locator]failure detection ports: 4834 35158
> [locator][info 2016/07/27 13:23:50.582 PDT <View Message Processor> tid=0x33]
> Membership: Processing addition < venezuela(Manager:31167)<ec><v101>:1026 >
> [locator][info 2016/07/27 13:23:50.582 PDT <View Message Processor> tid=0x33]
> Admitting member <venezuela(Manager:31167)<ec><v101>:1026>. Now there are 2
> non-admin member(s).
> [vm_0]old view is: null
> [vm_0][info 2016/07/27 13:23:50.581 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Finished joining (took 208ms).
> [vm_0][info 2016/07/27 13:23:50.581 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Starting DistributionManager
> venezuela(Manager:31167)<ec><v101>:1026. (took 217 ms)
> [vm_0][info 2016/07/27 13:23:50.582 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Initial (distribution manager) view =
> View[venezuela(31106:locator)<ec><v0>:1025|101] members:
> [venezuela(31106:locator)<ec><v0>:1025,
> venezuela(Manager:31167)<ec><v101>:1026{lead}] shutdown:
> [venezuela(31171)<ec><v98>:1028]
> [vm_0][info 2016/07/27 13:23:50.583 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Admitting member <venezuela(31106:locator)<ec><v0>:1025>. Now there
> are 1 non-admin member(s).
> [vm_0][info 2016/07/27 13:23:50.583 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Admitting member <venezuela(Manager:31167)<ec><v101>:1026>. Now
> there are 2 non-admin member(s).
> [locator][info 2016/07/27 13:23:50.584 PDT <Pooled High Priority Message
> Processor 1> tid=0x37] Member venezuela(Manager:31167)<ec><v101>:1026 is
> equivalent or in the same redundancy zone.
> [vm_0][info 2016/07/27 13:23:50.586 PDT <P2P message reader for
> venezuela(31106:locator)<ec><v0>:1025 shared unordered uid=97 port=45527>
> tid=0xaea] Member venezuela(31106:locator)<ec><v0>:1025 is equivalent or in
> the same redundancy zone.
> [vm_0][info 2016/07/27 13:23:50.592 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Initializing region _monitoringRegion_10.118.32.90<v101>1026
> [vm_0][info 2016/07/27 13:23:50.593 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Initialization of region _monitoringRegion_10.118.32.90<v101>1026
> completed
> [vm_0][info 2016/07/27 13:23:50.600 PDT <pool-57-thread-1> tid=0xaf7]
> Initializing region _monitoringRegion_10.118.32.90<v0>1025
> [vm_0][info 2016/07/27 13:23:50.602 PDT <pool-57-thread-1> tid=0xaf7] Region
> _monitoringRegion_10.118.32.90<v0>1025 requesting initial image from
> venezuela(31106:locator)<ec><v0>:1025
> [vm_0][info 2016/07/27 13:23:50.603 PDT <pool-57-thread-1> tid=0xaf7]
> _monitoringRegion_10.118.32.90<v0>1025 is done getting image from
> venezuela(31106:locator)<ec><v0>:1025. isDeltaGII is false
> [vm_0][info 2016/07/27 13:23:50.604 PDT <pool-57-thread-1> tid=0xaf7]
> Initialization of region _monitoringRegion_10.118.32.90<v0>1025 completed
> [vm_0][info 2016/07/27 13:23:50.604 PDT <pool-57-thread-1> tid=0xaf7]
> Initializing region _notificationRegion_10.118.32.90<v0>1025
> [vm_0][info 2016/07/27 13:23:50.605 PDT <pool-57-thread-1> tid=0xaf7]
> Initialization of region _notificationRegion_10.118.32.90<v0>1025 completed
> [vm_0][info 2016/07/27 13:23:50.607 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] GEMFIRE HOME:null
> [vm_0][info 2016/07/27 13:23:50.607 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Reading gemfire.home System Property -> null
> [vm_0][info 2016/07/27 13:23:50.607 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] GEMFIRE environment variable not set; HTTP service will not start.
> [vm_0][warn 2016/07/27 13:23:50.608 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] geode-web war file was not found
> [vm_0][info 2016/07/27 13:23:50.608 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] GEMFIRE HOME:null
> [vm_0][info 2016/07/27 13:23:50.608 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Reading gemfire.home System Property -> null
> [vm_0][info 2016/07/27 13:23:50.608 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] GEMFIRE environment variable not set; HTTP service will not start.
> [vm_0][warn 2016/07/27 13:23:50.609 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] geode-pulse war file was not found
> [vm_0][info 2016/07/27 13:23:50.609 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] GEMFIRE HOME:null
> [vm_0][info 2016/07/27 13:23:50.609 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Reading gemfire.home System Property -> null
> [vm_0][info 2016/07/27 13:23:50.609 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] GEMFIRE environment variable not set; HTTP service will not start.
> [vm_0][warn 2016/07/27 13:23:50.610 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] geode-web-api war file was not found
> [vm_0][info 2016/07/27 13:23:50.612 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] The cache has been created with "use-cluster-configuration=false".
> It will not receive any cluster configuration
> [vm_0][info 2016/07/27 13:23:50.613 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Initializing region PdxTypes
> [vm_0][info 2016/07/27 13:23:50.613 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Initialization of region PdxTypes completed
> [vm_0][info 2016/07/27 13:23:50.614 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] <ExpectedException
> action=add>org.eclipse.jetty.io.EofException</ExpectedException>
> [vm_0][info 2016/07/27 13:23:50.614 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] <ExpectedException
> action=add>org.eclipse.jetty.io.EofException</ExpectedException>
> [vm_0][info 2016/07/27 13:23:50.614 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] <ExpectedException
> action=add>org.eclipse.jetty.io.EofException</ExpectedException>
> [vm_0][info 2016/07/27 13:23:50.614 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] <ExpectedException
> action=add>java.nio.channels.ClosedChannelException</ExpectedException>
> [vm_0][info 2016/07/27 13:23:50.614 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] <ExpectedException
> action=add>java.nio.channels.ClosedChannelException</ExpectedException>
> [vm_0][info 2016/07/27 13:23:50.614 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] <ExpectedException
> action=add>java.nio.channels.ClosedChannelException</ExpectedException>
> [vm_0][info 2016/07/27 13:23:50.614 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Got result: [Ljava.lang.Object;@1fb46462
> [vm_0] from com.gemstone.gemfire.test.dunit.NamedCallable.call with 0 args on
> object: callable(setUpJmxManagerOnVm0) (took 253 ms)
> [info 2016/07/27 13:23:50.623 PDT <Test worker> tid=0xa] Started testable
> shell: com.gemstone.gemfire.management.internal.cli.HeadlessGfsh@56546351
> LuceneIndexCommandsDUnitTest using endpoint: venezuela[21141]
> [info 2016/07/27 13:23:50.623 PDT <Test worker> tid=0xa] Executing command
> connect --jmx-manager=venezuela[21141] with command Mgr
> com.gemstone.gemfire.management.internal.cli.CommandManager@7d297cc4
> [info 2016/07/27 13:23:50.640 PDT <Test worker> tid=0xa] Successfully
> connected to managing node using JMX
> [vm_1][info 2016/07/27 13:23:50.642 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Received method:
> com.gemstone.gemfire.cache.lucene.internal.cli.LuceneIndexCommandsDUnitTest$$Lambda$56/255229839.run
> with 0 args on object:
> com.gemstone.gemfire.cache.lucene.internal.cli.LuceneIndexCommandsDUnitTest$$Lambda$56/255229839@5418533d
> [vm_1]distributed system properties: {locators=localhost[50679],
> enable-cluster-configuration=false, use-cluster-configuration=false,
> mcast-port=0, disable-auto-reconnect=true, log-level=info}
> [vm_1][info 2016/07/27 13:23:50.644 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Startup Configuration:
> [vm_1] ### GemFire Properties defined with api ###
> [vm_1]disable-auto-reconnect=true
> [vm_1]enable-cluster-configuration=false
> [vm_1]locators=localhost[50679]
> [vm_1]log-level=info
> [vm_1]mcast-port=0
> [vm_1]use-cluster-configuration=false
> [vm_1]### GemFire Properties using default values ###
> [vm_1]ack-severe-alert-threshold=0
> [vm_1]ack-wait-threshold=15
> [vm_1]archive-disk-space-limit=0
> [vm_1]archive-file-size-limit=0
> [vm_1]async-distribution-timeout=0
> [vm_1]async-max-queue-size=8
> [vm_1]async-queue-timeout=60000
> [vm_1]bind-address=
> [vm_1]cache-xml-file=cache.xml
> [vm_1]cluster-configuration-dir=
> [vm_1]cluster-ssl-ciphers=any
> [vm_1]cluster-ssl-enabled=false
> [vm_1]cluster-ssl-keystore=
> [vm_1]cluster-ssl-keystore-password=
> [vm_1]cluster-ssl-keystore-type=
> [vm_1]cluster-ssl-protocols=any
> [vm_1]cluster-ssl-require-authentication=true
> [vm_1]cluster-ssl-truststore=
> [vm_1]cluster-ssl-truststore-password=
> [vm_1]conflate-events=server
> [vm_1]conserve-sockets=true
> [vm_1]delta-propagation=true
> [vm_1]deploy-working-dir=.
> [vm_1]disable-tcp=false
> [vm_1]distributed-system-id=-1
> [vm_1]distributed-transactions=false
> [vm_1]durable-client-id=
> [vm_1]durable-client-timeout=300
> [vm_1]enable-network-partition-detection=false
> [vm_1]enable-time-statistics=false
> [vm_1]enforce-unique-host=false
> [vm_1]gateway-ssl-ciphers=any
> [vm_1]gateway-ssl-enabled=false
> [vm_1]gateway-ssl-keystore=
> [vm_1]gateway-ssl-keystore-password=
> [vm_1]gateway-ssl-keystore-type=
> [vm_1]gateway-ssl-protocols=any
> [vm_1]gateway-ssl-require-authentication=true
> [vm_1]gateway-ssl-truststore=
> [vm_1]gateway-ssl-truststore-password=
> [vm_1]groups=
> [vm_1]http-service-bind-address=
> [vm_1]http-service-port=7070
> [vm_1]http-service-ssl-ciphers=any
> [vm_1]http-service-ssl-enabled=false
> [vm_1]http-service-ssl-keystore=
> [vm_1]http-service-ssl-keystore-password=
> [vm_1]http-service-ssl-keystore-type=
> [vm_1]http-service-ssl-protocols=any
> [vm_1]http-service-ssl-require-authentication=false
> [vm_1]http-service-ssl-truststore=
> [vm_1]http-service-ssl-truststore-password=
> [vm_1]jmx-manager=false
> [vm_1]jmx-manager-access-file=
> [vm_1]jmx-manager-bind-address=
> [vm_1]jmx-manager-hostname-for-clients=
> [vm_1]jmx-manager-http-port=7070
> [vm_1]jmx-manager-password-file=
> [vm_1]jmx-manager-port=1099
> [vm_1]jmx-manager-ssl=false
> [vm_1]jmx-manager-ssl-ciphers=any
> [vm_1]jmx-manager-ssl-enabled=false
> [vm_1]jmx-manager-ssl-keystore=
> [vm_1]jmx-manager-ssl-keystore-password=
> [vm_1]jmx-manager-ssl-keystore-type=
> [vm_1]jmx-manager-ssl-protocols=any
> [vm_1]jmx-manager-ssl-require-authentication=true
> [vm_1]jmx-manager-ssl-truststore=
> [vm_1]jmx-manager-ssl-truststore-password=
> [vm_1]jmx-manager-start=false
> [vm_1]jmx-manager-update-rate=2000
> [vm_1]load-cluster-configuration-from-dir=false
> [vm_1]locator-wait-time=0
> [vm_1]lock-memory=false
> [vm_1]log-disk-space-limit=0
> [vm_1]log-file=
> [vm_1]log-file-size-limit=0
> [vm_1]max-num-reconnect-tries=3
> [vm_1]max-wait-time-reconnect=60000
> [vm_1]mcast-address=239.192.81.1
> [vm_1]mcast-flow-control=1048576, 0.25, 5000
> [vm_1]mcast-recv-buffer-size=1048576
> [vm_1]mcast-send-buffer-size=65535
> [vm_1]mcast-ttl=32
> [vm_1]member-timeout=5000
> [vm_1]membership-port-range=1024-65535
> [vm_1]memcached-bind-address=
> [vm_1]memcached-port=0
> [vm_1]memcached-protocol=ASCII
> [vm_1]name=
> [vm_1]off-heap-memory-size=
> [vm_1]redis-bind-address=
> [vm_1]redis-password=
> [vm_1]redis-port=0
> [vm_1]redundancy-zone=
> [vm_1]remote-locators=
> [vm_1]remove-unresponsive-client=false
> [vm_1]roles=
> [vm_1]security-client-accessor=
> [vm_1]security-client-accessor-pp=
> [vm_1]security-client-auth-init=
> [vm_1]security-client-authenticator=
> [vm_1]security-client-dhalgo=
> [vm_1]security-log-file=
> [vm_1]security-log-level=config
> [vm_1]security-manager=
> [vm_1]security-peer-auth-init=
> [vm_1]security-peer-authenticator=
> [vm_1]security-peer-verifymember-timeout=1000
> [vm_1]security-post-processor=
> [vm_1]server-bind-address=
> [vm_1]server-ssl-ciphers=any
> [vm_1]server-ssl-enabled=false
> [vm_1]server-ssl-keystore=
> [vm_1]server-ssl-keystore-password=
> [vm_1]server-ssl-keystore-type=
> [vm_1]server-ssl-protocols=any
> [vm_1]server-ssl-require-authentication=true
> [vm_1]server-ssl-truststore=
> [vm_1]server-ssl-truststore-password=
> [vm_1]socket-buffer-size=32768
> [vm_1]socket-lease-time=60000
> [vm_1]ssl-ciphers=any
> [vm_1]ssl-enabled=false
> [vm_1]ssl-protocols=any
> [vm_1]ssl-require-authentication=true
> [vm_1]start-dev-rest-api=false
> [vm_1]start-locator=
> [vm_1]statistic-archive-file=
> [vm_1]statistic-sample-rate=1000
> [vm_1]statistic-sampling-enabled=true
> [vm_1]tcp-port=0
> [vm_1]udp-fragment-size=60000
> [vm_1]udp-recv-buffer-size=1048576
> [vm_1]udp-send-buffer-size=65535
> [vm_1]user-command-packages=
> [vm_1][info 2016/07/27 13:23:50.645 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Starting membership services
> [vm_1][info 2016/07/27 13:23:50.651 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] JGroups channel created (took 6ms)
> [vm_1][info 2016/07/27 13:23:50.653 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] GemFire P2P Listener started on null
> [vm_1][info 2016/07/27 13:23:50.653 PDT <Geode Failure Detection Server
> thread 0> tid=0xb3e] Started failure detection server thread on
> venezuela.gemstone.com/10.118.32.90:21178.
> [vm_1][info 2016/07/27 13:23:50.654 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Attempting to join the distributed system through coordinator
> venezuela(31106:locator)<ec><v0>:1025 using address venezuela(31171)<ec>:1028
> [locator][info 2016/07/27 13:23:50.655 PDT <unicast receiver,venezuela-20155>
> tid=0x21] received join request from venezuela(31171)<ec>:1028
> [locator][info 2016/07/27 13:23:50.962 PDT <Geode Membership View Creator>
> tid=0x27] View Creator is processing 1 requests for the next membership view
> [locator][info 2016/07/27 13:23:50.962 PDT <Geode Membership View Creator>
> tid=0x27] preparing new view View[venezuela(31106:locator)<ec><v0>:1025|102]
> members: [venezuela(31106:locator)<ec><v0>:1025,
> venezuela(Manager:31167)<ec><v101>:1026{lead},
> venezuela(31171)<ec><v102>:1028]
> [locator]failure detection ports: 4834 35158 21178
> [locator][info 2016/07/27 13:23:50.963 PDT <Geode Membership View Creator>
> tid=0x27] finished waiting for responses to view preparation
> [locator][info 2016/07/27 13:23:50.963 PDT <Geode Membership View Creator>
> tid=0x27] received new view: View[venezuela(31106:locator)<ec><v0>:1025|102]
> members: [venezuela(31106:locator)<ec><v0>:1025,
> venezuela(Manager:31167)<ec><v101>:1026{lead},
> venezuela(31171)<ec><v102>:1028]
> [locator]old view is: View[venezuela(31106:locator)<ec><v0>:1025|101]
> members: [venezuela(31106:locator)<ec><v0>:1025,
> venezuela(Manager:31167)<ec><v101>:1026{lead}] shutdown:
> [venezuela(31171)<ec><v98>:1028]
> [locator][info 2016/07/27 13:23:50.963 PDT <Geode Membership View Creator>
> tid=0x27] Peer locator received new membership view:
> View[venezuela(31106:locator)<ec><v0>:1025|102] members:
> [venezuela(31106:locator)<ec><v0>:1025,
> venezuela(Manager:31167)<ec><v101>:1026{lead},
> venezuela(31171)<ec><v102>:1028]
> [locator][info 2016/07/27 13:23:50.963 PDT <Geode Membership View Creator>
> tid=0x27] sending new view View[venezuela(31106:locator)<ec><v0>:1025|102]
> members: [venezuela(31106:locator)<ec><v0>:1025,
> venezuela(Manager:31167)<ec><v101>:1026{lead},
> venezuela(31171)<ec><v102>:1028]
> [locator]failure detection ports: 4834 35158 21178
> [vm_1][info 2016/07/27 13:23:50.963 PDT <unicast receiver,venezuela-18892>
> tid=0xb3a] received new view: View[venezuela(31106:locator)<ec><v0>:1025|102]
> members: [venezuela(31106:locator)<ec><v0>:1025,
> venezuela(Manager:31167)<ec><v101>:1026{lead},
> venezuela(31171)<ec><v102>:1028]
> [vm_1]old view is: null
> [locator][info 2016/07/27 13:23:50.964 PDT <View Message Processor> tid=0x33]
> Membership: Processing addition < venezuela(31171)<ec><v102>:1028 >
> [locator][info 2016/07/27 13:23:50.964 PDT <View Message Processor> tid=0x33]
> Admitting member <venezuela(31171)<ec><v102>:1028>. Now there are 3 non-admin
> member(s).
> [vm_1][info 2016/07/27 13:23:50.965 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Finished joining (took 312ms).
> [vm_1][info 2016/07/27 13:23:50.965 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Starting DistributionManager venezuela(31171)<ec><v102>:1028.
> (took 321 ms)
> [vm_0][info 2016/07/27 13:23:50.964 PDT <unicast receiver,venezuela-16744>
> tid=0xae0] received new view: View[venezuela(31106:locator)<ec><v0>:1025|102]
> members: [venezuela(31106:locator)<ec><v0>:1025,
> venezuela(Manager:31167)<ec><v101>:1026{lead},
> venezuela(31171)<ec><v102>:1028]
> [vm_0]old view is: View[venezuela(31106:locator)<ec><v0>:1025|101] members:
> [venezuela(31106:locator)<ec><v0>:1025,
> venezuela(Manager:31167)<ec><v101>:1026{lead}] shutdown:
> [venezuela(31171)<ec><v98>:1028]
> [vm_1][info 2016/07/27 13:23:50.965 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Initial (distribution manager) view =
> View[venezuela(31106:locator)<ec><v0>:1025|102] members:
> [venezuela(31106:locator)<ec><v0>:1025,
> venezuela(Manager:31167)<ec><v101>:1026{lead},
> venezuela(31171)<ec><v102>:1028]
> [vm_1][info 2016/07/27 13:23:50.965 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Admitting member <venezuela(31106:locator)<ec><v0>:1025>. Now there
> are 1 non-admin member(s).
> [vm_1][info 2016/07/27 13:23:50.965 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Admitting member <venezuela(Manager:31167)<ec><v101>:1026>. Now
> there are 2 non-admin member(s).
> [vm_1][info 2016/07/27 13:23:50.965 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Admitting member <venezuela(31171)<ec><v102>:1028>. Now there are 3
> non-admin member(s).
> [vm_0][info 2016/07/27 13:23:50.966 PDT <View Message Processor> tid=0xafa]
> Membership: Processing addition < venezuela(31171)<ec><v102>:1028 >
> [vm_0][info 2016/07/27 13:23:50.966 PDT <View Message Processor> tid=0xafa]
> Admitting member <venezuela(31171)<ec><v102>:1028>. Now there are 3 non-admin
> member(s).
> [vm_0][info 2016/07/27 13:23:50.967 PDT <pool-57-thread-2> tid=0xafc]
> Initializing region _monitoringRegion_10.118.32.90<v102>1028
> [vm_0][info 2016/07/27 13:23:50.970 PDT <Pooled High Priority Message
> Processor 1> tid=0xaf5] Member venezuela(31171)<ec><v102>:1028 is equivalent
> or in the same redundancy zone.
> [locator][info 2016/07/27 13:23:50.969 PDT <Pooled High Priority Message
> Processor 1> tid=0x37] Member venezuela(31171)<ec><v102>:1028 is equivalent
> or in the same redundancy zone.
> [vm_1][info 2016/07/27 13:23:50.970 PDT <P2P message reader for
> venezuela(31106:locator)<ec><v0>:1025 shared unordered uid=98 port=40711>
> tid=0xb46] Member venezuela(31106:locator)<ec><v0>:1025 is equivalent or in
> the same redundancy zone.
> [vm_1][info 2016/07/27 13:23:50.971 PDT <P2P message reader for
> venezuela(Manager:31167)<ec><v101>:1026 shared unordered uid=145 port=40710>
> tid=0xb43] Member venezuela(Manager:31167)<ec><v101>:1026 is equivalent or in
> the same redundancy zone.
> [vm_0][info 2016/07/27 13:23:50.971 PDT <pool-57-thread-2> tid=0xafc]
> Initialization of region _monitoringRegion_10.118.32.90<v102>1028 completed
> [vm_0][info 2016/07/27 13:23:50.973 PDT <pool-57-thread-2> tid=0xafc]
> Initializing region _notificationRegion_10.118.32.90<v102>1028
> [vm_0][info 2016/07/27 13:23:50.973 PDT <pool-57-thread-2> tid=0xafc]
> Initialization of region _notificationRegion_10.118.32.90<v102>1028 completed
> [vm_1][info 2016/07/27 13:23:50.979 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Initializing region _monitoringRegion_10.118.32.90<v102>1028
> [vm_1][info 2016/07/27 13:23:50.980 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Region _monitoringRegion_10.118.32.90<v102>1028 requesting initial
> image from venezuela(Manager:31167)<ec><v101>:1026
> [vm_1][info 2016/07/27 13:23:50.980 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] _monitoringRegion_10.118.32.90<v102>1028 is done getting image from
> venezuela(Manager:31167)<ec><v101>:1026. isDeltaGII is false
> [vm_1][info 2016/07/27 13:23:50.981 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Initialization of region _monitoringRegion_10.118.32.90<v102>1028
> completed
> [vm_1][info 2016/07/27 13:23:50.986 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] The cache has been created with "use-cluster-configuration=false".
> It will not receive any cluster configuration
> [vm_1][info 2016/07/27 13:23:50.987 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Initializing region PdxTypes
> [vm_1][info 2016/07/27 13:23:50.990 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Region PdxTypes requesting initial image from
> venezuela(Manager:31167)<ec><v101>:1026
> [vm_1][info 2016/07/27 13:23:50.990 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] PdxTypes is done getting image from
> venezuela(Manager:31167)<ec><v101>:1026. isDeltaGII is false
> [vm_1][info 2016/07/27 13:23:50.991 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Initialization of region PdxTypes completed
> [vm_1][info 2016/07/27 13:23:50.991 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Initializing region __PR
> [vm_1][info 2016/07/27 13:23:50.992 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Initialization of region __PR completed
> [vm_1][info 2016/07/27 13:23:50.994 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Partitioned Region /region is born with prId=223 ident:#region
> [vm_1][info 2016/07/27 13:23:50.998 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Partitioned Region /index#_region.files is born with prId=224
> ident:#index#_region.files
> [vm_1][info 2016/07/27 13:23:51.001 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Partitioned Region /index#_region.chunks is born with prId=225
> ident:#index#_region.chunks
> [vm_1][info 2016/07/27 13:23:51.003 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Initializing region gatewayEventIdIndexMetaData
> [vm_1][info 2016/07/27 13:23:51.005 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Initialization of region gatewayEventIdIndexMetaData completed
> [vm_1][info 2016/07/27 13:23:51.005 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] ConcurrentParallelGatewaySenderEventProcessor: dispatcher threads 1
> [vm_1][info 2016/07/27 13:23:51.009 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Created disk store
> DiskStore-0-1-com.gemstone.gemfire.cache.lucene.internal.cli.LuceneIndexCommandsDUnitTest.listIndexShouldReturnExistingIndexWithoutStats
> with unique id df074c398e5a4982-97afe06ba3fb8c19
> [vm_1][info 2016/07/27 13:23:51.009 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] recovery region initialization took 0 ms
> [vm_1][info 2016/07/27 13:23:51.011 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Partitioned Region
> /AsyncEventQueue_index#_region_PARALLEL_GATEWAY_SENDER_QUEUE is born with
> prId=226 ident:#AsyncEventQueue_index#_region_PARALLEL_GATEWAY_SENDER_QUEUE
> [vm_1][info 2016/07/27 13:23:51.015 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Started
> ParallelGatewaySender{id=AsyncEventQueue_index#_region,remoteDsId=-1,isRunning
> =true}
> [vm_1][info 2016/07/27 13:23:51.016 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Got result: null
> [vm_1] from
> com.gemstone.gemfire.cache.lucene.internal.cli.LuceneIndexCommandsDUnitTest$$Lambda$56/255229839.run
> with 0 args on object:
> com.gemstone.gemfire.cache.lucene.internal.cli.LuceneIndexCommandsDUnitTest$$Lambda$56/255229839@5418533d
> (took 374 ms)
> Command String :
> : listIndexShouldReturnExistingIndexWithoutStats :
> Command String :
> :list lucene indexes
> [info 2016/07/27 13:23:51.018 PDT <Test worker> tid=0xa] Executing command
> list lucene indexes with command Mgr
> com.gemstone.gemfire.management.internal.cli.CommandManager@7d297cc4
> [vm_0][info 2016/07/27 13:23:51.021 PDT <RMI TCP Connection(24)-10.118.32.90>
> tid=0xab5] (tid=2741 msgId=9) Executing list lucene indexes
> Result String :
> : listIndexShouldReturnExistingIndexWithoutStats :
> Result String :
> :Index Name | Region Path | Indexed Fields | Field Analyzer
> ---------- | ----------- | ------------------------ |
> -------------------------------------------------
> index | /region | [field1, field3, field2] |
> {field1=StandardAnalyzer, field2=KeywordAnalyzer}
> [info 2016/07/27 13:23:51.026 PDT <Test worker> tid=0xa] Executing command
> exit with command Mgr
> com.gemstone.gemfire.management.internal.cli.CommandManager@7d297cc4
> [vm_0][info 2016/07/27 13:23:51.027 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Received method:
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$52/620387750.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$52/620387750@6f9db9fb
> [vm_0][info 2016/07/27 13:23:51.035 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] GemFireCache[id = 1207093590; isClosing = true; isShutDownAll =
> false; created = Wed Jul 27 13:23:50 PDT 2016; server = false; copyOnRead =
> false; lockLease = 120; lockTimeout = 60]: Now closing.
> [vm_0][info 2016/07/27 13:23:51.040 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Shutting down DistributionManager
> venezuela(Manager:31167)<ec><v101>:1026.
> [vm_1][info 2016/07/27 13:23:51.040 PDT <Pooled High Priority Message
> Processor 2> tid=0xb52] received leave request from
> venezuela(Manager:31167)<ec><v101>:1026 for
> venezuela(Manager:31167)<ec><v101>:1026
> [vm_1][info 2016/07/27 13:23:51.040 PDT <Pooled High Priority Message
> Processor 2> tid=0xb52] Member at venezuela(Manager:31167)<ec><v101>:1026
> gracefully left the distributed cache: shutdown message received
> [locator][info 2016/07/27 13:23:51.041 PDT <Pooled High Priority Message
> Processor 1> tid=0x37] received leave request from
> venezuela(Manager:31167)<ec><v101>:1026 for
> venezuela(Manager:31167)<ec><v101>:1026
> [locator][info 2016/07/27 13:23:51.041 PDT <Pooled High Priority Message
> Processor 1> tid=0x37] Member at venezuela(Manager:31167)<ec><v101>:1026
> gracefully left the distributed cache: shutdown message received
> [vm_0][info 2016/07/27 13:23:51.145 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Now closing distribution for venezuela(Manager:31167)<ec><v101>:1026
> [vm_0][info 2016/07/27 13:23:51.145 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Stopping membership services
> [locator][info 2016/07/27 13:23:51.145 PDT <unicast receiver,venezuela-20155>
> tid=0x21] received leave request from venezuela(Manager:31167)<ec><v101>:1026
> for venezuela(Manager:31167)<ec><v101>:1026
> [vm_0][info 2016/07/27 13:23:51.145 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] GMSHealthMonitor server socket is closed in stopServices().
> [vm_0][info 2016/07/27 13:23:51.145 PDT <Geode Failure Detection Server
> thread 0> tid=0xae4] GMSHealthMonitor server thread exiting
> [vm_0][info 2016/07/27 13:23:51.145 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] GMSHealthMonitor serverSocketExecutor is terminated
> [vm_1][info 2016/07/27 13:23:51.145 PDT <unicast receiver,venezuela-18892>
> tid=0xb3a] received leave request from
> venezuela(Manager:31167)<ec><v101>:1026 for
> venezuela(Manager:31167)<ec><v101>:1026
> [vm_0][info 2016/07/27 13:23:51.148 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] DistributionManager stopped in 108ms.
> [vm_0][info 2016/07/27 13:23:51.149 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Marking DistributionManager venezuela(Manager:31167)<ec><v101>:1026
> as closed.
> [vm_0][info 2016/07/27 13:23:51.149 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Got result: null
> [vm_0] from
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$52/620387750.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$52/620387750@6f9db9fb
> (took 122 ms)
> [vm_1][info 2016/07/27 13:23:51.150 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Received method:
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$55/508096654.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$55/508096654@243d5b26
> [vm_1][info 2016/07/27 13:23:51.151 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] GemFireCache[id = 60466226; isClosing = true; isShutDownAll =
> false; created = Wed Jul 27 13:23:50 PDT 2016; server = false; copyOnRead =
> false; lockLease = 120; lockTimeout = 60]: Now closing.
> [locator][info 2016/07/27 13:23:51.349 PDT <Geode Membership View Creator>
> tid=0x27] View Creator is processing 2 requests for the next membership view
> [locator][info 2016/07/27 13:23:51.349 PDT <Geode Membership View Creator>
> tid=0x27] preparing new view View[venezuela(31106:locator)<ec><v0>:1025|103]
> members: [venezuela(31106:locator)<ec><v0>:1025,
> venezuela(31171)<ec><v102>:1028{lead}] shutdown:
> [venezuela(Manager:31167)<ec><v101>:1026]
> [locator]failure detection ports: 4834 21178
> [locator][info 2016/07/27 13:23:51.350 PDT <Geode Membership View Creator>
> tid=0x27] finished waiting for responses to view preparation
> [locator][info 2016/07/27 13:23:51.350 PDT <Geode Membership View Creator>
> tid=0x27] received new view: View[venezuela(31106:locator)<ec><v0>:1025|103]
> members: [venezuela(31106:locator)<ec><v0>:1025,
> venezuela(31171)<ec><v102>:1028{lead}] shutdown:
> [venezuela(Manager:31167)<ec><v101>:1026]
> [locator]old view is: View[venezuela(31106:locator)<ec><v0>:1025|102]
> members: [venezuela(31106:locator)<ec><v0>:1025,
> venezuela(Manager:31167)<ec><v101>:1026{lead},
> venezuela(31171)<ec><v102>:1028]
> [locator][info 2016/07/27 13:23:51.350 PDT <Geode Membership View Creator>
> tid=0x27] Peer locator received new membership view:
> View[venezuela(31106:locator)<ec><v0>:1025|103] members:
> [venezuela(31106:locator)<ec><v0>:1025,
> venezuela(31171)<ec><v102>:1028{lead}] shutdown:
> [venezuela(Manager:31167)<ec><v101>:1026]
> [locator][info 2016/07/27 13:23:51.351 PDT <Geode Membership View Creator>
> tid=0x27] sending new view View[venezuela(31106:locator)<ec><v0>:1025|103]
> members: [venezuela(31106:locator)<ec><v0>:1025,
> venezuela(31171)<ec><v102>:1028{lead}] shutdown:
> [venezuela(Manager:31167)<ec><v101>:1026]
> [locator]failure detection ports: 4834 21178
> [vm_1][info 2016/07/27 13:23:51.353 PDT <unicast receiver,venezuela-18892>
> tid=0xb3a] received new view: View[venezuela(31106:locator)<ec><v0>:1025|103]
> members: [venezuela(31106:locator)<ec><v0>:1025,
> venezuela(31171)<ec><v102>:1028{lead}] shutdown:
> [venezuela(Manager:31167)<ec><v101>:1026]
> [vm_1]old view is: View[venezuela(31106:locator)<ec><v0>:1025|102] members:
> [venezuela(31106:locator)<ec><v0>:1025,
> venezuela(Manager:31167)<ec><v101>:1026{lead},
> venezuela(31171)<ec><v102>:1028]
> [vm_1][info 2016/07/27 13:23:52.069 PDT <BatchRemovalThread> tid=0xb5b] The
> QueueRemovalThread is done.
> [vm_1][info 2016/07/27 13:23:52.069 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Stopped
> ParallelGatewaySender{id=AsyncEventQueue_index#_region,remoteDsId=-1,isRunning
> =false}
> [vm_1][info 2016/07/27 13:23:52.076 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Shutting down DistributionManager venezuela(31171)<ec><v102>:1028.
> [locator][info 2016/07/27 13:23:52.076 PDT <Pooled High Priority Message
> Processor 1> tid=0x37] received leave request from
> venezuela(31171)<ec><v102>:1028 for venezuela(31171)<ec><v102>:1028
> [locator][info 2016/07/27 13:23:52.077 PDT <Pooled High Priority Message
> Processor 1> tid=0x37] Member at venezuela(31171)<ec><v102>:1028 gracefully
> left the distributed cache: shutdown message received
> [vm_1][info 2016/07/27 13:23:52.180 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Now closing distribution for venezuela(31171)<ec><v102>:1028
> [vm_1][info 2016/07/27 13:23:52.180 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Stopping membership services
> [locator][info 2016/07/27 13:23:52.180 PDT <unicast receiver,venezuela-20155>
> tid=0x21] received leave request from venezuela(31171)<ec><v102>:1028 for
> venezuela(31171)<ec><v102>:1028
> [vm_1][info 2016/07/27 13:23:52.180 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] GMSHealthMonitor server socket is closed in stopServices().
> [vm_1][info 2016/07/27 13:23:52.180 PDT <Geode Failure Detection Server
> thread 0> tid=0xb3e] GMSHealthMonitor server thread exiting
> [vm_1][info 2016/07/27 13:23:52.180 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] GMSHealthMonitor serverSocketExecutor is terminated
> [vm_1][info 2016/07/27 13:23:52.183 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] DistributionManager stopped in 107ms.
> [vm_1][info 2016/07/27 13:23:52.183 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Marking DistributionManager venezuela(31171)<ec><v102>:1028 as
> closed.
> [vm_1][info 2016/07/27 13:23:52.184 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Got result: null
> [vm_1] from
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$55/508096654.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$55/508096654@243d5b26
> (took 1033 ms)
> [vm_2][info 2016/07/27 13:23:52.184 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Received method:
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$8/391722993.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$8/391722993@62f95263
> [vm_2][info 2016/07/27 13:23:52.185 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Got result: null
> [vm_2] from
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$8/391722993.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$8/391722993@62f95263
> (took 0 ms)
> [vm_3][info 2016/07/27 13:23:52.185 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Received method:
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$8/2126059778.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$8/2126059778@43f366e3
> [vm_3][info 2016/07/27 13:23:52.185 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Got result: null
> [vm_3] from
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$8/2126059778.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$8/2126059778@43f366e3
> (took 0 ms)
> [vm_0][info 2016/07/27 13:23:52.186 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run
> with 0 args on object: runnable(verify service stopped)
> [vm_0][info 2016/07/27 13:23:52.186 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Got result: null
> [vm_0] from com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on
> object: runnable(verify service stopped) (took 0 ms)
> [vm_0][info 2016/07/27 13:23:52.186 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Received method:
> com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$14/1685871809.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$14/1685871809@bba7116
> [vm_0][info 2016/07/27 13:23:52.187 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Got result: null
> [vm_0] from
> com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$14/1685871809.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$14/1685871809@bba7116
> (took 0 ms)
> [vm_1][info 2016/07/27 13:23:52.187 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Received method:
> com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$9/720099144.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$9/720099144@7c1d0fde
> [vm_1][info 2016/07/27 13:23:52.187 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Got result: null
> [vm_1] from
> com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$9/720099144.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$9/720099144@7c1d0fde
> (took 0 ms)
> [vm_2][info 2016/07/27 13:23:52.188 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Received method:
> com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$4/350815370.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$4/350815370@23bcbb57
> [vm_2][info 2016/07/27 13:23:52.188 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Got result: null
> [vm_2] from
> com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$4/350815370.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$4/350815370@23bcbb57
> (took 0 ms)
> [vm_3][info 2016/07/27 13:23:52.190 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Received method:
> com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$4/1823529895.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$4/1823529895@444b7a43
> [vm_3][info 2016/07/27 13:23:52.190 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Got result: null
> [vm_3] from
> com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$4/1823529895.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase$$Lambda$4/1823529895@444b7a43
> (took 0 ms)
> [vm_0][info 2016/07/27 13:23:52.191 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run
> with 0 args on object: runnable(tearDownCreationStackGenerator)
> [vm_0][info 2016/07/27 13:23:52.191 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Got result: null
> [vm_0] from com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on
> object: runnable(tearDownCreationStackGenerator) (took 0 ms)
> [vm_1][info 2016/07/27 13:23:52.192 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run
> with 0 args on object: runnable(tearDownCreationStackGenerator)
> [vm_1][info 2016/07/27 13:23:52.192 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Got result: null
> [vm_1] from com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on
> object: runnable(tearDownCreationStackGenerator) (took 0 ms)
> [vm_2][info 2016/07/27 13:23:52.192 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run
> with 0 args on object: runnable(tearDownCreationStackGenerator)
> [vm_2][info 2016/07/27 13:23:52.193 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Got result: null
> [vm_2] from com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on
> object: runnable(tearDownCreationStackGenerator) (took 0 ms)
> [vm_3][info 2016/07/27 13:23:52.193 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run
> with 0 args on object: runnable(tearDownCreationStackGenerator)
> [vm_3][info 2016/07/27 13:23:52.193 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Got result: null
> [vm_3] from com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on
> object: runnable(tearDownCreationStackGenerator) (took 0 ms)
> [vm_0][info 2016/07/27 13:23:52.194 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run
> with 0 args on object: runnable(tearDownVM)
> [vm_0][info 2016/07/27 13:23:52.194 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] <ExpectedException
> action=remove>org.eclipse.jetty.io.EofException</ExpectedException>
> [vm_0][info 2016/07/27 13:23:52.194 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] <ExpectedException
> action=remove>org.eclipse.jetty.io.EofException</ExpectedException>
> [vm_0][info 2016/07/27 13:23:52.194 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] <ExpectedException
> action=remove>java.nio.channels.ClosedChannelException</ExpectedException>
> [vm_0][info 2016/07/27 13:23:52.194 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] <ExpectedException
> action=remove>java.nio.channels.ClosedChannelException</ExpectedException>
> [vm_0][info 2016/07/27 13:23:52.194 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Got result: null
> [vm_0] from com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on
> object: runnable(tearDownVM) (took 0 ms)
> [vm_1][info 2016/07/27 13:23:52.195 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run
> with 0 args on object: runnable(tearDownVM)
> [vm_1][info 2016/07/27 13:23:52.195 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Got result: null
> [vm_1] from com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on
> object: runnable(tearDownVM) (took 0 ms)
> [vm_2][info 2016/07/27 13:23:52.195 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run
> with 0 args on object: runnable(tearDownVM)
> [vm_2][info 2016/07/27 13:23:52.195 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Got result: null
> [vm_2] from com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on
> object: runnable(tearDownVM) (took 0 ms)
> [vm_3][info 2016/07/27 13:23:52.196 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Received method: com.gemstone.gemfire.test.dunit.NamedRunnable.run
> with 0 args on object: runnable(tearDownVM)
> [vm_3][info 2016/07/27 13:23:52.196 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Got result: null
> [vm_3] from com.gemstone.gemfire.test.dunit.NamedRunnable.run with 0 args on
> object: runnable(tearDownVM) (took 0 ms)
> [locator][info 2016/07/27 13:23:52.197 PDT <RMI TCP
> Connection(1)-10.118.32.90> tid=0x12] Received method:
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/664344873.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/664344873@29af02cc
> [locator][info 2016/07/27 13:23:52.197 PDT <RMI TCP
> Connection(1)-10.118.32.90> tid=0x12] Got result: null
> [locator] from
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/664344873.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.internal.JUnit4DistributedTestCase$$Lambda$6/664344873@29af02cc
> (took 0 ms)
> [vm_0][info 2016/07/27 13:23:52.198 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Received method:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$2.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$2@27fd8abc
> [vm_0][info 2016/07/27 13:23:52.198 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Got result: null
> [vm_0] from
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$2.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$2@27fd8abc
> (took 0 ms)
> [vm_1][info 2016/07/27 13:23:52.199 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Received method:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$2.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$2@66149976
> [vm_1][info 2016/07/27 13:23:52.199 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Got result: null
> [vm_1] from
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$2.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$2@66149976
> (took 0 ms)
> [vm_2][info 2016/07/27 13:23:52.199 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Received method:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$2.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$2@65b8dfad
> [vm_2][info 2016/07/27 13:23:52.200 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Got result: null
> [vm_2] from
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$2.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$2@65b8dfad
> (took 0 ms)
> [vm_3][info 2016/07/27 13:23:52.200 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Received method:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$2.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$2@11aaf679
> [vm_3][info 2016/07/27 13:23:52.200 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Got result: null
> [vm_3] from
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$2.run
> with 0 args on object:
> com.gemstone.gemfire.test.dunit.rules.DistributedRestoreSystemProperties$2@11aaf679
> (took 0 ms)
> Standard Error
> Suspicious strings were written to the log during this run.
> Fix the strings or use IgnoredException.addIgnoredException to ignore.
> -----------------------------------------------------------------------
> Found suspect string in log4j at line 328
> [error 2016/07/27 13:23:32.619 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] Jmx manager could not be started because
> java.rmi.server.ExportException: Port already in use: 23729; nested exception
> is:
> java.net.BindException: Failed to create server socket on
> venezuela/10.118.32.90[23,729]
> com.gemstone.gemfire.management.ManagementException:
> java.rmi.server.ExportException: Port already in use: 23729; nested exception
> is:
> java.net.BindException: Failed to create server socket on
> venezuela/10.118.32.90[23,729]
> at
> com.gemstone.gemfire.management.internal.ManagementAgent.startAgent(ManagementAgent.java:147)
> at
> com.gemstone.gemfire.management.internal.SystemManagementService.startManager(SystemManagementService.java:479)
> at
> com.gemstone.gemfire.management.internal.beans.ManagementAdapter.handleCacheCreation(ManagementAdapter.java:197)
> at
> com.gemstone.gemfire.management.internal.beans.ManagementListener.handleEvent(ManagementListener.java:119)
> at
> com.gemstone.gemfire.distributed.internal.InternalDistributedSystem.notifyResourceEventListeners(InternalDistributedSystem.java:2085)
> at
> com.gemstone.gemfire.distributed.internal.InternalDistributedSystem.handleResourceEvent(InternalDistributedSystem.java:478)
> at
> com.gemstone.gemfire.internal.cache.GemFireCacheImpl.initialize(GemFireCacheImpl.java:1110)
> at
> com.gemstone.gemfire.internal.cache.GemFireCacheImpl.basicCreate(GemFireCacheImpl.java:763)
> at
> com.gemstone.gemfire.internal.cache.GemFireCacheImpl.create(GemFireCacheImpl.java:751)
> at com.gemstone.gemfire.cache.CacheFactory.create(CacheFactory.java:181)
> at com.gemstone.gemfire.cache.CacheFactory.create(CacheFactory.java:172)
> at
> com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase.createCache(JUnit4CacheTestCase.java:120)
> at
> com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase.getCache(JUnit4CacheTestCase.java:263)
> at
> com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase.getCache(JUnit4CacheTestCase.java:242)
> at
> com.gemstone.gemfire.test.dunit.cache.internal.JUnit4CacheTestCase.getCache(JUnit4CacheTestCase.java:234)
> at
> com.gemstone.gemfire.management.internal.cli.commands.CliCommandTestBase.lambda$setUpJMXManagerOnVM$d0384528$1(CliCommandTestBase.java:156)
> at
> com.gemstone.gemfire.test.dunit.NamedCallable.call(NamedCallable.java:33)
> 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 hydra.MethExecutor.executeObject(MethExecutor.java:268)
> at
> com.gemstone.gemfire.test.dunit.standalone.RemoteDUnitVM.executeMethodOnObject(RemoteDUnitVM.java:82)
> at sun.reflect.GeneratedMethodAccessor50.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)
> 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.rmi.server.ExportException: Port already in use: 23729;
> nested exception is:
> java.net.BindException: Failed to create server socket on
> venezuela/10.118.32.90[23,729]
> at sun.rmi.transport.tcp.TCPTransport.listen(TCPTransport.java:341)
> at
> sun.rmi.transport.tcp.TCPTransport.exportObject(TCPTransport.java:249)
> at sun.rmi.transport.tcp.TCPEndpoint.exportObject(TCPEndpoint.java:411)
> at sun.rmi.transport.LiveRef.exportObject(LiveRef.java:147)
> at
> sun.rmi.server.UnicastServerRef.exportObject(UnicastServerRef.java:208)
> at sun.rmi.registry.RegistryImpl.setup(RegistryImpl.java:152)
> at sun.rmi.registry.RegistryImpl.<init>(RegistryImpl.java:112)
> at
> java.rmi.registry.LocateRegistry.createRegistry(LocateRegistry.java:239)
> at
> com.gemstone.gemfire.management.internal.ManagementAgent.configureAndStart(ManagementAgent.java:389)
> at
> com.gemstone.gemfire.management.internal.ManagementAgent.startAgent(ManagementAgent.java:145)
> ... 38 more
> Caused by: java.net.BindException: Failed to create server socket on
> venezuela/10.118.32.90[23,729]
> at
> com.gemstone.gemfire.internal.SocketCreator.createServerSocket(SocketCreator.java:814)
> at
> com.gemstone.gemfire.internal.SocketCreator.createServerSocket(SocketCreator.java:768)
> at
> com.gemstone.gemfire.management.internal.ManagementAgent$GemFireRMIServerSocketFactory.createServerSocket(ManagementAgent.java:545)
> at
> sun.rmi.transport.tcp.TCPEndpoint.newServerSocket(TCPEndpoint.java:666)
> at sun.rmi.transport.tcp.TCPTransport.listen(TCPTransport.java:330)
> ... 47 more
> Caused by: java.net.BindException: Address already in use
> at java.net.PlainSocketImpl.socketBind(Native Method)
> at
> java.net.AbstractPlainSocketImpl.bind(AbstractPlainSocketImpl.java:387)
> at java.net.ServerSocket.bind(ServerSocket.java:375)
> at
> com.gemstone.gemfire.internal.SocketCreator.createServerSocket(SocketCreator.java:811)
> ... 51 more
> -----------------------------------------------------------------------
> Found suspect string in log4j at line 398
> [error 2016/07/27 13:23:32.625 PDT <RMI TCP Connection(1)-10.118.32.90>
> tid=0x12] com.gemstone.gemfire.management.ManagementException:
> java.rmi.server.ExportException: Port already in use: 23729; nested exception
> is:
> java.net.BindException: Failed to create server socket on
> venezuela/10.118.32.90[23,729]
> {noformat}
--
This message was sent by Atlassian JIRA
(v6.3.4#6332)
