sathish10 Contributor.
Contributor.
341 views

Login Issue - User Application Dashborad 4.6.3

Hi,

Unable to login userapp sometimes and its getting logged in after multiple try.

Below are the display messages in the portal while trying.

- Login failed, please try again.

- There are no login connections available. Please try again later.


We are not able to find anything in the catalina/idapps log as there is no error message in it.


Please help to resolve the issue. Below is the OSP.log,

Preamble: [OSP]
Priority Level: FINER
Java: internal.osp.common.logging.HttpRequestLogger.log() [181] thread=https-jsse-nio-8444-exec-837
Time: 2019-05-03T10:52:25.200+0200
Log Data: HttpServletRequest (Number 69937)
Method: POST
Request URL: /osp/a/idm/auth/app/login
Query String: acAuthCardId=np-contract-%24default-card%24&sid=3
Scheme: https
Context Path: /osp
Servlet Path: /a
Path Info: /idm/auth/app/login
Server Name: idmsso.abc.in
Server Port: 8444
Content Length: 294
Content Type: application/x-www-form-urlencoded
Locale: en_US
Host IP Address: 172.11.01.11
Remote Client IP Address: 172.11.01.12
Cookies
(1 of 7): JSESSIONID=808458B5CFC4DE4FABD65939CC6D8A88
(2 of 7): AzureAppProxyUserSessionCookie_41373532-3944-4542-4443-423531303043_1.3=1|SRQsyqXMfrH4QSx2afsSuvlncGbJwNz10daSfkNit8I04vNCncwB/6C7lr2DDIMWhIcMVPCgmqXjggLcXkl+nZOolpSFfGVYTKlVAYtIA4GGFhCHs3CGMBP2Itl5oH8r33KnFEGZPN/XCEe2cRsblJZHUYwPa1JZgoHfboQSeq/5F44aH/Hcp4bMEpg5wfJm/R/WWRJp5DgIPi+koFf6scr2bfMFe9oOLNOMsbHkn5B2jY70mbAt1rU0hWQiBzy0jU3+p0EucBnTKlUJ4+ZSHR8dswzrm2b2s3jcLrMmfGZDREgw7qtlZHGwdsbMbQnRL1xDn9YXAvF0ITGaAavtyFvfgtQ2uUrPUSMfYtNYgynr4JZ+o0+XrntKWIQCbJQx
(3 of 7): x-oidp-session59303d34382c2d310=200-9LLKAUXMTXIVX4FJZQ4QRHI6LXXX9HUIV5H2WDXC-27CFFFA6-E82B!1!E82B!120!127!6ThAb8a6kb36/fQ0wGpbyds8m3ZlA03dTBHpOS2BGpY~!8NkNdG5S5xXR0xY29BCj8p/+x1BLF5IoSXx+UDxBiOgUeonh1CYH4yXsThIatXeYB0cJRoVQx1k5jJn9IIp7K2Qj+ZfQg8fBTn+GFRvFc/SGUVkBpX4OSNxYBAp7yS/L4l27Wsbizt2rPrLrBjueZNkcyuV8uE2lkqjgHKS/LRi1ogO12XzsCV9Z5s51EW6VG0GVPHykiWjvni3J9WzyqRA5K/BonRjsDnZqPsKdyHo/ZCQWs7PmPoSWUrxAr/ScMI6tlnXQiF5oP+JHmq7nGbf3XhEqY534UT51nrH8KDKTmSMaRiUb9LreXLkP7Q7z7NBSMQIJTj264QWLIou8kjRWDIxhI+HpVMxJ7KYHs/iZUJQbkxV588A+/MONvikeyCEdWXc0euLhtC96P11pwGxsQn5gsWXuQ+abqf7cVZpIheX/JNgeA3jbNJp0WJ4ZWZfAZgBVUdxFPosgQVlEwVvn/CtByM4jLA3zeRoCe2getd0bjkwGD7m38GC5xDzWgAaySKN4bk6A0qSAXIFCwxtz4zRJ9d57tqVqneOEd+Ci82jS4xQGD9woLJLotUKebtN4mrtxJy70qQOLKmOun632E6WbxIi4JMiuE5DFSUneRfxpmbWuTTHa9kH4mnkeRTxqtow+ORIBbbKaC3XfTtY7AV808M+xFgULm0gB9G2WtDFwwCUy/0o/xzvlr6GEUROFHE9JfOv6hB7h2VAKM9Nx+3QVIKjThqawhqMWBuY~
(4 of 7): x-oidp-oauth2-1556871620265-2133509617=AuL620ORgVZBh1Mo5cFszKThvRTF7KOO6kNwh0YaSJnEUxZhrdnMjFHn5oBC7nL1FinlUOFislKzsQakfFYzgQPwqJo5Kwo5d8cqQBM@aFLYzv59ICABmwp6ONVHM4RmMih6kS3MA@0DBPr5UL0vOj8QWq6YpoUfYSY8@FEgnKf3yw7DH9kjtx/RMa2Vuy4ZKLc1BVXUvHE@NMubyT3CH0sg/3i76KXP55PgvuSCeK2m0eoxIqohLpuO9GIaXxgKWOPZE//F244RloZg/3/nPBZBsPYXVNI5vlgJYd6zjQpXIDrw4H0gg4Cx2e7dqxju
(5 of 7): x-oidp-oauth2-1556871672702-1050496094=AuL620ORgVZBh1Mo5cFszAkkLQpZC24fPjAyp9XIH/HD1mV1XRuduPPWrXlTNvBmMtQ9mT@OAT51p3iaFi9COAPwqJo5Kwo5d8cqQBM@aFLYzv59ICABmwp6ONVHM4RmMih6kS3MA@0DBPr5UL0vOj8QWq6YpoUfYSY8@FEgnKf3yw7DH9kjtx/RMa2Vuy4ZKLc1BVXUvHE@NMubyT3CH0sg/3i76KXP55PgvuSCeK2m0eoxIqohLpuO9GIaXxgKWOPZE//F244RloZg/3/nPBZBsPYXVNI5vlgJYd6zjQpXIDrw4H0gg4Cx2e7dqxju
(6 of 7): x-oidp-oauth2-1556873472184--604636503=AuL620ORgVZBh1Mo5cFszDcixlM9tDmTBK9oXUFhL7sMP5ZwsKGXp6HpWA0IF7/WD5wpcqRQlTEoF7PVByx@tgPwqJo5Kwo5d8cqQBM@aFLYzv59ICABmwp6ONVHM4RmMih6kS3MA@0DBPr5UL0vOj8QWq6YpoUfYSY8@FEgnKf3yw7DH9kjtx/RMa2Vuy4ZKLc1BVXUvHE@NMubyT3CH0sg/3i76KXP55PgvuSCeK2m0eoxIqohLpuO9GIaXxgKWOPZE//F244RloZg/3/nPBZBsPYXVNI5vlgJYd6zjQpXIDrw4H0gg4Cx2e7dqxju
(7 of 7): x-oidp-oauth2-1556873474811--566095920=AuL620ORgVZBh1Mo5cFszIJl4YRFHh9YW0YP46ua/Vb0YUguyefJtmEcnlTG7J3YjVoO8YLMCqeM@TtksMmPdAPwqJo5Kwo5d8cqQBM@aFLYzv59ICABmwp6ONVHM4RmMih6kS3MA@0DBPr5UL0vOj8QWq6YpoUfYSY8@FEgnKf3yw7DH9kjtx/RMa2Vuy4ZKLc1BVXUvHE@NMubyT3CH0sg/3i76KXP55PgvuSCeK2m0eoxIqohLpuO9GIaXxgKWOPZE//F244RloZg/3/nPBZBsPYXVNI5vlgJYd6zjQpXIDrw4H0gg4Cx2e7dqxju
Headers
accept=text/html, application/xhtml+xml, image/jxr, */*
referer=https://idmsso.abc.in:8444/osp/a/idm/auth/app/login?acAuthCardId=np-contract-%24default-card%24&sid=3
accept-language=en-US,en;q=0.7,da;q=0.3
user-agent=Mozilla/5.0 (Windows NT 10.0; WOW64; Trident/7.0; rv:11.0) like Gecko
content-type=application/x-www-form-urlencoded
accept-encoding=gzip, deflate
host=idmsso.abc.in:8444
content-length=294
connection=Keep-Alive
cache-control=no-cache
cookie=(see above)
Session
Id: 808458B5CFC4DE4FABD65939CC6D8A88
Last Accessed Time: 1556873519575
Parameters
acAuthCardId
sid
cexid
option
excludeParameterNames
FilterAuthenticationClass.dup-res-state
Ecom_User_ID
Ecom_Password
Attributes
org.apache.tomcat.util.net.secure_protocol_version
javax.servlet.request.key_size
javax.servlet.request.ssl_session_mgr
javax.servlet.request.cipher_suite
javax.servlet.request.ssl_session_id
OSPRequestContext

Preamble: [OSP]
Priority Level: FINEST
Java: internal.osp.framework.servlet.OSPServlet.process() [213] thread=https-jsse-nio-8444-exec-837
Time: 2019-05-03T10:52:25.200+0200
Log Data:
Class: OSPRequestContext
HttpServletResponse exists.
Http request type: POST
Request number: 69937
Tenant: For IDM and IG
Service: For IDM and IG(id=auth)
Path element count: 2
Element: app
Element: login
Override locale: en_US

Preamble: [OIDP]
Priority Level: FINEST
Java: internal.osp.oidp.service.servlets.handler.AuthenticationServiceRequestHandler.handleRequest() [363] thread=https-jsse-nio-8444-exec-837
Time: 2019-05-03T10:52:25.200+0200
Log Data: Loaded handler to fulfill request: CommonHandler

Preamble: [OIDP]
Priority Level: FINEST
Java: internal.osp.oidp.service.profile.authentication.MethodProfile.setExecutables() [236] thread=https-jsse-nio-8444-exec-837
Time: 2019-05-03T10:52:25.216+0200
Log Data: Number of Contract Executables set to run: 3

Preamble: [OIDP]
Priority Level: FINEST
Java: internal.osp.oidp.service.profile.authentication.MethodProfile.setExecutables() [236] thread=https-jsse-nio-8444-exec-837
Time: 2019-05-03T10:52:25.216+0200
Log Data: Number of Contract Executables set to run: 3

Preamble: [OIDP]
Priority Level: FINEST
Java: internal.osp.oidp.service.session.NIDPSession.<init>() [453] thread=https-jsse-nio-8444-exec-837
Time: 2019-05-03T10:52:25.216+0200
Log Data: Built Session from XML: id: c5a664eb8e4d468e910fa5204efe5d59-86efe2ebabe7f3f2ee-CX, Type: PERSISTENT

Preamble: [OIDP]
Priority Level: FINEST
Java: internal.osp.oidp.service.session.NIDPSession.reapSessionData() [756] thread=https-jsse-nio-8444-exec-837
Time: 2019-05-03T10:52:25.216+0200
Elapsed time: 3.0 microseconds
Log Data: Clean up expired session data instances:
No expired instances found.

Preamble: [OIDP]
Priority Level: FINEST
Java: internal.osp.oidp.service.session.NIDPSession.isAuthenticated() [2546] thread=https-jsse-nio-8444-exec-837
Time: 2019-05-03T10:52:25.216+0200
Elapsed time: 18.200 microseconds
Log Data: Session authenticated?
Identifier: c5a664eb8e4d468e910fa5204efe5d59-86efe2ebabe7f3f2ee
Zero consumed authentications.
Authenticated: false

Preamble: [OIDP]
Priority Level: FINER
Java: internal.osp.oidp.service.cluster.ClusterCookieContext.resolveSession() [153] thread=https-jsse-nio-8444-exec-837
Time: 2019-05-03T10:52:25.216+0200
Elapsed time: 85.800 microseconds
Log Data:
Session cached:
Class: NIDPSession
Id: c5a664eb8e4d468e910fa5204efe5d59-86efe2ebabe7f3f2ee-CX
SubId: 0
Private Id: +cp}0E*+;72A3wn1D#180$Iu_
Type: PERSISTANT
Created Time: 05-03 10:03:55 0090 (1556870635090) (Elapsed: 2910126)
Last Used Time: 05-03 10:51:59 0419 (1556873519419) (Elapsed: 25797)
Main JSP: main
Set Activity: true
Storage cache: idm_idv=fd8e5f32-67c5-4388-9a7f-3b5a9708e72c
Session Data Key: 1
Class: NIDPSessionData
Id: 1
Create Time: 05-03 10:51:12 0137 (1556873472137)) (Elapsed: 73079)
Session Data Key: 2
Class: NIDPSessionData
Id: 2
Create Time: 05-03 10:51:12 0184 (1556873472184)) (Elapsed: 73032)
Target stack:
0: https://idmsso.abc.in:8444/osp/a/idm/auth/oauth2/implicitcontinue?privateId=7f2a9db76bd28ffcc5d0&client_id=idmdash&irdpkg=1556873472184--604636503
Class: ContractExecutionProfile
Service: For IDM and IG(id=auth)
Request: /osp/a/idm/auth/app/login
Reset: false
First Time: true
Passive: false
Force: false
Counter: 0
Return URL: https://idmsso.abc.in:8444/osp/a/idm/auth/app/login?acAuthCardId=np-contract-%24default-card%24&sid=2
Top: false
Authenticated: false
Credential Update: false
Class: AuthenticationContract
Id: np-contract
Name: User Name/Password Login
URI: idm:login:user:np
Trust Level: 0
Timeout: 0
Check Trust Level: false
Show Pwd Expired UI: true
Remote: false
Default: true
Executable: (Auto) Name/Password (Form) Method(id={$auto}-np-auth-method)
Executable: (Auto) IDM Admin Role Mapping Method(id={$auto}-admin-role-mapping-method)
Executable: (Auto) Access Review Bootstrap Admin Role Assignment Method(id={$auto}-iac-bootstrap-role-assignment-method)
Authentication Card: User Name/Password Login-$default-card$(id=np-contract-$default-card$)
Session Data Key: 3
Class: NIDPSessionData
Id: 3
Create Time: 05-03 10:51:14 0811 (1556873474811)) (Elapsed: 70405)
Target stack:
0: https://idmsso.abc.in:8444/osp/a/idm/auth/oauth2/implicitcontinue?privateId=045fc29baf568c81330a&client_id=idmdash&irdpkg=1556873474811--566095920
Class: ContractExecutionProfile
Service: For IDM and IG(id=auth)
Request: /osp/a/idm/auth/app/login
Reset: false
First Time: true
Passive: false
Force: false
Counter: 0
Return URL: https://idmsso.abc.in:8444/osp/a/idm/auth/app/login?acAuthCardId=np-contract-%24default-card%24&sid=3
Top: false
Authenticated: false
Credential Update: false
Class: AuthenticationContract
Id: np-contract
Name: User Name/Password Login
URI: idm:login:user:np
Trust Level: 0
Timeout: 0
Check Trust Level: false
Show Pwd Expired UI: true
Remote: false
Default: true
Executable: (Auto) Name/Password (Form) Method(id={$auto}-np-auth-method)
Executable: (Auto) IDM Admin Role Mapping Method(id={$auto}-admin-role-mapping-method)
Executable: (Auto) Access Review Bootstrap Admin Role Assignment Method(id={$auto}-iac-bootstrap-role-assignment-method)
Authentication Card: User Name/Password Login-$default-card$(id=np-contract-$default-card$)
Logout Flag: 0
Show Logout: false

Preamble: [OIDP]
Priority Level: FINEST
Java: internal.osp.oidp.service.session.NIDPSession.checkAuthenticated() [2515] thread=https-jsse-nio-8444-exec-837
Time: 2019-05-03T10:52:25.216+0200
Elapsed time: 2.300 microseconds
Log Data: Session authenticated?
Identifier: c5a664eb8e4d468e910fa5204efe5d59-86efe2ebabe7f3f2ee
Zero consumed authentications.
Authenticated: false

Preamble: [OIDP]
Priority Level: FINEST
Java: internal.osp.oidp.service.servlets.handler.CommonHandler.processRequest() [412] thread=https-jsse-nio-8444-exec-837
Time: 2019-05-03T10:52:25.216+0200
Log Data: Handling request command: Login

Preamble: [OIDP]
Priority Level: FINER
Java: internal.osp.oidp.service.session.NIDPSession.getData() [593] thread=https-jsse-nio-8444-exec-837
Time: 2019-05-03T10:52:25.216+0200
Elapsed time: 4.800 microseconds
Log Data: Get session data:
Session data identifier source: request parameter
Found existing session data; id: 3

Preamble: [OIDP]
Priority Level: FINER
Java: internal.osp.oidp.service.profile.LoginProfile.isForceAuth() [119] thread=https-jsse-nio-8444-exec-837
Time: 2019-05-03T10:52:25.216+0200
Elapsed time: 3.0 microseconds
Log Data: Examining force authentication state:
forceAuth parameter: not present
Persisted AuthnRequest: not present
Force authentication: false

Preamble: [OIDP]
Priority Level: INFO
Java: internal.osp.oidp.service.profile.LoginProfile.login() [187] thread=https-jsse-nio-8444-exec-837
Time: 2019-05-03T10:52:25.216+0200
Log Data: Processing login request with TARGET: , Saved TARGET: https://idmsso.abc.in:8444/osp/a/idm/auth/oauth2/implicitcontinue?privateId=045fc29baf568c81330a&client_id=idmdash&irdpkg=1556873474811--566095920, Force: false.

Preamble: [OIDP]
Priority Level: FINER
Java: internal.osp.oidp.service.profile.LoginProfile.getContractToExecute() [422] thread=https-jsse-nio-8444-exec-837
Time: 2019-05-03T10:52:25.216+0200
Elapsed time: 13.700 microseconds
Log Data: Get contract to execute:
Existing contract execution profile: true
Get authentication contract by card identifier:
Card identifier: np-contract-$default-card$
Local card: true
Contract: np-contract
Found specified contract: User Name/Password Login(id=np-contract)
Timeout: 0

Preamble: [OIDP]
Priority Level: FINEST
Java: internal.osp.oidp.service.session.NIDPSession.isAuthenticated() [2546] thread=https-jsse-nio-8444-exec-837
Time: 2019-05-03T10:52:25.216+0200
Elapsed time: 2.900 microseconds
Log Data: Session authenticated?
Identifier: c5a664eb8e4d468e910fa5204efe5d59-86efe2ebabe7f3f2ee
Zero consumed authentications.
Authenticated: false

Preamble: [OIDP]
Priority Level: FINEST
Java: internal.osp.oidp.service.session.NIDPSession.checkAuthenticated() [2515] thread=https-jsse-nio-8444-exec-837
Time: 2019-05-03T10:52:25.216+0200
Elapsed time: 1.900 microseconds
Log Data: Session authenticated?
Identifier: c5a664eb8e4d468e910fa5204efe5d59-86efe2ebabe7f3f2ee
Zero consumed authentications.
Authenticated: false

Preamble: [OIDP]
Priority Level: INFO
Java: internal.osp.oidp.service.profile.authentication.ContractExecutionProfile.exec() [484] thread=https-jsse-nio-8444-exec-837
Time: 2019-05-03T10:52:25.216+0200
Log Data: Executing contract User Name/Password Login.

Preamble: [OIDP]
Priority Level: FINEST
Java: internal.osp.oidp.service.session.NIDPSession.checkAuthenticated() [2515] thread=https-jsse-nio-8444-exec-837
Time: 2019-05-03T10:52:25.216+0200
Elapsed time: 2.400 microseconds
Log Data: Session authenticated?
Identifier: c5a664eb8e4d468e910fa5204efe5d59-86efe2ebabe7f3f2ee
Zero consumed authentications.
Authenticated: false

Preamble: [OIDP]
Priority Level: FINEST
Java: internal.osp.oidp.service.session.NIDPSession.checkAuthenticated() [2515] thread=https-jsse-nio-8444-exec-837
Time: 2019-05-03T10:52:25.216+0200
Elapsed time: 1.400 microseconds
Log Data: Session authenticated?
Identifier: c5a664eb8e4d468e910fa5204efe5d59-86efe2ebabe7f3f2ee
Zero consumed authentications.
Authenticated: false

Preamble: [OIDP]
Priority Level: FINER
Java: internal.osp.oidp.service.profile.authentication.MethodProfile.getNextExecutable() [640] thread=https-jsse-nio-8444-exec-837
Time: 2019-05-03T10:52:25.216+0200
Elapsed time: 145.200 microseconds
Log Data: Get next contract executable:
Executables count: 3
Counter: 0
Executable: (Auto) Name/Password (Form) Method(id={$auto}-np-auth-method)
Type: user-authenticate
Session authenticated: false
Valid on session: false
Method selected for execution.

Preamble: [OIDP]
Priority Level: FINEST
Java: internal.osp.oidp.service.profile.authentication.MethodProfile.authenticateMethod() [685] thread=https-jsse-nio-8444-exec-837
Time: 2019-05-03T10:52:25.216+0200
Log Data: Authentication Method executing: (Auto) Name/Password (Form) Method

Preamble: [OIDP]
Priority Level: FINEST
Java: internal.osp.oidp.service.authentication.classes.PrincipalSelectionAuthClass.handlePostedData() [480] thread=https-jsse-nio-8444-exec-837
Time: 2019-05-03T10:52:25.216+0200
Elapsed time: 1.900 microseconds
Log Data: Searching for principal:
Identifier: user
Auth source: all configured

Priority Level: FINEST
Java: internal.atlaslite.jcce.ldap.jndi.JNDIConnectionPool.retireOldConnections() [1138] thread=https-jsse-nio-8444-exec-837
Time: 2019-05-03T10:52:25.216+0200
Log Data: Closing LDAP connection due to connection timeout! Interval: 39813, Timeout: 10000, Connection: Id: 7f0e55cd-9acd-4d70-925e-a45e2aa8c2fd, host: 172.31.104.48

Priority Level: INFO
Java: internal.atlaslite.jcce.ldap.jndi.JNDIConnectionPool.retireOldConnections() [1152] thread=https-jsse-nio-8444-exec-837
Time: 2019-05-03T10:52:25.216+0200
Log Data: Retire Conn:7f0e55cd-9acd-4d70-925e-a45e2aa8c2fd, Invalidated

Priority Level: INFO
Java: internal.atlaslite.jcce.ldap.jndi.JNDIConnection.close() [2235] thread=JCCE JNDI Connection Retirement
Time: 2019-05-03T10:52:28.700+0200
Log Data: Close Conn: cId: 7f0e55cd-9acd-4d70-925e-a45e2aa8c2fd

Preamble: [OIDP]
Priority Level: FINER
Java: internal.osp.oidp.service.source.ldap.LDAPSource.search() [703] thread=https-jsse-nio-8444-exec-837
Time: 2019-05-03T10:52:25.216+0200
Elapsed time: 14.999 seconds
Log Data: Search for LDAP principal:
Store: LDAP Directory Data Source(id=idm_idv)
Admin search:
Context: o=ABC
Scope: subtree
Filter: (&(objectClass=User)(|(cn=user)(mail=user)))
Attributes: cn, fullName, givenName, GUID, initials, loginIntruderAttempts, mail, mobile, nrfMemberOf, objectClass, sn, srvprvPreferredLocale
Get next available admin connection:
Get admin connection from pool:
Pool: PL41d53ada-99e3-4692-b5fa-ea4c546d2053:fd8e5f32-67c5-4388-9a7f-3b5a9708e72c
Reserve connection:
Type: ADMIN_CONNECTION
Retired admin connection count: 1
New reservation (non-existing): 75be42c7-42ec-4387-b3b7-c210f85fe288
New LDAP connection:
Connection: 28b9b888-3eaa-4451-8d5a-f734675548ea
User store: LDAP Directory Data Source(id=idm_idv)
Replica: LDAP Directory Data Source/172.31.104.48:389(id=fd8e5f32-67c5-4388-9a7f-3b5a9708e72c)
Username: cn=proxyeDirAdmin,ou=sa,o=system
Type: ADMIN_CONNECTION
Parameters:
java.naming.factory.initial: com.sun.jndi.ldap.LdapCtxFactory
java.naming.provider.url: ldap://172.31.104.48
com.sun.jndi.ldap.connect.timeout: 15000
java.naming.security.principal: cn=proxyeDirAdmin,ou=sa,o=system
java.naming.security.authentication: simple
java.naming.security.credentials: ********
java.naming.ldap.factory.socket: internal.osp.framework.util.net.client.OSP_SocketFactory
Naming error: javax.naming.ServiceUnavailableException: 172.31.104.48:389; socket closed
Error creating new connection:
internal.atlaslite.jcce.ldap.jndi.JNDIExceptionCommunication
172.31.104.48:389; socket closed
javax.naming.ServiceUnavailableException
172.31.104.48:389; socket closed
internal.atlaslite.jcce.ldap.jndi.JNDIConnection: JNDIConnection.java: <init>: 522
internal.atlaslite.jcce.ldap.jndi.JNDIConnectionPool: JNDIConnectionPool.java: getAdminConnection: 651
internal.atlaslite.jcce.ldap.jndi.JNDIStoreReplica: JNDIStoreReplica.java: getAdminConnection: 894
internal.atlaslite.jcce.ldap.jndi.JNDIStore: JNDIStore.java: getNextAdminConnection: 2.252
internal.atlaslite.jcce.ldap.jndi.JNDIStore: JNDIStore.java: doAdminSearch: 1.664
internal.atlaslite.jcce.ldap.jndi.JNDIStore: JNDIStore.java: doAdminSearch: 1.589
internal.atlaslite.jcce.ldap.jndi.JNDIStore: JNDIStore.java: doAdminSearch: 1.512
internal.osp.oidp.service.source.ldap.LDAPSource: LDAPSource.java: search: 623
internal.osp.oidp.service.source.ldap.LDAPSource: LDAPSource.java: searchImpl: 523
internal.osp.oidp.service.source.data.DataSourceHasSearchImpl: DataSourceHasSearchImpl.java: search: 131
internal.osp.oidp.service.source.ldap.LDAPAuthenticationSource: LDAPAuthenticationSource.java: searchUser: 997
internal.osp.oidp.service.source.ldap.LDAPAuthenticationSource: LDAPAuthenticationSource.java: searchUserByName: 944
internal.osp.oidp.service.authentication.classes.AuthenticationClass$NamePrincipalFinder: AuthenticationClass.java: findPrincipals: 917
internal.osp.oidp.service.authentication.classes.PrincipalFinder: PrincipalFinder.java: findPrincipals: 230
internal.osp.oidp.service.authentication.classes.AuthenticationClass: AuthenticationClass.java: findPrincipals: 575
internal.osp.oidp.service.authentication.classes.AuthenticationClass: AuthenticationClass.java: findPrincipals: 521
internal.osp.oidp.service.authentication.classes.PrincipalSelectionAuthClass: PrincipalSelectionAuthClass.java: handlePostedData: 481
internal.osp.oidp.service.authentication.classes.system.PasswordClassBase: PasswordClassBase.java: handlePostedData: 546
internal.osp.oidp.service.authentication.classes.system.PasswordClassBase: PasswordClassBase.java: handlePostedData: 510
internal.osp.oidp.service.authentication.classes.system.PasswordClassBase: PasswordClassBase.java: handlePostedData: 78
internal.osp.oidp.service.authentication.classes.PrincipalSelectionAuthClass: PrincipalSelectionAuthClass.java: doAuthenticate: 139
internal.osp.oidp.service.authentication.classes.system.PasswordClassBase: PasswordClassBase.java: doAuthenticate: 423
internal.osp.oidp.service.authentication.classes.system.PasswordClassBase: PasswordClassBase.java: doAuthenticate: 78
internal.osp.oidp.service.authentication.classes.AuthenticationClass: AuthenticationClass.java: authenticate: 656
internal.osp.oidp.service.profile.authentication.MethodProfile: MethodProfile.java: authenticateMethod: 688
internal.osp.oidp.service.profile.authentication.MethodProfile: MethodProfile.java: executeMethods: 406
internal.osp.oidp.service.profile.authentication.ContractExecutionProfile: ContractExecutionProfile.java: exec: 492
internal.osp.oidp.service.profile.authentication.ContractExecutionProfile: ContractExecutionProfile.java: execute: 440
internal.osp.oidp.service.profile.LoginProfile: LoginProfile.java: executeContract: 679
internal.osp.oidp.service.profile.LoginProfile: LoginProfile.java: login: 201
internal.osp.oidp.service.profile.LoginProfile: LoginProfile.java: login: 175
internal.osp.oidp.service.profile.LoginProfile: LoginProfile.java: login: 169
internal.osp.oidp.service.servlets.handler.CommonHandler: CommonHandler.java: processRequest: 425
internal.osp.oidp.service.servlets.handler.AuthenticationServiceRequestHandler: AuthenticationServiceRequestHandler.java: handleRequest: 408
internal.osp.framework.handler.TenantRequestHandler: TenantRequestHandler.java: handleRequest: 148
internal.osp.framework.handler.OSPHandler: OSPHandler.java: handleRequest: 153
internal.osp.framework.servlet.OSPServlet: OSPServlet.java: process: 231
internal.osp.framework.servlet.OSPServlet: OSPServlet.java: doPost: 132
javax.servlet.http.HttpServlet: HttpServlet.java: service: 661
javax.servlet.http.HttpServlet: HttpServlet.java: service: 742
org.apache.catalina.core.ApplicationFilterChain: ApplicationFilterChain.java: internalDoFilter: 231
org.apache.catalina.core.ApplicationFilterChain: ApplicationFilterChain.java: doFilter: 166
org.apache.tomcat.websocket.server.WsFilter: WsFilter.java: doFilter: 52
org.apache.catalina.core.ApplicationFilterChain: ApplicationFilterChain.java: internalDoFilter: 193
org.apache.catalina.core.ApplicationFilterChain: ApplicationFilterChain.java: doFilter: 166
org.apache.catalina.core.StandardWrapperValve: StandardWrapperValve.java: invoke: 198
org.apache.catalina.core.StandardContextValve: StandardContextValve.java: invoke: 96
org.apache.catalina.authenticator.AuthenticatorBase: AuthenticatorBase.java: invoke: 613
org.apache.catalina.core.StandardHostValve: StandardHostValve.java: invoke: 140
org.apache.catalina.valves.ErrorReportValve: ErrorReportValve.java: invoke: 81
org.apache.catalina.valves.AbstractAccessLogValve: AbstractAccessLogValve.java: invoke: 650
org.apache.catalina.core.StandardEngineValve: StandardEngineValve.java: invoke: 87
org.apache.catalina.ha.tcp.ReplicationValve: ReplicationValve.java: invoke: 322
org.apache.catalina.ha.session.JvmRouteBinderValve: JvmRouteBinderValve.java: invoke: 193
org.apache.catalina.connector.CoyoteAdapter: CoyoteAdapter.java: service: 342
org.apache.coyote.http11.Http11Processor: Http11Processor.java: service: 803
org.apache.coyote.AbstractProcessorLight: AbstractProcessorLight.java: process: 66
org.apache.coyote.AbstractProtocol$ConnectionHandler: AbstractProtocol.java: process: 790
org.apache.tomcat.util.net.NioEndpoint$SocketProcessor: NioEndpoint.java: doRun: 1.459
org.apache.tomcat.util.net.SocketProcessorBase: SocketProcessorBase.java: run: 49
java.util.concurrent.ThreadPoolExecutor: ThreadPoolExecutor.java: runWorker: 1.149
java.util.concurrent.ThreadPoolExecutor$Worker: ThreadPoolExecutor.java: run: 624
org.apache.tomcat.util.threads.TaskThread$WrappingRunnable: TaskThread.java: run: 61
java.lang.Thread: Thread.java: run: 748
Caused by:
com.sun.jndi.ldap.Connection: Connection.java: readReply: 454
com.sun.jndi.ldap.LdapClient: LdapClient.java: ldapBind: 365
com.sun.jndi.ldap.LdapClient: LdapClient.java: authenticate: 214
com.sun.jndi.ldap.LdapCtx: LdapCtx.java: connect: 2.791
com.sun.jndi.ldap.LdapCtx: LdapCtx.java: <init>: 319
com.sun.jndi.ldap.LdapCtxFactory: LdapCtxFactory.java: getUsingURL: 192
com.sun.jndi.ldap.LdapCtxFactory: LdapCtxFactory.java: getUsingURLs: 210
com.sun.jndi.ldap.LdapCtxFactory: LdapCtxFactory.java: getLdapCtxInstance: 153
com.sun.jndi.ldap.LdapCtxFactory: LdapCtxFactory.java: getInitialContext: 83
javax.naming.spi.NamingManager: NamingManager.java: getInitialContext: 684
javax.naming.InitialContext: InitialContext.java: getDefaultInitCtx: 313
javax.naming.InitialContext: InitialContext.java: init: 244
javax.naming.ldap.InitialLdapContext: InitialLdapContext.java: <init>: 154
internal.atlaslite.jcce.ldap.jndi.JNDIConnection: JNDIConnection.java: <init>: 321
internal.atlaslite.jcce.ldap.jndi.JNDIConnectionPool: JNDIConnectionPool.java: getAdminConnection: 651
internal.atlaslite.jcce.ldap.jndi.JNDIStoreReplica: JNDIStoreReplica.java: getAdminConnection: 894
internal.atlaslite.jcce.ldap.jndi.JNDIStore: JNDIStore.java: getNextAdminConnection: 2.252
internal.atlaslite.jcce.ldap.jndi.JNDIStore: JNDIStore.java: doAdminSearch: 1.664
internal.atlaslite.jcce.ldap.jndi.JNDIStore: JNDIStore.java: doAdminSearch: 1.589
internal.atlaslite.jcce.ldap.jndi.JNDIStore: JNDIStore.java: doAdminSearch: 1.512
internal.osp.oidp.service.source.ldap.LDAPSource: LDAPSource.java: search: 623
internal.osp.oidp.service.source.ldap.LDAPSource: LDAPSource.java: searchImpl: 523
internal.osp.oidp.service.source.data.DataSourceHasSearchImpl: DataSourceHasSearchImpl.java: search: 131
internal.osp.oidp.service.source.ldap.LDAPAuthenticationSource: LDAPAuthenticationSource.java: searchUser: 997
internal.osp.oidp.service.source.ldap.LDAPAuthenticationSource: LDAPAuthenticationSource.java: searchUserByName: 944
internal.osp.oidp.service.authentication.classes.AuthenticationClass$NamePrincipalFinder: AuthenticationClass.java: findPrincipals: 917
internal.osp.oidp.service.authentication.classes.PrincipalFinder: PrincipalFinder.java: findPrincipals: 230
internal.osp.oidp.service.authentication.classes.AuthenticationClass: AuthenticationClass.java: findPrincipals: 575
internal.osp.oidp.service.authentication.classes.AuthenticationClass: AuthenticationClass.java: findPrincipals: 521
internal.osp.oidp.service.authentication.classes.PrincipalSelectionAuthClass: PrincipalSelectionAuthClass.java: handlePostedData: 481
internal.osp.oidp.service.authentication.classes.system.PasswordClassBase: PasswordClassBase.java: handlePostedData: 546
internal.osp.oidp.service.authentication.classes.system.PasswordClassBase: PasswordClassBase.java: handlePostedData: 510
internal.osp.oidp.service.authentication.classes.system.PasswordClassBase: PasswordClassBase.java: handlePostedData: 78
internal.osp.oidp.service.authentication.classes.PrincipalSelectionAuthClass: PrincipalSelectionAuthClass.java: doAuthenticate: 139
internal.osp.oidp.service.authentication.classes.system.PasswordClassBase: PasswordClassBase.java: doAuthenticate: 423
internal.osp.oidp.service.authentication.classes.system.PasswordClassBase: PasswordClassBase.java: doAuthenticate: 78
internal.osp.oidp.service.authentication.classes.AuthenticationClass: AuthenticationClass.java: authenticate: 656
internal.osp.oidp.service.profile.authentication.MethodProfile: MethodProfile.java: authenticateMethod: 688
internal.osp.oidp.service.profile.authentication.MethodProfile: MethodProfile.java: executeMethods: 406
internal.osp.oidp.service.profile.authentication.ContractExecutionProfile: ContractExecutionProfile.java: exec: 492
internal.osp.oidp.service.profile.authentication.ContractExecutionProfile: ContractExecutionProfile.java: execute: 440
internal.osp.oidp.service.profile.LoginProfile: LoginProfile.java: executeContract: 679
internal.osp.oidp.service.profile.LoginProfile: LoginProfile.java: login: 201
internal.osp.oidp.service.profile.LoginProfile: LoginProfile.java: login: 175
internal.osp.oidp.service.profile.LoginProfile: LoginProfile.java: login: 169
internal.osp.oidp.service.servlets.handler.CommonHandler: CommonHandler.java: processRequest: 425
internal.osp.oidp.service.servlets.handler.AuthenticationServiceRequestHandler: AuthenticationServiceRequestHandler.java: handleRequest: 408
internal.osp.framework.handler.TenantRequestHandler: TenantRequestHandler.java: handleRequest: 148
internal.osp.framework.handler.OSPHandler: OSPHandler.java: handleRequest: 153
internal.osp.framework.servlet.OSPServlet: OSPServlet.java: process: 231
internal.osp.framework.servlet.OSPServlet: OSPServlet.java: doPost: 132
javax.servlet.http.HttpServlet: HttpServlet.java: service: 661
javax.servlet.http.HttpServlet: HttpServlet.java: service: 742
org.apache.catalina.core.ApplicationFilterChain: ApplicationFilterChain.java: internalDoFilter: 231
org.apache.catalina.core.ApplicationFilterChain: ApplicationFilterChain.java: doFilter: 166
org.apache.tomcat.websocket.server.WsFilter: WsFilter.java: doFilter: 52
org.apache.catalina.core.ApplicationFilterChain: ApplicationFilterChain.java: internalDoFilter: 193
org.apache.catalina.core.ApplicationFilterChain: ApplicationFilterChain.java: doFilter: 166
org.apache.catalina.core.StandardWrapperValve: StandardWrapperValve.java: invoke: 198
org.apache.catalina.core.StandardContextValve: StandardContextValve.java: invoke: 96
org.apache.catalina.authenticator.AuthenticatorBase: AuthenticatorBase.java: invoke: 613
org.apache.catalina.core.StandardHostValve: StandardHostValve.java: invoke: 140
org.apache.catalina.valves.ErrorReportValve: ErrorReportValve.java: invoke: 81
org.apache.catalina.valves.AbstractAccessLogValve: AbstractAccessLogValve.java: invoke: 650
org.apache.catalina.core.StandardEngineValve: StandardEngineValve.java: invoke: 87
org.apache.catalina.ha.tcp.ReplicationValve: ReplicationValve.java: invoke: 322
org.apache.catalina.ha.session.JvmRouteBinderValve: JvmRouteBinderValve.java: invoke: 193
org.apache.catalina.connector.CoyoteAdapter: CoyoteAdapter.java: service: 342
org.apache.coyote.http11.Http11Processor: Http11Processor.java: service: 803
org.apache.coyote.AbstractProcessorLight: AbstractProcessorLight.java: process: 66
org.apache.coyote.AbstractProtocol$ConnectionHandler: AbstractProtocol.java: process: 790
org.apache.tomcat.util.net.NioEndpoint$SocketProcessor: NioEndpoint.java: doRun: 1.459
org.apache.tomcat.util.net.SocketProcessorBase: SocketProcessorBase.java: run: 49
java.util.concurrent.ThreadPoolExecutor: ThreadPoolExecutor.java: runWorker: 1.149
java.util.concurrent.ThreadPoolExecutor$Worker: ThreadPoolExecutor.java: run: 624
org.apache.tomcat.util.threads.TaskThread$WrappingRunnable: TaskThread.java: run: 61
java.lang.Thread: Thread.java: run: 748
Restarting replica: fd8e5f32-67c5-4388-9a7f-3b5a9708e72c/172.31.104.48
Error attempting to obtain admin connection:
internal.atlaslite.jcce.ldap.jndi.JNDIExceptionLDAPServiceNotAvailable
internal.atlaslite.jcce.ldap.jndi.JNDIExceptionCommunication: 172.31.104.48:389; socket closed Root Cause: javax.naming.ServiceUnavailableException: 172.31.104.48:389; socket closed
internal.atlaslite.jcce.ldap.jndi.JNDIConnectionPool: JNDIConnectionPool.java: getAdminConnection: 706
internal.atlaslite.jcce.ldap.jndi.JNDIStoreReplica: JNDIStoreReplica.java: getAdminConnection: 894
internal.atlaslite.jcce.ldap.jndi.JNDIStore: JNDIStore.java: getNextAdminConnection: 2.252
internal.atlaslite.jcce.ldap.jndi.JNDIStore: JNDIStore.java: doAdminSearch: 1.664
internal.atlaslite.jcce.ldap.jndi.JNDIStore: JNDIStore.java: doAdminSearch: 1.589
internal.atlaslite.jcce.ldap.jndi.JNDIStore: JNDIStore.java: doAdminSearch: 1.512
internal.osp.oidp.service.source.ldap.LDAPSource: LDAPSource.java: search: 623
internal.osp.oidp.service.source.ldap.LDAPSource: LDAPSource.java: searchImpl: 523
internal.osp.oidp.service.source.data.DataSourceHasSearchImpl: DataSourceHasSearchImpl.java: search: 131
internal.osp.oidp.service.source.ldap.LDAPAuthenticationSource: LDAPAuthenticationSource.java: searchUser: 997
internal.osp.oidp.service.source.ldap.LDAPAuthenticationSource: LDAPAuthenticationSource.java: searchUserByName: 944
internal.osp.oidp.service.authentication.classes.AuthenticationClass$NamePrincipalFinder: AuthenticationClass.java: findPrincipals: 917
internal.osp.oidp.service.authentication.classes.PrincipalFinder: PrincipalFinder.java: findPrincipals: 230
internal.osp.oidp.service.authentication.classes.AuthenticationClass: AuthenticationClass.java: findPrincipals: 575
internal.osp.oidp.service.authentication.classes.AuthenticationClass: AuthenticationClass.java: findPrincipals: 521
internal.osp.oidp.service.authentication.classes.PrincipalSelectionAuthClass: PrincipalSelectionAuthClass.java: handlePostedData: 481
internal.osp.oidp.service.authentication.classes.system.PasswordClassBase: PasswordClassBase.java: handlePostedData: 546
internal.osp.oidp.service.authentication.classes.system.PasswordClassBase: PasswordClassBase.java: handlePostedData: 510
internal.osp.oidp.service.authentication.classes.system.PasswordClassBase: PasswordClassBase.java: handlePostedData: 78
internal.osp.oidp.service.authentication.classes.PrincipalSelectionAuthClass: PrincipalSelectionAuthClass.java: doAuthenticate: 139
internal.osp.oidp.service.authentication.classes.system.PasswordClassBase: PasswordClassBase.java: doAuthenticate: 423
internal.osp.oidp.service.authentication.classes.system.PasswordClassBase: PasswordClassBase.java: doAuthenticate: 78
internal.osp.oidp.service.authentication.classes.AuthenticationClass: AuthenticationClass.java: authenticate: 656
internal.osp.oidp.service.profile.authentication.MethodProfile: MethodProfile.java: authenticateMethod: 688
internal.osp.oidp.service.profile.authentication.MethodProfile: MethodProfile.java: executeMethods: 406
internal.osp.oidp.service.profile.authentication.ContractExecutionProfile: ContractExecutionProfile.java: exec: 492
internal.osp.oidp.service.profile.authentication.ContractExecutionProfile: ContractExecutionProfile.java: execute: 440
internal.osp.oidp.service.profile.LoginProfile: LoginProfile.java: executeContract: 679
internal.osp.oidp.service.profile.LoginProfile: LoginProfile.java: login: 201
internal.osp.oidp.service.profile.LoginProfile: LoginProfile.java: login: 175
internal.osp.oidp.service.profile.LoginProfile: LoginProfile.java: login: 169
internal.osp.oidp.service.servlets.handler.CommonHandler: CommonHandler.java: processRequest: 425
internal.osp.oidp.service.servlets.handler.AuthenticationServiceRequestHandler: AuthenticationServiceRequestHandler.java: handleRequest: 408
internal.osp.framework.handler.TenantRequestHandler: TenantRequestHandler.java: handleRequest: 148
internal.osp.framework.handler.OSPHandler: OSPHandler.java: handleRequest: 153
internal.osp.framework.servlet.OSPServlet: OSPServlet.java: process: 231
internal.osp.framework.servlet.OSPServlet: OSPServlet.java: doPost: 132
javax.servlet.http.HttpServlet: HttpServlet.java: service: 661
javax.servlet.http.HttpServlet: HttpServlet.java: service: 742
org.apache.catalina.core.ApplicationFilterChain: ApplicationFilterChain.java: internalDoFilter: 231
org.apache.catalina.core.ApplicationFilterChain: ApplicationFilterChain.java: doFilter: 166
org.apache.tomcat.websocket.server.WsFilter: WsFilter.java: doFilter: 52
org.apache.catalina.core.ApplicationFilterChain: ApplicationFilterChain.java: internalDoFilter: 193
org.apache.catalina.core.ApplicationFilterChain: ApplicationFilterChain.java: doFilter: 166
org.apache.catalina.core.StandardWrapperValve: StandardWrapperValve.java: invoke: 198
org.apache.catalina.core.StandardContextValve: StandardContextValve.java: invoke: 96
org.apache.catalina.authenticator.AuthenticatorBase: AuthenticatorBase.java: invoke: 613
org.apache.catalina.core.StandardHostValve: StandardHostValve.java: invoke: 140
org.apache.catalina.valves.ErrorReportValve: ErrorReportValve.java: invoke: 81
org.apache.catalina.valves.AbstractAccessLogValve: AbstractAccessLogValve.java: invoke: 650
org.apache.catalina.core.StandardEngineValve: StandardEngineValve.java: invoke: 87
org.apache.catalina.ha.tcp.ReplicationValve: ReplicationValve.java: invoke: 322
org.apache.catalina.ha.session.JvmRouteBinderValve: JvmRouteBinderValve.java: invoke: 193
org.apache.catalina.connector.CoyoteAdapter: CoyoteAdapter.java: service: 342
org.apache.coyote.http11.Http11Processor: Http11Processor.java: service: 803
org.apache.coyote.AbstractProcessorLight: AbstractProcessorLight.java: process: 66
org.apache.coyote.AbstractProtocol$ConnectionHandler: AbstractProtocol.java: process: 790
org.apache.tomcat.util.net.NioEndpoint$SocketProcessor: NioEndpoint.java: doRun: 1.459
org.apache.tomcat.util.net.SocketProcessorBase: SocketProcessorBase.java: run: 49
java.util.concurrent.ThreadPoolExecutor: ThreadPoolExecutor.java: runWorker: 1.149
java.util.concurrent.ThreadPoolExecutor$Worker: ThreadPoolExecutor.java: run: 624
org.apache.tomcat.util.threads.TaskThread$WrappingRunnable: TaskThread.java: run: 61
java.lang.Thread: Thread.java: run: 748
Unable to obtain a connection.

Preamble: [OIDP]
Priority Level: FINER
Java: internal.osp.oidp.service.authentication.classes.AuthenticationClass.findPrincipals() [597] thread=https-jsse-nio-8444-exec-837
Time: 2019-05-03T10:52:25.216+0200
Elapsed time: 14.999 seconds
Log Data: Find principals:
Authentication source count: 2
LDAP Directory User Authentication(id=bisadus)
CSV File User Authentication(id=asf1)
Find all principals: true
Total principals found: 0

Priority Level: FINEST
Java: internal.atlaslite.jcce.ldap.jndi.JNDIConnectionPool.close() [1522] thread=JNDIReplicaRestart-ee0cec3b-db87-4444-a469-40ca4a7ba690
Time: 2019-05-03T10:52:40.226+0200
Log Data: Pool Id: PL41d53ada-99e3-4692-b5fa-ea4c546d2053:fd8e5f32-67c5-4388-9a7f-3b5a9708e72c, Closing pool on host: 172.31.104.48

Preamble: [OIDP]
Priority Level: FINEST
Java: internal.osp.oidp.service.profile.authentication.MethodProfile.authenticateMethod() [689] thread=https-jsse-nio-8444-exec-837
Time: 2019-05-03T10:52:41.980+0200
Log Data: Authenticated authentication class: internal.osp.oidp.service.authentication.classes.system.PasswordClass, Status: SHOW_PAGE

Preamble: [OIDP]
Priority Level: INFO
Java: internal.osp.oidp.service.profile.authentication.MethodProfile.authenticateMethod() [773] thread=localhost-startStop-1
Time: 2019-04-18T14:55:20.253+0200
Log Data: Level: INFO
Code: internal.osp.oidp.service.profile.authentication.MethodProfile.authenticateMethod() [770]
Thread: https-jsse-nio-8444-exec-837
Correlation Id: ae3d7c5b-9c35-4124-9c3b-de0382bbda1b
Text: Authentication Method (Auto) Name/Password (Form) Method failed for session c5a664eb8e4d468e910fa5204efe5d59-86efe2ebabe7f3f2ee.
Level: WARNING
Code: internal.osp.oidp.service.authentication.classes.PrincipalSelectionAuthClass.handlePostedData() [492]
Thread: https-jsse-nio-8444-exec-837
Correlation Id: 39f13b15-89b4-4c0d-8f17-6060853863ed
Text: Unable to locate user name user

Preamble: [OIDP]
Priority Level: FINEST
Java: internal.osp.oidp.service.profile.authentication.ContractExecutionProfile.exec() [493] thread=https-jsse-nio-8444-exec-837
Time: 2019-05-03T10:52:41.980+0200
Log Data: Executing methods returned status: SHOW_PAGE

Preamble: [OIDP]
Priority Level: FINEST
Java: internal.osp.oidp.service.profile.authentication.ContractExecutionProfile.exec() [551] thread=https-jsse-nio-8444-exec-837
Time: 2019-05-03T10:52:41.980+0200
Log Data: UIResponder set to JSP response.

Preamble: [OIDP]
Priority Level: FINEST
Java: internal.osp.oidp.service.profile.LoginProfile.executeContract() [682] thread=https-jsse-nio-8444-exec-837
Time: 2019-05-03T10:52:41.980+0200
Log Data: Contract Execution Profile "execute()" returned status: HANDLED_REQUEST

Preamble: [OIDP]
Priority Level: FINER
Java: internal.osp.oidp.service.servlets.handler.AuthenticationServiceRequestHandler.commit() [535] thread=https-jsse-nio-8444-exec-837
Time: 2019-05-03T10:52:41.980+0200
Elapsed time: 16.819 milliseconds
Log Data: Persisting session: c5a664eb8e4d468e910fa5204efe5d59-86efe2ebabe7f3f2ee-CX
Session to cookie: true

Preamble: [OSP]
Priority Level: FINEST
Java: internal.osp.framework.UIResponder$PageResponse.setSpecificResponse() [2093] thread=https-jsse-nio-8444-exec-837
Time: 2019-05-03T10:52:41.995+0200
Log Data: Forwarding:
Class: PageToShow
Page: /idm/jsp/login.jsp
Attribute count: 16
FailedLoginAttempts=0
FilterAuthenticationClass.dup-res-data=iNGtdpShj/c5cR1Tf4pIUAuF22XENFesSfOrVfW440MTJQsfbAAV2OwGqpNx30XOmVTk1F2iCKNcpPQ/YTZjjeI9ueFtBVf0iY5FkF1009AUBZVa7GMXFsPC1/BPB7xHBVO0jbkaeCaE1cUqsrt4dQ==
FilterAuthenticationClass.dup-res-state=OFF
MaximumPasswordLength=512
MaximumUserNameLength=512
PreviousUserName=user
cexid=np-auth
correlation=ae3d7c5b-9c35-4124-9c3b-de0382bbda1b, 39f13b15-89b4-4c0d-8f17-6060853863ed
oidp.allow-show-hide=UNDEFINED
oidp.show-hide-initial-state=UNDEFINED
oidp.use-continue-button=true
originalRequestUrl=https://idmsso.abc.in:8444/osp/a/idm/auth/app/login?acAuthCardId=np-contract-%24default-card%24&sid=3
systemids=125
url=https://idmsso.abc.in:8444/osp/a/idm/auth/app/login?acAuthCardId=np-contract-%24default-card%24&sid=3
urlprops={}
usermessage=Login failed, please try again.

Preamble: [OIDP]
Priority Level: FINER
Java: internal.osp.oidp.service.session.NIDPSession.getSessionData() [664] thread=https-jsse-nio-8444-exec-837
Time: 2019-05-03T10:52:42.011+0200
Elapsed time: 3.500 microseconds
Log Data: Get session data:
Found existing session data; id: 3

Preamble: [OIDP]
Priority Level: FINER
Java: internal.osp.oidp.service.ui.UIHandler.getUIIcons() [640] thread=https-jsse-nio-8444-exec-837
Time: 2019-05-03T10:52:42.011+0200
Elapsed time: 1.179 milliseconds
Log Data: Get UI icons:
Authorization policy: false

Preamble: [OSP]
Priority Level: FINEST
Java: internal.osp.framework.UIResponder$Response.setResponse() [1388] thread=https-jsse-nio-8444-exec-837
Time: 2019-05-03T10:52:42.089+0200
Log Data: Response: PAGE
Class: PageToShow
Page: /idm/jsp/login.jsp
Attribute count: 16
FailedLoginAttempts=0
FilterAuthenticationClass.dup-res-data=iNGtdpShj/c5cR1Tf4pIUAuF22XENFesSfOrVfW440MTJQsfbAAV2OwGqpNx30XOmVTk1F2iCKNcpPQ/YTZjjeI9ueFtBVf0iY5FkF1009AUBZVa7GMXFsPC1/BPB7xHBVO0jbkaeCaE1cUqsrt4dQ==
FilterAuthenticationClass.dup-res-state=OFF
MaximumPasswordLength=512
MaximumUserNameLength=512
PreviousUserName=user
cexid=np-auth
correlation=ae3d7c5b-9c35-4124-9c3b-de0382bbda1b, 39f13b15-89b4-4c0d-8f17-6060853863ed
oidp.allow-show-hide=UNDEFINED
oidp.show-hide-initial-state=UNDEFINED
oidp.use-continue-button=true
originalRequestUrl=https://idmsso.abc.in:8444/osp/a/idm/auth/app/login?acAuthCardId=np-contract-%24default-card%24&sid=3
systemids=125
url=https://idmsso.abc.in:8444/osp/a/idm/auth/app/login?acAuthCardId=np-contract-%24default-card%24&sid=3
urlprops={}
usermessage=Login failed, please try again.

Preamble: [OSP]
Priority Level: FINER
Java: internal.osp.common.logging.HttpResponseLogger.log() [138] thread=https-jsse-nio-8444-exec-837
Time: 2019-05-03T10:52:42.089+0200
Log Data: HttpServletResponse (Number 69937)
Duration (seconds): 16.889
[Slow Request]
Content type: text/html;charset=UTF-8
Character encoding: UTF-8
Locale: en_US
Buffer size: 8192



Thanks,
Sathish
Labels (1)
0 Likes
6 Replies
Knowledge Partner
Knowledge Partner

Re: Login Issue - User Application Dashborad 4.6.3

On 5/3/2019 5:06 AM, sathish10 wrote:
> Preamble: [OIDP]
> Priority Level: FINER
> Java: internal.osp.oidp.service.source.ldap.LDAPSource.search() [703]
> thread=https-jsse-nio-8444-exec-837
> Time: 2019-05-03T10:52:25.216+0200
> Elapsed time: 14.999 seconds
> Log Data: Search for LDAP principal:
> Store: LDAP Directory Data Source(id=idm_idv)
> Admin search:
> Context: o=ABC
> Scope: subtree
> Filter: (&(objectClass=User)(|(cn=user)(mail=user)))
> Attributes: cn, fullName, givenName, GUID, initials,
> loginIntruderAttempts, mail, mobile, nrfMemberOf, objectClass, sn,
> srvprvPreferredLocale
> Get next available admin connection:
> Get admin connection from pool:
> Pool:
> PL41d53ada-99e3-4692-b5fa-ea4c546d2053:fd8e5f32-67c5-4388-9a7f-3b5a9708e72c
> Reserve connection:
> Type: ADMIN_CONNECTION
> Retired admin connection count: 1
> New reservation (non-existing):
> 75be42c7-42ec-4387-b3b7-c210f85fe288
> New LDAP connection:
> Connection: 28b9b888-3eaa-4451-8d5a-f734675548ea
> User store: LDAP Directory Data Source(id=idm_idv)
> Replica: LDAP Directory Data
> Source/172.31.104.48:389(id=fd8e5f32-67c5-4388-9a7f-3b5a9708e72c)
> Username: cn=proxyeDirAdmin,ou=sa,o=system
> Type: ADMIN_CONNECTION
> Parameters:
> java.naming.factory.initial:
> com.sun.jndi.ldap.LdapCtxFactory
> java.naming.provider.url:ldap://172.31.104.48
> com.sun.jndi.ldap.connect.timeout: 15000
> java.naming.security.principal:
> cn=proxyeDirAdmin,ou=sa,o=system
> java.naming.security.authentication: simple
> java.naming.security.credentials: ********
> java.naming.ldap.factory.socket:
> internal.osp.framework.util.net.client.OSP_SocketFactory
> Naming error: javax.naming.ServiceUnavailableException:
> 172.31.104.48:389; socket closed
> Error creating new connection:



I think this is likely an issue. LDAP connection to look user up is
failing.

0 Likes
sathish10 Contributor.
Contributor.

Re: Login Issue - User Application Dashborad 4.6.3

Hi,

Please find the below 'ndstrace.log' while we faced the login issue.

3305920256 LDAP: [2019/05/23 11:18:01.282] (172.31.104.45:49587)(0x001b:0x63) Sending operation result 0:"":"" to connection 0x8197180
4182013696 LDAP: [2019/05/23 11:18:09.443] Work info status: Total:4 Peak:2 Busy:0
3492259584 LDAP: [2019/05/23 11:18:10.129] (172.31.104.45:50091)(0x0004:0x63) DoSearch on connection 0xd1ad180
3492259584 LDAP: [2019/05/23 11:18:10.129] (172.31.104.45:50091)(0x0004:0x63) Search request:
base: "o=abc"
scope:2 dereference:3 sizelimit:0 timelimit:0 attrsonly:0
filter: "(&(objectClass=User)(|(cn=xsq)(mail=xsq)))"
attribute: "cn"
attribute: "fullName"
attribute: "givenName"
attribute: "GUID"
attribute: "initials"
attribute: "loginIntruderAttempts"
attribute: "mail"
attribute: "mobile"
attribute: "nrfMemberOf"
attribute: "objectClass"
attribute: "sn"
attribute: "srvprvPreferredLocale"
3492259584 LDAP: [2019/05/23 11:18:10.129] (172.31.104.45:50091)(0x0004:0x63) nds_back_search: Search Control OID 2.16.840.1.113730.3.4.2
3492259584 LDAP: [2019/05/23 11:18:10.134] (172.31.104.45:50091)(0x0004:0x63) Sending search result entry "cn=Z8XSQ,ou=contractors,ou=users,o=abc" to connection 0xd1ad180
3492259584 LDAP: [2019/05/23 11:18:10.134] (172.31.104.45:50091)(0x0004:0x63) Sending operation result 0:"":"" to connection 0xd1ad180
4188329728 LDAP: [2019/05/23 11:18:10.139] New cleartext connection 0x1c72d180 from 172.31.104.45:50092, monitor = 0xf9548700, index = 63
3493312256 LDAP: [2019/05/23 11:18:10.140] (172.31.104.45:50092)(0x0001:0x60) DoBind on connection 0x1c72d180
3493312256 LDAP: [2019/05/23 11:18:10.140] (172.31.104.45:50092)(0x0001:0x60) Bind name:cn=Z8XSQ,ou=contractors,ou=users,o=abc, version:3, authentication:simple
3479488256 LDAP: [2019/05/23 11:18:16.333] (172.31.104.45:64952)(0x09ba:0x63) DoSearch on connection 0xe81e000
3479488256 LDAP: [2019/05/23 11:18:16.333] (172.31.104.45:64952)(0x09ba:0x63) Search request:
base: "o=abc"
scope:2 dereference:0 sizelimit:0 timelimit:0 attrsonly:0
filter: "(&(givenName=bpt*))"
attribute: "initials"
attribute: "givenName"
attribute: "sn"
attribute: "groupMembership"
attribute: "srvprvHideUser"
attribute: "srvprvHideAttributes"
attribute: "modifyTimeStamp"
attribute: "objectClass"
3479488256 LDAP: [2019/05/23 11:18:16.333] (172.31.104.45:64952)(0x09ba:0x63) nds_back_search: Search Control OID 2.16.840.1.113730.3.4.2
3479488256 LDAP: [2019/05/23 11:18:16.334] (172.31.104.45:64952)(0x09ba:0x63) Sending operation result 0:"":"" to connection 0xe81e000
3305920256 LDAP: [2019/05/23 11:18:16.335] (172.31.104.45:64952)(0x09bb:0x63) DoSearch on connection 0xe81e000
3305920256 LDAP: [2019/05/23 11:18:16.335] (172.31.104.45:64952)(0x09bb:0x63) Search request:
base: "o=abc"
scope:2 dereference:0 sizelimit:0 timelimit:0 attrsonly:0
filter: "(&(sn=bpt*))"
attribute: "initials"
attribute: "givenName"
attribute: "sn"
attribute: "groupMembership"
attribute: "srvprvHideUser"
attribute: "srvprvHideAttributes"
attribute: "modifyTimeStamp"
attribute: "objectClass"
3305920256 LDAP: [2019/05/23 11:18:16.335] (172.31.104.45:64952)(0x09bb:0x63) nds_back_search: Search Control OID 2.16.840.1.113730.3.4.2
3305920256 LDAP: [2019/05/23 11:18:16.338] (172.31.104.45:64952)(0x09bb:0x63) Sending operation result 0:"":"" to connection 0xe81e000
3492259584 LDAP: [2019/05/23 11:18:16.338] (172.31.104.45:64952)(0x09bc:0x63) DoSearch on connection 0xe81e000
3492259584 LDAP: [2019/05/23 11:18:16.338] (172.31.104.45:64952)(0x09bc:0x63) Search request:
base: "o=abc"
scope:2 dereference:0 sizelimit:0 timelimit:0 attrsonly:0
filter: "(&(initials=bpt*))"
attribute: "initials"
attribute: "givenName"
attribute: "sn"
attribute: "groupMembership"
attribute: "srvprvHideUser"
attribute: "srvprvHideAttributes"
attribute: "modifyTimeStamp"
attribute: "objectClass"
3492259584 LDAP: [2019/05/23 11:18:16.338] (172.31.104.45:64952)(0x09bc:0x63) nds_back_search: Search Control OID 2.16.840.1.113730.3.4.2
3492259584 LDAP: [2019/05/23 11:18:16.340] (172.31.104.45:64952)(0x09bc:0x63) Sending search result entry "cn=Z5BPT,ou=employees,ou=users,o=abc" to connection 0xe81e000
3492259584 LDAP: [2019/05/23 11:18:16.340] (172.31.104.45:64952)(0x09bc:0x63) Sending operation result 0:"":"" to connection 0xe81e000
3463128832 LDAP: [2019/05/23 11:18:17.513] (172.31.104.45:64952)(0x09bd:0x63) DoSearch on connection 0xe81e000
3463128832 LDAP: [2019/05/23 11:18:17.513] (172.31.104.45:64952)(0x09bd:0x63) nds_back_search: Search Control OID 2.16.840.1.113730.3.4.2
3463128832 LDAP: [2019/05/23 11:18:17.523] (172.31.104.45:64952)(0x09bd:0x63) Sending search result entry "cn=Z5BPT,ou=employees,ou=users,o=abc" to connection 0xe81e000
3463128832 LDAP: [2019/05/23 11:18:17.524] (172.31.104.45:64952)(0x09bd:0x63) Sending operation result 0:"":"" to connection 0xe81e000
3418662656 LDAP: [2019/05/23 11:18:17.531] (172.31.104.45:64952)(0x09be:0x63) DoSearch on connection 0xe81e000
3418662656 LDAP: [2019/05/23 11:18:17.532] (172.31.104.45:64952)(0x09be:0x63) nds_back_search: Search Control OID 2.16.840.1.113730.3.4.2
3418662656 LDAP: [2019/05/23 11:18:17.537] (172.31.104.45:64952)(0x09be:0x63) Sending search result entry "cn=Z5BPT,ou=employees,ou=users,o=abc" to connection 0xe81e000
3418662656 LDAP: [2019/05/23 11:18:17.538] (172.31.104.45:64952)(0x09be:0x63) Sending operation result 0:"":"" to connection 0xe81e000
3463128832 LDAP: [2019/05/23 11:18:17.545] (172.31.104.45:64952)(0x09bf:0x63) DoSearch on connection 0xe81e000
3463128832 LDAP: [2019/05/23 11:18:17.545] (172.31.104.45:64952)(0x09bf:0x63) nds_back_search: Search Control OID 2.16.840.1.113730.3.4.2
3463128832 LDAP: [2019/05/23 11:18:17.550] (172.31.104.45:64952)(0x09bf:0x63) Sending search result entry "cn=Z5BPT,ou=employees,ou=users,o=abc" to connection 0xe81e000
3463128832 LDAP: [2019/05/23 11:18:17.551] (172.31.104.45:64952)(0x09bf:0x63) Sending operation result 0:"":"" to connection 0xe81e000
3418662656 LDAP: [2019/05/23 11:18:17.557] (172.31.104.45:64952)(0x09c0:0x63) DoSearch on connection 0xe81e000
3418662656 LDAP: [2019/05/23 11:18:17.557] (172.31.104.45:64952)(0x09c0:0x63) nds_back_search: Search Control OID 2.16.840.1.113730.3.4.2
3418662656 LDAP: [2019/05/23 11:18:17.562] (172.31.104.45:64952)(0x09c0:0x63) Sending search result entry "cn=Z5BPT,ou=employees,ou=users,o=abc" to connection 0xe81e000
3418662656 LDAP: [2019/05/23 11:18:17.563] (172.31.104.45:64952)(0x09c0:0x63) Sending operation result 0:"":"" to connection 0xe81e000
3463128832 LDAP: [2019/05/23 11:18:17.569] (172.31.104.45:64952)(0x09c1:0x63) DoSearch on connection 0xe81e000
3463128832 LDAP: [2019/05/23 11:18:17.569] (172.31.104.45:64952)(0x09c1:0x63) nds_back_search: Search Control OID 2.16.840.1.113730.3.4.2
3463128832 LDAP: [2019/05/23 11:18:17.575] (172.31.104.45:64952)(0x09c1:0x63) Sending search result entry "cn=Z5BPT,ou=employees,ou=users,o=abc" to connection 0xe81e000
3463128832 LDAP: [2019/05/23 11:18:17.575] (172.31.104.45:64952)(0x09c1:0x63) Sending operation result 0:"":"" to connection 0xe81e000
3418662656 LDAP: [2019/05/23 11:18:17.582] (172.31.104.45:64952)(0x09c2:0x63) DoSearch on connection 0xe81e000
3418662656 LDAP: [2019/05/23 11:18:17.582] (172.31.104.45:64952)(0x09c2:0x63) nds_back_search: Search Control OID 2.16.840.1.113730.3.4.2
3418662656 LDAP: [2019/05/23 11:18:17.588] (172.31.104.45:64952)(0x09c2:0x63) Sending search result entry "cn=Z5BPT,ou=employees,ou=users,o=abc" to connection 0xe81e000
3418662656 LDAP: [2019/05/23 11:18:17.588] (172.31.104.45:64952)(0x09c2:0x63) Sending operation result 0:"":"" to connection 0xe81e000
3463128832 LDAP: [2019/05/23 11:18:17.595] (172.31.104.45:64952)(0x09c3:0x63) DoSearch on connection 0xe81e000
3463128832 LDAP: [2019/05/23 11:18:17.595] (172.31.104.45:64952)(0x09c3:0x63) nds_back_search: Search Control OID 2.16.840.1.113730.3.4.2
3463128832 LDAP: [2019/05/23 11:18:17.601] (172.31.104.45:64952)(0x09c3:0x63) Sending search result entry "cn=Z5BPT,ou=employees,ou=users,o=abc" to connection 0xe81e000
3463128832 LDAP: [2019/05/23 11:18:17.601] (172.31.104.45:64952)(0x09c3:0x63) Sending operation result 0:"":"" to connection 0xe81e000
3418662656 LDAP: [2019/05/23 11:18:17.608] (172.31.104.45:64952)(0x09c4:0x63) DoSearch on connection 0xe81e000
3418662656 LDAP: [2019/05/23 11:18:17.608] (172.31.104.45:64952)(0x09c4:0x63) nds_back_search: Search Control OID 2.16.840.1.113730.3.4.2
3418662656 LDAP: [2019/05/23 11:18:17.613] (172.31.104.45:64952)(0x09c4:0x63) Sending search result entry "cn=Z5BPT,ou=employees,ou=users,o=abc" to connection 0xe81e000
3418662656 LDAP: [2019/05/23 11:18:17.614] (172.31.104.45:64952)(0x09c4:0x63) Sending operation result 0:"":"" to connection 0xe81e000
3463128832 LDAP: [2019/05/23 11:18:17.622] (172.31.104.45:64952)(0x09c5:0x63) DoSearch on connection 0xe81e000
3463128832 LDAP: [2019/05/23 11:18:17.622] (172.31.104.45:64952)(0x09c5:0x63) Search request:
base: "CN=BPT,OU=employees,OU=users,O=abc"
scope:0 dereference:0 sizelimit:0 timelimit:0 attrsonly:0
filter: "(objectClass=*)"
attribute: "abcADUnLock"
attribute: "mail"
attribute: "abcReleaseDate"
attribute: "KmdNextempstatus"
attribute: "eMailAddress"
attribute: "workforceID"
attribute: "employeeStatus"
attribute: "passwordExpirationTime"
attribute: "KmdDepartmentID"
attribute: "abcOrgUnit"
attribute: "abcADDeleteFlag"
attribute: "KmdNextstatusdate"
attribute: "sn"
attribute: "loginDisabled"
attribute: "abcStartDate"
attribute: "manager"
attribute: "initials"
attribute: "costCenter"
attribute: "givenName"
attribute: "abcEndDate"
attribute: "fullName"
attribute: "abcCommercialTitle"
attribute: "cn"
attribute: "employeeType"
attribute: "abcCPRNumber"
attribute: "lockedByIntruder"
attribute: "groupMembership"
attribute: "srvprvHideUser"
attribute: "srvprvHideAttributes"
attribute: "modifyTimeStamp"
attribute: "objectClass"
3463128832 LDAP: [2019/05/23 11:18:17.622] (172.31.104.45:64952)(0x09c5:0x63) nds_back_search: Search Control OID 2.16.840.1.113730.3.4.2
3463128832 LDAP: [2019/05/23 11:18:17.624] (172.31.104.45:64952)(0x09c5:0x63) Sending search result entry "cn=BPT,ou=employees,ou=users,o=abc" to connection 0xe81e000
3463128832 LDAP: [2019/05/23 11:18:17.624] (172.31.104.45:64952)(0x09c5:0x63) Sending operation result 0:"":"" to connection 0xe81e000
3418662656 LDAP: [2019/05/23 11:18:17.630] (172.31.104.45:64952)(0x09c6:0x63) DoSearch on connection 0xe81e000
3418662656 LDAP: [2019/05/23 11:18:17.630] (172.31.104.45:64952)(0x09c6:0x63) Search request:


Thanks,
Sathish
0 Likes
cpedersen Outstanding Contributor.
Outstanding Contributor.

Re: Login Issue - User Application Dashborad 4.6.3

On 23.05.19 11:44, sathish10 wrote:
>
> Hi,
>
> Please find the below 'ndstrace.log' while we faced the login issue.
>
> 3305920256 LDAP: [2019/05/23 11:18:01.282]
> (172.31.104.45:49587)(0x001b:0x63) Sending operation result 0:"":"" to
> connection 0x8197180
> 4182013696 LDAP: [2019/05/23 11:18:09.443] Work info status: Total:4
> Peak:2 Busy:0
> 3492259584 LDAP: [2019/05/23 11:18:10.129]
> (172.31.104.45:50091)(0x0004:0x63) DoSearch on connection 0xd1ad180
> 3492259584 LDAP: [2019/05/23 11:18:10.129]
> (172.31.104.45:50091)(0x0004:0x63) Search request:
> base: "o=abc"
> scope:2 dereference:3 sizelimit:0 timelimit:0 attrsonly:0
> filter: "(&(objectClass=User)(|(cn=xsq)(mail=xsq)))"
> attribute: "cn"
> attribute: "fullName"
> attribute: "givenName"
> attribute: "GUID"
> attribute: "initials"
> attribute: "loginIntruderAttempts"
> attribute: "mail"
> attribute: "mobile"
> attribute: "nrfMemberOf"
> attribute: "objectClass"
> attribute: "sn"
> attribute: "srvprvPreferredLocale"
> 3492259584 LDAP: [2019/05/23 11:18:10.129]
> (172.31.104.45:50091)(0x0004:0x63) nds_back_search: Search Control OID
> 2.16.840.1.113730.3.4.2
> 3492259584 LDAP: [2019/05/23 11:18:10.134]
> (172.31.104.45:50091)(0x0004:0x63) Sending search result entry
> "cn=Z8XSQ,ou=contractors,ou=users,o=abc" to connection 0xd1ad180
> 3492259584 LDAP: [2019/05/23 11:18:10.134]
> (172.31.104.45:50091)(0x0004:0x63) Sending operation result 0:"":"" to
> connection 0xd1ad180
> 4188329728 LDAP: [2019/05/23 11:18:10.139] New cleartext connection
> 0x1c72d180 from 172.31.104.45:50092, monitor = 0xf9548700, index = 63
> 3493312256 LDAP: [2019/05/23 11:18:10.140]
> (172.31.104.45:50092)(0x0001:0x60) DoBind on connection 0x1c72d180
> 3493312256 LDAP: [2019/05/23 11:18:10.140]
> (172.31.104.45:50092)(0x0001:0x60) Bind
> name:cn=Z8XSQ,ou=contractors,ou=users,o=abc, version:3,
> authentication:simple
> 3479488256 LDAP: [2019/05/23 11:18:16.333]
> (172.31.104.45:64952)(0x09ba:0x63) DoSearch on connection 0xe81e000
> 3479488256 LDAP: [2019/05/23 11:18:16.333]
> (172.31.104.45:64952)(0x09ba:0x63) Search request:
> base: "o=abc"
> scope:2 dereference:0 sizelimit:0 timelimit:0 attrsonly:0
> filter: "(&(givenName=bpt*))"
> attribute: "initials"
> attribute: "givenName"
> attribute: "sn"
> attribute: "groupMembership"
> attribute: "srvprvHideUser"
> attribute: "srvprvHideAttributes"
> attribute: "modifyTimeStamp"
> attribute: "objectClass"
> 3479488256 LDAP: [2019/05/23 11:18:16.333]
> (172.31.104.45:64952)(0x09ba:0x63) nds_back_search: Search Control OID
> 2.16.840.1.113730.3.4.2
> 3479488256 LDAP: [2019/05/23 11:18:16.334]
> (172.31.104.45:64952)(0x09ba:0x63) Sending operation result 0:"":"" to
> connection 0xe81e000
> 3305920256 LDAP: [2019/05/23 11:18:16.335]
> (172.31.104.45:64952)(0x09bb:0x63) DoSearch on connection 0xe81e000
> 3305920256 LDAP: [2019/05/23 11:18:16.335]
> (172.31.104.45:64952)(0x09bb:0x63) Search request:
> base: "o=abc"
> scope:2 dereference:0 sizelimit:0 timelimit:0 attrsonly:0
> filter: "(&(sn=bpt*))"
> attribute: "initials"
> attribute: "givenName"
> attribute: "sn"
> attribute: "groupMembership"
> attribute: "srvprvHideUser"
> attribute: "srvprvHideAttributes"
> attribute: "modifyTimeStamp"
> attribute: "objectClass"
> 3305920256 LDAP: [2019/05/23 11:18:16.335]
> (172.31.104.45:64952)(0x09bb:0x63) nds_back_search: Search Control OID
> 2.16.840.1.113730.3.4.2
> 3305920256 LDAP: [2019/05/23 11:18:16.338]
> (172.31.104.45:64952)(0x09bb:0x63) Sending operation result 0:"":"" to
> connection 0xe81e000
> 3492259584 LDAP: [2019/05/23 11:18:16.338]
> (172.31.104.45:64952)(0x09bc:0x63) DoSearch on connection 0xe81e000
> 3492259584 LDAP: [2019/05/23 11:18:16.338]
> (172.31.104.45:64952)(0x09bc:0x63) Search request:
> base: "o=abc"
> scope:2 dereference:0 sizelimit:0 timelimit:0 attrsonly:0
> filter: "(&(initials=bpt*))"
> attribute: "initials"
> attribute: "givenName"
> attribute: "sn"
> attribute: "groupMembership"
> attribute: "srvprvHideUser"
> attribute: "srvprvHideAttributes"
> attribute: "modifyTimeStamp"
> attribute: "objectClass"
> 3492259584 LDAP: [2019/05/23 11:18:16.338]
> (172.31.104.45:64952)(0x09bc:0x63) nds_back_search: Search Control OID
> 2.16.840.1.113730.3.4.2
> 3492259584 LDAP: [2019/05/23 11:18:16.340]
> (172.31.104.45:64952)(0x09bc:0x63) Sending search result entry
> "cn=Z5BPT,ou=employees,ou=users,o=abc" to connection 0xe81e000
> 3492259584 LDAP: [2019/05/23 11:18:16.340]
> (172.31.104.45:64952)(0x09bc:0x63) Sending operation result 0:"":"" to
> connection 0xe81e000
> 3463128832 LDAP: [2019/05/23 11:18:17.513]
> (172.31.104.45:64952)(0x09bd:0x63) DoSearch on connection 0xe81e000
> 3463128832 LDAP: [2019/05/23 11:18:17.513]
> (172.31.104.45:64952)(0x09bd:0x63) nds_back_search: Search Control OID
> 2.16.840.1.113730.3.4.2
> 3463128832 LDAP: [2019/05/23 11:18:17.523]
> (172.31.104.45:64952)(0x09bd:0x63) Sending search result entry
> "cn=Z5BPT,ou=employees,ou=users,o=abc" to connection 0xe81e000
> 3463128832 LDAP: [2019/05/23 11:18:17.524]
> (172.31.104.45:64952)(0x09bd:0x63) Sending operation result 0:"":"" to
> connection 0xe81e000
> 3418662656 LDAP: [2019/05/23 11:18:17.531]
> (172.31.104.45:64952)(0x09be:0x63) DoSearch on connection 0xe81e000
> 3418662656 LDAP: [2019/05/23 11:18:17.532]
> (172.31.104.45:64952)(0x09be:0x63) nds_back_search: Search Control OID
> 2.16.840.1.113730.3.4.2
> 3418662656 LDAP: [2019/05/23 11:18:17.537]
> (172.31.104.45:64952)(0x09be:0x63) Sending search result entry
> "cn=Z5BPT,ou=employees,ou=users,o=abc" to connection 0xe81e000
> 3418662656 LDAP: [2019/05/23 11:18:17.538]
> (172.31.104.45:64952)(0x09be:0x63) Sending operation result 0:"":"" to
> connection 0xe81e000
> 3463128832 LDAP: [2019/05/23 11:18:17.545]
> (172.31.104.45:64952)(0x09bf:0x63) DoSearch on connection 0xe81e000
> 3463128832 LDAP: [2019/05/23 11:18:17.545]
> (172.31.104.45:64952)(0x09bf:0x63) nds_back_search: Search Control OID
> 2.16.840.1.113730.3.4.2
> 3463128832 LDAP: [2019/05/23 11:18:17.550]
> (172.31.104.45:64952)(0x09bf:0x63) Sending search result entry
> "cn=Z5BPT,ou=employees,ou=users,o=abc" to connection 0xe81e000
> 3463128832 LDAP: [2019/05/23 11:18:17.551]
> (172.31.104.45:64952)(0x09bf:0x63) Sending operation result 0:"":"" to
> connection 0xe81e000
> 3418662656 LDAP: [2019/05/23 11:18:17.557]
> (172.31.104.45:64952)(0x09c0:0x63) DoSearch on connection 0xe81e000
> 3418662656 LDAP: [2019/05/23 11:18:17.557]
> (172.31.104.45:64952)(0x09c0:0x63) nds_back_search: Search Control OID
> 2.16.840.1.113730.3.4.2
> 3418662656 LDAP: [2019/05/23 11:18:17.562]
> (172.31.104.45:64952)(0x09c0:0x63) Sending search result entry
> "cn=Z5BPT,ou=employees,ou=users,o=abc" to connection 0xe81e000
> 3418662656 LDAP: [2019/05/23 11:18:17.563]
> (172.31.104.45:64952)(0x09c0:0x63) Sending operation result 0:"":"" to
> connection 0xe81e000
> 3463128832 LDAP: [2019/05/23 11:18:17.569]
> (172.31.104.45:64952)(0x09c1:0x63) DoSearch on connection 0xe81e000
> 3463128832 LDAP: [2019/05/23 11:18:17.569]
> (172.31.104.45:64952)(0x09c1:0x63) nds_back_search: Search Control OID
> 2.16.840.1.113730.3.4.2
> 3463128832 LDAP: [2019/05/23 11:18:17.575]
> (172.31.104.45:64952)(0x09c1:0x63) Sending search result entry
> "cn=Z5BPT,ou=employees,ou=users,o=abc" to connection 0xe81e000
> 3463128832 LDAP: [2019/05/23 11:18:17.575]
> (172.31.104.45:64952)(0x09c1:0x63) Sending operation result 0:"":"" to
> connection 0xe81e000
> 3418662656 LDAP: [2019/05/23 11:18:17.582]
> (172.31.104.45:64952)(0x09c2:0x63) DoSearch on connection 0xe81e000
> 3418662656 LDAP: [2019/05/23 11:18:17.582]
> (172.31.104.45:64952)(0x09c2:0x63) nds_back_search: Search Control OID
> 2.16.840.1.113730.3.4.2
> 3418662656 LDAP: [2019/05/23 11:18:17.588]
> (172.31.104.45:64952)(0x09c2:0x63) Sending search result entry
> "cn=Z5BPT,ou=employees,ou=users,o=abc" to connection 0xe81e000
> 3418662656 LDAP: [2019/05/23 11:18:17.588]
> (172.31.104.45:64952)(0x09c2:0x63) Sending operation result 0:"":"" to
> connection 0xe81e000
> 3463128832 LDAP: [2019/05/23 11:18:17.595]
> (172.31.104.45:64952)(0x09c3:0x63) DoSearch on connection 0xe81e000
> 3463128832 LDAP: [2019/05/23 11:18:17.595]
> (172.31.104.45:64952)(0x09c3:0x63) nds_back_search: Search Control OID
> 2.16.840.1.113730.3.4.2
> 3463128832 LDAP: [2019/05/23 11:18:17.601]
> (172.31.104.45:64952)(0x09c3:0x63) Sending search result entry
> "cn=Z5BPT,ou=employees,ou=users,o=abc" to connection 0xe81e000
> 3463128832 LDAP: [2019/05/23 11:18:17.601]
> (172.31.104.45:64952)(0x09c3:0x63) Sending operation result 0:"":"" to
> connection 0xe81e000
> 3418662656 LDAP: [2019/05/23 11:18:17.608]
> (172.31.104.45:64952)(0x09c4:0x63) DoSearch on connection 0xe81e000
> 3418662656 LDAP: [2019/05/23 11:18:17.608]
> (172.31.104.45:64952)(0x09c4:0x63) nds_back_search: Search Control OID
> 2.16.840.1.113730.3.4.2
> 3418662656 LDAP: [2019/05/23 11:18:17.613]
> (172.31.104.45:64952)(0x09c4:0x63) Sending search result entry
> "cn=Z5BPT,ou=employees,ou=users,o=abc" to connection 0xe81e000
> 3418662656 LDAP: [2019/05/23 11:18:17.614]
> (172.31.104.45:64952)(0x09c4:0x63) Sending operation result 0:"":"" to
> connection 0xe81e000
> 3463128832 LDAP: [2019/05/23 11:18:17.622]
> (172.31.104.45:64952)(0x09c5:0x63) DoSearch on connection 0xe81e000
> 3463128832 LDAP: [2019/05/23 11:18:17.622]
> (172.31.104.45:64952)(0x09c5:0x63) Search request:
> base: "CN=BPT,OU=employees,OU=users,O=abc"
> scope:0 dereference:0 sizelimit:0 timelimit:0 attrsonly:0
> filter: "(objectClass=*)"
> attribute: "abcADUnLock"
> attribute: "mail"
> attribute: "abcReleaseDate"
> attribute: "KmdNextempstatus"
> attribute: "eMailAddress"
> attribute: "workforceID"
> attribute: "employeeStatus"
> attribute: "passwordExpirationTime"
> attribute: "KmdDepartmentID"
> attribute: "abcOrgUnit"
> attribute: "abcADDeleteFlag"
> attribute: "KmdNextstatusdate"
> attribute: "sn"
> attribute: "loginDisabled"
> attribute: "abcStartDate"
> attribute: "manager"
> attribute: "initials"
> attribute: "costCenter"
> attribute: "givenName"
> attribute: "abcEndDate"
> attribute: "fullName"
> attribute: "abcCommercialTitle"
> attribute: "cn"
> attribute: "employeeType"
> attribute: "abcCPRNumber"
> attribute: "lockedByIntruder"
> attribute: "groupMembership"
> attribute: "srvprvHideUser"
> attribute: "srvprvHideAttributes"
> attribute: "modifyTimeStamp"
> attribute: "objectClass"
> 3463128832 LDAP: [2019/05/23 11:18:17.622]
> (172.31.104.45:64952)(0x09c5:0x63) nds_back_search: Search Control OID
> 2.16.840.1.113730.3.4.2
> 3463128832 LDAP: [2019/05/23 11:18:17.624]
> (172.31.104.45:64952)(0x09c5:0x63) Sending search result entry
> "cn=BPT,ou=employees,ou=users,o=abc" to connection 0xe81e000
> 3463128832 LDAP: [2019/05/23 11:18:17.624]
> (172.31.104.45:64952)(0x09c5:0x63) Sending operation result 0:"":"" to
> connection 0xe81e000
> 3418662656 LDAP: [2019/05/23 11:18:17.630]
> (172.31.104.45:64952)(0x09c6:0x63) DoSearch on connection 0xe81e000
> 3418662656 LDAP: [2019/05/23 11:18:17.630]
> (172.31.104.45:64952)(0x09c6:0x63) Search request:
>
>
> Thanks,
> Sathish
>
>

Hi,

This is the error:

Preamble: [OIDP]
Priority Level: FINER
Java: internal.osp.oidp.service.source.ldap.LDAPSource.search() [703]
thread=https-jsse-nio-8444-exec-837
Time: 2019-05-03T10:52:25.216+0200
Elapsed time: 14.999 seconds
Log Data: Search for LDAP principal:
Store: LDAP Directory Data Source(id=idm_idv)
Admin search:
Context: o=ABC
Scope: subtree
Filter: (&(objectClass=User)(|(cn=user)(mail=user)))
Attributes: cn, fullName, givenName, GUID, initials,
loginIntruderAttempts, mail, mobile, nrfMemberOf, objectClass, sn,
srvprvPreferredLocale
Get next available admin connection:
Get admin connection from pool:
Pool:
PL41d53ada-99e3-4692-b5fa-ea4c546d2053:fd8e5f32-67c5-4388-9a7f-3b5a9708e72c
Reserve connection:
Type: ADMIN_CONNECTION
Retired admin connection count: 1
New reservation (non-existing):
75be42c7-42ec-4387-b3b7-c210f85fe288
New LDAP connection:
Connection: 28b9b888-3eaa-4451-8d5a-f734675548ea
User store: LDAP Directory Data Source(id=idm_idv)
Replica: LDAP Directory Data
Source/172.31.104.48:389(id=fd8e5f32-67c5-4388-9a7f-3b5a9708e72c)
Username: cn=proxyeDirAdmin,ou=sa,o=system
Type: ADMIN_CONNECTION
Parameters:
java.naming.factory.initial:
com.sun.jndi.ldap.LdapCtxFactory
java.naming.provider.url: ldap://172.31.104.48
com.sun.jndi.ldap.connect.timeout: 15000
java.naming.security.principal:
cn=proxyeDirAdmin,ou=sa,o=system
java.naming.security.authentication: simple
java.naming.security.credentials: ********
java.naming.ldap.factory.socket:
internal.osp.framework.util.net.client.OSP_SocketFactory
Naming error: javax.naming.ServiceUnavailableException:
172.31.104.48:389; socket closed
Error creating new connection:


Somehow it cannot connect to eDirectory at this point in time.


Casper
Knowledge Partner
Knowledge Partner

Re: Login Issue - User Application Dashborad 4.6.3

On 5/23/2019 10:20 AM, Casper Pedersen wrote:
> Elapsed time: 14.999 seconds
> com.sun.jndi.ldap.connect.timeout: 15000


And then the filter is:
(&(objectClass=User)(|(cn=user)(mail=user)))

Actually I think this is the issue. Do you have an index on ObjectClass?
Or Internet EMail Address?

I found that switching my secondary login attribute from mail to uid
(which IS indexed) instantly fixed the issue. Took less than a second
on a 1 million object directory, where it was timing out every time.

ObjectClass indexes are generically good I believe. Mail is probably not
a bad idea.



0 Likes
sma2006 Outstanding Contributor.
Outstanding Contributor.

Re: Login Issue - User Application Dashborad 4.6.3

Hello,

I think I have the same issue with IDM Dashboard 4.7.3.

I can see the same filter search in LDAP trace, but I don't understand where did you change the secondary login attribute ?

Is this in userapp config , and where ?

Thanks a lot.

 

Sylvain

 

Knowledge Partner
Knowledge Partner

Re: Login Issue - User Application Dashborad 4.6.3

The secondary login attribute is available in configupdate.sh in the GUI or in the ism-configuration.properties file as:

 

com.netiq.idm.osp.as.duplicate-resolution-naming-attr = mail

 

I think it was that one.

Mail (Internet EMail Address) as an indexed attribute is 'expensive' since the addresses are usually 'long' and substrings are expensive to maintain on long strings.  But you can do it.

 

 

 

0 Likes
The opinions expressed above are the personal opinions of the authors, not of Micro Focus. By using this site, you accept the Terms of Use and Rules of Participation. Certain versions of content ("Material") accessible here may contain branding from Hewlett-Packard Company (now HP Inc.) and Hewlett Packard Enterprise Company. As of September 1, 2017, the Material is now offered by Micro Focus, a separately owned and operated company. Any reference to the HP and Hewlett Packard Enterprise/HPE marks is historical in nature, and the HP and Hewlett Packard Enterprise/HPE marks are the property of their respective owners.