FHEM Forum

FHEM - Hausautomations-Systeme => MQTT => Thema gestartet von: hubiuwe am 26 Dezember 2020, 10:30:11

Titel: [gelöst]MQTT2_CLIENT internal BUF füllt sich, ist das normal?
Beitrag von: hubiuwe am 26 Dezember 2020, 10:30:11
Hallo und schöne Feiertage!
Ich steige gerade in das Thema MQTT ein.
Ist es normal daß sich das Internal BUF im MQTT2_CLIENT füllt?

Fhem Publiziert auf dem Topic: Fhem/state/....
Dafür wird die MQTT_GENERIC_BRIDGE genutzt.
Ich nutze Mosquitto 2.04 als Broker.

Das Internal:
Internals:
   BUF        @�0+%Fhem/state/Umgebung/Wetter/brightness5891@�0+%Fhem/state/Umgebung/Wetter/brightness5425@�0$Fhem/state/Umgebung/Wetter/wind0.0@�0'#Fhem/state/Umgebung/Wetter/humidity74@�0+&Fhem/state/Umgebung/Wetter/temperature2.0@�0+%Fhem/state/Umgebung/Wetter/brightness5192@�0+%Fhem/state/Umgebung/Wetter/brightness5075@�0+%Fhem/state/Umgebung/Wetter/brightness5192@�0$Fhem/state/Umgebung/Wetter/wind0.0@�0'#Fhem/state/Umgebung/Wetter/humidity74@�0+&Fhem/state/Umgebung/Wetter/temperature1.9@�0+%Fhem/state/Umgebung/Wetter/brightness5658@�0+%Fhem/state/Umgebung/Wetter/brightness6124@�0+%Fhem/state/Umgebung/Wetter/brightness6706@�0+%Fhem/state/Umgebung/Wetter/brightness7288@�0$Fhem/state/Umgebung/Wetter/wind0.0@�0'#Fhem/state/Umgebung/Wetter/humidity74@�0+&Fhem/state/Umgebung/Wetter/temperature1.80+%Fhem/state/Umgebung/Wetter/brightness7871@�0+%Fhem/state/Umgebung/Wetter/brightness8453@�0+%Fhem/state/Umgebung/Wetter/brightness8803@�0$Fhem/state/Umgebung/Wetter/wind0.0@�0'#Fhem/state/Umgebung/Wetter/humidity740+&Fhem/state/Umgebung/Wetter/temperature1.80+%Fhem/state/Umgebung/Wetter/brightness9385@�0+%Fhem/state/Umgebung/Wetter/brightness9967@�0+%Fhem/state/Umgebung/Wetter/brightness9385@�0+%Fhem/state/Umgebung/Wetter/brightness9385@�0$Fhem/state/Umgebung/Wetter/wind0.0@�0'#Fhem/state/Umgebung/Wetter/humidity74@0+&Fhem/state/Umgebung/Wetter/temperature1.90+%Fhem/state/Umgebung/Wetter/brightness8803@0$Fhem/state/Umgebung/Wetter/wind0.0@0'#Fhem/state/Umgebung/Wetter/humidity74@0+&Fhem/state/Umgebung/Wetter/temperature1.90+%Fhem/state/Umgebung/Wetter/brightness8686@0+%Fhem/state/Umgebung/Wetter/brightness8919@0$Fhem/state/Umgebung/Wetter/wind0.0@0'#Fhem/state/Umgebung/Wetter/humidity75@0+&Fhem/state/Umgebung/Wetter/temperature2.00+%Fhem/state/Umgebung/Wetter/brightness9268@0FhemTest/hallo50+%Fhem/state/Umgebung/Wetter/brightness9268@ 0FhemTest/hallo50+%Fhem/state/Umgebung/Wetter/brightness8686@
0+%Fhem/state/Umgebung/Wetter/brightness8337@ 0+%Fhem/state/Umgebung/Wetter/brightness7754@ 0+%Fhem/state/Umgebung/Wetter/brightness7172@
0$Fhem/state/Umgebung/Wetter/wind0.0@0'#Fhem/state/Umgebung/Wetter/humidity75@0+&Fhem/state/Umgebung/Wetter/temperature2.2@0+%Fhem/state/Umgebung/Wetter/brightness6822@0+%Fhem/state/Umgebung/Wetter/brightness6589@0+%Fhem/state/Umgebung/Wetter/brightness6240@0+%Fhem/state/Umgebung/Wetter/brightness6124@
   DEF        127.0.0.1:1883
   DeviceName 127.0.0.1:1883
   FD         23
   FUUID      5fdfa506-f33f-e2e1-0374-8885415b4562c139
   NAME       MQTT_Broker
   NR         170
   PARTIAL   
   STATE      opened
   TYPE       MQTT2_CLIENT
   WBCallback
   clientId   Fhem
   lastMsgTime 1608973518.97176
   nextOpenDelay 5
   qosCnt     532
   qosMaxQueueLength 50
   Helper:
     DBLOG:
       state:
         DBLogging:
           TIME       1608969458.25452
           VALUE      CONNECTED
   READINGS:
     2020-12-26 08:50:30   lastPublish     Test1Fhem/hallo1:3
     2020-12-26 08:57:38   state           opened
   qosQueue:
     ARRAY(0x561dce773fa0)
     ARRAY(0x561dced03c90)
     ARRAY(0x561dceb1c2e0)
     ARRAY(0x561dc52093a8)
     ARRAY(0x561dc51406f0)
     ARRAY(0x561dce87b068)
     ARRAY(0x561dce497af8)
     ARRAY(0x561dcecc58b8)
     ARRAY(0x561dc64fc2b8)
     ARRAY(0x561dce960ca8)
     ARRAY(0x561dc5239f00)
     ARRAY(0x561dcead5570)
     ARRAY(0x561dce820da0)
     ARRAY(0x561dce82a878)
     ARRAY(0x561dce820bd8)
     ARRAY(0x561dce780ab8)
     ARRAY(0x561dce796748)
     ARRAY(0x561dced08490)
     ARRAY(0x561dced08f88)
     ARRAY(0x561dc51407e0)
     ARRAY(0x561dce9ee3f0)
     ARRAY(0x561dce806518)
     ARRAY(0x561dce77d808)
     ARRAY(0x561dceabfcf8)
     ARRAY(0x561dceab4738)
     ARRAY(0x561dce76c620)
     ARRAY(0x561dcead5a50)
     ARRAY(0x561dc651d420)
     ARRAY(0x561dceacf890)
     ARRAY(0x561dc5204c98)
     ARRAY(0x561dcea89db8)
     ARRAY(0x561dce81dc88)
     ARRAY(0x561dceaafc48)
     ARRAY(0x561dce7770c0)
     ARRAY(0x561dce292ca8)
     ARRAY(0x561dc65018b8)
     ARRAY(0x561dce4a7b10)
     ARRAY(0x561dce77dcd0)
     ARRAY(0x561dce81a910)
     ARRAY(0x561dceadb8a0)
     ARRAY(0x561dcecfd308)
     ARRAY(0x561dcead1ff0)
     ARRAY(0x561dceade528)
     ARRAY(0x561dce996ef0)
     ARRAY(0x561dce930970)
     ARRAY(0x561dc48bcea0)
     ARRAY(0x561dcca2b6d8)
Attributes:
   DbLogExclude .*
   DbLogInclude state
   autocreate simple
   clientId   Fhem
   icon       mqtt_broker
   ignoreRegexp Fhem/state/.*:|zigbee2mqtt/[A-Za-z0-9._]+/set:|TestFhem.*:
   keepaliveTimeout 0
   lwt        Fhem/state/client/online 0
   lwtRetain  1
   mqttVersion 3.1.1
   msgAfterConnect -r Fhem/state/client/online 1
   qosMaxQueueLength 50
   room       91_WebDevices,92_Interfaces,MQTT
   username   Fhem
   verbose    1

Vielen Dank
Titel: Antw:MQTT2_CLIENT internal BUF füllt sich, ist das normal?
Beitrag von: rudolfkoenig am 26 Dezember 2020, 12:22:26
Um die Frage im Betreff zu beantworten: nein.

Wenn die list Ausgabe hier richtig eingefuegt wurde, und ich beim Copy&Paste keinen Fehler gemacht habe, dann wartet das Modul auf ca. 23MB an Daten: die Laenge ist in diesem Fall in den Bytes 2 bis 5 kodiert (wenn man mit 1 anfaengt zu zaehlen).

Vermutlich ist dein mosquitto und FHEM nicht einig darueber, wie man QOS=1 bestaetigt (@ ist PUBACK, sollte aber laut RFC von 2 gefolgt sein und nicht von 239,191,189,48). Falls es reproduzierbar ist, dann bitte beschreiben, ich werde es versuchen zu fixen.
Ich hatte gerade mit meinem mosquitto, MQTT2_CLIENT und aktivierten QOS=1 keine Probleme.

Als Workaround empfehle ich QOS zu deaktivieren, ist mAn bei TCP/IP sowieso sinnfrei.
Titel: [gelöst] MQTT2_CLIENT internal BUF füllt sich, ist das normal?
Beitrag von: hubiuwe am 26 Dezember 2020, 12:23:48
Hallo
Ich denke, ich habe es gefunden ;D.

Mit Verbose 5 und Wireshark bin ich dann zum Ziel gekommen.

MQTT_GENERIC_BRIDGE publisht auf Fhem/state.
Mosquitto sendet das PUBLISH unter der gleichen Topic zurük.

Nun dachte ich,daß das Attribut ignoreRegexp "Fhem/set/.*:" reicht um dieses eingehende Topic zu filtern. ..Denkfehler.

Das Attribut "subscriptions" war die Lösung. Ich ging davon aus daß die Subscriptionen automatisch gesetzt werden.
Titel: Antw:MQTT2_CLIENT internal BUF füllt sich, ist das normal?
Beitrag von: rudolfkoenig am 26 Dezember 2020, 12:31:46
Das mag eine andere Art von Workaround zu sein, loest das eigentliche Problem aber nicht.

In meinem Test war subscriptions auch nicht gesetzt (und damit # bzw. "alles"), mosquitto hat brav alle meine meine "set m2c publish topic message" Nachrichten zurueckgeschickt, das hat aber trotz QOS keine Probleme verursacht.
Titel: Antw:MQTT2_CLIENT internal BUF füllt sich, ist das normal?
Beitrag von: hubiuwe am 26 Dezember 2020, 12:34:18
Danke für die Antwort.
Da hat sich was übeschnitten.

Das Attribut "qosMaxQueueLength" hatte ich auch aktiv. Dadurch wurde vermutlich QOS 1 verwendet.
Dises Attribut hatte bei v.g. Lösung übrigrns auch gelöscht und Fhem neu gestartet.

Jetzt sieht es gut aus und es wird nur noch QOS=0 benutzt.

Ich kann es auf meinem Testsystem mal näher anschauen.
Danke
Titel: Antw:MQTT2_CLIENT internal BUF füllt sich, ist das normal?
Beitrag von: hubiuwe am 26 Dezember 2020, 13:43:04
Hallo!
Ich habe das Attribut "ignoreRegexp" wieder gelöscht, Im MQTT_CLIENT verbose 5 gesetzt und neu gestartet.
Das Attribut "qosMaxQueueLength" ist auch gelöscht.

Jetz läuft es wieder mit QOS=0, es tauchen auch keine PUBACK Packete mehr auf.
BUF füllt sich aber weiterhin.

Gehe ich recht in der Annahme daß die Bytes im BUF vor den Topics: die Headerflags,Msg länge und Topic länge sind.
Sie decken sich mit den mitgeschnittenen Daten in Wireshark und werden dort so dekodiert.


Ein Log seit Neustart:
2020.12.26 12:43:54.428 0: Server shutdown
2020.12.26 12:43:54.431 5: MQTT_Broker: sending DISCONNECT (224)(0)
2020.12.26 12:43:54.431 5: SW: e000
2020.12.26 12:43:54.439 0: SONOS0: Das Lauschen auf der Schnittstelle wurde beendet. Prozess endet nun auch...
2020.12.26 12:44:09.416 1: telnetPort: Can't open server port at 7072: Address already in use. Exiting.
2020.12.26 12:44:09.562 4: RESIDENTStk rr_Uwe_wakeuptimer1: Wakeuptime recalculation triggered by at-device at_rr_Uwe_wakeuptimer1
2020.12.26 12:44:09.562 4: RESIDENTStk rr_Uwe_wakeuptimer1: wakeupGetBegin source: wakeupDefaultTime
2020.12.26 12:44:09.562 4: RESIDENTStk rr_Uwe_wakeuptimer1: wakeupGetBegin result: 05:15 = 18300 s - 10 m = 05:05:00
2020.12.26 12:44:11.163 1: PERL WARNING: Useless use of concatenation (.) or string in void context at ./FHEM/98_MSwitch.pm line 5427.
2020.12.26 12:44:11.920 4: RESIDENTStk rr_Uwe_wakeuptimer2: Wakeuptime recalculation triggered by at-device at_rr_Uwe_wakeuptimer2
2020.12.26 12:44:11.920 4: RESIDENTStk rr_Uwe_wakeuptimer2: wakeupGetBegin source: wakeupDefaultTime
2020.12.26 12:44:11.920 4: RESIDENTStk rr_Uwe_wakeuptimer2: wakeupGetBegin result: 07:30 = 26460 s - 9 m = 07:21:00
2020.12.26 12:44:12.700 1: PERL WARNING: Subroutine import redefined at FHEM/Meta.pm line 654.
2020.12.26 12:44:12.701 1: PERL WARNING: Subroutine InitMod redefined at FHEM/Meta.pm line 670.
2020.12.26 12:44:12.703 1: PERL WARNING: Subroutine Load redefined at FHEM/Meta.pm line 706.
2020.12.26 12:44:12.704 1: PERL WARNING: Subroutine SetInternals redefined at FHEM/Meta.pm line 878.
2020.12.26 12:44:12.705 1: PERL WARNING: Subroutine Get redefined at FHEM/Meta.pm line 901.
2020.12.26 12:44:12.705 1: PERL WARNING: Subroutine GetModuleSourceOrigin redefined at FHEM/Meta.pm line 919.
2020.12.26 12:44:12.706 1: PERL WARNING: Subroutine GetKeywordDesc redefined at FHEM/Meta.pm line 940.
2020.12.26 12:44:12.706 1: PERL WARNING: Subroutine ModuleIsCore redefined at FHEM/Meta.pm line 956.
2020.12.26 12:44:12.707 1: PERL WARNING: Subroutine ModuleIsInternal redefined at FHEM/Meta.pm line 961.
2020.12.26 12:44:12.707 1: PERL WARNING: Subroutine GetModuleFilepath redefined at FHEM/Meta.pm line 993.
2020.12.26 12:44:12.708 1: PERL WARNING: Subroutine ModuleIsPerlCore redefined at FHEM/Meta.pm line 1049.
2020.12.26 12:44:12.708 1: PERL WARNING: Subroutine __CopyMetaToInternals redefined at FHEM/Meta.pm line 1085.
2020.12.26 12:44:12.709 1: PERL WARNING: Subroutine __PutMetadata redefined at FHEM/Meta.pm line 1096.
2020.12.26 12:44:12.729 1: PERL WARNING: Subroutine __GetMetadata redefined at FHEM/Meta.pm line 1121.
2020.12.26 12:44:12.735 1: PERL WARNING: Subroutine __GenerateKeywordsFromSupportCommunity redefined at FHEM/Meta.pm line 2426.
2020.12.26 12:44:12.736 1: PERL WARNING: Subroutine __GetPackages redefined at FHEM/Meta.pm line 2521.
2020.12.26 12:44:12.739 1: PERL WARNING: Subroutine __GetMaintainerdata redefined at FHEM/Meta.pm line 2556.
2020.12.26 12:44:12.742 1: PERL WARNING: Subroutine __GetSupportForum redefined at FHEM/Meta.pm line 2770.
2020.12.26 12:44:12.746 1: PERL WARNING: Subroutine __GetUpdatedata redefined at FHEM/Meta.pm line 2956.
2020.12.26 12:44:12.747 1: PERL WARNING: Subroutine __SetXVersion redefined at FHEM/Meta.pm line 3206.
2020.12.26 12:44:13.852 1: PERL WARNING: Unescaped left brace in regex is deprecated here (and will be fatal in Perl 5.32), passed through in regex; marked by <-- HERE in m/^(.*)({ <-- HERE .*})(.*)$/ at ./FHEM/10_MQTT_GENERIC_BRIDGE.pm line 1358.
2020.12.26 12:44:16.546 0: Featurelevel: 6
2020.12.26 12:44:16.546 0: Server started with 174 defined entities (fhem.pl:23373/2020-12-17 perl:5.028001 os:linux user:fhem pid:2837633)
2020.12.26 12:44:16.549 4: RESIDENTStk rr_Uwe_wakeuptimer1: Wakeuptime recalculation triggered by at-device at_rr_Uwe_wakeuptimer1
2020.12.26 12:44:16.549 4: RESIDENTStk rr_Uwe_wakeuptimer1: wakeupGetBegin source: nextRun
2020.12.26 12:44:16.549 4: RESIDENTStk rr_Uwe_wakeuptimer1: wakeupGetBegin result: 05:15 = 18300 s - 10 m = 05:05:00
2020.12.26 12:44:16.550 4: RESIDENTStk rr_Uwe_wakeuptimer2: Wakeuptime recalculation triggered by at-device at_rr_Uwe_wakeuptimer2
2020.12.26 12:44:16.550 4: RESIDENTStk rr_Uwe_wakeuptimer2: wakeupGetBegin source: nextRun
2020.12.26 12:44:16.550 4: RESIDENTStk rr_Uwe_wakeuptimer2: wakeupGetBegin result: 07:30 = 26460 s - 9 m = 07:21:00
2020.12.26 12:44:16.551 5: HttpUtils url=http://127.0.0.1:1883/
2020.12.26 12:44:16.551 4: IP: 127.0.0.1 -> 127.0.0.1
2020.12.26 12:44:16.596 1: SONOS0: Kein UPnP-Server gefunden... Starte selber einen und warte 8 Sekunde(n) darauf...
2020.12.26 12:44:16.910 5: MQTT_Broker: sending CONNECT (16)=(0)(4)MQTT(4)(230)(0)(0)(0)(4)Fhem(0)(24)Fhem/state/client/online(0)(1)0(0)(4)Fhem(0)(8)xxxxxxx
2020.12.26 12:44:16.911 5: SW: 103d00044d51545404e6000000044668656d00184668656d2f73746174652f636c69656e742f6f6e6c696e6500013000044668656d00086236373838393635
2020.12.26 12:44:16.912 1: 127.0.0.1:1883 reappeared (MQTT_Broker)
2020.12.26 12:44:16.974 5: MQTT_Broker: received CONNACK (0)(0)
2020.12.26 12:44:16.974 5: MQTT_Broker: sending SUBSCRIBE (130)(6)(0)(21)(0)(1)#(0)
2020.12.26 12:44:17.129 5: MQTT_Broker: received SUBACK (0)(21)(0)
2020.12.26 12:44:17.130 5: MQTT_Broker: sending PUBLISH 1(27)(0)(24)Fhem/state/client/online1
2020.12.26 12:44:17.130 5: SW: 311b00184668656d2f73746174652f636c69656e742f6f6e6c696e6531
2020.12.26 12:44:17.180 5: MQTT_Broker: received PUBLISH (0)(24)Fhem/state/client/online1
2020.12.26 12:44:17.180 1: PERL WARNING: Exiting subroutine via next at ./FHEM/00_MQTT2_CLIENT.pm line 469.
2020.12.26 12:44:17.219 5: MQTT_Broker: received PUBLISH (0)%Fhem/state/Umgebung/Wetter/brightness4493
2020.12.26 12:44:17.661 1: SONOS0: ./FHEM/00_SONOS.pm is started by fhem...
2020.12.26 12:44:17.661 1: SONOS0: ./FHEM/00_SONOS.pm is listening to Port 4711
2020.12.26 12:44:32.480 5: MQTT_Broker: sending PUBLISH 1$(0)(31)Fhem/state/Umgebung/Wetter/wind5.0
2020.12.26 12:44:32.488 5: MQTT_Broker: sending PUBLISH 1'(0)#Fhem/state/Umgebung/Wetter/humidity70
2020.12.26 12:44:32.497 5: MQTT_Broker: sending PUBLISH 1+(0)&Fhem/state/Umgebung/Wetter/temperature3.1
2020.12.26 12:44:32.518 5: MQTT_Broker: received PUBLISH (0)(31)Fhem/state/Umgebung/Wetter/wind3.3
2020.12.26 12:44:32.519 5: MQTT_Broker: received PUBLISH (0)#Fhem/state/Umgebung/Wetter/humidity70
2020.12.26 12:44:45.554 5: MQTT_Broker: sending PUBLISH 1+(0)%Fhem/state/Umgebung/Wetter/brightness4260
2020.12.26 12:44:45.582 5: MQTT_Broker: received PUBLISH (0)&Fhem/state/Umgebung/Wetter/temperature3.2
2020.12.26 12:45:25.754 1: SONOS1: UPnP-Thread gestartet.
2020.12.26 12:45:25.832 1: SONOS2: LongJobs-Thread gestartet. Prüfe auf LongJobs...
2020.12.26 12:45:25.903 1: SONOS3: IsAlive-Thread gestartet. Warte 120 Sekunden und pruefe dann alle 30 Sekunden...
2020.12.26 12:45:25.974 1: SONOS4: Restore-Thread gestartet. Warte auf Arbeit...
2020.12.26 12:46:25.320 5: MQTT_Broker: sending PUBLISH 1+(0)%Fhem/state/Umgebung/Wetter/brightness4143
2020.12.26 12:46:25.358 5: MQTT_Broker: received PUBLISH (0)(24)Fhem/state/client/online1
2020.12.26 12:47:04.826 5: MQTT_Broker: sending PUBLISH 1$(0)(31)Fhem/state/Umgebung/Wetter/wind2.5
2020.12.26 12:47:04.837 5: MQTT_Broker: sending PUBLISH 1'(0)#Fhem/state/Umgebung/Wetter/humidity70
2020.12.26 12:47:04.846 5: MQTT_Broker: sending PUBLISH 1+(0)&Fhem/state/Umgebung/Wetter/temperature3.2
2020.12.26 12:47:04.875 5: MQTT_Broker: received PUBLISH (0)(31)Fhem/state/Umgebung/Wetter/wind5.0
2020.12.26 12:47:04.876 5: MQTT_Broker: received PUBLISH (0)#Fhem/state/Umgebung/Wetter/humidity70
2020.12.26 12:47:55.125 5: MQTT_Broker: sending PUBLISH 1+(0)%Fhem/state/Umgebung/Wetter/brightness4260
2020.12.26 12:47:55.153 5: MQTT_Broker: received PUBLISH (0)&Fhem/state/Umgebung/Wetter/temperature3.1
2020.12.26 12:49:24.925 5: MQTT_Broker: sending PUBLISH 1+(0)%Fhem/state/Umgebung/Wetter/brightness4493
2020.12.26 12:49:24.962 5: MQTT_Broker: received PUBLISH (0)%Fhem/state/Umgebung/Wetter/brightness4260
2020.12.26 12:49:37.327 5: MQTT_Broker: sending PUBLISH 1$(0)(31)Fhem/state/Umgebung/Wetter/wind3.3
2020.12.26 12:49:37.338 5: MQTT_Broker: sending PUBLISH 1'(0)#Fhem/state/Umgebung/Wetter/humidity70
2020.12.26 12:49:37.350 5: MQTT_Broker: sending PUBLISH 1+(0)&Fhem/state/Umgebung/Wetter/temperature3.2
2020.12.26 12:49:37.376 5: MQTT_Broker: received PUBLISH (0)%Fhem/state/Umgebung/Wetter/brightness4143
2020.12.26 12:50:54.728 5: MQTT_Broker: sending PUBLISH 1+(0)%Fhem/state/Umgebung/Wetter/brightness4609
2020.12.26 12:50:54.756 5: MQTT_Broker: received PUBLISH (0)(31)Fhem/state/Umgebung/Wetter/wind2.5
2020.12.26 12:52:09.828 5: MQTT_Broker: sending PUBLISH 1$(0)(31)Fhem/state/Umgebung/Wetter/wind1.9
2020.12.26 12:52:09.837 5: MQTT_Broker: sending PUBLISH 1'(0)#Fhem/state/Umgebung/Wetter/humidity70
2020.12.26 12:52:09.845 5: MQTT_Broker: sending PUBLISH 1+(0)&Fhem/state/Umgebung/Wetter/temperature3.3
2020.12.26 12:52:09.869 5: MQTT_Broker: received PUBLISH (0)#Fhem/state/Umgebung/Wetter/humidity70
2020.12.26 12:52:09.869 5: MQTT_Broker: received PUBLISH (0)&Fhem/state/Umgebung/Wetter/temperature3.2
2020.12.26 12:52:24.513 5: MQTT_Broker: sending PUBLISH 1+(0)%Fhem/state/Umgebung/Wetter/brightness4493
2020.12.26 12:52:24.557 5: MQTT_Broker: received PUBLISH (0)%Fhem/state/Umgebung/Wetter/brightness4260
2020.12.26 12:53:54.316 5: MQTT_Broker: sending PUBLISH 1+(0)%Fhem/state/Umgebung/Wetter/brightness4027
2020.12.26 12:53:54.346 5: MQTT_Broker: received PUBLISH (0)%Fhem/state/Umgebung/Wetter/brightness4493
2020.12.26 12:55:24.111 5: MQTT_Broker: sending PUBLISH 1+(0)%Fhem/state/Umgebung/Wetter/brightness3677
2020.12.26 12:55:24.225 5: MQTT_Broker: received PUBLISH (0)(31)Fhem/state/Umgebung/Wetter/wind3.3
2020.12.26 12:56:53.900 5: MQTT_Broker: sending PUBLISH 1+(0)%Fhem/state/Umgebung/Wetter/brightness3561
2020.12.26 12:56:53.984 5: MQTT_Broker: received PUBLISH (0)#Fhem/state/Umgebung/Wetter/humidity70
2020.12.26 12:58:33.669 5: MQTT_Broker: sending PUBLISH 1+(0)%Fhem/state/Umgebung/Wetter/brightness3561
2020.12.26 12:58:33.742 5: MQTT_Broker: received PUBLISH (0)&Fhem/state/Umgebung/Wetter/temperature3.2
2020.12.26 12:59:47.335 5: MQTT_Broker: sending PUBLISH 1$(0)(31)Fhem/state/Umgebung/Wetter/wind4.7
2020.12.26 12:59:47.344 5: MQTT_Broker: sending PUBLISH 1'(0)#Fhem/state/Umgebung/Wetter/humidity70
2020.12.26 12:59:47.352 5: MQTT_Broker: sending PUBLISH 1+(0)&Fhem/state/Umgebung/Wetter/temperature3.3
2020.12.26 12:59:47.373 5: MQTT_Broker: received PUBLISH (0)%Fhem/state/Umgebung/Wetter/brightness4609
2020.12.26 13:00:03.455 5: MQTT_Broker: sending PUBLISH 1+(0)%Fhem/state/Umgebung/Wetter/brightness3677
2020.12.26 13:00:03.537 5: MQTT_Broker: received PUBLISH (0)(31)Fhem/state/Umgebung/Wetter/wind1.9
2020.12.26 13:01:33.238 5: MQTT_Broker: sending PUBLISH 1+(0)%Fhem/state/Umgebung/Wetter/brightness3794
2020.12.26 13:01:33.311 5: MQTT_Broker: received PUBLISH (0)#Fhem/state/Umgebung/Wetter/humidity70
2020.12.26 13:02:19.835 5: MQTT_Broker: sending PUBLISH 1$(0)(31)Fhem/state/Umgebung/Wetter/wind3.6
2020.12.26 13:02:19.844 5: MQTT_Broker: sending PUBLISH 1'(0)#Fhem/state/Umgebung/Wetter/humidity70
2020.12.26 13:02:19.852 5: MQTT_Broker: sending PUBLISH 1+(0)&Fhem/state/Umgebung/Wetter/temperature3.4
2020.12.26 13:02:19.881 5: MQTT_Broker: received PUBLISH (0)&Fhem/state/Umgebung/Wetter/temperature3.3
2020.12.26 13:03:12.991 5: MQTT_Broker: sending PUBLISH 1+(0)%Fhem/state/Umgebung/Wetter/brightness3677
2020.12.26 13:03:13.064 5: MQTT_Broker: received PUBLISH (0)%Fhem/state/Umgebung/Wetter/brightness4493
2020.12.26 13:04:42.775 5: MQTT_Broker: sending PUBLISH 1+(0)%Fhem/state/Umgebung/Wetter/brightness3677
2020.12.26 13:04:42.848 5: MQTT_Broker: received PUBLISH (0)%Fhem/state/Umgebung/Wetter/brightness4027
2020.12.26 13:04:52.465 5: MQTT_Broker: sending PUBLISH 1$(0)(31)Fhem/state/Umgebung/Wetter/wind4.7
2020.12.26 13:04:52.475 5: MQTT_Broker: sending PUBLISH 1'(0)#Fhem/state/Umgebung/Wetter/humidity70
2020.12.26 13:04:52.483 5: MQTT_Broker: sending PUBLISH 1+(0)&Fhem/state/Umgebung/Wetter/temperature3.4
2020.12.26 13:04:52.505 5: MQTT_Broker: received PUBLISH (0)%Fhem/state/Umgebung/Wetter/brightness3677
2020.12.26 13:04:52.506 5: MQTT_Broker: received PUBLISH (0)%Fhem/state/Umgebung/Wetter/brightness3561
2020.12.26 13:06:22.544 5: MQTT_Broker: sending PUBLISH 1+(0)%Fhem/state/Umgebung/Wetter/brightness3794
2020.12.26 13:06:22.617 5: MQTT_Broker: received PUBLISH (0)%Fhem/state/Umgebung/Wetter/brightness3561
2020.12.26 13:08:02.326 5: MQTT_Broker: sending PUBLISH 1+(0)%Fhem/state/Umgebung/Wetter/brightness3794
2020.12.26 13:08:02.406 5: MQTT_Broker: received PUBLISH (0)(31)Fhem/state/Umgebung/Wetter/wind4.7
2020.12.26 13:09:32.127 5: MQTT_Broker: sending PUBLISH 1+(0)%Fhem/state/Umgebung/Wetter/brightness3794
2020.12.26 13:09:32.199 5: MQTT_Broker: received PUBLISH (0)#Fhem/state/Umgebung/Wetter/humidity70
2020.12.26 13:11:11.911 5: MQTT_Broker: sending PUBLISH 1+(0)%Fhem/state/Umgebung/Wetter/brightness3910
2020.12.26 13:11:11.986 5: MQTT_Broker: received PUBLISH (0)&Fhem/state/Umgebung/Wetter/temperature3.3
2020.12.26 13:12:29.844 5: MQTT_Broker: sending PUBLISH 1$(0)(31)Fhem/state/Umgebung/Wetter/wind1.6
2020.12.26 13:12:29.852 5: MQTT_Broker: sending PUBLISH 1'(0)#Fhem/state/Umgebung/Wetter/humidity70
2020.12.26 13:12:29.861 5: MQTT_Broker: sending PUBLISH 1+(0)&Fhem/state/Umgebung/Wetter/temperature3.5
2020.12.26 13:12:29.882 5: MQTT_Broker: received PUBLISH (0)%Fhem/state/Umgebung/Wetter/brightness3677
2020.12.26 13:12:29.882 5: MQTT_Broker: received PUBLISH (0)%Fhem/state/Umgebung/Wetter/brightness3794
2020.12.26 13:12:41.712 5: MQTT_Broker: sending PUBLISH 1+(0)%Fhem/state/Umgebung/Wetter/brightness4143
2020.12.26 13:12:41.786 5: MQTT_Broker: received PUBLISH (0)(31)Fhem/state/Umgebung/Wetter/wind3.6
2020.12.26 13:14:21.467 5: MQTT_Broker: sending PUBLISH 1+(0)%Fhem/state/Umgebung/Wetter/brightness4027
2020.12.26 13:14:21.543 5: MQTT_Broker: received PUBLISH (0)#Fhem/state/Umgebung/Wetter/humidity70
2020.12.26 13:15:51.260 5: MQTT_Broker: sending PUBLISH 1+(0)%Fhem/state/Umgebung/Wetter/brightness4027
2020.12.26 13:15:51.360 5: MQTT_Broker: received PUBLISH (0)&Fhem/state/Umgebung/Wetter/temperature3.4
2020.12.26 13:17:31.060 5: MQTT_Broker: sending PUBLISH 1+(0)%Fhem/state/Umgebung/Wetter/brightness4027
2020.12.26 13:17:31.134 5: MQTT_Broker: received PUBLISH (0)%Fhem/state/Umgebung/Wetter/brightness3677
2020.12.26 13:17:34.847 5: MQTT_Broker: sending PUBLISH 1$(0)(31)Fhem/state/Umgebung/Wetter/wind4.4
2020.12.26 13:17:34.856 5: MQTT_Broker: sending PUBLISH 1'(0)#Fhem/state/Umgebung/Wetter/humidity69
2020.12.26 13:17:34.864 5: MQTT_Broker: sending PUBLISH 1+(0)&Fhem/state/Umgebung/Wetter/temperature3.5
2020.12.26 13:17:34.892 5: MQTT_Broker: received PUBLISH (0)%Fhem/state/Umgebung/Wetter/brightness3677
2020.12.26 13:17:34.892 5: MQTT_Broker: received PUBLISH (0)(31)Fhem/state/Umgebung/Wetter/wind4.7
2020.12.26 13:19:10.847 5: MQTT_Broker: sending PUBLISH 1+(0)%Fhem/state/Umgebung/Wetter/brightness3794
2020.12.26 13:19:10.929 5: MQTT_Broker: received PUBLISH (0)#Fhem/state/Umgebung/Wetter/humidity70
2020.12.26 13:20:07.348 5: MQTT_Broker: sending PUBLISH 1$(0)(31)Fhem/state/Umgebung/Wetter/wind5.0
2020.12.26 13:20:07.360 5: MQTT_Broker: sending PUBLISH 1'(0)#Fhem/state/Umgebung/Wetter/humidity69
2020.12.26 13:20:07.371 5: MQTT_Broker: sending PUBLISH 1+(0)&Fhem/state/Umgebung/Wetter/temperature3.5
2020.12.26 13:20:07.398 5: MQTT_Broker: received PUBLISH (0)&Fhem/state/Umgebung/Wetter/temperature3.4
2020.12.26 13:20:07.398 5: MQTT_Broker: received PUBLISH (0)%Fhem/state/Umgebung/Wetter/brightness3794
2020.12.26 13:20:10.721 5: MQTT_Broker: sending PUBLISH 1+(0)%Fhem/state/Umgebung/Wetter/brightness3212
2020.12.26 13:20:10.794 5: MQTT_Broker: received PUBLISH (0)%Fhem/state/Umgebung/Wetter/brightness3794
2020.12.26 13:21:20.573 5: MQTT_Broker: sending PUBLISH 1+(0)%Fhem/state/Umgebung/Wetter/brightness2629
2020.12.26 13:21:20.646 5: MQTT_Broker: received PUBLISH (0)%Fhem/state/Umgebung/Wetter/brightness3794
2020.12.26 13:22:39.851 5: MQTT_Broker: sending PUBLISH 1$(0)(31)Fhem/state/Umgebung/Wetter/wind4.7
2020.12.26 13:22:39.859 5: MQTT_Broker: sending PUBLISH 1'(0)#Fhem/state/Umgebung/Wetter/humidity69
2020.12.26 13:22:39.867 5: MQTT_Broker: sending PUBLISH 1+(0)&Fhem/state/Umgebung/Wetter/temperature3.5
2020.12.26 13:22:39.888 5: MQTT_Broker: received PUBLISH (0)%Fhem/state/Umgebung/Wetter/brightness3910
2020.12.26 13:22:39.888 5: MQTT_Broker: received PUBLISH (0)(31)Fhem/state/Umgebung/Wetter/wind1.6
2020.12.26 13:23:00.359 5: MQTT_Broker: sending PUBLISH 1+(0)%Fhem/state/Umgebung/Wetter/brightness2629
2020.12.26 13:23:00.432 5: MQTT_Broker: received PUBLISH (0)#Fhem/state/Umgebung/Wetter/humidity70
2020.12.26 13:24:30.142 5: MQTT_Broker: sending PUBLISH 1+(0)%Fhem/state/Umgebung/Wetter/brightness2746
2020.12.26 13:24:30.216 5: MQTT_Broker: received PUBLISH (0)&Fhem/state/Umgebung/Wetter/temperature3.5
2020.12.26 13:26:09.924 5: MQTT_Broker: sending PUBLISH 1+(0)%Fhem/state/Umgebung/Wetter/brightness2979
2020.12.26 13:26:09.998 5: MQTT_Broker: received PUBLISH (0)%Fhem/state/Umgebung/Wetter/brightness4143


Das ist mir ins Auge gestochen:
Unescaped left brace in regex is deprecated here (and will be fatal in Perl 5.32), passed through in regex; marked by <-- HERE in m/^(.*)({ <-- HERE .*})(.*)$/ at ./FHEM/10_MQTT_GENERIC_BRIDGE.pm line 1358.
2020.12.26 12:44:17.180 1: PERL WARNING: Exiting subroutine via next at ./FHEM/00_MQTT2_CLIENT.pm line 469.


Und das List dazu:
Internals:
   BUF        0$Fhem/state/Umgebung/Wetter/wind4.40'#Fhem/state/Umgebung/Wetter/humidity690+&Fhem/state/Umgebung/Wetter/temperature3.50+%Fhem/state/Umgebung/Wetter/brightness37940$Fhem/state/Umgebung/Wetter/wind5.00'#Fhem/state/Umgebung/Wetter/humidity690+&Fhem/state/Umgebung/Wetter/temperature3.50+%Fhem/state/Umgebung/Wetter/brightness32120+%Fhem/state/Umgebung/Wetter/brightness26290$Fhem/state/Umgebung/Wetter/wind4.70'#Fhem/state/Umgebung/Wetter/humidity690+&Fhem/state/Umgebung/Wetter/temperature3.50+%Fhem/state/Umgebung/Wetter/brightness26290+%Fhem/state/Umgebung/Wetter/brightness27460+%Fhem/state/Umgebung/Wetter/brightness29790+%Fhem/state/Umgebung/Wetter/brightness29790$Fhem/state/Umgebung/Wetter/wind2.50'#Fhem/state/Umgebung/Wetter/humidity700+&Fhem/state/Umgebung/Wetter/temperature3.6
   DEF        127.0.0.1:1883
   DeviceName 127.0.0.1:1883
   FD         21
   FUUID      5fdfa506-f33f-e2e1-0374-8885415b4562c139
   NAME       MQTT_Broker
   NR         170
   PARTIAL   
   STATE      opened
   TYPE       MQTT2_CLIENT
   WBCallback
   clientId   Fhem
   lastMsgTime 1608985664.89318
   nextOpenDelay 5
   Helper:
     DBLOG:
       state:
         DBLogging:
           TIME       1608983056.9254
           VALUE      CONNECTED
   READINGS:
     2020-12-26 08:50:30   lastPublish     Test1Fhem/hallo1:3
     2020-12-26 12:44:16   state           opened
Attributes:
   DbLogExclude .*
   DbLogInclude state
   autocreate simple
   clientId   Fhem
   icon       mqtt_broker
   ignoreRegexp Fhem/state/.*:|zigbee2mqtt/[A-Za-z0-9._]+/set:|TestFhem.*:
   keepaliveTimeout 0
   lwt        Fhem/state/client/online 0
   lwtRetain  1
   mqttVersion 3.1.1
   msgAfterConnect -r Fhem/state/client/online 1
   room       91_WebDevices,92_Interfaces,MQTT
   username   Fhem
   verbose    5
Titel: Antw:MQTT2_CLIENT internal BUF füllt sich, ist das normal?
Beitrag von: rudolfkoenig am 26 Dezember 2020, 14:06:23
ZitatGehe ich recht in der Annahme daß die Bytes im BUF vor den Topics: die Headerflags,Msg länge und Topic länge sind.
http://docs.oasis-open.org/mqtt/mqtt/v3.1.1/os/mqtt-v3.1.1-os.html

ZitatBUF füllt sich aber weiterhin.
Falls "received" Nachrichten zu sehen sind, dann wird BUF (von vorne) abgebaut

Zitat2020.12.26 12:44:17.180 1: PERL WARNING: Exiting subroutine via next at ./FHEM/00_MQTT2_CLIENT.pm line 469.
Danke fuer den Hinweis: das koennte(!) beim gesetzten ignoreRegexp zu den gezeigten Symptomen fuehren.
Habs gefixt und eingecheckt, FHEM update liefert es erst morgen ab 8 aus.
Titel: [gelöst] MQTT2_CLIENT internal BUF füllt sich, ist das normal?
Beitrag von: hubiuwe am 28 Dezember 2020, 19:38:54
Hallo
Ich hab das Update seit Gestern laufen.
Die Attribute "ignoreRegexp" und "subscription" habe ich auch wieder gesetzt, läuft alles wieder gut!

Danke und ein schönes neues Jahr.