Error 500 on create new repo

[dimmuboy]

• Gitea version (or commit ref): 1.8.3/1.9.0-rc2
• Git version: 2.2.2
• Operating system: Windows 10
• 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:

Description

Hello there,
on fresh new server installation I get Error 500 page after create new repo when I check Initialize Repository otherwise repo is created but can’t push any data.

Gitea log:

2019/07/17 14:17:15 .../xorm/session_get.go:99:nocacheGet() [I] [SQL] SELECT `id`, `lower_name`, `name`, `full_name`, `email`, `keep_email_private`, `passwd`, `must_change_password`, `login_type`, `login_source`, `login_name`, `type`, `location`, `website`, `rands`, `salt`, `language`, `description`, `created_unix`, `updated_unix`, `last_login_unix`, `last_repo_visibility`, `max_repo_creation`, `is_active`, `is_admin`, `allow_git_hook`, `allow_import_local`, `allow_create_organization`, `prohibit_login`, `avatar`, `avatar_email`, `use_custom_avatar`, `num_followers`, `num_following`, `num_stars`, `num_repos`, `num_teams`, `num_members`, `visibility`, `diff_view_style`, `theme` FROM `user` WHERE `id`=? LIMIT 1 []interface {}{1}
2019/07/17 14:17:15 ...s/context/context.go:315:func1() [D] Session ID: 710c45021a6c10db
2019/07/17 14:17:15 ...s/context/context.go:316:func1() [D] CSRF Token: rmybewd2agCChqY8KL-nj917umI6MTU2MzM2NTEzNDc2ODQ0NjYwMA==
2019/07/17 14:17:15 .../xorm/session_raw.go:87:queryRow() [I] [SQL] SELECT count(*) FROM `notification` WHERE (user_id = ?) AND (status = ?) []interface {}{1, 0x1}
2019/07/17 14:17:15 ...xorm/session_find.go:199:noCacheFind() [I] [SQL] SELECT * FROM `user` INNER JOIN `team_user` ON `team_user`.org_id=`user`.id INNER JOIN `team` ON `team`.id=`team_user`.team_id WHERE (`team_user`.uid=?) AND (`team`.authorize=?) ORDER BY `updated_unix` DESC, `user`.`name` ASC []interface {}{1, 4}
2019/07/17 14:17:15 ...xorm/xorm/session.go:845:saveLastSQL() [I] [SQL] BEGIN TRANSACTION
2019/07/17 14:17:15 .../xorm/session_get.go:99:nocacheGet() [I] [SQL] SELECT `id`, `owner_id`, `lower_name`, `name`, `description`, `website`, `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`, `is_fork`, `fork_id`, `size`, `is_fsck_enabled`, `close_issues_via_commit_in_any_branch`, `topics`, `avatar`, `created_unix`, `updated_unix` FROM `repository` WHERE `owner_id`=? AND `lower_name`=? LIMIT 1 []interface {}{1, "test"}
2019/07/17 14:17:15 ...rm/session_insert.go:513:innerInsert() [I] [SQL] INSERT INTO `repository` (`owner_id`,`lower_name`,`name`,`description`,`website`,`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`,`is_fork`,`fork_id`,`size`,`is_fsck_enabled`,`close_issues_via_commit_in_any_branch`,`topics`,`avatar`,`created_unix`,`updated_unix`) VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?) []interface {}{1, "test", "test", "", "", "", 0, 0, 0, 0, 0, 0, 0, 0, 0, false, false, false, false, false, 0, 0, true, false, "null", "", 1563365835, 1563365835}
2019/07/17 14:17:15 ...rm/session_delete.go:211:Delete() [I] [SQL] DELETE FROM `repo_redirect` WHERE `owner_id`=? AND `lower_name`=? []interface {}{1, "test"}
2019/07/17 14:17:15 ...rm/session_insert.go:264:innerInsertMulti() [I] [SQL] INSERT INTO `repo_unit` (`repo_id`, `type`, `config`, `created_unix`) VALUES (?, ?, ?, ?),(?, ?, ?, ?),(?, ?, ?, ?),(?, ?, ?, ?),(?, ?, ?, ?) []interface {}{1, 1, interface {}(nil), 1563365835, 1, 2, "{\"EnableTimetracker\":true,\"AllowOnlyContributorsToTrackTime\":true,\"EnableDependencies\":true}", 1563365835, 1, 3, "{\"IgnoreWhitespaceConflicts\":false,\"AllowMerge\":true,\"AllowRebase\":true,\"AllowRebaseMerge\":true,\"AllowSquash\":true}", 1563365835, 1, 4, interface {}(nil), 1563365835, 1, 5, interface {}(nil), 1563365835}
2019/07/17 14:17:15 ...rm/session_update.go:375:Update() [I] [SQL] UPDATE `user` SET `lower_name` = ?, `name` = ?, `full_name` = ?, `email` = ?, `keep_email_private` = ?, `passwd` = ?, `must_change_password` = ?, `login_type` = ?, `login_source` = ?, `login_name` = ?, `type` = ?, `location` = ?, `website` = ?, `rands` = ?, `salt` = ?, `language` = ?, `description` = ?, `last_login_unix` = ?, `last_repo_visibility` = ?, `max_repo_creation` = ?, `is_active` = ?, `is_admin` = ?, `allow_git_hook` = ?, `allow_import_local` = ?, `allow_create_organization` = ?, `prohibit_login` = ?, `avatar` = ?, `avatar_email` = ?, `use_custom_avatar` = ?, `num_followers` = ?, `num_following` = ?, `num_stars` = ?, `num_repos` = ?, `num_teams` = ?, `num_members` = ?, `visibility` = ?, `diff_view_style` = ?, `theme` = ?, `updated_unix` = ? WHERE `id`=? []interface {}{"*****", "*****", "", "******", false, "0af4f44cca646115c9096d744e4f2e433c60aa6921c29d908f800ed18b54b2e679fb3d7a2503c42dfd8eabfcbf3e305bcff2", false, 0, 0, "", 0, "", "", "w8nGLC4LIl", "6be8WygioK", "en-US", "", 1563365134, false, -1, true, true, false, false, true, false, "fd0290e9e7ab5bcdb48fa58a11bd764e", "******", false, 0, 0, 0, 1, 0, 0, 0, "", "gitea", 1563365835, 1}
2019/07/17 14:17:15 ...xorm/session_find.go:199:noCacheFind() [I] [SQL] SELECT `id`, `repo_id`, `user_id`, `mode` FROM `collaboration` WHERE `repo_id`=? []interface {}{1}
2019/07/17 14:17:15 ...rm/session_delete.go:211:Delete() [I] [SQL] DELETE FROM `access` WHERE `repo_id`=? []interface {}{1}
2019/07/17 14:17:15 .../xorm/session_get.go:99:nocacheGet() [I] [SQL] SELECT `id`, `user_id`, `repo_id` FROM `watch` WHERE `user_id`=? AND `repo_id`=? LIMIT 1 []interface {}{1, 1}
2019/07/17 14:17:15 ...rm/session_insert.go:513:innerInsert() [I] [SQL] INSERT INTO `watch` (`user_id`,`repo_id`) VALUES (?, ?) []interface {}{1, 1}
2019/07/17 14:17:15 .../xorm/session_raw.go:226:Exec() [I] [SQL] UPDATE `repository` SET num_watches = num_watches + 1 WHERE id = ? []interface {}{1}
2019/07/17 14:17:15 ...xorm/session_find.go:199:noCacheFind() [I] [SQL] SELECT * FROM `watch` INNER JOIN `user` ON `user`.id = `watch`.user_id WHERE (`watch`.repo_id=?) AND (`user`.is_active=?) AND (`user`.prohibit_login=?) []interface {}{1, true, false}
2019/07/17 14:17:15 ...rm/session_insert.go:513:innerInsert() [I] [SQL] INSERT INTO `action` (`user_id`,`op_type`,`act_user_id`,`repo_id`,`comment_id`,`is_deleted`,`ref_name`,`is_private`,`content`,`created_unix`) VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?) []interface {}{1, 1, 1, 1, 0, false, "", false, "", 1563365835}
2019/07/17 14:17:15 models/action.go:323:newRepoAction() [T] action.newRepoAction: *****/test
2019/07/17 14:17:15 ...xorm/session_find.go:199:noCacheFind() [I] [SQL] SELECT `id`, `repo_id`, `org_id`, `url`, `signature`, `http_method`, `content_type`, `secret`, `events`, `is_ssl`, `is_active`, `hook_task_type`, `meta`, `last_status`, `created_unix`, `updated_unix` FROM `webhook` WHERE (repo_id=? AND org_id=?) []interface {}{0, 0}
2019/07/17 14:17:16 ...xorm/xorm/session.go:845:saveLastSQL() [I] [SQL] ROLL BACK
2019/07/17 14:17:16 routers/repo/repo.go:148:handleCreateError() [E] CreatePost: initRepository: initRepoCommit: git push: remote: Wsh
  To C:\test\data\repos\*****\test.git
   ! [remote rejected] master -> master (pre-receive hook declined)
  error: failed to push some refs to 'C:\test\data\repos\*****\test.git'
  
2019/07/17 14:17:16 ...s/context/context.go:137:HTML() [D] Template: status/500

Gitea config:

APP_NAME = Gitea: Git with a cup of tea
RUN_USER = TestServer2
RUN_MODE = prod

[oauth2]
JWT_SECRET = 52jm4x5JosPJp6MCcWU5vcVNs1IKU8UyqtbeBGIu0P8

[security]
INTERNAL_TOKEN = eyJhbGciOiJIUzI1NiIsInR5cCI6IkpXVCJ9.eyJuYmYiOjE1NjMzNjAzMjB9.tGJdvYUH5ABP7pZo4XSwdMZUhO4K7ybo2ODkcmKwHPc
INSTALL_LOCK   = true
SECRET_KEY     = 4YNgkae9KsdYPCg2SbYBdQeVNjYJxb9n3o1vbrL7lvp47alENWkqSlmLXcKPUlWo

[database]
DB_TYPE  = sqlite3
HOST     = 127.0.0.1:3306
NAME     = gitea
USER     = gitea
PASSWD   = 
SSL_MODE = disable
CHARSET  = utf8
PATH     = C:/test/data/gitea.db

[repository]
ROOT = C:/test/data/repos

[server]
SSH_DOMAIN       = localhost
DOMAIN           = localhost
HTTP_PORT        = 3000
ROOT_URL         = http://localhost:3000/
DISABLE_SSH      = false
SSH_PORT         = 22
LFS_START_SERVER = true
LFS_CONTENT_PATH = C:/test/data/lfs
LFS_JWT_SECRET   = EbD1G7qlGNDwX_Jpzyl8UEJ4FUgjSCByqwXEw7P5APU
OFFLINE_MODE     = false

[mailer]
ENABLED = false

[service]
REGISTER_EMAIL_CONFIRM            = false
ENABLE_NOTIFY_MAIL                = false
DISABLE_REGISTRATION              = false
ALLOW_ONLY_EXTERNAL_REGISTRATION  = false
ENABLE_CAPTCHA                    = false
REQUIRE_SIGNIN_VIEW               = false
DEFAULT_KEEP_EMAIL_PRIVATE        = false
DEFAULT_ALLOW_CREATE_ORGANIZATION = true
DEFAULT_ENABLE_TIMETRACKING       = true
NO_REPLY_ADDRESS                  = noreply.example.org

[picture]
DISABLE_GRAVATAR        = false
ENABLE_FEDERATED_AVATAR = true

[openid]
ENABLE_OPENID_SIGNIN = true
ENABLE_OPENID_SIGNUP = true

[session]
PROVIDER = file

[log]
MODE      = file
LEVEL     = trace
ROOT_PATH = C:/test/log

[samuelallan72]

I also am getting this issue. Gitea 1.8.3 (archlinux official package). Was working on previous versions with same config.

[dmorilha]

Same, very basic error. It already caused a very bad impression as I was just trying to migrate off of gogs...

[dmorilha]

logs read:

[root@master gitea]# Jul 18 10:35:07 master gitea[4593]: [Macaron] 2019-07-18 10:35:07: Started POST /repo/create for 127.0.0.1
Jul 18 10:35:07 master gitea[4593]: 2019/07/18 10:35:07 [...routers/repo/repo.go:169 CreatePost()] [E] initRepository: InitRepository: chdir /data/gitea/pak/app2.git: no such file or directory
Jul 18 10:35:07 master gitea[4593]: 2019/07/18 10:35:07 [...routers/repo/repo.go:146 handleCreateError()] [E] CreatePost: delete repo directory pak/app2 failed(2): unlinkat /data/gitea/pak/app2.git: read-only file system
Jul 18 10:35:07 master gitea[4593]: [Macaron] 2019-07-18 10:35:07: Completed POST /repo/create 500 Internal Server Error in 43.959367ms

[samuelallan72]

@dmorilha I get exactly the same log entries. It appears that it never creates the git repository on disk. The filesystem is definitely writable by gitea (confirmed by stat <dir> and the ability to push to existing repositories).

Same, very basic error.

While this is an annoying error, please try to avoid getting annoyed. Remember that this is a community project run by volunteers. :) The issue has been reported and confirmed by multiple users, so now we give the developers a chance to investigate and fix it.

[zeripath]

Hi peeps.

Sorry you've been suffering this - it sounds really annoying. There might be two different issues here. How are you creating the repos? It looks like you're creating them with default initialisation files? Does the error go away if you don't set that?

@dimmuboy the log above looks like the error is hitting in finding the webhook in the pre-receive hook during initrepository. (Btw those logs are from 1.9-rc not 1.8 - does 1.8 have different logs?)

This might be an initialisation bug - but I don't understand why 1.8 would be affected. Pre-receive should probably not be being called during initialisation as it's unlikely that the repository is set up correctly - although I thought this call was delayed till the repo was correctly set up. Does the equivalent work on try?

@dmorilha your log is a bit more odd. The important bit is the chdir /data/gitea/pak/app2.git: no such file or directory You're running 1.8 not 1.9 so I'm not certain why you're suddenly getting problems like this. If possible could you please run with a higher log level and give us some more information about your configuration in particular tell me whether your repositories are actually supposed to be in /data/gitea - I suspect this is not the case.

I think in either case the place to begin looking is in InitRepository. I won't probably be able to do this until Sunday but hopefully my hint suggests the place in the code to look for the problem for another maintainer.

[samuelallan72]

@zeripath

It looks like you're creating them with default initialisation files?

I wasn't, but I get a 500 error whether I check the default initialization files or not.

I get the same log output as @dmorilha and I can confirm that the directory it's mentioning is correct, and is owned by the gitea user and group. (the parent of the {reponame}.git directory that is; I haven't observed the {reponame}.git being created.)

Interestingly if I downgrade to 1.8.0 or 1.8.1, I'm still getting the 500 error and same log output. Could be same unrelated system change that is triggering it then.

[zeripath]

@swalladge how are you running Gitea? Is this docker, systemd or something else?

[dimmuboy]

v1.8.0, 1.8.1, 1.8.3 still same error

2019/07/19 08:42:50 [...routers/repo/repo.go:146 handleCreateError()] [E] CreatePost: initRepository: initRepoCommit: git push: remote: Wsh
To C:\test\data\repos\*****\test.git
 ! [remote rejected] master -> master (pre-receive hook declined)
error: failed to push some refs to 'C:\test\data\repos\*****\test.git'

[samuelallan72]

Bare metal on archlinux using the official archlinux gitea package, using systemd to manage running it, postgres database. July 19, 2019 6:42:00 AM UTC zeripath <notifications@github.com> kirjoitti:

…

@swalladge how are you running Gitea? Is this docker, systemd or something else?

-- Envoyé de mon appareil Android avec Courriel K-9 Mail. Veuillez excuser ma brièveté.

[dimmuboy]

If I try to create repo without initializing it is created but after push I got error

$ git push -u script master
Enumerating objects: 21, done.
Counting objects: 100% (21/21), done.
Delta compression using up to 4 threads
Compressing objects: 100% (21/21), done.
Writing objects: 100% (21/21), 36.04 KiB | 3.00 MiB/s, done.
Total 21 (delta 3), reused 0 (delta 0)
remote: Wsh
To http://localmachine:3000/*****/test.git
 ! [remote rejected] master -> master (pre-receive hook declined)
error: failed to push some refs to 'http://localmachine:3000/*****/test.git'

[zeripath]

Is your data directory mounted non exec?

That could easily be the cause of your problem.

[zeripath]

@dimmuboy your problem seems to indicate that there is an issue with the hooks on Windows. I don't use doze - have you tried resychronizing hooks on the admin panel?

@lunny, do you have any hints for this? I suspect one of your paths is incorrect.

How are you running Gitea? Is this as a service?

[dimmuboy]

@zeripath yes I tried it

[lunny]

@dmorilha I think your have a readonly filesystem which is why it failed.

@zeripath I have tested on Windows 7 x64 and macOS with gitea 1.9.0-rc2 binary. Both are OK.

@dimmuboy Could you test use a new repository directory, i.e. d:\gitea and ensure you have permissions to write it.