NEWS
Iobroker.backitup keine geplanten Backups mehr nach Update
-
Läuft!
backitup.0 2018-12-30 11:58:33.004 debug [minimal/history] backitup.0.history.html backitup.0 2018-12-30 11:58:32.957 debug [minimal/clean] done backitup.0 2018-12-30 11:58:32.957 debug [minimal/clean] delete /opt/iobroker/backups/minimal_2018_12_30-11_58_13_Tinker_02_backupiobroker.tar.gz backitup.0 2018-12-30 11:58:32.956 debug [minimal/clean] delete /opt/iobroker/backups/minimal_2018_12_30-11_51_47_Tinker_02_backupiobroker.tar.gz backitup.0 2018-12-30 11:58:32.935 debug [minimal/cifs] done backitup.0 2018-12-30 11:58:32.930 debug [minimal/minimal] done backitup.0 2018-12-30 11:58:32.853 debug [minimal/minimal] Backup created: /opt/iobroker/backups/minimal_2018_12_30-11_58_13_Tinker_02_backupiobroker.tar.gz backitup.0 2018-12-30 11:58:13.880 debug [minimal/mount] doneNach Github Install den Upload nicht vergessen :oops:
-
Sorry für die Konfusion!
NEIN es läuft nicht. Im Gegenteil.
Ich hatte nur im Augenwinkel gesehen, dass sich auf dem anderen Monitor etwas im Verzeichnis vom NAS änderte.
Statt dessen hatte der Task mit dem CRON mein manuelles Backup gelöscht.
Poste mal bitte den log `
Hatte ich vorhin, oder brauchst du etwas anderes?Sorry for any inconvenience
Rainer
-
Aber in der Konstellation haben auch User hier das Problem, dass immer das aktuellste gelöscht wird. `
Da scheinst du recht zu haben.Habe es nochmal getestet.
Auf dem NAS erscheint ganz kurz die Datei und verschwindet sofort wieder.
Habe die Instanz auf silly gestellt in der Hoffnung, das dann mehr zu erkennen ist. Bis auf eine zusätzliche Zeile mit Oneklick kam aber nichts erhellendes.
Gruß
Rainer
-
Ich mache mal heute noch ein Update.
Aber ich vermute es liegt an node 6.
Welche Version lief vor dem Update bei dir?
Wenn ich dich richtig verstanden habe, traten die Probleme ja erst nach deinem Update auf oder?
Gesendet von iPhone mit Tapatalk
-
-
Wenn ich sonst noch was testen kann, sag Bescheid!
> Ahh schade das wäre sehr Interessant gewesen
finde ich das in einem alten Backup? -
Nein im backup sieht man das leider nicht. `
Doch!
"id": "system.adapter.backitup", "value": { "_id": "system.adapter.backitup", "type": "adapter", "common": { "name": "backitup", "version": "0.2.7", "news": { "0.2.7": { "en": "Fix Delete old Files", "de": "Fix Lösche alte Dateien",:D :D :D
-
So Rainer … ich habe mal ein paar Logs mit reingepackt.
Kannst du den Adapter bitte noch einmal löschen (Instanz und Adapter) und dann vom github neu installieren?
Danach sollten im debug Modus einige Infos mehr kommen, was bei dir genau passiert.
-
Da bin ich ja mal gespannt:
backitup.0 2018-12-30 16:47:07.068 info [total] backup was activated at 03:00 every 7 day(s) backitup.0 2018-12-30 16:47:07.068 info [ccu] backup was activated at 01:30 every 3 day(s) backitup.0 2018-12-30 16:47:07.067 info [minimal] backup was activated at 16:48 every 1 day(s) backitup.0 2018-12-30 16:47:06.958 info starting. Version 1.0.0 in /opt/iobroker/node_modules/iobroker.backitup, node: v6.15.1 backitup.0 2018-12-30 16:47:06.785 debug statesDB connected backitup.0 2018-12-30 16:47:06.714 debug objectDB connected backitup.0 2018-12-30 16:47:03.865 info terminating -
Done!
! ````
backitup.0 2018-12-30 16:48:32.497 debug [minimal/history] backitup.0.history.html
backitup.0 2018-12-30 16:48:32.452 debug [minimal/clean] done
backitup.0 2018-12-30 16:48:32.451 debug [minimal/clean] delete /opt/iobroker/backups/minimal_2018_12_30-16_48_13_Tinker_02_backupiobroker.tar.gz
backitup.0 2018-12-30 16:48:32.451 debug [minimal/clean] files to delete: /opt/iobroker/backups/minimal_2018_12_30-16_48_13_Tinker_02_backupiobroker.tar.gz
backitup.0 2018-12-30 16:48:32.451 debug [minimal/clean] result after sort: minimalTinker_02-2018_12_17-02_00_10_backupiobroker.tar.gz,minimalTinker_02-2018_12_18-02_00_10_backupiobroker.tar.gz,minimalTinker_02-2018_12_19-02_00_10_backupiobr
backitup.0 2018-12-30 16:48:32.444 debug [minimal/clean] bt: undefined
backitup.0 2018-12-30 16:48:32.444 debug [minimal/clean] at: undefined
backitup.0 2018-12-30 16:48:32.444 debug [minimal/clean] bt: undefined
backitup.0 2018-12-30 16:48:32.444 debug [minimal/clean] at: undefined
backitup.0 2018-12-30 16:48:32.444 debug [minimal/clean] bt: undefined
backitup.0 2018-12-30 16:48:32.443 debug [minimal/clean] at: undefined
backitup.0 2018-12-30 16:48:32.443 debug [minimal/clean] bt: undefined
backitup.0 2018-12-30 16:48:32.443 debug [minimal/clean] at: undefined
backitup.0 2018-12-30 16:48:32.443 debug [minimal/clean] bt: undefined
backitup.0 2018-12-30 16:48:32.443 debug [minimal/clean] at: undefined
backitup.0 2018-12-30 16:48:32.443 debug [minimal/clean] bt: undefined
backitup.0 2018-12-30 16:48:32.443 debug [minimal/clean] at: undefined
backitup.0 2018-12-30 16:48:32.442 debug [minimal/clean] bt: undefined
backitup.0 2018-12-30 16:48:32.442 debug [minimal/clean] at: undefined
backitup.0 2018-12-30 16:48:32.441 debug [minimal/clean] result before sort: minimalTinker_02-2018_12_17-02_00_10_backupiobroker.tar.gz,minimalTinker_02-2018_12_18-02_00_10_backupiobroker.tar.gz,minimalTinker_02-2018_12_19-02_00_10_backupiob
backitup.0 2018-12-30 16:48:32.403 debug [minimal/cifs] done
backitup.0 2018-12-30 16:48:32.397 debug [minimal/minimal] done
backitup.0 2018-12-30 16:48:32.301 debug [minimal/minimal] Backup created: /opt/iobroker/backups/minimal_2018_12_30-16_48_13_Tinker_02_backupiobroker.tar.gz
backitup.0 2018-12-30 16:48:13.963 debug [minimal/mount] doneDatei ist auf dem NAS . . . und jetzt wieder weg :cry: :cry: EDIT: zwei Zeilen kamne nochim log:backitup.0 2018-12-30 16:49:32.615 debug [minimal] exec: done
backitup.0 2018-12-30 16:49:32.614 debug [minimal/umount] done
backitup.0 2018-12-30 16:48:32.497 debug [minimal/history] backitup.0.history.html
backitup.0 2018-12-30 16:48:32.452 debug [minimal/clean] done
backitup.0 2018-12-30 16:48:32.451 debug [minimal/clean] delete /opt/iobroker/backups/minimal_2018_12_30-16_48_13_Tinker_02_backupiobroker.tar.gz -
OK das hilft schonmal weiter …
Ingo hat mir gerade noch einen weiteren Log im Chat gegeben.
Kannst du bitte die gleiche Prozedur mit löschen von Instanz und Adapter noch einmal durchführen ...
Danke für deine Hilfe bei der Fehlersuche.
-
Danke für deine Hilfe bei der Fehlersuche. `
Nee, so wird das nix!DANKE für deine Mühen einen runden Adapter zu erstellen!
War leider nix.
! ````
backitup.0 2018-12-30 17:19:31.059 debug [minimal/history] backitup.0.history.html
backitup.0 2018-12-30 17:19:31.006 debug [minimal/clean] done
backitup.0 2018-12-30 17:19:31.006 debug [minimal/clean] delete /opt/iobroker/backups/minimal_2018_12_30-17_19_13_Tinker_02_backupiobroker.tar.gz
backitup.0 2018-12-30 17:19:31.006 debug [minimal/clean] files to delete: /opt/iobroker/backups/minimal_2018_12_30-17_19_13_Tinker_02_backupiobroker.tar.gz
backitup.0 2018-12-30 17:19:31.006 debug [minimal/clean] result after sort: minimalTinker_02-2018_12_17-02_00_10_backupiobroker.tar.gz,minimalTinker_02-2018_12_18-02_00_10_backupiobroker.tar.gz,minimalTinker_02-2018_12_19-02_00_10_backupiobr
backitup.0 2018-12-30 17:19:31.006 debug [minimal/clean] {"dev":38,"mode":33279,"nlink":1,"uid":1027,"gid":100,"rdev":0,"blksize":16384,"ino":302026,"size":7464185,"blocks":14579,"atime":"2018-12-23T01:00:24.052Z","mtime":"2018-12-23T01:00:2
backitup.0 2018-12-30 17:19:31.005 debug [minimal/clean] {"dev":38,"mode":33279,"nlink":1,"uid":1027,"gid":100,"rdev":0,"blksize":16384,"ino":302026,"size":7464185,"blocks":14579,"atime":"2018-12-23T01:00:24.052Z","mtime":"2018-12-23T01:00:2
backitup.0 2018-12-30 17:19:31.004 debug [minimal/clean] {"dev":38,"mode":33279,"nlink":1,"uid":1027,"gid":100,"rdev":0,"blksize":16384,"ino":301976,"size":7464148,"blocks":14579,"atime":"2018-12-22T01:00:23.045Z","mtime":"2018-12-22T01:00:2
backitup.0 2018-12-30 17:19:31.004 debug [minimal/clean] {"dev":38,"mode":33279,"nlink":1,"uid":1027,"gid":100,"rdev":0,"blksize":16384,"ino":301976,"size":7464148,"blocks":14579,"atime":"2018-12-22T01:00:23.045Z","mtime":"2018-12-22T01:00:2
backitup.0 2018-12-30 17:19:31.004 debug [minimal/clean] {"dev":38,"mode":33279,"nlink":1,"uid":1027,"gid":100,"rdev":0,"blksize":16384,"ino":301966,"size":7463752,"blocks":14578,"atime":"2018-12-21T01:00:23.623Z","mtime":"2018-12-21T01:00:2
backitup.0 2018-12-30 17:19:31.004 debug [minimal/clean] {"dev":38,"mode":33279,"nlink":1,"uid":1027,"gid":100,"rdev":0,"blksize":16384,"ino":301966,"size":7463752,"blocks":14578,"atime":"2018-12-21T01:00:23.623Z","mtime":"2018-12-21T01:00:2
backitup.0 2018-12-30 17:19:31.004 debug [minimal/clean] {"dev":38,"mode":33279,"nlink":1,"uid":1027,"gid":100,"rdev":0,"blksize":16384,"ino":301955,"size":7462929,"blocks":14577,"atime":"2018-12-30T13:04:58.855Z","mtime":"2018-12-20T01:00:2
backitup.0 2018-12-30 17:19:31.004 debug [minimal/clean] {"dev":38,"mode":33279,"nlink":1,"uid":1027,"gid":100,"rdev":0,"blksize":16384,"ino":301955,"size":7462929,"blocks":14577,"atime":"2018-12-30T13:04:58.855Z","mtime":"2018-12-20T01:00:2
backitup.0 2018-12-30 17:19:31.000 debug [minimal/clean] {"dev":38,"mode":33279,"nlink":1,"uid":1027,"gid":100,"rdev":0,"blksize":16384,"ino":301950,"size":7458962,"blocks":14569,"atime":"2018-12-19T01:00:22.641Z","mtime":"2018-12-19T01:00:2
backitup.0 2018-12-30 17:19:31.000 debug [minimal/clean] {"dev":38,"mode":33279,"nlink":1,"uid":1027,"gid":100,"rdev":0,"blksize":16384,"ino":301950,"size":7458962,"blocks":14569,"atime":"2018-12-19T01:00:22.641Z","mtime":"2018-12-19T01:00:2
backitup.0 2018-12-30 17:19:30.999 debug [minimal/clean] {"dev":38,"mode":33279,"nlink":1,"uid":1027,"gid":100,"rdev":0,"blksize":16384,"ino":301938,"size":7457111,"blocks":14565,"atime":"2018-12-18T01:00:26.722Z","mtime":"2018-12-18T01:00:3
backitup.0 2018-12-30 17:19:30.998 debug [minimal/clean] {"dev":38,"mode":33279,"nlink":1,"uid":1027,"gid":100,"rdev":0,"blksize":16384,"ino":301938,"size":7457111,"blocks":14565,"atime":"2018-12-18T01:00:26.722Z","mtime":"2018-12-18T01:00:3
backitup.0 2018-12-30 17:19:30.998 debug [minimal/clean] {"dev":38,"mode":33279,"nlink":1,"uid":1027,"gid":100,"rdev":0,"blksize":16384,"ino":301929,"size":7453033,"blocks":14557,"atime":"2018-12-17T01:00:25.845Z","mtime":"2018-12-17T01:00:3
backitup.0 2018-12-30 17:19:30.998 debug [minimal/clean] {"dev":38,"mode":33279,"nlink":1,"uid":1027,"gid":100,"rdev":0,"blksize":16384,"ino":301929,"size":7453033,"blocks":14557,"atime":"2018-12-17T01:00:25.845Z","mtime":"2018-12-17T01:00:3
backitup.0 2018-12-30 17:19:30.997 debug [minimal/clean] result before sort: minimalTinker_02-2018_12_17-02_00_10_backupiobroker.tar.gz,minimalTinker_02-2018_12_18-02_00_10_backupiobroker.tar.gz,minimalTinker_02-2018_12_19-02_00_10_backupiob
backitup.0 2018-12-30 17:19:30.964 debug [minimal/cifs] done
backitup.0 2018-12-30 17:19:30.954 debug [minimal/minimal] done
backitup.0 2018-12-30 17:19:30.863 debug [minimal/minimal] Backup created: /opt/iobroker/backups/minimal_2018_12_30-17_19_13_Tinker_02_backupiobroker.tar.gz
backitup.0 2018-12-30 17:19:13.918 debug [minimal/mount] done
backitup.0 2018-12-30 17:17:57.409 info [total] backup was activated at 03:00 every 7 day(s)
backitup.0 2018-12-30 17:17:57.394 info [ccu] backup was activated at 01:30 every 3 day(s)
backitup.0 2018-12-30 17:17:57.361 info [minimal] backup was activated at 17:19 every 1 day(s)
backitup.0 2018-12-30 17:17:57.297 info starting. Version 1.0.0 in /opt/iobroker/node_modules/iobroker.backitup, node: v6.15.1Auf dem NAS erscheint ganz kurz die Datei, allerdings mit size=0 und verschwindet direkt wieder. Hab es jetzt zweimal laufen lassen. kann es sein, dass der Copy-Vorgang zu lange dauert und in der Zwischenzeit die Quelldatei gelöscht wurde? Allerdings ist bei total die Datei noch größer -
Manuell ist das log viel kürzer:
! ````
backitup.0 2018-12-30 17:27:28.005 debug system.adapter.admin.0: logging true
backitup.0 2018-12-30 17:26:53.349 debug [minimal/history] backitup.0.history.html
backitup.0 2018-12-30 17:26:53.331 debug [minimal/clean] done
backitup.0 2018-12-30 17:26:53.326 debug [minimal/cifs] done
backitup.0 2018-12-30 17:26:53.316 debug [minimal/minimal] done
backitup.0 2018-12-30 17:26:53.211 debug [minimal/minimal] Backup created: /opt/iobroker/backups/minimal_2018_12_30-17_26_35_Tinker_02_backupiobroker.tar.gz
backitup.0 2018-12-30 17:26:35.922 debug system.adapter.admin.0: logging false
backitup.0 2018-12-30 17:26:35.881 debug [minimal/mount] doneund die Datei bleibt. Erst size=0, dann richtige Größe und bleibt. Und jetzt wird es richtig cool: Habe spaßeshalber nochmal ein scheduled Backup hinterhergejagt. File erscheint - size = 0, dann verschwinden BEIDE Dateien von heute. EDIT: Noch eine Idee: Die Filenames im Log haben mich etwas irritiert. kann es sein, dass…  es daran liegt, dass meine alten Backups noch der alten Namenskonvention unterliegen? Gruß Rainer -
Manuell wird die Datei nicht gelöscht.
Nur wenn du es mit cron ausführen lässt.
Dank Ingo könnte jetzt der Fehler gefunden und gefixt sein.
Node 6 unterstützt nicht ctimeMS.
Habe jetzt auf ctime gewechselt.
Kannst du bitte noch einmal testen und die Prozedur vom github noch einmal durchführen?
-
Simatec, du weißt ja, ich habe das identische Problem auf meinem Produktiv-System.
Nur habe ich zusätzlich noch einen Papierkorb auf meiner NAS und dort landen die vom Adapter fehlerhaft gelöschten aktuellen Backups (und mit der richtigen Dateigröße)
-
Noch eine Idee: Die Filenames im Log haben mich etwas irritiert.
kann es sein, dass…
es daran liegt, dass meine alten Backups noch der alten Namenskonvention unterliegen?
Gruß
Rainer `
Nein die wird backitup beim löschen zwar nicht berücksichtigen, aber trotzdem müssen die aktuellsten x backups liegen bleiben.