Neuer ioBroker-Blog online: Monatsrückblick März/April 2026

[Homoran]

@Donsen64 sagte in Backitup Komplett Backup KEIN Backup bei aktiver Option ioBroker Stop bei einem Backup:

Hat jemand eine Idee woran das liegen könnte?

Ja das log ;-)

mount: only root can do that

bitte Checkbox "mout as root/sudo" anhaken

[Donsen64]

Also der Fehler mit dem mount ist damit beseitigt. Aber auf dem NAS wird trotzdem keine Datei erzeugt. Hier nochmal das LOG dazu. Er behauptet aber trotzdem, dass alles erfolgreich war.

2019-03-17 17:56:39.002	debug: backitup.0 redis pmessage io.*.logging io.system.adapter.admin.0.logging {"val":true,"ack":true,"ts":1552841799001,"q":0,"from":"system.adapter.admin.0","lc":1552841799001}
2019-03-17 17:56:39.007	debug: backitup.0 system.adapter.admin.0: logging true
2019-03-17 17:56:42.080	debug: backitup.0 redis pmessage messagebox.system.adapter.backitup.0 messagebox.system.adapter.backitup.0 {"command":"getTelegramUser","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":12,"ack":false,"time":1552841802079},"_id":53587424}
2019-03-17 17:57:24.975	debug: backitup.0 redis pmessage messagebox.system.adapter.backitup.0 messagebox.system.adapter.backitup.0 {"command":"getTelegramUser","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":18,"ack":false,"time":1552841844974},"_id":53587430}
2019-03-17 17:57:39.755	debug: backitup.0 redis pmessage io.backitup.0.oneClick.* io.backitup.0.oneClick.total {"val":true,"ack":false,"ts":1552841859753,"q":0,"from":"system.adapter.admin.0","user":"system.user.admin","lc":1552841859753}
2019-03-17 17:57:39.786	debug: backitup.0 [total] exec: done
2019-03-17 17:57:39.795	debug: backitup.0 redis pmessage io.backitup.0.oneClick.* io.backitup.0.oneClick.total {"val":false,"ack":true,"ts":1552841859791,"q":0,"from":"system.adapter.backitup.0","user":"system.user.admin","lc":1552841859791}
2019-03-17 17:57:40.176	debug: backitup.0 redis pmessage io.*.logging io.system.adapter.admin.0.logging {"val":false,"ack":true,"ts":1552841860175,"q":0,"from":"system.host.ioBroker01","lc":1552841860175}
2019-03-17 17:57:40.176	debug: backitup.0 system.adapter.admin.0: logging false
2019-03-17 17:57:40.483	info: host.ioBroker01 instance system.adapter.backitup.0 terminated with code 0 (OK)
2019-03-17 17:58:47.379	info: host.ioBroker01 instance system.adapter.backitup.0 started with pid 21763
2019-03-17 17:58:48.982	debug: backitup.0 objectDB connected
2019-03-17 17:58:49.017	debug: backitup.0 statesDB connected
2019-03-17 17:58:49.025	info: backitup.0 States connected to redis: 127.0.0.1:6379
2019-03-17 17:58:49.140	info: backitup.0 starting. Version 1.1.3 in /opt/iobroker/node_modules/iobroker.backitup, node: v8.15.0
2019-03-17 17:58:49.212	info: backitup.0 [ccu] backup was activated at 03:30 every 1 day(s)
2019-03-17 17:58:49.250	debug: backitup.0 [ccu] 10 30 03 */1 * *
2019-03-17 17:58:49.251	info: backitup.0 [total] backup was activated at 03:10 every 1 day(s)
2019-03-17 17:58:49.258	debug: backitup.0 [total] 10 10 03 */1 * *
2019-03-17 17:58:49.310	debug: backitup.0 mount activ... umount in 2 Seconds!!
2019-03-17 18:09:16.589	debug: backitup.0 redis pmessage io.*.logging io.system.adapter.admin.0.logging {"val":false,"ack":true,"ts":1552842556587,"q":0,"from":"system.adapter.admin.0","lc":1552842556587}
2019-03-17 18:09:16.593	debug: backitup.0 system.adapter.admin.0: logging false
2019-03-17 18:10:06.837	debug: backitup.0 redis pmessage io.*.logging io.system.adapter.admin.0.logging {"val":true,"ack":true,"ts":1552842606832,"q":0,"from":"system.adapter.admin.0","lc":1552842606832}
2019-03-17 18:10:06.840	debug: backitup.0 system.adapter.admin.0: logging true

[Homoran]

@Donsen64
Bin nur am Tablet, aber ich sehe da kein log von einem backup

[simatec]

Ich sehe da auch nur den Start des Adapters ...
Ein backup definitiv nicht.

[Donsen64]

Habe es nochmals getestet. Es scheint wirklich so zu sein, dass er eigentlich nichts macht. Ich bekomme aber bei backitup.0.history.totalLastTime den folgenden Wert: 17. Maerz 2019 um 21:36 Uhr.
und bei backitup.0.history.totalSuccess steht true.

Unter backitup.0.history.html hat er nichts dazu gesetzt.

2019-03-17 21:36:11.796	debug: backitup.0 redis pmessage messagebox.system.adapter.backitup.0 messagebox.system.adapter.backitup.0 {"command":"getTelegramUser","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":18,"ack":false,"time":1552854971795},"_id":11320798}
2019-03-17 21:36:16.927	debug: backitup.0 redis pmessage io.*.logging io.system.adapter.admin.0.logging {"val":false,"ack":true,"ts":1552854976926,"q":0,"from":"system.adapter.admin.0","lc":1552854976926}
2019-03-17 21:36:16.928	debug: backitup.0 system.adapter.admin.0: logging false
2019-03-17 21:36:35.729	debug: backitup.0 redis pmessage io.backitup.0.oneClick.* io.backitup.0.oneClick.total {"val":true,"ack":false,"ts":1552854995727,"q":0,"from":"system.adapter.admin.0","user":"system.user.admin","lc":1552854995727}
2019-03-17 21:36:35.765	debug: backitup.0 [total] exec: done
2019-03-17 21:36:35.774	debug: backitup.0 redis pmessage io.backitup.0.oneClick.* io.backitup.0.oneClick.total {"val":false,"ack":true,"ts":1552854995770,"q":0,"from":"system.adapter.backitup.0","user":"system.user.admin","lc":1552854995770}
2019-03-17 21:36:36.187	debug: backitup.0 redis pmessage io.*.logging io.system.adapter.admin.0.logging {"val":false,"ack":true,"ts":1552854996180,"q":0,"from":"system.host.ioBroker01","lc":1552854976926}
2019-03-17 21:36:36.187	debug: backitup.0 system.adapter.admin.0: logging false
2019-03-17 21:36:36.563	info: host.ioBroker01 instance system.adapter.backitup.0 terminated with code 0 (OK)
2019-03-17 21:37:43.428	info: host.ioBroker01 instance system.adapter.backitup.0 started with pid 1733
2019-03-17 21:37:45.022	debug: backitup.0 objectDB connected
2019-03-17 21:37:45.058	debug: backitup.0 statesDB connected
2019-03-17 21:37:45.065	info: backitup.0 States connected to redis: 127.0.0.1:6379
2019-03-17 21:37:45.187	info: backitup.0 starting. Version 1.1.3 in /opt/iobroker/node_modules/iobroker.backitup, node: v8.15.0
2019-03-17 21:37:45.276	info: backitup.0 [ccu] backup was activated at 03:30 every 1 day(s)
2019-03-17 21:37:45.319	debug: backitup.0 [ccu] 10 30 03 */1 * *
2019-03-17 21:37:45.319	info: backitup.0 [total] backup was activated at 03:10 every 1 day(s)
2019-03-17 21:37:45.326	debug: backitup.0 [total] 10 10 03 */1 * *
2019-03-17 21:37:45.388	debug: backitup.0 mount activ... umount in 2 Seconds!!

[simatec]

@Donsen64
Machst du einen iobroker stop bei dem Total backup?
Wenn ja, schalte das mal aus und aktiviere auch mal debug in den Haupteinstellungen von backitup.
Dann sehen wir mehr

[Donsen64]

Ich glaube jetzt ist er total bockig mit mir. Bis jetzt ging es immer wenn ich "ioBroker Stop bei einem Backup" ausgeschaltet habe. Aber jetzt mach er da auch nichts. Das debug hatte ich schon eingeschaltet.

Nach dem LOG fehlen ihm jetzt scheinbar Rechte.

2019-03-17 21:47:12.020	debug: backitup.0 redis pmessage io.*.logging io.system.adapter.admin.0.logging {"val":false,"ack":true,"ts":1552855632005,"q":0,"from":"system.adapter.admin.0","lc":1552855632005}
2019-03-17 21:47:12.025	debug: backitup.0 system.adapter.admin.0: logging false
2019-03-17 21:56:27.871	debug: backitup.0 redis pmessage messagebox.system.adapter.backitup.0 messagebox.system.adapter.backitup.0 {"command":"getTelegramUser","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":8,"ack":false,"time":1552856187870},"_id":24096401}
2019-03-17 21:56:41.353	info: host.ioBroker01 object change system.adapter.backitup.0
2019-03-17 21:56:41.356	info: host.ioBroker01 stopInstance system.adapter.backitup.0
2019-03-17 21:56:41.356	info: host.ioBroker01 stopInstance system.adapter.backitup.0 killing pid 1733
2019-03-17 21:56:41.878	info: host.ioBroker01 instance system.adapter.backitup.0 terminated with code 0 (OK)
2019-03-17 21:56:43.877	info: host.ioBroker01 instance system.adapter.backitup.0 started with pid 2876
2019-03-17 21:56:45.237	debug: backitup.0 objectDB connected
2019-03-17 21:56:45.268	debug: backitup.0 statesDB connected
2019-03-17 21:56:45.274	info: backitup.0 States connected to redis: 127.0.0.1:6379
2019-03-17 21:56:45.371	info: backitup.0 starting. Version 1.1.3 in /opt/iobroker/node_modules/iobroker.backitup, node: v8.15.0
2019-03-17 21:56:45.462	info: backitup.0 [ccu] backup was activated at 03:30 every 1 day(s)
2019-03-17 21:56:45.493	debug: backitup.0 [ccu] 10 30 03 */1 * *
2019-03-17 21:56:45.494	info: backitup.0 [total] backup was activated at 03:10 every 1 day(s)
2019-03-17 21:56:45.500	debug: backitup.0 [total] 10 10 03 */1 * *
2019-03-17 21:56:51.773	debug: backitup.0 redis pmessage io.backitup.0.oneClick.* io.backitup.0.oneClick.total {"val":true,"ack":false,"ts":1552856211770,"q":0,"from":"system.adapter.admin.0","user":"system.user.admin","lc":1552856211770}
2019-03-17 21:57:03.048	debug: backitup.0 [total/mount] done
2019-03-17 21:57:03.062	debug: backitup.0 [total/total] TAR started...
2019-03-17 21:57:03.065	debug: backitup.0 [total/total] Ignore dir: /opt/iobroker/backups
2019-03-17 21:57:03.099	debug: backitup.0 [total/total] [IGNORED] Error: EACCES: permission denied, open '/opt/iobroker/backups/total_2019_03_17-21_57_03_backupiobroker.tar.gz'
2019-03-17 21:57:03.161	debug: backitup.0 [total/cifs] done
2019-03-17 21:57:03.174	debug: backitup.0 [total/clean] Backup files not deleted from /opt/iobroker/backups because some errors.
2019-03-17 21:57:03.175	debug: backitup.0 [total/clean] done
2019-03-17 21:57:03.192	debug: backitup.0 [total/email] [total] used E-Mail-Instance: email.0
2019-03-17 21:57:03.196	debug: backitup.0 sendTo "send" to system.adapter.email.0 from system.adapter.backitup.0
2019-03-17 21:57:03.198	debug: backitup.0 [total/email] done
2019-03-17 21:57:03.221	debug: backitup.0 [total/history] done
2019-03-17 21:57:03.246	debug: backitup.0 [total/umount] mount activ... umount in 60 Seconds!!
2019-03-17 21:58:03.483	debug: backitup.0 [total/umount] done
2019-03-17 21:58:03.485	debug: backitup.0 [total] exec: done
2019-03-17 21:58:03.493	debug: backitup.0 redis pmessage io.backitup.0.oneClick.* io.backitup.0.oneClick.total {"val":false,"ack":true,"ts":1552856283491,"q":0,"from":"system.adapter.backitup.0","user":"system.user.admin","lc":1552856283491}
2019-03-17 21:59:53.210	debug: backitup.0 redis pmessage io.backitup.0.oneClick.* io.backitup.0.oneClick.total {"val":true,"ack":false,"ts":1552856393208,"q":0,"from":"system.adapter.admin.0","user":"system.user.admin","lc":1552856393208}
2019-03-17 22:00:03.498	debug: backitup.0 [total/mount] done
2019-03-17 22:00:03.503	debug: backitup.0 [total/total] TAR started...
2019-03-17 22:00:03.504	debug: backitup.0 [total/total] Ignore dir: /opt/iobroker/backups
2019-03-17 22:00:03.514	debug: backitup.0 [total/total] [IGNORED] Error: EACCES: permission denied, open '/opt/iobroker/backups/total_2019_03_17-22_00_03_backupiobroker.tar.gz'
2019-03-17 22:00:03.525	debug: backitup.0 [total/cifs] done
2019-03-17 22:00:03.532	debug: backitup.0 [total/clean] Backup files not deleted from /opt/iobroker/backups because some errors.
2019-03-17 22:00:03.532	debug: backitup.0 [total/clean] done
2019-03-17 22:00:03.548	debug: backitup.0 [total/email] [total] used E-Mail-Instance: email.0
2019-03-17 22:00:03.549	debug: backitup.0 sendTo "send" to system.adapter.email.0 from system.adapter.backitup.0
2019-03-17 22:00:03.556	debug: backitup.0 [total/email] done
2019-03-17 22:00:03.573	debug: backitup.0 [total/history] done
2019-03-17 22:00:03.593	debug: backitup.0 [total/umount] mount activ... umount in 60 Seconds!!
2019-03-17 22:01:03.888	debug: backitup.0 [total/umount] done
2019-03-17 22:01:03.890	debug: backitup.0 [total] exec: done
2019-03-17 22:01:03.895	debug: backitup.0 redis pmessage io.backitup.0.oneClick.* io.backitup.0.oneClick.total {"val":false,"ack":true,"ts":1552856463892,"q":0,"from":"system.adapter.backitup.0","user":"system.user.admin","lc":1552856463892}
2019-03-17 22:03:55.096	debug: backitup.0 redis pmessage io.*.logging io.system.adapter.admin.0.logging {"val":true,"ack":true,"ts":1552856635094,"q":0,"from":"system.adapter.admin.0","lc":1552856635094}
2019-03-17 22:03:55.097	debug: backitup.0 system.adapter.admin.0: logging true

[Donsen64]

Kann es sein, dass er schlicht eine Altlast unter "/opt/iobroker/backups/total_2019_03_17-21_57_03_backupiobroker.tar.gz" hat an die er wegen fehlender Rechte nicht ran kommt. Habe mal nachgesehen, da ist diese Datei vorhanden. Wird denn dort nicht das NAS System eingebunden?

[Donsen64]

Heute Nacht habe ich für jede Sicherung eine Fehlermeldungen bekommen:

BackItUp:
Das Backup wurde nicht vollständig erstellt. Bitte überprüfe die Fehlermeldungen!!

total: Error: EACCES: permission denied, open '/opt/iobroker/backups/total_2019_03_18-03_10_43_backupiobroker.tar.gz'

BackItUp:
Das Backup wurde nicht vollständig erstellt. Bitte überprüfe die Fehlermeldungen!!

ccu: Cannot write file: Error: EACCES: permission denied, open '/opt/iobroker/backups/homematic_2019_03_18-03_31_33_3.43.15.20190223_backupiobroker.tar.sbk'

Er hat auch weder die CCU noch die Gesamtsicherung durchgeführt.

Gruß Ingo

[simatec]

So wie ich das sehe, fehlen dir die Schreibrechte auf deinem NAS.
Du kannst zwar den mount setzen, aber schreiben kann er nicht.

Schaue dir aber auch mal die Rechte des lokalen Pfads /opt/iobroker/backups an.

[Donsen64]

Hier die eingestellten Rechte:

pi@ioBroker01:/opt/iobroker $ ls -l
insgesamt 68
drwxrwxrwx+   2 iobroker iobroker  4096 Mär 17 16:25 backups
drwxrwxrwx+   2 iobroker iobroker  4096 Mär 30  2018 etc
-rwxrwxrwx+   1 iobroker iobroker   104 Mär 17 16:15 INSTALLER_INFO.txt
-rwxrwxrwx+   1 iobroker iobroker   297 Apr  1  2018 install.sh
lrwxrwxrwx    1 iobroker iobroker    22 Mär 17 16:15 iob -> /opt/iobroker/iobroker
-rwxr-xr-x+   1 iobroker iobroker   226 Mär 17 16:15 iobroker
drwxrwxrwx+   5 iobroker iobroker  4096 Apr  1  2018 iobroker-data
-rwxrwxrwx+   1 iobroker iobroker    92 Sep 28 21:04 killall.sh
drwxrwxrwx+   2 iobroker iobroker  4096 Mär 18 01:36 log
drwxrwxrwx+ 563 iobroker iobroker 20480 Mär 17 22:23 node_modules
-rwxrwxrwx+   1 iobroker iobroker  1269 Mär 17 22:23 package.json
-rwxrwxrwx+   1 iobroker iobroker  5868 Feb 16 20:02 reinstall.js
-rwxrwxrwx+   1 iobroker iobroker   709 Sep 28 21:04 reinstall.sh

pi@ioBroker01:/opt/iobroker/backups $ ls -l
insgesamt 176708
-rw-rw-rw-+ 1 iobroker iobroker 180948522 Mär 17 16:24 total_2019_03_17-16_19_44_backupiobroker.tar.gz

Sind die wie erwartet?

Bei meinem NAS hat sich nichts geändert. Hängt es eventuell mit der Ausführung vom folgenden zusammen:

pi@ioBroker01:~ $ sudo systemctl stop iobroker
pi@ioBroker01:~ $
pi@ioBroker01:~ $ curl -sL https://raw.githubusercontent.com/ioBroker/ioBroker/stable-installer/fix_installation.sh | bash -

[simatec]

Führe mal den aktuellen Fix aus.

iobroker stop
curl -sL https://iobroker.net/fix.sh | bash -
sudo reboot

[Donsen64]

Ich konnte das Problem finden. Die Rechte auf dem NAS waren schuld an dem Problem. Allerdings kann ich mir nicht erklären warum es erst ging und dann plötzlich Probleme aufgetreten sind. Am NAS wurde nichts geändert. Ich kann mir nur vorstellen, dass durch ändern der Rechte auf dem ioBroker die NAS Rechte nicht weiter dazu gepasst haben.

Ich habe bei allen Backup Verzeichnissen auf dem NAS die Rechte auf Everyone Vollzugriff gestellt. danach hat das Backup funktioniert.

Jetzt funktioniert das Backup auch bei aktiver Option ioBroker Stop bei einem Backup. Scheinbar hatte er sonst Probleme die logs.txt zu schreiben.

Nochmals Danke an alle die geholfen haben!

[simatec]

@Donsen64
Und wieder einmal war backitup nicht schuld 😬

[rascal]

Hallo,
ich habe seit ein paar Tagen auch das Problem, dass der Backup-Adapter keine Backups mehr anlegt oder die angelegten Backups beim Tab Wiederherstellen anzeigt.

Ich bin draufgekommen, wenn ich unter NAS/COPY das Hackerl beim "Mount als root" wegnehme, speichere, das Hackerl wieder setze und wieder speichere der Adapter wieder wie gewohnt funktioniert.

Er macht dann auch brav für ein paar Tage die verschiedenen Backups in der Nacht, bis es wieder nicht geht.
Im Moment habe ich keine Ahnung warum, die NAS wird nicht neu gestartet, der Ubuntu Rechner mit dem Iobroker auch nur selten.
Ich werde das Ganze weiter beobachten.
Danke für die Option der Benachrichtigungen über Telegram, sonst würde ich die Ausfälle gar nicht mitbekommen!

[simatec]

@rascal
Um genauer zu schauen, wäre ein Log im Debug Modus wichtig ...

[rascal]

Hi,
heute hat er ein CCU backup gemacht, aber kein Minimalbackup.
Den restlichen Tag hat er mir keinen Eintrag vom Backup Adapter gezeigt.

.
2019-04-27 01:30:21.774 - debug: backitup.0 [ccu/mount] done
2019-04-27 01:30:21.935 - debug: backitup.0 [ccu/ccu] CCU Version: 3.45.5.20190330
2019-04-27 01:30:21.938 - debug: backitup.0 [ccu/ccu] Requesting backup from CCU
2019-04-27 01:30:40.207 - debug: backitup.0 [ccu/ccu] done
2019-04-27 01:30:40.212 - debug: backitup.0 [ccu/cifs] done
2019-04-27 01:30:40.224 - debug: backitup.0 [ccu/clean] delete /opt/iobroker/backups/homematic_2019_04_10-01_30_38_3.45.5.20190330_backupiobroker.tar.sbk
2019-04-27 01:30:40.242 - debug: backitup.0 [ccu/clean] done
2019-04-27 01:30:40.250 - debug: backitup.0 sendTo "send" to system.adapter.telegram.0 from system.adapter.backitup.0
2019-04-27 01:30:40.251 - debug: backitup.0 [ccu/telegram] done
2019-04-27 01:30:40.280 - debug: backitup.0 [ccu/history] backitup.0.history.html
2019-04-27 01:30:40.305 - debug: backitup.0 [ccu/umount] mount activ... umount in 60 Seconds!!
2019-04-27 01:31:40.386 - debug: backitup.0 [ccu/umount] done
2019-04-27 01:31:40.388 - debug: backitup.0 [ccu] exec: done
.
2019-04-27 02:00:21.487 - debug: backitup.0 [minimal/mount] done
.
2019-04-27 11:43:36.940 - debug: backitup.0 system.adapter.admin.0: logging true
.

[rascal]

Hey,
ich wieder. Heute hat der Adapter überhaupt kein Backup gemacht. In der Nacht hat iobroker auch keine Logeinträge (01:30 und 02:00 wenn die Backups stattfinden sollten).

Er schreibt ab 09:09 was ins Log, auch, dass er was an Telegram sendet, ich habe aber nichts bekommen.

.
2019-04-28 09:09:32.278 - debug: backitup.0 [minimal/minimal] done
2019-04-28 09:09:32.282 - debug: backitup.0 [minimal/cifs] done
2019-04-28 09:09:32.287 - debug: backitup.0 [minimal/clean] done
.
2019-04-28 09:09:32.329 - debug: backitup.0 sendTo "send" to system.adapter.telegram.0 from system.adapter.backitup.0
2019-04-28 09:09:32.329 - debug: backitup.0 [minimal/telegram] done
.
2019-04-28 09:09:32.560 - debug: backitup.0 system.adapter.admin.0: logging false
.
2019-04-28 09:12:09.146 - debug: backitup.0 objectDB connected
2019-04-28 09:12:09.176 - debug: backitup.0 statesDB connected
2019-04-28 09:12:09.243 - info: backitup.0 starting. Version 1.1.4 in /opt/iobroker/node_modules/iobroker.backitup, node: v8.16.0
2019-04-28 09:12:09.309 - info: backitup.0 [minimal] backup was activated at 02:00 every 1 day(s)
2019-04-28 09:12:09.328 - info: backitup.0 [ccu] backup was activated at 01:30 every 1 day(s)
2019-04-28 09:12:09.334 - info: backitup.0 [total] backup was activated at 03:00 every 7 day(s)
2019-04-28 09:12:09.337 - debug: backitup.0 mount activ... umount in 2 Seconds!!
.
2019-04-28 12:22:48.696 - debug: backitup.0 system.adapter.admin.0: logging false
.
2019-04-28 12:26:04.783 - debug: backitup.0 objectDB connected
2019-04-28 12:26:04.811 - debug: backitup.0 statesDB connected
.
2019-04-28 12:58:06.671 - debug: backitup.0 system.adapter.admin.0: logging true
.