Files automaticly deleted from server

Server
1

Hello,

I have a problem with the windows 10 client or the owncloud server, I dont know exactly.

Behavier

I have copyed a file into the owncloud directory with the windows explorer.
A short time later the files is automaticly deleted from the client without any user activity.
This can be reproduced with the same file. Copy another file to the same folder works.
I had this problem some time ago with other files.

What I have analyzed:

The file don´t exists on the windows clients.
The file don´t exists an the web gui.
But:
The file still exists on the data directory on the server.
The file is also in the MariaDB oc_filecache database.

A simple occ files:scan don´t help. The file is not existing after this on the clients.
But if I am deleting the DB entries for this file, make then a occ files:scan, the the file was transfered to the clients.

I’m considering doing the following:

remove all DB entries from oc_filecache and make a occ files:scan --all
I hope that then all files are restored which have already this problem.
Is this a good idea or did I destroy my owncloud with this?

more Infos?

did you need more infos/logs or can I try diferent other things?

regards and thanks for help,
Frank

Server configuration

Operating system:
Debian Linux 4.9.0-11

Web server:
Apache Version 2.4.25

Database:
10.1.41-MariaDB-0+deb9u1 - Debian 9.9

PHP version:
PHP Version 7.3.12-1

ownCloud version: (see ownCloud admin page)
10.3.0.4

Updated from an older ownCloud or fresh install:
Update from Version 10.0.2.1

Where did you install ownCloud from:
With the web gui updater

Signing status (ownCloud 9.0 and above):
-> No errors have been found.

List of activated apps:
Enabled:

  • calendar: 1.6.3
  • comments: 0.3.0
  • configreport: 0.2.0
  • contacts: 1.5.5
  • dav: 0.5.0
  • federatedfilesharing: 0.5.0
  • federation: 0.1.0
  • files: 1.5.2
  • files_external: 0.7.1
  • files_mediaviewer: 1.0.1
  • files_sharing: 0.12.0
  • files_trashbin: 0.9.1
  • files_versions: 1.3.0
  • files_videoplayer: 0.10.1
  • firstrunwizard: 1.2.0
  • gallery: 16.1.1
  • market: 0.5.0
  • notifications: 0.5.0
  • ownbackup: 19.5.0
  • provisioning_api: 0.5.0
  • systemtags: 0.3.0
  • tasks: 0.9.7
  • updatenotification: 0.2.1
    Disabled:
  • encryption
  • external
  • user_external

Are you using external storage, if yes which one: local/smb/sftp/…
no

Are you using encryption: yes/no
no

Are you using an external user-backend, if yes which one: LDAP/ActiveDirectory/Webdav/…
no

Client configuration

Browser:
mostly Firefox last version
sometimes ie11

Operating system:
Windows 10

Logs

ownCloud log (data/owncloud.log)

{“reqId”:“WYmbdnce5hsCOaQmmjwB”,“level”:1,“time”:“2019-11-29T06:30:02+00:00”,“remoteAddr”:"",“user”:"–",“app”:“cron”,“method”:"–",“url”:"–",“message”:“Invalidating tokens older than 2019-11-29T06:10:02+00:00”}
{“reqId”:“yWOdNtITROWoy8vV5suy”,“level”:0,“time”:“2019-11-29T06:49:34+00:00”,“remoteAddr”:“192.168.213.18”,“user”:“frank”,“app”:“OC\User\Session::validateToken”,“method”:“GET”,“url”:"/owncloud/index.php/settings/admin?sectionid=general",“message”:“token 660a9dfba9153b2d9f2813e845193dec8cb6ee1ae714e02f9b764358c98d2cc2a1579bebd72e217ed305ceb12050a51f4407c4692b0bcb0dd78e4eeaa036340b with token id 44598 found, validating”}
{“reqId”:“YIPrCWmEWcYjFjyAIOGG”,“level”:0,“time”:“2019-11-29T06:49:34+00:00”,“remoteAddr”:“192.168.213.18”,“user”:“frank”,“app”:“OC\User\Session::validateToken”,“method”:“GET”,“url”:"/owncloud/index.php/core/js/oc.js?v=32cc372cb1be7754bc2a0be47f3d3612",“message”:“token 660a9dfba9153b2d9f2813e845193dec8cb6ee1ae714e02f9b764358c98d2cc2a1579bebd72e217ed305ceb12050a51f4407c4692b0bcb0dd78e4eeaa036340b with token id 44598 found, validating”}
{“reqId”:“OcY1OkLL0MrOx9oCSysc”,“level”:0,“time”:“2019-11-29T06:49:35+00:00”,“remoteAddr”:“192.168.213.18”,“user”:“frank”,“app”:“OC\User\Session::validateToken”,“method”:“GET”,“url”:"/owncloud/index.php/avatar/frank/32",“message”:“token 660a9dfba9153b2d9f2813e845193dec8cb6ee1ae714e02f9b764358c98d2cc2a1579bebd72e217ed305ceb12050a51f4407c4692b0bcb0dd78e4eeaa036340b with token id 44598 found, validating”}
{“reqId”:“RaSEpSRHIo2IAX029AaR”,“level”:0,“time”:“2019-11-29T06:49:35+00:00”,“remoteAddr”:“192.168.213.18”,“user”:“frank”,“app”:“OC\User\Session::validateToken”,“method”:“GET”,“url”:"/owncloud/ocs/v2.php/apps/notifications/api/v1/notifications?format=json",“message”:“token 660a9dfba9153b2d9f2813e845193dec8cb6ee1ae714e02f9b764358c98d2cc2a1579bebd72e217ed305ceb12050a51f4407c4692b0bcb0dd78e4eeaa036340b with token id 44598 found, validating”}
{“reqId”:“H9F68pzf6BQHFfvfX8tt”,“level”:0,“time”:“2019-11-29T06:49:35+00:00”,“remoteAddr”:“192.168.213.18”,“user”:“frank”,“app”:“OC\User\Session::validateToken”,“method”:“GET”,“url”:"/owncloud/index.php/settings/ajax/checksetup",“message”:“token 660a9dfba9153b2d9f2813e845193dec8cb6ee1ae714e02f9b764358c98d2cc2a1579bebd72e217ed305ceb12050a51f4407c4692b0bcb0dd78e4eeaa036340b with token id 44598 found, validating”}
{“reqId”:“ZApXUAGXN2IpWQvIZKbe”,“level”:0,“time”:“2019-11-29T06:49:35+00:00”,“remoteAddr”:“192.168.213.18”,“user”:“frank”,“app”:“OC\User\Session::validateToken”,“method”:“PROPFIND”,“url”:"/owncloud/remote.php/webdav",“message”:“token 660a9dfba9153b2d9f2813e845193dec8cb6ee1ae714e02f9b764358c98d2cc2a1579bebd72e217ed305ceb12050a51f4407c4692b0bcb0dd78e4eeaa036340b with token id 44598 found, validating”}
{“reqId”:“rrgxRsJYElHoR7D75KIX”,“level”:0,“time”:“2019-11-29T06:49:35+00:00”,“remoteAddr”:“192.168.213.18”,“user”:“frank”,“app”:“OC\User\Session::validateToken”,“method”:“GET”,“url”:"/owncloud/index.php/heartbeat",“message”:“token 660a9dfba9153b2d9f2813e845193dec8cb6ee1ae714e02f9b764358c98d2cc2a1579bebd72e217ed305ceb12050a51f4407c4692b0bcb0dd78e4eeaa036340b with token id 44598 found, validating”}
{“reqId”:“4f257f04-4492-4157-b4a6-c230c5821d61”,“level”:0,“time”:“2019-11-29T06:49:36+00:00”,“remoteAddr”:“192.168.213.18”,“user”:“frank”,“app”:“OC\Authentication\Token\DefaultTokenProvider::updateToken”,“method”:“PROPFIND”,“url”:"/owncloud/remote.php/dav/files/frank/",“message”:“updating token 44597, last check is now 1575009929”}
{“reqId”:“4f257f04-4492-4157-b4a6-c230c5821d61”,“level”:0,“time”:“2019-11-29T06:49:36+00:00”,“remoteAddr”:“192.168.213.18”,“user”:“frank”,“app”:“OC\Authentication\Token\DefaultTokenProvider::getToken”,“method”:“PROPFIND”,“url”:"/owncloud/remote.php/dav/files/frank/",“message”:“token c2b1b409938c3200c5b1dd71822d78b13e904c1b62a326fcdff8acf565afabae368296cacf7c1e51543bc3f864c2cbcfade02861a8b4e1916c7266f49a1bf588 does not exist”}
{“reqId”:“6cf7d940-1e11-4609-b40e-d558dcba0d77”,“level”:0,“time”:“2019-11-29T06:49:44+00:00”,“remoteAddr”:“192.168.213.18”,“user”:“frank”,“app”:“OC\Authentication\Token\DefaultTokenProvider::updateToken”,“method”:“GET”,“url”:"/owncloud/ocs/v2.php/apps/notifications/api/v1/notifications?format=json",“message”:“updating token 44597, last check is now 1575009929”}
{“reqId”:“6cf7d940-1e11-4609-b40e-d558dcba0d77”,“level”:0,“time”:“2019-11-29T06:49:45+00:00”,“remoteAddr”:“192.168.213.18”,“user”:“frank”,“app”:“OC\Authentication\Token\DefaultTokenProvider::updateToken”,“method”:“GET”,“url”:"/owncloud/ocs/v2.php/apps/notifications/api/v1/notifications?format=json",“message”:“updating token 44597, last check is now 1575009929”}
{“reqId”:“6cf7d940-1e11-4609-b40e-d558dcba0d77”,“level”:0,“time”:“2019-11-29T06:49:45+00:00”,“remoteAddr”:“192.168.213.18”,“user”:“frank”,“app”:“OC\Authentication\Token\DefaultTokenProvider::getToken”,“method”:“GET”,“url”:"/owncloud/ocs/v2.php/apps/notifications/api/v1/notifications?format=json",“message”:“token c2b1b409938c3200c5b1dd71822d78b13e904c1b62a326fcdff8acf565afabae368296cacf7c1e51543bc3f864c2cbcfade02861a8b4e1916c7266f49a1bf588 does not exist”}
{“reqId”:“206ad56a-d4e5-4107-81c7-a227533d657d”,“level”:0,“time”:“2019-11-29T06:49:45+00:00”,“remoteAddr”:“192.168.213.18”,“user”:“frank”,“app”:“OC\Authentication\Token\DefaultTokenProvider::updateToken”,“method”:“GET”,“url”:"/owncloud/ocs/v1.php/cloud/activity?page=0&pagesize=100&format=json",“message”:“updating token 44597, last check is now 1575009929”}
{“reqId”:“206ad56a-d4e5-4107-81c7-a227533d657d”,“level”:0,“time”:“2019-11-29T06:49:45+00:00”,“remoteAddr”:“192.168.213.18”,“user”:“frank”,“app”:“OC\Authentication\Token\DefaultTokenProvider::updateToken”,“method”:“GET”,“url”:"/owncloud/ocs/v1.php/cloud/activity?page=0&pagesize=100&format=json",“message”:“updating token 44597, last check is now 1575009929”}
{“reqId”:“206ad56a-d4e5-4107-81c7-a227533d657d”,“level”:0,“time”:“2019-11-29T06:49:45+00:00”,“remoteAddr”:“192.168.213.18”,“user”:“frank”,“app”:“OC\Authentication\Token\DefaultTokenProvider::getToken”,“method”:“GET”,“url”:"/owncloud/ocs/v1.php/cloud/activity?page=0&pagesize=100&format=json",“message”:“token c2b1b409938c3200c5b1dd71822d78b13e904c1b62a326fcdff8acf565afabae368296cacf7c1e51543bc3f864c2cbcfade02861a8b4e1916c7266f49a1bf588 does not exist”}
{“reqId”:“112544a4-40df-479b-af1f-5632d4cf2378”,“level”:0,“time”:“2019-11-29T06:49:45+00:00”,“remoteAddr”:“192.168.213.18”,“user”:“frank”,“app”:“OC\Authentication\Token\DefaultTokenProvider::updateToken”,“method”:“PROPFIND”,“url”:"/owncloud/remote.php/dav/files/frank/",“message”:“updating token 44597, last check is now 1575009929”}
{“reqId”:“112544a4-40df-479b-af1f-5632d4cf2378”,“level”:0,“time”:“2019-11-29T06:49:45+00:00”,“remoteAddr”:“192.168.213.18”,“user”:“frank”,“app”:“OC\Authentication\Token\DefaultTokenProvider::getToken”,“method”:“PROPFIND”,“url”:"/owncloud/remote.php/dav/files/frank/",“message”:“token c2b1b409938c3200c5b1dd71822d78b13e904c1b62a326fcdff8acf565afabae368296cacf7c1e51543bc3f864c2cbcfade02861a8b4e1916c7266f49a1bf588 does not exist”}
{“reqId”:“4b3cf982-fdf6-447d-b025-9895dbca5b6d”,“level”:0,“time”:“2019-11-29T06:49:45+00:00”,“remoteAddr”:“192.168.213.18”,“user”:“frank”,“app”:“OC\Authentication\Token\DefaultTokenProvider::updateToken”,“method”:“PUT”,“url”:"/owncloud/remote.php/dav/files/frank/_geteilt_von_SSG/SSG_Wettkampfberichte/RWK%2019-20/Altersklasse%20aufgelegt%203%20,%205%20-%202774.pdf",“message”:“updating token 44597, last check is now 1575009929”}
{“reqId”:“4b3cf982-fdf6-447d-b025-9895dbca5b6d”,“level”:0,“time”:“2019-11-29T06:49:45+00:00”,“remoteAddr”:“192.168.213.18”,“user”:“frank”,“app”:“OC\Authentication\Token\DefaultTokenProvider::getToken”,“method”:“PUT”,“url”:"/owncloud/remote.php/dav/files/frank/_geteilt_von_SSG/SSG_Wettkampfberichte/RWK%2019-20/Altersklasse%20aufgelegt%203%20,%205%20-%202774.pdf",“message”:“token c2b1b409938c3200c5b1dd71822d78b13e904c1b62a326fcdff8acf565afabae368296cacf7c1e51543bc3f864c2cbcfade02861a8b4e1916c7266f49a1bf588 does not exist”}
{“reqId”:“wyxLU8mid2oJImrGpaGr”,“level”:0,“time”:“2019-11-29T06:50:05+00:00”,“remoteAddr”:“192.168.213.18”,“user”:“frank”,“app”:“OC\User\Session::validateToken”,“method”:“GET”,“url”:"/owncloud/ocs/v2.php/apps/notifications/api/v1/notifications?format=json",“message”:“token 660a9dfba9153b2d9f2813e845193dec8cb6ee1ae714e02f9b764358c98d2cc2a1579bebd72e217ed305ceb12050a51f4407c4692b0bcb0dd78e4eeaa036340b with token id 44598 found, validating”}
{“reqId”:“35fd3849-f9ff-4139-a499-1a94a1c7d80f”,“level”:0,“time”:“2019-11-29T06:50:29+00:00”,“remoteAddr”:“192.168.213.18”,“user”:“frank”,“app”:“OC\Authentication\Token\DefaultTokenProvider::updateToken”,“method”:“PROPFIND”,“url”:"/owncloud/remote.php/dav/files/frank/",“message”:“updating token 44597, last check is now 1575009929”}
{“reqId”:“35fd3849-f9ff-4139-a499-1a94a1c7d80f”,“level”:0,“time”:“2019-11-29T06:50:29+00:00”,“remoteAddr”:“192.168.213.18”,“user”:“frank”,“app”:“OC\Authentication\Token\DefaultTokenProvider::getToken”,“method”:“PROPFIND”,“url”:"/owncloud/remote.php/dav/files/frank/",“message”:“token c2b1b409938c3200c5b1dd71822d78b13e904c1b62a326fcdff8acf565afabae368296cacf7c1e51543bc3f864c2cbcfade02861a8b4e1916c7266f49a1bf588 does not exist”}
{“reqId”:“d0eb097f-1f57-4737-a81f-1b296ee92d04”,“level”:0,“time”:“2019-11-29T06:50:30+00:00”,“remoteAddr”:“192.168.213.18”,“user”:“frank”,“app”:“OC\Authentication\Token\DefaultTokenProvider::updateToken”,“method”:“GET”,“url”:"/owncloud/ocs/v1.php/cloud/activity?page=0&pagesize=100&format=json",“message”:“updating token 44597, last check is now 1575009929”}
{“reqId”:“d0eb097f-1f57-4737-a81f-1b296ee92d04”,“level”:0,“time”:“2019-11-29T06:50:30+00:00”,“remoteAddr”:“192.168.213.18”,“user”:“frank”,“app”:“OC\Authentication\Token\DefaultTokenProvider::updateToken”,“method”:“GET”,“url”:"/owncloud/ocs/v1.php/cloud/activity?page=0&pagesize=100&format=json",“message”:“updating token 44597, last check is now 1575009929”}
{“reqId”:“d0eb097f-1f57-4737-a81f-1b296ee92d04”,“level”:0,“time”:“2019-11-29T06:50:30+00:00”,“remoteAddr”:“192.168.213.18”,“user”:“frank”,“app”:“OC\Authentication\Token\DefaultTokenProvider::getToken”,“method”:“GET”,“url”:"/owncloud/ocs/v1.php/cloud/activity?page=0&pagesize=100&format=json",“message”:“token c2b1b409938c3200c5b1dd71822d78b13e904c1b62a326fcdff8acf565afabae368296cacf7c1e51543bc3f864c2cbcfade02861a8b4e1916c7266f49a1bf588 does not exist”}
{“reqId”:“cad44834-a4f8-447d-b891-94db22b726b7”,“level”:0,“time”:“2019-11-29T06:50:30+00:00”,“remoteAddr”:“192.168.213.18”,“user”:“frank”,“app”:“OC\Authentication\Token\DefaultTokenProvider::updateToken”,“method”:“GET”,“url”:"/owncloud/ocs/v2.php/apps/notifications/api/v1/notifications?format=json",“message”:“updating token 44597, last check is now 1575009929”}
{“reqId”:“cad44834-a4f8-447d-b891-94db22b726b7”,“level”:0,“time”:“2019-11-29T06:50:30+00:00”,“remoteAddr”:“192.168.213.18”,“user”:“frank”,“app”:“OC\Authentication\Token\DefaultTokenProvider::updateToken”,“method”:“GET”,“url”:"/owncloud/ocs/v2.php/apps/notifications/api/v1/notifications?format=json",“message”:“updating token 44597, last check is now 1575009929”}
{“reqId”:“cad44834-a4f8-447d-b891-94db22b726b7”,“level”:0,“time”:“2019-11-29T06:50:30+00:00”,“remoteAddr”:“192.168.213.18”,“user”:“frank”,“app”:“OC\Authentication\Token\DefaultTokenProvider::getToken”,“method”:“GET”,“url”:"/owncloud/ocs/v2.php/apps/notifications/api/v1/notifications?format=json",“message”:“token c2b1b409938c3200c5b1dd71822d78b13e904c1b62a326fcdff8acf565afabae368296cacf7c1e51543bc3f864c2cbcfade02861a8b4e1916c7266f49a1bf588 does not exist”}
{“reqId”:“68194cd3-109d-4b6f-bcfd-83a37b9de9f0”,“level”:0,“time”:“2019-11-29T06:50:30+00:00”,“remoteAddr”:“192.168.213.18”,“user”:“frank”,“app”:“OC\Authentication\Token\DefaultTokenProvider::updateToken”,“method”:“PROPFIND”,“url”:"/owncloud/remote.php/dav/files/frank/",“message”:“updating token 44597, last check is now 1575009929”}
{“reqId”:“68194cd3-109d-4b6f-bcfd-83a37b9de9f0”,“level”:0,“time”:“2019-11-29T06:50:30+00:00”,“remoteAddr”:“192.168.213.18”,“user”:“frank”,“app”:“OC\Authentication\Token\DefaultTokenProvider::getToken”,“method”:“PROPFIND”,“url”:"/owncloud/remote.php/dav/files/frank/",“message”:“token c2b1b409938c3200c5b1dd71822d78b13e904c1b62a326fcdff8acf565afabae368296cacf7c1e51543bc3f864c2cbcfade02861a8b4e1916c7266f49a1bf588 does not exist”}
{“reqId”:“95b08fa6-25b8-4088-82af-695ca72788ce”,“level”:0,“time”:“2019-11-29T06:50:30+00:00”,“remoteAddr”:“192.168.213.18”,“user”:“frank”,“app”:“OC\Authentication\Token\DefaultTokenProvider::updateToken”,“method”:“PROPFIND”,“url”:"/owncloud/remote.php/dav/files/frank/_geteilt_von_SSG",“message”:“updating token 44597, last check is now 1575009929”}
{“reqId”:“95b08fa6-25b8-4088-82af-695ca72788ce”,“level”:0,“time”:“2019-11-29T06:50:30+00:00”,“remoteAddr”:“192.168.213.18”,“user”:“frank”,“app”:“OC\Authentication\Token\DefaultTokenProvider::getToken”,“method”:“PROPFIND”,“url”:"/owncloud/remote.php/dav/files/frank/_geteilt_von_SSG",“message”:“token c2b1b409938c3200c5b1dd71822d78b13e904c1b62a326fcdff8acf565afabae368296cacf7c1e51543bc3f864c2cbcfade02861a8b4e1916c7266f49a1bf588 does not exist”}
{“reqId”:“38c97b38-76dc-4e95-a54f-ecf67b3c5a8f”,“level”:0,“time”:“2019-11-29T06:50:31+00:00”,“remoteAddr”:“192.168.213.18”,“user”:“frank”,“app”:“OC\Authentication\Token\DefaultTokenProvider::updateToken”,“method”:“PROPFIND”,“url”:"/owncloud/remote.php/dav/files/frank/_geteilt_von_SSG/SSG_Wettkampfberichte",“message”:“updating token 44597, last check is now 1575009929”}
{“reqId”:“38c97b38-76dc-4e95-a54f-ecf67b3c5a8f”,“level”:0,“time”:“2019-11-29T06:50:31+00:00”,“remoteAddr”:“192.168.213.18”,“user”:“frank”,“app”:“OC\Authentication\Token\DefaultTokenProvider::getToken”,“method”:“PROPFIND”,“url”:"/owncloud/remote.php/dav/files/frank/_geteilt_von_SSG/SSG_Wettkampfberichte",“message”:“token c2b1b409938c3200c5b1dd71822d78b13e904c1b62a326fcdff8acf565afabae368296cacf7c1e51543bc3f864c2cbcfade02861a8b4e1916c7266f49a1bf588 does not exist”}
{“reqId”:“fd28cbe0-28ac-425b-b206-c848f5e238f7”,“level”:0,“time”:“2019-11-29T06:50:31+00:00”,“remoteAddr”:“192.168.213.18”,“user”:“frank”,“app”:“OC\Authentication\Token\DefaultTokenProvider::updateToken”,“method”:“PROPFIND”,“url”:"/owncloud/remote.php/dav/files/frank/_geteilt_von_SSG/SSG_Wettkampfberichte/RWK%2019-20",“message”:“updating token 44597, last check is now 1575009929”}
{“reqId”:“fd28cbe0-28ac-425b-b206-c848f5e238f7”,“level”:0,“time”:“2019-11-29T06:50:31+00:00”,“remoteAddr”:“192.168.213.18”,“user”:“frank”,“app”:“OC\Authentication\Token\DefaultTokenProvider::getToken”,“method”:“PROPFIND”,“url”:"/owncloud/remote.php/dav/files/frank/_geteilt_von_SSG/SSG_Wettkampfberichte/RWK%2019-20",“message”:“token c2b1b409938c3200c5b1dd71822d78b13e904c1b62a326fcdff8acf565afabae368296cacf7c1e51543bc3f864c2cbcfade02861a8b4e1916c7266f49a1bf588 does not exist”}
{“reqId”:“87a81e19-d415-4509-b17b-75df31d9d6ee”,“level”:0,“time”:“2019-11-29T06:50:31+00:00”,“remoteAddr”:“192.168.213.18”,“user”:“frank”,“app”:“OC\Authentication\Token\DefaultTokenProvider::updateToken”,“method”:“PROPFIND”,“url”:"/owncloud/remote.php/dav/files/frank/",“message”:“updating token 44597, last check is now 1575009929”}
{“reqId”:“87a81e19-d415-4509-b17b-75df31d9d6ee”,“level”:0,“time”:“2019-11-29T06:50:31+00:00”,“remoteAddr”:“192.168.213.18”,“user”:“frank”,“app”:“OC\Authentication\Token\DefaultTokenProvider::getToken”,“method”:“PROPFIND”,“url”:"/owncloud/remote.php/dav/files/frank/",“message”:“token c2b1b409938c3200c5b1dd71822d78b13e904c1b62a326fcdff8acf565afabae368296cacf7c1e51543bc3f864c2cbcfade02861a8b4e1916c7266f49a1bf588 does not exist”}
{“reqId”:“12921fed-cce2-4264-b05a-6df72e89cfdc”,“level”:0,“time”:“2019-11-29T06:50:33+00:00”,“remoteAddr”:“192.168.213.18”,“user”:“frank”,“app”:“OC\Authentication\Token\DefaultTokenProvider::updateToken”,“method”:“PROPFIND”,“url”:"/owncloud/remote.php/dav/files/frank/",“message”:“updating token 44597, last check is now 1575009929”}
{“reqId”:“12921fed-cce2-4264-b05a-6df72e89cfdc”,“level”:0,“time”:“2019-11-29T06:50:33+00:00”,“remoteAddr”:“192.168.213.18”,“user”:“frank”,“app”:“OC\Authentication\Token\DefaultTokenProvider::getToken”,“method”:“PROPFIND”,“url”:"/owncloud/remote.php/dav/files/frank/",“message”:“token c2b1b409938c3200c5b1dd71822d78b13e904c1b62a326fcdff8acf565afabae368296cacf7c1e51543bc3f864c2cbcfade02861a8b4e1916c7266f49a1bf588 does not exist”}
{“reqId”:“GAZoxCldwZCEZ5S3LCQi”,“level”:0,“time”:“2019-11-29T06:50:35+00:00”,“remoteAddr”:“192.168.213.18”,“user”:“frank”,“app”:“OC\User\Session::validateToken”,“method”:“GET”,“url”:"/owncloud/ocs/v2.php/apps/notifications/api/v1/notifications?format=json",“message”:“token 660a9dfba9153b2d9f2813e845193dec8cb6ee1ae714e02f9b764358c98d2cc2a1579bebd72e217ed305ceb12050a51f4407c4692b0bcb0dd78e4eeaa036340b with token id 44598 found, validating”}
{“reqId”:“GAZoxCldwZCEZ5S3LCQi”,“level”:0,“time”:“2019-11-29T06:50:35+00:00”,“remoteAddr”:“192.168.213.18”,“user”:“frank”,“app”:“OC\Authentication\Token\DefaultTokenProvider::updateTokenActivity”,“method”:“GET”,“url”:"/owncloud/ocs/v2.php/apps/notifications/api/v1/notifications?format=json",“message”:“updating activity of token 44598 to 1575010235”}
{“reqId”:“GAZoxCldwZCEZ5S3LCQi”,“level”:0,“time”:“2019-11-29T06:50:35+00:00”,“remoteAddr”:“192.168.213.18”,“user”:“frank”,“app”:“OC\User\Session::validateToken”,“method”:“GET”,“url”:"/owncloud/ocs/v2.php/apps/notifications/api/v1/notifications?format=json",“message”:“token 660a9dfba9153b2d9f2813e845193dec8cb6ee1ae714e02f9b764358c98d2cc2a1579bebd72e217ed305ceb12050a51f4407c4692b0bcb0dd78e4eeaa036340b with token id 44598 found, validating”}
{“reqId”:“f84213b9-2f78-4d8c-b52b-0517de379132”,“level”:0,“time”:“2019-11-29T06:51:29+00:00”,“remoteAddr”:“192.168.213.18”,“user”:“frank”,“app”:“OC\Authentication\Token\DefaultTokenProvider::updateToken”,“method”:“PROPFIND”,“url”:"/owncloud/remote.php/dav/files/frank/",“message”:“updating token 44597, last check is now 1575009929”}
{“reqId”:“f84213b9-2f78-4d8c-b52b-0517de379132”,“level”:0,“time”:“2019-11-29T06:51:29+00:00”,“remoteAddr”:“192.168.213.18”,“user”:“frank”,“app”:“OC\Authentication\Token\DefaultTokenProvider::getToken”,“method”:“PROPFIND”,“url”:"/owncloud/remote.php/dav/files/frank/",“message”:“token c2b1b409938c3200c5b1dd71822d78b13e904c1b62a326fcdff8acf565afabae368296cacf7c1e51543bc3f864c2cbcfade02861a8b4e1916c7266f49a1bf588 does not exist”}
{“reqId”:“e4044764-49de-485e-be25-16d613e39a84”,“level”:0,“time”:“2019-11-29T06:51:31+00:00”,“remoteAddr”:“192.168.213.18”,“user”:“frank”,“app”:“OC\Authentication\Token\DefaultTokenProvider::updateToken”,“method”:“GET”,“url”:"/owncloud/ocs/v2.php/apps/notifications/api/v1/notifications?format=json",“message”:“updating token 44597, last check is now 1575009929”}
{“reqId”:“e4044764-49de-485e-be25-16d613e39a84”,“level”:0,“time”:“2019-11-29T06:51:31+00:00”,“remoteAddr”:“192.168.213.18”,“user”:“frank”,“app”:“OC\Authentication\Token\DefaultTokenProvider::updateToken”,“method”:“GET”,“url”:"/owncloud/ocs/v2.php/apps/notifications/api/v1/notifications?format=json",“message”:“updating token 44597, last check is now 1575009929”}
{“reqId”:“e4044764-49de-485e-be25-16d613e39a84”,“level”:0,“time”:“2019-11-29T06:51:31+00:00”,“remoteAddr”:“192.168.213.18”,“user”:“frank”,“app”:“OC\Authentication\Token\DefaultTokenProvider::getToken”,“method”:“GET”,“url”:"/owncloud/ocs/v2.php/apps/notifications/api/v1/notifications?format=json",“message”:“token c2b1b409938c3200c5b1dd71822d78b13e904c1b62a326fcdff8acf565afabae368296cacf7c1e51543bc3f864c2cbcfade02861a8b4e1916c7266f49a1bf588 does not exist”}
{“reqId”:“wnIwbmHwJxGFMbdoMBuT”,“level”:0,“time”:“2019-11-29T06:51:35+00:00”,“remoteAddr”:“192.168.213.18”,“user”:“frank”,“app”:“OC\User\Session::validateToken”,“method”:“GET”,“url”:"/owncloud/ocs/v2.php/apps/notifications/api/v1/notifications?format=json",“message”:“token 660a9dfba9153b2d9f2813e845193dec8cb6ee1ae714e02f9b764358c98d2cc2a1579bebd72e217ed305ceb12050a51f4407c4692b0bcb0dd78e4eeaa036340b with token id 44598 found, validating”}
{“reqId”:“wnIwbmHwJxGFMbdoMBuT”,“level”:0,“time”:“2019-11-29T06:51:35+00:00”,“remoteAddr”:“192.168.213.18”,“user”:“frank”,“app”:“OC\User\Session::validateToken”,“method”:“GET”,“url”:"/owncloud/ocs/v2.php/apps/notifications/api/v1/notifications?format=json",“message”:“token 660a9dfba9153b2d9f2813e845193dec8cb6ee1ae714e02f9b764358c98d2cc2a1579bebd72e217ed305ceb12050a51f4407c4692b0bcb0dd78e4eeaa036340b with token id 44598 found, validating”}
{“reqId”:“b3a395b1-fc97-4097-a7b7-9ad9153dd8a2”,“level”:0,“time”:“2019-11-29T06:51:59+00:00”,“remoteAddr”:“192.168.213.18”,“user”:“frank”,“app”:“OC\Authentication\Token\DefaultTokenProvider::updateToken”,“method”:“PROPFIND”,“url”:"/owncloud/remote.php/dav/files/frank/",“message”:“updating token 44597, last check is now 1575009929”}
{“reqId”:“b3a395b1-fc97-4097-a7b7-9ad9153dd8a2”,“level”:0,“time”:“2019-11-29T06:51:59+00:00”,“remoteAddr”:“192.168.213.18”,“user”:“frank”,“app”:“OC\Authentication\Token\DefaultTokenProvider::getToken”,“method”:“PROPFIND”,“url”:"/owncloud/remote.php/dav/files/frank/",“message”:“token c2b1b409938c3200c5b1dd71822d78b13e904c1b62a326fcdff8acf565afabae368296cacf7c1e51543bc3f864c2cbcfade02861a8b4e1916c7266f49a1bf588 does not exist”}
{“reqId”:“b3a395b1-fc97-4097-a7b7-9ad9153dd8a2”,“level”:0,“time”:“2019-11-29T06:52:00+00:00”,“remoteAddr”:“192.168.213.18”,“user”:“frank”,“app”:“OC\User\SyncService”,“method”:“PROPFIND”,“url”:"/owncloud/remote.php/dav/files/frank/",“message”:“Setting lastLogin for to <1512033949>”}
{“reqId”:“byqBDIbC60GFVImL8EdL”,“level”:0,“time”:“2019-11-29T06:52:05+00:00”,“remoteAddr”:“192.168.213.18”,“user”:“frank”,“app”:“OC\User\Session::validateToken”,“method”:“GET”,“url”:"/owncloud/ocs/v2.php/apps/notifications/api/v1/notifications?format=json",“message”:“token 660a9dfba9153b2d9f2813e845193dec8cb6ee1ae714e02f9b764358c98d2cc2a1579bebd72e217ed305ceb12050a51f4407c4692b0bcb0dd78e4eeaa036340b with token id 44598 found, validating”}
{“reqId”:“byqBDIbC60GFVImL8EdL”,“level”:0,“time”:“2019-11-29T06:52:05+00:00”,“remoteAddr”:“192.168.213.18”,“user”:“frank”,“app”:“OC\Authentication\Token\DefaultTokenProvider::updateTokenActivity”,“method”:“GET”,“url”:"/owncloud/ocs/v2.php/apps/notifications/api/v1/notifications?format=json",“message”:“updating activity of token 44598 to 1575010325”}
{“reqId”:“byqBDIbC60GFVImL8EdL”,“level”:0,“time”:“2019-11-29T06:52:05+00:00”,“remoteAddr”:“192.168.213.18”,“user”:“frank”,“app”:“OC\User\Session::validateToken”,“method”:“GET”,“url”:"/owncloud/ocs/v2.php/apps/notifications/api/v1/notifications?format=json",“message”:“token 660a9dfba9153b2d9f2813e845193dec8cb6ee1ae714e02f9b764358c98d2cc2a1579bebd72e217ed305ceb12050a51f4407c4692b0bcb0dd78e4eeaa036340b with token id 44598 found, validating”}
{“reqId”:“321d399e-1533-4610-981e-03899bd8d534”,“level”:0,“time”:“2019-11-29T06:52:29+00:00”,“remoteAddr”:“192.168.213.18”,“user”:“frank”,“app”:“OC\Authentication\Token\DefaultTokenProvider::updateToken”,“method”:“PROPFIND”,“url”:"/owncloud/remote.php/dav/files/frank/",“message”:“updating token 44597, last check is now 1575009929”}

Client log

#=#=#=# Syncrun started 2019-11-29T06:48:10Z
#=#=#=#=# Propagation starts 2019-11-29T06:48:11Z (last step: 921 msec, total: 921 msec)
06:48:11||_geteilt_von_SSG/SSG_Wettkampfberichte/RWK 19-20/Altersklasse aufgelegt 3 , 5 - 2774.pdf|INST_NEW|Up|1575010054|d8b14fb72a201d46a9f158875d90eec5|131815|00162478ocbf6a66a54g|4||204|0|0|96ccf12f-2377-4a42-bf11-26cf6d283f76|
#=#=#=# Syncrun finished 2019-11-29T06:48:12Z (last step: 1309 msec, total: 2231 msec)
#=#=#=# Syncrun started 2019-11-29T06:49:00Z
#=#=#=#=# Propagation starts 2019-11-29T06:49:01Z (last step: 1337 msec, total: 1337 msec)
||_geteilt_von_SSG/SSG_Wettkampfberichte/RWK 19-20/Altersklasse aufgelegt 3 , 5 - 2774.pdf|INST_REMOVE|Down|1575010054|d8b14fb72a201d46a9f158875d90eec5|131815|00162478ocbf6a66a54g|4||0|131815|1575010054||
#=#=#=# Syncrun finished 2019-11-29T06:49:01Z (last step: 40 msec, total: 1378 msec)
#=#=#=# Syncrun started 2019-11-29T06:49:03Z
#=#=#=#=# Propagation starts 2019-11-29T06:49:03Z (last step: 318 msec, total: 318 msec)
#=#=#=# Syncrun finished 2019-11-29T06:49:03Z (last step: 5 msec, total: 324 msec)
#=#=#=# Syncrun started 2019-11-29T06:49:44Z
#=#=#=#=# Propagation starts 2019-11-29T06:49:45Z (last step: 730 msec, total: 730 msec)
06:49:45||_geteilt_von_SSG/SSG_Wettkampfberichte/RWK 19-20/Altersklasse aufgelegt 3 , 5 - 2774.pdf|INST_NEW|Up|1575010054|d8b14fb72a201d46a9f158875d90eec5|131815|00162478ocbf6a66a54g|4||204|0|0|4b3cf982-fdf6-447d-b025-9895dbca5b6d|
#=#=#=# Syncrun finished 2019-11-29T06:49:46Z (last step: 1414 msec, total: 2144 msec)
#=#=#=# Syncrun started 2019-11-29T06:50:30Z
#=#=#=#=# Propagation starts 2019-11-29T06:50:31Z (last step: 1338 msec, total: 1338 msec)
||_geteilt_von_SSG/SSG_Wettkampfberichte/RWK 19-20/Altersklasse aufgelegt 3 , 5 - 2774.pdf|INST_REMOVE|Down|1575010054|d8b14fb72a201d46a9f158875d90eec5|131815|00162478ocbf6a66a54g|4||0|131815|1575010054||
#=#=#=# Syncrun finished 2019-11-29T06:50:31Z (last step: 39 msec, total: 1378 msec)
#=#=#=# Syncrun started 2019-11-29T06:50:33Z
#=#=#=#=# Propagation starts 2019-11-29T06:50:33Z (last step: 319 msec, total: 319 msec)
#=#=#=# Syncrun finished 2019-11-29T06:50:33Z (last step: 11 msec, total: 331 msec)

2
  1. Deleting the oc_filecache is a bad idea, as you will lose all metadata information about all files in your ownCloud, like who shared what with whom, public links, …
  2. I didn’t notice an error message in your logs yet.
  3. Which ownCloud client version are you using?
  4. Can you upload the file that’s being deleted in this folder into another folder? And is it still being deleted in there?
  5. Have you gone through the desktop client troubleshooting document?
1 Like
3
  1. OK, I don´t touch the database
  2. Yes, I have also not seen an error message
  3. 2.6.0 Build 12644
  4. Yes, uploading the same file to another directory with nearly the same name is poosible.
    Uploading against to the original directory is directly deleted without an error mesage.
  5. yes, I have looked to this site. But I find no solution for my problem.
4

Hello,

Have no one an idea why the file is automaticly deleted?
And more important, how I can identify and restore the files which are deleted.

regards,
Frank

5

I guess you already have your loglevel set to 0?
https://doc.owncloud.com/server/admin_manual/configuration/server/config_sample_php_parameters.html#define-the-log-level

How big is the file?

Can you upgrade to 10.3.1?

From what I can tell there is no file-firewall / workflow or anti-virus enabled. Can you please confirm that?

Is the file a special system file or is it hidden? What is the filename?

Have you tried deleting and recreating the folder where this is happening? (because you said it’s only happening in a specific folder) What’s the folder name?

Perhaps the combination of folder and file name is very long?

What filesystem are you using?

1 Like
6

Up to now the log level was set to 2.But I have now set it to 0 and try to test this afternoon t reproduce the failure with different names.
The log file is actually 5MB big.

Yes, I have now did the update to 10.3.1

Yes, that right, there is no firewall or something like this.

So, the filename with the failure is:
“/home/owncloud/data/SSG/files/SSG_Wettkampfberichte/RWK 19-20/Altersklasse aufgelegt 3 , 5 - 2774.pdf”
but this with the exact same file:
“/home/owncloud/data/SSG/files/SSG_Wettkampfberichte/RWK 19-20/Altersklasse aufgelegt 3 , 5 - 2774__.pdf”
“/home/owncloud/data/SSG/files/SSG_Wettkampfberichte/RWK 18-19/Altersklasse aufgelegt 3 , 5 - 2774.pdf”
is working. So I think it is not a problem from the filename, file type or size.
I think owncloud has at the fist try got a failure (which is not logged with log level 2) and after that they always delete the file after snyc it again.

The filesystem is ext4.

regards,
Frank

7

How was the file originally uploaded? Was it through the WebUI or the sync client?
As authenticated user, or as guest user through a public link file drop?

Just to reiterate, this one doesn’t work:
/home/owncloud/data/SSG/files/SSG_Wettkampfberichte/RWK 19-20/Altersklasse aufgelegt 3 , 5 - 2774.pdf

But the other two do work:

/home/owncloud/data/SSG/files/SSG_Wettkampfberichte/RWK 19-20/Altersklasse aufgelegt 3 , 5 - 2774__.pdf
/home/owncloud/data/SSG/files/SSG_Wettkampfberichte/RWK 18-19/Altersklasse aufgelegt 3 , 5 - 2774.pdf

My theory would also be, that an initial upload failed for whatever reason. Some remnants of this failure are still present in the filesystem and prevent the proper upload in subsequent attempts.

Hopefully we’ll have a helpful error message, with the log level set to 0.

EDIT: Is your cron working?

1 Like
8

the file was originally uploaded with Windows 10, I think Client version 2.5.4 and with the owner user of this directory.

Yes, only the file in the spezific folder doesn´t work.

The cron is running as system cron job every 15 minutes and seems OK.
cmd: /usr/bin/php -f /var/www/owncloud/occ system:cron

I am stay to log level 0 and create a logrotate for this file.
So if I get the problem against, we hopefully see a new hint.

Log at try adding the file additional

{"reqId":"bc2aa596-ebdc-4e3c-a059-545aecb32d3e","level":0,"time":"2019-12-03T18:05:45+00:00","remoteAddr":"80.187.120.160","user":"SSG","app":"OC\\Authentication\\Token\\DefaultTokenProvider::updateToken","method":"PUT","url":"\/owncloud\/remote.php\/dav\/files\/SSG\/SSG_Wettkampfberichte\/RWK%2019-20\/Altersklasse%20aufgelegt%203%20,%205%20-%202774.pdf","message":"updating token 45874, last check is now 1575396007"}
{"reqId":"bc2aa596-ebdc-4e3c-a059-545aecb32d3e","level":0,"time":"2019-12-03T18:05:45+00:00","remoteAddr":"80.187.120.160","user":"SSG","app":"OC\\Authentication\\Token\\DefaultTokenProvider::getToken","method":"PUT","url":"\/owncloud\/remote.php\/dav\/files\/SSG\/SSG_Wettkampfberichte\/RWK%2019-20\/Altersklasse%20aufgelegt%203%20,%205%20-%202774.pdf","message":"token ff27578854ef9d87e5a62124326dc7dce1f6a642edb02c4a0a641d0aca34689baca7ace542f0c84869af278e464da92225c534a2d966513307fdb800d395819b does not exist"}

Later I see this

{"reqId":"YQzhfzUuTm0lK2NEGs5v","level":0,"time":"2019-12-03T18:15:10+00:00","remoteAddr":"","user":"--","app":"cron","method":"--","url":"--","message":"Started background job of class : OC\\Command\\CommandJob with arguments : O:33:\"OCA\\Files_Versions\\Command\\Expire\":2:{s:43:\"\u0000OCA\\Files_Versions\\Command\\Expire\u0000fileName\";s:72:\"\/SSG_Wettkampfberichte\/RWK 19-20\/Altersklasse aufgelegt 3 , 5 - 2774.pdf\";s:39:\"\u0000OCA\\Files_Versions\\Command\\Expire\u0000user\";s:3:\"SSG\";}"}
{"reqId":"YQzhfzUuTm0lK2NEGs5v","level":1,"time":"2019-12-03T18:15:10+00:00","remoteAddr":"","user":"--","app":"files_versions","method":"--","url":"--","message":"Mark to expire \/SSG_Wettkampfberichte\/RWK 19-20\/Altersklasse aufgelegt 3 , 5 - 2774.pdf next version should be 1574923654 or smaller. (prevTimestamp: 1575010054; step: 86400"}
{"reqId":"YQzhfzUuTm0lK2NEGs5v","level":1,"time":"2019-12-03T18:15:10+00:00","remoteAddr":"","user":"--","app":"files_versions","method":"--","url":"--","message":"Expire: \/SSG_Wettkampfberichte\/RWK 19-20\/Altersklasse aufgelegt 3 , 5 - 2774.pdf.v1574964924"}
{"reqId":"YQzhfzUuTm0lK2NEGs5v","level":0,"time":"2019-12-03T18:15:10+00:00","remoteAddr":"","user":"--","app":"cron","method":"--","url":"--","message":"Finished background job, the job took : 0 seconds, this job is an instance of class : OC\\Command\\CommandJob with arguments : O:33:\"OCA\\Files_Versions\\Command\\Expire\":2:{s:43:\"\u0000OCA\\Files_Versions\\Command\\Expire\u0000fileName\";s:72:\"\/SSG_Wettkampfberichte\/RWK 19-20\/Altersklasse aufgelegt 3 , 5 - 2774.pdf\";s:39:\"\u0000OCA\\Files_Versions\\Command\\Expire\u0000user\";s:3:\"SSG\";}"}

And from my cron job

{"reqId":"7PNCNgHiKEyA2aMgFllR","level":0,"time":"2019-12-04T06:45:02+00:00","remoteAddr":"","user":"--","app":"cron","method":"--","url":"--","message":"Job with id 3 and class OCA\\Files_Trashbin\\BackgroundJob\\ExpireTrash not running due to interval. Last run 1575440102 and interval 1800. Wait 0 seconds."}
{"reqId":"7PNCNgHiKEyA2aMgFllR","level":0,"time":"2019-12-04T06:45:02+00:00","remoteAddr":"","user":"--","app":"cron","method":"--","url":"--","message":"Job with id 4 and class OCA\\Files_Versions\\BackgroundJob\\ExpireVersions not running due to interval. Last run 1575440102 and interval 1800. Wait 0 seconds."}
{"reqId":"7PNCNgHiKEyA2aMgFllR","level":0,"time":"2019-12-04T06:45:02+00:00","remoteAddr":"","user":"--","app":"cron","method":"--","url":"--","message":"Running job with id 6 and class OCA\\Files\\BackgroundJob\\ScanFiles. Last run 1575441002 and interval 600"}
{"reqId":"7PNCNgHiKEyA2aMgFllR","level":0,"time":"2019-12-04T06:45:02+00:00","remoteAddr":"","user":"--","app":"cron","method":"--","url":"--","message":"Started background job of class : OCA\\Files\\BackgroundJob\\ScanFiles with arguments : "}
{"reqId":"7PNCNgHiKEyA2aMgFllR","level":0,"time":"2019-12-04T06:45:02+00:00","remoteAddr":"","user":"--","app":"cron","method":"--","url":"--","message":"Finished background job, the job took : 0 seconds, this job is an instance of class : OCA\\Files\\BackgroundJob\\ScanFiles with arguments : "}
{"reqId":"7PNCNgHiKEyA2aMgFllR","level":0,"time":"2019-12-04T06:45:02+00:00","remoteAddr":"","user":"--","app":"cron","method":"--","url":"--","message":"Job with id 7 and class OCA\\Files\\BackgroundJob\\DeleteOrphanedItems not running due to interval. Last run 1575440103 and interval 3600. Wait 1801 seconds."}
{"reqId":"7PNCNgHiKEyA2aMgFllR","level":0,"time":"2019-12-04T06:45:02+00:00","remoteAddr":"","user":"--","app":"cron","method":"--","url":"--","message":"Running job with id 8 and class OCA\\Files\\BackgroundJob\\CleanupFileLocks. Last run 1575441002 and interval 300"}
{"reqId":"7PNCNgHiKEyA2aMgFllR","level":0,"time":"2019-12-04T06:45:02+00:00","remoteAddr":"","user":"--","app":"cron","method":"--","url":"--","message":"Started background job of class : OCA\\Files\\BackgroundJob\\CleanupFileLocks with arguments : "}
{"reqId":"7PNCNgHiKEyA2aMgFllR","level":0,"time":"2019-12-04T06:45:02+00:00","remoteAddr":"","user":"--","app":"cron","method":"--","url":"--","message":"Finished background job, the job took : 0 seconds, this job is an instance of class : OCA\\Files\\BackgroundJob\\CleanupFileLocks with arguments : "}
{"reqId":"7PNCNgHiKEyA2aMgFllR","level":0,"time":"2019-12-04T06:45:02+00:00","remoteAddr":"","user":"--","app":"cron","method":"--","url":"--","message":"Job with id 10 and class OCA\\Federation\\SyncJob not running due to interval. Last run 1575429302 and interval 86400. Wait 73800 seconds."}
{"reqId":"7PNCNgHiKEyA2aMgFllR","level":0,"time":"2019-12-04T06:45:02+00:00","remoteAddr":"","user":"--","app":"cron","method":"--","url":"--","message":"Job with id 11 and class OCA\\DAV\\CardDAV\\SyncJob not running due to interval. Last run 1575429302 and interval 86400. Wait 73800 seconds."}
{"reqId":"7PNCNgHiKEyA2aMgFllR","level":0,"time":"2019-12-04T06:45:02+00:00","remoteAddr":"","user":"--","app":"cron","method":"--","url":"--","message":"Started background job of class : OC\\Authentication\\Token\\DefaultTokenCleanupJob with arguments : "}
{"reqId":"7PNCNgHiKEyA2aMgFllR","level":0,"time":"2019-12-04T06:45:02+00:00","remoteAddr":"","user":"--","app":"OC\\Authentication\\Token\\DefaultTokenProvider::invalidateOldTokens","method":"--","url":"--","message":"invalidating old tokens"}
{"reqId":"7PNCNgHiKEyA2aMgFllR","level":1,"time":"2019-12-04T06:45:02+00:00","remoteAddr":"","user":"--","app":"cron","method":"--","url":"--","message":"Invalidating tokens older than 2019-12-04T06:25:02+00:00"}
{"reqId":"7PNCNgHiKEyA2aMgFllR","level":0,"time":"2019-12-04T06:45:02+00:00","remoteAddr":"","user":"--","app":"cron","method":"--","url":"--","message":"Finished background job, the job took : 0 seconds, this job is an instance of class : OC\\Authentication\\Token\\DefaultTokenCleanupJob with arguments : "}
{"reqId":"7PNCNgHiKEyA2aMgFllR","level":0,"time":"2019-12-04T06:45:02+00:00","remoteAddr":"","user":"--","app":"cron","method":"--","url":"--","message":"Job with id 13 and class OCA\\UpdateNotification\\Notification\\BackgroundJob not running due to interval. Last run 1575426602 and interval 86400. Wait 71100 seconds."}
{"reqId":"7PNCNgHiKEyA2aMgFllR","level":0,"time":"2019-12-04T06:45:02+00:00","remoteAddr":"","user":"--","app":"cron","method":"--","url":"--","message":"Running job with id 16 and class OCA\\Files_Sharing\\DeleteOrphanedSharesJob. Last run 1575440103 and interval 900"}
{"reqId":"7PNCNgHiKEyA2aMgFllR","level":0,"time":"2019-12-04T06:45:02+00:00","remoteAddr":"","user":"--","app":"cron","method":"--","url":"--","message":"Started background job of class : OCA\\Files_Sharing\\DeleteOrphanedSharesJob with arguments : "}
{"reqId":"7PNCNgHiKEyA2aMgFllR","level":0,"time":"2019-12-04T06:45:02+00:00","remoteAddr":"","user":"--","app":"DeleteOrphanedSharesJob","method":"--","url":"--","message":"0 orphaned share(s) deleted"}
{"reqId":"7PNCNgHiKEyA2aMgFllR","level":0,"time":"2019-12-04T06:45:02+00:00","remoteAddr":"","user":"--","app":"cron","method":"--","url":"--","message":"Finished background job, the job took : 0 seconds, this job is an instance of class : OCA\\Files_Sharing\\DeleteOrphanedSharesJob with arguments : "}
{"reqId":"7PNCNgHiKEyA2aMgFllR","level":0,"time":"2019-12-04T06:45:02+00:00","remoteAddr":"","user":"--","app":"cron","method":"--","url":"--","message":"Job with id 17 and class OCA\\Files_Sharing\\ExpireSharesJob not running due to interval. Last run 1575410402 and interval 86400. Wait 54900 seconds."}
{"reqId":"7PNCNgHiKEyA2aMgFllR","level":0,"time":"2019-12-04T06:45:02+00:00","remoteAddr":"","user":"--","app":"cron","method":"--","url":"--","message":"Running job with id 65 and class OCA\\OwnBackup\\Jobs\\BackupJob. Last run 1575441002 and interval 600"}
{"reqId":"7PNCNgHiKEyA2aMgFllR","level":0,"time":"2019-12-04T06:45:02+00:00","remoteAddr":"","user":"--","app":"cron","method":"--","url":"--","message":"Started background job of class : OCA\\OwnBackup\\Jobs\\BackupJob with arguments : "}
{"reqId":"7PNCNgHiKEyA2aMgFllR","level":0,"time":"2019-12-04T06:45:02+00:00","remoteAddr":"","user":"--","app":"cron","method":"--","url":"--","message":"Finished background job, the job took : 0 seconds, this job is an instance of class : OCA\\OwnBackup\\Jobs\\BackupJob with arguments : "}
{"reqId":"7PNCNgHiKEyA2aMgFllR","level":0,"time":"2019-12-04T06:45:02+00:00","remoteAddr":"","user":"--","app":"cron","method":"--","url":"--","message":"Job with id 95 and class OCA\\Market\\CheckUpdateBackgroundJob not running due to interval. Last run 1575404103 and interval 86400. Wait 48601 seconds."}
{"reqId":"7PNCNgHiKEyA2aMgFllR","level":0,"time":"2019-12-04T06:45:02+00:00","remoteAddr":"","user":"--","app":"cron","method":"--","url":"--","message":"Running job with id 128 and class OCA\\UpdateNotification\\ResetTokenBackgroundJob. Last run 1575441002 and interval 600"}
{"reqId":"7PNCNgHiKEyA2aMgFllR","level":0,"time":"2019-12-04T06:45:02+00:00","remoteAddr":"","user":"--","app":"cron","method":"--","url":"--","message":"Started background job of class : OCA\\UpdateNotification\\ResetTokenBackgroundJob with arguments : "}
{"reqId":"7PNCNgHiKEyA2aMgFllR","level":0,"time":"2019-12-04T06:45:02+00:00","remoteAddr":"","user":"--","app":"cron","method":"--","url":"--","message":"Finished background job, the job took : 0 seconds, this job is an instance of class : OCA\\UpdateNotification\\ResetTokenBackgroundJob with arguments : "}
{"reqId":"7PNCNgHiKEyA2aMgFllR","level":0,"time":"2019-12-04T06:45:02+00:00","remoteAddr":"","user":"--","app":"cron","method":"--","url":"--","message":"Job with id 129 and class OCA\\DAV\\BackgroundJob\\CleanProperties not running due to interval. Last run 1575391502 and interval 86400. Wait 36000 seconds."}
9

Have you tried deleting and recreating the folder? Or is this not possible?

Can you run a filescan on the path like so:

occ files:scan --path="SSG/files/SSG_Wettkampfberichte/RWK 19-20"

And if that still doesn’t work can you run the following select statement in your database and paste the output here:

SELECT * FROM oc_filecache WHERE path LIKE "%SSG_Wettkampfberichte/RWK 19-20%"
1 Like
10

files:scan has no effect. But they find 17 files, in the folder on windows and webgui are only 16 files.
±--------±------±-------------±-----------------+
| Folders | Files | Elapsed time | Items per second |
±--------±------±-------------±-----------------+
| 1 | 17 | 00:00:00 | 94 |
±--------±------±-------------±-----------------+

Deleting the folder is possible. But I want to wait if I can test some other things before to find the bug.

**SQL result**
+--------+---------+----------------------------------------------------------------------------------------------------+----------------------------------+--------+-----------------------------------------------------+----------+----------+---------+------------+---------------+-----------+------------------+----------------------------------+-------------+----------+
| fileid | storage | path                                                                                               | path_hash                        | parent | name                                                | mimetype | mimepart | size    | mtime      | storage_mtime | encrypted | unencrypted_size | etag                             | permissions | checksum |
+--------+---------+----------------------------------------------------------------------------------------------------+----------------------------------+--------+-----------------------------------------------------+----------+----------+---------+------------+---------------+-----------+------------------+----------------------------------+-------------+----------+
| 157537 |      17 | files/SSG_Wettkampfberichte/RWK 19-20                                                              | 26c8741b0a6cfd9a6aaeb694e9219964 | 109764 | RWK 19-20                                           |        2 |        1 | 1932270 | 1575451543 |    1575396346 |         0 |                0 | 5de77b97ddba9                    |          31 |          |
| 157538 |      17 | files/SSG_Wettkampfberichte/RWK 19-20/Bezirksklasse 3 , 1 - 2021.pdf                               | bb29d7eb86110b20e1e252fc27e25886 | 157537 | Bezirksklasse 3 , 1 - 2021.pdf                      |        4 |        3 |  123529 | 1568918531 |    1568918531 |         0 |                0 | 1543a600b19a161a5afaff1b0dc37874 |          27 |          |
| 157931 |      17 | files/SSG_Wettkampfberichte/RWK 19-20/Luftpistolenklasse 3 , 1 - 3297.pdf                          | bf26c90246d865f30f47fc2ada203fb6 | 157537 | Luftpistolenklasse 3 , 1 - 3297.pdf                 |        4 |        3 |  131960 | 1569264669 |    1569264669 |         0 |                0 | d8488c00d6c6064db9855e4b9355cff6 |          27 |          |
| 157932 |      17 | files/SSG_Wettkampfberichte/RWK 19-20/Altersklasse aufgelegt 11 , 1 - 3207.pdf                     | b10ae6cf6a4fc7f18d81bd54757b585d | 157537 | Altersklasse aufgelegt 11 , 1 - 3207.pdf            |        4 |        3 |  130790 | 1569523598 |    1569523598 |         0 |                0 | db07d7986d3dd134413eb7a65620b41f |          27 |          |
| 158808 |      17 | files/SSG_Wettkampfberichte/RWK 19-20/Altersklasse aufgelegt 3 , 2 - 2763.pdf                      | 1c7ab19b864a539c22de08e1b07297de | 157537 | Altersklasse aufgelegt 3 , 2 - 2763.pdf             |        4 |        3 |  127809 | 1570734051 |    1570734051 |         0 |                0 | 4bee0b4dbb4f5a35bbe1f390cdfe8bce |          27 |          |
| 158809 |      17 | files/SSG_Wettkampfberichte/RWK 19-20/Bereichsklasse 0 , 2 - 1859.xlsx                             | 8e3ccc7d467bfd6babe94d07e8ca586c | 157537 | Bereichsklasse 0 , 2 - 1859.xlsx                    |       29 |        3 |    2695 | 1570743300 |    1570743300 |         0 |                0 | aca815568e2a3a286f885bebea8e1dc5 |          27 |          |
| 158889 |      17 | files/SSG_Wettkampfberichte/RWK 19-20/Altersklasse aufgelegt 10 , 3 - 3158.pdf                     | 547b2fec7e6abf45824a34faeda8305d | 157537 | Altersklasse aufgelegt 10 , 3 - 3158.pdf            |        4 |        3 |  130160 | 1571339496 |    1571339496 |         0 |                0 | 8d79ee6427281746405565a6187a35cb |          27 |          |
| 158890 |      17 | files/SSG_Wettkampfberichte/RWK 19-20/Bezirksklasse 3 , 3 - 2030.pdf                               | be5a2f8be6cfd6d79f537eaf8215a82d | 157537 | Bezirksklasse 3 , 3 - 2030.pdf                      |        4 |        3 |  122533 | 1571339453 |    1571339453 |         0 |                0 | e35b08236fb4cf51a2886ff3fb4241e9 |          27 |          |
| 158891 |      17 | files/SSG_Wettkampfberichte/RWK 19-20/Altersklasse aufgelegt 11 , 3 - 3212.pdf                     | 63eaed3234792ea782c08926ca56dca6 | 157537 | Altersklasse aufgelegt 11 , 3 - 3212.pdf            |        4 |        3 |  144137 | 1571335216 |    1571335216 |         0 |                0 | 6c036d12df4046e86f197978644022c2 |          27 |          |
| 158892 |      17 | files/SSG_Wettkampfberichte/RWK 19-20/Luftpistolenklasse 3 , 3 - 3302.pdf                          | 1ed1a89ebf96dd1f33d5205bd568a7e8 | 157537 | Luftpistolenklasse 3 , 3 - 3302.pdf                 |        4 |        3 |  130760 | 1571682561 |    1571682561 |         0 |                0 | 4976d0959118b33817b0a0248e0ae2e6 |          27 |          |
| 158967 |      17 | files/SSG_Wettkampfberichte/RWK 19-20/Altersklasse aufgelegt 11 , 4 - 3215.pdf                     | 39b71a77da7bebc2d43797494fe228e4 | 157537 | Altersklasse aufgelegt 11 , 4 - 3215.pdf            |        4 |        3 |  130769 | 1572547458 |    1572547458 |         0 |                0 | 73ddc47d219826353ea0d295082d955e |          27 |          |
| 158968 |      17 | files/SSG_Wettkampfberichte/RWK 19-20/Altersklasse aufgelegt 3 , 4 - 2772.pdf                      | 5a258bb88286dade48b6f8f79fa13758 | 157537 | Altersklasse aufgelegt 3 , 4 - 2772.pdf             |        4 |        3 |  136603 | 1572559652 |    1572559652 |         0 |                0 | 9b7fac72a793893c72679c1164a31593 |          27 |          |
| 158969 |      17 | files/SSG_Wettkampfberichte/RWK 19-20/Schülerklasse aufgelegt 1 , 4 - 1012.pdf                     | af89c8f9b557a9e717dd87e665d9cbd3 | 157537 | Schülerklasse aufgelegt 1 , 4 - 1012.pdf            |        4 |        3 |  118335 | 1572464573 |    1572464573 |         0 |                0 | 511dd68276351fe769b6533d5f249e4f |          27 |          |
| 162246 |      17 | files/SSG_Wettkampfberichte/RWK 19-20/Bereichsklasse 0 , 5 - 1870.pdf                              | d723ccd4d7018969d68b1c21ec941477 | 157537 | Bereichsklasse 0 , 5 - 1870.pdf                     |        4 |        3 |  130050 | 1573762408 |    1573762408 |         0 |                0 | 683f0be2660fe5cb580e3fea5dcfe2d9 |          27 |          |
| 162478 |      17 | files/SSG_Wettkampfberichte/RWK 19-20/Altersklasse aufgelegt 3 , 5 - 2774.pdf                      | 609a7a2f44e44537a4eca7a976d96456 | 157537 | Altersklasse aufgelegt 3 , 5 - 2774.pdf             |        4 |        3 |  131815 | 1574964933 |    1574964933 |         0 |                0 | f8d378c306c910db9794f27d63cadb9a |          27 |          |
| 162481 |      17 | files_versions/SSG_Wettkampfberichte/RWK 19-20                                                     | dbbe37c430ab8a03820e633fa97f6b88 | 109771 | RWK 19-20                                           |        2 |        1 |  251105 | 1575396910 |    1575396910 |         0 |                0 | 5de6a62e0afed                    |          31 |          |
| 162484 |      17 | files/SSG_Wettkampfberichte/RWK 19-20/Altersklasse aufgelegt 3 , 5 - 2774__.pdf                    | 2ca33c2df848e13de73b351c13eae436 | 157537 | Altersklasse aufgelegt 3 , 5 - 2774__.pdf           |        4 |        3 |  131815 | 1574964933 |    1574964933 |         0 |                0 | 5a665282cbc5927221a2d4be8e24f0c1 |          27 |          |
| 162485 |      17 | files/SSG_Wettkampfberichte/RWK 19-20/Bezirksklasse 3 , 6 - 2042.pdf                               | a48b391754100917316b8f36ed9fea1c | 157537 | Bezirksklasse 3 , 6 - 2042.pdf                      |        4 |        3 |  119829 | 1574970538 |    1574970538 |         0 |                0 | f9a23c82b6eefb105c34c306b7c422cb |          27 |          |
| 162486 |      17 | files_versions/SSG_Wettkampfberichte/RWK 19-20/Bezirksklasse 3 , 6 - 2042.pdf.v1574970135          | eaaf1f9b73843984d2444fcb82d1d376 | 162481 | Bezirksklasse 3 , 6 - 2042.pdf.v1574970135          |       10 |        3 |  119290 | 1574970542 |    1574970542 |         0 |                0 | a5a06e8983063f3242f866f8aafe61e8 |          27 |          |
| 162487 |      17 | files/SSG_Wettkampfberichte/RWK 19-20/Altersklasse aufgelegt 10 , 6 - 3170.pdf                     | 01fc3af42c9b7f22d2120522bf825b58 | 157537 | Altersklasse aufgelegt 10 , 6 - 3170.pdf            |        4 |        3 |  120496 | 1574972663 |    1574972663 |         0 |                0 | c55adcce268d7b6fad494b4c859a2290 |          27 |          |
| 162489 |      17 | files_versions/SSG_Wettkampfberichte/RWK 19-20/Altersklasse aufgelegt 3 , 5 - 2774.pdf.v1575010054 | 6a1cd93e46cac1e02f188e9cc636bd4f | 162481 | Altersklasse aufgelegt 3 , 5 - 2774.pdf.v1575010054 |       10 |        3 |  131815 | 1575396346 |    1575396346 |         0 |                0 | 0341abdbbbb32329d9bf34c6762b3d3e |          27 |          |
+--------+---------+----------------------------------------------------------------------------------------------------+----------------------------------+--------+-----------------------------------------------------+----------+----------+---------+------------+---------------+-----------+------------------+----------------------------------+-------------+----------+
11

What’s showing with an ls -al in that path on the server? That should show all 17 files.

If you look in the SQL output, you see that there are 17 files as well, and I can see the file in question:


| 162478 |      17 | files/SSG_Wettkampfberichte/RWK 19-20/Altersklasse aufgelegt 3 , 5 - 2774.pdf                      | 609a7a2f44e44537a4eca7a976d96456 | 157537 | Altersklasse aufgelegt 3 , 5 - 2774.pdf             |        4 |        3 |  131815 | 1574964933 |    1574964933 |         0 |                0 | f8d378c306c910db9794f27d63cadb9a |          27 |          |

Another thing you can try is a file scan on the files_versions path:

occ files:scan --path="SSG/files_versions/SSG_Wettkampfberichte/RWK 19-20"

If that doesn’t help you could try removing the versions, and then rerunning the file scan.

You could also try temporarily disabling the “files_versions”.

1 Like
12

yes, ls -al is showing all files.
I have also try to scan the versions folder. No difference.
I have to delete the file in the versions folder. then rescan it. No difference.
Also disable the files_versions function has not helped.

At least I have saved the server folder with all files, deleted the windows folder from the client and then copied the backuped folder to the windows machine.
Now all files are correctly shown on windows client and webgui.

So, for this file the problem is resolved. But the bug is not found :frowning:

So I keep logging with level 0 and if I get the problem a second time I post the logs and we can start a new analysis.

thanks for your efforts,
Frank

1 Like
13

Thanks for all your effort to try to reproduce this issue.

Make sure that you have logrotation in place, because the logs could get a little bigger now (depending on how many active users you have).

1 Like