tower move: error: move, 391: No such file or directory (2): lstat:

[mgutt]

Ich habe in meinen syslog festgestellt, dass der Mover um ca 6 Uhr versucht Dateien vom Cache ins Array zu verschieben, die dann aber nicht mehr da sind:

 

Jun 19 06:01:30 thoth emhttpd: spinning down /dev/sdh
Jun 19 06:08:01 thoth move: error: move, 391: No such file or directory (2): lstat: /mnt/cache/Backups/Shares/appdata/20220619_044035/plex/Library/Application Support/Plex Media Server/Metadata/Albums/6/...
Jun 19 06:08:01 thoth move: error: move, 391: No such file or directory (2): lstat: /mnt/cache/Backups/Shares/appdata/20220619_044035/plex/Library/Application Support/Plex Media Server/Metadata/Albums/6/...
Jun 19 06:08:01 thoth move: error: move, 391: No such file or directory (2): lstat: /mnt/cache/Backups/Shares/appdata/20220619_044035/plex/Library/Application Support/Plex Media Server/Metadata/Albums/6/...
...
Jun 19 06:16:26 thoth move: error: move, 391: No such file or directory (2): lstat: /mnt/cache/Backups/Shares/appdata/20220619_044035/plex/Library/Application Support/Plex Media Server/Plug-in Support/Databases/com.plexapp.plugins.library.db
Jun 19 06:16:26 thoth move: error: move, 391: No such file or directory (2): lstat: /mnt/cache/Backups/Shares/appdata/20220619_044035/20220619_044035.log
Jun 19 06:27:08 thoth crond[1842]: exit status 1 from user root /usr/local/sbin/mover &> /dev/null

 

Das kann eigentlich nur passieren, wenn die Dateien verschoben/gelöscht wurden, während der Mover angefangen hat zu arbeiten.

 

Allerdings habe ich das nicht verschoben und ich wüsste auch nicht, dass ich noch irgendwas laufen habe, dass das macht?!

 

Das Backup "20220619" wurde zB um 05:49 Uhr fertig:

Script Starting Jun 19, 2022  04:40.04
...
Create incremental backup from /mnt/user/appdata to /mnt/user/Backups/Shares/appdata/20220619_044035 by using last backup /mnt/user/Backups/Shares/appdata/20220618_044032/
...
Script Finished Jun 19, 2022  05:49.41

 

Komisch ist auch, dass die Datei "com.plexapp.plugins.library.db" im Ziel vorhanden ist, sie wurde also verschoben und das Dateidatum passt

ebenfalls:

 

Es wirkt so als würde der Mover quasi 2x gleichzeitig laufen. Oder habt ihr noch eine Idee?

 

 

[mgutt]

Ok, das war jetzt auch komisch. Ich habe zuerst auf dem Cache geschaut und da waren noch Dateien in /mnt/cache/Backups:

 

Dann dachte ich mir, dass ich die Mover Logs mal aktiviere und den Mover manuell starte. Der Ordner "Backups" ist nun vom Cache verschwunden, aber geloggt hat er das Verschieben dieses Ordners nicht. Nur einen anderen:

 

🤨

[mgutt]

Ok, war doch ich selbst. Ich habe wegen einem Bug vom Mover einen Workaround mit rsync als Skript hinterlegt gehabt und der lief parallel zum Mover:

 

Das habe ich jetzt so abgeändert, dass ich hoffentlich den eigentlichen Bug gelöst habe.