[17/Aug/2016:07:55:01][localhost-startStop-1]: ============================================ [17/Aug/2016:07:55:01][localhost-startStop-1]: ===== DEBUG SUBSYSTEM INITIALIZED ======= [17/Aug/2016:07:55:01][localhost-startStop-1]: ============================================ [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: restart at autoShutdown? false [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: autoShutdown crumb file path? /var/lib/pki/pki-tomcat/logs/autoShutdown.crumb [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: about to look for cert for auto-shutdown support:auditSigningCert cert-pki-tomcat [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: cert not found:auditSigningCert cert-pki-tomcat [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: Exception:org.mozilla.jss.crypto.ObjectNotFoundException [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: done init id=debug [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: initialized debug [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: initSubsystem id=log [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: ready to init id=log [17/Aug/2016:07:55:01][localhost-startStop-1]: Creating RollingLogFile(/var/lib/pki/pki-tomcat/logs/kra/signedAudit/kra_cert-kra_audit) [17/Aug/2016:07:55:01][localhost-startStop-1]: Creating RollingLogFile(/var/lib/pki/pki-tomcat/logs/kra/system) [17/Aug/2016:07:55:01][localhost-startStop-1]: Creating RollingLogFile(/var/lib/pki/pki-tomcat/logs/kra/transactions) [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: restart at autoShutdown? false [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: autoShutdown crumb file path? /var/lib/pki/pki-tomcat/logs/autoShutdown.crumb [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: about to look for cert for auto-shutdown support:auditSigningCert cert-pki-tomcat [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: cert not found:auditSigningCert cert-pki-tomcat [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: Exception:org.mozilla.jss.crypto.ObjectNotFoundException [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: done init id=log [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: initialized log [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: initSubsystem id=jss [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: ready to init id=jss [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: restart at autoShutdown? false [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: autoShutdown crumb file path? /var/lib/pki/pki-tomcat/logs/autoShutdown.crumb [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: about to look for cert for auto-shutdown support:auditSigningCert cert-pki-tomcat [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: cert not found:auditSigningCert cert-pki-tomcat [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: Exception:org.mozilla.jss.crypto.ObjectNotFoundException [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: done init id=jss [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: initialized jss [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: initSubsystem id=dbs [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: ready to init id=dbs [17/Aug/2016:07:55:01][localhost-startStop-1]: DBSubsystem: init() mEnableSerialMgmt=false [17/Aug/2016:07:55:01][localhost-startStop-1]: Creating LdapBoundConnFactor(DBSubsystem) [17/Aug/2016:07:55:01][localhost-startStop-1]: LdapBoundConnFactory: init [17/Aug/2016:07:55:01][localhost-startStop-1]: DBSubsystem: Ignore EPropertyNotDefined during pre-op: Property internaldb.ldapconn.port missing value [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: restart at autoShutdown? false [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: autoShutdown crumb file path? /var/lib/pki/pki-tomcat/logs/autoShutdown.crumb [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: about to look for cert for auto-shutdown support:auditSigningCert cert-pki-tomcat [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: cert not found:auditSigningCert cert-pki-tomcat [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: Exception:org.mozilla.jss.crypto.ObjectNotFoundException [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: done init id=dbs [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: initialized dbs [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: initSubsystem id=usrgrp [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: ready to init id=usrgrp [17/Aug/2016:07:55:01][localhost-startStop-1]: Creating LdapBoundConnFactor(UGSubsystem) [17/Aug/2016:07:55:01][localhost-startStop-1]: LdapBoundConnFactory: init Property internaldb.ldapconn.port missing value at com.netscape.cmscore.base.PropConfigStore.getInteger(PropConfigStore.java:452) at com.netscape.cmscore.ldapconn.LdapConnInfo.init(LdapConnInfo.java:55) at com.netscape.cmscore.ldapconn.LdapConnInfo.(LdapConnInfo.java:45) at com.netscape.cmscore.ldapconn.LdapBoundConnFactory.init(LdapBoundConnFactory.java:123) at com.netscape.cmscore.usrgrp.UGSubsystem.init(UGSubsystem.java:141) at com.netscape.cmscore.apps.CMSEngine.initSubsystem(CMSEngine.java:1169) at com.netscape.cmscore.apps.CMSEngine.initSubsystems(CMSEngine.java:1075) at com.netscape.cmscore.apps.CMSEngine.init(CMSEngine.java:571) at com.netscape.certsrv.apps.CMS.init(CMS.java:187) at com.netscape.certsrv.apps.CMS.start(CMS.java:1616) at com.netscape.cms.servlet.base.CMSStartServlet.init(CMSStartServlet.java:114) at javax.servlet.GenericServlet.init(GenericServlet.java:158) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at org.apache.catalina.security.SecurityUtil$1.run(SecurityUtil.java:293) at org.apache.catalina.security.SecurityUtil$1.run(SecurityUtil.java:290) at java.security.AccessController.doPrivileged(Native Method) at javax.security.auth.Subject.doAsPrivileged(Subject.java:549) at org.apache.catalina.security.SecurityUtil.execute(SecurityUtil.java:325) at org.apache.catalina.security.SecurityUtil.doAsPrivilege(SecurityUtil.java:176) at org.apache.catalina.security.SecurityUtil.doAsPrivilege(SecurityUtil.java:124) at org.apache.catalina.core.StandardWrapper.initServlet(StandardWrapper.java:1226) at org.apache.catalina.core.StandardWrapper.loadServlet(StandardWrapper.java:1151) at org.apache.catalina.core.StandardWrapper.load(StandardWrapper.java:1038) at org.apache.catalina.core.StandardContext.loadOnStartup(StandardContext.java:5027) at org.apache.catalina.core.StandardContext.startInternal(StandardContext.java:5337) at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:147) at org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:725) at org.apache.catalina.core.ContainerBase.access$000(ContainerBase.java:131) at org.apache.catalina.core.ContainerBase$PrivilegedAddChild.run(ContainerBase.java:153) at org.apache.catalina.core.ContainerBase$PrivilegedAddChild.run(ContainerBase.java:143) at java.security.AccessController.doPrivileged(Native Method) at org.apache.catalina.core.ContainerBase.addChild(ContainerBase.java:699) at org.apache.catalina.core.StandardHost.addChild(StandardHost.java:717) at org.apache.catalina.startup.HostConfig.deployDescriptor(HostConfig.java:587) at org.apache.catalina.startup.HostConfig$DeployDescriptor.run(HostConfig.java:1798) at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) at java.util.concurrent.FutureTask.run(FutureTask.java:266) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) at java.lang.Thread.run(Thread.java:745) [17/Aug/2016:07:55:01][localhost-startStop-1]: UGSubsystem.init(): Swallow exception in pre-op mode [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: restart at autoShutdown? false [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: autoShutdown crumb file path? /var/lib/pki/pki-tomcat/logs/autoShutdown.crumb [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: about to look for cert for auto-shutdown support:auditSigningCert cert-pki-tomcat [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: cert not found:auditSigningCert cert-pki-tomcat [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: Exception:org.mozilla.jss.crypto.ObjectNotFoundException [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: done init id=usrgrp [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: initialized usrgrp [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: initSubsystem id=registry [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: ready to init id=registry [17/Aug/2016:07:55:01][localhost-startStop-1]: RegistrySubsystem: start init [17/Aug/2016:07:55:01][localhost-startStop-1]: PluginRegistry: no types [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: restart at autoShutdown? false [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: autoShutdown crumb file path? /var/lib/pki/pki-tomcat/logs/autoShutdown.crumb [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: about to look for cert for auto-shutdown support:auditSigningCert cert-pki-tomcat [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: cert not found:auditSigningCert cert-pki-tomcat [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: Exception:org.mozilla.jss.crypto.ObjectNotFoundException [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: done init id=registry [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: initialized registry [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: initSubsystem id=oidmap [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: ready to init id=oidmap [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: restart at autoShutdown? false [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: autoShutdown crumb file path? /var/lib/pki/pki-tomcat/logs/autoShutdown.crumb [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: about to look for cert for auto-shutdown support:auditSigningCert cert-pki-tomcat [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: cert not found:auditSigningCert cert-pki-tomcat [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: Exception:org.mozilla.jss.crypto.ObjectNotFoundException [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: done init id=oidmap [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: initialized oidmap [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: initSubsystem id=X500Name [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: ready to init id=X500Name [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: restart at autoShutdown? false [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: autoShutdown crumb file path? /var/lib/pki/pki-tomcat/logs/autoShutdown.crumb [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: about to look for cert for auto-shutdown support:auditSigningCert cert-pki-tomcat [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: cert not found:auditSigningCert cert-pki-tomcat [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: Exception:org.mozilla.jss.crypto.ObjectNotFoundException [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: done init id=X500Name [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: initialized X500Name [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: initSubsystem id=request [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: ready to init id=request [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: restart at autoShutdown? false [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: autoShutdown crumb file path? /var/lib/pki/pki-tomcat/logs/autoShutdown.crumb [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: about to look for cert for auto-shutdown support:auditSigningCert cert-pki-tomcat [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: cert not found:auditSigningCert cert-pki-tomcat [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: Exception:org.mozilla.jss.crypto.ObjectNotFoundException [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: done init id=request [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: initialized request [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: initSubsystem id=kra [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: ready to init id=kra [17/Aug/2016:07:55:01][localhost-startStop-1]: KeyRecoveryAuthority init() begins [17/Aug/2016:07:55:01][localhost-startStop-1]: GenericPolicyProcessor::init begins [17/Aug/2016:07:55:01][localhost-startStop-1]: GenericPolicyProcessor::init Certificate Policy Framework (deprecated) is DISABLED [17/Aug/2016:07:55:01][localhost-startStop-1]: EncryptionUnit.EncryptionUnit this: com.netscape.kra.TransportKeyUnit@200b0ae0 [17/Aug/2016:07:55:01][localhost-startStop-1]: KeyRecoveryAuthority: transport unit exception Internal Error: org.mozilla.jss.crypto.ObjectNotFoundException [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: restart at autoShutdown? false [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: autoShutdown crumb file path? /var/lib/pki/pki-tomcat/logs/autoShutdown.crumb [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: about to look for cert for auto-shutdown support:auditSigningCert cert-pki-tomcat [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: cert not found:auditSigningCert cert-pki-tomcat [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: Exception:org.mozilla.jss.crypto.ObjectNotFoundException [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: done init id=kra [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: initialized kra [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine::initSubsystem kra Java subsytem about to calculate serverCertNickname. [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: initSubsystem: excludedLdapAttrs.enabled: false [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: initSubsystem id=selftests [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: ready to init id=selftests [17/Aug/2016:07:55:01][localhost-startStop-1]: SelfTestSubsystem::init(): ENTERING . . . [17/Aug/2016:07:55:01][localhost-startStop-1]: SelfTestSubsystem::init(): loading self test logger parameters [17/Aug/2016:07:55:01][localhost-startStop-1]: Creating RollingLogFile(/var/lib/pki/pki-tomcat/logs/kra/selftests.log) [17/Aug/2016:07:55:01][localhost-startStop-1]: SelfTestSubsystem::init(): loading self test plugins [17/Aug/2016:07:55:01][localhost-startStop-1]: SelfTestSubsystem::init(): loading self test plugin parameters [17/Aug/2016:07:55:01][localhost-startStop-1]: SelfTestSubsystem::init(): loading on demand self tests [17/Aug/2016:07:55:01][localhost-startStop-1]: SelfTestSubsystem::init(): loading startup self tests [17/Aug/2016:07:55:01][localhost-startStop-1]: SelfTestSubsystem::init(): EXITING. [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: restart at autoShutdown? false [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: autoShutdown crumb file path? /var/lib/pki/pki-tomcat/logs/autoShutdown.crumb [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: about to look for cert for auto-shutdown support:auditSigningCert cert-pki-tomcat [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: cert not found:auditSigningCert cert-pki-tomcat [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: Exception:org.mozilla.jss.crypto.ObjectNotFoundException [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: done init id=selftests [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: initialized selftests [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: initSubsystem id=stats [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: ready to init id=stats [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: restart at autoShutdown? false [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: autoShutdown crumb file path? /var/lib/pki/pki-tomcat/logs/autoShutdown.crumb [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: about to look for cert for auto-shutdown support:auditSigningCert cert-pki-tomcat [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: cert not found:auditSigningCert cert-pki-tomcat [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: Exception:org.mozilla.jss.crypto.ObjectNotFoundException [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: done init id=stats [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: initialized stats [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: initSubsystem id=auths [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: ready to init id=auths [17/Aug/2016:07:55:01][localhost-startStop-1]: PasswdUserDBAuthentication.init(): Abort due to missing LDAP connection info in pre-op mode [17/Aug/2016:07:55:01][localhost-startStop-1]: AuthSubsystem: initializing authentication manager AgentCertAuth [17/Aug/2016:07:55:01][localhost-startStop-1]: AuthSubsystem: initializing authentication manager TokenAuth [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: restart at autoShutdown? false [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: autoShutdown crumb file path? /var/lib/pki/pki-tomcat/logs/autoShutdown.crumb [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: about to look for cert for auto-shutdown support:auditSigningCert cert-pki-tomcat [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: cert not found:auditSigningCert cert-pki-tomcat [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: Exception:org.mozilla.jss.crypto.ObjectNotFoundException [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: done init id=auths [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: initialized auths [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: initSubsystem id=authz [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: ready to init id=authz [17/Aug/2016:07:55:01][localhost-startStop-1]: Found authz manager plugin BasicAclAuthz [17/Aug/2016:07:55:01][localhost-startStop-1]: AAclAuthz: init begins [17/Aug/2016:07:55:01][localhost-startStop-1]: GroupAccessEvaluator: init [17/Aug/2016:07:55:01][localhost-startStop-1]: UserAccessEvaluator: init [17/Aug/2016:07:55:01][localhost-startStop-1]: Found authz manager plugin DirAclAuthz [17/Aug/2016:07:55:01][localhost-startStop-1]: AAclAuthz: init begins [17/Aug/2016:07:55:01][localhost-startStop-1]: GroupAccessEvaluator: init [17/Aug/2016:07:55:01][localhost-startStop-1]: UserAccessEvaluator: init [17/Aug/2016:07:55:01][localhost-startStop-1]: Creating LdapBoundConnFactor(DirAclAuthz) [17/Aug/2016:07:55:01][localhost-startStop-1]: LdapBoundConnFactory: init Property internaldb.ldapconn.port missing value at com.netscape.cmscore.base.PropConfigStore.getInteger(PropConfigStore.java:452) at com.netscape.cmscore.ldapconn.LdapConnInfo.init(LdapConnInfo.java:55) at com.netscape.cmscore.ldapconn.LdapConnInfo.(LdapConnInfo.java:45) at com.netscape.cmscore.ldapconn.LdapBoundConnFactory.init(LdapBoundConnFactory.java:123) at com.netscape.cms.authorization.DirAclAuthz.init(DirAclAuthz.java:150) at com.netscape.cmscore.authorization.AuthzSubsystem.init(AuthzSubsystem.java:137) at com.netscape.cmscore.apps.CMSEngine.initSubsystem(CMSEngine.java:1169) at com.netscape.cmscore.apps.CMSEngine.initSubsystems(CMSEngine.java:1075) at com.netscape.cmscore.apps.CMSEngine.init(CMSEngine.java:582) at com.netscape.certsrv.apps.CMS.init(CMS.java:187) at com.netscape.certsrv.apps.CMS.start(CMS.java:1616) at com.netscape.cms.servlet.base.CMSStartServlet.init(CMSStartServlet.java:114) at javax.servlet.GenericServlet.init(GenericServlet.java:158) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at org.apache.catalina.security.SecurityUtil$1.run(SecurityUtil.java:293) at org.apache.catalina.security.SecurityUtil$1.run(SecurityUtil.java:290) at java.security.AccessController.doPrivileged(Native Method) at javax.security.auth.Subject.doAsPrivileged(Subject.java:549) at org.apache.catalina.security.SecurityUtil.execute(SecurityUtil.java:325) at org.apache.catalina.security.SecurityUtil.doAsPrivilege(SecurityUtil.java:176) at org.apache.catalina.security.SecurityUtil.doAsPrivilege(SecurityUtil.java:124) at org.apache.catalina.core.StandardWrapper.initServlet(StandardWrapper.java:1226) at org.apache.catalina.core.StandardWrapper.loadServlet(StandardWrapper.java:1151) at org.apache.catalina.core.StandardWrapper.load(StandardWrapper.java:1038) at org.apache.catalina.core.StandardContext.loadOnStartup(StandardContext.java:5027) at org.apache.catalina.core.StandardContext.startInternal(StandardContext.java:5337) at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:147) at org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:725) at org.apache.catalina.core.ContainerBase.access$000(ContainerBase.java:131) at org.apache.catalina.core.ContainerBase$PrivilegedAddChild.run(ContainerBase.java:153) at org.apache.catalina.core.ContainerBase$PrivilegedAddChild.run(ContainerBase.java:143) at java.security.AccessController.doPrivileged(Native Method) at org.apache.catalina.core.ContainerBase.addChild(ContainerBase.java:699) at org.apache.catalina.core.StandardHost.addChild(StandardHost.java:717) at org.apache.catalina.startup.HostConfig.deployDescriptor(HostConfig.java:587) at org.apache.catalina.startup.HostConfig$DeployDescriptor.run(HostConfig.java:1798) at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) at java.util.concurrent.FutureTask.run(FutureTask.java:266) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) at java.lang.Thread.run(Thread.java:745) [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: restart at autoShutdown? false [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: autoShutdown crumb file path? /var/lib/pki/pki-tomcat/logs/autoShutdown.crumb [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: about to look for cert for auto-shutdown support:auditSigningCert cert-pki-tomcat [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: cert not found:auditSigningCert cert-pki-tomcat [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: Exception:org.mozilla.jss.crypto.ObjectNotFoundException [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: done init id=authz [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: initialized authz [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: initSubsystem id=jobsScheduler [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: ready to init id=jobsScheduler [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: restart at autoShutdown? false [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: autoShutdown crumb file path? /var/lib/pki/pki-tomcat/logs/autoShutdown.crumb [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: about to look for cert for auto-shutdown support:auditSigningCert cert-pki-tomcat [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: cert not found:auditSigningCert cert-pki-tomcat [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: Exception:org.mozilla.jss.crypto.ObjectNotFoundException [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: done init id=jobsScheduler [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: initialized jobsScheduler [17/Aug/2016:07:55:01][localhost-startStop-1]: Java version=1.8.0_91 [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: Java Security Provider 0 class=Mozilla-JSS version 4.2.6 [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: Java Security Provider 1 class=SUN version 1.8 [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: Java Security Provider 2 class=SunRsaSign version 1.8 [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: Java Security Provider 3 class=SunEC version 1.8 [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: Java Security Provider 4 class=SunJSSE version 1.8 [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: Java Security Provider 5 class=SunJCE version 1.8 [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: Java Security Provider 6 class=SunJGSS version 1.8 [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: Java Security Provider 7 class=SunSASL version 1.8 [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: Java Security Provider 8 class=XMLDSig version 1.8 [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: Java Security Provider 9 class=SunPCSC version 1.8 [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: Java Security Provider 10 class=CMS version 1.0 [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: debug startup start [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: debug startup done [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: log startup start [17/Aug/2016:07:55:01][localhost-startStop-1]: entering LogSubsystem.startup() [17/Aug/2016:07:55:01][localhost-startStop-1]: about to call inst=Transactions in LogSubsystem.startup() [17/Aug/2016:07:55:01][localhost-startStop-1]: LogFile: entering LogFile.startup() [17/Aug/2016:07:55:01][localhost-startStop-1]: about to call inst=SignedAudit in LogSubsystem.startup() [17/Aug/2016:07:55:01][localhost-startStop-1]: LogFile: entering LogFile.startup() [17/Aug/2016:07:55:01][localhost-startStop-1]: about to call inst=System in LogSubsystem.startup() [17/Aug/2016:07:55:01][localhost-startStop-1]: LogFile: entering LogFile.startup() [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: log startup done [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: jss startup start [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: jss startup done [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: dbs startup start [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: dbs startup done [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: usrgrp startup start [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: usrgrp startup done [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: registry startup start [17/Aug/2016:07:55:01][localhost-startStop-1]: RegistrySubsystem: startup [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: registry startup done [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: oidmap startup start [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: oidmap startup done [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: X500Name startup start [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: X500Name startup done [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: request startup start [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: request startup done [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: kra startup start [17/Aug/2016:07:55:01][localhost-startStop-1]: KeyRecoveryAuthority startup() begins [17/Aug/2016:07:55:01][localhost-startStop-1]: KeyRecoveryAuthority: mRequestQueue is null, could be in preop mode [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: kra startup done [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: selftests startup start [17/Aug/2016:07:55:01][localhost-startStop-1]: SelfTestSubsystem.startup(): Do not run selftests in pre-op mode [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: selftests startup done [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: stats startup start [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: stats startup done [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: auths startup start [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: auths startup done [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: authz startup start [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: authz startup done [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: jobsScheduler startup start [17/Aug/2016:07:55:01][localhost-startStop-1]: CMSEngine: jobsScheduler startup done [17/Aug/2016:07:55:01][http-bio-8443-exec-1]: according to ccMode, authorization for servlet: kraGetStatus is LDAP based, not XML {1}, use default authz mgr: {2}. [17/Aug/2016:07:55:02][http-bio-8443-exec-1]: CMSServlet:service() uri = /kra/admin/kra/getStatus [17/Aug/2016:07:55:02][http-bio-8443-exec-1]: CMSServlet: kraGetStatus start to service. [17/Aug/2016:07:55:02][http-bio-8443-exec-1]: CMSServlet: curDate=Wed Aug 17 07:55:02 CEST 2016 id=kraGetStatus time=25 [17/Aug/2016:07:55:03][http-bio-8443-exec-3]: SessionContextInterceptor: SystemConfigResource.configure() [17/Aug/2016:07:55:03][http-bio-8443-exec-3]: SessionContextInterceptor: Not authenticated. [17/Aug/2016:07:55:03][http-bio-8443-exec-3]: AuthMethodInterceptor: SystemConfigResource.configure() [17/Aug/2016:07:55:03][http-bio-8443-exec-3]: AuthMethodInterceptor: mapping: default [17/Aug/2016:07:55:03][http-bio-8443-exec-3]: AuthMethodInterceptor: loading /usr/share/pki/kra/conf/auth-method.properties [17/Aug/2016:07:55:03][http-bio-8443-exec-3]: AuthMethodInterceptor: checking /var/lib/pki/pki-tomcat/kra/conf/auth-method.properties [17/Aug/2016:07:55:03][http-bio-8443-exec-3]: AuthMethodInterceptor: required auth methods: [*] [17/Aug/2016:07:55:03][http-bio-8443-exec-3]: AuthMethodInterceptor: anonymous access allowed [17/Aug/2016:07:55:03][http-bio-8443-exec-3]: ACLInterceptor: SystemConfigResource.configure() [17/Aug/2016:07:55:03][http-bio-8443-exec-3]: ACLInterceptor.filter: no authorization required [17/Aug/2016:07:55:03][http-bio-8443-exec-3]: ACLInterceptor: No ACL mapping; authz not required. [17/Aug/2016:07:55:03][http-bio-8443-exec-3]: SignedAuditEventFactory: create() message created for eventType=AUTHZ_SUCCESS [17/Aug/2016:07:55:03][http-bio-8443-exec-3]: MessageFormatInterceptor: SystemConfigResource.configure() [17/Aug/2016:07:55:03][http-bio-8443-exec-3]: MessageFormatInterceptor: content-type: application/json [17/Aug/2016:07:55:03][http-bio-8443-exec-3]: MessageFormatInterceptor: accept: [application/json] [17/Aug/2016:07:55:03][http-bio-8443-exec-3]: MessageFormatInterceptor: request format: application/json [17/Aug/2016:07:55:03][http-bio-8443-exec-3]: MessageFormatInterceptor: response format: application/json [17/Aug/2016:07:55:03][http-bio-8443-exec-3]: SystemConfigService: configure() [17/Aug/2016:07:55:03][http-bio-8443-exec-3]: SystemConfigService: request: ConfigurationRequest [pin=XXXX, token=Internal Key Storage Token, tokenPassword=XXXX, securityDomainType=existingdomain, securityDomainUri=https://vm-058-168.abc.idm.lab.eng.brq.redhat.com:443, securityDomainName=null, securityDomainUser=admin-vm-041.abc.idm.lab.eng.brq.redhat.com, securityDomainPassword=XXXX, isClone=true, cloneUri=https://vm-058-168.abc.idm.lab.eng.brq.redhat.com:443, subsystemName=KRA vm-041.abc.idm.lab.eng.brq.redhat.com 8443, p12File=/tmp/tmpfYEuDY, p12Password=XXXX, hierarchy=null, dsHost=vm-041.abc.idm.lab.eng.brq.redhat.com, dsPort=636, baseDN=o=kra,o=ipaca, bindDN=cn=Directory Manager, bindpwd=XXXX, database=ipaca, secureConn=true, removeData=true, replicateSchema=true, masterReplicationPort=null, cloneReplicationPort=null, replicationSecurity=None, systemCertsImported=false, systemCerts=[com.netscape.certsrv.system.SystemCertData@6979325d], issuingCA=https://vm-041.abc.idm.lab.eng.brq.redhat.com:443, backupKeys=true, backupPassword=XXXX, backupFile=/etc/pki/pki-tomcat/alias/kra_backup_keys.p12, adminUID=null, adminPassword=XXXX, adminEmail=null, adminCertRequest=null, adminCertRequestType=null, adminSubjectDN=null, adminName=null, adminProfileID=null, adminCert=null, importAdminCert=false, generateServerCert=false, external=false, standAlone=false, stepTwo=false, authdbBaseDN=null, authdbHost=null, authdbPort=null, authdbSecureConn=null, caUri=null, kraUri=null, tksUri=null, enableServerSideKeyGen=null, importSharedSecret=null, generateSubsystemCert=null, sharedDB=true, sharedDBUserDN=uid=pkidbuser,ou=people,o=ipaca, createNewDB=false, setupReplication=False, subordinateSecurityDomainName=null, reindexData=False, startingCrlNumber=null] [17/Aug/2016:07:55:03][http-bio-8443-exec-3]: === Token Authentication === [17/Aug/2016:07:55:03][http-bio-8443-exec-3]: === Security Domain Configuration === [17/Aug/2016:07:55:03][http-bio-8443-exec-3]: Joining existing security domain [17/Aug/2016:07:55:03][http-bio-8443-exec-3]: Resolving security domain URL https://vm-058-168.abc.idm.lab.eng.brq.redhat.com:443 [17/Aug/2016:07:55:03][http-bio-8443-exec-3]: Getting security domain cert chain [17/Aug/2016:07:55:03][http-bio-8443-exec-3]: ConfigurationUtils.importCertChain() [17/Aug/2016:07:55:03][http-bio-8443-exec-3]: ConfigurationUtils: GET https://vm-058-168.abc.idm.lab.eng.brq.redhat.com:443/ca/admin/ca/getCertChain [17/Aug/2016:07:55:03][http-bio-8443-exec-3]: Server certificate: [17/Aug/2016:07:55:03][http-bio-8443-exec-3]: - subject: CN=vm-058-168.abc.idm.lab.eng.brq.redhat.com,O=DOM-088.ABC.IDM.LAB.ENG.BRQ.REDHAT.COM [17/Aug/2016:07:55:03][http-bio-8443-exec-3]: - issuer: CN=Certificate Authority,O=DOM-088.ABC.IDM.LAB.ENG.BRQ.REDHAT.COM [17/Aug/2016:07:55:04][http-bio-8443-exec-3]: Getting install token [17/Aug/2016:07:55:04][http-bio-8443-exec-3]: Getting install token [17/Aug/2016:07:55:06][http-bio-8443-exec-3]: Getting domain XML [17/Aug/2016:07:55:06][http-bio-8443-exec-3]: ConfigurationUtils: getting domain info [17/Aug/2016:07:55:06][http-bio-8443-exec-3]: ConfigurationUtils: GET https://vm-058-168.abc.idm.lab.eng.brq.redhat.com:443/ca/admin/ca/getDomainXML [17/Aug/2016:07:55:06][http-bio-8443-exec-3]: Server certificate: [17/Aug/2016:07:55:06][http-bio-8443-exec-3]: - subject: CN=vm-058-168.abc.idm.lab.eng.brq.redhat.com,O=DOM-088.ABC.IDM.LAB.ENG.BRQ.REDHAT.COM [17/Aug/2016:07:55:06][http-bio-8443-exec-3]: - issuer: CN=Certificate Authority,O=DOM-088.ABC.IDM.LAB.ENG.BRQ.REDHAT.COM [17/Aug/2016:07:55:07][http-bio-8443-exec-3]: ConfigurationUtils: status: 0 [17/Aug/2016:07:55:07][http-bio-8443-exec-3]: ConfigurationUtils: domain info: IPAvm-088.abc.idm.lab.eng.brq.redhat.com80443443443443FALSECA vm-088.abc.idm.lab.eng.brq.redhat.com 8443TRUEvm-058-168.abc.idm.lab.eng.brq.redhat.com80443443443443TRUECA vm-058-168.abc.idm.lab.eng.brq.redhat.com 8443TRUEvm-058-117.abc.idm.lab.eng.brq.redhat.com80443443443443TRUECA vm-058-117.abc.idm.lab.eng.brq.redhat.com 8443TRUEvm-041.abc.idm.lab.eng.brq.redhat.com80443443443443TRUECA vm-041.abc.idm.lab.eng.brq.redhat.com 8443TRUE4vm-088.abc.idm.lab.eng.brq.redhat.com80443443443443FALSEKRA vm-088.abc.idm.lab.eng.brq.redhat.com 8443FALSEvm-058-168.abc.idm.lab.eng.brq.redhat.com80443443443443TRUEKRA vm-058-168.abc.idm.lab.eng.brq.redhat.com 8443FALSEvm-058-117.abc.idm.lab.eng.brq.redhat.com80443443443443TRUEKRA vm-058-117.abc.idm.lab.eng.brq.redhat.com 8443FALSE30000 [17/Aug/2016:07:55:07][http-bio-8443-exec-3]: len is 4 [17/Aug/2016:07:55:07][http-bio-8443-exec-3]: hostname: [17/Aug/2016:07:55:07][http-bio-8443-exec-3]: admin_port: <443> [17/Aug/2016:07:55:07][http-bio-8443-exec-3]: hostname: [17/Aug/2016:07:55:07][http-bio-8443-exec-3]: admin_port: <443> [17/Aug/2016:07:55:07][http-bio-8443-exec-3]: === Subsystem Configuration === [17/Aug/2016:07:55:07][http-bio-8443-exec-3]: SystemConfigService: validate clone URI: https://vm-058-168.abc.idm.lab.eng.brq.redhat.com:443 [17/Aug/2016:07:55:07][http-bio-8443-exec-3]: SystemConfigService: get configuration entries from master [17/Aug/2016:07:55:07][http-bio-8443-exec-3]: updateNumberRange start host=vm-058-168.abc.idm.lab.eng.brq.redhat.com adminPort=443 eePort=443 [17/Aug/2016:07:55:07][http-bio-8443-exec-3]: ConfigurationUtils: POST https://vm-058-168.abc.idm.lab.eng.brq.redhat.com:443/kra/admin/kra/updateNumberRange [17/Aug/2016:07:55:07][http-bio-8443-exec-3]: Server certificate: [17/Aug/2016:07:55:07][http-bio-8443-exec-3]: - subject: CN=vm-058-168.abc.idm.lab.eng.brq.redhat.com,O=DOM-088.ABC.IDM.LAB.ENG.BRQ.REDHAT.COM [17/Aug/2016:07:55:07][http-bio-8443-exec-3]: - issuer: CN=Certificate Authority,O=DOM-088.ABC.IDM.LAB.ENG.BRQ.REDHAT.COM [17/Aug/2016:07:55:08][http-bio-8443-exec-3]: content from admin interface =1Error: Failed to update number range. [17/Aug/2016:07:55:08][http-bio-8443-exec-3]: updateNumberRange(): status=1 java.io.IOException: Error: Failed to update number range. at com.netscape.cms.servlet.csadmin.ConfigurationUtils.updateNumberRange(ConfigurationUtils.java:690) at com.netscape.cms.servlet.csadmin.ConfigurationUtils.getConfigEntriesFromMaster(ConfigurationUtils.java:555) at org.dogtagpki.server.rest.SystemConfigService.configureClone(SystemConfigService.java:881) at org.dogtagpki.server.rest.SystemConfigService.configureSubsystem(SystemConfigService.java:1018) at org.dogtagpki.server.rest.SystemConfigService.configure(SystemConfigService.java:164) at org.dogtagpki.server.rest.SystemConfigService.configure(SystemConfigService.java:121) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at org.jboss.resteasy.core.MethodInjectorImpl.invoke(MethodInjectorImpl.java:139) at org.jboss.resteasy.core.ResourceMethodInvoker.invokeOnTarget(ResourceMethodInvoker.java:295) at org.jboss.resteasy.core.ResourceMethodInvoker.invoke(ResourceMethodInvoker.java:249) at org.jboss.resteasy.core.ResourceMethodInvoker.invoke(ResourceMethodInvoker.java:236) at org.jboss.resteasy.core.SynchronousDispatcher.invoke(SynchronousDispatcher.java:402) at org.jboss.resteasy.core.SynchronousDispatcher.invoke(SynchronousDispatcher.java:209) at org.jboss.resteasy.plugins.server.servlet.ServletContainerDispatcher.service(ServletContainerDispatcher.java:221) at org.jboss.resteasy.plugins.server.servlet.HttpServletDispatcher.service(HttpServletDispatcher.java:56) at org.jboss.resteasy.plugins.server.servlet.HttpServletDispatcher.service(HttpServletDispatcher.java:51) at javax.servlet.http.HttpServlet.service(HttpServlet.java:729) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at org.apache.catalina.security.SecurityUtil$1.run(SecurityUtil.java:293) at org.apache.catalina.security.SecurityUtil$1.run(SecurityUtil.java:290) at java.security.AccessController.doPrivileged(Native Method) at javax.security.auth.Subject.doAsPrivileged(Subject.java:549) at org.apache.catalina.security.SecurityUtil.execute(SecurityUtil.java:325) at org.apache.catalina.security.SecurityUtil.doAsPrivilege(SecurityUtil.java:176) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:286) at org.apache.catalina.core.ApplicationFilterChain.access$000(ApplicationFilterChain.java:56) at org.apache.catalina.core.ApplicationFilterChain$1.run(ApplicationFilterChain.java:190) at org.apache.catalina.core.ApplicationFilterChain$1.run(ApplicationFilterChain.java:186) at java.security.AccessController.doPrivileged(Native Method) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:185) at org.apache.tomcat.websocket.server.WsFilter.doFilter(WsFilter.java:52) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at org.apache.catalina.security.SecurityUtil$1.run(SecurityUtil.java:293) at org.apache.catalina.security.SecurityUtil$1.run(SecurityUtil.java:290) at java.security.AccessController.doPrivileged(Native Method) at javax.security.auth.Subject.doAsPrivileged(Subject.java:549) at org.apache.catalina.security.SecurityUtil.execute(SecurityUtil.java:325) at org.apache.catalina.security.SecurityUtil.doAsPrivilege(SecurityUtil.java:264) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:237) at org.apache.catalina.core.ApplicationFilterChain.access$000(ApplicationFilterChain.java:56) at org.apache.catalina.core.ApplicationFilterChain$1.run(ApplicationFilterChain.java:190) at org.apache.catalina.core.ApplicationFilterChain$1.run(ApplicationFilterChain.java:186) at java.security.AccessController.doPrivileged(Native Method) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:185) at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:213) at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:106) at org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:502) at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:141) at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:79) at org.apache.catalina.valves.AbstractAccessLogValve.invoke(AbstractAccessLogValve.java:616) at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:88) at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:528) at org.apache.coyote.http11.AbstractHttp11Processor.process(AbstractHttp11Processor.java:1099) at org.apache.coyote.AbstractProtocol$AbstractConnectionHandler.process(AbstractProtocol.java:670) at org.apache.tomcat.util.net.JIoEndpoint$SocketProcessor.run(JIoEndpoint.java:283) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) at org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61) at java.lang.Thread.run(Thread.java:745)