Hello GitLab Experts,
I hope you can help me. I am upgrading GitLab ultimately from 14.0.12 → 14.1.8. I am running GitLab on rhel7 Virutal Machine it is a small gitlab server. /var/gitlab is 37G. The VM has 8 CPUs and 24 GB RAM.
The upgrade fails. The GUI says "500 Whoops, something went wrong on our end. It has been left to finish any background jobs for 2 hours now. In presious upgrades this part, going from 502 to 500 to GUI being available takes about 2 minutes.
I have done the following so far.
- gitlab-ctl restart
- gitlab-ctl reconfigure
Above does not change anything.
The main log files appear to be OK.
[root@rhosgitlab2 opt]# gitlab-ctl tail postgresql
==> /var/log/gitlab/postgresql/state <==
==> /var/log/gitlab/postgresql/current <==
2022-09-15_13:32:30.63914 LOG: listening on Unix socket “/var/opt/gitlab/postgresql/.s.PGSQL.5432”
2022-09-15_13:32:30.74374 FATAL: the database system is starting up
2022-09-15_13:32:30.74375 FATAL: the database system is starting up
2022-09-15_13:32:30.74490 LOG: database system was shut down at 2022-09-15 13:32:30 GMT
2022-09-15_13:32:30.74492 FATAL: the database system is starting up
2022-09-15_13:32:30.74589 FATAL: the database system is starting up
2022-09-15_13:32:30.74591 FATAL: the database system is starting up
2022-09-15_13:32:30.74781 FATAL: the database system is starting up
2022-09-15_13:32:30.75295 FATAL: the database system is starting up
2022-09-15_13:32:30.75427 LOG: database system is ready to accept connections
[root@rhosgitlab2 opt]# gitlab-ctl tail nginx
==> /var/log/gitlab/nginx/state <==
==> /var/log/gitlab/nginx/error.log <==
==> /var/log/gitlab/nginx/gitlab_access.log <==
10.2.14.170 - - [15/Sep/2022:14:32:33 +0100] “GET /admin HTTP/2.0” 502 2940 “” “Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:104.0) Gecko/20100101 Firefox/104.0” -
10.2.14.170 - - [15/Sep/2022:14:33:18 +0100] “GET /admin HTTP/2.0” 500 2926 “” “Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:104.0) Gecko/20100101 Firefox/104.0” -
10.2.14.170 - - [15/Sep/2022:14:33:20 +0100] “GET /admin HTTP/2.0” 500 2926 “” “Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:104.0) Gecko/20100101 Firefox/104.0” -
10.2.14.170 - - [15/Sep/2022:14:35:06 +0100] “GET /admin HTTP/2.0” 500 2926 “” “Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:104.0) Gecko/20100101 Firefox/104.0” -
10.2.14.170 - - [15/Sep/2022:14:36:03 +0100] “GET /admin HTTP/2.0” 500 2926 “” “Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:104.0) Gecko/20100101 Firefox/104.0” -
10.2.14.170 - - [15/Sep/2022:14:42:58 +0100] “GET /admin HTTP/2.0” 500 2926 “” “Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:104.0) Gecko/20100101 Firefox/104.0” -
10.2.14.170 - - [15/Sep/2022:14:43:27 +0100] “GET /admin HTTP/2.0” 500 2926 “” “Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:104.0) Gecko/20100101 Firefox/104.0” -
10.2.14.170 - - [15/Sep/2022:14:44:41 +0100] “GET /admin HTTP/2.0” 500 2926 “” “Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:104.0) Gecko/20100101 Firefox/104.0” -
10.2.14.170 - - [15/Sep/2022:14:49:45 +0100] “GET /admin HTTP/2.0” 500 2926 “” “Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:104.0) Gecko/20100101 Firefox/104.0” -
10.2.14.170 - - [15/Sep/2022:14:51:47 +0100] “GET /admin HTTP/2.0” 500 2926 “” “Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:104.0) Gecko/20100101 Firefox/104.0” -
==> /var/log/gitlab/nginx/gitlab_error.log <==
==> /var/log/gitlab/nginx/access.log <==
==> /var/log/gitlab/nginx/current <==
==> /var/log/gitlab/nginx/gitlab_access.log <==
10.2.14.170 - - [15/Sep/2022:14:52:46 +0100] “GET /admin HTTP/2.0” 500 2926 “” “Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:104.0) Gecko/20100101 Firefox/104.0” -
[root@rhosgitlab2 opt]# gitlab-ctl tail puma
==> /var/log/gitlab/puma/state <==
==> /var/log/gitlab/puma/puma_stderr.log <==
unknown OID 194: failed to recognize type of ‘relpartbound’. It will be treated as String.
unknown OID 28: failed to recognize type of ‘relfrozenxid’. It will be treated as String.
unknown OID 1034: failed to recognize type of ‘relacl’. It will be treated as String.
unknown OID 194: failed to recognize type of ‘relpartbound’. It will be treated as String.
unknown OID 28: failed to recognize type of ‘relfrozenxid’. It will be treated as String.
unknown OID 1034: failed to recognize type of ‘relacl’. It will be treated as String.
unknown OID 194: failed to recognize type of ‘relpartbound’. It will be treated as String.
unknown OID 28: failed to recognize type of ‘relfrozenxid’. It will be treated as String.
unknown OID 1034: failed to recognize type of ‘relacl’. It will be treated as String.
unknown OID 194: failed to recognize type of ‘relpartbound’. It will be treated as String.
==> /var/log/gitlab/puma/puma_stdout.log <==
{“timestamp”:“2022-09-15T13:50:17.857Z”,“pid”:23060,“message”:“PumaWorkerKiller: Consuming 8239.1171875 mb with master and 8 workers.”}
{“timestamp”:“2022-09-15T13:50:37.860Z”,“pid”:23060,“message”:“PumaWorkerKiller: Consuming 8239.5 mb with master and 8 workers.”}
{“timestamp”:“2022-09-15T13:50:57.862Z”,“pid”:23060,“message”:“PumaWorkerKiller: Consuming 8239.65234375 mb with master and 8 workers.”}
{“timestamp”:“2022-09-15T13:51:17.865Z”,“pid”:23060,“message”:“PumaWorkerKiller: Consuming 8240.0703125 mb with master and 8 workers.”}
{“timestamp”:“2022-09-15T13:51:37.867Z”,“pid”:23060,“message”:“PumaWorkerKiller: Consuming 8240.4375 mb with master and 8 workers.”}
{“timestamp”:“2022-09-15T13:51:57.868Z”,“pid”:23060,“message”:“PumaWorkerKiller: Consuming 8253.62890625 mb with master and 8 workers.”}
{“timestamp”:“2022-09-15T13:52:17.871Z”,“pid”:23060,“message”:“PumaWorkerKiller: Consuming 8253.734375 mb with master and 8 workers.”}
{“timestamp”:“2022-09-15T13:52:37.873Z”,“pid”:23060,“message”:“PumaWorkerKiller: Consuming 8253.61328125 mb with master and 8 workers.”}
{“timestamp”:“2022-09-15T13:52:57.875Z”,“pid”:23060,“message”:“PumaWorkerKiller: Consuming 8266.765625 mb with master and 8 workers.”}
{“timestamp”:“2022-09-15T13:53:17.877Z”,“pid”:23060,“message”:“PumaWorkerKiller: Consuming 8267.62890625 mb with master and 8 workers.”}
==> /var/log/gitlab/puma/current <==
2022-09-15_13:32:32.59870 {“timestamp”:“2022-09-15T13:32:32.598Z”,“pid”:23060,“message”:“* Environment: production”}
2022-09-15_13:32:32.59871 {“timestamp”:“2022-09-15T13:32:32.598Z”,“pid”:23060,“message”:“* Master PID: 23060”}
2022-09-15_13:32:32.59871 {“timestamp”:“2022-09-15T13:32:32.598Z”,“pid”:23060,“message”:“* Workers: 8”}
2022-09-15_13:32:32.59872 {“timestamp”:“2022-09-15T13:32:32.598Z”,“pid”:23060,“message”:“* Restarts: () hot (
) phased”}
2022-09-15_13:32:32.59872 {“timestamp”:“2022-09-15T13:32:32.598Z”,“pid”:23060,“message”:“* Preloading application”}
2022-09-15_13:33:17.44170 {“timestamp”:“2022-09-15T13:33:17.439Z”,“pid”:23060,“message”:“* Listening on unix:///var/opt/gitlab/gitlab-rails/sockets/gitlab.socket”}
2022-09-15_13:33:17.44171 {“timestamp”:“2022-09-15T13:33:17.440Z”,“pid”:23060,“message”:“* Listening on http://127.0.0.1:8080”}
2022-09-15_13:33:17.44172 {“timestamp”:“2022-09-15T13:33:17.440Z”,“pid”:23060,“message”:“! WARNING: Detected 1 Thread(s) started in app boot:”}
2022-09-15_13:33:17.44172 {“timestamp”:“2022-09-15T13:33:17.440Z”,“pid”:23060,“message”:“! #\u003cThread:0x00007f7177e842d0 /opt/gitlab/embedded/lib/ruby/gems/2.7.0/gems/rack-timeout-0.5.2/lib/rack/timeout/support/scheduler.rb:73 sleep\u003e - /opt/gitlab/embedded/lib/ruby/gems/2.7.0/gems/rack-timeout-0.5.2/lib/rack/timeout/support/scheduler.rb:91:in `sleep’”}
2022-09-15_13:33:17.44172 {“timestamp”:“2022-09-15T13:33:17.440Z”,“pid”:23060,“message”:“Use Ctrl-C to stop”}
==> /var/log/gitlab/puma/puma_stdout.log <==
{“timestamp”:“2022-09-15T13:53:37.880Z”,“pid”:23060,“message”:“PumaWorkerKiller: Consuming 8267.75390625 mb with master and 8 workers.”}
root@rhosgitlab2 opt]# gitlab-ctl tail redis
==> /var/log/gitlab/redis/state <==
==> /var/log/gitlab/redis/current <==
2022-09-15_13:47:35.05730 23066:M 15 Sep 2022 14:47:35.057 * 10 changes in 300 seconds. Saving…
2022-09-15_13:47:35.05974 23066:M 15 Sep 2022 14:47:35.059 * Background saving started by pid 25595
2022-09-15_13:47:35.17469 25595:C 15 Sep 2022 14:47:35.174 * DB saved on disk
2022-09-15_13:47:35.17557 25595:C 15 Sep 2022 14:47:35.175 * RDB: 7 MB of memory used by copy-on-write
2022-09-15_13:47:35.25979 23066:M 15 Sep 2022 14:47:35.259 * Background saving terminated with success
2022-09-15_13:52:36.08872 23066:M 15 Sep 2022 14:52:36.088 * 10 changes in 300 seconds. Saving…
2022-09-15_13:52:36.09055 23066:M 15 Sep 2022 14:52:36.090 * Background saving started by pid 26333
2022-09-15_13:52:36.24830 26333:C 15 Sep 2022 14:52:36.248 * DB saved on disk
2022-09-15_13:52:36.25044 26333:C 15 Sep 2022 14:52:36.250 * RDB: 6 MB of memory used by copy-on-write
2022-09-15_13:52:36.29178 23066:M 15 Sep 2022 14:52:36.291 * Background saving terminated with success
Any help much appreciated. Please let me know if I need to provid any more logs or information.
Best Regards,
Kevin.