OH 3.3 M3 Update - Zwave no more switching

norbert_jordan · April 4, 2022, 7:20am

anyone else having issues with the zwave binding after the update to M3 of v3.3 on RPi4
I’ve tried to reboot,…several times but nothing changed. Items are sending e.g. Power values but thats the ony thing thats still working, so its not a complete zwave blocking. OH logs do not show any zwave related problems.

Kind Regards
Norbert

mstormi · April 4, 2022, 7:36am

Check your zwave thing parameter settings, one-by-one.
There have been changes that result in that bad values are now no longer accepted as zwave parameters.
So bad settings that were tolerated before 3.3 now result in functional problems.

PS:
be more verbose please on posts like this else they’re pretty annoying to read and follow up on.
What version did you run earlier ? Show openhab.log of a “non-switch” event with zwave logging level set to debug. There are hints and you have to make them visible and communicate them. Unless you do, noone can take a guess or help otherwise.

norbert_jordan · April 4, 2022, 8:11am

Thanks for the reply. I took all the way from Milestone1 to Milestone3. I realized that with M2 there started a single switch to make problems, and that this was “faulty parameter” based. But its hard to find a solution when the debug log does not show any errors at all. So i ignored this wall plug switch. But now its different and no switch does turn off/on annymore.

In my case the zwave things are added by the binding automatically (scanning). But i do link items manually via items files.

Here is the log of a wall plug that should go ON OFF via GUI but does not react in reality.
Is there a more bullet proof way to fix this than to look into all devices config. Debug log does not help me at all from what i see and going into the GUI things parameters i do not see anything that would help to change. Even if it means to reconfigure everything. better than to spend others time too heavy.

2022-04-04 10:05:41.566 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 0E 32 02 21 34 00 00 01 DD 00 00 00 00 00 00
2022-04-04 10:05:41.567 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2022-04-04 10:05:41.568 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Application Command Request (ALIVE:PING)
2022-04-04 10:05:41.569 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: Incoming command class COMMAND_CLASS_METER, endpoint 0
2022-04-04 10:05:41.570 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY NOT required on COMMAND_CLASS_METER
2022-04-04 10:05:41.571 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 5: Received COMMAND_CLASS_METER V3 METER_REPORT
2022-04-04 10:05:41.572 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 5: Meter: Type=Electric(1), Scale=W(2), Value=47.7
2022-04-04 10:05:41.573 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveMeterValueEvent
2022-04-04 10:05:41.573 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_METER, value=47.7
2022-04-04 10:05:41.575 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Updating channel state zwave:device:9b5794294d:node5:meter_watts to 47.7 [DecimalType]
2022-04-04 10:05:41.577 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Commands processed 1.
2022-04-04 10:05:41.578 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@a77a13.
2022-04-04 10:05:41.579 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2022-04-04 10:05:41.580 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2022-04-04 10:05:41.580 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2022-04-04 10:05:41.581 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2022-04-04 10:05:41.659 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 14 00 04 00 07 0E 32 02 21 34 00 00 00 0B 00 00 00 00 00 00 C8
2022-04-04 10:05:41.661 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=7, callback=0, payload=00 07 0E 32 02 21 34 00 00 00 0B 00 00 00 00 00 00
2022-04-04 10:05:41.663 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=7, callback=0, payload=00 07 0E 32 02 21 34 00 00 00 0B 00 00 00 00 00 00
2022-04-04 10:05:41.663 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2022-04-04 10:05:41.664 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 7: Application Command Request (ALIVE:PING)
2022-04-04 10:05:41.665 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 7: Incoming command class COMMAND_CLASS_METER, endpoint 0
2022-04-04 10:05:41.666 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 7: SECURITY NOT required on COMMAND_CLASS_METER
2022-04-04 10:05:41.667 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 7: Received COMMAND_CLASS_METER V3 METER_REPORT
2022-04-04 10:05:41.667 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 14 00 04 00 05 0E 32 02 21 34 00 00 01 DD 00 00 00 00 00 00 1D
2022-04-04 10:05:41.668 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 7: Meter: Type=Electric(1), Scale=W(2), Value=1.1
2022-04-04 10:05:41.668 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 7: Got an event from Z-Wave network: ZWaveMeterValueEvent
2022-04-04 10:05:41.669 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 7: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_METER, value=1.1
2022-04-04 10:05:41.670 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 7: Updating channel state zwave:device:9b5794294d:node7:meter_watts to 1.1 [DecimalType]
2022-04-04 10:05:41.670 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 0E 32 02 21 34 00 00 01 DD 00 00 00 00 00 00
2022-04-04 10:05:41.672 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 7: Commands processed 1.
2022-04-04 10:05:41.673 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 7: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@23b526.
2022-04-04 10:05:41.673 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2022-04-04 10:05:41.674 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2022-04-04 10:05:41.675 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 0E 32 02 21 34 00 00 01 DD 00 00 00 00 00 00
2022-04-04 10:05:41.676 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2022-04-04 10:05:41.676 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Application Command Request (ALIVE:PING)
2022-04-04 10:05:41.677 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: Incoming command class COMMAND_CLASS_METER, endpoint 0
2022-04-04 10:05:41.678 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY NOT required on COMMAND_CLASS_METER
2022-04-04 10:05:41.678 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 5: Received COMMAND_CLASS_METER V3 METER_REPORT
2022-04-04 10:05:41.679 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 5: Meter: Type=Electric(1), Scale=W(2), Value=47.7
2022-04-04 10:05:41.680 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveMeterValueEvent
2022-04-04 10:05:41.681 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_METER, value=47.7
2022-04-04 10:05:41.681 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Updating channel state zwave:device:9b5794294d:node5:meter_watts to 47.7 [DecimalType]
2022-04-04 10:05:41.683 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Commands processed 1.
2022-04-04 10:05:41.684 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1d582d6.
2022-04-04 10:05:41.684 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2022-04-04 10:05:41.685 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2022-04-04 10:05:41.686 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2022-04-04 10:05:41.687 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2022-04-04 10:05:41.695 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 14 00 04 00 05 0E 32 02 21 34 00 00 01 DD 00 00 00 00 00 00 1D
2022-04-04 10:05:41.697 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 0E 32 02 21 34 00 00 01 DD 00 00 00 00 00 00
2022-04-04 10:05:41.698 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 0E 32 02 21 34 00 00 01 DD 00 00 00 00 00 00
2022-04-04 10:05:41.699 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2022-04-04 10:05:41.700 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Application Command Request (ALIVE:PING)
2022-04-04 10:05:41.701 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: Incoming command class COMMAND_CLASS_METER, endpoint 0
2022-04-04 10:05:41.701 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY NOT required on COMMAND_CLASS_METER
2022-04-04 10:05:41.702 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 5: Received COMMAND_CLASS_METER V3 METER_REPORT
2022-04-04 10:05:41.703 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 5: Meter: Type=Electric(1), Scale=W(2), Value=47.7
2022-04-04 10:05:41.703 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveMeterValueEvent
2022-04-04 10:05:41.704 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_METER, value=47.7
2022-04-04 10:05:41.705 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Updating channel state zwave:device:9b5794294d:node5:meter_watts to 47.7 [DecimalType]
2022-04-04 10:05:41.706 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Commands processed 1.
2022-04-04 10:05:41.707 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@f8fd9d.
2022-04-04 10:05:41.708 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2022-04-04 10:05:41.708 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2022-04-04 10:05:41.709 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2022-04-04 10:05:41.710 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2022-04-04 10:05:41.724 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 14 00 04 10 07 0E 32 02 21 34 00 00 00 0E 00 00 00 00 00 00 DD
2022-04-04 10:05:41.726 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=7, callback=16, payload=10 07 0E 32 02 21 34 00 00 00 0E 00 00 00 00 00 00
2022-04-04 10:05:41.727 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=7, callback=16, payload=10 07 0E 32 02 21 34 00 00 00 0E 00 00 00 00 00 00
2022-04-04 10:05:41.728 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2022-04-04 10:05:41.728 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 7: Application Command Request (ALIVE:PING)
2022-04-04 10:05:41.729 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 7: Incoming command class COMMAND_CLASS_METER, endpoint 0
2022-04-04 10:05:41.730 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 7: SECURITY NOT required on COMMAND_CLASS_METER
2022-04-04 10:05:41.731 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 7: Received COMMAND_CLASS_METER V3 METER_REPORT
2022-04-04 10:05:41.732 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 7: Meter: Type=Electric(1), Scale=W(2), Value=1.4
2022-04-04 10:05:41.733 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 14 00 04 00 05 0E 32 02 21 34 00 00 01 DD 00 00 00 00 00 00 1D

mstormi · April 4, 2022, 8:51am

The log only shows METER messages if I read correctly.
Those are unrelated to any switching activity hence not relevant to your problem.
So is your observation that there’s NO outgoing message? Re-try switching and confirm there’s NO message going out immediately that moment you switch. Cross-check with a working switch.

If so, 1) work through all that things’s zwave parameter to ensure all have only valid values (UI should notify of invalid settings).
Then 2) reinitialize the node via UI. If that does not work, delete the thing and rediscover it.

kristofejro · April 4, 2022, 10:44am

There have been changes that result in that bad values are now no longer accepted as zwave parameters.
So bad settings that were tolerated before 3.3 now result in functional problems.

Where can I read more about it? I can’t see such information regarding z-wave in Release openHAB 3.3.0 Milestone 3 · openhab/openhab-distro · GitHub

For me z-wave seems to be working just fine after upgrade to 3.3.0M3

mstormi · April 4, 2022, 11:32am

I have no good reference but if you search the forum for “validation”, “borked” you should find some readings.

Michael_Grymonprez · May 8, 2022, 8:13pm

Same here. I had no time yet to investigate.

system · June 19, 2022, 12:14pm

This topic was automatically closed 41 days after the last reply. New replies are no longer allowed.