[gelöst]MQTT2_CLIENT internal BUF füllt sich, ist das normal?

Begonnen von hubiuwe, 26 Dezember 2020, 10:30:11

Vorheriges Thema - Nächstes Thema

hubiuwe

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
Die beste Automatik ist die, die man abschalten kann!

rudolfkoenig

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.

hubiuwe

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.
Die beste Automatik ist die, die man abschalten kann!

rudolfkoenig

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.

hubiuwe

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
Die beste Automatik ist die, die man abschalten kann!

hubiuwe

#5
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
Die beste Automatik ist die, die man abschalten kann!

rudolfkoenig

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.

hubiuwe

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.
Die beste Automatik ist die, die man abschalten kann!