45 Second delay for every request when collabora online is activated #24674

bastiandg · 2020-12-12T11:44:41Z

How to use GitHub

Please use the 👍 reaction to show that you are affected by the same issue.
Please don't comment if you have no relevant information to add. It's just extra noise for everyone subscribed to this issue.
Subscribe to receive notifications on status change and new comments.

Steps to reproduce

have Collabora Online and Collabora CODE Server activated
Update Nextcloud from 20.0.0 to 20.0.3.2
curl any page on the server

Expected behaviour

Fast reply

Actual behaviour

When requesting any page there is always a 45 second delay. Even when querying the url locally from the server.

curl -L -H "Host: my.domain" 172.21.0.4/

The issue is caused by the Collabora Apps. When they are deactivated, it works as intended. I only found the cause because of this help thread: https://help.nextcloud.com/t/login-delayed-by-45-seconds/99055 (Thanks @fighter3005 you saved my sanity).

Server configuration

Operating system: debian + docker

Web server: Apache/2.4.38 (Debian) (included in the docker image)

Database: 10.5.8+maria~focal

PHP version: PHP 7.4.13 (cli) (built: Dec 11 2020 08:24:16) ( NTS ) (included in the docker image)

Nextcloud version: Nextcloud 20.0.3

Updated from an older Nextcloud/ownCloud or fresh install: Updated

Where did you install Nextcloud from: from a docker-compose file

Signing status:

No errors have been found.

List of activated apps:

Enabled:
  - accessibility: 1.6.0
  - activity: 2.13.4
  - admin_audit: 1.10.0
  - bruteforcesettings: 2.0.1
  - calendar: 2.1.2
  - cloud_federation_api: 1.3.0
  - comments: 1.10.0
  - contacts: 3.4.2
  - contactsinteraction: 1.1.0
  - dashboard: 7.0.0
  - dav: 1.16.1
  - federatedfilesharing: 1.10.1
  - federation: 1.10.1
  - files: 1.15.0
  - files_external: 1.11.1
  - files_markdown: 2.3.1
  - files_pdfviewer: 2.0.1
  - files_rightclick: 0.17.0
  - files_sharing: 1.12.0
  - files_trashbin: 1.10.1
  - files_versions: 1.13.0
  - files_videoplayer: 1.9.0
  - logreader: 2.5.0
  - lookup_server_connector: 1.8.0
  - mail: 1.7.2
  - nextcloud_announcements: 1.9.0
  - notes: 4.0.1
  - notifications: 2.8.0
  - oauth2: 1.8.0
  - password_policy: 1.10.1
  - photos: 1.2.1
  - privacy: 1.4.0
  - provisioning_api: 1.10.0
  - recommendations: 0.8.0
  - richdocuments: 3.7.11
  - richdocumentscode: 6.4.202
  - serverinfo: 1.10.0
  - settings: 1.2.0
  - sharebymail: 1.10.0
  - support: 1.3.0
  - systemtags: 1.10.0
  - text: 3.1.0
  - theming: 1.11.0
  - twofactor_backupcodes: 1.9.0
  - twofactor_u2f: 6.0.0
  - updatenotification: 1.10.0
  - user_status: 1.0.1
  - viewer: 1.4.0
  - weather_status: 1.0.0
  - workflowengine: 2.2.0
Disabled:
  - encryption
  - firstrunwizard
  - survey_client
  - user_ldap

Weirdly enough the Collabora only shows up in the web frontend but not here. The command was done after the deactivation and reactivation of Collabora Online and Collabora CODE.

Nextcloud configuration:

{
    "system": {
        "apps_paths": [
            {
                "path": "\/var\/www\/html\/apps",
                "url": "\/apps",
                "writable": false
            },
            {
                "path": "\/var\/www\/html\/custom_apps",
                "url": "\/custom_apps",
                "writable": true
            }
        ],
        "trusted_domains": [
            "my.domain"
        ],
        "overwrite.cli.url": "https:\/\/my.domain",
        "overwriteprotocol": "https",
        "dbtype": "mysql",
        "version": "20.0.3.2",
        "dbport": "",
        "dbtableprefix": "oc_",
        "logtimezone": "UTC",
        "installed": true,
        "theme": "",
        "loglevel": 0,
        "maintenance": false,
        "twofactor_enforced": "false",
        "twofactor_enforced_groups": [],
        "twofactor_enforced_excluded_groups": [],
        "mail_smtpmode": "smtp",
        "mail_smtpsecure": "ssl",
        "mail_sendmailmode": "smtp",
        "mail_smtpauthtype": "LOGIN",
        "mail_smtpauth": 1,
        "mail_smtpport": "465",
    }
}

Are you using external storage, if yes which one: no

Are you using encryption: no

Are you using an external user-backend, if yes which one: no

Client configuration

Browser: curl / any

Operating system: any

Logs

Web server error log

172.21.0.1 - - [12/Dec/2020:10:40:38 +0000] "GET / HTTP/1.1" 302 1557 "-" "curl/7.38.0"
172.21.0.2 - - [12/Dec/2020:10:40:38 +0000] "GET /index.php/login HTTP/1.1" 200 14218 "-" "curl/7.38.0"

Nextcloud log (data/nextcloud.log)

Nextcloud log of a simple curl curl -L -H "Host: my.domain" 172.21.0.4/

{
  "reqId": "yEKXmiZBKYwzFIP7wR0j",
  "level": 0,
  "time": "2020-12-12T10:40:38+00:00",
  "remoteAddr": "172.21.0.1",
  "user": "--",
  "app": "contacts",
  "method": "GET",
  "url": "/",
  "message": "/appinfo/app.php is deprecated, use \\OCP\\AppFramework\\Bootstrap\\IBootstrap on the application class instead.",
  "userAgent": "curl/7.38.0",
  "version": "20.0.3.2"
}
{
  "reqId": "yEKXmiZBKYwzFIP7wR0j",
  "level": 0,
  "time": "2020-12-12T10:40:38+00:00",
  "remoteAddr": "172.21.0.1",
  "user": "--",
  "app": "richdocuments",
  "method": "GET",
  "url": "/",
  "message": "/appinfo/app.php is deprecated, use \\OCP\\AppFramework\\Bootstrap\\IBootstrap on the application class instead.",
  "userAgent": "curl/7.38.0",
  "version": "20.0.3.2"
}
{
  "reqId": "yEKXmiZBKYwzFIP7wR0j",
  "level": 0,
  "time": "2020-12-12T10:40:38+00:00",
  "remoteAddr": "172.21.0.1",
  "user": "--",
  "app": "files_external",
  "method": "GET",
  "url": "/",
  "message": "/appinfo/app.php is deprecated, use \\OCP\\AppFramework\\Bootstrap\\IBootstrap on the application class instead.",
  "userAgent": "curl/7.38.0",
  "version": "20.0.3.2"
}
{
  "reqId": "yEKXmiZBKYwzFIP7wR0j",
  "level": 0,
  "time": "2020-12-12T10:40:38+00:00",
  "remoteAddr": "172.21.0.1",
  "user": "--",
  "app": "files_sharing",
  "method": "GET",
  "url": "/",
  "message": "/appinfo/app.php is deprecated, use \\OCP\\AppFramework\\Bootstrap\\IBootstrap on the application class instead.",
  "userAgent": "curl/7.38.0",
  "version": "20.0.3.2"
}
{
  "reqId": "yEKXmiZBKYwzFIP7wR0j",
  "level": 0,
  "time": "2020-12-12T10:40:38+00:00",
  "remoteAddr": "172.21.0.1",
  "user": "--",
  "app": "files_markdown",
  "method": "GET",
  "url": "/",
  "message": "/appinfo/app.php is deprecated, use \\OCP\\AppFramework\\Bootstrap\\IBootstrap on the application class instead.",
  "userAgent": "curl/7.38.0",
  "version": "20.0.3.2"
}
{
  "reqId": "Fx7WUmHdlJ0VMTFARfGi",
  "level": 0,
  "time": "2020-12-12T10:40:38+00:00",
  "remoteAddr": "172.21.0.2",
  "user": "--",
  "app": "contacts",
  "method": "GET",
  "url": "/index.php/login",
  "message": "/appinfo/app.php is deprecated, use \\OCP\\AppFramework\\Bootstrap\\IBootstrap on the application class instead.",
  "userAgent": "curl/7.38.0",
  "version": "20.0.3.2"
}
{
  "reqId": "Fx7WUmHdlJ0VMTFARfGi",
  "level": 0,
  "time": "2020-12-12T10:40:38+00:00",
  "remoteAddr": "172.21.0.2",
  "user": "--",
  "app": "richdocuments",
  "method": "GET",
  "url": "/index.php/login",
  "message": "/appinfo/app.php is deprecated, use \\OCP\\AppFramework\\Bootstrap\\IBootstrap on the application class instead.",
  "userAgent": "curl/7.38.0",
  "version": "20.0.3.2"
}
{
  "reqId": "Fx7WUmHdlJ0VMTFARfGi",
  "level": 0,
  "time": "2020-12-12T10:40:38+00:00",
  "remoteAddr": "172.21.0.2",
  "user": "--",
  "app": "files_external",
  "method": "GET",
  "url": "/index.php/login",
  "message": "/appinfo/app.php is deprecated, use \\OCP\\AppFramework\\Bootstrap\\IBootstrap on the application class instead.",
  "userAgent": "curl/7.38.0",
  "version": "20.0.3.2"
}
{
  "reqId": "Fx7WUmHdlJ0VMTFARfGi",
  "level": 0,
  "time": "2020-12-12T10:40:38+00:00",
  "remoteAddr": "172.21.0.2",
  "user": "--",
  "app": "files_sharing",
  "method": "GET",
  "url": "/index.php/login",
  "message": "/appinfo/app.php is deprecated, use \\OCP\\AppFramework\\Bootstrap\\IBootstrap on the application class instead.",
  "userAgent": "curl/7.38.0",
  "version": "20.0.3.2"
}
{
  "reqId": "Fx7WUmHdlJ0VMTFARfGi",
  "level": 0,
  "time": "2020-12-12T10:40:38+00:00",
  "remoteAddr": "172.21.0.2",
  "user": "--",
  "app": "files_markdown",
  "method": "GET",
  "url": "/index.php/login",
  "message": "/appinfo/app.php is deprecated, use \\OCP\\AppFramework\\Bootstrap\\IBootstrap on the application class instead.",
  "userAgent": "curl/7.38.0",
  "version": "20.0.3.2"
}

Browser log

$ time curl -I -L -H "Host: my.domain" 172.21.0.4/
HTTP/1.1 302 Found
Date: Sat, 12 Dec 2020 10:51:24 GMT
Server: Apache/2.4.38 (Debian)
Referrer-Policy: no-referrer
X-Content-Type-Options: nosniff
X-Download-Options: noopen
X-Frame-Options: SAMEORIGIN
X-Permitted-Cross-Domain-Policies: none
X-Robots-Tag: none
X-XSS-Protection: 1; mode=block
X-Powered-By: PHP/7.4.13
Set-Cookie: […]; path=/; secure; HttpOnly; SameSite=Lax
Expires: Thu, 19 Nov 1981 08:52:00 GMT
Cache-Control: no-store, no-cache, must-revalidate
Pragma: no-cache
Set-Cookie: oc_sessionPassphrase=[…]; path=/; secure; HttpOnly; SameSite=Lax
Set-Cookie: […]; path=/; secure; HttpOnly; SameSite=Lax
Content-Security-Policy: default-src 'self'; script-src 'self' '[…]'; style-src 'self' 'unsafe-inline'; frame-src *; img-src * data: blob:; font-src 'self' data:; media-src *; connect-src *; object-src 'none'; base-uri 'self';
Set-Cookie: __Host-nc_sameSiteCookielax=true; path=/; httponly;secure; expires=Fri, 31-Dec-2100 23:59:59 GMT; SameSite=lax
Set-Cookie: __Host-nc_sameSiteCookiestrict=true; path=/; httponly;secure; expires=Fri, 31-Dec-2100 23:59:59 GMT; SameSite=strict
Location: https://my.domain/index.php/login
Content-Type: text/html; charset=UTF-8

HTTP/1.1 200 OK
Server: nginx
Date: Sat, 12 Dec 2020 10:52:09 GMT
Content-Type: text/html; charset=UTF-8
Content-Length: 12559
Connection: keep-alive
Referrer-Policy: no-referrer
X-Content-Type-Options: nosniff
X-Download-Options: noopen
X-Frame-Options: SAMEORIGIN
X-Permitted-Cross-Domain-Policies: none
X-Robots-Tag: none
X-XSS-Protection: 1; mode=block
X-Powered-By: PHP/7.4.13
Set-Cookie: […]; path=/; secure; HttpOnly; SameSite=Lax
Expires: Thu, 19 Nov 1981 08:52:00 GMT
Cache-Control: no-cache, no-store, must-revalidate
Pragma: no-cache
Set-Cookie: oc_sessionPassphrase=[…]; path=/; secure; HttpOnly; SameSite=Lax
Set-Cookie: […]; path=/; secure; HttpOnly; SameSite=Lax
Content-Security-Policy: default-src 'none';base-uri 'none';manifest-src 'self';script-src 'self';style-src 'self' 'unsafe-inline';img-src 'self' data: blob:;font-src 'self' data:;connect-src 'self';media-src 'self';frame-src 'self' https://my.domain;frame-ancestors 'self';worker-src 'self' blob:;form-action 'self' https://my.domain
Set-Cookie: __Host-nc_sameSiteCookielax=true; path=/; httponly;secure; expires=Fri, 31-Dec-2100 23:59:59 GMT; SameSite=lax
Set-Cookie: __Host-nc_sameSiteCookiestrict=true; path=/; httponly;secure; expires=Fri, 31-Dec-2100 23:59:59 GMT; SameSite=strict
Feature-Policy: autoplay 'self';camera 'none';fullscreen 'self';geolocation 'none';microphone 'none';payment 'none'
Strict-Transport-Security: max-age=31536000


real    0m45.166s
user    0m0.012s
sys     0m0.008s

The text was updated successfully, but these errors were encountered:

kesselb · 2020-12-12T15:53:53Z

Thanks for reporting 👍

I'm closing this report in favor of nextcloud/richdocuments#1282.

bastiandg added 0. Needs triage Pending check for reproducibility or if it fits our roadmap bug labels Dec 12, 2020

kesselb closed this as completed Dec 12, 2020

Provide feedback

Saved searches

Use saved searches to filter your results more quickly

45 Second delay for every request when collabora online is activated #24674

45 Second delay for every request when collabora online is activated #24674

bastiandg commented Dec 12, 2020 •

edited

Loading

kesselb commented Dec 12, 2020

45 Second delay for every request when collabora online is activated #24674

45 Second delay for every request when collabora online is activated #24674

Comments

bastiandg commented Dec 12, 2020 • edited Loading

How to use GitHub

Steps to reproduce

Expected behaviour

Actual behaviour

Server configuration

Client configuration

Logs

Web server error log

Nextcloud log (data/nextcloud.log)

Browser log

kesselb commented Dec 12, 2020

bastiandg commented Dec 12, 2020 •

edited

Loading