FHEM - Hardware > Einplatinencomputer

GELÖST fhem - verschiedene Fehlermeldungen ohne Ende. So nicht mehr nutzbar.

(1/9) > >>

Invers:
Mein fhem arbeitet nur noch sehr fehlerhaft. Das Log füllt sich mit den verschiedendsten Fehlermeldungen, die nichts mit einander zu tun haben. Es kommen Melfungen zur Fritzbox (Netzwerk), Presencemodul, Signalduino, Bluetooth, HM u.s.w.
Nach einem Neustart des Pi läuft alles für etwa 5-10 Minuten, dann beginnt alles von vorne.
Ich habe entsprechend dem WIKI Fehlersuche betrieben, ohne Erfolg.
Ich habe weitere folgende Lösungsversuche unternommen:

Netzteil am Pi getauscht
ganzen Pi getauscht
Netzwerkkabel vom Pi zu FBox getauscht
SSD getauscht mit einer SSD vom Vormonat, also fhem aus Monat 07.
Fritzbox neu gestartet
alle Geräte neu gestartet
Pi mit fhem ohne weitere Hardware laufen lassen

Den Rest habe ich vergessen.

Kann jemand versuchen, mir bei der Lösung des Problems behilflich zu sein?
Wenn ich keine Lösung finden kann, muss ich die Hausautomatisierung aufgeben. Mir sind die Ideen ausgegangen.

Bis zum 31.08. lief alles perfekt.

Hier mal ein Stück des Log, gibt aber noch viel mehr verschiedene Fehler.
Ich hatte das Logfile löschen müssen, war schon zu gross.
a51 sind Handys.
Am Pi steckt ausschliesslich die SSD (eigenes Netzteil) und der Bluetoothdongle.


--- Code: ---2021.09.05 12:58:12 1: Logfile gelöscht
2021.09.05 12:59:18 1: ▀ Schloss Status locked
2021.09.05 12:59:19 3: FBDECT set AlarmCam off
2021.09.05 12:59:19 3: FBDECT set Uhr on
2021.09.05 12:59:19 3: ▀ schalten bei Ankunft
2021.09.05 12:59:19 3: FBDECT set AlarmCam off
2021.09.05 12:59:19 3: ▀ Steckdose_H2 Ladegeräte on
2021.09.05 12:59:23 2: hue: http request failed: 192.168.178.66: Keine Route zum Zielrechner (113)
2021.09.05 12:59:28 1: ▀ Schloss Status unlocked
2021.09.05 12:59:29 3: ▀ Tab_A screen off
2021.09.05 12:59:30 3: ▀ Tab_b screen off
2021.09.05 12:59:31 3: CUL_HM set Schloss lock noArg
2021.09.05 12:59:40 1: ▀ Schloss Status locked
2021.09.05 13:00:21 3: FBDECT set Blumen off
2021.09.05 13:00:21 3: MQTT2_DEVICE set Steckdose_2 off
2021.09.05 13:00:25 2: hue: http request failed: 192.168.178.66: Keine Route zum Zielrechner (113)
2021.09.05 13:01:30 3: telnetForTvUpdateFn_1630839690: port 35581 opened
2021.09.05 13:02:01 1: ▀ Schloss Status locked
2021.09.05 13:02:02 2: PRESENCE (a51H) - device could not be checked (retrying in 10 seconds): Process died prematurely
Can't use an undefined value as a HASH reference at (eval 160) line 272.
2021.09.05 13:02:11 1: ▀ Schloss Status unlocked
2021.09.05 13:02:12 1: FRITZBOX Fritzbox: Readout_Aborted.1931 Error: Timeout when reading Fritz!Box data.
2021.09.05 13:02:12 2: PRESENCE (a51K) - device could not be checked (retrying in 10 seconds): Process died prematurely
2021.09.05 13:02:19 3: CUL_HM set Schloss lock noArg
2021.09.05 13:02:23 2: PRESENCE (a51H) - device could not be checked after 1 retry (retrying in 10 seconds): Process died prematurely
2021.09.05 13:02:28 1: ▀ Schloss Status locked
2021.09.05 13:02:33 2: PRESENCE (a51K) - device could not be checked after 1 retry (retrying in 10 seconds): Process died prematurely
2021.09.05 13:03:33 1: Timeout for PRESENCE_DoLocalBluetoothScan reached, terminated process 1553
2021.09.05 13:03:33 2: PRESENCE (a51H) - device could not be checked after 2 retries (retrying in 10 seconds): Timeout: process terminated
2021.09.05 13:03:33 2: PRESENCE (a51K) - device could not be checked after 2 retries (retrying in 10 seconds): Process died prematurely
2021.09.05 13:04:43 1: Timeout for PRESENCE_DoLocalBluetoothScan reached, terminated process 1571
2021.09.05 13:04:43 2: PRESENCE (a51H) - device could not be checked after 3 retries (resuming normal operation): Timeout: process terminated
2021.09.05 13:04:43 1: Timeout for PRESENCE_DoLocalBluetoothScan reached, terminated process 1572
2021.09.05 13:04:43 2: PRESENCE (a51K) - device could not be checked after 3 retries (resuming normal operation): Timeout: process terminated
2021.09.05 13:06:30 1: Timeout for DOIF::doParse reached, terminated process 1500
Can't use an undefined value as a HASH reference at (eval 160) line 272.
2021.09.05 13:06:47 2: PRESENCE (a51H) - device could not be checked (retrying in 10 seconds): Process died prematurely
2021.09.05 13:07:02 2: PRESENCE (a51K) - device could not be checked (retrying in 10 seconds): Process died prematurely
2021.09.05 13:07:12 2: PRESENCE (a51H) - device could not be checked after 1 retry (retrying in 10 seconds): Process died prematurely
2021.09.05 13:07:12 1: FRITZBOX Fritzbox: Readout_Aborted.1931 Error: Timeout when reading Fritz!Box data.
2021.09.05 13:07:22 1: 192.168.178.35:1000 disconnected, waiting to reappear (HMLAN1)
2021.09.05 13:07:22 1: HMLAN_Parse: HMLAN1 new condition disconnected
2021.09.05 13:08:12 1: Timeout for PRESENCE_DoLocalBluetoothScan reached, terminated process 1725
2021.09.05 13:08:12 2: PRESENCE (a51K) - device could not be checked after 1 retry (retrying in 10 seconds): Timeout: process terminated
2021.09.05 13:08:13 2: PRESENCE (a51H) - device could not be checked after 2 retries (retrying in 10 seconds): Process died prematurely
2021.09.05 13:08:22 1: HMLAN_Parse: HMLAN1 new condition init
2021.09.05 13:08:22 1: 192.168.178.35:1000 reappeared (HMLAN1)
2021.09.05 13:08:22 1: HMLAN_Parse: HMLAN1 new condition ok
2021.09.05 13:09:22 1: Timeout for PRESENCE_DoLocalBluetoothScan reached, terminated process 1746
2021.09.05 13:09:22 2: PRESENCE (a51K) - device could not be checked after 2 retries (retrying in 10 seconds): Timeout: process terminated
2021.09.05 13:09:23 2: PRESENCE (a51H) - device could not be checked after 3 retries (resuming normal operation): Process died prematurely
2021.09.05 13:10:32 2: PRESENCE (a51K) - device could not be checked after 3 retries (resuming normal operation): Process died prematurely
2021.09.05 13:11:30 1: Timeout for DOIF::doParse reached, terminated process 1656
Can't use an undefined value as a HASH reference at (eval 160) line 272.
2021.09.05 13:11:52 2: PRESENCE (a51H) - device could not be checked (retrying in 10 seconds): Process died prematurely
2021.09.05 13:12:02 2: PRESENCE (a51K) - device could not be checked (retrying in 10 seconds): Process died prematurely
2021.09.05 13:12:21 2: PRESENCE (a51H) - device could not be checked after 1 retry (retrying in 10 seconds): Process died prematurely
2021.09.05 13:12:31 2: PRESENCE (a51K) - device could not be checked after 1 retry (retrying in 10 seconds): Process died prematurely
2021.09.05 13:12:57 1: Timeout for FRITZBOX_Readout_Run_Web reached, terminated process 1954
2021.09.05 13:12:57 1: FRITZBOX Fritzbox: Readout_Aborted.1931 Error: Timeout when reading Fritz!Box data.
2021.09.05 13:12:58 2: PRESENCE (a51H) - device could not be checked after 2 retries (retrying in 10 seconds): Process died prematurely
2021.09.05 13:13:11 1: 192.168.178.35:1000 disconnected, waiting to reappear (HMLAN1)
2021.09.05 13:13:11 1: HMLAN_Parse: HMLAN1 new condition disconnected
2021.09.05 13:13:41 1: Timeout for PRESENCE_DoLocalBluetoothScan reached, terminated process 1975
2021.09.05 13:13:41 2: PRESENCE (a51K) - device could not be checked after 2 retries (retrying in 10 seconds): Timeout: process terminated
2021.09.05 13:13:41 2: PRESENCE (a51H) - device could not be checked after 3 retries (resuming normal operation): Process died prematurely
2021.09.05 13:14:11 1: HMLAN_Parse: HMLAN1 new condition init
2021.09.05 13:14:11 1: 192.168.178.35:1000 reappeared (HMLAN1)
2021.09.05 13:14:12 1: HMLAN_Parse: HMLAN1 new condition ok
2021.09.05 13:14:41 1: 192.168.178.35:1000 disconnected, waiting to reappear (HMLAN1)
2021.09.05 13:14:41 1: HMLAN_Parse: HMLAN1 new condition disconnected
2021.09.05 13:15:01 1: Timeout for PRESENCE_DoLocalBluetoothScan reached, terminated process 1997
2021.09.05 13:15:01 2: PRESENCE (a51K) - device could not be checked after 3 retries (resuming normal operation): Timeout: process terminated
2021.09.05 13:15:41 1: HMLAN_Parse: HMLAN1 new condition init
2021.09.05 13:15:41 1: 192.168.178.35:1000 reappeared (HMLAN1)
2021.09.05 13:15:42 1: HMLAN_Parse: HMLAN1 new condition ok
2021.09.05 13:16:30 1: Timeout for DOIF::doParse reached, terminated process 1919
--- Ende Code ---


Danke im Voraus.

Christoph Morrison:
Wie alt ist dein Blocking.pm?
Wie sieht die Systemauslastung aus?

Invers:
Hier noch ein Stück aus der Syslog
Blocking.pm 23268 2020-12-01 11:48:48Z rudolfkoenig

Ich hatte zusätzlich noch ein update force gemacht. Also sollte alles aktuell sein. Hatte ich vergessen zu erwähnen.

Systemauslastung ist bei etwa 15%, schwankend.


--- Code: ---pi@fhem3:~ $ sudo grep -i fail -A1 -B1 /var/log/syslog
Sep  5 08:19:57 fhem3 kernel: [    0.257084] bcm2835-rng 3f104000.rng: hwrng registered
Sep  5 08:19:57 fhem3 systemd-udevd[179]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1.
Sep  5 08:19:57 fhem3 kernel: [    0.259828] vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB)
--
Sep  5 09:42:41 fhem3 kernel: [    0.765686] Interrupt/Control Split Transaction hack enabled
Sep  5 09:42:41 fhem3 systemd-udevd[201]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1.
Sep  5 09:42:41 fhem3 kernel: [    0.776602] dwc_otg: Microframe scheduler enabled
--
Sep  5 09:42:41 fhem3 bluetoothd[364]: Bluetooth management interface 1.14 initialized
Sep  5 09:42:41 fhem3 bluetoothd[364]: Failed to obtain handles for "Service Changed" characteristic
Sep  5 09:42:41 fhem3 systemd[1]: Started System Logging Service.
--
Sep  5 09:42:41 fhem3 systemd[1]: Started Disable WiFi if country not set.
Sep  5 09:42:41 fhem3 bluetoothd[364]: Sap driver initialization failed.
Sep  5 09:42:41 fhem3 bluetoothd[364]: sap-server: Operation not permitted (1)
--
Sep  5 09:42:41 fhem3 kernel: [   11.653037] Bluetooth: RFCOMM ver 1.11
Sep  5 09:42:41 fhem3 bluetoothd[364]: Failed to set privacy: Rejected (0x0b)
Sep  5 09:42:41 fhem3 kernel: [   11.732131] Adding 102396k swap on /var/swap.  Priority:-2 extents:1 across:102396k FS
--
Sep  5 09:56:07 fhem3 systemd[1]: Reached target Local File Systems.
Sep  5 09:56:07 fhem3 systemd-udevd[206]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1.
Sep  5 09:56:07 fhem3 systemd[1]: Starting Create Volatile Files and Directories...
--
Sep  5 09:56:07 fhem3 systemd[1]: Starting Avahi mDNS/DNS-SD Stack...
Sep  5 09:56:07 fhem3 bluetoothd[356]: Failed to obtain handles for "Service Changed" characteristic
Sep  5 09:56:07 fhem3 dbus[375]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service'
--
Sep  5 09:56:07 fhem3 cron[422]: (CRON) INFO (Running @reboot jobs)
Sep  5 09:56:07 fhem3 bluetoothd[356]: Sap driver initialization failed.
Sep  5 09:56:07 fhem3 bluetoothd[356]: sap-server: Operation not permitted (1)
--
Sep  5 09:56:07 fhem3 dphys-swapfile[354]: done.
Sep  5 09:56:07 fhem3 bluetoothd[356]: Failed to set privacy: Rejected (0x0b)
Sep  5 09:56:07 fhem3 kernel: [   11.538026] Bluetooth: RFCOMM TTY layer initialized
--
Sep  5 11:16:26 fhem3 systemd[1]: Started Preprocess NFS configuration.
Sep  5 11:16:26 fhem3 systemd-udevd[224]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1.
Sep  5 11:16:26 fhem3 systemd[1]: Reached target NFS client services.
--
Sep  5 11:16:26 fhem3 bluetoothd[387]: Bluetooth management interface 1.14 initialized
Sep  5 11:16:26 fhem3 bluetoothd[387]: Failed to obtain handles for "Service Changed" characteristic
Sep  5 11:16:26 fhem3 wpa_supplicant[394]: Successfully initialized wpa_supplicant
Sep  5 11:16:26 fhem3 dphys-swapfile[396]: want /var/swap=100MByte, checking existing: keeping it
Sep  5 11:16:26 fhem3 bluetoothd[387]: Sap driver initialization failed.
Sep  5 11:16:26 fhem3 bluetoothd[387]: sap-server: Operation not permitted (1)
--
Sep  5 11:16:26 fhem3 dhcpcd[406]: eth0: waiting for carrier
Sep  5 11:16:26 fhem3 bluetoothd[387]: Failed to set privacy: Rejected (0x0b)
Sep  5 11:16:26 fhem3 dhcpcd[406]: eth0: carrier acquired
--
Sep  5 11:43:20 fhem3 systemd[1]: Starting Create Volatile Files and Directories...
Sep  5 11:43:20 fhem3 systemd-udevd[209]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1.
Sep  5 11:43:20 fhem3 kernel: [    0.000000] Booting Linux on physical CPU 0x0
--
Sep  5 12:44:50 fhem3 systemd[1]: Reached target Local File Systems.
Sep  5 12:44:50 fhem3 systemd-udevd[186]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1.
Sep  5 12:44:50 fhem3 systemd[1]: Starting Tell Plymouth To Write Out Runtime Data...
--
Sep  5 12:44:51 fhem3 bluetoothd[354]: Bluetooth management interface 1.14 initialized
Sep  5 12:44:51 fhem3 bluetoothd[354]: Failed to obtain handles for "Service Changed" characteristic
Sep  5 12:44:51 fhem3 bluetoothd[354]: Sap driver initialization failed.
Sep  5 12:44:51 fhem3 bluetoothd[354]: sap-server: Operation not permitted (1)
--
Sep  5 12:44:51 fhem3 wpa_supplicant[430]: Successfully initialized wpa_supplicant
Sep  5 12:44:51 fhem3 bluetoothd[354]: Failed to set privacy: Rejected (0x0b)
Sep  5 12:44:51 fhem3 kernel: [   11.145413] Adding 102396k swap on /var/swap.  Priority:-2 extents:1 across:102396k FS
--
Sep  5 13:19:53 fhem3 systemd[1]: Mounted /boot.
Sep  5 13:19:53 fhem3 systemd-udevd[178]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1.
Sep  5 13:19:53 fhem3 systemd[1]: Reached target Local File Systems.
--
Sep  5 13:19:53 fhem3 dbus[336]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service'
Sep  5 13:19:53 fhem3 bluetoothd[352]: Failed to obtain handles for "Service Changed" characteristic
Sep  5 13:19:53 fhem3 CRON[446]: (root) CMD (   PATH="$PATH:/usr/local/bin/" pihole updatechecker remote reboot)
--
Sep  5 13:19:53 fhem3 systemd[1]: Starting Hostname Service...
Sep  5 13:19:53 fhem3 bluetoothd[352]: Sap driver initialization failed.
Sep  5 13:19:53 fhem3 bluetoothd[352]: sap-server: Operation not permitted (1)
--
Sep  5 13:19:53 fhem3 systemd[1]: Started LSB: Autogenerate and use a swap file.
Sep  5 13:19:53 fhem3 bluetoothd[352]: Failed to set privacy: Rejected (0x0b)
Sep  5 13:19:53 fhem3 kernel: [   11.400836] Bluetooth: RFCOMM TTY layer initialized
--
Sep  5 14:04:13 fhem3 kernel: [    0.022275] futex hash table entries: 1024 (order: 4, 65536 bytes)
Sep  5 14:04:13 fhem3 systemd-udevd[188]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1.
Sep  5 14:04:13 fhem3 kernel: [    0.022890] pinctrl core: initialized pinctrl subsystem
--
Sep  5 14:04:14 fhem3 systemd[1]: Started Clean PHP session files every 30 mins.
Sep  5 14:04:14 fhem3 bluetoothd[358]: Failed to obtain handles for "Service Changed" characteristic
Sep  5 14:04:14 fhem3 systemd[1]: apt-daily.timer: Adding 9h 4min 30.399349s random time.
--
Sep  5 14:04:14 fhem3 systemd[1]: Started Disable WiFi if country not set.
Sep  5 14:04:14 fhem3 bluetoothd[358]: Sap driver initialization failed.
Sep  5 14:04:14 fhem3 bluetoothd[358]: sap-server: Operation not permitted (1)
--
Sep  5 14:04:14 fhem3 kernel: [   11.346236] Bluetooth: RFCOMM ver 1.11
Sep  5 14:04:14 fhem3 bluetoothd[358]: Failed to set privacy: Rejected (0x0b)
Sep  5 14:04:14 fhem3 dphys-swapfile[359]: want /var/swap=100MByte, checking existing: keeping it
--
Sep  5 14:05:29 fhem3 systemd[1]: Started Set console font and keymap.
Sep  5 14:05:29 fhem3 systemd-udevd[202]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1.
Sep  5 14:05:29 fhem3 systemd[1]: Started Create Volatile Files and Directories.
--
Sep  5 14:05:29 fhem3 kernel: [    0.000289] Console: colour dummy device 80x30
Sep  5 14:05:29 fhem3 bluetoothd[372]: Failed to obtain handles for "Service Changed" characteristic
Sep  5 14:05:29 fhem3 kernel: [    0.000914] console [tty1] enabled
--
Sep  5 14:05:29 fhem3 kernel: [    0.022254] futex hash table entries: 1024 (order: 4, 65536 bytes)
Sep  5 14:05:29 fhem3 bluetoothd[372]: Sap driver initialization failed.
Sep  5 14:05:29 fhem3 kernel: [    0.022872] pinctrl core: initialized pinctrl subsystem
--
Sep  5 14:05:29 fhem3 kernel: [   11.304665] Bluetooth: RFCOMM ver 1.11
Sep  5 14:05:29 fhem3 bluetoothd[372]: Failed to set privacy: Rejected (0x0b)
Sep  5 14:05:29 fhem3 kernel: [   11.345822] Adding 102396k swap on /var/swap.  Priority:-2 extents:1 across:102396k FS
pi@fhem3:~ $

--- Ende Code ---

Invers:
Das Problem scheint ja wirklich eine Herausforderung zu sein.
Hat denn keiner eine Idee?
Ich hatte schon den Verdacht, die Fritzbox wäre defekt. Aber dazu passen ja die Meldungen für z.B. den Signalduino nicht.

Wernieman:
Irgendwie hast Du ein Systemproblem....

- Hast Du es mal mit der "Debug"-Fhem-Config versucht?

Wie bootest Du Dein System? Du schreibst etwas von einer SSD? Wirklich SSD oder SD-Card?

Navigation

[0] Themen-Index

[#] Nächste Seite

Zur normalen Ansicht wechseln