Skip to content

Instantly share code, notes, and snippets.

@fidenz-chim

fidenz-chim/oidc-provider-debug.log

Created April 4, 2020 05:58
Show Gist options
  • Save fidenz-chim/d97b8f3f9b9640f061710c75ee09e7a0 to your computer and use it in GitHub Desktop.
Save fidenz-chim/d97b8f3f9b9640f061710c75ee09e7a0 to your computer and use it in GitHub Desktop.
Download ZIP
Raw
oidc-provider-debug.log
ChimMBP:oidc_provide chim$ DEBUG=* node example/express.js
ioredis:redis status[localhost:6379]: [empty] -> connecting +0ms
express:application set "x-powered-by" to true +0ms
express:application set "etag" to 'weak' +1ms
express:application set "etag fn" to [Function: generateETag] +1ms
express:application set "env" to 'development' +0ms
express:application set "query parser" to 'extended' +1ms
express:application set "query parser fn" to [Function: parseExtendedQueryString] +0ms
express:application set "subdomain offset" to 2 +0ms
express:application set "trust proxy" to false +0ms
express:application set "trust proxy fn" to [Function: trustNone] +0ms
express:application booting in development mode +0ms
express:application set "view" to [Function: View] +0ms
express:application set "views" to '/Chim/Fidenz/Dev/siop/libs/oidc_provide/views' +1ms
express:application set "jsonp callback name" to 'callback' +0ms
express:router use '/' query +1ms
express:router:layer new '/' +0ms
express:router use '/' expressInit +0ms
express:router:layer new '/' +0ms
express:router use '/' helmet +1ms
express:router:layer new '/' +0ms
express:router use '/' corsMiddleware +0ms
express:router:layer new '/' +0ms
express:application set "views" to '/Chim/Fidenz/Dev/siop/libs/oidc_provide/example/views' +0ms
express:application set "view engine" to 'ejs' +0ms
koa:application use proxyWarning +0ms
koa:application use errorHandler +1ms
koa:application use - +0ms
koa:application use - +0ms
express:router use '/' <anonymous> +35ms
express:router:layer new '/' +0ms
express:router:route new '/interaction/:uid' +1ms
express:router:layer new '/interaction/:uid' +0ms
express:router:route get '/interaction/:uid' +0ms
express:router:layer new '/' +0ms
express:router:route get '/interaction/:uid' +0ms
express:router:layer new '/' +0ms
express:router:route new '/interaction/:uid/login' +0ms
express:router:layer new '/interaction/:uid/login' +1ms
express:router:route post '/interaction/:uid/login' +0ms
express:router:layer new '/' +0ms
express:router:route post '/interaction/:uid/login' +0ms
express:router:layer new '/' +0ms
express:router:route post '/interaction/:uid/login' +0ms
express:router:layer new '/' +0ms
express:router:route new '/interaction/:uid/continue' +0ms
express:router:layer new '/interaction/:uid/continue' +0ms
express:router:route post '/interaction/:uid/continue' +0ms
express:router:layer new '/' +0ms
express:router:route post '/interaction/:uid/continue' +0ms
express:router:layer new '/' +0ms
express:router:route post '/interaction/:uid/continue' +0ms
express:router:layer new '/' +0ms
express:router:route new '/interaction/:uid/confirm' +1ms
express:router:layer new '/interaction/:uid/confirm' +0ms
express:router:route post '/interaction/:uid/confirm' +0ms
express:router:layer new '/' +0ms
express:router:route post '/interaction/:uid/confirm' +0ms
express:router:layer new '/' +0ms
express:router:route post '/interaction/:uid/confirm' +0ms
express:router:layer new '/' +0ms
express:router:route new '/interaction/:uid/abort' +0ms
express:router:layer new '/interaction/:uid/abort' +0ms
express:router:route get '/interaction/:uid/abort' +1ms
express:router:layer new '/' +0ms
express:router:route get '/interaction/:uid/abort' +0ms
express:router:layer new '/' +0ms
express:router use '/' <anonymous> +0ms
express:router:layer new '/' +0ms
express:router use '/' handleRequest +0ms
express:router:layer new '/' +0ms
application is listening on port 3000, check its /.well-known/openid-configuration
ioredis:redis status[127.0.0.1:6379]: connecting -> connect +745ms
ioredis:redis write command[127.0.0.1:6379]: 0 -> info([]) +2ms
ioredis:redis status[127.0.0.1:6379]: connect -> ready +4ms
express:router dispatching GET /.well-known/oauth-authorization-server +7s
express:router query : /.well-known/oauth-authorization-server +3ms
express:router expressInit : /.well-known/oauth-authorization-server +0ms
express:router helmet : /.well-known/oauth-authorization-server +1ms
express:router corsMiddleware : /.well-known/oauth-authorization-server +2ms
express:router <anonymous> : /.well-known/oauth-authorization-server +0ms
express:router <anonymous> : /.well-known/oauth-authorization-server +3ms
express:router handleRequest : /.well-known/oauth-authorization-server +1ms
oidc-provider:error uid=undefined path=/.well-known/oauth-authorization-server method=GET error={ error: 'invalid_request', error_description: 'unrecognized route or not allowed method (GET on /.well-known/oauth-authorization-server)' } detail=undefined +0ms
express:router dispatching GET /.well-known/openid-configuration +16ms
express:router query : /.well-known/openid-configuration +0ms
express:router expressInit : /.well-known/openid-configuration +0ms
express:router helmet : /.well-known/openid-configuration +1ms
express:router corsMiddleware : /.well-known/openid-configuration +0ms
express:router <anonymous> : /.well-known/openid-configuration +0ms
express:router <anonymous> : /.well-known/openid-configuration +0ms
express:router handleRequest : /.well-known/openid-configuration +0ms
express:router dispatching OPTIONS /auth?client_id=EPXH7xeHBvve0RMF-ZbCV&scope=openid%20email%20profile&response_type=code&redirect_uri=https%3A%2F%2Flocalhost%3A5000%2Fcallback&resource=http%3A%2F%2Flocalhost%3A3000%2Fauth&code_challenge=MR09wBrOHIX_q-FU1wrXGXaszcKUavg6FUcIY0W5XhU&code_challenge_method=S256 +33ms
express:router query : /auth?client_id=EPXH7xeHBvve0RMF-ZbCV&scope=openid%20email%20profile&response_type=code&redirect_uri=https%3A%2F%2Flocalhost%3A5000%2Fcallback&resource=http%3A%2F%2Flocalhost%3A3000%2Fauth&code_challenge=MR09wBrOHIX_q-FU1wrXGXaszcKUavg6FUcIY0W5XhU&code_challenge_method=S256 +1ms
express:router expressInit : /auth?client_id=EPXH7xeHBvve0RMF-ZbCV&scope=openid%20email%20profile&response_type=code&redirect_uri=https%3A%2F%2Flocalhost%3A5000%2Fcallback&resource=http%3A%2F%2Flocalhost%3A3000%2Fauth&code_challenge=MR09wBrOHIX_q-FU1wrXGXaszcKUavg6FUcIY0W5XhU&code_challenge_method=S256 +1ms
express:router helmet : /auth?client_id=EPXH7xeHBvve0RMF-ZbCV&scope=openid%20email%20profile&response_type=code&redirect_uri=https%3A%2F%2Flocalhost%3A5000%2Fcallback&resource=http%3A%2F%2Flocalhost%3A3000%2Fauth&code_challenge=MR09wBrOHIX_q-FU1wrXGXaszcKUavg6FUcIY0W5XhU&code_challenge_method=S256 +0ms
express:router corsMiddleware : /auth?client_id=EPXH7xeHBvve0RMF-ZbCV&scope=openid%20email%20profile&response_type=code&redirect_uri=https%3A%2F%2Flocalhost%3A5000%2Fcallback&resource=http%3A%2F%2Flocalhost%3A3000%2Fauth&code_challenge=MR09wBrOHIX_q-FU1wrXGXaszcKUavg6FUcIY0W5XhU&code_challenge_method=S256 +1ms
express:router dispatching GET /auth?client_id=EPXH7xeHBvve0RMF-ZbCV&scope=openid%20email%20profile&response_type=code&redirect_uri=https%3A%2F%2Flocalhost%3A5000%2Fcallback&resource=http%3A%2F%2Flocalhost%3A3000%2Fauth&code_challenge=MR09wBrOHIX_q-FU1wrXGXaszcKUavg6FUcIY0W5XhU&code_challenge_method=S256 +8ms
express:router query : /auth?client_id=EPXH7xeHBvve0RMF-ZbCV&scope=openid%20email%20profile&response_type=code&redirect_uri=https%3A%2F%2Flocalhost%3A5000%2Fcallback&resource=http%3A%2F%2Flocalhost%3A3000%2Fauth&code_challenge=MR09wBrOHIX_q-FU1wrXGXaszcKUavg6FUcIY0W5XhU&code_challenge_method=S256 +0ms
express:router expressInit : /auth?client_id=EPXH7xeHBvve0RMF-ZbCV&scope=openid%20email%20profile&response_type=code&redirect_uri=https%3A%2F%2Flocalhost%3A5000%2Fcallback&resource=http%3A%2F%2Flocalhost%3A3000%2Fauth&code_challenge=MR09wBrOHIX_q-FU1wrXGXaszcKUavg6FUcIY0W5XhU&code_challenge_method=S256 +0ms
express:router helmet : /auth?client_id=EPXH7xeHBvve0RMF-ZbCV&scope=openid%20email%20profile&response_type=code&redirect_uri=https%3A%2F%2Flocalhost%3A5000%2Fcallback&resource=http%3A%2F%2Flocalhost%3A3000%2Fauth&code_challenge=MR09wBrOHIX_q-FU1wrXGXaszcKUavg6FUcIY0W5XhU&code_challenge_method=S256 +0ms
express:router corsMiddleware : /auth?client_id=EPXH7xeHBvve0RMF-ZbCV&scope=openid%20email%20profile&response_type=code&redirect_uri=https%3A%2F%2Flocalhost%3A5000%2Fcallback&resource=http%3A%2F%2Flocalhost%3A3000%2Fauth&code_challenge=MR09wBrOHIX_q-FU1wrXGXaszcKUavg6FUcIY0W5XhU&code_challenge_method=S256 +10ms
express:router <anonymous> : /auth?client_id=EPXH7xeHBvve0RMF-ZbCV&scope=openid%20email%20profile&response_type=code&redirect_uri=https%3A%2F%2Flocalhost%3A5000%2Fcallback&resource=http%3A%2F%2Flocalhost%3A3000%2Fauth&code_challenge=MR09wBrOHIX_q-FU1wrXGXaszcKUavg6FUcIY0W5XhU&code_challenge_method=S256 +0ms
express:router <anonymous> : /auth?client_id=EPXH7xeHBvve0RMF-ZbCV&scope=openid%20email%20profile&response_type=code&redirect_uri=https%3A%2F%2Flocalhost%3A5000%2Fcallback&resource=http%3A%2F%2Flocalhost%3A3000%2Fauth&code_challenge=MR09wBrOHIX_q-FU1wrXGXaszcKUavg6FUcIY0W5XhU&code_challenge_method=S256 +0ms
express:router handleRequest : /auth?client_id=EPXH7xeHBvve0RMF-ZbCV&scope=openid%20email%20profile&response_type=code&redirect_uri=https%3A%2F%2Flocalhost%3A5000%2Fcallback&resource=http%3A%2F%2Flocalhost%3A3000%2Fauth&code_challenge=MR09wBrOHIX_q-FU1wrXGXaszcKUavg6FUcIY0W5XhU&code_challenge_method=S256 +0ms
ioredis:redis write command[127.0.0.1:6379]: 0 -> get([ 'oidc:Client:EPXH7xeHBvve0RMF-ZbCV' ]) +7s
ioredis:redis write command[127.0.0.1:6379]: 0 -> multi([]) +21ms
ioredis:redis write command[127.0.0.1:6379]: 0 -> set('oidc:Interaction:R5ytEDexxII-fK7LlOJdp,{"iat":1585977423,"exp":1585978023,"returnTo":"http://localhost:3000/auth/R5ytEDexxII-fK7LlOJdp","prompt":{"name":"login","reasons":["no_session"],"details":{}}, ... <REDACTED full-length="525">') +0ms
ioredis:redis write command[127.0.0.1:6379]: 0 -> expire([ 'oidc:Interaction:R5ytEDexxII-fK7LlOJdp', '600' ]) +37ms
ioredis:redis write command[127.0.0.1:6379]: 0 -> set([ 'oidc:uid:R5ytEDexxII-fK7LlOJdp', 'R5ytEDexxII-fK7LlOJdp' ]) +0ms
ioredis:redis write command[127.0.0.1:6379]: 0 -> expire([ 'oidc:uid:R5ytEDexxII-fK7LlOJdp', '600' ]) +1ms
ioredis:redis write command[127.0.0.1:6379]: 0 -> exec([]) +0ms
oidc-provider:authentication:interrupted uid=R5ytEDexxII-fK7LlOJdp interaction=Interaction { returnTo: 'http://localhost:3000/auth/R5ytEDexxII-fK7LlOJdp', prompt: { name: 'login', reasons: [ 'no_session' ], details: { max_age: undefined, login_hint: undefined, id_token_hint: undefined } }, lastSubmission: undefined, uid: 'R5ytEDexxII-fK7LlOJdp', params: { client_id: 'EPXH7xeHBvve0RMF-ZbCV', code_challenge: 'MR09wBrOHIX_q-FU1wrXGXaszcKUavg6FUcIY0W5XhU', code_challenge_method: 'S256', redirect_uri: 'https://localhost:5000/callback', response_type: 'code', scope: 'openid email profile' }, signed: undefined, session: undefined, kind: 'Interaction', jti: 'R5ytEDexxII-fK7LlOJdp', exp: 1585978023 } +0ms
express:router dispatching OPTIONS /interaction/R5ytEDexxII-fK7LlOJdp +100ms
express:router query : /interaction/R5ytEDexxII-fK7LlOJdp +0ms
express:router expressInit : /interaction/R5ytEDexxII-fK7LlOJdp +0ms
express:router helmet : /interaction/R5ytEDexxII-fK7LlOJdp +0ms
express:router corsMiddleware : /interaction/R5ytEDexxII-fK7LlOJdp +0ms
express:router dispatching GET /interaction/R5ytEDexxII-fK7LlOJdp +6ms
express:router query : /interaction/R5ytEDexxII-fK7LlOJdp +0ms
express:router expressInit : /interaction/R5ytEDexxII-fK7LlOJdp +0ms
express:router helmet : /interaction/R5ytEDexxII-fK7LlOJdp +0ms
express:router corsMiddleware : /interaction/R5ytEDexxII-fK7LlOJdp +0ms
express:router <anonymous> : /interaction/R5ytEDexxII-fK7LlOJdp +1ms
======>>routes /interaction/:uid
=============>>id undefined
express:router <anonymous> : /interaction/R5ytEDexxII-fK7LlOJdp +2ms
======>>routes err instanceof SessionNotFound
express:router handleRequest : /interaction/R5ytEDexxII-fK7LlOJdp +0ms
finalhandler default 400 +0ms
SessionNotFound: invalid_request
at Provider.getInteraction (/Chim/Fidenz/Dev/siop/libs/oidc_provide/lib/provider.js:55:11)
at Provider.interactionDetails (/Chim/Fidenz/Dev/siop/libs/oidc_provide/lib/provider.js:233:27)
at /Chim/Fidenz/Dev/siop/libs/oidc_provide/example/routes/express.js:54:26
at Layer.handle [as handle_request] (/Chim/Fidenz/Dev/siop/libs/oidc_provide/node_modules/express/lib/router/layer.js:95:5)
at next (/Chim/Fidenz/Dev/siop/libs/oidc_provide/node_modules/express/lib/router/route.js:137:13)
at setNoCache (/Chim/Fidenz/Dev/siop/libs/oidc_provide/example/routes/express.js:46:5)
at Layer.handle [as handle_request] (/Chim/Fidenz/Dev/siop/libs/oidc_provide/node_modules/express/lib/router/layer.js:95:5)
at next (/Chim/Fidenz/Dev/siop/libs/oidc_provide/node_modules/express/lib/router/route.js:137:13)
at Route.dispatch (/Chim/Fidenz/Dev/siop/libs/oidc_provide/node_modules/express/lib/router/route.js:112:3)
at Layer.handle [as handle_request] (/Chim/Fidenz/Dev/siop/libs/oidc_provide/node_modules/express/lib/router/layer.js:95:5)
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment