it’s not filtered in a way that something would be missing.
here is a more complete one
- I change der dimmer value and “watts” change triggers a meter report
4:32:21.463 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 84: Command received zwave:device:512:node84:switch_dimmer --> 100 [PercentType]
14:32:21.464 [DEBUG] [ass.ZWaveMultiLevelSwitchCommandClass] - NODE 84: Creating new message for command SWITCH_MULTILEVEL_SET
14:32:21.465 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 84: SECURITY NOT required on COMMAND_CLASS_SWITCH_MULTILEVEL
14:32:21.466 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 84: Command Class COMMAND_CLASS_SWITCH_MULTILEVEL is NOT required to be secured
14:32:21.467 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 84: Adding to device queue
14:32:21.468 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 84: Added 699 to queue - size 29
14:32:21.468 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
14:32:21.469 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 54 03 26 01 63 25 51 81
14:32:21.470 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 84: Sending REQUEST Message = 01 0A 00 13 54 03 26 01 63 25 51 81
14:32:21.473 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT
14:32:21.474 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 699: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 81
14:32:21.475 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 84: Polling initialised at 1800 seconds - start in 1500 milliseconds.
14:32:21.475 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
14:32:21.476 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
14:32:21.477 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
14:32:21.478 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 699: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 81
14:32:21.479 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg: ACK
14:32:21.479 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
14:32:21.480 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
14:32:21.480 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
14:32:21.481 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
14:32:21.482 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
14:32:21.483 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 699: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 81
14:32:21.483 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
14:32:21.483 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 699: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 81
14:32:21.483 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
14:32:21.484 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 84: sentData successfully placed on stack.
14:32:21.484 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 699: Advanced to WAIT_REQUEST
14:32:21.484 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 84: TID 699: Transaction not completed
14:32:21.485 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
14:32:21.485 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
14:32:21.498 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 51 00 00 02 B8
14:32:21.500 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=81, payload=51 00 00 02
14:32:21.503 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=81, payload=51 00 00 02
14:32:21.504 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 699: [WAIT_REQUEST] priority=Set, requiresResponse=true, callback: 81
14:32:21.514 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
14:32:21.515 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 699: [WAIT_REQUEST] priority=Set, requiresResponse=true, callback: 81
14:32:21.516 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking TID 699: (Callback 81)
14:32:21.516 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Callback match!
14:32:21.517 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Correlated to TID 699: callback 81
14:32:21.517 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=81, payload=51 00 00 02
14:32:21.518 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 84: SendData Request. CallBack ID = 81, Status = Transmission complete and ACK received(0)
14:32:21.520 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 699: Transaction COMPLETED
14:32:21.522 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 84: Response processed after 48ms
14:32:21.523 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 84: TID 699: Transaction completed
14:32:21.524 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 84: notifyTransactionResponse TID:699 DONE
14:32:21.527 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 84: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
14:32:21.528 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
14:32:21.529 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
14:32:22.975 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 84: Polling...
14:32:22.976 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 84: Polling deferred until initialisation complete
14:32:23.263 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'SebastianLatency' changed from 136.0 ms to 503.0 ms
14:32:23.899 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'SebastianLastOnline' changed from 2021-03-14T14:31:22.751+0100 to 2021-03-14T14:32:23.895+0100
14:32:26.393 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0D 00 04 00 54 07 60 0D 01 01 26 03 63 8E
14:32:26.395 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=84, callback=0, payload=00 54 07 60 0D 01 01 26 03 63
14:32:26.396 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=84, callback=0, payload=00 54 07 60 0D 01 01 26 03 63
14:32:26.397 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null
14:32:26.398 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 84: Application Command Request (ALIVE:DYNAMIC_VALUES)
14:32:26.399 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 84: Decapsulating COMMAND_CLASS_MULTI_CHANNEL
14:32:26.399 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 84: Incoming command class COMMAND_CLASS_SWITCH_MULTILEVEL, endpoint 1
14:32:26.400 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 84: SECURITY NOT required on COMMAND_CLASS_SWITCH_MULTILEVEL
14:32:26.401 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 84: Received COMMAND_CLASS_SWITCH_MULTILEVEL V3 SWITCH_MULTILEVEL_REPORT
14:32:26.402 [DEBUG] [ass.ZWaveMultiLevelSwitchCommandClass] - NODE 84: Switch Multi Level report, value = 99
14:32:26.403 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 84: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
14:32:26.404 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 84: Got a value event from Z-Wave network, endpoint=1, command class=COMMAND_CLASS_SWITCH_MULTILEVEL, value=99
14:32:26.404 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 84: Commands processed 1.
14:32:26.405 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 84: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@bc2d0d8.
14:32:26.405 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
14:32:26.406 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
14:32:26.407 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
14:32:26.407 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
14:32:27.492 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 14 00 04 00 54 0E 60 0D 01 01 32 02 21 34 00 00 00 C1 00 00 3C
14:32:27.493 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=84, callback=0, payload=00 54 0E 60 0D 01 01 32 02 21 34 00 00 00 C1 00 00
14:32:27.493 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=84, callback=0, payload=00 54 0E 60 0D 01 01 32 02 21 34 00 00 00 C1 00 00
14:32:27.494 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null
14:32:27.495 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 84: Application Command Request (ALIVE:DYNAMIC_VALUES)
14:32:27.495 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 84: Decapsulating COMMAND_CLASS_MULTI_CHANNEL
14:32:27.496 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 84: Incoming command class COMMAND_CLASS_METER, endpoint 1
14:32:27.496 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 84: SECURITY NOT required on COMMAND_CLASS_METER
14:32:27.498 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 84: Received COMMAND_CLASS_METER V3 METER_REPORT
14:32:27.498 [DEBUG] [l.commandclass.ZWaveMeterCommandClass] - NODE 84: Meter: Type=Electric(1), Scale=W(2), Value=19.3
14:32:27.499 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 84: Got an event from Z-Wave network: ZWaveMeterValueEvent
14:32:27.500 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 84: Got a value event from Z-Wave network, endpoint=1, command class=COMMAND_CLASS_METER, value=19.3
14:32:27.501 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 84: Commands processed 1.
14:32:27.502 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 84: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@59fb1e45.
14:32:27.502 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
14:32:27.503 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
14:32:27.503 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
14:32:27.504 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
looks like this in the log viewer
so the meter report is coming but the channel / item is not populated.
If the newer Dimmer version would need a database update I unfortunately can not tell what should be changed
edit:
I also see this in the log:
14:32:22.975 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 84: Polling...
14:32:22.976 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 84: Polling deferred until initialisation complete
which is odd since it is intialized fully
→ all other channels work as expected. dimmer, kwh reports, temp sensor connected to it …
