BackitUP startet iobroker nicht neu

[simatec]

@martinschm

Installiere dir bitte den Fix des iobroker Installers.
Der behebt einige Probleme bei den Rechten des Users "iobroker".

https://forum.iobroker.net/topic/20211/iobroker-installation-fixer-beta-verfügbar

[martinschm]

@simatec said in BackitUP startet iobroker nicht neu:

@martinschm

Installiere dir bitte den Fix des iobroker Installers.
Der behebt einige Probleme bei den Rechten des Users "iobroker".

https://forum.iobroker.net/topic/20211/iobroker-installation-fixer-beta-verfügbar

Hi,

Danke für den Hinweis.

Hab ich gestern abend laufen lassen. Werde mal berichten ob es geholfen hat.

[martinschm]

Hi @simatec ,

Das Problem ist leider heute wieder aufgetreten.

Muss das Fixer Skript unter einem bestimmten User ausgeführt werden ?
Hab mich glaube ich als root eingeloggt.

Ciao
Martin

[AlCalzone]

@martinschm sagte in BackitUP startet iobroker nicht neu:

Muss das Fixer Skript unter einem bestimmten User ausgeführt werden ?

Nein. Wenn du es nicht als root ausführst, führt es die Befehle automatisch mit sudo aus.

[martinschm]

Ich hab jetzt bei BackitUp die Telegram Benachrichtigung aktiviert.

Das waren aus Ausgaben auf Telegram heute.

02:00.45 
BackItUp:
Ein neues minimal Backup wurde am 08. Mai 2019 um 02:00 Uhr erstellt und in der Dropbox gespeichert.

05:03:39
Ich bin ma wech Chef

05:04:04
Moin Chef

06:30:09
Ich bin ma wech Chef

"Moin Chef" und "Ich bin ma wech Chef" sind die Ausgaben die ich im Telegram Adapter hinterlegt habe.

Um 05:03 scheint der "normale" Telegram Restart zu sein.
Ich dachte immer, das um 6:30 das große Backup läuft. Dazu kommt allerdings keine Meldung. Danach ist iobroker auch immer aus und ich muß per Console neustarten.

[martinschm]

Noch als Ergänzung. 6:30 ist genau die Zeit die für das große Backup eingestellt ist. Habe grade aber mal auf Dropbox geschaut, dort kommt kein großes Backup an.

Kann es sein, das der komplette Prozess gar nicht ausgeführt wird und damit dann auch der Restart nicht ?

Gibt es irgendwelche Logs die ich bereitstellen kann um das Problem einzugrenzen ?

[simatec]

@martinschm
Bitte die Instanz von backitup auf debug stellen und den Log vom backupprozess posten.
Stoppst du iobroker beim total backup?

[martinschm]

@simatec said in BackitUP startet iobroker nicht neu:

@martinschm
Bitte die Instanz von backitup auf debug stellen und den Log vom backupprozess posten.
Stoppst du iobroker beim total backup?

Habs jetzt mal auf debug gestellt und ja ich ab iobroker stoppen lassen. Kann ich eigentlich den Wochentag einstellen wann das große Backup gemacht werden soll?

[simatec]

@martinschm
Nein nur alle x Tage ...

[martinschm]

Hi,
hab dann bis heute gewartet und das Problem trat erneut auf. Anbei das Log

2019-05-15 02:00:10.056  - debug: backitup.0 redis pmessage  io.backitup.0.oneClick.* io.backitup.0.oneClick.minimal {"val":true,"ack":true,"ts":1557878410029,"q":0,"from":"system.adapter.backitup.0","lc":1557878410029}
2019-05-15 02:00:17.854  - debug: backitup.0 [minimal/minimal] Backup created: /opt/iobroker/backups/minimal_2019_05_15-02_00_10_rock64_backupiobroker.tar.gz
2019-05-15 02:00:17.900  - debug: backitup.0 [minimal/minimal] done
2019-05-15 02:00:17.941  - debug: backitup.0 [minimal/redis] done
2019-05-15 02:00:17.946  - debug: backitup.0 [minimal/dropbox] Copy minimal_2019_05_15-02_00_10_rock64_backupiobroker.tar.gz...
2019-05-15 02:00:47.739  - debug: backitup.0 [minimal/dropbox] Copy redis_2019_05_15-02_00_17_backupiobroker.tar.gz...
2019-05-15 02:00:49.446  - debug: backitup.0 [minimal/dropbox] delete /backupDir/minimal/minimal_2019_05_10-02_00_10_rock64_backupiobroker.tar.gz
2019-05-15 02:00:50.200  - debug: backitup.0 [minimal/dropbox] delete /backupDir/minimal/redis_2019_05_10-02_00_17_backupiobroker.tar.gz
2019-05-15 02:00:50.952  - debug: backitup.0 [minimal/dropbox] done
2019-05-15 02:00:50.957  - debug: backitup.0 [minimal/clean] delete /opt/iobroker/backups/minimal_2019_05_10-02_00_10_rock64_backupiobroker.tar.gz
2019-05-15 02:00:50.962  - debug: backitup.0 [minimal/clean] delete /opt/iobroker/backups/redis_2019_05_10-02_00_17_backupiobroker.tar.gz
2019-05-15 02:00:50.963  - debug: backitup.0 [minimal/clean] done
2019-05-15 02:00:50.976  - debug: backitup.0 sendTo "send" to system.adapter.telegram.0 from system.adapter.backitup.0
2019-05-15 02:00:50.977  - debug: backitup.0 [minimal/telegram] done
2019-05-15 02:00:51.004  - debug: backitup.0 [minimal/history] done
2019-05-15 02:00:51.007  - debug: backitup.0 [minimal] exec: done
2019-05-15 02:00:51.012  - debug: backitup.0 redis pmessage  io.backitup.0.oneClick.* io.backitup.0.oneClick.minimal {"val":false,"ack":true,"ts":1557878451010,"q":0,"from":"system.adapter.backitup.0","lc":1557878451010}
2019-05-15 05:04:00.027  - info: telegram.0 Scheduled restart.
2019-05-15 05:04:00.536  - info: telegram.0 terminating
2019-05-15 05:04:00.570  - error: host.DietPi instance system.adapter.telegram.0 terminated with code 156 ()
2019-05-15 05:04:00.571  - info: host.DietPi Restart adapter system.adapter.telegram.0 because enabled
2019-05-15 05:04:01.603  - info: host.DietPi instance system.adapter.telegram.0 started with pid 22127
2019-05-15 05:04:04.441  - info: telegram.0 States connected to redis: 127.0.0.1:6379
2019-05-15 05:04:04.670  - info: telegram.0 starting. Version 1.4.3 in /opt/iobroker/node_modules/iobroker.telegram, node: v8.15.1
2019-05-15 06:30:10.636  - debug: backitup.0 [total] exec: done
2019-05-15 06:30:10.642  - debug: backitup.0 redis pmessage  io.backitup.0.oneClick.* io.backitup.0.oneClick.total {"val":true,"ack":true,"ts":1557894610639,"q":0,"from":"system.adapter.backitup.0","lc":1557894610639}
2019-05-15 06:30:10.644  - debug: backitup.0 redis pmessage  io.backitup.0.oneClick.* io.backitup.0.oneClick.total {"val":false,"ack":true,"ts":1557894610640,"q":0,"from":"system.adapter.backitup.0","lc":1554638192550}
2019-05-15 06:30:10.737  - info: host.DietPi received SIGTERM
2019-05-15 06:30:10.779  - info: javascript.0 Stop script script.js.common.Alarmanlage.GartentorAlarm
2019-05-15 06:30:10.787  - info: hm-rpc.2 xmlrpc -> 192.168.1.181:8701/ init ["http://192.168.1.190:8701",""]
2019-05-15 06:30:10.754  - info: hm-rpc.1 xmlrpc -> 192.168.1.181:2010/ init ["http://192.168.1.190:2010",""]
2019-05-15 06:30:10.828  - info: host.DietPi instance system.adapter.history.0 terminated with code 0 (OK)
2019-05-15 06:30:10.829  - info: hm-rpc.1 Disconnected
2019-05-15 06:30:10.769  - info: cloud.0 Connection changed: io client disconnect
2019-05-15 06:30:10.750  - info: simple-api.0 terminating http server on port 8087
2019-05-15 06:30:10.861  - info: simple-api.0 terminating http server on port 8087
2019-05-15 06:30:10.782  - info: hm-rpc.0 xmlrpc -> 192.168.1.181:2001/ init ["http://192.168.1.190:2001",""]
2019-05-15 06:30:10.766  - info: landroid-s.0 cleaned everything up...
2019-05-15 06:30:10.754  - info: admin.0 terminating http server on port 8081
2019-05-15 06:30:10.868  - info: hm-rpc.2 Disconnected
2019-05-15 06:30:10.889  - info: hm-rpc.1 xmlrpc -> 192.168.1.181:2010/ init ["http://192.168.1.190:2010",""]
2019-05-15 06:30:10.807  - info: socketio.0 terminating http server on port 8084
2019-05-15 06:30:10.906  - info: hm-rpc.2 xmlrpc -> 192.168.1.181:8701/ init ["http://192.168.1.190:8701",""]
2019-05-15 06:30:10.778  - info: web.0 terminating http server on port 8082
2019-05-15 06:30:10.921  - info: host.DietPi instance system.adapter.iot.0 terminated with code 0 (OK)
2019-05-15 06:30:10.924  - info: hm-rpc.0 Disconnected
2019-05-15 06:30:10.785  - info: javascript.0 Stop script script.js.common.Kamera.Cam1BildonMotion
2019-05-15 06:30:10.807  - info: javascript.0 Stop script script.js.common.telegram.Lichtsteuerung
2019-05-15 06:30:10.808  - info: javascript.0 Stop script script.js.common.telegram.tastatur
2019-05-15 06:30:10.809  - info: javascript.0 Stop script script.js.common.Status_Checks.TemperaturCheck
2019-05-15 06:30:10.811  - info: javascript.0 Stop script script.js.common.telegram.Statustastatur
2019-05-15 06:30:10.812  - info: javascript.0 Stop script script.js.common.Status_Checks.RolladenCheck
2019-05-15 06:30:10.813  - info: javascript.0 Stop script script.js.common.Alarmanlage.Fensterueberwachung
2019-05-15 06:30:10.872  - info: javascript.0 Stop script script.js.common.Status_Checks.Fensterstatus
2019-05-15 06:30:10.875  - info: javascript.0 Stop script script.js.common.Alarmanlage.AlexaWerIstDa
2019-05-15 06:30:10.876  - info: javascript.0 Stop script script.js.common.FeuerWasserAlarm.Rauchmelder(1)
2019-05-15 06:30:10.877  - info: javascript.0 Stop script script.js.common.FeuerWasserAlarm.Wassermelder
2019-05-15 06:30:10.878  - info: javascript.0 Stop script script.js.common.Automatisierung.HaustuerLichtAn
2019-05-15 06:30:10.900  - info: javascript.0 Stop script script.js.common.telegram.Cam1Steuerung
2019-05-15 06:30:10.901  - info: javascript.0 Stop script script.js.common.Kamera.Cam2BildSendenOD
2019-05-15 06:30:10.902  - info: javascript.0 Stop script script.js.common.telegram.Kamerasteuerung
2019-05-15 06:30:10.936  - info: javascript.0 Stop script script.js.common.telegram.Rollosteuerung
2019-05-15 06:30:10.985  - info: hm-rpc.0 xmlrpc -> 192.168.1.181:2001/ init ["http://192.168.1.190:2001",""]
2019-05-15 06:30:10.996  - info: host.DietPi instance system.adapter.hm-rega.0 terminated with code 0 (OK)
2019-05-15 06:30:11.037  - info: host.DietPi instance system.adapter.cloud.0 terminated with code 0 (OK)
2019-05-15 06:30:11.154  - debug: backitup.0 redis pmessage  io.*.logging io.system.adapter.admin.0.logging {"val":false,"ack":true,"ts":1557894611150,"q":0,"from":"system.host.DietPi","lc":1557777905619}
2019-05-15 06:30:11.160  - debug: backitup.0 system.adapter.admin.0: logging false
2019-05-15 06:30:11.047  - info: host.DietPi instance system.adapter.hm-rpc.1 terminated with code 0 (OK)
2019-05-15 06:30:11.058  - info: host.DietPi instance system.adapter.alexa2.0 terminated with code 0 (OK)
2019-05-15 06:30:11.066  - info: host.DietPi instance system.adapter.simple-api.0 terminated with code 0 (OK)
2019-05-15 06:30:11.076  - info: host.DietPi instance system.adapter.admin.0 terminated with code 0 (OK)
2019-05-15 06:30:11.083  - info: host.DietPi instance system.adapter.mihome.0 terminated with code 0 (OK)
2019-05-15 06:30:11.092  - info: host.DietPi instance system.adapter.hm-rpc.0 terminated with code 0 (OK)
2019-05-15 06:30:11.100  - info: host.DietPi instance system.adapter.web.0 terminated with code 0 (OK)
2019-05-15 06:30:11.108  - info: host.DietPi instance system.adapter.hm-rpc.2 terminated with code 0 (OK)
2019-05-15 06:30:11.115  - info: host.DietPi instance system.adapter.socketio.0 terminated with code 0 (OK)
2019-05-15 06:30:11.122  - info: host.DietPi instance system.adapter.javascript.0 terminated with code 0 (OK)
2019-05-15 06:30:11.130  - info: host.DietPi instance system.adapter.landroid-s.0 terminated with code 0 (OK)
2019-05-15 06:30:11.316  - info: host.DietPi instance system.adapter.text2command.0 terminated with code 0 (OK)
2019-05-15 06:30:11.338  - info: host.DietPi instance system.adapter.backitup.0 terminated with code 0 (OK)
2019-05-15 06:30:11.361  - info: host.DietPi instance system.adapter.telegram.0 terminated with code 0 (OK)
2019-05-15 06:30:11.361  - info: host.DietPi All instances are stopped.
2019-05-15 06:30:11.402  - info: host.DietPi terminated
2019-05-15 20:49:42.632  - info: host.DietPi iobroker.js-controller version 1.4.2 js-controller starting
2019-05-15 20:49:42.643  - info: host.DietPi Copyright (c) 2014-2018 bluefox, 2014 hobbyquaker
2019-05-15 20:49:42.644  - info: host.DietPi hostname: DietPi, node: v8.15.1
2019-05-15 20:49:42.652  - info: host.DietPi ip addresses: 192.168.1.190 fe80::e4e5:86ff:fea3:12b1
2019-05-15 20:49:43.410  - info: host.DietPi  inMem-objects listening on port 9001
2019-05-15 20:49:43.455  - info: host.DietPi States connected to redis: 127.0.0.1:6379
2019-05-15 20:49:43.459  - info: host.DietPi InMemoryDB connected
2019-05-15 20:49:43.498  - info: host.DietPi 26 instances found
2019-05-15 20:49:43.575  - info: host.DietPi starting 21 instances
2019-05-15 20:49:43.653  - info: host.DietPi instance system.adapter.admin.0 started with pid 24312
2019-05-15 20:49:46.464  - info: admin.0 States connected to redis: 127.0.0.1:6379
2019-05-15 20:49:46.698  - error: admin.0 setObject id missing!!
2019-05-15 20:49:46.713  - info: admin.0 starting. Version 3.6.0 in /opt/iobroker/node_modules/iobroker.admin, node: v8.15.1
2019-05-15 20:49:46.756  - info: admin.0 requesting all states
2019-05-15 20:49:46.760  - info: admin.0 requesting all objects
2019-05-15 20:49:46.762  - info: admin.0 Request actual repository...
2019-05-15 20:49:47.932  - info: host.DietPi instance system.adapter.hm-rpc.0 started with pid 24333
2019-05-15 20:49:48.465  - info: admin.0 received all states
2019-05-15 20:49:49.998  - info: hm-rpc.0 States connected to redis: 127.0.0.1:6379
2019-05-15 20:49:50.247  - info: hm-rpc.0 starting. Version 1.9.11 in /opt/iobroker/node_modules/iobroker.hm-rpc, node: v8.15.1

Ich hoffe das bringt was zur Lösungsfindung.

[martinschm]

@simatec helfen die Logs irgendwie weiter?