vouch.log

vouch.log

nohup: ignoring input
{"level":"info","ts":1718242673.4445589,"msg":"Copyright 2020-2023 the Vouch Proxy Authors"}
{"level":"warn","ts":1718242673.4445863,"msg":"Vouch Proxy is free software with ABSOLUTELY NO WARRANTY."}
{"level":"info","ts":1718242673.4446263,"msg":"logging configured from cmdline"}
{"level":"info","ts":1718242673.4472048,"msg":"jwt.secret read from /root/vouch-proxy/config/secret"}
{"level":"warn","ts":1718242673.447218,"msg":"generating random session.key"}
{"level":"info","ts":1718242673.4473882,"msg":"configuring oidc OAuth with Endpoint https://infosite.auth.us-east-1.amazoncognito.com/oauth2/authorize"}
{"level":"debug","ts":1718242673.4473941,"msg":"cfg.RootDir: /root/vouch-proxy"}
{"level":"debug","ts":1718242673.447456,"msg":"Cfg {LogLevel:debug Listen:0.0.0.0 Port:9090 SocketMode:432 SocketGroup: DocumentRoot: WriteTimeout:15 ReadTimeout:15 IdleTimeout:15 Domains:[connflex.xyz] WhiteList:[] TeamWhiteList:[] AllowAllUsers:false PublicAccess:false TLS:{Cert: Key: Profile:intermediate} JWT:{SigningMethod:HS256 MaxAge:240 Issuer:Vouch Secret:XXXXXXXX PrivateKeyFile: PublicKeyFile: Compress:true} Cookie:{Name:VouchCookie Domain: Secure:false HTTPOnly:true MaxAge:240 SameSite:} Headers:{JWT:X-Vouch-Token User:X-Vouch-User QueryString:access_token Redirect:X-Vouch-Requested-URI Success:X-Vouch-Success Error:X-Vouch-Error ClaimHeader:X-Vouch-IdP-Claims- Claims:[] AccessToken: IDToken: ClaimsCleaned:map[]} Session:{Name:VouchSession MaxAge:5 Key:XXXXXXXX} TestURL: TestURLs:[] Testing:false LogoutRedirectURLs:[]}"}
{"level":"debug","ts":1718242673.4474716,"msg":"cfg.GenOauth {Provider:oidc ClientID:12345678 ClientSecret:XXXXXXXX AuthURL:https://infosite.auth.us-east-1.amazoncognito.com/oauth2/authorize TokenURL:https://infosite.auth.us-east-1.amazoncognito.com/oauth2/token LogoutURL: RedirectURL:https://connflex.xyz/auth RedirectURLs:[] RelyingPartyId: Scopes:[openid email profile] Claims:<nil> UserInfoURL:https://infosite.auth.us-east-1.amazoncognito.com/oauth2/userInfo UserTeamURL: UserOrgURL: PreferredDomain: AzureToken: CodeChallengeMethod:}"}
{"level":"debug","ts":1718242673.4476292,"msg":"vouch.jwt.secret is 44 characters long"}
{"level":"debug","ts":1718242673.447636,"msg":"vouch.session.key is 44 characters long"}
{"level":"info","ts":1718242673.4477365,"msg":"jwtcache: the returned headers for a valid jwt will be cached for 20 minutes"}
{"level":"debug","ts":1718242673.4477437,"msg":"responses.Configure() attempting to parse embedded templates"}
{"level":"debug","ts":1718242673.447846,"msg":"checking availability of tcp port: 0.0.0.0:9090"}
{"level":"info","ts":1718242673.4480898,"msg":"starting Vouch Proxy","version":"ad2e9ac","buildtime":"2024-06-12T23:18:22Z","uname":"Linux","buildhost":"ip-172-31-94-110","branch":"master","semver":"v0.40.0","listen":"http://0.0.0.0:9090","tls":false,"document_root":"","oauth.provider":"oidc"}
{"level":"debug","ts":1718242716.7827778,"msg":"/validate"}
{"level":"warn","ts":1718242716.7828434,"msg":"no jwt found in request"}
{"level":"debug","ts":1718242716.7828534,"msg":"domain connflex.xyz matched array value at [0]=connflex.xyz"}
{"level":"debug","ts":1718242716.7828662,"msg":"CaptureWriter.Write set w.StatusCode 401"}
{"level":"info","ts":1718242716.7830522,"msg":"|401|  153.925µs /validate","statusCode":401,"request":1,"latency":0.000153925,"avgLatency":0.000153925,"ipPort":"127.0.0.1:40832","method":"GET","host":"connflex.xyz","path":"/validate","referer":""}
{"level":"debug","ts":1718242716.7834344,"msg":"/login"}
{"level":"debug","ts":1718242716.783447,"msg":"domain connflex.xyz matched array value at [0]=connflex.xyz"}
{"level":"debug","ts":1718242716.7835307,"msg":"session state set to 9OTE2rGQ53fE24JKiJ6HH06QYLewdx"}
{"level":"debug","ts":1718242716.783559,"msg":"Login url param normalized to 'https://connflex.xyz/info'"}
{"level":"debug","ts":1718242716.783569,"msg":"domain connflex.xyz matched array value at [0]=connflex.xyz"}
{"level":"debug","ts":1718242716.7835724,"msg":"session requestedURL set to https://connflex.xyz/info"}
{"level":"debug","ts":1718242716.7835753,"msg":"saving session with failcount 1"}
{"level":"debug","ts":1718242716.7839994,"msg":"redirecting to oauthURL https://infosite.auth.us-east-1.amazoncognito.com/oauth2/authorize?client_id=64ekbs9qaoobil6oc58bmos5dl&redirect_uri=https%3A%2F%2Fconnflex.xyz%2Fauth&response_type=code&scope=openid+email+profile&state=9OTE2rGQ53fE24JKiJ6HH06QYLewdx"}
{"level":"debug","ts":1718242716.7840135,"msg":"CaptureWriter.Write set w.StatusCode 302"}
{"level":"info","ts":1718242716.7841024,"msg":"|302|  626.078µs /login","statusCode":302,"request":2,"latency":0.000626078,"avgLatency":0.000390001,"ipPort":"127.0.0.1:40844","method":"GET","host":"connflex.xyz","path":"/login","referer":""}
{"level":"debug","ts":1718242729.4332154,"msg":"/auth"}
{"level":"debug","ts":1718242729.4333022,"msg":"CaptureWriter.Write set w.StatusCode 302"}
{"level":"info","ts":1718242729.4333813,"msg":"|302|  114.175µs /auth","statusCode":302,"request":3,"latency":0.000114175,"avgLatency":0.000298059,"ipPort":"127.0.0.1:46382","method":"GET","host":"connflex.xyz","path":"/auth","referer":"https://infosite.auth.us-east-1.amazoncognito.com/login?client_id=64ekbs9qaoobil6oc58bmos5dl&redirect_uri=https%3A%2F%2Fconnflex.xyz%2Fauth&response_type=code&scope=openid+email+profile&state=9OTE2rGQ53fE24JKiJ6HH06QYLewdx"}
{"level":"debug","ts":1718242729.4848819,"msg":"/auth/{state}/"}
{"level":"debug","ts":1718242729.620759,"msg":"ptokens: accessToken length: 1039, IdToken length: 1076"}
{"level":"info","ts":1718242729.7859912,"msg":"OpenID userinfo body: {\"sub\":\"8488b438-a0a1-70ac-71ae-6d003cd4040e\",\"email\":\"cloudops.spoorthy@gmail.com\",\"username\":\"spoorthy\"}"}
{"level":"debug","ts":1718242729.786418,"msg":"/auth/{state}/ Claims from userinfo: {Claims:map[]}"}
{"level":"warn","ts":1718242729.786435,"msg":"domain gmail.com not found in any domains [connflex.xyz]"}
{"level":"warn","ts":1718242729.7864454,"msg":"/auth User is not authorized: verifyUser: Email cloudops.spoorthy@gmail.com is not within a Vouch Proxy managed domain . Please try again or seek support from your administrator"}
{"level":"debug","ts":1718242729.7864635,"msg":"domain connflex.xyz matched array value at [0]=connflex.xyz"}
{"level":"debug","ts":1718242729.786486,"msg":"rendering error for user: 403 Forbidden"}
{"level":"debug","ts":1718242729.7864914,"msg":"CaptureWriter.Write set w.StatusCode 403"}
{"level":"info","ts":1718242729.786795,"msg":"|403| 301.809123ms /auth/9OTE2rGQ53fE24JKiJ6HH06QYLewdx/","statusCode":403,"request":4,"latency":0.301809123,"avgLatency":0.075675825,"ipPort":"127.0.0.1:46386","method":"GET","host":"connflex.xyz","path":"/auth/9OTE2rGQ53fE24JKiJ6HH06QYLewdx/","referer":"https://infosite.auth.us-east-1.amazoncognito.com/login?client_id=64ekbs9qaoobil6oc58bmos5dl&redirect_uri=https%3A%2F%2Fconnflex.xyz%2Fauth&response_type=code&scope=openid+email+profile&state=9OTE2rGQ53fE24JKiJ6HH06QYLewdx"}
{"level":"debug","ts":1718242795.15787,"msg":"/validate"}
{"level":"warn","ts":1718242795.157925,"msg":"no jwt found in request"}
{"level":"debug","ts":1718242795.1579363,"msg":"domain connflex.xyz matched array value at [0]=connflex.xyz"}
{"level":"debug","ts":1718242795.1579506,"msg":"CaptureWriter.Write set w.StatusCode 401"}
{"level":"info","ts":1718242795.158061,"msg":"|401|  257.049µs /validate","statusCode":401,"request":5,"latency":0.000257049,"avgLatency":0.06059207,"ipPort":"127.0.0.1:46768","method":"GET","host":"connflex.xyz","path":"/validate","referer":""}
{"level":"debug","ts":1718242795.1582766,"msg":"/login"}
{"level":"debug","ts":1718242795.1582873,"msg":"domain connflex.xyz matched array value at [0]=connflex.xyz"}
{"level":"debug","ts":1718242795.1583428,"msg":"session state set to MtGwNK4KGfy1WKCfILPZazcUeJL2BF"}
{"level":"debug","ts":1718242795.1583638,"msg":"Login url param normalized to 'https://connflex.xyz/info/'"}
{"level":"debug","ts":1718242795.158369,"msg":"domain connflex.xyz matched array value at [0]=connflex.xyz"}
{"level":"debug","ts":1718242795.158373,"msg":"session requestedURL set to https://connflex.xyz/info/"}
{"level":"debug","ts":1718242795.1583822,"msg":"saving session with failcount 1"}
{"level":"debug","ts":1718242795.1584406,"msg":"redirecting to oauthURL https://infosite.auth.us-east-1.amazoncognito.com/oauth2/authorize?client_id=64ekbs9qaoobil6oc58bmos5dl&redirect_uri=https%3A%2F%2Fconnflex.xyz%2Fauth&response_type=code&scope=openid+email+profile&state=MtGwNK4KGfy1WKCfILPZazcUeJL2BF"}
{"level":"debug","ts":1718242795.1584466,"msg":"CaptureWriter.Write set w.StatusCode 302"}
{"level":"info","ts":1718242795.158515,"msg":"|302|   195.56µs /login","statusCode":302,"request":6,"latency":0.00019556,"avgLatency":0.050525985,"ipPort":"127.0.0.1:46782","method":"GET","host":"connflex.xyz","path":"/login","referer":""}
{"level":"debug","ts":1718242795.9156106,"msg":"/validate"}
{"level":"warn","ts":1718242795.915649,"msg":"no jwt found in request"}
{"level":"debug","ts":1718242795.9156573,"msg":"domain connflex.xyz matched array value at [0]=connflex.xyz"}
{"level":"debug","ts":1718242795.9156675,"msg":"CaptureWriter.Write set w.StatusCode 401"}
{"level":"info","ts":1718242795.9157052,"msg":"|401|   88.413µs /validate","statusCode":401,"request":7,"latency":0.000088413,"avgLatency":0.043320618,"ipPort":"127.0.0.1:46788","method":"GET","host":"connflex.xyz","path":"/validate","referer":""}
{"level":"debug","ts":1718242795.9160717,"msg":"/login"}
{"level":"debug","ts":1718242795.916082,"msg":"domain connflex.xyz matched array value at [0]=connflex.xyz"}
{"level":"debug","ts":1718242795.916105,"msg":"session state set to SFkak0A3kNu5RxjvSTd2aoMekky6FMm"}
{"level":"debug","ts":1718242795.916124,"msg":"Login url param normalized to 'https://connflex.xyz/info/'"}
{"level":"debug","ts":1718242795.9161294,"msg":"domain connflex.xyz matched array value at [0]=connflex.xyz"}
{"level":"debug","ts":1718242795.9161327,"msg":"session requestedURL set to https://connflex.xyz/info/"}
{"level":"debug","ts":1718242795.9161353,"msg":"saving session with failcount 1"}
{"level":"debug","ts":1718242795.9161952,"msg":"redirecting to oauthURL https://infosite.auth.us-east-1.amazoncognito.com/oauth2/authorize?client_id=64ekbs9qaoobil6oc58bmos5dl&redirect_uri=https%3A%2F%2Fconnflex.xyz%2Fauth&response_type=code&scope=openid+email+profile&state=SFkak0A3kNu5RxjvSTd2aoMekky6FMm"}
{"level":"debug","ts":1718242795.9162035,"msg":"CaptureWriter.Write set w.StatusCode 302"}
{"level":"info","ts":1718242795.9162457,"msg":"|302|  153.002µs /login","statusCode":302,"request":8,"latency":0.000153002,"avgLatency":0.037924666,"ipPort":"127.0.0.1:46804","method":"GET","host":"connflex.xyz","path":"/login","referer":""}
{"level":"debug","ts":1718242806.9188027,"msg":"/auth"}
{"level":"debug","ts":1718242806.9188714,"msg":"CaptureWriter.Write set w.StatusCode 302"}
{"level":"info","ts":1718242806.918962,"msg":"|302|  101.028µs /auth","statusCode":302,"request":9,"latency":0.000101028,"avgLatency":0.03372204,"ipPort":"127.0.0.1:36548","method":"GET","host":"connflex.xyz","path":"/auth","referer":"https://infosite.auth.us-east-1.amazoncognito.com/"}
{"level":"debug","ts":1718242806.9555666,"msg":"/auth/{state}/"}
{"level":"debug","ts":1718242807.0895627,"msg":"ptokens: accessToken length: 1039, IdToken length: 1076"}
{"level":"info","ts":1718242807.2250526,"msg":"OpenID userinfo body: {\"sub\":\"8488b438-a0a1-70ac-71ae-6d003cd4040e\",\"email\":\"cloudops.spoorthy@gmail.com\",\"username\":\"spoorthy\"}"}
{"level":"debug","ts":1718242807.2251325,"msg":"/auth/{state}/ Claims from userinfo: {Claims:map[]}"}
{"level":"warn","ts":1718242807.2251432,"msg":"domain gmail.com not found in any domains [connflex.xyz]"}
{"level":"warn","ts":1718242807.2251515,"msg":"/auth User is not authorized: verifyUser: Email cloudops.spoorthy@gmail.com is not within a Vouch Proxy managed domain . Please try again or seek support from your administrator"}
{"level":"debug","ts":1718242807.2251587,"msg":"domain connflex.xyz matched array value at [0]=connflex.xyz"}
{"level":"debug","ts":1718242807.225172,"msg":"rendering error for user: 403 Forbidden"}
{"level":"debug","ts":1718242807.2251756,"msg":"CaptureWriter.Write set w.StatusCode 403"}
{"level":"info","ts":1718242807.2252944,"msg":"|403| 269.71064ms /auth/SFkak0A3kNu5RxjvSTd2aoMekky6FMm/","statusCode":403,"request":10,"latency":0.26971064,"avgLatency":0.0573209,"ipPort":"127.0.0.1:36564","method":"GET","host":"connflex.xyz","path":"/auth/SFkak0A3kNu5RxjvSTd2aoMekky6FMm/","referer":"https://infosite.auth.us-east-1.amazoncognito.com/"}
{"level":"debug","ts":1718242987.7183843,"msg":"/auth/{state}/"}
{"level":"warn","ts":1718242987.7184544,"msg":"/auth Invalid session state: stored %!s(<nil>), returned "}
{"level":"debug","ts":1718242987.7184625,"msg":"domain connflex.xyz matched array value at [0]=connflex.xyz"}
{"level":"debug","ts":1718242987.718482,"msg":"rendering error for user: 400 Bad Request"}
{"level":"debug","ts":1718242987.7184875,"msg":"CaptureWriter.Write set w.StatusCode 400"}
{"level":"info","ts":1718242987.7188795,"msg":"|400|  186.237µs /auth/9OTE2rGQ53fE24JKiJ6HH06QYLewdx/","statusCode":400,"request":11,"latency":0.000186237,"avgLatency":0.05212684,"ipPort":"127.0.0.1:43456","method":"GET","host":"connflex.xyz","path":"/auth/9OTE2rGQ53fE24JKiJ6HH06QYLewdx/","referer":""}
{"level":"debug","ts":1718242987.7691107,"msg":"/auth/{state}/"}
{"level":"warn","ts":1718242987.769154,"msg":"/auth Invalid session state: stored %!s(<nil>), returned 9OTE2rGQ53fE24JKiJ6HH06QYLewdx"}
{"level":"debug","ts":1718242987.7691634,"msg":"domain connflex.xyz matched array value at [0]=connflex.xyz"}
{"level":"debug","ts":1718242987.7691755,"msg":"rendering error for user: 400 Bad Request"}
{"level":"debug","ts":1718242987.7691813,"msg":"CaptureWriter.Write set w.StatusCode 400"}
{"level":"info","ts":1718242987.769316,"msg":"|400|  136.297µs /auth/9OTE2rGQ53fE24JKiJ6HH06QYLewdx/","statusCode":400,"request":12,"latency":0.000136297,"avgLatency":0.047794295,"ipPort":"127.0.0.1:43458","method":"GET","host":"connflex.xyz","path":"/auth/9OTE2rGQ53fE24JKiJ6HH06QYLewdx/","referer":""}
{"level":"debug","ts":1718242991.754748,"msg":"/auth/{state}/"}
{"level":"warn","ts":1718242991.7547927,"msg":"/auth Invalid session state: stored %!s(<nil>), returned "}
{"level":"debug","ts":1718242991.7549205,"msg":"domain connflex.xyz matched array value at [0]=connflex.xyz"}
{"level":"debug","ts":1718242991.7549355,"msg":"rendering error for user: 400 Bad Request"}
{"level":"debug","ts":1718242991.754941,"msg":"CaptureWriter.Write set w.StatusCode 400"}
{"level":"info","ts":1718242991.7550828,"msg":"|400|  257.492µs /auth/9OTE2rGQ53fE24JKiJ6HH06QYLewdx/","statusCode":400,"request":13,"latency":0.000257492,"avgLatency":0.044137618,"ipPort":"127.0.0.1:43466","method":"GET","host":"connflex.xyz","path":"/auth/9OTE2rGQ53fE24JKiJ6HH06QYLewdx/","referer":""}
{"level":"debug","ts":1718242992.7887933,"msg":"/auth/{state}/"}
{"level":"warn","ts":1718242992.788831,"msg":"/auth Invalid session state: stored %!s(<nil>), returned 9OTE2rGQ53fE24JKiJ6HH06QYLewdx"}
{"level":"debug","ts":1718242992.788838,"msg":"domain connflex.xyz matched array value at [0]=connflex.xyz"}
{"level":"debug","ts":1718242992.7888515,"msg":"rendering error for user: 400 Bad Request"}
{"level":"debug","ts":1718242992.788857,"msg":"CaptureWriter.Write set w.StatusCode 400"}
{"level":"info","ts":1718242992.788954,"msg":"|400|   128.15µs /auth/9OTE2rGQ53fE24JKiJ6HH06QYLewdx/","statusCode":400,"request":14,"latency":0.00012815,"avgLatency":0.040994085,"ipPort":"127.0.0.1:43478","method":"GET","host":"connflex.xyz","path":"/auth/9OTE2rGQ53fE24JKiJ6HH06QYLewdx/","referer":""}
{"level":"debug","ts":1718243005.0903955,"msg":"/auth/{state}/"}
{"level":"warn","ts":1718243005.0904377,"msg":"/auth Invalid session state: stored %!s(<nil>), returned "}
{"level":"debug","ts":1718243005.090444,"msg":"domain connflex.xyz matched array value at [0]=connflex.xyz"}
{"level":"debug","ts":1718243005.0904524,"msg":"rendering error for user: 400 Bad Request"}
{"level":"debug","ts":1718243005.090456,"msg":"CaptureWriter.Write set w.StatusCode 400"}
{"level":"info","ts":1718243005.0905893,"msg":"|400|  122.337µs /auth/9OTE2rGQ53fE24JKiJ6HH06QYLewdx/","statusCode":400,"request":15,"latency":0.000122337,"avgLatency":0.038269302,"ipPort":"127.0.0.1:33380","method":"GET","host":"connflex.xyz","path":"/auth/9OTE2rGQ53fE24JKiJ6HH06QYLewdx/","referer":"https://www.google.com/"}