Hallo zusammen,
seit längerer Zeit beobachte ich, dass regelmäßig Readings in meinen Devices auftauchen bei denen entweder der Wert nicht plausibel ist oder das Gerät die Eigenschaft gar nicht unterstützt.
Bisher konnte ich dies nicht richtig eingrenzen, in Verbindung mit der Ansteuerung eines Fibaro FGD212 Dimmer 2 wurde es jetzt aber offensichtlich und nachvollziehbar:
Die Beleuchtung wird bei Bewegungserkennung immer mit dim 60 eingeschaltet. Zum Test wurden nun zuerst bei allen Devices die falschen Readings bereinigt und die dim Vorgabe im Code auf einen Wert (57) gesetzt welcher gleich auffällt.
Nach der ersten Ansteuerung durch FHEM zeigt das entsprechende Device EG_EZ_DI_1 den richtigen Wert (beim zweiten Dimmer wurde die Ansteuerung durch FHEM temporär deaktiviert)
2021-11-29_11:14:00 List .* dim
EG_EZ_DI_1 2021-11-29 11:12:19 57
EG_WZ_DR_1 2021-11-28 20:35:12 60
Log EG_EZ_DI_1
2021-11-29_11:12:19 EG_EZ_DI_1 set_dim 57
2021-11-29_11:12:19 EG_EZ_DI_1 dim 57
2021-11-29_11:12:19 EG_EZ_DI_1 dim: 57
2021-11-29_11:12:20 EG_EZ_DI_1 power: 1.9 W
2021-11-29_11:12:20 EG_EZ_DI_1 dim 57
2021-11-29_11:12:20 EG_EZ_DI_1 reportedState: dim 57
2021-11-29_11:12:26 EG_EZ_DI_1 power: 15.8 W
Nach 1-2 Stunden erscheinen dann nach und nach immer mehr Readings dim 57 bei WakeUp Devices welche überhaupt nicht in Verbindung mit dem Dimmer stehen und auch keine dim Eigenschaft unterstützen. Wartet man mehrere Tage ab besitzen zuletzt ALLE WakeUp Devices dieses Reading. Zur genannten Zeit ist im Log des Devices von dem dieses Reading stammt allerdings keine Antwort mit dim Reading gelistet.
============================================================
2021-11-30_01:00:00 List .* dim
EG_EZ_DI_1 2021-11-30 00:57:15 57 <= korrekt
EG_EZ_HR_1 2021-11-30 00:22:55 57
EG_EZ_MS_1 2021-11-29 13:31:34 57
EG_KB_HR_1 2021-11-29 21:25:34 57
EG_KB_MS_1 2021-11-29 22:27:44 57
EG_KF_MS_1 2021-11-29 18:54:00 57
EG_WZ_DR_1 2021-11-28 20:35:12 60 <= korrekt
EG_WZ_HR_1 2021-11-29 22:51:13 57
OG_FL_TK_1 2021-11-29 19:59:59 57
OG_KB_HR_1 2021-11-29 20:01:18 57
UG_BU_TK_1 2021-11-29 21:19:09 57
============================================================
2021-12-01_20:00:00 List .* dim
EG_BA_MS_1 2021-11-30 12:32:15 57
EG_EZ_DI_1 2021-12-01 18:14:05 57 <= korrekt
EG_EZ_HR_1 2021-12-01 17:53:31 57
EG_EZ_MS_1 2021-12-01 07:45:04 57
EG_FL_MS_1 2021-12-01 03:37:10 57
EG_FL_TK_1 2021-12-01 00:30:23 57
EG_KB_HR_1 2021-12-01 18:59:17 57
EG_KB_MS_1 2021-11-30 23:03:17 57
EG_KF_MS_1 2021-11-29 18:54:00 57
EG_KU_MS_1 2021-12-01 15:22:10 57
EG_SZ_MS_1 2021-11-30 13:49:21 57
EG_WZ_DR_1 2021-11-28 20:35:12 60 <= korrekt
EG_WZ_HR_1 2021-12-01 15:27:51 57
EG_WZ_MS_1 2021-12-01 18:00:27 57
OG_FL_TK_1 2021-12-01 06:46:01 57
OG_KB_HR_1 2021-12-01 10:14:23 57
OG_WZ_HR_1 2021-12-01 05:19:59 57
UG_BU_TK_1 2021-12-01 08:07:22 57
Außer den beiden Dimmern sind dies alles Fibaro-Motionsensoren, Türkontakte und Heizungsregler verschiedener Hersteller.
Aktiviert man auch beim zweiten Dimmer die Ansteuerung über FHEM ergibt sich eine Mischung aus beiden dim Werten.
Folglich wird es bei anderen Readings zu Eigenschaften welche viele Geräte besitzen oft schwer nachvollziehbar, da die Werte von ganz unterschiedlichen Devices stammen können und die Quelle immer wieder wechselt.
An dieser Stelle sollte ich auch anmerken, dass folgende globale Attribute gesetzt sind nachdem es früher immer wieder Probleme mit der Aktualisierung der Ist-Zustände von Aktoren gab:
setReadingOnAck = 1
showSetInState = 1
Ich habe hier den leisen Verdacht, dass in der Queue Antworten zu falschen Devices zugeordnet werden.
Falls jemand ähnliche Beobachtungen gemacht hat, bereits die Ursache oder ein Workaround kennt, bitte Bescheid geben.
Ansonsten wäre ich dankbar für Tipps wie man die Sache noch genauer analysieren könnte.
ZitatIch habe hier den leisen Verdacht, dass in der Queue Antworten zu falschen Devices zugeordnet werden.
Moeglich aber unwahrscheinlich.
Wenn man das mir mit RAW Nachrichten (attr IODev verbose 5) nachweist, dann werde ich das fixen.
Wahrscheinicher ist ein Fehler bei der Funkuebertragung, die bei 19.6+40k Datenrate nur mit einem Byte abgesichert ist.
Es gibt mehrere Moeglichkeiten, die Pruefsumme zu verbessern:
- Sicherstellen, dass fuer die Kommunikation die 100k Datenrate verwendet wird (durch besseren Funkkontakt bzw. modernere Chipsets), hier wird ein 16-bit CRC verwendet.
- durch Setzen des Attributes useCRC16, hier fuegt FHEM bzw. das Geraet zusaetzlich zum "Hardware-Checksum" eine Weitere hinzu. Das Geraet muss das aber unterstuetzen, d.h. classes muss CRC_16_ENCAP enthalten. Beim aktivierten SECURITY wird automatisch ein CRC16 hinzugefuegt.
Vielen Dank für die Hinweise.
Kommunikation mit dem genannten Device sollte bereits mit 100kbps erfolgen:
routeFor_EG_EZ_DI_1
last at 100kbps 2021-12-05 14:11:36
CRC_16_ENCAP wird unterstützt und wurde jetzt testweise aktiviert:
Attributes:
classes ZWAVEPLUS_INFO BASIC VERSION MANUFACTURER_SPECIFIC SWITCH_MULTILEVEL DEVICE_RESET_LOCALLY ASSOCIATION_GRP_INFO ASSOCIATION POWERLEVEL SECURITY
FIRMWARE_UPDATE_MD CRC_16_ENCAP CONFIGURATION SENSOR_MULTILEVEL METER MULTI_CHANNEL_ASSOCIATION MULTI_CHANNEL PROTECTION ALARM SWITCH_ALL
APPLICATION_STATUS MARK SCENE_ACTIVATION
useCRC16 1
Es folgt cleanup und erneuter Test, werde dann versuchen auch gleich die RAW-Nachrichten zu loggen.
Ergebnisse der Tests
Ausgangszustand:
===============================
deletereading .* dim
set EG_EZ_DI_1 dim 55
set EG_WZ_DR_1 dim 56
==============================
List .* dim
EG_EZ_DI_1 2021-12-05 14:42:35 55
EG_WZ_DR_1 2021-12-05 14:43:47 56
==============================
Nach 3 Stunden:
===============================
List .* dim
EG_EZ_DI_1 2021-12-05 14:50:43 55
EG_WZ_DR_1 2021-12-05 14:43:47 56
EG_WZ_HR_1 2021-12-05 17:24:31 56 <= falsch, Wert von EG_WZ_DR_1
===============================
Nach 5 Stunden:
===============================
List .* dim
EG_EZ_DI_1 2021-12-05 20:45:06 55
EG_EZ_MS_1 2021-12-05 19:47:29 55 <= falsch, Wert von EG_EZ_DI_1
EG_WZ_DR_1 2021-12-05 14:43:47 56
EG_WZ_HR_1 2021-12-05 17:24:31 56 <= falsch, Wert von EG_WZ_DR_1
===============================
Auszug RAW Werte zum Event 17:24:31 Uhr
2021.12.05 17:23:36 3: Notify n_Licht_EZ Event: MSaddN
2021.12.05 17:23:36 3: Watchdog Licht_EZ Set: additional 10min (Motion)
2021.12.05 17:23:47 4: ZWDongle_Read ZWAVE1: rcvd 0004001f06310504220023 (request APPLICATION_COMMAND_HANDLER), sending ACK
2021.12.05 17:23:47 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.05 17:23:47 5: ZWAVE1: dispatch 0004001f06310504220023
2021.12.05 17:23:47 4: CMD:APPLICATION_COMMAND_HANDLER ID:1f ARG:06310504220023 CB:00
2021.12.05 17:23:47 4: ZWDongle_Read ZWAVE1: rcvd 0004001f03260363 (request APPLICATION_COMMAND_HANDLER), sending ACK
2021.12.05 17:23:47 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.05 17:23:47 5: ZWAVE1: dispatch 0004001f03260363
2021.12.05 17:23:47 4: CMD:APPLICATION_COMMAND_HANDLER ID:1f ARG:03260363 CB:00
2021.12.05 17:23:49 4: ZWDongle_Read ZWAVE1: rcvd 0004002b0a32022144000000580000 (request APPLICATION_COMMAND_HANDLER), sending ACK
2021.12.05 17:23:49 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.05 17:23:49 5: ZWAVE1: dispatch 0004002b0a32022144000000580000
2021.12.05 17:23:49 4: CMD:APPLICATION_COMMAND_HANDLER ID:2b ARG:0a32022144000000580000 CB:00
2021.12.05 17:23:53 4: ZWDongle_Read ZWAVE1: rcvd 0004001f063105042200a3 (request APPLICATION_COMMAND_HANDLER), sending ACK
2021.12.05 17:23:53 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.05 17:23:53 5: ZWAVE1: dispatch 0004001f063105042200a3
2021.12.05 17:23:53 4: CMD:APPLICATION_COMMAND_HANDLER ID:1f ARG:063105042200a3 CB:00
2021.12.05 17:24:04 4: ZWDongle_Read ZWAVE1: rcvd 0004002703300300 (request APPLICATION_COMMAND_HANDLER), sending ACK
2021.12.05 17:24:04 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.05 17:24:04 5: ZWAVE1: dispatch 0004002703300300
2021.12.05 17:24:04 4: CMD:APPLICATION_COMMAND_HANDLER ID:27 ARG:03300300 CB:00
2021.12.05 17:24:28 4: ZWDongle_Read ZWAVE1: rcvd 0004001b028407 (request APPLICATION_COMMAND_HANDLER), sending ACK
2021.12.05 17:24:28 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.05 17:24:28 5: ZWAVE1: dispatch 0004001b028407
2021.12.05 17:24:28 4: CMD:APPLICATION_COMMAND_HANDLER ID:1b ARG:028407 CB:00
2021.12.05 17:24:29 3: Wake-Up Event EG_WZ_HR_1 (get Setpoint)
2021.12.05 17:24:29 3: ZWave get EG_WZ_HR_1 setpoint 1
2021.12.05 17:24:29 5: ZWDongle_Write 00131b03430201250f (e6845561)
2021.12.05 17:24:29 5: DevIo_SimpleWrite ZWAVE1: 010a00131b03430201250f94
2021.12.05 17:24:29 5: ACK received, WaitForAck=>2 for 010a00131b03430201250f94
2021.12.05 17:24:29 4: ZWDongle_Read ZWAVE1: rcvd 011301 (answer ZW_SEND_DATA), sending ACK
2021.12.05 17:24:29 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.05 17:24:29 5: ZWAVE1: dispatch 011301
2021.12.05 17:24:29 4: ZWDongle_Read ZWAVE1: rcvd 00130f00000b (request ZW_SEND_DATA), sending ACK
2021.12.05 17:24:29 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.05 17:24:29 5: device ack reveived, removing 010a00131b03430201250f94 from dongle sendstack
2021.12.05 17:24:29 5: ZWAVE1: dispatch 00130f00000b
2021.12.05 17:24:29 4: CMD:ZW_SEND_DATA ID:00 ARG:000b CB:0f
2021.12.05 17:24:29 4: ZWAVE1 transmit OK for CB 0f, target EG_WZ_HR_1
2021.12.05 17:24:29 4: ZWDongle_Read ZWAVE1: rcvd 0004001b064303012200d2 (request APPLICATION_COMMAND_HANDLER), sending ACK
2021.12.05 17:24:29 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.05 17:24:29 5: ZWAVE1: dispatch 0004001b064303012200d2
2021.12.05 17:24:29 4: CMD:APPLICATION_COMMAND_HANDLER ID:1b ARG:064303012200d2 CB:00
*2021.12.05 17:24:31 5: ZWDongle_Write 00131b0284082510 (e6845561)
2021.12.05 17:24:31 5: DevIo_SimpleWrite ZWAVE1: 010900131b028408251045
2021.12.05 17:24:31 5: ACK received, WaitForAck=>2 for 010900131b028408251045
2021.12.05 17:24:31 4: ZWDongle_Read ZWAVE1: rcvd 011301 (answer ZW_SEND_DATA), sending ACK
2021.12.05 17:24:31 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.05 17:24:31 5: ZWAVE1: dispatch 011301
2021.12.05 17:24:31 4: ZWDongle_Read ZWAVE1: rcvd 001310000008 (request ZW_SEND_DATA), sending ACK
2021.12.05 17:24:31 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.05 17:24:31 5: device ack reveived, removing 010900131b028408251045 from dongle sendstack
2021.12.05 17:24:31 5: ZWAVE1: dispatch 001310000008
2021.12.05 17:24:31 4: CMD:ZW_SEND_DATA ID:00 ARG:0008 CB:10
2021.12.05 17:24:31 4: ZWAVE1 transmit OK for CB 10, target EG_WZ_HR_1
2021.12.05 17:24:40 4: ZWDongle_Read ZWAVE1: rcvd 00040016063105030a0000 (request APPLICATION_COMMAND_HANDLER), sending ACK
2021.12.05 17:24:40 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.05 17:24:40 5: ZWAVE1: dispatch 00040016063105030a0000
2021.12.05 17:24:40 4: CMD:APPLICATION_COMMAND_HANDLER ID:16 ARG:063105030a0000 CB:00
2021.12.05 17:24:45 4: ZWDongle_Read ZWAVE1: rcvd 0004000e03300300 (request APPLICATION_COMMAND_HANDLER), sending ACK
2021.12.05 17:24:45 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.05 17:24:45 5: ZWAVE1: dispatch 0004000e03300300
2021.12.05 17:24:45 4: CMD:APPLICATION_COMMAND_HANDLER ID:0e ARG:03300300 CB:00
2021.12.05 17:24:47 4: ZWDongle_Read ZWAVE1: rcvd 00040011063105012200c1 (request APPLICATION_COMMAND_HANDLER), sending ACK
2021.12.05 17:24:47 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.05 17:24:47 5: ZWAVE1: dispatch 00040011063105012200c1
2021.12.05 17:24:47 4: CMD:APPLICATION_COMMAND_HANDLER ID:11 ARG:063105012200c1 CB:00
2021.12.05 17:24:48 4: ZWDongle_Read ZWAVE1: rcvd 0004001303300300 (request APPLICATION_COMMAND_HANDLER), sending ACK
2021.12.05 17:24:48 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.05 17:24:48 5: ZWAVE1: dispatch 0004001303300300
2021.12.05 17:24:48 4: CMD:APPLICATION_COMMAND_HANDLER ID:13 ARG:03300300 CB:00
2021.12.05 17:24:49 4: ZWDongle_Read ZWAVE1: rcvd 00040011063105030a0000 (request APPLICATION_COMMAND_HANDLER), sending ACK
2021.12.05 17:24:49 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.05 17:24:49 5: ZWAVE1: dispatch 00040011063105030a0000
2021.12.05 17:24:49 4: CMD:APPLICATION_COMMAND_HANDLER ID:11 ARG:063105030a0000 CB:00
2021.12.05 17:24:51 4: ZWDongle_Read ZWAVE1: rcvd 00040027028407 (request APPLICATION_COMMAND_HANDLER), sending ACK
2021.12.05 17:24:51 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.05 17:24:51 5: ZWAVE1: dispatch 00040027028407
2021.12.05 17:24:51 4: CMD:APPLICATION_COMMAND_HANDLER ID:27 ARG:028407 CB:00
2021.12.05 17:24:53 5: ZWDongle_Write 0013270284082511 (e6845561,28)
2021.12.05 17:24:53 5: DevIo_SimpleWrite ZWAVE1: 0109001327028408251178
2021.12.05 17:24:53 5: ACK received, WaitForAck=>2 for 0109001327028408251178
2021.12.05 17:24:53 4: ZWDongle_Read ZWAVE1: rcvd 011301 (answer ZW_SEND_DATA), sending ACK
2021.12.05 17:24:53 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.05 17:24:53 5: ZWAVE1: dispatch 011301
2021.12.05 17:24:53 4: ZWDongle_Read ZWAVE1: rcvd 001311000002 (request ZW_SEND_DATA), sending ACK
2021.12.05 17:24:53 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.05 17:24:53 5: device ack reveived, removing 0109001327028408251178 from dongle sendstack
2021.12.05 17:24:53 5: ZWAVE1: dispatch 001311000002
2021.12.05 17:24:53 4: CMD:ZW_SEND_DATA ID:00 ARG:0002 CB:11
2021.12.05 17:24:53 4: ZWAVE1 transmit OK for CB 11, target EG_KB_MS_1
2021.12.05 17:24:54 4: ZWDongle_Read ZWAVE1: rcvd 00040027063105012200d5 (request APPLICATION_COMMAND_HANDLER), sending ACK
2021.12.05 17:24:54 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.05 17:24:54 5: ZWAVE1: dispatch 00040027063105012200d5
2021.12.05 17:24:54 4: CMD:APPLICATION_COMMAND_HANDLER ID:27 ARG:063105012200d5 CB:00
2021.12.05 17:24:54 4: ZWDongle_Read ZWAVE1: rcvd 00040027063105012200d5 (request APPLICATION_COMMAND_HANDLER), sending ACK
2021.12.05 17:24:54 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.05 17:24:54 5: ZWAVE1: dispatch 00040027063105012200d5
2021.12.05 17:24:54 4: CMD:APPLICATION_COMMAND_HANDLER ID:27 ARG:063105012200d5 CB:00
2021.12.05 17:25:30 4: ZWDongle_Read ZWAVE1: rcvd 0004001c028407 (request APPLICATION_COMMAND_HANDLER), sending ACK
Auszug RAW Werte zum Event 19:47:29 Uhr
2021.12.05 19:46:49 4: CMD:APPLICATION_COMMAND_HANDLER ID:2e ARG:063105012200b5 CB:00
2021.12.05 19:47:14 4: ZWDongle_Read ZWAVE1: rcvd 0004001c028407 (request APPLICATION_COMMAND_HANDLER), sending ACK
2021.12.05 19:47:14 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.05 19:47:14 5: ZWAVE1: dispatch 0004001c028407
2021.12.05 19:47:14 4: CMD:APPLICATION_COMMAND_HANDLER ID:1c ARG:028407 CB:00
2021.12.05 19:47:14 5: ZWDongle_Write 00131c0231042506 (e6845561,08)
2021.12.05 19:47:14 5: DevIo_SimpleWrite ZWAVE1: 010900131c0231042506ed
2021.12.05 19:47:14 5: ACK received, WaitForAck=>2 for 010900131c0231042506ed
2021.12.05 19:47:14 4: ZWDongle_Read ZWAVE1: rcvd 011301 (answer ZW_SEND_DATA), sending ACK
2021.12.05 19:47:14 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.05 19:47:14 5: ZWAVE1: dispatch 011301
2021.12.05 19:47:14 4: ZWDongle_Read ZWAVE1: rcvd 00130600000b (request ZW_SEND_DATA), sending ACK
2021.12.05 19:47:14 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.05 19:47:14 5: device ack reveived, removing 010900131c0231042506ed from dongle sendstack
2021.12.05 19:47:14 5: ZWAVE1: dispatch 00130600000b
2021.12.05 19:47:14 4: CMD:ZW_SEND_DATA ID:00 ARG:000b CB:06
2021.12.05 19:47:14 4: ZWAVE1 transmit OK for CB 06, target EG_EZ_HR_1
2021.12.05 19:47:14 4: ZWDongle_Read ZWAVE1: rcvd 0004001c063105012200d7 (request APPLICATION_COMMAND_HANDLER), sending ACK
2021.12.05 19:47:14 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.05 19:47:14 5: ZWAVE1: dispatch 0004001c063105012200d7
2021.12.05 19:47:14 4: CMD:APPLICATION_COMMAND_HANDLER ID:1c ARG:063105012200d7 CB:00
2021.12.05 19:47:16 5: ZWDongle_Write 00131c028408250b (e6845561,08)
2021.12.05 19:47:16 5: DevIo_SimpleWrite ZWAVE1: 010900131c028408250b59
2021.12.05 19:47:16 5: ACK received, WaitForAck=>2 for 010900131c028408250b59
2021.12.05 19:47:16 4: ZWDongle_Read ZWAVE1: rcvd 011301 (answer ZW_SEND_DATA), sending ACK
2021.12.05 19:47:16 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.05 19:47:16 5: ZWAVE1: dispatch 011301
2021.12.05 19:47:16 4: ZWDongle_Read ZWAVE1: rcvd 00130b000005 (request ZW_SEND_DATA), sending ACK
2021.12.05 19:47:16 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.05 19:47:16 5: device ack reveived, removing 010900131c028408250b59 from dongle sendstack
2021.12.05 19:47:16 5: ZWAVE1: dispatch 00130b000005
2021.12.05 19:47:16 4: CMD:ZW_SEND_DATA ID:00 ARG:0005 CB:0b
2021.12.05 19:47:16 4: ZWAVE1 transmit OK for CB 0b, target EG_EZ_HR_1
2021.12.05 19:47:18 4: ZWDongle_Read ZWAVE1: rcvd 0004002703300300 (request APPLICATION_COMMAND_HANDLER), sending ACK
2021.12.05 19:47:18 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.05 19:47:18 5: ZWAVE1: dispatch 0004002703300300
2021.12.05 19:47:18 4: CMD:APPLICATION_COMMAND_HANDLER ID:27 ARG:03300300 CB:00
2021.12.05 19:47:27 4: ZWDongle_Read ZWAVE1: rcvd 0004000e028407 (request APPLICATION_COMMAND_HANDLER), sending ACK
2021.12.05 19:47:27 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.05 19:47:27 5: ZWAVE1: dispatch 0004000e028407
2021.12.05 19:47:27 4: CMD:APPLICATION_COMMAND_HANDLER ID:0e ARG:028407 CB:00
2021.12.05 19:47:29 5: ZWDongle_Write 00130e028408250c (e6845561)
2021.12.05 19:47:29 5: DevIo_SimpleWrite ZWAVE1: 010900130e028408250c4c
2021.12.05 19:47:29 5: ACK received, WaitForAck=>2 for 010900130e028408250c4c
2021.12.05 19:47:29 4: ZWDongle_Read ZWAVE1: rcvd 011301 (answer ZW_SEND_DATA), sending ACK
2021.12.05 19:47:29 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.05 19:47:29 5: ZWAVE1: dispatch 011301
2021.12.05 19:47:29 4: ZWDongle_Read ZWAVE1: rcvd 00130c000002 (request ZW_SEND_DATA), sending ACK
2021.12.05 19:47:29 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.05 19:47:29 5: device ack reveived, removing 010900130e028408250c4c from dongle sendstack
2021.12.05 19:47:29 5: ZWAVE1: dispatch 00130c000002
2021.12.05 19:47:29 4: CMD:ZW_SEND_DATA ID:00 ARG:0002 CB:0c
2021.12.05 19:47:29 4: ZWAVE1 transmit OK for CB 0c, target EG_EZ_MS_1
Soweit ich sehe, kann dim nur per SWITCH_MULTILEVEL (Hex 26) erzeugt werden.
Im 17-er Log finde ich nur einen Eintrag dafuer (0004001f03260363): das Geraet mit hexId 1f meldet den Wert Hex 63, dezimal 99.
Im 19-er Log finde ich gar kein 26.
Vermutlich uebersehe ich was.
ZitatSoweit ich sehe, kann dim nur per SWITCH_MULTILEVEL (Hex 26) erzeugt werden.
Sehe ich auch so.
ZitatVermutlich uebersehe ich was.
Nein, es kommt auch keine Nachricht über das IOdev (weder vom Dimmer selbst, noch von einem anderen Device mit fehlerhafter Übertragung.
Auch keine Nachricht welche das dim Reading Dec 58 also Hex 3a enthält.
Folglich muss der Wert aus anderen Gründen intern in FHEM einem der Wakeup-Devices zugeordnet werden nachdem ein Send/Receive-Vorgang mit völlig anderer Nachricht erfolgt.
Evtl. noch irgendwelche alten Relikte im Stack ?
Die einzige Node welche dim 58 senden kann ist EG_EZ_DI_1 mit Node-ID Hex 08.
Mittlerweile haben aber wieder so gut wie alle Wakeup-Devices dieses dim Reading:
List .* dim
Device-Name ID(HEX) Date/Time dim Comment
====================================================================================================
EG_BA_MS_1 1a 2021-12-06 00:13:10 58 wrong assignment (Motion sensor)
EG_EZ_DI_1 08 2021-12-06 22:23:08 58 OK (Dimmer) =set value
EG_EZ_HR_1 1c 2021-12-06 13:08:15 58 wrong assignment (Thermostat)
EG_EZ_MS_1 0e 2021-12-06 07:51:23 58 wrong assignment (Motion sensor)
EG_FL_MS_1 13 2021-12-06 18:53:17 58 wrong assignment (Motion sensor)
EG_FL_TK_1 19 2021-12-06 22:36:28 58 wrong assignment (Door Sensor)
EG_KB_HR_1 21 2021-12-06 06:47:17 58 wrong assignment (Thermostat)
EG_KB_MS_1 27 2021-12-06 14:28:57 58 wrong assignment (Motion sensor)
EG_KU_MS_1 11 2021-12-06 05:08:31 58 wrong assignment (Motion sensor)
EG_SZ_MS_1 17 2021-12-06 19:23:28 58 wrong assignment (Motion sensor)
EG_WZ_DR_1 1f 2021-12-05 14:43:47 56 OK (Dimmer) =set value
EG_WZ_HR_1 1b 2021-12-06 21:50:01 58 wrong assignment (Thermostat)
OG_FL_TK_1 2e 2021-12-06 20:13:54 58 wrong assignment (Door Sensor)
OG_KB_HR_1 2f 2021-12-06 08:48:58 58 wrong assignment (Thermostat)
OG_WZ_HR_1 30 2021-12-06 05:36:17 58 wrong assignment (Thermostat)
UG_BU_TK_1 2d 2021-12-06 01:36:09 58 wrong assignment (Door Sensor)
UG_FL_TK_1 25 2021-12-06 11:39:58 58 wrong assignment (Door Sensor)Am Beispiel von EG_WZ_HR_1, Thermostatventil, Node-ID Hex 1b um 21:50:01
2021.12.06 21:49:59 4: ZWDongle_Read ZWAVE1: rcvd 0004001b028407 (request APPLICATION_COMMAND_HANDLER), sending ACK
2021.12.06 21:49:59 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.06 21:49:59 5: ZWAVE1: dispatch 0004001b028407
2021.12.06 21:49:59 4: CMD:APPLICATION_COMMAND_HANDLER ID:1b ARG:028407 CB:00
2021.12.06 21:49:59 5: ZWDongle_Write 00131b023104253d (e6845561)
2021.12.06 21:49:59 5: DevIo_SimpleWrite ZWAVE1: 010900131b023104253dd1
2021.12.06 21:49:59 3: Wake-Up Event EG_WZ_HR_1 (get ThermostatMode)
2021.12.06 21:49:59 3: ZWave get EG_WZ_HR_1 thermostatMode
2021.12.06 21:49:59 5: ACK received, WaitForAck=>2 for 010900131b023104253dd1
2021.12.06 21:49:59 4: ZWDongle_Read ZWAVE1: rcvd 011301 (answer ZW_SEND_DATA), sending ACK
2021.12.06 21:49:59 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.06 21:49:59 5: ZWAVE1: dispatch 011301
2021.12.06 21:49:59 4: ZWDongle_Read ZWAVE1: rcvd 00133d000002 (request ZW_SEND_DATA), sending ACK
2021.12.06 21:49:59 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.06 21:49:59 5: device ack reveived, removing 010900131b023104253dd1 from dongle sendstack
2021.12.06 21:49:59 5: ZWAVE1: dispatch 00133d000002
2021.12.06 21:49:59 4: CMD:ZW_SEND_DATA ID:00 ARG:0002 CB:3d
2021.12.06 21:49:59 4: ZWAVE1 transmit OK for CB 3d, target EG_WZ_HR_1
2021.12.06 21:49:59 4: ZWDongle_Read ZWAVE1: rcvd 0004001b063105012200d2 (request APPLICATION_COMMAND_HANDLER), sending ACK
2021.12.06 21:49:59 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.06 21:49:59 5: ZWAVE1: dispatch 0004001b063105012200d2
2021.12.06 21:49:59 4: CMD:APPLICATION_COMMAND_HANDLER ID:1b ARG:063105012200d2 CB:00
2021.12.06 21:49:59 5: ZWDongle_Write 00131b0240022543 (e6845561)
2021.12.06 21:49:59 5: DevIo_SimpleWrite ZWAVE1: 010900131b0240022543d8
2021.12.06 21:49:59 5: ACK received, WaitForAck=>2 for 010900131b0240022543d8
2021.12.06 21:49:59 4: ZWDongle_Read ZWAVE1: rcvd 011301 (answer ZW_SEND_DATA), sending ACK
2021.12.06 21:49:59 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.06 21:49:59 5: ZWAVE1: dispatch 011301
2021.12.06 21:49:59 4: ZWDongle_Read ZWAVE1: rcvd 001343000001 (request ZW_SEND_DATA), sending ACK
2021.12.06 21:49:59 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.06 21:49:59 5: device ack reveived, removing 010900131b0240022543d8 from dongle sendstack
2021.12.06 21:49:59 5: ZWAVE1: dispatch 001343000001
2021.12.06 21:49:59 4: CMD:ZW_SEND_DATA ID:00 ARG:0001 CB:43
2021.12.06 21:49:59 4: ZWAVE1 transmit OK for CB 43, target EG_WZ_HR_1
2021.12.06 21:49:59 4: ZWDongle_Read ZWAVE1: rcvd 0004001b03400301 (request APPLICATION_COMMAND_HANDLER), sending ACK
2021.12.06 21:49:59 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.06 21:49:59 5: ZWAVE1: dispatch 0004001b03400301
2021.12.06 21:49:59 4: CMD:APPLICATION_COMMAND_HANDLER ID:1b ARG:03400301 CB:00
2021.12.06 21:50:01 5: ZWDongle_Write 00131b0284082544 (e6845561)
2021.12.06 21:50:01 5: DevIo_SimpleWrite ZWAVE1: 010900131b028408254411
2021.12.06 21:50:01 5: ACK received, WaitForAck=>2 for 010900131b028408254411
2021.12.06 21:50:01 4: ZWDongle_Read ZWAVE1: rcvd 011301 (answer ZW_SEND_DATA), sending ACK
2021.12.06 21:50:01 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.06 21:50:01 5: ZWAVE1: dispatch 011301
2021.12.06 21:50:01 4: ZWDongle_Read ZWAVE1: rcvd 001344000002 (request ZW_SEND_DATA), sending ACK
2021.12.06 21:50:01 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.06 21:50:01 5: device ack reveived, removing 010900131b028408254411 from dongle sendstack
2021.12.06 21:50:01 5: ZWAVE1: dispatch 001344000002
2021.12.06 21:50:01 4: CMD:ZW_SEND_DATA ID:00 ARG:0002 CB:44
2021.12.06 21:50:01 4: ZWAVE1 transmit OK for CB 44, target EG_WZ_HR_1
2021.12.06 21:50:08 4: ZWDongle_Read ZWAVE1: rcvd 0004000e03300300 (request APPLICATION_COMMAND_HANDLER), sending ACKDie Zuordnung erfolgt immer dann, wenn eigentlich ein völlig anderes Reading einem Wakeup-Device zugeordnet werden müsste.
Im Fall oben kommt die Antwort auf ein "get thermostatMode". Dabei wird im gleichen Zuge das dim 58 reading zugeordnet.
Betrachtet man alle Readings eines anderes Wakeup-Devices (z.b. EG_FL_MS_1, ein Motion-Sensor mit Node-ID Hex 13) fällt auf:
Außer dem falschen dim Reading um 18:53:17 taucht für heute auch ein thermostatSetpointSet 17 Reading auf :
READINGS:
2021-12-06 18:53:17 dim 58
2021-12-06 16:53:06 thermostatSetpointSet 17
Damit es noch etwas kurioser wird: 1,5min vor diesem falschen Reading wurde wirklich ein set dim 58 an EG_EZ_DI_1 mit Node-ID Hex 08 abgesetzt (aktiviert durch Motion detection)
Aber warum danach ein Reading auf diesem Device und dann auch noch thermostatSetpointSet 17 ? (17°C Soll kann auch nur zwischen 08:30 und 11:30 gesetzt sein, nicht um 16:53)
Und warum 2 Stunden später das Reading dim 58 ?
2021.12.06 16:51:28 3: Watchdog Licht_EZ Set: initial 5min (Motion)
2021.12.06 16:51:28 3: ZWave set EG_EZ_DI_1 dim 58
2021.12.06 16:51:28 5: ZWDongle_Write 00130807560126013aCF83252e (e6845561)
2021.12.06 16:51:28 5: DevIo_SimpleWrite ZWAVE1: 010e00130807560126013aCF83252ee0
2021.12.06 16:51:28 5: ACK received, WaitForAck=>2 for 010e00130807560126013aCF83252ee0
2021.12.06 16:51:28 4: ZWDongle_Read ZWAVE1: rcvd 011301 (answer ZW_SEND_DATA), sending ACK
2021.12.06 16:51:28 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.06 16:51:28 5: ZWAVE1: dispatch 011301
2021.12.06 16:51:28 4: ZWDongle_Read ZWAVE1: rcvd 00132e000002 (request ZW_SEND_DATA), sending ACK
2021.12.06 16:51:28 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.06 16:51:28 5: device ack reveived, removing 010e00130807560126013aCF83252ee0 from dongle sendstack
2021.12.06 16:51:28 5: ZWAVE1: dispatch 00132e000002
2021.12.06 16:51:28 4: CMD:ZW_SEND_DATA ID:00 ARG:0002 CB:2e
2021.12.06 16:51:28 4: ZWAVE1 transmit OK for CB 2e, target EG_EZ_DI_1
2021.12.06 16:51:28 4: ZWDongle_Read ZWAVE1: rcvd 000400080326033a (request APPLICATION_COMMAND_HANDLER), sending ACK
2021.12.06 16:51:28 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.06 16:51:28 5: ZWAVE1: dispatch 000400080326033a
2021.12.06 16:51:28 4: CMD:APPLICATION_COMMAND_HANDLER ID:08 ARG:0326033a CB:00
2021.12.06 16:51:28 4: ZWDongle_Read ZWAVE1: rcvd 0004000806310504220036 (request APPLICATION_COMMAND_HANDLER), sending ACK
2021.12.06 16:51:28 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.06 16:51:29 5: ZWAVE1: dispatch 0004000806310504220036
2021.12.06 16:51:29 4: CMD:APPLICATION_COMMAND_HANDLER ID:08 ARG:06310504220036 CB:00
2021.12.06 16:51:33 4: ZWDongle_Read ZWAVE1: rcvd 000400080631050422008c (request APPLICATION_COMMAND_HANDLER), sending ACK
2021.12.06 16:51:33 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.06 16:51:33 5: ZWAVE1: dispatch 000400080631050422008c
2021.12.06 16:51:34 4: CMD:APPLICATION_COMMAND_HANDLER ID:08 ARG:0631050422008c CB:00
2021.12.06 16:51:34 3: Notify n_Licht_EZ Event: power: 14.0 W
2021.12.06 16:51:34 4: ZWDongle_Read ZWAVE1: rcvd 0004000d03300300 (request APPLICATION_COMMAND_HANDLER), sending ACK
2021.12.06 16:51:34 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.06 16:51:34 5: ZWAVE1: dispatch 0004000d03300300
2021.12.06 16:51:34 4: CMD:APPLICATION_COMMAND_HANDLER ID:0d ARG:03300300 CB:00
2021.12.06 16:51:34 4: ZWDongle_Read ZWAVE1: rcvd 0004000d033003ff (request APPLICATION_COMMAND_HANDLER), sending ACK
2021.12.06 16:51:34 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.06 16:51:34 5: ZWAVE1: dispatch 0004000d033003ff
2021.12.06 16:51:34 4: CMD:APPLICATION_COMMAND_HANDLER ID:0d ARG:033003ff CB:00
2021.12.06 16:51:35 3: Notify n_Licht_WZ Event: MSaddN
2021.12.06 16:51:35 3: Watchdog Licht_WZ Set: additional 10min (Motion)
2021.12.06 16:51:49 4: ZWDongle_Read ZWAVE1: rcvd 00040011063105012200c3 (request APPLICATION_COMMAND_HANDLER), sending ACK
2021.12.06 16:51:49 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.06 16:51:49 5: ZWAVE1: dispatch 00040011063105012200c3
2021.12.06 16:51:49 4: CMD:APPLICATION_COMMAND_HANDLER ID:11 ARG:063105012200c3 CB:00
2021.12.06 16:51:53 4: ZWDongle_Read ZWAVE1: rcvd 00040011063105030a0000 (request APPLICATION_COMMAND_HANDLER), sending ACK
2021.12.06 16:51:53 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.06 16:51:53 5: ZWAVE1: dispatch 00040011063105030a0000
2021.12.06 16:51:53 4: CMD:APPLICATION_COMMAND_HANDLER ID:11 ARG:063105030a0000 CB:00
2021.12.06 16:52:12 4: ZWDongle_Read ZWAVE1: rcvd 0004000d063105030a0002 (request APPLICATION_COMMAND_HANDLER), sending ACK
2021.12.06 16:52:12 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.06 16:52:12 5: ZWAVE1: dispatch 0004000d063105030a0002
2021.12.06 16:52:12 4: CMD:APPLICATION_COMMAND_HANDLER ID:0d ARG:063105030a0002 CB:00
2021.12.06 16:52:28 4: ZWDongle_Read ZWAVE1: rcvd 0004000e03300300 (request APPLICATION_COMMAND_HANDLER), sending ACK
2021.12.06 16:52:28 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.06 16:52:28 5: ZWAVE1: dispatch 0004000e03300300
2021.12.06 16:52:28 4: CMD:APPLICATION_COMMAND_HANDLER ID:0e ARG:03300300 CB:00
2021.12.06 16:52:29 4: ZWDongle_Read ZWAVE1: rcvd 0004000e033003ff (request APPLICATION_COMMAND_HANDLER), sending ACK
2021.12.06 16:52:29 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.06 16:52:29 5: ZWAVE1: dispatch 0004000e033003ff
2021.12.06 16:52:29 4: CMD:APPLICATION_COMMAND_HANDLER ID:0e ARG:033003ff CB:00
2021.12.06 16:52:29 3: Notify n_Licht_EZ Event: MSaddN
2021.12.06 16:52:29 3: Watchdog Licht_EZ Set: additional 10min (Motion)
2021.12.06 16:53:04 4: ZWDongle_Read ZWAVE1: rcvd 00040013028407 (request APPLICATION_COMMAND_HANDLER), sending ACK
2021.12.06 16:53:04 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.06 16:53:04 5: ZWAVE1: dispatch 00040013028407
2021.12.06 16:53:04 4: CMD:APPLICATION_COMMAND_HANDLER ID:13 ARG:028407 CB:00
2021.12.06 16:53:06 5: ZWDongle_Write 001313028408252f (e6845561)
2021.12.06 16:53:06 5: DevIo_SimpleWrite ZWAVE1: 0109001313028408252f72
2021.12.06 16:53:06 5: ACK received, WaitForAck=>2 for 0109001313028408252f72
2021.12.06 16:53:06 4: ZWDongle_Read ZWAVE1: rcvd 011301 (answer ZW_SEND_DATA), sending ACK
2021.12.06 16:53:06 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.06 16:53:06 5: ZWAVE1: dispatch 011301
2021.12.06 16:53:06 4: ZWDongle_Read ZWAVE1: rcvd 00132f000002 (request ZW_SEND_DATA), sending ACK
2021.12.06 16:53:06 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.06 16:53:06 5: device ack reveived, removing 0109001313028408252f72 from dongle sendstack
2021.12.06 16:53:06 5: ZWAVE1: dispatch 00132f000002
2021.12.06 16:53:06 4: CMD:ZW_SEND_DATA ID:00 ARG:0002 CB:2f
2021.12.06 16:53:06 4: ZWAVE1 transmit OK for CB 2f, target EG_FL_MS_1
2021.12.06 16:53:07 4: ZWDongle_Read ZWAVE1: rcvd 00040013063105012200da (request APPLICATION_COMMAND_HANDLER), sending ACK
2021.12.06 16:53:07 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.06 16:53:07 5: ZWAVE1: dispatch 00040013063105012200da
2021.12.06 16:53:07 4: CMD:APPLICATION_COMMAND_HANDLER ID:13 ARG:063105012200da CB:00
2021.12.06 16:53:11 4: ZWDongle_Read ZWAVE1: rcvd 0004000f06310504220000 (request APPLICATION_COMMAND_HANDLER), sending ACK
ZitatEvtl. noch irgendwelche alten Relikte im Stack ?
Unwahrscheinlich, dafuer muessten wir auch eine Log Meldung mit "CMD:APPLICATION_COMMAND_HANDLER" sehen.
Ich wuerde ein notify fuer die "falschen" Geraete aufsetzen, was ein stacktrace produziert, so in etwa:
define nx notify *_MS_.*:dim.* { Log 1, "STACKTRACE for $NAME";; stacktrace() }
ZitatIch wuerde ein notify fuer die "falschen" Geraete aufsetzen, was ein stacktrace produziert, so in etwa:
Danke für die Unterstützung, genau so eine Möglichkeit suche ich (bin leider einfach nicht tief genug drin).
Werde es sofort ausprobieren - wäre ja gelacht, wenn man der Sache nicht auf die Schliche kommt :)
Werde leider nicht schlau aus der Sache.
Selbes Spiel: alle Readings bereinigt, IOdev Log an, Stacktrace bei dim auf Motion-Sensoren
Ausgangszustand: list .* dim
list .* dim
EG_EZ_DI_1 (ID 08Hex) => dim 57
EZ_WZ_DR_1 (ID 01fHex) => dim 60
Einige Stunden später mit list .* dim
EG_EZ_DI_1 2021-12-08 06:18:56 57
EG_WZ_DR_1 2021-12-07 23:53:32 60
EG_WZ_HR_1 2021-12-08 03:07:42 60
EG_WZ_MS_1 2021-12-08 07:09:24 57
EG_WZ_TK_1 2021-12-08 03:05:02 57
OG_KB_HR_1 2021-12-08 05:24:11 57
Diesmal der Log zu Motion-Sensor EG_WZ_MS_1 (ID 0dHex) zum Event um 07:09:24:
2021.12.08 07:09:22 4: ZWDongle_Read ZWAVE1: rcvd 0004000d028407 (request APPLICATION_COMMAND_HANDLER), sending ACK
2021.12.08 07:09:22 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.08 07:09:22 5: ZWAVE1: dispatch 0004000d028407
2021.12.08 07:09:22 4: CMD:APPLICATION_COMMAND_HANDLER ID:0d ARG:028407 CB:00
2021.12.08 07:09:24 5: ZWDongle_Write 00130d0284082549 (e6845561)
2021.12.08 07:09:24 5: DevIo_SimpleWrite ZWAVE1: 010900130d02840825490a
2021.12.08 07:09:24 5: ACK received, WaitForAck=>2 for 010900130d02840825490a
2021.12.08 07:09:24 4: ZWDongle_Read ZWAVE1: rcvd 011301 (answer ZW_SEND_DATA), sending ACK
2021.12.08 07:09:24 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.08 07:09:24 5: ZWAVE1: dispatch 011301
2021.12.08 07:09:24 4: ZWDongle_Read ZWAVE1: rcvd 001349000002 (request ZW_SEND_DATA), sending ACK
2021.12.08 07:09:24 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.08 07:09:24 5: device ack reveived, removing 010900130d02840825490a from dongle sendstack
2021.12.08 07:09:24 5: ZWAVE1: dispatch 001349000002
2021.12.08 07:09:24 4: CMD:ZW_SEND_DATA ID:00 ARG:0002 CB:49
2021.12.08 07:09:24 4: ZWAVE1 transmit OK for CB 49, target EG_WZ_MS_1
2021.12.08 07:09:24 1: STACKTRACE for EG_WZ_MS_1
2021.12.08 07:09:24 1: stacktrace:
2021.12.08 07:09:24 1: (eval) called by fhem.pl (1160)
2021.12.08 07:09:24 1: main::AnalyzePerlCommand called by fhem.pl (1189)
2021.12.08 07:09:24 1: main::AnalyzeCommand called by fhem.pl (1116)
2021.12.08 07:09:24 1: main::AnalyzeCommandChain called by ./FHEM/91_notify.pm (122)
2021.12.08 07:09:24 1: main::notify_Exec called by fhem.pl (3895)
2021.12.08 07:09:24 1: main::CallFn called by fhem.pl (3812)
2021.12.08 07:09:24 1: main::DoTrigger called by fhem.pl (4192)
2021.12.08 07:09:24 1: main::Dispatch called by ./FHEM/00_ZWDongle.pm (983)
2021.12.08 07:09:24 1: main::ZWDongle_Parse called by ./FHEM/00_ZWDongle.pm (878)
2021.12.08 07:09:24 1: main::ZWDongle_Read called by fhem.pl (3895)
2021.12.08 07:09:24 1: main::CallFn called by fhem.pl (773)
-Device meldet sich mit einem Wakeup-Event
-es liegt eine Nachricht in der Queue
-diese wird erfolgreich gesendet
-danach schlägt das notify für den dim an
Ich meine die Ursache ist setReadingOnAck.
Das wird realisiert, indem man zum callbackId den Befehl merkt, und beim Eintreffen der ack mit diesem callbackid ein Event mit dem Befehl generiert. callbackid ist ein Byte, und wird beim Generieren des Befehls erzeugt, und nicht beim Versenden. Das ist beim Mix von WakeUp Geraeten und vielen Befehlen problematisch.
Ich hoffe das jetzt gefixt zu haben, bitte testen.
Ich hoffe das jetzt gefixt zu haben, bitte testen.
Rückmeldung nach den ersten 20h Betrieb: leider noch keine große Veränderung zu beobachten.
-4 Wakeup-Devices erhalten das "dim" Reading des Dimmers
-7 Wakeup-Devices (Motionsensoren+Türkontakte) erhalten ein "thermostatSetpointSet" Reading von verschiedenen Thermostaten
Kann ich aktuell mit irgendwelchen Logs zur Abnalyse beitragen ?
Soll ich testweise das setReadingOnAck Attribut auf 0 setzen um sicherzugehen, dass das Verhalten bei dieser Kombination ausgelöst wird (im Normalbetrieb bräuchte ich später diese Funktion allerdings wieder dringend, sollte dann zudem wissen, ob der Test mit dem neuen oder mit dem ursprünglichen Code durchgeführt werden soll).
ZitatSoll ich testweise das setReadingOnAck Attribut auf 0 setzen [...]
Das waere hilfreich. Da die Aenderung nur das aktivierte setReadingOnAck betrifft, ist es egal ob es mit dem neuen oder alten Code.
Wenn es ohne Attribut keine Probleme gibt, dann bitte Attribut aktivieren, und in ZWave.pm, Zeile 5237 Folgendes einbauen:
Log 1, "ACK: $lname $msg $callbackid => $ackCmd";
Hier die Ergebnisse der Tests:
Wird setReadingOnAck auf 0 gesetzt tauchen die fehlerhaften Zuordnungen bei den Wake-Up-Geräten wie vermutet nicht auf.
Dafür fehlen aber logischerweise Werte bei Aktoren, welche durch diese Funktion automatisch upgedated werden sollen.
Nach erneuter Aktivierung von setReadingOnAck und zusätzlichem Log in ZWave.pm ergibt sich nach einigen Stunden das bekannte Bild:
list .* dim
=> OK (ist Dimmer):
EG_EZ_DI_1 2021-12-13 06:39:34 57 (OK)
EG_WZ_DR_1 2021-12-09 13:48:08 60 (OK)
====================================================
=> falsch (ist Motion-Sensor / Türkontakt / Thermostat):
EG_BA_MS_1 2021-12-12 23:00:37 57
EG_EZ_HR_1 2021-12-13 02:17:42 57
EG_EZ_MS_1 2021-12-12 10:00:29 57
EG_FL_MS_1 2021-12-12 03:06:54 57
EG_KF_MS_1 2021-12-13 06:28:01 57
EG_KU_MS_1 2021-12-12 04:47:45 57
EG_SZ_MS_1 2021-12-13 05:57:25 57
EG_WZ_HR_1 2021-12-13 04:58:23 57
EG_WZ_MS_1 2021-12-12 01:14:40 57
OG_FL_TK_1 2021-12-12 04:39:55 57
OG_KB_HR_1 2021-12-13 06:55:21 57
UG_FL_TK_1 2021-12-13 02:04:52 57
list .* thermostatSetpointSet
=> OK (ist Thermostat)
EG_BA_HR_2 2021-12-13 05:30:01 25
EG_EZ_HR_1 2021-12-13 05:32:26 21
EG_KB_HR_1 2021-12-12 19:05:54 17
EG_KF_HR_1 2021-12-12 19:33:01 19
EG_WZ_HR_1 2021-12-13 06:35:19 21
OG_KB_HR_1 2021-12-12 19:39:37 17
OG_WZ_HR_1 2021-12-13 06:35:39 22
====================================================
=> falsch (ist Motion-Sensor / Türkontakt):
EG_FL_TK_1 2021-12-13 02:17:58 17
EG_KF_MS_1 2021-12-12 13:31:03 20
EG_SZ_MS_1 2021-12-13 01:58:02 18
EG_WZ_MS_1 2021-12-11 12:47:47 18
OG_FL_TK_1 2021-12-11 12:22:52 20
UG_BU_TK_1 2021-12-12 19:32:58 25
Zudem fällt z.B. auch auf, dass keiner der Motion-Sensoren mehr erfolgreich ein Neighborupdate durchführt:
list .*_MS_1 neighborUpdate
EG_BA_MS_1 2021-12-13 06:01:40 failed
EG_EZ_MS_1 2021-12-13 06:35:46 failed
EG_FL_MS_1 2021-12-13 07:11:00 failed
EG_KB_MS_1 2021-12-13 05:27:32 failed
EG_KF_MS_1 2021-12-13 06:28:22 failed
EG_KU_MS_1 2021-12-13 06:44:06 failed
EG_SZ_MS_1 2021-12-13 06:27:50 failed
EG_WZ_MS_1 2021-12-13 05:38:23 failed
Beispiel Log für thermostatSetpointSet auf UG_BU_TK_1 um 2021-12-12 19:32:58
-der Wert kommt aus der letzten Kommunikation mit EG_WZ_HR_1
-danach kommt ein Wakeup-Event von UG_BU_TK_1
-bei dieser Kommunikation wird dann der thermostatSetpointSet auf den Türkontakt zugeordnet
2021.12.12 19:32:34 5: ACK received, WaitForAck=>2 for 010900131b028408257b2e
2021.12.12 19:32:34 4: ZWDongle_Read ZWAVE1: rcvd 011301 (answer ZW_SEND_DATA), sending ACK
2021.12.12 19:32:34 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.12 19:32:34 5: ZWAVE1: dispatch 011301
2021.12.12 19:32:34 4: ZWDongle_Read ZWAVE1: rcvd 00137b000002 (request ZW_SEND_DATA), sending ACK
2021.12.12 19:32:34 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.12 19:32:34 5: device ack reveived, removing 010900131b028408257b2e from dongle sendstack
2021.12.12 19:32:34 5: ZWAVE1: dispatch 00137b000002
2021.12.12 19:32:34 4: CMD:ZW_SEND_DATA ID:00 ARG:0002 CB:7b
2021.12.12 19:32:34 4: ZWAVE1 transmit OK for CB 7b, target EG_WZ_HR_1
2021.12.12 19:32:34 1: ACK: EG_WZ_HR_1 00137b000002 7b => get thermostatMode
2021.12.12 19:32:55 4: ZWDongle_Read ZWAVE1: rcvd 0004001603300300 (request APPLICATION_COMMAND_HANDLER), sending ACK
2021.12.12 19:32:55 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.12 19:32:55 5: ZWAVE1: dispatch 0004001603300300
2021.12.12 19:32:55 4: CMD:APPLICATION_COMMAND_HANDLER ID:16 ARG:03300300 CB:00
2021.12.12 19:32:56 4: ZWDongle_Read ZWAVE1: rcvd 0004002d06310501220097 (request APPLICATION_COMMAND_HANDLER), sending ACK
2021.12.12 19:32:56 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.12 19:32:56 5: ZWAVE1: dispatch 0004002d06310501220097
2021.12.12 19:32:56 4: CMD:APPLICATION_COMMAND_HANDLER ID:2d ARG:06310501220097 CB:00
2021.12.12 19:32:56 3: Notify n_TK_BU Event: temperature: 15.1 C
2021.12.12 19:32:56 4: ZWDongle_Read ZWAVE1: rcvd 0004002d028407 (request APPLICATION_COMMAND_HANDLER), sending ACK
2021.12.12 19:32:56 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.12 19:32:56 5: ZWAVE1: dispatch 0004002d028407
2021.12.12 19:32:56 4: CMD:APPLICATION_COMMAND_HANDLER ID:2d ARG:028407 CB:00
2021.12.12 19:32:56 3: Notify n_TK_BU Event: wakeup: notification
2021.12.12 19:32:56 4: ZWDongle_Read ZWAVE1: rcvd 0004101603300300 (request APPLICATION_COMMAND_HANDLER), sending ACK
2021.12.12 19:32:56 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.12 19:32:56 5: ZWAVE1: dispatch 0004101603300300
2021.12.12 19:32:56 4: CMD:APPLICATION_COMMAND_HANDLER ID:16 ARG:03300300 CB:10
2021.12.12 19:32:57 4: ZWDongle_Read ZWAVE1: rcvd 0004101603300300 (request APPLICATION_COMMAND_HANDLER), sending ACK
2021.12.12 19:32:57 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.12 19:32:57 5: ZWAVE1: dispatch 0004101603300300
2021.12.12 19:32:57 4: CMD:APPLICATION_COMMAND_HANDLER ID:16 ARG:03300300 CB:10
2021.12.12 19:32:57 4: ZWDongle_Read ZWAVE1: rcvd 0004101603300300 (request APPLICATION_COMMAND_HANDLER), sending ACK
2021.12.12 19:32:57 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.12 19:32:57 5: ZWAVE1: dispatch 0004101603300300
2021.12.12 19:32:57 4: CMD:APPLICATION_COMMAND_HANDLER ID:16 ARG:03300300 CB:10
2021.12.12 19:32:57 4: ZWDongle_Read ZWAVE1: rcvd 0004101603300300 (request APPLICATION_COMMAND_HANDLER), sending ACK
2021.12.12 19:32:57 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.12 19:32:57 5: ZWAVE1: dispatch 0004101603300300
2021.12.12 19:32:57 4: CMD:APPLICATION_COMMAND_HANDLER ID:16 ARG:03300300 CB:10
2021.12.12 19:32:57 4: ZWDongle_Read ZWAVE1: rcvd 0004101603300300 (request APPLICATION_COMMAND_HANDLER), sending ACK
2021.12.12 19:32:57 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.12 19:32:57 5: ZWAVE1: dispatch 0004101603300300
2021.12.12 19:32:57 4: CMD:APPLICATION_COMMAND_HANDLER ID:16 ARG:03300300 CB:10
2021.12.12 19:32:57 4: ZWDongle_Read ZWAVE1: rcvd 0004101603300300 (request APPLICATION_COMMAND_HANDLER), sending ACK
2021.12.12 19:32:57 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.12 19:32:57 5: ZWAVE1: dispatch 0004101603300300
2021.12.12 19:32:57 4: CMD:APPLICATION_COMMAND_HANDLER ID:16 ARG:03300300 CB:10
2021.12.12 19:32:57 4: ZWDongle_Read ZWAVE1: rcvd 0004101603300300 (request APPLICATION_COMMAND_HANDLER), sending ACK
2021.12.12 19:32:57 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.12 19:32:57 5: ZWAVE1: dispatch 0004101603300300
2021.12.12 19:32:57 4: CMD:APPLICATION_COMMAND_HANDLER ID:16 ARG:03300300 CB:10
2021.12.12 19:32:57 4: ZWDongle_Read ZWAVE1: rcvd 0004101603300300 (request APPLICATION_COMMAND_HANDLER), sending ACK
2021.12.12 19:32:57 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.12 19:32:57 5: ZWAVE1: dispatch 0004101603300300
2021.12.12 19:32:57 4: CMD:APPLICATION_COMMAND_HANDLER ID:16 ARG:03300300 CB:10
2021.12.12 19:32:58 5: ZWDongle_Write 00132d028408257c (e6845561)
2021.12.12 19:32:58 5: DevIo_SimpleWrite ZWAVE1: 010900132d028408257c1f
2021.12.12 19:32:58 5: ACK received, WaitForAck=>2 for 010900132d028408257c1f
2021.12.12 19:32:58 4: ZWDongle_Read ZWAVE1: rcvd 011301 (answer ZW_SEND_DATA), sending ACK
2021.12.12 19:32:58 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.12 19:32:58 5: ZWAVE1: dispatch 011301
2021.12.12 19:32:58 4: ZWDongle_Read ZWAVE1: rcvd 00137c00000e (request ZW_SEND_DATA), sending ACK
2021.12.12 19:32:58 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.12 19:32:58 5: device ack reveived, removing 010900132d028408257c1f from dongle sendstack
2021.12.12 19:32:58 5: ZWAVE1: dispatch 00137c00000e
2021.12.12 19:32:58 4: CMD:ZW_SEND_DATA ID:00 ARG:000e CB:7c
2021.12.12 19:32:58 4: ZWAVE1 transmit OK for CB 7c, target UG_BU_TK_1
2021.12.12 19:32:58 1: ACK: UG_BU_TK_1 00137c00000e 7c => set thermostatSetpointSet 25
2021.12.12 19:32:58 3: Notify n_TK_BU Event: thermostatSetpointSet: 25
Beispiel für dim auf EG_BA_MS_1 2021-12-12 23:00:37
-voraus geht eine Motion-Detection welche ein set EG_EZ_DI_1 dim 57 zur Folge hat
-danach kommt ein Wake-Up-Event von EG_BA_MS_1
-bei dieser Kommunikation wird dann der letzte dim-Wert auf den Motionsensor zugeordnet
2021.12.12 23:00:32 3: Notify n_Licht_EZ Event: MSon
2021.12.12 23:00:32 3: Watchdog Licht_EZ Set: initial 5min (Motion)
2021.12.12 23:00:32 3: ZWave set EG_EZ_DI_1 dim 57
2021.12.12 23:00:32 5: ZWDongle_Write 001308075601260139FFE025fe (e6845561)
2021.12.12 23:00:32 5: DevIo_SimpleWrite ZWAVE1: 010e001308075601260139FFE025fe60
2021.12.12 23:00:32 5: ACK received, WaitForAck=>2 for 010e001308075601260139FFE025fe60
2021.12.12 23:00:32 4: ZWDongle_Read ZWAVE1: rcvd 011301 (answer ZW_SEND_DATA), sending ACK
2021.12.12 23:00:32 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.12 23:00:32 5: ZWAVE1: dispatch 011301
2021.12.12 23:00:32 4: ZWDongle_Read ZWAVE1: rcvd 0013fe00000f (request ZW_SEND_DATA), sending ACK
2021.12.12 23:00:32 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.12 23:00:32 5: device ack reveived, removing 010e001308075601260139FFE025fe60 from dongle sendstack
2021.12.12 23:00:32 5: ZWAVE1: dispatch 0013fe00000f
2021.12.12 23:00:32 4: CMD:ZW_SEND_DATA ID:00 ARG:000f CB:fe
2021.12.12 23:00:32 4: ZWAVE1 transmit OK for CB fe, target EG_EZ_DI_1
2021.12.12 23:00:32 1: ACK: EG_EZ_DI_1 0013fe00000f fe => set dim 57
2021.12.12 23:00:33 4: ZWDongle_Read ZWAVE1: rcvd 0004000806310504220011 (request APPLICATION_COMMAND_HANDLER), sending ACK
2021.12.12 23:00:33 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.12 23:00:33 5: ZWAVE1: dispatch 0004000806310504220011
2021.12.12 23:00:33 4: CMD:APPLICATION_COMMAND_HANDLER ID:08 ARG:06310504220011 CB:00
2021.12.12 23:00:33 4: ZWDongle_Read ZWAVE1: rcvd 0004000803260339 (request APPLICATION_COMMAND_HANDLER), sending ACK
2021.12.12 23:00:33 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.12 23:00:33 5: ZWAVE1: dispatch 0004000803260339
2021.12.12 23:00:33 4: CMD:APPLICATION_COMMAND_HANDLER ID:08 ARG:03260339 CB:00
2021.12.12 23:00:35 4: ZWDongle_Read ZWAVE1: rcvd 0004001a028407 (request APPLICATION_COMMAND_HANDLER), sending ACK
2021.12.12 23:00:35 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.12 23:00:35 5: ZWAVE1: dispatch 0004001a028407
2021.12.12 23:00:35 4: CMD:APPLICATION_COMMAND_HANDLER ID:1a ARG:028407 CB:00
2021.12.12 23:00:37 5: ZWDongle_Write 00131a02840825ff (e6845561,28)
2021.12.12 23:00:37 5: DevIo_SimpleWrite ZWAVE1: 010900131a02840825ffab
2021.12.12 23:00:37 5: ACK received, WaitForAck=>2 for 010900131a02840825ffab
2021.12.12 23:00:37 4: ZWDongle_Read ZWAVE1: rcvd 011301 (answer ZW_SEND_DATA), sending ACK
2021.12.12 23:00:37 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.12 23:00:37 5: ZWAVE1: dispatch 011301
2021.12.12 23:00:37 4: ZWDongle_Read ZWAVE1: rcvd 0013ff000004 (request ZW_SEND_DATA), sending ACK
2021.12.12 23:00:37 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.12 23:00:37 5: device ack reveived, removing 010900131a02840825ffab from dongle sendstack
2021.12.12 23:00:37 5: ZWAVE1: dispatch 0013ff000004
2021.12.12 23:00:37 4: CMD:ZW_SEND_DATA ID:00 ARG:0004 CB:ff
2021.12.12 23:00:37 4: ZWAVE1 transmit OK for CB ff, target EG_BA_MS_1
2021.12.12 23:00:37 1: ACK: EG_BA_MS_1 0013ff000004 ff => set dim 57
2021.12.12 23:00:37 4: ZWDongle_Read ZWAVE1: rcvd 0004001a06310501220102 (request APPLICATION_COMMAND_HANDLER), sending ACK
2021.12.12 23:00:37 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.12 23:00:37 5: ZWAVE1: dispatch 0004001a06310501220102
Beispiel für NeighborUpdate an EG_KU_MS_1
2021.12.13 06:43:57 3: Wake-Up Event EG_KU_MS_1 (set NU)
2021.12.13 06:43:57 3: ZWave set EG_KU_MS_1 neighborUpdate
2021.12.13 06:43:57 5: ZWDongle_Write 00481137 (e6845561)
2021.12.13 06:43:57 5: DevIo_SimpleWrite ZWAVE1: 01050048113794
2021.12.13 06:43:57 5: ACK received, removing 01050048113794 from dongle sendstack
2021.12.13 06:43:57 4: ZWDongle_Read ZWAVE1: rcvd 00483721 (request ZW_REQUEST_NODE_NEIGHBOR_UPDATE), sending ACK
2021.12.13 06:43:57 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.13 06:43:57 5: ZWAVE1: dispatch 00483721
2021.12.13 06:43:57 4: CMD:ZW_REQUEST_NODE_NEIGHBOR_UPDATE ID:21 ARG: CB:37
2021.12.13 06:43:59 5: ZWDongle_Write 0013110284082538 (e6845561)
2021.12.13 06:43:59 5: DevIo_SimpleWrite ZWAVE1: 0109001311028408253867
2021.12.13 06:43:59 5: ACK received, WaitForAck=>2 for 0109001311028408253867
2021.12.13 06:43:59 4: ZWDongle_Read ZWAVE1: rcvd 011301 (answer ZW_SEND_DATA), sending ACK
2021.12.13 06:43:59 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.13 06:43:59 5: ZWAVE1: dispatch 011301
2021.12.13 06:44:01 4: no response from device, removing 0109001311028408253867 from dongle sendstack
2021.12.13 06:44:02 4: ZWDongle_Read ZWAVE1: rcvd 00133801012b (request ZW_SEND_DATA), sending ACK
2021.12.13 06:44:02 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.13 06:44:02 5: ZWAVE1: dispatch 00133801012b
2021.12.13 06:44:02 4: CMD:ZW_SEND_DATA ID:01 ARG:012b CB:38
2021.12.13 06:44:02 2: ZWAVE1 transmit NO_ACK for CB 38, target EG_KU_MS_1
2021.12.13 06:44:03 4: ZWDongle_Read ZWAVE1: rcvd 00040011063105012200c5 (request APPLICATION_COMMAND_HANDLER), sending ACK
2021.12.13 06:44:03 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.13 06:44:03 5: ZWAVE1: dispatch 00040011063105012200c5
2021.12.13 06:44:03 4: CMD:APPLICATION_COMMAND_HANDLER ID:11 ARG:063105012200c5 CB:00
2021.12.13 06:44:06 4: ZWDongle_Read ZWAVE1: rcvd 00483723 (request ZW_REQUEST_NODE_NEIGHBOR_UPDATE), sending ACK
2021.12.13 06:44:06 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.13 06:44:06 5: ZWAVE1: dispatch 00483723
2021.12.13 06:44:06 4: CMD:ZW_REQUEST_NODE_NEIGHBOR_UPDATE ID:23 ARG: CB:37
2021.12.13 06:44:36 4: ZWDongle_Read ZWAVE1: rcvd 00040016033003ff (request APPLICATION_COMMAND_HANDLER), sending ACK
2021.12.13 06:44:36 5: DevIo_SimpleWrite ZWAVE1: 06
2021.12.13 06:44:36 5: ZWAVE1: dispatch 00040016033003ff
2021.12.13 06:44:36 4: CMD:APPLICATION_COMMAND_HANDLER ID:16 ARG:033003ff CB:00
2021.12.13 06:44:36 3: Notify n_Licht_KF Event: MSadd
ZitatWird setReadingOnAck auf 0 gesetzt tauchen die fehlerhaften Zuordnungen bei den Wake-Up-Geräten wie vermutet nicht auf.
Na immerhin.
Aus den restlichen Logs bin ich nicht schlau geworden, ich kann z.Zt. das Problem nur mit dem alten Code erklaeren, was vmtl. doch nicht der Fall ist.
Ich habe jetzt eine neue Modulversion mit etwas besseren debugging eingebaut.
Kannst Du bitte mit dieser Version und "attr ZWAVE1 verbose 5" einen neuen Versuch starten, und wenn nachweislich ein Element falsche Readings hat, mir das ganze(!) Log zeigen.
Weiterhin brauche ich das "Raw definition" der beiden betroffenen Geraete.
Das mache ich doch gerne.
Werde also auf das aktuelle Release gehen, alle Einträge bereinigen, und dann das komplette Logfile (mit Loglevel 5 für ZWAVE1) hochladen nachdem wieder die ersten Readings gesetzt sind.
Hallo,
hoffentlich habe ich jetzt nicht alles zum Thema falsch verstanden.
Habe auch schon mal öfter gesehen das Readings logischer Weise nicht zu den Geräten passen.
Unter anderen aktuell mal ein List von einer Steckdose mit Reading setpointTemp 20.00 C heating ??
Internals:
DEF e769ab5c 24
FUUID 5cc0ca9c-f33f-cca1-7732-7a4e91569cae82fa
IODev ZWDongle_0
LASTInputDev ZWDongle_0
MSGCNT 8025
NAME Flur_ZW_STECKDOSE1
NR 262
STATE on
TYPE ZWave
ZWDongle_0_MSGCNT 8025
ZWDongle_0_RAWMSG 000400180a3202a14a0000012d0000
ZWDongle_0_TIME 2021-12-14 18:48:30
ZWaveSubDevice no
homeId e769ab5c
isWakeUp
nodeIdHex 18
READINGS:
2021-12-08 10:29:18 IODev ZWDongle_0
2021-02-14 00:14:43 UNPARSED SENSOR_MULTILEVEL 063101012200b5
2019-10-15 13:40:27 assocGroup_1 Max 5 Nodes ZWDongle_0
2019-10-15 13:40:27 assocGroup_2 Max 5 Nodes
2019-10-15 13:40:27 assocGroup_3 Max 5 Nodes
2019-10-15 13:40:27 assocGroups 3
2019-10-15 13:38:19 configButtonOnOff Enable
2019-10-15 13:38:19 configConfigureMaximumAlarmCurrent 12
2019-10-15 13:38:19 configConfigureMaximumOverLoadCurrent 13
2019-10-15 13:38:19 configConfigurePlugTimeSwitchFunction Disable
2019-10-15 13:38:19 configConfigurePowerReport 5
2019-10-15 13:38:20 configConfigureTimeSwitchPeriod 150
2019-10-15 13:38:20 configLedDisplay Enable
2019-10-15 13:38:20 configMeterReportInterval 300
2019-10-15 13:38:20 configRememberRelayONOFFStatus Enable
2019-10-15 13:38:20 configSendMeterReport Enable
2019-10-06 03:21:04 cooling 228.14 previous: 228.23 delta_time: 301 s
2021-12-14 18:48:30 current 0 A previous: 0 delta_time: 301 s
2021-12-14 18:48:27 energy 0 kWh previous: 0 delta_time: 301 s
2019-04-24 22:44:17 model Neo CoolCam Power plug 12A
2019-04-24 22:44:17 modelConfig shenzen_neo/nas-wr01z.xml
2019-04-24 22:44:17 modelId 0258-0003-1087
2019-10-10 22:26:45 neighborList ZWDongle_0 Thermostat_Buero SZ_DECKE_ZW_DIM WOH_ZW_THERMOSTAT_OST Thermostat1_UG_WZ ZWave_SWITCH_BINARY_10 Steckd_POPP Thermostat_Bad ZWave_SWITCH_BINARY_16 BUERO_ZW_FK_WEST SZ_ZW_THERMOSTAT SZ_ZW_STECKDOSE1 BEDROOM_ZW_FK ZWave_WALL_CONTROLLER_29 BUERO_ZW_TEMPSENSOR
2021-01-15 23:01:17 neighborUpdate done
2021-12-14 18:48:28 power 0 W previous: 0 delta_time: 301 s
2021-02-17 10:24:18 reportedState on
2019-09-10 14:12:07 setpointTemp 20.00 C heating
2021-02-17 10:24:18 state on
2021-10-07 05:42:55 temperature 17.8 C
2019-10-15 13:40:27 timeToAck 0.031
2019-10-15 13:40:27 transmit OK
2020-09-16 15:26:04 undef 0 undef previous: 0 delta_time: 301 s
2021-02-09 13:50:25 velocity 0.0 m/s
2021-12-14 18:48:29 voltage 224.26 V previous: 224.7 delta_time: 301 s
Attributes:
IODev ZWDongle_0
classes ZWAVEPLUS_INFO MANUFACTURER_SPECIFIC VERSION ASSOCIATION ASSOCIATION_GRP_INFO DEVICE_RESET_LOCALLY POWERLEVEL CONFIGURATION SWITCH_BINARY SWITCH_ALL ALARM METER BASIC
generateRouteInfoEvents 0
icon black_Steckdose.on
room Wohnung->Flur,ZWave
vclasses ALARM:8 ASSOCIATION:2 ASSOCIATION_GRP_INFO:1 BASIC:1 CONFIGURATION:1 DEVICE_RESET_LOCALLY:1 MANUFACTURER_SPECIFIC:2 METER:4 POWERLEVEL:1 SWITCH_ALL:1 SWITCH_BINARY:1 VERSION:2 ZWAVEPLUS_INFO:2
Sorry falls ich jetzt daneben liege...
Gruß
Hans-Jürgen
Genau richtig verstanden - diesen Effekt meinte ich.
Außer setpointTemp dürften bei diesem Device auch die "temperature" und "velocity" Readings vermutlich von anderen Quellen stammen.
So etwas kann sicher passieren, wenn es sich um Übertragungsfehler handelt und z.B. die ID nicht stimmt, bei der genauen Betrachtung der RAW-Messages konnte ich dies bei mir aber für den Großteil der Fälle ausschließen. Speziell bei Wakeup-Geräten hatte ich nachweislich im Log, dass ohne ersichtlichen Grund ein Reading zugeordnet wurde welches bereits einige Zeit zuvor von einem anderen Device gesendet und eigentlich auch schon abgearbeitet war.
Das Problem besteht bei mir schon längere Zeit, wollte der Sache jetzt aber mal richtig auf den Grund gehen. Ein setpointTemp auf einem Lichtschalter ist noch "unschön", wenn aber der automatische Schaltvorgang nicht mehr klappt, da durch falsch zugeordnete Readings eine Bedingung nicht mehr erfüllt ist oder umgekehrt ein Vorgang fälschlicherweise getriggert wird, dann wird es nervig (und man läuft Gefahr mit der gut gemeinten Hausautomatisierung mal wieder den Ärger der ganzen Familie auf sich zu ziehen ;) )
Also Logs gesammelt, analysiert und Debug-Meldungen eingebaut, freundlicherweise hat sich auch Rudolf der Sache angenommen und Hilfestellung zur Eingrenzung geliefert.
Jetzt der Brüller: seit im aktuellen Release optimierte Log-Möglichkeiten vorhanden sind ist bei mir der Fehler in den letzten 24h nicht mehr reproduzierbar (zuvor hatte es keine 2h gedauert).
Im Code wurden auf den ersten Blick aber wirklich nur Inhalte verändert welche sich auf das Logging auswirken.
Werde die Sache jetzt weiter beobachten und ggf. am WE nochmals den eingefrorenen Zustand einspielen, sowie die Code-Changes der vergangenen 2 Wochen vergleichen...
Hallo krusi,
Danke DIR und Rudi für die Mühe!
ZitatDas Problem besteht bei mir schon längere Zeit, wollte der Sache jetzt aber mal richtig auf den Grund gehen. Ein setpointTemp auf einem Lichtschalter ist noch "unschön", wenn aber der automatische Schaltvorgang nicht mehr klappt, da durch falsch zugeordnete Readings eine Bedingung nicht mehr erfüllt ist oder umgekehrt ein Vorgang fälschlicherweise getriggert wird, dann wird es nervig (und man läuft Gefahr mit der gut gemeinten Hausautomatisierung mal wieder den Ärger der ganzen Familie auf sich zu ziehen ;) )
Genau so auch meine denke....
Habe jetzt noch mal ein update gemacht und in einigen Geräten die unpassenden Readings gelöscht, hatte sich doch schon einiges angesammelt.
Mal sehen was noch kommt?
Eine angenehme Vorweihnachtszeit wünscht
Hans-Jürgen
Wohl wahr !
Ich habe jetzt 3 Tage mit diesem Problem zugebracht.
Letztlich konnte ich alles auf Kommunikationsprobleme zurueckfuehren, auch das woanders erwaehnte dubiose 'ERROR: cannot SEND_DATA to FlurObenSensor: transmit queue overflow'
Wenn irgendetwas dazwischenfunkt, passieren merkwuerdige Dinge, wobei dann erkannte Uebertragungsprobleme das kleinere Problem darstellen.
Am Ende habe ich mir einen Platz weit genug entfernt von Router, Servern oder anderen 'Funkern' gesucht.
Das hat zwar das Problem ertraeglich gemacht, zufriedenstellend ist das aber nicht.
Oder , um es mal andersherum zu formulieren : Eine Alarmanlage koennte ich so nicht betreiben.
Eine Frage, die eine Antwort braucht :
Passieren die Modfikationen einer Message
auf dem Wege vom Device zum Dongle
oder
in der Kommunikation Dongle-FHEM
?
@krusi:
Was ist dein Status zur Zeit ?
Eine Beschreibung dessen was passiert, ist gar nicht so einfach :
1. Bei einem FHEM-Device kommen Messages an, die nicht interpretierbar zu sein scheinen.
Resultat ist wohl dann ein UNPARSED, etc
2. Es kommen Messages an, die syntaktisch korrekt sind, aber z. B. nicht existierende Parameter betreffen.
Resultat : UNKNOWN_17, etc.
3. Es kommen Messages an, die korrekt sind, aber Readings erzeugen, die bei diesem Device nicht vorhanden sein sollte.
Etwa ein Reading fuer das Magnetfeld bei einem Switch.
4. Es kommen Messages an, die sinnvoll sind, aber mit unsinnigen Werten.
Etwa ein Power-Reading mit einem Wert, den es im ganzen System nicht geben kann, etwa oberhalb von 3600 Watt.
Ausnahmen sind Devices eines Herstellers, die wirklich ab und an falsche Zahlen liefern.
5. Es kommen Messages an, die korrekt und sinnvoll sind, aber offenbar mit Werten eines anderen Devices
In vielen Faellen erledigt sich das bei der naechsten Uebertragung, etwa alles unter 1.,2. und 3.
4. und 5. sind teilweise richtig unangenehm.
Ausserdem bedeutet es, dass Messages verloren gehen - nicht gut !
Aus meiner Erfahrung heraus glaube ich sagen zu koennen, dass das nicht Dongle-Probleme sind,
ich habe sowohl mal ein Razberry, ein Z-Wave.Me USB Stick und ein Aeotec Z-Stick Gen5+ im Einsatz (gehabt), alle mit vergleichbaren Problemen
ZitatPassieren die Modfikationen einer Message
Was ist damit gemeint?
Ich rate: die mit setReadingOnAck gesetzte Readings.
Das laeuft so: jede vom Modul an dem Controller uebergebene Befehl enthaelt eine 8-bit ID. Wenn das ZWave-Geraet den Empfang bestaetigt, sendet der Controller den ACK zusammen mit dieser ID an das Modul.
Das Modul merkt zu jede ID den dazugehoerigen Befehl. Bei WakeUp Geraeten kann es vorkommen, dass bis zum Aufwachen 256 _andere_ Nachrichten versendet wurden, deswegen wurde das ACK dem falschen Geraet+Befehl zugeordnet.
Ich habe das Problem gefixt (jedenfalls meine ich), und das Logging erweitert.
Bei Problemen brauche ich das Log.
ok.
Mit Modifizieren meine ich die Aenderung einer Message.
Beispiel :
Wenn ein Power-Sensor die Wattzahl zum Kontroller schickt, etwa 1250 W, gehe ich davon aus, dass diese Message erstmal korrekt abgeschickt wurde.
IN FHEM kommt etwa 3624 W an, also ist die Message unterwegs geaendert worden, richtig ?
Es geht nicht nur um falsche IDs !
Noch zur Info : Das betrifft nicht nur Wake-up Devices, sondern kann ueberall auftreten, ohne dass ich wirklich ein System erkennen koennte ( Hersteller, Platzierung , etc )
Wenn es bei mir Devices gibt, bei denen das haeufiger auftritt, dann sind es die Fibaro Wall Plugs FGWPF-102.
ZitatEs geht nicht nur um falsche IDs !
Dann ist das hier das falsche Thema.
Es werden oefter Probleme mit falschen Daten gemeldet, die Ursache ist mW dass die Nachrichten mit Bitfehler dekodiert wurden.
Bei 9.6k und 40k Datenrate wird ein Byte CRC verwendet, bei 100k zwei.
Falls das Geraet CRC_16_ENCAP unterstuetzt, und das Attribut useCRC16 gesetzt ist, dann versieht das Modul alle Befehle zusaetzlich mit CRC16. Weiss aber nicht, ob daraufhin das Geraet nur die Antwort oder auch die regelmaessigen Meldungen so einpackt.
Danke, verstanden.