SOLVED (GELÖST) --- Probleme mit iobroker - bin mit meinem Latein am Ende

[crepp1967]

@liv-in-sky

2019-05-03 06:40:08.714 - info: host.iobroker instance system.adapter.yr.0 terminated with code 0 (OK)
2019-05-03 06:40:12.815 - info: host.iobroker instance system.adapter.luftdaten.0 terminated with code 0 (OK)
2019-05-03 06:42:00.031 - info: host.iobroker instance system.adapter.todoist.0 started with pid 30294
2019-05-03 06:42:01.226 - info: todoist.0 starting. Version 0.1.0 in /opt/iobroker/node_modules/iobroker.todoist, node: v8.16.0
2019-05-03 06:42:02.378 - info: host.iobroker instance system.adapter.todoist.0 terminated with code 0 (OK)
2019-05-03 06:44:00.049 - info: host.iobroker instance system.adapter.todoist.0 started with pid 30551
2019-05-03 06:44:01.212 - info: todoist.0 starting. Version 0.1.0 in /opt/iobroker/node_modules/iobroker.todoist, node: v8.16.0
2019-05-03 06:44:02.383 - info: host.iobroker instance system.adapter.todoist.0 terminated with code 0 (OK)
2019-05-03 06:45:00.048 - info: host.iobroker instance system.adapter.luftdaten.0 started with pid 30700
2019-05-03 06:45:00.102 - info: host.iobroker instance system.adapter.dwd.0 started with pid 30705
2019-05-03 06:45:02.051 - info: dwd.0 starting. Version 2.4.3 in /opt/iobroker/node_modules/iobroker.dwd, node: v8.16.0
2019-05-03 06:45:03.493 - info: host.iobroker instance system.adapter.dwd.0 terminated with code 0 (OK)
2019-05-03 06:45:12.438 - info: host.iobroker instance system.adapter.luftdaten.0 terminated with code 0 (OK)
2019-05-03 06:46:00.023 - info: host.iobroker instance system.adapter.todoist.0 started with pid 30873
2019-05-03 06:46:01.176 - info: todoist.0 starting. Version 0.1.0 in /opt/iobroker/node_modules/iobroker.todoist, node: v8.16.0
2019-05-03 06:46:02.313 - info: host.iobroker instance system.adapter.todoist.0 terminated with code 0 (OK)
2019-05-03 06:47:41.440 - info: iobroker upgrade mqtt
2019-05-03 06:47:43.994 - info: iobroker Update mqtt from @2.0.4 to @2.1.0
2019-05-03 06:47:44.309 - info: iobroker upgrade node-red
2019-05-03 06:47:44.879 - info: iobroker NPM version: 6.4.1
2019-05-03 06:47:45.823 - info: iobroker npm install iobroker.mqtt@2.1.0 --unsafe-perm --production --save --prefix "/opt/iobroker" (System call)
2019-05-03 06:47:46.302 - info: iobroker Update node-red from @1.7.1 to @1.8.0
2019-05-03 06:47:47.129 - info: iobroker NPM version: 6.4.1
2019-05-03 06:47:47.936 - info: iobroker npm install iobroker.node-red@1.8.0 --unsafe-perm --production --save --prefix "/opt/iobroker" (System call)
2019-05-03 06:48:00.080 - info: host.iobroker instance system.adapter.todoist.0 started with pid 31175
2019-05-03 06:48:00.111 - info: host.iobroker instance system.adapter.ical.0 started with pid 31178
2019-05-03 06:48:04.719 - info: todoist.0 starting. Version 0.1.0 in /opt/iobroker/node_modules/iobroker.todoist, node: v8.16.0
2019-05-03 06:48:19.767 - info: host.iobroker instance system.adapter.todoist.0 terminated with code 0 (OK)
2019-05-03 06:48:27.197 - info: host.iobroker instance system.adapter.ical.0 terminated with code 0 (OK)
2019-05-03 06:50:13.720 - info: host.iobroker instance system.adapter.todoist.0 started with pid 31433
2019-05-03 06:50:13.746 - info: host.iobroker instance system.adapter.luftdaten.0 started with pid 31434
2019-05-03 06:52:18.825 - warn: host.iobroker instance system.adapter.todoist.0 already running with pid 31433
2019-05-03 06:57:36.850 - error: spotify-premium.0 erron in request: Error: socket hang up
2019-05-03 06:57:37.835 - warn: host.iobroker instance system.adapter.todoist.0 already running with pid 31433
2019-05-03 06:57:39.036 - error: parser.0 Cannot read link "http://forum.iobroker.net/": Error: socket hang up
2019-05-03 06:57:39.465 - info: iobroker Killed
2019-05-03 06:57:39.481 - error: iobroker host.iobroker Cannot install iobroker.node-red@1.8.0: 137
2019-05-03 06:57:39.509 - info: iobroker exit 25
2019-05-03 06:57:39.518 - warn: host.iobroker instance system.adapter.luftdaten.0 already running with pid 31434
2019-05-03 06:57:39.840 - warn: admin.0 Reconnection to DB.
2019-05-03 06:57:39.853 - warn: spotify-premium.0 Reconnection to DB.
2019-05-03 06:57:39.902 - warn: tr-064.0 Reconnection to DB.
2019-05-03 06:57:40.139 - debug: javascript.0 statesDB connected
2019-05-03 06:57:39.990 - warn: web.0 Reconnection to DB.
2019-05-03 06:57:40.107 - warn: backitup.0 Reconnection to DB.
2019-05-03 06:57:40.162 - warn: host.iobroker instance system.adapter.todoist.0 already running with pid 31433
2019-05-03 06:57:40.289 - warn: spotify-premium.0 Reconnection to DB.

6:57
Drei Zeilen, das ist alles. Im atop-log sehe ich wesentlich mehr. aber das ist kein klartext...
Im atop weisst alles daraufhin dass es daran lag dass ich beide adapter gleichzeitig aktualisieren wollte (mqtt und node-red)
Ich werde also das in Zukunft vermeiden.

Gruß
crepp

[liv-in-sky]

mal ein wenig brainstorming - bitte um feedback ob ich damit richtig liegen kann !!!

bei all diesen fehlern kommt immer wieder das netzwerk ins spiel. wenn man so was wie doppelte ip's mal weg läßt können auch falsch programmierte http requests solche fehler verursachen. sucht man auf ggl nach "socket error hang" gibt es einiges dazu.

solche fehler können in adaptern oder eigenen scripts vorkopmmen. wenn du aber ein adapter update machst und der fehler taucht da auf (habe heute auch den nodered upgedatet - ohne probleme), gehe ich davon aus, dass die adapter keine fehler haben, sondern es in richtung node/npm installation/version gehen könnte.

daher mein vorschlag:
versuch doch mal - nach einem "iobrocker backup" - das "npm rebuild"  (im iobroker verzeichnis) , um sicher zu sein, dass alle adapter auf die node version passen. evtl noch ein "npm cache clean" nach dem rebuild http://www.iobroker.net/docu/?page_id=8323&lang=de .
man könnte auch nochmal über eine neue installation der node usw nachdenken. mal node deinstallieren und prüfen, ob dann noch eine alte version zum vorschein kommen
ob die js-controller version da auch rein"spinnt" weiß ich nicht - welche hast du?

gibt es jmd. von euch der das nachvollziehen kann ?

[crepp1967]

Ich habe gerade gesehen dass ich ein update des js-controllers angezeigt bekomme. Ich habe heute npm rebuild gemacht. Davor, schwöre ich Stein u. Bein, war bei beiden Servern, Master & Slave die controller Version 1.57. Also ich werde jetzt auf dem Master erst mal die controller Version updaten und melde mich dann wieder wenn es neue Erkenntnisse gibt.
Gruß
crepp

[eumats]

Hallo zusammen,

ich möchte mich da mal ranhängen. Ich habe die gleich Probleme wie @crepp1967.
Ich benutze 2 Raspi 3b(1x +, 1x kein +) im Multihost. Darauf laufen 18 Adapter. 8 auf dem Client (inkl. admin und javascript) und 10 auf dem Master. Bei mit stützt der Client immer ab.

2 konkrete Fragen habe ich:

1. Wie kann ich das Diagram mit der Auslastung erzeugen?
2. Braucht man für js-controller 1.5.7 auf jeden Fall node >=10? Denn ich habe aktuell auch V8.15.1

Mein Log sieht beim Absturz wie folgt aus:

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)
...

[crepp1967]

Hallo, Ich denke das ich das Problem gelöst habe:
Habe wie schon oben gesagt 17 Adapter auf dem Master laufen. zusätzlich habe ich nun die aktuelle JS-Controller Version drauf. Das "npm rebuild" war der entscheidende Hinweis (denke ich) Danach und einem Neustart des Servers. wurde plötzlich die wahre Version auf dem Master angezeigt ! Dies war die veraltete 1.42. Seitdem ich die aktuelle 1.57 und nur noch 17 Adapter auf dem Master am Laufen habe läuft der Master superstabil . Alle restlichen (benötigten) Adpater ziehe ich jetzt so nach und nach auf den Slave um. Damit sollte das Problem gelöst sein.
Vielen Dank für die vielen helfenden Hinweise aus dem Forum !!!
Das schätze ich so an diesem Forum.
Grüße aus Thüringen
crepp

[liv-in-sky]

@eumats ich glaube nicht das du das selbe problem hast. der einzige error ist , dass du im admin nicht deine geo daten (wohnort) eingegeben hast

mach doch lieber einen eigenen thread auf und binde dein error log des iobrokers (das ganze) als txt file in den thread ein (wenn du willst, erwähne mich, dann schauen wir, was geht)

@crepp1967

cool das es funktioniert - vielleicht hast du lust dem eumats zu erklären, wie du den graphen gemacht hast

würdest du bitte noch ein gelöst vor deine überschrift im ersten thread setzen.

[eumats]

@liv-in-sky
Der Wohnort ist bei mir im admin eingetragen. Der Fehler im Log ist nur ein Nebeneffekt. Denn sonst läuft das System ja stabil. Nur alle 3-4 Tage stürzt er ab. Ich schätze das die beiden Raspis für meinen Anwendungsfall nicht mehr reichen. Daher der Umstieg auf den NUC (siehe anderen Thread).

[Homoran]

@crepp1967 sagte in Probleme mit iobroker - bin mit meinem Latein am Ende:

Danach und einem Neustart des Servers. wurde plötzlich die wahre Version auf dem Master angezeigt ! Dies war die veraltete 1.42. Seitdem ich die aktuelle 1.57

Hatt3st du den controller upgedate und danach NICHT rebooted?
Dann war der neustart die lösung.

Hattest du ein node update auf eine höhere major version gemacht war der rebuild die lösung.

[crepp1967]

@Homoran Ich kann es mir eigtl. nicht erklären warum der Server die veraltete JS-Version hatte. Ich mache standardmäßig nach einem JS Upgrade einen -Neustart. Ausserdem mache ich über Cron jede Nacht einen Neustart . Deswegen denke ich das rebuild wars ...

[crepp1967]

@eumats
Die Graphen habe ich über den rpimonitor (ein Programm welches bei mir auf jedem raspi landet, superflexibel schnell und braucht wenig Ressourcen (link)