UNSOLVED Adapter Backitup kopiert nicht auf FTP

[TepMan]

Hi habe den Fixer laufen lassen, per FTP immer noch kein Backup, obwohl er die Backup-Files im remoten Verzeichnis sogar sieht:

backitup.0	2020-05-06 12:29:17.870	debug	(3190) [iobroker/iobroker] done
backitup.0	2020-05-06 12:29:16.822	debug	(3190) [iobroker/iobroker] Backup created: /opt/iobroker/backups/iobroker_2020_05_06-12_29_12_-raspi4_backupiobroker.tar.gz
backitup.0	2020-05-06 12:29:14.872	debug	(3190) [iobroker/iobroker] host.raspi4 2154 objects saved
backitup.0	2020-05-06 12:29:14.850	debug	(3190) [iobroker/iobroker] host.raspi4 3190 states saved
backitup.0	2020-05-06 12:29:07.340	debug	(3190) detect last backup file: iobroker_2020_05_06-05_00_20_-raspi4_backupiobroker.tar.gz
backitup.0	2020-05-06 12:29:07.338	debug	(3190) detect backup file 4: iobroker_2020_05_06-05_00_20_-raspi4_backupiobroker.tar.gz
backitup.0	2020-05-06 12:29:07.337	debug	(3190) detect backup file 3: iobroker_2020_05_05-05_00_20_-raspi4_backupiobroker.tar.gz
backitup.0	2020-05-06 12:29:07.335	debug	(3190) detect backup file 2: iobroker_2020_05_04-16_15_54_-raspi4_backupiobroker.tar.gz
backitup.0	2020-05-06 12:29:07.334	debug	(3190) detect backup file 1: iobroker_2020_04_30-15_13_33_-raspi4_backupiobroker.tar.gz
backitup.0	2020-05-06 12:29:05.309	debug	(3190) FTP connected.
backitup.0	2020-05-06 12:29:04.806	info	(3190) [iobroker] backup was activated at 05:00 every 1 day(s)
backitup.0	2020-05-06 12:29:04.771	debug	(3190) Schedule restart: * */12 * * *
backitup.0	2020-05-06 12:29:04.731	info	(3190) starting. Version 1.5.4 in /opt/iobroker/node_modules/iobroker.backitup, node: v12.15.0
host.raspi4	2020-05-06 12:29:01.622	info	instance system.adapter.backitup.0 started with pid 3190
host.raspi4	2020-05-06 12:29:00.570	info	Restart adapter system.adapter.backitup.0 because enabled
host.raspi4	2020-05-06 12:29:00.569	error	instance system.adapter.backitup.0 terminated with code 156 (156)
backitup.0	2020-05-06 12:29:00.024	info	(3161) Terminated (START_IMMEDIATELY_AFTER_STOP): Without reason
backitup.0	2020-05-06 12:29:00.021	info	(3161) terminating
backitup.0	2020-05-06 12:29:00.014	info	(3161) cleaned everything up...
backitup.0	2020-05-06 12:29:00.012	info	(3161) Scheduled restart.

Weiterhin kommt jetzt ca. jede Minute folgender Fehler:

host.raspi4	2020-05-06 12:36:00.620	error	instance system.adapter.backitup.0 terminated with code 156 (156)

[TepMan]

Und hier noch die Debug-Ausgeb (hatte ich vorhin vergessen):

Gestartet...
[DEBUG] [iobroker] start with {"type":"creator","time":"05:00","debugging":true,"everyXDays":"1","nameSuffix":"-raspi4","deleteBackupAfter":0,"ftp":{"enabled":true,"type":"storage","source":"ftp","host":"192.168.178.20","debugging":true,"deleteOldBackup":true,"ownDir":false,"dir":"/ioBrokerBackups","dirMinimal":"/backupDir/iobroker","user":"ioBroker","pass":"****","port":"21"},"dir":"/opt/iobroker","redis":{"enabled":false,"type":"creator","ftp":{"enabled":true,"type":"storage","source":"ftp","host":"192.168.178.20","debugging":true,"deleteOldBackup":true,"ownDir":false,"dir":"/ioBrokerBackups","dirMinimal":"/backupDir/iobroker","user":"ioBroker","pass":"****","port":"21"},"cifs":{"enabled":false,"mountType":"NFS","type":"storage","source":"ftp","mount":"192.168.178.20","debugging":true,"fileDir":"/opt/iobroker/node_modules/iobroker.backitup","wakeOnLAN":false,"macAd":"20:DE:20:DE:20:DE","wolTime":"25","smb":"vers=1.0","sudo":true,"cifsDomain":"","deleteOldBackup":true,"ownDir":false,"dir":"/volume1/ioBroker/ioBrokerBackups","dirMinimal":"sharename/backupDir","user":"ioBroker","pass":"****"},"dropbox":{"enabled":false,"type":"storage","source":"ftp","debugging":true,"deleteOldBackup":false,"accessToken":"****","ownDir":false,"dir":"/","dirMinimal":"/backupDir/iobroker"},"googledrive":{"enabled":false,"type":"storage","source":"ftp","debugging":true,"deleteOldBackup":false,"accessJson":"****","ownDir":false,"dir":"/backupDir","dirMinimal":"/backupDir/iobroker"},"path":"/var/lib/redis"},"historyDB":{"enabled":false,"type":"creator","ftp":{"enabled":true,"type":"storage","source":"ftp","host":"192.168.178.20","debugging":true,"deleteOldBackup":true,"ownDir":false,"dir":"/ioBrokerBackups","dirMinimal":"/backupDir/iobroker","user":"ioBroker","pass":"****","port":"21"},"cifs":{"enabled":false,"mountType":"NFS","type":"storage","source":"ftp","mount":"192.168.178.20","debugging":true,"fileDir":"/opt/iobroker/node_modules/iobroker.backitup","wakeOnLAN":false,"macAd":"20:DE:20:DE:20:DE","wolTime":"25","smb":"vers=1.0","sudo":true,"cifsDomain":"","deleteOldBackup":true,"ownDir":false,"dir":"/volume1/ioBroker/ioBrokerBackups","dirMinimal":"sharename/backupDir","user":"ioBroker","pass":"****"},"dropbox":{"enabled":false,"type":"storage","source":"ftp","debugging":true,"deleteOldBackup":false,"accessToken":"****","ownDir":false,"dir":"/","dirMinimal":"/backupDir/iobroker"},"googledrive":{"enabled":false,"type":"storage","source":"ftp","debugging":true,"deleteOldBackup":false,"accessJson":"****","ownDir":false,"dir":"/backupDir","dirMinimal":"/backupDir/iobroker"},"path":"media/usb/history"},"zigbee":{"enabled":true,"type":"creator","ftp":{"enabled":true,"type":"storage","source":"ftp","host":"192.168.178.20","debugging":true,"deleteOldBackup":true,"ownDir":false,"dir":"/ioBrokerBackups","dirMinimal":"/backupDir/iobroker","user":"ioBroker","pass":"****","port":"21"},"cifs":{"enabled":false,"mountType":"NFS","type":"storage","source":"ftp","mount":"192.168.178.20","debugging":true,"fileDir":"/opt/iobroker/node_modules/iobroker.backitup","wakeOnLAN":false,"macAd":"20:DE:20:DE:20:DE","wolTime":"25","smb":"vers=1.0","sudo":true,"cifsDomain":"","deleteOldBackup":true,"ownDir":false,"dir":"/volume1/ioBroker/ioBrokerBackups","dirMinimal":"sharename/backupDir","user":"ioBroker","pass":"****"},"dropbox":{"enabled":false,"type":"storage","source":"ftp","debugging":true,"deleteOldBackup":false,"accessToken":"****","ownDir":false,"dir":"/","dirMinimal":"/backupDir/iobroker"},"googledrive":{"enabled":false,"type":"storage","source":"ftp","debugging":true,"deleteOldBackup":false,"accessJson":"****","ownDir":false,"dir":"/backupDir","dirMinimal":"/backupDir/iobroker"},"path":"/opt/iobroker/iobroker-data"},"history":{"enabled":true,"type":"message","entriesNumber":"25","systemLang":"de"},"email":{"enabled":false,"notificationsType":"E-Mail","type":"message","instance":"","NoticeType":"longEmailNotice","emailReceiver":"xxx@xxx.com","emailSender":"xxx@xxx.com","onlyError":false,"emailWaiting":0,"systemLang":"de"},"pushover":{"enabled":false,"notificationsType":"E-Mail","type":"message","instance":"","SilentNotice":false,"NoticeType":"longPushoverNotice","deviceID":"","onlyError":false,"pushoverWaiting":0,"systemLang":"de"},"backupDir":"/opt/iobroker/backups"}
[DEBUG] [iobroker] - host.raspi4 3181 states saved

[DEBUG] [iobroker] - host.raspi4 2154 objects saved

[DEBUG] [iobroker] - Backup created: /opt/iobroker/backups/iobroker_2020_05_06-15_21_24_-raspi4_backupiobroker.tar.gz

[DEBUG] [iobroker] - done

[simatec]

@TepMan
Hast du die Schreibrechte des FTP Users geprüft?

[TepMan]

Ja, wie oben geschrieben habe ich mich auch über einen anderen FTP-Client mit dem User angemeldet und Dateien auf dem Verzeichnis gelesen, geschrieben und gelöscht. Das Verzeichnis für die Backups liegt im Home-Ordner des Users, den ich für die Backups nutze. Hatte auf dem NAS auch test-weise Schreibrechte für alle Nutzer auf dem Verzeichnis eingerichtet. Kann daran also eigentlich nicht liegen.

[simatec]

@TepMan
Hat dein Passwort eventuell Sonderzeichen oder ist zu lang?
Da kann ich mich an Problem erinnern.
Wenn du Sonderzeichen drin hast, dann schreibe es in Anführungszeichen.

[TepMan]

Hätte es gern ausprobiert (im Passwort ist ein Sonderzeichen) aber leider terminated die Adapter-Instanz seit letzter Nacht im Minutentakt mit einem Zugriffsfehler auf das Backup-Verzeichnis:

[ERROR] [iobroker] - host.raspi4 Cannot pack directory /opt/iobroker/node_modules/iobroker.js-controller/tmp/backup: Error: EACCES: permission denied, open '/opt/iobroker/backups/iobroker_2020_05_08-18_46_39_-raspi4_backupiobroker.tar.gz'

Kann mir den Fehler gerade nicht erklären, da ich nichts geändert und keine Updates o.ä. eingespielt habe.

[simatec]

@TepMan
Mach mal einen Neustart und setze über iobroker fix die Rechte neu

[TepMan]

Puh, irgendwas geht richtig schief wenn der Adapter läuft, die Berechtigungen auf dem Backup-Verzeichnis waren mehr oder weniger zerstört, user war unknown und group -2 . Das hat selbst der Fixer nicht mehr hinbekommen, offenbar hing auch noch irgendein Prozess auf den Files. Habe es nur wieder hinbekommen, nachdem ich nach einem Neustart alle Backups die da lagen gelöscht habe, dann wieder Neustart, dann die Berechtigungen von Hand restauriert, noch ein Neustart und jetzt passt es wieder, der Fixer sagt jetzt auch wieder alles palletti.

Der Adapter ist jetzt deinstalliert - die eigentliche Idee war, den Adapter einmal neu zu installieren, weil er nach dem ersten Fix-Lauf halt immer noch nicht ging. Allerdings traue ich mich gerade nicht, den Adapter wieder zu installieren... Zumindest nicht, bevor ich das komplette Raspi-Image gesichert habe. Das wird allerdings heute bei mir nichts mehr.

[Thomas Braun]

@TepMan
Keine Ahnung, ob das bei dir so ist, aber irgendwelche Sonderzeichen in username und/oder Passwort kann hier auch zu komischen Ergebnissen führen.
Am besten nur Ziffern und einfache Buchstaben ohne Umlaute oder Sonderzeichen verwenden.

[TepMan]

@Thomas-Braun da ist ein schlichter Punkt im Passwort, spannenderweise funktionierte das per NFS und per FTP nicht (selber User, selbes NAS). Aber ja, ohne Sonderzeichen werde ich dann auch nochmal probieren.

[Thomas Braun]

@TepMan Jo, weil Punkte, Schrägstriche usw. auch z. B. schon in Dateipfaden verwendet werden. Und in Regulären Ausdrücken. Das scheppert dann.

[simatec]

@Thomas-Braun Allerdings nur, wenn es nicht in Anführungszeichen steht

[Thomas Braun]

@simatec Stimmt. Aber wer weiß ob das immer so abgefangen wird. Ich würde es direkt ganz lassen.

[simatec]

@TepMan Sorry, da hat aber backitup nix mit zu tun.
Der Code ist auf Github zu sehen und wie du da siehst, greift backitup weder auf dein System noch setzt backitup irgendwelche Rechte neu.

Da scheint was an deinem System nicht zu passen.

[TepMan]

@simatec Ich glaube auch nicht, das der Adapter selbst was kaputt gemacht hat. Aber der zeitliche Zusammenhang mit dem Einsatz des Adapters ist der einzige Anhaltspunkt, und ansonsten ist mit dem System nix passiert, außer das es halt läuft. Der Raspi macht auch nichts anderes außer ioBroker auszuführen. Vorstellbar wäre, das durch die vielen Versuche, Backups zu erstellen am Dateisystem irgendwas kaputt gegangen ist. Darauf deutet ja auch hin, das ich die Berechtigungen erst wieder flicken konnte, nachdem ich die Files gelöscht und den Raspi neu gestartet hatte. Wahrscheinlich haben sich da ein paar Prozesse verhakt oder sowas.

[simatec]

@TepMan
Installiere mal die aktuelle Version aus dem latest.
Lösche vorher alles von Backitup

Also Instanz, Adapter und ggfs. auch noch den Ordner backitup.0

Kann es auch eventuell sein, dass eine fehlerhafte NFS Verbindung deine Rechte zerhauen hat. Eventuell hatte deine NFS Verbindung nur Leserechte

[TepMan]

@simatec Das hatte ich eh vor, allerdings, wie oben geschrieben, nicht mehr heute. Melde mich, sobald ich das nochmal ausprobiert habe.

Das mit der NFS-Verbindung verstehe ich allerdings nicht, ich greife doch auf ein entferntes Verzeichnis zu, um das Backup dort abzulegen (was im Übrigen ja klappte, also sollten auch Schreibrechte vorhanden gewesen sein), wie sollte das die Berechtigungen vom lokalen Backupverzeichnis beeinflussen können?

[simatec]

@TepMan
Backitup setzt aber den mount auf das Backup Verzeichnis

[TepMan]

So, hat mir ja nun keine Ruhe gelassen, gerade mit der 1.5.6 probiert, FTP klappt nach wie vor nicht, gleiches Verhalten wie vorher, im Log steht FTP connected, das Backup wird lokal in /opt/iobroker/backups abgelegt, aber der Adapter macht offenbar keine Anstalten, das Backup per FTP hochzuladen. Der verwendete User hat auch mittlerweile keine Sonderzeichen mehr im Passwort.

Dafür habe ich die Ursache für das Problem vorhin nachvollziehen können. Auf dem NAS sind die Backup-Files weg, lokal auf dem Raspi liegen sie noch. D.h. der Mountpoint wahr tatsächlich nicht ausgehängt und das der Raspi die Dateiberechtigungen vom NAS nicht lesen kann ist nachvollziehbar. Wahrscheinlich war das die ganze Zeit der Grund für die Zugriffsprobleme. Irgendwann ist der Mountpoint nicht oder nicht richtig ausgehängt worden.

[simatec]

@TepMan
Zeige doch mal deine FTP Einstellungen in backitup und die auf deinem NAS.
Hast du mal das ganze per Konsole probiert?
Meist sind es Probleme mit den Rechten oder falsche Pfade