Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

[Bug]: thousands of php sessions in /var/lib/php/sessions #35430

Closed
6 of 9 tasks
raid1 opened this issue Nov 25, 2022 · 14 comments
Closed
6 of 9 tasks

[Bug]: thousands of php sessions in /var/lib/php/sessions #35430

raid1 opened this issue Nov 25, 2022 · 14 comments
Labels
0. Needs triage Pending check for reproducibility or if it fits our roadmap 25-feedback bug feature: authentication needs info

Comments

@raid1
Copy link

raid1 commented Nov 25, 2022

⚠️ 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 (I've searched it).
  • Nextcloud Server is up to date. See Maintenance and Release Schedule for supported versions.
  • Nextcloud Server is running on 64bit capable CPU, PHP and OS.
  • I agree to follow Nextcloud's Code of Conduct.

Bug description

There are thousands of php sessions in /var/lib/php/sessions and NC constantly creates more.
Yesterday I deleted 2,5 million sess_* files there!
(session.gc_maxlifetime = 172800)
We noticed it because the NC didn't allow new logins (the inode table was full).
I was running 24.0.7 but then yesterday I upgraded to 25.0.1
But the problem persists. I'm getting at least one new session per minute:

/var/lib/php/sessions# 
-rw------- 1 www-data www-data  420 2022-11-25 13:29:23 sess_8p7ou97nh5e13t44nt2ijm6vae
-rw------- 1 www-data www-data  772 2022-11-25 13:29:45 sess_ubgrfertqhppam691l5d5olkuk
-rw------- 1 www-data www-data  420 2022-11-25 13:30:23 sess_il8hv287ghg095f47i9th0c2os
-rw------- 1 www-data www-data  420 2022-11-25 13:31:23 sess_k3d118np48abu6pmd0p10oskc3
-rw------- 1 www-data www-data 1125 2022-11-25 13:31:23 sess_vt2luab738lbj7msbmp020mbcq
-rw------- 1 www-data www-data 1125 2022-11-25 13:31:28 sess_d4bsl6efoqt81k1jf1cv1kb1up
-rw------- 1 www-data www-data 1125 2022-11-25 13:31:30 sess_c97vbkmohm93u7ufdqt8jqshf7
-rw------- 1 www-data www-data 1733 2022-11-25 13:31:37 sess_ejm48a3c4bcff1fh824jlo2bpi
-rw------- 1 www-data www-data 1125 2022-11-25 13:31:37 sess_7m361bklknog8nene001rlgakc

The session data file contains encrypted data such that I cannot check from which user it's coming from.
The NC log does not show any related lines.
I "impersonated" all users and checked their profile for too many sessions but nothing suspicious there either.
I checked the apache logs for connections from many IPs but am unable to deduct a problem from there (except that it's many lines)
See:

[25/Nov/2022:13:30:00 +0000] "GET /remote.php/dav/avatars/maiermueller/128.png HTTP/1.1" 200 3866 "-" "Mozilla/5.0 (Macintosh) mirall/3.1.2git (build 4615) (Nextcloud)"
[25/Nov/2022:13:30:01 +0000] "GET /apps/richdocumentscode/proxy.php?req=/hosting/capabilities HTTP/1.1" 200 5692 "-" "Nextcloud Server Crawler"
[25/Nov/2022:13:30:01 +0000] "GET /apps/richdocumentscode/proxy.php?req=/hosting/capabilities HTTP/1.1" 200 5693 "-" "Nextcloud Server Crawler"
[25/Nov/2022:13:30:13 +0000] "PROPFIND /remote.php/dav/files/maiermueller/ HTTP/1.1" 207 6106 "-" "Mozilla/5.0 (Macintosh) mirall/3.1.2git (build 4615) (Nextcloud)"
[25/Nov/2022:13:30:17 +0000] "GET /apps/richdocumentscode/proxy.php?req=/hosting/capabilities HTTP/1.1" 200 5693 "-" "Nextcloud Server Crawler"
[25/Nov/2022:13:30:17 +0000] "GET /apps/richdocumentscode/proxy.php?req=/hosting/capabilities HTTP/1.1" 200 5692 "-" "Nextcloud Server Crawler"
[25/Nov/2022:13:30:17 +0000] "GET /ocs/v2.php/apps/user_status/api/v1/user_status?format=json HTTP/1.1" 200 5930 "-" "Mozilla/5.0 (Macintosh) mirall/3.6.0git (build 11568) (Nextcloud, osx-19.6.0 Cl>
[25/Nov/2022:13:30:17 +0000] "GET /ocs/v2.php/apps/notifications/api/v2/notifications?format=json HTTP/1.1" 304 5775 "-" "Mozilla/5.0 (Macintosh) mirall/3.6.0git (build 11568) (Nextcloud, osx-19.6.>
[25/Nov/2022:13:30:22 +0000] "PROPFIND /remote.php/dav/files/maiermueller/ HTTP/1.1" 207 6095 "-" "Mozilla/5.0 (Macintosh) mirall/3.1.2git (build 4615) (Nextcloud)"
[25/Nov/2022:13:30:23 +0000] "GET /apps/richdocumentscode/proxy.php?req=/hosting/capabilities HTTP/1.1" 200 5693 "-" "Nextcloud Server Crawler"
[25/Nov/2022:13:30:23 +0000] "GET / HTTP/1.1" 302 6345 "-" "check_http/v2.2 (monitoring-plugins 2.2)"
[25/Nov/2022:13:30:23 +0000] "PROPFIND /remote.php/dav/files/maiermueller/ HTTP/1.1" 207 6095 "-" "Mozilla/5.0 (Macintosh) mirall/3.5.4git (build 11184) (Nextcloud, osx-20.6.0 ClientArchitecture: arm64 >
[25/Nov/2022:13:30:28 +0000] "PROPFIND /remote.php/dav/files/maiermueller/ HTTP/1.1" 207 6095 "-" "Mozilla/5.0 (Macintosh) mirall/3.5.4git (build 11184) (Nextcloud, osx-21.4.0 ClientArchitecture: arm64>
[25/Nov/2022:13:30:29 +0000] "GET /apps/richdocumentscode/proxy.php?req=/hosting/capabilities HTTP/1.1" 200 5693 "-" "Nextcloud Server Crawler"
[25/Nov/2022:13:30:29 +0000] "GET /ocs/v1.php/cloud/user?format=json HTTP/1.1" 200 6360 "-" "Mozilla/5.0 (Macintosh) mirall/3.1.2git (build 4615) (Nextcloud)"
[25/Nov/2022:13:30:30 +0000] "GET /remote.php/dav/avatars/maiermueller/128.png HTTP/1.1" 200 3866 "-" "Mozilla/5.0 (Macintosh) mirall/3.1.2git (build 4615) (Nextcloud)"
[25/Nov/2022:13:30:35 +0000] "PROPFIND /remote.php/dav/files/Nico/ HTTP/1.1" 207 6105 "-" "Mozilla/5.0 (Macintosh) mirall/3.6.0git (build 11568) (Nextcloud, osx-19.6.0 ClientArchitecture: x86_64 Os>
[25/Nov/2022:13:30:37 +0000] "GET /ocs/v2.php/apps/notifications/api/v2/notifications HTTP/1.1" 304 1459 "-" "Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/106.0.0.0>
[25/Nov/2022:13:30:45 +0000] "PROPFIND /remote.php/dav/files/maiermueller/ HTTP/1.1" 207 6106 "-" "Mozilla/5.0 (Macintosh) mirall/3.1.2git (build 4615) (Nextcloud)"
[25/Nov/2022:13:30:52 +0000] "GET / HTTP/1.1" 301 739 "-" "check_http/v2.2 (monitoring-plugins 2.2)"
[25/Nov/2022:13:30:52 +0000] "PROPFIND /remote.php/dav/files/maiermueller/ HTTP/1.1" 207 6096 "-" "Mozilla/5.0 (Macintosh) mirall/3.1.2git (build 4615) (Nextcloud)"
[25/Nov/2022:13:30:53 +0000] "GET /apps/richdocumentscode/proxy.php?req=/hosting/capabilities HTTP/1.1" 200 5691 "-" "Nextcloud Server Crawler"
[25/Nov/2022:13:30:53 +0000] "GET /ocs/v2.php/apps/notifications/api/v2/notifications?format=json HTTP/1.1" 304 5775 "-" "Mozilla/5.0 (Macintosh) mirall/3.5.4git (build 11184) (Nextcloud, osx-20.6>
[25/Nov/2022:13:30:53 +0000] "GET /apps/richdocumentscode/proxy.php?req=/hosting/capabilities HTTP/1.1" 200 5692 "-" "Nextcloud Server Crawler"
[25/Nov/2022:13:30:53 +0000] "PROPFIND /remote.php/dav/files/maiermueller/ HTTP/1.1" 207 6094 "-" "Mozilla/5.0 (Macintosh) mirall/3.5.4git (build 11184) (Nextcloud, osx-20.6.0 ClientArchitecture: arm64 >
[25/Nov/2022:13:30:53 +0000] "GET /ocs/v2.php/apps/user_status/api/v1/user_status?format=json HTTP/1.1" 200 5932 "-" "Mozilla/5.0 (Macintosh) mirall/3.5.4git (build 11184) (Nextcloud, osx-20.6.0 C>
[25/Nov/2022:13:30:58 +0000] "GET /ocs/v2.php/apps/user_status/api/v1/user_status?format=json HTTP/1.1" 200 5931 "-" "Mozilla/5.0 (Macintosh) mirall/3.5.4git (build 11184) (Nextcloud, osx-21.4.0 >
[25/Nov/2022:13:30:58 +0000] "GET /ocs/v2.php/apps/notifications/api/v2/notifications?format=json HTTP/1.1" 304 5776 "-" "Mozilla/5.0 (Macintosh) mirall/3.5.4git (build 11184) (Nextcloud, osx-21.>
[25/Nov/2022:13:30:58 +0000] "PROPFIND /remote.php/dav/files/maiermueller/ HTTP/1.1" 207 6095 "-" "Mozilla/5.0 (Macintosh) mirall/3.5.4git (build 11184) (Nextcloud, osx-21.4.0 ClientArchitecture: arm64>
[25/Nov/2022:13:30:59 +0000] "GET /apps/richdocumentscode/proxy.php?req=/hosting/capabilities HTTP/1.1" 200 5691 "-" "Nextcloud Server Crawler"
[25/Nov/2022:13:30:59 +0000] "GET /ocs/v1.php/cloud/user?format=json HTTP/1.1" 200 6359 "-" "Mozilla/5.0 (Macintosh) mirall/3.1.2git (build 4615) (Nextcloud)"
[25/Nov/2022:13:31:00 +0000] "GET /remote.php/dav/avatars/maiermueller/128.png HTTP/1.1" 200 3866 "-" "Mozilla/5.0 (Macintosh) mirall/3.1.2git (build 4615) (Nextcloud)"
[25/Nov/2022:13:31:17 +0000] "GET /ocs/v2.php/apps/user_status/api/v1/user_status?format=json HTTP/1.1" 200 5931 "-" "Mozilla/5.0 (Macintosh) mirall/3.6.0git (build 11568) (Nextcloud, osx-19.6.0 Cl>
[25/Nov/2022:13:31:17 +0000] "GET /ocs/v2.php/apps/notifications/api/v2/notifications?format=json HTTP/1.1" 304 5775 "-" "Mozilla/5.0 (Macintosh) mirall/3.6.0git (build 11568) (Nextcloud, osx-19.6.>
[25/Nov/2022:13:31:17 +0000] "PROPFIND /remote.php/dav/files/maiermueller/ HTTP/1.1" 207 6105 "-" "Mozilla/5.0 (Macintosh) mirall/3.1.2git (build 4615) (Nextcloud)"
[25/Nov/2022:13:31:22 +0000] "PROPFIND /remote.php/dav/files/maiermueller/ HTTP/1.1" 207 1234 "-" "Mozilla/5.0 (Macintosh) mirall/3.1.2git (build 4615) (Nextcloud)"

(I removed the first column with IPs and changed the username.)

Running out of ideas now...

Is this a NC bug or a misconfiguration from one of my users?
How can I differentiate between these two possibilities since the NC log doesn't help?

Thanks,
Andy

Steps to reproduce

  1. Setup NC
  2. Have a few users (only 23 so far in my case)
  3. Wait for /var/lib/php/sessions to get filled

Expected behavior

/var/lib/php/sessions should not contain more than a few hundred files.
Depending on the amount of NC users and devices.

Installation method

Community Web installer on a VPS or web space

Operating system

Debian/Ubuntu

PHP engine version

PHP 7.4

Web server

Apache (supported)

Database engine version

MariaDB

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

Updated from a minor version (ex. 22.2.3 to 22.2.4)

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": {
        "instanceid": "***REMOVED SENSITIVE VALUE***",
        "passwordsalt": "***REMOVED SENSITIVE VALUE***",
        "secret": "***REMOVED SENSITIVE VALUE***",
        "trusted_domains": [
            "xxx",
            "yyy",
            "zzz"
        ],
        "datadirectory": "***REMOVED SENSITIVE VALUE***",
        "dbtype": "mysql",
        "version": "25.0.1.1",
        "overwrite.cli.url": "https:\/\/xxx",
        "dbname": "***REMOVED SENSITIVE VALUE***",
        "dbhost": "***REMOVED SENSITIVE VALUE***",
        "dbport": "",
        "dbtableprefix": "oc_",
        "mysql.utf8mb4": true,
        "dbuser": "***REMOVED SENSITIVE VALUE***",
        "dbpassword": "***REMOVED SENSITIVE VALUE***",
        "installed": true,
        "memcache.local": "\\OC\\Memcache\\APCu",
        "default_phone_region": "DE",
        "mail_from_address": "***REMOVED SENSITIVE VALUE***",
        "mail_smtpmode": "smtp",
        "mail_sendmailmode": "smtp",
        "mail_domain": "***REMOVED SENSITIVE VALUE***",
        "mail_smtphost": "***REMOVED SENSITIVE VALUE***",
        "maintenance": false,
        "theme": "",
        "loglevel": 2,
        "updater.secret": "***REMOVED SENSITIVE VALUE***",
        "app_install_overwrite": [
            "impersonate"
        ]
    }
}

List of activated Apps

Enabled:
  - activity: 2.17.0
  - admin_audit: 1.15.0
  - bruteforcesettings: 2.5.0
  - calendar: 4.1.0
  - circles: 25.0.0
  - cloud_federation_api: 1.8.0
  - collectives: 2.1.0
  - comments: 1.15.0
  - contacts: 5.0.1
  - contactsinteraction: 1.6.0
  - dashboard: 7.5.0
  - dav: 1.24.0
  - deck: 1.8.2
  - federatedfilesharing: 1.15.0
  - federation: 1.15.0
  - files: 1.20.1
  - files_external: 1.17.0
  - files_mindmap: 0.0.27
  - files_pdfviewer: 2.6.0
  - files_rightclick: 1.4.0
  - files_sharing: 1.17.0
  - files_trashbin: 1.15.0
  - files_versions: 1.18.0
  - firstrunwizard: 2.14.0
  - groupfolders: 13.1.0
  - impersonate: 1.11.0
  - logreader: 2.10.0
  - lookup_server_connector: 1.13.0
  - mail: 2.1.2
  - nextcloud_announcements: 1.14.0
  - notifications: 2.13.1
  - oauth2: 1.13.0
  - password_policy: 1.15.0
  - photos: 2.0.0
  - polls: 4.0.0
  - privacy: 1.9.0
  - provisioning_api: 1.15.0
  - recommendations: 1.4.0
  - related_resources: 1.0.3
  - richdocuments: 7.0.1
  - richdocumentscode: 22.5.802
  - serverinfo: 1.15.0
  - settings: 1.7.0
  - sharebymail: 1.15.0
  - support: 1.8.0
  - survey_client: 1.13.0
  - systemtags: 1.15.0
  - text: 3.6.0
  - theming: 2.0.1
  - twofactor_backupcodes: 1.14.0
  - updatenotification: 1.15.0
  - user_status: 1.5.0
  - user_usage_report: 1.9.0
  - viewer: 1.9.0
  - weather_status: 1.5.0
  - workflowengine: 2.7.0
Disabled:
  - encryption
  - files_markdown: 2.3.6
  - spreed: 15.0.1
  - suspicious_login: 4.3.0
  - twofactor_totp
  - user_ldap

Nextcloud Signing status

No errors have been found.

Nextcloud Logs

(I removed sensible data and all lines before the update to 25.0.1)
{"reqId":"5rQhtC8utXMzRfiXLnXX","level":1,"time":"2022-11-24T12:19:49+00:00","remoteAddr":"","user":"--","app":"updater","method":"","url":"--","message":"\\OC\\Updater::updateEnd: Update successful","userAgent":"--","version":"25.0.1.1","data":{"app":"updater"}}
{"reqId":"5rQhtC8utXMzRfiXLnXX","level":1,"time":"2022-11-24T12:19:49+00:00","remoteAddr":"","user":"--","app":"updater","method":"","url":"--","message":"\\OC\\Updater::maintenanceDisabled: Turned off maintenance mode","userAgent":"--","version":"25.0.1.1","data":{"app":"updater"}}
{"reqId":"5rQhtC8utXMzRfiXLnXX","level":1,"time":"2022-11-24T12:19:49+00:00","remoteAddr":"","user":"--","app":"updater","method":"","url":"--","message":"\\OC\\Updater::resetLogLevel: Reset log level to Warning(2)","userAgent":"--","version":"25.0.1.1","data":{"app":"updater"}}
{"reqId":"Y39h-hhk-fDb9BebRB_YQwAAABA","level":2,"time":"2022-11-24T12:22:19+00:00","remoteAddr":"999.999.103.8","user":"maiermueller","app":"suspicious_login","method":"REPORT","url":"/remote.php/dav/calendars/maiermueller/con/","message":"Could not predict suspiciousness: No models found","userAgent":"macOS/12.3 (21E230) CalendarAgent/961.4.2","version":"25.0.1.1","data":{"app":"suspicious_login"}}
{"reqId":"Y39h-xhk-fDb9BebRB_YRQAAAAQ","level":2,"time":"2022-11-24T12:22:20+00:00","remoteAddr":"999.999.103.8","user":"maiermueller","app":"suspicious_login","method":"REPORT","url":"/remote.php/dav/calendars/maiermueller/con-away/","message":"Could not predict suspiciousness: No models found","userAgent":"macOS/12.3 (21E230) CalendarAgent/961.4.2","version":"25.0.1.1","data":{"app":"suspicious_login"}}
{"reqId":"Y39h_Bhk-fDb9BebRB_YSQAAAAE","level":2,"time":"2022-11-24T12:22:21+00:00","remoteAddr":"999.999.103.8","user":"maiermueller","app":"suspicious_login","method":"PROPFIND","url":"/remote.php/dav/calendars/maiermueller/inbox/","message":"Could not predict suspiciousness: No models found","userAgent":"macOS/12.3 (21E230) CalendarAgent/961.4.2","version":"25.0.1.1","data":{"app":"suspicious_login"}}
{"reqId":"Y39h_Rhk-fDb9BebRB_YTgAAAAg","level":2,"time":"2022-11-24T12:22:22+00:00","remoteAddr":"999.999.103.8","user":"maiermueller","app":"suspicious_login","method":"PROPFIND","url":"/remote.php/dav/calendars/maiermueller/inbox/","message":"Could not predict suspiciousness: No models found","userAgent":"macOS/12.3 (21E230) CalendarAgent/961.4.2","version":"25.0.1.1","data":{"app":"suspicious_login"}}
{"reqId":"Y39v9GmNHu7unk9QdDstLwAAAEM","level":3,"time":"2022-11-24T13:21:57+00:00","remoteAddr":"10.111.22.44","user":"AAA BBB","app":"no app in context","method":"GET","url":"/ocs/v2.php/references/resolve?reference=https%3A%2F%2Fgithub.com%2Ftseemann%2Fprokka","message":"Failed to fetch and store the open graph image for https://github.com/tseemann/prokka","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:106.0) Gecko/20100101 Firefox/106.0","version":"25.0.1.1","exception":{"Exception":"OCP\\Files\\NotPermittedException","Message":"Could not create folder","Code":0,"Trace":[{"file":"/var/www/XXX/lib/private/Files/AppData/AppData.php","line":147,"function":"newFolder","class":"OC\\Files\\Node\\Folder","type":"->"},{"file":"/var/www/XXX/lib/private/Collaboration/Reference/LinkReferenceProvider.php","line":137,"function":"newFolder","class":"OC\\Files\\AppData\\AppData","type":"->"},{"file":"/var/www/XXX/lib/private/Collaboration/Reference/LinkReferenceProvider.php","line":87,"function":"fetchReference","class":"OC\\Collaboration\\Reference\\LinkReferenceProvider","type":"->"},{"file":"/var/www/XXX/lib/private/Collaboration/Reference/ReferenceManager.php","line":100,"function":"resolveReference","class":"OC\\Collaboration\\Reference\\LinkReferenceProvider","type":"->"},{"file":"/var/www/XXX/core/Controller/ReferenceApiController.php","line":64,"function":"resolveReference","class":"OC\\Collaboration\\Reference\\ReferenceManager","type":"->"},{"file":"/var/www/XXX/lib/private/AppFramework/Http/Dispatcher.php","line":225,"function":"resolveOne","class":"OC\\Core\\Controller\\ReferenceApiController","type":"->"},{"file":"/var/www/XXX/lib/private/AppFramework/Http/Dispatcher.php","line":133,"function":"executeController","class":"OC\\AppFramework\\Http\\Dispatcher","type":"->"},{"file":"/var/www/XXX/lib/private/AppFramework/App.php","line":172,"function":"dispatch","class":"OC\\AppFramework\\Http\\Dispatcher","type":"->"},{"file":"/var/www/XXX/lib/private/Route/Router.php","line":298,"function":"main","class":"OC\\AppFramework\\App","type":"::"},{"file":"/var/www/XXX/ocs/v1.php","line":63,"function":"match","class":"OC\\Route\\Router","type":"->"},{"file":"/var/www/XXX/ocs/v2.php","line":23,"args":["/var/www/XXX/ocs/v1.php"],"function":"require_once"}],"File":"/var/www/XXX/lib/private/Files/Node/Folder.php","Line":165,"message":"Failed to fetch and store the open graph image for https://github.com/tseemann/prokka","exception":{},"CustomMessage":"Failed to fetch and store the open graph image for https://github.com/tseemann/prokka"}}
{"reqId":"Y3-3a0-Qo3eILpB_FKgZ3AAAAEI","level":2,"time":"2022-11-24T18:26:51+00:00","remoteAddr":"999.999.115.145","user":"--","app":"no app in context","method":"POST","url":"/index.php/login","message":"Login failed: elisabeth.gruener03@campus.de (Remote IP: 217.87.115.145)","userAgent":"Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_6) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/15.4 Safari/605.1.15","version":"25.0.1.1","data":[]}
{"reqId":"Y3-3eU-Qo3eILpB_FKgZ4wAAAE4","level":2,"time":"2022-11-24T18:27:05+00:00","remoteAddr":"999.999.115.145","user":"--","app":"no app in context","method":"POST","url":"/index.php/login","message":"Login failed: elisabeth.gruener03@campus.lmu.de (Remote IP: 217.87.115.145)","userAgent":"Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_6) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/15.4 Safari/605.1.15","version":"25.0.1.1","data":[]}
{"reqId":"Y3_gO0-Qo3eILpB_FKgpSAAAAE8","level":3,"time":"2022-11-24T21:21:00+00:00","remoteAddr":"10.111.22.33","user":"AAA BBB","app":"PHP","method":"PUT","url":"/remote.php/dav/files/Elisabeth%20Gruener/Dateien/Auswertung/stacked_barplot.jpeg","message":"exif_read_data(): File not supported at /var/www/XXX/lib/private/Metadata/Provider/ExifProvider.php#59","userAgent":"Mozilla/5.0 (Windows) mirall/3.6.2stable-Win64 (build 20221110) (Nextcloud, windows-10.0.22000 ClientArchitecture: x86_64 OsArchitecture: x86_64)","version":"25.0.1.1","exception":{"Exception":"Error","Message":"exif_read_data(): File not supported at /var/www/XXX/lib/private/Metadata/Provider/ExifProvider.php#59","Code":0,"Trace":[{"function":"onError","class":"OC\\Log\\ErrorHandler","type":"::"},{"file":"/var/www/XXX/lib/private/Metadata/Provider/ExifProvider.php","line":59,"function":"exif_read_data"},{"file":"/var/www/XXX/lib/private/Metadata/MetadataManager.php","line":68,"function":"execute","class":"OC\\Metadata\\Provider\\ExifProvider","type":"->"},{"file":"/var/www/XXX/lib/private/Metadata/FileEventListener.php","line":101,"function":"generateMetadata","class":"OC\\Metadata\\MetadataManager","type":"->"},{"file":"/var/www/XXX/lib/private/EventDispatcher/ServiceEventListener.php","line":87,"function":"handle","class":"OC\\Metadata\\FileEventListener","type":"->"},{"file":"/var/www/XXX/3rdparty/symfony/event-dispatcher/EventDispatcher.php","line":251,"function":"__invoke","class":"OC\\EventDispatcher\\ServiceEventListener","type":"->"},{"file":"/var/www/XXX/3rdparty/symfony/event-dispatcher/EventDispatcher.php","line":73,"function":"callListeners","class":"Symfony\\Component\\EventDispatcher\\EventDispatcher","type":"->"},{"file":"/var/www/XXX/lib/private/EventDispatcher/EventDispatcher.php","line":88,"function":"dispatch","class":"Symfony\\Component\\EventDispatcher\\EventDispatcher","type":"->"},{"file":"/var/www/XXX/lib/private/EventDispatcher/EventDispatcher.php","line":100,"function":"dispatch","class":"OC\\EventDispatcher\\EventDispatcher","type":"->"},{"file":"/var/www/XXX/lib/private/Files/Node/HookConnector.php","line":118,"function":"dispatchTyped","class":"OC\\EventDispatcher\\EventDispatcher","type":"->"},{"file":"/var/www/XXX/lib/private/legacy/OC_Hook.php","line":106,"function":"postWrite","class":"OC\\Files\\Node\\HookConnector","type":"->"},{"file":"/var/www/XXX/apps/dav/lib/Connector/Sabre/File.php","line":471,"function":"emit","class":"OC_Hook","type":"::"},{"file":"/var/www/XXX/apps/dav/lib/Connector/Sabre/File.php","line":398,"function":"emitPostHooks","class":"OCA\\DAV\\Connector\\Sabre\\File","type":"->"},{"file":"/var/www/XXX/3rdparty/sabre/dav/lib/DAV/Server.php","line":1137,"function":"put","class":"OCA\\DAV\\Connector\\Sabre\\File","type":"->"},{"file":"/var/www/XXX/3rdparty/sabre/dav/lib/DAV/CorePlugin.php","line":492,"function":"updateFile","class":"Sabre\\DAV\\Server","type":"->","args":["*** sensitive parameters replaced ***"]},{"file":"/var/www/XXX/3rdparty/sabre/event/lib/WildcardEmitterTrait.php","line":89,"function":"httpPut","class":"Sabre\\DAV\\CorePlugin","type":"->"},{"file":"/var/www/XXX/3rdparty/sabre/dav/lib/DAV/Server.php","line":472,"function":"emit","class":"Sabre\\DAV\\Server","type":"->"},{"file":"/var/www/XXX/3rdparty/sabre/dav/lib/DAV/Server.php","line":253,"function":"invokeMethod","class":"Sabre\\DAV\\Server","type":"->"},{"file":"/var/www/XXX/3rdparty/sabre/dav/lib/DAV/Server.php","line":321,"function":"start","class":"Sabre\\DAV\\Server","type":"->"},{"file":"/var/www/XXX/apps/dav/lib/Server.php","line":360,"function":"exec","class":"Sabre\\DAV\\Server","type":"->"},{"file":"/var/www/XXX/apps/dav/appinfo/v2/remote.php","line":35,"function":"exec","class":"OCA\\DAV\\Server","type":"->"},{"file":"/var/www/XXX/remote.php","line":171,"args":["/var/www/XXX/apps/dav/appinfo/v2/remote.php"],"function":"require_once"}],"File":"/var/www/XXX/lib/private/Log/ErrorHandler.php","Line":92,"CustomMessage":"--"}}
{"reqId":"Y3_hJ0-Qo3eILpB_FKgppgAAAEE","level":3,"time":"2022-11-24T21:24:56+00:00","remoteAddr":"10.111.22.33","user":"AAA BBB","app":"PHP","method":"PUT","url":"/remote.php/dav/files/Elisabeth%20Gruener/Dateien/Auswertung/NMDS_adults_time.png","message":"exif_read_data(): File not supported at /var/www/XXX/lib/private/Metadata/Provider/ExifProvider.php#59","userAgent":"Mozilla/5.0 (Windows) mirall/3.6.2stable-Win64 (build 20221110) (Nextcloud, windows-10.0.22000 ClientArchitecture: x86_64 OsArchitecture: x86_64)","version":"25.0.1.1","exception":{"Exception":"Error","Message":"exif_read_data(): File not supported at /var/www/XXX/lib/private/Metadata/Provider/ExifProvider.php#59","Code":0,"Trace":[{"function":"onError","class":"OC\\Log\\ErrorHandler","type":"::"},{"file":"/var/www/XXX/lib/private/Metadata/Provider/ExifProvider.php","line":59,"function":"exif_read_data"},{"file":"/var/www/XXX/lib/private/Metadata/MetadataManager.php","line":68,"function":"execute","class":"OC\\Metadata\\Provider\\ExifProvider","type":"->"},{"file":"/var/www/XXX/lib/private/Metadata/FileEventListener.php","line":101,"function":"generateMetadata","class":"OC\\Metadata\\MetadataManager","type":"->"},{"file":"/var/www/XXX/lib/private/EventDispatcher/ServiceEventListener.php","line":87,"function":"handle","class":"OC\\Metadata\\FileEventListener","type":"->"},{"file":"/var/www/XXX/3rdparty/symfony/event-dispatcher/EventDispatcher.php","line":251,"function":"__invoke","class":"OC\\EventDispatcher\\ServiceEventListener","type":"->"},{"file":"/var/www/XXX/3rdparty/symfony/event-dispatcher/EventDispatcher.php","line":73,"function":"callListeners","class":"Symfony\\Component\\EventDispatcher\\EventDispatcher","type":"->"},{"file":"/var/www/XXX/lib/private/EventDispatcher/EventDispatcher.php","line":88,"function":"dispatch","class":"Symfony\\Component\\EventDispatcher\\EventDispatcher","type":"->"},{"file":"/var/www/XXX/lib/private/EventDispatcher/EventDispatcher.php","line":100,"function":"dispatch","class":"OC\\EventDispatcher\\EventDispatcher","type":"->"},{"file":"/var/www/XXX/lib/private/Files/Node/HookConnector.php","line":118,"function":"dispatchTyped","class":"OC\\EventDispatcher\\EventDispatcher","type":"->"},{"file":"/var/www/XXX/lib/private/legacy/OC_Hook.php","line":106,"function":"postWrite","class":"OC\\Files\\Node\\HookConnector","type":"->"},{"file":"/var/www/XXX/apps/dav/lib/Connector/Sabre/File.php","line":471,"function":"emit","class":"OC_Hook","type":"::"},{"file":"/var/www/XXX/apps/dav/lib/Connector/Sabre/File.php","line":398,"function":"emitPostHooks","class":"OCA\\DAV\\Connector\\Sabre\\File","type":"->"},{"file":"/var/www/XXX/apps/dav/lib/Connector/Sabre/Directory.php","line":151,"function":"put","class":"OCA\\DAV\\Connector\\Sabre\\File","type":"->"},{"file":"/var/www/XXX/3rdparty/sabre/dav/lib/DAV/Server.php","line":1098,"function":"createFile","class":"OCA\\DAV\\Connector\\Sabre\\Directory","type":"->"},{"file":"/var/www/XXX/3rdparty/sabre/dav/lib/DAV/CorePlugin.php","line":504,"function":"createFile","class":"Sabre\\DAV\\Server","type":"->"},{"file":"/var/www/XXX/3rdparty/sabre/event/lib/WildcardEmitterTrait.php","line":89,"function":"httpPut","class":"Sabre\\DAV\\CorePlugin","type":"->"},{"file":"/var/www/XXX/3rdparty/sabre/dav/lib/DAV/Server.php","line":472,"function":"emit","class":"Sabre\\DAV\\Server","type":"->"},{"file":"/var/www/XXX/3rdparty/sabre/dav/lib/DAV/Server.php","line":253,"function":"invokeMethod","class":"Sabre\\DAV\\Server","type":"->"},{"file":"/var/www/XXX/3rdparty/sabre/dav/lib/DAV/Server.php","line":321,"function":"start","class":"Sabre\\DAV\\Server","type":"->"},{"file":"/var/www/XXX/apps/dav/lib/Server.php","line":360,"function":"exec","class":"Sabre\\DAV\\Server","type":"->"},{"file":"/var/www/XXX/apps/dav/appinfo/v2/remote.php","line":35,"function":"exec","class":"OCA\\DAV\\Server","type":"->"},{"file":"/var/www/XXX/remote.php","line":171,"args":["/var/www/XXX/apps/dav/appinfo/v2/remote.php"],"function":"require_once"}],"File":"/var/www/XXX/lib/private/Log/ErrorHandler.php","Line":92,"CustomMessage":"--"}}
{"reqId":"Y3_hijlx6cgASk9CxV6sZgAAAA4","level":3,"time":"2022-11-24T21:26:35+00:00","remoteAddr":"10.111.22.33","user":"AAA BBB","app":"PHP","method":"PUT","url":"/remote.php/dav/files/Elisabeth%20Gruener/Dateien/Auswertung/NMDS_diversity.png","message":"exif_read_data(): File not supported at /var/www/XXX/lib/private/Metadata/Provider/ExifProvider.php#59","userAgent":"Mozilla/5.0 (Windows) mirall/3.6.2stable-Win64 (build 20221110) (Nextcloud, windows-10.0.22000 ClientArchitecture: x86_64 OsArchitecture: x86_64)","version":"25.0.1.1","exception":{"Exception":"Error","Message":"exif_read_data(): File not supported at /var/www/XXX/lib/private/Metadata/Provider/ExifProvider.php#59","Code":0,"Trace":[{"function":"onError","class":"OC\\Log\\ErrorHandler","type":"::"},{"file":"/var/www/XXX/lib/private/Metadata/Provider/ExifProvider.php","line":59,"function":"exif_read_data"},{"file":"/var/www/XXX/lib/private/Metadata/MetadataManager.php","line":68,"function":"execute","class":"OC\\Metadata\\Provider\\ExifProvider","type":"->"},{"file":"/var/www/XXX/lib/private/Metadata/FileEventListener.php","line":101,"function":"generateMetadata","class":"OC\\Metadata\\MetadataManager","type":"->"},{"file":"/var/www/XXX/lib/private/EventDispatcher/ServiceEventListener.php","line":87,"function":"handle","class":"OC\\Metadata\\FileEventListener","type":"->"},{"file":"/var/www/XXX/3rdparty/symfony/event-dispatcher/EventDispatcher.php","line":251,"function":"__invoke","class":"OC\\EventDispatcher\\ServiceEventListener","type":"->"},{"file":"/var/www/XXX/3rdparty/symfony/event-dispatcher/EventDispatcher.php","line":73,"function":"callListeners","class":"Symfony\\Component\\EventDispatcher\\EventDispatcher","type":"->"},{"file":"/var/www/XXX/lib/private/EventDispatcher/EventDispatcher.php","line":88,"function":"dispatch","class":"Symfony\\Component\\EventDispatcher\\EventDispatcher","type":"->"},{"file":"/var/www/XXX/lib/private/EventDispatcher/EventDispatcher.php","line":100,"function":"dispatch","class":"OC\\EventDispatcher\\EventDispatcher","type":"->"},{"file":"/var/www/XXX/lib/private/Files/Node/HookConnector.php","line":118,"function":"dispatchTyped","class":"OC\\EventDispatcher\\EventDispatcher","type":"->"},{"file":"/var/www/XXX/lib/private/legacy/OC_Hook.php","line":106,"function":"postWrite","class":"OC\\Files\\Node\\HookConnector","type":"->"},{"file":"/var/www/XXX/apps/dav/lib/Connector/Sabre/File.php","line":471,"function":"emit","class":"OC_Hook","type":"::"},{"file":"/var/www/XXX/apps/dav/lib/Connector/Sabre/File.php","line":398,"function":"emitPostHooks","class":"OCA\\DAV\\Connector\\Sabre\\File","type":"->"},{"file":"/var/www/XXX/apps/dav/lib/Connector/Sabre/Directory.php","line":151,"function":"put","class":"OCA\\DAV\\Connector\\Sabre\\File","type":"->"},{"file":"/var/www/XXX/3rdparty/sabre/dav/lib/DAV/Server.php","line":1098,"function":"createFile","class":"OCA\\DAV\\Connector\\Sabre\\Directory","type":"->"},{"file":"/var/www/XXX/3rdparty/sabre/dav/lib/DAV/CorePlugin.php","line":504,"function":"createFile","class":"Sabre\\DAV\\Server","type":"->"},{"file":"/var/www/XXX/3rdparty/sabre/event/lib/WildcardEmitterTrait.php","line":89,"function":"httpPut","class":"Sabre\\DAV\\CorePlugin","type":"->"},{"file":"/var/www/XXX/3rdparty/sabre/dav/lib/DAV/Server.php","line":472,"function":"emit","class":"Sabre\\DAV\\Server","type":"->"},{"file":"/var/www/XXX/3rdparty/sabre/dav/lib/DAV/Server.php","line":253,"function":"invokeMethod","class":"Sabre\\DAV\\Server","type":"->"},{"file":"/var/www/XXX/3rdparty/sabre/dav/lib/DAV/Server.php","line":321,"function":"start","class":"Sabre\\DAV\\Server","type":"->"},{"file":"/var/www/XXX/apps/dav/lib/Server.php","line":360,"function":"exec","class":"Sabre\\DAV\\Server","type":"->"},{"file":"/var/www/XXX/apps/dav/appinfo/v2/remote.php","line":35,"function":"exec","class":"OCA\\DAV\\Server","type":"->"},{"file":"/var/www/XXX/remote.php","line":171,"args":["/var/www/XXX/apps/dav/appinfo/v2/remote.php"],"function":"require_once"}],"File":"/var/www/XXX/lib/private/Log/ErrorHandler.php","Line":92,"CustomMessage":"--"}}
{"reqId":"Y3_iTDlx6cgASk9CxV6ssAAAAAM","level":3,"time":"2022-11-24T21:29:49+00:00","remoteAddr":"10.111.22.33","user":"AAA BBB","app":"PHP","method":"PUT","url":"/remote.php/dav/files/Elisabeth%20Gruener/Dateien/Auswertung/network_time.png","message":"exif_read_data(): File not supported at /var/www/XXX/lib/private/Metadata/Provider/ExifProvider.php#59","userAgent":"Mozilla/5.0 (Windows) mirall/3.6.2stable-Win64 (build 20221110) (Nextcloud, windows-10.0.22000 ClientArchitecture: x86_64 OsArchitecture: x86_64)","version":"25.0.1.1","exception":{"Exception":"Error","Message":"exif_read_data(): File not supported at /var/www/XXX/lib/private/Metadata/Provider/ExifProvider.php#59","Code":0,"Trace":[{"function":"onError","class":"OC\\Log\\ErrorHandler","type":"::"},{"file":"/var/www/XXX/lib/private/Metadata/Provider/ExifProvider.php","line":59,"function":"exif_read_data"},{"file":"/var/www/XXX/lib/private/Metadata/MetadataManager.php","line":68,"function":"execute","class":"OC\\Metadata\\Provider\\ExifProvider","type":"->"},{"file":"/var/www/XXX/lib/private/Metadata/FileEventListener.php","line":101,"function":"generateMetadata","class":"OC\\Metadata\\MetadataManager","type":"->"},{"file":"/var/www/XXX/lib/private/EventDispatcher/ServiceEventListener.php","line":87,"function":"handle","class":"OC\\Metadata\\FileEventListener","type":"->"},{"file":"/var/www/XXX/3rdparty/symfony/event-dispatcher/EventDispatcher.php","line":251,"function":"__invoke","class":"OC\\EventDispatcher\\ServiceEventListener","type":"->"},{"file":"/var/www/XXX/3rdparty/symfony/event-dispatcher/EventDispatcher.php","line":73,"function":"callListeners","class":"Symfony\\Component\\EventDispatcher\\EventDispatcher","type":"->"},{"file":"/var/www/XXX/lib/private/EventDispatcher/EventDispatcher.php","line":88,"function":"dispatch","class":"Symfony\\Component\\EventDispatcher\\EventDispatcher","type":"->"},{"file":"/var/www/XXX/lib/private/EventDispatcher/EventDispatcher.php","line":100,"function":"dispatch","class":"OC\\EventDispatcher\\EventDispatcher","type":"->"},{"file":"/var/www/XXX/lib/private/Files/Node/HookConnector.php","line":118,"function":"dispatchTyped","class":"OC\\EventDispatcher\\EventDispatcher","type":"->"},{"file":"/var/www/XXX/lib/private/legacy/OC_Hook.php","line":106,"function":"postWrite","class":"OC\\Files\\Node\\HookConnector","type":"->"},{"file":"/var/www/XXX/apps/dav/lib/Connector/Sabre/File.php","line":471,"function":"emit","class":"OC_Hook","type":"::"},{"file":"/var/www/XXX/apps/dav/lib/Connector/Sabre/File.php","line":398,"function":"emitPostHooks","class":"OCA\\DAV\\Connector\\Sabre\\File","type":"->"},{"file":"/var/www/XXX/apps/dav/lib/Connector/Sabre/Directory.php","line":151,"function":"put","class":"OCA\\DAV\\Connector\\Sabre\\File","type":"->"},{"file":"/var/www/XXX/3rdparty/sabre/dav/lib/DAV/Server.php","line":1098,"function":"createFile","class":"OCA\\DAV\\Connector\\Sabre\\Directory","type":"->"},{"file":"/var/www/XXX/3rdparty/sabre/dav/lib/DAV/CorePlugin.php","line":504,"function":"createFile","class":"Sabre\\DAV\\Server","type":"->"},{"file":"/var/www/XXX/3rdparty/sabre/event/lib/WildcardEmitterTrait.php","line":89,"function":"httpPut","class":"Sabre\\DAV\\CorePlugin","type":"->"},{"file":"/var/www/XXX/3rdparty/sabre/dav/lib/DAV/Server.php","line":472,"function":"emit","class":"Sabre\\DAV\\Server","type":"->"},{"file":"/var/www/XXX/3rdparty/sabre/dav/lib/DAV/Server.php","line":253,"function":"invokeMethod","class":"Sabre\\DAV\\Server","type":"->"},{"file":"/var/www/XXX/3rdparty/sabre/dav/lib/DAV/Server.php","line":321,"function":"start","class":"Sabre\\DAV\\Server","type":"->"},{"file":"/var/www/XXX/apps/dav/lib/Server.php","line":360,"function":"exec","class":"Sabre\\DAV\\Server","type":"->"},{"file":"/var/www/XXX/apps/dav/appinfo/v2/remote.php","line":35,"function":"exec","class":"OCA\\DAV\\Server","type":"->"},{"file":"/var/www/XXX/remote.php","line":171,"args":["/var/www/XXX/apps/dav/appinfo/v2/remote.php"],"function":"require_once"}],"File":"/var/www/XXX/lib/private/Log/ErrorHandler.php","Line":92,"CustomMessage":"--"}}
{"reqId":"Y3_n0U-Qo3eILpB_FKgtEwAAAEg","level":3,"time":"2022-11-24T21:53:23+00:00","remoteAddr":"10.111.22.33","user":"AAA BBB","app":"PHP","method":"PUT","url":"/remote.php/dav/files/Elisabeth%20Gruener/Dateien/Auswertung/network_diversity.png","message":"exif_read_data(): File not supported at /var/www/XXX/lib/private/Metadata/Provider/ExifProvider.php#59","userAgent":"Mozilla/5.0 (Windows) mirall/3.6.2stable-Win64 (build 20221110) (Nextcloud, windows-10.0.22000 ClientArchitecture: x86_64 OsArchitecture: x86_64)","version":"25.0.1.1","exception":{"Exception":"Error","Message":"exif_read_data(): File not supported at /var/www/XXX/lib/private/Metadata/Provider/ExifProvider.php#59","Code":0,"Trace":[{"function":"onError","class":"OC\\Log\\ErrorHandler","type":"::"},{"file":"/var/www/XXX/lib/private/Metadata/Provider/ExifProvider.php","line":59,"function":"exif_read_data"},{"file":"/var/www/XXX/lib/private/Metadata/MetadataManager.php","line":68,"function":"execute","class":"OC\\Metadata\\Provider\\ExifProvider","type":"->"},{"file":"/var/www/XXX/lib/private/Metadata/FileEventListener.php","line":101,"function":"generateMetadata","class":"OC\\Metadata\\MetadataManager","type":"->"},{"file":"/var/www/XXX/lib/private/EventDispatcher/ServiceEventListener.php","line":87,"function":"handle","class":"OC\\Metadata\\FileEventListener","type":"->"},{"file":"/var/www/XXX/3rdparty/symfony/event-dispatcher/EventDispatcher.php","line":251,"function":"__invoke","class":"OC\\EventDispatcher\\ServiceEventListener","type":"->"},{"file":"/var/www/XXX/3rdparty/symfony/event-dispatcher/EventDispatcher.php","line":73,"function":"callListeners","class":"Symfony\\Component\\EventDispatcher\\EventDispatcher","type":"->"},{"file":"/var/www/XXX/lib/private/EventDispatcher/EventDispatcher.php","line":88,"function":"dispatch","class":"Symfony\\Component\\EventDispatcher\\EventDispatcher","type":"->"},{"file":"/var/www/XXX/lib/private/EventDispatcher/EventDispatcher.php","line":100,"function":"dispatch","class":"OC\\EventDispatcher\\EventDispatcher","type":"->"},{"file":"/var/www/XXX/lib/private/Files/Node/HookConnector.php","line":118,"function":"dispatchTyped","class":"OC\\EventDispatcher\\EventDispatcher","type":"->"},{"file":"/var/www/XXX/lib/private/legacy/OC_Hook.php","line":106,"function":"postWrite","class":"OC\\Files\\Node\\HookConnector","type":"->"},{"file":"/var/www/XXX/apps/dav/lib/Connector/Sabre/File.php","line":471,"function":"emit","class":"OC_Hook","type":"::"},{"file":"/var/www/XXX/apps/dav/lib/Connector/Sabre/File.php","line":398,"function":"emitPostHooks","class":"OCA\\DAV\\Connector\\Sabre\\File","type":"->"},{"file":"/var/www/XXX/apps/dav/lib/Connector/Sabre/Directory.php","line":151,"function":"put","class":"OCA\\DAV\\Connector\\Sabre\\File","type":"->"},{"file":"/var/www/XXX/3rdparty/sabre/dav/lib/DAV/Server.php","line":1098,"function":"createFile","class":"OCA\\DAV\\Connector\\Sabre\\Directory","type":"->"},{"file":"/var/www/XXX/3rdparty/sabre/dav/lib/DAV/CorePlugin.php","line":504,"function":"createFile","class":"Sabre\\DAV\\Server","type":"->"},{"file":"/var/www/XXX/3rdparty/sabre/event/lib/WildcardEmitterTrait.php","line":89,"function":"httpPut","class":"Sabre\\DAV\\CorePlugin","type":"->"},{"file":"/var/www/XXX/3rdparty/sabre/dav/lib/DAV/Server.php","line":472,"function":"emit","class":"Sabre\\DAV\\Server","type":"->"},{"file":"/var/www/XXX/3rdparty/sabre/dav/lib/DAV/Server.php","line":253,"function":"invokeMethod","class":"Sabre\\DAV\\Server","type":"->"},{"file":"/var/www/XXX/3rdparty/sabre/dav/lib/DAV/Server.php","line":321,"function":"start","class":"Sabre\\DAV\\Server","type":"->"},{"file":"/var/www/XXX/apps/dav/lib/Server.php","line":360,"function":"exec","class":"Sabre\\DAV\\Server","type":"->"},{"file":"/var/www/XXX/apps/dav/appinfo/v2/remote.php","line":35,"function":"exec","class":"OCA\\DAV\\Server","type":"->"},{"file":"/var/www/XXX/remote.php","line":171,"args":["/var/www/XXX/apps/dav/appinfo/v2/remote.php"],"function":"require_once"}],"File":"/var/www/XXX/lib/private/Log/ErrorHandler.php","Line":92,"CustomMessage":"--"}}

Additional info

No response

@raid1 raid1 added 0. Needs triage Pending check for reproducibility or if it fits our roadmap bug labels Nov 25, 2022
@tcitworld
Copy link
Member

Do you have both phpsessionclean.service and phpsessionclean.timer activated?

@raid1
Copy link
Author

raid1 commented Nov 25, 2022

Do you have both phpsessionclean.service and phpsessionclean.timer activated?

Yes

@kesselb
Copy link
Contributor

kesselb commented Nov 25, 2022

Likely a duplicate of #7628

@raid1
Copy link
Author

raid1 commented Nov 28, 2022

Uhm, yes you could be right. I'll have to check all my users whether they are using webdav or API calls but I don't think so.
Is there any other possible cause for that many session files?
And work on #7628 seems to be pretty stale... :-(

@raid1
Copy link
Author

raid1 commented Dec 23, 2022

I think this has to do with the Apps "Nextcloud Office" and "Collabora Online - Built-in CODE Server".
I noticed that most http requests came from the server itself. After disabling these two apps (and killing the coolwsd and coolforkit processes, no more superflous php sessions are created.

@szaimen
Copy link
Contributor

szaimen commented Dec 23, 2022

#7628 has ben resolved

@szaimen szaimen closed this as completed Dec 23, 2022
@raid1
Copy link
Author

raid1 commented Dec 23, 2022

Uhm, are you still sure that this is a duplicate of #7628 ?
I just added some information which makes me believe that this a distinct issue. (I hope I'm wrong)
Or can you confirm that these Office apps are using the same API?
Thanks

@raid1
Copy link
Author

raid1 commented Dec 30, 2022

I'm afraid noone is reading my last comment, since the issue is already closed.
I still don't believe it's resolved, though.

Do I really have to open a new issue?

@kesselb
Copy link
Contributor

kesselb commented Dec 30, 2022

Do I really have to open a new issue?

If you see the issue with Nextcloud 26 yes.

@raid1
Copy link
Author

raid1 commented Dec 30, 2022

This does not really make sense.
The issue should not be closed because someone believes it's a duplicate.
Especially since I provided data that show the opposite.

@kesselb kesselb reopened this Dec 30, 2022
@joshtrichards
Copy link
Member

You should be able to match many, if not most, of these sessions up with the transactions in (either) your web server or nextcloud logs by just matching up the timestamps. Some may inadvertently match up, but if you do it across a large enough window (from the looks of it 5-10 minutes should be sufficient) a clear pattern should emerge.

Unfortunately the logs you provided were only a narrow slice, but the only one that does match up in what you provided is:

-rw------- 1 www-data www-data  420 2022-11-25 13:30:23 sess_il8hv287ghg095f47i9th0c2os

with:

25/Nov/2022:13:30:23 +0000] "GET /apps/richdocumentscode/proxy.php?req=/hosting/capabilities HTTP/1.1" 200 5693 "-" "Nextcloud Server Crawler"
[25/Nov/2022:13:30:23 +0000] "GET / HTTP/1.1" 302 6345 "-" "check_http/v2.2 (monitoring-plugins 2.2)"

That doesn't mean Office/CODE is the culprit - only that it's one of the things accessing your server regularly (you'll note there are 8 other similar transactions during the same narrow time window without corresponding session files).

Do you by chance have session.auto_start enabled in your php.ini (or one of your per directory overrides)? I think that could result in every single transaction (including bots/etc) triggering session creation even without a login...

@joshtrichards
Copy link
Member

Yesterday I deleted 2,5 million sess_* files there!
(session.gc_maxlifetime = 172800)

@raid1 What is the oldest sess_* file? That'll give you an idea of how well your gc is functioning. Your session.gc_maxlifetime is pretty high. The default is 1440.

@nextcloud-command
Copy link
Contributor

This issue has been automatically marked as stale because it has not had recent activity and seems to be missing some essential information. It will be closed if no further activity occurs. Thank you for your contributions.

@nextcloud-command nextcloud-command added the stale Ticket or PR with no recent activity label Oct 19, 2023
@raid1
Copy link
Author

raid1 commented Oct 19, 2023

@raid1 What is the oldest sess_* file? That'll give you an idea of how well your gc is functioning. Your session.gc_maxlifetime is pretty high. The default is 1440.

Thanks for taking care of this issue.
However I cannot provide more details after such a long time unfortunately.
Workaround was to switch all my users to use "app passwords".
That helped.

@nextcloud-command nextcloud-command removed stale Ticket or PR with no recent activity needs info labels Oct 20, 2023
@joshtrichards joshtrichards closed this as not planned Won't fix, can't repro, duplicate, stale Aug 4, 2024
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
0. Needs triage Pending check for reproducibility or if it fits our roadmap 25-feedback bug feature: authentication needs info
Projects
None yet
Development

No branches or pull requests

6 participants