NEWS
Adapter starten automatisch ständig neu
-
Hallo zusammen,
ich glaube ich habe ein etwas schwerwiegendes Problem. Aktuell habe ich nur Fernzugriff und kann den Raspi nicht from Strom trennen für einen Neustart …
Eben irgendwann vor über einer Stunde hatte ich plötzlich keinen Zugriff mehr auf die Adminoberfläche. Es war nur noch Die Hintergrundgrafik sichtbar und der obere Balken lief, kein Kreisel in der Bildschirmmitte. Nach über einer Stunde habe ich mich dann per fernzugriff mit ssh verbunden und den Paspi neu gestartet. Nun bin ich wieder in der Adminoberfläche und kann auch die Instanzen sehen.
Nun kann ich beobachten wie die aktivierten Instanzen immer wieder neu starten.
Ich habe jetzt alle instanzen deaktiviert die ständig neu gestartet haben. Leider gehört die Admin Instanz aber ebenfalls dazu.
host.ioBroker-RasPi 2018-08-24 13:37:46.907 info Restart adapter system.adapter.admin.0 because enabled host.ioBroker-RasPi 2018-08-24 13:37:46.907 error instance system.adapter.admin.0 terminated with code 7 (Adapter already running) host.ioBroker-RasPi 2018-08-24 13:37:44.444 info instance system.adapter.admin.0 started with pid 27226 rpi2.0 2018-08-24 13:37:15.899 error No Value found for cpu_frequency host.ioBroker-RasPi 2018-08-24 13:37:14.416 info Restart adapter system.adapter.admin.0 because enabled host.ioBroker-RasPi 2018-08-24 13:37:14.415 error instance system.adapter.admin.0 terminated with code 7 (Adapter already running) host.ioBroker-RasPi 2018-08-24 13:37:12.095 info instance system.adapter.admin.0 started with pid 27134 host.ioBroker-RasPi 2018-08-24 13:36:42.074 info Restart adapter system.adapter.admin.0 because enabled host.ioBroker-RasPi 2018-08-24 13:36:42.073 error instance system.adapter.admin.0 terminated with code 7 (Adapter already running) host.ioBroker-RasPi 2018-08-24 13:36:39.678 info instance system.adapter.admin.0 started with pid 27106 rpi2.0 2018-08-24 13:36:15.544 error No Value found for cpu_frequency host.ioBroker-RasPi 2018-08-24 13:36:09.651 info Restart adapter system.adapter.admin.0 because enabled host.ioBroker-RasPi 2018-08-24 13:36:09.650 error instance system.adapter.admin.0 terminated with code 7 (Adapter already running)
Wie kann ich diesen Spuk beenden ? Oder muss ich den Raspi erst stromlos machen damit es wieder funktioniert ? Wie kann es sein, dass der Admin abbricht weil er schon läuft ???
Vg
Marc
-
so wie es aussieht hast den 2 mal am Laufen
-
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 ...
-
Warte mal bis einer der Wissenden hier was zu schreibt. Fakt, von alleine laufen keine 2 Adapter parallel. Da hast wohl irgendwo gebastelt
-
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
-
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
-
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
-
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 mitsudo reboot
-
Ich meine ich hätte … aber jetzt ist ja wieder alles in Ordnung ... Man(n) lernt nie aus
-
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? -
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 ...
-
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
-
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.
-
Wäre das ein Bug, dann hätten den wohl andere auch.
Mach mal:
node -v
nodejs -v
npm -v
-
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
-
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?
-
Bei mir sind es folgende Stände:
node V6.12.0
nodejs V6.12.0
npm V3.10.10
-
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
-
@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
-
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.