Skip to content

Instantly share code, notes, and snippets.

@fumiyas

fumiyas/inspect.md

Last active November 24, 2017 07:26
Show Gist options
  • Star 0 You must be signed in to star a gist
  • Fork 0 You must be signed in to fork a gist
  • Save fumiyas/083c98c623706449399608dafbffd0cc to your computer and use it in GitHub Desktop.
Save fumiyas/083c98c623706449399608dafbffd0cc to your computer and use it in GitHub Desktop.
Download ZIP
デスクトップSSOが効かない問題のデバッグ
Raw
inspect.md 
DSAMECAllbackhandler...[Ljavax.security.auth.callback.Callback;@13e342ec
amAuthWindowsDesktopSSO:11/24/2017 04:13:22:199 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
WindowsDesktopSSO params: 
principal: HTTP/desktop.sso.example.co.jp@DOMAIN.EXAMPLE.CO.JP
keytab file: /opt/osstech/var/lib/tomcat/openam/private/HTTP_desktop.sso.example.co.jp@DOMAIN.EXAMPLE.CO.JP.keytab
realm : DOMAIN.EXAMPLE.CO.JP
kdc server: dc1.domain.example.co.jp
domain principal: false
Lookup user in realm:false
Accepted Kerberos realms: []
auth level: 0
amAuthWindowsDesktopSSO:11/24/2017 04:13:22:199 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
Retrieved config params from cache.
amAuth:11/24/2017 04:13:22:199 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
spi authLevel :0
amAuth:11/24/2017 04:13:22:199 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
module configuration authLevel :0
amAuth:11/24/2017 04:13:22:199 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
levelSet :false
amAuthWindowsDesktopSSO:11/24/2017 04:13:22:200 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
SPNEGO token: 
4e 54 4c 4d 53 53 50 00 01 00 00 00 97 82 08 e2 
00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 
06 01 b1 1d 00 00 00 0f 
amAuthWindowsDesktopSSO:11/24/2017 04:13:22:200 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
WARNING: spnego token is NTLMSSP.
amLoginModule:11/24/2017 04:13:22:200 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
Login NEXT State : 2
amLoginModule:11/24/2017 04:13:22:200 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
Login, state = 2
amLoginModule:11/24/2017 04:13:22:200 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
clone #0 is PagePropertiesCallback
amCallback:11/24/2017 04:13:22:200 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
callback handler method
amAuth:11/24/2017 04:13:22:200 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
Setting page timeout :120
amAuth:11/24/2017 04:13:22:200 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
setting Last Callback Sent :1511507602200
amCallback:11/24/2017 04:13:22:200 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
Set callbacks, throwing java.lang.Error.
amJAAS:11/24/2017 04:13:22:200 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
LoginContext.invoke(): Handling expected java.lang.Error
amAuth:11/24/2017 04:13:22:200 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
Caught error returned from DSAMEHandler
amAuth:11/24/2017 04:13:22:200 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
getStatus : status is... : 2
amAuth:11/24/2017 04:13:22:200 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
getStatus : status is... : 2
amAuthContextLocal:11/24/2017 04:13:22:200 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
AuthContextLocal::submitRequirements end
amAuthContextLocal:11/24/2017 04:13:22:200 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
Status at the end of submitRequirements() : in_progress
amAuthContextLocal:11/24/2017 04:13:22:201 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
AuthContextLocal::hasMoreRequirements()
amAuth:11/24/2017 04:13:22:201 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
getStatus : status is... : 2
amAuth:11/24/2017 04:13:22:201 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
getStatus : status is... : 2
amAuth:11/24/2017 04:13:22:201 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
Recd Callback in amlc.getRequiredInfo : com.sun.identity.authentication.spi.PagePropertiesCallback@406e3e0e
amAuth:11/24/2017 04:13:22:201 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
Recd Callback in amlc.getRequiredInfo : com.sun.identity.authentication.spi.HttpCallback@4993e0cd
amAuthContextLocal:11/24/2017 04:13:22:201 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
AuthContextLocal::getStatus()
amAuth:11/24/2017 04:13:22:201 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
getStatus : status is... : 2
amAuth:11/24/2017 04:13:22:201 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
getStatus : status is... : 2
amAuth:11/24/2017 04:13:22:201 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
getStatus : status is... : 2
amAuth:11/24/2017 04:13:22:201 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
getStatus : status is... : 2
amAuth:11/24/2017 04:13:22:201 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
getStatus : status is... : 2
amAuthContextLocal:11/24/2017 04:13:22:201 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
AuthContextLocal:: Status : in_progress
amAuthContextLocal:11/24/2017 04:13:22:201 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
AuthContextLocal::getRequirements()
amAuth:11/24/2017 04:13:22:201 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
getStatus : status is... : 2
amAuth:11/24/2017 04:13:22:201 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
getStatus : status is... : 2
amAuthContextLocal:11/24/2017 04:13:22:201 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
In getCallbacks() callback : com.sun.identity.authentication.spi.PagePropertiesCallback@406e3e0e
amAuthContextLocal:11/24/2017 04:13:22:201 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
In getCallbacks() callback : com.sun.identity.authentication.spi.HttpCallback@4993e0cd
amAuthREST:11/24/2017 04:13:22:201 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
Authorization Header not set in request.
amAuthUtils:11/24/2017 04:13:22:203 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
URL name : PostProcessLoginFailureURL Value : Not set - null or empty string
amAuth:11/24/2017 04:13:22:203 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
processURL : null
amAuthREST:11/24/2017 04:13:22:203 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
AuthenticationService.authenticate() :: Exception from CallbackHandler
org.forgerock.openam.core.rest.authn.exceptions.RestAuthResponseException: { "failure": true, "reason": "http-auth-failed" }
	at org.forgerock.openam.core.rest.authn.callbackhandlers.RestAuthHttpCallbackHandler.updateCallbackFromRequest(RestAuthHttpCallbackHandler.java:68)
	at org.forgerock.openam.core.rest.authn.callbackhandlers.RestAuthHttpCallbackHandler.updateCallbackFromRequest(RestAuthHttpCallbackHandler.java:36)
	at org.forgerock.openam.core.rest.authn.RestAuthCallbackHandlerManager.handleCallbacksInternally(RestAuthCallbackHandlerManager.java:107)
	at org.forgerock.openam.core.rest.authn.RestAuthCallbackHandlerManager.handleCallbacks(RestAuthCallbackHandlerManager.java:70)
	at org.forgerock.openam.core.rest.authn.RestAuthenticationHandler.handleCallbacks(RestAuthenticationHandler.java:320)
	at org.forgerock.openam.core.rest.authn.RestAuthenticationHandler.processAuthentication(RestAuthenticationHandler.java:246)
	at org.forgerock.openam.core.rest.authn.RestAuthenticationHandler.processAuthentication(RestAuthenticationHandler.java:264)
	at org.forgerock.openam.core.rest.authn.RestAuthenticationHandler.authenticate(RestAuthenticationHandler.java:168)
	at org.forgerock.openam.core.rest.authn.RestAuthenticationHandler.initiateAuthentication(RestAuthenticationHandler.java:99)
	at org.forgerock.openam.core.rest.authn.http.AuthenticationServiceV1.authenticate(AuthenticationServiceV1.java:142)
	at sun.reflect.GeneratedMethodAccessor44.invoke(Unknown Source)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:498)
	at org.forgerock.openam.http.annotations.AnnotatedMethod.invoke(AnnotatedMethod.java:81)
	at org.forgerock.openam.http.annotations.Endpoints$1.handle(Endpoints.java:68)
	at org.forgerock.http.routing.Router.handle(Router.java:92)
	at org.forgerock.openam.audit.AbstractHttpAccessAuditFilter.filter(AbstractHttpAccessAuditFilter.java:76)
	at org.forgerock.http.handler.Handlers$1.handle(Handlers.java:56)
	at org.forgerock.http.routing.Router.handle(Router.java:92)
	at org.forgerock.openam.rest.RealmContextFilter.filter(RealmContextFilter.java:84)
	at org.forgerock.http.handler.Handlers$1.handle(Handlers.java:56)
	at org.forgerock.http.routing.Router.handle(Router.java:92)
	at org.forgerock.http.routing.ResourceApiVersionRoutingFilter.filter(ResourceApiVersionRoutingFilter.java:64)
	at org.forgerock.http.handler.Handlers$1.handle(Handlers.java:56)
	at org.forgerock.caf.authentication.framework.AuthenticationFramework.grantAccess(AuthenticationFramework.java:220)
	at org.forgerock.caf.authentication.framework.AuthenticationFramework.access$400(AuthenticationFramework.java:65)
	at org.forgerock.caf.authentication.framework.AuthenticationFramework$3.apply(AuthenticationFramework.java:212)
	at org.forgerock.caf.authentication.framework.AuthenticationFramework$3.apply(AuthenticationFramework.java:205)
	at org.forgerock.util.promise.Promises$CompletedPromise.thenAsync(Promises.java:255)
	at org.forgerock.util.promise.Promises$CompletedPromise.thenAsync(Promises.java:244)
	at org.forgerock.caf.authentication.framework.AuthenticationFramework.validateRequest(AuthenticationFramework.java:168)
	at org.forgerock.caf.authentication.framework.AuthenticationFramework.access$100(AuthenticationFramework.java:65)
	at org.forgerock.caf.authentication.framework.AuthenticationFramework$1.apply(AuthenticationFramework.java:155)
	at org.forgerock.caf.authentication.framework.AuthenticationFramework$1.apply(AuthenticationFramework.java:152)
	at org.forgerock.util.promise.PromiseImpl$7.handleStateChange(PromiseImpl.java:485)
	at org.forgerock.util.promise.PromiseImpl.handleCompletion(PromiseImpl.java:567)
	at org.forgerock.util.promise.PromiseImpl.addOrFireListener(PromiseImpl.java:555)
	at org.forgerock.util.promise.PromiseImpl.thenAsync(PromiseImpl.java:477)
	at org.forgerock.util.promise.PromiseImpl.thenAsync(PromiseImpl.java:468)
	at org.forgerock.caf.authentication.framework.AuthenticationFramework.processMessage(AuthenticationFramework.java:146)
	at org.forgerock.caf.authentication.framework.AuthenticationFilter.filter(AuthenticationFilter.java:96)
	at org.forgerock.http.handler.Handlers$1.handle(Handlers.java:56)
	at org.forgerock.openam.http.HandlerProvider.handle(HandlerProvider.java:50)
	at org.forgerock.openam.http.HttpRoute$3.handle(HttpRoute.java:142)
	at org.forgerock.http.routing.Router.handle(Router.java:92)
	at org.forgerock.http.filter.TransactionIdInboundFilter.filter(TransactionIdInboundFilter.java:60)
	at org.forgerock.http.handler.Handlers$1.handle(Handlers.java:56)
	at org.forgerock.http.servlet.HttpFrameworkServlet.service(HttpFrameworkServlet.java:225)
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:731)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:303)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208)
	at org.apache.tomcat.websocket.server.WsFilter.doFilter(WsFilter.java:52)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:241)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208)
	at org.forgerock.openam.validation.ResponseValidationFilter.doFilter(ResponseValidationFilter.java:44)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:241)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208)
	at com.sun.identity.setup.AMSetupFilter.doFilter(AMSetupFilter.java:111)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:241)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208)
	at org.forgerock.openam.audit.context.AuditContextFilter.doFilter(AuditContextFilter.java:51)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:241)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208)
	at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:220)
	at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:122)
	at org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:505)
	at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:169)
	at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:103)
	at org.apache.catalina.valves.RemoteIpValve.invoke(RemoteIpValve.java:683)
	at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:116)
	at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:436)
	at org.apache.coyote.ajp.AjpProcessor.process(AjpProcessor.java:190)
	at org.apache.coyote.AbstractProtocol$AbstractConnectionHandler.process(AbstractProtocol.java:625)
	at org.apache.tomcat.util.net.JIoEndpoint$SocketProcessor.run(JIoEndpoint.java:316)
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
	at org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61)
	at java.lang.Thread.run(Thread.java:748)

amAuthClientUtils:11/24/2017 04:13:22:262 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
AuthUtils::decodeHash: clientEncoding='null', encoding='UTF-8'
amAuthClientUtils:11/24/2017 04:13:22:262 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
orgParam='/SiteUserRealm'
amAuthClientUtils:11/24/2017 04:13:22:262 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
getOrganizationDN : orgParam.='/SiteUserRealm', orgDN='o=siteuserrealm,ou=services,dc=openam,dc=osstech,dc=co,dc=jp'
amAuthClientUtils:11/24/2017 04:13:22:263 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
orgDN='o=siteuserrealm,ou=services,dc=openam,dc=osstech,dc=co,dc=jp'
amAuthClientUtils:11/24/2017 04:13:22:263 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
Error in getExistingValidSSOToken
com.iplanet.sso.SSOException: Invalid session ID.
	at com.iplanet.sso.providers.dpro.SSOProviderImpl.createSSOToken(SSOProviderImpl.java:220)
	at com.iplanet.sso.providers.dpro.SSOProviderImpl.createSSOToken(SSOProviderImpl.java:184)
	at com.iplanet.sso.providers.dpro.SSOProviderImpl.createSSOToken(SSOProviderImpl.java:236)
	at com.iplanet.sso.SSOTokenManager.createSSOToken(SSOTokenManager.java:367)
	at com.sun.identity.authentication.client.AuthClientUtils.getExistingValidSSOToken(AuthClientUtils.java:1812)
	at org.forgerock.openam.core.CoreServicesWrapper.getExistingValidSSOToken(CoreServicesWrapper.java:135)
	at org.forgerock.openam.core.rest.authn.core.LoginAuthenticator.getLoginProcess(LoginAuthenticator.java:86)
	at org.forgerock.openam.core.rest.authn.RestAuthenticationHandler.authenticate(RestAuthenticationHandler.java:166)
	at org.forgerock.openam.core.rest.authn.RestAuthenticationHandler.continueAuthentication(RestAuthenticationHandler.java:115)
	at org.forgerock.openam.core.rest.authn.http.AuthenticationServiceV1.authenticate(AuthenticationServiceV1.java:136)
	at sun.reflect.GeneratedMethodAccessor44.invoke(Unknown Source)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:498)
	at org.forgerock.openam.http.annotations.AnnotatedMethod.invoke(AnnotatedMethod.java:81)
	at org.forgerock.openam.http.annotations.Endpoints$1.handle(Endpoints.java:68)
	at org.forgerock.http.routing.Router.handle(Router.java:92)
	at org.forgerock.openam.audit.AbstractHttpAccessAuditFilter.filter(AbstractHttpAccessAuditFilter.java:76)
	at org.forgerock.http.handler.Handlers$1.handle(Handlers.java:56)
	at org.forgerock.http.routing.Router.handle(Router.java:92)
	at org.forgerock.openam.rest.RealmContextFilter.filter(RealmContextFilter.java:84)
	at org.forgerock.http.handler.Handlers$1.handle(Handlers.java:56)
	at org.forgerock.http.routing.Router.handle(Router.java:92)
	at org.forgerock.http.routing.ResourceApiVersionRoutingFilter.filter(ResourceApiVersionRoutingFilter.java:64)
	at org.forgerock.http.handler.Handlers$1.handle(Handlers.java:56)
	at org.forgerock.caf.authentication.framework.AuthenticationFramework.grantAccess(AuthenticationFramework.java:220)
	at org.forgerock.caf.authentication.framework.AuthenticationFramework.access$400(AuthenticationFramework.java:65)
	at org.forgerock.caf.authentication.framework.AuthenticationFramework$3.apply(AuthenticationFramework.java:212)
	at org.forgerock.caf.authentication.framework.AuthenticationFramework$3.apply(AuthenticationFramework.java:205)
	at org.forgerock.util.promise.Promises$CompletedPromise.thenAsync(Promises.java:255)
	at org.forgerock.util.promise.Promises$CompletedPromise.thenAsync(Promises.java:244)
	at org.forgerock.caf.authentication.framework.AuthenticationFramework.validateRequest(AuthenticationFramework.java:168)
	at org.forgerock.caf.authentication.framework.AuthenticationFramework.access$100(AuthenticationFramework.java:65)
	at org.forgerock.caf.authentication.framework.AuthenticationFramework$1.apply(AuthenticationFramework.java:155)
	at org.forgerock.caf.authentication.framework.AuthenticationFramework$1.apply(AuthenticationFramework.java:152)
	at org.forgerock.util.promise.PromiseImpl$7.handleStateChange(PromiseImpl.java:485)
	at org.forgerock.util.promise.PromiseImpl.handleCompletion(PromiseImpl.java:567)
	at org.forgerock.util.promise.PromiseImpl.addOrFireListener(PromiseImpl.java:555)
	at org.forgerock.util.promise.PromiseImpl.thenAsync(PromiseImpl.java:477)
	at org.forgerock.util.promise.PromiseImpl.thenAsync(PromiseImpl.java:468)
	at org.forgerock.caf.authentication.framework.AuthenticationFramework.processMessage(AuthenticationFramework.java:146)
	at org.forgerock.caf.authentication.framework.AuthenticationFilter.filter(AuthenticationFilter.java:96)
	at org.forgerock.http.handler.Handlers$1.handle(Handlers.java:56)
	at org.forgerock.openam.http.HandlerProvider.handle(HandlerProvider.java:50)
	at org.forgerock.openam.http.HttpRoute$3.handle(HttpRoute.java:142)
	at org.forgerock.http.routing.Router.handle(Router.java:92)
	at org.forgerock.http.filter.TransactionIdInboundFilter.filter(TransactionIdInboundFilter.java:60)
	at org.forgerock.http.handler.Handlers$1.handle(Handlers.java:56)
	at org.forgerock.http.servlet.HttpFrameworkServlet.service(HttpFrameworkServlet.java:225)
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:731)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:303)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208)
	at org.apache.tomcat.websocket.server.WsFilter.doFilter(WsFilter.java:52)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:241)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208)
	at org.forgerock.openam.validation.ResponseValidationFilter.doFilter(ResponseValidationFilter.java:44)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:241)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208)
	at com.sun.identity.setup.AMSetupFilter.doFilter(AMSetupFilter.java:111)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:241)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208)
	at org.forgerock.openam.audit.context.AuditContextFilter.doFilter(AuditContextFilter.java:51)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:241)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208)
	at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:220)
	at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:122)
	at org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:505)
	at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:169)
	at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:103)
	at org.apache.catalina.valves.RemoteIpValve.invoke(RemoteIpValve.java:683)
	at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:116)
	at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:436)
	at org.apache.coyote.ajp.AjpProcessor.process(AjpProcessor.java:190)
	at org.apache.coyote.AbstractProtocol$AbstractConnectionHandler.process(AbstractProtocol.java:625)
	at org.apache.tomcat.util.net.JIoEndpoint$SocketProcessor.run(JIoEndpoint.java:316)
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
	at org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61)
	at java.lang.Thread.run(Thread.java:748)
Caused by: com.iplanet.dpro.session.SessionException: Invalid session ID.
	at org.forgerock.openam.session.SessionCache.getSession(SessionCache.java:253)
	at com.iplanet.sso.providers.dpro.SSOProviderImpl.createSSOToken(SSOProviderImpl.java:205)
	... 77 more

amAuthUtils:11/24/2017 04:13:22:263 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
In AuthUtils:getAuthContext
amAuthClientUtils:11/24/2017 04:13:22:263 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
AuthUtils::decodeHash: clientEncoding='null', encoding='UTF-8'
amAuthUtils:11/24/2017 04:13:22:263 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
retrieveAuthContext - InternalSession = com.iplanet.dpro.session.service.InternalSession@41aaa32a
amAuthUtils:11/24/2017 04:13:22:263 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
retrieveAuthContext - aclocal = com.sun.identity.authentication.server.AuthContextLocal@6b0c039a
amAuthUtils:11/24/2017 04:13:22:263 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
AuthUtil:getAuthContext:sid is.. .: AQIC5wM2LY4SfczOMnJG7FELIgtZuA_-oWr-Sk3IH32Cn-k.*AAJTSQACMDIAAlNLABQtODMzNjc4NTk0Mjg3MDE1NTA5NQACUzEAAjAx*
amAuthUtils:11/24/2017 04:13:22:263 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
AuthUtil:getAuthContext:authContext is..: com.sun.identity.authentication.server.AuthContextLocal@6b0c039a
amAuthUtils:11/24/2017 04:13:22:263 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
isSessionUpgrade  :false
amAuthUtils:11/24/2017 04:13:22:264 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
BACK with Request method POST : false
amAuthUtils:11/24/2017 04:13:22:264 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
getAuthContext: found existing request.
amAuthUtils:11/24/2017 04:13:22:264 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
in processAuthContext authcontext : com.sun.identity.authentication.server.AuthContextLocal@6b0c039a
amAuthUtils:11/24/2017 04:13:22:264 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
in processAuthContext request : org.forgerock.openam.core.rest.authn.http.AuthenticationServiceV1$1@1562acbd
amAuthUtils:11/24/2017 04:13:22:264 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
in processAuthContext response : org.forgerock.openam.validation.ResponseValidationFilter$ValidationWrapper@349d2f08
amAuthUtils:11/24/2017 04:13:22:264 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
in processAuthContext sid : AQIC5wM2LY4SfczOMnJG7FELIgtZuA_-oWr-Sk3IH32Cn-k.*AAJTSQACMDIAAlNLABQtODMzNjc4NTk0Mjg3MDE1NTA5NQACUzEAAjAx*
amAuth:11/24/2017 04:13:22:264 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
AuthUtils .processAuthContext().com.sun.identity.authentication.multiple.tabs.used=false
amAuthUtils:11/24/2017 04:13:22:264 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
new session arg does not exist
amAuthUtils:11/24/2017 04:13:22:264 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
AuthUtil :Session is .. : com.iplanet.dpro.session.service.InternalSession@41aaa32a
amAuthClientUtils:11/24/2017 04:13:22:264 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
AuthUtils::decodeHash: clientEncoding='null', encoding='UTF-8'
amAuthClientUtils:11/24/2017 04:13:22:264 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
orgParam='/SiteUserRealm'
amAuthClientUtils:11/24/2017 04:13:22:264 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
getOrganizationDN : orgParam.='/SiteUserRealm', orgDN='o=siteuserrealm,ou=services,dc=openam,dc=osstech,dc=co,dc=jp'
amAuthClientUtils:11/24/2017 04:13:22:264 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
orgDN='o=siteuserrealm,ou=services,dc=openam,dc=osstech,dc=co,dc=jp'
amAuthUtils:11/24/2017 04:13:22:264 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
this is an existing request
amAuthContextLocal:11/24/2017 04:13:22:264 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
AuthContextLocal::hasMoreRequirements()
amAuth:11/24/2017 04:13:22:264 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
getStatus : status is... : 2
amAuth:11/24/2017 04:13:22:264 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
getStatus : status is... : 2
amAuth:11/24/2017 04:13:22:264 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
Recd Callback in amlc.getRequiredInfo : com.sun.identity.authentication.spi.PagePropertiesCallback@406e3e0e
amAuth:11/24/2017 04:13:22:264 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
Recd Callback in amlc.getRequiredInfo : com.sun.identity.authentication.spi.HttpCallback@4993e0cd
amAuthContextLocal:11/24/2017 04:13:22:264 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
AuthContextLocal::getStatus()
amAuth:11/24/2017 04:13:22:264 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
getStatus : status is... : 2
amAuth:11/24/2017 04:13:22:264 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
getStatus : status is... : 2
amAuth:11/24/2017 04:13:22:264 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
getStatus : status is... : 2
amAuth:11/24/2017 04:13:22:264 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
getStatus : status is... : 2
amAuth:11/24/2017 04:13:22:264 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
getStatus : status is... : 2
amAuthContextLocal:11/24/2017 04:13:22:264 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
AuthContextLocal:: Status : in_progress
amAuthContextLocal:11/24/2017 04:13:22:264 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
AuthContextLocal::getRequirements()
amAuth:11/24/2017 04:13:22:264 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
getStatus : status is... : 2
amAuth:11/24/2017 04:13:22:264 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
getStatus : status is... : 2
amAuthContextLocal:11/24/2017 04:13:22:265 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
In getCallbacks() callback : com.sun.identity.authentication.spi.PagePropertiesCallback@406e3e0e
amAuthContextLocal:11/24/2017 04:13:22:265 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
In getCallbacks() callback : com.sun.identity.authentication.spi.HttpCallback@4993e0cd
amAuthContextLocal:11/24/2017 04:13:22:265 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
AuthContextLocal::submitRequirements()
amAuth:11/24/2017 04:13:22:265 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
submit required info... :com.sun.identity.authentication.spi.HttpCallback@4993e0cd
amAuth:11/24/2017 04:13:22:265 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
Returning from submitRequiredInfo
amLoginModule:11/24/2017 04:13:22:265 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
This module is not done yet. CurrentState: 2
amLoginModule:11/24/2017 04:13:22:265 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
Login, state = 2
amCallback:11/24/2017 04:13:22:265 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
callback handler method
amCallback:11/24/2017 04:13:22:265 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
DSAMEHandler: found submitted callbacks !
amAuth:11/24/2017 04:13:22:265 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
Returning Last Callback Sent :1511507602200
amAuth:11/24/2017 04:13:22:265 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
Returning page timeout :120
amCallback:11/24/2017 04:13:22:265 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
DSAMECAllbackhandler...[Ljavax.security.auth.callback.Callback;@35ae8419
amLoginModule:11/24/2017 04:13:22:265 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
Login NEXT State : 0
amLoginModule:11/24/2017 04:13:22:265 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
SETTING Failure Module name.... :WindowsDesktopSSOAuthModule
amAuth:11/24/2017 04:13:22:265 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
Module name is .. WindowsDesktopSSOAuthModule
amAuth:11/24/2017 04:13:22:265 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
failureModuleSet is : [WindowsDesktopSSOAuthModule]
amJAAS:11/24/2017 04:13:22:266 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
login ignored
amCallback:11/24/2017 04:13:22:266 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
callback handler method
amCallback:11/24/2017 04:13:22:266 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
LoginState Callbacks
amAuthClientUtils:11/24/2017 04:13:22:266 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
rootOrgName is : openam
amAuthClientUtils:11/24/2017 04:13:22:266 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
clientDetectionEnabled = false
amAuthClientUtils:11/24/2017 04:13:22:266 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
getDefaultClientType, ClientType = genericHTML
amAuthClientUtils:11/24/2017 04:13:22:266 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
clientDetectionEnabled = false
amAuthClientUtils:11/24/2017 04:13:22:266 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
FilePath='/html'
amAuthClientUtils:11/24/2017 04:13:22:266 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
getOrgFilePath : orgDN is: o=siteuserrealm,ou=services,dc=openam,dc=osstech,dc=co,dc=jp
amAuthClientUtils:11/24/2017 04:13:22:266 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
remOrgDN is : ou=services,dc=openam,dc=osstech,dc=co,dc=jp
amAuthClientUtils:11/24/2017 04:13:22:266 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
remOrgDN is : dc=openam,dc=osstech,dc=co,dc=jp
amAuthClientUtils:11/24/2017 04:13:22:266 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
getOrgFilePath: orgPath is : /services/siteuserrealm
amAuthClientUtils:11/24/2017 04:13:22:266 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
Calling ResourceLookup: filename='DataStore.xml', defaultOrg='openam', locale='ja_JP', filePath='/html', orgPath='/services/siteuserrealm'
amAuthClientUtils:11/24/2017 04:13:22:266 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
clientDetectionEnabled = false
amAuthClientUtils:11/24/2017 04:13:22:266 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
getDefaultClientType, ClientType = genericHTML
amAuthClientUtils:11/24/2017 04:13:22:266 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
getDefaultClientType, ClientType = genericHTML
amAuthClientUtils:11/24/2017 04:13:22:266 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
resourceName='/config/auth/default_ja/DataStore.xml'
amAuthClientUtils:11/24/2017 04:13:22:266 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
File/Resource is : /config/auth/default_ja/DataStore.xml
amLoginModule:11/24/2017 04:13:22:266 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
AMLoginModule resbundle locale=ja_JP
amLoginModule:11/24/2017 04:13:22:266 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
Login, class = com.sun.identity.authentication.modules.datastore.DataStore, module=DataStore, file=/config/auth/default_ja/DataStore.xml
amLoginModule:11/24/2017 04:13:22:266 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
AMLoginModuleiplanet-am-auth-shared-state-behavior-pattern is set to tryFirstPass
amAuth:11/24/2017 04:13:22:266 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
spi authLevel :0
amAuth:11/24/2017 04:13:22:266 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
module configuration authLevel :0
amAuth:11/24/2017 04:13:22:267 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
levelSet :false
amAuthDataStore:11/24/2017 04:13:22:267 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
DataStore resbundle locale=ja_JP
amLoginModule:11/24/2017 04:13:22:267 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
This module is not done yet. CurrentState: 1
amLoginModule:11/24/2017 04:13:22:267 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
callback stateLength in file = 1
amLoginModule:11/24/2017 04:13:22:267 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
callback size for state 1=1
amLoginModule:11/24/2017 04:13:22:267 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
clone #0 is PagePropertiesCallback
amLoginModule:11/24/2017 04:13:22:267 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
clone #1 is NameCallback
amLoginModule:11/24/2017 04:13:22:267 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
clone #2 is PasswordCallback
amLoginModule:11/24/2017 04:13:22:267 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
Login, state = 1
amCallback:11/24/2017 04:13:22:267 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
callback handler method
amAuth:11/24/2017 04:13:22:267 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
Setting page timeout :120
amAuth:11/24/2017 04:13:22:267 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
setting Last Callback Sent :1511507602267
amCallback:11/24/2017 04:13:22:267 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
Set callbacks, throwing java.lang.Error.
amJAAS:11/24/2017 04:13:22:267 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
LoginContext.invoke(): Handling expected java.lang.Error
amAuth:11/24/2017 04:13:22:267 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
Caught error returned from DSAMEHandler
amAuth:11/24/2017 04:13:22:267 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
getStatus : status is... : 2
amAuth:11/24/2017 04:13:22:267 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
getStatus : status is... : 2
amAuthContextLocal:11/24/2017 04:13:22:267 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
AuthContextLocal::submitRequirements end
amAuthContextLocal:11/24/2017 04:13:22:267 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
Status at the end of submitRequirements() : in_progress
amAuthContextLocal:11/24/2017 04:13:22:267 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
AuthContextLocal::hasMoreRequirements()
amAuth:11/24/2017 04:13:22:267 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
getStatus : status is... : 2
amAuth:11/24/2017 04:13:22:267 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
getStatus : status is... : 2
amAuth:11/24/2017 04:13:22:267 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
Recd Callback in amlc.getRequiredInfo : com.sun.identity.authentication.spi.PagePropertiesCallback@7b0da679
amAuth:11/24/2017 04:13:22:267 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
Recd Callback in amlc.getRequiredInfo : javax.security.auth.callback.NameCallback@7b0afae1
amAuth:11/24/2017 04:13:22:267 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
Recd Callback in amlc.getRequiredInfo : javax.security.auth.callback.PasswordCallback@26b26fe7
amAuthContextLocal:11/24/2017 04:13:22:267 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
AuthContextLocal::getStatus()
amAuth:11/24/2017 04:13:22:267 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
getStatus : status is... : 2
amAuth:11/24/2017 04:13:22:267 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
getStatus : status is... : 2
amAuth:11/24/2017 04:13:22:267 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
getStatus : status is... : 2
amAuth:11/24/2017 04:13:22:267 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
getStatus : status is... : 2
amAuth:11/24/2017 04:13:22:267 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
getStatus : status is... : 2
amAuthContextLocal:11/24/2017 04:13:22:267 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
AuthContextLocal:: Status : in_progress
amAuthContextLocal:11/24/2017 04:13:22:267 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
AuthContextLocal::getRequirements()
amAuth:11/24/2017 04:13:22:267 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
getStatus : status is... : 2
amAuth:11/24/2017 04:13:22:268 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
getStatus : status is... : 2
amAuthContextLocal:11/24/2017 04:13:22:268 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
In getCallbacks() callback : com.sun.identity.authentication.spi.PagePropertiesCallback@7b0da679
amAuthContextLocal:11/24/2017 04:13:22:268 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
In getCallbacks() callback : javax.security.auth.callback.NameCallback@7b0afae1
amAuthContextLocal:11/24/2017 04:13:22:268 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
In getCallbacks() callback : javax.security.auth.callback.PasswordCallback@26b26fe7
amAuthREST:11/24/2017 04:13:22:268 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
username not set in request.
amAuthREST:11/24/2017 04:13:22:268 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
Cannot handle callbacks internally. Converting to JSON instead.
amAuthContextLocal:11/24/2017 04:13:22:268 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
AuthContextLocal::getRequirements()
amAuth:11/24/2017 04:13:22:268 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
getStatus : status is... : 2
amAuth:11/24/2017 04:13:22:268 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
getStatus : status is... : 2
amAuthClientUtils:11/24/2017 04:13:22:321 PM JST: Thread[ajp-bio-8009-exec-38,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1576]
Returning host name : sso.example.co.jp
amAuthClientUtils:11/24/2017 04:13:22:321 PM JST: Thread[ajp-bio-8009-exec-38,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1576]
hostName is : sso.example.co.jp
amAuthClientUtils:11/24/2017 04:13:22:321 PM JST: Thread[ajp-bio-8009-exec-38,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1576]
hostname  and fqdnDefault match returning true
amAuthClientUtils:11/24/2017 04:13:22:321 PM JST: Thread[ajp-bio-8009-exec-38,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1576]
retVal is : true
amAuthClientUtils:11/24/2017 04:13:22:372 PM JST: Thread[ajp-bio-8009-exec-56,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1577]
Returning host name : sso.example.co.jp
amAuthClientUtils:11/24/2017 04:13:22:372 PM JST: Thread[ajp-bio-8009-exec-56,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1577]
hostName is : sso.example.co.jp
amAuthClientUtils:11/24/2017 04:13:22:372 PM JST: Thread[ajp-bio-8009-exec-56,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1577]
hostname  and fqdnDefault match returning true
amAuthClientUtils:11/24/2017 04:13:22:372 PM JST: Thread[ajp-bio-8009-exec-56,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1577]
retVal is : true
amAuthClientUtils:11/24/2017 04:13:22:471 PM JST: Thread[ajp-bio-8009-exec-35,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1578]
Returning host name : sso.example.co.jp
amAuthClientUtils:11/24/2017 04:13:22:472 PM JST: Thread[ajp-bio-8009-exec-35,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1578]
hostName is : sso.example.co.jp
amAuthClientUtils:11/24/2017 04:13:22:472 PM JST: Thread[ajp-bio-8009-exec-35,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1578]
hostname  and fqdnDefault match returning true
amAuthClientUtils:11/24/2017 04:13:22:472 PM JST: Thread[ajp-bio-8009-exec-35,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1578]
retVal is : true
amAuthClientUtils:11/24/2017 04:13:22:472 PM JST: Thread[ajp-bio-8009-exec-88,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1579]
Returning host name : sso.example.co.jp
amAuthClientUtils:11/24/2017 04:13:22:472 PM JST: Thread[ajp-bio-8009-exec-88,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1579]
hostName is : sso.example.co.jp
amAuthClientUtils:11/24/2017 04:13:22:472 PM JST: Thread[ajp-bio-8009-exec-88,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1579]
hostname  and fqdnDefault match returning true
amAuthClientUtils:11/24/2017 04:13:22:472 PM JST: Thread[ajp-bio-8009-exec-88,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1579]
retVal is : true

==> Configuration <==
amSMS:11/24/2017 04:13:21:950 PM JST: Thread[ajp-bio-8009-exec-38,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1550]
SMSEntry:parseResult:orgAttr o=
amSMS:11/24/2017 04:13:21:950 PM JST: Thread[ajp-bio-8009-exec-38,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1550]
SMSEntry:parseResult:DNName ou=services,dc=openam,dc=osstech,dc=co,dc=jp
amSMS:11/24/2017 04:13:21:950 PM JST: Thread[ajp-bio-8009-exec-38,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1550]
SMSEntry:parseResult:RFCDN o=SiteUserRealm,ou=services,dc=openam,dc=osstech,dc=co,dc=jp
amSMS:11/24/2017 04:13:21:950 PM JST: Thread[ajp-bio-8009-exec-38,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1550]
DNMapper.replaceString() /SiteUserRealm
amSMS:11/24/2017 04:13:21:950 PM JST: Thread[ajp-bio-8009-exec-38,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1550]
SMSEntry:parseResult:origStr1 /SiteUserRealm
amSMS:11/24/2017 04:13:21:950 PM JST: Thread[ajp-bio-8009-exec-38,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1550]
DNMapper.replaceString() /SiteUserRealm
amSMS:11/24/2017 04:13:21:950 PM JST: Thread[ajp-bio-8009-exec-38,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1550]
SMSEntry:parseResult:origStr2 /SiteUserRealm
amSMSEmbeddedLdap:11/24/2017 04:13:21:950 PM JST: Thread[ajp-bio-8009-exec-38,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1550]
SMSEmbeddedLdapObject.entryExists: checking if entry exists: o=serverinfo,o=SiteUserRealm,ou=services,dc=openam,dc=osstech,dc=co,dc=jp
amSMSEmbeddedLdap:11/24/2017 04:13:21:951 PM JST: Thread[ajp-bio-8009-exec-38,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1550]
SMSEmbeddedLdapObject.entryExists: entry present in not-present-cache: o=serverinfo,o=siteuserrealm,ou=services,dc=openam,dc=osstech,dc=co,dc=jp
amSMS:11/24/2017 04:13:21:952 PM JST: Thread[ajp-bio-8009-exec-38,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1550]
OrganizationConfigMgrImpl::getInstance: called: (/)=ou=services,dc=openam,dc=osstech,dc=co,dc=jp
amSMS:11/24/2017 04:13:21:952 PM JST: Thread[ajp-bio-8009-exec-38,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1550]
OrganizationConfigMgrImpl::getInstance: success: ou=services,dc=openam,dc=osstech,dc=co,dc=jp
amSMS:11/24/2017 04:13:21:952 PM JST: Thread[ajp-bio-8009-exec-38,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1550]
CachedSubEntries: reading subOrgNames DN: ou=services,dc=openam,dc=osstech,dc=co,dc=jp pattern: serverinfo
amSMSEmbeddedLdap:11/24/2017 04:13:21:952 PM JST: Thread[ajp-bio-8009-exec-38,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1550]
SMSEmbeddedLdapObject.searchSubOrgNames: search: ou=services,dc=openam,dc=osstech,dc=co,dc=jp
amSMSEmbeddedLdap:11/24/2017 04:13:21:952 PM JST: Thread[ajp-bio-8009-exec-38,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1550]
SMSEmbeddedLdapObject.searchSubOrganizationName: Successfully obtained suborganization names for : ou=services,dc=openam,dc=osstech,dc=co,dc=jp
amSMSEmbeddedLdap:11/24/2017 04:13:21:952 PM JST: Thread[ajp-bio-8009-exec-38,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1550]
SMSEmbeddedLdapObject.searchSubOrganizationName: Successfully obtained suborganization names  : []
amSMS:11/24/2017 04:13:21:952 PM JST: Thread[ajp-bio-8009-exec-38,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1550]
SMSEntry: Successfully obtained suborganization names for : ou=services,dc=openam,dc=osstech,dc=co,dc=jp
amSMS:11/24/2017 04:13:21:952 PM JST: Thread[ajp-bio-8009-exec-38,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1550]
CachedSubEntries::getInstance DN: ou=services,dc=openam,dc=osstech,dc=co,dc=jp
amSMS:11/24/2017 04:13:21:953 PM JST: Thread[ajp-bio-8009-exec-38,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1550]
CachedSubEntries: reading orgNames DN: ou=services,dc=openam,dc=osstech,dc=co,dc=jp attrName: sunOrganizationAliases
amSMSEmbeddedLdap:11/24/2017 04:13:21:953 PM JST: Thread[ajp-bio-8009-exec-38,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1550]
SMSEmbeddedLdapObject.searchOrganizationNames search dn: ou=services,dc=openam,dc=osstech,dc=co,dc=jp
amSMSEmbeddedLdap:11/24/2017 04:13:21:953 PM JST: Thread[ajp-bio-8009-exec-38,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1550]
SMSEmbeddedLdapObject.searchOrganizationNames: orgNames search filter: (|(&(objectclass=sunRealmService)(&(|(sunxmlKeyValue=sunidentityrepositoryservice-sunOrganizationAliases=serverinfo)(sunxmlKeyValue=sunOrganizationAliases=serverinfo))))(&(objectclass=sunServiceComponent)(&(|(sunxmlKeyValue=sunidentityrepositoryservice-sunOrganizationAliases=serverinfo)(sunxmlKeyValue=sunOrganizationAliases=serverinfo)))))
amSMSEmbeddedLdap:11/24/2017 04:13:21:953 PM JST: Thread[ajp-bio-8009-exec-38,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1550]
SMSEmbeddedLdapObject.searchSubOrganizationName: Successfully obtained suborganization names for : ou=services,dc=openam,dc=osstech,dc=co,dc=jp
amSMSEmbeddedLdap:11/24/2017 04:13:21:953 PM JST: Thread[ajp-bio-8009-exec-38,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1550]
SMSEmbeddedLdapObject.searchSubOrganizationName: Successfully obtained suborganization names  : []
amSMS:11/24/2017 04:13:21:953 PM JST: Thread[ajp-bio-8009-exec-38,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1550]
SMSEntry: Successfully obtained organization names for : ou=services,dc=openam,dc=osstech,dc=co,dc=jp
amSMSEmbeddedLdap:11/24/2017 04:13:21:953 PM JST: Thread[ajp-bio-8009-exec-38,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1550]
SMSEmbeddedLdapObject.entryExists: checking if entry exists: o=SiteUserRealm,ou=services,dc=openam,dc=osstech,dc=co,dc=jp
amSMSEmbeddedLdap:11/24/2017 04:13:21:953 PM JST: Thread[ajp-bio-8009-exec-38,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1550]
SMSEmbeddedLdapObject.entryExists: entry present in cache: o=siteuserrealm,ou=services,dc=openam,dc=osstech,dc=co,dc=jp
amSMS:11/24/2017 04:13:21:961 PM JST: Thread[ajp-bio-8009-exec-38,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1550]
ServiceConfigMgrImpl::getInstance: called: iPlanetAMAuthService(1.0)
amSMS:11/24/2017 04:13:22:055 PM JST: Thread[ajp-bio-8009-exec-11,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1560]
SMSEntry:parseResult:orgAttr o=
amSMS:11/24/2017 04:13:22:056 PM JST: Thread[ajp-bio-8009-exec-11,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1560]
SMSEntry:parseResult:DNName ou=services,dc=openam,dc=osstech,dc=co,dc=jp
amSMS:11/24/2017 04:13:22:056 PM JST: Thread[ajp-bio-8009-exec-11,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1560]
SMSEntry:parseResult:RFCDN o=SiteUserRealm,ou=services,dc=openam,dc=osstech,dc=co,dc=jp
amSMS:11/24/2017 04:13:22:056 PM JST: Thread[ajp-bio-8009-exec-11,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1560]
DNMapper.replaceString() /SiteUserRealm
amSMS:11/24/2017 04:13:22:056 PM JST: Thread[ajp-bio-8009-exec-11,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1560]
SMSEntry:parseResult:origStr1 /SiteUserRealm
amSMS:11/24/2017 04:13:22:056 PM JST: Thread[ajp-bio-8009-exec-11,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1560]
DNMapper.replaceString() /SiteUserRealm
amSMS:11/24/2017 04:13:22:056 PM JST: Thread[ajp-bio-8009-exec-11,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1560]
SMSEntry:parseResult:origStr2 /SiteUserRealm
amSMSEmbeddedLdap:11/24/2017 04:13:22:056 PM JST: Thread[ajp-bio-8009-exec-11,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1560]
SMSEmbeddedLdapObject.entryExists: checking if entry exists: o=authenticate,o=SiteUserRealm,ou=services,dc=openam,dc=osstech,dc=co,dc=jp
amSMSEmbeddedLdap:11/24/2017 04:13:22:056 PM JST: Thread[ajp-bio-8009-exec-11,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1560]
SMSEmbeddedLdapObject.entryExists: entry present in not-present-cache: o=authenticate,o=siteuserrealm,ou=services,dc=openam,dc=osstech,dc=co,dc=jp
amSMS:11/24/2017 04:13:22:056 PM JST: Thread[ajp-bio-8009-exec-11,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1560]
OrganizationConfigMgrImpl::getInstance: called: (/)=ou=services,dc=openam,dc=osstech,dc=co,dc=jp
amSMS:11/24/2017 04:13:22:056 PM JST: Thread[ajp-bio-8009-exec-11,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1560]
OrganizationConfigMgrImpl::getInstance: success: ou=services,dc=openam,dc=osstech,dc=co,dc=jp
amSMS:11/24/2017 04:13:22:056 PM JST: Thread[ajp-bio-8009-exec-11,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1560]
CachedSubEntries: reading subOrgNames DN: ou=services,dc=openam,dc=osstech,dc=co,dc=jp pattern: authenticate
amSMSEmbeddedLdap:11/24/2017 04:13:22:056 PM JST: Thread[ajp-bio-8009-exec-11,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1560]
SMSEmbeddedLdapObject.searchSubOrgNames: search: ou=services,dc=openam,dc=osstech,dc=co,dc=jp
amSMSEmbeddedLdap:11/24/2017 04:13:22:057 PM JST: Thread[ajp-bio-8009-exec-11,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1560]
SMSEmbeddedLdapObject.searchSubOrganizationName: Successfully obtained suborganization names for : ou=services,dc=openam,dc=osstech,dc=co,dc=jp
amSMSEmbeddedLdap:11/24/2017 04:13:22:057 PM JST: Thread[ajp-bio-8009-exec-11,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1560]
SMSEmbeddedLdapObject.searchSubOrganizationName: Successfully obtained suborganization names  : []
amSMS:11/24/2017 04:13:22:057 PM JST: Thread[ajp-bio-8009-exec-11,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1560]
SMSEntry: Successfully obtained suborganization names for : ou=services,dc=openam,dc=osstech,dc=co,dc=jp
amSMS:11/24/2017 04:13:22:057 PM JST: Thread[ajp-bio-8009-exec-11,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1560]
CachedSubEntries::getInstance DN: ou=services,dc=openam,dc=osstech,dc=co,dc=jp
amSMS:11/24/2017 04:13:22:057 PM JST: Thread[ajp-bio-8009-exec-11,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1560]
CachedSubEntries: reading orgNames DN: ou=services,dc=openam,dc=osstech,dc=co,dc=jp attrName: sunOrganizationAliases
amSMSEmbeddedLdap:11/24/2017 04:13:22:057 PM JST: Thread[ajp-bio-8009-exec-11,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1560]
SMSEmbeddedLdapObject.searchOrganizationNames search dn: ou=services,dc=openam,dc=osstech,dc=co,dc=jp
amSMSEmbeddedLdap:11/24/2017 04:13:22:057 PM JST: Thread[ajp-bio-8009-exec-11,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1560]
SMSEmbeddedLdapObject.searchOrganizationNames: orgNames search filter: (|(&(objectclass=sunRealmService)(&(|(sunxmlKeyValue=sunidentityrepositoryservice-sunOrganizationAliases=authenticate)(sunxmlKeyValue=sunOrganizationAliases=authenticate))))(&(objectclass=sunServiceComponent)(&(|(sunxmlKeyValue=sunidentityrepositoryservice-sunOrganizationAliases=authenticate)(sunxmlKeyValue=sunOrganizationAliases=authenticate)))))
amSMSEmbeddedLdap:11/24/2017 04:13:22:057 PM JST: Thread[ajp-bio-8009-exec-11,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1560]
SMSEmbeddedLdapObject.searchSubOrganizationName: Successfully obtained suborganization names for : ou=services,dc=openam,dc=osstech,dc=co,dc=jp
amSMSEmbeddedLdap:11/24/2017 04:13:22:057 PM JST: Thread[ajp-bio-8009-exec-11,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1560]
SMSEmbeddedLdapObject.searchSubOrganizationName: Successfully obtained suborganization names  : []
amSMS:11/24/2017 04:13:22:057 PM JST: Thread[ajp-bio-8009-exec-11,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1560]
SMSEntry: Successfully obtained organization names for : ou=services,dc=openam,dc=osstech,dc=co,dc=jp
amSMSEmbeddedLdap:11/24/2017 04:13:22:058 PM JST: Thread[ajp-bio-8009-exec-11,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1560]
SMSEmbeddedLdapObject.entryExists: checking if entry exists: o=SiteUserRealm,ou=services,dc=openam,dc=osstech,dc=co,dc=jp
amSMSEmbeddedLdap:11/24/2017 04:13:22:058 PM JST: Thread[ajp-bio-8009-exec-11,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1560]
SMSEmbeddedLdapObject.entryExists: entry present in cache: o=siteuserrealm,ou=services,dc=openam,dc=osstech,dc=co,dc=jp
amSMS:11/24/2017 04:13:22:064 PM JST: Thread[ajp-bio-8009-exec-11,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1560]
ServiceConfigMgrImpl::getInstance: called: iPlanetAMAuthService(1.0)
amSMS:11/24/2017 04:13:22:064 PM JST: Thread[ajp-bio-8009-exec-11,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1560]
ServiceConfigMgrImpl::getInstance: called: iPlanetAMAuthService(1.0)
amSMS:11/24/2017 04:13:22:070 PM JST: Thread[ajp-bio-8009-exec-11,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1560]
ServiceConfigMgrImpl::getInstance: called: iPlanetAMAuthService(1.0)
amSMS:11/24/2017 04:13:22:073 PM JST: Thread[ajp-bio-8009-exec-11,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1560]
ServiceConfigMgrImpl::getInstance: called: iPlanetAMAuthService(1.0)
amSMS:11/24/2017 04:13:22:174 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
SMSEntry:parseResult:orgAttr o=
amSMS:11/24/2017 04:13:22:174 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
SMSEntry:parseResult:DNName ou=services,dc=openam,dc=osstech,dc=co,dc=jp
amSMS:11/24/2017 04:13:22:174 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
SMSEntry:parseResult:RFCDN o=SiteUserRealm,ou=services,dc=openam,dc=osstech,dc=co,dc=jp
amSMS:11/24/2017 04:13:22:174 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
DNMapper.replaceString() /SiteUserRealm
amSMS:11/24/2017 04:13:22:174 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
SMSEntry:parseResult:origStr1 /SiteUserRealm
amSMS:11/24/2017 04:13:22:174 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
DNMapper.replaceString() /SiteUserRealm
amSMS:11/24/2017 04:13:22:174 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
SMSEntry:parseResult:origStr2 /SiteUserRealm
amSMSEmbeddedLdap:11/24/2017 04:13:22:174 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
SMSEmbeddedLdapObject.entryExists: checking if entry exists: o=authenticate,o=SiteUserRealm,ou=services,dc=openam,dc=osstech,dc=co,dc=jp
amSMSEmbeddedLdap:11/24/2017 04:13:22:174 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
SMSEmbeddedLdapObject.entryExists: entry present in not-present-cache: o=authenticate,o=siteuserrealm,ou=services,dc=openam,dc=osstech,dc=co,dc=jp
amSMS:11/24/2017 04:13:22:174 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
OrganizationConfigMgrImpl::getInstance: called: (/)=ou=services,dc=openam,dc=osstech,dc=co,dc=jp
amSMS:11/24/2017 04:13:22:174 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
OrganizationConfigMgrImpl::getInstance: success: ou=services,dc=openam,dc=osstech,dc=co,dc=jp
amSMS:11/24/2017 04:13:22:174 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
CachedSubEntries: reading subOrgNames DN: ou=services,dc=openam,dc=osstech,dc=co,dc=jp pattern: authenticate
amSMSEmbeddedLdap:11/24/2017 04:13:22:174 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
SMSEmbeddedLdapObject.searchSubOrgNames: search: ou=services,dc=openam,dc=osstech,dc=co,dc=jp
amSMSEmbeddedLdap:11/24/2017 04:13:22:175 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
SMSEmbeddedLdapObject.searchSubOrganizationName: Successfully obtained suborganization names for : ou=services,dc=openam,dc=osstech,dc=co,dc=jp
amSMSEmbeddedLdap:11/24/2017 04:13:22:175 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
SMSEmbeddedLdapObject.searchSubOrganizationName: Successfully obtained suborganization names  : []
amSMS:11/24/2017 04:13:22:175 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
SMSEntry: Successfully obtained suborganization names for : ou=services,dc=openam,dc=osstech,dc=co,dc=jp
amSMS:11/24/2017 04:13:22:175 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
CachedSubEntries::getInstance DN: ou=services,dc=openam,dc=osstech,dc=co,dc=jp
amSMS:11/24/2017 04:13:22:175 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
CachedSubEntries: reading orgNames DN: ou=services,dc=openam,dc=osstech,dc=co,dc=jp attrName: sunOrganizationAliases
amSMSEmbeddedLdap:11/24/2017 04:13:22:175 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
SMSEmbeddedLdapObject.searchOrganizationNames search dn: ou=services,dc=openam,dc=osstech,dc=co,dc=jp
amSMSEmbeddedLdap:11/24/2017 04:13:22:175 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
SMSEmbeddedLdapObject.searchOrganizationNames: orgNames search filter: (|(&(objectclass=sunRealmService)(&(|(sunxmlKeyValue=sunidentityrepositoryservice-sunOrganizationAliases=authenticate)(sunxmlKeyValue=sunOrganizationAliases=authenticate))))(&(objectclass=sunServiceComponent)(&(|(sunxmlKeyValue=sunidentityrepositoryservice-sunOrganizationAliases=authenticate)(sunxmlKeyValue=sunOrganizationAliases=authenticate)))))
amSMSEmbeddedLdap:11/24/2017 04:13:22:175 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
SMSEmbeddedLdapObject.searchSubOrganizationName: Successfully obtained suborganization names for : ou=services,dc=openam,dc=osstech,dc=co,dc=jp
amSMSEmbeddedLdap:11/24/2017 04:13:22:176 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
SMSEmbeddedLdapObject.searchSubOrganizationName: Successfully obtained suborganization names  : []
amSMS:11/24/2017 04:13:22:176 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
SMSEntry: Successfully obtained organization names for : ou=services,dc=openam,dc=osstech,dc=co,dc=jp
amSMSEmbeddedLdap:11/24/2017 04:13:22:176 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
SMSEmbeddedLdapObject.entryExists: checking if entry exists: o=SiteUserRealm,ou=services,dc=openam,dc=osstech,dc=co,dc=jp
amSMSEmbeddedLdap:11/24/2017 04:13:22:176 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
SMSEmbeddedLdapObject.entryExists: entry present in cache: o=siteuserrealm,ou=services,dc=openam,dc=osstech,dc=co,dc=jp
amSMS:11/24/2017 04:13:22:191 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
ServiceConfigMgrImpl::getInstance: called: iPlanetAMAuthService(1.0)
amSMS:11/24/2017 04:13:22:191 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
ServiceConfigMgrImpl::getInstance: called: iPlanetAMAuthService(1.0)
amSMS:11/24/2017 04:13:22:195 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
ServiceConfigMgrImpl::getInstance: called: iPlanetAMAuthService(1.0)
amSMS:11/24/2017 04:13:22:203 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
ServiceConfigMgrImpl::getInstance: called: iPlanetAMAuthService(1.0)
amSMS:11/24/2017 04:13:22:247 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
SMSEntry:parseResult:orgAttr o=
amSMS:11/24/2017 04:13:22:247 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
SMSEntry:parseResult:DNName ou=services,dc=openam,dc=osstech,dc=co,dc=jp
amSMS:11/24/2017 04:13:22:247 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
SMSEntry:parseResult:RFCDN o=SiteUserRealm,ou=services,dc=openam,dc=osstech,dc=co,dc=jp
amSMS:11/24/2017 04:13:22:247 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
DNMapper.replaceString() /SiteUserRealm
amSMS:11/24/2017 04:13:22:247 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
SMSEntry:parseResult:origStr1 /SiteUserRealm
amSMS:11/24/2017 04:13:22:247 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
DNMapper.replaceString() /SiteUserRealm
amSMS:11/24/2017 04:13:22:247 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
SMSEntry:parseResult:origStr2 /SiteUserRealm
amSMSEmbeddedLdap:11/24/2017 04:13:22:247 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
SMSEmbeddedLdapObject.entryExists: checking if entry exists: o=authenticate,o=SiteUserRealm,ou=services,dc=openam,dc=osstech,dc=co,dc=jp
amSMSEmbeddedLdap:11/24/2017 04:13:22:247 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
SMSEmbeddedLdapObject.entryExists: entry present in not-present-cache: o=authenticate,o=siteuserrealm,ou=services,dc=openam,dc=osstech,dc=co,dc=jp
amSMS:11/24/2017 04:13:22:247 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
OrganizationConfigMgrImpl::getInstance: called: (/)=ou=services,dc=openam,dc=osstech,dc=co,dc=jp
amSMS:11/24/2017 04:13:22:247 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
OrganizationConfigMgrImpl::getInstance: success: ou=services,dc=openam,dc=osstech,dc=co,dc=jp
amSMS:11/24/2017 04:13:22:247 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
CachedSubEntries: reading subOrgNames DN: ou=services,dc=openam,dc=osstech,dc=co,dc=jp pattern: authenticate
amSMSEmbeddedLdap:11/24/2017 04:13:22:247 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
SMSEmbeddedLdapObject.searchSubOrgNames: search: ou=services,dc=openam,dc=osstech,dc=co,dc=jp
amSMSEmbeddedLdap:11/24/2017 04:13:22:248 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
SMSEmbeddedLdapObject.searchSubOrganizationName: Successfully obtained suborganization names for : ou=services,dc=openam,dc=osstech,dc=co,dc=jp
amSMSEmbeddedLdap:11/24/2017 04:13:22:248 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
SMSEmbeddedLdapObject.searchSubOrganizationName: Successfully obtained suborganization names  : []
amSMS:11/24/2017 04:13:22:248 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
SMSEntry: Successfully obtained suborganization names for : ou=services,dc=openam,dc=osstech,dc=co,dc=jp
amSMS:11/24/2017 04:13:22:248 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
CachedSubEntries::getInstance DN: ou=services,dc=openam,dc=osstech,dc=co,dc=jp
amSMS:11/24/2017 04:13:22:248 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
CachedSubEntries: reading orgNames DN: ou=services,dc=openam,dc=osstech,dc=co,dc=jp attrName: sunOrganizationAliases
amSMSEmbeddedLdap:11/24/2017 04:13:22:248 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
SMSEmbeddedLdapObject.searchOrganizationNames search dn: ou=services,dc=openam,dc=osstech,dc=co,dc=jp
amSMSEmbeddedLdap:11/24/2017 04:13:22:248 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
SMSEmbeddedLdapObject.searchOrganizationNames: orgNames search filter: (|(&(objectclass=sunRealmService)(&(|(sunxmlKeyValue=sunidentityrepositoryservice-sunOrganizationAliases=authenticate)(sunxmlKeyValue=sunOrganizationAliases=authenticate))))(&(objectclass=sunServiceComponent)(&(|(sunxmlKeyValue=sunidentityrepositoryservice-sunOrganizationAliases=authenticate)(sunxmlKeyValue=sunOrganizationAliases=authenticate)))))
amSMSEmbeddedLdap:11/24/2017 04:13:22:248 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
SMSEmbeddedLdapObject.searchSubOrganizationName: Successfully obtained suborganization names for : ou=services,dc=openam,dc=osstech,dc=co,dc=jp
amSMSEmbeddedLdap:11/24/2017 04:13:22:248 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
SMSEmbeddedLdapObject.searchSubOrganizationName: Successfully obtained suborganization names  : []
amSMS:11/24/2017 04:13:22:248 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
SMSEntry: Successfully obtained organization names for : ou=services,dc=openam,dc=osstech,dc=co,dc=jp
amSMSEmbeddedLdap:11/24/2017 04:13:22:248 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
SMSEmbeddedLdapObject.entryExists: checking if entry exists: o=SiteUserRealm,ou=services,dc=openam,dc=osstech,dc=co,dc=jp
amSMSEmbeddedLdap:11/24/2017 04:13:22:248 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
SMSEmbeddedLdapObject.entryExists: entry present in cache: o=siteuserrealm,ou=services,dc=openam,dc=osstech,dc=co,dc=jp
amSMS:11/24/2017 04:13:22:262 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
ServiceConfigMgrImpl::getInstance: called: iPlanetAMAuthService(1.0)
amSMS:11/24/2017 04:13:22:265 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
SMSEntry:parseResult:orgAttr o=
amSMS:11/24/2017 04:13:22:265 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
SMSEntry:parseResult:DNName ou=services,dc=openam,dc=osstech,dc=co,dc=jp
amSMS:11/24/2017 04:13:22:265 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
SMSEntry:parseResult:RFCDN o=siteuserrealm,ou=services,dc=openam,dc=osstech,dc=co,dc=jp
amSMS:11/24/2017 04:13:22:265 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
DNMapper.replaceString() /siteuserrealm
amSMS:11/24/2017 04:13:22:265 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
SMSEntry:parseResult:origStr1 /siteuserrealm
amSMS:11/24/2017 04:13:22:265 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
DNMapper.replaceString() /siteuserrealm
amSMS:11/24/2017 04:13:22:265 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
SMSEntry:parseResult:origStr2 /siteuserrealm

==> CoreSystem <==
amCookieUtils:11/24/2017 04:13:21:948 PM JST: Thread[ajp-bio-8009-exec-38,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1550]
No Cookie is in the request
frRest:11/24/2017 04:13:21:958 PM JST: Thread[ajp-bio-8009-exec-38,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1550]
CrestLoggingFilter :: no token from context, logging user as 'null'
frRest:11/24/2017 04:13:21:959 PM JST: Thread[ajp-bio-8009-exec-38,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1550]
* :: READ attempted by [unknown]
frRest:11/24/2017 04:13:21:959 PM JST: Thread[ajp-bio-8009-exec-38,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1550]
ServerInfoResource :: READ : in realm: /SiteUserRealm
frRest:11/24/2017 04:13:21:961 PM JST: Thread[ajp-bio-8009-exec-38,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1550]
ServerInfoResource.getAllServerInfo :: Added resource to response: *
org.forgerock.audit.AuditServiceImpl:11/24/2017 04:13:21:961 PM JST: Thread[ajp-bio-8009-exec-38,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1550]
Audit create called for access
org.forgerock.audit.AuditServiceImpl:11/24/2017 04:13:21:961 PM JST: Thread[ajp-bio-8009-exec-38,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1550]
Audit create id 5440a0f3-05f7-487c-8284-d52a52e82820-1553
org.forgerock.audit.AuditServiceImpl:11/24/2017 04:13:21:961 PM JST: Thread[ajp-bio-8009-exec-38,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1550]
Cascading the event of topic access to the handlers : [org.forgerock.audit.handlers.csv.CsvAuditEventHandler@9a96a16]
org.forgerock.audit.handlers.csv.CsvFormatter:11/24/2017 04:13:21:962 PM JST: Thread[ajp-bio-8009-exec-38,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1550]
Formatted event: "5440a0f3-05f7-487c-8284-d52a52e82820-1553","2017-11-24T07:13:21.961Z","AM-ACCESS-OUTCOME","5440a0f3-05f7-487c-8284-d52a52e82820-1550",,"[""""]","sso.example.co.jp","443","61.116.105.120","60742","CREST","READ",,"true","GET","https://sso.example.co.jp:443/openam/json/serverinfo/*","{""realm"":[""/SiteUserRealm""]}","{""accept"":[""application/json, text/javascript, */*; q=0.01""],""Accept-API-Version"":[""protocol=1.0,resource=1.1""],""DNT"":[""1""],""host"":[""sso.example.co.jp""],""If-None-Match"":[""\""1508616689\""""],""referer"":[""https://sso.example.co.jp/openam/XUI/""],""user-agent"":[""Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 6.1; Trident/7.0; SLCC2; .NET CLR 2.0.50727; .NET CLR 3.5.30729; .NET CLR 3.0.30729; Media Center PC 6.0; .NET4.0C; .NET4.0E; MDDRJS)""],""X-NoSession"":[""true""],""X-Password"":[""anonymous""],""X-Requested-With"":[""XMLHttpRequest""],""X-Username"":[""anonymous""]}","{""JSESSIONID"":""5A6B081DCF8EC3C79F7A219E83BF06D1"",""i18next"":""ja-JP""}",,"SUCCESSFUL",,,"14","MILLISECONDS","Server Info","/SiteUserRealm"

org.forgerock.audit.events.handlers.writers.RotatableWriter:11/24/2017 04:13:21:962 PM JST: Thread[CsvHandler,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1505]
Actually writing to file: "5440a0f3-05f7-487c-8284-d52a52e82820-1553","2017-11-24T07:13:21.961Z","AM-ACCESS-OUTCOME","5440a0f3-05f7-487c-8284-d52a52e82820-1550",,"[""""]","sso.example.co.jp","443","61.116.105.120","60742","CREST","READ",,"true","GET","https://sso.example.co.jp:443/openam/json/serverinfo/*","{""realm"":[""/SiteUserRealm""]}","{""accept"":[""application/json, text/javascript, */*; q=0.01""],""Accept-API-Version"":[""protocol=1.0,resource=1.1""],""DNT"":[""1""],""host"":[""sso.example.co.jp""],""If-None-Match"":[""\""1508616689\""""],""referer"":[""https://sso.example.co.jp/openam/XUI/""],""user-agent"":[""Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 6.1; Trident/7.0; SLCC2; .NET CLR 2.0.50727; .NET CLR 3.5.30729; .NET CLR 3.0.30729; Media Center PC 6.0; .NET4.0C; .NET4.0E; MDDRJS)""],""X-NoSession"":[""true""],""X-Password"":[""anonymous""],""X-Requested-With"":[""XMLHttpRequest""],""X-Username"":[""anonymous""]}","{""JSESSIONID"":""5A6B081DCF8EC3C79F7A219E83BF06D1"",""i18next"":""ja-JP""}",,"SUCCESSFUL",,,"14","MILLISECONDS","Server Info","/SiteUserRealm"

org.forgerock.audit.events.handlers.writers.RotatableWriter:11/24/2017 04:13:21:962 PM JST: Thread[CsvHandler,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1505]
bytes written=2229818
amCookieUtils:11/24/2017 04:13:22:055 PM JST: Thread[ajp-bio-8009-exec-11,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1560]
No Cookie is in the request
amComm:11/24/2017 04:13:22:062 PM JST: Thread[ajp-bio-8009-exec-11,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1560]
ClientUtils.getClientIPAddress : remoteAddr=[61.116.105.120]
amNaming:11/24/2017 04:13:22:063 PM JST: Thread[ajp-bio-8009-exec-11,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1560]
WebtopNaming : SiteID for 01 is 02
amNaming:11/24/2017 04:13:22:064 PM JST: Thread[ajp-bio-8009-exec-11,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1560]
WebtopNaming : SiteID for 01 is 02
amNaming:11/24/2017 04:13:22:064 PM JST: Thread[ajp-bio-8009-exec-11,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1560]
WebtopNaming : SiteID for 01 is 02
amNaming:11/24/2017 04:13:22:064 PM JST: Thread[ajp-bio-8009-exec-11,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1560]
WebtopNaming.getLBCookieValue(): lbCookieValuefor 01 is 01
amCookieUtils:11/24/2017 04:13:22:066 PM JST: Thread[ajp-bio-8009-exec-11,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1560]
No Cookie is in the request
amCookieUtils:11/24/2017 04:13:22:068 PM JST: Thread[ajp-bio-8009-exec-11,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1560]
No Cookie is in the request
amNaming:11/24/2017 04:13:22:072 PM JST: Thread[ajp-bio-8009-exec-11,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1560]
WebtopNaming.getLBCookieValue(): lbCookieValuefor 01 is 01
amComm:11/24/2017 04:13:22:074 PM JST: Thread[ajp-bio-8009-exec-11,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1560]
ClientUtils.getClientIPAddress : remoteAddr=[61.116.105.120]
org.forgerock.audit.AuditServiceImpl:11/24/2017 04:13:22:074 PM JST: Thread[ajp-bio-8009-exec-11,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1560]
Audit create called for access
org.forgerock.audit.AuditServiceImpl:11/24/2017 04:13:22:074 PM JST: Thread[ajp-bio-8009-exec-11,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1560]
Audit create id 5440a0f3-05f7-487c-8284-d52a52e82820-1562
org.forgerock.audit.AuditServiceImpl:11/24/2017 04:13:22:074 PM JST: Thread[ajp-bio-8009-exec-11,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1560]
Cascading the event of topic access to the handlers : [org.forgerock.audit.handlers.csv.CsvAuditEventHandler@9a96a16]
org.forgerock.audit.handlers.csv.CsvFormatter:11/24/2017 04:13:22:075 PM JST: Thread[ajp-bio-8009-exec-11,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1560]
Formatted event: "5440a0f3-05f7-487c-8284-d52a52e82820-1562","2017-11-24T07:13:22.074Z","AM-ACCESS-OUTCOME","5440a0f3-05f7-487c-8284-d52a52e82820-1560",,"[""df5650a87decbb8901""]",,,"61.116.105.120","63696",,,,"true","POST","https://sso.example.co.jp:443/openam/json/authenticate","{""realm"":[""/SiteUserRealm""],""forward"":[""true""],""spEntityID"":[""google.com/a/jp.example.com""],""goto"":[""/openam/SSORedirect/metaAlias/SiteUserRealm/idp?ReqID=bjdpbeanhohkaapoldleoafdclninmpfpdjiiloo&index=null&acsURL=https%3A%2F%2Fwww.google.com%2Fa%2Fjp.example.com%2Facs&spEntityID=google.com%2Fa%2Fjp.example.com&binding=urn%3Aoasis%3Anames%3Atc%3ASAML%3A2.0%3Abindings%3AHTTP-POST""],""AMAuthCookie"":[""""]}","{""accept"":[""application/json, text/javascript, */*; q=0.01""],""Accept-API-Version"":[""protocol=1.0,resource=2.0""],""DNT"":[""1""],""host"":[""sso.example.co.jp""],""referer"":[""https://sso.example.co.jp/openam/XUI/""],""user-agent"":[""Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 6.1; Trident/7.0; SLCC2; .NET CLR 2.0.50727; .NET CLR 3.5.30729; .NET CLR 3.0.30729; Media Center PC 6.0; .NET4.0C; .NET4.0E; MDDRJS)""],""X-NoSession"":[""true""],""X-Password"":[""anonymous""],""X-Requested-With"":[""XMLHttpRequest""],""X-Username"":[""anonymous""]}","{""JSESSIONID"":""5A6B081DCF8EC3C79F7A219E83BF06D1"",""i18next"":""ja-JP""}",,"FAILED","401","{""reason"":""Unauthorized""}","19","MILLISECONDS","Authentication","/SiteUserRealm"

org.forgerock.audit.events.handlers.writers.RotatableWriter:11/24/2017 04:13:22:075 PM JST: Thread[CsvHandler,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1505]
Actually writing to file: "5440a0f3-05f7-487c-8284-d52a52e82820-1562","2017-11-24T07:13:22.074Z","AM-ACCESS-OUTCOME","5440a0f3-05f7-487c-8284-d52a52e82820-1560",,"[""df5650a87decbb8901""]",,,"61.116.105.120","63696",,,,"true","POST","https://sso.example.co.jp:443/openam/json/authenticate","{""realm"":[""/SiteUserRealm""],""forward"":[""true""],""spEntityID"":[""google.com/a/jp.example.com""],""goto"":[""/openam/SSORedirect/metaAlias/SiteUserRealm/idp?ReqID=bjdpbeanhohkaapoldleoafdclninmpfpdjiiloo&index=null&acsURL=https%3A%2F%2Fwww.google.com%2Fa%2Fjp.example.com%2Facs&spEntityID=google.com%2Fa%2Fjp.example.com&binding=urn%3Aoasis%3Anames%3Atc%3ASAML%3A2.0%3Abindings%3AHTTP-POST""],""AMAuthCookie"":[""""]}","{""accept"":[""application/json, text/javascript, */*; q=0.01""],""Accept-API-Version"":[""protocol=1.0,resource=2.0""],""DNT"":[""1""],""host"":[""sso.example.co.jp""],""referer"":[""https://sso.example.co.jp/openam/XUI/""],""user-agent"":[""Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 6.1; Trident/7.0; SLCC2; .NET CLR 2.0.50727; .NET CLR 3.5.30729; .NET CLR 3.0.30729; Media Center PC 6.0; .NET4.0C; .NET4.0E; MDDRJS)""],""X-NoSession"":[""true""],""X-Password"":[""anonymous""],""X-Requested-With"":[""XMLHttpRequest""],""X-Username"":[""anonymous""]}","{""JSESSIONID"":""5A6B081DCF8EC3C79F7A219E83BF06D1"",""i18next"":""ja-JP""}",,"FAILED","401","{""reason"":""Unauthorized""}","19","MILLISECONDS","Authentication","/SiteUserRealm"

org.forgerock.audit.events.handlers.writers.RotatableWriter:11/24/2017 04:13:22:075 PM JST: Thread[CsvHandler,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1505]
bytes written=2230897
amCookieUtils:11/24/2017 04:13:22:173 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
No Cookie is in the request
amComm:11/24/2017 04:13:22:180 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
ClientUtils.getClientIPAddress : remoteAddr=[61.116.105.120]
amNaming:11/24/2017 04:13:22:181 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
WebtopNaming : SiteID for 01 is 02
amNaming:11/24/2017 04:13:22:190 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
WebtopNaming : SiteID for 01 is 02
amNaming:11/24/2017 04:13:22:190 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
WebtopNaming : SiteID for 01 is 02
amNaming:11/24/2017 04:13:22:190 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
WebtopNaming.getLBCookieValue(): lbCookieValuefor 01 is 01
amCookieUtils:11/24/2017 04:13:22:194 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
No Cookie is in the request
amCookieUtils:11/24/2017 04:13:22:194 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
No Cookie is in the request
amNaming:11/24/2017 04:13:22:198 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
WebtopNaming.getLBCookieValue(): lbCookieValuefor 01 is 01
amComm:11/24/2017 04:13:22:204 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
ClientUtils.getClientIPAddress : remoteAddr=[61.116.105.120]
org.forgerock.audit.AuditServiceImpl:11/24/2017 04:13:22:204 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
Audit create called for access
org.forgerock.audit.AuditServiceImpl:11/24/2017 04:13:22:204 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
Audit create id 5440a0f3-05f7-487c-8284-d52a52e82820-1567
org.forgerock.audit.AuditServiceImpl:11/24/2017 04:13:22:204 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
Cascading the event of topic access to the handlers : [org.forgerock.audit.handlers.csv.CsvAuditEventHandler@9a96a16]
org.forgerock.audit.handlers.csv.CsvFormatter:11/24/2017 04:13:22:204 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
Formatted event: "5440a0f3-05f7-487c-8284-d52a52e82820-1567","2017-11-24T07:13:22.204Z","AM-ACCESS-OUTCOME","5440a0f3-05f7-487c-8284-d52a52e82820-1565",,"[""3468dd8e238e576801""]",,,"61.116.105.120","62507",,,,"true","POST","https://sso.example.co.jp:443/openam/json/authenticate","{""realm"":[""/SiteUserRealm""],""forward"":[""true""],""spEntityID"":[""google.com/a/jp.example.com""],""goto"":[""/openam/SSORedirect/metaAlias/SiteUserRealm/idp?ReqID=bjdpbeanhohkaapoldleoafdclninmpfpdjiiloo&index=null&acsURL=https%3A%2F%2Fwww.google.com%2Fa%2Fjp.example.com%2Facs&spEntityID=google.com%2Fa%2Fjp.example.com&binding=urn%3Aoasis%3Anames%3Atc%3ASAML%3A2.0%3Abindings%3AHTTP-POST""],""AMAuthCookie"":[""""]}","{""accept"":[""application/json, text/javascript, */*; q=0.01""],""Accept-API-Version"":[""protocol=1.0,resource=2.0""],""DNT"":[""1""],""host"":[""sso.example.co.jp""],""referer"":[""https://sso.example.co.jp/openam/XUI/""],""user-agent"":[""Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 6.1; Trident/7.0; SLCC2; .NET CLR 2.0.50727; .NET CLR 3.5.30729; .NET CLR 3.0.30729; Media Center PC 6.0; .NET4.0C; .NET4.0E; MDDRJS)""],""X-NoSession"":[""true""],""X-Password"":[""anonymous""],""X-Requested-With"":[""XMLHttpRequest""],""X-Username"":[""anonymous""]}","{""JSESSIONID"":""5A6B081DCF8EC3C79F7A219E83BF06D1"",""i18next"":""ja-JP"",""amlbcookie"":""01""}",,"FAILED","401","{""reason"":""Unauthorized""}","31","MILLISECONDS","Authentication","/SiteUserRealm"

org.forgerock.audit.events.handlers.writers.RotatableWriter:11/24/2017 04:13:22:207 PM JST: Thread[CsvHandler,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1505]
Actually writing to file: "5440a0f3-05f7-487c-8284-d52a52e82820-1567","2017-11-24T07:13:22.204Z","AM-ACCESS-OUTCOME","5440a0f3-05f7-487c-8284-d52a52e82820-1565",,"[""3468dd8e238e576801""]",,,"61.116.105.120","62507",,,,"true","POST","https://sso.example.co.jp:443/openam/json/authenticate","{""realm"":[""/SiteUserRealm""],""forward"":[""true""],""spEntityID"":[""google.com/a/jp.example.com""],""goto"":[""/openam/SSORedirect/metaAlias/SiteUserRealm/idp?ReqID=bjdpbeanhohkaapoldleoafdclninmpfpdjiiloo&index=null&acsURL=https%3A%2F%2Fwww.google.com%2Fa%2Fjp.example.com%2Facs&spEntityID=google.com%2Fa%2Fjp.example.com&binding=urn%3Aoasis%3Anames%3Atc%3ASAML%3A2.0%3Abindings%3AHTTP-POST""],""AMAuthCookie"":[""""]}","{""accept"":[""application/json, text/javascript, */*; q=0.01""],""Accept-API-Version"":[""protocol=1.0,resource=2.0""],""DNT"":[""1""],""host"":[""sso.example.co.jp""],""referer"":[""https://sso.example.co.jp/openam/XUI/""],""user-agent"":[""Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 6.1; Trident/7.0; SLCC2; .NET CLR 2.0.50727; .NET CLR 3.5.30729; .NET CLR 3.0.30729; Media Center PC 6.0; .NET4.0C; .NET4.0E; MDDRJS)""],""X-NoSession"":[""true""],""X-Password"":[""anonymous""],""X-Requested-With"":[""XMLHttpRequest""],""X-Username"":[""anonymous""]}","{""JSESSIONID"":""5A6B081DCF8EC3C79F7A219E83BF06D1"",""i18next"":""ja-JP"",""amlbcookie"":""01""}",,"FAILED","401","{""reason"":""Unauthorized""}","31","MILLISECONDS","Authentication","/SiteUserRealm"

org.forgerock.audit.events.handlers.writers.RotatableWriter:11/24/2017 04:13:22:207 PM JST: Thread[CsvHandler,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1505]
bytes written=2232352
amCookieUtils:11/24/2017 04:13:22:246 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
No Cookie is in the request
amCookieUtils:11/24/2017 04:13:22:264 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
No Cookie is in the request
amCookieUtils:11/24/2017 04:13:22:264 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
No Cookie is in the request
org.forgerock.audit.AuditServiceImpl:11/24/2017 04:13:22:265 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
Audit create called for authentication
org.forgerock.audit.AuditServiceImpl:11/24/2017 04:13:22:265 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
Audit create id 5440a0f3-05f7-487c-8284-d52a52e82820-1572
org.forgerock.audit.AuditServiceImpl:11/24/2017 04:13:22:265 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
Cascading the event of topic authentication to the handlers : [org.forgerock.audit.handlers.csv.CsvAuditEventHandler@9a96a16]
org.forgerock.audit.handlers.csv.CsvFormatter:11/24/2017 04:13:22:266 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
Formatted event: "5440a0f3-05f7-487c-8284-d52a52e82820-1572","2017-11-24T07:13:22.265Z","AM-LOGIN-MODULE-COMPLETED","5440a0f3-05f7-487c-8284-d52a52e82820-1570",,"[""3468dd8e238e576801""]","FAILED",,,"[{""moduleId"":""WindowsDesktopSSOAuthModule"",""info"":{""authControlFlag"":""SUFFICIENT"",""moduleClass"":""WindowsDesktopSSO"",""ipAddress"":""61.116.105.120"",""authLevel"":""0""}}]","Authentication","/siteuserrealm"

org.forgerock.audit.events.handlers.writers.RotatableWriter:11/24/2017 04:13:22:266 PM JST: Thread[CsvHandler,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1573]
Actually writing to file: "5440a0f3-05f7-487c-8284-d52a52e82820-1572","2017-11-24T07:13:22.265Z","AM-LOGIN-MODULE-COMPLETED","5440a0f3-05f7-487c-8284-d52a52e82820-1570",,"[""3468dd8e238e576801""]","FAILED",,,"[{""moduleId"":""WindowsDesktopSSOAuthModule"",""info"":{""authControlFlag"":""SUFFICIENT"",""moduleClass"":""WindowsDesktopSSO"",""ipAddress"":""61.116.105.120"",""authLevel"":""0""}}]","Authentication","/siteuserrealm"

org.forgerock.audit.events.handlers.writers.RotatableWriter:11/24/2017 04:13:22:266 PM JST: Thread[CsvHandler,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1573]
bytes written=239876
amComm:11/24/2017 04:13:22:268 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
ClientUtils.getClientIPAddress : remoteAddr=[61.116.105.120]
org.forgerock.audit.AuditServiceImpl:11/24/2017 04:13:22:268 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
Audit create called for access
org.forgerock.audit.AuditServiceImpl:11/24/2017 04:13:22:268 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
Audit create id 5440a0f3-05f7-487c-8284-d52a52e82820-1575
org.forgerock.audit.AuditServiceImpl:11/24/2017 04:13:22:268 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
Cascading the event of topic access to the handlers : [org.forgerock.audit.handlers.csv.CsvAuditEventHandler@9a96a16]
org.forgerock.audit.handlers.csv.CsvFormatter:11/24/2017 04:13:22:268 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
Formatted event: "5440a0f3-05f7-487c-8284-d52a52e82820-1575","2017-11-24T07:13:22.268Z","AM-ACCESS-OUTCOME","5440a0f3-05f7-487c-8284-d52a52e82820-1570",,"[""3468dd8e238e576801""]",,,"61.116.105.120","60634",,,,"true","POST","https://sso.example.co.jp:443/openam/json/authenticate","{""realm"":[""/SiteUserRealm""]}","{""accept"":[""application/json, text/javascript, */*; q=0.01""],""Accept-API-Version"":[""protocol=1.0,resource=2.0""],""DNT"":[""1""],""host"":[""sso.example.co.jp""],""referer"":[""https://sso.example.co.jp/openam/XUI/""],""user-agent"":[""Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 6.1; Trident/7.0; SLCC2; .NET CLR 2.0.50727; .NET CLR 3.5.30729; .NET CLR 3.0.30729; Media Center PC 6.0; .NET4.0C; .NET4.0E; MDDRJS)""],""X-NoSession"":[""true""],""X-Password"":[""anonymous""],""X-Requested-With"":[""XMLHttpRequest""],""X-Username"":[""anonymous""]}","{""JSESSIONID"":""5A6B081DCF8EC3C79F7A219E83BF06D1"",""i18next"":""ja-JP"",""amlbcookie"":""01""}",,"SUCCESSFUL",,,"22","MILLISECONDS","Authentication","/SiteUserRealm"

org.forgerock.audit.events.handlers.writers.RotatableWriter:11/24/2017 04:13:22:269 PM JST: Thread[CsvHandler,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1505]
Actually writing to file: "5440a0f3-05f7-487c-8284-d52a52e82820-1575","2017-11-24T07:13:22.268Z","AM-ACCESS-OUTCOME","5440a0f3-05f7-487c-8284-d52a52e82820-1570",,"[""3468dd8e238e576801""]",,,"61.116.105.120","60634",,,,"true","POST","https://sso.example.co.jp:443/openam/json/authenticate","{""realm"":[""/SiteUserRealm""]}","{""accept"":[""application/json, text/javascript, */*; q=0.01""],""Accept-API-Version"":[""protocol=1.0,resource=2.0""],""DNT"":[""1""],""host"":[""sso.example.co.jp""],""referer"":[""https://sso.example.co.jp/openam/XUI/""],""user-agent"":[""Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 6.1; Trident/7.0; SLCC2; .NET CLR 2.0.50727; .NET CLR 3.5.30729; .NET CLR 3.0.30729; Media Center PC 6.0; .NET4.0C; .NET4.0E; MDDRJS)""],""X-NoSession"":[""true""],""X-Password"":[""anonymous""],""X-Requested-With"":[""XMLHttpRequest""],""X-Username"":[""anonymous""]}","{""JSESSIONID"":""5A6B081DCF8EC3C79F7A219E83BF06D1"",""i18next"":""ja-JP"",""amlbcookie"":""01""}",,"SUCCESSFUL",,,"22","MILLISECONDS","Authentication","/SiteUserRealm"

org.forgerock.audit.events.handlers.writers.RotatableWriter:11/24/2017 04:13:22:269 PM JST: Thread[CsvHandler,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1505]
bytes written=2233829

==> IdRepo <==
amIdm:11/24/2017 04:13:21:951 PM JST: Thread[ajp-bio-8009-exec-38,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1550]
IdUtils:getOrganization Input orgname: serverinfo
amIdm:11/24/2017 04:13:21:952 PM JST: Thread[ajp-bio-8009-exec-38,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1550]
IdUtils.getOrganization: getting from SMS realms
amIdm:11/24/2017 04:13:21:952 PM JST: Thread[ajp-bio-8009-exec-38,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1550]
IdUtils.getOrganization: getting from SMS realms aliases
amIdm:11/24/2017 04:13:21:953 PM JST: Thread[ajp-bio-8009-exec-38,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1550]
WARNING: IdUtils.getOrganization Unable to find Org name for: serverinfo
amIdm:11/24/2017 04:13:22:056 PM JST: Thread[ajp-bio-8009-exec-11,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1560]
IdUtils:getOrganization Input orgname: authenticate
amIdm:11/24/2017 04:13:22:056 PM JST: Thread[ajp-bio-8009-exec-11,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1560]
IdUtils.getOrganization: getting from SMS realms
amIdm:11/24/2017 04:13:22:057 PM JST: Thread[ajp-bio-8009-exec-11,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1560]
IdUtils.getOrganization: getting from SMS realms aliases
amIdm:11/24/2017 04:13:22:057 PM JST: Thread[ajp-bio-8009-exec-11,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1560]
WARNING: IdUtils.getOrganization Unable to find Org name for: authenticate
amIdm:11/24/2017 04:13:22:174 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
IdUtils:getOrganization Input orgname: authenticate
amIdm:11/24/2017 04:13:22:174 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
IdUtils.getOrganization: getting from SMS realms
amIdm:11/24/2017 04:13:22:175 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
IdUtils.getOrganization: getting from SMS realms aliases
amIdm:11/24/2017 04:13:22:176 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
WARNING: IdUtils.getOrganization Unable to find Org name for: authenticate
amIdm:11/24/2017 04:13:22:247 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
IdUtils:getOrganization Input orgname: authenticate
amIdm:11/24/2017 04:13:22:247 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
IdUtils.getOrganization: getting from SMS realms
amIdm:11/24/2017 04:13:22:248 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
IdUtils.getOrganization: getting from SMS realms aliases
amIdm:11/24/2017 04:13:22:248 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
WARNING: IdUtils.getOrganization Unable to find Org name for: authenticate

==> Session <==
amSSOProvider:11/24/2017 04:13:21:955 PM JST: Thread[ajp-bio-8009-exec-38,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1550]
SSOProviderImpl.createSSOToken(tokenId, false, true) could not create SSOToken for token ID "null" (Invalid session ID.)
amSession:11/24/2017 04:13:21:957 PM JST: Thread[ajp-bio-8009-exec-38,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1550]
Could not get SSOToken from context
com.iplanet.sso.SSOException: Invalid session ID.
	at com.iplanet.sso.providers.dpro.SSOProviderImpl.createSSOToken(SSOProviderImpl.java:220)
	at com.iplanet.sso.providers.dpro.SSOProviderImpl.createSSOToken(SSOProviderImpl.java:184)
	at com.iplanet.sso.providers.dpro.SSOProviderImpl.createSSOToken(SSOProviderImpl.java:236)
	at com.iplanet.sso.SSOTokenManager.createSSOToken(SSOTokenManager.java:367)
	at org.forgerock.openam.rest.resource.SSOTokenContext.getSsoToken(SSOTokenContext.java:132)
	at org.forgerock.openam.rest.resource.SSOTokenContext$4.get(SSOTokenContext.java:107)
	at org.forgerock.openam.rest.resource.SSOTokenContext$4.get(SSOTokenContext.java:103)
	at org.forgerock.guava.common.base.Suppliers$MemoizingSupplier.get(Suppliers.java:125)
	at org.forgerock.openam.rest.resource.SSOTokenContext.getCallerSSOToken(SSOTokenContext.java:142)
	at org.forgerock.openam.rest.resource.SSOTokenContext.getSsoToken(SSOTokenContext.java:129)
	at org.forgerock.openam.rest.fluent.CrestLoggingFilter.logAccess(CrestLoggingFilter.java:192)
	at org.forgerock.openam.rest.fluent.CrestLoggingFilter.filterRead(CrestLoggingFilter.java:165)
	at org.forgerock.json.resource.FilterChain$Cursor.handleRead(FilterChain.java:107)
	at org.forgerock.openam.rest.ContextFilter.filterRead(ContextFilter.java:87)
	at org.forgerock.json.resource.FilterChain$Cursor.handleRead(FilterChain.java:107)
	at org.forgerock.openam.rest.AuthenticationEnforcer.filterRead(AuthenticationEnforcer.java:182)
	at org.forgerock.json.resource.FilterChain$Cursor.handleRead(FilterChain.java:107)
	at org.forgerock.json.resource.FilterChain.handleRead(FilterChain.java:237)
	at org.forgerock.json.resource.Router.handleRead(Router.java:324)
	at org.forgerock.json.resource.FilterChain$Cursor.handleRead(FilterChain.java:109)
	at org.forgerock.openam.rest.ContextFilter.filterRead(ContextFilter.java:87)
	at org.forgerock.json.resource.FilterChain$Cursor.handleRead(FilterChain.java:107)
	at org.forgerock.json.resource.FilterChain.handleRead(FilterChain.java:237)
	at org.forgerock.json.resource.InternalConnection.readAsync(InternalConnection.java:89)
	at org.forgerock.json.resource.http.RequestRunner.visitReadRequest(RequestRunner.java:316)
	at org.forgerock.json.resource.http.RequestRunner.visitReadRequest(RequestRunner.java:73)
	at org.forgerock.json.resource.Requests$ReadRequestImpl.accept(Requests.java:591)
	at org.forgerock.json.resource.http.RequestRunner.handleResult(RequestRunner.java:119)
	at org.forgerock.json.resource.http.HttpAdapter$2.apply(HttpAdapter.java:566)
	at org.forgerock.json.resource.http.HttpAdapter$2.apply(HttpAdapter.java:563)
	at org.forgerock.util.promise.Promises$CompletedPromise.thenAsync(Promises.java:255)
	at org.forgerock.util.promise.Promises$CompletedPromise.thenAsync(Promises.java:244)
	at org.forgerock.json.resource.http.HttpAdapter.doRequest(HttpAdapter.java:562)
	at org.forgerock.json.resource.http.HttpAdapter.doRead(HttpAdapter.java:256)
	at org.forgerock.json.resource.http.HttpAdapter.handle(HttpAdapter.java:163)
	at org.forgerock.http.filter.OptionsFilter.filter(OptionsFilter.java:77)
	at org.forgerock.http.handler.Handlers$1.handle(Handlers.java:56)
	at org.forgerock.openam.rest.CrestProtocolEnforcementFilter.filter(CrestProtocolEnforcementFilter.java:61)
	at org.forgerock.http.handler.Handlers$1.handle(Handlers.java:56)
	at org.forgerock.http.routing.Router.handle(Router.java:92)
	at org.forgerock.openam.rest.RealmContextFilter.filter(RealmContextFilter.java:84)
	at org.forgerock.http.handler.Handlers$1.handle(Handlers.java:56)
	at org.forgerock.http.routing.Router.handle(Router.java:92)
	at org.forgerock.http.routing.ResourceApiVersionRoutingFilter.filter(ResourceApiVersionRoutingFilter.java:64)
	at org.forgerock.http.handler.Handlers$1.handle(Handlers.java:56)
	at org.forgerock.caf.authentication.framework.AuthenticationFramework.grantAccess(AuthenticationFramework.java:220)
	at org.forgerock.caf.authentication.framework.AuthenticationFramework.access$400(AuthenticationFramework.java:65)
	at org.forgerock.caf.authentication.framework.AuthenticationFramework$3.apply(AuthenticationFramework.java:212)
	at org.forgerock.caf.authentication.framework.AuthenticationFramework$3.apply(AuthenticationFramework.java:205)
	at org.forgerock.util.promise.Promises$CompletedPromise.thenAsync(Promises.java:255)
	at org.forgerock.util.promise.Promises$CompletedPromise.thenAsync(Promises.java:244)
	at org.forgerock.caf.authentication.framework.AuthenticationFramework.validateRequest(AuthenticationFramework.java:168)
	at org.forgerock.caf.authentication.framework.AuthenticationFramework.access$100(AuthenticationFramework.java:65)
	at org.forgerock.caf.authentication.framework.AuthenticationFramework$1.apply(AuthenticationFramework.java:155)
	at org.forgerock.caf.authentication.framework.AuthenticationFramework$1.apply(AuthenticationFramework.java:152)
	at org.forgerock.util.promise.PromiseImpl$7.handleStateChange(PromiseImpl.java:485)
	at org.forgerock.util.promise.PromiseImpl.handleCompletion(PromiseImpl.java:567)
	at org.forgerock.util.promise.PromiseImpl.addOrFireListener(PromiseImpl.java:555)
	at org.forgerock.util.promise.PromiseImpl.thenAsync(PromiseImpl.java:477)
	at org.forgerock.util.promise.PromiseImpl.thenAsync(PromiseImpl.java:468)
	at org.forgerock.caf.authentication.framework.AuthenticationFramework.processMessage(AuthenticationFramework.java:146)
	at org.forgerock.caf.authentication.framework.AuthenticationFilter.filter(AuthenticationFilter.java:96)
	at org.forgerock.http.handler.Handlers$1.handle(Handlers.java:56)
	at org.forgerock.openam.http.HandlerProvider.handle(HandlerProvider.java:50)
	at org.forgerock.openam.http.HttpRoute$3.handle(HttpRoute.java:142)
	at org.forgerock.http.routing.Router.handle(Router.java:92)
	at org.forgerock.http.filter.TransactionIdInboundFilter.filter(TransactionIdInboundFilter.java:60)
	at org.forgerock.http.handler.Handlers$1.handle(Handlers.java:56)
	at org.forgerock.http.servlet.HttpFrameworkServlet.service(HttpFrameworkServlet.java:225)
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:731)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:303)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208)
	at org.apache.tomcat.websocket.server.WsFilter.doFilter(WsFilter.java:52)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:241)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208)
	at org.forgerock.openam.validation.ResponseValidationFilter.doFilter(ResponseValidationFilter.java:44)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:241)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208)
	at com.sun.identity.setup.AMSetupFilter.doFilter(AMSetupFilter.java:111)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:241)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208)
	at org.forgerock.openam.audit.context.AuditContextFilter.doFilter(AuditContextFilter.java:51)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:241)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208)
	at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:220)
	at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:122)
	at org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:505)
	at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:169)
	at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:103)
	at org.apache.catalina.valves.RemoteIpValve.invoke(RemoteIpValve.java:683)
	at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:116)
	at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:436)
	at org.apache.coyote.ajp.AjpProcessor.process(AjpProcessor.java:190)
	at org.apache.coyote.AbstractProtocol$AbstractConnectionHandler.process(AbstractProtocol.java:625)
	at org.apache.tomcat.util.net.JIoEndpoint$SocketProcessor.run(JIoEndpoint.java:316)
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
	at org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61)
	at java.lang.Thread.run(Thread.java:748)
Caused by: com.iplanet.dpro.session.SessionException: Invalid session ID.
	at org.forgerock.openam.session.SessionCache.getSession(SessionCache.java:253)
	at com.iplanet.sso.providers.dpro.SSOProviderImpl.createSSOToken(SSOProviderImpl.java:205)
	... 98 more

amSSOProvider:11/24/2017 04:13:21:958 PM JST: Thread[ajp-bio-8009-exec-38,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1550]
SSOProviderImpl.createSSOToken(tokenId, false, true) could not create SSOToken for token ID "null" (Invalid session ID.)
amSession:11/24/2017 04:13:21:958 PM JST: Thread[ajp-bio-8009-exec-38,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1550]
Could not get SSOToken from context
com.iplanet.sso.SSOException: Invalid session ID.
	at com.iplanet.sso.providers.dpro.SSOProviderImpl.createSSOToken(SSOProviderImpl.java:220)
	at com.iplanet.sso.providers.dpro.SSOProviderImpl.createSSOToken(SSOProviderImpl.java:184)
	at com.iplanet.sso.providers.dpro.SSOProviderImpl.createSSOToken(SSOProviderImpl.java:236)
	at com.iplanet.sso.SSOTokenManager.createSSOToken(SSOTokenManager.java:367)
	at org.forgerock.openam.rest.resource.SSOTokenContext.getSsoToken(SSOTokenContext.java:132)
	at org.forgerock.openam.rest.resource.SSOTokenContext$4.get(SSOTokenContext.java:107)
	at org.forgerock.openam.rest.resource.SSOTokenContext$4.get(SSOTokenContext.java:103)
	at org.forgerock.openam.rest.resource.SSOTokenContext$1.get(SSOTokenContext.java:71)
	at org.forgerock.openam.rest.resource.SSOTokenContext$1.get(SSOTokenContext.java:68)
	at org.forgerock.guava.common.base.Suppliers$MemoizingSupplier.get(Suppliers.java:125)
	at org.forgerock.openam.rest.resource.SSOTokenContext.getCallerSubject(SSOTokenContext.java:137)
	at org.forgerock.openam.forgerockrest.utils.PrincipalRestUtils.getPrincipalNameFromServerContext(PrincipalRestUtils.java:42)
	at org.forgerock.openam.forgerockrest.utils.RestLog.debugOperationAttemptAsPrincipal(RestLog.java:91)
	at org.forgerock.openam.rest.fluent.CrestLoggingFilter.logAccess(CrestLoggingFilter.java:205)
	at org.forgerock.openam.rest.fluent.CrestLoggingFilter.filterRead(CrestLoggingFilter.java:165)
	at org.forgerock.json.resource.FilterChain$Cursor.handleRead(FilterChain.java:107)
	at org.forgerock.openam.rest.ContextFilter.filterRead(ContextFilter.java:87)
	at org.forgerock.json.resource.FilterChain$Cursor.handleRead(FilterChain.java:107)
	at org.forgerock.openam.rest.AuthenticationEnforcer.filterRead(AuthenticationEnforcer.java:182)
	at org.forgerock.json.resource.FilterChain$Cursor.handleRead(FilterChain.java:107)
	at org.forgerock.json.resource.FilterChain.handleRead(FilterChain.java:237)
	at org.forgerock.json.resource.Router.handleRead(Router.java:324)
	at org.forgerock.json.resource.FilterChain$Cursor.handleRead(FilterChain.java:109)
	at org.forgerock.openam.rest.ContextFilter.filterRead(ContextFilter.java:87)
	at org.forgerock.json.resource.FilterChain$Cursor.handleRead(FilterChain.java:107)
	at org.forgerock.json.resource.FilterChain.handleRead(FilterChain.java:237)
	at org.forgerock.json.resource.InternalConnection.readAsync(InternalConnection.java:89)
	at org.forgerock.json.resource.http.RequestRunner.visitReadRequest(RequestRunner.java:316)
	at org.forgerock.json.resource.http.RequestRunner.visitReadRequest(RequestRunner.java:73)
	at org.forgerock.json.resource.Requests$ReadRequestImpl.accept(Requests.java:591)
	at org.forgerock.json.resource.http.RequestRunner.handleResult(RequestRunner.java:119)
	at org.forgerock.json.resource.http.HttpAdapter$2.apply(HttpAdapter.java:566)
	at org.forgerock.json.resource.http.HttpAdapter$2.apply(HttpAdapter.java:563)
	at org.forgerock.util.promise.Promises$CompletedPromise.thenAsync(Promises.java:255)
	at org.forgerock.util.promise.Promises$CompletedPromise.thenAsync(Promises.java:244)
	at org.forgerock.json.resource.http.HttpAdapter.doRequest(HttpAdapter.java:562)
	at org.forgerock.json.resource.http.HttpAdapter.doRead(HttpAdapter.java:256)
	at org.forgerock.json.resource.http.HttpAdapter.handle(HttpAdapter.java:163)
	at org.forgerock.http.filter.OptionsFilter.filter(OptionsFilter.java:77)
	at org.forgerock.http.handler.Handlers$1.handle(Handlers.java:56)
	at org.forgerock.openam.rest.CrestProtocolEnforcementFilter.filter(CrestProtocolEnforcementFilter.java:61)
	at org.forgerock.http.handler.Handlers$1.handle(Handlers.java:56)
	at org.forgerock.http.routing.Router.handle(Router.java:92)
	at org.forgerock.openam.rest.RealmContextFilter.filter(RealmContextFilter.java:84)
	at org.forgerock.http.handler.Handlers$1.handle(Handlers.java:56)
	at org.forgerock.http.routing.Router.handle(Router.java:92)
	at org.forgerock.http.routing.ResourceApiVersionRoutingFilter.filter(ResourceApiVersionRoutingFilter.java:64)
	at org.forgerock.http.handler.Handlers$1.handle(Handlers.java:56)
	at org.forgerock.caf.authentication.framework.AuthenticationFramework.grantAccess(AuthenticationFramework.java:220)
	at org.forgerock.caf.authentication.framework.AuthenticationFramework.access$400(AuthenticationFramework.java:65)
	at org.forgerock.caf.authentication.framework.AuthenticationFramework$3.apply(AuthenticationFramework.java:212)
	at org.forgerock.caf.authentication.framework.AuthenticationFramework$3.apply(AuthenticationFramework.java:205)
	at org.forgerock.util.promise.Promises$CompletedPromise.thenAsync(Promises.java:255)
	at org.forgerock.util.promise.Promises$CompletedPromise.thenAsync(Promises.java:244)
	at org.forgerock.caf.authentication.framework.AuthenticationFramework.validateRequest(AuthenticationFramework.java:168)
	at org.forgerock.caf.authentication.framework.AuthenticationFramework.access$100(AuthenticationFramework.java:65)
	at org.forgerock.caf.authentication.framework.AuthenticationFramework$1.apply(AuthenticationFramework.java:155)
	at org.forgerock.caf.authentication.framework.AuthenticationFramework$1.apply(AuthenticationFramework.java:152)
	at org.forgerock.util.promise.PromiseImpl$7.handleStateChange(PromiseImpl.java:485)
	at org.forgerock.util.promise.PromiseImpl.handleCompletion(PromiseImpl.java:567)
	at org.forgerock.util.promise.PromiseImpl.addOrFireListener(PromiseImpl.java:555)
	at org.forgerock.util.promise.PromiseImpl.thenAsync(PromiseImpl.java:477)
	at org.forgerock.util.promise.PromiseImpl.thenAsync(PromiseImpl.java:468)
	at org.forgerock.caf.authentication.framework.AuthenticationFramework.processMessage(AuthenticationFramework.java:146)
	at org.forgerock.caf.authentication.framework.AuthenticationFilter.filter(AuthenticationFilter.java:96)
	at org.forgerock.http.handler.Handlers$1.handle(Handlers.java:56)
	at org.forgerock.openam.http.HandlerProvider.handle(HandlerProvider.java:50)
	at org.forgerock.openam.http.HttpRoute$3.handle(HttpRoute.java:142)
	at org.forgerock.http.routing.Router.handle(Router.java:92)
	at org.forgerock.http.filter.TransactionIdInboundFilter.filter(TransactionIdInboundFilter.java:60)
	at org.forgerock.http.handler.Handlers$1.handle(Handlers.java:56)
	at org.forgerock.http.servlet.HttpFrameworkServlet.service(HttpFrameworkServlet.java:225)
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:731)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:303)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208)
	at org.apache.tomcat.websocket.server.WsFilter.doFilter(WsFilter.java:52)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:241)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208)
	at org.forgerock.openam.validation.ResponseValidationFilter.doFilter(ResponseValidationFilter.java:44)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:241)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208)
	at com.sun.identity.setup.AMSetupFilter.doFilter(AMSetupFilter.java:111)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:241)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208)
	at org.forgerock.openam.audit.context.AuditContextFilter.doFilter(AuditContextFilter.java:51)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:241)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208)
	at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:220)
	at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:122)
	at org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:505)
	at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:169)
	at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:103)
	at org.apache.catalina.valves.RemoteIpValve.invoke(RemoteIpValve.java:683)
	at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:116)
	at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:436)
	at org.apache.coyote.ajp.AjpProcessor.process(AjpProcessor.java:190)
	at org.apache.coyote.AbstractProtocol$AbstractConnectionHandler.process(AbstractProtocol.java:625)
	at org.apache.tomcat.util.net.JIoEndpoint$SocketProcessor.run(JIoEndpoint.java:316)
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
	at org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61)
	at java.lang.Thread.run(Thread.java:748)
Caused by: com.iplanet.dpro.session.SessionException: Invalid session ID.
	at org.forgerock.openam.session.SessionCache.getSession(SessionCache.java:253)
	at com.iplanet.sso.providers.dpro.SSOProviderImpl.createSSOToken(SSOProviderImpl.java:205)
	... 101 more

amSSOProvider:11/24/2017 04:13:22:058 PM JST: Thread[ajp-bio-8009-exec-11,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1560]
SSOProviderImpl.createSSOToken(tokenId, false, true) could not create SSOToken for token ID "" (Invalid session ID.)
amSession:11/24/2017 04:13:22:066 PM JST: Thread[ajp-bio-8009-exec-11,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1560]
SessionID(HttpServletRequest) : is forward = null
amSessionEncodeURL:11/24/2017 04:13:22:066 PM JST: Thread[ajp-bio-8009-exec-11,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1560]
getSidFromQuery: request =org.forgerock.openam.core.rest.authn.http.AuthenticationServiceV1$1@506bdbd3
amSessionEncodeURL:11/24/2017 04:13:22:068 PM JST: Thread[ajp-bio-8009-exec-11,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1560]
getSidFromQuery: sid =null
amSessionEncodeURL:11/24/2017 04:13:22:068 PM JST: Thread[ajp-bio-8009-exec-11,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1560]
before decoding getSidFromURL:sidString=null
amSessionEncodeURL:11/24/2017 04:13:22:068 PM JST: Thread[ajp-bio-8009-exec-11,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1560]
after decoding: getSidFromURL:sidString=null
amSession:11/24/2017 04:13:22:068 PM JST: Thread[ajp-bio-8009-exec-11,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1560]
SessionID(HttpServletRequest) : is forward = null
amSessionEncodeURL:11/24/2017 04:13:22:068 PM JST: Thread[ajp-bio-8009-exec-11,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1560]
getSidFromQuery: request =org.forgerock.openam.core.rest.authn.http.AuthenticationServiceV1$1@506bdbd3
amSessionEncodeURL:11/24/2017 04:13:22:068 PM JST: Thread[ajp-bio-8009-exec-11,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1560]
getSidFromQuery: sid =null
amSessionEncodeURL:11/24/2017 04:13:22:068 PM JST: Thread[ajp-bio-8009-exec-11,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1560]
before decoding getSidFromURL:sidString=null
amSessionEncodeURL:11/24/2017 04:13:22:068 PM JST: Thread[ajp-bio-8009-exec-11,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1560]
after decoding: getSidFromURL:sidString=null
amSSOProvider:11/24/2017 04:13:22:069 PM JST: Thread[ajp-bio-8009-exec-11,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1560]
could not create SSOToken from HttpRequest (Invalid session ID.)
amSSOProvider:11/24/2017 04:13:22:176 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
SSOProviderImpl.createSSOToken(tokenId, false, true) could not create SSOToken for token ID "" (Invalid session ID.)
amSession:11/24/2017 04:13:22:194 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
SessionID(HttpServletRequest) : is forward = null
amSessionEncodeURL:11/24/2017 04:13:22:194 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
getSidFromQuery: request =org.forgerock.openam.core.rest.authn.http.AuthenticationServiceV1$1@3b5edef7
amSessionEncodeURL:11/24/2017 04:13:22:194 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
getSidFromQuery: sid =null
amSessionEncodeURL:11/24/2017 04:13:22:194 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
before decoding getSidFromURL:sidString=null
amSessionEncodeURL:11/24/2017 04:13:22:194 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
after decoding: getSidFromURL:sidString=null
amSession:11/24/2017 04:13:22:194 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
SessionID(HttpServletRequest) : is forward = null
amSessionEncodeURL:11/24/2017 04:13:22:194 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
getSidFromQuery: request =org.forgerock.openam.core.rest.authn.http.AuthenticationServiceV1$1@3b5edef7
amSessionEncodeURL:11/24/2017 04:13:22:194 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
getSidFromQuery: sid =null
amSessionEncodeURL:11/24/2017 04:13:22:194 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
before decoding getSidFromURL:sidString=null
amSessionEncodeURL:11/24/2017 04:13:22:194 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
after decoding: getSidFromURL:sidString=null
amSSOProvider:11/24/2017 04:13:22:195 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1565]
could not create SSOToken from HttpRequest (Invalid session ID.)
amSSOProvider:11/24/2017 04:13:22:263 PM JST: Thread[ajp-bio-8009-exec-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1570]
SSOProviderImpl.createSSOToken(tokenId, false, true) could not create SSOToken for token ID "" (Invalid session ID.)

==> CoreSystem <==
org.forgerock.audit.events.handlers.writers.RotatableWriter:11/24/2017 04:13:22:975 PM JST: Thread[pool-1-thread-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1506]
bytes written=2233829
org.forgerock.audit.events.handlers.writers.RotatableWriter:11/24/2017 04:13:22:980 PM JST: Thread[pool-2-thread-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1507]
bytes written=176385
org.forgerock.audit.events.handlers.writers.RotatableWriter:11/24/2017 04:13:22:981 PM JST: Thread[pool-3-thread-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1508]
bytes written=3789
org.forgerock.audit.events.handlers.writers.RotatableWriter:11/24/2017 04:13:22:983 PM JST: Thread[pool-4-thread-1,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-1509]
bytes written=239876


# /opt/osstech/bin/ssoadm list-cots --adminid amadmin --password-file /opt/osstech/etc/openam/private/amadmin.password --realm SiteUserRealm

トラストサークルは次のとおりです。
  SiteCoT
libCOT:11/24/2017 03:57:16:887 PM JST: Thread[ajp-bio-8009-exec-7,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-960]
ERROR: COTManager.isValidCOTName: invalid circle of trust name :
libCOT:11/24/2017 03:57:16:887 PM JST: Thread[ajp-bio-8009-exec-7,5,main]: TransactionId[5440a0f3-05f7-487c-8284-d52a52e82820-960]
ERROR: COTManager.isInCircleOfTrust
com.sun.identity.cot.COTException: Circle of Trust name in realm /SiteUserRealm is invalid :
        at com.sun.identity.cot.CircleOfTrustManager.isValidCOTName(CircleOfTrustManager.java:1003)
        at com.sun.identity.cot.CircleOfTrustManager.getCircleOfTrust(CircleOfTrustManager.java:888)
        at com.sun.identity.cot.CircleOfTrustManager.isInCircleOfTrust(CircleOfTrustManager.java:973)
        at com.sun.identity.saml2.meta.SAML2MetaManager.isSameCircleOfTrust(SAML2MetaManager.java:1773)
        at com.sun.identity.saml2.meta.SAML2MetaManager.isTrustedProvider(SAML2MetaManager.java:1720)
        at com.sun.identity.saml2.common.SAML2Utils.isSourceSiteValid(SAML2Utils.java:1158)
        at org.forgerock.openam.saml2.UtilProxySAMLAuthenticator.authenticate(UtilProxySAMLAuthenticator.java:140)
        at com.sun.identity.saml2.profile.IDPSSOFederate.process(IDPSSOFederate.java:236)
        at com.sun.identity.saml2.profile.IDPSSOFederate.doSSOFederate(IDPSSOFederate.java:142)
        at com.sun.identity.saml2.profile.IDPSSOFederate.doSSOFederate(IDPSSOFederate.java:102)
        at org.apache.jsp.saml2.jsp.idpSSOFederate_jsp._jspService(idpSSOFederate_jsp.java:157)
        at org.apache.jasper.runtime.HttpJspBase.service(HttpJspBase.java:70)
        at javax.servlet.http.HttpServlet.service(HttpServlet.java:731)
        at org.apache.jasper.servlet.JspServletWrapper.service(JspServletWrapper.java:439)
        at org.apache.jasper.servlet.JspServlet.serviceJspFile(JspServlet.java:395)
        at org.apache.jasper.servlet.JspServlet.service(JspServlet.java:339)
        at javax.servlet.http.HttpServlet.service(HttpServlet.java:731)
        at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:303)
        at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208)
        at org.apache.tomcat.websocket.server.WsFilter.doFilter(WsFilter.java:52)
        at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:241)
        at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208)
        at org.forgerock.openam.validation.ResponseValidationFilter.doFilter(ResponseValidationFilter.java:44)
        at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:241)
        at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208)
        at com.sun.identity.setup.AMSetupFilter.doFilter(AMSetupFilter.java:111)
        at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:241)
        at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208)
        at org.forgerock.openam.audit.context.AuditContextFilter.doFilter(AuditContextFilter.java:51)
        at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:241)
        at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208)
        at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:220)
        at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:122)
        at org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:505)
        at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:169)
        at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:103)
        at org.apache.catalina.valves.RemoteIpValve.invoke(RemoteIpValve.java:683)
        at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:116)
        at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:436)
        at org.apache.coyote.ajp.AjpProcessor.process(AjpProcessor.java:190)
        at org.apache.coyote.AbstractProtocol$AbstractConnectionHandler.process(AbstractProtocol.java:625)
        at org.apache.tomcat.util.net.JIoEndpoint$SocketProcessor.run(JIoEndpoint.java:316)
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
        at org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61)
        at java.lang.Thread.run(Thread.java:748)
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment