Nach Node Update ständig restarts

[ManuelW]

Hallo zusammen,
ich habe gestern auf meinem Server (Ubuntu 18 LTS) node von 8.X auf 10.X aktualisiert,
um den neuen HUE Adapter nutzen zu können.

Seit dem werden ca. alle 3min sämtliche Adapter restarted, evtl stürzt auch iobroker ab?
Ich kann in den Logfiles leider keine weiteren Angaben finden, außer dies:

2020-01-16 13:18:35.552  - error: host.zeus instance system.adapter.admin.0 terminated with code 156 (156)
2020-01-16 13:18:39.660  - error: host.zeus instance system.adapter.javascript.0 terminated with code 156 (156)
2020-01-16 13:18:43.569  - error: host.zeus instance system.adapter.hue.0 terminated with code 156 (156)
2020-01-16 13:18:47.595  - error: host.zeus instance system.adapter.geofency.0 terminated with code 156 (156)
2020-01-16 13:18:51.547  - error: host.zeus instance system.adapter.web.0 terminated with code 156 (156)
2020-01-16 13:18:55.585  - error: host.zeus instance system.adapter.yahka.0 terminated with code 156 (156)
2020-01-16 13:19:01.537  - error: host.zeus instance system.adapter.scenes.0 terminated with code 156 (156)
2020-01-16 13:19:05.536  - error: host.zeus instance system.adapter.mqtt.0 terminated with code 156 (156)
2020-01-16 13:22:06.093  - error: host.zeus instance system.adapter.admin.0 terminated with code 156 (156)
2020-01-16 13:22:10.229  - error: host.zeus instance system.adapter.javascript.0 terminated with code 156 (156)
2020-01-16 13:22:14.114  - error: host.zeus instance system.adapter.hue.0 terminated with code 156 (156)
2020-01-16 13:22:18.142  - error: host.zeus instance system.adapter.geofency.0 terminated with code 156 (156)
2020-01-16 13:22:22.075  - error: host.zeus instance system.adapter.web.0 terminated with code 156 (156)
2020-01-16 13:22:26.117  - error: host.zeus instance system.adapter.yahka.0 terminated with code 156 (156)
2020-01-16 13:22:32.568  - error: host.zeus instance system.adapter.scenes.0 terminated with code 156 (156)
2020-01-16 13:22:36.568  - error: host.zeus instance system.adapter.mqtt.0 terminated with code 156 (156)

im vollständigen Log sieht man beim Beginn des restarts nur dies:

2020-01-16 13:28:55.275  - info: mqtt.0 (19331) send2Server javascript.0.Strom.esp-sz-strom.Kosten.Woche[javascript/0/Strom/esp-sz-strom/Kosten/Woche]
2020-01-16 13:28:55.275  - info: mqtt.0 (19331) send2Server javascript.0.Strom.esp-sz-strom.Verbrauch.Monat[javascript/0/Strom/esp-sz-strom/Verbrauch/Monat]
2020-01-16 13:28:55.276  - info: mqtt.0 (19331) send2Server javascript.0.Strom.esp-sz-strom.Kosten.Monat[javascript/0/Strom/esp-sz-strom/Kosten/Monat]
2020-01-16 13:28:55.277  - info: mqtt.0 (19331) send2Server javascript.0.Strom.esp-sz-strom.Verbrauch.Quartal[javascript/0/Strom/esp-sz-strom/Verbrauch/Quartal]
2020-01-16 13:28:55.277  - info: mqtt.0 (19331) send2Server javascript.0.Strom.esp-sz-strom.Kosten.Quartal[javascript/0/Strom/esp-sz-strom/Kosten/Quartal]
2020-01-16 13:28:55.278  - info: mqtt.0 (19331) send2Server javascript.0.Strom.esp-sz-strom.Verbrauch.Jahr[javascript/0/Strom/esp-sz-strom/Verbrauch/Jahr]
2020-01-16 13:28:55.279  - info: mqtt.0 (19331) send2Server javascript.0.Strom.esp-sz-strom.Kosten.Jahr[javascript/0/Strom/esp-sz-strom/Kosten/Jahr]
2020-01-16 13:28:57.239  - info: influxdb.0 (13757) Store 101 buffered influxDB history points
2020-01-16 13:28:59.022  - info: mqtt.0 (19331) send2Server mqtt.0.led_matrix_1[led_matrix_1]
2020-01-16 13:29:04.471  - info: mqtt.0 (19331) send2Server mqtt.0.led_matrix_1[led_matrix_1]
2020-01-16 13:29:06.642  - info: mqtt.0 (19331) send2Server system.adapter.admin.0.sigKill[undefined]
2020-01-16 13:29:06.642  - info: mqtt.0 (19331) send2Server system.adapter.admin.0.sigKill[system/adapter/admin/0/sigKill]
2020-01-16 13:29:06.645  - warn: admin.0 (18309) Got terminate signal. Checking desired PID:  vs own PID 18309
2020-01-16 13:29:06.650  - info: admin.0 (18309) terminating http server on port 8081
2020-01-16 13:29:06.650  - info: admin.0 (18309) terminating
2020-01-16 13:29:06.651  - info: admin.0 (18309) Terminated (START_IMMEDIATELY_AFTER_STOP): Without reason
2020-01-16 13:29:07.165  - error: host.zeus instance system.adapter.admin.0 terminated with code 156 (156)
2020-01-16 13:29:07.166  - info: host.zeus Restart adapter system.adapter.admin.0 because enabled
2020-01-16 13:29:09.135  - info: mqtt.0 (19331) send2Server mqtt.0.led_matrix_2[led_matrix_2]
2020-01-16 13:29:10.841  - info: mqtt.0 (19331) send2Server system.adapter.javascript.0.sigKill[undefined]
2020-01-16 13:29:10.842  - info: mqtt.0 (19331) send2Server system.adapter.javascript.0.sigKill[system/adapter/javascript/0/sigKill]
2020-01-16 13:29:10.861  - warn: javascript.0 (18338) Got terminate signal. Checking desired PID:  vs own PID 18338
2020-01-16 13:29:10.862  - info: javascript.0 (18338) Stop script script.js.Wohnung.Licht_bei_Bewegung
2020-01-16 13:29:10.863  - info: javascript.0 (18338) Stop script script.js.Wohnung.BM_zurücksetzen
2020-01-16 13:29:10.863  - info: javascript.0 (18338) Stop script script.js.Wohnung.Stromverbrauch
2020-01-16 13:29:10.864  - info: javascript.0 (18338) Stop script script.js.Wohnung.Home_State

manuel@zeus:/opt/iobroker$ nodejs -v
v10.18.1
manuel@zeus:/opt/iobroker$ node -v
v10.18.1
manuel@zeus:/opt/iobroker$ npm -v
6.13.4

Ich habe schon reinstall.sh, fix.sh, npm rebuild laufen lassen, aber keine Änderung.

Habt ihr nen Tipp was das Problem sein könnte?

[ManuelW]

Ich habe jetzt sämtliche Optionen, bis hin zur kompletten Neuinstalltion von iobroker, nach diesem Thread gemacht, aber das Problem besteht weiterhin. https://forum.iobroker.net/topic/22867/how-to-node-js-für-iobroker-richtig-updaten

[Jey Cee]

@ManuelW Passiert das auch wenn alle Adapter deaktiviert sind bis auf admin?
Wenn Nein schritt für schritt die Adapter Starten und beobachten.

Alle 3 Minuten klingt nach einem Cronjob, Wahrscheinlich ein Skript, kann aber auch ein Adapter sein.

[ManuelW]

@Jey-Cee danke für deinen Hinweis. Ich bin jetzt nochmal Adapter für Adapter durchgegangen und
es scheint am mqtt Adapter zu liegen. Zumindest ist das der einzige, der jetzt noch abschmiert.

2020-01-17 11:34:24.947  - info: mqtt.0 (2664) send2Server system.adapter.mqtt.0.sigKill[undefined]
2020-01-17 11:34:24.951  - info: mqtt.0 (2664) send2Server system.adapter.mqtt.0.sigKill[system/adapter/mqtt/0/sigKill]
2020-01-17 11:34:24.960  - warn: mqtt.0 (2664) Got terminate signal. Checking desired PID:  vs own PID 2664
2020-01-17 11:34:24.962  - info: mqtt.0 (2664) Disconnected from 192.168.1.3: undefined
2020-01-17 11:34:25.461  - info: mqtt.0 (2664) terminating
2020-01-17 11:34:25.462  - info: mqtt.0 (2664) Terminated (START_IMMEDIATELY_AFTER_STOP): Without reason
2020-01-17 11:34:25.978  - error: host.zeus instance system.adapter.mqtt.0 terminated with code 156 (156)

Das er so regelmäßig gekillt wird wundert mich auch, habe aber nirgends einen cron der das macht.

2020-01-17 12:01:38.948  - info: mqtt.0 (19559) send2Server system.adapter.mqtt.0.sigKill[undefined]
2020-01-17 12:01:38.949  - info: mqtt.0 (19559) send2Server system.adapter.mqtt.0.sigKill[system/adapter/mqtt/0/sigKill]
2020-01-17 12:05:10.007  - info: mqtt.0 (26034) send2Server system.adapter.mqtt.0.sigKill[undefined]
2020-01-17 12:05:10.016  - info: mqtt.0 (26034) send2Server system.adapter.mqtt.0.sigKill[system/adapter/mqtt/0/sigKill]
2020-01-17 12:08:41.069  - info: mqtt.0 (32568) send2Server system.adapter.mqtt.0.sigKill[undefined]
2020-01-17 12:08:41.070  - info: mqtt.0 (32568) send2Server system.adapter.mqtt.0.sigKill[system/adapter/mqtt/0/sigKill]
2020-01-17 12:12:12.117  - info: mqtt.0 (6927) send2Server system.adapter.mqtt.0.sigKill[undefined]
2020-01-17 12:12:12.117  - info: mqtt.0 (6927) send2Server system.adapter.mqtt.0.sigKill[system/adapter/mqtt/0/sigKill]
2020-01-17 12:15:43.160  - info: mqtt.0 (13071) send2Server system.adapter.mqtt.0.sigKill[undefined]
2020-01-17 12:15:43.160  - info: mqtt.0 (13071) send2Server system.adapter.mqtt.0.sigKill[system/adapter/mqtt/0/sigKill]
2020-01-17 12:19:14.209  - info: mqtt.0 (19404) send2Server system.adapter.mqtt.0.sigKill[undefined]
2020-01-17 12:19:14.210  - info: mqtt.0 (19404) send2Server system.adapter.mqtt.0.sigKill[system/adapter/mqtt/0/sigKill]
2020-01-17 12:22:45.255  - info: mqtt.0 (25619) send2Server system.adapter.mqtt.0.sigKill[undefined]
2020-01-17 12:22:45.255  - info: mqtt.0 (25619) send2Server system.adapter.mqtt.0.sigKill[system/adapter/mqtt/0/sigKill]

Ich habe den Adapter per admin nochmal neu installiert, aber es bringt keine Abhilfe.

[ManuelW]

Es ist tatsächlich nur noch der mqtt Adapter der regelmäßig neu startet.
Ich habe diesen hier installiert und als Client laufen

MQTT Broker/Client	V2.1.1

[olanwe]

Hi @ManuelW, konnstest Du das Problem lösen?
Ich habe hier seit gestern exakt das gleiche Problem mit einem Raspi 3, MQTT-Adapter im Client-Betrieb und auch schon alles durchprobiert, inklusive Downgrade des MQTT-und Admin-Adapters, NPM und Node (weder Node 8.17 noch die aktuelle Node 12.x lösen das Problem) und der Installation-Fixer hilft ebenfalls nicht.
Exakt alle 3 Minuten läuft der MQTT-Adapter Amok und killt Prozesse.
Ich habe ebenfalls davor ein Node-Update gemacht, allerdings war ich davor schon auf Node 10.x, und da hat es definitiv funktioniert.

Das Problem trat bei mir aber auch dann auf, als ich testhalber eine komplette Neuinstallation gemacht (ohne Datenübernahme) und den MQTT-Adapter hinzugefügt habe. Deshalb vermute ich, dass es mit einer Änderung an irgendeinem sonstigen Linux-Package zusammenhängt (die habe ich dummerweise zeitgleich aktualisiert...), mit welcher der MQTT-Adapter nicht klar kommt.

Alle anderen Adapter (web, skript, vis...) laufen absolut stabil, wenn ich den MQTT deaktiviere.

[olanwe]

@ManuelW - ich habe das Problem für mich gelöst!

Das Problem wird durch den js-controller verursacht. Das MQTT-Modul verträgt sich offenbar nicht mit der aktuellen Version 2.2.8.
Ich bin auf die Version 2.1.1 zurückgegangen und damit läuft die Kiste wieder perfekt!

npm install iobroker.js-controller@2.1.1