UNSOLVED Adapter Backitup kopiert nicht auf FTP
-
Hi, ich weiß nicht, ob ich mich zu blöd anstelle, aber der Adapter kopiert keine Files auf entfernte Ziele. Habe es mit FTP und Dropbox versucht. Beide sind korrekt konfiguriert und auch erreichbar. Das Logging habe ich auf "silly" gestellt, aber es kommen auch keine Fehler. Er startet, legt das lokale Backup an und sagt denn "done".
Offenbar versucht er gar nicht, die Files auf eine konfigurierte remote-location zu kopieren:
Version: 1.4.5 und 1.5.4 (Github)
backitup.0 2020-04-30 14:32:18.308 silly (12611) States system redis pmessage io.messagebox.system.adapter.backitup.0/io.messagebox.system.adapter.backitup.0:{"command":"getTelegramUser","message":null,"from":"system.adapter.admin.0","callb backitup.0 2020-04-30 14:32:17.909 debug (12611) system.adapter.admin.0: logging true backitup.0 2020-04-30 14:32:17.906 silly (12611) States system redis pmessage logging/system.adapter.admin.0.logging:{"val":true,"ack":true,"ts":1588249937880,"q":0,"from":"system.adapter.admin.0","lc":1588249937880} backitup.0 2020-04-30 14:31:58.030 debug (12611) [iobroker/iobroker] done backitup.0 2020-04-30 14:31:56.995 debug (12611) [iobroker/iobroker] Backup created: /opt/iobroker/backups/iobroker_2020_04_30-14_31_53_-raspi4_backupiobroker.tar.gz backitup.0 2020-04-30 14:31:55.169 debug (12611) [iobroker/iobroker] host.raspi4 1162 objects saved backitup.0 2020-04-30 14:31:55.135 debug (12611) [iobroker/iobroker] host.raspi4 2245 states saved backitup.0 2020-04-30 14:31:53.181 silly (12611) States user redis pmessage backitup.0.oneClick.*/backitup.0.oneClick.iobroker:{"val":true,"ack":false,"ts":1588249913176,"q":0,"from":"system.adapter.admin.0","user":"system.user.tep","lc":15 backitup.0 2020-04-30 14:31:18.535 silly (12611) States system redis pmessage io.messagebox.system.adapter.backitup.0/io.messagebox.system.adapter.backitup.0:{"command":"getTelegramUser","message":null,"from":"system.adapter.admin.0","callb backitup.0 2020-04-30 14:31:15.334 debug (12611) No backup file was found backitup.0 2020-04-30 14:31:13.324 debug (12611) FTP connected. backitup.0 2020-04-30 14:31:12.878 silly (12611) States system redis pmessage system.adapter.backitup.0.logLevel/system.adapter.backitup.0.logLevel:{"val":"silly","ack":true,"ts":1588249872736,"q":0,"from":"system.adapter.backitup.0","lc":1 backitup.0 2020-04-30 14:31:12.747 info (12611) [iobroker] backup was activated at 02:15 every 1 day(s) backitup.0 2020-04-30 14:31:12.681 info (12611) starting. Version 1.5.4 in /opt/iobroker/node_modules/iobroker.backitup, node: v12.15.0 backitup.0 2020-04-30 14:31:11.953 debug (12611) statesDB connected backitup.0 2020-04-30 14:31:11.952 debug (12611) States connected to redis: 127.0.0.1:9000 backitup.0 2020-04-30 14:31:11.945 debug (12611) States create System PubSub Client backitup.0 2020-04-30 14:31:11.942 debug (12611) States create User PubSub Client backitup.0 2020-04-30 14:31:11.936 debug (12611) Redis States: Use Redis connection: 127.0.0.1:9000 backitup.0 2020-04-30 14:31:11.934 debug (12611) objectDB connected backitup.0 2020-04-30 14:31:11.928 debug (12611) Objects connected to redis: 127.0.0.1:9001 backitup.0 2020-04-30 14:31:11.918 debug (12611) Objects client initialize lua scripts backitup.0 2020-04-30 14:31:11.917 debug (12611) Objects create User PubSub Client backitup.0 2020-04-30 14:31:11.915 debug (12611) Objects create System PubSub Client backitup.0 2020-04-30 14:31:11.912 debug (12611) Objects client ready ... initialize now backitup.0 2020-04-30 14:31:11.882 debug (12611) Redis Objects: Use Redis connection: 127.0.0.1:9001MOD-Edit: Code in code-tags gesetzt!
Ist das Problem bekannt? Was mach ich falsch?
Danke und Gruß,
Andreas0
-
Wo wurde ein Backup angelegt ?
Laut dem Log lese ich folgendes:
Das backup wurde für 1x pro Tag um nachts 02:15 konfiguriert.
backitup.0 2020-04-30 14:31:12.747 info (12611) [iobroker] backup was activated at 02:15 every 1 day(s)FTP wurde korrekt connected aber es ist kein backup vorhanden:
backitup.0 2020-04-30 14:31:15.334 debug (12611) No backup file was found backitup.0 2020-04-30 14:31:13.324 debug (12611) FTP connected.Also, wird auch nichts nach ftp kopiert
-
@TepMan
Wo liegt das Backup?
Hast du mal den Installer Fix laufen lassen?
Wenn dein FTP erreichbar ist, können es entweder ein Rechteproblem lokal im Backup Ordner sein oder dein FTP User hat keine Schreibrechte auf dem NAS -
Das Backup liegt unter /opt/ioBroker/backups, das lokale erzeugen des Backups klappt. FTP ist erreichbar, auch für den konfigurierten User. Das ist genau der Punkt, der mich wundert, es liegt lokal ein frisches Backup im richtigen Ordner, aber der Adapter findet es offenbar nicht, wenn er es direkt nach der Erstellung per FTP kopieren.
Allerdings habe ich es jetzt hinbekommen, die Backups per NFS auf das NAS zu packen. Trotzdem würde mich interessieren, warum FTP nicht läuft.
-
@TepMan
Wie gesagt, lasse mal den Installer Fix laufen und poste mal den Log des Backups im Debugmodus von der Backitup Instanz.
Sonst ist es nur in die Glaskugel schauen -
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) -
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 -
@TepMan
Hast du die Schreibrechte des FTP Users geprüft? -
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.
-
@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. -
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.
-
@TepMan
Mach mal einen Neustart und setze über iobroker fix die Rechte neu -
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.
-
@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. -
@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.
-
@TepMan Jo, weil Punkte, Schrägstriche usw. auch z. B. schon in Dateipfaden verwendet werden. Und in Regulären Ausdrücken. Das scheppert dann.
-
@Thomas-Braun Allerdings nur, wenn es nicht in Anführungszeichen steht
-
@simatec Stimmt. Aber wer weiß ob das immer so abgefangen wird. Ich würde es direkt ganz lassen.
-
@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.
-
@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.
