Can't connect to 127.0.0.1:43199: IO::Socket::INET:

Begonnen von Burny4600, 02 April 2022, 10:29:20

Vorheriges Thema - Nächstes Thema

Burny4600

Einige meiner PiS haben nach längerem Betrieb Verbindungsprobleme. Per FHEMWEB sind sie dann auch nicht mehr erreichbar.
Ein Neustart hilft dann oft auch nicht mehr, und plötzlich funktioniert FHEM wieder. Ich habe hierauf keine Idee mehr, was die Ursache sein kann.
2022.04.02 09:41:48.832 1: Timeout for JSONMETER_GetJsonFile reached, terminated process 5636
2022.04.02 09:41:48.874 1: Timeout for JSONMETER_GetJsonFile reached, terminated process 5637
2022.04.02 09:41:56.141 1: Timeout for SYSMON_blockingCall reached, terminated process 5635
2022.04.02 09:41:57.544 2: SUSV: invalid Voltage In: 513 mV <- 208 1 2
2022.04.02 09:42:01.237 3: FS20 set AB_SG_BLGO off
2022.04.02 09:42:01.340 3: PV_Anlage_1: Read callback: Error: read from http://192.168.17.177:80 timed out
2022.04.02 09:42:01.517 1: PERL WARNING: Argument "" isn't numeric in numeric eq (==) at (eval 58370) line 1.
2022.04.02 09:42:01.517 3: eval: OG1_KU_RT_SSST: warning in condition c01
2022.04.02 09:42:02.113 3: FS20 set L_OG1_WZ_Aqua_Mond on
2022.04.02 09:42:07.467 3: FS20 set OG1_KI_FR_EDV on
2022.04.02 09:42:07.595 3: FS20 set Freigabe_KI on
2022.04.02 09:42:09.852 3: FS20 set OG1_KI_WDLO off
2022.04.02 09:42:10.063 2: LuftdatenInfo (SDS011) - error while request: read from http://192.168.17.160:80 timed out
2022.04.02 09:44:03.563 3: nanoCUL433_OG1 IT_set: R_OG1_BA on
2022.04.02 09:44:03.663 2: IT IODev device didn't answer is command correctly:   raw => om87EC3B483FFA8CE743F61DA41FFD4673A1FB0ED20002
2022.04.02 09:44:03.923 3: FS20 set R_OG1_KUE on
2022.04.02 09:44:07.202 1: BlockingInformParent (BlockingRegisterTelnet): Can't connect to 127.0.0.1:43199: IO::Socket::INET: connect: Connection timed out
2022.04.02 09:44:07.202 1: BlockingInformParent (BlockingRegisterTelnet): Can't connect to 127.0.0.1:43199: IO::Socket::INET: connect: Connection timed out
2022.04.02 09:44:07.202 1: BlockingInformParent (BlockingRegisterTelnet): Can't connect to 127.0.0.1:43199: IO::Socket::INET: connect: Connection timed out
2022.04.02 09:44:07.212 1: BlockingInformParent (BlockingRegisterTelnet): Can't connect to 127.0.0.1:43199: IO::Socket::INET: connect: Connection timed out
2022.04.02 09:44:12.322 1: BlockingInformParent (BlockingRegisterTelnet): Can't connect to 127.0.0.1:43199: IO::Socket::INET: connect: Connection timed out
2022.04.02 09:44:12.322 1: BlockingInformParent (BlockingRegisterTelnet): Can't connect to 127.0.0.1:43199: IO::Socket::INET: connect: Connection timed out
2022.04.02 09:44:17.442 1: BlockingInformParent (BlockingRegisterTelnet): Can't connect to 127.0.0.1:43199: IO::Socket::INET: connect: Connection timed out
2022.04.02 09:46:20.322 1: BlockingInformParent (BlockingStart): Can't connect to 127.0.0.1:43199: IO::Socket::INET: connect: Connection timed out
2022.04.02 09:46:20.322 1: BlockingInformParent (BlockingStart): Can't connect to 127.0.0.1:43199: IO::Socket::INET: connect: Connection timed out
2022.04.02 09:46:20.322 1: BlockingInformParent (BlockingStart): Can't connect to 127.0.0.1:43199: IO::Socket::INET: connect: Connection timed out
2022.04.02 09:46:20.323 1: BlockingInformParent (BlockingStart): Can't connect to 127.0.0.1:43199: IO::Socket::INET: connect: Connection timed out
2022.04.02 09:46:25.442 1: BlockingInformParent (BlockingStart): Can't connect to 127.0.0.1:43199: IO::Socket::INET: connect: Connection timed out
2022.04.02 09:46:25.442 1: BlockingInformParent (BlockingStart): Can't connect to 127.0.0.1:43199: IO::Socket::INET: connect: Connection timed out
2022.04.02 09:46:51.042 1: BlockingInformParent (BlockingStart): Can't connect to 127.0.0.1:43199: IO::Socket::INET: connect: Connection timed out
2022.04.02 09:48:33.441 1: BlockingInformParent (JSONMETER_ParseJsonFile): Can't connect to 127.0.0.1:43199: IO::Socket::INET: connect: Connection timed out
2022.04.02 09:48:33.441 1: BlockingInformParent (JSONMETER_ParseJsonFile): Can't connect to 127.0.0.1:43199: IO::Socket::INET: connect: Connection timed out
2022.04.02 09:48:33.442 1: BlockingInformParent (JSONMETER_ParseJsonFile): Can't connect to 127.0.0.1:43199: IO::Socket::INET: connect: Connection timed out
2022.04.02 09:48:33.453 1: BlockingInformParent (SYSMON_blockingFinish): Can't connect to 127.0.0.1:43199: IO::Socket::INET: connect: Connection timed out
2022.04.02 09:48:38.562 1: BlockingInformParent (JSONMETER_ParseJsonFile): Can't connect to 127.0.0.1:43199: IO::Socket::INET: connect: Connection timed out
2022.04.02 09:48:38.562 1: BlockingInformParent (JSONMETER_ParseJsonFile): Can't connect to 127.0.0.1:43199: IO::Socket::INET: connect: Connection timed out
2022.04.02 09:49:04.162 1: BlockingInformParent (PROPLANTA_Done): Can't connect to 127.0.0.1:43199: IO::Socket::INET: connect: Connection timed out
2022.04.02 10:15:39.549 3: FS20 set OG1_KI_WDLO on

Mfg Chris

Raspberry Pi 2/2+/3/3+/4 / Betriebssystem: Bullseye Lite
Schnittstellen: RFXtrx433E, SIGNALduino, MQTT, nanoCUL, HM-MOD-UART, 1-Wire, LAN, ser2net, FHEM2FEHEM
Devices: S.USV, APC-USV, Fronius Datalogger Web 2, FS20, IT, Resol VBUS & DL2, TEK603, WMR200, YouLess, Homematic, MQTT

rudolfkoenig

Da eine sinnvolle Portnummer (43199) angezeigt wird, konnte ein telnet Serverport auf 127.0.0.1 geoeffnet werden.
Die Verbindung zum Serverprozess wird mit voreingestellten Timeout gemacht, das ist bei mir (gerade getestet) ca 140 Sekunden.
Der Serverprozess war solange nicht blockiert, wenn man den Logausgaben glauben kann: BlockingRegisterTelnet erfolgt direkt nach dem BlockingCall Aufruf aus dem Server.

Habe keine gute Idee, versuche zu raten: routing zu 127.0.0.1 ist kaputt.
Wenn ja: keine Ahnung, wie man das hinkriegt, bzw. wie man es repariert, wenn es nach einem reboot immer noch der Fall ist.

Hat jemand bessere Ideen?

DS_Starter

#2
Ich beobachte dieses Verhalten bei mir ebenfalls auf dem produktiven System nach einem Update vor 2 Tagen.
Nach einem Restart funktioniert alles wie gewohnt. Aber nach unbestimmter Zeit gibt es diese Netzwerkprobleme wie vom TE beschrieben.

In dem Logauszug sieht man dass sich das Problem auf alle möglichen Module mit Netzwerkkommunikation / BlockingCall auswirkt.


2022.04.09 17:13:48.678 3: MQTT_Broker: MQTT_Broker_192.168.2.57_54912/DVES_0608FA left us (keepalive check)
2022.04.09 17:14:01.665 1: DbRep Rep.CPU -> BlockingCall DbRep_minval pid:14665 Timeout: process terminated
2022.04.09 17:14:01.970 2: DbRep Rep.CPU - Database command aborted: "Timeout: process terminated"
2022.04.09 17:14:11.813 1: BlockingInformParent (BlockingRegisterTelnet): Can't connect to 127.0.0.1:7072: IO::Socket::INET: connect: Connection timed out
2022.04.09 17:14:12.198 2: deCONZ: http request failed: read from http://192.168.2.10:32785 timed out
2022.04.09 17:14:12.199 2: HUEBridge: http request failed: read from http://192.168.2.7:80 timed out
2022.04.09 17:14:12.200 2: HUEBridge: http request failed: read from http://192.168.2.7:80 timed out
2022.04.09 17:14:12.513 2: SynCal - ERROR message: read from http://192.168.2.10:5000 timed out
2022.04.09 17:14:12.820 2: SynCal - ERROR - "eventlist" SendQueue index "41" not executed. Restart SendQueue in 3600 s (retryCount 9).
2022.04.09 17:14:33.573 1: BlockingInformParent (BlockingRegisterTelnet): Can't connect to 127.0.0.1:7072: IO::Socket::INET: connect: Connection timed out
2022.04.09 17:14:43.557 1: BlockingInformParent (BlockingStart): Can't connect to 127.0.0.1:7072: IO::Socket::INET: connect: Connection timed out
2022.04.09 17:14:46.987 1: ZWDongle_1: SOF missing (got 00 instead of 01)
2022.04.09 17:14:47.296 2: CamHE1 - error while requesting http://192.168.2.10:5000/webapi/entry.cgi?api=SYNO.SurveillanceStation.Camera&version=8&method="GetCapabilityByCamId"&cameraId=1&_sid="nZRjBqS0HxaImD9p_E-ynulqFTUIDCG9j8NwePDblVI91WPdPJFIUQ4mSlcWwA0a9idQMHh9D0Zuk8Yu53Pwds" - http://192.168.2.10:5000/webapi/entry.cgi?api=SYNO.SurveillanceStation.Camera&version=8&method="GetCapabilityByCamId"&cameraId=1&_sid="nZRjBqS0HxaImD9p_E-ynulqFTUIDCG9j8NwePDblVI91WPdPJFIUQ4mSlcWwA0a9idQMHh9D0Zuk8Yu53Pwds": empty answer received


Bis dato hatte ich solche Probleme noch nie und sie traten augenfällig nach dem FHEM Update auf.
Aber das kann natürlich Zufall sein. Deswegen habe ich nach allen möglichen Restarts und was man in solchen Situation erst einmal tut, heute ein Restore auf die Versionen vor dem Update gemacht.

Ich werde nun beobachten ob es tatsächlich einen Zusammenhang mit dem Update gibt und wenn ja, versuchen Schritt für Schritt das Problem einzukreisen.
ESXi@NUC+Debian+MariaDB, PV: SMA, Victron MPII+Pylontech+CerboGX
Maintainer: SSCam, SSChatBot, SSCal, SSFile, DbLog/DbRep, Log2Syslog, SolarForecast,Watches, Dashboard, PylonLowVoltage
Kaffeekasse: https://www.paypal.me/HMaaz
Contrib: https://svn.fhem.de/trac/browser/trunk/fhem/contrib/DS_Starter

rudolfkoenig

Scheinbar werden in diesem Fall alle Verbindungen mit Timeout terminiert, selbst sowas wie DbRep, wo ich mich unschuldig fuehle :)

DS_Starter

Moin,

ja, DbRep ist ein ganz schlimmer Finger   :)

Kurzes Update ... nach dem Restore gestern läuft mein FHEM bis jetzt wieder schön problemlos.
Ich gehe aktuell davon aus, dass tatsächlich irgendeine ungünstige Konstellation mit dem Update reingekommen war.

Jetzt update ich die anstehenden Änderungen nach und nach wieder in das System.
Das Vorgehen dauert nun etwas weil ich nach jedem Teilupdate einige Stunden beobachten möchte wie sich das System verhält.
Mein letztes Update mit dem noch alles i.O. war, hatte ich am 13.03.22 durchgeführt.

Ich hatte in meiner Meldung vergessen zu erwähnen, dass im Fehlerfall eine 100%ige CPU Auslastung durch Perl im Server zu beobachten war.

Ich melde mich wieder wenn ich weitere Erkenntnisse habe ...
ESXi@NUC+Debian+MariaDB, PV: SMA, Victron MPII+Pylontech+CerboGX
Maintainer: SSCam, SSChatBot, SSCal, SSFile, DbLog/DbRep, Log2Syslog, SolarForecast,Watches, Dashboard, PylonLowVoltage
Kaffeekasse: https://www.paypal.me/HMaaz
Contrib: https://svn.fhem.de/trac/browser/trunk/fhem/contrib/DS_Starter

DS_Starter

@Chris, kannst du bitte eine Liste der von dir genutzten Module posten ? (version)

Meine aktuell eingesetzten Versionen sind:


Latest Revision: 25939

File                        Rev   Last Change

fhem.pl                     25777 2022-03-05 10:24:57Z rudolfkoenig
95_Alarm.pm                 17344 2018-09-14 14:05:23Z phenning
96_allowed.pm               25797 2022-03-08 08:54:57Z rudolfkoenig
No Id found for 98_Analyze.pm
42_AptToDate.pm             19639 2019-06-18 13:43:31Z CoolTux
95_Astro.pm                 25198 2021-11-07 15:42:54Z phenning
90_at.pm                    25248 2021-11-21 10:29:01Z rudolfkoenig
99_attrTmqtt2_ebus_Utils.pm 25044 2021-10-03 19:41:45Z Beta-User
98_autocreate.pm            23727 2021-02-12 20:31:37Z rudolfkoenig
98_cmdalias.pm              16300 2018-03-01 08:48:21Z rudolfkoenig
00_CUL.pm                   24815 2021-08-01 16:14:02Z rudolfkoenig
10_CUL_HM.pm                24031 2021-03-21 09:30:57Z martinp876
95_Dashboard.pm             21180 2020-02-11 21:04:55Z DS_Starter
93_DbLog.pm                 25800 2022-03-08 20:07:32Z DS_Starter
93_DbRep.pm                 25939 2022-04-09 19:03:34Z DS_Starter
98_dummy.pm                 25606 2022-02-01 10:43:57Z rudolfkoenig
55_DWD_OpenData.pm          23797 2021-02-21 19:49:57Z jensb
99_DWD_OpenData_Weblink.pm  201602 2002-10-09 11:06:00Z jensb
91_eventTypes.pm            23471 2021-01-04 19:24:21Z rudolfkoenig
93_FHEM2FHEM.pm             25703 2022-02-18 18:25:03Z rudolfkoenig
01_FHEMWEB.pm               25869 2022-03-21 10:38:18Z rudolfkoenig
92_FileLog.pm               25748 2022-02-27 10:29:56Z rudolfkoenig
10_FS20.pm                  14888 2017-08-13 12:07:12Z rudolfkoenig
89_FULLY.pm                 25516 2022-01-20 16:00:19Z zap
98_HMinfo.pm                24032 2021-03-21 09:32:15Z martinp876
00_HMLAN.pm                 25204 2021-11-09 05:41:42Z martinp876
95_holiday.pm               25187 2021-11-06 09:54:35Z rudolfkoenig
98_HTTPMOD.pm               24708 2021-07-06 17:15:46Z StefanStrobel
02_HTTPSRV.pm               20110 2019-09-05 17:30:20Z neubert
30_HUEBridge.pm             25769 2022-03-03 20:45:24Z justme1968
31_HUEDevice.pm             25648 2022-02-07 11:49:47Z justme1968
98_Installer.pm             20949 2020-01-12 09:53:11Z loredo
10_IT.pm                    20839 2019-12-28 09:41:47Z bjoernh
98_JsonList2.pm             23727 2021-02-12 20:31:37Z rudolfkoenig
93_Log2Syslog.pm            23875 2021-03-01 20:14:15Z DS_Starter
10_MQTT2_DEVICE.pm          25564 2022-01-26 12:23:35Z rudolfkoenig
00_MQTT2_SERVER.pm          25723 2022-02-21 08:59:09Z rudolfkoenig
74_Nmap.pm                  25647 2022-02-07 09:14:08Z rudolfkoenig
91_notify.pm                25888 2022-03-27 10:22:58Z rudolfkoenig
42_npmjs.pm                 20933 2020-01-10 12:27:41Z loredo
34_NUT.pm                    9023 2015-08-05 09:00:12Z narsskrarc
33_readingsGroup.pm         23844 2021-02-27 19:43:24Z justme1968
33_readingsProxy.pm         16299 2018-03-01 08:06:55Z justme1968
95_remotecontrol.pm         10724 2016-02-04 18:17:33Z ulimaass
10_RESIDENTS.pm             19533 2019-06-02 19:33:11Z loredo
20_ROOMMATE.pm              19533 2019-06-02 19:33:11Z loredo
91_sequence.pm              22810 2020-09-21 10:19:17Z rudolfkoenig
98_serviced.pm              24390 2021-05-06 22:25:17Z DeeSPe
39_siri.pm                  14044 2017-04-20 07:48:44Z justme1968
77_SMAEM.pm                 25136 2021-10-28 06:25:29Z vk
76_SMAInverter.pm           24737 2021-07-12 16:46:51Z MadMax
76_SMAPortal.pm             23272 2020-12-01 20:51:52Z DS_Starter
76_SMAPortalSPG.pm          21735 2020-04-20 20:53:24Z DS_Starter
76_SolarForecast.pm         21735 2020-04-20 20:53:24Z DS_Starter
00_SONOS.pm                 22400 2020-07-14 17:54:36Z Reinerlein
21_SONOSPLAYER.pm           22400 2020-07-14 17:54:36Z Reinerlein
32_speedtest.pm             23867 2021-03-01 08:23:09Z justme1968
57_SSCal.pm                 24736 2021-07-12 15:43:19Z DS_Starter
49_SSCam.pm                 25554 2022-01-24 21:23:40Z DS_Starter
49_SSCamSTRM.pm             25406 2022-01-01 17:39:52Z DS_Starter
50_SSChatBot.pm             23250 2020-11-28 12:38:53Z DS_Starter
50_SSFile.pm                24774 2021-07-18 19:49:56Z DS_Starter
98_structure.pm             24999 2021-09-21 07:54:25Z rudolfkoenig
99_SUNRISE_EL.pm            24249 2021-04-14 05:45:49Z rudolfkoenig
98_SVG.pm                   25876 2022-03-23 11:28:24Z rudolfkoenig
42_SYSMON.pm                24758 2021-07-16 13:54:47Z hexenmeister
# $Id: 32_SysStatHM.pm $
50_TelegramBot.pm           24867 2021-08-23 10:23:15Z viegener
98_telnet.pm                25754 2022-02-27 16:49:52Z rudolfkoenig
24_TPLinkHS110.pm           25206 2021-11-09 11:55:44Z vk
74_Unifi.pm                 23500 2021-01-09 15:14:50Z wuehler
74_UnifiClient.pm           19989 2019-08-12 18:25:21Z wuehler
99_Utils.pm                 24128 2021-04-02 16:29:11Z rudolfkoenig
98_Verkehrsinfo.pm          18521 2019-02-07 21:39:00Z martins
98_version.pm               15140 2017-09-26 09:20:09Z markusbloch
60_Watches.pm               23499 2021-01-09 13:55:39Z DS_Starter
59_Weather.pm               22982 2020-10-17 12:49:38Z CoolTux
98_weblink.pm               23597 2021-01-23 17:57:05Z rudolfkoenig
98_weekprofile.pm           25318 2021-12-07 20:01:44Z Risiko
32_withings.pm              25815 2022-03-12 16:12:33Z moises
10_ZWave.pm                 25357 2021-12-20 08:46:36Z rudolfkoenig
00_ZWDongle.pm              24597 2021-06-07 16:05:33Z rudolfkoenig

AttrTemplate.pm             25155 2021-10-30 12:48:21Z rudolfkoenig
Blocking.pm                 23268 2020-12-01 11:48:48Z rudolfkoenig
No Id found for CDDB_File.pm
Color.pm                    20813 2019-12-22 18:42:10Z justme1968
Common.pm                   10759 2016-02-07 20:00:12Z rleins
ControlPoint.pm             22400 2020-07-14 17:54:36Z Reinerlein
No Id found for Cue.pm
DarkSkyAPI.pm               23645 2021-01-30 17:55:57Z CoolTux
DevIo.pm                    25716 2022-02-20 17:39:00Z rudolfkoenig
No Id found for File.pm
GPUtils.pm                  19666 2019-06-20 11:17:29Z CoolTux
HMConfig.pm                 24033 2021-03-21 09:33:32Z martinp876
HttpUtils.pm                25784 2022-03-06 09:20:24Z rudolfkoenig
No Id found for ID3v1.pm
No Id found for ID3v2.pm
No Id found for ImageExifTool.pm
No Id found for ImageSize.pm
No Id found for Inf.pm
Info.pm                        28 2008-11-09 01:08:44Z dsully
No Id found for LastResort.pm
Meta.pm                     21008 2020-01-18 10:22:10Z loredo
myUtilsTemplate.pm           7570 2015-01-14 18:31:44Z rudolfkoenig
myUtilsTemplate.pm           7570 2015-01-14 18:31:44Z rudolfkoenig
myUtilsTemplate.pm           7570 2015-01-14 18:31:44Z rudolfkoenig
myUtilsTemplate.pm           7570 2015-01-14 18:31:44Z rudolfkoenig
myUtilsTemplate.pm           7570 2015-01-14 18:31:44Z rudolfkoenig
myUtilsTemplate.pm           7570 2015-01-14 18:31:44Z rudolfkoenig
myUtilsTemplate.pm           7570 2015-01-14 18:31:44Z rudolfkoenig
myUtilsTemplate.pm           7570 2015-01-14 18:31:44Z rudolfkoenig
No Id found for ParseData.pm
RESIDENTStk.pm              19788 2019-07-06 08:10:55Z loredo
RTypes.pm                   10476 2016-01-12 21:03:33Z borisneubert
SetExtensions.pm            25286 2021-12-03 10:16:56Z rudolfkoenig
SubProcess.pm               14334 2017-05-20 23:11:06Z neubert
No Id found for Tag.pm
TcpServerUtils.pm           25743 2022-02-26 10:35:32Z rudolfkoenig
TimeSeries.pm               22980 2020-10-17 09:21:43Z neubert
UConv.pm                    21159 2020-02-09 14:04:27Z loredo
Unit.pm                     19614 2019-06-13 23:11:25Z loredo
ZWLib.pm                    17186 2018-08-20 20:10:55Z rudolfkoenig

HMinfoTools.js             1010 2021-03-25 22:00:00Z frank
fhemweb.js                 25924 2022-04-05 14:38:39Z rudolfkoenig
fhemweb_readingsGroup.js   15189 2017-10-03 17:53:27Z justme1968
hm.js                      2008 2021-03-01 12:00:00Z frank
svg.js                     23428 2020-12-27 22:07:20Z rudolfkoenig
ESXi@NUC+Debian+MariaDB, PV: SMA, Victron MPII+Pylontech+CerboGX
Maintainer: SSCam, SSChatBot, SSCal, SSFile, DbLog/DbRep, Log2Syslog, SolarForecast,Watches, Dashboard, PylonLowVoltage
Kaffeekasse: https://www.paypal.me/HMaaz
Contrib: https://svn.fhem.de/trac/browser/trunk/fhem/contrib/DS_Starter

hanswerner1

Ich habe das gleiche Problem seit einem Update am 1.4.
Bin jetzt wieder zurück vor den 1.4 und alles läuft wieder perfekt.
Folgende Module wurden bei mir am 1.4 geupdatet:
10_EnOcean.pm
10_MQTT2_DEVICE.pm
70_KODI.pm
70_SVDRP.pm
82_LGTV_WebOS.pm
89_AndroidDB.pm
89_AndroidDBHost.pm
91_notify.pm
98_archetype.pm
98_SVG.pm
fhem.pl

VG HW1


DS_Starter

Ich habe gestern auch weitere Teilupdates vorgenommen und bis heute läuft FHEM noch wie gewohnt problemlos.
Die nächsten Updates teste ich jetzt:


UPD FHEM/00_MQTT2_CLIENT.pm
UPD FHEM/00_MQTT2_SERVER.pm
UPD FHEM/TcpServerUtils.pm


Aussteht dann noch update von fhem.pl.
ESXi@NUC+Debian+MariaDB, PV: SMA, Victron MPII+Pylontech+CerboGX
Maintainer: SSCam, SSChatBot, SSCal, SSFile, DbLog/DbRep, Log2Syslog, SolarForecast,Watches, Dashboard, PylonLowVoltage
Kaffeekasse: https://www.paypal.me/HMaaz
Contrib: https://svn.fhem.de/trac/browser/trunk/fhem/contrib/DS_Starter

DS_Starter

Bei mir hat sich jetzt nach dem update der fhem.pl die Netzwerkproblematik wieder eingestellt.
Nach einigen Stunden ! Laufzeit treten wieder Netzwerkprobleme / BlockingCall timeouts in jeglicher Ausprägung auf.

Ausschnitt:


2022.04.12 07:31:42.669 1: Timeout for SYSMON_blockingCall reached, terminated process 19092
2022.04.12 07:31:49.412 1: DbRep Bezug -> BlockingCall DbRep_sumval pid:19064 Timeout: process terminated
2022.04.12 07:31:49.668 2: DbRep Bezug - Database command aborted: "Timeout: process terminated"
2022.04.12 07:31:50.210 2: deCONZ: http request failed: read from http://192.168.2.10:32785 timed out
2022.04.12 07:31:50.214 2: HUEBridge: http request failed: read from http://192.168.2.7:80 timed out
2022.04.12 07:32:00.976 1: ZWDongle_1: SOF missing (got 00 instead of 01)
2022.04.12 07:32:08.375 2: HUEBridge: http request failed: http://192.168.2.7/api/glps784HOB2pW60bR82sJvxj0sd0FAIl1OvF7QSL/sensors/2: empty answer received
2022.04.12 07:32:09.273 1: DbRep Einspeisung -> BlockingCall DbRep_sumval pid:19073 Timeout: process terminated
2022.04.12 07:32:09.278 2: DbRep Einspeisung - Database command aborted: "Timeout: process terminated"
2022.04.12 07:32:09.394 2: CamHE1 - error while requesting http://192.168.2.10:5000/webapi/entry.cgi?api=SYNO.SurveillanceStation.Camera&version=8&method="GetCapabilityByCamId"&cameraId=1&_sid="Sq52Hw41_TddnpFruUiVv6NDZ2OlWW6oCc9XdMGXL3MtryjTSVltQUkOdXSFeMwmHvtdgEiYR0gN6Rrftg5vQI" - http://192.168.2.10:5000/webapi/entry.cgi?api=SYNO.SurveillanceStation.Camera&version=8&method="GetCapabilityByCamId"&cameraId=1&_sid="Sq52Hw41_TddnpFruUiVv6NDZ2OlWW6oCc9XdMGXL3MtryjTSVltQUkOdXSFeMwmHvtdgEiYR0gN6Rrftg5vQI": empty answer received
2022.04.12 07:32:09.402 2: SDS1_SVS - error while requesting http://192.168.2.10:5000/webapi/entry.cgi?api="SYNO.SurveillanceStation.HomeMode"&version="1"&method=GetInfo&_sid="IxvnvI2UhvZhkFRHvplKFqv_gmOPIRfyXrwyfpzO_C8UO46lpKOu7HRWhKz6IiABvpM4bWsVy1WPqmymmsbA9E" - http://192.168.2.10:5000/webapi/entry.cgi?api="SYNO.SurveillanceStation.HomeMode"&version="1"&method=GetInfo&_sid="IxvnvI2UhvZhkFRHvplKFqv_gmOPIRfyXrwyfpzO_C8UO46lpKOu7HRWhKz6IiABvpM4bWsVy1WPqmymmsbA9E": empty answer received
2022.04.12 07:32:09.451 1: ZWDongle_1: SOF missing (got 64 instead of 01)
2022.04.12 07:32:09.454 2: CamTER - error while requesting http://192.168.2.10:5000/webapi/entry.cgi?api=SYNO.SurveillanceStation.Camera&version=8&method="GetCapabilityByCamId"&cameraId=2&_sid="VvT-N8VM4hNhkv64UNVRAJDCHZIRi-J8bMqGX-X4iWwBvajt62tNtynbly28x2ADTk-Z5XsCFdy7vAMbvA5Pp8" - http://192.168.2.10:5000/webapi/entry.cgi?api=SYNO.SurveillanceStation.Camera&version=8&method="GetCapabilityByCamId"&cameraId=2&_sid="VvT-N8VM4hNhkv64UNVRAJDCHZIRi-J8bMqGX-X4iWwBvajt62tNtynbly28x2ADTk-Z5XsCFdy7vAMbvA5Pp8": empty answer received
2022.04.12 07:32:09.456 2: HUEBridge: http request failed: http://192.168.2.7/api/glps784HOB2pW60bR82sJvxj0sd0FAIl1OvF7QSL/sensors/2: empty answer received
2022.04.12 07:32:09.594 2: HUEBridge: http request failed: http://192.168.2.7/api/glps784HOB2pW60bR82sJvxj0sd0FAIl1OvF7QSL/sensors/2: empty answer received
2022.04.12 07:32:09.596 1: ZWDongle_1: SOF missing (got 21 instead of 01)
2022.04.12 07:32:09.631 2: HUEBridge: http request failed: http://192.168.2.7/api/glps784HOB2pW60bR82sJvxj0sd0FAIl1OvF7QSL: empty answer received


D.h. also mit


fhem.pl  25777 2022-03-05 10:24:57Z


ist alles fein, aber mit


fhem.pl 25922 2022-04-05 08:55:13Z


treten die Probleme auf.
Unschön ist natürlich dass man nicht sofort Probleme feststellt und auch bestimmte Bedingungen, die zu dem Zustand führen, nicht erkennt. Es hat ca. 7 h gedauert bis die oben gezeigten Netzwerkprobleme auftreten, dann aber massiv und auch nicht wieder endend.
Außerdem vermute ich auch, dass bestimmte Nebenbedingungen zu dem Zustand führen, denn auf meinem Testsystem tritt diese Thematik nicht auf. Natürlich sind auf dem Testsystem nicht sämtliche Bestandteile in produktiven Einsatz welche Last verursachen können. Das betrifft z.B. HUE, MQTTSERVER / DEVICE, Homematic,ZWAVE,deCONZ.

Ich hätte gern mehr Infos gegeben die zur Lösung dienlich sein können, aber leider kann ich nur das Symptom beim Einsatz beider fhem.pl Versionen feststellen.

Bin jetzt wieder auf fhem.pl  25777 zurück gegangen.

Grüße,
Heiko

ESXi@NUC+Debian+MariaDB, PV: SMA, Victron MPII+Pylontech+CerboGX
Maintainer: SSCam, SSChatBot, SSCal, SSFile, DbLog/DbRep, Log2Syslog, SolarForecast,Watches, Dashboard, PylonLowVoltage
Kaffeekasse: https://www.paypal.me/HMaaz
Contrib: https://svn.fhem.de/trac/browser/trunk/fhem/contrib/DS_Starter

rudolfkoenig

Ich habe den Diff angeschaut, und staune.
Kannst Du bitte die Versionen 25889 und 25896 auch probieren?
Sieht man ausser den Meldungen im Log (und deren direkten Konsequenzen) auch andere Probleme, wie z.Bsp. ein traeges Frontend?

DS_Starter

ZitatKannst Du bitte die Versionen 25889 und 25896 auch probieren?
Ja, mache ich gerne. Zieht sich wegen der stundenlangen Verzögerung etwas hin.

Zitat
Sieht man ausser den Meldungen im Log (und deren direkten Konsequenzen) auch andere Probleme, wie z.Bsp. ein traeges Frontend?
Nein, konnte ich bei mir nicht feststellen. Nur die Logmeldungen und das natürlich bestimmte Funktionen wie Datenanalysen oder Schaltvorgänge der betroffenen Module/Devices nicht bzw. sehr zeitverzögert ausgeführt werden.

Interessant wäre auch was die anderen betroffenen User beobachten können.
ESXi@NUC+Debian+MariaDB, PV: SMA, Victron MPII+Pylontech+CerboGX
Maintainer: SSCam, SSChatBot, SSCal, SSFile, DbLog/DbRep, Log2Syslog, SolarForecast,Watches, Dashboard, PylonLowVoltage
Kaffeekasse: https://www.paypal.me/HMaaz
Contrib: https://svn.fhem.de/trac/browser/trunk/fhem/contrib/DS_Starter

hanswerner1

Zitat von: DS_Starter am 12 April 2022, 11:08:23
Interessant wäre auch was die anderen betroffenen User beobachten können.

Bei mir, Schaltverzögerung, bzw Ausfall der Schaltung und die Weboberfäche ist extrem verzögert. Der Aufruf der Oberfläche dauert zum Teil 1 bis 2 Minuten.

rudolfkoenig

@hanswerner1: kannst Du bitte beide fhem.pl Versionsnummer zeigen?

hanswerner1

25852 funktioniert noch und 25896 produziert bei mir dann den Fehler

DS_Starter

Ich habe jetzt die 25889 eingespielt und beobachte ...
ESXi@NUC+Debian+MariaDB, PV: SMA, Victron MPII+Pylontech+CerboGX
Maintainer: SSCam, SSChatBot, SSCal, SSFile, DbLog/DbRep, Log2Syslog, SolarForecast,Watches, Dashboard, PylonLowVoltage
Kaffeekasse: https://www.paypal.me/HMaaz
Contrib: https://svn.fhem.de/trac/browser/trunk/fhem/contrib/DS_Starter