Very slow login after updating to 21.0.3

Goodmorning everyone,
I would need your help please.
On my server with:
Ubuntu Server 20.04.1
Php 7.4
mariadb
after updating, through the stable channel, to 21.0.1 (I was starting from 19, if I’m not mistaken), the login from pc became very long. Only from a PC, however, because the apps (Android and not) all work regularly.
Where did I go wrong?
Thank you all!

As usual check the content of the Nextcloud log file for any related messages which might unveal the root cause of your problem.

1 Like

Sorry, I’m not very experienced …
I found two log files, I hope they are the right ones: would you please help me interpret them?

Which ones have you found? The Nextcloud log file is the one of interest at first.

I found two: nextcloud.log and updater.log .
How can I go about posting them?

E.g. use:

tail -20 /path/to/nextclould.log

to read the last 20 log entries. Before you post them here, assure to remove/mask any identifying strings like IPs, domain names and such.

1 Like

Fine thanks!
Here are those of yesterday (since I updated to 21.0.1 yesterday) of the nextcloud.log file:

{“reqId”:“VV7nvt4dHBK9eoMVysOd”,“level”:1,“time”:“2021-04-13T13:05:42+00:00”,“remoteAddr”:“192.168.1.3”,“user”:"–",“app”:“no app in context”,“method”:“GET”,“url”:"/core/ajax/update.php?requesttoken=O1yDay5WCksbGKKjmflvCLigx4NcKGNyO9Op5zFJwXo%3D%3AcgzMIk15XwdCLebm6LM7WMHuge5kYAY5U%2BHL03wgqAg%3D",“message”:“Deprecated event type for \OC\Repair::step: Symfony\Component\EventDispatcher\GenericEvent is used”,“userAgent”:“Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:87.0) Gecko/20100101 Firefox/87.0”,“version”:“21.0.0.18”}
{“reqId”:“VV7nvt4dHBK9eoMVysOd”,“level”:1,“time”:“2021-04-13T13:05:42+00:00”,“remoteAddr”:“192.168.1.3”,“user”:"–",“app”:“updater”,“method”:“GET”,“url”:"/core/ajax/update.php?requesttoken=O1yDay5WCksbGKKjmflvCLigx4NcKGNyO9Op5zFJwXo%3D%3AcgzMIk15XwdCLebm6LM7WMHuge5kYAY5U%2BHL03wgqAg%3D",“message”:"\OC\Repair::step: Repair step: Clear access cache of projects",“userAgent”:“Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:87.0) Gecko/20100101 Firefox/87.0”,“version”:“21.0.0.18”}
{“reqId”:“VV7nvt4dHBK9eoMVysOd”,“level”:1,“time”:“2021-04-13T13:05:42+00:00”,“remoteAddr”:“192.168.1.3”,“user”:"–",“app”:“no app in context”,“method”:“GET”,“url”:"/core/ajax/update.php?requesttoken=O1yDay5WCksbGKKjmflvCLigx4NcKGNyO9Op5zFJwXo%3D%3AcgzMIk15XwdCLebm6LM7WMHuge5kYAY5U%2BHL03wgqAg%3D",“message”:“Deprecated event type for \OC\Repair::step: Symfony\Component\EventDispatcher\GenericEvent is used”,“userAgent”:“Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:87.0) Gecko/20100101 Firefox/87.0”,“version”:“21.0.0.18”}
{“reqId”:“VV7nvt4dHBK9eoMVysOd”,“level”:1,“time”:“2021-04-13T13:05:42+00:00”,“remoteAddr”:“192.168.1.3”,“user”:"–",“app”:“updater”,“method”:“GET”,“url”:"/core/ajax/update.php?requesttoken=O1yDay5WCksbGKKjmflvCLigx4NcKGNyO9Op5zFJwXo%3D%3AcgzMIk15XwdCLebm6LM7WMHuge5kYAY5U%2BHL03wgqAg%3D",“message”:"\OC\Repair::step: Repair step: Reset generated avatar flag",“userAgent”:“Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:87.0) Gecko/20100101 Firefox/87.0”,“version”:“21.0.0.18”}
{“reqId”:“VV7nvt4dHBK9eoMVysOd”,“level”:1,“time”:“2021-04-13T13:05:42+00:00”,“remoteAddr”:“192.168.1.3”,“user”:"–",“app”:“no app in context”,“method”:“GET”,“url”:"/core/ajax/update.php?requesttoken=O1yDay5WCksbGKKjmflvCLigx4NcKGNyO9Op5zFJwXo%3D%3AcgzMIk15XwdCLebm6LM7WMHuge5kYAY5U%2BHL03wgqAg%3D",“message”:“Deprecated event type for \OC\Repair::step: Symfony\Component\EventDispatcher\GenericEvent is used”,“userAgent”:“Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:87.0) Gecko/20100101 Firefox/87.0”,“version”:“21.0.0.18”}
{“reqId”:“VV7nvt4dHBK9eoMVysOd”,“level”:1,“time”:“2021-04-13T13:05:42+00:00”,“remoteAddr”:“192.168.1.3”,“user”:"–",“app”:“updater”,“method”:“GET”,“url”:"/core/ajax/update.php?requesttoken=O1yDay5WCksbGKKjmflvCLigx4NcKGNyO9Op5zFJwXo%3D%3AcgzMIk15XwdCLebm6LM7WMHuge5kYAY5U%2BHL03wgqAg%3D",“message”:"\OC\Repair::step: Repair step: Keep legacy encryption enabled",“userAgent”:“Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:87.0) Gecko/20100101 Firefox/87.0”,“version”:“21.0.0.18”}
{“reqId”:“VV7nvt4dHBK9eoMVysOd”,“level”:1,“time”:“2021-04-13T13:05:42+00:00”,“remoteAddr”:“192.168.1.3”,“user”:"–",“app”:“no app in context”,“method”:“GET”,“url”:"/core/ajax/update.php?requesttoken=O1yDay5WCksbGKKjmflvCLigx4NcKGNyO9Op5zFJwXo%3D%3AcgzMIk15XwdCLebm6LM7WMHuge5kYAY5U%2BHL03wgqAg%3D",“message”:“Deprecated event type for \OC\Repair::step: Symfony\Component\EventDispatcher\GenericEvent is used”,“userAgent”:“Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:87.0) Gecko/20100101 Firefox/87.0”,“version”:“21.0.0.18”}
{“reqId”:“VV7nvt4dHBK9eoMVysOd”,“level”:1,“time”:“2021-04-13T13:05:42+00:00”,“remoteAddr”:“192.168.1.3”,“user”:"–",“app”:“updater”,“method”:“GET”,“url”:"/core/ajax/update.php?requesttoken=O1yDay5WCksbGKKjmflvCLigx4NcKGNyO9Op5zFJwXo%3D%3AcgzMIk15XwdCLebm6LM7WMHuge5kYAY5U%2BHL03wgqAg%3D",“message”:"\OC\Repair::step: Repair step: Check encryption key format",“userAgent”:“Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:87.0) Gecko/20100101 Firefox/87.0”,“version”:“21.0.0.18”}
{“reqId”:“VV7nvt4dHBK9eoMVysOd”,“level”:1,“time”:“2021-04-13T13:05:42+00:00”,“remoteAddr”:“192.168.1.3”,“user”:"–",“app”:“no app in context”,“method”:“GET”,“url”:"/core/ajax/update.php?requesttoken=O1yDay5WCksbGKKjmflvCLigx4NcKGNyO9Op5zFJwXo%3D%3AcgzMIk15XwdCLebm6LM7WMHuge5kYAY5U%2BHL03wgqAg%3D",“message”:“Deprecated event type for \OC\Repair::step: Symfony\Component\EventDispatcher\GenericEvent is used”,“userAgent”:“Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:87.0) Gecko/20100101 Firefox/87.0”,“version”:“21.0.0.18”}
{“reqId”:“VV7nvt4dHBK9eoMVysOd”,“level”:1,“time”:“2021-04-13T13:05:42+00:00”,“remoteAddr”:“192.168.1.3”,“user”:"–",“app”:“updater”,“method”:“GET”,“url”:"/core/ajax/update.php?requesttoken=O1yDay5WCksbGKKjmflvCLigx4NcKGNyO9Op5zFJwXo%3D%3AcgzMIk15XwdCLebm6LM7WMHuge5kYAY5U%2BHL03wgqAg%3D",“message”:"\OC\Repair::step: Repair step: Remove old dashboard app config data",“userAgent”:“Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:87.0) Gecko/20100101 Firefox/87.0”,“version”:“21.0.0.18”}
{“reqId”:“VV7nvt4dHBK9eoMVysOd”,“level”:1,“time”:“2021-04-13T13:05:42+00:00”,“remoteAddr”:“192.168.1.3”,“user”:"–",“app”:“no app in context”,“method”:“GET”,“url”:"/core/ajax/update.php?requesttoken=O1yDay5WCksbGKKjmflvCLigx4NcKGNyO9Op5zFJwXo%3D%3AcgzMIk15XwdCLebm6LM7WMHuge5kYAY5U%2BHL03wgqAg%3D",“message”:“Deprecated event type for \OC\Repair::step: Symfony\Component\EventDispatcher\GenericEvent is used”,“userAgent”:“Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:87.0) Gecko/20100101 Firefox/87.0”,“version”:“21.0.0.18”}
{“reqId”:“VV7nvt4dHBK9eoMVysOd”,“level”:1,“time”:“2021-04-13T13:05:42+00:00”,“remoteAddr”:“192.168.1.3”,“user”:"–",“app”:“updater”,“method”:“GET”,“url”:"/core/ajax/update.php?requesttoken=O1yDay5WCksbGKKjmflvCLigx4NcKGNyO9Op5zFJwXo%3D%3AcgzMIk15XwdCLebm6LM7WMHuge5kYAY5U%2BHL03wgqAg%3D",“message”:"\OC\Repair::step: Repair step: Add job to cleanup the bruteforce entries",“userAgent”:“Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:87.0) Gecko/20100101 Firefox/87.0”,“version”:“21.0.0.18”}
{“reqId”:“VV7nvt4dHBK9eoMVysOd”,“level”:1,“time”:“2021-04-13T13:05:42+00:00”,“remoteAddr”:“192.168.1.3”,“user”:"–",“app”:“no app in context”,“method”:“GET”,“url”:"/core/ajax/update.php?requesttoken=O1yDay5WCksbGKKjmflvCLigx4NcKGNyO9Op5zFJwXo%3D%3AcgzMIk15XwdCLebm6LM7WMHuge5kYAY5U%2BHL03wgqAg%3D",“message”:“Deprecated event type for \OC\Repair::step: Symfony\Component\EventDispatcher\GenericEvent is used”,“userAgent”:“Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:87.0) Gecko/20100101 Firefox/87.0”,“version”:“21.0.0.18”}
{“reqId”:“VV7nvt4dHBK9eoMVysOd”,“level”:1,“time”:“2021-04-13T13:05:42+00:00”,“remoteAddr”:“192.168.1.3”,“user”:"–",“app”:“updater”,“method”:“GET”,“url”:"/core/ajax/update.php?requesttoken=O1yDay5WCksbGKKjmflvCLigx4NcKGNyO9Op5zFJwXo%3D%3AcgzMIk15XwdCLebm6LM7WMHuge5kYAY5U%2BHL03wgqAg%3D",“message”:"\OC\Repair::step: Repair step: Queue a one-time job to check for user uploaded certificates",“userAgent”:“Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:87.0) Gecko/20100101 Firefox/87.0”,“version”:“21.0.0.18”}
{“reqId”:“VV7nvt4dHBK9eoMVysOd”,“level”:1,“time”:“2021-04-13T13:05:42+00:00”,“remoteAddr”:“192.168.1.3”,“user”:"–",“app”:“no app in context”,“method”:“GET”,“url”:"/core/ajax/update.php?requesttoken=O1yDay5WCksbGKKjmflvCLigx4NcKGNyO9Op5zFJwXo%3D%3AcgzMIk15XwdCLebm6LM7WMHuge5kYAY5U%2BHL03wgqAg%3D",“message”:“Deprecated event type for \OC\Repair::step: Symfony\Component\EventDispatcher\GenericEvent is used”,“userAgent”:“Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:87.0) Gecko/20100101 Firefox/87.0”,“version”:“21.0.0.18”}
{“reqId”:“VV7nvt4dHBK9eoMVysOd”,“level”:1,“time”:“2021-04-13T13:05:42+00:00”,“remoteAddr”:“192.168.1.3”,“user”:"–",“app”:“updater”,“method”:“GET”,“url”:"/core/ajax/update.php?requesttoken=O1yDay5WCksbGKKjmflvCLigx4NcKGNyO9Op5zFJwXo%3D%3AcgzMIk15XwdCLebm6LM7WMHuge5kYAY5U%2BHL03wgqAg%3D",“message”:"\OC\Repair::step: Repair step: Repair DAV shares",“userAgent”:“Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:87.0) Gecko/20100101 Firefox/87.0”,“version”:“21.0.0.18”}
{“reqId”:“VV7nvt4dHBK9eoMVysOd”,“level”:1,“time”:“2021-04-13T13:05:42+00:00”,“remoteAddr”:“192.168.1.3”,“user”:"–",“app”:“updater”,“method”:“GET”,“url”:"/core/ajax/update.php?requesttoken=O1yDay5WCksbGKKjmflvCLigx4NcKGNyO9Op5zFJwXo%3D%3AcgzMIk15XwdCLebm6LM7WMHuge5kYAY5U%2BHL03wgqAg%3D",“message”:"\OC\Updater::startCheckCodeIntegrity: Starting code integrity check…",“userAgent”:“Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:87.0) Gecko/20100101 Firefox/87.0”,“version”:“21.0.0.18”}
{“reqId”:“VV7nvt4dHBK9eoMVysOd”,“level”:1,“time”:“2021-04-13T13:05:48+00:00”,“remoteAddr”:“192.168.1.3”,“user”:"–",“app”:“updater”,“method”:“GET”,“url”:"/core/ajax/update.php?requesttoken=O1yDay5WCksbGKKjmflvCLigx4NcKGNyO9Op5zFJwXo%3D%3AcgzMIk15XwdCLebm6LM7WMHuge5kYAY5U%2BHL03wgqAg%3D",“message”:"\OC\Updater::finishedCheckCodeIntegrity: Finished code integrity check",“userAgent”:“Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:87.0) Gecko/20100101 Firefox/87.0”,“version”:“21.0.0.18”}
{“reqId”:“VV7nvt4dHBK9eoMVysOd”,“level”:1,“time”:“2021-04-13T13:05:48+00:00”,“remoteAddr”:“192.168.1.3”,“user”:"–",“app”:“updater”,“method”:“GET”,“url”:"/core/ajax/update.php?requesttoken=O1yDay5WCksbGKKjmflvCLigx4NcKGNyO9Op5zFJwXo%3D%3AcgzMIk15XwdCLebm6LM7WMHuge5kYAY5U%2BHL03wgqAg%3D",“message”:"\OC\Updater::updateEnd: Update successful",“userAgent”:“Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:87.0) Gecko/20100101 Firefox/87.0”,“version”:“21.0.1.1”}
{“reqId”:“VV7nvt4dHBK9eoMVysOd”,“level”:1,“time”:“2021-04-13T13:05:48+00:00”,“remoteAddr”:“192.168.1.3”,“user”:"–",“app”:“updater”,“method”:“GET”,“url”:"/core/ajax/update.php?requesttoken=O1yDay5WCksbGKKjmflvCLigx4NcKGNyO9Op5zFJwXo%3D%3AcgzMIk15XwdCLebm6LM7WMHuge5kYAY5U%2BHL03wgqAg%3D",“message”:"\OC\Updater::maintenanceDisabled: Turned off maintenance

and these are those of the updater.log file:

2021-04-13T13:04:23+0000 914Ges2Sx0 [info] request to updater
2021-04-13T13:04:23+0000 914Ges2Sx0 [info] currentStep()
2021-04-13T13:04:23+0000 914Ges2Sx0 [info] show HTML page
2021-04-13T13:04:23+0000 914Ges2Sx0 [info] current version: 21.0.0 build time: 2021-02-19T08:52:18+00:00 8985b7930653139859002eb3eca2852999ed8f0d
2021-04-13T13:04:23+0000 914Ges2Sx0 [info] getUpdateServerResponse()
2021-04-13T13:04:23+0000 914Ges2Sx0 [info] updaterServer: https://updates.nextcloud.com/updater_server/
2021-04-13T13:04:23+0000 914Ges2Sx0 [info] releaseChannel: stable
2021-04-13T13:04:23+0000 914Ges2Sx0 [info] internal version: 21.0.0.18
2021-04-13T13:04:23+0000 914Ges2Sx0 [info] updateURL: https://updates.nextcloud.com/updater_server/?version=21x0x0x18xxxstablexx2021-02-19T08%3A52%3A18%2B00%3A00+8985b7930653139859002eb3eca2852999ed8f0dx7x4x16
2021-04-13T13:04:23+0000 914Ges2Sx0 [info] getUpdateServerResponse response: Array
(
[version] => 21.0.1.1
[versionstring] => Nextcloud 21.0.1
[url] => https://download.nextcloud.com/server/releases/nextcloud-21.0.1.zip
[web] => How to upgrade — Nextcloud latest Administration Manual latest documentation
[changes] => https://updates.nextcloud.com/changelog_server/?version=21.0.1
[autoupdater] => 1
[eol] => 0
[signature] => YJQMb8iq13NhbvfEddaSjNeqlMe/dB0nJ27EwmiAuqSMMRpvVg4BjijfNjoG8lY9
rs0k+YN4EkGSr1lhZvdWZ9LpmP1wug/l1wj8lWzjxp/588yp5jHs24XBsi14GrDY
tgcx/V6E2ELFfgXJY6R4y2bIaaDPPjDfi+a2nq5ut0RTalGaUh6jr1dzaKQ0rJXm
lqcZSGdY+smeb7ciFf0hykivKZATXHLczvYO3FUu/HVqlgRUxi+Q+wNjFmfFL3Vr
RGlZGz7bpa48/2sprNJ2CYGVLMjxtktUrUIH6NB0diVMK8kAd7+OIqtjCmUG307S
qRc0DHdtzXMmzq4t4PfZhg==
)

2021-04-13T13:04:23+0000 914Ges2Sx0 [info] checkForUpdate() Array
(
[version] => 21.0.1.1
[versionstring] => Nextcloud 21.0.1
[url] => https://download.nextcloud.com/server/releases/nextcloud-21.0.1.zip
[web] => How to upgrade — Nextcloud latest Administration Manual latest documentation
[changes] => https://updates.nextcloud.com/changelog_server/?version=21.0.1
[autoupdater] => 1
[eol] => 0
[signature] => YJQMb8iq13NhbvfEddaSjNeqlMe/dB0nJ27EwmiAuqSMMRpvVg4BjijfNjoG8lY9
rs0k+YN4EkGSr1lhZvdWZ9LpmP1wug/l1wj8lWzjxp/588yp5jHs24XBsi14GrDY
tgcx/V6E2ELFfgXJY6R4y2bIaaDPPjDfi+a2nq5ut0RTalGaUh6jr1dzaKQ0rJXm
lqcZSGdY+smeb7ciFf0hykivKZATXHLczvYO3FUu/HVqlgRUxi+Q+wNjFmfFL3Vr
RGlZGz7bpa48/2sprNJ2CYGVLMjxtktUrUIH6NB0diVMK8kAd7+OIqtjCmUG307S
qRc0DHdtzXMmzq4t4PfZhg==
)

2021-04-13T13:04:23+0000 914Ges2Sx0 [info] getChangelogURL()
2021-04-13T13:04:23+0000 914Ges2Sx0 [info] end of checkForUpdate() Update to Nextcloud 21.0.1 available. (channel: “stable”)
Following file will be downloaded automatically: https://download.nextcloud.com/server/releases/nextcloud-21.0.1.zip
Open changelog :arrow_upper_right:
2021-04-13T13:04:25+0000 0iRntoxTBj [info] request to updater
2021-04-13T13:04:25+0000 0iRntoxTBj [info] currentStep()
2021-04-13T13:04:25+0000 0iRntoxTBj [info] POST request for step “1”
2021-04-13T13:04:25+0000 0iRntoxTBj [info] startStep(“1”)
2021-04-13T13:04:25+0000 0iRntoxTBj [info] checkForExpectedFilesAndFolders()
2021-04-13T13:04:25+0000 0iRntoxTBj [info] end of checkForExpectedFilesAndFolders()
2021-04-13T13:04:25+0000 0iRntoxTBj [info] endStep(“1”)
2021-04-13T13:04:25+0000 Fsj6wrrCm5 [info] request to updater
2021-04-13T13:04:25+0000 Fsj6wrrCm5 [info] currentStep()
2021-04-13T13:04:25+0000 Fsj6wrrCm5 [info] Step 1 is in state “end”.
2021-04-13T13:04:25+0000 Fsj6wrrCm5 [info] POST request for step “2”
2021-04-13T13:04:25+0000 Fsj6wrrCm5 [info] startStep(“2”)
2021-04-13T13:04:25+0000 Fsj6wrrCm5 [info] checkWritePermissions()
2021-04-13T13:04:25+0000 Fsj6wrrCm5 [info] end of checkWritePermissions()
2021-04-13T13:04:25+0000 Fsj6wrrCm5 [info] endStep(“2”)
2021-04-13T13:04:25+0000 39JFFfpXOi [info] request to updater
2021-04-13T13:04:25+0000 39JFFfpXOi [info] currentStep()
2021-04-13T13:04:25+0000 39JFFfpXOi [info] Step 2 is in state “end”.
2021-04-13T13:04:25+0000 39JFFfpXOi [info] POST request for step “3”
2021-04-13T13:04:25+0000 39JFFfpXOi [info] startStep(“3”)
2021-04-13T13:04:25+0000 39JFFfpXOi [info] createBackup()
2021-04-13T13:04:25+0000 39JFFfpXOi [info] backup folder location: /home/orestis/nextcloud/data/updater-oci2jv9xgwal/backups/nextcloud-21.0.0.18-1618319065/
2021-04-13T13:04:47+0000 39JFFfpXOi [info] end of createBackup()
2021-04-13T13:04:47+0000 39JFFfpXOi [info] endStep(“3”)
2021-04-13T13:04:47+0000 zejF10dhZm [info] request to updater
2021-04-13T13:04:47+0000 zejF10dhZm [info] currentStep()
2021-04-13T13:04:47+0000 zejF10dhZm [info] Step 3 is in state “end”.
2021-04-13T13:04:47+0000 zejF10dhZm [info] POST request for step “4”
2021-04-13T13:04:47+0000 zejF10dhZm [info] startStep(“4”)
2021-04-13T13:04:47+0000 zejF10dhZm [info] downloadUpdate()
2021-04-13T13:04:47+0000 zejF10dhZm [info] getUpdateServerResponse()
2021-04-13T13:04:47+0000 zejF10dhZm [info] updaterServer: https://updates.nextcloud.com/updater_server/
2021-04-13T13:04:47+0000 zejF10dhZm [info] releaseChannel: stable
2021-04-13T13:04:47+0000 zejF10dhZm [info] internal version: 21.0.0.18
2021-04-13T13:04:47+0000 zejF10dhZm [info] updateURL: https://updates.nextcloud.com/updater_server/?version=21x0x0x18xxxstablexx2021-02-19T08%3A52%3A18%2B00%3A00+8985b7930653139859002eb3eca2852999ed8f0dx7x4x16
2021-04-13T13:04:47+0000 zejF10dhZm [info] getUpdateServerResponse response: Array
(
[version] => 21.0.1.1
[versionstring] => Nextcloud 21.0.1
[url] => https://download.nextcloud.com/server/releases/nextcloud-21.0.1.zip
[web] => How to upgrade — Nextcloud latest Administration Manual latest documentation
[changes] => https://updates.nextcloud.com/changelog_server/?version=21.0.1
[autoupdater] => 1
[eol] => 0
[signature] => YJQMb8iq13NhbvfEddaSjNeqlMe/dB0nJ27EwmiAuqSMMRpvVg4BjijfNjoG8lY9
rs0k+YN4EkGSr1lhZvdWZ9LpmP1wug/l1wj8lWzjxp/588yp5jHs24XBsi14GrDY
tgcx/V6E2ELFfgXJY6R4y2bIaaDPPjDfi+a2nq5ut0RTalGaUh6jr1dzaKQ0rJXm
lqcZSGdY+smeb7ciFf0hykivKZATXHLczvYO3FUu/HVqlgRUxi+Q+wNjFmfFL3Vr
RGlZGz7bpa48/2sprNJ2CYGVLMjxtktUrUIH6NB0diVMK8kAd7+OIqtjCmUG307S
qRc0DHdtzXMmzq4t4PfZhg==
)

2021-04-13T13:04:47+0000 zejF10dhZm [info] storage location exists
2021-04-13T13:05:02+0000 zejF10dhZm [info] end of downloadUpdate()
2021-04-13T13:05:02+0000 zejF10dhZm [info] endStep(“4”)
2021-04-13T13:05:02+0000 fNNaUXg65f [info] request to updater
2021-04-13T13:05:02+0000 fNNaUXg65f [info] currentStep()
2021-04-13T13:05:02+0000 fNNaUXg65f [info] Step 4 is in state “end”.
2021-04-13T13:05:02+0000 fNNaUXg65f [info] POST request for step “5”
2021-04-13T13:05:02+0000 fNNaUXg65f [info] startStep(“5”)
2021-04-13T13:05:02+0000 fNNaUXg65f [info] verifyIntegrity()
2021-04-13T13:05:02+0000 fNNaUXg65f [info] getUpdateServerResponse()
2021-04-13T13:05:02+0000 fNNaUXg65f [info] updaterServer: https://updates.nextcloud.com/updater_server/
2021-04-13T13:05:02+0000 fNNaUXg65f [info] releaseChannel: stable
2021-04-13T13:05:02+0000 fNNaUXg65f [info] internal version: 21.0.0.18
2021-04-13T13:05:02+0000 fNNaUXg65f [info] updateURL: https://updates.nextcloud.com/updater_server/?version=21x0x0x18xxxstablexx2021-02-19T08%3A52%3A18%2B00%3A00+8985b7930653139859002eb3eca2852999ed8f0dx7x4x16
2021-04-13T13:05:02+0000 fNNaUXg65f [info] getUpdateServerResponse response: Array
(
[version] => 21.0.1.1
[versionstring] => Nextcloud 21.0.1
[url] => https://download.nextcloud.com/server/releases/nextcloud-21.0.1.zip
[web] => How to upgrade — Nextcloud latest Administration Manual latest documentation
[changes] => https://updates.nextcloud.com/changelog_server/?version=21.0.1
[autoupdater] => 1
[eol] => 0
[signature] => YJQMb8iq13NhbvfEddaSjNeqlMe/dB0nJ27EwmiAuqSMMRpvVg4BjijfNjoG8lY9
rs0k+YN4EkGSr1lhZvdWZ9LpmP1wug/l1wj8lWzjxp/588yp5jHs24XBsi14GrDY
tgcx/V6E2ELFfgXJY6R4y2bIaaDPPjDfi+a2nq5ut0RTalGaUh6jr1dzaKQ0rJXm
lqcZSGdY+smeb7ciFf0hykivKZATXHLczvYO3FUu/HVqlgRUxi+Q+wNjFmfFL3Vr
RGlZGz7bpa48/2sprNJ2CYGVLMjxtktUrUIH6NB0diVMK8kAd7+OIqtjCmUG307S
qRc0DHdtzXMmzq4t4PfZhg==
)

2021-04-13T13:05:02+0000 fNNaUXg65f [info] storage location: /home/orestis/nextcloud/data/updater-oci2jv9xgwal/downloads/
2021-04-13T13:05:03+0000 fNNaUXg65f [info] end of verifyIntegrity()
2021-04-13T13:05:03+0000 fNNaUXg65f [info] endStep(“5”)
2021-04-13T13:05:03+0000 EDQWCNlukU [info] request to updater
2021-04-13T13:05:03+0000 EDQWCNlukU [info] currentStep()
2021-04-13T13:05:03+0000 EDQWCNlukU [info] Step 5 is in state “end”.
2021-04-13T13:05:03+0000 EDQWCNlukU [info] POST request for step “6”
2021-04-13T13:05:03+0000 EDQWCNlukU [info] startStep(“6”)
2021-04-13T13:05:03+0000 EDQWCNlukU [info] extractDownload()
2021-04-13T13:05:03+0000 EDQWCNlukU [info] storage location: /home/orestis/nextcloud/data/updater-oci2jv9xgwal/downloads/
2021-04-13T13:05:06+0000 EDQWCNlukU [info] end of extractDownload()
2021-04-13T13:05:06+0000 EDQWCNlukU [info] endStep(“6”)
2021-04-13T13:05:06+0000 Fweq5gZAsb [info] request to updater
2021-04-13T13:05:06+0000 Fweq5gZAsb [info] currentStep()
2021-04-13T13:05:06+0000 Fweq5gZAsb [info] Step 6 is in state “end”.
2021-04-13T13:05:06+0000 Fweq5gZAsb [info] POST request for step “7”
2021-04-13T13:05:07+0000 Fweq5gZAsb [info] startStep(“7”)
2021-04-13T13:05:07+0000 Fweq5gZAsb [info] setMaintenanceMode(“true”)
2021-04-13T13:05:07+0000 Fweq5gZAsb [info] configFileName /var/www/html/nextcloud/updater/…/config/config.php
2021-04-13T13:05:07+0000 Fweq5gZAsb [info] end of setMaintenanceMode()
2021-04-13T13:05:07+0000 Fweq5gZAsb [info] endStep(“7”)
2021-04-13T13:05:07+0000 KpqbLZcyH5 [info] request to updater
2021-04-13T13:05:07+0000 KpqbLZcyH5 [info] currentStep()
2021-04-13T13:05:07+0000 KpqbLZcyH5 [info] Step 7 is in state “end”.
2021-04-13T13:05:07+0000 KpqbLZcyH5 [info] POST request for step “8”
2021-04-13T13:05:07+0000 KpqbLZcyH5 [info] startStep(“8”)
2021-04-13T13:05:07+0000 KpqbLZcyH5 [info] replaceEntryPoints()
2021-04-13T13:05:07+0000 KpqbLZcyH5 [info] replace index.php
2021-04-13T13:05:07+0000 KpqbLZcyH5 [info] replace status.php
2021-04-13T13:05:07+0000 KpqbLZcyH5 [info] replace remote.php
2021-04-13T13:05:07+0000 KpqbLZcyH5 [info] replace public.php
2021-04-13T13:05:07+0000 KpqbLZcyH5 [info] replace ocs/v1.php
2021-04-13T13:05:07+0000 KpqbLZcyH5 [info] replace ocs/v2.php
2021-04-13T13:05:07+0000 KpqbLZcyH5 [info] end of replaceEntryPoints()
2021-04-13T13:05:07+0000 KpqbLZcyH5 [info] endStep(“8”)
2021-04-13T13:05:07+0000 pl0xzzfJWp [info] request to updater
2021-04-13T13:05:07+0000 pl0xzzfJWp [info] currentStep()
2021-04-13T13:05:07+0000 pl0xzzfJWp [info] Step 8 is in state “end”.
2021-04-13T13:05:07+0000 pl0xzzfJWp [info] POST request for step “9”
2021-04-13T13:05:07+0000 pl0xzzfJWp [info] startStep(“9”)
2021-04-13T13:05:07+0000 pl0xzzfJWp [info] deleteOldFiles()
2021-04-13T13:05:07+0000 pl0xzzfJWp [info] config sample exists
2021-04-13T13:05:07+0000 pl0xzzfJWp [info] themes README exists
2021-04-13T13:05:08+0000 pl0xzzfJWp [info] end of deleteOldFiles()
2021-04-13T13:05:08+0000 pl0xzzfJWp [info] endStep(“9”)
2021-04-13T13:05:08+0000 b7bEdcqeVk [info] request to updater
2021-04-13T13:05:08+0000 b7bEdcqeVk [info] currentStep()
2021-04-13T13:05:08+0000 b7bEdcqeVk [info] Step 9 is in state “end”.
2021-04-13T13:05:08+0000 b7bEdcqeVk [info] POST request for step “10”
2021-04-13T13:05:08+0000 b7bEdcqeVk [info] startStep(“10”)
2021-04-13T13:05:08+0000 b7bEdcqeVk [info] moveNewVersionInPlace()
2021-04-13T13:05:08+0000 b7bEdcqeVk [info] storage location: /home/orestis/nextcloud/data/updater-oci2jv9xgwal/downloads/nextcloud/
2021-04-13T13:05:09+0000 b7bEdcqeVk [info] end of moveNewVersionInPlace()
2021-04-13T13:05:09+0000 b7bEdcqeVk [info] endStep(“10”)
2021-04-13T13:05:17+0000 mKdLPQTdoG [info] request to updater
2021-04-13T13:05:17+0000 mKdLPQTdoG [info] currentStep()
2021-04-13T13:05:17+0000 mKdLPQTdoG [info] Step 10 is in state “end”.
2021-04-13T13:05:17+0000 mKdLPQTdoG [info] POST request for step “11”
2021-04-13T13:05:17+0000 mKdLPQTdoG [info] startStep(“11”)
2021-04-13T13:05:17+0000 mKdLPQTdoG [info] setMaintenanceMode(“false”)
2021-04-13T13:05:17+0000 mKdLPQTdoG [info] configFileName /var/www/html/nextcloud/updater/…/config/config.php
2021-04-13T13:05:17+0000 mKdLPQTdoG [info] end of setMaintenanceMode()
2021-04-13T13:05:17+0000 mKdLPQTdoG [info] endStep(“11”)
2021-04-13T13:05:17+0000 3HVwIf3O6k [info] request to updater
2021-04-13T13:05:17+0000 3HVwIf3O6k [info] currentStep()
2021-04-13T13:05:17+0000 3HVwIf3O6k [info] Step 11 is in state “end”.
2021-04-13T13:05:17+0000 3HVwIf3O6k [info] POST request for step “12”
2021-04-13T13:05:17+0000 3HVwIf3O6k [info] startStep(“12”)
2021-04-13T13:05:17+0000 3HVwIf3O6k [info] finalize()
2021-04-13T13:05:17+0000 3HVwIf3O6k [info] storage location: /home/orestis/nextcloud/data/updater-oci2jv9xgwal/downloads/nextcloud/
2021-04-13T13:05:17+0000 3HVwIf3O6k [info] call opcache_reset()
2021-04-13T13:05:17+0000 3HVwIf3O6k [info] end of finalize()
2021-04-13T13:05:17+0000 3HVwIf3O6k [info] endStep(“12”)

I do not see any error. Btw such logs are best readable here in a Markdown forum when you wrap them into code fences:

```
paste logs here
```

Does the webserver or kernel log any errors? E.g. when using Apache2 usually it’s:

cat /var/log/apache2/error.log
dmesg -l emerg,alert,crit,err

Thank you for your patience!
So this is the result:

orestis@orestis:~$ sudo dmesg -l emerg,alert,crit,err
[sudo] password di orestis:
[ 3.253970] sd 0:0:0:0: [sdb] No Caching mode page found
[ 3.253976] sd 0:0:0:0: [sdb] Assuming drive cache: write through
[84338.153477] usb 1-1: device descriptor read/all, error -71
[92184.081376] FAT-fs (sdc1): unable to read boot sector to mark fs as dirty

Same problem there:

  • login on a specific user very slow 60s, so slow my nginx gives error 405, cant connect thru the reverse proxy !
  • login time ok from nextcloud-admin and other users

Happend when I upgrade from 21.0 (no problem) to 21.0.1.1

I tried to disabled most application without change like in: [Solved] Nextcloud 21 very slow and connection impossible since upgrade
May be correlated with oc_filecache which is huge.

Just hope someone find a solution or a workaround.

Same issue here. Extremely slow after upgrade to 21.0.1

There were 1-2 errors for the sdc1 device. Since it’s a FAT file system, it cannot be the Nextcloud data directory (missing POSIX permissions support). So while you should have a look at that, e.g. start with fsck /dev/sdc1, it should not be related to the slow login.

Which webserver do you use? Does it’s log show anything related at the time when you login? And does the browser console show any error or warning when you access the login page or after login?

And does the admin panel in Nextcloud show a warning, e.g. about missing indices, columns or primary keys in the database?

1 Like

Thanks again for your advice!
A little while ago, I repeated the command you advised me yesterday, and this was the result:

orestis@orestis:~$ sudo dmesg -l emerg,alert,crit,err
[    3.253970] sd 0:0:0:0: [sdb] No Caching mode page found
[    3.253976] sd 0:0:0:0: [sdb] Assuming drive cache: write through
[84338.153477] usb 1-1: device descriptor read/all, error -71
[92184.081376] FAT-fs (sdc1): unable to read boot sector to mark fs as dirty
[98129.297883] sd 7:0:0:0: [sdc] tag#0 access beyond end of device
[98129.297890] blk_update_request: I/O error, dev sdc, sector 30277504 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 0
[98129.297908] sd 7:0:0:0: [sdc] tag#0 access beyond end of device
[98129.297911] blk_update_request: I/O error, dev sdc, sector 30277504 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 0
[98129.297913] Buffer I/O error on dev sdc, logical block 3784688, async page read

I’m really afraid I have operating system problems… :weary:
This weekend I’ll try to format everything and do a clean install.

@mercurio this is an error of your storage, maybe there is a bad sector in it…

As said, I don’t think it is related to your slow Nextcloud logins. Please check the webserver logs for this:

cat /var/log/apache2/error.log

This command works in most cases when Apache2 is the webserver with default logging settings, but do you use Apache2 as well or another webserver?


To check the drive that causes the I/O errors:

umount /dev/sdc1
fsck /dev/sdc1
1 Like

You don’t give up, I see: thank you! :blush: :bowing_man:
This is the result of the first command:

orestis@orestis:~$ cat /var/log/apache2/error.log
[Thu Apr 15 00:00:13.915264 2021] [mpm_prefork:notice] [pid 969] AH00163: Apache/2.4.41 (Ubuntu) OpenSSL/1.1.1g configured -- resuming normal operations
[Thu Apr 15 00:00:13.915291 2021] [core:notice] [pid 969] AH00094: Command line: '/usr/sbin/apache2'
[Thu Apr 15 00:14:47.142411 2021] [php7:error] [pid 17429] [client 1.14.141.139:55188] script '/var/www/html/elrekt.php' not found or unable to stat
[Thu Apr 15 00:14:47.632552 2021] [php7:error] [pid 17556] [client 1.14.141.139:56342] script '/var/www/html/index.php' not found or unable to stat
[Thu Apr 15 00:49:22.096061 2021] [php7:error] [pid 17429] [client 46.101.188.139:52516] script '/var/www/html/wp-login.php' not found or unable to stat
[Thu Apr 15 07:58:22.226626 2021] [php7:error] [pid 21435] [client 188.166.115.110:60548] script '/var/www/html/system_api.php' not found or unable to stat
[Thu Apr 15 07:58:22.427084 2021] [php7:error] [pid 21438] [client 188.166.115.110:44534] script '/var/www/html/nextcloud/system_api.php' not found or unable to stat
[Thu Apr 15 07:58:22.552813 2021] [php7:error] [pid 20587] [client 188.166.115.110:60752] script '/var/www/html/system_api.php' not found or unable to stat
[Thu Apr 15 07:58:22.753064 2021] [php7:error] [pid 17715] [client 188.166.115.110:44730] script '/var/www/html/nextcloud/system_api.php' not found or unable to stat
[Thu Apr 15 11:31:24.979200 2021] [php7:error] [pid 22423] [client 45.155.205.211:35734] script '/var/www/html/index.php' not found or unable to stat

While, as for the others, it tells me that there is no sdc1 drive…

Okay, webserver logs are fine as well. No errors aside of usual bots trying to access random API entpoints.

Ah least we can check PHP as well. Based on the webserver logs I’d say you use PHP-FPM, so:

journalctl -u php7.4-fpm
cat /var/log/php7.4-fpm.log

As of the drive: Do you know which one sdc/sdc1 is? The file system/partition name sdc1 was part of the second kernel error, but below only the raw drive name sdc is mentioned, so probably the partition is not even recognised anymore. But to be sure:

lsblk
df -T

Be sure to mask sensitive info from the output, e.g. CurlFtsFS and CIFS mounts may contain access credentials (passwords).

2 Likes

Here I am, thank you! :smiley:
Of all the lines in journalctl -u php7.4-fpm, I only posted those from the day of the update to 21.0.1:

apr 13 15:10:40 orestis systemd[1]: php7.4-fpm.service: Succeeded.
apr 13 15:10:40 orestis systemd[1]: Stopped The PHP 7.4 FastCGI Process Manager.
-- Reboot --
apr 13 15:11:49 orestis systemd[1]: Starting The PHP 7.4 FastCGI Process Manager...
apr 13 15:12:17 orestis systemd[1]: Started The PHP 7.4 FastCGI Process Manager.
apr 13 15:14:23 orestis systemd[1]: Stopping The PHP 7.4 FastCGI Process Manager...
apr 13 15:14:25 orestis systemd[1]: php7.4-fpm.service: Succeeded.
apr 13 15:14:25 orestis systemd[1]: Stopped The PHP 7.4 FastCGI Process Manager.
-- Reboot --
apr 13 15:15:32 orestis systemd[1]: Starting The PHP 7.4 FastCGI Process Manager...
apr 13 15:16:01 orestis systemd[1]: Started The PHP 7.4 FastCGI Process Manager.
apr 13 15:45:35 orestis systemd[1]: Stopping The PHP 7.4 FastCGI Process Manager...
apr 13 15:45:35 orestis systemd[1]: php7.4-fpm.service: Succeeded.
apr 13 15:45:35 orestis systemd[1]: Stopped The PHP 7.4 FastCGI Process Manager.
-- Reboot --
apr 13 15:46:41 orestis systemd[1]: Starting The PHP 7.4 FastCGI Process Manager...
apr 13 15:47:10 orestis systemd[1]: Started The PHP 7.4 FastCGI Process Manager.
apr 13 16:22:21 orestis systemd[1]: Stopping The PHP 7.4 FastCGI Process Manager...
apr 13 16:22:21 orestis systemd[1]: php7.4-fpm.service: Succeeded.
apr 13 16:22:21 orestis systemd[1]: Stopped The PHP 7.4 FastCGI Process Manager.
-- Reboot --
apr 13 16:23:27 orestis systemd[1]: Starting The PHP 7.4 FastCGI Process Manager...
apr 13 16:23:58 orestis systemd[1]: Started The PHP 7.4 FastCGI Process Manager.
apr 13 16:42:36 orestis systemd[1]: Stopping The PHP 7.4 FastCGI Process Manager...
apr 13 16:42:36 orestis systemd[1]: php7.4-fpm.service: Succeeded.
apr 13 16:42:36 orestis systemd[1]: Stopped The PHP 7.4 FastCGI Process Manager.
-- Reboot --
apr 13 16:43:41 orestis systemd[1]: Starting The PHP 7.4 FastCGI Process Manager...
apr 13 16:44:10 orestis systemd[1]: Started The PHP 7.4 FastCGI Process Manager

cat /var/log/php7.4-fpm.log:

orestis@orestis:~$ sudo cat /var/log/php7.4-fpm.log
[sudo] password di orestis: 
[11-Apr-2021 00:00:16] NOTICE: error log file re-opened
[13-Apr-2021 15:10:39] NOTICE: Terminating ...
[13-Apr-2021 15:10:39] NOTICE: exiting, bye-bye!
[13-Apr-2021 15:12:13] NOTICE: fpm is running, pid 794
[13-Apr-2021 15:12:13] NOTICE: ready to handle connections
[13-Apr-2021 15:12:13] NOTICE: systemd monitor interval set to 10000ms
[13-Apr-2021 15:14:23] NOTICE: Terminating ...
[13-Apr-2021 15:14:23] NOTICE: exiting, bye-bye!
[13-Apr-2021 15:15:57] NOTICE: fpm is running, pid 791
[13-Apr-2021 15:15:57] NOTICE: ready to handle connections
[13-Apr-2021 15:15:57] NOTICE: systemd monitor interval set to 10000ms
[13-Apr-2021 15:45:35] NOTICE: Terminating ...
[13-Apr-2021 15:45:35] NOTICE: exiting, bye-bye!
[13-Apr-2021 15:47:06] NOTICE: fpm is running, pid 794
[13-Apr-2021 15:47:06] NOTICE: ready to handle connections
[13-Apr-2021 15:47:06] NOTICE: systemd monitor interval set to 10000ms
[13-Apr-2021 16:22:21] NOTICE: Terminating ...
[13-Apr-2021 16:22:21] NOTICE: exiting, bye-bye!
[13-Apr-2021 16:23:55] NOTICE: fpm is running, pid 790
[13-Apr-2021 16:23:55] NOTICE: ready to handle connections
[13-Apr-2021 16:23:55] NOTICE: systemd monitor interval set to 10000ms
[13-Apr-2021 16:42:36] NOTICE: Terminating ...
[13-Apr-2021 16:42:36] NOTICE: exiting, bye-bye!
[13-Apr-2021 16:44:07] NOTICE: fpm is running, pid 790
[13-Apr-2021 16:44:07] NOTICE: ready to handle connections
[13-Apr-2021 16:44:07] NOTICE: systemd monitor interval set to 10000ms

and finally:

orestis@orestis:~$ lsblk
NAME   MAJ:MIN RM  SIZE RO TYPE MOUNTPOINT
loop0    7:0    0 55,5M  1 loop /snap/core18/1988
loop1    7:1    0 69,9M  1 loop /snap/lxd/19188
loop2    7:2    0 55,5M  1 loop /snap/core18/1997
loop3    7:3    0 32,3M  1 loop /snap/snapd/11402
loop4    7:4    0 32,3M  1 loop /snap/snapd/11588
loop5    7:5    0 70,4M  1 loop /snap/lxd/19647
sda      8:0    0  1,8T  0 disk 
├─sda1   8:1    0    1M  0 part 
└─sda2   8:2    0  1,8T  0 part /
sdb      8:16   0  1,8T  0 disk 
└─sdb1   8:17   0  1,8T  0 part /media/orestis/C20F-3CB9
sr0     11:0    1 1024M  0 rom  
File system    Tipo     1K-blocchi     Usati  Disponib. Uso% Montato su
udev           devtmpfs    8120548         0    8120548   0% /dev
tmpfs          tmpfs       1639612      1732    1637880   1% /run
/dev/sda2      ext4     1921800384 381640860 1442467572  21% /
tmpfs          tmpfs       8198048         0    8198048   0% /dev/shm
tmpfs          tmpfs          5120         4       5116   1% /run/lock
tmpfs          tmpfs       8198048         0    8198048   0% /sys/fs/cgroup
/dev/loop0     squashfs      56832     56832          0 100% /snap/core18/1988
/dev/loop1     squashfs      71680     71680          0 100% /snap/lxd/19188
/dev/loop2     squashfs      56832     56832          0 100% /snap/core18/1997
/dev/loop5     squashfs      72192     72192          0 100% /snap/lxd/19647
/dev/loop3     squashfs      33152     33152          0 100% /snap/snapd/11402
/dev/loop4     squashfs      33152     33152          0 100% /snap/snapd/11588
tmpfs          tmpfs       1639608        44    1639564   1% /run/user/1000
/dev/sdb1      vfat     1953002784 299506272 1653496512  16% /media/orestis/C20F-3CB9

Hello friends, hope you are doing well…

Before trying to repair anything on the application server, inspect the page and see where is your main time loads. The best way to do that (using chrome) is to press Ctrl+Shift+I to inspect the page, go to “Performance” and record the session while you log in and load the first page of you nextcloud server.

With that, you will have some info about what files/scripts/css are consuming resources into your server. And than, we can start fixing things up.

Hope this can help…

Have a nice weekend.

:smiley:

2 Likes

Beltran1337 has some very good point here. Browser developer tools allow you to inspect such things from the other side.

And btw, the logs from the update day are not relevant, but logs from the very minutes after you did a login that took so long, to see potential errors or warnings that happen as part of that particular login request, is what I had in mind :wink:.

2 Likes