Test Adapter ioBroker.backitup v3.1.x

[simatec]

@ltsalvatore
Schaue ich mir noch einmal an. Allerdings kann ich den Fehler nicht nachstellen, da auf meinem Dev-System "Lange Benachrichtigung" eingestellt ist und die Nachrichten sauber gesendet werden

[Frank_y]

@BoehserWolf sagte in Test Adapter Backitup v1.6.x:

@simatec Morgen, setzte backitup in v1.6.7 ein. Wurde heute morgen mit einer Fehlermeldung beim Backup in diesem Datenpunkt begrüßt:

backitup.0.history.iobrokerLastTime

Meldung:

error: 20. September 2020 um 03:30 Uhr

Wenn ich allerdings auf Filesystem Ebene nachsehe scheint alles ok:

ls -la
total 96200
drwxrwxrwx+ 2 iobroker iobroker    4096 Sep 20 03:30 .
drwxrwxrwx+ 8 iobroker iobroker    4096 Sep 19 10:05 ..
-rw-rw-rw-+ 1 iobroker iobroker 6842369 May  9 13:25 2020_05_09-13_25_34_backupiobroker.tar.gz
-rw-rw-rw-+ 1 iobroker iobroker 8210513 Jun 10 18:53 2020_06_10-18_53_46_backupiobroker.tar.gz
-rw-rw-rw-+ 1 iobroker iobroker 8298907 Jun 28 11:26 2020_06_28-11_26_20_backupiobroker.tar.gz
-rw-rw-rw-+ 1 iobroker iobroker 6645760 Sep  7 01:30 homematic_2020_09_07-01_30_22_3.51.6.20200229_backupiobroker.tar.sbk
-rw-rw-rw-+ 1 iobroker iobroker 6645760 Sep 10 01:30 homematic_2020_09_10-01_30_22_3.51.6.20200229_backupiobroker.tar.sbk
-rw-rw-rw-+ 1 iobroker iobroker 6645760 Sep 13 01:30 homematic_2020_09_13-01_30_22_3.51.6.20200229_backupiobroker.tar.sbk
-rw-rw-rw-+ 1 iobroker iobroker 6645760 Sep 16 01:30 homematic_2020_09_16-01_30_22_3.51.6.20200229_backupiobroker.tar.sbk
-rw-rw-rw-+ 1 iobroker iobroker 6645760 Sep 19 01:30 homematic_2020_09_19-01_30_22_3.51.6.20200229_backupiobroker.tar.sbk
-rw-rw-rw-+ 1 iobroker iobroker 8345290 Sep 16 03:30 iobroker_2020_09_16-03_30_10_iobroker_wald_backupiobroker.tar.gz
-rw-rw-rw-+ 1 iobroker iobroker 8345873 Sep 17 03:30 iobroker_2020_09_17-03_30_10_iobroker_wald_backupiobroker.tar.gz
-rw-rw-rw-+ 1 iobroker iobroker 8345298 Sep 18 03:30 iobroker_2020_09_18-03_30_10_iobroker_wald_backupiobroker.tar.gz
-rw-rw-rw-+ 1 iobroker iobroker 8449703 Sep 19 03:30 iobroker_2020_09_19-03_30_10_iobroker_wald_backupiobroker.tar.gz
-rw-rw-rw-+ 1 iobroker iobroker 8401710 Sep 20 03:30 iobroker_2020_09_20-03_30_10_iobroker_wald_backupiobroker.tar.gz

Das Archiv iobroker_2020_09_20-03_30_10_iobroker_wald_backupiobroker.tar.gz lässt sich entpacken und ist damit wohl i.O.

Eine Idee dazu?

Bei mir auch selber Effekt mit v1.6.7:
backitup.0.history.iobrokerLastTime "error: 23. September 2020 um 02:03 Uhr"
backitup.0.history.iobrokerSuccess "false"
json: [{"date":"23. September 2020 um 02:03 Uhr","name":"iobroker_2020_09_23-02_00_10_backupiobroker.tar.gz","type":"iobroker","storage":"Dropbox: Ja","filesize":"9MB","error":"none"},{"date":"21. September 2020 um 16:13 Uhr","name":"iobroker_2020_09_21-16_09_26_backupiobroker.tar.gz","type":"iobroker","storage":"Dropbox: Ja","filesize":"9MB","error":"none"},{"date":"21. September 2020 um 02:03 Uhr","name":"iobroker_2020_09_21-02_00_10_backupiobroker.tar.gz","type":"iobroker","storage":"Dropbox: Ja","filesize":"9MB","error":"none"},{"date":"19. September 2020 um 02:03 Uhr","name":"iobroker_2020_09_19-02_00_10_backupiobroker.tar.gz","type":"iobroker","storage":"Dropbox: Ja","filesize":"9MB","error":"none"},
.....
laut json-Datenpunkt scheint alles in Ordnung zu sein. Die beiden anderen Datenpunkte zeigen einen Fehler. Datei ist auch auf Dropbox verfügbar.
Ich habe die iobrokerVM jetzt neu gestartet und schaue morgen noch einmal

[Frank_y]

auch ein Systemneustart bringt keine Änderung
backitup.0.history.iobrokerLastTime und backitup.0.history.iobrokerSuccess zeigen einen Fehler an
json zeigt alles i.O. an.
Datei ist auf Dropbox vorhanden

[simatec]

@Frank_y
Bitte führe iobroker fix aus und poste mal den Debuglog des Backupprozesses

[Frank_y]

@simatec
fixer ausgeführt (curl -fsL https://iobroker.net/fix.sh | bash -)

• adapter auf debug gestellt

cron-job liefert folgendes LOG

backitup.0	2020-09-24 18:03:57.755	debug	(17894) system.adapter.admin.0: logging true
backitup.0	2020-09-24 18:03:29.468	debug	(17894) [iobroker/clean] delete /opt/iobroker/backups/redis_2020_09_21-16_09_34_backupiobroker.tar.gz
backitup.0	2020-09-24 18:03:29.462	debug	(17894) [iobroker/clean] delete /opt/iobroker/backups/iobroker_2020_09_23-02_00_10_backupiobroker.tar.gz
backitup.0	2020-09-24 18:03:29.199	debug	(17894) [iobroker] exec: done
backitup.0	2020-09-24 18:03:29.190	debug	(17894) [iobroker] exec: done
backitup.0	2020-09-24 18:03:29.187	debug	(17894) [iobroker/history] backitup.0.history.json
backitup.0	2020-09-24 18:03:29.179	debug	(17894) [iobroker/history] backitup.0.history.html
backitup.0	2020-09-24 18:03:29.159	debug	(17894) [iobroker/clean] done
backitup.0	2020-09-24 18:03:29.151	debug	(17894) [iobroker/dropbox] done
backitup.0	2020-09-24 18:03:28.620	debug	(17894) [iobroker/dropbox] Dropbox: delete /backupDir/redis_2020_09_09-02_00_18_backupiobroker.tar.gz
backitup.0	2020-09-24 18:03:28.043	debug	(17894) [iobroker/dropbox] Dropbox: delete /backupDir/iobroker_2020_09_09-02_00_10_backupiobroker.tar.gz
backitup.0	2020-09-24 18:03:18.542	debug	(17894) [iobroker/dropbox] Dropbox: Copy redis_2020_09_24-18_00_17_backupiobroker.tar.gz...
backitup.0	2020-09-24 18:00:18.148	debug	(17894) [iobroker/dropbox] Dropbox: Copy iobroker_2020_09_24-18_00_10_backupiobroker.tar.gz...
backitup.0	2020-09-24 18:00:18.139	debug	(17894) [iobroker/redis] done
backitup.0	2020-09-24 18:00:17.974	debug	(17894) [iobroker/iobroker] done
backitup.0	2020-09-24 18:00:16.948	debug	(17894) [iobroker/iobroker] Backup created: /opt/iobroker/backups/iobroker_2020_09_24-18_00_10_backupiobroker.tar.gz
backitup.0	2020-09-24 18:00:12.747	debug	(17894) [iobroker/iobroker] host.iobrokerVM 4876 objects saved
ba[ckitup.0	2020-09-24 18:00:12.715	debug	(17894) [iobroker/iobroker] host.iobrokerVM 4042 states saved
backitup.0	2020-09-24 18:00:10.143	debug	(17894) Backup has started ...
backitup.0	2020-09-24 17:57:01.485	debug	(17894) system.adapter.admin.0: logging false
backitup.0	2020-09-24 17:55:42.772	debug	(17894) system.adapter.admin.0: logging true
backitup.0	2020-09-24 17:55:37.241	debug	(17894) system.adapter.admin.0: logging false[Link Text](Link Adresse)](Link Adresse)

Leider bleiben beide Datenpunkte fehlerhaft

---

Mod-Edit: Code/Log in Code Tags gepackt. Bitte benutzt die Code Tags Funktion -> </>
Hier gehts zur Hilfe.

[Frank_y]

@simatec
habe danach noch einmal manuell <jetzt sichern> angestoßen und dann bestätigen beide Datenpunkte das korrekte backup:
backitup.0.history.iobrokerLastTime 24. September 2020 um 18:10 Uhr
backitup.0.history.iobrokerSuccess true

das Log sieht genauso aus

->es hat irgendetwas mit dem Cron-Job zu tun

[simatec]

@Frank_y
history wird nicht sauber beendet.
Hatten wir hier schonmal gehabt. ..

• Instanz löschen
• Adapter löschen
• iobroker neustarten
• backitup neu installieren und einrichten

Scheint so, als ob bei deinem Update was schief gelaufen ist

[Frank_y]

@simatec
alles gemacht, gleiches Bild:
-manuelle Auslösung in Ordnung
-über den cron-job werden die Datenpunkte nicht richtig gesetzt

bin per Rollback wieder zurück zur v1.5.8....hier läuft alles einwandfrei durch, manuell und per cron

[simatec]

@Frank_y

Was steht bei der 1.6.7 in folgenden Objekt nach dem Backup?

backitup.0.output.line

Gib mir mal bitte den Inhalt ...

[Frank_y]

@simatec
[DEBUG] [clean] - delete /opt/iobroker/backups/redis_2020_09_09-02_00_18_backupiobroker.tar.gz

[simatec]

@Frank_y
Kann das sein, dass genau hier das Problem liegt?
Laut deinem anderen Post, gibt es in dem Ordner kein Redis-Backup.
Stimmen deine Rechte in dem Ordner backup

[Frank_y]

@simatec
die Ordner-Berechtigungen sind in beiden Versionen gleich:
v.1.5.8:

v1.6.7:

Wenn ich manuell starte funktioniert ja auch alles. Auch beim Cron-Job werden Backups erstellt. Nur die Rückmeldungen in den beiden Datenpunkt scheinen nicht korrekt

[simatec]

@Frank_y
Bei einem manuellen Backup werden keine alten Dateien gelöscht.
Deshalb kommt der Fehler dort nicht.

Zeige doch bitte mal den kompletten Inhalt deines Backup-Ordners ...

[Frank_y]

@simatec
v1.5.8

v.1.6.7

[Thomas Braun]

@Frank_y
Falsche Rechte.
Das sollte so aussehen:

-rw-rwxr--+ 1 iobroker iobroker 5127439 Sep 20 02:42 iobroker_2020_09_20-02_42_51_backupiobroker.tar.gz

Bzw. so:

pi@raspberrypi:~ $ getfacl /opt/iobroker/backups/iobroker_2020_09_20-02_42_51_backupiobroker.tar.gz
getfacl: Entferne führende '/' von absoluten Pfadnamen
# file: opt/iobroker/backups/iobroker_2020_09_20-02_42_51_backupiobroker.tar.gz
# owner: iobroker
# group: iobroker
user::rw-
group::r-x
group:iobroker:rwx
mask::rwx
other::r--

Konsolenausgaben bitte nicht als Screenshot sondern als Text in CodeTags setzen.

[simatec]

@Frank_y
So wie es aber ausschaut, wird auch mit der 1.5.8 dein altes Redis Backup nicht gelöscht

[Frank_y]

@simatec sagte in Test Adapter Backitup v1.6.x:

@Frank_y
So wie es aber ausschaut, wird auch mit der 1.5.8 dein altes Redis Backup nicht gelöscht

kann ich nicht bestätigen....mit den Rechten werden in 1.5.8 auch die alten Redis-Backups gelöscht. Und zwar im System als auch auf Dropbox.
Aber egal, was kann ich tun. Den Fixer hatte ich schon laufen lassen.

[simatec]

@Frank_y
Hmmm sehe ich nicht so. Bei der 1.6.7 bleibt er bei dem Redis Backup vom 9.9. hängen und bei der 1.5.8 ist es auch noch da. Also wird es nicht gelöscht.

Passe deine Rechte nach den Vorgaben von @Thomas-Braun an.

[BoehserWolf]

@Thomas-Braun
Ok, Rechte waren bei mir im Backup Verzeichnis ebenfalls anders, iobroker fix hat es nicht gerichtet. Habe die für die iobroker*.tar.gz nun angepasst.
Von:

getfacl iobroker_2020_09_27-03_30_10_iobroker_wald_backupiobroker.tar.gz
# file: iobroker_2020_09_27-03_30_10_iobroker_wald_backupiobroker.tar.gz
# owner: iobroker
# group: iobroker
user::rw-
group::rwx                      #effective:rw-
group:iobroker:rwx              #effective:rw-
mask::rw-
other::rw-

Nach:

getfacl iobroker_2020_09_27-03_30_10_iobroker_wald_backupiobroker.tar.gz
# file: iobroker_2020_09_27-03_30_10_iobroker_wald_backupiobroker.tar.gz
# owner: iobroker
# group: iobroker
user::rw-
group::r-x
group:iobroker:rwx
mask::rwx
other::r--

Verzeichnis:

getfacl /opt/iobroker/backups
getfacl: Removing leading '/' from absolute path names
# file: opt/iobroker/backups
# owner: iobroker
# group: iobroker
user::rwx
group::rwx
group:iobroker:rwx
mask::rwx
other::rwx
default:user::rwx
default:group::rwx
default:group:iobroker:rwx
default:mask::rwx
default:other::rwx

@simatec Mir stellt sich nun die Frage wie kommt es dazu und was hat backitup v1.6.7 damit zu tun? Bei mir tritt das "Phänomen" seit v1.6.7 auf. Irgendetwas muss sich da geändert haben.

Mit homematic*.tar.sbk sehen die Rechte bei mir auch nicht wie im Post von @Thomas-Braun aus. Der Datenpunkt backitup.0.history.ccuSuccess steht auf false. Sollte ich diese Dateien auch anpassen? Bspw. jetzt:

getfacl homematic_2020_09_25-01_30_10_3.51.6.20200229_backupiobroker.tar.sbk
# file: homematic_2020_09_25-01_30_10_3.51.6.20200229_backupiobroker.tar.sbk
# owner: iobroker
# group: iobroker
user::rw-
group::rwx                      #effective:rw-
group:iobroker:rwx              #effective:rw-
mask::rw-
other::rw-

[Frank_y]

@simatec
@Thomas-Braun

Dem Ordner /backups habe ich über chmod 777 Rechte gegeben.

@iobrokerVM:/opt/iobroker$ sudo chmod 777 backups/
[sudo] Passwort für 
@iobrokerVM:/opt/iobroker$ getfacl backups/
# file: backups/
# owner: iobroker
# group: iobroker
user::rwx
group::r-x
group:iobroker:rwx
mask::rwx
other::rwx
default:user::rwx
default:group::r-x
default:group:iobroker:rwx
default:mask::rwx
default:other::r-x]

Datei-Rechte im /backups habe ich manuell mit setfacl angepasst, dann Update auf 1.6.7.
Backup über Cron-job gestartet. Die neu erzeugten Dateien (Sep 27 12:30 ) haben wieder die Rechte -rw-rw-r--+ 1

@iobrokerVM:/opt/iobroker/backups$ ls -l
insgesamt 116460
-rw-rwxr--+ 1 iobroker iobroker 10407690 Sep  7 02:00 iobroker_2020_09_07-02_00_10_backupiobroker.tar.gz
-rw-rwxr--+ 1 iobroker iobroker 10408118 Sep  9 02:00 iobroker_2020_09_09-02_00_10_backupiobroker.tar.gz
-rw-rwxr--+ 1 iobroker iobroker 10405895 Sep 11 02:00 iobroker_2020_09_11-02_00_10_backupiobroker.tar.gz
-rw-rwxr--+ 1 iobroker iobroker 10404922 Sep 13 02:00 iobroker_2020_09_13-02_00_10_backupiobroker.tar.gz
-rw-rwxr--+ 1 iobroker iobroker 10405309 Sep 15 02:00 iobroker_2020_09_15-02_00_10_backupiobroker.tar.gz
-rw-rwxr--+ 1 iobroker iobroker 10406213 Sep 17 02:00 iobroker_2020_09_17-02_00_10_backupiobroker.tar.gz
-rw-rwxr--+ 1 iobroker iobroker 10414384 Sep 19 02:00 iobroker_2020_09_19-02_00_10_backupiobroker.tar.gz
-rw-rwxr--+ 1 iobroker iobroker 10424841 Sep 24 21:51 iobroker_2020_09_24-21_51_10_backupiobroker.tar.gz
-rw-rwxr--+ 1 iobroker iobroker 10425075 Sep 25 02:00 iobroker_2020_09_25-02_00_14_backupiobroker.tar.gz
-rw-rwxr--+ 1 iobroker iobroker 10434793 Sep 27 02:00 iobroker_2020_09_27-02_00_10_backupiobroker.tar.gz
-rw-rw-r--+ 1 iobroker iobroker 10437056 Sep 27 12:30 iobroker_2020_09_27-12_30_10_backupiobroker.tar.gz
-rw-rwxr--+ 1 iobroker iobroker   418520 Sep  7 02:00 redis_2020_09_07-02_00_18_backupiobroker.tar.gz
-rw-rwxr--+ 1 iobroker iobroker   419410 Sep  9 02:00 redis_2020_09_09-02_00_18_backupiobroker.tar.gz
-rw-rwxr--+ 1 iobroker iobroker   414630 Sep 11 02:00 redis_2020_09_11-02_00_18_backupiobroker.tar.gz
-rw-rwxr--+ 1 iobroker iobroker   416276 Sep 13 02:00 redis_2020_09_13-02_00_18_backupiobroker.tar.gz
-rw-rwxr--+ 1 iobroker iobroker   418406 Sep 15 02:00 redis_2020_09_15-02_00_18_backupiobroker.tar.gz
-rw-rwxr--+ 1 iobroker iobroker   419599 Sep 17 02:00 redis_2020_09_17-02_00_18_backupiobroker.tar.gz
-rw-rwxr--+ 1 iobroker iobroker   419813 Sep 19 02:00 redis_2020_09_19-02_00_18_backupiobroker.tar.gz
-rw-rwxr--+ 1 iobroker iobroker   420060 Sep 24 21:51 redis_2020_09_24-21_51_18_backupiobroker.tar.gz
-rw-rwxr--+ 1 iobroker iobroker   419945 Sep 25 02:00 redis_2020_09_25-02_00_22_backupiobroker.tar.gz
-rw-rwxr--+ 1 iobroker iobroker   429191 Sep 27 02:00 redis_2020_09_27-02_00_19_backupiobroker.tar.gz
-rw-rw-r--+ 1 iobroker iobroker   430312 Sep 27 12:30 redis_2020_09_27-12_30_18_backupiobroker.tar.gz

Die Datenpunkte zeigen weiterhin ein fehlerhaftes Backup an