Gitea 1.14.0 not running as Windows service

[PaulBol]

• Gitea version (or commit ref): 1.14.0
• Git version: 2.18.0
• Operating system: Windows Server 2012 R2
• Database:
  • PostgreSQL
  • MySQL
  • MSSQL
  • SQLite
• Can you reproduce the bug at https://try.gitea.io:
  • Yes (provide example URL)
  • No
• Log gist:
  Log

  
  2021/04/13 18:23:56 ...dules/setting/log.go:333:newLogService() [I] Gitea Log Mode: File(File:debug)
  2021/04/13 18:23:56 ...dules/setting/log.go:250:generateNamedLogger() [I] Router Log: Console(console:debug)
  2021/04/13 18:23:56 ...les/setting/cache.go:73:newCacheService() [I] Cache Service Enabled
  2021/04/13 18:23:56 ...les/setting/cache.go:88:newCacheService() [I] Last Commit Cache Service Enabled
  2021/04/13 18:23:56 ...s/setting/session.go:77:newSessionService() [I] Session Service Enabled
  2021/04/13 18:23:56 ...es/setting/mailer.go:109:newMailService() [I] Mail Service Enabled
  2021/04/13 18:23:56 ...es/setting/mailer.go:120:newRegisterMailService() [I] Register Mail Service Enabled
  2021/04/13 18:23:56 ...es/setting/mailer.go:131:newNotifyMailService() [I] Notify Mail Service Enabled
  2021/04/13 18:23:56 ...s/storage/storage.go:158:initAttachments() [I] Initialising Attachment storage with type:
  2021/04/13 18:23:56 ...les/storage/local.go:47:NewLocalStorage() [I] Creating new Local Storage at C:\Gitea\data\attachments
  2021/04/13 18:23:56 ...s/storage/storage.go:152:initAvatars() [I] Initialising Avatar storage with type:
  2021/04/13 18:23:56 ...les/storage/local.go:47:NewLocalStorage() [I] Creating new Local Storage at C:\Gitea\data\avatars
  2021/04/13 18:23:56 ...s/storage/storage.go:170:initRepoAvatars() [I] Initialising Repository Avatar storage with type:
  2021/04/13 18:23:56 ...les/storage/local.go:47:NewLocalStorage() [I] Creating new Local Storage at C:\Gitea\data\repo-avatars
  2021/04/13 18:23:56 ...s/storage/storage.go:164:initLFS() [I] Initialising LFS storage with type:
  2021/04/13 18:23:56 ...les/storage/local.go:47:NewLocalStorage() [I] Creating new Local Storage at C:/Gitea/data/lfs
  2021/04/13 18:23:56 ...ueue_disk_channel.go:137:Run() [D] PersistableChannelQueue: push_update Starting
  2021/04/13 18:23:56 ...ue/queue_bytefifo.go:96:Run() [D] level: push_update-level Starting
  2021/04/13 18:23:56 ...ueue_disk_channel.go:137:Run() [D] PersistableChannelQueue: mail Starting
  2021/04/13 18:23:56 ...ue/queue_bytefifo.go:96:Run() [D] level: mail-level Starting
  2021/04/13 18:23:56 ...ueue_disk_channel.go:137:Run() [D] PersistableChannelQueue: notification-service Starting
  2021/04/13 18:23:56 ...ue/queue_bytefifo.go:96:Run() [D] level: notification-service-level Starting
  2021/04/13 18:23:56 routers/init.go:150:GlobalInit() [I] SQLite3 Supported
  2021/04/13 18:23:56 routers/init.go:68:initDBEngine() [I] Beginning ORM engine initialization.
  2021/04/13 18:23:56 routers/init.go:75:initDBEngine() [I] ORM engine initialization attempt Rename import paths: "github.com/gogits/gogs" -> "github.com/go-gitea/gitea" #1/10...
  2021/04/13 18:23:56 ...om/urfave/cli/app.go:524:HandleAction() [I] PING DATABASE mssql
  2021/04/13 18:23:56 ...rfave/cli/command.go:173:Run() [W] Table user Column keep_email_private db default is 0, struct default is
  2021/04/13 18:23:56 ...rfave/cli/command.go:173:Run() [W] Table user Column last_repo_visibility db default is 0, struct default is
  2021/04/13 18:23:56 ...rfave/cli/command.go:173:Run() [W] Table user Column is_active db default is 0, struct default is
  2021/04/13 18:23:56 ...rfave/cli/command.go:173:Run() [W] Table user Column is_admin db default is 0, struct default is
  2021/04/13 18:23:56 ...rfave/cli/command.go:173:Run() [W] Table user Column allow_git_hook db default is 0, struct default is
  2021/04/13 18:23:56 ...rfave/cli/command.go:173:Run() [W] Table user Column allow_import_local db default is 0, struct default is
  2021/04/13 18:23:56 ...rfave/cli/command.go:173:Run() [W] Table user Column allow_create_organization db default is 0, struct default is 1
  2021/04/13 18:23:56 ...rfave/cli/command.go:173:Run() [W] Table user Column use_custom_avatar db default is 0, struct default is
  2021/04/13 18:23:56 ...rfave/cli/command.go:173:Run() [W] Table user column theme db type is NVARCHAR(30), struct type is NVARCHAR(255)
  2021/04/13 18:23:56 ...rfave/cli/command.go:173:Run() [W] Table user Column keep_activity_private db default is , struct default is 0
  2021/04/13 18:23:56 ...rfave/cli/command.go:173:Run() [W] Table user Column keep_activity_private db nullable is true, struct nullable is false
  2021/04/13 18:23:56 ...rfave/cli/command.go:173:Run() [W] Table repository column description db type is NVARCHAR(255), struct type is NVARCHAR(MAX)
  2021/04/13 18:23:56 ...rfave/cli/command.go:173:Run() [W] Table repository column website db type is NVARCHAR(255), struct type is NVARCHAR(2048)
  2021/04/13 18:23:56 ...rfave/cli/command.go:173:Run() [W] Table repository Column original_service_type db default is 0, struct default is
  2021/04/13 18:23:56 ...rfave/cli/command.go:173:Run() [W] Table repository column original_url db type is NVARCHAR(255), struct type is NVARCHAR(2048)
  2021/04/13 18:23:56 ...rfave/cli/command.go:173:Run() [W] Table repository Column is_private db default is 0, struct default is
  2021/04/13 18:23:56 ...rfave/cli/command.go:173:Run() [W] Table repository Column is_mirror db default is 0, struct default is
  2021/04/13 18:23:56 ...rfave/cli/command.go:173:Run() [W] Table repository Column is_fsck_enabled db default is 0, struct default is 1
  2021/04/13 18:23:56 ...rfave/cli/command.go:173:Run() [W] Table upload column uuid db type is NVARCHAR(40), struct type is VARCHAR(40)
  2021/04/13 18:23:56 ...rfave/cli/command.go:173:Run() [W] Table issue Column is_closed db default is 0, struct default is
  2021/04/13 18:23:56 ...rfave/cli/command.go:173:Run() [W] Table issue Column is_pull db default is 0, struct default is
  2021/04/13 18:23:56 ...rfave/cli/command.go:173:Run() [W] Table pull_request Column has_merged db default is 0, struct default is
  2021/04/13 18:23:56 ...rfave/cli/command.go:173:Run() [W] Table comment Column removed_assignee db default is 0, struct default is
  2021/04/13 18:23:56 ...rfave/cli/command.go:173:Run() [W] Table comment Column invalidated db default is 0, struct default is
  2021/04/13 18:23:56 ...rfave/cli/command.go:173:Run() [W] Table comment has column old_assignee_id but struct has not related field
  2021/04/13 18:23:56 ...rfave/cli/command.go:173:Run() [W] Table attachment column uuid db type is NVARCHAR(40), struct type is VARCHAR(40)
  2021/04/13 18:23:56 ...rfave/cli/command.go:173:Run() [W] Table milestone Column is_closed db default is 0, struct default is
  2021/04/13 18:23:56 ...rfave/cli/command.go:173:Run() [W] Table mirror Column enable_prune db default is 0, struct default is 1
  2021/04/13 18:23:56 ...rfave/cli/command.go:173:Run() [W] Table webhook Column is_system_webhook db default is 0, struct default is
  2021/04/13 18:23:56 ...rfave/cli/command.go:173:Run() [W] Table webhook Column is_system_webhook db nullable is false, struct nullable is true
  2021/04/13 18:23:56 ...rfave/cli/command.go:173:Run() [W] Table webhook Column http_method db default is 'POST', struct default is
  2021/04/13 18:23:56 ...rfave/cli/command.go:173:Run() [W] Table webhook Column is_ssl db default is 0, struct default is
  2021/04/13 18:23:56 ...rfave/cli/command.go:173:Run() [W] Table webhook Column is_active db default is 0, struct default is
  2021/04/13 18:23:56 ...rfave/cli/command.go:173:Run() [W] Table hook_task Column is_ssl db default is 0, struct default is
  2021/04/13 18:23:56 ...rfave/cli/command.go:173:Run() [W] Table hook_task Column is_delivered db default is 0, struct default is
  2021/04/13 18:23:56 ...rfave/cli/command.go:173:Run() [W] Table hook_task Column is_succeed db default is 0, struct default is
  2021/04/13 18:23:56 ...rfave/cli/command.go:173:Run() [W] Table org_user Column is_public db default is 0, struct default is
  2021/04/13 18:23:56 ...rfave/cli/command.go:173:Run() [W] Table email_address Column is_activated db default is 0, struct default is
  2021/04/13 18:23:56 ...rfave/cli/command.go:173:Run() [W] Table issue_user Column is_read db default is 0, struct default is
  2021/04/13 18:23:56 ...rfave/cli/command.go:173:Run() [W] Table issue_user Column is_mentioned db default is 0, struct default is
  2021/04/13 18:23:56 ...rfave/cli/command.go:173:Run() [W] Table gpg_key Column can_sign db default is 0, struct default is
  2021/04/13 18:23:56 ...rfave/cli/command.go:173:Run() [W] Table gpg_key Column can_encrypt_comms db default is 0, struct default is
  2021/04/13 18:23:56 ...rfave/cli/command.go:173:Run() [W] Table gpg_key Column can_encrypt_storage db default is 0, struct default is
  2021/04/13 18:23:56 ...rfave/cli/command.go:173:Run() [W] Table gpg_key Column can_certify db default is 0, struct default is
  2021/04/13 18:23:56 ...rfave/cli/command.go:173:Run() [W] Table external_login_user column access_token db type is NVARCHAR(255), struct type is NVARCHAR(MAX)
  2021/04/13 18:23:56 ...rfave/cli/command.go:173:Run() [W] Table external_login_user column access_token_secret db type is NVARCHAR(255), struct type is NVARCHAR(MAX)
  2021/04/13 18:23:56 ...rfave/cli/command.go:173:Run() [W] Table external_login_user column refresh_token db type is NVARCHAR(255), struct type is NVARCHAR(MAX)
  2021/04/13 18:23:56 ...rfave/cli/command.go:173:Run() [W] Table protected_branch Column enable_whitelist db default is 0, struct default is
  2021/04/13 18:23:56 ...rfave/cli/command.go:173:Run() [W] Table issue_watch Column is_watching db default is 0, struct default is
  2021/04/13 18:23:56 ...rfave/cli/command.go:173:Run() [W] Table tracked_time Column time db nullable is true, struct nullable is false
  2021/04/13 18:23:56 ...rfave/cli/command.go:173:Run() [W] Table review column type db type is NVARCHAR(255), struct type is INT
  2021/04/13 18:23:56 ...rfave/cli/command.go:173:Run() [W] Table language_stat Column language db nullable is true, struct nullable is false
  2021/04/13 18:23:56 ...rfave/cli/command.go:173:Run() [W] Table session has column created_unix but struct has not related field
  2021/04/13 18:23:56 routers/init.go:155:GlobalInit() [I] ORM engine initialization successful!
  2021/04/13 18:23:56 ...odules/cron/tasks.go:120:RegisterTask() [D] Registering task: update_mirrors
  2021/04/13 18:23:56 ...odules/cron/tasks.go:120:RegisterTask() [D] Registering task: repo_health_check
  2021/04/13 18:23:56 ...odules/cron/tasks.go:120:RegisterTask() [D] Registering task: check_repo_stats
  2021/04/13 18:23:56 ...odules/cron/tasks.go:120:RegisterTask() [D] Registering task: archive_cleanup
  2021/04/13 18:23:56 ...odules/cron/tasks.go:120:RegisterTask() [D] Registering task: sync_external_users
  2021/04/13 18:23:56 ...odules/cron/tasks.go:120:RegisterTask() [D] Registering task: deleted_branches_cleanup
  2021/04/13 18:23:56 ...odules/cron/tasks.go:120:RegisterTask() [D] Registering task: update_migration_poster_id
  2021/04/13 18:23:56 ...odules/cron/tasks.go:120:RegisterTask() [D] Registering task: cleanup_hook_task_table
  2021/04/13 18:23:56 ...odules/cron/tasks.go:120:RegisterTask() [D] Registering task: delete_inactive_accounts
  2021/04/13 18:23:56 ...odules/cron/tasks.go:120:RegisterTask() [D] Registering task: delete_repo_archives
  2021/04/13 18:23:56 ...odules/cron/tasks.go:120:RegisterTask() [D] Registering task: git_gc_repos
  2021/04/13 18:23:56 ...odules/cron/tasks.go:120:RegisterTask() [D] Registering task: resync_all_sshkeys
  2021/04/13 18:23:56 ...odules/cron/tasks.go:120:RegisterTask() [D] Registering task: resync_all_sshprincipals
  2021/04/13 18:23:56 ...odules/cron/tasks.go:120:RegisterTask() [D] Registering task: resync_all_hooks
  2021/04/13 18:23:56 ...odules/cron/tasks.go:120:RegisterTask() [D] Registering task: reinit_missing_repos
  2021/04/13 18:23:56 ...odules/cron/tasks.go:120:RegisterTask() [D] Registering task: delete_missing_repos
  2021/04/13 18:23:56 ...odules/cron/tasks.go:120:RegisterTask() [D] Registering task: delete_generated_repository_avatars
  2021/04/13 18:23:57 ...er/issues/indexer.go:142:func2() [I] PID 5476: Initializing Issue Indexer: bleve
  2021/04/13 18:23:57 ...er/issues/indexer.go:171:func2() [D] Created Bleve Indexer
  2021/04/13 18:23:57 ...er/issues/indexer.go:221:func3() [I] Issue Indexer Initialization took 8.0033ms
  2021/04/13 18:23:57 ...ue/queue_bytefifo.go:96:Run() [D] level: issue_indexer Starting
  2021/04/13 18:23:57 ...ueue_disk_channel.go:162:Run() [D] PersistableChannelUniqueQueue: repo_stats_update Starting
  2021/04/13 18:23:57 ...ue/queue_bytefifo.go:96:Run() [D] unique-level: repo_stats_update-level Starting
  2021/04/13 18:23:57 ...xer/stats/indexer.go:38:populateRepoIndexer() [I] Populating the repo stats indexer with existing repositories
  2021/04/13 18:23:57 ...xer/stats/indexer.go:84:populateRepoIndexer() [I] Done (re)populating the repo stats indexer with existing repositories
  2021/04/13 18:23:57 ...ueue_disk_channel.go:162:Run() [D] PersistableChannelUniqueQueue: pr_patch_checker Starting
  2021/04/13 18:23:57 ...ue/queue_bytefifo.go:96:Run() [D] unique-level: pr_patch_checker-level Starting
  2021/04/13 18:23:57 ...ueue_disk_channel.go:137:Run() [D] PersistableChannelQueue: task Starting
  2021/04/13 18:23:57 ...ue/queue_bytefifo.go:96:Run() [D] level: task-level Starting
  2021/04/13 18:23:57 ...i/websspi_windows.go:87:New() [I] Credential handle expiry: 2185-07-21 00:34:33.712009116 +0100 CET
  2021/04/13 18:23:59 cmd/web.go:189:listen() [I] Listen: https://0.0.0.0:3000
  2021/04/13 18:23:59 cmd/web.go:192:listen() [I] LFS server enabled
  2021/04/13 18:23:59 ...s/graceful/server.go:55:NewServer() [I] Starting new Web server: tcp:0.0.0.0:3000 on PID: 5476
  2021/04/13 18:23:59 ...s/graceful/server.go:66:func1() [D] Starting server on tcp:0.0.0.0:3000 (PID: 5476)

Description

I'm running Gitea as a Windows service. Service GiteaSvc is launching C:\Gitea\gitea.exe web --config C:\Gitea\conf\app.ini. This was working fine until updating from version 1.13.4 to 1.14.0. Now attempting to run net start giteasvc results in

The service is not responding to the control function.

More help is available by typing NET HELPMSG 2186.

The Windows event logs show errors 7009 and 7000:

A timeout was reached (30000 milliseconds) while waiting for the GiteaSvc service to connect.

The GiteaSvc service failed to start due to the following error: The service did not respond to the start or control request in a timely fashion.

I captured debug logs but didn't notice anything that looks like an error. Running C:\Gitea\gitea.exe web --config C:\Gitea\conf\app.ini from a command line window still works fine.

Can anyone please help me to restore my Gitea installation? Happy to try out things or supply additional information.

[zeripath]

OK looks we need to add a trace level logger in. Can you adjust your logging to add a Trace level logger in as per https://docs.gitea.io/en-us/logging-configuration/#debugging-problems

i.e.

[log]
...
MODE=console, traceconsole
...

[log.traceconsole]
MODE = console
LEVEL = trace
EXPRESSION = modules/graceful

In particular I am looking for this to be logged:

gitea/modules/graceful/manager_windows.go

Line 83 in 55e159c

log.Trace("Not running a service ... using the debug SVC manager")

I suspect that for whatever reason Gitea is not detecting that it is running as a windows service.

[sdweiyu]

I had the same problem.

Gitea version (or commit ref): 1.14.0
Operating system: Windows Server 2019 v1809
Database: SQLite

Although the service starts with an error, the gitea process has been started and can also serve normally.

[zeripath]

@sdweiyu whilst I appreciate you want to say that this is a problem, I need the logs I have asked for. I cannot fix this without them.

It would also be helpful to know how you registered gitea with the SVC and whether there are any logs available there.

[PaulBol]

Although the service starts with an error, the gitea process has been started and can also serve normally.

This part is different for my installation. When net start returns the Gitea.exe process has terminated.

Anyway, I'll capture the trace level log and share it here like @zeripath suggested.

[PaulBol]

@zeripath - The log recorded with LEVEL = trace and EXPRESSION = modules/graceful does not show the line you pointed out:

2021/04/14 08:38:20 ...s/graceful/server.go:55:NewServer() [I] Starting new Web server: tcp:0.0.0.0:3000 on PID: 6248
2021/04/14 08:38:20 ...s/graceful/server.go:66:func1() [D] Starting server on tcp:0.0.0.0:3000 (PID: 6248)
2021/04/14 08:38:20 ...l/manager_windows.go:106:Execute() [T] Sending Running state to SVC
2021/04/14 08:38:20 ...l/manager_windows.go:114:Execute() [T] Started

I got one more without the EXPRESSION filter. Do you see a problem here?

Log

2021/04/14 08:42:05 ...dules/setting/log.go:333:newLogService() [I] Gitea Log Mode: Tracefile(File:trace)
2021/04/14 08:42:05 ...dules/setting/log.go:250:generateNamedLogger() [I] Router Log: Console(console:debug)
2021/04/14 08:42:05 ...les/setting/cache.go:73:newCacheService() [I] Cache Service Enabled
2021/04/14 08:42:05 ...les/setting/cache.go:88:newCacheService() [I] Last Commit Cache Service Enabled
2021/04/14 08:42:05 ...s/setting/session.go:77:newSessionService() [I] Session Service Enabled
2021/04/14 08:42:05 ...es/setting/mailer.go:109:newMailService() [I] Mail Service Enabled
2021/04/14 08:42:05 ...es/setting/mailer.go:120:newRegisterMailService() [I] Register Mail Service Enabled
2021/04/14 08:42:05 ...es/setting/mailer.go:131:newNotifyMailService() [I] Notify Mail Service Enabled
2021/04/14 08:42:05 ...s/storage/storage.go:158:initAttachments() [I] Initialising Attachment storage with type: 
2021/04/14 08:42:05 ...les/storage/local.go:47:NewLocalStorage() [I] Creating new Local Storage at C:\Gitea\data\attachments
2021/04/14 08:42:05 ...s/storage/storage.go:152:initAvatars() [I] Initialising Avatar storage with type: 
2021/04/14 08:42:05 ...les/storage/local.go:47:NewLocalStorage() [I] Creating new Local Storage at C:\Gitea\data\avatars
2021/04/14 08:42:05 ...s/storage/storage.go:170:initRepoAvatars() [I] Initialising Repository Avatar storage with type: 
2021/04/14 08:42:05 ...les/storage/local.go:47:NewLocalStorage() [I] Creating new Local Storage at C:\Gitea\data\repo-avatars
2021/04/14 08:42:05 ...s/storage/storage.go:164:initLFS() [I] Initialising LFS storage with type: 
2021/04/14 08:42:05 ...les/storage/local.go:47:NewLocalStorage() [I] Creating new Local Storage at C:/Gitea/data/lfs
2021/04/14 08:42:05 ...les/queue/manager.go:134:Add() [T] Queue Manager registered: push_update-channel (QID: 1)
2021/04/14 08:42:05 ...les/queue/manager.go:134:Add() [T] Queue Manager registered: push_update-level (QID: 2)
2021/04/14 08:42:05 ...les/queue/manager.go:134:Add() [T] Queue Manager registered: push_update (QID: 3)
2021/04/14 08:42:05 ...ueue_disk_channel.go:137:Run() [D] PersistableChannelQueue: push_update Starting
2021/04/14 08:42:05 ...les/queue/manager.go:134:Add() [T] Queue Manager registered: mail-channel (QID: 4)
2021/04/14 08:42:05 ...ueue_disk_channel.go:160:Run() [T] PersistableChannelQueue: push_update Waiting til closed
2021/04/14 08:42:05 ...ue/queue_bytefifo.go:96:Run() [D] level: push_update-level Starting
2021/04/14 08:42:05 .../queue/workerpool.go:213:commonRegisterWorkers() [T] WorkerPool: 1 (for push_update-channel) adding 1 workers with group id: 1
2021/04/14 08:42:05 ...ue/queue_bytefifo.go:104:Run() [T] level: push_update-level Waiting til closed
2021/04/14 08:42:05 .../queue/workerpool.go:213:commonRegisterWorkers() [T] WorkerPool: 2 (for push_update-level) adding 1 workers with group id: 1
2021/04/14 08:42:05 ...les/queue/manager.go:134:Add() [T] Queue Manager registered: mail-level (QID: 5)
2021/04/14 08:42:05 ...les/queue/manager.go:134:Add() [T] Queue Manager registered: mail (QID: 6)
2021/04/14 08:42:05 ...les/queue/manager.go:134:Add() [T] Queue Manager registered: notification-service-channel (QID: 7)
2021/04/14 08:42:05 ...ueue_disk_channel.go:137:Run() [D] PersistableChannelQueue: mail Starting
2021/04/14 08:42:05 ...ueue_disk_channel.go:160:Run() [T] PersistableChannelQueue: mail Waiting til closed
2021/04/14 08:42:05 .../queue/workerpool.go:213:commonRegisterWorkers() [T] WorkerPool: 4 (for mail-channel) adding 1 workers with group id: 1
2021/04/14 08:42:05 ...ue/queue_bytefifo.go:96:Run() [D] level: mail-level Starting
2021/04/14 08:42:05 ...ue/queue_bytefifo.go:104:Run() [T] level: mail-level Waiting til closed
2021/04/14 08:42:05 .../queue/workerpool.go:213:commonRegisterWorkers() [T] WorkerPool: 5 (for mail-level) adding 1 workers with group id: 1
2021/04/14 08:42:05 ...les/queue/manager.go:134:Add() [T] Queue Manager registered: notification-service-level (QID: 8)
2021/04/14 08:42:05 ...les/queue/manager.go:134:Add() [T] Queue Manager registered: notification-service (QID: 9)
2021/04/14 08:42:05 ...ueue_disk_channel.go:137:Run() [D] PersistableChannelQueue: notification-service Starting
2021/04/14 08:42:05 ...ueue_disk_channel.go:160:Run() [T] PersistableChannelQueue: notification-service Waiting til closed
2021/04/14 08:42:05 .../queue/workerpool.go:213:commonRegisterWorkers() [T] WorkerPool: 7 (for notification-service-channel) adding 1 workers with group id: 1
2021/04/14 08:42:05 ...ue/queue_bytefifo.go:96:Run() [D] level: notification-service-level Starting
2021/04/14 08:42:05 ...ue/queue_bytefifo.go:104:Run() [T] level: notification-service-level Waiting til closed
2021/04/14 08:42:05 .../queue/workerpool.go:213:commonRegisterWorkers() [T] WorkerPool: 8 (for notification-service-level) adding 1 workers with group id: 1
2021/04/14 08:42:05 routers/init.go:150:GlobalInit() [I] SQLite3 Supported
2021/04/14 08:42:05 routers/init.go:68:initDBEngine() [I] Beginning ORM engine initialization.
2021/04/14 08:42:05 routers/init.go:75:initDBEngine() [I] ORM engine initialization attempt #1/10...
2021/04/14 08:42:05 ...om/urfave/cli/app.go:524:HandleAction() [I] PING DATABASE mssql
2021/04/14 08:42:05 ...rfave/cli/command.go:173:Run() [W] Table user Column keep_email_private db default is 0, struct default is 
2021/04/14 08:42:05 ...rfave/cli/command.go:173:Run() [W] Table user Column last_repo_visibility db default is 0, struct default is 
2021/04/14 08:42:05 ...rfave/cli/command.go:173:Run() [W] Table user Column is_active db default is 0, struct default is 
2021/04/14 08:42:05 ...rfave/cli/command.go:173:Run() [W] Table user Column is_admin db default is 0, struct default is 
2021/04/14 08:42:05 ...rfave/cli/command.go:173:Run() [W] Table user Column allow_git_hook db default is 0, struct default is 
2021/04/14 08:42:05 ...rfave/cli/command.go:173:Run() [W] Table user Column allow_import_local db default is 0, struct default is 
2021/04/14 08:42:05 ...rfave/cli/command.go:173:Run() [W] Table user Column allow_create_organization db default is 0, struct default is 1
2021/04/14 08:42:05 ...rfave/cli/command.go:173:Run() [W] Table user Column use_custom_avatar db default is 0, struct default is 
2021/04/14 08:42:05 ...rfave/cli/command.go:173:Run() [W] Table user column theme db type is NVARCHAR(30), struct type is NVARCHAR(255)
2021/04/14 08:42:05 ...rfave/cli/command.go:173:Run() [W] Table user Column keep_activity_private db default is , struct default is 0
2021/04/14 08:42:05 ...rfave/cli/command.go:173:Run() [W] Table user Column keep_activity_private db nullable is true, struct nullable is false
2021/04/14 08:42:05 ...rfave/cli/command.go:173:Run() [W] Table repository column description db type is NVARCHAR(255), struct type is NVARCHAR(MAX)
2021/04/14 08:42:05 ...rfave/cli/command.go:173:Run() [W] Table repository column website db type is NVARCHAR(255), struct type is NVARCHAR(2048)
2021/04/14 08:42:05 ...rfave/cli/command.go:173:Run() [W] Table repository Column original_service_type db default is 0, struct default is 
2021/04/14 08:42:05 ...rfave/cli/command.go:173:Run() [W] Table repository column original_url db type is NVARCHAR(255), struct type is NVARCHAR(2048)
2021/04/14 08:42:05 ...rfave/cli/command.go:173:Run() [W] Table repository Column is_private db default is 0, struct default is 
2021/04/14 08:42:05 ...rfave/cli/command.go:173:Run() [W] Table repository Column is_mirror db default is 0, struct default is 
2021/04/14 08:42:05 ...rfave/cli/command.go:173:Run() [W] Table repository Column is_fsck_enabled db default is 0, struct default is 1
2021/04/14 08:42:05 ...rfave/cli/command.go:173:Run() [W] Table upload column uuid db type is NVARCHAR(40), struct type is VARCHAR(40)
2021/04/14 08:42:05 ...rfave/cli/command.go:173:Run() [W] Table issue Column is_closed db default is 0, struct default is 
2021/04/14 08:42:05 ...rfave/cli/command.go:173:Run() [W] Table issue Column is_pull db default is 0, struct default is 
2021/04/14 08:42:05 ...rfave/cli/command.go:173:Run() [W] Table pull_request Column has_merged db default is 0, struct default is 
2021/04/14 08:42:05 ...rfave/cli/command.go:173:Run() [W] Table comment Column removed_assignee db default is 0, struct default is 
2021/04/14 08:42:05 ...rfave/cli/command.go:173:Run() [W] Table comment Column invalidated db default is 0, struct default is 
2021/04/14 08:42:05 ...rfave/cli/command.go:173:Run() [W] Table comment has column old_assignee_id but struct has not related field
2021/04/14 08:42:05 ...rfave/cli/command.go:173:Run() [W] Table attachment column uuid db type is NVARCHAR(40), struct type is VARCHAR(40)
2021/04/14 08:42:06 ...rfave/cli/command.go:173:Run() [W] Table milestone Column is_closed db default is 0, struct default is 
2021/04/14 08:42:06 ...rfave/cli/command.go:173:Run() [W] Table mirror Column enable_prune db default is 0, struct default is 1
2021/04/14 08:42:06 ...rfave/cli/command.go:173:Run() [W] Table webhook Column is_system_webhook db default is 0, struct default is 
2021/04/14 08:42:06 ...rfave/cli/command.go:173:Run() [W] Table webhook Column is_system_webhook db nullable is false, struct nullable is true
2021/04/14 08:42:06 ...rfave/cli/command.go:173:Run() [W] Table webhook Column http_method db default is 'POST', struct default is 
2021/04/14 08:42:06 ...rfave/cli/command.go:173:Run() [W] Table webhook Column is_ssl db default is 0, struct default is 
2021/04/14 08:42:06 ...rfave/cli/command.go:173:Run() [W] Table webhook Column is_active db default is 0, struct default is 
2021/04/14 08:42:06 ...rfave/cli/command.go:173:Run() [W] Table hook_task Column is_ssl db default is 0, struct default is 
2021/04/14 08:42:06 ...rfave/cli/command.go:173:Run() [W] Table hook_task Column is_delivered db default is 0, struct default is 
2021/04/14 08:42:06 ...rfave/cli/command.go:173:Run() [W] Table hook_task Column is_succeed db default is 0, struct default is 
2021/04/14 08:42:06 ...rfave/cli/command.go:173:Run() [W] Table org_user Column is_public db default is 0, struct default is 
2021/04/14 08:42:06 ...rfave/cli/command.go:173:Run() [W] Table email_address Column is_activated db default is 0, struct default is 
2021/04/14 08:42:06 ...rfave/cli/command.go:173:Run() [W] Table issue_user Column is_read db default is 0, struct default is 
2021/04/14 08:42:06 ...rfave/cli/command.go:173:Run() [W] Table issue_user Column is_mentioned db default is 0, struct default is 
2021/04/14 08:42:06 ...rfave/cli/command.go:173:Run() [W] Table gpg_key Column can_sign db default is 0, struct default is 
2021/04/14 08:42:06 ...rfave/cli/command.go:173:Run() [W] Table gpg_key Column can_encrypt_comms db default is 0, struct default is 
2021/04/14 08:42:06 ...rfave/cli/command.go:173:Run() [W] Table gpg_key Column can_encrypt_storage db default is 0, struct default is 
2021/04/14 08:42:06 ...rfave/cli/command.go:173:Run() [W] Table gpg_key Column can_certify db default is 0, struct default is 
2021/04/14 08:42:06 ...rfave/cli/command.go:173:Run() [W] Table external_login_user column access_token db type is NVARCHAR(255), struct type is NVARCHAR(MAX)
2021/04/14 08:42:06 ...rfave/cli/command.go:173:Run() [W] Table external_login_user column access_token_secret db type is NVARCHAR(255), struct type is NVARCHAR(MAX)
2021/04/14 08:42:06 ...rfave/cli/command.go:173:Run() [W] Table external_login_user column refresh_token db type is NVARCHAR(255), struct type is NVARCHAR(MAX)
2021/04/14 08:42:06 ...rfave/cli/command.go:173:Run() [W] Table protected_branch Column enable_whitelist db default is 0, struct default is 
2021/04/14 08:42:06 ...rfave/cli/command.go:173:Run() [W] Table issue_watch Column is_watching db default is 0, struct default is 
2021/04/14 08:42:06 ...rfave/cli/command.go:173:Run() [W] Table tracked_time Column time db nullable is true, struct nullable is false
2021/04/14 08:42:06 ...rfave/cli/command.go:173:Run() [W] Table review column type db type is NVARCHAR(255), struct type is INT
2021/04/14 08:42:06 ...rfave/cli/command.go:173:Run() [W] Table language_stat Column language db nullable is true, struct nullable is false
2021/04/14 08:42:06 ...rfave/cli/command.go:173:Run() [W] Table session has column created_unix but struct has not related field
2021/04/14 08:42:06 routers/init.go:155:GlobalInit() [I] ORM engine initialization successful!
2021/04/14 08:42:06 ...odules/cron/tasks.go:120:RegisterTask() [D] Registering task: update_mirrors
2021/04/14 08:42:06 ...odules/cron/tasks.go:120:RegisterTask() [D] Registering task: repo_health_check
2021/04/14 08:42:06 ...odules/cron/tasks.go:120:RegisterTask() [D] Registering task: check_repo_stats
2021/04/14 08:42:06 ...odules/cron/tasks.go:120:RegisterTask() [D] Registering task: archive_cleanup
2021/04/14 08:42:06 ...odules/cron/tasks.go:120:RegisterTask() [D] Registering task: sync_external_users
2021/04/14 08:42:06 ...odules/cron/tasks.go:120:RegisterTask() [D] Registering task: deleted_branches_cleanup
2021/04/14 08:42:06 ...odules/cron/tasks.go:120:RegisterTask() [D] Registering task: update_migration_poster_id
2021/04/14 08:42:06 ...odules/cron/tasks.go:120:RegisterTask() [D] Registering task: cleanup_hook_task_table
2021/04/14 08:42:06 ...odules/cron/tasks.go:120:RegisterTask() [D] Registering task: delete_inactive_accounts
2021/04/14 08:42:06 ...odules/cron/tasks.go:120:RegisterTask() [D] Registering task: delete_repo_archives
2021/04/14 08:42:06 ...odules/cron/tasks.go:120:RegisterTask() [D] Registering task: git_gc_repos
2021/04/14 08:42:06 ...odules/cron/tasks.go:120:RegisterTask() [D] Registering task: resync_all_sshkeys
2021/04/14 08:42:06 ...odules/cron/tasks.go:120:RegisterTask() [D] Registering task: resync_all_sshprincipals
2021/04/14 08:42:06 ...odules/cron/tasks.go:120:RegisterTask() [D] Registering task: resync_all_hooks
2021/04/14 08:42:06 ...odules/cron/tasks.go:120:RegisterTask() [D] Registering task: reinit_missing_repos
2021/04/14 08:42:06 ...odules/cron/tasks.go:120:RegisterTask() [D] Registering task: delete_missing_repos
2021/04/14 08:42:06 ...odules/cron/tasks.go:120:RegisterTask() [D] Registering task: delete_generated_repository_avatars
2021/04/14 08:42:06 models/branches.go:610:RemoveOldDeletedBranches() [T] Doing: DeletedBranchesCleanup
2021/04/14 08:42:06 models/repo.go:1823:CheckRepoStats() [T] Doing: CheckRepoStats
2021/04/14 08:42:06 models/repo.go:1731:DeleteOldRepositoryArchives() [T] Doing: ArchiveCleanup
2021/04/14 08:42:06 ...les/queue/manager.go:134:Add() [T] Queue Manager registered: issue_indexer (QID: 10)
2021/04/14 08:42:06 ...les/queue/manager.go:134:Add() [T] Queue Manager registered: repo_stats_update-channel (QID: 11)
2021/04/14 08:42:06 ...er/issues/indexer.go:142:func2() [I] PID 5872: Initializing Issue Indexer: bleve
2021/04/14 08:42:06 ...les/queue/manager.go:134:Add() [T] Queue Manager registered: repo_stats_update-level (QID: 12)
2021/04/14 08:42:06 ...les/queue/manager.go:134:Add() [T] Queue Manager registered: repo_stats_update (QID: 13)
2021/04/14 08:42:06 ...les/queue/manager.go:134:Add() [T] Queue Manager registered: pr_patch_checker-channel (QID: 14)
2021/04/14 08:42:06 ...ueue_disk_channel.go:162:Run() [D] PersistableChannelUniqueQueue: repo_stats_update Starting
2021/04/14 08:42:06 ...xer/stats/indexer.go:38:populateRepoIndexer() [I] Populating the repo stats indexer with existing repositories
2021/04/14 08:42:06 ...ueue_disk_channel.go:192:Run() [T] PersistableChannelUniqueQueue: repo_stats_update Waiting til closed
2021/04/14 08:42:06 .../queue/workerpool.go:213:commonRegisterWorkers() [T] WorkerPool: 11 (for repo_stats_update-channel) adding 1 workers with group id: 1
2021/04/14 08:42:06 ...ue/queue_bytefifo.go:96:Run() [D] unique-level: repo_stats_update-level Starting
2021/04/14 08:42:06 ...er/issues/indexer.go:171:func2() [D] Created Bleve Indexer
2021/04/14 08:42:06 ...ue/queue_bytefifo.go:104:Run() [T] unique-level: repo_stats_update-level Waiting til closed
2021/04/14 08:42:06 .../queue/workerpool.go:213:commonRegisterWorkers() [T] WorkerPool: 12 (for repo_stats_update-level) adding 1 workers with group id: 1
2021/04/14 08:42:06 ...er/issues/indexer.go:221:func3() [I] Issue Indexer Initialization took 45.0762ms
2021/04/14 08:42:06 ...ue/queue_bytefifo.go:96:Run() [D] level: issue_indexer Starting
2021/04/14 08:42:06 ...ue/queue_bytefifo.go:104:Run() [T] level: issue_indexer Waiting til closed
2021/04/14 08:42:06 .../queue/workerpool.go:213:commonRegisterWorkers() [T] WorkerPool: 10 (for issue_indexer) adding 1 workers with group id: 1
2021/04/14 08:42:06 ...les/queue/manager.go:134:Add() [T] Queue Manager registered: pr_patch_checker-level (QID: 15)
2021/04/14 08:42:06 ...les/queue/manager.go:134:Add() [T] Queue Manager registered: pr_patch_checker (QID: 16)
2021/04/14 08:42:06 ...les/queue/manager.go:134:Add() [T] Queue Manager registered: task-channel (QID: 17)
2021/04/14 08:42:06 ...ueue_disk_channel.go:162:Run() [D] PersistableChannelUniqueQueue: pr_patch_checker Starting
2021/04/14 08:42:06 ...ueue_disk_channel.go:192:Run() [T] PersistableChannelUniqueQueue: pr_patch_checker Waiting til closed
2021/04/14 08:42:06 ...ue/queue_bytefifo.go:96:Run() [D] unique-level: pr_patch_checker-level Starting
2021/04/14 08:42:06 .../queue/workerpool.go:213:commonRegisterWorkers() [T] WorkerPool: 14 (for pr_patch_checker-channel) adding 1 workers with group id: 1
2021/04/14 08:42:06 ...ue/queue_bytefifo.go:104:Run() [T] unique-level: pr_patch_checker-level Waiting til closed
2021/04/14 08:42:06 .../queue/workerpool.go:213:commonRegisterWorkers() [T] WorkerPool: 15 (for pr_patch_checker-level) adding 1 workers with group id: 1
2021/04/14 08:42:06 ...xer/stats/indexer.go:84:populateRepoIndexer() [I] Done (re)populating the repo stats indexer with existing repositories
2021/04/14 08:42:06 models/repo.go:1740:DeleteOldRepositoryArchives() [T] Finished: ArchiveCleanup
2021/04/14 08:42:06 ...les/queue/manager.go:134:Add() [T] Queue Manager registered: task-level (QID: 18)
2021/04/14 08:42:06 ...les/queue/manager.go:134:Add() [T] Queue Manager registered: task (QID: 19)
2021/04/14 08:42:06 ...ueue_disk_channel.go:137:Run() [D] PersistableChannelQueue: task Starting
2021/04/14 08:42:06 ...ueue_disk_channel.go:160:Run() [T] PersistableChannelQueue: task Waiting til closed
2021/04/14 08:42:06 .../queue/workerpool.go:213:commonRegisterWorkers() [T] WorkerPool: 17 (for task-channel) adding 1 workers with group id: 1
2021/04/14 08:42:06 ...ue/queue_bytefifo.go:96:Run() [D] level: task-level Starting
2021/04/14 08:42:06 ...ue/queue_bytefifo.go:104:Run() [T] level: task-level Waiting til closed
2021/04/14 08:42:06 .../queue/workerpool.go:213:commonRegisterWorkers() [T] WorkerPool: 18 (for task-level) adding 1 workers with group id: 1
2021/04/14 08:42:06 ...i/websspi_windows.go:87:New() [I] Credential handle expiry: 2185-07-21 00:34:33.712009116 +0100 CET
2021/04/14 08:42:08 cmd/web.go:189:listen() [I] Listen: https://0.0.0.0:3000
2021/04/14 08:42:08 cmd/web.go:192:listen() [I] LFS server enabled
2021/04/14 08:42:08 ...s/graceful/server.go:55:NewServer() [I] Starting new Web server: tcp:0.0.0.0:3000 on PID: 5872
2021/04/14 08:42:08 ...s/graceful/server.go:66:func1() [D] Starting server on tcp:0.0.0.0:3000 (PID: 5872)
2021/04/14 08:42:08 ...l/manager_windows.go:106:Execute() [T] Sending Running state to SVC
2021/04/14 08:42:08 ...l/manager_windows.go:114:Execute() [T] Started
2021/04/14 08:42:09 ...s/context/context.go:675:1() [D] Session ID: a596f2f8eb226778
2021/04/14 08:42:09 ...s/context/context.go:676:1() [D] CSRF Token: d-TNAA2aXAmfZPlOlvDpBWAxveU6MTYxODM4MDM1MjY3OTQ3ODgwMA
2021/04/14 08:42:11 ...s/context/context.go:675:1() [D] Session ID: 7a00f25cbb12b58e
2021/04/14 08:42:11 ...s/context/context.go:676:1() [D] CSRF Token: lGTh2tvkxe0xmRKRcmuPhMXPi9U6MTYxODM4MTA1Mjk0Nzk2OTQwMA
2021/04/14 08:42:11 ...s/context/context.go:675:1() [D] Session ID: 14ce78768316e6b9
2021/04/14 08:42:11 ...s/context/context.go:676:1() [D] CSRF Token: Y6YSPgU6r6v_8LeCV8cibD6HTKM6MTYxODM3OTA0NTY3MDg0NTcwMA

[zeripath]

Ok well it looks that as far gitea is concerned it's connected to the SVC host...

Not logging that particular line is a good thing btw

[sdweiyu]

@zeripath I got the log.

using:

[log]
MODE      = file
LEVEL     = trace
EXPRESSION = modules/graceful
ROOT_PATH = C:/gitea/log2

gitea.log

2021/04/14 14:10:16 ...dules/setting/log.go:333:newLogService() [I] Gitea Log Mode: File(File:trace)
2021/04/14 14:10:16 ...dules/setting/log.go:250:generateNamedLogger() [I] Router Log: Console(console:trace)
2021/04/14 14:10:16 ...les/setting/cache.go:73:newCacheService() [I] Cache Service Enabled
2021/04/14 14:10:16 ...les/setting/cache.go:88:newCacheService() [I] Last Commit Cache Service Enabled
2021/04/14 14:10:16 ...s/setting/session.go:77:newSessionService() [I] Session Service Enabled
2021/04/14 14:10:16 ...es/setting/mailer.go:109:newMailService() [I] Mail Service Enabled
2021/04/14 14:10:16 ...s/storage/storage.go:158:initAttachments() [I] Initialising Attachment storage with type: 
2021/04/14 14:10:16 ...les/storage/local.go:47:NewLocalStorage() [I] Creating new Local Storage at C:\gitea\data\attachments
2021/04/14 14:10:16 ...s/storage/storage.go:152:initAvatars() [I] Initialising Avatar storage with type: 
2021/04/14 14:10:16 ...les/storage/local.go:47:NewLocalStorage() [I] Creating new Local Storage at C:\gitea\data\avatars
2021/04/14 14:10:16 ...s/storage/storage.go:170:initRepoAvatars() [I] Initialising Repository Avatar storage with type: 
2021/04/14 14:10:16 ...les/storage/local.go:47:NewLocalStorage() [I] Creating new Local Storage at C:\gitea\data\repo-avatars
2021/04/14 14:10:16 ...s/storage/storage.go:164:initLFS() [I] Initialising LFS storage with type: 
2021/04/14 14:10:16 ...les/storage/local.go:47:NewLocalStorage() [I] Creating new Local Storage at C:/gitea/data/lfs
2021/04/14 14:10:16 ...les/queue/manager.go:134:Add() [T] Queue Manager registered: push_update-channel (QID: 1)
2021/04/14 14:10:16 ...les/queue/manager.go:134:Add() [T] Queue Manager registered: push_update-level (QID: 2)
2021/04/14 14:10:16 ...les/queue/manager.go:134:Add() [T] Queue Manager registered: push_update (QID: 3)
2021/04/14 14:10:16 ...ueue_disk_channel.go:137:Run() [D] PersistableChannelQueue: push_update Starting
2021/04/14 14:10:16 ...les/queue/manager.go:134:Add() [T] Queue Manager registered: mail-channel (QID: 4)
2021/04/14 14:10:16 ...ueue_disk_channel.go:160:Run() [T] PersistableChannelQueue: push_update Waiting til closed
2021/04/14 14:10:16 .../queue/workerpool.go:213:commonRegisterWorkers() [T] WorkerPool: 1 (for push_update-channel) adding 1 workers with group id: 1
2021/04/14 14:10:16 ...ue/queue_bytefifo.go:96:Run() [D] level: push_update-level Starting
2021/04/14 14:10:16 ...ue/queue_bytefifo.go:104:Run() [T] level: push_update-level Waiting til closed
2021/04/14 14:10:16 .../queue/workerpool.go:213:commonRegisterWorkers() [T] WorkerPool: 2 (for push_update-level) adding 1 workers with group id: 1
2021/04/14 14:10:16 ...les/queue/manager.go:134:Add() [T] Queue Manager registered: mail-level (QID: 5)
2021/04/14 14:10:16 ...les/queue/manager.go:134:Add() [T] Queue Manager registered: mail (QID: 6)
2021/04/14 14:10:16 ...ueue_disk_channel.go:137:Run() [D] PersistableChannelQueue: mail Starting
2021/04/14 14:10:16 ...ueue_disk_channel.go:160:Run() [T] PersistableChannelQueue: mail Waiting til closed
2021/04/14 14:10:16 ...ue/queue_bytefifo.go:96:Run() [D] level: mail-level Starting
2021/04/14 14:10:16 ...ue/queue_bytefifo.go:104:Run() [T] level: mail-level Waiting til closed
2021/04/14 14:10:16 .../queue/workerpool.go:213:commonRegisterWorkers() [T] WorkerPool: 4 (for mail-channel) adding 1 workers with group id: 1
2021/04/14 14:10:16 .../queue/workerpool.go:213:commonRegisterWorkers() [T] WorkerPool: 5 (for mail-level) adding 1 workers with group id: 1
2021/04/14 14:10:16 ...les/queue/manager.go:134:Add() [T] Queue Manager registered: notification-service-channel (QID: 7)
2021/04/14 14:10:16 ...les/queue/manager.go:134:Add() [T] Queue Manager registered: notification-service-level (QID: 8)
2021/04/14 14:10:16 ...les/queue/manager.go:134:Add() [T] Queue Manager registered: notification-service (QID: 9)
2021/04/14 14:10:16 ...ueue_disk_channel.go:137:Run() [D] PersistableChannelQueue: notification-service Starting
2021/04/14 14:10:16 ...ueue_disk_channel.go:160:Run() [T] PersistableChannelQueue: notification-service Waiting til closed
2021/04/14 14:10:16 ...ue/queue_bytefifo.go:96:Run() [D] level: notification-service-level Starting
2021/04/14 14:10:16 .../queue/workerpool.go:213:commonRegisterWorkers() [T] WorkerPool: 7 (for notification-service-channel) adding 1 workers with group id: 1
2021/04/14 14:10:16 ...ue/queue_bytefifo.go:104:Run() [T] level: notification-service-level Waiting til closed
2021/04/14 14:10:16 .../queue/workerpool.go:213:commonRegisterWorkers() [T] WorkerPool: 8 (for notification-service-level) adding 1 workers with group id: 1
2021/04/14 14:10:16 routers/init.go:150:GlobalInit() [I] SQLite3 Supported
2021/04/14 14:10:16 routers/init.go:68:initDBEngine() [I] Beginning ORM engine initialization.
2021/04/14 14:10:16 routers/init.go:75:initDBEngine() [I] ORM engine initialization attempt #1/10...
2021/04/14 14:10:16 ...om/urfave/cli/app.go:524:HandleAction() [I] PING DATABASE sqlite3
2021/04/14 14:10:16 ...ations/migrations.go:357:Migrate() [I] [SQL] SELECT name FROM sqlite_master WHERE type='table' and name = ? [version]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT * FROM version LIMIT 0 []
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT * FROM version LIMIT 0 []
2021/04/14 14:10:16 ...rm.io/xorm/engine.go:1130:Get() [I] [SQL] SELECT `id`, `version` FROM `version` WHERE `id`=? LIMIT 1 [1]
2021/04/14 14:10:16 routers/init.go:154:GlobalInit() [I] [SQL] SELECT name FROM sqlite_master WHERE type='table' []
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [user]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [user]
2021/04/14 14:10:16 ...rfave/cli/command.go:173:Run() [W] Table user Column keep_activity_private db default is , struct default is 0
2021/04/14 14:10:16 ...rfave/cli/command.go:173:Run() [W] Table user Column keep_activity_private db nullable is true, struct nullable is false
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [public_key]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [public_key]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [access_token]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [access_token]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [repository]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [repository]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [deploy_key]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [deploy_key]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [collaboration]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [collaboration]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [access]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [access]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [upload]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [upload]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [watch]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [watch]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [star]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [star]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [follow]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [follow]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [action]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [action]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [issue]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [issue]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [pull_request]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [pull_request]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [comment]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [comment]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [attachment]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [attachment]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [label]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [label]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [issue_label]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [issue_label]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [milestone]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [milestone]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [mirror]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [mirror]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [release]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [release]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [login_source]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [login_source]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [webhook]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [webhook]
2021/04/14 14:10:16 ...rfave/cli/command.go:173:Run() [W] Table webhook Column is_system_webhook db default is 0, struct default is 
2021/04/14 14:10:16 ...rfave/cli/command.go:173:Run() [W] Table webhook Column is_system_webhook db nullable is false, struct nullable is true
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [hook_task]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [hook_task]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [team]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [team]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [org_user]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [org_user]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [team_user]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [team_user]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [team_repo] - 10.0232ms
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [team_repo]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [notice]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [notice]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [email_address]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [email_address]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [notification]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [notification]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [issue_user]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [issue_user]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [lfs_meta_object]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [lfs_meta_object]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [two_factor]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [two_factor]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [gpg_key]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [gpg_key]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [gpg_key_import]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [gpg_key_import]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [repo_unit]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [repo_unit]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [repo_redirect]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [repo_redirect]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [external_login_user]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [external_login_user]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [protected_branch]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [protected_branch]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [user_open_id]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [user_open_id]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [issue_watch]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [issue_watch]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [commit_status]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [commit_status]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [stopwatch]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [stopwatch]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [tracked_time]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [tracked_time]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [deleted_branch]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [deleted_branch]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [repo_indexer_status]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [repo_indexer_status]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [issue_dependency]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [issue_dependency]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [lfs_lock]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [lfs_lock]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [reaction]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [reaction]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [issue_assignees]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [issue_assignees]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [u2f_registration]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [u2f_registration]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [team_unit]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [team_unit]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [review]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [review]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [oauth2_application]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [oauth2_application]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [oauth2_authorization_code]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [oauth2_authorization_code]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [oauth2_grant]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [oauth2_grant]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [task]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [task]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [language_stat]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [language_stat]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [email_hash]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [email_hash]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [user_redirect]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [user_redirect]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [project]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [project]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [project_board]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [project_board]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [project_issue]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [project_issue]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [session]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [session]
2021/04/14 14:10:16 ...rfave/cli/command.go:173:Run() [W] Table session has column created_unix but struct has not related field
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [repo_transfer]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [repo_transfer]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [topic]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [topic]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [repo_topic]
2021/04/14 14:10:16 routers/init.go:76:initDBEngine() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [repo_topic]
2021/04/14 14:10:16 routers/init.go:155:GlobalInit() [I] ORM engine initialization successful!
2021/04/14 14:10:16 ...uth/oauth2/oauth2.go:49:Init() [I] [SQL] SELECT name FROM sqlite_master WHERE type='table' []
2021/04/14 14:10:16 ...rmstore/xormstore.go:110:NewOptions() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [oauth2_session]
2021/04/14 14:10:16 ...rmstore/xormstore.go:110:NewOptions() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [oauth2_session]
2021/04/14 14:10:16 models/oauth2.go:89:GetActiveOAuth2ProviderLoginSources() [I] [SQL] SELECT `id`, `type`, `name`, `is_actived`, `is_sync_enabled`, `cfg`, `created_unix`, `updated_unix` FROM `login_source` WHERE (is_actived = ? and type = ?) [true 6]
2021/04/14 14:10:16 ...odules/cron/tasks.go:120:RegisterTask() [D] Registering task: update_mirrors
2021/04/14 14:10:16 ...odules/cron/tasks.go:120:RegisterTask() [D] Registering task: repo_health_check
2021/04/14 14:10:16 ...odules/cron/tasks.go:120:RegisterTask() [D] Registering task: check_repo_stats
2021/04/14 14:10:16 ...odules/cron/tasks.go:120:RegisterTask() [D] Registering task: archive_cleanup
2021/04/14 14:10:16 ...odules/cron/tasks.go:120:RegisterTask() [D] Registering task: sync_external_users
2021/04/14 14:10:16 ...odules/cron/tasks.go:120:RegisterTask() [D] Registering task: deleted_branches_cleanup
2021/04/14 14:10:16 ...odules/cron/tasks.go:120:RegisterTask() [D] Registering task: update_migration_poster_id
2021/04/14 14:10:16 ...odules/cron/tasks.go:120:RegisterTask() [D] Registering task: cleanup_hook_task_table
2021/04/14 14:10:16 ...odules/cron/tasks.go:120:RegisterTask() [D] Registering task: delete_inactive_accounts
2021/04/14 14:10:16 ...odules/cron/tasks.go:120:RegisterTask() [D] Registering task: delete_repo_archives
2021/04/14 14:10:16 ...odules/cron/tasks.go:120:RegisterTask() [D] Registering task: git_gc_repos
2021/04/14 14:10:16 ...odules/cron/tasks.go:120:RegisterTask() [D] Registering task: resync_all_sshkeys
2021/04/14 14:10:16 ...odules/cron/tasks.go:120:RegisterTask() [D] Registering task: resync_all_sshprincipals
2021/04/14 14:10:16 ...odules/cron/tasks.go:120:RegisterTask() [D] Registering task: resync_all_hooks
2021/04/14 14:10:16 ...odules/cron/tasks.go:120:RegisterTask() [D] Registering task: reinit_missing_repos
2021/04/14 14:10:16 ...odules/cron/tasks.go:120:RegisterTask() [D] Registering task: delete_missing_repos
2021/04/14 14:10:16 ...odules/cron/tasks.go:120:RegisterTask() [D] Registering task: delete_generated_repository_avatars
2021/04/14 14:10:16 models/branches.go:610:RemoveOldDeletedBranches() [T] Doing: DeletedBranchesCleanup
2021/04/14 14:10:16 models/repo.go:1731:DeleteOldRepositoryArchives() [T] Doing: ArchiveCleanup
2021/04/14 14:10:16 models/repo.go:1823:CheckRepoStats() [T] Doing: CheckRepoStats
2021/04/14 14:10:16 ...odules/cron/tasks.go:88:func2() [I] [SQL] DELETE FROM `deleted_branch` WHERE (deleted_unix < ?) [1618294216]
2021/04/14 14:10:16 models/repo.go:1733:DeleteOldRepositoryArchives() [I] [SQL] SELECT `id`, `owner_id`, `owner_name`, `lower_name`, `name`, `description`, `website`, `original_service_type`, `original_url`, `default_branch`, `num_watches`, `num_stars`, `num_forks`, `num_issues`, `num_closed_issues`, `num_pulls`, `num_closed_pulls`, `num_milestones`, `num_closed_milestones`, `num_projects`, `num_closed_projects`, `is_private`, `is_empty`, `is_archived`, `is_mirror`, `status`, `is_fork`, `fork_id`, `is_template`, `template_id`, `size`, `is_fsck_enabled`, `close_issues_via_commit_in_any_branch`, `topics`, `trust_model`, `avatar`, `created_unix`, `updated_unix` FROM `repository` WHERE (id > 0) []
2021/04/14 14:10:16 models/admin.go:53:createNotice() [I] [SQL] INSERT INTO `notice` (`type`,`description`,`created_unix`) VALUES (?,?,?) [2 Cron: Clean-up deleted branches has finished 1618380616] - 10.5124ms
2021/04/14 14:10:16 ...ternal_login_user.go:162:FindExternalUsersByProvider() [I] [SQL] SELECT `external_id`, `user_id`, `login_source_id`, `raw_data`, `provider`, `email`, `name`, `first_name`, `last_name`, `nick_name`, `description`, `avatar_url`, `location`, `access_token`, `access_token_secret`, `refresh_token`, `expires_at` FROM `external_login_user` WHERE provider=? ORDER BY login_source_id ASC, external_id ASC LIMIT 100 [github] - 10.5124ms
2021/04/14 14:10:16 ...ternal_login_user.go:162:FindExternalUsersByProvider() [I] [SQL] SELECT `external_id`, `user_id`, `login_source_id`, `raw_data`, `provider`, `email`, `name`, `first_name`, `last_name`, `nick_name`, `description`, `avatar_url`, `location`, `access_token`, `access_token_secret`, `refresh_token`, `expires_at` FROM `external_login_user` WHERE provider=? ORDER BY login_source_id ASC, external_id ASC LIMIT 100 [gitlab]
2021/04/14 14:10:16 ...ternal_login_user.go:162:FindExternalUsersByProvider() [I] [SQL] SELECT `external_id`, `user_id`, `login_source_id`, `raw_data`, `provider`, `email`, `name`, `first_name`, `last_name`, `nick_name`, `description`, `avatar_url`, `location`, `access_token`, `access_token_secret`, `refresh_token`, `expires_at` FROM `external_login_user` WHERE provider=? ORDER BY login_source_id ASC, external_id ASC LIMIT 100 [gitea]
2021/04/14 14:10:16 ...ternal_login_user.go:162:FindExternalUsersByProvider() [I] [SQL] SELECT `external_id`, `user_id`, `login_source_id`, `raw_data`, `provider`, `email`, `name`, `first_name`, `last_name`, `nick_name`, `description`, `avatar_url`, `location`, `access_token`, `access_token_secret`, `refresh_token`, `expires_at` FROM `external_login_user` WHERE provider=? ORDER BY login_source_id ASC, external_id ASC LIMIT 100 [gogs]
2021/04/14 14:10:16 models/admin.go:53:createNotice() [I] [SQL] INSERT INTO `notice` (`type`,`description`,`created_unix`) VALUES (?,?,?) [2 Cron: Update migration poster IDs has finished 1618380616] - 10.1328ms
2021/04/14 14:10:16 models/repo.go:1800:repoStatsCheck() [I] [SQL] SELECT repo.id FROM `repository` repo WHERE repo.num_watches!=(SELECT COUNT(*) FROM `watch` WHERE repo_id=repo.id AND mode<>2) [] - 20.6452ms
2021/04/14 14:10:16 models/repo.go:1800:repoStatsCheck() [I] [SQL] SELECT repo.id FROM `repository` repo WHERE repo.num_stars!=(SELECT COUNT(*) FROM `star` WHERE repo_id=repo.id) []
2021/04/14 14:10:16 models/repo.go:1800:repoStatsCheck() [I] [SQL] SELECT label.id FROM `label` WHERE label.num_issues!=(SELECT COUNT(*) FROM `issue_label` WHERE label_id=label.id) []
2021/04/14 14:10:16 models/repo.go:1800:repoStatsCheck() [I] [SQL] SELECT `user`.id FROM `user` WHERE `user`.num_repos!=(SELECT COUNT(*) FROM `repository` WHERE owner_id=`user`.id) []
2021/04/14 14:10:16 models/repo.go:1800:repoStatsCheck() [I] [SQL] SELECT `issue`.id FROM `issue` WHERE `issue`.num_comments!=(SELECT COUNT(*) FROM `comment` WHERE issue_id=`issue`.id AND type=0) []
2021/04/14 14:10:16 models/repo.go:1869:CheckRepoStats() [I] [SQL] SELECT repo.id FROM `repository` repo WHERE repo.num_closed_issues!=(SELECT COUNT(*) FROM `issue` WHERE repo_id=repo.id AND is_closed=? AND is_pull=?) [true false]
2021/04/14 14:10:16 models/repo.go:1892:CheckRepoStats() [I] [SQL] SELECT repo.id FROM `repository` repo WHERE repo.num_closed_pulls!=(SELECT COUNT(*) FROM `issue` WHERE repo_id=repo.id AND is_closed=? AND is_pull=?) [true true]
2021/04/14 14:10:16 models/repo.go:1915:CheckRepoStats() [I] [SQL] SELECT repo.id FROM `repository` repo WHERE repo.num_forks!=(SELECT COUNT(*) FROM `repository` WHERE fork_id=repo.id) [] - 231µs
2021/04/14 14:10:16 models/admin.go:53:createNotice() [I] [SQL] INSERT INTO `notice` (`type`,`description`,`created_unix`) VALUES (?,?,?) [2 Cron: Check all repository statistics has finished 1618380616] - 4.3511ms
2021/04/14 14:10:16 models/repo.go:1740:DeleteOldRepositoryArchives() [T] Finished: ArchiveCleanup
2021/04/14 14:10:16 ...les/queue/manager.go:134:Add() [T] Queue Manager registered: issue_indexer (QID: 10)
2021/04/14 14:10:16 ...er/issues/indexer.go:142:func2() [I] PID 4452: Initializing Issue Indexer: bleve
2021/04/14 14:10:16 ...les/queue/manager.go:134:Add() [T] Queue Manager registered: repo_stats_update-channel (QID: 11)
2021/04/14 14:10:16 models/admin.go:53:createNotice() [I] [SQL] INSERT INTO `notice` (`type`,`description`,`created_unix`) VALUES (?,?,?) [2 Cron: Delete old repository archives has finished 1618380616] - 5.6545ms
2021/04/14 14:10:16 ...er/issues/indexer.go:171:func2() [D] Created Bleve Indexer
2021/04/14 14:10:16 ...er/issues/indexer.go:221:func3() [I] Issue Indexer Initialization took 14.3597ms
2021/04/14 14:10:16 ...ue/queue_bytefifo.go:96:Run() [D] level: issue_indexer Starting
2021/04/14 14:10:16 ...ue/queue_bytefifo.go:104:Run() [T] level: issue_indexer Waiting til closed
2021/04/14 14:10:16 .../queue/workerpool.go:213:commonRegisterWorkers() [T] WorkerPool: 10 (for issue_indexer) adding 1 workers with group id: 1
2021/04/14 14:10:16 ...les/queue/manager.go:134:Add() [T] Queue Manager registered: repo_stats_update-level (QID: 12)
2021/04/14 14:10:16 ...les/queue/manager.go:134:Add() [T] Queue Manager registered: repo_stats_update (QID: 13)
2021/04/14 14:10:16 ...xer/stats/indexer.go:38:populateRepoIndexer() [I] Populating the repo stats indexer with existing repositories
2021/04/14 14:10:16 ...ueue_disk_channel.go:162:Run() [D] PersistableChannelUniqueQueue: repo_stats_update Starting
2021/04/14 14:10:16 ...ueue_disk_channel.go:192:Run() [T] PersistableChannelUniqueQueue: repo_stats_update Waiting til closed
2021/04/14 14:10:16 .../queue/workerpool.go:213:commonRegisterWorkers() [T] WorkerPool: 11 (for repo_stats_update-channel) adding 1 workers with group id: 1
2021/04/14 14:10:16 ...ue/queue_bytefifo.go:96:Run() [D] unique-level: repo_stats_update-level Starting
2021/04/14 14:10:16 ...les/queue/manager.go:134:Add() [T] Queue Manager registered: pr_patch_checker-channel (QID: 14)
2021/04/14 14:10:16 .../runtime/asm_amd64.s:1371:goexit() [I] [SQL] SELECT * FROM `repository`  LIMIT 1 [] - 503.1µs
2021/04/14 14:10:16 ...ue/queue_bytefifo.go:104:Run() [T] unique-level: repo_stats_update-level Waiting til closed
2021/04/14 14:10:16 .../queue/workerpool.go:213:commonRegisterWorkers() [T] WorkerPool: 12 (for repo_stats_update-level) adding 1 workers with group id: 1
2021/04/14 14:10:16 models/webhook.go:753:FindUndeliveredHookTasks() [I] [SQL] SELECT `id`, `repo_id`, `hook_id`, `uuid`, `typ`, `url`, `signature`, `payload_content`, `http_method`, `content_type`, `event_type`, `is_ssl`, `is_delivered`, `delivered`, `is_succeed`, `request_content`, `response_content` FROM `hook_task` WHERE (is_delivered=?) [false]
2021/04/14 14:10:16 models/models.go:361:GetMaxID() [I] [SQL] SELECT MAX(id) FROM `repository` LIMIT 1 []
2021/04/14 14:10:16 ...dels/repo_indexer.go:55:GetUnindexedRepos() [I] [SQL] SELECT `repository`.`id` FROM `repository` LEFT OUTER JOIN `repo_indexer_status` ON repository.id = repo_indexer_status.repo_id AND repo_indexer_status.indexer_type = ? WHERE repo_indexer_status.id IS NULL AND repository.is_empty=? AND repository.id<=? ORDER BY `repository`.`id` DESC LIMIT 50 [1 false 89]
2021/04/14 14:10:16 ...xer/stats/indexer.go:84:populateRepoIndexer() [I] Done (re)populating the repo stats indexer with existing repositories
2021/04/14 14:10:16 ...les/queue/manager.go:134:Add() [T] Queue Manager registered: pr_patch_checker-level (QID: 15)
2021/04/14 14:10:16 ...les/queue/manager.go:134:Add() [T] Queue Manager registered: pr_patch_checker (QID: 16)
2021/04/14 14:10:16 ...ueue_disk_channel.go:162:Run() [D] PersistableChannelUniqueQueue: pr_patch_checker Starting
2021/04/14 14:10:16 ...ueue_disk_channel.go:192:Run() [T] PersistableChannelUniqueQueue: pr_patch_checker Waiting til closed
2021/04/14 14:10:16 ...ue/queue_bytefifo.go:96:Run() [D] unique-level: pr_patch_checker-level Starting
2021/04/14 14:10:16 ...ue/queue_bytefifo.go:104:Run() [T] unique-level: pr_patch_checker-level Waiting til closed
2021/04/14 14:10:16 ...les/queue/manager.go:134:Add() [T] Queue Manager registered: task-channel (QID: 17)
2021/04/14 14:10:16 .../queue/workerpool.go:213:commonRegisterWorkers() [T] WorkerPool: 14 (for pr_patch_checker-channel) adding 1 workers with group id: 1
2021/04/14 14:10:16 .../queue/workerpool.go:213:commonRegisterWorkers() [T] WorkerPool: 15 (for pr_patch_checker-level) adding 1 workers with group id: 1
2021/04/14 14:10:16 models/pull_list.go:77:GetPullRequestIDsByCheckStatus() [I] [SQL] SELECT `pull_request`.`id` FROM `pull_request` WHERE (status=?) [1]
2021/04/14 14:10:16 ...les/queue/manager.go:134:Add() [T] Queue Manager registered: task-level (QID: 18)
2021/04/14 14:10:16 ...les/queue/manager.go:134:Add() [T] Queue Manager registered: task (QID: 19)
2021/04/14 14:10:16 ...ueue_disk_channel.go:137:Run() [D] PersistableChannelQueue: task Starting
2021/04/14 14:10:16 ...ueue_disk_channel.go:160:Run() [T] PersistableChannelQueue: task Waiting til closed
2021/04/14 14:10:16 ...ue/queue_bytefifo.go:96:Run() [D] level: task-level Starting
2021/04/14 14:10:16 ...ue/queue_bytefifo.go:104:Run() [T] level: task-level Waiting til closed
2021/04/14 14:10:16 .../queue/workerpool.go:213:commonRegisterWorkers() [T] WorkerPool: 17 (for task-channel) adding 1 workers with group id: 1
2021/04/14 14:10:16 .../queue/workerpool.go:213:commonRegisterWorkers() [T] WorkerPool: 18 (for task-level) adding 1 workers with group id: 1
2021/04/14 14:10:16 ...i/websspi_windows.go:87:New() [I] Credential handle expiry: 2185-07-21 07:34:33.712009116 +0800 CST
2021/04/14 14:10:16 cmd/web.go:189:listen() [I] Listen: http://0.0.0.0:80
2021/04/14 14:10:16 cmd/web.go:192:listen() [I] LFS server enabled
2021/04/14 14:10:16 ...s/graceful/server.go:55:NewServer() [I] Starting new Web server: tcp:0.0.0.0:80 on PID: 4452
2021/04/14 14:10:16 ...l/manager_windows.go:106:Execute() [T] Sending Running state to SVC
2021/04/14 14:10:16 ...s/graceful/server.go:66:func1() [D] Starting server on tcp:0.0.0.0:80 (PID: 4452)
2021/04/14 14:10:16 ...l/manager_windows.go:114:Execute() [T] Started

I use the command sc.exe create gitea ..... to create the service as the docs
gitea is updated from 1.14.0-rc2 to 1.14.0,
When starting the service from services.msc，there was an Error 1053: The service did not respond to the start or control request in a timely fashion.
When staring the servie from cmd net start gitea, there was an error: The service is not responding to the control function.

[zeripath]

Both of your logs indicate that gitea is connected to the SVC host and has sent the started state. So I'm not sure what's going on here.

Do you have logs when the SVC host claims that gitea hasn't started up?

It would also be useful to explicitly see an interrogate event, so after that startup message is sent with this trace logger running - is it possible to send an interrogate event? sc interrogate gitea and see if the SVC sent interrogate trace log is emitted.

Maybe you need to set a STARTUP_TIMEOUT but the service is sending the startup status pretty rapidly.

[zeripath]

Perhaps we need to be querying the interrogate whilst we are starting up and there's an out of date interrogate on the stack that has the wrong state? The go SVC docs for interrogate are weird and suggest that we should just send back the state the interrogate sent - I suspect that this is incorrect and we should be sending back out current state but this code hasn't changed so I don't understand why it should suddenly cause a problem.

[PaulBol]

I seem to have found a solution, I had previously used a dedicated user account gitea_user to run the service. app.config included RUN_USER = gitea_user and the service had the log on account configured as .\gitea_user.

Looks like this configuration is not supported anymore. https://docs.gitea.io/en-us/windows-service/ now suggests to use COMPUTERNAME$. When I configured RUN_USER = COMPUTERNAME$ and let the service use Local System, Gitea is working normally.

[PaulBol]

@sdweiyu - I observed the same behavior that you reported when I had the service configured to run as Local System but still had set RUN_USER = gitea_user. What I mean is that net start would respond with The service is not responding to the control function. and the Gitea.exe process kept running.

[PaulBol]

@zeripath - Just read your posts, thanks for the input. Is it still worth trying sc interrogate gitea and playing around with STARTUP_TIMEOUT or should I just accept that the old user setup is not supported anymore and should be updated as described above?

[zeripath]

Ok so I don't understand why the run_user thing is needed - the person that changed that documentation didn't change the code so it's perhaps related to how they've suggested the service be started?

If however changing the run user works and that's what the docs suggest then ... I guess we can close.

[zeripath]

Feel free to check out the interrogate thing tho and try to work out why the old user thing isn't working

[PaulBol]

sc interrogate gitea consistently responded with [SC] ControlService FAILED 1062: The service has not been started. while net start gitea was running and afterwards.

Maybe interacting with the service manager has changed in a way that now requires additional privileges? I guess running the service with an admin account instead of a regular user account could tell. However, if this setup isn't recommended and not mentioned in the documentation I don't see much sense in trying to get it to work.

Feel free to close the issue unless @sdweiyu has additional insights or concerns. Thanks again for the quick and helpful response! 👏