UserSignIn: not in WebAuthn session #13835

Closed
opened 2025-11-02 10:54:37 -06:00 by GiteaMirror · 10 comments
Owner

Originally created by @YowFung on GitHub (Dec 17, 2024).

Description

My Gitea was working properly before. Suddenly, one day when I tried to log in, an error page with the code 500 popped up. I don't know what the reason is, nor do I know how to solve this problem. Here are some logs that I've captured. Could you please help me?

access.log:

192.168.31.20 - - [17/Dec/2024:12:24:08 +0800] "GET /user/webauthn HTTP/1.1" 500 2068 "" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Edg/131.0.0.0"
192.168.31.20 - - [17/Dec/2024:12:24:12 +0800] "GET / HTTP/1.1" 303 35 "" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Edg/131.0.0.0"
192.168.31.20 - - [17/Dec/2024:12:24:12 +0800] "GET /explore HTTP/1.1" 303 38 "" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Edg/131.0.0.0"
192.168.31.20 - - [17/Dec/2024:12:24:12 +0800] "GET /user/login HTTP/1.1" 200 9496 "" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Edg/131.0.0.0"
172.17.0.1 - - [17/Dec/2024:12:24:13 +0800] "GET /assets/img/logo.png HTTP/1.1" 200 13932 "" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Edg/131.0.0.0"
172.17.0.1 - - [17/Dec/2024:12:24:13 +0800] "GET /assets/img/logo.svg HTTP/1.1" 200 1078 "" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Edg/131.0.0.0"
192.168.31.20 - - [17/Dec/2024:12:24:19 +0800] "POST /user/login HTTP/1.1" 200 9622 "" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Edg/131.0.0.0"
192.168.31.20 - - [17/Dec/2024:12:24:24 +0800] "POST /user/login HTTP/1.1" 303 0 "" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Edg/131.0.0.0"
192.168.31.20 - - [17/Dec/2024:12:24:24 +0800] "GET /user/webauthn HTTP/1.1" 500 2068 "" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Edg/131.0.0.0"

error.log:

2024/12/17 12:24:08 ...web/auth/webauthn.go:36:WebAuthn() [E] UserSignIn: not in WebAuthn session
2024/12/17 12:24:24 ...web/auth/webauthn.go:36:WebAuthn() [E] UserSignIn: not in WebAuthn session

gitea.log:

2024/12/17 12:23:57 ...eful/manager_unix.go:198:handleSignals() [W] PID 16. Received SIGTERM. Shutting down...
2024/12/17 12:23:57 cmd/web.go:336:listen() [I] HTTP Listener: 0.0.0.0:3000 Closed
2024/12/17 12:23:57 .../graceful/manager.go:168:doHammerTime() [W] Setting Hammer condition
2024/12/17 12:23:57 ...eful/server_hooks.go:46:doShutdown() [I] PID: 16 Listener ([::]:3000) closed.
2024/12/17 12:23:58 .../graceful/manager.go:184:doTerminate() [W] Terminating
2024/12/17 12:23:58 ...er/issues/indexer.go:183:2() [I] PID: 16 Issue Indexer closed
2024/12/17 12:23:58 ...eful/manager_unix.go:208:handleSignals() [W] PID: 16. Background context for manager closed - context canceled - Shutting down...
2024/12/17 12:23:58 cmd/web.go:195:serveInstalled() [I] PID: 16 Gitea Web Finished
2024/12/17 12:24:07 ...les/setting/cache.go:75:loadCacheFrom() [I] Cache Service Enabled
2024/12/17 12:24:07 ...les/setting/cache.go:90:loadCacheFrom() [I] Last Commit Cache Service Enabled
2024/12/17 12:24:07 ...s/setting/session.go:74:loadSessionFrom() [I] Session Service Enabled
2024/12/17 12:24:07 ...es/setting/mailer.go:237:loadMailerFrom() [I] Mail Service Enabled
2024/12/17 12:24:07 ...es/setting/mailer.go:248:loadRegisterMailFrom() [I] Register Mail Service Enabled
2024/12/17 12:24:07 ...es/setting/mailer.go:259:loadNotifyMailFrom() [I] Notify Mail Service Enabled
2024/12/17 12:24:07 ...s/storage/storage.go:176:initAttachments() [I] Initialising Attachment storage with type: local
2024/12/17 12:24:07 ...les/storage/local.go:33:NewLocalStorage() [I] Creating new Local Storage at /data/gitea/attachments
2024/12/17 12:24:07 ...s/storage/storage.go:166:initAvatars() [I] Initialising Avatar storage with type: local
2024/12/17 12:24:07 ...les/storage/local.go:33:NewLocalStorage() [I] Creating new Local Storage at /data/gitea/avatars
2024/12/17 12:24:07 ...s/storage/storage.go:192:initRepoAvatars() [I] Initialising Repository Avatar storage with type: local
2024/12/17 12:24:07 ...les/storage/local.go:33:NewLocalStorage() [I] Creating new Local Storage at /data/gitea/repo-avatars
2024/12/17 12:24:07 ...s/storage/storage.go:186:initLFS() [I] Initialising LFS storage with type: local
2024/12/17 12:24:07 ...les/storage/local.go:33:NewLocalStorage() [I] Creating new Local Storage at /data/git/lfs
2024/12/17 12:24:07 ...s/storage/storage.go:198:initRepoArchives() [I] Initialising Repository Archive storage with type: local
2024/12/17 12:24:07 ...les/storage/local.go:33:NewLocalStorage() [I] Creating new Local Storage at /data/gitea/repo-archive
2024/12/17 12:24:07 ...s/storage/storage.go:208:initPackages() [I] Initialising Packages storage with type: local
2024/12/17 12:24:07 ...les/storage/local.go:33:NewLocalStorage() [I] Creating new Local Storage at /data/gitea/packages
2024/12/17 12:24:07 ...queue/workergroup.go:255:doRun() [D] Queue "mail" starts running
2024/12/17 12:24:07 ...queue/workergroup.go:255:doRun() [D] Queue "notification-service" starts running
2024/12/17 12:24:07 ...queue/workergroup.go:255:doRun() [D] Queue "repo-archive" starts running
2024/12/17 12:24:07 routers/init.go:130:InitWebInstalled() [I] SQLite3 support is enabled
2024/12/17 12:24:07 routers/common/db.go:21:InitDBEngine() [I] Beginning ORM engine initialization.
2024/12/17 12:24:07 routers/common/db.go:28:InitDBEngine() [I] ORM engine initialization attempt #1/10...
2024/12/17 12:24:07 cmd/web.go:175:serveInstalled() [I] PING DATABASE sqlite3
2024/12/17 12:24:08 cmd/web.go:175:serveInstalled() [W] Table system_setting Column version db default is , struct default is 1
2024/12/17 12:24:08 cmd/web.go:175:serveInstalled() [W] Table project Column card_type db default is 0, struct default is 
2024/12/17 12:24:08 cmd/web.go:175:serveInstalled() [W] Table project Column card_type db nullable is false, struct nullable is true
2024/12/17 12:24:08 cmd/web.go:175:serveInstalled() [W] Table hook_task has column repo_id but struct has not related field
2024/12/17 12:24:08 routers/init.go:136:InitWebInstalled() [I] ORM engine initialization successful!
2024/12/17 12:24:08 ...queue/workergroup.go:255:doRun() [D] Queue "push_update" starts running
2024/12/17 12:24:08 ...er/issues/indexer.go:156:func2() [I] PID 17: Initializing Issue Indexer: bleve
2024/12/17 12:24:08 ...queue/workergroup.go:255:doRun() [D] Queue "repo_stats_update" starts running
2024/12/17 12:24:08 ...xer/stats/indexer.go:38:populateRepoIndexer() [I] Populating the repo stats indexer with existing repositories
2024/12/17 12:24:08 ...queue/workergroup.go:255:doRun() [D] Queue "mirror" starts running
2024/12/17 12:24:08 ...queue/workergroup.go:255:doRun() [D] Queue "pr_patch_checker" starts running
2024/12/17 12:24:08 ...queue/workergroup.go:255:doRun() [D] Queue "webhook_sender" starts running
2024/12/17 12:24:08 ...queue/workergroup.go:255:doRun() [D] Queue "pr_auto_merge" starts running
2024/12/17 12:24:08 ...queue/workergroup.go:255:doRun() [D] Queue "task" starts running
2024/12/17 12:24:08 ...er/issues/indexer.go:185:func2() [D] Created Bleve Indexer
2024/12/17 12:24:08 ...er/issues/indexer.go:246:func3() [I] Issue Indexer Initialization took 53.665131ms
2024/12/17 12:24:08 ...queue/workergroup.go:255:doRun() [D] Queue "issue_indexer" starts running
2024/12/17 12:24:08 ...rvices/cron/tasks.go:147:RegisterTask() [D] Registering task: update_mirrors
2024/12/17 12:24:08 ...rvices/cron/tasks.go:147:RegisterTask() [D] Registering task: repo_health_check
2024/12/17 12:24:08 ...rvices/cron/tasks.go:147:RegisterTask() [D] Registering task: check_repo_stats
2024/12/17 12:24:08 ...rvices/cron/tasks.go:147:RegisterTask() [D] Registering task: archive_cleanup
2024/12/17 12:24:08 ...rvices/cron/tasks.go:147:RegisterTask() [D] Registering task: sync_external_users
2024/12/17 12:24:08 ...rvices/cron/tasks.go:147:RegisterTask() [D] Registering task: deleted_branches_cleanup
2024/12/17 12:24:08 ...rvices/cron/tasks.go:147:RegisterTask() [D] Registering task: update_migration_poster_id
2024/12/17 12:24:08 ...rvices/cron/tasks.go:147:RegisterTask() [D] Registering task: cleanup_hook_task_table
2024/12/17 12:24:08 ...rvices/cron/tasks.go:147:RegisterTask() [D] Registering task: cleanup_packages
2024/12/17 12:24:08 ...rvices/cron/tasks.go:147:RegisterTask() [D] Registering task: delete_inactive_accounts
2024/12/17 12:24:08 ...rvices/cron/tasks.go:147:RegisterTask() [D] Registering task: delete_repo_archives
2024/12/17 12:24:08 ...rvices/cron/tasks.go:147:RegisterTask() [D] Registering task: git_gc_repos
2024/12/17 12:24:08 ...rvices/cron/tasks.go:147:RegisterTask() [D] Registering task: resync_all_sshkeys
2024/12/17 12:24:08 ...rvices/cron/tasks.go:147:RegisterTask() [D] Registering task: resync_all_sshprincipals
2024/12/17 12:24:08 ...rvices/cron/tasks.go:147:RegisterTask() [D] Registering task: resync_all_hooks
2024/12/17 12:24:08 ...rvices/cron/tasks.go:147:RegisterTask() [D] Registering task: reinit_missing_repos
2024/12/17 12:24:08 ...rvices/cron/tasks.go:147:RegisterTask() [D] Registering task: delete_missing_repos
2024/12/17 12:24:08 ...rvices/cron/tasks.go:147:RegisterTask() [D] Registering task: delete_generated_repository_avatars
2024/12/17 12:24:08 ...rvices/cron/tasks.go:147:RegisterTask() [D] Registering task: delete_old_actions
2024/12/17 12:24:08 ...rvices/cron/tasks.go:147:RegisterTask() [D] Registering task: update_checker
2024/12/17 12:24:08 ...rvices/cron/tasks.go:147:RegisterTask() [D] Registering task: delete_old_system_notices
2024/12/17 12:24:08 ...rvices/cron/tasks.go:147:RegisterTask() [D] Registering task: gc_lfs
2024/12/17 12:24:08 ...ates/htmlrenderer.go:113:initHTMLRenderer() [D] Creating static HTML Renderer
2024/12/17 12:24:08 ...xer/stats/indexer.go:84:populateRepoIndexer() [I] Done (re)populating the repo stats indexer with existing repositories
2024/12/17 12:24:08 cmd/web.go:285:listen() [I] Listen: http://0.0.0.0:3000
2024/12/17 12:24:08 cmd/web.go:289:listen() [I] AppURL(ROOT_URL): https://xxx.xxx.xxx/
2024/12/17 12:24:08 cmd/web.go:292:listen() [I] LFS server enabled
2024/12/17 12:24:08 ...s/graceful/server.go:62:NewServer() [I] Starting new Web server: tcp:0.0.0.0:3000 on PID: 17
2024/12/17 12:24:08 ...s/graceful/server.go:75:func1() [D] Starting server on tcp:0.0.0.0:3000 (PID: 17)
2024/12/17 12:24:08 ...web/auth/webauthn.go:36:WebAuthn() [E] UserSignIn: not in WebAuthn session
2024/12/17 12:24:08 .../context_response.go:74:HTML() [D] Template: status/500
2024/12/17 12:24:08 ...eb/routing/logger.go:102:func1() [I] router: completed GET /user/webauthn for 192.168.31.20:8521, 500 Internal Server Error in 2.2ms @ auth/webauthn.go:26(auth.WebAuthn)
2024/12/17 12:24:12 ...eb/routing/logger.go:102:func1() [I] router: completed GET / for 192.168.31.20:8521, 303 See Other in 0.7ms @ web/home.go:32(web.Home)
2024/12/17 12:24:12 ...eb/routing/logger.go:102:func1() [I] router: completed GET /explore for 192.168.31.20:8521, 303 See Other in 0.6ms @ auth/middleware.go:87(auth.VerifyAuthWithOptions)
2024/12/17 12:24:12 .../context_response.go:74:HTML() [D] Template: user/auth/signin
2024/12/17 12:24:12 ...eb/routing/logger.go:102:func1() [I] router: completed GET /user/login for 192.168.31.20:8521, 200 OK in 16.4ms @ auth/auth.go:141(auth.SignIn)
2024/12/17 12:24:19 .../context_response.go:74:HTML() [D] Template: user/auth/signin
2024/12/17 12:24:19 ...ers/web/auth/auth.go:206:SignInPost() [I] Failed authentication attempt for yowfung from 192.168.31.20:8521: user's password is invalid [uid: 1, name: yowfung]
2024/12/17 12:24:19 ...eb/routing/logger.go:102:func1() [I] router: completed POST /user/login for 192.168.31.20:8521, 200 OK in 69.2ms @ auth/auth.go:170(auth.SignInPost)
2024/12/17 12:24:24 ...eb/routing/logger.go:102:func1() [I] router: completed POST /user/login for 192.168.31.20:8521, 303 See Other in 68.2ms @ auth/auth.go:170(auth.SignInPost)
2024/12/17 12:24:24 ...web/auth/webauthn.go:36:WebAuthn() [E] UserSignIn: not in WebAuthn session
2024/12/17 12:24:24 .../context_response.go:74:HTML() [D] Template: status/500
2024/12/17 12:24:24 ...eb/routing/logger.go:102:func1() [I] router: completed GET /user/webauthn for 192.168.31.20:8521, 500 Internal Server Error in 1.0ms @ auth/webauthn.go:26(auth.WebAuthn)

Gitea Version

1.20.5

Can you reproduce the bug on the Gitea demo site?

No

Log Gist

No response

Screenshots

1734409997988-20241217_123122

Git Version

2.40.1

Operating System

Linux (in Docker)

How are you running Gitea?

Install from the official Docker image.

Database

SQLite

Originally created by @YowFung on GitHub (Dec 17, 2024). ### Description My Gitea was working properly before. Suddenly, one day when I tried to log in, an error page with the code 500 popped up. I don't know what the reason is, nor do I know how to solve this problem. Here are some logs that I've captured. Could you please help me? **access.log**: ```log 192.168.31.20 - - [17/Dec/2024:12:24:08 +0800] "GET /user/webauthn HTTP/1.1" 500 2068 "" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Edg/131.0.0.0" 192.168.31.20 - - [17/Dec/2024:12:24:12 +0800] "GET / HTTP/1.1" 303 35 "" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Edg/131.0.0.0" 192.168.31.20 - - [17/Dec/2024:12:24:12 +0800] "GET /explore HTTP/1.1" 303 38 "" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Edg/131.0.0.0" 192.168.31.20 - - [17/Dec/2024:12:24:12 +0800] "GET /user/login HTTP/1.1" 200 9496 "" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Edg/131.0.0.0" 172.17.0.1 - - [17/Dec/2024:12:24:13 +0800] "GET /assets/img/logo.png HTTP/1.1" 200 13932 "" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Edg/131.0.0.0" 172.17.0.1 - - [17/Dec/2024:12:24:13 +0800] "GET /assets/img/logo.svg HTTP/1.1" 200 1078 "" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Edg/131.0.0.0" 192.168.31.20 - - [17/Dec/2024:12:24:19 +0800] "POST /user/login HTTP/1.1" 200 9622 "" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Edg/131.0.0.0" 192.168.31.20 - - [17/Dec/2024:12:24:24 +0800] "POST /user/login HTTP/1.1" 303 0 "" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Edg/131.0.0.0" 192.168.31.20 - - [17/Dec/2024:12:24:24 +0800] "GET /user/webauthn HTTP/1.1" 500 2068 "" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Edg/131.0.0.0" ``` **error.log**: ```log 2024/12/17 12:24:08 ...web/auth/webauthn.go:36:WebAuthn() [E] UserSignIn: not in WebAuthn session 2024/12/17 12:24:24 ...web/auth/webauthn.go:36:WebAuthn() [E] UserSignIn: not in WebAuthn session ``` **gitea.log**: ```log 2024/12/17 12:23:57 ...eful/manager_unix.go:198:handleSignals() [W] PID 16. Received SIGTERM. Shutting down... 2024/12/17 12:23:57 cmd/web.go:336:listen() [I] HTTP Listener: 0.0.0.0:3000 Closed 2024/12/17 12:23:57 .../graceful/manager.go:168:doHammerTime() [W] Setting Hammer condition 2024/12/17 12:23:57 ...eful/server_hooks.go:46:doShutdown() [I] PID: 16 Listener ([::]:3000) closed. 2024/12/17 12:23:58 .../graceful/manager.go:184:doTerminate() [W] Terminating 2024/12/17 12:23:58 ...er/issues/indexer.go:183:2() [I] PID: 16 Issue Indexer closed 2024/12/17 12:23:58 ...eful/manager_unix.go:208:handleSignals() [W] PID: 16. Background context for manager closed - context canceled - Shutting down... 2024/12/17 12:23:58 cmd/web.go:195:serveInstalled() [I] PID: 16 Gitea Web Finished 2024/12/17 12:24:07 ...les/setting/cache.go:75:loadCacheFrom() [I] Cache Service Enabled 2024/12/17 12:24:07 ...les/setting/cache.go:90:loadCacheFrom() [I] Last Commit Cache Service Enabled 2024/12/17 12:24:07 ...s/setting/session.go:74:loadSessionFrom() [I] Session Service Enabled 2024/12/17 12:24:07 ...es/setting/mailer.go:237:loadMailerFrom() [I] Mail Service Enabled 2024/12/17 12:24:07 ...es/setting/mailer.go:248:loadRegisterMailFrom() [I] Register Mail Service Enabled 2024/12/17 12:24:07 ...es/setting/mailer.go:259:loadNotifyMailFrom() [I] Notify Mail Service Enabled 2024/12/17 12:24:07 ...s/storage/storage.go:176:initAttachments() [I] Initialising Attachment storage with type: local 2024/12/17 12:24:07 ...les/storage/local.go:33:NewLocalStorage() [I] Creating new Local Storage at /data/gitea/attachments 2024/12/17 12:24:07 ...s/storage/storage.go:166:initAvatars() [I] Initialising Avatar storage with type: local 2024/12/17 12:24:07 ...les/storage/local.go:33:NewLocalStorage() [I] Creating new Local Storage at /data/gitea/avatars 2024/12/17 12:24:07 ...s/storage/storage.go:192:initRepoAvatars() [I] Initialising Repository Avatar storage with type: local 2024/12/17 12:24:07 ...les/storage/local.go:33:NewLocalStorage() [I] Creating new Local Storage at /data/gitea/repo-avatars 2024/12/17 12:24:07 ...s/storage/storage.go:186:initLFS() [I] Initialising LFS storage with type: local 2024/12/17 12:24:07 ...les/storage/local.go:33:NewLocalStorage() [I] Creating new Local Storage at /data/git/lfs 2024/12/17 12:24:07 ...s/storage/storage.go:198:initRepoArchives() [I] Initialising Repository Archive storage with type: local 2024/12/17 12:24:07 ...les/storage/local.go:33:NewLocalStorage() [I] Creating new Local Storage at /data/gitea/repo-archive 2024/12/17 12:24:07 ...s/storage/storage.go:208:initPackages() [I] Initialising Packages storage with type: local 2024/12/17 12:24:07 ...les/storage/local.go:33:NewLocalStorage() [I] Creating new Local Storage at /data/gitea/packages 2024/12/17 12:24:07 ...queue/workergroup.go:255:doRun() [D] Queue "mail" starts running 2024/12/17 12:24:07 ...queue/workergroup.go:255:doRun() [D] Queue "notification-service" starts running 2024/12/17 12:24:07 ...queue/workergroup.go:255:doRun() [D] Queue "repo-archive" starts running 2024/12/17 12:24:07 routers/init.go:130:InitWebInstalled() [I] SQLite3 support is enabled 2024/12/17 12:24:07 routers/common/db.go:21:InitDBEngine() [I] Beginning ORM engine initialization. 2024/12/17 12:24:07 routers/common/db.go:28:InitDBEngine() [I] ORM engine initialization attempt #1/10... 2024/12/17 12:24:07 cmd/web.go:175:serveInstalled() [I] PING DATABASE sqlite3 2024/12/17 12:24:08 cmd/web.go:175:serveInstalled() [W] Table system_setting Column version db default is , struct default is 1 2024/12/17 12:24:08 cmd/web.go:175:serveInstalled() [W] Table project Column card_type db default is 0, struct default is 2024/12/17 12:24:08 cmd/web.go:175:serveInstalled() [W] Table project Column card_type db nullable is false, struct nullable is true 2024/12/17 12:24:08 cmd/web.go:175:serveInstalled() [W] Table hook_task has column repo_id but struct has not related field 2024/12/17 12:24:08 routers/init.go:136:InitWebInstalled() [I] ORM engine initialization successful! 2024/12/17 12:24:08 ...queue/workergroup.go:255:doRun() [D] Queue "push_update" starts running 2024/12/17 12:24:08 ...er/issues/indexer.go:156:func2() [I] PID 17: Initializing Issue Indexer: bleve 2024/12/17 12:24:08 ...queue/workergroup.go:255:doRun() [D] Queue "repo_stats_update" starts running 2024/12/17 12:24:08 ...xer/stats/indexer.go:38:populateRepoIndexer() [I] Populating the repo stats indexer with existing repositories 2024/12/17 12:24:08 ...queue/workergroup.go:255:doRun() [D] Queue "mirror" starts running 2024/12/17 12:24:08 ...queue/workergroup.go:255:doRun() [D] Queue "pr_patch_checker" starts running 2024/12/17 12:24:08 ...queue/workergroup.go:255:doRun() [D] Queue "webhook_sender" starts running 2024/12/17 12:24:08 ...queue/workergroup.go:255:doRun() [D] Queue "pr_auto_merge" starts running 2024/12/17 12:24:08 ...queue/workergroup.go:255:doRun() [D] Queue "task" starts running 2024/12/17 12:24:08 ...er/issues/indexer.go:185:func2() [D] Created Bleve Indexer 2024/12/17 12:24:08 ...er/issues/indexer.go:246:func3() [I] Issue Indexer Initialization took 53.665131ms 2024/12/17 12:24:08 ...queue/workergroup.go:255:doRun() [D] Queue "issue_indexer" starts running 2024/12/17 12:24:08 ...rvices/cron/tasks.go:147:RegisterTask() [D] Registering task: update_mirrors 2024/12/17 12:24:08 ...rvices/cron/tasks.go:147:RegisterTask() [D] Registering task: repo_health_check 2024/12/17 12:24:08 ...rvices/cron/tasks.go:147:RegisterTask() [D] Registering task: check_repo_stats 2024/12/17 12:24:08 ...rvices/cron/tasks.go:147:RegisterTask() [D] Registering task: archive_cleanup 2024/12/17 12:24:08 ...rvices/cron/tasks.go:147:RegisterTask() [D] Registering task: sync_external_users 2024/12/17 12:24:08 ...rvices/cron/tasks.go:147:RegisterTask() [D] Registering task: deleted_branches_cleanup 2024/12/17 12:24:08 ...rvices/cron/tasks.go:147:RegisterTask() [D] Registering task: update_migration_poster_id 2024/12/17 12:24:08 ...rvices/cron/tasks.go:147:RegisterTask() [D] Registering task: cleanup_hook_task_table 2024/12/17 12:24:08 ...rvices/cron/tasks.go:147:RegisterTask() [D] Registering task: cleanup_packages 2024/12/17 12:24:08 ...rvices/cron/tasks.go:147:RegisterTask() [D] Registering task: delete_inactive_accounts 2024/12/17 12:24:08 ...rvices/cron/tasks.go:147:RegisterTask() [D] Registering task: delete_repo_archives 2024/12/17 12:24:08 ...rvices/cron/tasks.go:147:RegisterTask() [D] Registering task: git_gc_repos 2024/12/17 12:24:08 ...rvices/cron/tasks.go:147:RegisterTask() [D] Registering task: resync_all_sshkeys 2024/12/17 12:24:08 ...rvices/cron/tasks.go:147:RegisterTask() [D] Registering task: resync_all_sshprincipals 2024/12/17 12:24:08 ...rvices/cron/tasks.go:147:RegisterTask() [D] Registering task: resync_all_hooks 2024/12/17 12:24:08 ...rvices/cron/tasks.go:147:RegisterTask() [D] Registering task: reinit_missing_repos 2024/12/17 12:24:08 ...rvices/cron/tasks.go:147:RegisterTask() [D] Registering task: delete_missing_repos 2024/12/17 12:24:08 ...rvices/cron/tasks.go:147:RegisterTask() [D] Registering task: delete_generated_repository_avatars 2024/12/17 12:24:08 ...rvices/cron/tasks.go:147:RegisterTask() [D] Registering task: delete_old_actions 2024/12/17 12:24:08 ...rvices/cron/tasks.go:147:RegisterTask() [D] Registering task: update_checker 2024/12/17 12:24:08 ...rvices/cron/tasks.go:147:RegisterTask() [D] Registering task: delete_old_system_notices 2024/12/17 12:24:08 ...rvices/cron/tasks.go:147:RegisterTask() [D] Registering task: gc_lfs 2024/12/17 12:24:08 ...ates/htmlrenderer.go:113:initHTMLRenderer() [D] Creating static HTML Renderer 2024/12/17 12:24:08 ...xer/stats/indexer.go:84:populateRepoIndexer() [I] Done (re)populating the repo stats indexer with existing repositories 2024/12/17 12:24:08 cmd/web.go:285:listen() [I] Listen: http://0.0.0.0:3000 2024/12/17 12:24:08 cmd/web.go:289:listen() [I] AppURL(ROOT_URL): https://xxx.xxx.xxx/ 2024/12/17 12:24:08 cmd/web.go:292:listen() [I] LFS server enabled 2024/12/17 12:24:08 ...s/graceful/server.go:62:NewServer() [I] Starting new Web server: tcp:0.0.0.0:3000 on PID: 17 2024/12/17 12:24:08 ...s/graceful/server.go:75:func1() [D] Starting server on tcp:0.0.0.0:3000 (PID: 17) 2024/12/17 12:24:08 ...web/auth/webauthn.go:36:WebAuthn() [E] UserSignIn: not in WebAuthn session 2024/12/17 12:24:08 .../context_response.go:74:HTML() [D] Template: status/500 2024/12/17 12:24:08 ...eb/routing/logger.go:102:func1() [I] router: completed GET /user/webauthn for 192.168.31.20:8521, 500 Internal Server Error in 2.2ms @ auth/webauthn.go:26(auth.WebAuthn) 2024/12/17 12:24:12 ...eb/routing/logger.go:102:func1() [I] router: completed GET / for 192.168.31.20:8521, 303 See Other in 0.7ms @ web/home.go:32(web.Home) 2024/12/17 12:24:12 ...eb/routing/logger.go:102:func1() [I] router: completed GET /explore for 192.168.31.20:8521, 303 See Other in 0.6ms @ auth/middleware.go:87(auth.VerifyAuthWithOptions) 2024/12/17 12:24:12 .../context_response.go:74:HTML() [D] Template: user/auth/signin 2024/12/17 12:24:12 ...eb/routing/logger.go:102:func1() [I] router: completed GET /user/login for 192.168.31.20:8521, 200 OK in 16.4ms @ auth/auth.go:141(auth.SignIn) 2024/12/17 12:24:19 .../context_response.go:74:HTML() [D] Template: user/auth/signin 2024/12/17 12:24:19 ...ers/web/auth/auth.go:206:SignInPost() [I] Failed authentication attempt for yowfung from 192.168.31.20:8521: user's password is invalid [uid: 1, name: yowfung] 2024/12/17 12:24:19 ...eb/routing/logger.go:102:func1() [I] router: completed POST /user/login for 192.168.31.20:8521, 200 OK in 69.2ms @ auth/auth.go:170(auth.SignInPost) 2024/12/17 12:24:24 ...eb/routing/logger.go:102:func1() [I] router: completed POST /user/login for 192.168.31.20:8521, 303 See Other in 68.2ms @ auth/auth.go:170(auth.SignInPost) 2024/12/17 12:24:24 ...web/auth/webauthn.go:36:WebAuthn() [E] UserSignIn: not in WebAuthn session 2024/12/17 12:24:24 .../context_response.go:74:HTML() [D] Template: status/500 2024/12/17 12:24:24 ...eb/routing/logger.go:102:func1() [I] router: completed GET /user/webauthn for 192.168.31.20:8521, 500 Internal Server Error in 1.0ms @ auth/webauthn.go:26(auth.WebAuthn) ``` ### Gitea Version 1.20.5 ### Can you reproduce the bug on the Gitea demo site? No ### Log Gist _No response_ ### Screenshots ![1734409997988-20241217_123122](https://github.com/user-attachments/assets/8fd5c1f7-71e3-46c2-b4f1-9c6a17f7e4db) ### Git Version 2.40.1 ### Operating System Linux (in Docker) ### How are you running Gitea? Install from the official Docker image. ### Database SQLite
GiteaMirror added the topic/authenticationtype/bugissue/needs-feedback labels 2025-11-02 10:54:38 -06:00
Author
Owner

@hiifong commented on GitHub (Dec 17, 2024):

SignInPost() [I] Failed authentication attempt for yowfung from 192.168.31.20:8521: user's password is invalid [uid: 1, name: yowfung]

@hiifong commented on GitHub (Dec 17, 2024): SignInPost() [I] Failed authentication attempt for yowfung from 192.168.31.20:8521: user's password is invalid [uid: 1, name: yowfung]
Author
Owner

@hiifong commented on GitHub (Dec 17, 2024):

SignInPost() [I] Failed authentication attempt for yowfung from 192.168.31.20:8521: user's password is invalid [uid: 1, name: yowfung]

Can you check your password is correct?

@hiifong commented on GitHub (Dec 17, 2024): > SignInPost() [I] Failed authentication attempt for yowfung from 192.168.31.20:8521: user's password is invalid [uid: 1, name: yowfung] Can you check your password is correct?
Author
Owner

@YowFung commented on GitHub (Dec 17, 2024):

SignInPost() [I] Failed authentication attempt for yowfung from 192.168.31.20:8521: user's password is invalid [uid: 1, name: yowfung]

Can you check your password is correct?

It was a mistake of mine. I corrected the password later and logged in again for a 500 error. When the password is wrong, there will be no 500 error, but "Password incorrect" will be displayed.

@YowFung commented on GitHub (Dec 17, 2024): > > SignInPost() [I] Failed authentication attempt for yowfung from 192.168.31.20:8521: user's password is invalid [uid: 1, name: yowfung] > > Can you check your password is correct? It was a mistake of mine. I corrected the password later and logged in again for a 500 error. When the password is wrong, there will be no 500 error, but "Password incorrect" will be displayed.
Author
Owner

@hiifong commented on GitHub (Dec 17, 2024):

Can you back up gitea, then upgrade gitea to the latest version and try again?

@hiifong commented on GitHub (Dec 17, 2024): Can you back up gitea, then upgrade gitea to the latest version and try again?
Author
Owner

@YowFung commented on GitHub (Dec 17, 2024):

Can you back up gitea, then upgrade gitea to the latest version and try again?

I tried deleting the docker container and then recreating it, but I still get the same problem.

@YowFung commented on GitHub (Dec 17, 2024): > Can you back up gitea, then upgrade gitea to the latest version and try again? I tried deleting the docker container and then recreating it, but I still get the same problem.
Author
Owner

@wxiaoguang commented on GitHub (Dec 17, 2024):

As a workaround, you could try delete the user's record in two_factor and webauthn_credential table.

(ps: just a guess, I have no idea about the root problem at the moment)

@wxiaoguang commented on GitHub (Dec 17, 2024): As a workaround, you could try delete the user's record in `two_factor` and `webauthn_credential` table. (ps: just a guess, I have no idea about the root problem at the moment)
Author
Owner

@wxiaoguang commented on GitHub (Jan 7, 2025):

@kemzeb @wxiaoguang @YowFung Can I work on this?

Have you got some ideas about the real problem?

@wxiaoguang commented on GitHub (Jan 7, 2025): > @kemzeb @wxiaoguang @YowFung Can I work on this? Have you got some ideas about the real problem?
Author
Owner

@techknowlogick commented on GitHub (Jan 7, 2025):

Oh, you're on version 1.20.5 could you try upgrading to the latest stable version (at the time of writing it is 1.22.6)?

@techknowlogick commented on GitHub (Jan 7, 2025): Oh, you're on version `1.20.5` could you try upgrading to the latest stable version (at the time of writing it is 1.22.6)?
Author
Owner

@wxiaoguang commented on GitHub (Jan 19, 2025):

Inactive for long time, feel free to provide more clues and reopen.

@wxiaoguang commented on GitHub (Jan 19, 2025): Inactive for long time, feel free to provide more clues and reopen.
Author
Owner

@YowFung commented on GitHub (Feb 9, 2025):

As a workaround, you could try delete the user's record in two_factor and webauthn_credential table.

(ps: just a guess, I have no idea about the root problem at the moment)

It works for me. Thank you!

@YowFung commented on GitHub (Feb 9, 2025): > As a workaround, you could try delete the user's record in `two_factor` and `webauthn_credential` table. > > (ps: just a guess, I have no idea about the root problem at the moment) It works for me. Thank you!
Sign in to join this conversation.
1 Participants
Notifications
Due Date
No due date set.
Dependencies

No dependencies set.

Reference: github-starred/gitea#13835