FHEM Forum

FHEM => Anfängerfragen => Thema gestartet von: SamNitro am 12 April 2020, 16:01:21

Titel: FHEM nicht erreichbar, Vermutung dbLog oder configDB?
Beitrag von: SamNitro am 12 April 2020, 16:01:21
Hallo zusammen und frohe Ostern.

seit einer Woche kann ich in regelmäßigen abständen nicht mehr auf mein FHEM zugreifen.
Der Raspberry läuft noch (Stretch) und der Service FHEM auch. Deconz und NodeRed kann ich auch noch drauf zugreifen!
(Alle updates aktuell)

Die letzten log Einträge sind:
2020.04.12 15:16:58 3: AC: device didn't reply to k(eeepAlive), count=1
2020.04.12 15:17:07 3: AC: device didn't reply to k(eeepAlive), count=2
2020.04.12 15:17:15 3: m2s: m2s_10.1.1.34_63915/DVES_007F7F left us (keepalive check)
2020.04.12 15:17:15 3: m2s: m2s_10.1.1.36_55147/DVES_93ACB0 left us (keepalive check)
2020.04.12 15:17:17 3: AC: device didn't reply to k(eeepAlive), no retries left, setting device to disconnected
2020.04.12 15:17:17 3: 10.1.1.44:80 disconnected, waiting to reappear (AC)
2020.04.12 15:17:24 3: m2s: m2s_10.1.1.41_61302/DVES_2ACB61 left us (keepalive check)
2020.04.12 15:17:44 3: m2s: m2s_10.1.1.45_65139/DVES_2ACCCD left us (keepalive check)
2020.04.12 15:18:13 3: m2s: m2s_127.0.0.1_60224/mqtt_267067dd.7fecf8 left us (keepalive check)
2020.04.12 15:18:35 1: PERL WARNING: readdir() attempted on invalid dirhandle DIR at ./FHEM/93_DbLog.pm line 563.
2020.04.12 15:18:35 1: PERL WARNING: closedir() attempted on invalid dirhandle DIR at ./FHEM/93_DbLog.pm line 568.
2020.04.12 15:21:19 3: error while requesting https://cdn2.atudo.net/api/1.0/vl.php?type=0,1,2,3,4,5,6&box=50.9992089099099,6.44143319973067,51.1793890900901,6.72829480026933 - Creating socket: Bad file descriptor
2020.04.12 15:31:19 3: error while requesting https://cdn2.atudo.net/api/1.0/vl.php?type=0,1,2,3,4,5,6&box=50.9992089099099,6.44143319973067,51.1793890900901,6.72829480026933 - Cant create UDP socket:Bad file descriptor
2020.04.12 15:41:19 3: error while requesting https://cdn2.atudo.net/api/1.0/vl.php?type=0,1,2,3,4,5,6&box=50.9992089099099,6.44143319973067,51.1793890900901,6.72829480026933 - Cant create UDP socket:Bad file descriptor

pi@FHEM:~ $ systemctl status fhem.service
● fhem.service - FHEM Home Automation
   Loaded: loaded (/etc/systemd/system/fhem.service; enabled; vendor preset: ena
   Active: active (running) since Sun 2020-04-12 14:01:05 CEST; 1h 57min ago
  Process: 4339 ExecStop=//etc/init.d/fhem stop (code=exited, status=1/FAILURE)
  Process: 4414 ExecStart=/etc/init.d/fhem start (code=exited, status=0/SUCCESS)
Main PID: 4481 (perl)
   CGroup: /system.slice/fhem.service
           ├─ 4481 perl fhem.pl configDB
           ├─ 4687 node /usr/bin/alexa-fhem -c ./alexa-fhem.cfg
           └─21972 perl fhem.pl configDB

Apr 12 14:01:04 FHEM systemd[1]: Starting FHEM Home Automation...
Apr 12 14:01:04 FHEM fhem[4414]: Starting fhem...
Apr 12 14:01:05 FHEM systemd[1]: Started FHEM Home Automation.
lines 1-14/14 (END)

Weiß nicht wo ich ansetzen soll, hoffe es kann schon jemand helfen..

LG Patrick
Titel: Antw:FHEM nicht erreichbar, Vermutung dbLog oder configDB?
Beitrag von: DS_Starter am 12 April 2020, 16:39:20
Hallo Patrick,

zu configDB kann ich nichts sagen, aber das hier:


2020.04.12 15:18:35 1: PERL WARNING: readdir() attempted on invalid dirhandle DIR at ./FHEM/93_DbLog.pm line 563.
2020.04.12 15:18:35 1: PERL WARNING: closedir() attempted on invalid dirhandle DIR at ./FHEM/93_DbLog.pm line 568.


ist eine Folgeerscheinung da das Modul nicht auf (/opt/fhem)/log zugreifen kann. Hast du das evntuell gemountet und hast Netzwerkprobleme ? Würde darauf hindeuten.

LG,
Heiko
Titel: Antw:FHEM nicht erreichbar, Vermutung dbLog oder configDB?
Beitrag von: SamNitro am 12 April 2020, 17:03:53
weil es nicht lief habe ich hiermit versucht die rechte neu zu setzen:

cd /opt && sudo chmod -R a+w fhem && sudo usermod -a -G tty pi && sudo usermod -a -G tty fhem

evtl war das falsch?!?

Alexa-connector hatte auch falsche rechte und schrieb ich soll das ausführen
sudo chmod 755 /opt/fhem
sudo chmod 600 /opt/fhem/.ssh/id_rsa


EDIT:
was hat das zu bedeuten?

2020.04.12 18:01:22 3: DbLog DBLogging -> INFO - 10 of 11 events inserted into table history due to PK on columns TIMESTAMP,DEVICE,READING


Die hatte ich zwar schon immer, aber nie wirklich Beachtung geschenkt
Titel: Antw:FHEM nicht erreichbar, Vermutung dbLog oder configDB?
Beitrag von: SamNitro am 12 April 2020, 17:12:46
Hier ein älterer Auszug, was mich wundert ist der Zeitsprung
2020.04.09 17:07:55 3: DbLog DBLogging -> Insert into history rejected (possible PK violation) - TS: 2020-04-09 16:07:00, Device: Sensor_Tuer_Abstellkammer, Event: state: closed
2020.04.09 17:07:55 3: DbLog DBLogging -> INFO - 5 of 7 events inserted into table history due to PK on columns TIMESTAMP,DEVICE,READING
2020.04.09 18:34:54 3: EnOcean set EnO_licht_keller_flur off
2020.04.09 19:39:14 3: m2s: m2s_10.1.1.34_50122/DVES_007F7F left us (keepalive check)
2020.04.09 18:39:15 3: m2s: m2s_10.1.1.36_60503/DVES_93ACB0 left us (keepalive check)
2020.04.09 18:39:15 3: m2s: m2s_10.1.1.45_55895/DVES_2ACCCD left us (keepalive check)
2020.04.09 18:39:15 3: m2s: m2s_127.0.0.1_48788/mqtt_c3d935a4.0330b8 left us (keepalive check)
2020.04.09 18:39:15 3: m2s: m2s_10.1.1.41_56080/DVES_2ACB61 left us (keepalive check)
2020.04.09 18:39:15 3: AC: device didn't reply to k(eeepAlive), count=1
2020.04.09 18:39:15 3: AC: device didn't reply to k(eeepAlive), count=2
2020.04.09 18:39:15 3: AC: device didn't reply to k(eeepAlive), no retries left, setting device to disconnected
2020.04.09 18:39:15 3: 10.1.1.44:80 disconnected, waiting to reappear (AC)
2020.04.09 19:39:33 3: [Twilight] got no weather info from yahoo. Error code: DNS: Cant find host
2020.04.09 18:39:34 3: 10.1.1.44:80 reappeared (AC)
2020.04.09 18:39:34 3: AC: device sent hello: ArduCounter V3.12 on ESP8266 ESP8266_NODEMCU compiled Feb 22 2019 18:01:12 Hello, pins 0,1,2,5,17 available T512865816,0 B10372602,0
2020.04.09 18:39:35 3: AC: ConfigureDevice: no analogThresholds attribute
2020.04.09 19:52:11 3: m2s: m2s_10.1.1.45_57416/DVES_2ACCCD left us (keepalive check)
2020.04.09 19:52:11 3: m2s: m2s_10.1.1.34_64186/DVES_007F7F left us (keepalive check)
2020.04.09 19:52:11 3: m2s: m2s_127.0.0.1_44422/mqtt_c3d935a4.0330b8 left us (keepalive check)
2020.04.09 19:52:11 3: m2s: m2s_10.1.1.36_60401/DVES_93ACB0 left us (keepalive check)
2020.04.09 18:52:11 3: m2s: m2s_10.1.1.41_57009/DVES_2ACB61 left us (keepalive check)
2020.04.09 19:47:30 3: [Twilight] got no weather info from yahoo. Error code: DNS: Cant find host
2020.04.09 19:47:30 1: 127.0.0.1:1883 disconnected, waiting to reappear (MQTT)
2020.04.09 19:47:30 3: 10.1.1.44:80 disconnected, waiting to reappear (AC)
2020.04.09 19:47:31 1: 127.0.0.1:1883 reappeared (MQTT)
2020.04.09 19:47:33 2: TCM FGW14 wrong data checksum: got 00, computed 83
2020.04.09 19:48:35 3: 10.1.1.44:80 reappeared (AC)
2020.04.09 19:48:35 3: AC: device sent hello: ArduCounter V3.12 on ESP8266 ESP8266_NODEMCU compiled Feb 22 2019 18:01:12 Hello, pins 0,1,2,5,17 available T517006637,0 B10372602,0
2020.04.09 19:48:35 3: AC: pin D2 (pin2) missed 37 reports in 42.7328770160675 seconds. Last reported sequence was 86, now 124. Device count before was 39841, now 41865 with rDiff 2. Adding 2022 to long count and intpolated count readings
2020.04.09 19:48:36 3: AC: ConfigureDevice: no analogThresholds attribute
2020.04.09 20:48:55 3: m2s: m2s_127.0.0.1_53992/mqtt_c3d935a4.0330b8 left us (keepalive check)
2020.04.09 20:48:55 3: m2s: m2s_10.1.1.34_52130/DVES_007F7F left us (keepalive check)
2020.04.09 20:48:55 3: m2s: m2s_10.1.1.45_53935/DVES_2ACCCD left us (keepalive check)
2020.04.09 20:48:55 3: m2s: m2s_10.1.1.41_55552/DVES_2ACB61 left us (keepalive check)
2020.04.09 20:48:55 3: m2s: m2s_10.1.1.36_53030/DVES_93ACB0 left us (keepalive check)
2020.04.09 19:55:25 3: 99_j2r.pm v1.04 (re)loaded
2020.04.09 19:55:26 3: telnetPort: port 7072 opened
2020.04.09 19:55:26 3: Opening CUL device /dev/serial/by-id/usb-busware.de_CUL868-if00
2020.04.09 19:55:26 3: Setting CUL serial parameters to 9600,8,N,1
2020.04.09 19:55:26 3: CUL: Possible commands: ABbCeFGhiKkLlMmNRTtUuVWXxYZ
2020.04.09 19:55:26 3: CUL device opened
2020.04.09 19:55:34 3: WEB: port 8083 opened


19:39 Tritt wohl der Fehler auf, und springt auf 18:39

19:55:25 starte ich fhem neu
Titel: Antw:FHEM nicht erreichbar, Vermutung dbLog oder configDB?
Beitrag von: DS_Starter am 12 April 2020, 17:22:06
Zitat
was hat das zu bedeuten?
Das bedeutet, dass die DB wegen des gesetzten primary key (PK) verhindert hat dass ein Datensatz doppelt in die DB geschrieben wird. Das ist der Sinn des PK, deswegen wird er verwendet.
Und deswegen auch

2020.04.12 18:01:22 3: DbLog DBLogging -> INFO - 10 of 11 events inserted into table history due to PK on columns TIMESTAMP,DEVICE,READING

Alles io -> verbose 2 im Device setzen.

Ansonsten sollte

sudo chmod 755 /opt/fhem

passen. Wobei DbLog an der besagten Stelle (/opt/fhem/log) nur lesen will, also auch mit evtl. weniger vorhandenen Rechten zurecht käme.
Titel: Antw:FHEM nicht erreichbar, Vermutung dbLog oder configDB?
Beitrag von: Wernieman am 12 April 2020, 17:26:11
cd /opt && sudo chmod -R a+w fhem && sudo usermod -a -G tty pi && sudo usermod -a -G tty fhem
Du solltest Dur angewöhnen, Befehle nacheinander auszuführen und nicht in einem Einzeiler. So kann man schneller sehen, ob nicht doch etwas schiefläuft ....

Was mich aber richtig wundert:
2020.04.12 15:21:19 3: error while requesting https://cdn2.atudo.net/api/1.0/vl.php?type=0,1,2,3,4,5,6&box=50.9992089099099,6.44143319973067,51.1793890900901,6.72829480026933 - Creating socket: Bad file descriptor
Genau an dieser Stelle "Bad file descriptor"??
Wenn er jetzt schreiben würde, DNS Probleme, Netzwerk oder andere, würde ich verstehen. Kannst Du mal ins "kern-Log" gucken, ob das System ein Problem hat?
Also: /var/log/kern.log (oder /var/log/syslog) zu den Zeiten prüfen.

Und Zur Sicherheit, kannst Du vom PI die SD-Card prüfen?

Alternativ gebe ich DS_Starter Recht, Du hast im FHEM-Log viele "keepalive" Probleme stehen, hast Du wirklich keine Netzwerkprobleme?
Titel: Antw:FHEM nicht erreichbar, Vermutung dbLog oder configDB?
Beitrag von: SamNitro am 12 April 2020, 18:26:51
Okay kern.log
Apr 12 16:50:05 FHEM kernel: [ 2705.489631] brcmfmac: power management disabled
Apr 12 16:50:11 FHEM kernel: [ 2711.867538] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
Apr 12 16:50:11 FHEM kernel: [ 2711.915960] brcmfmac: power management disabled
Apr 12 16:53:28 FHEM kernel: [ 2908.606821] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
Apr 12 16:53:28 FHEM kernel: [ 2908.660510] brcmfmac: power management disabled
Apr 12 16:53:29 FHEM kernel: [ 2909.628467] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
Apr 12 16:53:29 FHEM kernel: [ 2909.642585] brcmfmac: power management disabled
Apr 12 16:53:35 FHEM kernel: [ 2915.867498] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
Apr 12 16:53:35 FHEM kernel: [ 2915.930618] brcmfmac: power management disabled
Apr 12 17:57:50 FHEM kernel: [ 3171.336640] smsc95xx 1-1.1:1.0 eth0: link down
Apr 12 17:57:54 FHEM kernel: [ 3174.913004] smsc95xx 1-1.1:1.0 eth0: link up, 100Mbps, full-duplex, lpa 0xDDE1
Apr 12 17:58:00 FHEM kernel: [ 3181.224364] smsc95xx 1-1.1:1.0 eth0: link down
Apr 12 17:58:26 FHEM kernel: [ 3207.465036] smsc95xx 1-1.1:1.0 eth0: link up, 100Mbps, full-duplex, lpa 0xD1E1
Apr 12 18:04:31 FHEM kernel: [ 3572.526108] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
Apr 12 18:04:31 FHEM kernel: [ 3572.572091] brcmfmac: power management disabled
Apr 12 18:04:32 FHEM kernel: [ 3573.474282] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
Apr 12 18:04:32 FHEM kernel: [ 3573.484754] brcmfmac: power management disabled
Apr 12 18:04:39 FHEM kernel: [ 3579.866385] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
Apr 12 18:04:39 FHEM kernel: [ 3579.921816] brcmfmac: power management disabled
Apr 12 18:07:55 FHEM kernel: [ 3776.515267] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
Apr 12 18:07:55 FHEM kernel: [ 3776.576691] brcmfmac: power management disabled
Apr 12 18:07:56 FHEM kernel: [ 3777.523699] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
Apr 12 18:07:56 FHEM kernel: [ 3777.535983] brcmfmac: power management disabled
Apr 12 18:08:03 FHEM kernel: [ 3783.817393] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
Apr 12 18:08:03 FHEM kernel: [ 3783.859230] brcmfmac: power management disabled
Apr 12 18:11:19 FHEM kernel: [ 3980.360009] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
Apr 12 18:11:19 FHEM kernel: [ 3980.408059] brcmfmac: power management disabled
Apr 12 18:11:20 FHEM kernel: [ 3981.325595] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
Apr 12 18:11:20 FHEM kernel: [ 3981.337561] brcmfmac: power management disabled
Apr 12 18:11:27 FHEM kernel: [ 3987.798098] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
Apr 12 18:11:27 FHEM kernel: [ 3987.847990] brcmfmac: power management disabled
Apr 12 18:14:43 FHEM kernel: [ 7783.906704] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
Apr 12 18:14:43 FHEM kernel: [ 7783.952380] brcmfmac: power management disabled
Apr 12 18:14:44 FHEM kernel: [ 7784.866625] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
Apr 12 18:14:44 FHEM kernel: [ 7784.877602] brcmfmac: power management disabled
Apr 12 18:14:50 FHEM kernel: [ 7790.826757] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
Apr 12 18:14:50 FHEM kernel: [ 7790.875486] brcmfmac: power management disabled
Apr 12 18:18:07 FHEM kernel: [ 7987.570215] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
Apr 12 18:18:07 FHEM kernel: [ 7987.626870] brcmfmac: power management disabled
Apr 12 18:18:08 FHEM kernel: [ 7988.549044] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
Apr 12 18:18:08 FHEM kernel: [ 7988.561041] brcmfmac: power management disabled
Apr 12 18:18:14 FHEM kernel: [ 7994.928192] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
Apr 12 18:18:14 FHEM kernel: [ 7994.973450] brcmfmac: power management disabled


Syslog:
Apr 12 18:11:18 FHEM dhclient[12507]: Internet Systems Consortium DHCP Client 4.3.5
Apr 12 18:11:18 FHEM dhclient[12507]: Copyright 2004-2016 Internet Systems Consortium.
Apr 12 18:11:18 FHEM dhclient[12507]: All rights reserved.
Apr 12 18:11:18 FHEM dhclient[12507]: For info, please visit https://www.isc.org/software/dhcp/
Apr 12 18:11:18 FHEM dhclient[12507]:
Apr 12 18:11:19 FHEM dhclient[12507]: Listening on LPF/wlan0/b8:27:eb:4a:a1:2c
Apr 12 18:11:19 FHEM dhclient[12507]: Sending on   LPF/wlan0/b8:27:eb:4a:a1:2c
Apr 12 18:11:19 FHEM dhclient[12507]: Sending on   Socket/fallback
Apr 12 18:11:19 FHEM kernel: [ 3980.360009] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
Apr 12 18:11:19 FHEM kernel: [ 3980.408059] brcmfmac: power management disabled
Apr 12 18:11:20 FHEM dhclient[12561]: Internet Systems Consortium DHCP Client 4.3.5
Apr 12 18:11:20 FHEM dhclient[12561]: Copyright 2004-2016 Internet Systems Consortium.
Apr 12 18:11:20 FHEM dhclient[12561]: All rights reserved.
Apr 12 18:11:20 FHEM dhclient[12561]: For info, please visit https://www.isc.org/software/dhcp/
Apr 12 18:11:20 FHEM dhclient[12561]:
Apr 12 18:11:20 FHEM dhclient[12561]: Listening on LPF/wlan0/b8:27:eb:4a:a1:2c
Apr 12 18:11:20 FHEM dhclient[12561]: Sending on   LPF/wlan0/b8:27:eb:4a:a1:2c
Apr 12 18:11:20 FHEM dhclient[12561]: Sending on   Socket/fallback
Apr 12 18:11:20 FHEM kernel: [ 3981.325595] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
Apr 12 18:11:20 FHEM kernel: [ 3981.337561] brcmfmac: power management disabled
Apr 12 18:11:26 FHEM dhclient[12666]: Internet Systems Consortium DHCP Client 4.3.5
Apr 12 18:11:26 FHEM dhclient[12666]: Copyright 2004-2016 Internet Systems Consortium.
Apr 12 18:11:26 FHEM dhclient[12666]: All rights reserved.
Apr 12 18:11:26 FHEM dhclient[12666]: For info, please visit https://www.isc.org/software/dhcp/
Apr 12 18:11:26 FHEM dhclient[12666]:
Apr 12 18:11:26 FHEM dhclient[12666]: Listening on LPF/wlan0/b8:27:eb:4a:a1:2c
Apr 12 18:11:26 FHEM dhclient[12666]: Sending on   LPF/wlan0/b8:27:eb:4a:a1:2c
Apr 12 18:11:26 FHEM dhclient[12666]: Sending on   Socket/fallback
Apr 12 18:11:27 FHEM kernel: [ 3987.798098] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
Apr 12 18:11:27 FHEM kernel: [ 3987.847990] brcmfmac: power management disabled
Apr 12 18:12:48 FHEM systemd-timesyncd[272]: Timed out waiting for reply from 78.46.102.180:123 (0.debian.pool.ntp.org).
Apr 12 18:12:58 FHEM systemd-timesyncd[272]: Timed out waiting for reply from 216.117.164.1:123 (0.debian.pool.ntp.org).
Apr 12 17:12:58 FHEM systemd[4218]: Time has been changed
Apr 12 17:12:58 FHEM systemd-timesyncd[272]: Synchronized to time server 147.156.7.26:123 (0.debian.pool.ntp.org).
Apr 12 17:12:58 FHEM systemd[1]: Time has been changed
Apr 12 17:12:58 FHEM systemd[1]: apt-daily.timer: Adding 1h 8min 42.519753s random time.
Apr 12 17:12:58 FHEM systemd[1]: apt-daily-upgrade.timer: Adding 57min 23.092956s random time.
Apr 12 17:14:08 FHEM dbus[293]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service'
Apr 12 17:14:08 FHEM systemd[1]: Starting Time & Date Service...
Apr 12 17:14:08 FHEM dbus[293]: [system] Successfully activated service 'org.freedesktop.timedate1'
Apr 12 17:14:08 FHEM systemd[1]: Started Time & Date Service.
Apr 12 17:14:42 FHEM dbus[293]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service'
Apr 12 17:14:42 FHEM systemd[1]: Starting Time & Date Service...
Apr 12 17:14:42 FHEM dbus[293]: [system] Successfully activated service 'org.freedesktop.timedate1'
Apr 12 17:14:42 FHEM systemd[1]: Started Time & Date Service.
Apr 12 17:17:01 FHEM CRON[16311]: (root) CMD (   cd / && run-parts --report /etc/cron.hourly)
Apr 12 18:14:43 FHEM dhclient[18673]: Internet Systems Consortium DHCP Client 4.3.5
Apr 12 18:14:43 FHEM dhclient[18673]: Copyright 2004-2016 Internet Systems Consortium.
Apr 12 18:14:43 FHEM dhclient[18673]: All rights reserved.
Apr 12 18:14:43 FHEM dhclient[18673]: For info, please visit https://www.isc.org/software/dhcp/
Apr 12 18:14:43 FHEM dhclient[18673]:
Apr 12 18:14:43 FHEM dhclient[18673]: Listening on LPF/wlan0/b8:27:eb:4a:a1:2c
Apr 12 18:14:43 FHEM dhclient[18673]: Sending on   LPF/wlan0/b8:27:eb:4a:a1:2c
Apr 12 18:14:43 FHEM dhclient[18673]: Sending on   Socket/fallback
Apr 12 18:14:43 FHEM systemd-timesyncd[272]: Network configuration changed, trying to establish connection.
Apr 12 18:14:43 FHEM kernel: [ 7783.906704] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
Apr 12 18:14:43 FHEM systemd-timesyncd[272]: Synchronized to time server 147.156.7.26:123 (0.debian.pool.ntp.org).
Apr 12 18:14:43 FHEM kernel: [ 7783.952380] brcmfmac: power management disabled
Apr 12 18:14:44 FHEM systemd-timesyncd[272]: Network configuration changed, trying to establish connection.
Apr 12 18:14:44 FHEM dhclient[18726]: Internet Systems Consortium DHCP Client 4.3.5
Apr 12 18:14:44 FHEM dhclient[18726]: Copyright 2004-2016 Internet Systems Consortium.
Apr 12 18:14:44 FHEM dhclient[18726]: All rights reserved.
Apr 12 18:14:44 FHEM dhclient[18726]: For info, please visit https://www.isc.org/software/dhcp/
Apr 12 18:14:44 FHEM dhclient[18726]:
Apr 12 18:14:44 FHEM systemd-timesyncd[272]: Synchronized to time server 147.156.7.26:123 (0.debian.pool.ntp.org).
Apr 12 18:14:44 FHEM dhclient[18726]: Listening on LPF/wlan0/b8:27:eb:4a:a1:2c
Apr 12 18:14:44 FHEM dhclient[18726]: Sending on   LPF/wlan0/b8:27:eb:4a:a1:2c
Apr 12 18:14:44 FHEM dhclient[18726]: Sending on   Socket/fallback
Apr 12 18:14:44 FHEM systemd-timesyncd[272]: Network configuration changed, trying to establish connection.
Apr 12 18:14:44 FHEM kernel: [ 7784.866625] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
Apr 12 18:14:44 FHEM kernel: [ 7784.877602] brcmfmac: power management disabled
Apr 12 18:14:44 FHEM systemd-timesyncd[272]: Synchronized to time server 147.156.7.26:123 (0.debian.pool.ntp.org).
Apr 12 18:14:49 FHEM dhclient[18786]: Internet Systems Consortium DHCP Client 4.3.5
Apr 12 18:14:49 FHEM dhclient[18786]: Copyright 2004-2016 Internet Systems Consortium.
Apr 12 18:14:49 FHEM dhclient[18786]: All rights reserved.
Apr 12 18:14:49 FHEM dhclient[18786]: For info, please visit https://www.isc.org/software/dhcp/
Apr 12 18:14:49 FHEM dhclient[18786]:
Apr 12 18:14:49 FHEM dhclient[18786]: Listening on LPF/wlan0/b8:27:eb:4a:a1:2c
Apr 12 18:14:49 FHEM dhclient[18786]: Sending on   LPF/wlan0/b8:27:eb:4a:a1:2c
Apr 12 18:14:49 FHEM dhclient[18786]: Sending on   Socket/fallback
Apr 12 18:14:50 FHEM systemd-timesyncd[272]: Network configuration changed, trying to establish connection.
Apr 12 18:14:50 FHEM kernel: [ 7790.826757] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
Apr 12 18:14:50 FHEM systemd-timesyncd[272]: Synchronized to time server 147.156.7.26:123 (0.debian.pool.ntp.org).
Apr 12 18:14:50 FHEM kernel: [ 7790.875486] brcmfmac: power management disabled
Apr 12 19:16:15 FHEM systemd[4218]: Time has been changed
Apr 12 19:16:15 FHEM systemd[1]: Time has been changed
Apr 12 19:16:15 FHEM systemd[1]: apt-daily.timer: Adding 10h 31.206834s random time.
Apr 12 19:16:15 FHEM systemd[1]: apt-daily-upgrade.timer: Adding 56min 5.802080s random time.
Apr 12 18:16:16 FHEM systemd[4218]: Time has been changed
Apr 12 18:16:16 FHEM systemd[1]: Time has been changed
Apr 12 18:16:16 FHEM systemd[1]: apt-daily.timer: Adding 10h 18min 25.727886s random time.
Apr 12 18:16:16 FHEM systemd[1]: apt-daily-upgrade.timer: Adding 54min 1.831436s random time.
Apr 12 18:16:58 FHEM systemd[1]: Started Session c7 of user pi.
Apr 12 18:17:01 FHEM CRON[20214]: (root) CMD (   cd / && run-parts --report /etc/cron.hourly)
Apr 12 18:17:08 FHEM systemd[1]: Started Session c8 of user pi.
Apr 12 18:17:38 FHEM Node-RED[334]: 12 Apr 18:17:38 - [info] [mqtt-broker:3b8261cd.9e831e] Verbindung zum Broker  mqtt://localhost:1884 wurde beendet.
Apr 12 18:18:06 FHEM dhclient[20906]: Internet Systems Consortium DHCP Client 4.3.5
Apr 12 18:18:06 FHEM dhclient[20906]: Copyright 2004-2016 Internet Systems Consortium.
Apr 12 18:18:06 FHEM dhclient[20906]: All rights reserved.
Apr 12 18:18:06 FHEM dhclient[20906]: For info, please visit https://www.isc.org/software/dhcp/
Apr 12 18:18:06 FHEM dhclient[20906]:
Apr 12 18:18:06 FHEM dhclient[20906]: Listening on LPF/wlan0/b8:27:eb:4a:a1:2c
Apr 12 18:18:06 FHEM dhclient[20906]: Sending on   LPF/wlan0/b8:27:eb:4a:a1:2c
Apr 12 18:18:06 FHEM dhclient[20906]: Sending on   Socket/fallback
Apr 12 18:18:07 FHEM systemd-timesyncd[272]: Network configuration changed, trying to establish connection.
Apr 12 18:18:07 FHEM kernel: [ 7987.570215] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
Apr 12 18:18:07 FHEM systemd-timesyncd[272]: Synchronized to time server 147.156.7.26:123 (0.debian.pool.ntp.org).
Apr 12 18:18:07 FHEM kernel: [ 7987.626870] brcmfmac: power management disabled
Apr 12 18:18:08 FHEM systemd-timesyncd[272]: Network configuration changed, trying to establish connection.
Apr 12 18:18:08 FHEM dhclient[21002]: Internet Systems Consortium DHCP Client 4.3.5
Apr 12 18:18:08 FHEM dhclient[21002]: Copyright 2004-2016 Internet Systems Consortium.
Apr 12 18:18:08 FHEM dhclient[21002]: All rights reserved.
Apr 12 18:18:08 FHEM dhclient[21002]: For info, please visit https://www.isc.org/software/dhcp/
Apr 12 18:18:08 FHEM dhclient[21002]:
Apr 12 18:18:08 FHEM systemd-timesyncd[272]: Synchronized to time server 147.156.7.26:123 (0.debian.pool.ntp.org).
Apr 12 18:18:08 FHEM dhclient[21002]: Listening on LPF/wlan0/b8:27:eb:4a:a1:2c
Apr 12 18:18:08 FHEM dhclient[21002]: Sending on   LPF/wlan0/b8:27:eb:4a:a1:2c
Apr 12 18:18:08 FHEM dhclient[21002]: Sending on   Socket/fallback
Apr 12 18:18:08 FHEM systemd-timesyncd[272]: Network configuration changed, trying to establish connection.
Apr 12 18:18:08 FHEM kernel: [ 7988.549044] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
Apr 12 18:18:08 FHEM kernel: [ 7988.561041] brcmfmac: power management disabled
Apr 12 18:18:08 FHEM systemd-timesyncd[272]: Synchronized to time server 147.156.7.26:123 (0.debian.pool.ntp.org).
Apr 12 18:18:13 FHEM dhclient[21062]: Internet Systems Consortium DHCP Client 4.3.5
Apr 12 18:18:13 FHEM dhclient[21062]: Copyright 2004-2016 Internet Systems Consortium.
Apr 12 18:18:13 FHEM dhclient[21062]: All rights reserved.
Apr 12 18:18:13 FHEM dhclient[21062]: For info, please visit https://www.isc.org/software/dhcp/
Apr 12 18:18:13 FHEM dhclient[21062]:
Apr 12 18:18:14 FHEM dhclient[21062]: Listening on LPF/wlan0/b8:27:eb:4a:a1:2c
Apr 12 18:18:14 FHEM dhclient[21062]: Sending on   LPF/wlan0/b8:27:eb:4a:a1:2c
Apr 12 18:18:14 FHEM dhclient[21062]: Sending on   Socket/fallback
Apr 12 18:18:14 FHEM systemd-timesyncd[272]: Network configuration changed, trying to establish connection.
Apr 12 18:18:14 FHEM kernel: [ 7994.928192] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
Apr 12 18:18:14 FHEM systemd-timesyncd[272]: Synchronized to time server 147.156.7.26:123 (0.debian.pool.ntp.org).
Apr 12 18:18:14 FHEM kernel: [ 7994.973450] brcmfmac: power management disabled
Apr 12 18:18:24 FHEM systemd[1]: Started Session c9 of user pi.



Die sd karte hatte ich schon getauscht. mit dem selben backup..

Warum der ein Wlan Problem hat keine Ahnung, aber ich gehe eigentlich über Lan!


Edit Raspi-config wifi settings sagt:
Could not communicate with wpa_supplicant
Titel: Antw:FHEM nicht erreichbar, Vermutung dbLog oder configDB?
Beitrag von: Wernieman am 12 April 2020, 19:45:14
Du hast auf jedem falle ein Netzwerkproblem! So heufig, wie Dein Netzwerk "wackelt" würde ich von defektem Netzwerkkabel ausgehen .... selber gelegt/gekrimpt?

Und wie hast Du Netzwerk angelegt? Gebe uns bitte mal:
cat /etc/network/interfaces
Titel: Antw:FHEM nicht erreichbar, Vermutung dbLog oder configDB?
Beitrag von: SamNitro am 12 April 2020, 23:38:23
Zitat von: Wernieman am 12 April 2020, 19:45:14
Du hast auf jedem falle ein Netzwerkproblem! So heufig, wie Dein Netzwerk "wackelt" würde ich von defektem Netzwerkkabel ausgehen .... selber gelegt/gekrimpt?
Der Rpi hängt direkt an der Hauptfritzbox mit einem fertigen Cat 7 Kabel. Ich kann mich ja per Terminal mich noch mit dem Rpi verbinden. Mein Netzwerk ist sonst eigentlich stabil was meinen NAS und VPN angeht. Was bei mir relativ oft passiert, das Unitymedia sich aufhängt.. passiert aber laut Facebook hier öfter im Dorf.

ich hatte mal eine feste IP vergeben...
(aber kann leider nicht mehr genau sagen wo)

pi@FHEM:~ $ cat /etc/network/interfaces
# interfaces(5) file used by ifup(8) and ifdown(8)

# Please note that this file is written to be used with dhcpcd
# For static IP, consult /etc/dhcpcd.conf and 'man dhcpcd.conf'

# Include files from /etc/network/interfaces.d:
source-directory /etc/network/interfaces.d
Titel: Antw:FHEM nicht erreichbar, Vermutung dbLog oder configDB?
Beitrag von: SamNitro am 12 April 2020, 23:43:32
Diese Variante lief bei mir:
Lösung (Variante 5): IPv4-Konfiguration per systemd-networkd
Neben der alten Konfiguration per "/etc/network/interfaces" oder "dhcpcd" gibt es noch die systemd-Variante. In zukünftigen Raspbian-Versionen wird "systemd" die Netzwerk-Konfiguration übernehmen.

Man sollte die Umstellung auf "systemd-networkd" nicht per SSH machen. Gibt es eine Verbindungsunterbrechung, dann bekommt man die Verbindung unter Umständen nicht wieder aufgebaut.

Zuerst prüfen wir, ob der Dienst "systemd-networkd" vorhanden ist. Das ist die Voraussetzung, dass diese Lösung überhaupt möglich ist. Ab Raspbian Jessie sollte das funktionieren.

systemctl status systemd-networkd
Dort sollte stehen "Loaded: loaded".

Zuerst geht es darum, alte Network- oder Networking-Komponenten zumindest zu deaktivieren. Deinstallieren und löschen empfiehlt sich nicht, weil man sonst nur schwer wieder zurückkehren kann.

sudo update-rc.d networking remove
sudo systemctl stop dhcpcd
sudo systemctl disable dhcpcd
Den Dienst für die Namensauflösung "systemd-resolved" aktivieren und starten:

sudo systemctl enable systemd-resolved
sudo systemctl start systemd-resolved
Prüfen, ob "systemd-resolved" läuft:

sudo systemctl status systemd-resolved
In der Zeile "Loaded: loaded" sollte hinten "enabled" stehen.

Dann setzen wir noch einen symbolischen Link für die Datei mit der Adresse des Nameservers. Wenn dieser Link fehlt, dann kann es sein, dass die Namensauflösung nicht funktioniert.

sudo ln -sf /run/systemd/resolve/resolv.conf /etc/resolv.conf
Dann geht es darum, eine Netzwerk-Konfiguration anzulegen. Die kann in mehreren Dateien verteilt sein, die sich alle im selben Verzeichnis befinden.

ls /etc/systemd/network/
Wenn das Verzeichnis leer ist, dann muss man die notwendigen Dateien erst noch anlegen. Der Dateiname ist dabei unerheblich. Er muss nur auf ".network" enden. Trotzdem empfiehlt es sich, sinnvolle Dateinamen zu wählen, um sich im Fehlerfall leichter zurecht zu finden.

Beispiel für eine statische IPv4-Konfiguration für das Interface "eth0":

sudo nano /etc/systemd/network/eth0.network
Eintrag für eine statische IPv4-Konfiguration:

[Match]
Name=eth0

[Network]
Address=192.168.1.2/24
Gateway=192.168.1.1
DNS=192.168.1.1
Alternativ: Eintrag für eine IP-Konfiguration mit DHCP:

[Match]
Name=eth0

[Network]
DHCP=yes
Speichern und schließen mit Strg + O, Return, Strg + X.

Den Dienst "systemd-networkd" aktivieren und starten:

sudo systemctl enable systemd-networkd
sudo systemctl start systemd-networkd
Prüfen, ob "systemd-networkd" läuft:

sudo systemctl status systemd-networkd
In der Zeile "Loaded:" sollte hinten "enabled" stehen und die Schnittstelle sollte konfiguriert sein. In diesem Fall "eth0 : link configured".

Danach das System neustarten:

sudo reboot
Nach dem Neustart sollte das System mit "systemd-networkd" laufen.

Titel: Antw:FHEM nicht erreichbar, Vermutung dbLog oder configDB?
Beitrag von: Wernieman am 13 April 2020, 10:10:08
fertigen Cat 7 Kabel.
Kannst Du mal das Kabel tauschen?
Titel: Antw:FHEM nicht erreichbar, Vermutung dbLog oder configDB?
Beitrag von: SamNitro am 13 April 2020, 10:51:30
So habe jetzt mal das Kabel getauscht und auch die Wlan Schnittstelle aktiviert bekommen.
Hier nach der Anleitung:
https://www.raspberrypi.org/forums/viewtopic.php?t=216660 (https://www.raspberrypi.org/forums/viewtopic.php?t=216660)

Jetzt mal testen, vorerst vielen Dank :)

Ich werde berichten ob es geht oder nicht!
Titel: Antw:FHEM nicht erreichbar, Vermutung dbLog oder configDB?
Beitrag von: SamNitro am 13 April 2020, 11:41:40
Habe gestern ohne zu überlegen ein Kernel Update versucht. weil ich eigentlich nur auf Buster updaten wollte...
habe das backup aber wieder zurück gespielt.......

im Kernel Log habe ich jetzt 100fach bluetooth Probleme...
Apr 13 11:35:56 FHEM kernel: [  132.653508] Bluetooth: hci0: Frame reassembly failed (-84)
Apr 13 11:35:56 FHEM kernel: [  132.783504] Bluetooth: hci0: Frame reassembly failed (-84)
Apr 13 11:35:56 FHEM kernel: [  132.855039] Bluetooth: hci0: Frame reassembly failed (-84)
Apr 13 11:35:56 FHEM kernel: [  132.855932] Bluetooth: hci0: Frame reassembly failed (-84)
Apr 13 11:35:56 FHEM kernel: [  132.903823] Bluetooth: hci0: Frame reassembly failed (-84)
Apr 13 11:35:56 FHEM kernel: [  132.918187] Bluetooth: hci0: Frame reassembly failed (-84)
Apr 13 11:35:56 FHEM kernel: [  132.973095] Bluetooth: hci0: Frame reassembly failed (-84)


Ist das alles eine scheisse.... von einem Problem zum anderen.
Titel: Antw:FHEM nicht erreichbar, Vermutung dbLog oder configDB?
Beitrag von: Gisbert am 13 April 2020, 12:01:50
Hallo Patrick,

auch wenn es im Moment grad garnicht hilft, ich mache monatliche snapshots und habe damit ein Backup des root-Verzeichnisses, das wenigstens auf der gleichen Maschine einsetzbar ist.

Ich interessiere mich im Moment für Docker und VM. VM, z.B. Virtualbox oder Proxmox scheinen mir aber den Nachteil zu haben, dass man eine gute Ausstattung mit RAM haben sollte. Docker ist aber auch etwas, was ich nicht aus dem Ärmel schütteln kann. Der Vorteil für die Zukunft ist, dass ein Crash viel besser zuhandhaben sein wird. Da mein System stabil läuft, habe ich noch nicht nötigen Leidensdruck etwas zu ändern, *klopf auf Holz.

In der Situation, in der du jetzt steckst, wäre es zu überlegen, sich mit Docker zu beschäftigen und dein System neu aufzusetzen, falls deine Hausautomation ein paar Tage ohne Fhem auskommt.

Viel Glück und viele​ Grüße​
Gisbert​
Titel: Antw:FHEM nicht erreichbar, Vermutung dbLog oder configDB?
Beitrag von: SamNitro am 13 April 2020, 12:04:22
Ich habe ja auch ein komplettes backup der SD karte. aber ist damit auch der Kernel Gemeint???

Dann hatte das damit ja nix zu tun...dann ist das Bluetooth Problem schon länger...
Titel: Antw:FHEM nicht erreichbar, Vermutung dbLog oder configDB?
Beitrag von: Wernieman am 13 April 2020, 12:54:34
Der Kernel ist auf auf de SD-Card ....
Titel: Antw:FHEM nicht erreichbar, Vermutung dbLog oder configDB?
Beitrag von: SamNitro am 13 April 2020, 13:05:11
Okay dann weiß ich nicht warum der auf einmal Probleme mit dem Bluetooth hat.
Titel: Antw:FHEM nicht erreichbar, Vermutung dbLog oder configDB?
Beitrag von: Wernieman am 13 April 2020, 13:11:09
Interner oder externer, d.h. eigener Stick?
Titel: Antw:FHEM nicht erreichbar, Vermutung dbLog oder configDB?
Beitrag von: SamNitro am 13 April 2020, 13:12:04
WLAN und Bluetooth sind intern.
Titel: Antw:FHEM nicht erreichbar, Vermutung dbLog oder configDB?
Beitrag von: Wernieman am 13 April 2020, 13:14:04
Du hast doch eine FritzBox? WLAN (intern) vom PI und FritzBox sind von Problemen bekannt. Brauchst Du wirklich WLAN auf dem PI? Hast doch eine Kabelverbindung .....
Titel: Antw:FHEM nicht erreichbar, Vermutung dbLog oder configDB?
Beitrag von: SamNitro am 13 April 2020, 13:21:33
Nein wlan brauche ich nicht.

Ich wollte das nur ans laufen bringen damit der Fehler weg ist.
Titel: Antw:FHEM nicht erreichbar, Vermutung dbLog oder configDB?
Beitrag von: Wernieman am 13 April 2020, 13:45:27
dann schalte es doch komplett aus .....
Titel: Antw:FHEM nicht erreichbar, Vermutung dbLog oder configDB?
Beitrag von: SamNitro am 13 April 2020, 14:13:14
Bluetooth hatte ich mal aktiv um mein Home Status zu erkennen.
Titel: Antw:FHEM nicht erreichbar, Vermutung dbLog oder configDB?
Beitrag von: SamNitro am 13 April 2020, 17:51:22
Wollte die SD karte neu beschreiben dabei wird der bei 7-10 % sehr langsam und bricht fast ab. Also denke ich mal das die karte doch defekt ist. Teste jetzt mit einer neuen Karte. aber Wlan und BT zeigen immer noch die Fehler an. BT Funktioniert trotzdem in FHEM... Werde mal versuchen Wlan wieder einzurichten.