Server not listening on defined port after upgrade from 1.11.1 to 1.11.2

[joshsziegler]

• Gitea version (or commit ref): 1.11.2
• Git version: 2.17.1
• Operating system: Ubuntu 18.04.4
• Database (use [x]):
  • PostgreSQL
  • MySQL
  • MSSQL
  • SQLite
• Can you reproduce the bug at https://try.gitea.io:
  • Yes (provide example URL)
  • No
  • Not relevant
• Log gist:

# This is from the upgrade
$ ./update-gitea.sh 1.11.2
Updating gitea to version: '1.11.2'
+ wget -O gitea http://dl.gitea.io/gitea/1.11.2/gitea-1.11.2-linux-amd64
--2020-03-06 14:49:11--  http://dl.gitea.io/gitea/1.11.2/gitea-1.11.2-linux-amd64
Resolving dl.gitea.io (dl.gitea.io)... 104.27.143.155, 104.27.142.155, 2606:4700:3033::681b:8f9b, ...
Connecting to dl.gitea.io (dl.gitea.io)|104.27.143.155|:80... connected.
HTTP request sent, awaiting response... 302 Found
Location: https://dl.gitea.io:443/gitea/1.11.2/gitea-1.11.2-linux-amd64 [following]
--2020-03-06 14:49:11--  https://dl.gitea.io/gitea/1.11.2/gitea-1.11.2-linux-amd64
Connecting to dl.gitea.io (dl.gitea.io)|104.27.143.155|:443... connected.
HTTP request sent, awaiting response... 200 OK
Length: 81229448 (77M) [application/octet-stream]
Saving to: ‘gitea’

gitea                                                100%[=====================================================================================================================>]  77.47M  3.82MB/s    in 35s

2020-03-06 14:49:47 (2.20 MB/s) - ‘gitea’ saved [81229448/81229448]

+ chmod +x gitea
+ sudo chown gitea:gitea gitea
+ sudo mv gitea /srv/gitea/gitea-v1.11.2
+ sudo systemctl stop gitea
+ cd /srv/gitea
+ sudo ln -sf gitea-v1.11.2 gitea
+ sudo systemctl start gitea
+ sudo systemctl status gitea
● gitea.service - Gitea
   Loaded: loaded (/etc/systemd/system/gitea.service; enabled; vendor preset: enabled)
   Active: active (running) since Fri 2020-03-06 14:49:48 EST; 22ms ago
 Main PID: 66761 (gitea)
    Tasks: 6 (limit: 11059)
   Memory: 5.6M
      CPU: 15ms
   CGroup: /system.slice/gitea.service
           └─66761 /srv/gitea/gitea web

Mar 06 14:49:48 srv-1 systemd[1]: Started Gitea.
# This is where I notice the issue. Systemctl says it's running, and the Git logs looks fine:
$ sudo journalctl -u gitea
Mar 06 14:35:06 srv-1 gitea[4263]: [Macaron] 2020-03-06 14:35:06: Completed GET /serviceworker.js 200 OK in 1.96247ms
Mar 06 14:49:47 srv-1 systemd[1]: Stopping Gitea...
Mar 06 14:49:48 srv-1 systemd[1]: Stopped Gitea.
Mar 06 14:49:48 srv-1 systemd[1]: gitea.service: Consumed 3h 43min 1.604s CPU time
Mar 06 14:49:48 srv-1 systemd[1]: Started Gitea.
# Ok, but the website isn't responding. I then look at the following:
$ sudo netstat -nltp | grep git
$
# It's not listening on the correct port (9090 as defined in my config) which was working before this in 1.11.1

Description

I try to keep updated on the latest point releases. Typically this is smooth, but today it 1.11.2 didn't come up on the correct port (or apparently any port). The process was running and the logs don't show any errors. Not sure what is going on.

[guillep2k]

It would be useful if you could check the logs from Gitea around that time.

Does the UI start properly? What version does it show in the footer?

[joshsziegler]

The UI doesn't seem to start. Like I said, it doesn't appear to be listening to any port.

Here is the log from when I stopped the old version and started the new one. Not sure how much to include:

2020/03/06 14:49:47 ...eful/manager_unix.go:133:handleSignals() [W] PID 4263. Received SIGTERM. Shutting down...
2020/03/06 14:49:47 cmd/web.go:206:runWeb() [I] HTTP Listener: 0.0.0.0:9090 Closed
2020/03/06 14:49:47 ...rvices/pull/check.go:214:TestPullRequests() [I] PID: 4263 Pull Request testing shutdown
2020/03/06 14:49:47 ...ndexer/code/queue.go:46:processRepoIndexerOperationQueue() [I] PID: 4263 Repository indexer queue processing stopped
2020/03/06 14:49:47 ...eful/server_hooks.go:47:doShutdown() [I] PID: 4263 Listener ([::]:9090) closed.
2020/03/06 14:49:47 .../graceful/manager.go:184:doHammerTime() [W] Setting Hammer condition
2020/03/06 14:49:48 .../graceful/manager.go:198:doTerminate() [W] Terminating
2020/03/06 14:49:48 ...ndexer/code/bleve.go:235:Close() [I] PID: 4263 Repository Indexer closed
2020/03/06 14:49:48 ...exer/code/indexer.go:48:func1() [I] PID: 4263 Repository Indexer closed
2020/03/06 14:49:48 ...er/issues/indexer.go:160:1() [I] PID: 4263 Issue Indexer closed
2020/03/06 14:49:48 cmd/web.go:208:runWeb() [I] PID: 4263 Gitea Web Finished
2020/03/06 14:54:19 ...dules/setting/log.go:276:newLogService() [I] Gitea Log Mode: File(File:info)
2020/03/06 14:54:19 ...les/setting/cache.go:45:newCacheService() [I] Cache Service Enabled
2020/03/06 14:54:19 ...s/setting/session.go:44:newSessionService() [I] Session Service Enabled
2020/03/06 14:54:19 routers/init.go:59:initDBEngine() [I] Beginning ORM engine initialization.
2020/03/06 14:54:19 routers/init.go:66:initDBEngine() [I] ORM engine initialization attempt #1/10...
2020/03/06 14:54:19 ...rm.io/xorm/engine.go:351:Ping() [I] PING DATABASE sqlite3
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT name FROM sqlite_master WHERE type='table' and name = ? []interface {}{"version"} - took: 70.255µs
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:260:IsColumnExist() [I] [SQL] SELECT name FROM sqlite_master WHERE type='table' and name = ? and ((sql like '%`id`%') or (sql like '%[id]%')) [version]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:260:IsColumnExist() [I] [SQL] SELECT name FROM sqlite_master WHERE type='table' and name = ? and ((sql like '%`version`%') or (sql like '%[version]%')) [version]
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `id`, `version` FROM `version` WHERE `id`=? LIMIT 1 []interface {}{1} - took: 33.002µs
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:395:GetTables() [I] [SQL] SELECT name FROM sqlite_master WHERE type='table'
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:338:GetColumns() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [user]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:421:GetIndexes() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [user]
2020/03/06 14:54:19 models/models.go:189:NewEngine() [W] Table user Column allow_create_organization db default is , struct default is 1
2020/03/06 14:54:19 models/models.go:189:NewEngine() [W] Table user Column prohibit_login db default is , struct default is 0
2020/03/06 14:54:19 models/models.go:189:NewEngine() [W] Table user Column prohibit_login db nullable is true, struct nullable is false
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:338:GetColumns() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [public_key]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:421:GetIndexes() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [public_key]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:338:GetColumns() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [access_token]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:421:GetIndexes() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [access_token]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:338:GetColumns() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [repository]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:421:GetIndexes() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [repository]
2020/03/06 14:54:19 models/models.go:189:NewEngine() [W] Table repository Column original_service_type db default is 0, struct default is 
2020/03/06 14:54:19 models/models.go:189:NewEngine() [W] Table repository has column is_bare but struct has not related field
2020/03/06 14:54:19 models/models.go:189:NewEngine() [W] Table repository has column enable_wiki but struct has not related field
2020/03/06 14:54:19 models/models.go:189:NewEngine() [W] Table repository has column allow_public_wiki but struct has not related field
2020/03/06 14:54:19 models/models.go:189:NewEngine() [W] Table repository has column enable_external_wiki but struct has not related field
2020/03/06 14:54:19 models/models.go:189:NewEngine() [W] Table repository has column external_wiki_url but struct has not related field
2020/03/06 14:54:19 models/models.go:189:NewEngine() [W] Table repository has column enable_issues but struct has not related field
2020/03/06 14:54:19 models/models.go:189:NewEngine() [W] Table repository has column allow_public_issues but struct has not related field
2020/03/06 14:54:19 models/models.go:189:NewEngine() [W] Table repository has column enable_external_tracker but struct has not related field
2020/03/06 14:54:19 models/models.go:189:NewEngine() [W] Table repository has column external_tracker_url but struct has not related field
2020/03/06 14:54:19 models/models.go:189:NewEngine() [W] Table repository has column external_tracker_format but struct has not related field
2020/03/06 14:54:19 models/models.go:189:NewEngine() [W] Table repository has column external_tracker_style but struct has not related field
2020/03/06 14:54:19 models/models.go:189:NewEngine() [W] Table repository has column enable_pulls but struct has not related field
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:338:GetColumns() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [deploy_key]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:421:GetIndexes() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [deploy_key]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:338:GetColumns() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [collaboration]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:421:GetIndexes() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [collaboration]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:338:GetColumns() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [access]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:421:GetIndexes() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [access]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:338:GetColumns() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [upload]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:421:GetIndexes() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [upload]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:338:GetColumns() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [watch]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:421:GetIndexes() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [watch]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:338:GetColumns() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [star]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:421:GetIndexes() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [star]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:338:GetColumns() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [follow]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:421:GetIndexes() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [follow]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:338:GetColumns() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [action]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:421:GetIndexes() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [action]
2020/03/06 14:54:19 models/models.go:189:NewEngine() [W] Table action has column act_user_name but struct has not related field
2020/03/06 14:54:19 models/models.go:189:NewEngine() [W] Table action has column repo_user_name but struct has not related field
2020/03/06 14:54:19 models/models.go:189:NewEngine() [W] Table action has column repo_name but struct has not related field
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:338:GetColumns() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [issue]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:421:GetIndexes() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [issue]
2020/03/06 14:54:19 models/models.go:189:NewEngine() [W] Table issue has column assignee_id but struct has not related field
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:338:GetColumns() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [pull_request]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:421:GetIndexes() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [pull_request]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:338:GetColumns() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [comment]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:421:GetIndexes() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [comment]
2020/03/06 14:54:19 models/models.go:189:NewEngine() [W] Table comment has column old_assignee_id but struct has not related field
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:338:GetColumns() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [attachment]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:421:GetIndexes() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [attachment]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:338:GetColumns() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [label]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:421:GetIndexes() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [label]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:338:GetColumns() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [issue_label]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:421:GetIndexes() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [issue_label]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:338:GetColumns() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [milestone]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:421:GetIndexes() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [milestone]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:338:GetColumns() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [mirror]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:421:GetIndexes() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [mirror]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:338:GetColumns() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [release]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:421:GetIndexes() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [release]
2020/03/06 14:54:19 models/models.go:189:NewEngine() [W] Table release Column is_prerelease db default is , struct default is 0
2020/03/06 14:54:19 models/models.go:189:NewEngine() [W] Table release Column is_prerelease db nullable is true, struct nullable is false
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:338:GetColumns() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [login_source]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:421:GetIndexes() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [login_source]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:338:GetColumns() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [webhook]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:421:GetIndexes() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [webhook]
2020/03/06 14:54:19 models/models.go:189:NewEngine() [W] Table webhook Column http_method db default is 'POST', struct default is 
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:338:GetColumns() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [hook_task]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:421:GetIndexes() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [hook_task]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:338:GetColumns() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [team]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:421:GetIndexes() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [team]
2020/03/06 14:54:19 models/models.go:189:NewEngine() [W] Table team has column unit_types but struct has not related field
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:338:GetColumns() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [org_user]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:421:GetIndexes() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [org_user]
2020/03/06 14:54:19 models/models.go:189:NewEngine() [W] Table org_user has column is_owner but struct has not related field
2020/03/06 14:54:19 models/models.go:189:NewEngine() [W] Table org_user has column num_teams but struct has not related field
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:338:GetColumns() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [team_user]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:421:GetIndexes() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [team_user]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:338:GetColumns() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [team_repo]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:421:GetIndexes() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [team_repo]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:338:GetColumns() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [notice]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:421:GetIndexes() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [notice]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:338:GetColumns() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [email_address]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:421:GetIndexes() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [email_address]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:338:GetColumns() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [notification]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:421:GetIndexes() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [notification]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:338:GetColumns() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [issue_user]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:421:GetIndexes() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [issue_user]
2020/03/06 14:54:19 models/models.go:189:NewEngine() [W] Table issue_user has column repo_id but struct has not related field
2020/03/06 14:54:19 models/models.go:189:NewEngine() [W] Table issue_user has column milestone_id but struct has not related field
2020/03/06 14:54:19 models/models.go:189:NewEngine() [W] Table issue_user has column is_assigned but struct has not related field
2020/03/06 14:54:19 models/models.go:189:NewEngine() [W] Table issue_user has column is_poster but struct has not related field
2020/03/06 14:54:19 models/models.go:189:NewEngine() [W] Table issue_user has column is_closed but struct has not related field
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:338:GetColumns() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [lfs_meta_object]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:421:GetIndexes() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [lfs_meta_object]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:338:GetColumns() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [two_factor]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:421:GetIndexes() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [two_factor]
2020/03/06 14:54:19 models/models.go:189:NewEngine() [W] Table two_factor has column user_id but struct has not related field
2020/03/06 14:54:19 models/models.go:189:NewEngine() [W] Table two_factor has column scratch_token but struct has not related field
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:338:GetColumns() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [gpg_key]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:421:GetIndexes() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [gpg_key]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:338:GetColumns() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [gpg_key_import]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:421:GetIndexes() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [gpg_key_import]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:338:GetColumns() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [repo_unit]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:421:GetIndexes() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [repo_unit]
2020/03/06 14:54:19 models/models.go:189:NewEngine() [W] Table repo_unit has column index but struct has not related field
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:338:GetColumns() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [repo_redirect]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:421:GetIndexes() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [repo_redirect]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:338:GetColumns() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [external_login_user]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:421:GetIndexes() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [external_login_user]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:338:GetColumns() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [protected_branch]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:421:GetIndexes() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [protected_branch]
2020/03/06 14:54:19 models/models.go:189:NewEngine() [W] Table protected_branch Column can_push db default is , struct default is 0
2020/03/06 14:54:19 models/models.go:189:NewEngine() [W] Table protected_branch Column can_push db nullable is true, struct nullable is false
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:338:GetColumns() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [user_open_id]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:421:GetIndexes() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [user_open_id]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:338:GetColumns() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [issue_watch]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:421:GetIndexes() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [issue_watch]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:338:GetColumns() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [commit_status]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:421:GetIndexes() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [commit_status]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:338:GetColumns() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [stopwatch]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:421:GetIndexes() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [stopwatch]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:338:GetColumns() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [tracked_time]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:421:GetIndexes() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [tracked_time]
2020/03/06 14:54:19 models/models.go:189:NewEngine() [W] Table tracked_time Column time db nullable is true, struct nullable is false
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:338:GetColumns() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [deleted_branch]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:421:GetIndexes() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [deleted_branch]
2020/03/06 14:54:19 models/models.go:189:NewEngine() [W] Table deleted_branch Column deleted_by_id db nullable is false, struct nullable is true
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:338:GetColumns() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [repo_indexer_status]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:421:GetIndexes() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [repo_indexer_status]
2020/03/06 14:54:19 models/models.go:189:NewEngine() [W] Table repo_indexer_status Column repo_id db nullable is false, struct nullable is true
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:338:GetColumns() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [issue_dependency]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:421:GetIndexes() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [issue_dependency]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:338:GetColumns() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [lfs_lock]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:421:GetIndexes() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [lfs_lock]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:338:GetColumns() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [reaction]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:421:GetIndexes() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [reaction]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:338:GetColumns() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [issue_assignees]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:421:GetIndexes() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [issue_assignees]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:338:GetColumns() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [u2f_registration]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:421:GetIndexes() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [u2f_registration]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:338:GetColumns() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [team_unit]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:421:GetIndexes() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [team_unit]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:338:GetColumns() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [review]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:421:GetIndexes() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [review]
2020/03/06 14:54:19 models/models.go:189:NewEngine() [W] Table review column type db type is TEXT, struct type is INTEGER
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:338:GetColumns() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [oauth2_application]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:421:GetIndexes() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [oauth2_application]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:338:GetColumns() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [oauth2_authorization_code]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:421:GetIndexes() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [oauth2_authorization_code]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:338:GetColumns() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [oauth2_grant]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:421:GetIndexes() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [oauth2_grant]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:338:GetColumns() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [task]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:421:GetIndexes() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [task]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:338:GetColumns() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [topic]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:421:GetIndexes() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [topic]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:338:GetColumns() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [repo_topic]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:421:GetIndexes() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [repo_topic]
2020/03/06 14:54:19 routers/init.go:99:GlobalInit() [I] ORM engine initialization successful!
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:395:GetTables() [I] [SQL] SELECT name FROM sqlite_master WHERE type='table'
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:338:GetColumns() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='table' and name = ? [oauth2_session]
2020/03/06 14:54:19 ...m/dialect_sqlite3.go:421:GetIndexes() [I] [SQL] SELECT sql FROM sqlite_master WHERE type='index' and tbl_name = ? [oauth2_session]
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `id`, `type`, `name`, `is_actived`, `is_sync_enabled`, `cfg`, `created_unix`, `updated_unix` FROM `login_source` WHERE (is_actived = ? and type = ?) []interface {}{true, 6} - took: 45.979µs
2020/03/06 14:54:19 .../xorm/session_raw.go:196:exec() [I] [SQL] DELETE FROM `deleted_branch` WHERE (deleted_unix < ?) []interface {}{1583438059} - took: 132.795µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT repo.id FROM `repository` repo WHERE repo.num_watches!=(SELECT COUNT(*) FROM `watch` WHERE repo_id=repo.id AND mode<>2) - took: 158.973µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT repo.id FROM `repository` repo WHERE repo.num_stars!=(SELECT COUNT(*) FROM `star` WHERE repo_id=repo.id) - took: 61.874µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT label.id FROM `label` WHERE label.num_issues!=(SELECT COUNT(*) FROM `issue_label` WHERE label_id=label.id) - took: 107.469µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [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 []interface {}{"github"} - took: 4.629733ms
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `user`.id FROM `user` WHERE `user`.num_repos!=(SELECT COUNT(*) FROM `repository` WHERE owner_id=`user`.id) - took: 323.793µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `id`, `owner_id`, `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`, `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`, `avatar`, `created_unix`, `updated_unix` FROM `repository` WHERE (id > 0) - took: 4.588438ms
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `issue`.id FROM `issue` WHERE `issue`.num_comments!=(SELECT COUNT(*) FROM `comment` WHERE issue_id=`issue`.id AND type=0) - took: 84.21µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [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=?) []interface {}{true, false} - took: 63.628µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{4} - took: 2.514495ms
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [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=?) []interface {}{true, true} - took: 79.744µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT repo.id FROM `repository` repo WHERE repo.num_forks!=(SELECT COUNT(*) FROM `repository` WHERE fork_id=repo.id) - took: 53.51µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{8} - took: 73.304µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{25} - took: 45.157µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{19} - took: 36.632µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{2} - took: 34.337µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{25} - took: 74.014µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{8} - took: 38.05µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{8} - took: 39.823µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{11} - took: 43.516µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{18} - took: 46.329µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{17} - took: 73.221µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{17} - took: 37.076µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{8} - took: 38.174µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{8} - took: 44.836µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{8} - took: 36.805µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{8} - took: 74.967µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{8} - took: 44.972µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{8} - took: 41.431µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{22} - took: 38.176µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{8} - took: 36.733µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{25} - took: 42.16µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{25} - took: 61.12µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{25} - took: 48.228µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{25} - took: 45.535µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{25} - took: 36.562µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{25} - took: 38.71µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{25} - took: 64.72µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{25} - took: 42.395µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{25} - took: 44.136µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{25} - took: 36.76µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{25} - took: 45.645µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{25} - took: 65.732µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{25} - took: 38.282µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{25} - took: 36.518µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{25} - took: 43.057µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{25} - took: 35.392µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{25} - took: 47.982µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{25} - took: 80.793µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{25} - took: 39.911µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{25} - took: 56.109µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{25} - took: 24.913µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{25} - took: 37.614µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{25} - took: 42.445µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{25} - took: 34.269µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{25} - took: 28.337µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{25} - took: 58.685µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{25} - took: 44.849µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{25} - took: 44.245µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{25} - took: 54.667µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{25} - took: 89.795µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{25} - took: 48.573µs
2020/03/06 14:54:19 ...exer/code/indexer.go:54:func2() [I] PID: 67157 Initializing Repository Indexer at: /srv/gitea/indexers/repos.bleve
2020/03/06 14:54:19 ...er/issues/indexer.go:142:func2() [I] PID 67157: Initializing Issue Indexer: bleve
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{25} - took: 50.839µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `pull_request`.`id` FROM `pull_request` WHERE (status=?) []interface {}{1} - took: 88.143µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `id`, `repo_id`, `hook_id`, `uuid`, `type`, `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=?) []interface {}{false} - took: 58.347µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{25} - took: 476.727µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{25} - took: 78.378µs
2020/03/06 14:54:19 ...er/issues/indexer.go:199:func3() [I] Issue Indexer Initialization took 8.516123ms
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{25} - took: 50.252µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{25} - took: 43.39µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{25} - took: 45.977µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{25} - took: 39.329µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{25} - took: 39.379µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{25} - took: 54.487µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{25} - took: 31.039µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{25} - took: 37.115µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{25} - took: 28.919µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{26} - took: 33.805µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{26} - took: 30.92µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{26} - took: 32.291µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{26} - took: 34.039µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{26} - took: 28.488µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{26} - took: 29.128µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{26} - took: 35.45µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{26} - took: 29.789µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{26} - took: 31.295µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{26} - took: 30µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{26} - took: 27.84µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{26} - took: 29.232µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{26} - took: 43.98µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{26} - took: 39.616µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{26} - took: 30.056µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{19} - took: 28.287µs
2020/03/06 14:54:19 routers/init.go:122:GlobalInit() [I] SQLite3 Supported
2020/03/06 14:54:19 routers/init.go:46:checkRunMode() [I] Run Mode: Production
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{19} - took: 29.387µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{27} - took: 38.575µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{27} - took: 37.913µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{8} - took: 29.84µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{8} - took: 45.54µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{4} - took: 35.882µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{27} - took: 28.896µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{8} - took: 37.347µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{27} - took: 32.176µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{27} - took: 27.995µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{32} - took: 28.523µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{8} - took: 29.665µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{27} - took: 29.574µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{19} - took: 25.898µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{4} - took: 28.295µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{27} - took: 26.8µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{27} - took: 50.04µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{9} - took: 39.514µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{27} - took: 28.862µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{27} - took: 28.704µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{9} - took: 32.9µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{25} - took: 34.15µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{27} - took: 29.191µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{27} - took: 26.434µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{27} - took: 33.042µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{27} - took: 25.49µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{27} - took: 21.535µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{39} - took: 22.109µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{4} - took: 20.511µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{27} - took: 22.678µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{27} - took: 23.704µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{43} - took: 22.693µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{43} - took: 21.324µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{40} - took: 21.231µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{45} - took: 31.301µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{45} - took: 40.025µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{2} - took: 25.318µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{36} - took: 23.574µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{27} - took: 23.862µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{27} - took: 33.109µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{27} - took: 21.967µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{43} - took: 30.913µs
2020/03/06 14:54:19 .../xorm/session_raw.go:78:queryRows() [I] [SQL] SELECT `name` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{45} - took: 17.536µs
2020/03/06 14:54:19 ...exer/code/indexer.go:98:func3() [I] Repository Indexer Initialization took 63.641319ms
2020/03/06 14:54:20 cmd/web.go:161:runWeb() [I] Listen: http://0.0.0.0:9090
2020/03/06 14:54:20 cmd/web.go:164:runWeb() [I] LFS server enabled
2020/03/06 14:54:20 ...s/graceful/server.go:55:NewServer() [I] Starting new server: tcp:0.0.0.0:9090 on PID: 67157

[guillep2k]

I can't figure out something in your logs. I read:

2020/03/06 14:49:48 ...exer/code/indexer.go:48:func1() [I] PID: 4263 Repository Indexer closed
2020/03/06 14:49:48 ...er/issues/indexer.go:160:1() [I] PID: 4263 Issue Indexer closed
2020/03/06 14:49:48 cmd/web.go:208:runWeb() [I] PID: 4263 Gitea Web Finished
2020/03/06 14:54:19 ...dules/setting/log.go:276:newLogService() [I] Gitea Log Mode: File(File:info)
2020/03/06 14:54:19 ...les/setting/cache.go:45:newCacheService() [I] Cache Service Enabled
2020/03/06 14:54:19 ...s/setting/session.go:44:newSessionService() [I] Session Service Enabled
2020/03/06 14:54:19 routers/init.go:59:initDBEngine() [I] Beginning ORM engine initialization.
[...]
2020/03/06 14:54:20 ...s/graceful/server.go:55:NewServer() [I] Starting new server: tcp:0.0.0.0:9090 on PID: 67157

But I also read:

Mar 06 14:49:48 srv-1 systemd[1]: Started Gitea.
# Ok, but the website isn't responding. I then look at the following:

The systemd start time does not seem to match Gitea's? I'd shutdown Gitea, wait a few moments, make sure no gitea process is running and start it up again. Can you do that and let us know the results?

[joshsziegler]

Ok, I tried the upgrade again. This time -- as you suggested -- I waited for all gitea's processes to exit cleanly. Then I started back up with the new version. That seems to have done the trick. Sorry for the noobie spam!