[Solved] Z-Wave Basic Report not updated on event bus

Hi,

I’m not sure what the problem is but when I do a manual switch (press the physical button) on several of my Aeotec DSC24 switches the event bus is not updated with the new status (ON/OFF)… However, it is updated if I do the switching through my sitemap.

I’ve checked that the “Basic CC report” is set on the device through habmin:

On further checking it seems the event/update does get through (this debug shows when I switch it OFF and ON):

12:10:48.419 [DEBUG] [eController$ZWaveReceiveThread:1601 ] - Receive Message = 01 1B 00 49 84 33 15 04 10 01 5E 25 32 27 2C 2B 70 85 59 56 72 86 7A 73 98 EF 5A 82 DB
12:10:48.425 [DEBUG] [eController$ZWaveReceiveThread:1517 ] - Receive queue ADD: Length=1
12:10:48.425 [DEBUG] [b.z.i.protocol.ZWaveController:1244 ] - Receive queue TAKE: Length=0
12:10:48.428 [DEBUG] [o.b.z.i.protocol.SerialMessage:255  ] - Assembled message buffer = 01 1B 00 49 84 33 15 04 10 01 5E 25 32 27 2C 2B 70 85 59 56 72 86 7A 73 98 EF 5A 82 DB
12:10:48.431 [DEBUG] [b.z.i.protocol.ZWaveController:1245 ] - Process Message = 01 1B 00 49 84 33 15 04 10 01 5E 25 32 27 2C 2B 70 85 59 56 72 86 7A 73 98 EF 5A 82 DB
12:10:48.433 [DEBUG] [b.z.i.protocol.ZWaveController:200  ] - Message: class = ApplicationUpdate (0x49), type = Request (0x00), payload = 84 33 15 04 10 01 5E 25 32 27 2C 2B 70 85 59 56 72 86 7A 73 98 EF 5A 82 , callbackid = 0
12:10:48.444 [DEBUG] [.ApplicationUpdateMessageClass:49   ] - NODE 51: Application update request. Node information received.
12:10:48.446 [DEBUG] [b.z.i.protocol.ZWaveController:668  ] - NODE 51: Notifying event listeners: ZWaveNodeInfoEvent
12:10:48.448 [DEBUG] [.z.internal.ZWaveActiveBinding:455  ] - ZwaveIncomingEvent
12:10:48.449 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:1186 ] - NODE 51: 35 NIF event during initialisation stage PING
12:10:48.450 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:1186 ] - NODE 51: 54 NIF event during initialisation stage PING
12:10:48.452 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:1186 ] - NODE 51: 50 NIF event during initialisation stage PING
12:10:48.453 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:1186 ] - NODE 51: 33 NIF event during initialisation stage PING
12:10:48.454 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:1186 ] - NODE 51: 22 NIF event during initialisation stage PING
12:10:48.455 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:1186 ] - NODE 51: 55 NIF event during initialisation stage PING
12:10:48.457 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:1186 ] - NODE 51: 24 NIF event during initialisation stage PING
12:10:48.458 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:1186 ] - NODE 51: 15 NIF event during initialisation stage PING
12:10:48.459 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:1186 ] - NODE 51: 18 NIF event during initialisation stage PING
12:10:48.460 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:1186 ] - NODE 51: 25 NIF event during initialisation stage PING
12:10:48.463 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:83   ] - Sent message Message: class = SendData (0x13), type = Request (0x00), payload = 3B 03 31 04 01 , callbackid = 227
12:10:48.466 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:84   ] - Recv message Message: class = ApplicationUpdate (0x49), type = Request (0x00), payload = 84 33 15 04 10 01 5E 25 32 27 2C 2B 70 85 59 56 72 86 7A 73 98 EF 5A 82 , callbackid = 0
12:10:48.468 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:85   ] - Checking transaction complete: Sent message Message: class = SendData (0x13), type = Request (0x00), payload = 3B 03 31 04 01 , callbackid = 227
12:10:48.470 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:96   ] - Checking transaction complete: Recv message Message: class = ApplicationUpdate (0x49), type = Request (0x00), payload = 84 33 15 04 10 01 5E 25 32 27 2C 2B 70 85 59 56 72 86 7A 73 98 EF 5A 82 , callbackid = 0
12:10:48.472 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:107  ] - Checking transaction complete: class=ApplicationUpdate, callback id=227, expected=ApplicationCommandHandler, cancelled=false      mismatch
12:10:49.371 [DEBUG] [eController$ZWaveReceiveThread:1601 ] - Receive Message = 01 09 00 04 00 33 03 20 03 00 E1
12:10:49.375 [DEBUG] [eController$ZWaveReceiveThread:1517 ] - Receive queue ADD: Length=1
12:10:49.375 [DEBUG] [b.z.i.protocol.ZWaveController:1244 ] - Receive queue TAKE: Length=0
12:10:49.377 [DEBUG] [o.b.z.i.protocol.SerialMessage:255  ] - Assembled message buffer = 01 09 00 04 00 33 03 20 03 00 E1
12:10:49.379 [DEBUG] [b.z.i.protocol.ZWaveController:1245 ] - Process Message = 01 09 00 04 00 33 03 20 03 00 E1
12:10:49.381 [DEBUG] [b.z.i.protocol.ZWaveController:200  ] - Message: class = ApplicationCommandHandler (0x04), type = Request (0x00), payload = 00 33 03 20 03 00 , callbackid = 0
12:10:49.382 [DEBUG] [ApplicationCommandMessageClass:41   ] - NODE 51: Application Command Request (ALIVE:DONE)
12:10:49.384 [DEBUG] [ApplicationCommandMessageClass:146  ] - NODE 51: Incoming command class BASIC (0x20)
12:10:49.385 [DEBUG] [z.i.p.c.ZWaveBasicCommandClass:73   ] - NODE 51: Received Basic Request
12:10:49.387 [DEBUG] [z.i.p.c.ZWaveBasicCommandClass:106  ] - NODE 51: Basic report, value = 0x00
12:10:49.388 [DEBUG] [b.z.i.protocol.ZWaveController:668  ] - NODE 51: Notifying event listeners: ZWaveCommandClassValueEvent
12:10:49.389 [DEBUG] [.z.internal.ZWaveActiveBinding:455  ] - ZwaveIncomingEvent
12:10:49.390 [DEBUG] [.z.internal.ZWaveActiveBinding:472  ] - NODE 51: Got a value event from Z-Wave network, endpoint = 0, command class = BASIC, value = 0
12:10:49.393 [DEBUG] [ApplicationCommandMessageClass:122  ] - NODE 59: Transaction not completed: node address inconsistent.  lastSent=59, incoming=255
12:10:51.169 [DEBUG] [eController$ZWaveReceiveThread:1601 ] - Receive Message = 01 1B 00 49 84 33 15 04 10 01 5E 25 32 27 2C 2B 70 85 59 56 72 86 7A 73 98 EF 5A 82 DB
12:10:51.173 [DEBUG] [eController$ZWaveReceiveThread:1517 ] - Receive queue ADD: Length=1
12:10:51.173 [DEBUG] [b.z.i.protocol.ZWaveController:1244 ] - Receive queue TAKE: Length=0
12:10:51.176 [DEBUG] [o.b.z.i.protocol.SerialMessage:255  ] - Assembled message buffer = 01 1B 00 49 84 33 15 04 10 01 5E 25 32 27 2C 2B 70 85 59 56 72 86 7A 73 98 EF 5A 82 DB
12:10:51.178 [DEBUG] [b.z.i.protocol.ZWaveController:1245 ] - Process Message = 01 1B 00 49 84 33 15 04 10 01 5E 25 32 27 2C 2B 70 85 59 56 72 86 7A 73 98 EF 5A 82 DB
12:10:51.180 [DEBUG] [b.z.i.protocol.ZWaveController:200  ] - Message: class = ApplicationUpdate (0x49), type = Request (0x00), payload = 84 33 15 04 10 01 5E 25 32 27 2C 2B 70 85 59 56 72 86 7A 73 98 EF 5A 82 , callbackid = 0
12:10:51.181 [DEBUG] [.ApplicationUpdateMessageClass:49   ] - NODE 51: Application update request. Node information received.
12:10:51.182 [DEBUG] [b.z.i.protocol.ZWaveController:668  ] - NODE 51: Notifying event listeners: ZWaveNodeInfoEvent
12:10:51.183 [DEBUG] [.z.internal.ZWaveActiveBinding:455  ] - ZwaveIncomingEvent
12:10:51.184 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:1186 ] - NODE 51: 35 NIF event during initialisation stage PING
12:10:51.185 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:1186 ] - NODE 51: 54 NIF event during initialisation stage PING
12:10:51.186 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:1186 ] - NODE 51: 50 NIF event during initialisation stage PING
12:10:51.187 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:1186 ] - NODE 51: 33 NIF event during initialisation stage PING
12:10:51.188 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:1186 ] - NODE 51: 22 NIF event during initialisation stage PING
12:10:51.188 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:1186 ] - NODE 51: 55 NIF event during initialisation stage PING
12:10:51.189 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:1186 ] - NODE 51: 24 NIF event during initialisation stage PING
12:10:51.190 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:1186 ] - NODE 51: 15 NIF event during initialisation stage PING
12:10:51.191 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:1186 ] - NODE 51: 18 NIF event during initialisation stage PING
12:10:51.192 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:1186 ] - NODE 51: 25 NIF event during initialisation stage PING
12:10:51.193 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:83   ] - Sent message Message: class = SendData (0x13), type = Request (0x00), payload = 3B 03 31 04 01 , callbackid = 227
12:10:51.196 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:84   ] - Recv message Message: class = ApplicationUpdate (0x49), type = Request (0x00), payload = 84 33 15 04 10 01 5E 25 32 27 2C 2B 70 85 59 56 72 86 7A 73 98 EF 5A 82 , callbackid = 0
12:10:51.197 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:85   ] - Checking transaction complete: Sent message Message: class = SendData (0x13), type = Request (0x00), payload = 3B 03 31 04 01 , callbackid = 227
12:10:51.199 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:96   ] - Checking transaction complete: Recv message Message: class = ApplicationUpdate (0x49), type = Request (0x00), payload = 84 33 15 04 10 01 5E 25 32 27 2C 2B 70 85 59 56 72 86 7A 73 98 EF 5A 82 , callbackid = 0
12:10:51.200 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:107  ] - Checking transaction complete: class=ApplicationUpdate, callback id=227, expected=ApplicationCommandHandler, cancelled=false      mismatch
12:10:52.121 [DEBUG] [eController$ZWaveReceiveThread:1601 ] - Receive Message = 01 09 00 04 00 33 03 20 03 FF 1E
12:10:52.125 [DEBUG] [eController$ZWaveReceiveThread:1517 ] - Receive queue ADD: Length=1
12:10:52.125 [DEBUG] [b.z.i.protocol.ZWaveController:1244 ] - Receive queue TAKE: Length=0
12:10:52.127 [DEBUG] [o.b.z.i.protocol.SerialMessage:255  ] - Assembled message buffer = 01 09 00 04 00 33 03 20 03 FF 1E
12:10:52.129 [DEBUG] [b.z.i.protocol.ZWaveController:1245 ] - Process Message = 01 09 00 04 00 33 03 20 03 FF 1E
12:10:52.130 [DEBUG] [b.z.i.protocol.ZWaveController:200  ] - Message: class = ApplicationCommandHandler (0x04), type = Request (0x00), payload = 00 33 03 20 03 FF , callbackid = 0
12:10:52.131 [DEBUG] [ApplicationCommandMessageClass:41   ] - NODE 51: Application Command Request (ALIVE:DONE)
12:10:52.133 [DEBUG] [ApplicationCommandMessageClass:146  ] - NODE 51: Incoming command class BASIC (0x20)
12:10:52.134 [DEBUG] [z.i.p.c.ZWaveBasicCommandClass:73   ] - NODE 51: Received Basic Request
12:10:52.135 [DEBUG] [z.i.p.c.ZWaveBasicCommandClass:106  ] - NODE 51: Basic report, value = 0xFF
12:10:52.137 [DEBUG] [b.z.i.protocol.ZWaveController:668  ] - NODE 51: Notifying event listeners: ZWaveCommandClassValueEvent
12:10:52.138 [DEBUG] [.z.internal.ZWaveActiveBinding:455  ] - ZwaveIncomingEvent
12:10:52.139 [DEBUG] [.z.internal.ZWaveActiveBinding:472  ] - NODE 51: Got a value event from Z-Wave network, endpoint = 0, command class = BASIC, value = 255
12:10:52.140 [DEBUG] [ApplicationCommandMessageClass:122  ] - NODE 59: Transaction not completed: node address inconsistent.  lastSent=59, incoming=255

I’m seeing this same thing on atleast 3 different DSC24 devices (2 ed. and 3.edition).
This is using OH 1.8.3 + Z-Wave binding 1.9.0 (The debug log is with yesterdays zway snapshot from cb).
(I first noticed this on an older snapshot from september, tried updating it today to see if it fixed the problem but it didn’t.)
NB: The devices do report watt/kwh without a problem1

@chris , have you seen this before?

I’m reading this on my phone so not so easy to evaluate but I suspect that in your item definition you need to add the respond_to_basic=true parameter.

Chris

1 Like

Aaaaah, didn’t even think of that, thank you very much! (Seems to work fine now) :smile: