Opening a document slowed down from 3 seconds to 13 seconds

Installation & Configuration
1

Hi there,
when I open a document on my VPS, the loading screen opens, then nothing happens for 10 seconds, then I see an error message, that opening failed, try again later - then to my surprise the document loads and opens within two seconds. 2-3 seconds used to be the very quick and promising opening times after installation. The degradation came when switching docker from 21.1.0.1 to 21.1.1.1. and a restart of the VPS.

In these 10 seconds of waiting the system is on idle - CPU does nothing, network does nothing, logs from apache, nextcloud and docker don’t show any errors to track.

System setup: Archlinux, Nextcloud 23.0.2, PHP 8.1, Apache, Postgresql, Collabora Docker 21.11.1.3 (latest version, happened with 21.11.1.1 also). Even a switch back to 21.1.0.1 showed the same degradation. Nextcloud runs on a subdomain cloud.andsoon, the docker container is adressed by the vhost apache settings via https proxying and has an own subdomain office.andsoon. The rest of the vhost settings is taken from the collabora installation guide for docker.

So, it may be, that the collabora container is alright and my setup is wrong and I my system waits for a timeout before it quickly finishes opening the document.

Here is, what I have tried so far withour changing anything:

  • switched off my adblocker from firefox for the nextcloud and office domain
  • check all configs of vhost, collabora vhost settings
  • reinstall docker
  • reinstall collabora app in nextcloud
  • remove all 297 entries in collaboras nextcloud database
  • put the public IP of the cloud into /etc/hosts for the two subdomains cloud and office
  • checked that the configs of apache do not differ from the ones that worked before (compared them with a backup)

So, I am running out of ideas… Where can I investigate further?

Thank you all for the community and the great collabora!

Here is some log information both from the firefox network logs (F12->network) and the corresponding logs from the docker container.


docker logs - docker clock runs one hour before that system clock

click to open document
frk-00029-00029 2022-01-22 14:08:28.624242 +0000 [ forkit ] ERR  Failed to unmount [/opt/cool/child-roots/ggFqy8eFZkhlHbLE]| common/JailUtil.cpp:70

10 seconds "Loading documentname.xslx"... document starts to open

wsd-00001-00545 2022-01-22 14:08:38.036016 +0000 [ docbroker_01f ] WRN  Waking up dead poll thread [HttpSynReqPoll], started: false, finished: false| ./net/Socket.hpp:722
wsd-00001-00545 2022-01-22 14:08:43.140543 +0000 [ docbroker_01f ] WRN  Waking up dead poll thread [HttpSynReqPoll], started: false, finished: false| ./net/Socket.hpp:722

document is not open

closing the document
wsd-00001-00545 2022-01-22 14:08:47.601598 +0000 [ docbroker_01f ] WRN  Ignoring attempted read from 19| ./net/Socket.hpp:1099
sh: 1: /usr/bin/coolmount: Operation not permitted
frk-00029-00029 2022-01-22 14:08:48.064916 +0000 [ forkit ] ERR  Failed to unmount [/opt/cool/child-roots/CqPSNJQ7AioDimxh/tmp]| common/JailUtil.cpp:70
sh: 1: /usr/bin/coolmount: Operation not permitted
frk-00029-00029 2022-01-22 14:08:48.080223 +0000 [ forkit ] ERR  Failed to unmount [/opt/cool/child-roots/CqPSNJQ7AioDimxh/lo]| common/JailUtil.cpp:70
sh: 1: /usr/bin/coolmount: Operation not permitted
frk-00029-00029 2022-01-22 14:08:48.095844 +0000 [ forkit ] ERR  Failed to unmount [/opt/cool/child-roots/CqPSNJQ7AioDimxh]| common/JailUtil.cpp:70

corresponding Firefox Network Logs
    {
        "pageref": "page_1",
        "startedDateTime": "2022-01-22T15:08:33.077+01:00",
        "request": {
          "bodySize": 0,
          "method": "GET",
          "url": "https://office.mydomain.tld/browser/21324cf/l10n/uno/de-override.json",
          "httpVersion": "HTTP/1.1",
          [ removed 2kb of messages...]
        "_securityState": "secure"
      },
      {
        "pageref": "page_1",
        "startedDateTime": "2022-01-22T15:08:43.788+01:00",
        "request": {
          "bodySize": 0,
          "method": "GET",
          "url": "https://office.mydomain.tld/browser/21324cf/nextcloud/images/toolbar-bg-logo.svg",
          "httpVersion": "",
          "headers": [
            {
              "name": "Host",
              "value": "office.mydomain.tld"
            },
            {
              "name": "User-Agent",
              "value": "Mozilla/5.0 (X11; Linux x86_64; rv:96.0) Gecko/20100101 Firefox/96.0"
[...]     
      },
      {
        "pageref": "page_1",
        "startedDateTime": "2022-01-22T15:08:43.832+01:00",
        "request": {
          "bodySize": 0,
          "method": "GET",
          "url": "https://cloud.mydomain.tld/index.php/avatar/achim/32",
          "httpVersion": "",
          "headers": [
            {
              "name": "Host",
              "value": "cloud.mydomain.tld"
            },
            {
              "name": "User-Agent",
              "value": "Mozilla/5.0 (X11; Linux x86_64; rv:96.0) Gecko/20100101 Firefox/96.0"
            },
            {
              "name": "Accept",
              "value": "image/avif,image/webp,*/*"
2

Thanks @capslock for reporting this, I wonder if this is not a problem of executing coolmount (before named loolmount) from user space. Could you please try to add --privileged to your docker run command and report back please?

3

Thank you @pedro.silva
I have tried that and forgot it to mention. It did not change anything.

This morning I thought I am chacing a unicorn for a week now. Overnight - without changes or software updates - the problem vanished.

Opening now takes 2-3 seconds from click to visible and editable document. It may be that there was some problem in the hardware that houses my VPS. I will drop back here in future when the problem rises again…

1 Like
4

Oh glad that ended up working out somehow : p