Strange EJBCA-CE behaivor #698

aloeenmae · 2024-10-28T13:14:21Z

aloeenmae
Oct 28, 2024

Hi!

I have a strange issue with dockerized EJBCA-CE v8.3.2. For some reason loading https://my-ca/ejbca/adminweb and https://my-ca/ejbca/adminweb/ca/cafunctions.xhtml takes over 1 minute. However, opening https://my-ca/ejbca/adminweb/ca/caactivation.xhtml, https://my-ca/ejbca/adminweb/sysconfig/systemconfiguration.xhtml, https://my-ca/ejbca/adminweb/ca/editcertificateprofiles/editcertificateprofiles.xhtml, and all other sub-pages in the EJBCA UI opens instantly.

Logs on the EJBCA container side:

On the UI side I hit CA Functions->CA Structure & CRL tab

2024-10-28 12:57:19,789+0000 DEBUG [org.jboss.jca.core.connectionmanager.pool.validator.ConnectionValidator] (ConnectionValidator) Notifying pools, interval: 15000
2024-10-28 12:57:19,789+0000 DEBUG [org.jboss.jca.core.connectionmanager.pool.strategy.OnePool] (ConnectionValidator) Checking for connection within frequency
2024-10-28 12:57:19,789+0000 DEBUG [org.mariadb.jdbc.client.impl.StandardClient] (ConnectionValidator) execute query: SELECT 1;
2024-10-28 12:57:19,790+0000 DEBUG [org.jboss.jca.core.connectionmanager.pool.strategy.OnePool] (ConnectionValidator) Returning for connection within frequency
2024-10-28 12:57:19,790+0000 DEBUG [org.jboss.jca.core.connectionmanager.pool.strategy.OnePool] (ConnectionValidator) Checking for connection within frequency
2024-10-28 12:57:19,790+0000 DEBUG [org.mariadb.jdbc.client.impl.StandardClient] (ConnectionValidator) execute query: SELECT 1;
2024-10-28 12:57:19,790+0000 DEBUG [org.jboss.jca.core.connectionmanager.pool.strategy.OnePool] (ConnectionValidator) Returning for connection within frequency
2024-10-28 12:57:19,790+0000 DEBUG [org.jboss.jca.core.connectionmanager.pool.strategy.OnePool] (ConnectionValidator) Checking for connection within frequency
2024-10-28 12:57:19,790+0000 DEBUG [org.mariadb.jdbc.client.impl.StandardClient] (ConnectionValidator) execute query: SELECT 1;
2024-10-28 12:57:19,791+0000 DEBUG [org.jboss.jca.core.connectionmanager.pool.strategy.OnePool] (ConnectionValidator) Returning for connection within frequency
2024-10-28 12:57:19,791+0000 DEBUG [org.jboss.jca.core.connectionmanager.pool.strategy.OnePool] (ConnectionValidator) Checking for connection within frequency
2024-10-28 12:57:19,953+0000 DEBUG [io.undertow.request] (default I/O-32) Matched default handler path /ejbca/adminweb/ca/cafunctions.xhtml
2024-10-28 12:57:19,954+0000 DEBUG [io.undertow.request] (default I/O-32) Matched default handler path /ejbca/adminweb/ca/cafunctions.xhtml
2024-10-28 12:57:19,954+0000 DEBUG [io.undertow.request] (default I/O-32) Matched prefix path /ejbca for path /ejbca/adminweb/ca/cafunctions.xhtml
2024-10-28 12:57:19,954+0000 DEBUG [io.undertow.request] (default I/O-32) Matched prefix path /ejbca/adminweb for path /ejbca/adminweb/ca/cafunctions.xhtml
2024-10-28 12:57:19,955+0000 DEBUG [io.undertow.request.security] (default task-2) Security constraints for request /ejbca/adminweb/ca/cafunctions.xhtml are [SingleConstraintMatch{emptyRoleSemantic=PERMIT, requiredRoles=[]}]
2024-10-28 12:57:19,956+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.application] (default task-2) servletPath /ca/cafunctions.xhtml
2024-10-28 12:57:19,956+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.application] (default task-2) pathInfo null
2024-10-28 12:57:19,956+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.application] (default task-2) URL pattern of the FacesServlet executing the current request .xhtml
2024-10-28 12:57:19,956+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.lifecycle] (default task-2) execute(org.primefaces.context.PrimeFacesContext@a9f86b)
2024-10-28 12:57:19,956+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.lifecycle] (default task-2) Entering RestoreViewPhase
2024-10-28 12:57:19,956+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.application] (default task-2) Created component with component type of 'javax.faces.ViewRoot'
2024-10-28 12:57:19,956+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.application] (default task-2) Created component with component type of 'javax.faces.ViewRoot'
2024-10-28 12:57:19,956+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.application] (default task-2) Created component with component type of 'javax.faces.ViewRoot'
2024-10-28 12:57:19,956+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.lifecycle] (default task-2) New request: creating a view for /ca/cafunctions.xhtml
2024-10-28 12:57:19,956+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.application] (default task-2) URL pattern of the FacesServlet executing the current request .xhtml
2024-10-28 12:57:19,956+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.application] (default task-2) URL pattern of the FacesServlet executing the current request .xhtml
2024-10-28 12:57:19,956+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.application] (default task-2) Created component with component type of 'javax.faces.ViewRoot'
2024-10-28 12:57:19,956+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.application] (default task-2) Created new view for /ca/cafunctions.xhtml
2024-10-28 12:57:19,956+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.application] (default task-2) Locale for this view as determined by calculateLocale en_US
2024-10-28 12:57:19,956+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.application] (default task-2) Created component with component type of 'javax.faces.ViewRoot'
2024-10-28 12:57:19,956+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.application] (default task-2) RenderKitId for this view as determined by calculateRenderKitId HTML_BASIC
2024-10-28 12:57:19,957+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.facelets.factory] (default task-2) Resource-Url from external context: file:/opt/keyfactor/wildfly-26.1.3.Final/standalone/tmp/vfs/deployment/deploymentc944ebdc542df01d/adminweb.war-3de0c5b44625e3f9/ca/cafunctions.xhtml
2024-10-28 12:57:19,957+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.resource] (default task-2) Caching ResourceInfo: com.sun.faces.application.resource.FaceletResourceInfo@8c4a540a
2024-10-28 12:57:19,957+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.lifecycle] (default task-2) Exiting RestoreViewPhase
2024-10-28 12:57:19,957+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.timing] (default task-2)  [TIMING] - [1ms] : Execution time for phase (including any PhaseListeners) -> RESTORE_VIEW 1
2024-10-28 12:57:19,957+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.lifecycle] (default task-2) render(org.primefaces.context.PrimeFacesContext@a9f86b)
2024-10-28 12:57:19,957+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.lifecycle] (default task-2) Entering RenderResponsePhase
2024-10-28 12:57:19,957+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.lifecycle] (default task-2) About to render view /ca/cafunctions.xhtml
2024-10-28 12:57:19,957+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.application] (default task-2) Building View: /ca/cafunctions.xhtml
2024-10-28 12:57:19,960+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.application] (default task-2) Created component with component type of 'javax.faces.Output'
2024-10-28 12:57:19,961+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.facelets.tag.component] (default task-2) /WEB-INF/include/template.xhtml @38,23 <h:head> Component[560837884_28d4d1bc] Created: javax.faces.component.UIOutput
2024-10-28 12:57:19,961+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.application] (default task-2) Created component with component type of 'javax.faces.HtmlOutputText'
2024-10-28 12:57:19,961+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.facelets.tag.component] (default task-2) /WEB-INF/include/template.xhtml @41,67 <h:outputText> Component[560837884_28d4d152] Created: javax.faces.component.html.HtmlOutputText
2024-10-28 12:57:19,961+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.application] (default task-2) Created component with component type of 'javax.faces.Output'
2024-10-28 12:57:19,962+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.facelets.tag.component] (default task-2) /WEB-INF/include/template.xhtml @46,85 <h:outputStylesheet> Component[560837884_28d4d15d] Created: javax.faces.component.UIOutput
2024-10-28 12:57:19,962+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.application] (default task-2) Created component with component type of 'javax.faces.ComponentResourceContainer'
2024-10-28 12:57:19,962+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.application] (default task-2) Created component with component type of 'javax.faces.OutputBody'
2024-10-28 12:57:19,963+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.facelets.tag.component] (default task-2) /WEB-INF/include/template.xhtml @48,55 <h:body> Component[560837884_28d4d150] Created: javax.faces.component.html.HtmlBody
2024-10-28 12:57:19,963+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.application] (default task-2) Created component with component type of 'org.primefaces.component.InputText'
2024-10-28 12:57:19,963+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.application] (default task-2) Created component with component type of 'javax.faces.Output'
2024-10-28 12:57:19,963+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.application] (default task-2) Created component with component type of 'javax.faces.Output'
2024-10-28 12:57:19,964+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.application] (default task-2) Created component with component type of 'javax.faces.Output'
2024-10-28 12:57:19,964+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.application] (default task-2) Created component with component type of 'javax.faces.Output'
2024-10-28 12:57:19,964+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.facelets.tag.component] (default task-2) /WEB-INF/include/template.xhtml @50,38 <p:inputText> Component[560837884_28d4d166] Created: org.primefaces.component.inputtext.InputText
2024-10-28 12:57:19,964+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.application] (default task-2) created validator of type 'javax.faces.Bean'

and finally over 1 minute later CA Structure & CRLs page loads:

2024-10-28 12:58:24,438+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.application] (default task-2) Begin writing marker for viewId /ca/cafunctions.xhtml
2024-10-28 12:58:24,438+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.application] (default task-2) End writing marker for viewId /ca/cafunctions.xhtml
2024-10-28 12:58:24,438+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.renderkit] (default task-2) component.getValue() returned MyCAs
2024-10-28 12:58:24,439+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.renderkit] (default task-2) Value to be rendered MyCAs
2024-10-28 12:58:24,439+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.renderkit] (default task-2) component.getValue() returned Root CA:
2024-10-28 12:58:24,440+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.renderkit] (default task-2) Value to be rendered Root CA:
2024-10-28 12:58:24,440+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.renderkit] (default task-2) component.getValue() returned CN=My Root CA,OU=XXX,O=XXX,C=XX
2024-10-28 12:58:24,440+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.renderkit] (default task-2) Value to be rendered CN=XXX Root CA,OU=XXX,O=XXX,C=XX
2024-10-28 12:58:24,441+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.renderkit] (default task-2) component.getValue() returned null
2024-10-28 12:58:24,441+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.renderkit] (default task-2) Value to be rendered null
2024-10-28 12:58:24,441+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.renderkit] (default task-2) Value to be rendered /ejbca/adminweb/ca/cacert?cmd=iecacert&level=1&issuer= "Removed my CA CN part"
2024-10-28 12:58:24,441+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.renderkit] (default task-2) component.getValue() returned Download binary/to IE
2024-10-28 12:58:24,442+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.renderkit] (default task-2) Value to be rendered Download binary/to IE
2024-10-28 12:58:24,442+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.renderkit] (default task-2) Value to be rendered /ejbca/adminweb/ca/cacert?cmd=nscacert&level=1&issuer="Removed my CA CN part"
2024-10-28 12:58:24,446+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.renderkit] (default task-2) component.getValue() returned Download to Firefox
2024-10-28 12:58:24,446+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.renderkit] (default task-2) Value to be rendered Download to Firefox
2024-10-28 12:58:24,446+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.renderkit] (default task-2) Value to be rendered /ejbca/adminweb/ca/cacert?cmd=cacert&level=1&issuer="Removed my CA CN part"
2024-10-28 12:58:24,446+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.renderkit] (default task-2) component.getValue() returned Download PEM file
2024-10-28 12:58:24,447+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.renderkit] (default task-2) Value to be rendered Download PEM file
2024-10-28 12:58:24,447+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.application] (default task-2) URL pattern of the FacesServlet executing the current request .xhtml
2024-10-28 12:58:24,447+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.application] (default task-2) URL pattern of the FacesServlet executing the current request .xhtml
2024-10-28 12:58:24,448+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.renderkit] (default task-2) Value to be rendered Download JKS file
2024-10-28 12:58:24,448+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.application] (default task-2) Begin writing marker for viewId /ca/cafunctions.xhtml
2024-10-28 12:58:24,448+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.application] (default task-2) End writing marker for viewId /ca/cafunctions.xhtml
2024-10-28 12:58:24,449+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.renderkit] (default task-2) component.getValue() returned Subordinate CA, level 1:
2024-10-28 12:58:24,449+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.renderkit] (default task-2) Value to be rendered Subordinate CA, level 1:
2024-10-28 12:58:24,449+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.renderkit] (default task-2) component.getValue() returned CN=XXX,OU=XXX,O=XXX,C=XX
2024-10-28 12:58:24,449+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.renderkit] (default task-2) Value to be rendered CN=XXX,OU=XXX,O=XXX,C=XX
2024-10-28 12:58:24,450+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.renderkit] (default task-2) component.getValue() returned null
2024-10-28 12:58:24,450+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.renderkit] (default task-2) Value to be rendered null
2024-10-28 12:58:24,450+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.renderkit] (default task-2) Value to be rendered /ejbca/adminweb/ca/cacert?cmd=iecacert&level=0&issuer="Removed my CA CN part"
2024-10-28 12:58:24,451+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.renderkit] (default task-2) component.getValue() returned Download binary/to IE
2024-10-28 12:58:24,451+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.renderkit] (default task-2) Value to be rendered Download binary/to IE
2024-10-28 12:58:24,451+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.renderkit] (default task-2) Value to be rendered /ejbca/adminweb/ca/cacert?cmd=nscacert&level=0&issuer="Removed my CA CN part"
2024-10-28 12:58:24,451+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.renderkit] (default task-2) component.getValue() returned Download to Firefox
2024-10-28 12:58:24,452+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.renderkit] (default task-2) Value to be rendered Download to Firefox
2024-10-28 12:58:24,452+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.renderkit] (default task-2) Value to be rendered /ejbca/adminweb/ca/cacert?cmd=cacert&level=0&issuer="Removed my CA CN part"
2024-10-28 12:58:24,452+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.renderkit] (default task-2) component.getValue() returned Download PEM file
2024-10-28 12:58:24,453+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.renderkit] (default task-2) Value to be rendered Download PEM file
2024-10-28 12:58:24,453+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.application] (default task-2) URL pattern of the FacesServlet executing the current request .xhtml
2024-10-28 12:58:24,453+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.application] (default task-2) URL pattern of the FacesServlet executing the current request .xhtml
2024-10-28 12:58:24,454+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.renderkit] (default task-2) Value to be rendered Download JKS file
2024-10-28 12:58:24,454+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.application] (default task-2) Begin writing marker for viewId /ca/cafunctions.xhtml
2024-10-28 12:58:24,454+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.application] (default task-2) End writing marker for viewId /ca/cafunctions.xhtml
2024-10-28 12:58:24,455+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.renderkit] (default task-2) component.getValue() returned Latest CRL:
2024-10-28 12:58:24,455+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.renderkit] (default task-2) Value to be rendered Latest CRL:
2024-10-28 12:58:24,455+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.renderkit] (default task-2) component.getValue() returned Created
2024-10-28 12:58:24,455+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.renderkit] (default task-2) Value to be rendered Created
2024-10-28 12:58:24,456+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.renderkit] (default task-2) component.getValue() returned Expires
2024-10-28 12:58:24,456+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.renderkit] (default task-2) Value to be rendered Expires
2024-10-28 12:58:24,456+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.renderkit] (default task-2) component.getValue() returned Partition
2024-10-28 12:58:24,457+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.renderkit] (default task-2) Value to be rendered Partition
2024-10-28 12:58:24,457+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.renderkit] (default task-2) component.getValue() returned Number
2024-10-28 12:58:24,457+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.renderkit] (default task-2) Value to be rendered Number
2024-10-28 12:58:24,458+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.renderkit] (default task-2) component.getValue() returned 2024-10-28 12:20:49+00:00
2024-10-28 12:58:24,458+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.renderkit] (default task-2) Value to be rendered 2024-10-28 12:20:49+00:00
2024-10-28 12:58:24,459+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.renderkit] (default task-2) component.getValue() returned  2024-10-31 12:20:48+00:00
2024-10-28 12:58:24,459+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.renderkit] (default task-2) Value to be rendered  2024-10-31 12:20:48+00:00
2024-10-28 12:58:24,460+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.renderkit] (default task-2) component.getValue() returned 0
2024-10-28 12:58:24,460+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.application] (default task-2) Created converter of type 'org.primefaces.convert.IntegerConverter'
2024-10-28 12:58:24,460+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.renderkit] (default task-2) Value to be rendered 0
2024-10-28 12:58:24,460+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.renderkit] (default task-2) component.getValue() returned  15008
2024-10-28 12:58:24,461+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.renderkit] (default task-2) Value to be rendered  15008
2024-10-28 12:58:24,465+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.renderkit] (default task-2) Value to be rendered /ejbca/adminweb/ca/getcrl/getcrl?cmd=crl&issuer="Removed my CA CN part"
2024-10-28 12:58:24,465+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.renderkit] (default task-2) component.getValue() returned  Get CRL
2024-10-28 12:58:24,465+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.renderkit] (default task-2) Value to be rendered  Get CRL
2024-10-28 12:58:24,466+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.renderkit] (default task-2) component.getValue() returned Delta CRLs are not enabled.
2024-10-28 12:58:24,466+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.renderkit] (default task-2) Value to be rendered Delta CRLs are not enabled.
2024-10-28 12:58:24,466+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.application] (default task-2) URL pattern of the FacesServlet executing the current request .xhtml
2024-10-28 12:58:24,466+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.application] (default task-2) URL pattern of the FacesServlet executing the current request .xhtml
2024-10-28 12:58:24,467+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.renderkit] (default task-2) component.getValue() returned Create a new updated CRL :
2024-10-28 12:58:24,467+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.renderkit] (default task-2) Value to be rendered Create a new updated CRL :
2024-10-28 12:58:24,467+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.application] (default task-2) Begin writing marker for viewId /ca/cafunctions.xhtml
2024-10-28 12:58:24,468+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.application] (default task-2) End writing marker for viewId /ca/cafunctions.xhtml
2024-10-28 12:58:24,468+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.renderkit] (default task-2) component.getValue() returned ? 2002?2024. EJBCA? is a registered trademark.
2024-10-28 12:58:24,468+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.renderkit] (default task-2) Value to be rendered ? 2002?2024. EJBCA? is a registered trademark.
2024-10-28 12:58:24,469+0000 FINE  [javax.faces.component] (default task-2) javax_faces_location_HEAD
2024-10-28 12:58:24,469+0000 FINE  [javax.faces.component] (default task-2) j_idt18
2024-10-28 12:58:24,470+0000 FINE  [javax.faces.component] (default task-2) supervisionAuditsearch
2024-10-28 12:58:24,470+0000 FINE  [javax.faces.component] (default task-2) sysFuncsPeerSystems
2024-10-28 12:58:24,470+0000 FINE  [javax.faces.component] (default task-2) sysConfigAcme
2024-10-28 12:58:24,470+0000 FINE  [javax.faces.component] (default task-2) sysConfigAutoenroll
2024-10-28 12:58:24,471+0000 FINE  [javax.faces.component] (default task-2) sysConfigEst
2024-10-28 12:58:24,471+0000 FINE  [javax.faces.component] (default task-2) sysConfigUpgrade
2024-10-28 12:58:24,471+0000 FINE  [javax.faces.component] (default task-2) j_idt19
2024-10-28 12:58:24,471+0000 FINE  [javax.faces.component] (default task-2) mainDocumentation
2024-10-28 12:58:24,472+0000 FINE  [javax.faces.component] (default task-2) mainLogout
2024-10-28 12:58:24,472+0000 FINE  [javax.faces.component] (default task-2) j_idt34
2024-10-28 12:58:24,472+0000 FINE  [javax.faces.component] (default task-2) j_idt42
2024-10-28 12:58:24,472+0000 FINE  [javax.faces.component] (default task-2) j_idt49
2024-10-28 12:58:24,473+0000 FINE  [javax.faces.component] (default task-2) j_idt52
2024-10-28 12:58:24,473+0000 FINE  [javax.faces.component] (default task-2) j_idt87
2024-10-28 12:58:24,473+0000 FINE  [javax.faces.component] (default task-2) j_idt89
2024-10-28 12:58:24,473+0000 FINE  [javax.faces.component] (default task-2) j_idt91
2024-10-28 12:58:24,474+0000 FINE  [javax.faces.component] (default task-2) j_idt97
2024-10-28 12:58:24,474+0000 FINE  [javax.faces.component] (default task-2) j_idt100
2024-10-28 12:58:24,474+0000 FINE  [javax.faces.component] (default task-2) j_idt110
2024-10-28 12:58:24,474+0000 FINE  [javax.faces.component] (default task-2) j_idt113
2024-10-28 12:58:24,475+0000 FINE  [javax.faces.component] (default task-2) j_idt116
2024-10-28 12:58:24,475+0000 FINE  [javax.faces.component] (default task-2) j_id1
2024-10-28 12:58:24,480+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.lifecycle] (default task-2) Exiting RenderResponsePhase
2024-10-28 12:58:24,482+0000 FINE  [javax.enterprise.resource.webcontainer.jsf.timing] (default task-2)  [TIMING] - [64525ms] : Execution time for phase (including any PhaseListeners) -> RENDER_RESPONSE 6
2024-10-28 12:58:24,648+0000 DEBUG [io.undertow.request] (default I/O-55) Matched default handler path /ejbca/adminweb/images/CE/favicon.png
2024-10-28 12:58:24,649+0000 DEBUG [io.undertow.request] (default I/O-55) Matched default handler path /ejbca/adminweb/images/CE/favicon.png
2024-10-28 12:58:24,649+0000 DEBUG [io.undertow.request] (default I/O-55) Matched prefix path /ejbca for path /ejbca/adminweb/images/CE/favicon.png
2024-10-28 12:58:24,649+0000 DEBUG [io.undertow.request] (default I/O-55) Matched prefix path /ejbca/adminweb for path /ejbca/adminweb/images/CE/favicon.png
2024-10-28 12:58:24,650+0000 DEBUG [io.undertow.request.security] (default task-2) Security constraints for request /ejbca/adminweb//images/CE/favicon.png are [SingleConstraintMatch{emptyRoleSemantic=PERMIT, requiredRoles=[]}]
2024-10-28 12:58:33,767+0000 DEBUG [org.jboss.jca.core.connectionmanager.pool.idle.IdleRemover] (IdleRemover) Notifying pools, interval: 90000
2024-10-28 12:58:33,768+0000 DEBUG [org.jboss.jca.core.connectionmanager.pool.strategy.OnePool] (IdleRemover) appds: removeIdleConnections(1730120133768) [0/150]
2024-10-28 12:58:34,813+0000 DEBUG [org.jboss.jca.core.connectionmanager.pool.validator.ConnectionValidator] (ConnectionValidator) Notifying pools, interval: 15000
2024-10-28 12:58:34,814+0000 DEBUG [org.jboss.jca.core.connectionmanager.pool.strategy.OnePool] (ConnectionValidator) Checking for connection within frequency

Any ideas why loading the main home page and the CA Structure & CRLs page takes so much time?

Thanks!

Answered by aloeenmae

Oct 29, 2024

@primetomas, thank you very much! Indeed, at least one index was missing.

-- Index to ensure CRL generation is not slowed down when looking for the next CRL Number, even of you have hundreds of thousands of old CRLs in the DB
CREATE INDEX crldata_idx5 ON CRLData(cRLNumber, issuerDN, crlPartitionIndex);
CREATE UNIQUE INDEX crldata_idx6 ON CRLData(issuerDN, crlPartitionIndex, deltaCRLIndicator, cRLNumber);

View full answer

primetomas · 2024-10-28T14:29:28Z

primetomas
Oct 28, 2024
Maintainer

What kind of debug settings did you enable in order to get that log? (it's excessive).

You'll have to give some more information about your environment and installation.

For me with a no-frills simple stastup of the container everything is very fast.

5 replies

primetomas Oct 28, 2024
Maintainer

Do you have a lot of data in your database?
Do you have the recommended database indexes applied?

aloeenmae Oct 28, 2024
Author

I have 8.6 million records in my AuditRecordData table.

select count(*) from AuditRecordData;
+----------+
| count(*) |
+----------+
|  8613237 |
+----------+

I also have 87,000 records for 5 CA’s CRLs.

select count(*) from CRLData;
+----------+
| count(*) |
+----------+
|    87271 |
+----------+

However, my production environment (which still runs on standalone EJBCA-CE v7) is double that size without any issues. I don’t understand why only the main home page and the CA Structure & CRLs page take so much time.

@primetomas , could you maybe specify which commands I should provide the output for?"

primetomas Oct 29, 2024
Maintainer

Ensure you have indexes in your database.
https://github.com/Keyfactor/ejbca-ce/blob/main/doc/sql-scripts/create-index-ejbca.sql

aloeenmae Oct 29, 2024
Author

@primetomas, thank you very much! Indeed, at least one index was missing.

-- Index to ensure CRL generation is not slowed down when looking for the next CRL Number, even of you have hundreds of thousands of old CRLs in the DB
CREATE INDEX crldata_idx5 ON CRLData(cRLNumber, issuerDN, crlPartitionIndex);
CREATE UNIQUE INDEX crldata_idx6 ON CRLData(issuerDN, crlPartitionIndex, deltaCRLIndicator, cRLNumber);

Answer selected by aloeenmae

Provide feedback

Saved searches

Use saved searches to filter your results more quickly

Strange EJBCA-CE behaivor #698

{{title}}

Replies: 1 comment 5 replies

{{title}}

{{title}}

{{title}}

{{title}}

{{title}}

Select a reply

Strange EJBCA-CE behaivor #698

aloeenmae Oct 28, 2024

Replies: 1 comment · 5 replies

primetomas Oct 28, 2024 Maintainer

primetomas Oct 28, 2024 Maintainer

aloeenmae Oct 28, 2024 Author

primetomas Oct 29, 2024 Maintainer

aloeenmae Oct 29, 2024 Author

aloeenmae
Oct 28, 2024

Replies: 1 comment 5 replies

primetomas
Oct 28, 2024
Maintainer

primetomas Oct 28, 2024
Maintainer

aloeenmae Oct 28, 2024
Author

primetomas Oct 29, 2024
Maintainer

aloeenmae Oct 29, 2024
Author