NEWS
ioBroker (JS Controller) stürzt alle 6-8 Tage ab
-
Hi. Ich habe das gleiche Problem auf einem Raspi3. Alle 3-4 Tage stürzt auch bei mir der js-controller im Zeitpunkte des Backups (minimal) ab. Mit einer geänderten Backupzeit ist auch die Absturzzeit des js-controllers auch "gewandert". Das Komprimieren in gz scheint den Rechner dabei lahm zu legen.
Ich habe bei Backitup den Debug-Level auf silly gesetzt. Anbei die Ausgaben. Aus meiner Sicht ist nichts besonderes zu sehen. Außer das 2min nach dem Start des Backup der Raspi faxen macht...Wäre ein "nice -n 19" für das tar Kommando vielleicht eine Möglichkeit der Lösung?
2019-05-02 02:00:10.054 - silly: backitup.1 inMem message backitup.1.oneClick.* backitup.1.oneClick.minimal val=true, ack=true, ts=1556755210039, q=0, from=system.adapter.backitup.1, user=system.user.admin, lc=1556755210039 2019-05-02 02:00:20.242 - debug: backitup.1 [minimal/mount] done 2019-05-02 02:02:47.757 - debug: backitup.1 [minimal/minimal] done 2019-05-02 02:02:47.881 - silly: backitup.1 transport close 2019-05-02 02:02:47.916 - debug: backitup.1 [minimal/cifs] done 2019-05-02 02:02:47.923 - debug: backitup.1 [minimal/clean] done 2019-05-02 02:02:47.927 - debug: backitup.1 [minimal/telegram] [minimal] used Telegram-Instance: telegram.0 2019-05-02 02:02:47.937 - debug: backitup.1 sendTo "send" to system.adapter.telegram.0 from system.adapter.backitup.1 2019-05-02 02:02:47.937 - debug: backitup.1 [minimal/telegram] done 2019-05-02 02:02:48.101 - error: cloud.0 Ping timeout 2019-05-02 02:02:48.606 - warn: web.0 Reconnection to DB. 2019-05-02 02:02:48.710 - warn: web.0 Reconnection to DB. 2019-05-02 02:02:48.713 - warn: backitup.1 Reconnection to DB. 2019-05-02 02:02:48.636 - warn: sonos.0 Reconnection to DB. 2019-05-02 02:02:48.729 - warn: sonos.0 Reconnection to DB. 2019-05-02 02:02:48.710 - warn: admin.0 Reconnection to DB. 2019-05-02 02:02:48.741 - warn: backitup.1 Reconnection to DB. 2019-05-02 02:02:48.743 - warn: admin.0 Reconnection to DB. 2019-05-02 02:02:48.828 - warn: shelly.0 Reconnection to DB. 2019-05-02 02:02:48.881 - warn: shelly.0 Reconnection to DB. 2019-05-02 02:02:48.894 - debug: backitup.1 statesDB connected 2019-05-02 02:02:48.912 - debug: backitup.1 statesDB connected 2019-05-02 02:02:48.952 - warn: cloud.0 Reconnection to DB. 2019-05-02 02:02:48.930 - warn: sayit.0 Reconnection to DB. 2019-05-02 02:02:49.020 - warn: cloud.0 Reconnection to DB. 2019-05-02 02:02:49.045 - info: backitup.1 starting. Version 1.1.4 in /opt/iobroker/node_modules/iobroker.backitup, node: v8.15.1 2019-05-02 02:02:49.060 - warn: sayit.0 Reconnection to DB. 2019-05-02 02:02:49.011 - warn: deconz.0 Reconnection to DB. 2019-05-02 02:02:49.057 - warn: text2command.0 Reconnection to DB. 2019-05-02 02:02:49.163 - warn: deconz.0 Reconnection to DB. 2019-05-02 02:02:49.192 - warn: text2command.0 Reconnection to DB. 2019-05-02 02:02:49.319 - warn: tradfri.0 Reconnection to DB. 2019-05-02 02:02:49.475 - debug: backitup.1 mount activ... umount in 2 Seconds!! 2019-05-02 02:02:49.756 - warn: tradfri.0 Reconnection to DB. 2019-05-02 02:02:49.760 - info: backitup.1 [minimal] backup was activated at 02:00 every 1 day(s) 2019-05-02 02:02:49.755 - warn: telegram.0 Reconnection to DB. 2019-05-02 02:02:49.803 - debug: backitup.1 [minimal] 10 00 02 */1 * * 2019-05-02 02:02:49.803 - info: backitup.1 [total] backup was activated at 02:30 every 3 day(s) 2019-05-02 02:02:49.822 - debug: backitup.1 [total] 10 30 02 */3 * * 2019-05-02 02:02:49.965 - debug: backitup.1 [minimal/history] backitup.1.history.html 2019-05-02 02:02:49.977 - warn: telegram.0 Reconnection to DB. 2019-05-02 02:02:49.999 - info: admin.0 starting. Version 3.6.0 in /opt/iobroker/node_modules/iobroker.admin, node: v8.15.1 2019-05-02 02:02:50.001 - warn: javascript.0 Reconnection to DB. 2019-05-02 02:02:50.031 - info: backitup.1 starting. Version 1.1.4 in /opt/iobroker/node_modules/iobroker.backitup, node: v8.15.1 2019-05-02 02:02:50.067 - debug: backitup.1 [minimal/umount] mount activ... umount in 60 Seconds!! 2019-05-02 02:02:50.077 - debug: backitup.1 mount activ... umount in 2 Seconds!! 2019-05-02 02:02:50.186 - info: backitup.1 [minimal] backup was activated at 02:00 every 1 day(s) 2019-05-02 02:02:50.220 - info: admin.0 starting. Version 3.6.0 in /opt/iobroker/node_modules/iobroker.admin, node: v8.15.1 2019-05-02 02:02:50.241 - info: javascript.0 starting. Version 4.1.12 in /opt/iobroker/node_modules/iobroker.javascript, node: v8.15.1 2019-05-02 02:02:50.228 - info: admin.0 requesting all states 2019-05-02 02:02:50.229 - info: admin.0 requesting all objects 2019-05-02 02:02:50.231 - info: admin.0 Request actual repository... 2019-05-02 02:02:50.256 - debug: backitup.1 [minimal] 10 00 02 */1 * * 2019-05-02 02:02:50.257 - info: backitup.1 [total] backup was activated at 02:30 every 3 day(s) 2019-05-02 02:02:50.293 - debug: backitup.1 [total] 10 30 02 */3 * * 2019-05-02 02:02:50.303 - info: javascript.0 requesting all states 2019-05-02 02:02:50.305 - info: javascript.0 requesting all objects 2019-05-02 02:02:50.336 - info: javascript.0 starting. Version 4.1.12 in /opt/iobroker/node_modules/iobroker.javascript, node: v8.15.1 2019-05-02 02:02:50.340 - info: javascript.0 requesting all states 2019-05-02 02:02:50.341 - info: javascript.0 requesting all objects 2019-05-02 02:02:50.342 - warn: javascript.0 Reconnection to DB. 2019-05-02 02:02:50.462 - error: web.0 port 8082 already in use 2019-05-02 02:02:50.516 - error: host.homepi-client instance system.adapter.web.0 terminated with code 1 () 2019-05-02 02:02:50.517 - info: host.homepi-client Restart adapter system.adapter.web.0 because enabled 2019-05-02 02:02:50.683 - error: javascript.0 Longitude or latitude does not set. Cannot use astro. 2019-05-02 02:02:50.693 - error: javascript.0 Error in callback: TypeError: Cannot read property 'toLocaleTimeString' of undefined 2019-05-02 02:02:50.694 - error: javascript.0 at Object. (script.js.common.SK_Astro:346:47) ... -
Ok - Update: Habe einige Backups auf der Shell laufen lassen und dabei geht auch der js-controller irgendwann auf die Bretter:


-
Hi. Ich habe das gleiche Problem auf einem Raspi3. Alle 3-4 Tage stürzt auch bei mir der js-controller im Zeitpunkte des Backups (minimal) ab. Mit einer geänderten Backupzeit ist auch die Absturzzeit des js-controllers auch "gewandert". Das Komprimieren in gz scheint den Rechner dabei lahm zu legen.
Ich habe bei Backitup den Debug-Level auf silly gesetzt. Anbei die Ausgaben. Aus meiner Sicht ist nichts besonderes zu sehen. Außer das 2min nach dem Start des Backup der Raspi faxen macht...Wäre ein "nice -n 19" für das tar Kommando vielleicht eine Möglichkeit der Lösung?
2019-05-02 02:00:10.054 - silly: backitup.1 inMem message backitup.1.oneClick.* backitup.1.oneClick.minimal val=true, ack=true, ts=1556755210039, q=0, from=system.adapter.backitup.1, user=system.user.admin, lc=1556755210039 2019-05-02 02:00:20.242 - debug: backitup.1 [minimal/mount] done 2019-05-02 02:02:47.757 - debug: backitup.1 [minimal/minimal] done 2019-05-02 02:02:47.881 - silly: backitup.1 transport close 2019-05-02 02:02:47.916 - debug: backitup.1 [minimal/cifs] done 2019-05-02 02:02:47.923 - debug: backitup.1 [minimal/clean] done 2019-05-02 02:02:47.927 - debug: backitup.1 [minimal/telegram] [minimal] used Telegram-Instance: telegram.0 2019-05-02 02:02:47.937 - debug: backitup.1 sendTo "send" to system.adapter.telegram.0 from system.adapter.backitup.1 2019-05-02 02:02:47.937 - debug: backitup.1 [minimal/telegram] done 2019-05-02 02:02:48.101 - error: cloud.0 Ping timeout 2019-05-02 02:02:48.606 - warn: web.0 Reconnection to DB. 2019-05-02 02:02:48.710 - warn: web.0 Reconnection to DB. 2019-05-02 02:02:48.713 - warn: backitup.1 Reconnection to DB. 2019-05-02 02:02:48.636 - warn: sonos.0 Reconnection to DB. 2019-05-02 02:02:48.729 - warn: sonos.0 Reconnection to DB. 2019-05-02 02:02:48.710 - warn: admin.0 Reconnection to DB. 2019-05-02 02:02:48.741 - warn: backitup.1 Reconnection to DB. 2019-05-02 02:02:48.743 - warn: admin.0 Reconnection to DB. 2019-05-02 02:02:48.828 - warn: shelly.0 Reconnection to DB. 2019-05-02 02:02:48.881 - warn: shelly.0 Reconnection to DB. 2019-05-02 02:02:48.894 - debug: backitup.1 statesDB connected 2019-05-02 02:02:48.912 - debug: backitup.1 statesDB connected 2019-05-02 02:02:48.952 - warn: cloud.0 Reconnection to DB. 2019-05-02 02:02:48.930 - warn: sayit.0 Reconnection to DB. 2019-05-02 02:02:49.020 - warn: cloud.0 Reconnection to DB. 2019-05-02 02:02:49.045 - info: backitup.1 starting. Version 1.1.4 in /opt/iobroker/node_modules/iobroker.backitup, node: v8.15.1 2019-05-02 02:02:49.060 - warn: sayit.0 Reconnection to DB. 2019-05-02 02:02:49.011 - warn: deconz.0 Reconnection to DB. 2019-05-02 02:02:49.057 - warn: text2command.0 Reconnection to DB. 2019-05-02 02:02:49.163 - warn: deconz.0 Reconnection to DB. 2019-05-02 02:02:49.192 - warn: text2command.0 Reconnection to DB. 2019-05-02 02:02:49.319 - warn: tradfri.0 Reconnection to DB. 2019-05-02 02:02:49.475 - debug: backitup.1 mount activ... umount in 2 Seconds!! 2019-05-02 02:02:49.756 - warn: tradfri.0 Reconnection to DB. 2019-05-02 02:02:49.760 - info: backitup.1 [minimal] backup was activated at 02:00 every 1 day(s) 2019-05-02 02:02:49.755 - warn: telegram.0 Reconnection to DB. 2019-05-02 02:02:49.803 - debug: backitup.1 [minimal] 10 00 02 */1 * * 2019-05-02 02:02:49.803 - info: backitup.1 [total] backup was activated at 02:30 every 3 day(s) 2019-05-02 02:02:49.822 - debug: backitup.1 [total] 10 30 02 */3 * * 2019-05-02 02:02:49.965 - debug: backitup.1 [minimal/history] backitup.1.history.html 2019-05-02 02:02:49.977 - warn: telegram.0 Reconnection to DB. 2019-05-02 02:02:49.999 - info: admin.0 starting. Version 3.6.0 in /opt/iobroker/node_modules/iobroker.admin, node: v8.15.1 2019-05-02 02:02:50.001 - warn: javascript.0 Reconnection to DB. 2019-05-02 02:02:50.031 - info: backitup.1 starting. Version 1.1.4 in /opt/iobroker/node_modules/iobroker.backitup, node: v8.15.1 2019-05-02 02:02:50.067 - debug: backitup.1 [minimal/umount] mount activ... umount in 60 Seconds!! 2019-05-02 02:02:50.077 - debug: backitup.1 mount activ... umount in 2 Seconds!! 2019-05-02 02:02:50.186 - info: backitup.1 [minimal] backup was activated at 02:00 every 1 day(s) 2019-05-02 02:02:50.220 - info: admin.0 starting. Version 3.6.0 in /opt/iobroker/node_modules/iobroker.admin, node: v8.15.1 2019-05-02 02:02:50.241 - info: javascript.0 starting. Version 4.1.12 in /opt/iobroker/node_modules/iobroker.javascript, node: v8.15.1 2019-05-02 02:02:50.228 - info: admin.0 requesting all states 2019-05-02 02:02:50.229 - info: admin.0 requesting all objects 2019-05-02 02:02:50.231 - info: admin.0 Request actual repository... 2019-05-02 02:02:50.256 - debug: backitup.1 [minimal] 10 00 02 */1 * * 2019-05-02 02:02:50.257 - info: backitup.1 [total] backup was activated at 02:30 every 3 day(s) 2019-05-02 02:02:50.293 - debug: backitup.1 [total] 10 30 02 */3 * * 2019-05-02 02:02:50.303 - info: javascript.0 requesting all states 2019-05-02 02:02:50.305 - info: javascript.0 requesting all objects 2019-05-02 02:02:50.336 - info: javascript.0 starting. Version 4.1.12 in /opt/iobroker/node_modules/iobroker.javascript, node: v8.15.1 2019-05-02 02:02:50.340 - info: javascript.0 requesting all states 2019-05-02 02:02:50.341 - info: javascript.0 requesting all objects 2019-05-02 02:02:50.342 - warn: javascript.0 Reconnection to DB. 2019-05-02 02:02:50.462 - error: web.0 port 8082 already in use 2019-05-02 02:02:50.516 - error: host.homepi-client instance system.adapter.web.0 terminated with code 1 () 2019-05-02 02:02:50.517 - info: host.homepi-client Restart adapter system.adapter.web.0 because enabled 2019-05-02 02:02:50.683 - error: javascript.0 Longitude or latitude does not set. Cannot use astro. 2019-05-02 02:02:50.693 - error: javascript.0 Error in callback: TypeError: Cannot read property 'toLocaleTimeString' of undefined 2019-05-02 02:02:50.694 - error: javascript.0 at Object. (script.js.common.SK_Astro:346:47) ... -
Hhmmm. Aber die Nachricht per Telegramm über das erfolgreiche Backup kam ohne Probleme an...
-
@simatec
Da muss ich etwas ausholen....
Den von mir beschriebenen Fehlerfall konnte ich wieder auflösen, in dem ich admin und javascript wieder neu gestartet hatte. Aber in diesem Zustand, also nach dem Fehler aus meinem oben geposteten Log, konnte ich mit iobroker backup kein Backup mehr starten. Die Prozesse zum Backup wurden zwar gestartet, aber es kam nie zum Ende.
Erst nach einem Reboot beider Raspi (Master und Slave im Multihostsystem) konnte ich iobroker backup wieder ausführen. Innerhalb von 1-2 min konnte ich mehrfach (5-6x) ein Backup ohne Problem oder Absturz erzeugen.Ich bin nun etwas ratlos, warum ist wirklich liegt, bzw. wo ich weiter suchen kann...
-
Ok - Update: Habe einige Backups auf der Shell laufen lassen und dabei geht auch der js-controller irgendwann auf die Bretter:


-
Ich habe jetzt mal 45min zyklisch Biobroker backup mit 30sek Pause dazwischen durchlaufen lassen. Und bisher ist das Problem nicht aufgetreten.
Mein Fehlerbild ist sehr ähnlich zu dem folgenden Thread:
https://forum.iobroker.net/topic/21541/probleme-mit-iobroker-bin-mit-meinem-latein-am-endeWobei es da auch noch keine Lösung gibt...
-
Ich kann den Absturz immer wieder nachstellen, im ioBroker Log zu dem Zeitpunkt leider keine Meldungen:
