Hallo Zusammen,
ich hab ein sehr kurioses Problem welches ich nicht ganz nachvollziehen kann.
Kurz etwas zu meiner Hardware:
- Intel NUC mit Proxmox und mehreren VM's (teilweise auch LXC) für verschiedene Dienste
- Synology Diskstation als Speicher für "nebensächliche" VM's (über NFS und wenige als iSCSI)
Womit fingen die Probleme (jedenfalls für mich offensichtlich) an?
Ich habe ein Update von der Synology gemacht OHNE vorher die VM/LXC welche sich auf dieser befinden zu beenden.
Nach dem Start war das gesamte Image eines LXC Container (dort war Homebridge installiert) verschwunden. Naja, leider war genau dieses auch nicht im Backup enthalten. Also schnell neu installiert und bei der Verbindung zu FHEM hing es dann.
Also wollte ich FHEM öffnen (diese Ubuntu-VM liegt lokal auf dem NUC, war also vom Update nicht direkt betroffen), jedoch kein Zugriff über die Weboberfläche.
Was habe ich alles zur Fehlersuche unternommen?
Der FHEM Dienst (systemctl Status) läuft ohne Probleme. Die die Logdatei wächst, jedoch mit vielen Einträgen welche für mich nicht plausibel sind da diese vorher nie geloggt wurden.
Neue VM erstellt und FHEM installiert und gestartet. Super dachte ich und habe meine fhem.cfg dorthin kopiert. Neugestartet und gleiches Problem.
Also bin ich bei meiner "normalen" FHEM Installation hingegangen und habe ein altes Backup (gesamte VM) eingespielt, alter 1 Woche. Gleiches Problem das kein Zugriff über Web geht. Also ein paar ältere Backups probiert und bei allen das gleiche.
Nun bin ich echt überfragt wo ich nach dem Fehler suchen soll.
Anbei mal ein Log vom Start bis ca. 5 Minuten nach dem Start. Kurios finde ich das einige Verbindung ständig abbrechen oder sich gar nicht erst aufbauen (USV 10.29.10.14 und 10.29.10.30; HUE 10.29.13.8; DENON 10.29.10.127; FRITZBOX 10.29.10.12; HMUARTLGW 10.29.10.50; AMBILIGHT 10.29.10.51). Ein Ping von der VM zu den Geräten ist möglich. Auch im Netzwerk wurde nichts verändert.
2020.08.13 07:39:38.591 1: Including ./log/fhem.save
2020.08.13 07:39:38.853 2: Alexa: starting alexa-fhem: /usr/bin/alexa-fhem -c ./alexa-fhem.cfg -a xx:xx
2020.08.13 07:39:38.855 3: Alexa: starting
2020.08.13 07:39:41.994 3: Alexa: using logfile: ./log/alexa-2020-08-13.log
2020.08.13 07:39:42.009 3: DashButton: stopped
2020.08.13 07:39:42.014 3: DashButton: listening
2020.08.13 07:39:47.681 2: OG.ak.NE.HUE: empty answer received for http://10.29.13.8/api/yTHYhqwp-wOvSCeLMZd0AP5OaJ4PYmyjGJsTZAC9/lights
2020.08.13 07:39:48.893 3: OG.ak.SZ.SDM120M: RegisterAtIODev called from SetIODev registers OG.ak.SZ.SDM120M at ModBusLine with id 1, MODE master, PROTOCOL RTU
2020.08.13 07:39:48.893 3: OG.ak.SZ.SDM120M: Notify / Init: using ModBusLine for communication
2020.08.13 07:39:48.894 3: OG.ak.SZ.SDM630M1: RegisterAtIODev called from SetIODev registers OG.ak.SZ.SDM630M1 at ModBusLine with id 6, MODE master, PROTOCOL RTU
2020.08.13 07:39:48.894 3: OG.ak.SZ.SDM630M1: Notify / Init: using ModBusLine for communication
2020.08.13 07:39:53.513 3: ESPEasy espBridge: Bridge v2.18 port [TCP:IPV4:8383] opened.
2020.08.13 07:39:53.518 3: Opening myHmUART device 10.29.10.50:2000
2020.08.13 07:39:59.111 0: Featurelevel: 6.0
2020.08.13 07:39:59.111 0: Server started with 309 defined entities (fhem.pl:22408/2020-07-16 perl:5.026001 os:linux user:root pid:1252)
2020.08.13 07:39:59.862 3: DbLog DbLog - Creating Push-Handle to database mysql:database=fhem;host=10.29.10.14;port=3306 with user fhemuser
2020.08.13 07:39:59.989 3: DbLog DbLog - Push-Handle to db mysql:database=fhem;host=10.29.10.14;port=3306 created
2020.08.13 07:40:00.046 1: PERL WARNING: Use of uninitialized value $d in hash element at fhem.pl line 4568.
2020.08.13 07:40:00.046 1: stacktrace:
2020.08.13 07:40:00.046 1: main::__ANON__ called by fhem.pl (4568)
2020.08.13 07:40:00.046 1: main::AttrVal called by ./FHEM/71_DENON_AVR_ZONE.pm (443)
2020.08.13 07:40:00.046 1: main::DENON_AVR_ZONE_Parse called by fhem.pl (3991)
2020.08.13 07:40:00.047 1: main::Dispatch called by ./FHEM/70_DENON_AVR.pm (3258)
2020.08.13 07:40:00.047 1: main::DENON_AVR_UpdateConfig called by fhem.pl (3330)
2020.08.13 07:40:00.047 1: main::HandleTimeout called by fhem.pl (684)
2020.08.13 07:40:00.047 1: PERL WARNING: Use of uninitialized value $d in hash element at fhem.pl line 4568.
2020.08.13 07:40:00.047 1: stacktrace:
2020.08.13 07:40:00.047 1: main::__ANON__ called by fhem.pl (4568)
2020.08.13 07:40:00.047 1: main::AttrVal called by ./FHEM/71_DENON_AVR_ZONE.pm (444)
2020.08.13 07:40:00.047 1: main::DENON_AVR_ZONE_Parse called by fhem.pl (3991)
2020.08.13 07:40:00.047 1: main::Dispatch called by ./FHEM/70_DENON_AVR.pm (3258)
2020.08.13 07:40:00.047 1: main::DENON_AVR_UpdateConfig called by fhem.pl (3330)
2020.08.13 07:40:00.047 1: main::HandleTimeout called by fhem.pl (684)
2020.08.13 07:40:02.193 1: SONOS0: Kein UPnP-Server gefunden... Starte selber einen und warte 1 Sekunde(n) darauf...
2020.08.13 07:40:02.661 1: SONOS0: ./FHEM/00_SONOS.pm is started by fhem...
2020.08.13 07:40:02.661 1: SONOS0: ./FHEM/00_SONOS.pm is listening to Port 4711
2020.08.13 07:40:20.616 3: NUT antwortet nicht
2020.08.13 07:40:20.617 1: 10.29.10.30:3493 disconnected, waiting to reappear (USV)
2020.08.13 07:40:20.621 1: 10.29.10.30:3493 reappeared (USV)
2020.08.13 07:40:20.622 3: NUT antwortet nicht
2020.08.13 07:40:20.622 1: 10.29.10.14:3493 disconnected, waiting to reappear (USV_Abstellkammer)
2020.08.13 07:40:20.630 1: 10.29.10.14:3493 reappeared (USV_Abstellkammer)
2020.08.13 07:40:40.081 3: DbRep DbRep - Connectiontest to database mysql:database=fhem;host=10.29.10.14;port=3306 with user fhemuser
2020.08.13 07:40:40.092 2: OG.ak.NE.HUE: http request failed: 10.29.13.8: Connection reset by peer (104)
2020.08.13 07:40:40.093 2: OG.ak.NE.HUE: http request failed: 10.29.13.8: Connection reset by peer (104)
2020.08.13 07:40:42.119 3: FHEM2FHEM device opened (FHEM_Pi3)
2020.08.13 07:40:42.240 2: OG.ak.NE.HUE: http request failed: 10.29.13.8: Connection reset by peer (104)
2020.08.13 07:40:45.190 2: OG.ak.NE.HUE: http request failed: 10.29.13.8: Connection reset by peer (104)
2020.08.13 07:40:45.196 2: OG.ak.NE.HUE: http request failed: 10.29.13.8: Connection reset by peer (104)
2020.08.13 07:40:45.199 1: PERL WARNING: Use of uninitialized value $d in hash element at fhem.pl line 4568.
2020.08.13 07:40:45.199 1: stacktrace:
2020.08.13 07:40:45.199 1: main::__ANON__ called by fhem.pl (4568)
2020.08.13 07:40:45.199 1: main::AttrVal called by ./FHEM/71_DENON_AVR_ZONE.pm (443)
2020.08.13 07:40:45.199 1: main::DENON_AVR_ZONE_Parse called by fhem.pl (3991)
2020.08.13 07:40:45.199 1: main::Dispatch called by ./FHEM/70_DENON_AVR.pm (1367)
2020.08.13 07:40:45.199 1: main::DENON_AVR_Notify called by fhem.pl (3795)
2020.08.13 07:40:45.199 1: main::CallFn called by fhem.pl (3715)
2020.08.13 07:40:45.199 1: main::DoTrigger called by ./FHEM/DevIo.pm (494)
2020.08.13 07:40:45.199 1: main::__ANON__ called by ./FHEM/DevIo.pm (536)
2020.08.13 07:40:45.199 1: main::__ANON__ called by FHEM/HttpUtils.pm (439)
2020.08.13 07:40:45.199 1: main::__ANON__ called by fhem.pl (769)
2020.08.13 07:40:45.199 1: PERL WARNING: Use of uninitialized value $d in hash element at fhem.pl line 4568.
2020.08.13 07:40:45.199 1: stacktrace:
2020.08.13 07:40:45.199 1: main::__ANON__ called by fhem.pl (4568)
2020.08.13 07:40:45.199 1: main::AttrVal called by ./FHEM/71_DENON_AVR_ZONE.pm (444)
2020.08.13 07:40:45.199 1: main::DENON_AVR_ZONE_Parse called by fhem.pl (3991)
2020.08.13 07:40:45.199 1: main::Dispatch called by ./FHEM/70_DENON_AVR.pm (1367)
2020.08.13 07:40:45.199 1: main::DENON_AVR_Notify called by fhem.pl (3795)
2020.08.13 07:40:45.199 1: main::CallFn called by fhem.pl (3715)
2020.08.13 07:40:45.199 1: main::DoTrigger called by ./FHEM/DevIo.pm (494)
2020.08.13 07:40:45.199 1: main::__ANON__ called by ./FHEM/DevIo.pm (536)
2020.08.13 07:40:45.200 1: main::__ANON__ called by FHEM/HttpUtils.pm (439)
2020.08.13 07:40:45.200 1: main::__ANON__ called by fhem.pl (769)
2020.08.13 07:40:45.200 1: PERL WARNING: Use of uninitialized value $d in hash element at fhem.pl line 4568.
2020.08.13 07:40:45.200 1: stacktrace:
2020.08.13 07:40:45.200 1: main::__ANON__ called by fhem.pl (4568)
2020.08.13 07:40:45.200 1: main::AttrVal called by ./FHEM/71_DENON_AVR_ZONE.pm (443)
2020.08.13 07:40:45.200 1: main::DENON_AVR_ZONE_Parse called by fhem.pl (3991)
2020.08.13 07:40:45.200 1: main::Dispatch called by ./FHEM/70_DENON_AVR.pm (1368)
2020.08.13 07:40:45.200 1: main::DENON_AVR_Notify called by fhem.pl (3795)
2020.08.13 07:40:45.200 1: main::CallFn called by fhem.pl (3715)
2020.08.13 07:40:45.200 1: main::DoTrigger called by ./FHEM/DevIo.pm (494)
2020.08.13 07:40:45.200 1: main::__ANON__ called by ./FHEM/DevIo.pm (536)
2020.08.13 07:40:45.200 1: main::__ANON__ called by FHEM/HttpUtils.pm (439)
2020.08.13 07:40:45.200 1: main::__ANON__ called by fhem.pl (769)
2020.08.13 07:40:45.200 1: PERL WARNING: Use of uninitialized value $d in hash element at fhem.pl line 4568.
2020.08.13 07:40:45.200 1: stacktrace:
2020.08.13 07:40:45.200 1: main::__ANON__ called by fhem.pl (4568)
2020.08.13 07:40:45.200 1: main::AttrVal called by ./FHEM/71_DENON_AVR_ZONE.pm (444)
2020.08.13 07:40:45.200 1: main::DENON_AVR_ZONE_Parse called by fhem.pl (3991)
2020.08.13 07:40:45.200 1: main::Dispatch called by ./FHEM/70_DENON_AVR.pm (1368)
2020.08.13 07:40:45.200 1: main::DENON_AVR_Notify called by fhem.pl (3795)
2020.08.13 07:40:45.200 1: main::CallFn called by fhem.pl (3715)
2020.08.13 07:40:45.200 1: main::DoTrigger called by ./FHEM/DevIo.pm (494)
2020.08.13 07:40:45.200 1: main::__ANON__ called by ./FHEM/DevIo.pm (536)
2020.08.13 07:40:45.200 1: main::__ANON__ called by FHEM/HttpUtils.pm (439)
2020.08.13 07:40:45.200 1: main::__ANON__ called by fhem.pl (769)
2020.08.13 07:40:45.200 1: PERL WARNING: Use of uninitialized value $d in hash element at fhem.pl line 4568.
2020.08.13 07:40:45.201 1: stacktrace:
2020.08.13 07:40:45.201 1: main::__ANON__ called by fhem.pl (4568)
2020.08.13 07:40:45.201 1: main::AttrVal called by ./FHEM/71_DENON_AVR_ZONE.pm (443)
2020.08.13 07:40:45.201 1: main::DENON_AVR_ZONE_Parse called by fhem.pl (3991)
2020.08.13 07:40:45.201 1: main::Dispatch called by ./FHEM/70_DENON_AVR.pm (1369)
2020.08.13 07:40:45.201 1: main::DENON_AVR_Notify called by fhem.pl (3795)
2020.08.13 07:40:45.201 1: main::CallFn called by fhem.pl (3715)
2020.08.13 07:40:45.201 1: main::DoTrigger called by ./FHEM/DevIo.pm (494)
2020.08.13 07:40:45.201 1: main::__ANON__ called by ./FHEM/DevIo.pm (536)
2020.08.13 07:40:45.201 1: main::__ANON__ called by FHEM/HttpUtils.pm (439)
2020.08.13 07:40:45.201 1: main::__ANON__ called by fhem.pl (769)
2020.08.13 07:40:45.201 1: PERL WARNING: Use of uninitialized value $d in hash element at fhem.pl line 4568.
2020.08.13 07:40:45.201 1: stacktrace:
2020.08.13 07:40:45.201 1: main::__ANON__ called by fhem.pl (4568)
2020.08.13 07:40:45.201 1: main::AttrVal called by ./FHEM/71_DENON_AVR_ZONE.pm (444)
2020.08.13 07:40:45.201 1: main::DENON_AVR_ZONE_Parse called by fhem.pl (3991)
2020.08.13 07:40:45.201 1: main::Dispatch called by ./FHEM/70_DENON_AVR.pm (1369)
2020.08.13 07:40:45.201 1: main::DENON_AVR_Notify called by fhem.pl (3795)
2020.08.13 07:40:45.201 1: main::CallFn called by fhem.pl (3715)
2020.08.13 07:40:45.201 1: main::DoTrigger called by ./FHEM/DevIo.pm (494)
2020.08.13 07:40:45.201 1: main::__ANON__ called by ./FHEM/DevIo.pm (536)
2020.08.13 07:40:45.201 1: main::__ANON__ called by FHEM/HttpUtils.pm (439)
2020.08.13 07:40:45.201 1: main::__ANON__ called by fhem.pl (769)
2020.08.13 07:40:45.201 1: PERL WARNING: Use of uninitialized value $d in hash element at fhem.pl line 4568.
2020.08.13 07:40:45.201 1: stacktrace:
2020.08.13 07:40:45.201 1: main::__ANON__ called by fhem.pl (4568)
2020.08.13 07:40:45.201 1: main::AttrVal called by ./FHEM/71_DENON_AVR_ZONE.pm (443)
2020.08.13 07:40:45.201 1: main::DENON_AVR_ZONE_Parse called by fhem.pl (3991)
2020.08.13 07:40:45.201 1: main::Dispatch called by ./FHEM/70_DENON_AVR.pm (1370)
2020.08.13 07:40:45.201 1: main::DENON_AVR_Notify called by fhem.pl (3795)
2020.08.13 07:40:45.201 1: main::CallFn called by fhem.pl (3715)
2020.08.13 07:40:45.201 1: main::DoTrigger called by ./FHEM/DevIo.pm (494)
2020.08.13 07:40:45.201 1: main::__ANON__ called by ./FHEM/DevIo.pm (536)
2020.08.13 07:40:45.201 1: main::__ANON__ called by FHEM/HttpUtils.pm (439)
2020.08.13 07:40:45.201 1: main::__ANON__ called by fhem.pl (769)
2020.08.13 07:40:45.201 1: PERL WARNING: Use of uninitialized value $d in hash element at fhem.pl line 4568.
2020.08.13 07:40:45.201 1: stacktrace:
2020.08.13 07:40:45.202 1: main::__ANON__ called by fhem.pl (4568)
2020.08.13 07:40:45.202 1: main::AttrVal called by ./FHEM/71_DENON_AVR_ZONE.pm (444)
2020.08.13 07:40:45.202 1: main::DENON_AVR_ZONE_Parse called by fhem.pl (3991)
2020.08.13 07:40:45.202 1: main::Dispatch called by ./FHEM/70_DENON_AVR.pm (1370)
2020.08.13 07:40:45.202 1: main::DENON_AVR_Notify called by fhem.pl (3795)
2020.08.13 07:40:45.202 1: main::CallFn called by fhem.pl (3715)
2020.08.13 07:40:45.202 1: main::DoTrigger called by ./FHEM/DevIo.pm (494)
2020.08.13 07:40:45.202 1: main::__ANON__ called by ./FHEM/DevIo.pm (536)
2020.08.13 07:40:45.202 1: main::__ANON__ called by FHEM/HttpUtils.pm (439)
2020.08.13 07:40:45.202 1: main::__ANON__ called by fhem.pl (769)
2020.08.13 07:40:45.203 1: OG.wz.HE.Denon: Can't connect to 10.29.10.127:23: 10.29.10.127: No route to host (113)
2020.08.13 07:40:45.204 1: 10.29.10.51:19444 reappeared (Ambilight)
2020.08.13 07:40:45.235 0: DENON_AVR (OG.wz.HE.Denon) - Error while requesting http://10.29.10.127/goform/Deviceinfo.xml - 10.29.10.127: No route to host (113)
2020.08.13 07:40:45.236 3: myHmUART device opened
2020.08.13 07:40:45.239 3: Opening Sonos device 127.0.0.1:4711
2020.08.13 07:40:45.239 3: SONOS0: Connection accepted from localhost:43780
2020.08.13 07:40:45.241 3: Sonos device opened
2020.08.13 07:40:45.533 2: OG.ak.NE.HUE: http request failed: http://10.29.13.8/api/yTHYhqwp-wOvSCeLMZd0AP5OaJ4PYmyjGJsTZAC9/groups/0: empty answer received
2020.08.13 07:40:45.533 2: OG.ak.NE.HUE: http request failed: http://10.29.13.8/api/yTHYhqwp-wOvSCeLMZd0AP5OaJ4PYmyjGJsTZAC9/lights/3: empty answer received
2020.08.13 07:40:45.533 2: OG.ak.NE.HUE: http request failed: http://10.29.13.8/api/yTHYhqwp-wOvSCeLMZd0AP5OaJ4PYmyjGJsTZAC9/lights/6: empty answer received
2020.08.13 07:40:51.334 2: OG.ak.NE.HUE: http request failed: http://10.29.13.8/api/yTHYhqwp-wOvSCeLMZd0AP5OaJ4PYmyjGJsTZAC9/lights/10: empty answer received
2020.08.13 07:40:51.336 2: OG.ak.NE.HUE: http request failed: http://10.29.13.8/api/yTHYhqwp-wOvSCeLMZd0AP5OaJ4PYmyjGJsTZAC9: empty answer received
2020.08.13 07:40:51.336 2: OG.ak.NE.HUE: http request failed: http://10.29.13.8/api/yTHYhqwp-wOvSCeLMZd0AP5OaJ4PYmyjGJsTZAC9/lights/11: empty answer received
2020.08.13 07:40:51.339 2: OG.ak.NE.HUE: http request failed: http://10.29.13.8/api/yTHYhqwp-wOvSCeLMZd0AP5OaJ4PYmyjGJsTZAC9/lights/14: empty answer received
2020.08.13 07:40:51.340 2: OG.ak.NE.HUE: http request failed: http://10.29.13.8/api/yTHYhqwp-wOvSCeLMZd0AP5OaJ4PYmyjGJsTZAC9/lights/9: empty answer received
2020.08.13 07:40:51.340 2: OG.ak.NE.HUE: http request failed: http://10.29.13.8/api/yTHYhqwp-wOvSCeLMZd0AP5OaJ4PYmyjGJsTZAC9/lights/5: empty answer received
2020.08.13 07:40:54.499 3: FBAHAHTTP: read from https://10.29.10.12:499 timed out
2020.08.13 07:40:54.502 0: DENON_AVR (OG.wz.HE.Denon) - Error while requesting http://10.29.10.127/ajax/get_config?type=25 - connect to http://10.29.10.127:80 timed out
2020.08.13 07:41:00.555 2: OG.ak.NE.HUE: http request failed: http://10.29.13.8/api/yTHYhqwp-wOvSCeLMZd0AP5OaJ4PYmyjGJsTZAC9/lights: empty answer received
2020.08.13 07:41:03.626 3: PM: Read callback: Error: read from https://github.com:443 timed out
2020.08.13 07:41:06.724 2: AttrTemplates: got 185 entries
2020.08.13 07:41:06.727 1: HMUARTLGW myHmUART did not respond for the 1. time, resending
2020.08.13 07:42:48.406 3: DbRep DbRep - WARNING - Index Report_Idx doesn't exist. Please create the index by "set DbRep index recreate_Report_Idx" command !
2020.08.13 07:42:48.428 3: DbRep DbRep - Initial data information retrieved successfully - total time used: 107.8698 seconds
2020.08.13 07:44:23.304 1: HMUARTLGW myHmUART did not respond for the 2. time, resending
2020.08.13 07:44:29.452 3: PM: Read callback: Error: write to https://github.com:443 timed out
2020.08.13 07:44:32.522 3: FBAHAHTTP: write to https://10.29.10.12:499 timed out
2020.08.13 07:44:32.522 1: Allergie: URL error (1): read from http://www.allergie.hexal.de:80 timed out
2020.08.13 07:44:38.664 1: Calendar AbfallKalender: retrieval failed with error message read from https://p24-calendars.icloud.com:443 timed out
2020.08.13 07:44:38.666 1: Calendar AbfallKalender: retrieved no or empty data
2020.08.13 07:45:12.456 1: 10.29.10.14:3493 disconnected, waiting to reappear (USV_Abstellkammer)
2020.08.13 07:45:12.525 1: 10.29.10.30:3493 disconnected, waiting to reappear (USV)Hier noch ein Logauszug von einem Neustart am 27.7. wo noch alles geklappt hat.
2020.07.27 16:16:26.045 1: Including fhem.cfg
2020.07.27 16:16:26.055 3: telnetPort: port 7072 opened
2020.07.27 16:16:26.175 3: WEB_alt: port 8084 opened
2020.07.27 16:16:26.277 2: eventTypes: loaded 9561 events from ./log/eventTypes.txt
2020.07.27 16:16:26.730 3: [Twilight] got no weather info from yahoo. Error code: http://query.yahooapis.com/v1/public/yql?q=select%20*%20from%20weather.forecast%20where%20woeid=0%20and%20u=%27c%27&format=json&env=store%3A%2F%2Fdatatables.org%2Falltableswithkeys: Can't connect(1) to http://query.yahooapis.com:80: IO::Socket::INET: Bad hostname 'query.yahooapis.com:80'
2020.07.27 16:16:26.865 3: OG.wz.LI.Weinschrank: I/O device is OG.ak.NE.HUE
2020.07.27 16:16:26.866 3: OG.sz.LI.Michi: I/O device is OG.ak.NE.HUE
2020.07.27 16:16:26.867 3: OG.wz.LI.Highboard: I/O device is OG.ak.NE.HUE
2020.07.27 16:16:26.867 3: OG.ku.LI.Schrank: I/O device is OG.ak.NE.HUE
2020.07.27 16:16:26.868 3: OG.sz.LI.Thiemo: I/O device is OG.ak.NE.HUE
2020.07.27 16:16:26.977 2: Registering GEOFANCY geofency for URL /geo...
2020.07.27 16:16:27.024 3: WEBhook: port 8088 opened
2020.07.27 16:16:27.714 3: HUEGroup0: I/O device is OG.ak.NE.HUE
2020.07.27 16:16:28.759 3: define OG.ki.LI.Highboard: can't reach (IO::Socket::INET: connect: timeout)
2020.07.27 16:16:28.772 3: WEBfire: port 8089 opened
2020.07.27 16:16:28.916 3: OG.wz.LI.Standlampe.oben: I/O device is OG.ak.NE.HUE
2020.07.27 16:16:28.916 3: OG.wz.LI.Standlampe.unten: I/O device is OG.ak.NE.HUE
2020.07.27 16:16:28.966 3: DashButton: listening
2020.07.27 16:16:28.980 3: Opening OG.wz.HE.Denon device 10.29.10.127:23
2020.07.27 16:16:29.080 3: HUEGroup3: I/O device is OG.ak.NE.HUE
2020.07.27 16:16:29.081 3: HUEGroup2: I/O device is OG.ak.NE.HUE
2020.07.27 16:16:29.081 3: HUEGroup1: I/O device is OG.ak.NE.HUE
2020.07.27 16:16:29.082 3: HUEGroup4: I/O device is OG.ak.NE.HUE
2020.07.27 16:16:31.320 3: Raiffeisen_Drabenderhoehe: Defined with URL http://www.clever-tanken.de/tankstelle_details/17585 and interval 600 featurelevel 6.0
2020.07.27 16:16:31.330 3: Jaeger_Overath: Defined with URL http://www.clever-tanken.de/tankstelle_details/151932 and interval 600 featurelevel 6.0
2020.07.27 16:16:31.340 3: Jet_Vilkerath: Defined with URL http://www.clever-tanken.de/tankstelle_details/10976 and interval 600 featurelevel 6.0
2020.07.27 16:16:31.349 3: Aral_Much: Defined with URL http://www.clever-tanken.de/tankstelle_details/12330 and interval 600 featurelevel 6.0
2020.07.27 16:16:31.392 3: freezemon defined myFreezemon freezemon
2020.07.27 16:16:31.407 3: OG.ki.LI.RomysTraumlicht: I/O device is OG.ak.NE.HUE
2020.07.27 16:16:31.407 3: HUEGroup5: I/O device is OG.ak.NE.HUE
2020.07.27 16:16:31.420 3: FHEM2FHEM opening FHEM_Pi3 at 10.29.10.50:7072
2020.07.27 16:16:31.435 3: XiaomiBTLESens (OG.wz.SE.Blumensensor02) - defined with BTMAC none
2020.07.27 16:16:31.436 3: XiaomiBTLESens (OG.wz.SE.Blumensensor02) - disabled
2020.07.27 16:16:31.442 3: Opening CUL_0 device 10.29.10.50:2001
2020.07.27 16:16:31.548 3: CUL_0: Possible commands: BCFiAZEGMRTVWXefmltux
2020.07.27 16:16:31.602 3: CUL_0 device opened
2020.07.27 16:16:31.609 3: TABLETUI: new ext defined infix:ftui/: dir:./www/tablet:
2020.07.27 16:16:31.609 3: Registering HTTPSRV TABLETUI for URL /ftui and assigned link ftui/ ...
2020.07.27 16:16:31.614 3: Opening USV device 10.29.10.30:3493
2020.07.27 16:16:31.615 3: USV device opened
2020.07.27 16:16:31.728 0: [echodevice] load ECHO Device Amazon.Connect
2020.07.27 16:16:31.789 3: OG.wz.CL.Staubsaugroboter: initialized, using AES
2020.07.27 16:16:31.789 3: OG.wz.CL.Staubsaugroboter: disconnecting
2020.07.27 16:16:31.789 2: OG.wz.CL.Staubsaugroboter: connecting
2020.07.27 16:16:31.789 3: OG.wz.CL.Staubsaugroboter: initialized
2020.07.27 16:16:31.793 3: XiaomiBTLESens (OG.wz.SE.Blumensensor01) - defined with BTMAC none
2020.07.27 16:16:31.794 3: XiaomiBTLESens (OG.wz.SE.Blumensensor01) - disabled
2020.07.27 16:16:31.864 3: Opening USV_Abstellkammer device 10.29.10.14:3493
2020.07.27 16:16:31.866 3: USV_Abstellkammer device opened
2020.07.27 16:16:31.911 3: ModBusLine: defined as /dev/ttyUSB0@2400
2020.07.27 16:16:31.913 3: OG.ak.SZ.SDM120M: defined with id 1, interval 10, protocol default (RTU), mode master
2020.07.27 16:16:31.919 3: OG.ak.SZ.SDM630M1: defined with id 6, interval 10, protocol default (RTU), mode master
2020.07.27 16:16:34.997 3: 1215C S7_connect: connect to PLC with maxPDUlength=240
2020.07.27 16:16:34.998 3: 1215C S7_Attr: setting Intervall= 1
2020.07.27 16:16:35.161 3: WEB: port 8083 opened
2020.07.27 16:16:35.170 3: HEOSMaster (OG.wz.HE.Heos) - defined with host 10.29.10.127
2020.07.27 16:16:35.176 3: HEOSPlayer (OG.wz.HE.Heos.Wohnzimmer) - I/O device is OG.wz.HE.Heos
2020.07.27 16:16:35.176 3: HEOSPlayer (OG.wz.HE.Heos.Wohnzimmer) - defined with Code: OG.wz.HE.Heos-1338151996
2020.07.27 16:16:35.181 3: PM: Defined with URL https://github.com/Koenkk/zigbee2mqtt/tags and interval 60 featurelevel 6.0
2020.07.27 16:16:35.189 1: Including ./log/fhem.save
2020.07.27 16:16:35.320 2: Alexa: starting alexa-fhem: /usr/bin/alexa-fhem -c ./alexa-fhem.cfg -a xx:xx
2020.07.27 16:16:35.322 3: Alexa: starting
2020.07.27 16:16:35.362 3: Alexa: using logfile: ./log/alexa-2020-07-27.log
2020.07.27 16:16:35.379 3: DashButton: stopped
2020.07.27 16:16:35.382 3: DashButton: listening
2020.07.27 16:16:42.064 2: OG.ak.NE.HUE: empty answer received for http://10.29.13.8/api/yTHYhqwp-wOvSCeLMZd0AP5OaJ4PYmyjGJsTZAC9/lights
2020.07.27 16:16:43.286 3: OG.ak.SZ.SDM120M: RegisterAtIODev called from SetIODev registers OG.ak.SZ.SDM120M at ModBusLine with id 1, MODE master, PROTOCOL RTU
2020.07.27 16:16:43.286 3: OG.ak.SZ.SDM120M: Notify / Init: using ModBusLine for communication
2020.07.27 16:16:43.287 3: OG.ak.SZ.SDM630M1: RegisterAtIODev called from SetIODev registers OG.ak.SZ.SDM630M1 at ModBusLine with id 6, MODE master, PROTOCOL RTU
2020.07.27 16:16:43.287 3: OG.ak.SZ.SDM630M1: Notify / Init: using ModBusLine for communication
2020.07.27 16:16:45.682 3: ESPEasy espBridge: Bridge v2.18 port [TCP:IPV4:8383] opened.
2020.07.27 16:16:45.684 3: Opening myHmUART device 10.29.10.50:2000
2020.07.27 16:16:45.704 0: Featurelevel: 6.0
2020.07.27 16:16:45.704 0: Server started with 309 defined entities (fhem.pl:22475/2020-07-26 perl:5.026001 os:linux user:root pid:20265)
2020.07.27 16:16:46.288 3: Opening MQTT2_CLIENT device 10.29.10.37:1883
2020.07.27 16:16:46.327 3: DbLog DbLog - Creating Push-Handle to database mysql:database=fhem;host=10.29.10.14;port=3306 with user fhemuser
2020.07.27 16:16:46.330 3: DbLog DbLog - Push-Handle to db mysql:database=fhem;host=10.29.10.14;port=3306 created
2020.07.27 16:16:46.378 1: PERL WARNING: Use of uninitialized value $d in hash element at fhem.pl line 4579.
2020.07.27 16:16:46.379 1: stacktrace:
2020.07.27 16:16:46.379 1: main::__ANON__ called by fhem.pl (4579)
2020.07.27 16:16:46.379 1: main::AttrVal called by ./FHEM/71_DENON_AVR_ZONE.pm (443)
2020.07.27 16:16:46.379 1: main::DENON_AVR_ZONE_Parse called by fhem.pl (4002)
2020.07.27 16:16:46.379 1: main::Dispatch called by ./FHEM/70_DENON_AVR.pm (3258)
2020.07.27 16:16:46.379 1: main::DENON_AVR_UpdateConfig called by fhem.pl (3341)
2020.07.27 16:16:46.379 1: main::HandleTimeout called by fhem.pl (684)
2020.07.27 16:16:46.379 1: PERL WARNING: Use of uninitialized value $d in hash element at fhem.pl line 4579.
2020.07.27 16:16:46.379 1: stacktrace:
2020.07.27 16:16:46.379 1: main::__ANON__ called by fhem.pl (4579)
2020.07.27 16:16:46.379 1: main::AttrVal called by ./FHEM/71_DENON_AVR_ZONE.pm (444)
2020.07.27 16:16:46.379 1: main::DENON_AVR_ZONE_Parse called by fhem.pl (4002)
2020.07.27 16:16:46.379 1: main::Dispatch called by ./FHEM/70_DENON_AVR.pm (3258)
2020.07.27 16:16:46.379 1: main::DENON_AVR_UpdateConfig called by fhem.pl (3341)
2020.07.27 16:16:46.379 1: main::HandleTimeout called by fhem.pl (684)
2020.07.27 16:16:46.431 1: SONOS0: Kein UPnP-Server gefunden... Starte selber einen und warte 1 Sekunde(n) darauf...
2020.07.27 16:16:46.450 3: NUT antwortet nicht
2020.07.27 16:16:46.450 1: 10.29.10.30:3493 disconnected, waiting to reappear (USV)
2020.07.27 16:16:46.459 1: 10.29.10.30:3493 reappeared (USV)
2020.07.27 16:16:46.460 3: NUT antwortet nicht
2020.07.27 16:16:46.460 1: 10.29.10.14:3493 disconnected, waiting to reappear (USV_Abstellkammer)
2020.07.27 16:16:46.466 1: 10.29.10.14:3493 reappeared (USV_Abstellkammer)
2020.07.27 16:16:46.537 2: OG.ak.NE.HUE: http request failed: 10.29.13.8: Connection reset by peer (104)
2020.07.27 16:16:46.537 2: OG.ak.NE.HUE: http request failed: 10.29.13.8: Connection reset by peer (104)
2020.07.27 16:16:46.537 1: 10.29.10.51:19444 reappeared (Ambilight)
2020.07.27 16:16:46.540 2: OG.ak.NE.HUE: http request failed: 10.29.13.8: Connection reset by peer (104)
2020.07.27 16:16:46.546 3: OG.wz.HE.Denon device opened
2020.07.27 16:16:46.573 2: OG.ak.NE.HUE: http request failed: 10.29.13.8: Connection reset by peer (104)
2020.07.27 16:16:46.575 3: MQTT2_CLIENT device opened
2020.07.27 16:16:46.577 3: FHEM2FHEM device opened (FHEM_Pi3)
2020.07.27 16:16:46.577 2: OG.ak.NE.HUE: http request failed: 10.29.13.8: Connection reset by peer (104)
2020.07.27 16:16:46.577 2: OG.ak.NE.HUE: http request failed: 10.29.13.8: Connection reset by peer (104)
2020.07.27 16:16:46.638 2: OG.ak.NE.HUE: http request failed: 10.29.13.8: Connection reset by peer (104)
2020.07.27 16:16:46.638 3: myHmUART device opened
2020.07.27 16:16:46.731 1: SONOS0: ./FHEM/00_SONOS.pm is started by fhem...
2020.07.27 16:16:46.731 1: SONOS0: ./FHEM/00_SONOS.pm is listening to Port 4711
2020.07.27 16:16:47.920 3: Opening Sonos device 127.0.0.1:4711
2020.07.27 16:16:47.920 3: SONOS0: Connection accepted from localhost:48182
2020.07.27 16:16:47.922 3: Sonos device opened
2020.07.27 16:16:47.930 0: DENON_AVR (OG.wz.HE.Denon) - Error while requesting http://10.29.10.127/goform/Deviceinfo.xml - http://10.29.10.127/goform/Deviceinfo.xml: empty answer received
2020.07.27 16:16:47.931 2: OG.ak.NE.HUE: http request failed: http://10.29.13.8/api/yTHYhqwp-wOvSCeLMZd0AP5OaJ4PYmyjGJsTZAC9: empty answer received
2020.07.27 16:16:48.011 2: AttrTemplates: got 184 entries
2020.07.27 16:16:51.589 1: PERL WARNING: Use of uninitialized value $d in hash element at fhem.pl line 4579.
2020.07.27 16:16:51.589 1: stacktrace:
2020.07.27 16:16:51.589 1: main::__ANON__ called by fhem.pl (4579)
2020.07.27 16:16:51.589 1: main::AttrVal called by ./FHEM/71_DENON_AVR_ZONE.pm (443)
2020.07.27 16:16:51.589 1: main::DENON_AVR_ZONE_Parse called by fhem.pl (4002)
2020.07.27 16:16:51.589 1: main::Dispatch called by ./FHEM/70_DENON_AVR.pm (3258)
2020.07.27 16:16:51.589 1: main::DENON_AVR_UpdateConfig called by fhem.pl (3341)
2020.07.27 16:16:51.589 1: main::HandleTimeout called by fhem.pl (684)
2020.07.27 16:16:51.589 1: PERL WARNING: Use of uninitialized value $d in hash element at fhem.pl line 4579.
2020.07.27 16:16:51.589 1: stacktrace:
2020.07.27 16:16:51.589 1: main::__ANON__ called by fhem.pl (4579)
2020.07.27 16:16:51.589 1: main::AttrVal called by ./FHEM/71_DENON_AVR_ZONE.pm (444)
2020.07.27 16:16:51.589 1: main::DENON_AVR_ZONE_Parse called by fhem.pl (4002)
2020.07.27 16:16:51.589 1: main::Dispatch called by ./FHEM/70_DENON_AVR.pm (3258)
2020.07.27 16:16:51.589 1: main::DENON_AVR_UpdateConfig called by fhem.pl (3341)
2020.07.27 16:16:51.589 1: main::HandleTimeout called by fhem.pl (684)
2020.07.27 16:16:56.183 3: DbRep DbRep - Connectiontest to database mysql:database=fhem;host=10.29.10.14;port=3306 with user fhemuser
2020.07.27 16:18:50.377 3: DbRep DbRep - WARNING - Index Report_Idx doesn't exist. Please create the index by "set DbRep index recreate_Report_Idx" command !
2020.07.27 16:18:50.414 3: DbRep DbRep - Initial data information retrieved successfully - total time used: 114.2208 seconds
2020.07.27 16:18:50.425 3: DbRep DbRep - Connectiontest to db mysql:database=fhem;host=10.29.10.14;port=3306 successfulIch bin wirklich sehr dankbar wenn ihr mir auf die Sprünge helfen könnt wo ich noch suchen kann.
Danke im Voraus!!!
Gruß Thiemo
Hi Thiemo,
ZitatAuch im Netzwerk wurde nichts verändert.
aber genau da scheint Dein Problem zu liegen. Die http-request bleiben sämtlichst hängen.
Hast Du global dnsserver gesetzt ? Das hilft möglicherweise gegen das "blockierende" Verhalten.
ZitatEin Ping von der VM zu den Geräten ist möglich.
sagt ja eigentlich auch aus, dass es wohl am DNSServer liegt.
Viel Glück
Markus
Zitat von: KölnSolar am 13 August 2020, 10:07:36
aber genau da scheint Dein Problem zu liegen. Die http-request bleiben sämtlichst hängen.
Also am DNS kann es nicht liegen. Ich kann überall hin pingen von der VM.
Auch der dnsserver ist gesetzt.
Was ja komisch ist das es mit der Standard-Installation alles klappt. Sobald ich aber meine Config einspiele nichts mehr geht.
Und ich habe ja noch Voll-Backups der VM über mehrere Monate. Selbst damit (also mit älterem Stand von FHEM und Ubuntu) hängt es sich auch auf.
Gibt es noch andere Logs welche man sich dafür anschauen sollte?
Zur Fehlersuche habe ich nun einfach mal die ersten 1000 Zeilen (natürlich nach dem attr global und den WEB Device) aus der fhem.cfg gelöscht. Und siehe da, es klappt.
Wie kann ich nun am besten/schnellsten/einfachen feststellen an welchem Device es liegt? Oder ist das pragmatischste einfach von den 1000 Zeilen einfach Schritt für Schritt wieder rückwärts zu gehen?
Merkwürdig ist z.B.
2020.08.13 07:40:45.235 0: DENON_AVR (OG.wz.HE.Denon) - Error while requesting http://10.29.10.127/goform/Deviceinfo.xml - 10.29.10.127: No route to host (113)
Kann man ein ping 10.29.10.127 aus der FHEM VM sehen?
Zitat von: amenomade am 13 August 2020, 22:38:33
Merkwürdig ist z.B.
2020.08.13 07:40:45.235 0: DENON_AVR (OG.wz.HE.Denon) - Error while requesting http://10.29.10.127/goform/Deviceinfo.xml - 10.29.10.127: No route to host (113)
Kann man ein ping 10.29.10.127 aus der FHEM VM sehen?
Ja, kein Problem. Habe gerade fast parallel einen Beitrag mit dir geschrieben. Die Definition von dem Denon ist immer noch drin und es klappt aktuell, jedoch halt um die ersten 1000 Zeilen reduziert.
Und wenn Du dabei bist das ping zu liefern, zeig auch ein traceroute
Hier der Trace:
thiemo@fhem:~$ traceroute 10.29.10.127
traceroute to 10.29.10.127 (10.29.10.127), 30 hops max, 60 byte packets
1 Denon-AVR-X2600H.local (10.29.10.127) 0.816 ms 0.792 ms 0.803 ms
Komisch, er hat doch ne Route.
Ich muss sagen, ich tippe wie Markus auf ein Netzwerkproblem, aber welches...
Funktionieren aus dem FHEM Server:
nc -zvw3 10.29.10.127 80
nc -zw3 10.29.10.127 23
nc -zvw3 10.29.13.8 80
nc -zw3 10.29.10.12 499
?
Und wie sieht es aus in der Config mit deinem DENON_AVR_ZONE? Wird es vielleicht vor seinem IODev definiert?
Alle Befehle haben geklappt ohne Fehler.
Inzwischen bin ich alle Defines durchgegangen und auch mal aussortiert was nicht mehr benutzt wird (z.B. habe ich mal Withings eingerichtet, nutze es aber nirgends). Und beim nächsten Start hat alles geklappt. Würde zwar gerne noch wissen woran es wirklich gelegen hat aber die Zeit nehme ich mir heute Abend nicht mehr.
Danke für alle Hilfeansätze/-vorschläge !!!!