[erledigt] seit 22.09.20: ERROR: addToWritebuffer for

the ratman · 22 September 2020, 12:29:32

hoffe, ich bin im richtigen abeteil - seit heute nach meine täglichen update kommt immer weider mal:

2020.09.22 08:25:54 1:  logfile wurde gelöscht
2020.09.22 08:26:02 1:  ERROR: addToWritebuffer for handyWEB_192.168.178.51_5938 without FD
2020.09.22 08:26:02 1:  probably caused at  TcpServer_Close:352 TcpServer_WriteBlocking:394 FW_Read:3816 CallFn:763
2020.09.22 08:26:02 1:  ERROR: addToWritebuffer for handyWEB_192.168.178.51_5938 without FD
2020.09.22 08:26:02 1:  probably caused at  TcpServer_Close:352 TcpServer_WriteBlocking:394 FW_Read:3816 CallFn:763
2020.09.22 08:57:26 1:  ERROR: addToWritebuffer for handyWEB_192.168.178.51_9128 without FD
2020.09.22 08:57:26 1:  probably caused at  TcpServer_Close:352 TcpServer_WriteBlocking:394 FW_Read:3816 CallFn:763
2020.09.22 08:59:34 1:  RMDIR: ./restoreDir/save/2020-09-16
2020.09.22 09:00:12 1:  ERROR: addToWritebuffer for handyWEB_192.168.178.51_9393 without FD
2020.09.22 09:00:12 1:  probably caused at  TcpServer_Close:352 TcpServer_WriteBlocking:394 FW_Read:3816 CallFn:763

wat den da los?

Nobbynews · 22 September 2020, 12:41:34

Selbiges hier auch, allerdings auch schon am 18.9.2020, Update am 17.9.2020 ausgeführt:

Code Auswählen

2020.09.18 15:59:29 1: ERROR: addToWritebuffer for MQTT2_Server_192.168.2.222_51002 without FD
2020.09.18 15:59:29 1: probably caused at  TcpServer_Close:104 MQTT2_SERVER_Undef:3816 CallFn:2252 CommandDelete:254 MQTT2_SERVER_Read:3816 CallFn:763

Und gestern nach Update am 20.9.2020:

Code Auswählen

2020.09.21 14:47:09 1: ERROR: addToWritebuffer for MQTT2_Server_192.168.2.222_49707 without FD
2020.09.21 14:47:09 1: probably caused at  TcpServer_Close:104 MQTT2_SERVER_Undef:3816 CallFn:2252 CommandDelete:254 MQTT2_SERVER_Read:3816 CallFn:763

rudolfkoenig · 23 September 2020, 17:03:18

Da meine Fantasie nicht ausreicht, um die Ursache zu finden, habe ich jetzt die Fehlermeldung erweitert, bitte mit dem neuen fhem.pl testen, und melden.
Die Meldung sollte jetzt 3 Zeilen beinhalten:

ZitatERROR: addToWritebuffer for $n without FD
callstack:...
FD closed in ....

Dabei ist mir aufgefallen, dass chrome und chromium die Verbindungen zu FHEM nicht schliessen, wenn man den Tab zumacht. Dieses Verhalten finde ich nicht richtig.
Immerhin werden die Verbindungen geschlossen, wenn man Chrome beendet.

Nobbynews · 24 September 2020, 06:43:24

Seit dem Log-Eintrag vom 21.9.2020 ist der Fehler nicht mehr aufgetreten.
Das Update werde ich heute noch durchführen und dann mal beobachten.
Danke!

the ratman · 27 September 2020, 21:09:42

ha! erster *g*

Code Auswählen

2020.09.27 19:53:32 1:  ERROR: addToWritebuffer for handyWEB_192.168.178.51_3043 without FD
2020.09.27 19:53:32 1:  callstack: addToWritebuffer:726 FW_addToWritebuffer:3165 FW_Notify:3817 CallFn:3735 DoTrigger:4808 readingsEndUpdate:2958 HTTPMOD_Read:634 __ANON__:760
2020.09.27 19:53:32 1:  FD closed in  TcpServer_Close:352 TcpServer_WriteBlocking:394 FW_Read:3817 CallFn:763

also ich hab um die zeit selber 0 gemacht - schawööhre!

rudolfkoenig · 28 September 2020, 09:53:23

Danke.
Ich meine die Ursache gefunden zu haben, und habe einen Fix eingecheckt (TcpServerUtils.pm)

the ratman · 28 September 2020, 10:07:04

ebenfalls danke! *verbeug*

RockFan · 29 September 2020, 18:11:26

Hallo zusammen,

ich bin gestern auf diesen Thread gestoßen, da ich den gleichen Fehler im Log hatte, wenn ich über FhemNative und dem Verbindungstyp "Fhemweb" mein Internetradio (SIRD) einschalte.
Habe nun gerade FHEM aktualisiert und ausprobiert. Leider bekomme ich immer noch den gleichen Fehler:

Code Auswählen


2020.09.29 17:57:07 1: ERROR: addToWritebuffer for WEB_192.168.178.105_52287 without FD
2020.09.29 17:57:07 1: callstack: addToWritebuffer:726 FW_addToWritebuffer:3165 FW_Notify:3817 CallFn:3735 DoTrigger:4808 readingsEndUpdate:2072 SIRD_ParseMultiple:634 __ANON__:760
2020.09.29 17:57:07 1: FD closed in  TcpServer_Close:353 TcpServer_WriteBlocking:394 FW_Read:3817 CallFn:763
2020.09.29 17:57:07 1: ERROR: addToWritebuffer for WEB_192.168.178.105_52289 without FD
2020.09.29 17:57:07 1: callstack: addToWritebuffer:726 FW_addToWritebuffer:3165 FW_Notify:3817 CallFn:3735 DoTrigger:4808 readingsEndUpdate:2072 SIRD_ParseMultiple:634 __ANON__:760
2020.09.29 17:57:07 1: FD closed in  TcpServer_Close:353 TcpServer_WriteBlocking:394 FW_Read:3817 CallFn:763
2020.09.29 17:57:07 1: ERROR: addToWritebuffer for WEB_192.168.178.105_52292 without FD
2020.09.29 17:57:07 1: callstack: addToWritebuffer:726 FW_addToWritebuffer:3165 FW_Notify:3817 CallFn:3735 DoTrigger:4808 readingsEndUpdate:2072 SIRD_ParseMultiple:634 __ANON__:760
2020.09.29 17:57:07 1: FD closed in  TcpServer_Close:353 TcpServer_WriteBlocking:394 FW_Read:3817 CallFn:763
2020.09.29 17:57:07 1: ERROR: addToWritebuffer for WEB_192.168.178.105_52294 without FD
2020.09.29 17:57:07 1: callstack: addToWritebuffer:726 FW_addToWritebuffer:3165 FW_Notify:3817 CallFn:3735 DoTrigger:4808 readingsEndUpdate:2072 SIRD_ParseMultiple:634 __ANON__:760
2020.09.29 17:57:07 1: FD closed in  TcpServer_Close:353 TcpServer_WriteBlocking:394 FW_Read:3817 CallFn:763
2020.09.29 17:57:08 3: YeeLight YeeLight_wz_Lampe - set YeeLight_wz_Lampe ? 
2020.09.29 17:57:10 3: YeeLight YeeLight_wz_Lampe - set YeeLight_wz_Lampe ? 
2020.09.29 17:57:12 3: YeeLight YeeLight_wz_Lampe - set YeeLight_wz_Lampe ? 
2020.09.29 17:57:13 3: XiaomiGateway: DEV_Read> Name: XMI_Bewegungsmelder_3 SID: 158d000200eab4 Type: sensor_motion.aq2 Illuminance: 0
2020.09.29 17:57:13 3: XiaomiGateway: DEV_Read> Name: XMI_Bewegungsmelder_3 SID: 158d000200eab4 Type: sensor_motion.aq2 Status: motion
2020.09.29 17:57:13 1: ERROR: addToWritebuffer for WEB_192.168.178.105_52296 without FD
2020.09.29 17:57:13 1: callstack: addToWritebuffer:726 FW_addToWritebuffer:3165 FW_Notify:3817 CallFn:3735 DoTrigger:1934 DoSet:1955 CommandSet:1255 AnalyzeCommand:1106 AnalyzeCommandChain:1823 ParseCommandsDoIf:2242 DOIF_cmd:2501 DOIF_Trigger:2715 DOIF_Notify:3817 CallFn:3735 DoTrigger:4104 Dispatch:172 XiaomiSmartHome_Read:3817 CallFn:763
2020.09.29 17:57:13 1: FD closed in  TcpServer_Close:353 TcpServer_WriteBlocking:394 FW_Read:3817 CallFn:763
2020.09.29 17:57:13 1: ERROR: addToWritebuffer for WEB_192.168.178.105_52298 without FD
2020.09.29 17:57:13 1: callstack: addToWritebuffer:726 FW_addToWritebuffer:3165 FW_Notify:3817 CallFn:3735 DoTrigger:1934 DoSet:1955 CommandSet:1255 AnalyzeCommand:1106 AnalyzeCommandChain:1823 ParseCommandsDoIf:2242 DOIF_cmd:2501 DOIF_Trigger:2715 DOIF_Notify:3817 CallFn:3735 DoTrigger:4104 Dispatch:172 XiaomiSmartHome_Read:3817 CallFn:763
2020.09.29 17:57:13 1: FD closed in  TcpServer_Close:353 TcpServer_WriteBlocking:394 FW_Read:3817 CallFn:763
2020.09.29 17:57:13 1: ERROR: addToWritebuffer for WEB_192.168.178.105_52301 without FD
2020.09.29 17:57:13 1: callstack: addToWritebuffer:726 FW_addToWritebuffer:3165 FW_Notify:3817 CallFn:3735 DoTrigger:1934 DoSet:1955 CommandSet:1255 AnalyzeCommand:1106 AnalyzeCommandChain:1823 ParseCommandsDoIf:2242 DOIF_cmd:2501 DOIF_Trigger:2715 DOIF_Notify:3817 CallFn:3735 DoTrigger:4104 Dispatch:172 XiaomiSmartHome_Read:3817 CallFn:763
2020.09.29 17:57:13 1: FD closed in  TcpServer_Close:353 TcpServer_WriteBlocking:394 FW_Read:3817 CallFn:763
2020.09.29 17:57:14 3: YeeLight YeeLight_wz_Lampe - set YeeLight_wz_Lampe ? 
2020.09.29 17:57:17 1: ERROR: addToWritebuffer for WEB_192.168.178.105_52303 without FD
2020.09.29 17:57:17 1: callstack: addToWritebuffer:726 FW_addToWritebuffer:3165 FW_Notify:3817 CallFn:3735 DoTrigger:4808 readingsEndUpdate:4991 readingsSingleUpdate:2174 SIRD_ParsePower:634 __ANON__:760
2020.09.29 17:57:17 1: FD closed in  TcpServer_Close:353 TcpServer_WriteBlocking:394 FW_Read:3817 CallFn:763

Habe ich ein anderes Problem oder hat der Fix nicht funktioniert?

Viele Grüße
Dieter

rudolfkoenig · 29 September 2020, 19:42:36

Vermutlich Letzteres.
Ich habe eine neue Variente (aka Versuch #2) eingecheckt.

RockFan · 30 September 2020, 23:10:05

Danke, der neue Fix ist bei mir erfolgreich!
Mit der heutigen Version kann ich die Fehlermeldung nicht mehr erzeugen.

rallye · 01 Dezember 2020, 13:14:38

Ich habe diese Meldungen in meinem Log auch gefunden und ein Update von FHEM durchgeführt. Doch leider ändert das in meiner Installation überhaupt nichts.Wenn ich mich außerhalb meines WLANs befinde wird mein Log mit u.a. Meldungen vollgefüllt (es handelt sich um Owntracks mittesl MQTT2 angebunden)

Code Auswählen

2020.12.01 00:00:27 3: Login denied via MQTT_Owntracks
2020.12.01 00:00:28 1: ERROR: addToWritebuffer for MQTT_Owntracks_89.144.218.111_14214 without FD
2020.12.01 00:00:28 1: callstack: addToWritebuffer:219 MQTT2_SERVER_out:499 MQTT2_SERVER_sendto:387 __ANON__:3342 HandleTimeout:677
2020.12.01 00:00:28 1: FD closed in  TcpServer_Close:105 MQTT2_SERVER_Undef:3810 CallFn:2244 CommandDelete:256 MQTT2_SERVER_Read:3810 CallFn:755
2020.12.01 00:00:28 1: ERROR: addToWritebuffer for MQTT_Owntracks_89.144.218.111_14214 without FD
2020.12.01 00:00:28 1: callstack: addToWritebuffer:219 MQTT2_SERVER_out:499 MQTT2_SERVER_sendto:387 __ANON__:3342 HandleTimeout:677
2020.12.01 00:00:28 1: FD closed in  TcpServer_Close:105 MQTT2_SERVER_Undef:3810 CallFn:2244 CommandDelete:256 MQTT2_SERVER_Read:3810 CallFn:755
2020.12.01 00:00:28 1: ERROR: addToWritebuffer for MQTT_Owntracks_89.144.218.111_14214 without FD
2020.12.01 00:00:28 1: callstack: addToWritebuffer:219 MQTT2_SERVER_out:499 MQTT2_SERVER_sendto:387 __ANON__:3342 HandleTimeout:677
2020.12.01 00:00:28 1: FD closed in  TcpServer_Close:105 MQTT2_SERVER_Undef:3810 CallFn:2244 CommandDelete:256 MQTT2_SERVER_Read:3810 CallFn:755
2020.12.01 00:00:34 3: Login denied via MQTT_Owntracks
2020.12.01 00:00:37 3: Login denied via MQTT_Owntracks
2020.12.01 00:00:39 1: ERROR: addToWritebuffer for MQTT_Owntracks_89.144.218.111_14215 without FD
2020.12.01 00:00:39 1: callstack: addToWritebuffer:219 MQTT2_SERVER_out:499 MQTT2_SERVER_sendto:387 __ANON__:3342 HandleTimeout:677
2020.12.01 00:00:39 1: FD closed in  TcpServer_Close:105 MQTT2_SERVER_Undef:3810 CallFn:2244 CommandDelete:256 MQTT2_SERVER_Read:3810 CallFn:755
2020.12.01 00:00:39 1: ERROR: addToWritebuffer for MQTT_Owntracks_89.144.218.111_14215 without FD
2020.12.01 00:00:39 1: callstack: addToWritebuffer:219 MQTT2_SERVER_out:499 MQTT2_SERVER_sendto:387 __ANON__:3342 HandleTimeout:677
2020.12.01 00:00:39 1: FD closed in  TcpServer_Close:105 MQTT2_SERVER_Undef:3810 CallFn:2244 CommandDelete:256 MQTT2_SERVER_Read:3810 CallFn:755
2020.12.01 00:00:39 1: ERROR: addToWritebuffer for MQTT_Owntracks_89.144.218.111_14215 without FD
2020.12.01 00:00:39 1: callstack: addToWritebuffer:219 MQTT2_SERVER_out:499 MQTT2_SERVER_sendto:387 __ANON__:3342 HandleTimeout:677
2020.12.01 00:00:39 1: FD closed in  TcpServer_Close:105 MQTT2_SERVER_Undef:3810 CallFn:2244 CommandDelete:256 MQTT2_SERVER_Read:3810 CallFn:755

Bin leider ratlos was ich dagegen tun kann (ausser mit verbose spielen)

Danke

rudolfkoenig · 01 Dezember 2020, 13:52:55

Ich meine die addToWriteBuffer Meldungen gefixt zu haben.
Die eigentliche Ursache ist vmtl. die "Login deined" Meldung, und das ist eine Benutzerkonfiguration.

rallye · 03 Dezember 2020, 12:30:49

Zitat von: rudolfkoenig am 01 Dezember 2020, 13:52:55
Ich meine die addToWriteBuffer Meldungen gefixt zu haben.
Die eigentliche Ursache ist vmtl. die "Login deined" Meldung, und das ist eine Benutzerkonfiguration.

Das dachte ich auch und habe deshalb ein Update des Systems gemacht.
Woher die "Login denied" kommen ist mir ebenfalls unklar. Ich gehe davon aus, dass es sich um die Mobiltelefone mit Owntracks handelt, wenn die Datenübertragung nicht 100% funktioniert. Aber das ist nur eine grobe Vermutung. Ich wüsste keinen Weg mehr Details darüber herauszufinden. Klar ist jedenfalls, dass die IDs & PWs in den Telefonen nicht andauernd geändert werden, daher denke ich, liegt es an einer fehlerhaften Übertragung.