[SOLVED] Problem with zwave smart plugs and 5.0.0.M4: channel power switch not working

ariela · July 14, 2025, 11:26am

Hi folks,

I’m on 5.0.0.M4 here (openhabian on vm). After the upgrade from M3, all the switch commands with my Fibaro and Qubino zwave smart plugs are not working. I can change in OH from on to off and viceversa, but this is not reflected to the device itself.

This is not for a specific zwave plug, but all my smart plugs. I can see the power meters, as usual, but I can’t switch off / turn on.

The zwave network map is ok.

Can you please help me in troubleshooting this issue?
Already rebooted. And unlinked/linked again the item. I’m used to use the UI for all of my zwave devices.

thanks
Andrea

ariela · July 14, 2025, 11:41am

all the devices are in “node initialising: PING”

ariela · July 14, 2025, 12:32pm

not sure if this log can help

@chris can you please help me with it? Any known issue?


2025-07-14 14:24:02.061 [TRACE] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage nothing
2025-07-14 14:24:02.110 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Command received zwave:device:f00cbb6e00:node6:switch_binary --> OFF [OnOffType]
2025-07-14 14:24:02.110 [DEBUG] [dclass.ZWaveBinarySwitchCommandClass] - NODE 6: Creating new message for application command SWITCH_BINARY_SET
2025-07-14 14:24:02.110 [TRACE] [ng.zwave.internal.protocol.ZWaveNode] - NODE 6: Encapsulating message, endpoint 0
2025-07-14 14:24:02.110 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 6: SECURITY not supported
2025-07-14 14:24:02.110 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 6: Command Class COMMAND_CLASS_SWITCH_BINARY is NOT required to be secured
2025-07-14 14:24:02.110 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 6: Adding to device queue
2025-07-14 14:24:02.110 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 6: Added 627 to queue - size 39
2025-07-14 14:24:02.110 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

2025-07-14 14:24:02.113 [TRACE] [ng.zwave.internal.protocol.ZWaveNode] - NODE 6: listening == true, frequentlyListening == false, awake == false
2025-07-14 14:24:02.113 [TRACE] [nal.protocol.ZWaveTransactionManager] - Transaction from sendQueue
2025-07-14 14:24:02.113 [TRACE] [nal.protocol.ZWaveTransactionManager] - getTransactionToSend 6
2025-07-14 14:24:02.113 [TRACE] [wave.internal.protocol.SerialMessage] - NODE 6: Creating empty message of class = SendData (0x13), type = Request
2025-07-14 14:24:02.113 [TRACE] [wave.internal.protocol.SerialMessage] - Calculated checksum = 23
2025-07-14 14:24:02.113 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 06 03 25 01 00 25 F5 17 
2025-07-14 14:24:02.113 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 6: Sending REQUEST Message = 01 0A 00 13 06 03 25 01 00 25 F5 17 
2025-07-14 14:24:02.115 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2025-07-14 14:24:02.116 [TRACE] [wave.internal.protocol.SerialMessage] - NODE 255: Creating new SerialMessage from buffer = 06 
2025-07-14 14:24:02.116 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2025-07-14 14:24:02.116 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2025-07-14 14:24:02.116 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2025-07-14 14:24:02.116 [TRACE] [e.internal.protocol.ZWaveTransaction] - TID 627: Transaction Start type SendData 
2025-07-14 14:24:02.116 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2025-07-14 14:24:02.116 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 627: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 245
2025-07-14 14:24:02.116 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2025-07-14 14:24:02.116 [TRACE] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: expected cmd class: null
2025-07-14 14:24:02.116 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2025-07-14 14:24:02.116 [TRACE] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: expected cmd: 0
2025-07-14 14:24:02.116 [TRACE] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage Transactions outstanding: 1
2025-07-14 14:24:02.116 [TRACE] [nal.protocol.ZWaveTransactionManager] - Start transaction timer to Mon Jul 14 14:24:04 CEST 2025 - 2000ms
2025-07-14 14:24:02.116 [TRACE] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage lastTransaction: TID 627: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 245
2025-07-14 14:24:02.116 [TRACE] [nal.protocol.ZWaveTransactionManager] - STOP transaction timer
2025-07-14 14:24:02.116 [TRACE] [nal.protocol.ZWaveTransactionManager] - Start transaction timer to Mon Jul 14 14:24:04 CEST 2025 - 2000ms
2025-07-14 14:24:02.116 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2025-07-14 14:24:02.116 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Polling initialised at 86400 seconds - start in 1500 milliseconds.
2025-07-14 14:24:02.116 [TRACE] [nal.protocol.ZWaveTransactionManager] - Transaction lastTransaction outstanding...
2025-07-14 14:24:02.116 [TRACE] [nal.protocol.ZWaveTransactionManager] - STOP transaction timer
2025-07-14 14:24:02.116 [TRACE] [nal.protocol.ZWaveTransactionManager] - Start transaction timer to Mon Jul 14 14:24:04 CEST 2025 - 2000ms
2025-07-14 14:24:02.117 [TRACE] [WaveSerialHandler$ZWaveReceiveThread] - Received SOF
2025-07-14 14:24:02.118 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 00 E9 
2025-07-14 14:24:02.118 [TRACE] [wave.internal.protocol.SerialMessage] - NODE 255: Creating new SerialMessage from buffer = 01 04 01 13 00 E9 
2025-07-14 14:24:02.118 [TRACE] [wave.internal.protocol.SerialMessage] - Calculated checksum = -23
2025-07-14 14:24:02.118 [TRACE] [wave.internal.protocol.SerialMessage] - NODE 255: Checksum matched
2025-07-14 14:24:02.118 [TRACE] [wave.internal.protocol.SerialMessage] - NODE 255: Message payload = 00 
2025-07-14 14:24:02.118 [TRACE] [WaveSerialHandler$ZWaveReceiveThread] - Message is valid, sending ACK
2025-07-14 14:24:02.119 [TRACE] [WaveSerialHandler$ZWaveReceiveThread] - Response SENT 6
2025-07-14 14:24:02.120 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=00 
2025-07-14 14:24:02.120 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=00 
2025-07-14 14:24:02.120 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 627: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 245
2025-07-14 14:24:02.120 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2025-07-14 14:24:02.120 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 627: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 245
2025-07-14 14:24:02.120 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=00 
2025-07-14 14:24:02.120 [TRACE] [ve.internal.protocol.ZWaveController] - Incoming Message type = RESPONSE
2025-07-14 14:24:02.120 [TRACE] [l.serialmessage.SendDataMessageClass] - Handle Message Send Data Response
2025-07-14 14:24:02.120 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 6: sentData was not placed on stack.
2025-07-14 14:24:02.120 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 627: Transaction CANCELLED
2025-07-14 14:24:02.120 [TRACE] [e.internal.protocol.ZWaveTransaction] - TID 627: TransactionAdvance ST: CANCELLED
2025-07-14 14:24:02.120 [TRACE] [e.internal.protocol.ZWaveTransaction] - TID 627: TransactionAdvance WT: null
2025-07-14 14:24:02.120 [TRACE] [e.internal.protocol.ZWaveTransaction] - TID 627: TransactionAdvance RX: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=00 
2025-07-14 14:24:02.120 [TRACE] [e.internal.protocol.ZWaveTransaction] - TID 627: TransactionAdvance TO: CANCELLED
2025-07-14 14:24:02.120 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Holdoff Timer started...
2025-07-14 14:24:02.120 [TRACE] [nal.protocol.ZWaveTransactionManager] - STOP transaction timer
2025-07-14 14:24:02.120 [TRACE] [nal.protocol.ZWaveTransactionManager] - Holdoff Timer finishing in 250ms
2025-07-14 14:24:02.120 [TRACE] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
2025-07-14 14:24:02.120 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2025-07-14 14:24:02.120 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 6: CANCEL while sending message. Requeueing - 2 attempts left!
2025-07-14 14:24:02.120 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 627: Transaction RESET with 2 retries remaining.
2025-07-14 14:24:02.121 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 6: Adding to device queue
2025-07-14 14:24:02.121 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 6: Added 627 to queue - size 39
2025-07-14 14:24:02.121 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff true.
2025-07-14 14:24:02.121 [TRACE] [nal.protocol.ZWaveTransactionManager] - Holdoff Timer active - no send...
2025-07-14 14:24:02.121 [TRACE] [nal.protocol.ZWaveTransactionManager] - STOP transaction timer
2025-07-14 14:24:02.121 [TRACE] [nal.protocol.ZWaveTransactionManager] - Holdoff Timer finishing in 249ms
2025-07-14 14:24:02.121 [TRACE] [wave.internal.protocol.SerialMessage] - NODE 6: Creating empty message of class = SendData (0x13), type = Request
2025-07-14 14:24:02.121 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 6: TID 627: Transaction not completed
2025-07-14 14:24:02.121 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2025-07-14 14:24:02.121 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff true.
2025-07-14 14:24:02.121 [TRACE] [nal.protocol.ZWaveTransactionManager] - Holdoff Timer active - no send...
2025-07-14 14:24:02.121 [TRACE] [nal.protocol.ZWaveTransactionManager] - STOP transaction timer
2025-07-14 14:24:02.121 [TRACE] [nal.protocol.ZWaveTransactionManager] - Holdoff Timer finishing in 249ms
2025-07-14 14:24:02.133 [TRACE] [WaveSerialHandler$ZWaveReceiveThread] - Received SOF
2025-07-14 14:24:02.135 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 04 02 0E 0A 32 02 21 34 00 00 00 02 00 00 CA 
2025-07-14 14:24:02.135 [TRACE] [wave.internal.protocol.SerialMessage] - NODE 255: Creating new SerialMessage from buffer = 01 10 00 04 02 0E 0A 32 02 21 34 00 00 00 02 00 00 CA 
2025-07-14 14:24:02.135 [TRACE] [wave.internal.protocol.SerialMessage] - Calculated checksum = -54
2025-07-14 14:24:02.135 [TRACE] [wave.internal.protocol.SerialMessage] - NODE 255: Checksum matched
2025-07-14 14:24:02.135 [TRACE] [wave.internal.protocol.SerialMessage] - NODE 14: Message payload = 02 0E 0A 32 02 21 34 00 00 00 02 00 00 
2025-07-14 14:24:02.135 [TRACE] [WaveSerialHandler$ZWaveReceiveThread] - Message is valid, sending ACK
2025-07-14 14:24:02.137 [TRACE] [WaveSerialHandler$ZWaveReceiveThread] - Response SENT 6
2025-07-14 14:24:02.137 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=14, callback=2, payload=02 0E 0A 32 02 21 34 00 00 00 02 00 00 
2025-07-14 14:24:02.137 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=14, callback=2, payload=02 0E 0A 32 02 21 34 00 00 00 02 00 00 
2025-07-14 14:24:02.137 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null

ariela · July 14, 2025, 8:43pm

interesting. As usual, L1 troubleshooting is always my friend. It was the controller, I’ve moved a bit the key and now all the devices are reachable.

strange thing … I was able to see the power meter values, but not able to switch off / turn on. Crazy