Iobroker.backitup keine geplanten Backups mehr nach Update
-
Um den anderen Thread mit 65 Seiten nicht noch mehr zu belasten, hier mal getrennt.
Ich habe vor ein paar Tagen von Version 0.x auf die aktuelle Version upgedatet.
Hat soweit auch alles gut geklappt. Ja, die Dateinamen sind jetzt anders, das ist IMHO aber alles.
Manuelle Backups, egal ob minimal, total oder CCU aufs NAS funktionieren einwandfrei.
Aber seit dem Update bekomme ich keine automatischen Updates mehr.
Ich habe schon die Zeiten geändert, gespeichert und wieder zurück geändert. Ohne Erfolg
Was kann ich noch testen?
Gruß
Rainer
-
Hallo Rainer,
das ist sehr komisch, denn am cronjob wurde nichts geändert.
Was wirft der Log aus? Werden die Zeiten laut Log gesetzt?
Hast du mal einen Upload probiert?
Eventuell ging beim Update irgendwas schief.
Zur Not sichere mal deine Einstellungen von backitup und installiere den Adapter neu.
Gesendet von iPhone mit Tapatalk
-
Habe gerade gesehen, dass das CCU Backup läuft.
aber weder total noch standard.
Was wirft der Log aus? Werden die Zeiten laut Log gesetzt? `
Gute Idee :oops:Im Log von heute morgen fand ich dann:
2018-12-29 03:00:13.277 - debug: backitup.0 [total/mount] done 2018-12-29 03:00:13.288 - debug: backitup.0 [total/total] TAR started... 2018-12-29 03:00:13.290 - debug: backitup.0 [total/total] Ignore dir: /opt/iobroker/backups 2018-12-29 03:00:23.290 - debug: backitup.0 [total/total] Packed 5MB so far...und später:
2018-12-29 03:00:13.277 - debug: backitup.0 [total/mount] done 2018-12-29 03:00:13.288 - debug: backitup.0 [total/total] TAR started... 2018-12-29 03:00:13.290 - debug: backitup.0 [total/total] Ignore dir: /opt/iobroker/backups 2018-12-29 03:00:23.290 - debug: backitup.0 [total/total] Packed 5MB so far.. : : : 2018-12-29 04:05:06.080 - debug: backitup.0 [total/total] Packed 2344MB so far... 2018-12-29 04:05:16.102 - debug: backitup.0 [total/total] Packed 2351MB so far... 2018-12-29 04:05:20.995 - debug: backitup.0 [total/total] done 2018-12-29 04:05:21.021 - debug: backitup.0 [total/cifs] done 2018-12-29 04:05:21.092 - debug: backitup.0 [total/clean] done 2018-12-29 04:05:21.125 - debug: backitup.0 [total/history] backitup.0.history.html 2018-12-29 04:05:35.506 - warn: history.0 Reconnection to DB. 2018-12-29 04:05:35.524 - warn: history.0 Reconnection to DB. 2018-12-29 04:06:21.547 - debug: backitup.0 [total/umount] done 2018-12-29 04:06:21.550 - debug: backitup.0 [total] exec: doneAuf dem NAS ist nix
Gruß
Rainer
-
Manuell geht alles.
ich dachte ja erst, dass der Mount nicht klappt, habe dann manuell angestoßen -> keine Probleme.
Liegen die Backups eventuell local? `
unter /opt/iobroker/backups liegen nur alte, bzw. einer, der irgendwie hängengeblieben war
Hatte auch gedacht, dass der irgendwie im Timeout landet, oder sich minimal und komplett überschneiden, aber manuell geht es ja.
OK - man muss erst Beweisfotos machen um zu sehen dass total wohl auch geklappt hat :oops:
Gruß
Rainer
-
Ja manchmal ist das so Rainer …
Ich suche oft auch stundenlang nach Fehlern ... dabei sieht man dann irgendwann den Wald vor lauter Bäumen nicht ;)
Aber genau aus dem Grund liebe ich dieses Forum und iobroker ... alle helfen sich und gehen dabei sehr hilfsbereit miteinander um
-
:kleinlaut nachfrag:
Aber minimal läuft noch nicht
Gruß
Rainer
-
Poste mal den Log. `
2018-12-29 02:00:13.263 - debug: backitup.0 [minimal/mount] done 2018-12-29 02:00:30.027 - debug: backitup.0 [minimal/minimal] Backup created: /opt/iobroker/backups/minimal_2018_12_29-02_00_13_Tinker_02_backupiobroker.tar.gz 2018-12-29 02:00:30.101 - debug: backitup.0 [minimal/minimal] done 2018-12-29 02:00:30.103 - debug: backitup.0 [minimal/cifs] done 2018-12-29 02:00:30.110 - debug: backitup.0 [minimal/clean] delete /opt/iobroker/backups/minimal_2018_12_29-02_00_13_Tinker_02_backupiobroker.tar.gz 2018-12-29 02:00:30.115 - debug: backitup.0 [minimal/clean] done 2018-12-29 02:00:30.139 - debug: backitup.0 [minimal/history] backitup.0.history.html 2018-12-29 02:01:30.246 - debug: backitup.0 [minimal/umount] done 2018-12-29 02:01:30.248 - debug: backitup.0 [minimal] exec: doneProbiere mal die Zeit auf eine aktuelle Zeit zu stellen und simuliere es mal damit `
Mach ich - bis gleichGruß
Rainer
-
Da bin ich wieder;
backitup.0 2018-12-29 17:24:37.434 debug [minimal] exec: done backitup.0 2018-12-29 17:24:37.433 debug [minimal/umount] done backitup.0 2018-12-29 17:23:48.785 debug system.adapter.admin.0: logging true backitup.0 2018-12-29 17:23:37.330 debug [minimal/history] backitup.0.history.html backitup.0 2018-12-29 17:23:37.288 debug [minimal/clean] done backitup.0 2018-12-29 17:23:37.284 debug [minimal/clean] delete /opt/iobroker/backups/minimal_2018_12_29-17_23_14_Tinker_02_backupiobroker.tar.gz backitup.0 2018-12-29 17:23:37.273 debug [minimal/cifs] done backitup.0 2018-12-29 17:23:37.265 debug [minimal/minimal] done backitup.0 2018-12-29 17:23:37.142 debug [minimal/minimal] Backup created: /opt/iobroker/backups/minimal_2018_12_29-17_23_14_Tinker_02_backupiobroker.tar.gz backitup.0 2018-12-29 17:23:27.926 debug system.adapter.admin.0: logging false backitup.0 2018-12-29 17:23:14.279 debug [minimal/mount] doneAuf dem NAS ist nix.
Die Pfade für minimal und total sind identisch - hatte ich sogar bei der Konfiguration aus dem Explorer identisch in beide Felder kopiert.
Mir gehen die Ideen aus.
EDIT:
Manuell gestartet:
backitup.0 2018-12-29 17:37:41.476 debug system.adapter.admin.0: logging true backitup.0 2018-12-29 17:37:09.034 debug [minimal/history] backitup.0.history.html backitup.0 2018-12-29 17:37:08.997 debug [minimal/clean] done backitup.0 2018-12-29 17:37:08.995 debug [minimal/cifs] done backitup.0 2018-12-29 17:37:08.992 debug [minimal/minimal] done backitup.0 2018-12-29 17:37:08.916 debug [minimal/minimal] Backup created: /opt/iobroker/backups/minimal_2018_12_29-17_36_51_Tinker_02_backupiobroker.tar.gz backitup.0 2018-12-29 17:36:51.886 debug [minimal/mount] done backitup.0 2018-12-29 17:34:26.992 debug system.adapter.admin.0: logging false backitup.0 2018-12-29 17:24:37.403 debug [minimal] exec: done backitup.0 2018-12-29 17:24:37.400 debug [minimal/umount] done backitup.0 2018-12-29 17:23:48.784 debug system.adapter.admin.0: logging true backitup.0 2018-12-29 17:23:37.330 debug [minimal/history] backitup.0.history.html backitup.0 2018-12-29 17:23:37.288 debug [minimal/clean] done backitup.0 2018-12-29 17:23:37.284 debug [minimal/clean] delete /opt/iobroker/backups/minimal_2018_12_29-17_23_14_Tinker_02_backupiobroker.tar.gz backitup.0 2018-12-29 17:23:37.273 debug [minimal/cifs] done backitup.0 2018-12-29 17:23:37.265 debug [minimal/minimal] done backitup.0 2018-12-29 17:23:37.142 debug [minimal/minimal] Backup created: /opt/iobroker/backups/minimal_2018_12_29-17_23_14_Tinker_02_backupiobroker.tar.gz backitup.0 2018-12-29 17:23:27.926 debug system.adapter.admin.0: logging false backitup.0 2018-12-29 17:23:14.279 debug [minimal/mount] doneDas ist im NAS angekommen
Gruß
Rainer
-
Was hast du genau laufen?
Gerade nachgesehen: `
root@linaro-alip:~# node -v v6.15.1 root@linaro-alip:~# npm -v 3.10.10mich stört gerade viel mehr:
Swap usage: 100 %Gruß
Rainer
-
Ich denke daran liegt es
Ob es an npm oder node liegt, bin ich mir etwas unsicher
Aber in der Konstellation haben auch User hier das Problem, dass immer das aktuellste gelöscht wird.
Ein Upgrade von node und npm hat den Fehler behoben.
Meine Dev Umgebung hat folgendes.
Node.js: v8.14.0
NPM: 6.4.1
Mein Produktiv hat folgendes
Node.js: v8.12.0
NPM: 4.6.1
-
Hallo Rainer,
falls du noch kein Update von node und npm gemacht hast, teste mal bitte folgendes.
Lösche mal die backitup Instanz (sichere vorher die config im Menü), dann lösche den Adapter backitup komplett.
Danach installiere mal bitte backitup über github.
Ist die gleiche Version, aber das könnte eventuell dein Problem beheben.
Zumindest ist es meine Theorie [emoji51]
Gesendet von iPhone mit Tapatalk
-
falls du noch kein Update von node und npm gemacht hast `
komme jetzt erst dazu ;-)Zumindest ist es meine Theorie [emoji51] `
Davon gibt es solche und solche ;-)Der Reihe nach:
zuerst nicht richtig gelesen (Dumme Angewohnheit, wenn man zuviel posts lesen muss) und Konfig gesichert, Instanz gelöscht, neue Instanz erstellt, Konfig eingelesen, CRON auf aktuelle Zeit +2Minuten gestellt - gewartet:
! ````
backitup.0 2018-12-30 11:35:51.582 error at process._tickCallback (internal/process/next_tick.js:104:9)
backitup.0 2018-12-30 11:35:51.582 error at _combinedTickCallback (internal/process/next_tick.js:73:7)
backitup.0 2018-12-30 11:35:51.582 error at processImmediate (timers.js:617:5)
backitup.0 2018-12-30 11:35:51.582 error at tryOnImmediate (timers.js:645:5)
backitup.0 2018-12-30 11:35:51.582 error at runCallback (timers.js:672:20)
backitup.0 2018-12-30 11:35:51.582 error at Immediate. <anonymous>(/opt/iobroker/node_modules/iobroker.js-controller/lib/adapter.js:3429:38)
backitup.0 2018-12-30 11:35:51.582 error at Adapter.emit (events.js:191:7)
backitup.0 2018-12-30 11:35:51.582 error at emitTwo (events.js:106:13)
backitup.0 2018-12-30 11:35:51.582 error at Adapter.adapter.on (/opt/iobroker/node_modules/iobroker.backitup/main.js:45:15)
backitup.0 2018-12-30 11:35:51.582 error TypeError: Cannot read property 'val' of null
backitup.0 2018-12-30 11:35:51.582 error uncaught exception: Cannot read property 'val' of null
backitup.0 2018-12-30 11:35:51.579 error at processImmediate [as _immediateCallback] (timers.js:617:5)
backitup.0 2018-12-30 11:35:51.579 error at tryOnImmediate (timers.js:645:5)
backitup.0 2018-12-30 11:35:51.579 error at runCallback (timers.js:672:20)
backitup.0 2018-12-30 11:35:51.579 error at Immediate. <anonymous>(/opt/iobroker/node_modules/iobroker.js-controller/lib/adapter.js:3429:38)
backitup.0 2018-12-30 11:35:51.579 error at Adapter.emit (events.js:191:7)
backitup.0 2018-12-30 11:35:51.579 error at emitTwo (events.js:106:13)
backitup.0 2018-12-30 11:35:51.579 error at Adapter.adapter.on (/opt/iobroker/node_modules/iobroker.backitup/main.js:45:15)
backitup.0 2018-12-30 11:35:51.579 error TypeError: Cannot read property 'val' of null
backitup.0 2018-12-30 11:35:51.578 error uncaught exception: Cannot read property 'val' of null
backitup.0 2018-12-30 11:35:51.571 error at processImmediate [as _immediateCallback] (timers.js:617:5)
backitup.0 2018-12-30 11:35:51.571 error at tryOnImmediate (timers.js:645:5)
backitup.0 2018-12-30 11:35:51.571 error at runCallback (timers.js:672:20)
backitup.0 2018-12-30 11:35:51.571 error at Immediate. <anonymous>(/opt/iobroker/node_modules/iobroker.js-controller/lib/adapter.js:3429:38)
backitup.0 2018-12-30 11:35:51.571 error at Adapter.emit (events.js:191:7)
backitup.0 2018-12-30 11:35:51.571 error at emitTwo (events.js:106:13)
backitup.0 2018-12-30 11:35:51.571 error at Adapter.adapter.on (/opt/iobroker/node_modules/iobroker.backitup/main.js:45:15)
backitup.0 2018-12-30 11:35:51.571 error TypeError: Cannot read property 'val' of null
backitup.0 2018-12-30 11:35:51.569 error uncaught exception: Cannot read property 'val' of null</anonymous></anonymous></anonymous>Dann doch richtig gemacht - und es passiert nix!backitup.0 2018-12-30 11:45:48.129 info [total] backup was activated at 03:00 every 7 day(s)
backitup.0 2018-12-30 11:45:48.117 info [ccu] backup was activated at 01:30 every 3 day(s)
backitup.0 2018-12-30 11:45:48.090 info [minimal] backup was activated at 11:50 every 1 day(s)
backitup.0 2018-12-30 11:45:48.018 info starting. Version 1.0.0 in /opt/iobroker/node_modules/iobroker.backitup, node: v6.15.1
backitup.0 2018-12-30 11:45:44.784 info terminatingGruß Rainer PS sehe gerade dass logstufe in der Konfig nicht mehr auf debug steht - neuer Versuch -
EDIT1
läuft wie bisher
backitup.0 2018-12-30 11:50:32.731 debug [minimal/history] backitup.0.history.html backitup.0 2018-12-30 11:50:32.671 debug [minimal/clean] done backitup.0 2018-12-30 11:50:32.670 debug [minimal/clean] delete /opt/iobroker/backups/minimal_2018_12_30-11_50_13_Tinker_02_backupiobroker.tar.gz backitup.0 2018-12-30 11:50:32.636 debug [minimal/cifs] done backitup.0 2018-12-30 11:50:32.628 debug [minimal/minimal] done backitup.0 2018-12-30 11:50:32.497 debug [minimal/minimal] Backup created: /opt/iobroker/backups/minimal_2018_12_30-11_50_13_Tinker_02_backupiobroker.tar.gz backitup.0 2018-12-30 11:50:14.011 debug [minimal/mount] done backitup.0 2018-12-30 11:49:48.399 debug system.adapter.admin.0: logging truenichts auf dem NAS :(
Anschließend manuell gestartet erscheint ein Backup auf dem NAS.
das log:
ckitup.0 2018-12-30 11:53:04.940 debug [minimal] exec: done backitup.0 2018-12-30 11:53:04.938 debug [minimal/umount] done backitup.0 2018-12-30 11:52:04.872 debug [minimal/history] backitup.0.history.html backitup.0 2018-12-30 11:52:04.833 debug [minimal/clean] done backitup.0 2018-12-30 11:52:04.831 debug [minimal/cifs] done backitup.0 2018-12-30 11:52:04.828 debug [minimal/minimal] done backitup.0 2018-12-30 11:52:04.751 debug [minimal/minimal] Backup created: /opt/iobroker/backups/minimal_2018_12_30-11_51_47_Tinker_02_backupiobroker.tar.gz backitup.0 2018-12-30 11:51:47.591 debug [minimal/mount] donesieht irgendwie unterschiedlich aus
Gruß
Rainer
-
über Octocat im Admin
Ich mach mal ein Upload
-
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:
594
Online32.7k
Benutzer82.5k
Themen1.3m
Beiträge