Bug 1380657 - [GSS] (6.4.z) JGroups ASYM_ENCRYPT logs error message "key server is currently not set" during startup
Summary: [GSS] (6.4.z) JGroups ASYM_ENCRYPT logs error message "key server is currentl...
Keywords:
Status: CLOSED CURRENTRELEASE
Alias: None
Product: JBoss Enterprise Application Platform 6
Classification: JBoss
Component: Clustering, Infinispan
Version: 6.4.10
Hardware: Unspecified
OS: Unspecified
unspecified
high
Target Milestone: CR1
: EAP 6.4.16
Assignee: Miroslav Sochurek
QA Contact: Jiří Bílek
URL:
Whiteboard:
Depends On:
Blocks: 1387585 eap6416-payload
TreeView+ depends on / blocked
 
Reported: 2016-09-30 09:14 UTC by Lami Akagwu
Modified: 2020-09-10 09:49 UTC (History)
7 users (show)

Fixed In Version:
Clone Of:
Environment:
Last Closed: 2017-06-22 09:18:22 UTC
Type: Bug
Embargoed:


Attachments (Terms of Use)


Links
System ID Private Priority Status Summary Last Updated
Red Hat Issue Tracker JBEAP-6563 0 Major Resolved (7.0.z) ASYM_ENCRYPT error message "key server is currently not set" should be logged with debug level 2017-10-18 21:06:16 UTC
Red Hat Issue Tracker JBEAP-6564 0 Major Verified (7.1.0) ASYM_ENCRYPT error message "key server is currently not set" should be logged with debug level 2017-10-18 21:06:16 UTC
Red Hat Issue Tracker JGRP-2121 0 Trivial Resolved ASYM_ENCRYPT error message "key server is currently not set" should be logged with debug level 2017-10-18 21:06:16 UTC
Red Hat Knowledge Base (Solution) 2664601 0 None None None 2016-09-30 09:14:30 UTC

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.


Note You need to log in before you can comment on or make changes to this bug.