Bug 1380657

Summary: [GSS] (6.4.z) JGroups ASYM_ENCRYPT logs error message "key server is currently not set" during startup
Product: [JBoss] JBoss Enterprise Application Platform 6 Reporter: Lami Akagwu <lakagwu>
Component: Clustering, InfinispanAssignee: Miroslav Sochurek <msochure>
Status: CLOSED CURRENTRELEASE QA Contact: Jiří Bílek <jbilek>
Severity: high Docs Contact:
Priority: unspecified    
Version: 6.4.10CC: bmaxwell, dereed, jbilek, msochure, paul.ferraro, rnetuka, rstancel
Target Milestone: CR1   
Target Release: EAP 6.4.16   
Hardware: Unspecified   
OS: Unspecified   
Whiteboard:
Fixed In Version: Doc Type: If docs needed, set a value
Doc Text:
Story Points: ---
Clone Of: Environment:
Last Closed: 2017-06-22 09:18:22 UTC Type: Bug
Regression: --- Mount Type: ---
Documentation: --- CRM:
Verified Versions: Category: ---
oVirt Team: --- RHEL 7.3 requirements from Atomic Host:
Cloudforms Team: --- Target Upstream Version:
Embargoed:
Bug Depends On:    
Bug Blocks: 1387585, 1434495    

Description Lami Akagwu 2016-09-30 09:14:30 UTC
Description of problem:

When using ASYM_ENCRYPT, in the JGroups stack, the error below is reported when the second server instance is joining the cluster.
-------------------
[Server:server-two] 16:33:33,896 INFO  [org.jboss.as.clustering.infinispan] (ServerService Thread Pool -- 66) JBAS010281: Started remote-connector-client-mappings cache from ejb container
[Server:server-two] 16:33:33,910 INFO  [org.jboss.as.clustering.infinispan] (ServerService Thread Pool -- 67) JBAS010281: Started repl cache from ejb container
[Server:server-two] 16:33:34,019 INFO  [org.jboss.as.server] (Controller Boot Thread) JBAS015859: Deployed "cluster-ejb-test-ear-stateless-clustered.ear" (runtime-name : "cluster-ejb-test-ear-stateless-clustered.ear")
[Server:server-two] 16:33:34,047 INFO  [org.jboss.as] (Controller Boot Thread) JBAS015874: JBoss EAP 6.4.9.GA (AS 7.5.9.Final-redhat-2) started in 13985ms - Started 318 of 383 services (129 services are lazy, passive or on-demand)
[Server:server-one] 16:33:34,707 DEBUG [org.jgroups.protocols.pbcast.GMS] (ServerService Thread Pool -- 68) election results: {}
[Server:server-one] 16:33:35,211 DEBUG [org.jgroups.protocols.pbcast.GMS] (ServerService Thread Pool -- 68) election results: {master:server-two/ejb=1}
[Server:server-one] 16:33:35,211 DEBUG [org.jgroups.protocols.pbcast.GMS] (ServerService Thread Pool -- 68) sending JOIN(master:server-one/ejb) to master:server-two/ejb
****************************************************************************************************************
[Server:server-one] 16:33:35,226 ***ERROR*** [org.jgroups.protocols.ASYM_ENCRYPT] (OOB-4,shared=tcpping) master:server-one/ejb: key server is currently not set
****************************************************************************************************************
[Server:server-two] 16:33:35,273 DEBUG [org.jgroups.protocols.pbcast.STABLE] (ViewHandler,ejb,master:server-two/ejb) suspending message garbage collection
[Server:server-two] 16:33:35,274 DEBUG [org.jgroups.protocols.pbcast.STABLE] (ViewHandler,ejb,master:server-two/ejb) resume task started, max_suspend_time=33000
[Server:server-two] 16:33:35,277 DEBUG [org.jgroups.protocols.pbcast.NAKACK2] (Incoming-1,shared=tcpping)
[Server:server-two] [master:server-two/ejb setDigest()]
[Server:server-two] existing digest:  master:server-two/ejb: [1 (1)]
[Server:server-two] new digest:       master:server-two/ejb: [0 (0)], master:server-one/ejb: [0 (0)]
[Server:server-two] resulting digest: master:server-two/ejb: [1 (1)], master:server-one/ejb: [0 (0)]
[Server:server-two] 16:33:35,277 DEBUG [org.jgroups.protocols.pbcast.GMS] (Incoming-1,shared=tcpping) master:server-two/ejb: installing view [master:server-two/ejb|1] [master:server-two/ejb, master:server-one/ejb]
[Server:server-two] 16:33:35,278 DEBUG [org.jgroups.protocols.FD_SOCK] (Incoming-1,shared=tcpping) VIEW_CHANGE received: [master:server-two/ejb, master:server-one/ejb]

-------------------

The error "key server is currently not set" occurs as the server just got started, and hadn't joined the cluster yet.
The error message means that it can't decode a message it received because it doesn't have the encryption keys and shouldn't be of concern.


Version-Release number of selected component (if applicable):


How reproducible:


Steps to Reproduce:
1.
2.
3.

Actual results:


Expected results:
Change the "ERROR" message for "key server is currently not set" to INFO level or even DEBUG level

Additional info:
Ops teams are usually on alert for ERROR/WARN messages requiring some sort of action on their part. The message in this instance should be of a lower severity

Comment 1 Vlado Pakan 2016-10-21 10:16:28 UTC
PR: https://github.com/belaban/JGroups/pull/329

Comment 6 Jiří Bílek 2017-06-07 15:10:27 UTC
Verified with EAP 6.4.16.CP.CR1

Comment 7 Petr Penicka 2017-06-22 09:18:22 UTC
Released on June 20 2017 as part of the EAP 6.4.16 maintenance release.