Adapter starten automatisch ständig neu

[Jan1]

so wie es aussieht hast den 2 mal am Laufen

[meicker]

ja, so sieht das aus … aber warum ist das so ? Ist ja nur einmal da, wie soll er dann zweimal laufen ?

Und da sind ja noch nichtmal mehr die anderen Adapter im Log, die habe ich ja abgeschaltet. Jede Instanz wird gekillt und neu gestartet ...

[Jan1]

Warte mal bis einer der Wissenden hier was zu schreibt. Fakt, von alleine laufen keine 2 Adapter parallel. Da hast wohl irgendwo gebastelt

[meicker]

Mal abwarten - aber @Jan1:

Da hast wohl irgendwo gebastelt ` NÖ ! Ich war mit einem Script beschäftigt und plötzlich hatte ich keine Verbindung mehr

[paul53]

@meicker:

Oder muss ich den Raspi erst stromlos machen damit es wieder funktioniert ? `
reboot genügt.
@meicker:

Wie kann es sein, dass der Admin abbricht weil er schon läuft ??? `
Irgend etwas versucht, die Admin-Instanz zu starten, obwohl sie bereits läuft.

Stoppe mal ioBroker und schau mit top, ob noch ioBroker-Prozesse laufen.

sudo iobroker stop
top

[meicker]

@paul53:

reboot genügt. `

Ich habe per Konsole noch einmal den reboot gemacht. Ich hatte so viel probiert, aber ich bin mir sicher das zuvor ausch schon mal gemacht zu haben. Ich war aber als pi angemeldet, jetzt bin ich zum root gewechselt und habe reboot erneut ausgeführt - scheint ruhig zu sein

[paul53]

@meicker:

Ich hatte so viel probiert, aber ich bin mir sicher das zuvor ausch schon mal gemacht zu haben. Ich war aber als pi angemeldet `
Dann kannst Du nur rebooten mit

sudo reboot

[meicker]

Ich meine ich hätte … aber jetzt ist ja wieder alles in Ordnung ... Man(n) lernt nie aus

[AlCalzone]

@meicker:

NÖ ! Ich war mit einem Script beschäftigt und plötzlich hatte ich keine Verbindung mehr `
Hast du dabei vielleicht irgendeine Endlosschleife produziert, die dafür gesorgt hat, dass das System eine Weile beschäftigt war?

[meicker]

Das werde ich sehen wenn eines der bearbeiteten scripte anfängt zu arbeiten Aber ich hoffe nicht …

Glaube ich aber auch nicht weil die Adapter gestartet wurden und scheinbar im Hintergrund schon gestartet waren und dann immer wieder abgebrochen wurden. Auch der freie Speicher lag zwischen 50 und 20 MB jetzt habe ich wieder 200 ... Irgend etwas war da krumm ... Aber was ... k.A - es läuft wieder, das ist erstmal die Hauptsache ...

[meicker]

Das Problem tauchte wieder auf und wird durch den geplanten Neustart des Admin Adapterv verursacht, denke ich …

Hier das Log:

2018-08-25 03:00:02.253  - [32minfo[39m: javascript.0 script.js.Controme.Sensordaten: setForeignState(id=javascript.0.Controme.wohnung.vsensor1_temp, state={"val":22.5,"ack":true})
2018-08-25 03:00:03.314  - [32minfo[39m: rpi2.0 States connected to redis: 127.0.0.1:6379
2018-08-25 03:00:03.509  - [32minfo[39m: rpi2.0 starting. Version 1.0.0 in /opt/iobroker/node_modules/iobroker.rpi2, node: v8.11.3
2018-08-25 03:00:05.645  - [32minfo[39m: homepilot.0 States connected to redis: 127.0.0.1:6379
2018-08-25 03:00:05.751  - [32minfo[39m: homepilot.0 starting. Version 1.0.3 in /opt/iobroker/node_modules/iobroker.homepilot, node: v8.11.3
2018-08-25 03:00:18.107  - [32minfo[39m: host.ioBroker-RasPi instance system.adapter.daswetter.0 terminated with code 0 (OK)
2018-08-25 03:00:31.348  - [32minfo[39m: host.ioBroker-RasPi received SIGTERM
2018-08-25 03:00:37.359  - [32minfo[39m: host.ioBroker-RasPi received SIGTERM
2018-08-25 03:00:41.504  - [32minfo[39m: host.ioBroker-RasPi force terminating
2018-08-25 03:00:41.506  - [32minfo[39m: Adapter admin still running
2018-08-25 03:00:41.517  - [32minfo[39m: Adapter discovery still running
2018-08-25 03:00:41.518  - [32minfo[39m: Adapter web still running
2018-08-25 03:00:41.518  - [32minfo[39m: Adapter rpi2 still running
2018-08-25 03:00:41.519  - [32minfo[39m: Adapter socketio still running
2018-08-25 03:00:41.519  - [32minfo[39m: Adapter javascript still running
2018-08-25 03:00:41.519  - [32minfo[39m: Adapter tr-064 still running
2018-08-25 03:00:41.520  - [32minfo[39m: Adapter hue still running
2018-08-25 03:00:41.520  - [32minfo[39m: Adapter ping still running
2018-08-25 03:00:41.520  - [32minfo[39m: Adapter backitup still running
2018-08-25 03:00:41.521  - [32minfo[39m: Adapter homepilot still running
2018-08-25 03:00:41.521  - [32minfo[39m: Adapter geofency still running
2018-08-25 03:00:41.521  - [32minfo[39m: Adapter harmony still running
2018-08-25 03:00:41.522  - [32minfo[39m: Adapter sql still running
2018-08-25 03:00:41.522  - [32minfo[39m: Adapter terminal still running
2018-08-25 03:00:41.523  - [32minfo[39m: Adapter alexa2 still running
2018-08-25 03:00:41.523  - [32minfo[39m: Adapter info still running
2018-08-25 03:00:41.523  - [32minfo[39m: Adapter telegram still running
2018-08-25 03:00:41.523  - [32minfo[39m: Adapter text2command still running
2018-08-25 03:00:41.524  - [32minfo[39m: Adapter cloud still running
2018-08-25 03:01:00.016  - [32minfo[39m: homepilot.0 Scheduled restart.
2018-08-25 03:01:00.016  - [32minfo[39m: rpi2.0 Scheduled restart.
2018-08-25 03:01:00.018  - [32minfo[39m: homepilot.0 terminating homepilot adapter
2018-08-25 03:01:00.019  - [32minfo[39m: homepilot.0 Homepilot adapter stopped
2018-08-25 03:01:00.025  - [32minfo[39m: rpi2.0 terminating
2018-08-25 03:01:00.028  - [32minfo[39m: homepilot.0 terminating
2018-08-25 03:01:00.560  - [32minfo[39m: javascript.0 script.js.Homepilot.Gerätesteuerung.Ventilator: Ventilator ausgeschaltet
2018-08-25 03:02:00.548  - [32minfo[39m: javascript.0 script.js.Homepilot.Gerätesteuerung.Ventilator: Ventilator ausgeschaltet
2018-08-25 03:03:00.556  - [32minfo[39m: javascript.0 script.js.Homepilot.Gerätesteuerung.Ventilator: Ventilator ausgeschaltet
2018-08-25 03:03:46.331  - [32minfo[39m: host.ioBroker-RasPi iobroker.js-controller version 1.4.2 js-controller starting
2018-08-25 03:03:46.339  - [32minfo[39m: host.ioBroker-RasPi Copyright (c) 2014-2018 bluefox, 2014 hobbyquaker
2018-08-25 03:03:46.340  - [32minfo[39m: host.ioBroker-RasPi hostname: ioBroker-RasPi, node: v8.11.3
2018-08-25 03:03:46.344  - [32minfo[39m: host.ioBroker-RasPi ip addresses: 192.168.1.22 fe80::7e2c:305f:c951:9267
2018-08-25 03:03:47.628  - [32minfo[39m: host.ioBroker-RasPi  inMem-objects listening on port 9001
2018-08-25 03:03:47.655  - [32minfo[39m: host.ioBroker-RasPi InMemoryDB connected
2018-08-25 03:03:47.736  - [32minfo[39m: host.ioBroker-RasPi 44 instances found
2018-08-25 03:03:47.818  - [32minfo[39m: host.ioBroker-RasPi starting 32 instances
2018-08-25 03:03:47.857  - [32minfo[39m: host.ioBroker-RasPi instance system.adapter.admin.0 started with pid 12490
2018-08-25 03:03:47.959  - [33mwarn[39m: sql.0 Reconnection to DB.
2018-08-25 03:03:47.968  - [33mwarn[39m: sql.0 Reconnection to DB.
2018-08-25 03:03:47.884  - [32minfo[39m: host.ioBroker-RasPi States connected to redis: 127.0.0.1:6379
2018-08-25 03:03:48.009  - [33mwarn[39m: admin.0 Reconnection to DB.
2018-08-25 03:03:48.017  - [33mwarn[39m: admin.0 Reconnection to DB.
2018-08-25 03:03:48.109  - [33mwarn[39m: hue.0 Reconnection to DB.
2018-08-25 03:03:48.116  - [33mwarn[39m: hue.0 Reconnection to DB.
2018-08-25 03:03:48.448  - [33mwarn[39m: discovery.0 Reconnection to DB.
2018-08-25 03:03:48.455  - [33mwarn[39m: discovery.0 Reconnection to DB.
2018-08-25 03:03:48.602  - [33mwarn[39m: info.0 Reconnection to DB.
2018-08-25 03:03:48.609  - [33mwarn[39m: info.0 Reconnection to DB.
2018-08-25 03:03:48.659  - [33mwarn[39m: terminal.0 Reconnection to DB.
2018-08-25 03:03:48.667  - [33mwarn[39m: terminal.0 Reconnection to DB.
2018-08-25 03:03:48.716  - [33mwarn[39m: harmony.0 Reconnection to DB.
2018-08-25 03:03:48.721  - [33mwarn[39m: harmony.0 Reconnection to DB.
2018-08-25 03:03:49.021  - [33mwarn[39m: ping.0 Reconnection to DB.
2018-08-25 03:03:49.030  - [33mwarn[39m: ping.0 Reconnection to DB.
2018-08-25 03:03:49.022  - [33mwarn[39m: socketio.0 Reconnection to DB.
2018-08-25 03:03:49.048  - [33mwarn[39m: socketio.0 Reconnection to DB.
2018-08-25 03:03:49.419  - [33mwarn[39m: javascript.0 Reconnection to DB.
2018-08-25 03:03:49.444  - [33mwarn[39m: javascript.0 Reconnection to DB.
2018-08-25 03:03:49.493  - [33mwarn[39m: alexa2.0 Reconnection to DB.
2018-08-25 03:03:49.500  - [33mwarn[39m: alexa2.0 Reconnection to DB.
2018-08-25 03:03:49.612  - [33mwarn[39m: text2command.0 Reconnection to DB.
2018-08-25 03:03:49.621  - [33mwarn[39m: text2command.0 Reconnection to DB.
2018-08-25 03:03:49.644  - [33mwarn[39m: cloud.1 Reconnection to DB.
2018-08-25 03:03:49.651  - [33mwarn[39m: cloud.1 Reconnection to DB.
2018-08-25 03:03:50.067  - [33mwarn[39m: geofency.0 Reconnection to DB.
2018-08-25 03:03:50.094  - [33mwarn[39m: geofency.0 Reconnection to DB.
2018-08-25 03:03:50.249  - [33mwarn[39m: tr-064.0 Reconnection to DB.
2018-08-25 03:03:50.256  - [33mwarn[39m: tr-064.0 Reconnection to DB.
2018-08-25 03:03:50.341  - [33mwarn[39m: telegram.0 Reconnection to DB.
2018-08-25 03:03:50.347  - [33mwarn[39m: telegram.0 Reconnection to DB.
2018-08-25 03:03:50.519  - [31merror[39m: host.ioBroker-RasPi instance system.adapter.admin.0 terminated with code 7 (Adapter already running)
2018-08-25 03:03:50.520  - [32minfo[39m: host.ioBroker-RasPi Restart adapter system.adapter.admin.0 because enabled
2018-08-25 03:03:51.846  - [32minfo[39m: host.ioBroker-RasPi instance system.adapter.discovery.0 started with pid 12505
2018-08-25 03:03:52.509  - [33mwarn[39m: backitup.0 Reconnection to DB.
2018-08-25 03:03:52.530  - [33mwarn[39m: backitup.0 Reconnection to DB.
2018-08-25 03:03:52.540  - [33mwarn[39m: web.0 Reconnection to DB.
2018-08-25 03:03:52.548  - [33mwarn[39m: web.0 Reconnection to DB.
2018-08-25 03:03:53.859  - [31merror[39m: host.ioBroker-RasPi instance system.adapter.discovery.0 terminated with code 7 (Adapter already running)
2018-08-25 03:03:53.860  - [32minfo[39m: host.ioBroker-RasPi Restart adapter system.adapter.discovery.0 because enabled
2018-08-25 03:03:55.849  - [32minfo[39m: host.ioBroker-RasPi instance system.adapter.web.0 started with pid 12515
2018-08-25 03:03:57.485  - [31merror[39m: host.ioBroker-RasPi instance system.adapter.web.0 terminated with code 7 (Adapter already running)
2018-08-25 03:03:57.486  - [32minfo[39m: host.ioBroker-RasPi Restart adapter system.adapter.web.0 because enabled
2018-08-25 03:03:59.848  - [32minfo[39m: host.ioBroker-RasPi instance system.adapter.rpi2.0 started with pid 12529
2018-08-25 03:04:00.548  - [32minfo[39m: javascript.0 script.js.Homepilot.Gerätesteuerung.Ventilator: Ventilator ausgeschaltet
2018-08-25 03:04:01.078  - [32minfo[39m: rpi2.0 States connected to redis: 127.0.0.1:6379
2018-08-25 03:04:01.242  - [32minfo[39m: rpi2.0 starting. Version 1.0.0 in /opt/iobroker/node_modules/iobroker.rpi2, node: v8.11.3
2018-08-25 03:04:03.847  - [32minfo[39m: host.ioBroker-RasPi instance system.adapter.socketio.0 started with pid 12539
2018-08-25 03:04:05.753  - [31merror[39m: host.ioBroker-RasPi instance system.adapter.socketio.0 terminated with code 7 (Adapter already running)
2018-08-25 03:04:05.754  - [32minfo[39m: host.ioBroker-RasPi Restart adapter system.adapter.socketio.0 because enabled
2018-08-25 03:04:09.285  - [32minfo[39m: vis.0 States connected to redis: 127.0.0.1:6379
2018-08-25 03:04:09.461  - [32minfo[39m: vis.0 starting. Version 1.1.7 in /opt/iobroker/node_modules/iobroker.vis, node: v8.11.3
2018-08-25 03:04:09.677  - [32minfo[39m: vis.0 vis license is OK.
2018-08-25 03:04:12.842  - [32minfo[39m: host.ioBroker-RasPi instance system.adapter.javascript.0 started with pid 12605
2018-08-25 03:04:13.569  - [32minfo[39m: host.ioBroker-RasPi instance system.adapter.vis.0 terminated while should be started once
2018-08-25 03:04:17.773  - [32minfo[39m: javascript.0 States connected to redis: 127.0.0.1:6379
2018-08-25 03:04:17.822  - [31merror[39m: javascript.0 already running
2018-08-25 03:04:17.861  - [31merror[39m: host.ioBroker-RasPi instance system.adapter.javascript.0 terminated with code 7 (Adapter already running)
2018-08-25 03:04:17.862  - [32minfo[39m: host.ioBroker-RasPi Restart adapter system.adapter.javascript.0 because enabled
2018-08-25 03:04:19.886  - [32minfo[39m: host.ioBroker-RasPi instance system.adapter.tr-064.0 started with pid 12619
2018-08-25 03:04:20.569  - [32minfo[39m: host.ioBroker-RasPi instance system.adapter.admin.0 started with pid 12625
2018-08-25 03:04:22.460  - [31merror[39m: host.ioBroker-RasPi instance system.adapter.tr-064.0 terminated with code 7 (Adapter already running)
2018-08-25 03:04:22.461  - [32minfo[39m: host.ioBroker-RasPi Restart adapter system.adapter.tr-064.0 because enabled
2018-08-25 03:04:23.821  - [31merror[39m: host.ioBroker-RasPi instance system.adapter.admin.0 terminated with code 7 (Adapter already running)
2018-08-25 03:04:23.822  - [32minfo[39m: host.ioBroker-RasPi Restart adapter system.adapter.admin.0 because enabled
2018-08-25 03:04:23.854  - [32minfo[39m: host.ioBroker-RasPi instance system.adapter.hue.0 started with pid 12641
2018-08-25 03:04:23.900  - [32minfo[39m: host.ioBroker-RasPi instance system.adapter.discovery.0 started with pid 12646
2018-08-25 03:04:25.262  - [31merror[39m: host.ioBroker-RasPi instance system.adapter.discovery.0 terminated with code 7 (Adapter already running)
2018-08-25 03:04:25.263  - [32minfo[39m: host.ioBroker-RasPi Restart adapter system.adapter.discovery.0 because enabled
2018-08-25 03:04:25.486  - [31merror[39m: host.ioBroker-RasPi instance system.adapter.hue.0 terminated with code 7 (Adapter already running)
2018-08-25 03:04:25.487  - [32minfo[39m: host.ioBroker-RasPi Restart adapter system.adapter.hue.0 because enabled
2018-08-25 03:04:27.532  - [32minfo[39m: host.ioBroker-RasPi instance system.adapter.web.0 started with pid 12671
2018-08-25 03:04:27.855  - [32minfo[39m: host.ioBroker-RasPi instance system.adapter.ping.0 started with pid 12677
2018-08-25 03:04:29.226  - [31merror[39m: host.ioBroker-RasPi instance system.adapter.ping.0 terminated with code 7 (Adapter already running)
2018-08-25 03:04:29.227  - [32minfo[39m: host.ioBroker-RasPi Restart adapter system.adapter.ping.0 because enabled
2018-08-25 03:04:29.448  - [31merror[39m: host.ioBroker-RasPi instance system.adapter.web.0 terminated with code 7 (Adapter already running)
2018-08-25 03:04:29.449  - [32minfo[39m: host.ioBroker-RasPi Restart adapter system.adapter.web.0 because enabled
2018-08-25 03:04:31.881  - [32minfo[39m: host.ioBroker-RasPi instance system.adapter.backitup.0 started with pid 12693
2018-08-25 03:04:33.444  - [31merror[39m: host.ioBroker-RasPi instance system.adapter.backitup.0 terminated with code 7 (Adapter already running)

Ich bin mir ziemlich sicher einen geplanten Neustart für den Admin drin gehabt zu haben der aber nicht sichtbar ist. Ich schalte jetzt sicherheitshalber alle geplanten Neustarts einmal ab und beobachte weiter. Oder kann jemand anhand des Logs irgend etwas seltsames erkennen ?

Viele Grüße,

marc

[Tom]

Hallo,

ich hatte das selbe Problem.

Ich denke es ist ein Bug der aktuellen Version 3.5.6 des ADMIN Adapters. Ich bin zurück auf Version 3.5.5 und die Fehlermeldungen bleiben aus.

[Jan1]

Wäre das ein Bug, dann hätten den wohl andere auch.

Mach mal:

node -v

nodejs -v

npm -v

[meicker]

@Jan1:

Wäre das ein Bug, dann hätten den wohl andere auch.

Mach mal:

node -v

nodejs -v

npm -v `

Was passiert dann ? Und wenn - wann machen ? Wenn der fehler da ist oder wenn er nicht da ist ?

Und Fehler können auftreten - Und manche Fehler tauchen eben nur unter bestimmten bedingungen auf. Bei mir tritt er aktuell nicht mehr auf weil ich, so sieht es zumindest aus, die geplanten Neustarts diverser Adapter abgeschaltet habe. Vielleicht war es auch nur der Admin Adapter schuld den ich um 3 Uhr morgens neu gestartet hatte …

vg

marc

[Jan1]

Oder vielleicht machst das einfach mal und prostet das Ergebnis hier, bevor meine Glaskugel den Geist komplett aufgibt :lol:

Ich geh mal davon aus, dass der IOBroker bei Dir auf nem Pi läuft und wenn das in der Konsole eingibst, kommen schon mal die wichtigsten Versionsstände der Installation und wenn man dann mal ne Basis hat, kann man den Fehler auch eingrenzen.

Das solltest aber eigentlich auch wissen, steht ja in der Doku zur Installation des IOBroker, oder wurde hier wieder was fertiges verwendet?

[Tom]

Bei mir sind es folgende Stände:

node V6.12.0

nodejs V6.12.0

npm V3.10.10

[Homoran]

@meicker:

ja, so sieht das aus … aber warum ist das so ? Ist ja nur einmal da, wie soll er dann zweimal laufen ? `
und genau das ist das Problem.

Natürlich existiert nur eine Instanz.

Aus irgendeinem Grund meint ioBroker aber, dass diese nicht mehr läuft und startet sie (nochmal - als zweiten Prozess), der merkt jetzt, dass der admin eigentlich schon läuft und stoppt den (anderen??) Prozess. dann fährt dieser wieder hoch und das Ganze befindet sich in einer Schleife so dass du nicht mehr auf den admin kommst, weil dieser nie komplett hochfährt.

Zu Erkennen am code 7 - Fehler. (Das gilt für alle Instanzen)

Pragmatische lösung ist ein reboot des gesamten Systems - danach sollte alles sauber laufen

politisch korrekte Lösung ist die entsprechenden Prozesse (nicht Instanzen!!) zu killen und übder den Admin anschließend einmal neu zu starten

Gruß

Rainer

[Jan1]

@Tom:

Bei mir sind es folgende Stände:

node V6.12.0

nodejs V6.12.0

npm V3.10.10 `

Die sind zwar nicht aktuell, aber auch nicht falsch. Damit sollte es kein Problem geben. Update nach Doku kann aber auch nicht schaden

[AlCalzone]

@Jan1:

Update nach Doku kann aber auch nicht schaden `
Da wäre ich aktuell vorsichtig. apt-get installiert wohl inkompatible Versionen.

Bezüglich des Neustarts mit Code 7 planen wir eine Verbesserung in der nächsten großen JS-Controller-Version. Daher würde ich erst mal die Füße stillhalten und nicht voreilig Systemupdates vornehmen.

[meicker]

@Jan1:

Wäre das ein Bug, dann hätten den wohl andere auch.

Mach mal:

node -v

nodejs -v

npm -v `

node -v 8.11.3

nodejs -v 8.11.3

npm -v 6.3.0

@Homoran:

Natürlich existiert nur eine Instanz.

Aus irgendeinem Grund meint ioBroker aber, dass diese nicht mehr läuft und startet sie (nochmal - als zweiten Prozess), der merkt jetzt, dass der admin eigentlich schon läuft und stoppt den (anderen??) Prozess. dann fährt dieser wieder hoch und das Ganze befindet sich in einer Schleife so dass du nicht mehr auf den admin kommst, weil dieser nie komplett hochfährt.

Zu Erkennen am code 7 - Fehler. (Das gilt für alle Instanzen)

Pragmatische lösung ist ein reboot des gesamten Systems - danach sollte alles sauber laufen

politisch korrekte Lösung ist die entsprechenden Prozesse (nicht Instanzen!!) zu killen und übder den Admin anschließend einmal neu zu starten `

Ich hatte geplante Neustarts drin um einmal am tag ein frisches System zu haben. Ich habe auch Adapter da eingefügt und alles gleichzeitig um 3 Uhr gemacht. kann man das verhalten evtl. provozieren wenn man den Admin und Javascript.0 gleichzeitig neu startet ? An irgend einer Stelle muss er da ja durcheinander kommen. Oder wenn Admin und irgendetwas mit Abhängkeit gleichzeitig gestartet wird ?

Jedenfalls seit ich die Neustarts raus habe ist alles ruhig. Ich habe den jetzt nur für den Admin auf 9 Uhr morgens stehen, dann sehe ich schneller wenn was aus dem Ruder läuft. Aber auch heute früh um 9 war Ruhe …

@AlCalzone:

Bezüglich des Neustarts mit Code 7 planen wir eine Verbesserung in der nächsten großen JS-Controller-Version. Daher würde ich erst mal die Füße stillhalten und nicht voreilig Systemupdates vornehmen. `

Sind das die Updates von

Typ: js-controller

Titel: JS controller

OS: linux

Verfügbar: 1.4.2

Eingerichtet: 1.4.2

die gemeint sind ? Oder wovon sollte man die Finger lassen ?

vg

Marc