[
https://issues.apache.org/jira/browse/GEODE-6888?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=16867141#comment-16867141
]
Aaron Lindsey commented on GEODE-6888:
--------------------------------------
Notes on this failure:
* I ran this test locally, and I saw the suspect string
"java.net.ConnectException: Connection refused (Connection refused)" in the
logs.
* However, it was a WARN level log message, and those should be skipped
according to LogConsumer.skipLevelPattern.
* Looking at the logs in std out for this failure, all of the suspect strings
are found in WARN level log messages.
* Therefore, I am surprised this resulted in a failure since WARN level
messages are supposed to be ignored.
> CI failure: HAGIIDUnitTest.testGIIRegionQueue Suspect string in the log
> -----------------------------------------------------------------------
>
> Key: GEODE-6888
> URL: https://issues.apache.org/jira/browse/GEODE-6888
> Project: Geode
> Issue Type: Bug
> Components: client/server
> Reporter: Anilkumar Gingade
> Priority: Major
>
> java.lang.AssertionError: 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 2826 java.net.ConnectException:
> Connection refused (Connection refused)
> Vm2 log with Exception:
> [vm2] Library Path: [vm2] /usr/java/packages/lib [vm2]
> /usr/lib/x86_64-linux-gnu/jni [vm2] /lib/x86_64-linux-gnu [vm2]
> /usr/lib/x86_64-linux-gnu [vm2] /usr/lib/jni [vm2] /lib [vm2] /usr/lib [vm2]
> System Properties: [vm2] Locator.inhibitDMBanner = true [vm2] WORKSPACE_DIR =
> /home/geode/geode/geode-core/build/distributedTest933/. [vm2] awt.toolkit =
> sun.awt.X11.XToolkit [vm2] dummyArg = true [vm2] file.encoding = UTF-8 [vm2]
> file.separator = / [vm2] gemfire.DEFAULT_MAX_OPLOG_SIZE = 10 [vm2]
> gemfire.DUnitLauncher.LAUNCHED = true [vm2] gemfire.DUnitLauncher.RMI_PORT =
> 24145 [vm2] gemfire.DUnitLauncher.VM_NUM = 2 [vm2]
> gemfire.DUnitLauncher.VM_VERSION = 000 [vm2] gemfire.disallowMcastDefaults =
> true [vm2] gemfire.free-off-heap-memory = true [vm2]
> gemfire.use-ephemeral-ports = true [vm2]
> gemfire.validate-serializable-objects = true [vm2] java.awt.graphicsenv =
> sun.awt.X11GraphicsEnvironment [vm2] java.awt.printerjob =
> sun.print.PSPrinterJob [vm2] java.class.version = 55.0 [vm2] java.home =
> /usr/lib/jvm/java-11-openjdk-amd64 [vm2] java.io.tmpdir = /tmp [vm2]
> java.runtime.name = OpenJDK Runtime Environment [vm2] java.runtime.version =
> 11.0.3+7-Ubuntu-1ubuntu218.04.1 [vm2] java.specification.name = Java Platform
> API Specification [vm2] java.specification.vendor = Oracle Corporation [vm2]
> java.specification.version = 11 [vm2] java.vendor = Oracle Corporation [vm2]
> java.vendor.url = [http://java.oracle.com/] [vm2] java.vendor.url.bug =
> [http://bugreport.java.com/bugreport/] [vm2] java.version = 11.0.3 [vm2]
> java.version.date = 2019-04-16 [vm2] java.vm.compressedOopsMode = 32-bit
> [vm2] java.vm.info = mixed mode, sharing [vm2] java.vm.name = OpenJDK 64-Bit
> Server VM [vm2] java.vm.specification.name = Java Virtual Machine
> Specification [vm2] java.vm.specification.vendor = Oracle Corporation [vm2]
> java.vm.specification.version = 11 [vm2] java.vm.vendor = Oracle Corporation
> [vm2] java.vm.version = 11.0.3+7-Ubuntu-1ubuntu218.04.1 [vm2] jdk.debug =
> release [vm2] line.separator = [vm2] log-level = info [vm2] os.version =
> 4.15.0-1033-gcp [vm2] path.separator = : [vm2] sun.arch.data.model = 64 [vm2]
> sun.boot.library.path = /usr/lib/jvm/java-11-openjdk-amd64/lib [vm2]
> sun.cpu.endian = little [vm2] sun.cpu.isalist = [vm2] sun.io.unicode.encoding
> = UnicodeLittle [vm2] sun.java.command =
> org.apache.geode.test.dunit.internal.ChildVM [vm2] sun.java.launcher =
> SUN_STANDARD [vm2] sun.jnu.encoding = UTF-8 [vm2] sun.management.compiler =
> HotSpot 64-Bit Tiered Compilers [vm2] sun.nio.ch.bugLevel = [vm2]
> sun.os.patch.level = unknown [vm2] user.language = en [vm2] user.timezone =
> GMT [vm2] Log4J 2 Configuration: [vm2]
> /home/geode/geode/geode-core/build/resources/main/log4j2.xml [vm2]
> ---------------------------------------------------------------------------
> [vm2] [info 2019/06/18 16:53:44.443 GMT <RMI TCP Connection(1)-172.17.0.8>
> tid=0x22] Startup Configuration: [vm2] ### GemFire Properties defined with
> system property ### [vm2] validate-serializable-objects=true [vm2] ###
> GemFire Properties defined with api ### [vm2] disable-auto-reconnect=true
> [vm2] enable-cluster-configuration=false [vm2] locators= [vm2] log-level=info
> [vm2] mcast-port=0 [vm2] use-cluster-configuration=false [vm2] ### GemFire
> Properties using default values ### [vm2] ack-severe-alert-threshold=0 [vm2]
> ack-wait-threshold=15 [vm2] archive-disk-space-limit=0 [vm2]
> archive-file-size-limit=0 [vm2] async-distribution-timeout=0 [vm2]
> async-max-queue-size=8 [vm2] async-queue-timeout=60000 [vm2] bind-address=
> [vm2] cache-xml-file=cache.xml [vm2] cluster-configuration-dir= [vm2]
> cluster-ssl-ciphers=any [vm2] cluster-ssl-enabled=false [vm2]
> cluster-ssl-keystore= [vm2] cluster-ssl-keystore-password= [vm2]
> cluster-ssl-keystore-type= [vm2] cluster-ssl-protocols=any [vm2]
> cluster-ssl-require-authentication=true [vm2] cluster-ssl-truststore= [vm2]
> cluster-ssl-truststore-password= [vm2] conflate-events=server [vm2]
> conserve-sockets=true [vm2] delta-propagation=true [vm2]
> deploy-working-dir=/home/geode/geode/geode-core/build/distributedTest933/dunit/vm2
> [vm2] disable-jmx=false [vm2] disable-tcp=false [vm2]
> distributed-system-id=-1 [vm2] distributed-transactions=false [vm2]
> durable-client-id= [vm2] durable-client-timeout=300 [vm2]
> enable-network-partition-detection=true [vm2] enable-time-statistics=false
> [vm2] enforce-unique-host=false [vm2] gateway-ssl-ciphers=any [vm2]
> gateway-ssl-enabled=false [vm2] gateway-ssl-keystore= [vm2]
> gateway-ssl-keystore-password= [vm2] gateway-ssl-keystore-type= [vm2]
> gateway-ssl-protocols=any [vm2] gateway-ssl-require-authentication=true [vm2]
> gateway-ssl-truststore= [vm2] gateway-ssl-truststore-password= [vm2] groups=
> [vm2] http-service-bind-address= [vm2] http-service-port=7070 [vm2]
> http-service-ssl-ciphers=any [vm2] http-service-ssl-enabled=false [vm2]
> http-service-ssl-keystore= [vm2] http-service-ssl-keystore-password= [vm2]
> http-service-ssl-keystore-type= [vm2] http-service-ssl-protocols=any [vm2]
> http-service-ssl-require-authentication=false [vm2]
> http-service-ssl-truststore= [vm2] http-service-ssl-truststore-password=
> [vm2] jmx-manager=false [vm2] jmx-manager-access-file= [vm2]
> jmx-manager-bind-address= [vm2] jmx-manager-hostname-for-clients= [vm2]
> jmx-manager-http-port=7070 [vm2] jmx-manager-password-file= [vm2]
> jmx-manager-port=1099 [vm2] jmx-manager-ssl-ciphers=any [vm2]
> jmx-manager-ssl-enabled=false [vm2] jmx-manager-ssl-keystore= [vm2]
> jmx-manager-ssl-keystore-password= [vm2] jmx-manager-ssl-keystore-type= [vm2]
> jmx-manager-ssl-protocols=any [vm2]
> jmx-manager-ssl-require-authentication=true [vm2] jmx-manager-ssl-truststore=
> [vm2] jmx-manager-ssl-truststore-password= [vm2] jmx-manager-start=false
> [vm2] jmx-manager-update-rate=2000 [vm2]
> load-cluster-configuration-from-dir=false [vm2] locator-wait-time=0 [vm2]
> lock-memory=false [vm2] log-disk-space-limit=0 [vm2] log-file= [vm2]
> log-file-size-limit=0 [vm2] max-num-reconnect-tries=3 [vm2]
> max-wait-time-reconnect=60000 [vm2] mcast-address=239.192.81.1 [vm2]
> mcast-flow-control=1048576, 0.25, 5000 [vm2] mcast-recv-buffer-size=1048576
> [vm2] mcast-send-buffer-size=65535 [vm2] mcast-ttl=32 [vm2]
> member-timeout=5000 [vm2] membership-port-range=41000-61000 [vm2]
> memcached-bind-address= [vm2] memcached-port=0 [vm2] memcached-protocol=ASCII
> [vm2] name= [vm2] off-heap-memory-size= [vm2] redis-bind-address= [vm2]
> redis-password= [vm2] redis-port=0 [vm2] redundancy-zone= [vm2]
> remote-locators= [vm2] remove-unresponsive-client=false [vm2] roles= [vm2]
> security-client-accessor= [vm2] security-client-accessor-pp= [vm2]
> security-client-auth-init= [vm2] security-client-authenticator= [vm2]
> security-client-dhalgo= [vm2] security-log-file= [vm2]
> security-log-level=config [vm2] security-manager= [vm2]
> security-peer-auth-init= [vm2] security-peer-authenticator= [vm2]
> security-peer-verifymember-timeout=1000 [vm2] security-post-processor= [vm2]
> security-udp-dhalgo= [vm2] serializable-object-filter=Unable to render
> embedded object: File (53:44.444 GMT <RMI TCP Connection(1)-172.17.0.8>
> tid=0x22] initializing InternalDataSerializer with 0 services [vm2] [info
> 2019/06/18 16:53:44.452 GMT <RMI TCP Connection(1)-172.17.0.8> tid=0x22]
> setting a serialization filter containing
> java.**;javax.management.**;javax.print.attribute.EnumSyntax;antlr.**;org.apache.commons.modeler.AttributeInfo;org.apache.commons.modeler.FeatureInfo;org.apache.commons.modeler.ManagedBean;org.apache.geode.distributed.internal.DistributionConfigSnapshot;org.apache.geode.distributed.internal.RuntimeDistributionConfigImpl;org.apache.geode.distributed.internal.DistributionConfigImpl;org.apache.geode.distributed.internal.membership.InternalDistributedMember;org.apache.geode.internal.cache.persistence.PersistentMemberID;org.apache.geode.internal.cache.persistence.DiskStoreID;org.apache.geode.internal.cache.tier.sockets.VersionedObjectList;org.apache.shiro.*;org.apache.shiro.authz.*;org.apache.shiro.authc.*;org.apache.logging.log4j.Level;org.apache.logging.log4j.spi.StandardLevel;com.sun.proxy.$Proxy*;com.healthmarketscience.rmiio.RemoteInputStream;javax.rmi.ssl.SslRMIClientSocketFactory;javax.net.ssl.SSLHandshakeException;javax.net.ssl.SSLException;sun.security.validator.ValidatorException;sun.security.provider.certpath.SunCertPathBuilderException;org.apache.geode.modules.util.SessionCustomExpiry;)
> not found.*;!* [vm2] [info 2019/06/18 16:53:44.464 GMT <RMI TCP
> Connection(1)-172.17.0.8> tid=0x22] [ThreadsMonitor] New Monitor object and
> process were created. [vm2] [info 2019/06/18 16:53:44.475 GMT <StatSampler>
> tid=0x32] Disabling statistic archival. [vm2] [info 2019/06/18 16:53:44.642
> GMT <RMI TCP Connection(1)-172.17.0.8> tid=0x22] Running in local mode since
> no locators were specified. [vm2] [info 2019/06/18 16:53:44.827 GMT <RMI TCP
> Connection(1)-172.17.0.8> tid=0x22] Enabled HttpService on port 7070 [vm2]
> [info 2019/06/18 16:53:44.947 GMT <RMI TCP Connection(1)-172.17.0.8>
> tid=0x22] Initializing region _monitoringRegion_172.17.0.8<v-1>0 [vm2] [info
> 2019/06/18 16:53:44.959 GMT <RMI TCP Connection(1)-172.17.0.8> tid=0x22]
> Initialization of region _monitoringRegion_172.17.0.8<v-1>0 completed [vm2]
> [info 2019/06/18 16:53:45.894 GMT <RMI TCP Connection(1)-172.17.0.8>
> tid=0x22] Initialized cache service
> org.apache.geode.internal.cache.MockCacheServiceImpl [vm2] [info 2019/06/18
> 16:53:45.897 GMT <RMI TCP Connection(1)-172.17.0.8> tid=0x22] Loading
> previously deployed jars [vm2] [warn 2019/06/18 16:53:45.988 GMT
> <poolTimer-testPool-2> tid=0x3d] Could not connect to: c031e683c5e7:27401
> [vm2] java.net.ConnectException: Connection refused (Connection refused)
> [vm2] at java.base/java.net.PlainSocketImpl.socketConnect(Native Method)
> [vm2] at
> java.base/java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:399)
> [vm2] at
> java.base/java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:242)
> [vm2] at
> java.base/java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:224)
> [vm2] at
> java.base/java.net.SocksSocketImpl.connect(SocksSocketImpl.java:403) [vm2] at
> java.base/java.net.Socket.connect(Socket.java:591) [vm2] at
> org.apache.geode.internal.net.SocketCreator.connect(SocketCreator.java:898)
> [vm2] at
> org.apache.geode.internal.net.SocketCreator.connect(SocketCreator.java:839)
> [vm2] at
> org.apache.geode.internal.net.SocketCreator.connectForClient(SocketCreator.java:811)
> [vm2] at
> org.apache.geode.cache.client.internal.ConnectionImpl.connect(ConnectionImpl.java:106)
> [vm2] at
> org.apache.geode.cache.client.internal.ConnectionConnector.connectClientToServer(ConnectionConnector.java:75)
> [vm2] at
> org.apache.geode.cache.client.internal.ConnectionFactoryImpl.createClientToServerConnection(ConnectionFactoryImpl.java:111)
> [vm2] at
> org.apache.geode.cache.client.internal.ConnectionFactoryImpl.createClientToServerConnection(ConnectionFactoryImpl.java:222)
> [vm2] at
> org.apache.geode.cache.client.internal.pooling.ConnectionManagerImpl.createPooledConnection(ConnectionManagerImpl.java:192)
> [vm2] at
> org.apache.geode.cache.client.internal.pooling.ConnectionManagerImpl.createPooledConnection(ConnectionManagerImpl.java:186)
> [vm2] at
> org.apache.geode.cache.client.internal.pooling.ConnectionManagerImpl.prefillConnection(ConnectionManagerImpl.java:583)
> [vm2] at
> org.apache.geode.cache.client.internal.pooling.ConnectionManagerImpl.prefill(ConnectionManagerImpl.java:551)
> [vm2] at
> org.apache.geode.cache.client.internal.pooling.ConnectionManagerImpl.access$200(ConnectionManagerImpl.java:70)
> [vm2] at
> org.apache.geode.cache.client.internal.pooling.ConnectionManagerImpl$PrefillConnectionsTask.run2(ConnectionManagerImpl.java:663)
> [vm2] at
> org.apache.geode.cache.client.internal.PoolImpl$PoolTask.run(PoolImpl.java:1303)
> [vm2] at
> java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
> [vm2] at
> java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
> [vm2] at java.base/java.lang.Thread.run(Thread.java:834) [vm2] [warn
> 2019/06/18 16:53:45.988 GMT <RMI TCP Connection(1)-172.17.0.8> tid=0x22]
> Could not connect to: c031e683c5e7:27401 [vm2] java.net.ConnectException:
> Connection refused (Connection refused) [vm2] at
> java.base/java.net.PlainSocketImpl.socketConnect(Native Method) [vm2] at
> java.base/java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:399)
> [vm2] at
> java.base/java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:242)
> [vm2] at
> java.base/java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:224)
> [vm2] at
> java.base/java.net.SocksSocketImpl.connect(SocksSocketImpl.java:403) [vm2] at
> java.base/java.net.Socket.connect(Socket.java:591) [vm2] at
> org.apache.geode.internal.net.SocketCreator.connect(SocketCreator.java:898)
> [vm2] at
> org.apache.geode.internal.net.SocketCreator.connect(SocketCreator.java:839)
> [vm2] at
> org.apache.geode.internal.net.SocketCreator.connectForClient(SocketCreator.java:811)
> [vm2] at
> org.apache.geode.cache.client.internal.ConnectionImpl.connect(ConnectionImpl.java:106)
> [vm2] at
> org.apache.geode.cache.client.internal.ConnectionConnector.connectClientToServer(ConnectionConnector.java:75)
> [vm2] at
> org.apache.geode.cache.client.internal.ConnectionFactoryImpl.createClientToServerConnection(ConnectionFactoryImpl.java:111)
> [vm2] at
> org.apache.geode.cache.client.internal.QueueManagerImpl.initializeConnections(QueueManagerImpl.java:453)
> [vm2] at
> org.apache.geode.cache.client.internal.QueueManagerImpl.start(QueueManagerImpl.java:292)
> [vm2] at
> org.apache.geode.cache.client.internal.PoolImpl.start(PoolImpl.java:338)
> [vm2] at
> org.apache.geode.cache.client.internal.PoolImpl.finishCreate(PoolImpl.java:175)
> [vm2] at
> org.apache.geode.cache.client.internal.PoolImpl.create(PoolImpl.java:161)
> [vm2] at
> org.apache.geode.internal.cache.PoolFactoryImpl.create(PoolFactoryImpl.java:374)
> [vm2] at
> org.apache.geode.cache30.ClientServerTestCase.configureConnectionPoolWithNameAndFactory(ClientServerTestCase.java:223)
> [vm2] at
> org.apache.geode.cache30.ClientServerTestCase.configureConnectionPoolWithName(ClientServerTestCase.java:165)
> [vm2] at
> org.apache.geode.cache30.ClientServerTestCase.configureConnectionPool(ClientServerTestCase.java:240)
> [vm2] at
> org.apache.geode.internal.cache.ha.HAGIIDUnitTest.createClientCache(HAGIIDUnitTest.java:143)
> [vm2] at
> org.apache.geode.internal.cache.ha.HAGIIDUnitTest.lambda$postSetUp$ff2366df$1(HAGIIDUnitTest.java:106)
> [vm2] at
> java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native
> Method) [vm2] at
> java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
> [vm2] at
> java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
> [vm2] at java.base/java.lang.reflect.Method.invoke(Method.java:566) [vm2] at
> org.apache.geode.test.dunit.internal.MethodInvoker.executeObject(MethodInvoker.java:123)
> [vm2] at
> org.apache.geode.test.dunit.internal.RemoteDUnitVM.executeMethodOnObject(RemoteDUnitVM.java:69)
> [vm2] at
> java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native
> Method) [vm2] at
> java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
> [vm2] at
> java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
> [vm2] at java.base/java.lang.reflect.Method.invoke(Method.java:566) [vm2] at
> java.rmi/sun.rmi.server.UnicastServerRef.dispatch(UnicastServerRef.java:359)
> [vm2] at java.rmi/sun.rmi.transport.Transport$1.run(Transport.java:200) [vm2]
> at java.rmi/sun.rmi.transport.Transport$1.run(Transport.java:197) [vm2] at
> java.base/java.security.AccessController.doPrivileged(Native Method) [vm2] at
> java.rmi/sun.rmi.transport.Transport.serviceCall(Transport.java:196) [vm2] at
> java.rmi/sun.rmi.transport.tcp.TCPTransport.handleMessages(TCPTransport.java:562)
> [vm2] at
> java.rmi/sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.run0(TCPTransport.java:796)
> [vm2] at
> java.rmi/sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.lambda$run$0(TCPTransport.java:677)
> [vm2] at java.base/java.security.AccessController.doPrivileged(Native
> Method) [vm2] at
> java.rmi/sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.run(TCPTransport.java:676)
> [vm2] at
> java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
> [vm2] at
> java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
> [vm2] at java.base/java.lang.Thread.run(Thread.java:834) [vm2] [info
> 2019/06/18 16:53:45.995 GMT <RMI TCP Connection(1)-172.17.0.8> tid=0x22]
> Updating membership port. Port changed from 0 to 35402. ID is now
> c031e683c5e7(157:loner):0:8937826b [vm2] [warn 2019/06/18 16:53:46.144 GMT
> <poolTimer-testPool-2> tid=0x3d] Could not connect to: c031e683c5e7:27401
> [vm2] java.net.ConnectException: Connection refused (Connection refused)
> [vm2] at java.base/java.net.PlainSocketImpl.socketConnect(Native Method)
> [vm2] at
> java.base/java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:399)
> [vm2] at
> java.base/java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:242)
> [vm2] at
> java.base/java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:224)
> [vm2] at
> java.base/java.net.SocksSocketImpl.connect(SocksSocketImpl.java:403) [vm2] at
> java.base/java.net.Socket.connect(Socket.java:591) [vm0] [info 2019/06/18
> 16:53:46.144 GMT <Client Queue Initialization Thread 1> tid=0x71] :Cache
> server: Initializing primary server-to-client communication socket:
> Socket[addr=/172.17.0.8,port=35408,localport=28355] [vm2] at
> org.apache.geode.internal.net.SocketCreator.connect(SocketCreator.java:898)
> [vm2] at
> org.apache.geode.internal.net.SocketCreator.connect(SocketCreator.java:839)
> [vm2] at
> org.apache.geode.internal.net.SocketCreator.connectForClient(SocketCreator.java:811)
> [vm2] at
> org.apache.geode.cache.client.internal.ConnectionImpl.connect(ConnectionImpl.java:106)
> [vm2] at
> org.apache.geode.cache.client.internal.ConnectionConnector.connectClientToServer(ConnectionConnector.java:75)
> [vm2] at
> org.apache.geode.cache.client.internal.ConnectionFactoryImpl.createClientToServerConnection(ConnectionFactoryImpl.java:111)
> [vm2] at
> org.apache.geode.cache.client.internal.ConnectionFactoryImpl.createClientToServerConnection(ConnectionFactoryImpl.java:222)
> [vm2] at
> org.apache.geode.cache.client.internal.pooling.ConnectionManagerImpl.createPooledConnection(ConnectionManagerImpl.java:192)
> [vm2] at
> org.apache.geode.cache.client.internal.pooling.ConnectionManagerImpl.createPooledConnection(ConnectionManagerImpl.java:186)
> [vm2] at
> org.apache.geode.cache.client.internal.pooling.ConnectionManagerImpl.prefillConnection(ConnectionManagerImpl.java:583)
> [vm2] at
> org.apache.geode.cache.client.internal.pooling.ConnectionManagerImpl.prefill(ConnectionManagerImpl.java:551)
> [vm2] at
> org.apache.geode.cache.client.internal.pooling.ConnectionManagerImpl.access$200(ConnectionManagerImpl.java:70)
> [vm2] at
> org.apache.geode.cache.client.internal.pooling.ConnectionManagerImpl$PrefillConnectionsTask.run2(ConnectionManagerImpl.java:663)
> [vm2] at
> org.apache.geode.cache.client.internal.PoolImpl$PoolTask.run(PoolImpl.java:1303)
> [vm2] at
> java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
> [vm2] at
> java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
> [vm2] at java.base/java.lang.Thread.run(Thread.java:834) [vm2] [warn
> 2019/06/18 16:53:46.150 GMT <poolTimer-testPool-2> tid=0x3d] Could not
> connect to: c031e683c5e7:27401 [vm2] java.net.ConnectException: Connection
> refused (Connection refused) [vm2] at
> java.base/java.net.PlainSocketImpl.socketConnect(Native Method) [vm2] at
> java.base/java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:399)
> [vm2] at
> java.base/java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:242)
> [vm2] at
> java.base/java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:224)
> [vm2] at
> java.base/java.net.SocksSocketImpl.connect(SocksSocketImpl.java:403) [vm2] at
> java.base/java.net.Socket.connect(Socket.java:591) [vm2] at
> org.apache.geode.internal.net.SocketCreator.connect(SocketCreator.java:898)
> [vm2] at
> org.apache.geode.internal.net.SocketCreator.connect(SocketCreator.java:839)
> [vm2] at
> org.apache.geode.internal.net.SocketCreator.connectForClient(SocketCreator.java:811)
> [vm2] at
> org.apache.geode.cache.client.internal.ConnectionImpl.connect(ConnectionImpl.java:106)
> [vm2] at
> org.apache.geode.cache.client.internal.ConnectionConnector.connectClientToServer(ConnectionConnector.java:75)
> [vm2] at
> org.apache.geode.cache.client.internal.ConnectionFactoryImpl.createClientToServerConnection(ConnectionFactoryImpl.java:111)
> [vm2] at
> org.apache.geode.cache.client.internal.ConnectionFactoryImpl.createClientToServerConnection(ConnectionFactoryImpl.java:222)
> [vm2] at
> org.apache.geode.cache.client.internal.pooling.ConnectionManagerImpl.createPooledConnection(ConnectionManagerImpl.java:192)
> [vm2] at
> org.apache.geode.cache.client.internal.pooling.ConnectionManagerImpl.createPooledConnection(ConnectionManagerImpl.java:186)
> [vm2] at
> org.apache.geode.cache.client.internal.pooling.ConnectionManagerImpl.prefillConnection(ConnectionManagerImpl.java:583)
> [vm2] at
> org.apache.geode.cache.client.internal.pooling.ConnectionManagerImpl.prefill(ConnectionManagerImpl.java:551)
> [vm2] at
> org.apache.geode.cache.client.internal.pooling.ConnectionManagerImpl.access$200(ConnectionManagerImpl.java:70)
> [vm2] at
> org.apache.geode.cache.client.internal.pooling.ConnectionManagerImpl$PrefillConnectionsTask.run2(ConnectionManagerImpl.java:663)
> [vm2] at
> org.apache.geode.cache.client.internal.PoolImpl$PoolTask.run(PoolImpl.java:1303)
> [vm2] at
> java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
> [vm2] at
> java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
> [vm2] at java.base/java.lang.Thread.run(Thread.java:834) [vm0] [info
> 2019/06/18 16:53:46.165 GMT <Client Queue Initialization Thread 1> tid=0x71]
> Initializing region
> _gfe_non_durable_client_with_id_172.17.0.8(157:loner):35402:8937826b_1_queue
> [vm0] [info 2019/06/18 16:53:46.169 GMT <Client Queue Initialization Thread
> 1> tid=0x71] Initialization of region
> _gfe_non_durable_client_with_id_172.17.0.8(157:loner):35402:8937826b_1_queue
> completed [vm2] [info 2019/06/18 16:53:46.182 GMT <RMI TCP
> Connection(1)-172.17.0.8> tid=0x22] Pool testPool started with
> multiuser-authentication=false [vm2] [warn 2019/06/18 16:53:46.185 GMT
> <poolTimer-testPool-2> tid=0x3d] Could not connect to: c031e683c5e7:27401
> [vm2] java.net.ConnectException: Connection refused (Connection refused)
> [vm2] at java.base/java.net.PlainSocketImpl.socketConnect(Native Method)
> [vm2] at
> java.base/java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:399)
> [vm2] at
> java.base/java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:242)
> [vm2] at
> java.base/java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:224)
> [vm2] at
> java.base/java.net.SocksSocketImpl.connect(SocksSocketImpl.java:403) [vm2] at
> java.base/java.net.Socket.connect(Socket.java:591) [vm2] at
> org.apache.geode.internal.net.SocketCreator.connect(SocketCreator.java:898)
> [vm2] at
> org.apache.geode.internal.net.SocketCreator.connect(SocketCreator.java:839)
> [vm2] at
> org.apache.geode.internal.net.SocketCreator.connectForClient(SocketCreator.java:811)
> [vm2] at
> org.apache.geode.cache.client.internal.ConnectionImpl.connect(ConnectionImpl.java:106)
> [vm2] at
> org.apache.geode.cache.client.internal.ConnectionConnector.connectClientToServer(ConnectionConnector.java:75)
> [vm2] at
> org.apache.geode.cache.client.internal.ConnectionFactoryImpl.createClientToServerConnection(ConnectionFactoryImpl.java:111)
> [vm2] at
> org.apache.geode.cache.client.internal.ConnectionFactoryImpl.createClientToServerConnection(ConnectionFactoryImpl.java:222)
> [vm2] at
> org.apache.geode.cache.client.internal.pooling.ConnectionManagerImpl.createPooledConnection(ConnectionManagerImpl.java:192)
> [vm2] at
> org.apache.geode.cache.client.internal.pooling.ConnectionManagerImpl.createPooledConnection(ConnectionManagerImpl.java:186)
> [vm2] at
> org.apache.geode.cache.client.internal.pooling.ConnectionManagerImpl.prefillConnection(ConnectionManagerImpl.java:583)
> [vm2] at
> org.apache.geode.cache.client.internal.pooling.ConnectionManagerImpl.prefill(ConnectionManagerImpl.java:551)
> [vm2] at
> org.apache.geode.cache.client.internal.pooling.ConnectionManagerImpl.access$200(ConnectionManagerImpl.java:70)
> [vm2] at
> org.apache.geode.cache.client.internal.pooling.ConnectionManagerImpl$PrefillConnectionsTask.run2(ConnectionManagerImpl.java:663)
> [vm2] at
> org.apache.geode.cache.client.internal.PoolImpl$PoolTask.run(PoolImpl.java:1303)
> [vm2] at
> java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
> [vm2] at
> java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
> [vm2] at java.base/java.lang.Thread.run(Thread.java:834) [vm0] [info
> 2019/06/18 16:53:46.186 GMT <Client Queue Initialization Thread 1> tid=0x71]
> Entry expiry tasks disabled because the queue became primary. Old
> messageTimeToLive was: 180 [vm2] [info 2019/06/18 16:53:46.187 GMT <Cache
> Client Updater Thread on c031e683c5e7(149)<v2>:41001 port 28355> tid=0x40]
> Cache Client Updater Thread on c031e683c5e7(149)<v2>:41001 port 28355
> (c031e683c5e7:28355) : ready to process messages. [vm2] [info 2019/06/18
> 16:53:46.222 GMT <RMI TCP Connection(1)-172.17.0.8> tid=0x22] Got result:
> null [vm2] from
> org.apache.geode.internal.cache.ha.HAGIIDUnitTest$$Lambda$136/0x00000008401a9840.run
> with 0 args on object:
> org.apache.geode.internal.cache.ha.HAGIIDUnitTest$$Lambda$136/0x00000008401a9840@3a9287c8
> (took 2494 ms) [vm2] [info 2019/06/18 16:53:46.229 GMT <RMI TCP
> Connection(1)-172.17.0.8> tid=0x22] Received method:
> org.apache.geode.internal.cache.ha.HAGIIDUnitTest$$Lambda$264/0x000000084045b440.run
> with 0 args on object:
> org.apache.geode.internal.cache.ha.HAGIIDUnitTest$$Lambda$264/0x000000084045b440@4a464fdd
> [vm2] [info 2019/06/18 16:53:46.297 GMT <RMI TCP Connection(1)-172.17.0.8>
> tid=0x22] Got result: null [vm2] from
> org.apache.geode.internal.cache.ha.HAGIIDUnitTest$$Lambda$264/0x000000084045b440.run
> with 0 args on object:
> org.apache.geode.internal.cache.ha.HAGIIDUnitTest$$Lambda$264/0x000000084045b440@4a464fdd
> (took 67 ms) [vm2] [info 2019/06/18 16:53:46.302 GMT <RMI TCP
> Connection(1)-172.17.0.8> tid=0x22] Received method:
> org.apache.geode.internal.cache.ha.HAGIIDUnitTest$$Lambda$273/0x000000084045d840.run
> with 0 args on object:
> org.apache.geode.internal.cache.ha.HAGIIDUnitTest$$Lambda$273/0x000000084045d840@9985f2c
> [vm2] [info 2019/06/18 16:53:46.332 GMT <RMI TCP Connection(1)-172.17.0.8>
> tid=0x22] Got result: null [vm2] from
> org.apache.geode.internal.cache.ha.HAGIIDUnitTest$$Lambda$273/0x000000084045d840.run
> with 0 args on object:
> org.apache.geode.internal.cache.ha.HAGIIDUnitTest$$Lambda$273/0x000000084045d840@9985f2c
> (took 29 ms) [vm0] [info 2019/06/18 16:53:46.336 GMT <RMI TCP
> Connection(1)-172.17.0.8> tid=0x22] Received method:
> org.apache.geode.internal.cache.ha.HAGIIDUnitTest$$Lambda$328/0x00000008404ae040.run
> with 0 args on object:
> org.apache.geode.internal.cache.ha.HAGIIDUnitTest$$Lambda$328/0x00000008404ae040@226c354c
> [vm0] [info 2019/06/18 16:53:46.352 GMT <RMI TCP Connection(1)-172.17.0.8>
> tid=0x22] Got result: null [vm0] from
> org.apache.geode.internal.cache.ha.HAGIIDUnitTest$$Lambda$328/0x00000008404ae040.run
> with 0 args on object:
> org.apache.geode.internal.cache.ha.HAGIIDUnitTest$$Lambda$328/0x00000008404ae040@226c354c
> (took 14 ms) [vm0] [info 2019/06/18 16:53:46.356 GMT <RMI TCP
> Connection(1)-172.17.0.8> tid=0x22] Received method:
> org.apache.geode.internal.cache.ha.HAGIIDUnitTest$$Lambda$331/0x00000008404ad040.run
> with 0 args on object:
> org.apache.geode.internal.cache.ha.HAGIIDUnitTest$$Lambda$331/0x00000008404ad040@6de2b314
> [vm0] [info 2019/06/18 16:53:46.359 GMT <RMI TCP Connection(1)-172.17.0.8>
> tid=0x22] Got result: null [vm0] from
> org.apache.geode.internal.cache.ha.HAGIIDUnitTest$$Lambda$331/0x00000008404ad040.run
> with 0 args on object:
> org.apache.geode.internal.cache.ha.HAGIIDUnitTest$$Lambda$331/0x00000008404ad040@6de2b314
> (took 1 ms) [vm2] [info 2019/06/18 16:53:46.363 GMT <RMI TCP
> Connection(1)-172.17.0.8> tid=0x22] Received method:
> org.apache.geode.internal.cache.ha.HAGIIDUnitTest$$Lambda$274/0x000000084045dc40.run
> with 0 args on object:
> org.apache.geode.internal.cache.ha.HAGIIDUnitTest$$Lambda$274/0x000000084045dc40@39ac8e0d
> [vm2] [info 2019/06/18 16:53:46.595 GMT <RMI TCP Connection(1)-172.17.0.8>
> tid=0x22] Got result: null [vm2] from
> org.apache.geode.internal.cache.ha.HAGIIDUnitTest$$Lambda$274/0x000000084045dc40.run
> with 0 args on object:
> org.apache.geode.internal.cache.ha.HAGIIDUnitTest$$Lambda$274/0x000000084045dc40@39ac8e0d
> (took 230 ms) [vm1] [info 2019/06/18 16:53:46.599 GMT <RMI TCP
> Connection(1)-172.17.0.8> tid=0x22] Received method:
> org.apache.geode.internal.cache.ha.HAGIIDUnitTest.createServer2Cache with 1
> args [vm1] distributed system properties: {locators=localhost[35189],
> validate-serializable-objects=true, disable-auto-reconnect=true,
> enable-cluster-configuration=false, use-cluster-configuration=false,
> log-level=info, mcast-port=0} [vm2] [warn 2019/06/18 16:53:47.181 GMT
> <queueTimer-testPool1> tid=0x42] Could not connect to: c031e683c5e7:27401
> [vm2] java.net.ConnectException: Connection refused (Connection refused)
> [vm2] at java.base/java.net.PlainSocketImpl.socketConnect(Native Method)
> [vm2] at
> java.base/java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:399)
> [vm2] at
> java.base/java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:242)
> [vm2] at
> java.base/java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:224)
> [vm2] at
> java.base/java.net.SocksSocketImpl.connect(SocksSocketImpl.java:403) [vm2] at
> java.base/java.net.Socket.connect(Socket.java:591) [vm2] at
> org.apache.geode.internal.net.SocketCreator.connect(SocketCreator.java:898)
> [vm2] at
> org.apache.geode.internal.net.SocketCreator.connect(SocketCreator.java:839)
> [vm2] at
> org.apache.geode.internal.net.SocketCreator.connectForClient(SocketCreator.java:811)
> [vm2] at
> org.apache.geode.cache.client.internal.ConnectionImpl.connect(ConnectionImpl.java:106)
> [vm2] at
> org.apache.geode.cache.client.internal.ConnectionConnector.connectClientToServer(ConnectionConnector.java:75)
> [vm2] at
> org.apache.geode.cache.client.internal.ConnectionFactoryImpl.createClientToServerConnection(ConnectionFactoryImpl.java:111)
> [vm2] at
> org.apache.geode.cache.client.internal.QueueManagerImpl.recoverRedundancy(QueueManagerImpl.java:660)
> [vm2] at
> org.apache.geode.cache.client.internal.QueueManagerImpl.access$700(QueueManagerImpl.java:76)
> [vm2] at
> org.apache.geode.cache.client.internal.QueueManagerImpl$RedundancySatisfierTask.run2(QueueManagerImpl.java:1444)
> [vm2] at
> org.apache.geode.cache.client.internal.PoolImpl$PoolTask.run(PoolImpl.java:1303)
> [vm2] at
> java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515)
> [vm2] at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
> [vm2] at
> java.base/java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:304)
> [vm2] at
> java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
> [vm2] at
> java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
> [vm2] at java.base/java.lang.Thread.run(Thread.java:834)
>
>
>
>
>
--
This message was sent by Atlassian JIRA
(v7.6.3#76005)
