Severe performance degradation when browsing WebDAV external storage

⚠️ This issue respects the following points: ⚠️

• This is a bug, not a question or a configuration/webserver/proxy issue.
• This issue is not already reported on Github OR Nextcloud Community Forum (I've searched it).
• Nextcloud Server is up to date. See Maintenance and Release Schedule for supported versions.
• I agree to follow Nextcloud's Code of Conduct.

Bug description

Browsing an External Storage mount (local - underlying dav2fs mount) of a directory containing 436 items takes 98 seconds to respond via the web interface. The same mount lists within a shell in 4,3s and via the same Nextcloud PHP executed via CURL in the shell in 6,2s.

The entire subdirectory contains 36GB of content when listed recursively (ie the full content of the External Storage mount).

This performance is on Nextcloud AIO Dockerised with almost all other apps disabled.

External Storage:

The underlying webdav is from an Alfresco instance.

(Profiler and debug logging enabled to diagnose this issue)

Events:

time ls -l /mnt/alfresco/Infobase/documentLibrary

real 0m4.277s
user 0m0.014s
sys 0m0.062s

time curl -u user:pass https://server/remote.php/dav/files/admin/Infobase/documentLibrary

real 0m6.207s
user 0m0.112s
sys 0m0.069s

time curl -X PROPFIND -H "Depth: 0" -u user:pass https://server/remote.php/dav/files/admin/Infobase/documentLibrary

real 0m5.420s
user 0m0.080s
sys 0m0.091s

I have read extensively and deleted authtoken entries in the database based on findings such as this:
https://github.com/nextcloud/server/issues/33453

I have also disabled almost all other apps with no impact. I have disabled LDAP user auth with no impact.

Nextcloud AIO is running in Docker on a dedicated server on a dedicated Xen VM.

The server has 256GB of memory, 4x Intel Xeon E5-4650 @ 3.5GHz and 3TB of SSD running as RAID5.

I am desperate to resolve this - accessing Alfresco via External storage offers a path to migration or co-existence.

The Alfresco server is a dedicated server running a dedicated Xen VM and Dockerised instance.

Both the Alfresco and Nextcloud servers are behind a Nginx reverse proxy (a further server) all on the same LAN and subnet.

Alfresco access on the Nextcloud server and from within the Docker container seems quick as evidenced by the shell access times above.

Steps to reproduce

1. Mount webdav directory via dav2fs on server containing Dockerised Nextcloud AIO.
   2.Expose mount to Nextcloud container via Docker compose configuration.
   3.Create External Storage local mount within Nextcloud.
2. Browse External Storage mount via Files app in Nextcloud.

Expected behavior

I would expect External Storage browsing response times in the Nextcloud Files app to be similar to those of the same underlying PHP script executed via CURL from the shell.

Installation method

Community Docker image

Nextcloud Server version

27

Operating system

Debian/Ubuntu

PHP engine version

PHP 8.1

Web server

Apache (supported)

Database engine version

PostgreSQL

Is this bug present after an update or on a fresh install?

Fresh Nextcloud Server install

Are you using the Nextcloud Server Encryption module?

Encryption is Disabled

What user-backends are you using?

• Default user-backend (database)
• LDAP/ Active Directory
• SSO - SAML
• Other

Configuration report

{
    "system": {
        "one-click-instance": true,
        "one-click-instance.user-limit": 100,
        "memcache.local": "\\OC\\Memcache\\APCu",
        "apps_paths": [
            {
                "path": "\/var\/www\/html\/apps",
                "url": "\/apps",
                "writable": false
            },
            {
                "path": "\/var\/www\/html\/custom_apps",
                "url": "\/custom_apps",
                "writable": true
            }
        ],
        "check_data_directory_permissions": false,
        "memcache.distributed": "\\OC\\Memcache\\Redis",
        "memcache.locking": "\\OC\\Memcache\\Redis",
        "redis": {
            "host": "***REMOVED SENSITIVE VALUE***",
            "password": "***REMOVED SENSITIVE VALUE***",
            "port": 6379
        },
        "overwritehost": "server",
        "overwriteprotocol": "https",
        "passwordsalt": "***REMOVED SENSITIVE VALUE***",
        "secret": "***REMOVED SENSITIVE VALUE***",
        "trusted_domains": [
            "localhost",
            "server"
        ],
        "datadirectory": "***REMOVED SENSITIVE VALUE***",
        "dbtype": "pgsql",
        "version": "27.0.2.1",
        "overwrite.cli.url": "https:\/\/server\/",
        "dbname": "***REMOVED SENSITIVE VALUE***",
        "dbhost": "***REMOVED SENSITIVE VALUE***",
        "dbport": "",
        "dbtableprefix": "oc_",
        "dbuser": "***REMOVED SENSITIVE VALUE***",
        "dbpassword": "***REMOVED SENSITIVE VALUE***",
        "installed": true,
        "instanceid": "***REMOVED SENSITIVE VALUE***",
        "maintenance": false,
        "loglevel": "0",
        "log_type": "file",
        "logfile": "\/var\/www\/html\/data\/nextcloud.log",
        "log_rotate_size": "10485760",
        "log.condition": {
            "apps": [
                "admin_audit"
            ]
        },
        "preview_max_x": "2048",
        "preview_max_y": "2048",
        "jpeg_quality": "60",
        "enabledPreviewProviders": {
            "1": "OC\\Preview\\Image",
            "2": "OC\\Preview\\MarkDown",
            "3": "OC\\Preview\\MP3",
            "4": "OC\\Preview\\TXT",
            "5": "OC\\Preview\\OpenDocument",
            "6": "OC\\Preview\\Movie",
            "7": "OC\\Preview\\Krita",
            "0": "OC\\Preview\\Imaginary"
        },
        "enable_previews": false,
        "upgrade.disable-web": true,
        "mail_smtpmode": "smtp",
        "trashbin_retention_obligation": "auto, 30",
        "versions_retention_obligation": "auto, 30",
        "activity_expire_days": "30",
        "simpleSignUpLink.shown": false,
        "share_folder": "\/Shared",
        "one-click-instance.link": "https:\/\/nextcloud.com\/all-in-one\/",
        "upgrade.cli-upgrade-link": "https:\/\/github.com\/nextcloud\/all-in-one\/discussions\/2726",
        "updatedirectory": "\/nc-updater",
        "davstorage.request_timeout": 3600,
        "htaccess.RewriteBase": "\/",
        "dbpersistent": true,
        "files_external_allow_create_new_local": true,
        "trusted_proxies": "***REMOVED SENSITIVE VALUE***",
        "allow_local_remote_servers": true,
        "preview_imaginary_url": "http:\/\/nextcloud-aio-imaginary:9000",
        "mail_from_address": "***REMOVED SENSITIVE VALUE***",
        "mail_sendmailmode": "smtp",
        "mail_domain": "***REMOVED SENSITIVE VALUE***",
        "mail_smtphost": "***REMOVED SENSITIVE VALUE***",
        "mail_smtpport": "25",
        "twofactor_enforced": "false",
        "twofactor_enforced_groups": [],
        "twofactor_enforced_excluded_groups": [],
        "ldapProviderFactory": "OCA\\User_LDAP\\LDAPProviderFactory",
        "debug": true,
        "profiler": true,
        "app_install_overwrite": [
            "integration_moodle",
            "workflow_kitinerary",
            "workflow_media_converter",
            "vdirsyncerui"
        ]
    }
}

List of activated Apps

Enabled:
  - calendar: 4.4.4
  - cloud_federation_api: 1.10.0
  - dashboard: 7.7.0
  - dav: 1.27.0
  - federatedfilesharing: 1.17.0
  - files: 1.22.0
  - files_antivirus: 5.2.2
  - files_external: 1.19.0
  - files_fulltextsearch: 27.0.1
  - fulltextsearch: 27.0.1
  - fulltextsearch_elasticsearch: 27.0.2
  - logreader: 2.12.0
  - lookup_server_connector: 1.15.0
  - nextcloud-aio: 0.4.0
  - notify_push: 0.6.3
  - oauth2: 1.15.1
  - profiler: 1.3.1
  - provisioning_api: 1.17.0
  - richdocuments: 8.1.1
  - serverinfo: 1.17.0
  - settings: 1.9.0
  - spreed: 17.0.3
  - support: 1.10.0
  - theming: 2.2.0
  - twofactor_backupcodes: 1.16.0
  - twofactor_totp: 9.0.0
  - user_ldap: 1.17.0
  - viewer: 2.1.0
  - workflowengine: 2.9.0
Disabled:
  - activity: 2.19.0 (installed 2.19.0)
  - admin_audit: 1.17.0 (installed 1.17.0)
  - announcementcenter: 6.6.2 (installed 6.6.2)
  - approval: 1.1.0 (installed 1.1.0)
  - audioplayer: 3.4.0 (installed 3.4.0)
  - bruteforcesettings: 2.7.0 (installed 2.7.0)
  - circles: 27.0.1 (installed 27.0.1)
  - comments: 1.17.0 (installed 1.17.0)
  - contacts: 5.3.2 (installed 5.3.2)
  - contactsinteraction: 1.8.0 (installed 1.8.0)
  - deck: 1.10.0 (installed 1.10.0)
  - encryption: 2.15.0
  - epubviewer: 1.5.0 (installed 1.5.0)
  - external: 5.2.1 (installed 5.2.1)
  - extract: 1.3.6 (installed 1.3.6)
  - federation: 1.17.0 (installed 1.17.0)
  - files_accesscontrol: 1.17.0 (installed 1.17.0)
  - files_archive: 1.1.3 (installed 1.1.3)
  - files_automatedtagging: 1.17.0 (installed 1.17.0)
  - files_fulltextsearch_tesseract: 27.0.0 (installed 27.0.0)
  - files_mindmap: 0.0.28 (installed 0.0.28)
  - files_pdfviewer: 2.8.0 (installed 2.8.0)
  - files_retention: 1.16.0 (installed 1.16.0)
  - files_rightclick: 1.6.0 (installed 1.6.0)
  - files_sharing: 1.19.0 (installed 1.19.0)
  - files_trashbin: 1.17.0 (installed 1.17.0)
  - files_versions: 1.20.0 (installed 1.20.0)
  - files_zip: 1.4.0 (installed 1.4.0)
  - fileslibreofficeedit: 1.1.0 (installed 1.1.0)
  - firstrunwizard: 2.16.0 (installed 2.16.0)
  - forms: 3.3.1 (installed 3.3.1)
  - groupfolders: 15.0.2 (installed 15.0.2)
  - impersonate: 1.14.0 (installed 1.14.0)
  - integration_deepl: 1.1.0 (installed 1.1.0)
  - integration_gptzero: 0.1.3 (installed 0.1.3)
  - integration_mattermost: 1.0.4 (installed 1.0.4)
  - integration_moodle: 1.0.2 (installed 1.0.2)
  - integration_openai: 1.0.11 (installed 1.0.11)
  - integration_openproject: 2.4.4 (installed 2.4.4)
  - integration_openstreetmap: 1.0.6 (installed 1.0.6)
  - integration_youtube: 0.1.4 (installed 0.1.4)
  - integration_zimbra: 1.0.6 (installed 1.0.6)
  - keeporsweep: 0.3.0 (installed 0.3.0)
  - keeweb: 0.6.13 (installed 0.6.13)
  - login_notes: 1.2.0 (installed 1.2.0)
  - mail: 3.3.1 (installed 3.3.1)
  - maps: 1.1.1 (installed 1.1.1)
  - mediadc: 0.3.6 (installed 0.3.6)
  - news: 23.0.0 (installed 23.0.0)
  - nextcloud_announcements: 1.16.0 (installed 1.16.0)
  - notes: 4.8.1 (installed 4.8.1)
  - notifications: 2.15.0 (installed 2.15.0)
  - openotp_sign: 1.5.2 (installed 1.5.2)
  - password_policy: 1.17.0 (installed 1.17.0)
  - photos: 2.3.0 (installed 2.3.0)
  - polls: 5.2.0 (installed 5.2.0)
  - privacy: 1.11.0 (installed 1.11.0)
  - quota_warning: 1.17.0 (installed 1.17.0)
  - recommendations: 1.6.0 (installed 1.6.0)
  - related_resources: 1.2.0 (installed 1.2.0)
  - richdocumentscode: 23.5.301 (installed 23.5.301)
  - riotchat: 0.16.0 (installed 0.16.0)
  - secrets: 1.5.1 (installed 1.5.1)
  - sharebymail: 1.17.0 (installed 1.17.0)
  - sharepoint: 1.15.0 (installed 1.15.0)
  - socialsharing_email: 2.6.0 (installed 2.6.0)
  - stt_whisper: 1.0.5 (installed 1.0.5)
  - survey_client: 1.15.0 (installed 1.15.0)
  - suspicious_login: 5.0.0 (installed 5.0.0)
  - systemtags: 1.17.0 (installed 1.17.0)
  - tables: 0.5.1 (installed 0.5.1)
  - tasks: 0.15.0 (installed 0.15.0)
  - terms_of_service: 2.3.0 (installed 2.3.0)
  - text: 3.8.0 (installed 3.8.0)
  - user_status: 1.7.0 (installed 1.7.0)
  - vdirsyncerui: 0.0.8 (installed 0.0.8)
  - video_converter: 1.0.6 (installed 1.0.6)
  - weather_status: 1.7.0 (installed 1.7.0)
  - workflow_kitinerary: 1.2.0 (installed 1.2.0)
  - workflow_media_converter: 1.8.4 (installed 1.8.4)
  - workflow_ocr: 1.27.1 (installed 1.27.1)
  - workflow_pdf_converter: 1.12.0 (installed 1.12.0)

Nextcloud Signing status

No errors have been found.

Nextcloud Logs

Cleared log prior to running directory listing:

{"reqId":"F03DKQKGFtm0rkrKYrkX","level":0,"time":"2023-09-07T18:28:37+00:00","remoteAddr":"192.168.0.189","user":"admin","app":"logreader","method":"GET","url":"/settings/admin/logging","message":"Could not find resource file \"/apps/logreader/css/../js/logreader-main.css\"","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/116.0.0.0 Safari/537.36","version":"27.0.2.1","data":{"app":"logreader"}}
{"reqId":"WB7HFtf2n8vtxQOEBuJs","level":0,"time":"2023-09-07T18:28:42+00:00","remoteAddr":"192.168.0.189","user":"admin","app":"no app in context","method":"GET","url":"/apps/files/","message":"Deprecated event type for \\OCP\\Collaboration\\Resources::loadAdditionalScripts: null","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/116.0.0.0 Safari/537.36","version":"27.0.2.1","data":[]}
{"reqId":"WB7HFtf2n8vtxQOEBuJs","level":0,"time":"2023-09-07T18:28:42+00:00","remoteAddr":"192.168.0.189","user":"admin","app":"PHP","method":"GET","url":"/apps/files/","message":"Calling the \"Symfony\\Component\\EventDispatcher\\EventDispatcherInterface::dispatch()\" method with the event name as the first argument is deprecated since Symfony 4.3, pass it as the second argument and provide the event object as the first argument instead. at /var/www/html/3rdparty/symfony/event-dispatcher/EventDispatcher.php#58","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/116.0.0.0 Safari/537.36","version":"27.0.2.1","data":{"app":"PHP"}}
{"reqId":"x9AvAV09g1BWu6x8TWA0","level":0,"time":"2023-09-07T18:30:40+00:00","remoteAddr":"192.168.0.189","user":"admin","app":"logreader","method":"GET","url":"/settings/admin/logging","message":"Could not find resource file \"/apps/logreader/css/../js/logreader-main.css\"","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/116.0.0.0 Safari/537.36","version":"27.0.2.1","data":{"app":"logreader"}}
{"reqId":"RjHxdE0UD3MFgTJtoEe9","level":0,"time":"2023-09-07T18:30:50+00:00","remoteAddr":"","user":"--","app":"cron","method":"","url":"--","message":"CLI cron call has selected job with ID 359","userAgent":"--","version":"27.0.2.1","data":{"app":"cron"}}
{"reqId":"RjHxdE0UD3MFgTJtoEe9","level":0,"time":"2023-09-07T18:30:51+00:00","remoteAddr":"","user":"--","app":"cron","method":"","url":"--","message":"CLI cron call has selected job with ID 121","userAgent":"--","version":"27.0.2.1","data":{"app":"cron"}}
{"reqId":"RjHxdE0UD3MFgTJtoEe9","level":0,"time":"2023-09-07T18:30:51+00:00","remoteAddr":"","user":"--","app":"cron","method":"","url":"--","message":"CLI cron call has selected job with ID 7","userAgent":"--","version":"27.0.2.1","data":{"app":"cron"}}
{"reqId":"RjHxdE0UD3MFgTJtoEe9","level":0,"time":"2023-09-07T18:30:51+00:00","remoteAddr":"","user":"--","app":"cron","method":"","url":"--","message":"CLI cron call has selected job with ID 375","userAgent":"--","version":"27.0.2.1","data":{"app":"cron"}}
{"reqId":"RjHxdE0UD3MFgTJtoEe9","level":0,"time":"2023-09-07T18:30:51+00:00","remoteAddr":"","user":"--","app":"cron","method":"","url":"--","message":"Run OCA\\Support\\BackgroundJobs\\CheckSubscription job with ID 375","userAgent":"--","version":"27.0.2.1","data":{"app":"cron"}}
{"reqId":"RjHxdE0UD3MFgTJtoEe9","level":0,"time":"2023-09-07T18:30:51+00:00","remoteAddr":"","user":"--","app":"cron","method":"","url":"--","message":"Finished OCA\\Support\\BackgroundJobs\\CheckSubscription job with ID 375 in 0 seconds","userAgent":"--","version":"27.0.2.1","data":{"app":"cron"}}
{"reqId":"RjHxdE0UD3MFgTJtoEe9","level":0,"time":"2023-09-07T18:30:51+00:00","remoteAddr":"","user":"--","app":"cron","method":"","url":"--","message":"CLI cron call has selected job with ID 34","userAgent":"--","version":"27.0.2.1","data":{"app":"cron"}}
{"reqId":"RjHxdE0UD3MFgTJtoEe9","level":0,"time":"2023-09-07T18:30:51+00:00","remoteAddr":"","user":"--","app":"cron","method":"","url":"--","message":"Run OC\\Preview\\BackgroundCleanupJob job with ID 34","userAgent":"--","version":"27.0.2.1","data":{"app":"cron"}}
{"reqId":"RjHxdE0UD3MFgTJtoEe9","level":0,"time":"2023-09-07T18:30:51+00:00","remoteAddr":"","user":"--","app":"cron","method":"","url":"--","message":"Finished OC\\Preview\\BackgroundCleanupJob job with ID 34 in 0 seconds","userAgent":"--","version":"27.0.2.1","data":{"app":"cron"}}
{"reqId":"RjHxdE0UD3MFgTJtoEe9","level":0,"time":"2023-09-07T18:30:51+00:00","remoteAddr":"","user":"--","app":"cron","method":"","url":"--","message":"CLI cron call has selected job with ID 357","userAgent":"--","version":"27.0.2.1","data":{"app":"cron"}}
{"reqId":"RjHxdE0UD3MFgTJtoEe9","level":0,"time":"2023-09-07T18:30:51+00:00","remoteAddr":"","user":"--","app":"cron","method":"","url":"--","message":"CLI cron call has selected job with ID 32","userAgent":"--","version":"27.0.2.1","data":{"app":"cron"}}
{"reqId":"RjHxdE0UD3MFgTJtoEe9","level":0,"time":"2023-09-07T18:30:51+00:00","remoteAddr":"","user":"--","app":"cron","method":"","url":"--","message":"CLI cron call has selected job with ID 358","userAgent":"--","version":"27.0.2.1","data":{"app":"cron"}}
{"reqId":"RjHxdE0UD3MFgTJtoEe9","level":0,"time":"2023-09-07T18:30:51+00:00","remoteAddr":"","user":"--","app":"cron","method":"","url":"--","message":"CLI cron call has selected job with ID 61","userAgent":"--","version":"27.0.2.1","data":{"app":"cron"}}
{"reqId":"RjHxdE0UD3MFgTJtoEe9","level":0,"time":"2023-09-07T18:30:51+00:00","remoteAddr":"","user":"--","app":"cron","method":"","url":"--","message":"CLI cron call has selected job with ID 10","userAgent":"--","version":"27.0.2.1","data":{"app":"cron"}}
{"reqId":"RjHxdE0UD3MFgTJtoEe9","level":0,"time":"2023-09-07T18:30:51+00:00","remoteAddr":"","user":"--","app":"cron","method":"","url":"--","message":"Run OCA\\Files\\BackgroundJob\\CleanupDirectEditingTokens job with ID 10","userAgent":"--","version":"27.0.2.1","data":{"app":"cron"}}
{"reqId":"RjHxdE0UD3MFgTJtoEe9","level":0,"time":"2023-09-07T18:30:51+00:00","remoteAddr":"","user":"--","app":"cron","method":"","url":"--","message":"Finished OCA\\Files\\BackgroundJob\\CleanupDirectEditingTokens job with ID 10 in 0 seconds","userAgent":"--","version":"27.0.2.1","data":{"app":"cron"}}
{"reqId":"RjHxdE0UD3MFgTJtoEe9","level":0,"time":"2023-09-07T18:30:51+00:00","remoteAddr":"","user":"--","app":"cron","method":"","url":"--","message":"CLI cron call has selected job with ID 11","userAgent":"--","version":"27.0.2.1","data":{"app":"cron"}}
{"reqId":"RjHxdE0UD3MFgTJtoEe9","level":0,"time":"2023-09-07T18:30:51+00:00","remoteAddr":"","user":"--","app":"cron","method":"","url":"--","message":"CLI cron call has selected job with ID 9","userAgent":"--","version":"27.0.2.1","data":{"app":"cron"}}
{"reqId":"RjHxdE0UD3MFgTJtoEe9","level":0,"time":"2023-09-07T18:30:51+00:00","remoteAddr":"","user":"--","app":"cron","method":"","url":"--","message":"Run OCA\\Files\\BackgroundJob\\CleanupFileLocks job with ID 9","userAgent":"--","version":"27.0.2.1","data":{"app":"cron"}}
{"reqId":"RjHxdE0UD3MFgTJtoEe9","level":0,"time":"2023-09-07T18:30:51+00:00","remoteAddr":"","user":"--","app":"cron","method":"","url":"--","message":"Finished OCA\\Files\\BackgroundJob\\CleanupFileLocks job with ID 9 in 0 seconds","userAgent":"--","version":"27.0.2.1","data":{"app":"cron"}}
{"reqId":"RjHxdE0UD3MFgTJtoEe9","level":0,"time":"2023-09-07T18:30:51+00:00","remoteAddr":"","user":"--","app":"cron","method":"","url":"--","message":"CLI cron call has selected job with ID 92","userAgent":"--","version":"27.0.2.1","data":{"app":"cron"}}
{"reqId":"RjHxdE0UD3MFgTJtoEe9","level":0,"time":"2023-09-07T18:30:51+00:00","remoteAddr":"","user":"--","app":"cron","method":"","url":"--","message":"Run OCA\\Files_External\\BackgroundJob\\CredentialsCleanup job with ID 92","userAgent":"--","version":"27.0.2.1","data":{"app":"cron"}}

REDACTED LDAP query

{"reqId":"RjHxdE0UD3MFgTJtoEe9","level":0,"time":"2023-09-07T18:30:51+00:00","remoteAddr":"","user":"--","app":"cron","method":"","url":"--","message":"Finished OCA\\Files_External\\BackgroundJob\\CredentialsCleanup job with ID 92 in 0 seconds","userAgent":"--","version":"27.0.2.1","data":{"app":"cron"}}
{"reqId":"RjHxdE0UD3MFgTJtoEe9","level":0,"time":"2023-09-07T18:30:51+00:00","remoteAddr":"","user":"--","app":"cron","method":"","url":"--","message":"CLI cron call has selected job with ID 376","userAgent":"--","version":"27.0.2.1","data":{"app":"cron"}}
{"reqId":"RjHxdE0UD3MFgTJtoEe9","level":0,"time":"2023-09-07T18:30:51+00:00","remoteAddr":"","user":"--","app":"cron","method":"","url":"--","message":"CLI cron call has selected job with ID 377","userAgent":"--","version":"27.0.2.1","data":{"app":"cron"}}
{"reqId":"RjHxdE0UD3MFgTJtoEe9","level":0,"time":"2023-09-07T18:30:51+00:00","remoteAddr":"","user":"--","app":"cron","method":"","url":"--","message":"CLI cron call has selected job with ID 26","userAgent":"--","version":"27.0.2.1","data":{"app":"cron"}}
{"reqId":"RjHxdE0UD3MFgTJtoEe9","level":0,"time":"2023-09-07T18:30:51+00:00","remoteAddr":"","user":"--","app":"cron","method":"","url":"--","message":"CLI cron call has selected job with ID 48","userAgent":"--","version":"27.0.2.1","data":{"app":"cron"}}
{"reqId":"RjHxdE0UD3MFgTJtoEe9","level":0,"time":"2023-09-07T18:30:51+00:00","remoteAddr":"","user":"--","app":"cron","method":"","url":"--","message":"CLI cron call has selected job with ID 378","userAgent":"--","version":"27.0.2.1","data":{"app":"cron"}}
{"reqId":"RjHxdE0UD3MFgTJtoEe9","level":0,"time":"2023-09-07T18:30:51+00:00","remoteAddr":"","user":"--","app":"cron","method":"","url":"--","message":"Run OCA\\Talk\\BackgroundJob\\ExpireSignalingMessage job with ID 378","userAgent":"--","version":"27.0.2.1","data":{"app":"cron"}}
{"reqId":"RjHxdE0UD3MFgTJtoEe9","level":0,"time":"2023-09-07T18:30:51+00:00","remoteAddr":"","user":"--","app":"cron","method":"","url":"--","message":"Finished OCA\\Talk\\BackgroundJob\\ExpireSignalingMessage job with ID 378 in 0 seconds","userAgent":"--","version":"27.0.2.1","data":{"app":"cron"}}
{"reqId":"RjHxdE0UD3MFgTJtoEe9","level":0,"time":"2023-09-07T18:30:51+00:00","remoteAddr":"","user":"--","app":"cron","method":"","url":"--","message":"CLI cron call has selected job with ID 379","userAgent":"--","version":"27.0.2.1","data":{"app":"cron"}}
{"reqId":"RjHxdE0UD3MFgTJtoEe9","level":0,"time":"2023-09-07T18:30:51+00:00","remoteAddr":"","user":"--","app":"cron","method":"","url":"--","message":"Run OCA\\Talk\\BackgroundJob\\RemoveEmptyRooms job with ID 379","userAgent":"--","version":"27.0.2.1","data":{"app":"cron"}}
{"reqId":"RjHxdE0UD3MFgTJtoEe9","level":0,"time":"2023-09-07T18:30:51+00:00","remoteAddr":"","user":"--","app":"cron","method":"","url":"--","message":"Finished OCA\\Talk\\BackgroundJob\\RemoveEmptyRooms job with ID 379 in 0 seconds","userAgent":"--","version":"27.0.2.1","data":{"app":"cron"}}
{"reqId":"RjHxdE0UD3MFgTJtoEe9","level":0,"time":"2023-09-07T18:30:51+00:00","remoteAddr":"","user":"--","app":"cron","method":"","url":"--","message":"CLI cron call has selected job with ID 380","userAgent":"--","version":"27.0.2.1","data":{"app":"cron"}}
{"reqId":"RjHxdE0UD3MFgTJtoEe9","level":0,"time":"2023-09-07T18:30:51+00:00","remoteAddr":"","user":"--","app":"cron","method":"","url":"--","message":"Run OCA\\Talk\\BackgroundJob\\ResetAssignedSignalingServer job with ID 380","userAgent":"--","version":"27.0.2.1","data":{"app":"cron"}}
{"reqId":"RjHxdE0UD3MFgTJtoEe9","level":0,"time":"2023-09-07T18:30:51+00:00","remoteAddr":"","user":"--","app":"cron","method":"","url":"--","message":"Finished OCA\\Talk\\BackgroundJob\\ResetAssignedSignalingServer job with ID 380 in 0 seconds","userAgent":"--","version":"27.0.2.1","data":{"app":"cron"}}
{"reqId":"RjHxdE0UD3MFgTJtoEe9","level":0,"time":"2023-09-07T18:30:51+00:00","remoteAddr":"","user":"--","app":"cron","method":"","url":"--","message":"CLI cron call has selected job with ID 382","userAgent":"--","version":"27.0.2.1","data":{"app":"cron"}}
{"reqId":"RjHxdE0UD3MFgTJtoEe9","level":0,"time":"2023-09-07T18:30:51+00:00","remoteAddr":"","user":"--","app":"cron","method":"","url":"--","message":"CLI cron call has selected job with ID 383","userAgent":"--","version":"27.0.2.1","data":{"app":"cron"}}
{"reqId":"RjHxdE0UD3MFgTJtoEe9","level":0,"time":"2023-09-07T18:30:51+00:00","remoteAddr":"","user":"--","app":"cron","method":"","url":"--","message":"Run OCA\\Talk\\BackgroundJob\\CheckMatterbridges job with ID 383","userAgent":"--","version":"27.0.2.1","data":{"app":"cron"}}
{"reqId":"RjHxdE0UD3MFgTJtoEe9","level":0,"time":"2023-09-07T18:30:52+00:00","remoteAddr":"","user":"--","app":"cron","method":"","url":"--","message":"Finished OCA\\Talk\\BackgroundJob\\CheckMatterbridges job with ID 383 in 1 seconds","userAgent":"--","version":"27.0.2.1","data":{"app":"cron"}}
{"reqId":"RjHxdE0UD3MFgTJtoEe9","level":0,"time":"2023-09-07T18:30:52+00:00","remoteAddr":"","user":"--","app":"cron","method":"","url":"--","message":"CLI cron call has selected job with ID 384","userAgent":"--","version":"27.0.2.1","data":{"app":"cron"}}
{"reqId":"RjHxdE0UD3MFgTJtoEe9","level":0,"time":"2023-09-07T18:30:52+00:00","remoteAddr":"","user":"--","app":"cron","method":"","url":"--","message":"Run OCA\\Talk\\BackgroundJob\\ExpireChatMessages job with ID 384","userAgent":"--","version":"27.0.2.1","data":{"app":"cron"}}
{"reqId":"RjHxdE0UD3MFgTJtoEe9","level":0,"time":"2023-09-07T18:30:52+00:00","remoteAddr":"","user":"--","app":"cron","method":"","url":"--","message":"Finished OCA\\Talk\\BackgroundJob\\ExpireChatMessages job with ID 384 in 0 seconds","userAgent":"--","version":"27.0.2.1","data":{"app":"cron"}}
{"reqId":"RjHxdE0UD3MFgTJtoEe9","level":0,"time":"2023-09-07T18:30:52+00:00","remoteAddr":"","user":"--","app":"cron","method":"","url":"--","message":"CLI cron call has selected job with ID 8","userAgent":"--","version":"27.0.2.1","data":{"app":"cron"}}
{"reqId":"RjHxdE0UD3MFgTJtoEe9","level":0,"time":"2023-09-07T18:30:52+00:00","remoteAddr":"","user":"--","app":"cron","method":"","url":"--","message":"CLI cron call has selected job with ID 2","userAgent":"--","version":"27.0.2.1","data":{"app":"cron"}}
{"reqId":"RjHxdE0UD3MFgTJtoEe9","level":0,"time":"2023-09-07T18:30:52+00:00","remoteAddr":"","user":"--","app":"cron","method":"","url":"--","message":"Run OCA\\DAV\\BackgroundJob\\UpdateCalendarResourcesRoomsBackgroundJob job with ID 2","userAgent":"--","version":"27.0.2.1","data":{"app":"cron"}}
{"reqId":"RjHxdE0UD3MFgTJtoEe9","level":0,"time":"2023-09-07T18:30:52+00:00","remoteAddr":"","user":"--","app":"cron","method":"","url":"--","message":"Finished OCA\\DAV\\BackgroundJob\\UpdateCalendarResourcesRoomsBackgroundJob job with ID 2 in 0 seconds","userAgent":"--","version":"27.0.2.1","data":{"app":"cron"}}
{"reqId":"RjHxdE0UD3MFgTJtoEe9","level":0,"time":"2023-09-07T18:30:52+00:00","remoteAddr":"","user":"--","app":"cron","method":"","url":"--","message":"CLI cron call has selected job with ID 47","userAgent":"--","version":"27.0.2.1","data":{"app":"cron"}}
{"reqId":"RjHxdE0UD3MFgTJtoEe9","level":0,"time":"2023-09-07T18:30:52+00:00","remoteAddr":"","user":"--","app":"cron","method":"","url":"--","message":"CLI cron call has selected job with ID 1","userAgent":"--","version":"27.0.2.1","data":{"app":"cron"}}
{"reqId":"RjHxdE0UD3MFgTJtoEe9","level":0,"time":"2023-09-07T18:30:52+00:00","remoteAddr":"","user":"--","app":"cron","method":"","url":"--","message":"CLI cron call has selected job with ID 3","userAgent":"--","version":"27.0.2.1","data":{"app":"cron"}}
{"reqId":"RjHxdE0UD3MFgTJtoEe9","level":0,"time":"2023-09-07T18:30:52+00:00","remoteAddr":"","user":"--","app":"cron","method":"","url":"--","message":"CLI cron call has selected job with ID 33","userAgent":"--","version":"27.0.2.1","data":{"app":"cron"}}
{"reqId":"RjHxdE0UD3MFgTJtoEe9","level":0,"time":"2023-09-07T18:30:52+00:00","remoteAddr":"","user":"--","app":"cron","method":"","url":"--","message":"Run OC\\Log\\Rotate job with ID 33","userAgent":"--","version":"27.0.2.1","data":{"app":"cron"}}
{"reqId":"RjHxdE0UD3MFgTJtoEe9","level":0,"time":"2023-09-07T18:30:52+00:00","remoteAddr":"","user":"--","app":"cron","method":"","url":"--","message":"Finished OC\\Log\\Rotate job with ID 33 in 0 seconds","userAgent":"--","version":"27.0.2.1","data":{"app":"cron"}}
{"reqId":"RjHxdE0UD3MFgTJtoEe9","level":0,"time":"2023-09-07T18:30:52+00:00","remoteAddr":"","user":"--","app":"cron","method":"","url":"--","message":"CLI cron call has selected job with ID 385","userAgent":"--","version":"27.0.2.1","data":{"app":"cron"}}
{"reqId":"RjHxdE0UD3MFgTJtoEe9","level":0,"time":"2023-09-07T18:30:52+00:00","remoteAddr":"","user":"--","app":"cron","method":"","url":"--","message":"Run OCA\\Files_Antivirus\\BackgroundJob\\BackgroundScanner job with ID 385","userAgent":"--","version":"27.0.2.1","data":{"app":"cron"}}
{"reqId":"RjHxdE0UD3MFgTJtoEe9","level":0,"time":"2023-09-07T18:30:52+00:00","remoteAddr":"","user":"--","app":"files_antivirus","method":"","url":"--","message":"Batch size is: 100","userAgent":"--","version":"27.0.2.1","data":{"app":"files_antivirus"}}
{"reqId":"RjHxdE0UD3MFgTJtoEe9","level":0,"time":"2023-09-07T18:30:52+00:00","remoteAddr":"","user":"--","app":"files_antivirus","method":"","url":"--","message":"Start background scan","userAgent":"--","version":"27.0.2.1","data":{"app":"files_antivirus"}}
{"reqId":"RjHxdE0UD3MFgTJtoEe9","level":0,"time":"2023-09-07T18:30:52+00:00","remoteAddr":"","user":"--","app":"files_antivirus","method":"","url":"--","message":"Batch size is: 100","userAgent":"--","version":"27.0.2.1","data":{"app":"files_antivirus"}}
{"reqId":"RjHxdE0UD3MFgTJtoEe9","level":0,"time":"2023-09-07T18:30:52+00:00","remoteAddr":"","user":"--","app":"files_antivirus","method":"","url":"--","message":"Batch size is: 100","userAgent":"--","version":"27.0.2.1","data":{"app":"files_antivirus"}}
{"reqId":"RjHxdE0UD3MFgTJtoEe9","level":0,"time":"2023-09-07T18:30:52+00:00","remoteAddr":"","user":"--","app":"files_antivirus","method":"","url":"--","message":"Batch size is: 100","userAgent":"--","version":"27.0.2.1","data":{"app":"files_antivirus"}}
{"reqId":"RjHxdE0UD3MFgTJtoEe9","level":0,"time":"2023-09-07T18:30:52+00:00","remoteAddr":"","user":"--","app":"cron","method":"","url":"--","message":"Finished OCA\\Files_Antivirus\\BackgroundJob\\BackgroundScanner job with ID 385 in 0 seconds","userAgent":"--","version":"27.0.2.1","data":{"app":"cron"}}
{"reqId":"RjHxdE0UD3MFgTJtoEe9","level":0,"time":"2023-09-07T18:30:52+00:00","remoteAddr":"","user":"--","app":"cron","method":"","url":"--","message":"CLI cron call has selected job with ID 185","userAgent":"--","version":"27.0.2.1","data":{"app":"cron"}}
{"reqId":"RjHxdE0UD3MFgTJtoEe9","level":0,"time":"2023-09-07T18:30:52+00:00","remoteAddr":"","user":"--","app":"cron","method":"","url":"--","message":"CLI cron call has selected job with ID 5","userAgent":"--","version":"27.0.2.1","data":{"app":"cron"}}
{"reqId":"RjHxdE0UD3MFgTJtoEe9","level":0,"time":"2023-09-07T18:30:52+00:00","remoteAddr":"","user":"--","app":"cron","method":"","url":"--","message":"CLI cron call has selected job with ID 122","userAgent":"--","version":"27.0.2.1","data":{"app":"cron"}}
{"reqId":"RjHxdE0UD3MFgTJtoEe9","level":0,"time":"2023-09-07T18:30:52+00:00","remoteAddr":"","user":"--","app":"cron","method":"","url":"--","message":"CLI cron call has selected job with ID 6","userAgent":"--","version":"27.0.2.1","data":{"app":"cron"}}
{"reqId":"RjHxdE0UD3MFgTJtoEe9","level":0,"time":"2023-09-07T18:30:52+00:00","remoteAddr":"","user":"--","app":"cron","method":"","url":"--","message":"CLI cron call has selected job with ID 4","userAgent":"--","version":"27.0.2.1","data":{"app":"cron"}}
{"reqId":"RjHxdE0UD3MFgTJtoEe9","level":0,"time":"2023-09-07T18:30:52+00:00","remoteAddr":"","user":"--","app":"cron","method":"","url":"--","message":"Run OCA\\DAV\\BackgroundJob\\EventReminderJob job with ID 4","userAgent":"--","version":"27.0.2.1","data":{"app":"cron"}}
{"reqId":"RjHxdE0UD3MFgTJtoEe9","level":0,"time":"2023-09-07T18:30:52+00:00","remoteAddr":"","user":"--","app":"dav","method":"","url":"--","message":"0 reminders to process","userAgent":"--","version":"27.0.2.1","data":{"app":"dav"}}
{"reqId":"RjHxdE0UD3MFgTJtoEe9","level":0,"time":"2023-09-07T18:30:52+00:00","remoteAddr":"","user":"--","app":"cron","method":"","url":"--","message":"Finished OCA\\DAV\\BackgroundJob\\EventReminderJob job with ID 4 in 0 seconds","userAgent":"--","version":"27.0.2.1","data":{"app":"cron"}}
{"reqId":"RjHxdE0UD3MFgTJtoEe9","level":0,"time":"2023-09-07T18:30:52+00:00","remoteAddr":"","user":"--","app":"cron","method":"","url":"--","message":"CLI cron call has selected job with ID 386","userAgent":"--","version":"27.0.2.1","data":{"app":"cron"}}
{"reqId":"RjHxdE0UD3MFgTJtoEe9","level":0,"time":"2023-09-07T18:30:52+00:00","remoteAddr":"","user":"--","app":"cron","method":"","url":"--","message":"CLI cron call has selected job with ID 387","userAgent":"--","version":"27.0.2.1","data":{"app":"cron"}}
{"reqId":"RjHxdE0UD3MFgTJtoEe9","level":0,"time":"2023-09-07T18:30:52+00:00","remoteAddr":"","user":"--","app":"cron","method":"","url":"--","message":"CLI cron call has selected job with ID 12","userAgent":"--","version":"27.0.2.1","data":{"app":"cron"}}
{"reqId":"RjHxdE0UD3MFgTJtoEe9","level":0,"time":"2023-09-07T18:30:52+00:00","remoteAddr":"","user":"--","app":"user_ldap","method":"","url":"--","message":"Calling LDAP function ldap_unbind with parameters [{}]","userAgent":"--","version":"27.0.2.1","data":{"app":"user_ldap"}}

Additional info

No response

Reproduce the slowdown using the listed dav2fs mount, Files app, and CURL timings, then review the enabled profiler and debug logging output. Done means browsing the External Storage directory has response times comparable to the same underlying request executed through CURL.