Attachment 1458123 Details for Bug 1600149 – CA debug log during this issue

CA debug log during this issue

ca-agent.log (text/plain), 31.19 KB, created by Roshni on 2018-07-11 14:16:17 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Roshni

Created: 2018-07-11 14:16:17 UTC

Size: 31.19 KB

patch

obsolete

>[11/Jul/2018:10:08:28][Timer-0]: SessionTimer: run()
>[11/Jul/2018:10:08:28][Timer-0]: LDAPSecurityDomainSessionTable: getSessionIds() 
>[11/Jul/2018:10:08:28][Timer-0]: LDAPSecurityDomainSessionTable: searching ou=sessions,ou=Security Domain,o=topology-02-CA-CA
>[11/Jul/2018:10:08:28][Timer-0]: In LdapBoundConnFactory::getConn()
>[11/Jul/2018:10:08:28][Timer-0]: masterConn is connected: true
>[11/Jul/2018:10:08:28][Timer-0]: getConn: conn is connected true
>[11/Jul/2018:10:08:28][Timer-0]: getConn: mNumConns now 2
>[11/Jul/2018:10:08:28][Timer-0]: returnConn: mNumConns now 3
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: CMSServlet:service() uri = /ca/agent/ca/queryReq
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: CMSServlet::service() param name='reqType' value='enrollment'
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: CMSServlet::service() param name='reqState' value='showWaiting'
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: CMSServlet::service() param name='lastEntryOnPage' value='50'
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: CMSServlet::service() param name='direction' value='first'
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: CMSServlet::service() param name='maxCount' value='20'
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: CMSServlet: caqueryReq start to service.
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: in QueryReq servlet
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: IP: 10.19.34.102
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: AuthMgrName: certUserDBAuthMgr
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: CMSServlet: retrieving SSL certificate
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: CMSServlet: certUID=CN=PKI Administrator,E=caadmin@example.com,OU=topology-02-CA,O=topology-02_Foobarmaster.org
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: CertUserDBAuth: started
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: CertUserDBAuth: Retrieving client certificate
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: CertUserDBAuth: Got client certificate
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: Authentication: client certificate found
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: In LdapBoundConnFactory::getConn()
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: masterConn is connected: true
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: getConn: conn is connected true
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: getConn: mNumConns now 2
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: returnConn: mNumConns now 3
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: Authentication: mapped certificate to user
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: authenticated uid=caadmin,ou=people,o=topology-02-CA-CA
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: CMSServlet: userid=caadmin
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: CMSServlet: in auditSubjectID
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: CMSServlet: auditSubjectID auditContext {locale=en_US,EN;Q=0.5, userid=caadmin, ipAddress=10.19.34.102, authManagerId=certUserDBAuthMgr}
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: CMSServlet auditSubjectID: subjectID: caadmin
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: SignedAuditLogger: event AUTH
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: CMSServlet.authorize(DirAclAuthz)
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: CMSServlet: in auditSubjectID
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: CMSServlet: auditSubjectID auditContext {locale=en_US,EN;Q=0.5, userid=caadmin, ipAddress=10.19.34.102, authManagerId=certUserDBAuthMgr}
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: CMSServlet auditSubjectID: subjectID: caadmin
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: CMSServlet: in auditGroupID
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: CMSServlet: auditGroupID auditContext {locale=en_US,EN;Q=0.5, userid=caadmin, ipAddress=10.19.34.102, authManagerId=certUserDBAuthMgr}
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: CMSServlet auditGroupID: groupID: null
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: In LdapBoundConnFactory::getConn()
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: masterConn is connected: true
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: getConn: conn is connected true
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: getConn: mNumConns now 2
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: returnConn: mNumConns now 3
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: AAclAuthz.checkPermission(certServer.ca.requests, list)
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: checkAllowEntries(): expressions: group="Certificate Manager Agents"|| group="Registration Manager Agents"
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: evaluating expressions: group="Certificate Manager Agents"|| group="Registration Manager Agents"
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: GroupAccessEvaluator: evaluate: uid=caadmin value="Certificate Manager Agents"
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: GroupAccessEvaluator: evaluate: no groups in authToken
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: In LdapBoundConnFactory::getConn()
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: masterConn is connected: true
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: getConn: conn is connected true
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: getConn: mNumConns now 2
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: returnConn: mNumConns now 3
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: UGSubsystem.isMemberOf() using new lookup code
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: In LdapBoundConnFactory::getConn()
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: masterConn is connected: true
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: getConn: conn is connected true
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: getConn: mNumConns now 2
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: authorization search base: cn=Certificate Manager Agents,ou=groups,o=topology-02-CA-CA
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: authorization search filter: (uniquemember=uid=caadmin,ou=people,o=topology-02-CA-CA)
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: authorization result: true
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: returnConn: mNumConns now 3
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: evaluated expression: group="Certificate Manager Agents" to be true
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: GroupAccessEvaluator: evaluate: uid=caadmin value="Registration Manager Agents"
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: GroupAccessEvaluator: evaluate: no groups in authToken
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: In LdapBoundConnFactory::getConn()
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: masterConn is connected: true
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: getConn: conn is connected true
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: getConn: mNumConns now 2
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: returnConn: mNumConns now 3
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: UGSubsystem.isMemberOf() using new lookup code
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: In LdapBoundConnFactory::getConn()
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: masterConn is connected: true
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: getConn: conn is connected true
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: getConn: mNumConns now 2
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: authorization search base: cn=Registration Manager Agents,ou=groups,o=topology-02-CA-CA
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: authorization search filter: (uniquemember=uid=caadmin,ou=people,o=topology-02-CA-CA)
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: authorization result: false
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: returnConn: mNumConns now 3
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: evaluated expression: group="Registration Manager Agents" to be false
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: DirAclAuthz: authorization passed
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: SignedAuditLogger: event AUTHZ
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: SignedAuditLogger: event ROLE_ASSUME
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: In LdapBoundConnFactory::getConn()
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: masterConn is connected: true
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: getConn: conn is connected true
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: getConn: mNumConns now 5
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: In DBVirtualList filter attrs startFrom sortKey pageSize filter: (&(requeststate=pending)(requestType=enrollment)) attrs: null pageSize 21 startFrom 0250
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: returnConn: mNumConns now 6
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: DBVirtualList: searching for entry 0250
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: DBVirtualList.getEntries()
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: DBVirtualList: entries: 1
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: DBVirtualList: top: 0
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: DBVirtualList: size: 1
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: DBVirtualList: size: 1
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: DBVirtualList: size: 1
>[11/Jul/2018:10:08:31][http-bio-20443-exec-23]: CMSServlet: curDate=Wed Jul 11 10:08:31 EDT 2018 id=caqueryReq time=15
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>[11/Jul/2018:10:09:06][http-bio-20443-exec-23]: CMSServlet:service() uri = /ca/agent/ca/listRequests.html
>[11/Jul/2018:10:09:06][http-bio-20443-exec-23]: CMSServlet: caListRequests start to service.
>[11/Jul/2018:10:09:06][http-bio-20443-exec-23]: DisplayHtmlServlet about to service 
>[11/Jul/2018:10:09:06][http-bio-20443-exec-23]: IP: 10.19.34.102
>[11/Jul/2018:10:09:06][http-bio-20443-exec-23]: AuthMgrName: certUserDBAuthMgr
>[11/Jul/2018:10:09:06][http-bio-20443-exec-23]: CMSServlet: retrieving SSL certificate
>[11/Jul/2018:10:09:06][http-bio-20443-exec-23]: CMSServlet: certUID=CN=PKI Administrator,E=caadmin@example.com,OU=topology-02-CA,O=topology-02_Foobarmaster.org
>[11/Jul/2018:10:09:06][http-bio-20443-exec-23]: CertUserDBAuth: started
>[11/Jul/2018:10:09:06][http-bio-20443-exec-23]: CertUserDBAuth: Retrieving client certificate
>[11/Jul/2018:10:09:06][http-bio-20443-exec-23]: CertUserDBAuth: Got client certificate
>[11/Jul/2018:10:09:06][http-bio-20443-exec-23]: In LdapBoundConnFactory::getConn()
>[11/Jul/2018:10:09:06][http-bio-20443-exec-23]: masterConn is connected: true
>[11/Jul/2018:10:09:06][http-bio-20443-exec-23]: getConn: conn is connected true
>[11/Jul/2018:10:09:06][http-bio-20443-exec-23]: getConn: mNumConns now 5
>[11/Jul/2018:10:09:06][http-bio-20443-exec-23]: returnConn: mNumConns now 6
>[11/Jul/2018:10:09:06][http-bio-20443-exec-23]: Authentication: client certificate found
>[11/Jul/2018:10:09:06][http-bio-20443-exec-23]: In LdapBoundConnFactory::getConn()
>[11/Jul/2018:10:09:06][http-bio-20443-exec-23]: masterConn is connected: true
>[11/Jul/2018:10:09:06][http-bio-20443-exec-23]: getConn: conn is connected true
>[11/Jul/2018:10:09:06][http-bio-20443-exec-23]: getConn: mNumConns now 2
>[11/Jul/2018:10:09:06][http-bio-20443-exec-23]: returnConn: mNumConns now 3
>[11/Jul/2018:10:09:06][http-bio-20443-exec-23]: Authentication: mapped certificate to user
>[11/Jul/2018:10:09:06][http-bio-20443-exec-23]: authenticated uid=caadmin,ou=people,o=topology-02-CA-CA
>[11/Jul/2018:10:09:06][http-bio-20443-exec-23]: CMSServlet: userid=caadmin
>[11/Jul/2018:10:09:06][http-bio-20443-exec-23]: CMSServlet: in auditSubjectID
>[11/Jul/2018:10:09:06][http-bio-20443-exec-23]: CMSServlet: auditSubjectID auditContext {locale=en_US,EN;Q=0.5, userid=caadmin, ipAddress=10.19.34.102, authManagerId=certUserDBAuthMgr}
>[11/Jul/2018:10:09:06][http-bio-20443-exec-23]: CMSServlet auditSubjectID: subjectID: caadmin
>[11/Jul/2018:10:09:06][http-bio-20443-exec-23]: SignedAuditLogger: event AUTH
>[11/Jul/2018:10:09:06][http-bio-20443-exec-23]: CMSServlet: curDate=Wed Jul 11 10:09:06 EDT 2018 id=caListRequests time=4
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: CMSServlet:service() uri = /ca/agent/ca/queryReq
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: CMSServlet::service() param name='reqType' value='enrollment'
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: CMSServlet::service() param name='reqState' value='showWaiting'
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: CMSServlet::service() param name='lastEntryOnPage' value='20'
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: CMSServlet::service() param name='direction' value='first'
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: CMSServlet::service() param name='maxCount' value='20'
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: CMSServlet: caqueryReq start to service.
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: in QueryReq servlet
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: IP: 10.19.34.102
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: AuthMgrName: certUserDBAuthMgr
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: CMSServlet: retrieving SSL certificate
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: CMSServlet: certUID=CN=PKI Administrator,E=caadmin@example.com,OU=topology-02-CA,O=topology-02_Foobarmaster.org
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: CertUserDBAuth: started
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: CertUserDBAuth: Retrieving client certificate
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: CertUserDBAuth: Got client certificate
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: Authentication: client certificate found
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: In LdapBoundConnFactory::getConn()
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: masterConn is connected: true
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: getConn: conn is connected true
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: getConn: mNumConns now 2
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: returnConn: mNumConns now 3
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: Authentication: mapped certificate to user
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: authenticated uid=caadmin,ou=people,o=topology-02-CA-CA
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: CMSServlet: userid=caadmin
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: CMSServlet: in auditSubjectID
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: CMSServlet: auditSubjectID auditContext {locale=en_US,EN;Q=0.5, userid=caadmin, ipAddress=10.19.34.102, authManagerId=certUserDBAuthMgr}
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: CMSServlet auditSubjectID: subjectID: caadmin
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: SignedAuditLogger: event AUTH
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: CMSServlet.authorize(DirAclAuthz)
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: CMSServlet: in auditSubjectID
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: CMSServlet: auditSubjectID auditContext {locale=en_US,EN;Q=0.5, userid=caadmin, ipAddress=10.19.34.102, authManagerId=certUserDBAuthMgr}
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: CMSServlet auditSubjectID: subjectID: caadmin
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: CMSServlet: in auditGroupID
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: CMSServlet: auditGroupID auditContext {locale=en_US,EN;Q=0.5, userid=caadmin, ipAddress=10.19.34.102, authManagerId=certUserDBAuthMgr}
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: CMSServlet auditGroupID: groupID: null
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: In LdapBoundConnFactory::getConn()
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: masterConn is connected: true
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: getConn: conn is connected true
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: getConn: mNumConns now 2
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: returnConn: mNumConns now 3
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: AAclAuthz.checkPermission(certServer.ca.requests, list)
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: checkAllowEntries(): expressions: group="Certificate Manager Agents"|| group="Registration Manager Agents"
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: evaluating expressions: group="Certificate Manager Agents"|| group="Registration Manager Agents"
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: GroupAccessEvaluator: evaluate: uid=caadmin value="Certificate Manager Agents"
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: GroupAccessEvaluator: evaluate: no groups in authToken
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: In LdapBoundConnFactory::getConn()
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: masterConn is connected: true
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: getConn: conn is connected true
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: getConn: mNumConns now 2
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: returnConn: mNumConns now 3
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: UGSubsystem.isMemberOf() using new lookup code
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: In LdapBoundConnFactory::getConn()
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: masterConn is connected: true
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: getConn: conn is connected true
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: getConn: mNumConns now 2
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: authorization search base: cn=Certificate Manager Agents,ou=groups,o=topology-02-CA-CA
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: authorization search filter: (uniquemember=uid=caadmin,ou=people,o=topology-02-CA-CA)
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: authorization result: true
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: returnConn: mNumConns now 3
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: evaluated expression: group="Certificate Manager Agents" to be true
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: GroupAccessEvaluator: evaluate: uid=caadmin value="Registration Manager Agents"
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: GroupAccessEvaluator: evaluate: no groups in authToken
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: In LdapBoundConnFactory::getConn()
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: masterConn is connected: true
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: getConn: conn is connected true
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: getConn: mNumConns now 2
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: returnConn: mNumConns now 3
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: UGSubsystem.isMemberOf() using new lookup code
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: In LdapBoundConnFactory::getConn()
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: masterConn is connected: true
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: getConn: conn is connected true
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: getConn: mNumConns now 2
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: authorization search base: cn=Registration Manager Agents,ou=groups,o=topology-02-CA-CA
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: authorization search filter: (uniquemember=uid=caadmin,ou=people,o=topology-02-CA-CA)
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: authorization result: false
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: returnConn: mNumConns now 3
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: evaluated expression: group="Registration Manager Agents" to be false
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: DirAclAuthz: authorization passed
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: SignedAuditLogger: event AUTHZ
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: SignedAuditLogger: event ROLE_ASSUME
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: In LdapBoundConnFactory::getConn()
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: masterConn is connected: true
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: getConn: conn is connected true
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: getConn: mNumConns now 5
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: In DBVirtualList filter attrs startFrom sortKey pageSize filter: (&(requeststate=pending)(requestType=enrollment)) attrs: null pageSize 21 startFrom 0220
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: returnConn: mNumConns now 6
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: DBVirtualList: searching for entry 0220
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: DBVirtualList.getEntries()
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: DBVirtualList: entries: 1
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: DBVirtualList: top: 0
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: DBVirtualList: size: 1
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: DBVirtualList: size: 1
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: DBVirtualList: size: 1
>[11/Jul/2018:10:09:11][http-bio-20443-exec-23]: CMSServlet: curDate=Wed Jul 11 10:09:11 EDT 2018 id=caqueryReq time=13
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>[11/Jul/2018:10:09:45][http-bio-20443-exec-23]: CMSServlet:service() uri = /ca/agent/ca/listRequests.html
>[11/Jul/2018:10:09:45][http-bio-20443-exec-23]: CMSServlet: caListRequests start to service.
>[11/Jul/2018:10:09:45][http-bio-20443-exec-23]: DisplayHtmlServlet about to service 
>[11/Jul/2018:10:09:45][http-bio-20443-exec-23]: IP: 10.19.34.102
>[11/Jul/2018:10:09:45][http-bio-20443-exec-23]: AuthMgrName: certUserDBAuthMgr
>[11/Jul/2018:10:09:45][http-bio-20443-exec-23]: CMSServlet: retrieving SSL certificate
>[11/Jul/2018:10:09:45][http-bio-20443-exec-23]: CMSServlet: certUID=CN=PKI Administrator,E=caadmin@example.com,OU=topology-02-CA,O=topology-02_Foobarmaster.org
>[11/Jul/2018:10:09:45][http-bio-20443-exec-23]: CertUserDBAuth: started
>[11/Jul/2018:10:09:45][http-bio-20443-exec-23]: CertUserDBAuth: Retrieving client certificate
>[11/Jul/2018:10:09:45][http-bio-20443-exec-23]: CertUserDBAuth: Got client certificate
>[11/Jul/2018:10:09:45][http-bio-20443-exec-23]: Authentication: client certificate found
>[11/Jul/2018:10:09:45][http-bio-20443-exec-23]: In LdapBoundConnFactory::getConn()
>[11/Jul/2018:10:09:45][http-bio-20443-exec-23]: masterConn is connected: true
>[11/Jul/2018:10:09:45][http-bio-20443-exec-23]: getConn: conn is connected true
>[11/Jul/2018:10:09:45][http-bio-20443-exec-23]: getConn: mNumConns now 2
>[11/Jul/2018:10:09:45][http-bio-20443-exec-23]: returnConn: mNumConns now 3
>[11/Jul/2018:10:09:45][http-bio-20443-exec-23]: Authentication: mapped certificate to user
>[11/Jul/2018:10:09:45][http-bio-20443-exec-23]: authenticated uid=caadmin,ou=people,o=topology-02-CA-CA
>[11/Jul/2018:10:09:45][http-bio-20443-exec-23]: CMSServlet: userid=caadmin
>[11/Jul/2018:10:09:45][http-bio-20443-exec-23]: CMSServlet: in auditSubjectID
>[11/Jul/2018:10:09:45][http-bio-20443-exec-23]: CMSServlet: auditSubjectID auditContext {locale=en_US,EN;Q=0.5, userid=caadmin, ipAddress=10.19.34.102, authManagerId=certUserDBAuthMgr}
>[11/Jul/2018:10:09:45][http-bio-20443-exec-23]: CMSServlet auditSubjectID: subjectID: caadmin
>[11/Jul/2018:10:09:45][http-bio-20443-exec-23]: SignedAuditLogger: event AUTH
>[11/Jul/2018:10:09:45][http-bio-20443-exec-23]: CMSServlet: curDate=Wed Jul 11 10:09:45 EDT 2018 id=caListRequests time=3
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: CMSServlet:service() uri = /ca/agent/ca/queryReq
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: CMSServlet::service() param name='reqType' value='enrollment'
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: CMSServlet::service() param name='reqState' value='showWaiting'
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: CMSServlet::service() param name='lastEntryOnPage' value='0x10'
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: CMSServlet::service() param name='direction' value='first'
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: CMSServlet::service() param name='maxCount' value='20'
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: CMSServlet: caqueryReq start to service.
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: in QueryReq servlet
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: IP: 10.19.34.102
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: AuthMgrName: certUserDBAuthMgr
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: CMSServlet: retrieving SSL certificate
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: CMSServlet: certUID=CN=PKI Administrator,E=caadmin@example.com,OU=topology-02-CA,O=topology-02_Foobarmaster.org
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: CertUserDBAuth: started
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: CertUserDBAuth: Retrieving client certificate
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: CertUserDBAuth: Got client certificate
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: Authentication: client certificate found
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: In LdapBoundConnFactory::getConn()
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: masterConn is connected: true
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: getConn: conn is connected true
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: getConn: mNumConns now 2
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: returnConn: mNumConns now 3
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: Authentication: mapped certificate to user
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: authenticated uid=caadmin,ou=people,o=topology-02-CA-CA
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: CMSServlet: userid=caadmin
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: CMSServlet: in auditSubjectID
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: CMSServlet: auditSubjectID auditContext {locale=en_US,EN;Q=0.5, userid=caadmin, ipAddress=10.19.34.102, authManagerId=certUserDBAuthMgr}
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: CMSServlet auditSubjectID: subjectID: caadmin
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: SignedAuditLogger: event AUTH
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: CMSServlet.authorize(DirAclAuthz)
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: CMSServlet: in auditSubjectID
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: CMSServlet: auditSubjectID auditContext {locale=en_US,EN;Q=0.5, userid=caadmin, ipAddress=10.19.34.102, authManagerId=certUserDBAuthMgr}
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: CMSServlet auditSubjectID: subjectID: caadmin
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: CMSServlet: in auditGroupID
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: CMSServlet: auditGroupID auditContext {locale=en_US,EN;Q=0.5, userid=caadmin, ipAddress=10.19.34.102, authManagerId=certUserDBAuthMgr}
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: CMSServlet auditGroupID: groupID: null
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: In LdapBoundConnFactory::getConn()
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: masterConn is connected: true
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: getConn: conn is connected true
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: getConn: mNumConns now 2
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: returnConn: mNumConns now 3
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: AAclAuthz.checkPermission(certServer.ca.requests, list)
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: checkAllowEntries(): expressions: group="Certificate Manager Agents"|| group="Registration Manager Agents"
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: evaluating expressions: group="Certificate Manager Agents"|| group="Registration Manager Agents"
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: GroupAccessEvaluator: evaluate: uid=caadmin value="Certificate Manager Agents"
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: GroupAccessEvaluator: evaluate: no groups in authToken
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: In LdapBoundConnFactory::getConn()
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: masterConn is connected: true
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: getConn: conn is connected true
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: getConn: mNumConns now 2
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: returnConn: mNumConns now 3
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: UGSubsystem.isMemberOf() using new lookup code
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: In LdapBoundConnFactory::getConn()
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: masterConn is connected: true
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: getConn: conn is connected true
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: getConn: mNumConns now 2
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: authorization search base: cn=Certificate Manager Agents,ou=groups,o=topology-02-CA-CA
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: authorization search filter: (uniquemember=uid=caadmin,ou=people,o=topology-02-CA-CA)
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: authorization result: true
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: returnConn: mNumConns now 3
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: evaluated expression: group="Certificate Manager Agents" to be true
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: GroupAccessEvaluator: evaluate: uid=caadmin value="Registration Manager Agents"
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: GroupAccessEvaluator: evaluate: no groups in authToken
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: In LdapBoundConnFactory::getConn()
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: masterConn is connected: true
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: getConn: conn is connected true
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: getConn: mNumConns now 2
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: returnConn: mNumConns now 3
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: UGSubsystem.isMemberOf() using new lookup code
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: In LdapBoundConnFactory::getConn()
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: masterConn is connected: true
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: getConn: conn is connected true
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: getConn: mNumConns now 2
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: authorization search base: cn=Registration Manager Agents,ou=groups,o=topology-02-CA-CA
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: authorization search filter: (uniquemember=uid=caadmin,ou=people,o=topology-02-CA-CA)
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: authorization result: false
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: returnConn: mNumConns now 3
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: evaluated expression: group="Registration Manager Agents" to be false
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: DirAclAuthz: authorization passed
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: SignedAuditLogger: event AUTHZ
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: SignedAuditLogger: event ROLE_ASSUME
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: In LdapBoundConnFactory::getConn()
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: masterConn is connected: true
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: getConn: conn is connected true
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: getConn: mNumConns now 5
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: In DBVirtualList filter attrs startFrom sortKey pageSize filter: (&(requeststate=pending)(requestType=enrollment)) attrs: null pageSize 21 startFrom 0216
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: returnConn: mNumConns now 6
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: DBVirtualList: searching for entry 0216
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: DBVirtualList.getEntries()
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: DBVirtualList: entries: 1
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: DBVirtualList: top: 0
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: DBVirtualList: size: 1
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: DBVirtualList: size: 1
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: DBVirtualList: size: 1
>[11/Jul/2018:10:09:53][http-bio-20443-exec-23]: CMSServlet: curDate=Wed Jul 11 10:09:53 EDT 2018 id=caqueryReq time=11

Actions: View

Attachments on bug 1600149: 1458123