By: Thomas Ruth user 21 Oct 2019 at 1:57 p.m. CDT

19 Responses
Thomas Ruth gravatar
I'm attempting to use Kubernetes RBAC with GLUU as an openid connect provider. I am able to get the kubectl client to retrieve an initial token using a third-party token tool. This authorization works for an hour. After this hour, I expect kubectl to be able to retrieve a new token using the refresh_token grant, and the refresh token I received originally. Instead, what is happening is I'm getting this error back from kubectl: ``` tom@truth:~> kubectl get nodes Unable to connect to the server: failed to refresh token: oauth2: cannot fetch token: 401 Unauthorized Response: {"error":"invalid_client","error_description":"Client authentication failed (e.g. unknown client, no client authentication included, or unsupported authentication method). The authorization server MAY return an HTTP 401 (Unauthorized) status code to indicate which HTTP authentication schemes are supported. If the client attempted to authenticate via the Authorization request header field, the authorization server MUST respond with an HTTP 401 (Unauthorized) status code, and include the WWW-Authenticate response header field matching the authentication scheme used by the client."} ``` I put oxauth in to debug mode, and this is what was returned during this interaction: ``` 2019-10-21 18:07:51,109 DEBUG [qtp804611486-18] [org.xdi.oxauth.service.ClientService] (ClientService.java:141) - Found 1 entries for client id = @!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F 2019-10-21 18:07:51,110 DEBUG [qtp804611486-18] [org.xdi.oxauth.auth.AuthenticationFilter] (AuthenticationFilter.java:98) - Starting token endpoint authentication 2019-10-21 18:07:51,110 DEBUG [qtp804611486-18] [org.xdi.oxauth.auth.AuthenticationFilter] (AuthenticationFilter.java:111) - Starting Basic Auth token endpoint authentication 2019-10-21 18:07:51,111 DEBUG [qtp804611486-18] [org.xdi.oxauth.service.ClientService] (ClientService.java:141) - Found 1 entries for client id = @!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F 2019-10-21 18:07:51,111 DEBUG [qtp804611486-18] [org.xdi.oxauth.service.ClientService] (ClientService.java:101) - Authenticating Client with LDAP: clientId = @!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F 2019-10-21 18:07:51,112 DEBUG [qtp804611486-18] [org.xdi.oxauth.service.ClientService] (ClientService.java:141) - Found 1 entries for client id = @!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F 2019-10-21 18:07:51,112 DEBUG [qtp804611486-18] [org.xdi.oxauth.service.AuthenticationService] (AuthenticationService.java:515) - ConfigureSessionClient: username: '@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F', credentials: '1979088033' 2019-10-21 18:07:51,112 DEBUG [qtp804611486-18] [org.xdi.oxauth.service.ClientService] (ClientService.java:141) - Found 1 entries for client id = @!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F 2019-10-21 18:07:51,122 INFO [qtp804611486-18] [org.xdi.oxauth.auth.Authenticator] (Authenticator.java:262) - Authentication success for Client: '@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F' 2019-10-21 18:07:51,124 DEBUG [qtp804611486-18] [xdi.oxauth.token.ws.rs.TokenRestWebServiceImpl] (TokenRestWebServiceImpl.java:107) - Attempting to request access token: grantType = refresh_token, code = null, redirectUri = null, username = null, refreshToken = 8e84766a-6036-4749-96de-8b35e40478ca, clientId = null, ExtraParams = {refresh_token=[8e84766a-6036-4749-96de-8b35e40478ca], grant_type=[refresh_token]}, isSecure = true, codeVerifier = null, ticket = null 2019-10-21 18:07:51,124 DEBUG [qtp804611486-18] [xdi.oxauth.token.ws.rs.TokenRestWebServiceImpl] (TokenRestWebServiceImpl.java:129) - Starting to validate request parameters 2019-10-21 18:07:51,124 DEBUG [qtp804611486-18] [xdi.oxauth.token.ws.rs.TokenRestWebServiceImpl] (TokenRestWebServiceImpl.java:137) - Grant type: 'refresh_token' 2019-10-21 18:07:51,125 DEBUG [qtp804611486-18] [xdi.oxauth.token.ws.rs.TokenRestWebServiceImpl] (TokenRestWebServiceImpl.java:143) - Get sessionClient: 'org.xdi.oxauth.model.session.SessionClient@250cbb71' 2019-10-21 18:07:51,125 DEBUG [qtp804611486-18] [xdi.oxauth.token.ws.rs.TokenRestWebServiceImpl] (TokenRestWebServiceImpl.java:147) - Get client from session: '@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F' 2019-10-21 18:07:51,126 DEBUG [qtp804611486-18] [org.xdi.oxauth.model.error.ErrorResponseFactory] (ErrorResponseFactory.java:70) - Looking for the error with id: invalid_grant 2019-10-21 18:07:51,126 DEBUG [qtp804611486-18] [org.xdi.oxauth.model.error.ErrorResponseFactory] (ErrorResponseFactory.java:75) - Found error, id: invalid_grant 2019-10-21 18:07:51,322 DEBUG [qtp804611486-18] [org.xdi.oxauth.service.ClientService] (ClientService.java:141) - Found 1 entries for client id = @!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F 2019-10-21 18:07:51,323 DEBUG [qtp804611486-18] [org.xdi.oxauth.auth.AuthenticationFilter] (AuthenticationFilter.java:98) - Starting token endpoint authentication 2019-10-21 18:07:51,323 DEBUG [qtp804611486-18] [org.xdi.oxauth.auth.AuthenticationFilter] (AuthenticationFilter.java:114) - Starting POST Auth token endpoint authentication 2019-10-21 18:07:51,323 DEBUG [qtp804611486-18] [org.xdi.oxauth.auth.AuthenticationFilter] (AuthenticationFilter.java:296) - requireAuth: 'true' 2019-10-21 18:07:51,323 DEBUG [qtp804611486-18] [org.xdi.oxauth.service.ClientService] (ClientService.java:141) - Found 1 entries for client id = @!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F 2019-10-21 18:07:51,324 DEBUG [qtp804611486-18] [org.xdi.oxauth.model.error.ErrorResponseFactory] (ErrorResponseFactory.java:70) - Looking for the error with id: invalid_client 2019-10-21 18:07:51,324 DEBUG [qtp804611486-18] [org.xdi.oxauth.model.error.ErrorResponseFactory] (ErrorResponseFactory.java:75) - Found error, id: invalid_client ``` The errors above seem to indicate that the refresh_token is an invalid grant, not the invalid client that eventually gets returned. In my client configuration, I have refresh_token listed under the grant types. Client config summary is below. I first started by creating a GLUU server following the installation instructions on the site. I have integrated it with my Active Directory servers and validated that I can login with AD credentials. I then got some certificates signed by a CA system and installed them because the Kubernetes docs said that I needed them. I replaced the following certificates: HTTPD SSL IDP SIGNING IDP ENCRYPTION I then created a client for my Kubernetes system. I downloaded a GO project called oidc-gen-token from github.com/lpabon and used it to generate a token. By default, it doesn't return the refresh_token for some reason, so I added that and recompiled it. For it to work, I had to use a redirect_URI which I provided. I then took those tokens and put them into my kubectl config file like so: ``` - name: truth user: auth-provider: config: client-id: '@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F' client-secret: zzGih4gZwNUihACjZYrboz0z id-token: eyJraWQiOiJiOTg3OWFkMy0yOGQ3LTQ3MzItYjk3My1kOTNlOGY0MWY4ZGIiLCJ0eXAiOiJKV1QiLCJhbGciOiJSUzI1NiJ9.eyJpc3MiOiJodHRwczovL2dhdXRoLnNxZC5zb2xpZGZpcmUubmV0IiwiYXVkIjoiQCFDQTQ1LjA0MjQuNEUyRi5COUU2ITAwMDEhRjJDRi40MkFFITAwMDghRTRCNS5DQTJELjkxMDguNUE0RiIsImV4cCI6MTU3MTM0NDMxOSwiaWF0IjoxNTcxMzQwNzE5LCJhdXRoX3RpbWUiOjE1NzEzNDA3MTgsImF0X2hhc2giOiJPSXhPZlR3WWkxZ00yYVlHcmQ4ZEZnIiwib3hPcGVuSURDb25uZWN0VmVyc2lvbiI6Im9wZW5pZGNvbm5lY3QtMS4wIiwibmFtZSI6IlRvbSBSdXRoIiwiZmFtaWx5X25hbWUiOiJSdXRoIiwiZ2l2ZW5fbmFtZSI6IlRvbSIsInVwZGF0ZWRfYXQiOiIyMDE5MTAxNjE4MTQ1My43ODlaIiwiZW1haWwiOiJ0cnV0aEBuZXRhcHAuY29tIiwiZ3JvdXBzIjoiaW51bT1AIUNBNDUuMDQyNC40RTJGLkI5RTYhMDAwMSFGMkNGLjQyQUUhMDAwMyE3MDc2LkFDOTUsb3U9Z3JvdXBzLG89QCFDQTQ1LjA0MjQuNEUyRi5COUU2ITAwMDEhRjJDRi40MkFFLG89Z2x1dSIsInN1YiI6Ino2S0pic3RHWElheGN4Z3BjaGJRS3BCN3p2MWU2NFF0LW54QTIxWFR1dEUifQ.FwFN9-w2fPtoSndC2kmMMuEp3jy-oVCpINsU-rRoWgVlb0wCuE5CFFPMS2ST1LwOvHoXpc6W4COlMcRrd06DNGroPR9YPsRGYZckB3dtbF6qUtIm-tFp1pytQ4vuy9vn-ct4nvuDc-LHK16yDgo7o0Y1uAWRxnT6EJOghCPrwwjpG-MdlyIUWJTt2AdFG-Liv-Bj3yT4kEc8xQEM7BLKvZredz0-EY4gbxLkWGigNs_3Vg_hl-DO6lyOg17yT20Qk87yfd-KOGyaKt0dLUOX8XBdpUhW4xPnobmIHPDzncjm1gjm971pC1h7HfFruXk5nbDu67WjhcBy7_UeCH_Udw idp-certificate-authority: /etc/ssl/certs/SQD-Solutions-CA.crt idp-issuer-url: https://gauth.sqd.solidfire.net refresh-token: 8e84766a-6036-4749-96de-8b35e40478ca name: oidc ``` I already figured out that I need to enable the advanced setting "Include claims in ID Token" as well, since Kubernetes wasn't getting the information it wanted. I wanted to use my e-mail address as the username in Kubernetes, so I added an e-mail claim to a few of the scopes as well. The Kubernetes API server has been configured with the following flags to enable this: ``` - --oidc-issuer-url=https://gauth.sqd.solidfire.net - --oidc-client-id=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F - --oidc-username-claim=email ``` Is there anything I need to do to get the refresh_token working correctly with Kubernetes? I've been attempting to find guides or documentation on how to set up Kubernetes authentication/authorization with GLUU, but I am only able to find how to run GLUU on top of Kubernetes, and generic docs about how Kubernetes openid works, and how to configure with other software packages. I would love to know if there is a guide specifically on integrating Kubernetes RBAC with GLUU. Client configuration summary: OPENID CONNECT CLIENTS DETAILS ------------------------------ - **Name:** sqd-k8s-test - **Description:** SQD Kubernetes test instance - **Client ID:** @!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F - **Subject Type:** pairwise - **Expirattion date:** Tue Sep 26 00:00:00 UTC 2119 - **ClientSecret:** XXXXXXXXXXX - **Application Type:** native - **Persist Client Authorizations:** true - **Pre-Authorization:** false - **Authentication method for the Token Endpoint:** client_secret_basic - **Logout Session Required:** false - **Include Claims In Id Token:** true - **Disabled:** false - **Login Redirect URIs:** [http://127.0.0.1:5556/auth/callback, http://localhost:18000, http://localhost:8000] - **Scopes:** [email, openid, profile, user_name, clientinfo, groups] - **Grant types:** [implicit, refresh_token, authorization_code] - **Response types:** [token, id_token, code]

By Aliaksandr Samuseu staff 21 Oct 2019 at 9:19 p.m. CDT

Aliaksandr Samuseu gravatar
Hi, Thomas. I'll try to reproduce your issue. May I ask you to enable HTTP request logging for oxAuth as well, and find out what **exactly** your RP (Kubernets) sends when it tries to use refresh token? Then please share it with us. Here is how: 1. Log in to web UI and move to "Configuration -> JSON configuration -> oxAuth configuration" page 2. Find "loggingLevel" property and make sure it's set to DEBUG 3. Find "httpLoggingEnabled" and set it to "true" 4. Restart "oxauth" service to make sure it's applied It should start dumping HTTP requests to `/opt/gluu/jetty/oxauth/logs/http_request_response.log` You need somehow find your refresh token request in it, unless sharing the full file with us is an option.

By Thomas Ruth user 23 Oct 2019 at 10:25 a.m. CDT

Thomas Ruth gravatar
FYI I responded to the e-mail yesterday, but it doesn't look like it posted to the bug. Here was my response: This is what was sent to the http request log when I ran my “kubectl get nodes” command: ``` 2019-10-22 15:37:01,667 DEBUG [qtp804611486-11] [org.xdi.oxauth.audit.debug.ServletLoggingFilter] (ServletLoggingFilter.java:76) - {"senderIP":"127.0.0.1","method":"GET","path":"/oxauth/.well-known/openid-configuration","headers":{"User-Agent":"Go-http-client/1.1","X-Forwarded-Proto":"https","X-Forwarded-Host":"gauth.sqd.solidfire.net","Connection":"keep-alive","X-Forwarded-For":"10.116.156.251","Host":"gauth.sqd.solidfire.net","Accept-Encoding":"gzip","X-Forwarded-Server":"gauth.sqd.solidfire.net"}} 2019-10-22 15:37:01,668 DEBUG [qtp804611486-11] [org.xdi.oxauth.audit.debug.ServletLoggingFilter] (ServletLoggingFilter.java:77) - {"status":200,"headers":{"Content-Type":"application/json"}} 2019-10-22 15:37:01,894 DEBUG [qtp804611486-15] [org.xdi.oxauth.audit.debug.ServletLoggingFilter] (ServletLoggingFilter.java:76) - {"senderIP":"127.0.0.1","method":"POST","path":"/oxauth/restv1/token","headers":{"Authorization":"Basic JTQwJTIxQ0E0NS4wNDI0LjRFMkYuQjlFNiUyMTAwMDElMjFGMkNGLjQyQUUlMjEwMDA4JTIxRTRCNS5DQTJELjkxMDguNUE0Rjp6ekdpaDRnWndOVWloQUNqWllyYm96MHo=","User-Agent":"Go-http-client/1.1","X-Forwarded-Proto":"https","X-Forwarded-Host":"gauth.sqd.solidfire.net","Connection":"keep-alive","X-Forwarded-For":"10.116.156.251","Host":"gauth.sqd.solidfire.net","Accept-Encoding":"gzip","Content-Length":"75","X-Forwarded-Server":"gauth.sqd.solidfire.net","Content-Type":"application/x-www-form-urlencoded"},"body":"refresh_token=8e84766a-6036-4749-96de-8b35e40478ca&grant_type=refresh_token"} 2019-10-22 15:37:01,895 DEBUG [qtp804611486-15] [org.xdi.oxauth.audit.debug.ServletLoggingFilter] (ServletLoggingFilter.java:77) - {"status":401,"headers":{"Cache-Control":"no-store","Content-Type":"application/json","Pragma":"no-cache"}} 2019-10-22 15:37:02,098 DEBUG [qtp804611486-11] [org.xdi.oxauth.audit.debug.ServletLoggingFilter] (ServletLoggingFilter.java:76) - {"senderIP":"127.0.0.1","method":"POST","path":"/oxauth/restv1/token","headers":{"User-Agent":"Go-http-client/1.1","X-Forwarded-Proto":"https","X-Forwarded-Host":"gauth.sqd.solidfire.net","Connection":"keep-alive","X-Forwarded-For":"10.116.156.251","Host":"gauth.sqd.solidfire.net","Accept-Encoding":"gzip","Content-Length":"198","X-Forwarded-Server":"gauth.sqd.solidfire.net","Content-Type":"application/x-www-form-urlencoded"},"body":"refresh_token=8e84766a-6036-4749-96de-8b35e40478ca&client_secret=zzGih4gZwNUihACjZYrboz0z&grant_type=refresh_token&client_id=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F"} 2019-10-22 15:37:02,098 DEBUG [qtp804611486-11] [org.xdi.oxauth.audit.debug.ServletLoggingFilter] (ServletLoggingFilter.java:77) - {"status":401,"headers":{"WWW-Authenticate":"Basic realm=\"oxAuth\"","Content-Type":"application/json;charset=iso-8859-1"}} 2019-10-22 15:37:02,311 DEBUG [qtp804611486-15] [org.xdi.oxauth.audit.debug.ServletLoggingFilter] (ServletLoggingFilter.java:76) - {"senderIP":"127.0.0.1","method":"GET","path":"/oxauth/.well-known/openid-configuration","headers":{"User-Agent":"Go-http-client/1.1","X-Forwarded-Proto":"https","X-Forwarded-Host":"gauth.sqd.solidfire.net","Connection":"keep-alive","X-Forwarded-For":"10.116.156.251","Host":"gauth.sqd.solidfire.net","Accept-Encoding":"gzip","X-Forwarded-Server":"gauth.sqd.solidfire.net"}} 2019-10-22 15:37:02,312 DEBUG [qtp804611486-15] [org.xdi.oxauth.audit.debug.ServletLoggingFilter] (ServletLoggingFilter.java:77) - {"status":200,"headers":{"Content-Type":"application/json"}} 2019-10-22 15:37:02,531 DEBUG [qtp804611486-15] [org.xdi.oxauth.audit.debug.ServletLoggingFilter] (ServletLoggingFilter.java:76) - {"senderIP":"127.0.0.1","method":"POST","path":"/oxauth/restv1/token","headers":{"Authorization":"Basic JTQwJTIxQ0E0NS4wNDI0LjRFMkYuQjlFNiUyMTAwMDElMjFGMkNGLjQyQUUlMjEwMDA4JTIxRTRCNS5DQTJELjkxMDguNUE0Rjp6ekdpaDRnWndOVWloQUNqWllyYm96MHo=","User-Agent":"Go-http-client/1.1","X-Forwarded-Proto":"https","X-Forwarded-Host":"gauth.sqd.solidfire.net","Connection":"keep-alive","X-Forwarded-For":"10.116.156.251","Host":"gauth.sqd.solidfire.net","Accept-Encoding":"gzip","Content-Length":"75","X-Forwarded-Server":"gauth.sqd.solidfire.net","Content-Type":"application/x-www-form-urlencoded"},"body":"refresh_token=8e84766a-6036-4749-96de-8b35e40478ca&grant_type=refresh_token"} 2019-10-22 15:37:02,531 DEBUG [qtp804611486-15] [org.xdi.oxauth.audit.debug.ServletLoggingFilter] (ServletLoggingFilter.java:77) - {"status":401,"headers":{"Cache-Control":"no-store","Content-Type":"application/json","Pragma":"no-cache"}} 2019-10-22 15:37:02,733 DEBUG [qtp804611486-15] [org.xdi.oxauth.audit.debug.ServletLoggingFilter] (ServletLoggingFilter.java:76) - {"senderIP":"127.0.0.1","method":"POST","path":"/oxauth/restv1/token","headers":{"User-Agent":"Go-http-client/1.1","X-Forwarded-Proto":"https","X-Forwarded-Host":"gauth.sqd.solidfire.net","Connection":"keep-alive","X-Forwarded-For":"10.116.156.251","Host":"gauth.sqd.solidfire.net","Accept-Encoding":"gzip","Content-Length":"198","X-Forwarded-Server":"gauth.sqd.solidfire.net","Content-Type":"application/x-www-form-urlencoded"},"body":"refresh_token=8e84766a-6036-4749-96de-8b35e40478ca&client_secret=zzGih4gZwNUihACjZYrboz0z&grant_type=refresh_token&client_id=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F"} 2019-10-22 15:37:02,733 DEBUG [qtp804611486-15] [org.xdi.oxauth.audit.debug.ServletLoggingFilter] (ServletLoggingFilter.java:77) - {"status":401,"headers":{"WWW-Authenticate":"Basic realm=\"oxAuth\"","Content-Type":"application/json;charset=iso-8859-1"}} 2019-10-22 15:37:02,944 DEBUG [qtp804611486-11] [org.xdi.oxauth.audit.debug.ServletLoggingFilter] (ServletLoggingFilter.java:76) - {"senderIP":"127.0.0.1","method":"GET","path":"/oxauth/.well-known/openid-configuration","headers":{"User-Agent":"Go-http-client/1.1","X-Forwarded-Proto":"https","X-Forwarded-Host":"gauth.sqd.solidfire.net","Connection":"keep-alive","X-Forwarded-For":"10.116.156.251","Host":"gauth.sqd.solidfire.net","Accept-Encoding":"gzip","X-Forwarded-Server":"gauth.sqd.solidfire.net"}} 2019-10-22 15:37:02,944 DEBUG [qtp804611486-11] [org.xdi.oxauth.audit.debug.ServletLoggingFilter] (ServletLoggingFilter.java:77) - {"status":200,"headers":{"Content-Type":"application/json"}} 2019-10-22 15:37:03,170 DEBUG [qtp804611486-15] [org.xdi.oxauth.audit.debug.ServletLoggingFilter] (ServletLoggingFilter.java:76) - {"senderIP":"127.0.0.1","method":"POST","path":"/oxauth/restv1/token","headers":{"Authorization":"Basic JTQwJTIxQ0E0NS4wNDI0LjRFMkYuQjlFNiUyMTAwMDElMjFGMkNGLjQyQUUlMjEwMDA4JTIxRTRCNS5DQTJELjkxMDguNUE0Rjp6ekdpaDRnWndOVWloQUNqWllyYm96MHo=","User-Agent":"Go-http-client/1.1","X-Forwarded-Proto":"https","X-Forwarded-Host":"gauth.sqd.solidfire.net","Connection":"keep-alive","X-Forwarded-For":"10.116.156.251","Host":"gauth.sqd.solidfire.net","Accept-Encoding":"gzip","Content-Length":"75","X-Forwarded-Server":"gauth.sqd.solidfire.net","Content-Type":"application/x-www-form-urlencoded"},"body":"refresh_token=8e84766a-6036-4749-96de-8b35e40478ca&grant_type=refresh_token"} 2019-10-22 15:37:03,170 DEBUG [qtp804611486-15] [org.xdi.oxauth.audit.debug.ServletLoggingFilter] (ServletLoggingFilter.java:77) - {"status":401,"headers":{"Cache-Control":"no-store","Content-Type":"application/json","Pragma":"no-cache"}} 2019-10-22 15:37:03,376 DEBUG [qtp804611486-11] [org.xdi.oxauth.audit.debug.ServletLoggingFilter] (ServletLoggingFilter.java:76) - {"senderIP":"127.0.0.1","method":"POST","path":"/oxauth/restv1/token","headers":{"User-Agent":"Go-http-client/1.1","X-Forwarded-Proto":"https","X-Forwarded-Host":"gauth.sqd.solidfire.net","Connection":"keep-alive","X-Forwarded-For":"10.116.156.251","Host":"gauth.sqd.solidfire.net","Accept-Encoding":"gzip","Content-Length":"198","X-Forwarded-Server":"gauth.sqd.solidfire.net","Content-Type":"application/x-www-form-urlencoded"},"body":"refresh_token=8e84766a-6036-4749-96de-8b35e40478ca&client_secret=zzGih4gZwNUihACjZYrboz0z&grant_type=refresh_token&client_id=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F"} 2019-10-22 15:37:03,376 DEBUG [qtp804611486-11] [org.xdi.oxauth.audit.debug.ServletLoggingFilter] (ServletLoggingFilter.java:77) - {"status":401,"headers":{"WWW-Authenticate":"Basic realm=\"oxAuth\"","Content-Type":"application/json;charset=iso-8859-1"}} 2019-10-22 15:37:03,589 DEBUG [qtp804611486-15] [org.xdi.oxauth.audit.debug.ServletLoggingFilter] (ServletLoggingFilter.java:76) - {"senderIP":"127.0.0.1","method":"GET","path":"/oxauth/.well-known/openid-configuration","headers":{"User-Agent":"Go-http-client/1.1","X-Forwarded-Proto":"https","X-Forwarded-Host":"gauth.sqd.solidfire.net","Connection":"keep-alive","X-Forwarded-For":"10.116.156.251","Host":"gauth.sqd.solidfire.net","Accept-Encoding":"gzip","X-Forwarded-Server":"gauth.sqd.solidfire.net"}} 2019-10-22 15:37:03,589 DEBUG [qtp804611486-15] [org.xdi.oxauth.audit.debug.ServletLoggingFilter] (ServletLoggingFilter.java:77) - {"status":200,"headers":{"Content-Type":"application/json"}} 2019-10-22 15:37:03,807 DEBUG [qtp804611486-15] [org.xdi.oxauth.audit.debug.ServletLoggingFilter] (ServletLoggingFilter.java:76) - {"senderIP":"127.0.0.1","method":"POST","path":"/oxauth/restv1/token","headers":{"Authorization":"Basic JTQwJTIxQ0E0NS4wNDI0LjRFMkYuQjlFNiUyMTAwMDElMjFGMkNGLjQyQUUlMjEwMDA4JTIxRTRCNS5DQTJELjkxMDguNUE0Rjp6ekdpaDRnWndOVWloQUNqWllyYm96MHo=","User-Agent":"Go-http-client/1.1","X-Forwarded-Proto":"https","X-Forwarded-Host":"gauth.sqd.solidfire.net","Connection":"keep-alive","X-Forwarded-For":"10.116.156.251","Host":"gauth.sqd.solidfire.net","Accept-Encoding":"gzip","Content-Length":"75","X-Forwarded-Server":"gauth.sqd.solidfire.net","Content-Type":"application/x-www-form-urlencoded"},"body":"refresh_token=8e84766a-6036-4749-96de-8b35e40478ca&grant_type=refresh_token"} 2019-10-22 15:37:03,807 DEBUG [qtp804611486-15] [org.xdi.oxauth.audit.debug.ServletLoggingFilter] (ServletLoggingFilter.java:77) - {"status":401,"headers":{"Cache-Control":"no-store","Content-Type":"application/json","Pragma":"no-cache"}} 2019-10-22 15:37:04,011 DEBUG [qtp804611486-11] [org.xdi.oxauth.audit.debug.ServletLoggingFilter] (ServletLoggingFilter.java:76) - {"senderIP":"127.0.0.1","method":"POST","path":"/oxauth/restv1/token","headers":{"User-Agent":"Go-http-client/1.1","X-Forwarded-Proto":"https","X-Forwarded-Host":"gauth.sqd.solidfire.net","Connection":"keep-alive","X-Forwarded-For":"10.116.156.251","Host":"gauth.sqd.solidfire.net","Accept-Encoding":"gzip","Content-Length":"198","X-Forwarded-Server":"gauth.sqd.solidfire.net","Content-Type":"application/x-www-form-urlencoded"},"body":"refresh_token=8e84766a-6036-4749-96de-8b35e40478ca&client_secret=zzGih4gZwNUihACjZYrboz0z&grant_type=refresh_token&client_id=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F"} 2019-10-22 15:37:04,011 DEBUG [qtp804611486-11] [org.xdi.oxauth.audit.debug.ServletLoggingFilter] (ServletLoggingFilter.java:77) - {"status":401,"headers":{"WWW-Authenticate":"Basic realm=\"oxAuth\"","Content-Type":"application/json;charset=iso-8859-1"}} 2019-10-22 15:37:04,222 DEBUG [qtp804611486-15] [org.xdi.oxauth.audit.debug.ServletLoggingFilter] (ServletLoggingFilter.java:76) - {"senderIP":"127.0.0.1","method":"GET","path":"/oxauth/.well-known/openid-configuration","headers":{"User-Agent":"Go-http-client/1.1","X-Forwarded-Proto":"https","X-Forwarded-Host":"gauth.sqd.solidfire.net","Connection":"keep-alive","X-Forwarded-For":"10.116.156.251","Host":"gauth.sqd.solidfire.net","Accept-Encoding":"gzip","X-Forwarded-Server":"gauth.sqd.solidfire.net"}} 2019-10-22 15:37:04,222 DEBUG [qtp804611486-15] [org.xdi.oxauth.audit.debug.ServletLoggingFilter] (ServletLoggingFilter.java:77) - {"status":200,"headers":{"Content-Type":"application/json"}} 2019-10-22 15:37:04,437 DEBUG [qtp804611486-15] [org.xdi.oxauth.audit.debug.ServletLoggingFilter] (ServletLoggingFilter.java:76) - {"senderIP":"127.0.0.1","method":"POST","path":"/oxauth/restv1/token","headers":{"Authorization":"Basic JTQwJTIxQ0E0NS4wNDI0LjRFMkYuQjlFNiUyMTAwMDElMjFGMkNGLjQyQUUlMjEwMDA4JTIxRTRCNS5DQTJELjkxMDguNUE0Rjp6ekdpaDRnWndOVWloQUNqWllyYm96MHo=","User-Agent":"Go-http-client/1.1","X-Forwarded-Proto":"https","X-Forwarded-Host":"gauth.sqd.solidfire.net","Connection":"keep-alive","X-Forwarded-For":"10.116.156.251","Host":"gauth.sqd.solidfire.net","Accept-Encoding":"gzip","Content-Length":"75","X-Forwarded-Server":"gauth.sqd.solidfire.net","Content-Type":"application/x-www-form-urlencoded"},"body":"refresh_token=8e84766a-6036-4749-96de-8b35e40478ca&grant_type=refresh_token"} 2019-10-22 15:37:04,437 DEBUG [qtp804611486-15] [org.xdi.oxauth.audit.debug.ServletLoggingFilter] (ServletLoggingFilter.java:77) - {"status":401,"headers":{"Cache-Control":"no-store","Content-Type":"application/json","Pragma":"no-cache"}} 2019-10-22 15:37:04,639 DEBUG [qtp804611486-15] [org.xdi.oxauth.audit.debug.ServletLoggingFilter] (ServletLoggingFilter.java:76) - {"senderIP":"127.0.0.1","method":"POST","path":"/oxauth/restv1/token","headers":{"User-Agent":"Go-http-client/1.1","X-Forwarded-Proto":"https","X-Forwarded-Host":"gauth.sqd.solidfire.net","Connection":"keep-alive","X-Forwarded-For":"10.116.156.251","Host":"gauth.sqd.solidfire.net","Accept-Encoding":"gzip","Content-Length":"198","X-Forwarded-Server":"gauth.sqd.solidfire.net","Content-Type":"application/x-www-form-urlencoded"},"body":"refresh_token=8e84766a-6036-4749-96de-8b35e40478ca&client_secret=zzGih4gZwNUihACjZYrboz0z&grant_type=refresh_token&client_id=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F"} 2019-10-22 15:37:04,639 DEBUG [qtp804611486-15] [org.xdi.oxauth.audit.debug.ServletLoggingFilter] (ServletLoggingFilter.java:77) - {"status":401,"headers":{"WWW-Authenticate":"Basic realm=\"oxAuth\"","Content-Type":"application/json;charset=iso-8859-1"}} ```

By Thomas Ruth user 28 Oct 2019 at 11:12 a.m. CDT

Thomas Ruth gravatar
Hello, Have you been able to figure out what my issue is with this? I'm still having the problem. Please let me know if there is anything more I can provide to help resolve this. Thanks! Tom

By Aliaksandr Samuseu staff 05 Nov 2019 at 2:15 p.m. CST

Aliaksandr Samuseu gravatar
Hi, Thomas. Sorry for the delayed response. I tried to mimick the requests seen in your last logs in my tests as close as possible - and still refresh token requests work just fine for me. Though I was testing in our latest 3.1.x package - 3.1.7, that seems to be the only difference. You could consider upgrading to it, perhaps, and see whether it will help. Another thing you could try is to fetch the refresh token from this log above next time, and try to issue a refresh token request manually using `curl` (for example). See if it will work this way. Please also double-check you indeed added the refresh token grant to the client in question in Gluu Server.

By Thomas Ruth user 06 Nov 2019 at 10:32 a.m. CST

Thomas Ruth gravatar
OK, I will upgrade and give it another run. I pasted my client configuration at the end of my original post. I'm assuming that because refresh_token is listed under "Grant types" that it is configured to do this.

By Aliaksandr Samuseu staff 06 Nov 2019 at 10:45 a.m. CST

Aliaksandr Samuseu gravatar
>I'm assuming that because refresh_token is listed under "Grant types" that it is configured to do this. It should be, true. A few other things to check: 1. Make sure that time is in sync at this host (running ntpd is one option) 2. Move to "Configuration -> JSON Configuration -> oxAuth" and check: - `grantTypesSupported` has "refresh_token" enabled - `persistRefreshTokenInLdap` is True - `refreshTokenLifetime` has sufficiently high value (in seconds) 3. Try to remove "implicit" from "Grant types" list in client's properties, and see whether this will change something. I believe you use authz code flow anyway, so shouldn't need implicit stay allowed, correct?

By Thomas Ruth user 06 Nov 2019 at 10:59 a.m. CST

Thomas Ruth gravatar
I will double-check all those settings. I'm not seeing version 3.1.7 in the centos 7 repository, not sure how to get the new version?

By Aliaksandr Samuseu staff 06 Nov 2019 at 11:15 a.m. CST

Aliaksandr Samuseu gravatar
Sorry, it turns out it haven't been yet released. You can only get it from devel repo, for example by adding next line to `/etc/apt/sources.list.d/gluu-repo.list`: ``` deb http://repo.gluu.org/ubuntu/ xenial-devel main ```

By Aliaksandr Samuseu staff 06 Nov 2019 at 11:17 a.m. CST

Aliaksandr Samuseu gravatar
Must correct myself. You need to edit `/etc/yum.repos.d/Gluu.repo` by adding next section to it: ``` [Gluu-rpm-centos7-test-repo] name=Gluu centos7 test repo baseurl=https://repo.gluu.org/centos/7-testing/ enabled=1 gpgcheck=1 gpgkey=http://repo.gluu.org/centos/RPM-GPG-KEY-GLUU ```

By Aliaksandr Samuseu staff 06 Nov 2019 at 12:09 p.m. CST

Aliaksandr Samuseu gravatar
Thomas, I've just seen a notification that 3.1.7 packages were pushed to the main repos. You don't need to enable devel repo any more.

By Aliaksandr Samuseu staff 06 Nov 2019 at 5:51 p.m. CST

Aliaksandr Samuseu gravatar
I've conducted a new series of tests, in 3.1.6 this time - and I still can't reproduce it, no matter what. Unless more clues are gathered somehow, I doubt it will be very effective to spend more time on it, unfortunately. Ideally, you should completely exclude your RP from that last step (refreshing the token), and run that final request manually (with Postman or curl, by somehow fetching the refresh token from app's logs may be), and see whether you can still get the same error this way. Setting logging level to TRACE instead of debug may help as well. Share the `oxauth.log` section related to this moment with us again then.

By Thomas Ruth user 07 Nov 2019 at 11:18 a.m. CST

Thomas Ruth gravatar
Just waiting for one more piece to fall in line before I can upgrade: ``` [root@gauth ~]# gluu-serverd-3.1.6 login Last login: Wed Nov 6 18:35:34 2019 from ip6-localhost Welcome to the Gluu Server! [root@localhost ~]# wget https://repo.gluu.org/upd/3.1.7-upg.sh --2019-11-07 17:14:23-- https://repo.gluu.org/upd/3.1.7-upg.sh Resolving repo.gluu.org (repo.gluu.org)... 165.227.82.89 Connecting to repo.gluu.org (repo.gluu.org)|165.227.82.89|:443... connected. HTTP request sent, awaiting response... 404 Not Found 2019-11-07 17:14:24 ERROR 404: Not Found. ``` Also, it's just cosmetic, but the text in the guide says to upgrade from "3.1.7 to 3.1.7" everywhere: https://gluu.org/docs/ce/3.1.7/upgrade/

By William Lowe staff 07 Nov 2019 at 11:38 a.m. CST

William Lowe gravatar
> says to upgrade from "3.1.7 to 3.1.7" > Thanks, we fixed it. Just refresh the page to see the updates.

By Thomas Ruth user 08 Nov 2019 at 2:36 p.m. CST

Thomas Ruth gravatar
Do you know how long it will be before the upgrade script is available? Still getting 404...

By Aliaksandr Samuseu staff 08 Nov 2019 at 4:31 p.m. CST

Aliaksandr Samuseu gravatar
Hi, Thomas. I asked the dev team to check its status. I also wanted to note that again: refresh token shouldn't be supported for implicit flows. It's possible that one is issued still (sorry, didn't test it in implicit), but still rejected if used - as spec states refresh tokens aren't allowed for implicit. So if there any chance your RP uses that flow, that could explain it.

By Aliaksandr Samuseu staff 08 Nov 2019 at 4:32 p.m. CST

Aliaksandr Samuseu gravatar
Even if you don't use it explicitly, please try to remove corresponding grant from client's properties - there may be some check for that in oxAuth's code.

By Thomas Ruth user 08 Nov 2019 at 4:38 p.m. CST

Thomas Ruth gravatar
I did remove that from the client settings, I'm still running into the issue. I've double-checked all the other settings and they match what you have provided. I'm assuming I'm using the authz flow based on the URL below. This is the page I've been using as reference for determining how to configure it: https://kubernetes.io/docs/reference/access-authn-authz/authentication/

By Aliaksandr Samuseu staff 08 Nov 2019 at 5:02 p.m. CST

Aliaksandr Samuseu gravatar
I see. Let us know if you'll be able to collect new `oxauth.log` at TRACE level then. May be there will be some clue.

By Thomas Ruth user 08 Nov 2019 at 5:15 p.m. CST

Thomas Ruth gravatar
Oh, I can provide that now. The logs from the specific time range where I attempted to refresh my token follows. It still seems to indicate the refresh_token is an invalid grant from what I can see. My client config now shows only 2 grants: OPENID CONNECT CLIENTS DETAILS ------------------------------ - **Name:** sqd-k8s-test - **Description:** SQD Kubernetes test instance - **Client ID:** @!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F - **Subject Type:** pairwise - **Expirattion date:** Tue Sep 26 00:00:00 UTC 2119 - **ClientSecret:** XXXXXXXXXXX - **Application Type:** native - **Persist Client Authorizations:** true - **Pre-Authorization:** false - **Authentication method for the Token Endpoint:** client_secret_basic - **Logout Session Required:** false - **Include Claims In Id Token:** true - **Disabled:** false - **Login Redirect URIs:** [http://127.0.0.1:5556/auth/callback, http://localhost:18000, http://localhost:8000] - **Scopes:** [email, openid, profile, user_name, clientinfo, groups] - **Grant types:** [refresh_token, authorization_code] - **Response types:** [token, id_token, code] ``` 2019-11-08 23:05:19,613 DEBUG [oxAuthScheduler_Worker-2] [org.xdi.service.timer.RequestJobListener] (RequestJobListener.java:52) - Bound request started 2019-11-08 23:05:19,614 DEBUG [oxAuthScheduler_Worker-2] [org.xdi.service.timer.TimerJob] (TimerJob.java:34) - Fire timer event [org.xdi.service.cdi.event.UpdateScriptEvent] with qualifiers [@org.xdi.service.cdi.event.Scheduled()] 2019-11-08 23:05:19,643 DEBUG [oxAuthScheduler_Worker-2] [org.xdi.service.timer.RequestJobListener] (RequestJobListener.java:62) - Bound request ended 2019-11-08 23:05:29,153 TRACE [qtp804611486-10] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!CAE3,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:29,205 TRACE [qtp804611486-10] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!CAE3,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:29,205 TRACE [qtp804611486-10] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!D2E0,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:29,226 TRACE [qtp804611486-10] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!2B29,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:29,425 DEBUG [qtp804611486-18] [org.xdi.oxauth.service.ClientService] (ClientService.java:141) - Found 1 entries for client id = @!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F 2019-11-08 23:05:29,426 TRACE [qtp804611486-18] [org.xdi.oxauth.auth.AuthenticationFilter] (AuthenticationFilter.java:91) - Get request to: 'https://gauth.sqd.solidfire.net/oxauth/restv1/token' 2019-11-08 23:05:29,426 DEBUG [qtp804611486-18] [org.xdi.oxauth.auth.AuthenticationFilter] (AuthenticationFilter.java:98) - Starting token endpoint authentication 2019-11-08 23:05:29,426 DEBUG [qtp804611486-18] [org.xdi.oxauth.auth.AuthenticationFilter] (AuthenticationFilter.java:111) - Starting Basic Auth token endpoint authentication 2019-11-08 23:05:29,427 TRACE [qtp804611486-18] [org.xdi.oxauth.service.ClientService] (ClientService.java:199) - Get client from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F,ou=clients,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:29,427 DEBUG [qtp804611486-18] [org.xdi.oxauth.service.ClientService] (ClientService.java:141) - Found 1 entries for client id = @!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F 2019-11-08 23:05:29,427 TRACE [qtp804611486-18] [org.xdi.oxauth.auth.Authenticator] (Authenticator.java:181) - Authenticating ... (interactive: false, skipPassword: false, credentials.username: @!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F) 2019-11-08 23:05:29,427 DEBUG [qtp804611486-18] [org.xdi.oxauth.service.ClientService] (ClientService.java:101) - Authenticating Client with LDAP: clientId = @!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F 2019-11-08 23:05:29,428 TRACE [qtp804611486-18] [org.xdi.oxauth.service.ClientService] (ClientService.java:199) - Get client from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F,ou=clients,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:29,428 DEBUG [qtp804611486-18] [org.xdi.oxauth.service.ClientService] (ClientService.java:141) - Found 1 entries for client id = @!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F 2019-11-08 23:05:29,428 DEBUG [qtp804611486-18] [org.xdi.oxauth.service.AuthenticationService] (AuthenticationService.java:515) - ConfigureSessionClient: username: '@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F', credentials: '1136399818' 2019-11-08 23:05:29,428 TRACE [qtp804611486-18] [org.xdi.oxauth.service.ClientService] (ClientService.java:199) - Get client from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F,ou=clients,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:29,429 DEBUG [qtp804611486-18] [org.xdi.oxauth.service.ClientService] (ClientService.java:141) - Found 1 entries for client id = @!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F 2019-11-08 23:05:29,437 INFO [qtp804611486-18] [org.xdi.oxauth.auth.Authenticator] (Authenticator.java:262) - Authentication success for Client: '@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F' 2019-11-08 23:05:29,438 TRACE [qtp804611486-18] [org.xdi.oxauth.auth.Authenticator] (Authenticator.java:205) - Authentication successfully for '@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F' 2019-11-08 23:05:29,439 DEBUG [qtp804611486-18] [xdi.oxauth.token.ws.rs.TokenRestWebServiceImpl] (TokenRestWebServiceImpl.java:107) - Attempting to request access token: grantType = refresh_token, code = null, redirectUri = null, username = null, refreshToken = e23a7274-6f5e-409d-b1a9-fdf14bdef2bd, clientId = null, ExtraParams = {refresh_token=[e23a7274-6f5e-409d-b1a9-fdf14bdef2bd], grant_type=[refresh_token]}, isSecure = true, codeVerifier = null, ticket = null 2019-11-08 23:05:29,440 DEBUG [qtp804611486-18] [xdi.oxauth.token.ws.rs.TokenRestWebServiceImpl] (TokenRestWebServiceImpl.java:129) - Starting to validate request parameters 2019-11-08 23:05:29,440 TRACE [qtp804611486-18] [xdi.oxauth.token.ws.rs.TokenRestWebServiceImpl] (TokenRestWebServiceImpl.java:135) - Request parameters are right 2019-11-08 23:05:29,440 DEBUG [qtp804611486-18] [xdi.oxauth.token.ws.rs.TokenRestWebServiceImpl] (TokenRestWebServiceImpl.java:137) - Grant type: 'refresh_token' 2019-11-08 23:05:29,440 DEBUG [qtp804611486-18] [xdi.oxauth.token.ws.rs.TokenRestWebServiceImpl] (TokenRestWebServiceImpl.java:143) - Get sessionClient: 'org.xdi.oxauth.model.session.SessionClient@347e388f' 2019-11-08 23:05:29,441 DEBUG [qtp804611486-18] [xdi.oxauth.token.ws.rs.TokenRestWebServiceImpl] (TokenRestWebServiceImpl.java:147) - Get client from session: '@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F' 2019-11-08 23:05:29,441 TRACE [qtp804611486-18] [xdi.oxauth.model.crypto.binding.TokenBindingMessage] (TokenBindingMessage.java:57) - TokenBindingMessage present: false, rpCnfKey: null 2019-11-08 23:05:29,442 DEBUG [qtp804611486-18] [org.xdi.oxauth.model.error.ErrorResponseFactory] (ErrorResponseFactory.java:70) - Looking for the error with id: invalid_grant 2019-11-08 23:05:29,443 DEBUG [qtp804611486-18] [org.xdi.oxauth.model.error.ErrorResponseFactory] (ErrorResponseFactory.java:75) - Found error, id: invalid_grant 2019-11-08 23:05:29,640 DEBUG [qtp804611486-10] [org.xdi.oxauth.service.ClientService] (ClientService.java:141) - Found 1 entries for client id = @!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F 2019-11-08 23:05:29,640 TRACE [qtp804611486-10] [org.xdi.oxauth.auth.AuthenticationFilter] (AuthenticationFilter.java:91) - Get request to: 'https://gauth.sqd.solidfire.net/oxauth/restv1/token' 2019-11-08 23:05:29,640 DEBUG [qtp804611486-10] [org.xdi.oxauth.auth.AuthenticationFilter] (AuthenticationFilter.java:98) - Starting token endpoint authentication 2019-11-08 23:05:29,641 DEBUG [qtp804611486-10] [org.xdi.oxauth.auth.AuthenticationFilter] (AuthenticationFilter.java:114) - Starting POST Auth token endpoint authentication 2019-11-08 23:05:29,641 TRACE [qtp804611486-10] [org.xdi.oxauth.auth.AuthenticationFilter] (AuthenticationFilter.java:292) - isExistUserPassword: true 2019-11-08 23:05:29,642 DEBUG [qtp804611486-10] [org.xdi.oxauth.auth.AuthenticationFilter] (AuthenticationFilter.java:296) - requireAuth: 'true' 2019-11-08 23:05:29,642 TRACE [qtp804611486-10] [org.xdi.oxauth.service.ClientService] (ClientService.java:199) - Get client from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F,ou=clients,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:29,642 DEBUG [qtp804611486-10] [org.xdi.oxauth.service.ClientService] (ClientService.java:141) - Found 1 entries for client id = @!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F 2019-11-08 23:05:29,642 DEBUG [qtp804611486-10] [org.xdi.oxauth.model.error.ErrorResponseFactory] (ErrorResponseFactory.java:70) - Looking for the error with id: invalid_client 2019-11-08 23:05:29,643 DEBUG [qtp804611486-10] [org.xdi.oxauth.model.error.ErrorResponseFactory] (ErrorResponseFactory.java:75) - Found error, id: invalid_client 2019-11-08 23:05:29,881 TRACE [qtp804611486-18] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!B17A,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:29,881 TRACE [qtp804611486-18] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!0C18,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:29,882 TRACE [qtp804611486-18] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!6DA6,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:29,882 TRACE [qtp804611486-18] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!8F88,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:29,883 TRACE [qtp804611486-18] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!CAE3,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:29,885 TRACE [qtp804611486-18] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!D2E0,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:29,886 TRACE [qtp804611486-18] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!42E0,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:29,886 TRACE [qtp804611486-18] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!CAE3,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:29,887 TRACE [qtp804611486-18] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!2B29,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:29,887 TRACE [qtp804611486-18] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!0C85,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:29,887 TRACE [qtp804611486-18] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!B4B0,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:29,888 TRACE [qtp804611486-18] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!A0E8,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:29,888 TRACE [qtp804611486-18] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!5EC6,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:29,889 TRACE [qtp804611486-18] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!B52A,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:29,889 TRACE [qtp804611486-18] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!64A0,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:29,889 TRACE [qtp804611486-18] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!EC3A,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:29,890 TRACE [qtp804611486-18] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!3B47,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:29,890 TRACE [qtp804611486-18] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!3692,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:29,890 TRACE [qtp804611486-18] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!98FC,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:29,891 TRACE [qtp804611486-18] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!A901,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:29,891 TRACE [qtp804611486-18] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!36D9,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:29,891 TRACE [qtp804611486-18] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!BE64,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:29,892 TRACE [qtp804611486-18] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!6493,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:29,892 TRACE [qtp804611486-18] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!CAE3,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:29,893 TRACE [qtp804611486-18] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!D2E0,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:29,893 TRACE [qtp804611486-18] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!27DB,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:29,894 TRACE [qtp804611486-18] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!2A3D,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:29,894 TRACE [qtp804611486-18] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!6609,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:29,895 TRACE [qtp804611486-18] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!6EEB,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:29,895 TRACE [qtp804611486-18] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!BCE8,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:29,896 TRACE [qtp804611486-18] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!D90B,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:29,897 TRACE [qtp804611486-18] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!E6B8,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:29,897 TRACE [qtp804611486-18] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!E999,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:29,898 TRACE [qtp804611486-18] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!2B29,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:29,898 TRACE [qtp804611486-18] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!29DA,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:30,096 TRACE [qtp804611486-10] [org.xdi.oxauth.service.ClientService] (ClientService.java:199) - Get client from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F,ou=clients,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:30,097 DEBUG [qtp804611486-10] [org.xdi.oxauth.service.ClientService] (ClientService.java:141) - Found 1 entries for client id = @!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F 2019-11-08 23:05:30,097 TRACE [qtp804611486-10] [org.xdi.oxauth.auth.AuthenticationFilter] (AuthenticationFilter.java:91) - Get request to: 'https://gauth.sqd.solidfire.net/oxauth/restv1/token' 2019-11-08 23:05:30,098 DEBUG [qtp804611486-10] [org.xdi.oxauth.auth.AuthenticationFilter] (AuthenticationFilter.java:98) - Starting token endpoint authentication 2019-11-08 23:05:30,098 DEBUG [qtp804611486-10] [org.xdi.oxauth.auth.AuthenticationFilter] (AuthenticationFilter.java:111) - Starting Basic Auth token endpoint authentication 2019-11-08 23:05:30,099 TRACE [qtp804611486-10] [org.xdi.oxauth.service.ClientService] (ClientService.java:199) - Get client from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F,ou=clients,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:30,100 DEBUG [qtp804611486-10] [org.xdi.oxauth.service.ClientService] (ClientService.java:141) - Found 1 entries for client id = @!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F 2019-11-08 23:05:30,101 TRACE [qtp804611486-10] [org.xdi.oxauth.auth.Authenticator] (Authenticator.java:181) - Authenticating ... (interactive: false, skipPassword: false, credentials.username: @!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F) 2019-11-08 23:05:30,101 DEBUG [qtp804611486-10] [org.xdi.oxauth.service.ClientService] (ClientService.java:101) - Authenticating Client with LDAP: clientId = @!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F 2019-11-08 23:05:30,102 TRACE [qtp804611486-10] [org.xdi.oxauth.service.ClientService] (ClientService.java:199) - Get client from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F,ou=clients,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:30,102 DEBUG [qtp804611486-10] [org.xdi.oxauth.service.ClientService] (ClientService.java:141) - Found 1 entries for client id = @!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F 2019-11-08 23:05:30,103 DEBUG [qtp804611486-10] [org.xdi.oxauth.service.AuthenticationService] (AuthenticationService.java:515) - ConfigureSessionClient: username: '@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F', credentials: '1136399818' 2019-11-08 23:05:30,103 TRACE [qtp804611486-10] [org.xdi.oxauth.service.ClientService] (ClientService.java:199) - Get client from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F,ou=clients,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:30,104 DEBUG [qtp804611486-10] [org.xdi.oxauth.service.ClientService] (ClientService.java:141) - Found 1 entries for client id = @!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F 2019-11-08 23:05:30,125 INFO [qtp804611486-10] [org.xdi.oxauth.auth.Authenticator] (Authenticator.java:262) - Authentication success for Client: '@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F' 2019-11-08 23:05:30,126 TRACE [qtp804611486-10] [org.xdi.oxauth.auth.Authenticator] (Authenticator.java:205) - Authentication successfully for '@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F' 2019-11-08 23:05:30,129 DEBUG [qtp804611486-10] [xdi.oxauth.token.ws.rs.TokenRestWebServiceImpl] (TokenRestWebServiceImpl.java:107) - Attempting to request access token: grantType = refresh_token, code = null, redirectUri = null, username = null, refreshToken = e23a7274-6f5e-409d-b1a9-fdf14bdef2bd, clientId = null, ExtraParams = {refresh_token=[e23a7274-6f5e-409d-b1a9-fdf14bdef2bd], grant_type=[refresh_token]}, isSecure = true, codeVerifier = null, ticket = null 2019-11-08 23:05:30,130 DEBUG [qtp804611486-10] [xdi.oxauth.token.ws.rs.TokenRestWebServiceImpl] (TokenRestWebServiceImpl.java:129) - Starting to validate request parameters 2019-11-08 23:05:30,131 TRACE [qtp804611486-10] [xdi.oxauth.token.ws.rs.TokenRestWebServiceImpl] (TokenRestWebServiceImpl.java:135) - Request parameters are right 2019-11-08 23:05:30,131 DEBUG [qtp804611486-10] [xdi.oxauth.token.ws.rs.TokenRestWebServiceImpl] (TokenRestWebServiceImpl.java:137) - Grant type: 'refresh_token' 2019-11-08 23:05:30,132 DEBUG [qtp804611486-10] [xdi.oxauth.token.ws.rs.TokenRestWebServiceImpl] (TokenRestWebServiceImpl.java:143) - Get sessionClient: 'org.xdi.oxauth.model.session.SessionClient@64eab512' 2019-11-08 23:05:30,132 DEBUG [qtp804611486-10] [xdi.oxauth.token.ws.rs.TokenRestWebServiceImpl] (TokenRestWebServiceImpl.java:147) - Get client from session: '@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F' 2019-11-08 23:05:30,133 TRACE [qtp804611486-10] [xdi.oxauth.model.crypto.binding.TokenBindingMessage] (TokenBindingMessage.java:57) - TokenBindingMessage present: false, rpCnfKey: null 2019-11-08 23:05:30,136 DEBUG [qtp804611486-10] [org.xdi.oxauth.model.error.ErrorResponseFactory] (ErrorResponseFactory.java:70) - Looking for the error with id: invalid_grant 2019-11-08 23:05:30,137 DEBUG [qtp804611486-10] [org.xdi.oxauth.model.error.ErrorResponseFactory] (ErrorResponseFactory.java:75) - Found error, id: invalid_grant 2019-11-08 23:05:30,343 DEBUG [qtp804611486-18] [org.xdi.oxauth.service.ClientService] (ClientService.java:141) - Found 1 entries for client id = @!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F 2019-11-08 23:05:30,343 TRACE [qtp804611486-18] [org.xdi.oxauth.auth.AuthenticationFilter] (AuthenticationFilter.java:91) - Get request to: 'https://gauth.sqd.solidfire.net/oxauth/restv1/token' 2019-11-08 23:05:30,343 DEBUG [qtp804611486-18] [org.xdi.oxauth.auth.AuthenticationFilter] (AuthenticationFilter.java:98) - Starting token endpoint authentication 2019-11-08 23:05:30,343 DEBUG [qtp804611486-18] [org.xdi.oxauth.auth.AuthenticationFilter] (AuthenticationFilter.java:114) - Starting POST Auth token endpoint authentication 2019-11-08 23:05:30,343 TRACE [qtp804611486-18] [org.xdi.oxauth.auth.AuthenticationFilter] (AuthenticationFilter.java:292) - isExistUserPassword: true 2019-11-08 23:05:30,344 DEBUG [qtp804611486-18] [org.xdi.oxauth.auth.AuthenticationFilter] (AuthenticationFilter.java:296) - requireAuth: 'true' 2019-11-08 23:05:30,344 TRACE [qtp804611486-18] [org.xdi.oxauth.service.ClientService] (ClientService.java:199) - Get client from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F,ou=clients,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:30,344 DEBUG [qtp804611486-18] [org.xdi.oxauth.service.ClientService] (ClientService.java:141) - Found 1 entries for client id = @!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F 2019-11-08 23:05:30,344 DEBUG [qtp804611486-18] [org.xdi.oxauth.model.error.ErrorResponseFactory] (ErrorResponseFactory.java:70) - Looking for the error with id: invalid_client 2019-11-08 23:05:30,345 DEBUG [qtp804611486-18] [org.xdi.oxauth.model.error.ErrorResponseFactory] (ErrorResponseFactory.java:75) - Found error, id: invalid_client 2019-11-08 23:05:30,558 TRACE [qtp804611486-10] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!B17A,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:30,558 TRACE [qtp804611486-10] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!0C18,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:30,558 TRACE [qtp804611486-10] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!6DA6,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:30,558 TRACE [qtp804611486-10] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!8F88,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:30,559 TRACE [qtp804611486-10] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!CAE3,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:30,560 TRACE [qtp804611486-10] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!D2E0,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:30,561 TRACE [qtp804611486-10] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!42E0,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:30,561 TRACE [qtp804611486-10] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!CAE3,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:30,561 TRACE [qtp804611486-10] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!2B29,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:30,562 TRACE [qtp804611486-10] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!0C85,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:30,562 TRACE [qtp804611486-10] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!B4B0,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:30,562 TRACE [qtp804611486-10] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!A0E8,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:30,562 TRACE [qtp804611486-10] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!5EC6,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:30,562 TRACE [qtp804611486-10] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!B52A,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:30,563 TRACE [qtp804611486-10] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!64A0,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:30,563 TRACE [qtp804611486-10] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!EC3A,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:30,563 TRACE [qtp804611486-10] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!3B47,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:30,563 TRACE [qtp804611486-10] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!3692,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:30,564 TRACE [qtp804611486-10] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!98FC,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:30,564 TRACE [qtp804611486-10] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!A901,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:30,564 TRACE [qtp804611486-10] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!36D9,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:30,564 TRACE [qtp804611486-10] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!BE64,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:30,565 TRACE [qtp804611486-10] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!6493,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:30,565 TRACE [qtp804611486-10] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!CAE3,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:30,565 TRACE [qtp804611486-10] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!D2E0,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:30,565 TRACE [qtp804611486-10] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!27DB,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:30,566 TRACE [qtp804611486-10] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!2A3D,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:30,566 TRACE [qtp804611486-10] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!6609,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:30,566 TRACE [qtp804611486-10] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!6EEB,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:30,567 TRACE [qtp804611486-10] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!BCE8,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:30,567 TRACE [qtp804611486-10] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!D90B,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:30,567 TRACE [qtp804611486-10] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!E6B8,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:30,567 TRACE [qtp804611486-10] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!E999,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:30,568 TRACE [qtp804611486-10] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!2B29,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:30,568 TRACE [qtp804611486-10] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!29DA,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:30,761 TRACE [qtp804611486-18] [org.xdi.oxauth.service.ClientService] (ClientService.java:199) - Get client from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F,ou=clients,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:30,762 DEBUG [qtp804611486-18] [org.xdi.oxauth.service.ClientService] (ClientService.java:141) - Found 1 entries for client id = @!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F 2019-11-08 23:05:30,762 TRACE [qtp804611486-18] [org.xdi.oxauth.auth.AuthenticationFilter] (AuthenticationFilter.java:91) - Get request to: 'https://gauth.sqd.solidfire.net/oxauth/restv1/token' 2019-11-08 23:05:30,762 DEBUG [qtp804611486-18] [org.xdi.oxauth.auth.AuthenticationFilter] (AuthenticationFilter.java:98) - Starting token endpoint authentication 2019-11-08 23:05:30,762 DEBUG [qtp804611486-18] [org.xdi.oxauth.auth.AuthenticationFilter] (AuthenticationFilter.java:111) - Starting Basic Auth token endpoint authentication 2019-11-08 23:05:30,763 TRACE [qtp804611486-18] [org.xdi.oxauth.service.ClientService] (ClientService.java:199) - Get client from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F,ou=clients,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:30,763 DEBUG [qtp804611486-18] [org.xdi.oxauth.service.ClientService] (ClientService.java:141) - Found 1 entries for client id = @!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F 2019-11-08 23:05:30,763 TRACE [qtp804611486-18] [org.xdi.oxauth.auth.Authenticator] (Authenticator.java:181) - Authenticating ... (interactive: false, skipPassword: false, credentials.username: @!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F) 2019-11-08 23:05:30,763 DEBUG [qtp804611486-18] [org.xdi.oxauth.service.ClientService] (ClientService.java:101) - Authenticating Client with LDAP: clientId = @!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F 2019-11-08 23:05:30,763 TRACE [qtp804611486-18] [org.xdi.oxauth.service.ClientService] (ClientService.java:199) - Get client from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F,ou=clients,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:30,764 DEBUG [qtp804611486-18] [org.xdi.oxauth.service.ClientService] (ClientService.java:141) - Found 1 entries for client id = @!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F 2019-11-08 23:05:30,764 DEBUG [qtp804611486-18] [org.xdi.oxauth.service.AuthenticationService] (AuthenticationService.java:515) - ConfigureSessionClient: username: '@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F', credentials: '1136399818' 2019-11-08 23:05:30,764 TRACE [qtp804611486-18] [org.xdi.oxauth.service.ClientService] (ClientService.java:199) - Get client from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F,ou=clients,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:30,764 DEBUG [qtp804611486-18] [org.xdi.oxauth.service.ClientService] (ClientService.java:141) - Found 1 entries for client id = @!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F 2019-11-08 23:05:30,773 INFO [qtp804611486-18] [org.xdi.oxauth.auth.Authenticator] (Authenticator.java:262) - Authentication success for Client: '@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F' 2019-11-08 23:05:30,773 TRACE [qtp804611486-18] [org.xdi.oxauth.auth.Authenticator] (Authenticator.java:205) - Authentication successfully for '@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F' 2019-11-08 23:05:30,776 DEBUG [qtp804611486-18] [xdi.oxauth.token.ws.rs.TokenRestWebServiceImpl] (TokenRestWebServiceImpl.java:107) - Attempting to request access token: grantType = refresh_token, code = null, redirectUri = null, username = null, refreshToken = e23a7274-6f5e-409d-b1a9-fdf14bdef2bd, clientId = null, ExtraParams = {refresh_token=[e23a7274-6f5e-409d-b1a9-fdf14bdef2bd], grant_type=[refresh_token]}, isSecure = true, codeVerifier = null, ticket = null 2019-11-08 23:05:30,776 DEBUG [qtp804611486-18] [xdi.oxauth.token.ws.rs.TokenRestWebServiceImpl] (TokenRestWebServiceImpl.java:129) - Starting to validate request parameters 2019-11-08 23:05:30,776 TRACE [qtp804611486-18] [xdi.oxauth.token.ws.rs.TokenRestWebServiceImpl] (TokenRestWebServiceImpl.java:135) - Request parameters are right 2019-11-08 23:05:30,776 DEBUG [qtp804611486-18] [xdi.oxauth.token.ws.rs.TokenRestWebServiceImpl] (TokenRestWebServiceImpl.java:137) - Grant type: 'refresh_token' 2019-11-08 23:05:30,776 DEBUG [qtp804611486-18] [xdi.oxauth.token.ws.rs.TokenRestWebServiceImpl] (TokenRestWebServiceImpl.java:143) - Get sessionClient: 'org.xdi.oxauth.model.session.SessionClient@1153fc70' 2019-11-08 23:05:30,777 DEBUG [qtp804611486-18] [xdi.oxauth.token.ws.rs.TokenRestWebServiceImpl] (TokenRestWebServiceImpl.java:147) - Get client from session: '@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F' 2019-11-08 23:05:30,777 TRACE [qtp804611486-18] [xdi.oxauth.model.crypto.binding.TokenBindingMessage] (TokenBindingMessage.java:57) - TokenBindingMessage present: false, rpCnfKey: null 2019-11-08 23:05:30,778 DEBUG [qtp804611486-18] [org.xdi.oxauth.model.error.ErrorResponseFactory] (ErrorResponseFactory.java:70) - Looking for the error with id: invalid_grant 2019-11-08 23:05:30,778 DEBUG [qtp804611486-18] [org.xdi.oxauth.model.error.ErrorResponseFactory] (ErrorResponseFactory.java:75) - Found error, id: invalid_grant 2019-11-08 23:05:30,979 DEBUG [qtp804611486-10] [org.xdi.oxauth.service.ClientService] (ClientService.java:141) - Found 1 entries for client id = @!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F 2019-11-08 23:05:30,980 TRACE [qtp804611486-10] [org.xdi.oxauth.auth.AuthenticationFilter] (AuthenticationFilter.java:91) - Get request to: 'https://gauth.sqd.solidfire.net/oxauth/restv1/token' 2019-11-08 23:05:30,980 DEBUG [qtp804611486-10] [org.xdi.oxauth.auth.AuthenticationFilter] (AuthenticationFilter.java:98) - Starting token endpoint authentication 2019-11-08 23:05:30,980 DEBUG [qtp804611486-10] [org.xdi.oxauth.auth.AuthenticationFilter] (AuthenticationFilter.java:114) - Starting POST Auth token endpoint authentication 2019-11-08 23:05:30,980 TRACE [qtp804611486-10] [org.xdi.oxauth.auth.AuthenticationFilter] (AuthenticationFilter.java:292) - isExistUserPassword: true 2019-11-08 23:05:30,981 DEBUG [qtp804611486-10] [org.xdi.oxauth.auth.AuthenticationFilter] (AuthenticationFilter.java:296) - requireAuth: 'true' 2019-11-08 23:05:30,981 TRACE [qtp804611486-10] [org.xdi.oxauth.service.ClientService] (ClientService.java:199) - Get client from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F,ou=clients,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:30,981 DEBUG [qtp804611486-10] [org.xdi.oxauth.service.ClientService] (ClientService.java:141) - Found 1 entries for client id = @!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F 2019-11-08 23:05:30,982 DEBUG [qtp804611486-10] [org.xdi.oxauth.model.error.ErrorResponseFactory] (ErrorResponseFactory.java:70) - Looking for the error with id: invalid_client 2019-11-08 23:05:30,982 DEBUG [qtp804611486-10] [org.xdi.oxauth.model.error.ErrorResponseFactory] (ErrorResponseFactory.java:75) - Found error, id: invalid_client 2019-11-08 23:05:31,188 TRACE [qtp804611486-18] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!B17A,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:31,188 TRACE [qtp804611486-18] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!0C18,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:31,188 TRACE [qtp804611486-18] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!6DA6,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:31,189 TRACE [qtp804611486-18] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!8F88,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:31,189 TRACE [qtp804611486-18] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!CAE3,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:31,190 TRACE [qtp804611486-18] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!D2E0,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:31,191 TRACE [qtp804611486-18] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!42E0,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:31,191 TRACE [qtp804611486-18] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!CAE3,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:31,191 TRACE [qtp804611486-18] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!2B29,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:31,191 TRACE [qtp804611486-18] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!0C85,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:31,192 TRACE [qtp804611486-18] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!B4B0,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:31,192 TRACE [qtp804611486-18] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!A0E8,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:31,192 TRACE [qtp804611486-18] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!5EC6,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:31,192 TRACE [qtp804611486-18] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!B52A,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:31,193 TRACE [qtp804611486-18] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!64A0,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:31,193 TRACE [qtp804611486-18] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!EC3A,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:31,193 TRACE [qtp804611486-18] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!3B47,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:31,193 TRACE [qtp804611486-18] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!3692,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:31,194 TRACE [qtp804611486-18] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!98FC,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:31,194 TRACE [qtp804611486-18] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!A901,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:31,194 TRACE [qtp804611486-18] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!36D9,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:31,194 TRACE [qtp804611486-18] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!BE64,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:31,195 TRACE [qtp804611486-18] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!6493,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:31,195 TRACE [qtp804611486-18] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!CAE3,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:31,195 TRACE [qtp804611486-18] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!D2E0,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:31,195 TRACE [qtp804611486-18] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!27DB,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:31,196 TRACE [qtp804611486-18] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!2A3D,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:31,196 TRACE [qtp804611486-18] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!6609,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:31,196 TRACE [qtp804611486-18] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!6EEB,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:31,196 TRACE [qtp804611486-18] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!BCE8,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:31,197 TRACE [qtp804611486-18] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!D90B,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:31,197 TRACE [qtp804611486-18] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!E6B8,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:31,197 TRACE [qtp804611486-18] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!E999,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:31,197 TRACE [qtp804611486-18] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!2B29,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:31,198 TRACE [qtp804611486-18] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!29DA,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:31,391 TRACE [qtp804611486-18] [org.xdi.oxauth.service.ClientService] (ClientService.java:199) - Get client from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F,ou=clients,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:31,391 DEBUG [qtp804611486-18] [org.xdi.oxauth.service.ClientService] (ClientService.java:141) - Found 1 entries for client id = @!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F 2019-11-08 23:05:31,391 TRACE [qtp804611486-18] [org.xdi.oxauth.auth.AuthenticationFilter] (AuthenticationFilter.java:91) - Get request to: 'https://gauth.sqd.solidfire.net/oxauth/restv1/token' 2019-11-08 23:05:31,392 DEBUG [qtp804611486-18] [org.xdi.oxauth.auth.AuthenticationFilter] (AuthenticationFilter.java:98) - Starting token endpoint authentication 2019-11-08 23:05:31,392 DEBUG [qtp804611486-18] [org.xdi.oxauth.auth.AuthenticationFilter] (AuthenticationFilter.java:111) - Starting Basic Auth token endpoint authentication 2019-11-08 23:05:31,392 TRACE [qtp804611486-18] [org.xdi.oxauth.service.ClientService] (ClientService.java:199) - Get client from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F,ou=clients,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:31,393 DEBUG [qtp804611486-18] [org.xdi.oxauth.service.ClientService] (ClientService.java:141) - Found 1 entries for client id = @!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F 2019-11-08 23:05:31,393 TRACE [qtp804611486-18] [org.xdi.oxauth.auth.Authenticator] (Authenticator.java:181) - Authenticating ... (interactive: false, skipPassword: false, credentials.username: @!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F) 2019-11-08 23:05:31,393 DEBUG [qtp804611486-18] [org.xdi.oxauth.service.ClientService] (ClientService.java:101) - Authenticating Client with LDAP: clientId = @!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F 2019-11-08 23:05:31,394 TRACE [qtp804611486-18] [org.xdi.oxauth.service.ClientService] (ClientService.java:199) - Get client from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F,ou=clients,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:31,394 DEBUG [qtp804611486-18] [org.xdi.oxauth.service.ClientService] (ClientService.java:141) - Found 1 entries for client id = @!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F 2019-11-08 23:05:31,394 DEBUG [qtp804611486-18] [org.xdi.oxauth.service.AuthenticationService] (AuthenticationService.java:515) - ConfigureSessionClient: username: '@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F', credentials: '1136399818' 2019-11-08 23:05:31,394 TRACE [qtp804611486-18] [org.xdi.oxauth.service.ClientService] (ClientService.java:199) - Get client from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F,ou=clients,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:31,395 DEBUG [qtp804611486-18] [org.xdi.oxauth.service.ClientService] (ClientService.java:141) - Found 1 entries for client id = @!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F 2019-11-08 23:05:31,404 INFO [qtp804611486-18] [org.xdi.oxauth.auth.Authenticator] (Authenticator.java:262) - Authentication success for Client: '@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F' 2019-11-08 23:05:31,404 TRACE [qtp804611486-18] [org.xdi.oxauth.auth.Authenticator] (Authenticator.java:205) - Authentication successfully for '@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F' 2019-11-08 23:05:31,405 DEBUG [qtp804611486-18] [xdi.oxauth.token.ws.rs.TokenRestWebServiceImpl] (TokenRestWebServiceImpl.java:107) - Attempting to request access token: grantType = refresh_token, code = null, redirectUri = null, username = null, refreshToken = e23a7274-6f5e-409d-b1a9-fdf14bdef2bd, clientId = null, ExtraParams = {refresh_token=[e23a7274-6f5e-409d-b1a9-fdf14bdef2bd], grant_type=[refresh_token]}, isSecure = true, codeVerifier = null, ticket = null 2019-11-08 23:05:31,406 DEBUG [qtp804611486-18] [xdi.oxauth.token.ws.rs.TokenRestWebServiceImpl] (TokenRestWebServiceImpl.java:129) - Starting to validate request parameters 2019-11-08 23:05:31,406 TRACE [qtp804611486-18] [xdi.oxauth.token.ws.rs.TokenRestWebServiceImpl] (TokenRestWebServiceImpl.java:135) - Request parameters are right 2019-11-08 23:05:31,406 DEBUG [qtp804611486-18] [xdi.oxauth.token.ws.rs.TokenRestWebServiceImpl] (TokenRestWebServiceImpl.java:137) - Grant type: 'refresh_token' 2019-11-08 23:05:31,406 DEBUG [qtp804611486-18] [xdi.oxauth.token.ws.rs.TokenRestWebServiceImpl] (TokenRestWebServiceImpl.java:143) - Get sessionClient: 'org.xdi.oxauth.model.session.SessionClient@7a8a7c52' 2019-11-08 23:05:31,407 DEBUG [qtp804611486-18] [xdi.oxauth.token.ws.rs.TokenRestWebServiceImpl] (TokenRestWebServiceImpl.java:147) - Get client from session: '@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F' 2019-11-08 23:05:31,407 TRACE [qtp804611486-18] [xdi.oxauth.model.crypto.binding.TokenBindingMessage] (TokenBindingMessage.java:57) - TokenBindingMessage present: false, rpCnfKey: null 2019-11-08 23:05:31,408 DEBUG [qtp804611486-18] [org.xdi.oxauth.model.error.ErrorResponseFactory] (ErrorResponseFactory.java:70) - Looking for the error with id: invalid_grant 2019-11-08 23:05:31,408 DEBUG [qtp804611486-18] [org.xdi.oxauth.model.error.ErrorResponseFactory] (ErrorResponseFactory.java:75) - Found error, id: invalid_grant 2019-11-08 23:05:31,605 DEBUG [qtp804611486-18] [org.xdi.oxauth.service.ClientService] (ClientService.java:141) - Found 1 entries for client id = @!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F 2019-11-08 23:05:31,605 TRACE [qtp804611486-18] [org.xdi.oxauth.auth.AuthenticationFilter] (AuthenticationFilter.java:91) - Get request to: 'https://gauth.sqd.solidfire.net/oxauth/restv1/token' 2019-11-08 23:05:31,605 DEBUG [qtp804611486-18] [org.xdi.oxauth.auth.AuthenticationFilter] (AuthenticationFilter.java:98) - Starting token endpoint authentication 2019-11-08 23:05:31,605 DEBUG [qtp804611486-18] [org.xdi.oxauth.auth.AuthenticationFilter] (AuthenticationFilter.java:114) - Starting POST Auth token endpoint authentication 2019-11-08 23:05:31,605 TRACE [qtp804611486-18] [org.xdi.oxauth.auth.AuthenticationFilter] (AuthenticationFilter.java:292) - isExistUserPassword: true 2019-11-08 23:05:31,606 DEBUG [qtp804611486-18] [org.xdi.oxauth.auth.AuthenticationFilter] (AuthenticationFilter.java:296) - requireAuth: 'true' 2019-11-08 23:05:31,606 TRACE [qtp804611486-18] [org.xdi.oxauth.service.ClientService] (ClientService.java:199) - Get client from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F,ou=clients,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:31,606 DEBUG [qtp804611486-18] [org.xdi.oxauth.service.ClientService] (ClientService.java:141) - Found 1 entries for client id = @!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F 2019-11-08 23:05:31,606 DEBUG [qtp804611486-18] [org.xdi.oxauth.model.error.ErrorResponseFactory] (ErrorResponseFactory.java:70) - Looking for the error with id: invalid_client 2019-11-08 23:05:31,607 DEBUG [qtp804611486-18] [org.xdi.oxauth.model.error.ErrorResponseFactory] (ErrorResponseFactory.java:75) - Found error, id: invalid_client 2019-11-08 23:05:31,813 TRACE [qtp804611486-10] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!B17A,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:31,813 TRACE [qtp804611486-10] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!0C18,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:31,813 TRACE [qtp804611486-10] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!6DA6,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:31,813 TRACE [qtp804611486-10] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!8F88,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:31,813 TRACE [qtp804611486-10] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!CAE3,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:31,815 TRACE [qtp804611486-10] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!D2E0,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:31,815 TRACE [qtp804611486-10] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!42E0,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:31,815 TRACE [qtp804611486-10] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!CAE3,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:31,815 TRACE [qtp804611486-10] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!2B29,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:31,815 TRACE [qtp804611486-10] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!0C85,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:31,815 TRACE [qtp804611486-10] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!B4B0,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:31,816 TRACE [qtp804611486-10] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!A0E8,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:31,816 TRACE [qtp804611486-10] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!5EC6,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:31,816 TRACE [qtp804611486-10] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!B52A,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:31,816 TRACE [qtp804611486-10] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!64A0,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:31,816 TRACE [qtp804611486-10] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!EC3A,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:31,816 TRACE [qtp804611486-10] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!3B47,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:31,817 TRACE [qtp804611486-10] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!3692,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:31,817 TRACE [qtp804611486-10] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!98FC,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:31,817 TRACE [qtp804611486-10] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!A901,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:31,817 TRACE [qtp804611486-10] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!36D9,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:31,817 TRACE [qtp804611486-10] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!BE64,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:31,818 TRACE [qtp804611486-10] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!6493,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:31,818 TRACE [qtp804611486-10] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!CAE3,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:31,818 TRACE [qtp804611486-10] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!D2E0,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:31,818 TRACE [qtp804611486-10] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!27DB,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:31,818 TRACE [qtp804611486-10] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!2A3D,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:31,818 TRACE [qtp804611486-10] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!6609,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:31,819 TRACE [qtp804611486-10] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!6EEB,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:31,819 TRACE [qtp804611486-10] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!BCE8,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:31,819 TRACE [qtp804611486-10] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!D90B,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:31,819 TRACE [qtp804611486-10] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!E6B8,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:31,819 TRACE [qtp804611486-10] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!E999,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:31,819 TRACE [qtp804611486-10] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!2B29,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:31,820 TRACE [qtp804611486-10] [org.xdi.oxauth.service.AttributeService] (AttributeService.java:53) - Get attribute from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0005!29DA,ou=attributes,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:32,013 TRACE [qtp804611486-18] [org.xdi.oxauth.service.ClientService] (ClientService.java:199) - Get client from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F,ou=clients,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:32,014 DEBUG [qtp804611486-18] [org.xdi.oxauth.service.ClientService] (ClientService.java:141) - Found 1 entries for client id = @!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F 2019-11-08 23:05:32,014 TRACE [qtp804611486-18] [org.xdi.oxauth.auth.AuthenticationFilter] (AuthenticationFilter.java:91) - Get request to: 'https://gauth.sqd.solidfire.net/oxauth/restv1/token' 2019-11-08 23:05:32,014 DEBUG [qtp804611486-18] [org.xdi.oxauth.auth.AuthenticationFilter] (AuthenticationFilter.java:98) - Starting token endpoint authentication 2019-11-08 23:05:32,015 DEBUG [qtp804611486-18] [org.xdi.oxauth.auth.AuthenticationFilter] (AuthenticationFilter.java:111) - Starting Basic Auth token endpoint authentication 2019-11-08 23:05:32,015 TRACE [qtp804611486-18] [org.xdi.oxauth.service.ClientService] (ClientService.java:199) - Get client from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F,ou=clients,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:32,016 DEBUG [qtp804611486-18] [org.xdi.oxauth.service.ClientService] (ClientService.java:141) - Found 1 entries for client id = @!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F 2019-11-08 23:05:32,016 TRACE [qtp804611486-18] [org.xdi.oxauth.auth.Authenticator] (Authenticator.java:181) - Authenticating ... (interactive: false, skipPassword: false, credentials.username: @!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F) 2019-11-08 23:05:32,016 DEBUG [qtp804611486-18] [org.xdi.oxauth.service.ClientService] (ClientService.java:101) - Authenticating Client with LDAP: clientId = @!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F 2019-11-08 23:05:32,017 TRACE [qtp804611486-18] [org.xdi.oxauth.service.ClientService] (ClientService.java:199) - Get client from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F,ou=clients,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:32,017 DEBUG [qtp804611486-18] [org.xdi.oxauth.service.ClientService] (ClientService.java:141) - Found 1 entries for client id = @!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F 2019-11-08 23:05:32,017 DEBUG [qtp804611486-18] [org.xdi.oxauth.service.AuthenticationService] (AuthenticationService.java:515) - ConfigureSessionClient: username: '@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F', credentials: '1136399818' 2019-11-08 23:05:32,018 TRACE [qtp804611486-18] [org.xdi.oxauth.service.ClientService] (ClientService.java:199) - Get client from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F,ou=clients,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:32,018 DEBUG [qtp804611486-18] [org.xdi.oxauth.service.ClientService] (ClientService.java:141) - Found 1 entries for client id = @!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F 2019-11-08 23:05:32,031 INFO [qtp804611486-18] [org.xdi.oxauth.auth.Authenticator] (Authenticator.java:262) - Authentication success for Client: '@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F' 2019-11-08 23:05:32,031 TRACE [qtp804611486-18] [org.xdi.oxauth.auth.Authenticator] (Authenticator.java:205) - Authentication successfully for '@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F' 2019-11-08 23:05:32,033 DEBUG [qtp804611486-18] [xdi.oxauth.token.ws.rs.TokenRestWebServiceImpl] (TokenRestWebServiceImpl.java:107) - Attempting to request access token: grantType = refresh_token, code = null, redirectUri = null, username = null, refreshToken = e23a7274-6f5e-409d-b1a9-fdf14bdef2bd, clientId = null, ExtraParams = {refresh_token=[e23a7274-6f5e-409d-b1a9-fdf14bdef2bd], grant_type=[refresh_token]}, isSecure = true, codeVerifier = null, ticket = null 2019-11-08 23:05:32,033 DEBUG [qtp804611486-18] [xdi.oxauth.token.ws.rs.TokenRestWebServiceImpl] (TokenRestWebServiceImpl.java:129) - Starting to validate request parameters 2019-11-08 23:05:32,033 TRACE [qtp804611486-18] [xdi.oxauth.token.ws.rs.TokenRestWebServiceImpl] (TokenRestWebServiceImpl.java:135) - Request parameters are right 2019-11-08 23:05:32,033 DEBUG [qtp804611486-18] [xdi.oxauth.token.ws.rs.TokenRestWebServiceImpl] (TokenRestWebServiceImpl.java:137) - Grant type: 'refresh_token' 2019-11-08 23:05:32,033 DEBUG [qtp804611486-18] [xdi.oxauth.token.ws.rs.TokenRestWebServiceImpl] (TokenRestWebServiceImpl.java:143) - Get sessionClient: 'org.xdi.oxauth.model.session.SessionClient@32f33eb1' 2019-11-08 23:05:32,034 DEBUG [qtp804611486-18] [xdi.oxauth.token.ws.rs.TokenRestWebServiceImpl] (TokenRestWebServiceImpl.java:147) - Get client from session: '@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F' 2019-11-08 23:05:32,034 TRACE [qtp804611486-18] [xdi.oxauth.model.crypto.binding.TokenBindingMessage] (TokenBindingMessage.java:57) - TokenBindingMessage present: false, rpCnfKey: null 2019-11-08 23:05:32,035 DEBUG [qtp804611486-18] [org.xdi.oxauth.model.error.ErrorResponseFactory] (ErrorResponseFactory.java:70) - Looking for the error with id: invalid_grant 2019-11-08 23:05:32,036 DEBUG [qtp804611486-18] [org.xdi.oxauth.model.error.ErrorResponseFactory] (ErrorResponseFactory.java:75) - Found error, id: invalid_grant 2019-11-08 23:05:32,235 DEBUG [qtp804611486-10] [org.xdi.oxauth.service.ClientService] (ClientService.java:141) - Found 1 entries for client id = @!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F 2019-11-08 23:05:32,235 TRACE [qtp804611486-10] [org.xdi.oxauth.auth.AuthenticationFilter] (AuthenticationFilter.java:91) - Get request to: 'https://gauth.sqd.solidfire.net/oxauth/restv1/token' 2019-11-08 23:05:32,235 DEBUG [qtp804611486-10] [org.xdi.oxauth.auth.AuthenticationFilter] (AuthenticationFilter.java:98) - Starting token endpoint authentication 2019-11-08 23:05:32,235 DEBUG [qtp804611486-10] [org.xdi.oxauth.auth.AuthenticationFilter] (AuthenticationFilter.java:114) - Starting POST Auth token endpoint authentication 2019-11-08 23:05:32,235 TRACE [qtp804611486-10] [org.xdi.oxauth.auth.AuthenticationFilter] (AuthenticationFilter.java:292) - isExistUserPassword: true 2019-11-08 23:05:32,236 DEBUG [qtp804611486-10] [org.xdi.oxauth.auth.AuthenticationFilter] (AuthenticationFilter.java:296) - requireAuth: 'true' 2019-11-08 23:05:32,236 TRACE [qtp804611486-10] [org.xdi.oxauth.service.ClientService] (ClientService.java:199) - Get client from cache by Dn 'inum=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F,ou=clients,o=@!CA45.0424.4E2F.B9E6!0001!F2CF.42AE,o=gluu' 2019-11-08 23:05:32,236 DEBUG [qtp804611486-10] [org.xdi.oxauth.service.ClientService] (ClientService.java:141) - Found 1 entries for client id = @!CA45.0424.4E2F.B9E6!0001!F2CF.42AE!0008!E4B5.CA2D.9108.5A4F 2019-11-08 23:05:32,236 DEBUG [qtp804611486-10] [org.xdi.oxauth.model.error.ErrorResponseFactory] (ErrorResponseFactory.java:70) - Looking for the error with id: invalid_client 2019-11-08 23:05:32,236 DEBUG [qtp804611486-10] [org.xdi.oxauth.model.error.ErrorResponseFactory] (ErrorResponseFactory.java:75) - Found error, id: invalid_client 2019-11-08 23:05:32,501 DEBUG [oxAuthScheduler_Worker-1] [org.xdi.service.timer.RequestJobListener] (RequestJobListener.java:52) - Bound request started 2019-11-08 23:05:32,502 DEBUG [oxAuthScheduler_Worker-1] [org.xdi.service.timer.TimerJob] (TimerJob.java:34) - Fire timer event [org.xdi.service.cdi.event.LoggerUpdateEvent] with qualifiers [@org.xdi.service.cdi.event.Scheduled()] 2019-11-08 23:05:32,502 DEBUG [oxAuthScheduler_Worker-1] [org.xdi.service.timer.RequestJobListener] (RequestJobListener.java:62) - Bound request ended ```