Test Adapter ioBroker.backitup v3.1.x

[ltsalvatore]

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

@ltsalvatore
Dann teste, ob deine Einstellungen im Whatsapp Adapter korrekt sind und schicke dir Testnachrichten aus iobroker (Blockly oder Javascript)

Backitup verwendet am Ende die gleichen Funktionen ...
Ansonsten bitte debuglog schicken

hi simatec,

über blockly und objekt "send message" funktioniert es einwandfrei.
ich habe dann diverse male getestet und es scheint wohl tatsächlich daran zu liegen, was man beim backitup adapter als benachrichtigungsstil auswählt.

wenn ich "kurze benachrichtigungen" wähle, dann kommen die messages sofort an.
wähle ich stattdessen "lange benachrichtigungen" kommt nichts an...

mir reicht im endeffekt die kurze benachrichtigung vollkommen aus... sollte sich doch jemand das ganze noch mal im detail anschauen wollen hier das log file:

whatsapp-cmb.0	2020-09-22 22:56:19.396	debug	(29603) system.adapter.admin.0: logging true
whatsapp-cmb.0	2020-09-22 22:53:27.906	debug	(29603) system.adapter.admin.0: logging false
whatsapp-cmb.0	2020-09-22 22:50:24.653	debug	(29603) <p>Message to: +491799700000<p>Text to send: BackItUp:%0AEin neues iobroker Backup wurde am 22. September 2020 um 22:50 Uhr erstellt.<p><b>Message queued.</b> You will receive it in a few sec
whatsapp-cmb.0	2020-09-22 22:50:24.388	debug	(29603) Call https://api.callmebot.com/whatsapp.php?phone=+491799700000&text=BackItUp%3A%0AEin%20neues%20iobroker%20Backup%20wurde%20am%2022.%20September%202020%20um%2022%3A50%20Uhr%20erstellt.&apike
whatsapp-cmb.0	2020-09-22 22:50:24.388	info	Ein neues iobroker Backup wurde am 22. September 2020 um 22:50 Uhr erstellt. to +491799700000
whatsapp-cmb.0	2020-09-22 22:50:24.388	info	(29603) Send BackItUp:
whatsapp-cmb.0	2020-09-22 22:48:55.316	debug	(29603) system.adapter.admin.0: logging true
whatsapp-cmb.0	2020-09-22 22:48:41.649	debug	(29603) system.adapter.admin.0: logging false
whatsapp-cmb.0	2020-09-22 22:47:24.706	debug	(29603) <p>Message to: +491799700000<p>Text to send: BackItUp:%0AEin neues iobroker Backup wurde am 22. September 2020 um 22:47 Uhr erstellt, und unter //192.168.111.100/"prv\Salva\P1\Backups\Server\
whatsapp-cmb.0	2020-09-22 22:47:24.504	debug	(29603) Call https://api.callmebot.com/whatsapp.php?phone=+491799700000&text=BackItUp%3A%0AEin%20neues%20iobroker%20Backup%20wurde%20am%2022.%20September%202020%20um%2022%3A47%20Uhr%20erstellt%2C%20u
whatsapp-cmb.0	2020-09-22 22:47:24.504	info	Ein neues iobroker Backup wurde am 22. September 2020 um 22:47 Uhr erstellt, und unter //192.168.111.100/"prv\Salva\P1\Backups\Server\Smarthome\iobroker\minimal" gespeichert. to +491799700000
whatsapp-cmb.0	2020-09-22 22:47:24.504	info	(29603) Send BackItUp:
whatsapp-cmb.0	2020-09-22 22:44:24.815	debug	(29603) <p>Message to: +491799700000<p>Text to send: BackItUp:%0AEin neues iobroker Backup wurde am 22. September 2020 um 22:44 Uhr erstellt.<p><b>Message queued.</b> You will receive it in a few sec
whatsapp-cmb.0	2020-09-22 22:44:24.502	debug	(29603) Call https://api.callmebot.com/whatsapp.php?phone=+491799700000&text=BackItUp%3A%0AEin%20neues%20iobroker%20Backup%20wurde%20am%2022.%20September%202020%20um%2022%3A44%20Uhr%20erstellt.&apike
whatsapp-cmb.0	2020-09-22 22:44:24.502	info	Ein neues iobroker Backup wurde am 22. September 2020 um 22:44 Uhr erstellt. to +491799700000
whatsapp-cmb.0	2020-09-22 22:44:24.502	info	(29603) Send BackItUp:
whatsapp-cmb.0	2020-09-22 22:43:17.411	info	(29603) starting. Version 0.1.6 in /opt/iobroker/node_modules/iobroker.whatsapp-cmb, node: v10.21.0, js-controller: 3.1.6
whatsapp-cmb.0	2020-09-22 22:43:16.954	debug	(29603) statesDB connected
whatsapp-cmb.0	2020-09-22 22:43:16.954	debug	(29603) States connected to redis: 127.0.0.1:9000
whatsapp-cmb.0	2020-09-22 22:43:16.950	debug	(29603) States create System PubSub Client
whatsapp-cmb.0	2020-09-22 22:43:16.950	debug	(29603) States create User PubSub Client
whatsapp-cmb.0	2020-09-22 22:43:16.947	debug	(29603) Redis States: Use Redis connection: 127.0.0.1:9000
whatsapp-cmb.0	2020-09-22 22:43:16.946	debug	(29603) objectDB connected
whatsapp-cmb.0	2020-09-22 22:43:16.944	debug	(29603) Objects connected to redis: 127.0.0.1:9001
whatsapp-cmb.0	2020-09-22 22:43:16.939	debug	(29603) Objects client initialize lua scripts
whatsapp-cmb.0	2020-09-22 22:43:16.939	debug	(29603) Objects create User PubSub Client
whatsapp-cmb.0	2020-09-22 22:43:16.938	debug	(29603) Objects create System PubSub Client
whatsapp-cmb.0	2020-09-22 22:43:16.937	debug	(29603) Objects client ready ... initialize now
whatsapp-cmb.0	2020-09-22 22:43:16.921	debug	(29603) Redis Objects: Use Redis connection: 127.0.0.1:9001

[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-