EAP 7 DNS_PING tracing logs in OCP 4

Solution Verified - Updated

Environment

  • Red Hat JBoss Enterprise Application Platform (EAP)
    • 7.x
  • Red hat OpenShift Container Platform (OCP)
    • 4.x

Issue

  • How to enable DNS_PING tracing logs in EAP 7 in OCP 4?
  • How to interpret DNS_PING's tracing logs?

Resolution

The solution EAP 7 image clustering in OCP 4 bring several aspects of clustering in EAP 7.

For enabling DNS_PING tracing logging

What are the options to use a custom runtime JBoss EAP 7 configurations in OCP.

Set org.jgroups.protocols.dns with level TRACE so the complete list of operations will appear. DNS_PING trace logs, will not bring jgroups' protocols output. The recommendation is to org.jgroups.protocols.DNS_PING and org.jgroups:TRACE.
Example:

# set LOGGER_CATEGORIES for org.jgroups.protocols.DNS_PING:TRACE org.jgroups:TRACE
$ oc set env dc/eap-app LOGGER_CATEGORIES=org.jgroups.protocols.DNS_PING:TRACE,org.jgroups:TRACE

^ Above will be an update on the deployment(config) and terminate the pod and recreate new pods.
Note that setting org.jgroups.protocols.DNS_PING with level TRACE won't show the GET_MBRS_REQ or GET_MBRS_RSP messages.

Process

  1. dns.DNS_PING: set the discovery protocol
  2. Logging: Category org.jgroups.protocols.dns with level TRACE
  3. Initializing DNS Context with factory: com.sun.jndi.dns.DnsContextFactory and url
  4. Resolving DNS query: eap-app-ping of a type: A <---------------- the query is sent to eap-app-ping service
  5. Collect the entries: eap-app-3-gkcjb: entries collected from DNS (in 2 ms): [10.128.2.18:0, 10.128.2.17:0, 10.129.2.20:0, 10.131.0.34:0, 10.131.0.33:0] <-------------- collect
  6. Send discovery request: Sending discovery request to 10.128.2.17:7600
  7. Received: GET_MBRS_REQ and GET_MBRS_RSP for each member
  8. Node will get GET_MBRS_RSP from other nodes, which will join the cluster: ISPN100000: Node eap-app-3-jvn9z joined the cluster
  9. The reply will be name address and coordinator: sending response eap-app-3-gkcjb, name=eap-app-3-gkcjb, addr=10.128.2.18:7600, coord
  10. Rebalance: ISPN100002: Starting rebalance with members [eap-app-3-gkcjb, eap-app-3-jvn9z], phase READ_OLD_WRITE_ALL, topology id 2

GET_MBRS_REQ VS GET_MBRS_RSP

For the starting node the outgoing GET_MBRS_REQ (->) and incoming GET_MBRS_RSP (<-) are the important ones.

Interpreting

  1. See INFO Configuring JGroups discovery protocol to dns.DNS_PING: mean discovery protocol == dns_ping
2022-12-22 18:08:10 Launching EAP Server
INFO Configuring JGroups cluster traffic encryption protocol to SYM_ENCRYPT.
WARN Detected missing JGroups encryption configuration, the communication within the cluster WILL NOT be encrypted.
INFO Configuring JGroups discovery protocol to dns.DNS_PING
INFO Using PicketBox SSL configuration.
INFO Found env LOGGER_CATEGORIES, configuring....
INFO Configuring logger category org.jgroups.protocols.dns with level TRACE
INFO Access log is disabled, ignoring configuration.
WARN Configuration of an embedded messaging broker within the appserver is enabled but is not recommended. Support for such a configuration will be removed in a future release.
WARN If you are not configuring messaging destinations, to disable configuring an embedded messaging broker set the DISABLE_EMBEDDED_JMS_BROKER environment variable to true.
INFO Server started in admin mode, CLI script executed during server boot.
INFO Running jboss-eap-7/eap74-openjdk8-runtime-openshift-rhel7 image, version 7.4.8
...
  JAVA_OPTS: -javaagent:"/opt/eap/jboss-modules.jar"  -server -verbose:gc -Xloggc:"/opt/eap/standalone/log/gc.log" -XX:+PrintGCDetails -XX:+PrintGCDateStamps -XX:+UseGCLogFileRotation -XX:NumberOfGCLogFiles=5 -XX:GCLogFileSize=3M -XX:-TraceClassUnloading -Xms128m -Xmx512m -XX:MetaspaceSize=96m -Djava.net.preferIPv4Stack=true -Djboss.modules.system.pkgs=jdk.nashorn.api,com.sun.crypto.provider -Djava.awt.headless=true -XX:+UseParallelOldGC -XX:MinHeapFreeRatio=10 -XX:MaxHeapFreeRatio=20 -XX:GCTimeRatio=4 -XX:AdaptiveSizePolicyWeight=90 -XX:+ExitOnOutOfMemoryError -Djava.security.egd=file:/dev/./urandom  
....
18:08:12,433 INFO  [org.jboss.as] (MSC service thread 1-1) WFLYSRV0049: JBoss EAP 7.4.8.GA (WildFly Core 15.0.19.Final-redhat-00001) starting
	[Standalone] = 
	...
	jboss.bind.address = 10.128.2.18
	jboss.bind.address.management = 0.0.0.0
	jboss.bind.address.private = 10.128.2.18
	jboss.home.dir = /opt/eap
	jboss.host.name = eap-app-3-gkcjb
	jboss.messaging.cluster.password = <redacted>
	jboss.messaging.host = 10.128.2.18
	jboss.modules.dir = /opt/eap/modules
	jboss.modules.system.pkgs = jdk.nashorn.api,com.sun.crypto.provider
	jboss.node.name = eap-app-3-gkcjb
	jboss.qualified.host.name = eap-app-3-gkcjb
	jboss.server.base.dir = /opt/eap/standalone
	jboss.server.config.dir = /opt/eap/standalone/configuration
	jboss.server.data.dir = /opt/eap/standalone/data
	jboss.server.deploy.dir = /opt/eap/standalone/data/content
	jboss.server.log.dir = /opt/eap/standalone/log
	jboss.server.name = eap-app-3-gkcjb
	jboss.server.persist.config = true
	sun.java.command = /opt/eap/jboss-modules.jar -javaagent:/usr/share/java/jolokia-jvm-agent/jolokia-jvm.jar=config=/opt/jboss/container/jolokia/etc/jolokia.properties -mp /opt/eap/modules org.jboss.as.standalone -Djboss.home.dir=/opt/eap -Djboss.server.base.dir=/opt/eap/standalone -c standalone-openshift.xml -bmanagement 0.0.0.0 -Djboss.server.data.dir=/opt/eap/standalone/data -Dwildfly.statistics-enabled=true -b 10.128.2.18 -bprivate 10.128.2.18 -Djboss.node.name=eap-app-3-gkcjb -Djboss.messaging.host=10.128.2.18 -Djboss.messaging.cluster.password=<redacted> --start-mode=admin-only -Dorg.wildfly.internal.cli.boot.hook.script=/tmp/cli-script-1671732490.cli -Dorg.wildfly.internal.cli.boot.hook.marker.dir=/tmp/cli-boot-reload-marker-1671732491 -Dorg.wildfly.internal.cli.boot.hook.script.properties=/tmp/cli-script-property-1671732490.cli -Dorg.wildfly.internal.cli.boot.hook.script.output.file=/tmp/cli-script-output-1671732490.cli -Dorg.wildfly.internal.cli.boot.hook.script.error.file=/tmp/cli-script-error-1671732490.cli -Dorg.wildfly.internal.cli.boot.hook.script.warn.file=/tmp/cli-warning-1671732490.log
	sun.java.launcher = SUN_STANDARD
	sun.jnu.encoding = ANSI_X3.4-1968
	sun.management.compiler = HotSpot 64-Bit Tiered Compilers
	sun.os.patch.level = unknown
	user.country = US
	user.dir = /home/jboss
	user.home = /home/jboss
	user.language = en
	user.name = 1000670000
	user.timezone = UTC
	wildfly.statistics-enabled = true
18:08:12,435 DEBUG [org.jboss.as.config] (MSC service thread 1-1) VM Arguments: -D[Standalone] -javaagent:/opt/eap/jboss-modules.jar -verbose:gc -Xloggc:/opt/eap/standalone/log/gc.log -XX:+PrintGCDetails -XX:+PrintGCDateStamps -XX:+UseGCLogFileRotation -XX:NumberOfGCLogFiles=5 -XX:GCLogFileSize=3M -XX:-TraceClassUnloading -Xms128m -Xmx512m -XX:MetaspaceSize=96m -Djava.net.preferIPv4Stack=true -Djboss.modules.system.pkgs=jdk.nashorn.api,com.sun.crypto.provider -Djava.awt.headless=true -XX:+UseParallelOldGC -XX:MinHeapFreeRatio=10 -XX:MaxHeapFreeRatio=20 -XX:GCTimeRatio=4 -XX:AdaptiveSizePolicyWeight=90 -XX:+ExitOnOutOfMemoryError -Djava.security.egd=file:/dev/./urandom -Dorg.jboss.boot.log.file=/opt/eap/standalone/log/server.log -Dlogging.configuration=file:/opt/eap/standalone/configuration/logging.properties 
...
18:08:13,977 INFO  [org.jboss.as.cli] (Controller Boot Thread) WFLYCLI0001: Processing CLI script /tmp/cli-script-1671732490.cli
18:08:14,434 INFO  [org.jboss.as.controller.management-deprecated] (CLI command executor) WFLYCTL0395: Operation add against the resource at address /subsystem=jgroups/stack=tcp/protocol=dns.DNS_PING/property=dns_query is deprecated, and it might be removed in future version. See the the output of the read-operation-description operation to learn more about the deprecation.
18:08:14,434 INFO  [org.jboss.as.controller.management-deprecated] (CLI command executor) WFLYCTL0395: Operation add against the resource at address /subsystem=jgroups/stack=tcp/protocol=dns.DNS_PING/property=async_discovery_use_separate_thread_per_request is deprecated, and it might be removed in future version. See the the output of the read-operation-description operation to learn more about the deprecation.
18:08:14,452 INFO  [org.jboss.as.controller.management-deprecated] (CLI command executor) WFLYCTL0395: Operation add against the resource at address /subsystem=jgroups/stack=udp/protocol=dns.DNS_PING/property=dns_query is deprecated, and it might be removed in future version. See the the output of the read-operation-description operation to learn more about the deprecation.
18:08:14,453 INFO  [org.jboss.as.controller.management-deprecated] (CLI command executor) WFLYCTL0395: Operation add against the resource at address /subsystem=jgroups/stack=udp/protocol=dns.DNS_PING/property=async_discovery_use_separate_thread_per_request is deprecated, and it might be removed in future version. See the the output of the read-operation-description operation to learn more about the deprecation.
...
18:08:14,511 DEBUG [org.jboss.as.config] (MSC service thread 1-2) Configured system properties:
	[Standalone] = 
	awt.toolkit = sun.awt.X11.XToolkit
	file.encoding = ANSI_X3.4-1968
	file.encoding.pkg = sun.io
	file.separator = /
	java.awt.graphicsenv = sun.awt.X11GraphicsEnvironment
	java.awt.headless = true
	java.awt.printerjob = sun.print.PSPrinterJob
	java.class.path = /opt/eap/jboss-modules.jar:/opt/eap/jboss-modules.jar
	java.class.version = 52.0
	java.endorsed.dirs = /usr/lib/jvm/java-1.8.0-openjdk-1.8.0.352.b08-2.el7_9.x86_64/jre/lib/endorsed
	java.ext.dirs = /usr/lib/jvm/java-1.8.0-openjdk-1.8.0.352.b08-2.el7_9.x86_64/jre/lib/ext:/usr/java/packages/lib/ext
	java.home = /usr/lib/jvm/java-1.8.0-openjdk-1.8.0.352.b08-2.el7_9.x86_64/jre
	java.io.tmpdir = /tmp
	java.library.path = /usr/java/packages/lib/amd64:/usr/lib64:/lib64:/lib:/usr/lib
	java.net.preferIPv4Stack = true
	java.runtime.name = OpenJDK Runtime Environment
	java.runtime.version = 1.8.0_352-b08
	java.security.egd = file:/dev/./urandom
	java.specification.maintenance.version = 4
	java.specification.name = Java Platform API Specification
	java.specification.vendor = Oracle Corporation
	java.specification.version = 1.8
	java.util.logging.manager = org.jboss.logmanager.LogManager
	java.vendor = Red Hat, Inc.
	java.vendor.url = https://www.redhat.com/
	java.vendor.url.bug = https://bugzilla.redhat.com/enter_bug.cgi?product=Red%20Hat%20Enterprise%20Linux%207&component=java-1.8.0-openjdk
	java.version = 1.8.0_352
	java.vm.info = mixed mode
	java.vm.name = OpenJDK 64-Bit Server VM
	java.vm.specification.name = Java Virtual Machine Specification
	java.vm.specification.vendor = Oracle Corporation
	java.vm.specification.version = 1.8
	java.vm.vendor = Red Hat, Inc.
	java.vm.version = 25.352-b08
	javax.management.builder.initial = org.jboss.as.jmx.PluggableMBeanServerBuilder
	jboss.bind.address = 10.128.2.18
	jboss.bind.address.management = 0.0.0.0
	jboss.bind.address.private = 10.128.2.18
	jboss.home.dir = /opt/eap
	jboss.host.name = eap-app-3-gkcjb
	jboss.messaging.cluster.password = <redacted>
	jboss.messaging.host = 10.128.2.18
	jboss.modules.dir = /opt/eap/modules
	jboss.modules.system.pkgs = jdk.nashorn.api,com.sun.crypto.provider
	jboss.node.name = eap-app-3-gkcjb
	jboss.qualified.host.name = eap-app-3-gkcjb
	jboss.server.base.dir = /opt/eap/standalone
	jboss.server.config.dir = /opt/eap/standalone/configuration
	jboss.server.data.dir = /opt/eap/standalone/data
	jboss.server.deploy.dir = /opt/eap/standalone/data/content
	jboss.server.log.dir = /opt/eap/standalone/log
	jboss.server.name = eap-app-3-gkcjb
	jboss.server.persist.config = true
	jboss.server.temp.dir = /opt/eap/standalone/tmp
	jolokia.agent = https://10.128.2.18:8778/jolokia/
	....
18:08:14,511 DEBUG [org.jboss.as.config] (MSC service thread 1-2) VM Arguments: -D[Standalone] -javaagent:/opt/eap/jboss-modules.jar -verbose:gc -Xloggc:/opt/eap/standalone/log/gc.log -XX:+PrintGCDetails -XX:+PrintGCDateStamps -XX:+UseGCLogFileRotation -XX:NumberOfGCLogFiles=5 -XX:GCLogFileSize=3M -XX:-TraceClassUnloading -Xms128m -Xmx512m -XX:MetaspaceSize=96m -Djava.net.preferIPv4Stack=true -Djboss.modules.system.pkgs=jdk.nashorn.api,com.sun.crypto.provider -Djava.awt.headless=true -XX:+UseParallelOldGC -XX:MinHeapFreeRatio=10 -XX:MaxHeapFreeRatio=20 -XX:GCTimeRatio=4 -XX:AdaptiveSizePolicyWeight=90 -XX:+ExitOnOutOfMemoryError -Djava.security.egd=file:/dev/./urandom -Dorg.jboss.boot.log.file=/opt/eap/standalone/log/server.log -Dlogging.configuration=file:/opt/eap/standalone/configuration/logging.properties 
18:08:14,632 INFO  [org.jboss.as.repository] (ServerService Thread Pool -- 9) WFLYDR0001: Content added at location /opt/eap/standalone/data/content/3f/99ba57ad67304be65f7f751400949523f75236/content
...
18:08:14,833 INFO  [org.jboss.as.naming] (MSC service thread 1-1) WFLYNAM0003: Starting Naming Service
18:08:14,913 INFO  [org.wildfly.extension.undertow] (MSC service thread 1-1) WFLYUT0012: Started server default-server.
18:08:14,991 INFO  [org.jboss.as.ejb3] (MSC service thread 1-1) WFLYEJB0482: Strict pool mdb-strict-max-pool is using a max instance size of 4 (per class), which is derived from the number of CPUs on this host.
18:08:14,991 INFO  [org.wildfly.extension.undertow] (MSC service thread 1-1) Queuing requests.
18:08:14,992 INFO  [org.wildfly.extension.undertow] (MSC service thread 1-1) WFLYUT0018: Host default-host starting
18:08:14,996 INFO  [org.wildfly.extension.undertow] (MSC service thread 1-1) WFLYUT0006: Undertow HTTP listener default listening on 0.0.0.0:8080
18:08:15,032 INFO  [org.jboss.as.server.deployment] (MSC service thread 1-2) WFLYSRV0027: Starting deployment of "ROOT.war" (runtime-name: "ROOT.war")
18:08:15,034 INFO  [org.jboss.as.server.deployment.scanner] (MSC service thread 1-2) WFLYDS0013: Started FileSystemDeploymentService for directory /opt/eap/standalone/deployments
18:08:15,038 INFO  [org.jboss.as.ejb3] (MSC service thread 1-2) WFLYEJB0493: Jakarta Enterprise Beans subsystem suspension complete
18:08:15,108 INFO  [org.apache.activemq.artemis.core.server] (ServerService Thread Pool -- 62) AMQ221000: live Message Broker is starting with configuration Broker Configuration (clustered=false,journalDirectory=/opt/eap/standalone/data/activemq/journal,bindingsDirectory=/opt/eap/standalone/data/activemq/bindings,largeMessagesDirectory=/opt/eap/standalone/data/activemq/largemessages,pagingDirectory=/opt/eap/standalone/data/activemq/paging)
....
18:08:15,472 INFO  [org.jboss.weld.deployer] (MSC service thread 1-2) WFLYWELD0003: Processing weld deployment ROOT.war
18:08:15,529 INFO  [org.jboss.as.connector.deployment] (MSC service thread 1-1) WFLYJCA0007: Registered connection factory java:/JmsXA
18:08:15,582 INFO  [org.jboss.as.connector.deployment] (MSC service thread 1-1) WFLYJCA0002: Bound Jakarta Connectors ConnectionFactory [java:/JmsXA]
18:08:16,398 INFO  [org.infinispan.CONTAINER] (ServerService Thread Pool -- 64) ISPN000128: Infinispan version: Infinispan 'Corona Extra' 11.0.16.Final-redhat-00001
18:08:16,450 INFO  [org.infinispan.CONFIG] (MSC service thread 1-1) ISPN000152: Passivation configured without an eviction policy being selected. Only manually evicted entities will be passivated.
18:08:16,527 INFO  [org.infinispan.PERSISTENCE] (ServerService Thread Pool -- 64) ISPN000556: Starting user marshaller 'org.wildfly.clustering.infinispan.spi.marshalling.InfinispanProtoStreamMarshaller'
18:08:16,749 INFO  [org.jboss.as.clustering.infinispan] (ServerService Thread Pool -- 64) WFLYCLINF0002: Started http-remoting-connector cache from ejb container
18:08:16,955 INFO  [org.jboss.as] (Controller Boot Thread) WFLYSRV0025: JBoss EAP 7.4.8.GA (WildFly Core 15.0.19.Final-redhat-00001) started in 2444ms - Started 417 of 709 services (437 services are lazy, passive or on-demand)

18:11:07,102 INFO  [org.jboss.as.repository] (DeploymentScanner-threads - 1) WFLYDR0001: Content added at location /opt/eap/standalone/data/content/33/d9d526f12c45adf6bbd0372e1f240fdd7026db/content
18:11:07,105 INFO  [org.jboss.as.server.deployment] (MSC service thread 1-2) WFLYSRV0027: Starting deployment of "clusterbench-ee8.ear" (runtime-name: "clusterbench-ee8.ear")
18:11:07,134 INFO  [org.jboss.as.server.deployment] (MSC service thread 1-2) WFLYSRV0207: Starting subdeployment (runtime-name: "clusterbench-ee8-web-passivating.war")
...
18:11:07,327 WARN  [org.jboss.as.dependency.unsupported] (MSC service thread 1-1) WFLYSRV0019: Deployment "deployment.clusterbench-ee8.ear.clusterbench-ee8-web-granular.war" is using an unsupported module ("org.jgroups") which may be changed or removed in future versions without notice.
18:11:07,331 WARN  [org.jboss.as.dependency.unsupported] (MSC service thread 1-2) WFLYSRV0019: Deployment "deployment.clusterbench-ee8.ear.clusterbench-ee8-web.war" is using an unsupported module ("org.jgroups") which may be changed or removed in future versions without notice.
18:11:07,333 WARN  [org.jboss.as.dependency.unsupported] (MSC service thread 1-1) WFLYSRV0019: Deployment "deployment.clusterbench-ee8.ear.clusterbench-ee8-web-passivating.war" is using an unsupported module ("org.jgroups") which may be changed or removed in future versions without notice.
18:11:07,334 INFO  [org.jboss.weld.deployer] (MSC service thread 1-2) WFLYWELD0003: Processing weld deployment clusterbench-ee8.ear
18:11:07,372 INFO  [org.jboss.weld.deployer] (MSC service thread 1-2) WFLYWELD0003: Processing weld deployment clusterbench-ee8-web-passivating.war
18:11:07,374 INFO  [org.jboss.weld.deployer] (MSC service thread 1-1) WFLYWELD0003: Processing weld deployment clusterbench-ee8-ejb.jar
18:11:07,383 INFO  [org.jboss.as.ejb3.deployment] (MSC service thread 1-1) WFLYEJB0473: JNDI bindings for session bean named 'RemoteStatelessSBImpl' in deployment unit 'subdeployment "clusterbench-ee8-ejb.jar" of deployment "clusterbench-ee8.ear"' are as follows:
...
	java:global/clusterbench-ee8/clusterbench-ee8-ejb/RemoteStatelessSBImpl!org.jboss.test.clusterbench.ejb.stateless.RemoteStatelessSB
	java:app/clusterbench-ee8-ejb/RemoteStatelessSBImpl!org.jboss.test.clusterbench.ejb.stateless.RemoteStatelessSB
	java:module/RemoteStatelessSBImpl!org.jboss.test.clusterbench.ejb.stateless.RemoteStatelessSB
	java:jboss/exported/clusterbench-ee8/clusterbench-ee8-ejb/RemoteStatelessSBImpl!org.jboss.test.clusterbench.ejb.stateless.RemoteStatelessSB
	ejb:clusterbench-ee8/clusterbench-ee8-ejb/RemoteStatelessSBImpl!org.jboss.test.clusterbench.ejb.stateless.RemoteStatelessSB
	java:global/clusterbench-ee8/clusterbench-ee8-ejb/RemoteStatelessSBImpl
	java:app/clusterbench-ee8-ejb/RemoteStatelessSBImpl
	java:module/RemoteStatelessSBImpl
....
18:11:07,391 INFO  [org.jboss.weld.deployer] (MSC service thread 1-1) WFLYWELD0003: Processing weld deployment clusterbench-ee8-web.war
18:11:07,472 WARN  [org.wildfly.clustering.web.undertow] (MSC service thread 1-1) WFLYCLWEBUT0004: Legacy <replication-config/> overriding attached distributable session management provider for clusterbench-ee8-web-granular.war
18:11:07,609 TRACE [org.jgroups.protocols.dns.DefaultDNSResolver] (ServerService Thread Pool -- 9) initializing DNS Context with factory: com.sun.jndi.dns.DnsContextFactory and url: 
18:11:07,687 TRACE [org.jgroups.protocols.dns.DefaultDNSResolver] (ServerService Thread Pool -- 9) resolving DNS query: eap-app-ping of a type: A <---------------------------------------------- the query is sent to eap-app-ping
18:11:07,689 DEBUG [org.jgroups.protocols.dns.DNS_PING] (ServerService Thread Pool -- 9) eap-app-3-gkcjb: entries collected from DNS (in 2 ms): [10.128.2.18:0, 10.128.2.17:0, 10.129.2.20:0, 10.131.0.34:0, 10.131.0.33:0] <-------------- collect
18:11:07,689 DEBUG [org.jgroups.protocols.dns.DNS_PING] (ServerService Thread Pool -- 9) eap-app-3-gkcjb: sending discovery requests to hosts [10.128.2.18:0, 10.128.2.17:0, 10.129.2.20:0, 10.131.0.34:0, 10.131.0.33:0] on ports [7600 .. 7600] <-----
18:11:07,692 TRACE [org.jgroups.protocols.dns.DNS_PING] (thread-4,null,eap-app-3-gkcjb) eap-app-3-gkcjb: sending discovery request to 10.128.2.17:7600
18:11:07,692 TRACE [org.jgroups.protocols.dns.DNS_PING] (thread-7,ee,eap-app-3-gkcjb) eap-app-3-gkcjb: sending discovery request to 10.129.2.20:7600
18:11:07,692 TRACE [org.jgroups.protocols.dns.DNS_PING] (thread-5,null,eap-app-3-gkcjb) eap-app-3-gkcjb: sending discovery request to 10.128.2.18:7600
18:11:07,692 TRACE [org.jgroups.protocols.dns.DNS_PING] (thread-8,ee,eap-app-3-gkcjb) eap-app-3-gkcjb: sending discovery request to 10.131.0.34:7600
18:11:07,694 TRACE [org.jgroups.protocols.dns.DNS_PING] (thread-9,ee,eap-app-3-gkcjb) eap-app-3-gkcjb: sending discovery request to 10.131.0.33:7600
18:11:07,696 TRACE [org.jgroups.protocols.dns.DNS_PING] (thread-10,ee,eap-app-3-gkcjb) eap-app-3-gkcjb: received GET_MBRS_REQ from eap-app-3-gkcjb, sending response eap-app-3-gkcjb, name=eap-app-3-gkcjb, addr=10.128.2.18:7600
18:11:07,697 TRACE [org.jgroups.protocols.dns.DNS_PING] (thread-5,null,eap-app-3-gkcjb) eap-app-3-gkcjb: received GET_MBRS_RSP from eap-app-3-gkcjb: eap-app-3-gkcjb, name=eap-app-3-gkcjb, addr=10.128.2.18:7600
18:11:10,759 INFO  [org.infinispan.PERSISTENCE] (ServerService Thread Pool -- 9) ISPN000556: Starting user marshaller 'org.wildfly.clustering.infinispan.spi.marshalling.InfinispanProtoStreamMarshaller'
18:11:10,763 INFO  [org.infinispan.CLUSTER] (ServerService Thread Pool -- 9) ISPN000078: Starting JGroups channel ee
18:11:10,765 INFO  [org.infinispan.CLUSTER] (ServerService Thread Pool -- 9) ISPN000094: Received new cluster view for channel ee: [eap-app-3-gkcjb|0] (1) [eap-app-3-gkcjb]
18:11:10,769 INFO  [org.infinispan.CLUSTER] (ServerService Thread Pool -- 9) ISPN000079: Channel ee local address is eap-app-3-gkcjb, physical addresses are [10.128.2.18:7600]
18:11:10,777 INFO  [org.infinispan.CONFIG] (MSC service thread 1-2) ISPN000152: Passivation configured without an eviction policy being selected. Only manually evicted entities will be passivated.
18:11:10,777 INFO  [org.infinispan.CONFIG] (MSC service thread 1-2) ISPN000152: Passivation configured without an eviction policy being selected. Only manually evicted entities will be passivated.
18:11:10,778 INFO  [org.infinispan.CONFIG] (MSC service thread 1-2) ISPN000152: Passivation configured without an eviction policy being selected. Only manually evicted entities will be passivated.
18:11:10,779 INFO  [org.infinispan.CONFIG] (MSC service thread 1-2) ISPN000152: Passivation configured without an eviction policy being selected. Only manually evicted entities will be passivated.
18:11:10,816 WARN  [org.jboss.weld.Bootstrap] (MSC service thread 1-2) WELD-000146: BeforeBeanDiscovery.addAnnotatedType(AnnotatedType<?>) used for class com.sun.faces.flow.FlowDiscoveryCDIHelper is deprecated from CDI 1.1!
18:11:11,039 INFO  [org.jboss.as.clustering.infinispan] (ServerService Thread Pool -- 9) WFLYCLINF0002: Started default-server cache from web container
18:11:11,064 INFO  [org.jboss.as.clustering.infinispan] (ServerService Thread Pool -- 70) WFLYCLINF0002: Started clusterbench-ee8.ear.clusterbench-ee8-web.war cache from web container
18:11:11,090 INFO  [org.jboss.as.clustering.infinispan] (ServerService Thread Pool -- 69) WFLYCLINF0002: Started clusterbench-ee8.ear.clusterbench-ee8-web-passivating.war cache from web container
18:11:11,091 INFO  [org.jboss.as.clustering.infinispan] (ServerService Thread Pool -- 68) WFLYCLINF0002: Started clusterbench-ee8.ear.clusterbench-ee8-web-granular.war cache from web container
18:11:11,098 SEVERE [javax.enterprise.resource.webcontainer.jsf.application.view] (MSC service thread 1-2) Unable to obtain CDI 1.1 utilities for Mojarra
18:11:11,100 SEVERE [javax.enterprise.resource.webcontainer.jsf.flow] (MSC service thread 1-2) Unable to obtain CDI 1.1 utilities for Mojarra
18:11:11,230 INFO  [org.wildfly.extension.undertow] (ServerService Thread Pool -- 9) WFLYUT0021: Registered web context: '/clusterbench-granular' for server 'default-server'
18:11:11,248 INFO  [javax.enterprise.resource.webcontainer.jsf.config] (ServerService Thread Pool -- 74) Initializing Mojarra 2.3.14.SP06 for context '/clusterbench-passivating'
18:11:11,250 INFO  [javax.enterprise.resource.webcontainer.jsf.config] (ServerService Thread Pool -- 75) Initializing Mojarra 2.3.14.SP06 for context '/clusterbench'
18:11:11,595 INFO  [org.wildfly.extension.undertow] (ServerService Thread Pool -- 75) WFLYUT0021: Registered web context: '/clusterbench' for server 'default-server'
18:11:11,599 INFO  [org.wildfly.extension.undertow] (ServerService Thread Pool -- 74) WFLYUT0021: Registered web context: '/clusterbench-passivating' for server 'default-server'
18:11:11,613 INFO  [org.jboss.as.server] (DeploymentScanner-threads - 1) WFLYSRV0010: Deployed "clusterbench-ee8.ear" (runtime-name : "clusterbench-ee8.ear")
18:11:15,001 TRACE [org.jgroups.protocols.dns.DNS_PING] (thread-9,ee,eap-app-3-gkcjb) eap-app-3-gkcjb: received GET_MBRS_REQ from eap-app-3-jvn9z, sending response eap-app-3-gkcjb, name=eap-app-3-gkcjb, addr=10.128.2.18:7600, coord
18:11:15,015 INFO  [org.infinispan.CLUSTER] (thread-9,ee,eap-app-3-gkcjb) ISPN000094: Received new cluster view for channel ee: [eap-app-3-gkcjb|1] (2) [eap-app-3-gkcjb, eap-app-3-jvn9z]
18:11:15,017 INFO  [org.infinispan.CLUSTER] (thread-9,ee,eap-app-3-gkcjb) ISPN100000: Node eap-app-3-jvn9z joined the cluster
18:11:15,306 INFO  [org.infinispan.CLUSTER] (thread-9,ee,eap-app-3-gkcjb) [Context=default-server] ISPN100002: Starting rebalance with members [eap-app-3-gkcjb, eap-app-3-jvn9z], phase READ_OLD_WRITE_ALL, topology id 2
18:11:15,432 INFO  [org.infinispan.CLUSTER] (thread-8,ee,eap-app-3-gkcjb) [Context=clusterbench-ee8.ear.clusterbench-ee8-web-granular.war] ISPN100002: Starting rebalance with members [eap-app-3-gkcjb, eap-app-3-jvn9z], phase READ_OLD_WRITE_ALL, topology id 2
18:11:15,435 INFO  [org.infinispan.CLUSTER] (thread-8,ee,eap-app-3-gkcjb) [Context=clusterbench-ee8.ear.clusterbench-ee8-web.war] ISPN100002: Starting rebalance with members [eap-app-3-gkcjb, eap-app-3-jvn9z], phase READ_OLD_WRITE_ALL, topology id 2
18:11:15,436 INFO  [org.infinispan.CLUSTER] (thread-4,null,eap-app-3-gkcjb) [Context=clusterbench-ee8.ear.clusterbench-ee8-web-passivating.war] ISPN100002: Starting rebalance with members [eap-app-3-gkcjb, eap-app-3-jvn9z], phase READ_OLD_WRITE_ALL, topology id 2
18:11:15,486 INFO  [org.infinispan.CLUSTER] (thread-4,null,eap-app-3-gkcjb) [Context=clusterbench-ee8.ear.clusterbench-ee8-web-passivating.war] ISPN100009: Advancing to rebalance phase READ_ALL_WRITE_ALL, topology id 3
18:11:15,487 INFO  [org.infinispan.CLUSTER] (thread-9,ee,eap-app-3-gkcjb) [Context=clusterbench-ee8.ear.clusterbench-ee8-web.war] ISPN100009: Advancing to rebalance phase READ_ALL_WRITE_ALL, topology id 3
18:11:15,498 INFO  [org.infinispan.CLUSTER] (thread-4,null,eap-app-3-gkcjb) [Context=clusterbench-ee8.ear.clusterbench-ee8-web.war] ISPN100009: Advancing to rebalance phase READ_NEW_WRITE_ALL, topology id 4
18:11:15,505 INFO  [org.infinispan.CLUSTER] (thread-4,null,eap-app-3-gkcjb) [Context=clusterbench-ee8.ear.clusterbench-ee8-web-granular.war] ISPN100009: Advancing to rebalance phase READ_ALL_WRITE_ALL, topology id 3
18:11:15,511 INFO  [org.infinispan.CLUSTER] (thread-4,null,eap-app-3-gkcjb) [Context=clusterbench-ee8.ear.clusterbench-ee8-web-granular.war] ISPN100009: Advancing to rebalance phase READ_NEW_WRITE_ALL, topology id 4
18:11:15,511 INFO  [org.infinispan.CLUSTER] (thread-7,ee,eap-app-3-gkcjb) [Context=clusterbench-ee8.ear.clusterbench-ee8-web.war] ISPN100010: Finished rebalance with members [eap-app-3-gkcjb, eap-app-3-jvn9z], topology id 5
18:11:15,512 INFO  [org.infinispan.CLUSTER] (thread-9,ee,eap-app-3-gkcjb) [Context=clusterbench-ee8.ear.clusterbench-ee8-web-passivating.war] ISPN100009: Advancing to rebalance phase READ_NEW_WRITE_ALL, topology id 4
18:11:15,528 INFO  [org.infinispan.CLUSTER] (thread-9,ee,eap-app-3-gkcjb) [Context=default-server] ISPN100009: Advancing to rebalance phase READ_ALL_WRITE_ALL, topology id 3
18:11:15,546 INFO  [org.infinispan.CLUSTER] (non-blocking-thread--p7-t2) [Context=clusterbench-ee8.ear.clusterbench-ee8-web-passivating.war] ISPN100010: Finished rebalance with members [eap-app-3-gkcjb, eap-app-3-jvn9z], topology id 5
18:11:15,546 INFO  [org.infinispan.CLUSTER] (non-blocking-thread--p7-t1) [Context=clusterbench-ee8.ear.clusterbench-ee8-web-granular.war] ISPN100010: Finished rebalance with members [eap-app-3-gkcjb, eap-app-3-jvn9z], topology id 5
18:11:15,560 INFO  [org.infinispan.CLUSTER] (thread-9,ee,eap-app-3-gkcjb) [Context=default-server] ISPN100009: Advancing to rebalance phase READ_NEW_WRITE_ALL, topology id 4
18:11:15,566 INFO  [org.infinispan.CLUSTER] (thread-9,ee,eap-app-3-gkcjb) [Context=default-server] ISPN100010: Finished rebalance with members [eap-app-3-gkcjb, eap-app-3-jvn9z], topology id 5
18:11:18,790 TRACE [org.jgroups.protocols.dns.DNS_PING] (thread-9,ee,eap-app-3-gkcjb) eap-app-3-gkcjb: received GET_MBRS_REQ from eap-app-3-msczt, sending response eap-app-3-gkcjb, name=eap-app-3-gkcjb, addr=10.128.2.18:7600, coord
18:11:18,797 INFO  [org.infinispan.CLUSTER] (thread-9,ee,eap-app-3-gkcjb) ISPN000094: Received new cluster view for channel ee: [eap-app-3-gkcjb|2] (3) [eap-app-3-gkcjb, eap-app-3-jvn9z, eap-app-3-msczt]
18:11:18,797 INFO  [org.infinispan.CLUSTER] (thread-9,ee,eap-app-3-gkcjb) ISPN100000: Node eap-app-3-msczt joined the cluster
18:11:19,301 INFO  [org.infinispan.CLUSTER] (thread-8,ee,eap-app-3-gkcjb) [Context=default-server] ISPN100002: Starting rebalance with members [eap-app-3-gkcjb, eap-app-3-jvn9z, eap-app-3-msczt], phase READ_OLD_WRITE_ALL, topology id 6
18:11:19,428 INFO  [org.infinispan.CLUSTER] (thread-9,ee,eap-app-3-gkcjb) [Context=clusterbench-ee8.ear.clusterbench-ee8-web.war] ISPN100002: Starting rebalance with members [eap-app-3-gkcjb, eap-app-3-jvn9z, eap-app-3-msczt], phase READ_OLD_WRITE_ALL, topology id 6
18:11:19,437 INFO  [org.infinispan.CLUSTER] (thread-9,ee,eap-app-3-gkcjb) [Context=clusterbench-ee8.ear.clusterbench-ee8-web-passivating.war] ISPN100002: Starting rebalance with members [eap-app-3-gkcjb, eap-app-3-jvn9z, eap-app-3-msczt], phase READ_OLD_WRITE_ALL, topology id 6
18:11:19,441 INFO  [org.infinispan.CLUSTER] (thread-8,ee,eap-app-3-gkcjb) [Context=clusterbench-ee8.ear.clusterbench-ee8-web-granular.war] ISPN100002: Starting rebalance with members [eap-app-3-gkcjb, eap-app-3-jvn9z, eap-app-3-msczt], phase READ_OLD_WRITE_ALL, topology id 6
18:11:19,492 INFO  [org.infinispan.CLUSTER] (thread-9,ee,eap-app-3-gkcjb) [Context=clusterbench-ee8.ear.clusterbench-ee8-web-granular.war] ISPN100009: Advancing to rebalance phase READ_ALL_WRITE_ALL, topology id 7
18:11:19,493 INFO  [org.infinispan.CLUSTER] (thread-9,ee,eap-app-3-gkcjb) [Context=clusterbench-ee8.ear.clusterbench-ee8-web-passivating.war] ISPN100009: Advancing to rebalance phase READ_ALL_WRITE_ALL, topology id 7
18:11:19,494 INFO  [org.infinispan.CLUSTER] (thread-9,ee,eap-app-3-gkcjb) [Context=clusterbench-ee8.ear.clusterbench-ee8-web.war] ISPN100009: Advancing to rebalance phase READ_ALL_WRITE_ALL, topology id 7
18:11:19,508 INFO  [org.infinispan.CLUSTER] (thread-8,ee,eap-app-3-gkcjb) [Context=clusterbench-ee8.ear.clusterbench-ee8-web-granular.war] ISPN100009: Advancing to rebalance phase READ_NEW_WRITE_ALL, topology id 8
18:11:19,509 INFO  [org.infinispan.CLUSTER] (thread-7,ee,eap-app-3-gkcjb) [Context=clusterbench-ee8.ear.clusterbench-ee8-web-passivating.war] ISPN100009: Advancing to rebalance phase READ_NEW_WRITE_ALL, topology id 8
18:11:19,509 INFO  [org.infinispan.CLUSTER] (thread-4,null,eap-app-3-gkcjb) [Context=clusterbench-ee8.ear.clusterbench-ee8-web.war] ISPN100009: Advancing to rebalance phase READ_NEW_WRITE_ALL, topology id 8
18:11:19,510 INFO  [org.infinispan.CLUSTER] (thread-7,ee,eap-app-3-gkcjb) [Context=default-server] ISPN100009: Advancing to rebalance phase READ_ALL_WRITE_ALL, topology id 7
18:11:19,513 INFO  [org.infinispan.CLUSTER] (thread-4,null,eap-app-3-gkcjb) [Context=clusterbench-ee8.ear.clusterbench-ee8-web-granular.war] ISPN100010: Finished rebalance with members [eap-app-3-gkcjb, eap-app-3-jvn9z, eap-app-3-msczt], topology id 9
18:11:19,525 INFO  [org.infinispan.CLUSTER] (thread-7,ee,eap-app-3-gkcjb) [Context=clusterbench-ee8.ear.clusterbench-ee8-web.war] ISPN100010: Finished rebalance with members [eap-app-3-gkcjb, eap-app-3-jvn9z, eap-app-3-msczt], topology id 9
18:11:19,525 INFO  [org.infinispan.CLUSTER] (thread-5,null,eap-app-3-gkcjb) [Context=clusterbench-ee8.ear.clusterbench-ee8-web-passivating.war] ISPN100010: Finished rebalance with members [eap-app-3-gkcjb, eap-app-3-jvn9z, eap-app-3-msczt], topology id 9
18:11:19,527 INFO  [org.infinispan.CLUSTER] (thread-8,ee,eap-app-3-gkcjb) [Context=default-server] ISPN100009: Advancing to rebalance phase READ_NEW_WRITE_ALL, topology id 8
18:11:19,546 INFO  [org.infinispan.CLUSTER] (thread-8,ee,eap-app-3-gkcjb) [Context=default-server] ISPN100010: Finished rebalance with members [eap-app-3-gkcjb, eap-app-3-jvn9z, eap-app-3-msczt], topology id 9
18:11:22,751 TRACE [org.jgroups.protocols.dns.DNS_PING] (thread-8,ee,eap-app-3-gkcjb) eap-app-3-gkcjb: received GET_MBRS_REQ from eap-app-3-qthbb, sending response eap-app-3-gkcjb, name=eap-app-3-gkcjb, addr=10.128.2.18:7600, coord
18:11:22,756 INFO  [org.infinispan.CLUSTER] (thread-8,ee,eap-app-3-gkcjb) ISPN000094: Received new cluster view for channel ee: [eap-app-3-gkcjb|3] (4) [eap-app-3-gkcjb, eap-app-3-jvn9z, eap-app-3-msczt, eap-app-3-qthbb]
18:11:22,756 INFO  [org.infinispan.CLUSTER] (thread-8,ee,eap-app-3-gkcjb) ISPN100000: Node eap-app-3-qthbb joined the cluster
18:11:23,037 INFO  [org.infinispan.CLUSTER] (thread-8,ee,eap-app-3-gkcjb) [Context=default-server] ISPN100002: Starting rebalance with members [eap-app-3-gkcjb, eap-app-3-jvn9z, eap-app-3-msczt, eap-app-3-qthbb], phase READ_OLD_WRITE_ALL, topology id 10
18:11:23,201 INFO  [org.infinispan.CLUSTER] (thread-8,ee,eap-app-3-gkcjb) [Context=clusterbench-ee8.ear.clusterbench-ee8-web-granular.war] ISPN100002: Starting rebalance with members [eap-app-3-gkcjb, eap-app-3-jvn9z, eap-app-3-msczt, eap-app-3-qthbb], phase READ_OLD_WRITE_ALL, topology id 10
18:11:23,203 INFO  [org.infinispan.CLUSTER] (thread-8,ee,eap-app-3-gkcjb) [Context=clusterbench-ee8.ear.clusterbench-ee8-web.war] ISPN100002: Starting rebalance with members [eap-app-3-gkcjb, eap-app-3-jvn9z, eap-app-3-msczt, eap-app-3-qthbb], phase READ_OLD_WRITE_ALL, topology id 10
18:11:23,204 INFO  [org.infinispan.CLUSTER] (thread-5,null,eap-app-3-gkcjb) [Context=clusterbench-ee8.ear.clusterbench-ee8-web-passivating.war] ISPN100002: Starting rebalance with members [eap-app-3-gkcjb, eap-app-3-jvn9z, eap-app-3-msczt, eap-app-3-qthbb], phase READ_OLD_WRITE_ALL, topology id 10
18:11:23,249 INFO  [org.infinispan.CLUSTER] (thread-4,null,eap-app-3-gkcjb) [Context=clusterbench-ee8.ear.clusterbench-ee8-web.war] ISPN100009: Advancing to rebalance phase READ_ALL_WRITE_ALL, topology id 11
18:11:23,250 INFO  [org.infinispan.CLUSTER] (thread-5,null,eap-app-3-gkcjb) [Context=clusterbench-ee8.ear.clusterbench-ee8-web-passivating.war] ISPN100009: Advancing to rebalance phase READ_ALL_WRITE_ALL, topology id 11
18:11:23,250 INFO  [org.infinispan.CLUSTER] (thread-8,ee,eap-app-3-gkcjb) [Context=clusterbench-ee8.ear.clusterbench-ee8-web-granular.war] ISPN100009: Advancing to rebalance phase READ_ALL_WRITE_ALL, topology id 11
18:11:23,257 INFO  [org.infinispan.CLUSTER] (thread-8,ee,eap-app-3-gkcjb) [Context=clusterbench-ee8.ear.clusterbench-ee8-web-granular.war] ISPN100009: Advancing to rebalance phase READ_NEW_WRITE_ALL, topology id 12
18:11:23,262 INFO  [org.infinispan.CLUSTER] (thread-5,null,eap-app-3-gkcjb) [Context=clusterbench-ee8.ear.clusterbench-ee8-web.war] ISPN100009: Advancing to rebalance phase READ_NEW_WRITE_ALL, topology id 12
18:11:23,266 INFO  [org.infinispan.CLUSTER] (thread-8,ee,eap-app-3-gkcjb) [Context=clusterbench-ee8.ear.clusterbench-ee8-web-passivating.war] ISPN100009: Advancing to rebalance phase READ_NEW_WRITE_ALL, topology id 12
18:11:23,271 INFO  [org.infinispan.CLUSTER] (thread-10,ee,eap-app-3-gkcjb) [Context=default-server] ISPN100009: Advancing to rebalance phase READ_ALL_WRITE_ALL, topology id 11
18:11:23,276 INFO  [org.infinispan.CLUSTER] (thread-7,ee,eap-app-3-gkcjb) [Context=clusterbench-ee8.ear.clusterbench-ee8-web.war] ISPN100010: Finished rebalance with members [eap-app-3-gkcjb, eap-app-3-jvn9z, eap-app-3-msczt, eap-app-3-qthbb], topology id 13
18:11:23,281 INFO  [org.infinispan.CLUSTER] (thread-9,ee,eap-app-3-gkcjb) [Context=clusterbench-ee8.ear.clusterbench-ee8-web-passivating.war] ISPN100010: Finished rebalance with members [eap-app-3-gkcjb, eap-app-3-jvn9z, eap-app-3-msczt, eap-app-3-qthbb], topology id 13
18:11:23,282 INFO  [org.infinispan.CLUSTER] (thread-8,ee,eap-app-3-gkcjb) [Context=clusterbench-ee8.ear.clusterbench-ee8-web-granular.war] ISPN100010: Finished rebalance with members [eap-app-3-gkcjb, eap-app-3-jvn9z, eap-app-3-msczt, eap-app-3-qthbb], topology id 13
18:11:23,292 INFO  [org.infinispan.CLUSTER] (thread-18,ee,eap-app-3-gkcjb) [Context=default-server] ISPN100009: Advancing to rebalance phase READ_NEW_WRITE_ALL, topology id 12
18:11:23,300 INFO  [org.infinispan.CLUSTER] (thread-8,ee,eap-app-3-gkcjb) [Context=default-server] ISPN100010: Finished rebalance with members [eap-app-3-gkcjb, eap-app-3-jvn9z, eap-app-3-msczt, eap-app-3-qthbb], topology id 13
18:11:27,806 TRACE [org.jgroups.protocols.dns.DNS_PING] (thread-8,ee,eap-app-3-gkcjb) eap-app-3-gkcjb: received GET_MBRS_REQ from eap-app-3-qzd4z, sending response eap-app-3-gkcjb, name=eap-app-3-gkcjb, addr=10.128.2.18:7600, coord
18:11:27,810 INFO  [org.infinispan.CLUSTER] (thread-8,ee,eap-app-3-gkcjb) ISPN000094: Received new cluster view for channel ee: [eap-app-3-gkcjb|4] (5) [eap-app-3-gkcjb, eap-app-3-jvn9z, eap-app-3-msczt, eap-app-3-qthbb, eap-app-3-qzd4z]
18:11:27,810 INFO  [org.infinispan.CLUSTER] (thread-8,ee,eap-app-3-gkcjb) ISPN100000: Node eap-app-3-qzd4z joined the cluster
18:11:28,099 INFO  [org.infinispan.CLUSTER] (thread-9,ee,eap-app-3-gkcjb) [Context=default-server] ISPN100002: Starting rebalance with members [eap-app-3-gkcjb, eap-app-3-jvn9z, eap-app-3-msczt, eap-app-3-qthbb, eap-app-3-qzd4z], phase READ_OLD_WRITE_ALL, topology id 14
18:11:28,251 INFO  [org.infinispan.CLUSTER] (thread-18,ee,eap-app-3-gkcjb) [Context=clusterbench-ee8.ear.clusterbench-ee8-web-passivating.war] ISPN100002: Starting rebalance with members [eap-app-3-gkcjb, eap-app-3-jvn9z, eap-app-3-msczt, eap-app-3-qthbb, eap-app-3-qzd4z], phase READ_OLD_WRITE_ALL, topology id 14
18:11:28,255 INFO  [org.infinispan.CLUSTER] (thread-18,ee,eap-app-3-gkcjb) [Context=clusterbench-ee8.ear.clusterbench-ee8-web.war] ISPN100002: Starting rebalance with members [eap-app-3-gkcjb, eap-app-3-jvn9z, eap-app-3-msczt, eap-app-3-qthbb, eap-app-3-qzd4z], phase READ_OLD_WRITE_ALL, topology id 14
18:11:28,263 INFO  [org.infinispan.CLUSTER] (thread-18,ee,eap-app-3-gkcjb) [Context=clusterbench-ee8.ear.clusterbench-ee8-web-granular.war] ISPN100002: Starting rebalance with members [eap-app-3-gkcjb, eap-app-3-jvn9z, eap-app-3-msczt, eap-app-3-qthbb, eap-app-3-qzd4z], phase READ_OLD_WRITE_ALL, topology id 14
18:11:28,335 INFO  [org.infinispan.CLUSTER] (thread-18,ee,eap-app-3-gkcjb) [Context=default-server] ISPN100009: Advancing to rebalance phase READ_ALL_WRITE_ALL, topology id 15
18:11:28,335 INFO  [org.infinispan.CLUSTER] (thread-18,ee,eap-app-3-gkcjb) [Context=clusterbench-ee8.ear.clusterbench-ee8-web.war] ISPN100009: Advancing to rebalance phase READ_ALL_WRITE_ALL, topology id 15
18:11:28,336 INFO  [org.infinispan.CLUSTER] (thread-18,ee,eap-app-3-gkcjb) [Context=clusterbench-ee8.ear.clusterbench-ee8-web-granular.war] ISPN100009: Advancing to rebalance phase READ_ALL_WRITE_ALL, topology id 15
18:11:28,339 INFO  [org.infinispan.CLUSTER] (thread-8,ee,eap-app-3-gkcjb) [Context=clusterbench-ee8.ear.clusterbench-ee8-web-passivating.war] ISPN100009: Advancing to rebalance phase READ_ALL_WRITE_ALL, topology id 15
18:11:28,342 INFO  [org.infinispan.CLUSTER] (thread-8,ee,eap-app-3-gkcjb) [Context=clusterbench-ee8.ear.clusterbench-ee8-web-granular.war] ISPN100009: Advancing to rebalance phase READ_NEW_WRITE_ALL, topology id 16
18:11:28,343 INFO  [org.infinispan.CLUSTER] (thread-8,ee,eap-app-3-gkcjb) [Context=clusterbench-ee8.ear.clusterbench-ee8-web-passivating.war] ISPN100009: Advancing to rebalance phase READ_NEW_WRITE_ALL, topology id 16
18:11:28,352 INFO  [org.infinispan.CLUSTER] (thread-8,ee,eap-app-3-gkcjb) [Context=clusterbench-ee8.ear.clusterbench-ee8-web.war] ISPN100009: Advancing to rebalance phase READ_NEW_WRITE_ALL, topology id 16
18:11:28,352 INFO  [org.infinispan.CLUSTER] (thread-18,ee,eap-app-3-gkcjb) [Context=clusterbench-ee8.ear.clusterbench-ee8-web-granular.war] ISPN100010: Finished rebalance with members [eap-app-3-gkcjb, eap-app-3-jvn9z, eap-app-3-msczt, eap-app-3-qthbb, eap-app-3-qzd4z], topology id 17
18:11:28,352 INFO  [org.infinispan.CLUSTER] (thread-9,ee,eap-app-3-gkcjb) [Context=default-server] ISPN100009: Advancing to rebalance phase READ_NEW_WRITE_ALL, topology id 16
18:11:28,370 INFO  [org.infinispan.CLUSTER] (thread-18,ee,eap-app-3-gkcjb) [Context=clusterbench-ee8.ear.clusterbench-ee8-web-passivating.war] ISPN100010: Finished rebalance with members [eap-app-3-gkcjb, eap-app-3-jvn9z, eap-app-3-msczt, eap-app-3-qthbb, eap-app-3-qzd4z], topology id 17
18:11:28,374 INFO  [org.infinispan.CLUSTER] (thread-18,ee,eap-app-3-gkcjb) [Context=default-server] ISPN100010: Finished rebalance with members [eap-app-3-gkcjb, eap-app-3-jvn9z, eap-app-3-msczt, eap-app-3-qthbb, eap-app-3-qzd4z], topology id 17
18:11:28,384 INFO  [org.infinispan.CLUSTER] (thread-18,ee,eap-app-3-gkcjb) [Context=clusterbench-ee8.ear.clusterbench-ee8-web.war] ISPN100010: Finished rebalance with members [eap-app-3-gkcjb, eap-app-3-jvn9z, eap-app-3-msczt, eap-app-3-qthbb, eap-app-3-qzd4z], topology id 17


Root Cause

The solution EAP 7 image clustering in OCP 4 bring several aspects of clustering in EAP 7.

Avoid restricting DNS namespace comms.

Avoid restricting DNS namespace comms, as detailed on Pods are having issues with DNS after the network policies are applied. Remember to always allow openshift-dns namespace communication.

For enabling DNS_PING tracing logging

What are the options to use a custom runtime JBoss EAP 7 configurations in OCP

Clustering deployment boundaries

EAP 7 clustering deployments boundaries in OCP cluster

EAP 7 deployment with Service Mesh

See solution EAP 7 deployment with Service Mesh

IssueSolution
EAP 7 clustering (main solution)EAP 7 image clustering in OCP 4
Deployment boundaries: labels of serviceEAP 7 clustering deployments boundaries in OCP cluster
Istio/Service Mesh with EAP 7EAP 7 deployment with Service Mesh
To set custom configurationsWhat are the options to use a custom runtime JBoss EAP 7 configurations in OCP

Diagnostic Steps

  1. Setting org.jgroups.protocols.DNS_PING with level TRACE:

    # set DNS_PING
    $ oc set env dc/eap-app LOGGER_CATEGORIES=org.jgroups.protocols.DNS_PING:TRACE
    ...
    # confirming DNS_PING to TRACE
    INFO Found env LOGGER_CATEGORIES, configuring....
    INFO Configuring logger category org.jgroups.protocols.DNS_PING with level TRACE <----
    
  2. For jgroups.protocols set org.jgroups:TRACE:

    oc set env dc/eap-app LOGGER_CATEGORIES=org.jgroups.protocols.DNS_PING:TRACE,org.jgroups:TRACE
    
  3. Search for GET_MBRS_REQ and GET_MBRS_RSP - for outgoing and incoming nodes;

  4. EAP 7's service will be eap-app-ping by default and it will use deploymentConfig label by default

  5. The EAP 7's default service is headless, so one cannot curl the service.

  6. oc get ep,svc -o wide to get the services and the endpoints:

### service
oc get svc -o wide
server/eap-app-ping Type ClusterIP None <none> 8888 75M  selector: deploymentConfig=eap-app
### endpoint
oc get ep -o wide
NAME                                                     ENDPOINTS                                                          AGE
endpoints/eap-app-ping                                   1.1.1.127:8888, 1.1.1.128:8888                3 min
Components
Category
Tags

This solution is part of Red Hat’s fast-track publication program, providing a huge library of solutions that Red Hat engineers have created while supporting our customers. To give you the knowledge you need the instant it becomes available, these articles may be presented in a raw and unedited form.