Gelöst: Backitup Komplett Backup KEIN Backup bei aktiver Option ioBroker Stop bei einem Backup

Donsen64

Hallo,
Wenn ich bei meinem Backitup Adapter (Version 1.1.3) unter Komplett Backup die Option "ioBroker Stop bei einem Backup" aktiviere wird kein Backup durchgeführt. Ich habe das LOG in den Debug Modus versetzt. Aber es sind nicht wirklich Meldungen im LOG.
Wenn ich die Option "ioBroker Stop bei einem Backup" abschalte funktioniert alles ohne Probleme.

Hier die drei Meldungen die ich im LOG sehe:

2019-03-16 16:23:57.924 - debug: backitup.0 [total] exec: done
2019-03-16 16:23:57.934 - debug: backitup.0 redis pmessage io.backitup.0.oneClick.* io.backitup.0.oneClick.total {"val":false,"ack":true,"ts":1552749837928,"q":0,"from":"system.adapter.backitup.0","user":"system.user.admin","lc":1552749837928}

Hat jemand eine Idee woran das liegen könnte?
Außerdem frage ich mich ob es wirklich notwendig ist die Option "ioBroker Stop bei einem Backup" zu aktivieren?

Der Backitup Adapter hat die Version 1.1.3, der js-controller hat die Version 1.5.7 und ioBroker.admin ist auf Version 3.6.0. Auch alle anderen Installierten Adapter sind auf dem neusten Stand.

Gruß Ingo

simatec

@Donsen64

Hast du dein iobroker mit dem neuen Installer installiert und wenn ja, hast du den Fix des Installers drüber laufen lassen?

Dann sollte auch der stop gehen.
Aber grundsätzlich sollte es auch ohne stop gehen.
Wenn du redis nutzt, dann brauchst du definitiv nicht stoppen

Donsen64

Also ich habe als erstes die Grundinstallation vom Raspberry PI 3 durchgeführt und dann den ioBroker über den folgenden Aufruf installiert:

curl -sL https://iobroker.net/install.sh | bash -

Ich bin daher mal deinem Rat gefolgt und habe folgendes gemacht:

pi@ioBroker01:~ $ sudo systemctl stop iobroker
pi@ioBroker01:~ $
pi@ioBroker01:~ $ curl -sL https://raw.githubusercontent.com/ioBroker/ioBroker/stable-installer/fix_installation.sh | bash -
==========================================================================
    Welcome to the ioBroker installation fixer!
    Script version: 2019-03-10

    You might need to enter your password a couple of times.
==========================================================================

==========================================================================
    Installing prerequisites (1/3)
==========================================================================
Installed acl
Installed libpam0g-dev
==========================================================================
    Checking ioBroker user and directory permissions (2/3)
==========================================================================
User iobroker created
Created /etc/sudoers.d/iobroker
Fixing directory permissions...
==========================================================================
    Checking autostart (3/3)
==========================================================================
Enabling autostart...
Created symlink /etc/systemd/system/multi-user.target.wants/iobroker.service → /lib/systemd/system/iobroker.service.
Autostart enabled!
==========================================================================
    Your installation was fixed successfully
    Run iobroker start to start ioBroker again!
==========================================================================
pi@ioBroker01:~ $  iobroker start

Danach macht er wesentlich mehr. Hat aber scheinbar Probleme mit dem mount auf der NAS. Hat jemand eine Idee woran das liegen könnte? Übrigens behauptet er, dass er das Backup erfolgreich abgeschlossen hat.

17. Maerz 2019 um 16:25 Uhr - Typ: total - NFS / CIFS-Mount: Ja 
17. Maerz 2019 um 03:31 Uhr - Typ: ccu - NFS / CIFS-Mount: Ja

Gruß Ingo

2019-03-17 03:10:10.075	debug: backitup.0 [total] exec: done
2019-03-17 03:10:10.083	debug: backitup.0 redis pmessage io.backitup.0.oneClick.* io.backitup.0.oneClick.total {"val":true,"ack":true,"ts":1552788610077,"q":0,"from":"system.adapter.backitup.0","user":"system.user.admin","lc":1552788610077}
2019-03-17 03:10:10.084	debug: backitup.0 redis pmessage io.backitup.0.oneClick.* io.backitup.0.oneClick.total {"val":false,"ack":true,"ts":1552788610079,"q":0,"from":"system.adapter.backitup.0","user":"system.user.admin","lc":1552749837928}
2019-03-17 03:30:11.545	debug: backitup.0 redis pmessage io.backitup.0.oneClick.* io.backitup.0.oneClick.ccu {"val":true,"ack":true,"ts":1552789811535,"q":0,"from":"system.adapter.backitup.0","user":"system.user.admin","lc":1552789811535}
2019-03-17 03:30:45.421	debug: backitup.0 [ccu/mount] done
2019-03-17 03:30:45.610	debug: backitup.0 [ccu/ccu] CCU Version: 3.43.15.20190223
2019-03-17 03:30:45.610	debug: backitup.0 [ccu/ccu] Requesting backup from CCU
2019-03-17 03:31:59.290	debug: backitup.0 [ccu/ccu] done
2019-03-17 03:31:59.296	debug: backitup.0 [ccu/cifs] done
2019-03-17 03:31:59.316	debug: backitup.0 [ccu/clean] delete /opt/iobroker/backups/homematic_2019_02_25-03_31_31_3.41.11.20181222_backupiobroker.tar.sbk
2019-03-17 03:31:59.352	debug: backitup.0 [ccu/clean] done
2019-03-17 03:31:59.361	debug: backitup.0 [ccu/email] [ccu] used E-Mail-Instance: email.0
2019-03-17 03:31:59.368	debug: backitup.0 [ccu/email] done
2019-03-17 03:31:59.387	debug: backitup.0 [ccu/history] done
2019-03-17 03:31:59.431	debug: backitup.0 [ccu/umount] mount activ... umount in 60 Seconds!!
2019-03-17 03:32:59.737	debug: backitup.0 [ccu/umount] done
2019-03-17 03:32:59.740	debug: backitup.0 [ccu] exec: done
2019-03-17 03:32:59.753	debug: backitup.0 redis pmessage io.backitup.0.oneClick.* io.backitup.0.oneClick.ccu {"val":false,"ack":true,"ts":1552789979748,"q":0,"from":"system.adapter.backitup.0","user":"system.user.admin","lc":1552789979748}
2019-03-17 16:14:47.366	info: host.ioBroker01 stopInstance system.adapter.backitup.0
2019-03-17 16:14:47.366	info: host.ioBroker01 stopInstance system.adapter.backitup.0 killing pid 27610
2019-03-17 16:14:47.595	debug: backitup.0 redis pmessage io.*.logging io.system.adapter.admin.0.logging {"val":false,"ack":true,"ts":1552835687594,"q":0,"from":"system.host.ioBroker01","lc":1552772528089}
2019-03-17 16:14:47.596	debug: backitup.0 system.adapter.admin.0: logging false
2019-03-17 16:14:47.894	info: host.ioBroker01 instance system.adapter.backitup.0 terminated with code 0 (OK)
2019-03-17 16:18:49.982	info: host.ioBroker01 instance system.adapter.backitup.0 started with pid 15483
2019-03-17 16:18:51.633	debug: backitup.0 objectDB connected
2019-03-17 16:18:51.671	debug: backitup.0 statesDB connected
2019-03-17 16:18:51.678	info: backitup.0 States connected to redis: 127.0.0.1:6379
2019-03-17 16:18:51.819	info: backitup.0 starting. Version 1.1.3 in /opt/iobroker/node_modules/iobroker.backitup, node: v8.15.0
2019-03-17 16:18:51.927	info: backitup.0 [ccu] backup was activated at 03:30 every 1 day(s)
2019-03-17 16:18:51.971	debug: backitup.0 [ccu] 10 30 03 */1 * *
2019-03-17 16:18:51.971	info: backitup.0 [total] backup was activated at 03:10 every 1 day(s)
2019-03-17 16:18:51.979	debug: backitup.0 [total] 10 10 03 */1 * *
2019-03-17 16:19:30.304	debug: backitup.0 redis pmessage io.backitup.0.oneClick.* io.backitup.0.oneClick.total {"val":true,"ack":false,"ts":1552835970302,"q":0,"from":"system.adapter.admin.0","user":"system.user.admin","lc":1552835970302}
2019-03-17 16:19:30.325	debug: backitup.0 [total] exec: done
2019-03-17 16:19:30.331	debug: backitup.0 redis pmessage io.backitup.0.oneClick.* io.backitup.0.oneClick.total {"val":false,"ack":true,"ts":1552835970328,"q":0,"from":"system.adapter.backitup.0","user":"system.user.admin","lc":1552835970328}
2019-03-17 16:19:30.762	debug: backitup.0 redis pmessage io.*.logging io.system.adapter.admin.0.logging {"val":false,"ack":true,"ts":1552835970755,"q":0,"from":"system.host.ioBroker01","lc":1552772528089}
2019-03-17 16:19:30.763	debug: backitup.0 system.adapter.admin.0: logging false
2019-03-17 16:19:31.083	info: host.ioBroker01 instance system.adapter.backitup.0 terminated with code 0 (OK)
2019-03-17 16:25:12.619	info: host.ioBroker01 instance system.adapter.backitup.0 started with pid 15971
2019-03-17 16:25:14.279	debug: backitup.0 objectDB connected
2019-03-17 16:25:14.316	debug: backitup.0 statesDB connected
2019-03-17 16:25:14.324	info: backitup.0 States connected to redis: 127.0.0.1:6379
2019-03-17 16:25:14.474	info: backitup.0 starting. Version 1.1.3 in /opt/iobroker/node_modules/iobroker.backitup, node: v8.15.0
2019-03-17 16:25:14.535	debug: backitup.0 Printing logs of previous backup
2019-03-17 16:25:14.536	error: backitup.0 [ERROR] [total/mount] [undefined mount: only root can do that
2019-03-17 16:25:14.538	error: backitup.0 [ERROR] [total/mount] [IGNORED] Error: Command failed: mount 192.168.1.1:/volume2/Backup-HD2/ioBroker01_Backup/Komplett /opt/iobroker/backups
2019-03-17 16:25:14.539	error: backitup.0 [ERROR] [total/mount] mount: only root can do that
2019-03-17 16:25:14.539	debug: backitup.0 [DEBUG] [total/total] TAR started...
2019-03-17 16:25:14.540	debug: backitup.0 [DEBUG] [total/total] Ignore dir: /opt/iobroker/backups
2019-03-17 16:25:14.541	debug: backitup.0 [DEBUG] [total/total] done
2019-03-17 16:25:14.541	debug: backitup.0 [DEBUG] [total/cifs] done
2019-03-17 16:25:14.542	error: backitup.0 [ERROR] [total/clean] Backup files not deleted from /opt/iobroker/backups because some errors.
2019-03-17 16:25:14.544	debug: backitup.0 [DEBUG] [total/clean] done
2019-03-17 16:25:14.545	debug: backitup.0 [DEBUG] [total/start] done
2019-03-17 16:25:14.546	debug: backitup.0 [DEBUG] [total/email] done
2019-03-17 16:25:14.547	debug: backitup.0 [DEBUG] [total/start] IoBroker started ...
2019-03-17 16:25:15.576	debug: backitup.0 [total/email] [total] used E-Mail-Instance: email.0
2019-03-17 16:25:15.579	debug: backitup.0 [total/email] done
2019-03-17 16:25:15.594	info: backitup.0 [ccu] backup was activated at 03:30 every 1 day(s)
2019-03-17 16:25:15.633	debug: backitup.0 [ccu] 10 30 03 */1 * *
2019-03-17 16:25:15.634	info: backitup.0 [total] backup was activated at 03:10 every 1 day(s)
2019-03-17 16:25:15.642	debug: backitup.0 [total] 10 10 03 */1 * *
2019-03-17 16:25:15.729	debug: backitup.0 [total/history] done
2019-03-17 16:29:49.480	debug: backitup.0 redis pmessage io.*.logging io.system.adapter.admin.0.logging {"val":true,"ack":true,"ts":1552836589462,"q":0,"from":"system.adapter.admin.0","lc":1552836589462}
2019-03-17 16:29:49.487	debug: backitup.0 system.adapter.admin.0: logging true

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
.

Community Forum

NEWS

Gelöst: Backitup Komplett Backup KEIN Backup bei aktiver Option ioBroker Stop bei einem Backup

Support us

264

32.8k

82.8k

1.3m