FHEM startet nicht mehr auf Raspi 4

Begonnen von Nemo0815, 16 August 2019, 17:13:42

Vorheriges Thema - Nächstes Thema

Wernieman

Oder einfach mal "ssh" eintackten ... ;o)
- Bitte um Input für Output
- When there is a Shell, there is a Way
- Wann war Dein letztes Backup?

Wie man Fragen stellt: https://tty1.net/smart-questions_de.html

frank

ZitatVerstehe nur nicht wieso das jetzt plötzlich - ohne Änderungen oder Updates am System, nur nach einem Router neustart so auftritt. Macht irgendwie keinen Sinn, weils vorher immer problemlos funktioniert hatte...(aber evtl. braucht der Raspi jetzt länger bis er seine IP bekommt...)
da fallen mir aber einige scenarien ein, die sinn ergeben.

router config gändert und nicht gespeichert.
nach neustart kommt die alte config.

oder wlan channel ändert sich.
FHEM: 6.0(SVN) => Pi3(buster)
IO: CUL433|CUL868|HMLAN|HMUSB2|HMUART
CUL_HM: CC-TC|CC-VD|SEC-SD|SEC-SC|SEC-RHS|Sw1PBU-FM|Sw1-FM|Dim1TPBU-FM|Dim1T-FM|ES-PMSw1-Pl
IT: ITZ500|ITT1500|ITR1500|GRR3500
WebUI [HMdeviceTools.js (hm.js)]: https://forum.fhem.de/index.php/topic,106959.0.html

cetri

Zitat von: Wernieman am 17 August 2019, 20:47:55

PS: vor der Installation von OWServer und des HM USB Cfg Models konnte ich FHEM noch erreichen da ich da ein Update durchgeführt habe?
Hatten wir vor kurzen mit buster nicht so etwas hier im Forum??

Zumindest mit OWServer unter Buster hab ich Probleme die immer noch nicht gelöst sind. Vielleicht meintest du ja meinen Beitrag. https://forum.fhem.de/index.php/topic,103008.msg966690.html#msg966690

Jens_B

Zitat von: Nemo0815 am 17 August 2019, 12:38:32
Wenn ich mir das syslog so anschaue sind da schon etwas seltsame Einträge enthalten: 

Aug 17 11:54:56 raspberrypi rc.local[598]: Can't connect to localhost:7072
Aug 17 11:54:59 raspberrypi java[610]: 2019-08-17 11:54:59,488 [main] WARN  com.bwssystems.HABridge.upnp.UpnpListener - UpnpListener encountered an error sending upnp notify packet. IP: 239.255.255.250 with message: Das Netzwerk ist nicht erreichbar (sendto failed)
Aug 17 11:55:01 raspberrypi CRON[6342]: (root) CMD (command -v debian-sa1 > /dev/null && debian-sa1 1 1)
Aug 17 11:55:01 raspberrypi rc.local[598]: Can't connect to localhost:7072
Aug 17 11:55:06 raspberrypi rc.local[598]: Can't connect to localhost:7072
Aug 17 11:55:11 raspberrypi rc.local[598]: Can't connect to localhost:7072
Aug 17 11:55:16 raspberrypi rc.local[598]: Can't connect to localhost:7072
Aug 17 11:55:19 raspberrypi java[610]: 2019-08-17 11:55:19,509 [main] WARN  com.bwssystems.HABridge.upnp.UpnpListener - UpnpListener encountered an error sending upnp notify packet. IP: 239.255.255.250 with message: Das Netzwerk ist nicht erreichbar (sendto failed)
Aug 17 11:55:21 raspberrypi rc.local[598]: Can't connect to localhost:7072
Aug 17 11:55:26 raspberrypi rc.local[598]: Can't connect to localhost:7072
Aug 17 11:55:31 raspberrypi ntpd[682]: error resolving pool 2.debian.pool.ntp.org: Temporary failure in name resolution (-3)
Aug 17 11:55:31 raspberrypi rc.local[598]: Can't connect to localhost:7072
Aug 17 11:55:36 raspberrypi rc.local[598]: Can't connect to localhost:7072
Aug 17 11:55:39 raspberrypi java[610]: 2019-08-17 11:55:39,530 [main] WARN  com.bwssystems.HABridge.upnp.UpnpListener - UpnpListener encountered an error sending upnp notify packet. IP: 239.255.255.250 with message: Das Netzwerk ist nicht erreichbar (sendto failed)
Aug 17 11:55:42 raspberrypi rc.local[598]: Can't connect to localhost:7072
Aug 17 11:55:46 raspberrypi ntpd[682]: error resolving pool 0.debian.pool.ntp.org: Temporary failure in name resolution (-3)
Aug 17 11:55:46 raspberrypi rc.local[598]: Can't connect to localhost:7072
Aug 17 11:55:47 raspberrypi ntpd[682]: error resolving pool 3.debian.pool.ntp.org: Temporary failure in name resolution (-3)
Aug 17 11:55:52 raspberrypi rc.local[598]: Can't connect to localhost:7072
Aug 17 11:55:52 raspberrypi ntpd[682]: error resolving pool 1.debian.pool.ntp.org: Temporary failure in name resolution (-3)
Aug 17 11:55:57 raspberrypi rc.local[598]: Can't connect to localhost:7072
Aug 17 11:55:59 raspberrypi java[610]: 2019-08-17 11:55:59,552 [main] WARN  com.bwssystems.HABridge.upnp.UpnpListener - UpnpListener encountered an error sending upnp notify packet. IP: 239.255.255.250 with message: Das Netzwerk ist nicht erreichbar (sendto failed)
Aug 17 11:56:02 raspberrypi rc.local[598]: Can't connect to localhost:7072
Aug 17 11:56:07 raspberrypi rc.local[598]: Can't connect to localhost:7072
Aug 17 11:56:12 raspberrypi rc.local[598]: Can't connect to localhost:7072
Aug 17 11:56:17 raspberrypi rc.local[598]: Can't connect to localhost:7072
Aug 17 11:56:19 raspberrypi java[610]: 2019-08-17 11:56:19,573 [main] WARN  com.bwssystems.HABridge.upnp.UpnpListener - UpnpListener encountered an error sending upnp notify packet. IP: 239.255.255.250 with message: Das Netzwerk ist nicht erreichbar (sendto failed)
Aug 17 11:56:22 raspberrypi rc.local[598]: Can't connect to localhost:7072
Aug 17 11:56:27 raspberrypi rc.local[598]: Can't connect to localhost:7072
Aug 17 11:56:32 raspberrypi rc.local[598]: Can't connect to localhost:7072
Aug 17 11:56:37 raspberrypi rc.local[598]: Can't connect to localhost:7072
Aug 17 11:56:38 raspberrypi ntpd[682]: error resolving pool 2.debian.pool.ntp.org: Temporary failure in name resolution (-3)
Aug 17 11:56:39 raspberrypi java[610]: 2019-08-17 11:56:39,594 [main] WARN  com.bwssystems.HABridge.upnp.UpnpListener - UpnpListener encountered an error sending upnp notify packet. IP: 239.255.255.250 with message: Das Netzwerk ist nicht erreichbar (sendto failed)
Aug 17 11:56:42 raspberrypi rc.local[598]: Can't connect to localhost:7072
Aug 17 11:56:47 raspberrypi rc.local[598]: Can't connect to localhost:7072
Aug 17 11:56:52 raspberrypi ntpd[682]: error resolving pool 3.debian.pool.ntp.org: Temporary failure in name resolution (-3)
Aug 17 11:56:52 raspberrypi ntpd[682]: error resolving pool 0.debian.pool.ntp.org: Temporary failure in name resolution (-3)
Aug 17 11:56:52 raspberrypi rc.local[598]: Can't connect to localhost:7072
Aug 17 11:56:57 raspberrypi ntpd[682]: error resolving pool 1.debian.pool.ntp.org: Temporary failure in name resolution (-3)
Aug 17 11:56:57 raspberrypi rc.local[598]: Can't connect to localhost:7072
Aug 17 11:56:59 raspberrypi java[610]: 2019-08-17 11:56:59,616 [main] WARN  com.bwssystems.HABridge.upnp.UpnpListener - UpnpListener encountered an error sending upnp notify packet. IP: 239.255.255.250 with message: Das Netzwerk ist nicht erreichbar (sendto failed)
Aug 17 11:57:02 raspberrypi rc.local[598]: Can't connect to localhost:7072
Aug 17 11:57:07 raspberrypi rc.local[598]: Can't connect to localhost:7072
Aug 17 11:57:08 raspberrypi motion: [1:ml1] [NTC] [ALL] Above message repeats 1 times
Aug 17 11:57:08 raspberrypi motion: [1:ml1] [NTC] [ALL] motion_detected: Motion detected - starting event 5
Aug 17 11:57:13 raspberrypi rc.local[598]: Can't connect to localhost:7072
Aug 17 11:57:18 raspberrypi rc.local[598]: Can't connect to localhost:7072
Aug 17 11:57:19 raspberrypi java[610]: 2019-08-17 11:57:19,637 [main] WARN  com.bwssystems.HABridge.upnp.UpnpListener - UpnpListener encountered an error sending upnp notify packet. IP: 239.255.255.250 with message: Das Netzwerk ist nicht erreichbar (sendto failed)
Aug 17 11:57:23 raspberrypi rc.local[598]: Can't connect to localhost:7072
Aug 17 11:57:28 raspberrypi rc.local[598]: Can't connect to localhost:7072
Aug 17 11:57:33 raspberrypi rc.local[598]: Can't connect to localhost:7072
Aug 17 11:57:38 raspberrypi rc.local[598]: Can't connect to localhost:7072



Benutzt Du Alexa in Verbindung mit fhem?
Was passiert mit Deinem PI, wenn Du die default cfg von fhem nimmst? Läuft es dann ganz normal?
RaspberryPi 4 (Raspian Buster)FHEM+Homebridge
HMLAN für Homematic
Z-Wave USB Stick
Shelly Devices
Fritz!Box 7590Ax

Christoph Morrison

Hast du inzwischen mal den Test mit Ethernet gemacht?

Nemo0815

Zitat von: Jens_B am 19 August 2019, 16:50:36

Benutzt Du Alexa in Verbindung mit fhem?
Was passiert mit Deinem PI, wenn Du die default cfg von fhem nimmst? Läuft es dann ganz normal?

Ja, ich benutze Alexa, mit der default config hab ichs noch nicht probiert, kann ich noch machen.

Zitat von: Christoph Morrison am 19 August 2019, 17:26:18
Hast du inzwischen mal den Test mit Ethernet gemacht?

Nein noch nicht, mangels Kabel.

Mein oben beschriebener Fix hat übrigens nichts bewirkt so wie's aussieht, beim nächsten "sudo reboot" hängt alles wieder, bzw. ist nicht mehr erreichbar (weder der pi per ssh oder ping, noch fhem).

Nemo0815

#21
Hab mir nochmal das syslog angeschaut, sieht schon iwie nach Netzwerk-/Interfaceproblem aus...

run-parts: /etc/network/if-pre-up.d/wpasupplicant exited with return code 1
Aug 19 19:17:06 raspberrypi sh[307]: ifup: failed to bring up wlan0
Aug 19 19:17:06 raspberrypi systemd[1]: ifup@wlan0.service: Main process exited, code=exited, status=1/FAILURE
Aug 19 19:17:06 raspberrypi systemd[1]: ifup@wlan0.service: Failed with result 'exit-code'.
Aug 19 19:17:06 raspberrypi kernel: [    7.991937] bcmgenet fd580000.genet: configuring instance for external RGMII (no delay)
Aug 19 19:17:06 raspberrypi kernel: [    7.992910] brcmfmac: power management disabled
Aug 19 19:17:06 raspberrypi dhcpcd[378]: eth0: waiting for carrier
Aug 19 19:17:06 raspberrypi dhcpcd[378]: wlan0: waiting for carrier
Aug 19 19:17:06 raspberrypi kernel: [    8.161728] Under-voltage detected! (0x00050005)
Aug 19 19:17:07 raspberrypi systemd[1]: Started LSB: FHEM server.
Aug 19 19:17:07 raspberrypi kernel: [    9.031858] bcmgenet fd580000.genet eth0: Link is Down



allerdings das hier spricht wieder eine andere Sprache...

19 20:05:31 raspberrypi systemd[1]: Binding to IPv6 address not available since kernel does no
t support IPv6.
Aug 19 20:05:31 raspberrypi systemd[1]: Binding to IPv6 address not available since kernel does no
t support IPv6.
Aug 19 20:05:31 raspberrypi systemd[1]: Stopping LSB: FHEM server...
Aug 19 20:05:31 raspberrypi fhem[1605]: Stopping fhem...
Aug 19 20:05:31 raspberrypi systemd[1]: fhem.service: Succeeded.
Aug 19 20:05:31 raspberrypi systemd[1]: Stopped LSB: FHEM server.
Aug 19 20:05:42 raspberrypi watchdog[978]: loadavg 38 8 2 is higher than the given threshold 24 18
12!
Aug 19 20:05:42 raspberrypi watchdog[978]: shutting down the system because of error 253 = 'load a
verage too high'

frank

1. fhem startet vor dem netzwerk.
2. undervoltage detected => besseres netzteil nutzen
FHEM: 6.0(SVN) => Pi3(buster)
IO: CUL433|CUL868|HMLAN|HMUSB2|HMUART
CUL_HM: CC-TC|CC-VD|SEC-SD|SEC-SC|SEC-RHS|Sw1PBU-FM|Sw1-FM|Dim1TPBU-FM|Dim1T-FM|ES-PMSw1-Pl
IT: ITZ500|ITT1500|ITR1500|GRR3500
WebUI [HMdeviceTools.js (hm.js)]: https://forum.fhem.de/index.php/topic,106959.0.html

Nemo0815

Zitat von: frank am 19 August 2019, 21:16:32
1. fhem startet vor dem netzwerk.
2. undervoltage detected => besseres netzteil nutzen

Das mit dem Netzteil ist kein Problem, zumindest funktioniert es auch mit einem Guten (15W USB-C) ebenso wenig.

Aber wie bringe ich fhem jetzt dazu nach dem Netzwerk zu starten? habe ja in der service config schon selbiges ohne Erfolg versucht (siehe vorher).

Wernieman

run-parts: /etc/network/if-pre-up.d/wpasupplicant exited with return code 1
Du hast ein WLAN-Problem ... was sagen die passenden Logfiles?
- Bitte um Input für Output
- When there is a Shell, there is a Way
- Wann war Dein letztes Backup?

Wie man Fragen stellt: https://tty1.net/smart-questions_de.html

Jens_B

Zitat von: Nemo0815 am 19 August 2019, 21:30:04
Das mit dem Netzteil ist kein Problem, zumindest funktioniert es auch mit einem Guten (15W USB-C) ebenso wenig.

Aber wie bringe ich fhem jetzt dazu nach dem Netzwerk zu starten? habe ja in der service config schon selbiges ohne Erfolg versucht (siehe vorher).

Das mit dem Netzteil ist manchmal doch ein Problem. Zumindest auf den älteren PIs.

Die Under Voltage zum Beispiel kann dazu führen das das WLAN nicht vernünftig funktioniert. Selbst schon erlebt....

Schon mal versucht, den PI über Ethernet anzubinden? Was passiert dann?
RaspberryPi 4 (Raspian Buster)FHEM+Homebridge
HMLAN für Homematic
Z-Wave USB Stick
Shelly Devices
Fritz!Box 7590Ax

Christoph Morrison

Zitat von: Jens_B am 21 August 2019, 12:04:43
Schon mal versucht, den PI über Ethernet anzubinden? Was passiert dann?

17. August #msg967540
19. August #msg968154

Ceterum censeo filum autem probentur.

Jens_B

Zitat von: Christoph Morrison am 21 August 2019, 12:13:26
17. August #msg967540
19. August #msg968154

Ceterum censeo filum autem probentur.

Ja sorry, habe ich nicht gelesen, das es schon erwähnt wurde und noch nicht probiert wurde. Kann ja nicht schaden es öfter zu erwähnen ;-)
RaspberryPi 4 (Raspian Buster)FHEM+Homebridge
HMLAN für Homematic
Z-Wave USB Stick
Shelly Devices
Fritz!Box 7590Ax

Jens_B

Zitat von: Nemo0815 am 19 August 2019, 21:30:04
Das mit dem Netzteil ist kein Problem, zumindest funktioniert es auch mit einem Guten (15W USB-C) ebenso wenig.

Aber wie bringe ich fhem jetzt dazu nach dem Netzwerk zu starten? habe ja in der service config schon selbiges ohne Erfolg versucht (siehe vorher).

Also erstmal würde ich fhem mal probeweise deaktivieren über
sudo systemctl disable fhem

Außerdem fährt dein Raspberry ja anscheinend runter weil der watchdog so eingerichtet ist das er bei überlast runterfährt.

RaspberryPi 4 (Raspian Buster)FHEM+Homebridge
HMLAN für Homematic
Z-Wave USB Stick
Shelly Devices
Fritz!Box 7590Ax

Jens_B

Zitat von: Helmi55 am 17 August 2019, 18:39:23
guten Abend ich hänge mich hier dran.
Habe meinen RPi4 mit 4GB heute neu mit Buster light aufgesetzt. Einiges nachinstalliert und dann das BU des alten RPi's aufgespielt.
Am Mac in der Konsole bekomme ich eine Verbindung zum Pi aber FHEM kann ich nicht erreichen.

ein sudo systemctl status fhem

bringt folgendes und sagt mir leider nix.....  sorry
● fhem.service - FHEM Home Automation
   Loaded: loaded (/etc/systemd/system/fhem.service; enabled; vendor preset: enabled)
   Active: failed (Result: exit-code) since Sat 2019-08-17 16:31:36 CEST; 8min ago

Aug 17 16:31:36 RPiGarageneu systemd[1]: fhem.service: Service RestartSec=100ms expired, scheduling restart.
Aug 17 16:31:36 RPiGarageneu systemd[1]: fhem.service: Scheduled restart job, restart counter is at 5.
Aug 17 16:31:36 RPiGarageneu systemd[1]: Stopped FHEM Home Automation.
Aug 17 16:31:36 RPiGarageneu systemd[1]: fhem.service: Start request repeated too quickly.
Aug 17 16:31:36 RPiGarageneu systemd[1]: fhem.service: Failed with result 'exit-code'.
Aug 17 16:31:36 RPiGarageneu systemd[1]: Failed to start FHEM Home Automation.



Was kann ich da bitte noch versuchen??

PS: vor der Installation von OWServer und des HM USB Cfg Models konnte ich FHEM noch erreichen da ich da ein Update durchgeführt habe????

Gruß
Helmut

Das Logfile von fhem wäre vielleicht hilfreich...  oder mal den Zustand vor Installation von OWServer oder HM USB zurückspielen...
RaspberryPi 4 (Raspian Buster)FHEM+Homebridge
HMLAN für Homematic
Z-Wave USB Stick
Shelly Devices
Fritz!Box 7590Ax