Z-wave curtain found while scanning but without any channels

Neolinger · May 21, 2023, 8:24am

Sorry, but obviously I didn’t understand what to do. I used “contact us” on the website and opened a ticket but that doesn’t seem to be the right way. Can anybody tell me what I should do?
Best regards

apella12 · May 21, 2023, 2:12pm

No idea

What I would do is fill in the zwave DB the best you can. (Scan manual that you have, etc.) to clear the errors and ask for review. It will then get included with at least the one channel that you can test and adjust if necessary.

anon71759204 · May 21, 2023, 3:14pm

Return it (lack of information and lack of support by the manufacturer).

Neolinger · May 24, 2023, 12:58am

So @chris granted me write access, I updated the device and Chris approved it. Shouldn’t that be enough to find those channels while scanning? Or am I missing something?

Best regards

Neolinger · May 24, 2023, 1:10am

I read his message again an he wrote “I’ll take a look at the updates and get them exported”.
Is the export a missing step that makes the channels not to be found yet?

apella12 · May 24, 2023, 1:42pm

The export is one missing step. I see that the device has been merged into the binding, but not yet compiled (should happen soon). However, the problem you have is the updated jar only works on OH4.0. You have to have the binding with the new XML on your OH instance for the channel to appear.

One option is to copy the xml from the Zwave github and add it to your current jar along the lines of this post then update your binding using the documentation.

Another option would be to upgrade to OH4, then update the bundle when it is available on this site.

Neolinger · May 25, 2023, 8:51am

Hi Bob,
thank you for your help! What is the “right” way to do it? I would prefer to upgrade to OH4 but I didn’t find instructions to do so.
Best regards

apella12 · May 25, 2023, 1:20pm

Hard to say as it depends on what bindings and rule language you are using, but if you are just getting started and plan on expanding, going to 4.0 is the way to go. Are you using openhabian? Are you on a Debian 11 (Bullseye)? If yes, you can use the openhabian-config tool to upgrade to Java 17 and then switch your openhab related to “testing” from “stable”. It should pick up OH4.0M3

Neolinger · May 25, 2023, 5:44pm

Yes, I’m using openhabian. I installed OpenJDK17 but is there a way to set it as default in the config tool?
And now my real problem:
I only found the option to install Release, Mileston, Snapshot. Where do I switch from stable to testing?

Wolfgang_S · May 25, 2023, 8:15pm

Release = stable
Milestone = testing
Snapshot = unstable

apella12 · May 25, 2023, 9:19pm

If you installed it openhabian probably made it the default. You can check on the command line
sudo update-alternatives --list java

Neolinger · May 26, 2023, 8:38am

@Wolfgang_S Thanks for the explanation

@apella12 OpenJDK seems to be installed and also set as default but since there is the error message and it is not set as default in openhabian-config so I’m a little unsure if it’s save to install milestone. I will create a backup, test and report back.

Neolinger · May 26, 2023, 7:59pm

It worked. The channel is finally found but does not work. I still have write access to the database but I have no clue what needs to be changed to make it work.

apella12 · May 26, 2023, 9:12pm

Provide a debug level log while trying to manually operate. It should provide a clue as to what channel needs to be added.

Edit: I thought this might happen, but without documentation it is hard to guess.

Edit2: I’m thinking blinds_control instead of dimmer

Neolinger · May 27, 2023, 3:10pm

Hi Bob,

I thought I knew what you meant. I have set the log level to debug for the z-wave binding, operated the slider, but I don’t see anything in the openhab.log, so I assume that I didn’t understand it correctly.
What am I supposed to do?

Best regards

apella12 · May 27, 2023, 4:11pm

The slider in OH won’t work, I think. I meant use the remote that came with the device, or press a button/switch on the device to make it move in debug mode.

Neolinger · May 28, 2023, 3:37pm

I excluded and included the device just to be sure there is nothing left.
Now the thing got ID 118 and inclusion seems to be okay (at least to me)

2023-05-28 13:31:08.677 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - NODE 118: Adding slave.
2023-05-28 13:31:08.677 [DEBUG] [al.protocol.ZWaveInclusionController] - Inclusion event: Current state IncludeStart, new event IncludeSlaveFound
2023-05-28 13:31:08.678 [DEBUG] [al.protocol.ZWaveInclusionController] - NODE 118: Including node.
2023-05-28 13:31:08.678 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2023-05-28 13:31:08.678 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2023-05-28 13:31:12.191 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 4A A9 05 76 00 68 
2023-05-28 13:31:12.202 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=AddNodeToNetwork[74], type=Request[0], dest=5, callback=169, payload=A9 05 76 00 
2023-05-28 13:31:12.203 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=AddNodeToNetwork[74], type=Request[0], dest=5, callback=169, payload=A9 05 76 00 
2023-05-28 13:31:12.204 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2023-05-28 13:31:12.204 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 0
2023-05-28 13:31:12.205 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: null
2023-05-28 13:31:12.205 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=AddNodeToNetwork[74], type=Request[0], dest=5, callback=169, payload=A9 05 76 00 
2023-05-28 13:31:12.206 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - NODE 118: Add Node: Protocol done.
2023-05-28 13:31:12.207 [DEBUG] [al.protocol.ZWaveInclusionController] - Inclusion event: Current state IncludeSlaveFound, new event IncludeProtocolDone
2023-05-28 13:31:12.208 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Ending INCLUSION mode.
2023-05-28 13:31:12.209 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Added 4923 to queue - size 1
2023-05-28 13:31:12.209 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2023-05-28 13:31:12.210 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 4A 05 AA 1F 
2023-05-28 13:31:12.211 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 4A 05 AA 1F 
2023-05-28 13:31:12.213 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2023-05-28 13:31:12.214 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2023-05-28 13:31:12.222 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2023-05-28 13:31:12.223 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 4923: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 170
2023-05-28 13:31:12.224 [DEBUG] [al.protocol.ZWaveInclusionController] - NODE 118: Inclusion protocol completed.
2023-05-28 13:31:12.225 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 118: Creating new instance of command class COMMAND_CLASS_NO_OPERATION
2023-05-28 13:31:12.227 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 118: Command class COMMAND_CLASS_NO_OPERATION, endpoint 0 created
2023-05-28 13:31:12.228 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 118: Version = 1, version set. Enabling extra functionality.
2023-05-28 13:31:12.229 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 118: Adding command class COMMAND_CLASS_NO_OPERATION to the list of supported command classes.
2023-05-28 13:31:12.229 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 118: Creating new instance of command class COMMAND_CLASS_BASIC
2023-05-28 13:31:12.230 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 118: Command class COMMAND_CLASS_BASIC, endpoint 0 created
2023-05-28 13:31:12.232 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 118: Adding command class COMMAND_CLASS_BASIC to the list of supported command classes.
2023-05-28 13:31:12.233 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 118: Creating new instance of command class COMMAND_CLASS_ZWAVEPLUS_INFO
2023-05-28 13:31:12.234 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 118: Command class COMMAND_CLASS_ZWAVEPLUS_INFO, endpoint 0 created
2023-05-28 13:31:12.235 [DEBUG] [al.protocol.ZWaveInclusionController] - NODE 118: Inclusion is adding command class COMMAND_CLASS_ZWAVEPLUS_INFO.
2023-05-28 13:31:12.236 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 118: Adding command class COMMAND_CLASS_ZWAVEPLUS_INFO to the list of supported command classes.
2023-05-28 13:31:12.236 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 118: Creating new instance of command class COMMAND_CLASS_SWITCH_MULTILEVEL
2023-05-28 13:31:12.237 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 118: Command class COMMAND_CLASS_SWITCH_MULTILEVEL, endpoint 0 created
2023-05-28 13:31:12.238 [DEBUG] [al.protocol.ZWaveInclusionController] - NODE 118: Inclusion is adding command class COMMAND_CLASS_SWITCH_MULTILEVEL.
2023-05-28 13:31:12.239 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 118: Adding command class COMMAND_CLASS_SWITCH_MULTILEVEL to the list of supported command classes.
2023-05-28 13:31:12.239 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 118: Unsupported command class COMMAND_CLASS_WINDOW_COVERING
2023-05-28 13:31:12.240 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 118: Creating new instance of command class COMMAND_CLASS_CONFIGURATION
2023-05-28 13:31:12.241 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 118: Command class COMMAND_CLASS_CONFIGURATION, endpoint 0 created
2023-05-28 13:31:12.242 [DEBUG] [al.protocol.ZWaveInclusionController] - NODE 118: Inclusion is adding command class COMMAND_CLASS_CONFIGURATION.
2023-05-28 13:31:12.243 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 118: Adding command class COMMAND_CLASS_CONFIGURATION to the list of supported command classes.
2023-05-28 13:31:12.243 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 118: Creating new instance of command class COMMAND_CLASS_ASSOCIATION
2023-05-28 13:31:12.244 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 118: Command class COMMAND_CLASS_ASSOCIATION, endpoint 0 created
2023-05-28 13:31:12.245 [DEBUG] [al.protocol.ZWaveInclusionController] - NODE 118: Inclusion is adding command class COMMAND_CLASS_ASSOCIATION.
2023-05-28 13:31:12.245 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 118: Adding command class COMMAND_CLASS_ASSOCIATION to the list of supported command classes.
2023-05-28 13:31:12.246 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 118: Creating new instance of command class COMMAND_CLASS_MULTI_CHANNEL_ASSOCIATION
2023-05-28 13:31:12.247 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 118: Command class COMMAND_CLASS_MULTI_CHANNEL_ASSOCIATION, endpoint 0 created
2023-05-28 13:31:12.248 [DEBUG] [al.protocol.ZWaveInclusionController] - NODE 118: Inclusion is adding command class COMMAND_CLASS_MULTI_CHANNEL_ASSOCIATION.
2023-05-28 13:31:12.248 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 118: Adding command class COMMAND_CLASS_MULTI_CHANNEL_ASSOCIATION to the list of supported command classes.
2023-05-28 13:31:12.249 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 118: Creating new instance of command class COMMAND_CLASS_ASSOCIATION_GRP_INFO
2023-05-28 13:31:12.250 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 118: Command class COMMAND_CLASS_ASSOCIATION_GRP_INFO, endpoint 0 created
2023-05-28 13:31:12.251 [DEBUG] [al.protocol.ZWaveInclusionController] - NODE 118: Inclusion is adding command class COMMAND_CLASS_ASSOCIATION_GRP_INFO.
2023-05-28 13:31:12.251 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 118: Adding command class COMMAND_CLASS_ASSOCIATION_GRP_INFO to the list of supported command classes.
2023-05-28 13:31:12.252 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 118: Unsupported command class COMMAND_CLASS_TRANSPORT_SERVICE
2023-05-28 13:31:12.253 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 118: Creating new instance of command class COMMAND_CLASS_VERSION
2023-05-28 13:31:12.253 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 118: Command class COMMAND_CLASS_VERSION, endpoint 0 created
2023-05-28 13:31:12.254 [DEBUG] [al.protocol.ZWaveInclusionController] - NODE 118: Inclusion is adding command class COMMAND_CLASS_VERSION.
2023-05-28 13:31:12.255 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 118: Adding command class COMMAND_CLASS_VERSION to the list of supported command classes.
2023-05-28 13:31:12.255 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 118: Creating new instance of command class COMMAND_CLASS_MANUFACTURER_SPECIFIC
2023-05-28 13:31:12.256 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 118: Command class COMMAND_CLASS_MANUFACTURER_SPECIFIC, endpoint 0 created
2023-05-28 13:31:12.257 [DEBUG] [al.protocol.ZWaveInclusionController] - NODE 118: Inclusion is adding command class COMMAND_CLASS_MANUFACTURER_SPECIFIC.
2023-05-28 13:31:12.257 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 118: Adding command class COMMAND_CLASS_MANUFACTURER_SPECIFIC to the list of supported command classes.
2023-05-28 13:31:12.258 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 118: Creating new instance of command class COMMAND_CLASS_DEVICE_RESET_LOCALLY
2023-05-28 13:31:12.259 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 118: Command class COMMAND_CLASS_DEVICE_RESET_LOCALLY, endpoint 0 created
2023-05-28 13:31:12.259 [DEBUG] [al.protocol.ZWaveInclusionController] - NODE 118: Inclusion is adding command class COMMAND_CLASS_DEVICE_RESET_LOCALLY.
2023-05-28 13:31:12.260 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 118: Adding command class COMMAND_CLASS_DEVICE_RESET_LOCALLY to the list of supported command classes.
2023-05-28 13:31:12.260 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 118: Creating new instance of command class COMMAND_CLASS_INDICATOR
2023-05-28 13:31:12.261 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 118: Command class COMMAND_CLASS_INDICATOR, endpoint 0 created
2023-05-28 13:31:12.262 [DEBUG] [al.protocol.ZWaveInclusionController] - NODE 118: Inclusion is adding command class COMMAND_CLASS_INDICATOR.
2023-05-28 13:31:12.263 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 118: Adding command class COMMAND_CLASS_INDICATOR to the list of supported command classes.
2023-05-28 13:31:12.263 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 118: Creating new instance of command class COMMAND_CLASS_POWERLEVEL
2023-05-28 13:31:12.264 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 118: Command class COMMAND_CLASS_POWERLEVEL, endpoint 0 created
2023-05-28 13:31:12.265 [DEBUG] [al.protocol.ZWaveInclusionController] - NODE 118: Inclusion is adding command class COMMAND_CLASS_POWERLEVEL.
2023-05-28 13:31:12.266 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 118: Adding command class COMMAND_CLASS_POWERLEVEL to the list of supported command classes.
2023-05-28 13:31:12.266 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 4A AA 06 76 00 68 
2023-05-28 13:31:12.267 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 118: Creating new instance of command class COMMAND_CLASS_SECURITY
2023-05-28 13:31:12.267 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 118: Command class COMMAND_CLASS_SECURITY, endpoint 0 created
2023-05-28 13:31:12.268 [DEBUG] [al.protocol.ZWaveInclusionController] - NODE 118: Inclusion is adding command class COMMAND_CLASS_SECURITY_2.
2023-05-28 13:31:12.269 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 118: Adding command class COMMAND_CLASS_SECURITY to the list of supported command classes.
2023-05-28 13:31:12.270 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 118: Unsupported command class COMMAND_CLASS_SUPERVISION
2023-05-28 13:31:12.270 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 118: Creating new instance of command class COMMAND_CLASS_FIRMWARE_UPDATE_MD
2023-05-28 13:31:12.271 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 118: Command class COMMAND_CLASS_FIRMWARE_UPDATE_MD, endpoint 0 created
2023-05-28 13:31:12.272 [DEBUG] [al.protocol.ZWaveInclusionController] - NODE 118: Inclusion is adding command class COMMAND_CLASS_FIRMWARE_UPDATE_MD.
2023-05-28 13:31:12.272 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 118: Adding command class COMMAND_CLASS_FIRMWARE_UPDATE_MD to the list of supported command classes.
2023-05-28 13:31:12.273 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 118: Creating new instance of command class COMMAND_CLASS_APPLICATION_STATUS
2023-05-28 13:31:12.273 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 118: Command class COMMAND_CLASS_APPLICATION_STATUS, endpoint 0 created
2023-05-28 13:31:12.274 [DEBUG] [al.protocol.ZWaveInclusionController] - NODE 118: Inclusion is adding command class COMMAND_CLASS_APPLICATION_STATUS.
2023-05-28 13:31:12.275 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 118: Adding command class COMMAND_CLASS_APPLICATION_STATUS to the list of supported command classes.
2023-05-28 13:31:12.276 [DEBUG] [ve.internal.protocol.ZWaveController] - NODE 118: ZWaveController include node
2023-05-28 13:31:12.277 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 118: Starting initialisation from INCLUSION_START
2023-05-28 13:31:12.279 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2023-05-28 13:31:12.279 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 118: Node advancer: Node just included (3644154)
2023-05-28 13:31:12.280 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 4923: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 170
2023-05-28 13:31:12.280 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 118: Node advancer - advancing to IDENTIFY_NODE
2023-05-28 13:31:12.280 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2023-05-28 13:31:12.281 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2023-05-28 13:31:12.281 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 118: Node advancer: Initialisation starting from inclusion
2023-05-28 13:31:12.281 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2023-05-28 13:31:12.282 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: sendTransaction org.openhab.binding.zwave.internal.protocol.ZWaveSerialPayload@9135487
2023-05-28 13:31:12.282 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=AddNodeToNetwork[74], type=Request[0], dest=6, callback=170, payload=AA 06 76 00 
2023-05-28 13:31:12.283 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=AddNodeToNetwork[74], type=Request[0], dest=6, callback=170, payload=AA 06 76 00 
2023-05-28 13:31:12.284 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 4923: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 170
2023-05-28 13:31:12.284 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Added 4924 to queue - size 1
2023-05-28 13:31:12.285 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2023-05-28 13:31:12.286 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2023-05-28 13:31:12.287 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 4923: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 170
2023-05-28 13:31:12.287 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 4923: (Callback 170)
2023-05-28 13:31:12.288 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2023-05-28 13:31:12.289 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 4923: callback 170
2023-05-28 13:31:12.289 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=AddNodeToNetwork[74], type=Request[0], dest=6, callback=170, payload=AA 06 76 00 
2023-05-28 13:31:12.290 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Add Node: Done.
2023-05-28 13:31:12.291 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 118: Device discovered
2023-05-28 13:31:12.295 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing 'zwave:device:razberry:node118' to inbox.
2023-05-28 13:31:12.297 [DEBUG] [al.protocol.ZWaveInclusionController] - Inclusion event: Current state IncludeProtocolDone, new event IncludeDone
2023-05-28 13:31:12.297 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Ending INCLUSION mode.
2023-05-28 13:31:12.298 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Added 4925 to queue - size 2
2023-05-28 13:31:12.299 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2023-05-28 13:31:12.300 [DEBUG] [al.protocol.ZWaveInclusionController] - ZWave controller end exclusion
2023-05-28 13:31:12.301 [DEBUG] [ve.internal.protocol.ZWaveController] - ZWaveController include done
2023-05-28 13:31:12.302 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 4923: Advanced to DONE
2023-05-28 13:31:12.302 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Response processed after 79ms
2023-05-28 13:31:12.303 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: TID 4923: Transaction completed
2023-05-28 13:31:12.304 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: notifyTransactionResponse TID:4923 DONE
2023-05-28 13:31:12.306 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2023-05-28 13:31:12.307 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2023-05-28 13:31:12.308 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 04 00 41 76 CC 
2023-05-28 13:31:12.309 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 04 00 41 76 CC 
2023-05-28 13:31:12.311 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2023-05-28 13:31:12.312 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2023-05-28 13:31:12.313 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 01 41 D3 9C 01 04 11 07 EA 
2023-05-28 13:31:12.318 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2023-05-28 13:31:12.319 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 4924: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0
2023-05-28 13:31:12.319 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2023-05-28 13:31:12.320 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 4924: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0
2023-05-28 13:31:12.320 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2023-05-28 13:31:12.320 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2023-05-28 13:31:12.321 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2023-05-28 13:31:12.330 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=IdentifyNode[65], type=Response[1], dest=255, callback=0, payload=D3 9C 01 04 11 07 
2023-05-28 13:31:12.331 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=IdentifyNode[65], type=Response[1], dest=255, callback=0, payload=D3 9C 01 04 11 07 
2023-05-28 13:31:12.331 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 4924: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0
2023-05-28 13:31:12.331 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2023-05-28 13:31:12.332 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 4924: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0
2023-05-28 13:31:12.332 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=IdentifyNode[65], type=Response[1], dest=255, callback=0, payload=D3 9C 01 04 11 07 
2023-05-28 13:31:12.333 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 118: ProtocolInfo
2023-05-28 13:31:12.333 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 118: Listening = true
2023-05-28 13:31:12.334 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 118: Routing   = true
2023-05-28 13:31:12.334 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 118: Beaming   = true
2023-05-28 13:31:12.335 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 118: Version   = 4
2023-05-28 13:31:12.335 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 118: FLIRS     = false
2023-05-28 13:31:12.335 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 118: Security  = false
2023-05-28 13:31:12.336 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 118: Max Baud  = 40000
2023-05-28 13:31:12.336 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 118: Basic    = BASIC_TYPE_ROUTING_SLAVE
2023-05-28 13:31:12.336 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 118: Generic  = GENERIC_TYPE_SWITCH_MULTILEVEL
2023-05-28 13:31:12.337 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 118: Specific = SPECIFIC_TYPE_CLASS_C_MOTOR_CONTROL
2023-05-28 13:31:12.337 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 118: Creating new instance of command class COMMAND_CLASS_NO_OPERATION
2023-05-28 13:31:12.338 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 118: Command class COMMAND_CLASS_NO_OPERATION, endpoint 0 created
2023-05-28 13:31:12.338 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 118: Version = 1, version set. Enabling extra functionality.
2023-05-28 13:31:12.339 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 118: Adding command class COMMAND_CLASS_NO_OPERATION to the list of supported command classes.
2023-05-28 13:31:12.339 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 118: Creating new instance of command class COMMAND_CLASS_BASIC
2023-05-28 13:31:12.340 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 118: Command class COMMAND_CLASS_BASIC, endpoint 0 created
2023-05-28 13:31:12.340 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 118: Adding command class COMMAND_CLASS_BASIC to the list of supported command classes.
2023-05-28 13:31:12.341 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 4924: Transaction COMPLETED
2023-05-28 13:31:12.341 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Response processed after 22ms
2023-05-28 13:31:12.341 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: TID 4924: Transaction completed
2023-05-28 13:31:12.342 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: notifyTransactionResponse TID:4924 DONE
2023-05-28 13:31:12.343 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2023-05-28 13:31:12.343 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 4924: Transaction event listener: DONE: DONE -> UNINTIALIZED
2023-05-28 13:31:12.343 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2023-05-28 13:31:12.344 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 118: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@3eb3ff7
2023-05-28 13:31:12.344 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 4A 05 00 B5 
2023-05-28 13:31:12.344 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 118: Node Init transaction completed with response COMPLETE
2023-05-28 13:31:12.344 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 4A 05 00 B5 
2023-05-28 13:31:12.346 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2023-05-28 13:31:12.347 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2023-05-28 13:31:12.354 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2023-05-28 13:31:12.354 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 4925: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 0
2023-05-28 13:31:12.355 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2023-05-28 13:31:12.355 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 4925: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 0
2023-05-28 13:31:12.355 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2023-05-28 13:31:12.356 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2023-05-28 13:31:12.356 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2023-05-28 13:31:13.345 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 118: Node advancer - advancing to SECURITY_REPORT
2023-05-28 13:31:13.346 [DEBUG] [mmandclass.ZWaveSecurityCommandClass] - NODE 118: Updated networkKey
2023-05-28 13:31:13.346 [DEBUG] [mmandclass.ZWaveSecurityCommandClass] - NODE 118: setupNetworkKey useSchemeZero=false
2023-05-28 13:31:13.347 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 118: Skipping secure inclusion
2023-05-28 13:31:13.348 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 118: Node advancer - advancing to MANUFACTURER
2023-05-28 13:31:13.349 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 118: Node advancer: MANUFACTURER - send ManufacturerSpecific
2023-05-28 13:31:13.349 [DEBUG] [WaveManufacturerSpecificCommandClass] - NODE 118: Creating new message for command MANUFACTURER_SPECIFIC_GET
2023-05-28 13:31:13.349 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 118: ZWaveCommandClassTransactionPayload - send to node
2023-05-28 13:31:13.350 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 118: SECURITY NOT required on COMMAND_CLASS_MANUFACTURER_SPECIFIC
2023-05-28 13:31:13.350 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 118: Command Class COMMAND_CLASS_MANUFACTURER_SPECIFIC is NOT required to be secured
2023-05-28 13:31:13.351 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 118: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@46e9b1ee
2023-05-28 13:31:13.351 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 118: Adding to device queue
2023-05-28 13:31:13.352 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 118: Added 4926 to queue - size 14
2023-05-28 13:31:13.352 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2023-05-28 13:31:16.035 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0E 00 04 00 69 06 31 05 03 0A 00 00 BB 00 1C 
2023-05-28 13:31:16.046 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=105, callback=0, payload=00 69 06 31 05 03 0A 00 00 BB 00 
2023-05-28 13:31:16.047 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=105, callback=0, payload=00 69 06 31 05 03 0A 00 00 BB 00 
2023-05-28 13:31:16.047 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 4925: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 0
2023-05-28 13:31:16.047 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 105: Application Command Request (ALIVE:DONE)
2023-05-28 13:31:16.048 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 105: resetResendCount initComplete=true isDead=false
2023-05-28 13:31:16.048 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 105: Incoming command class COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 0
2023-05-28 13:31:16.048 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 105: SECURITY not supported
2023-05-28 13:31:16.048 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 105: Received COMMAND_CLASS_SENSOR_MULTILEVEL V5 SENSOR_MULTILEVEL_REPORT
2023-05-28 13:31:16.049 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 105: Sensor Type = Luminance(3), Scale = 1
2023-05-28 13:31:16.049 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 105: Sensor Value = 0E+1
2023-05-28 13:31:16.049 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 105: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent
2023-05-28 13:31:16.050 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 105: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SENSOR_MULTILEVEL, value=0E+1
2023-05-28 13:31:16.050 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 105: Updating channel state zwave:device:razberry:node105:sensor_luminance to 0 lx [QuantityType]
2023-05-28 13:31:16.051 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 105: Commands processed 1.
2023-05-28 13:31:16.051 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 105: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@2a1ead31.
2023-05-28 13:31:16.052 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
2023-05-28 13:31:16.053 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
2023-05-28 13:31:16.053 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2023-05-28 13:31:16.054 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2023-05-28 13:31:17.355 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 255: TID 4925: Timeout at state WAIT_REQUEST. 3 retries remaining.
2023-05-28 13:31:17.356 [DEBUG] [sactionManager$ZWaveTransactionTimer] - TID 4925: Transaction is current transaction, so clearing!!!!!
2023-05-28 13:31:17.357 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 4925: Transaction CANCELLED
2023-05-28 13:31:17.359 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: notifyTransactionResponse TID:4925 CANCELLED
2023-05-28 13:31:17.360 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2023-05-28 13:31:17.362 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 76 02 72 04 25 AC 6E 
2023-05-28 13:31:17.363 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 118: Sending REQUEST Message = 01 09 00 13 76 02 72 04 25 AC 6E 
2023-05-28 13:31:17.365 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2023-05-28 13:31:17.366 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2023-05-28 13:31:17.367 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2023-05-28 13:31:17.368 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2023-05-28 13:31:17.368 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2023-05-28 13:31:17.368 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2023-05-28 13:31:17.370 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
2023-05-28 13:31:17.374 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2023-05-28 13:31:17.375 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 4926: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 172
2023-05-28 13:31:17.376 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2023-05-28 13:31:17.386 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2023-05-28 13:31:17.387 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2023-05-28 13:31:17.387 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 4926: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 172
2023-05-28 13:31:17.388 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2023-05-28 13:31:17.388 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 4926: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 172
2023-05-28 13:31:17.389 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2023-05-28 13:31:17.390 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 118: sentData successfully placed on stack.
2023-05-28 13:31:17.391 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 4926: Advanced to WAIT_REQUEST
2023-05-28 13:31:17.391 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 118: TID 4926: Transaction not completed
2023-05-28 13:31:17.392 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2023-05-28 13:31:17.392 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2023-05-28 13:31:17.420 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 18 00 13 AC 00 00 05 01 BE A5 7F 7F 7F 01 01 04 18 00 00 00 02 01 00 00 27 
2023-05-28 13:31:17.434 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=172, payload=AC 00 00 05 01 BE A5 7F 7F 7F 01 01 04 18 00 00 00 02 01 00 00 
2023-05-28 13:31:17.435 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=172, payload=AC 00 00 05 01 BE A5 7F 7F 7F 01 01 04 18 00 00 00 02 01 00 00 
2023-05-28 13:31:17.436 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 4926: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 172
2023-05-28 13:31:17.437 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2023-05-28 13:31:17.437 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 4926: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 172
2023-05-28 13:31:17.438 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 4926: (Callback 172)
2023-05-28 13:31:17.438 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2023-05-28 13:31:17.439 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 4926: callback 172
2023-05-28 13:31:17.440 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=172, payload=AC 00 00 05 01 BE A5 7F 7F 7F 01 01 04 18 00 00 00 02 01 00 00 
2023-05-28 13:31:17.440 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 118: SendData Request. CallBack ID = 172, Status = Transmission complete and ACK received(0)
2023-05-28 13:31:17.441 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 4926: Advanced to WAIT_DATA
2023-05-28 13:31:17.441 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 118: TID 4926: Transaction not completed
2023-05-28 13:31:17.442 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2023-05-28 13:31:17.442 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2023-05-28 13:31:17.501 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 04 00 76 08 72 05 04 5A 00 04 05 05 BE 00 06 
2023-05-28 13:31:17.514 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=118, callback=0, payload=00 76 08 72 05 04 5A 00 04 05 05 BE 00 
2023-05-28 13:31:17.515 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=118, callback=0, payload=00 76 08 72 05 04 5A 00 04 05 05 BE 00 
2023-05-28 13:31:17.515 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2023-05-28 13:31:17.516 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 118: Application Command Request (ALIVE:MANUFACTURER)
2023-05-28 13:31:17.516 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 118: Incoming command class COMMAND_CLASS_MANUFACTURER_SPECIFIC, endpoint 0
2023-05-28 13:31:17.517 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 118: SECURITY NOT required on COMMAND_CLASS_MANUFACTURER_SPECIFIC
2023-05-28 13:31:17.517 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 118: Received COMMAND_CLASS_MANUFACTURER_SPECIFIC V0 MANUFACTURER_SPECIFIC_REPORT
2023-05-28 13:31:17.518 [DEBUG] [WaveManufacturerSpecificCommandClass] - NODE 118: Manufacturer ID = 0x45a
2023-05-28 13:31:17.518 [DEBUG] [WaveManufacturerSpecificCommandClass] - NODE 118: Device Type     = 0x4
2023-05-28 13:31:17.519 [DEBUG] [WaveManufacturerSpecificCommandClass] - NODE 118: Device ID       = 0x505
2023-05-28 13:31:17.519 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 118: Commands processed 1.
2023-05-28 13:31:17.520 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 118: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@142850c2.
2023-05-28 13:31:17.520 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 118: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@142850c2.
2023-05-28 13:31:17.520 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 118: notifyTransactionResponse TID:4926 DONE
2023-05-28 13:31:17.522 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
2023-05-28 13:31:17.522 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 4926: Transaction event listener: DONE: DONE -> UNINTIALIZED
2023-05-28 13:31:17.522 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2023-05-28 13:31:17.523 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 118: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@554d62d8
2023-05-28 13:31:17.523 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2023-05-28 13:31:17.523 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 118: Node Init transaction completed with response COMPLETE
2023-05-28 13:31:17.523 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2023-05-28 13:31:17.524 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 118: Node advancer - advancing to APP_VERSION
2023-05-28 13:31:17.524 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 118: Node advancer: APP_VERSION - send VersionMessage
2023-05-28 13:31:17.525 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 118: Creating new message for command VERSION_GET
2023-05-28 13:31:17.525 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 118: ZWaveCommandClassTransactionPayload - send to node
2023-05-28 13:31:17.526 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 118: SECURITY NOT required on COMMAND_CLASS_VERSION
2023-05-28 13:31:17.526 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 118: Command Class COMMAND_CLASS_VERSION is NOT required to be secured
2023-05-28 13:31:17.527 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 118: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@77e571b7
2023-05-28 13:31:17.527 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 118: Adding to device queue
2023-05-28 13:31:17.528 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 118: Added 4927 to queue - size 14
2023-05-28 13:31:17.528 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2023-05-28 13:31:17.529 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 76 02 86 11 25 AD 8E 
2023-05-28 13:31:17.530 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 118: Sending REQUEST Message = 01 09 00 13 76 02 86 11 25 AD 8E 
2023-05-28 13:31:17.532 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2023-05-28 13:31:17.533 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2023-05-28 13:31:17.533 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2023-05-28 13:31:17.534 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2023-05-28 13:31:17.535 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2023-05-28 13:31:17.535 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2023-05-28 13:31:17.536 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
2023-05-28 13:31:17.542 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2023-05-28 13:31:17.543 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 4927: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 173
2023-05-28 13:31:17.543 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2023-05-28 13:31:17.554 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2023-05-28 13:31:17.555 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2023-05-28 13:31:17.555 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 4927: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 173
2023-05-28 13:31:17.556 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2023-05-28 13:31:17.557 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 4927: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 173
2023-05-28 13:31:17.557 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2023-05-28 13:31:17.558 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 118: sentData successfully placed on stack.
2023-05-28 13:31:17.558 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 4927: Advanced to WAIT_REQUEST
2023-05-28 13:31:17.559 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 118: TID 4927: Transaction not completed
2023-05-28 13:31:17.559 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2023-05-28 13:31:17.560 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2023-05-28 13:31:17.630 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 18 00 13 AD 00 00 09 01 BE A5 7F 7F 7F 01 01 03 18 00 00 00 02 01 00 00 2D 
2023-05-28 13:31:17.642 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=173, payload=AD 00 00 09 01 BE A5 7F 7F 7F 01 01 03 18 00 00 00 02 01 00 00 
2023-05-28 13:31:17.643 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=173, payload=AD 00 00 09 01 BE A5 7F 7F 7F 01 01 03 18 00 00 00 02 01 00 00 
2023-05-28 13:31:17.644 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 4927: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 173
2023-05-28 13:31:17.645 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2023-05-28 13:31:17.645 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 4927: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 173
2023-05-28 13:31:17.646 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 4927: (Callback 173)
2023-05-28 13:31:17.647 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2023-05-28 13:31:17.647 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 4927: callback 173
2023-05-28 13:31:17.648 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=173, payload=AD 00 00 09 01 BE A5 7F 7F 7F 01 01 03 18 00 00 00 02 01 00 00 
2023-05-28 13:31:17.649 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 118: SendData Request. CallBack ID = 173, Status = Transmission complete and ACK received(0)
2023-05-28 13:31:17.650 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 4927: Advanced to WAIT_DATA
2023-05-28 13:31:17.650 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 118: TID 4927: Transaction not completed
2023-05-28 13:31:17.651 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2023-05-28 13:31:17.651 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2023-05-28 13:31:17.714 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 11 00 04 00 76 09 86 12 03 07 10 01 01 01 00 BE 00 AA 
2023-05-28 13:31:17.726 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=118, callback=0, payload=00 76 09 86 12 03 07 10 01 01 01 00 BE 00 
2023-05-28 13:31:17.727 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=118, callback=0, payload=00 76 09 86 12 03 07 10 01 01 01 00 BE 00 
2023-05-28 13:31:17.728 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2023-05-28 13:31:17.729 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 118: Application Command Request (ALIVE:APP_VERSION)
2023-05-28 13:31:17.729 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 118: Incoming command class COMMAND_CLASS_VERSION, endpoint 0
2023-05-28 13:31:17.730 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 118: SECURITY NOT required on COMMAND_CLASS_VERSION
2023-05-28 13:31:17.731 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 118: Received COMMAND_CLASS_VERSION V0 VERSION_REPORT
2023-05-28 13:31:17.731 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 118: Process Version Report
2023-05-28 13:31:17.732 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 118: Library Type        = 3 (Slave Enhanced)
2023-05-28 13:31:17.733 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 118: Protocol Version    = 7.16
2023-05-28 13:31:17.734 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 118: Application Version = 1.1
2023-05-28 13:31:17.735 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 118: Hardware Version     = 1
2023-05-28 13:31:17.735 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 118: Commands processed 1.
2023-05-28 13:31:17.736 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 118: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@17d68d15.
2023-05-28 13:31:17.736 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 118: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@17d68d15.
2023-05-28 13:31:17.737 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 118: notifyTransactionResponse TID:4927 DONE
2023-05-28 13:31:17.738 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
2023-05-28 13:31:17.739 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 4927: Transaction event listener: DONE: DONE -> UNINTIALIZED
2023-05-28 13:31:17.739 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2023-05-28 13:31:17.739 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 118: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@2dabfc3b
2023-05-28 13:31:17.740 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 118: Node Init transaction completed with response COMPLETE
2023-05-28 13:31:17.740 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2023-05-28 13:31:17.741 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2023-05-28 13:31:17.741 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 118: Node advancer - advancing to DISCOVERY_COMPLETE
2023-05-28 13:31:17.741 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 118: Device discovery completed
2023-05-28 13:31:17.745 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 118: Device discovery resolved to thingType zwave:zemismart_smartcurtain_00_000
2023-05-28 13:31:17.753 [DEBUG] [l.initialization.ZWaveNodeSerializer] - NODE 118: Serialise aborted as static stages not complete
2023-05-28 13:31:17.754 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 118: Node advancer - advancing to VERSION
2023-05-28 13:31:17.767 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 118: Node advancer: VERSION - checking COMMAND_CLASS_DEVICE_RESET_LOCALLY, version is 0
2023-05-28 13:31:17.768 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 118: Node advancer: VERSION - queued   COMMAND_CLASS_DEVICE_RESET_LOCALLY
2023-05-28 13:31:17.768 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 118: Creating new message for application command VERSION_COMMAND_CLASS_GET command class COMMAND_CLASS_DEVICE_RESET_LOCALLY
2023-05-28 13:31:17.769 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 118: ZWaveCommandClassTransactionPayload - send to node
2023-05-28 13:31:17.769 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 118: SECURITY NOT required on COMMAND_CLASS_VERSION
2023-05-28 13:31:17.770 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 118: Command Class COMMAND_CLASS_VERSION is NOT required to be secured
2023-05-28 13:31:17.770 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 118: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@26c64ea1
2023-05-28 13:31:17.771 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 118: Adding to device queue
2023-05-28 13:31:17.771 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 118: Added 4928 to queue - size 14
2023-05-28 13:31:17.771 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2023-05-28 13:31:17.773 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 76 03 86 13 5A 25 AE D7 
2023-05-28 13:31:17.774 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 118: Sending REQUEST Message = 01 0A 00 13 76 03 86 13 5A 25 AE D7 
2023-05-28 13:31:17.776 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2023-05-28 13:31:17.777 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2023-05-28 13:31:17.777 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2023-05-28 13:31:17.778 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2023-05-28 13:31:17.778 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2023-05-28 13:31:17.778 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2023-05-28 13:31:17.780 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
2023-05-28 13:31:17.790 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2023-05-28 13:31:17.791 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 4928: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 174
2023-05-28 13:31:17.791 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2023-05-28 13:31:17.802 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2023-05-28 13:31:17.803 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2023-05-28 13:31:17.803 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 4928: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 174
2023-05-28 13:31:17.804 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2023-05-28 13:31:17.804 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 4928: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 174
2023-05-28 13:31:17.805 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2023-05-28 13:31:17.805 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 118: sentData successfully placed on stack.
2023-05-28 13:31:17.805 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 4928: Advanced to WAIT_REQUEST
2023-05-28 13:31:17.806 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 118: TID 4928: Transaction not completed
2023-05-28 13:31:17.806 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2023-05-28 13:31:17.807 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2023-05-28 13:31:17.843 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 18 00 13 AE 00 00 06 01 BE A2 7F 7F 7F 01 01 03 18 00 00 00 02 01 00 00 26 
2023-05-28 13:31:17.854 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=174, payload=AE 00 00 06 01 BE A2 7F 7F 7F 01 01 03 18 00 00 00 02 01 00 00 
2023-05-28 13:31:17.855 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=174, payload=AE 00 00 06 01 BE A2 7F 7F 7F 01 01 03 18 00 00 00 02 01 00 00 
2023-05-28 13:31:17.856 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 4928: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 174
2023-05-28 13:31:17.856 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2023-05-28 13:31:17.856 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 4928: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 174
2023-05-28 13:31:17.857 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 4928: (Callback 174)
2023-05-28 13:31:17.857 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2023-05-28 13:31:17.857 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 4928: callback 174
2023-05-28 13:31:17.858 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=174, payload=AE 00 00 06 01 BE A2 7F 7F 7F 01 01 03 18 00 00 00 02 01 00 00 
2023-05-28 13:31:17.859 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 118: SendData Request. CallBack ID = 174, Status = Transmission complete and ACK received(0)
2023-05-28 13:31:17.859 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 4928: Advanced to WAIT_DATA
2023-05-28 13:31:17.859 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 118: TID 4928: Transaction not completed
2023-05-28 13:31:17.860 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2023-05-28 13:31:17.860 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2023-05-28 13:31:17.922 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0C 00 04 00 76 04 86 14 5A 01 BE 00 F2 
2023-05-28 13:31:17.934 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=118, callback=0, payload=00 76 04 86 14 5A 01 BE 00 
2023-05-28 13:31:17.935 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=118, callback=0, payload=00 76 04 86 14 5A 01 BE 00 
2023-05-28 13:31:17.935 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2023-05-28 13:31:17.936 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 118: Application Command Request (ALIVE:VERSION)
2023-05-28 13:31:17.936 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 118: Incoming command class COMMAND_CLASS_VERSION, endpoint 0
2023-05-28 13:31:17.936 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 118: SECURITY NOT required on COMMAND_CLASS_VERSION
2023-05-28 13:31:17.937 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 118: Received COMMAND_CLASS_VERSION V0 VERSION_COMMAND_CLASS_REPORT
2023-05-28 13:31:17.937 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 118: Process Version Command Class Report
2023-05-28 13:31:17.938 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 118: Requested Command Class = COMMAND_CLASS_DEVICE_RESET_LOCALLY, Version = 1
2023-05-28 13:31:17.938 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 118: Version = 1, version set. Enabling extra functionality.
2023-05-28 13:31:17.938 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 118: Commands processed 1.
2023-05-28 13:31:17.939 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 118: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@62e19eed.
2023-05-28 13:31:17.939 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 118: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@62e19eed.
2023-05-28 13:31:17.940 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 118: notifyTransactionResponse TID:4928 DONE
2023-05-28 13:31:17.941 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
2023-05-28 13:31:17.941 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 4928: Transaction event listener: DONE: DONE -> UNINTIALIZED
2023-05-28 13:31:17.941 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 118: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@18a3a303
2023-05-28 13:31:17.941 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2023-05-28 13:31:17.942 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 118: Node Init transaction completed with response COMPLETE
2023-05-28 13:31:17.942 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2023-05-28 13:31:17.943 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 118: Node advancer: VERSION - checking COMMAND_CLASS_INDICATOR, version is 0
2023-05-28 13:31:17.943 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2023-05-28 13:31:17.943 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 118: Node advancer: VERSION - queued   COMMAND_CLASS_INDICATOR
2023-05-28 13:31:17.943 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 118: Creating new message for application command VERSION_COMMAND_CLASS_GET command class COMMAND_CLASS_INDICATOR
2023-05-28 13:31:17.944 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 118: ZWaveCommandClassTransactionPayload - send to node
2023-05-28 13:31:17.944 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 118: SECURITY NOT required on COMMAND_CLASS_VERSION
2023-05-28 13:31:17.944 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 118: Command Class COMMAND_CLASS_VERSION is NOT required to be secured
2023-05-28 13:31:17.945 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 118: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@44171169
2023-05-28 13:31:17.945 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 118: Adding to device queue
2023-05-28 13:31:17.945 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 118: Added 4929 to queue - size 14
2023-05-28 13:31:17.946 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2023-05-28 13:31:17.946 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 76 03 86 13 87 25 AF 0B 
2023-05-28 13:31:17.947 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 118: Sending REQUEST Message = 01 0A 00 13 76 03 86 13 87 25 AF 0B 
2023-05-28 13:31:17.949 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2023-05-28 13:31:17.949 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2023-05-28 13:31:17.950 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2023-05-28 13:31:17.950 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2023-05-28 13:31:17.950 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2023-05-28 13:31:17.951 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2023-05-28 13:31:17.953 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
2023-05-28 13:31:17.962 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2023-05-28 13:31:17.962 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 4929: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 175
2023-05-28 13:31:17.963 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2023-05-28 13:31:17.974 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2023-05-28 13:31:17.975 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2023-05-28 13:31:17.975 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 4929: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 175
2023-05-28 13:31:17.975 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2023-05-28 13:31:17.976 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 4929: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 175
2023-05-28 13:31:17.976 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2023-05-28 13:31:17.977 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 118: sentData successfully placed on stack.
2023-05-28 13:31:17.977 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 4929: Advanced to WAIT_REQUEST
2023-05-28 13:31:17.977 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 118: TID 4929: Transaction not completed
2023-05-28 13:31:17.978 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2023-05-28 13:31:17.978 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2023-05-28 13:31:18.053 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 18 00 13 AF 00 00 0A 01 BE A7 7F 7F 7F 01 01 03 18 00 00 00 02 01 00 00 2E 
2023-05-28 13:31:18.066 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=175, payload=AF 00 00 0A 01 BE A7 7F 7F 7F 01 01 03 18 00 00 00 02 01 00 00 
2023-05-28 13:31:18.067 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=175, payload=AF 00 00 0A 01 BE A7 7F 7F 7F 01 01 03 18 00 00 00 02 01 00 00 
2023-05-28 13:31:18.067 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 4929: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 175
2023-05-28 13:31:18.068 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2023-05-28 13:31:18.068 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 4929: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 175
2023-05-28 13:31:18.069 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 4929: (Callback 175)
2023-05-28 13:31:18.069 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2023-05-28 13:31:18.069 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 4929: callback 175
2023-05-28 13:31:18.070 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=175, payload=AF 00 00 0A 01 BE A7 7F 7F 7F 01 01 03 18 00 00 00 02 01 00 00 
2023-05-28 13:31:18.071 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 118: SendData Request. CallBack ID = 175, Status = Transmission complete and ACK received(0)
2023-05-28 13:31:18.071 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 4929: Advanced to WAIT_DATA
2023-05-28 13:31:18.071 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 118: TID 4929: Transaction not completed
2023-05-28 13:31:18.072 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2023-05-28 13:31:18.072 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2023-05-28 13:31:18.131 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0C 00 04 00 76 04 86 14 87 03 BE 00 2D 
2023-05-28 13:31:18.142 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=118, callback=0, payload=00 76 04 86 14 87 03 BE 00 
2023-05-28 13:31:18.143 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=118, callback=0, payload=00 76 04 86 14 87 03 BE 00 
2023-05-28 13:31:18.143 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2023-05-28 13:31:18.144 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 118: Application Command Request (ALIVE:VERSION)
2023-05-28 13:31:18.144 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 118: Incoming command class COMMAND_CLASS_VERSION, endpoint 0
2023-05-28 13:31:18.144 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 118: SECURITY NOT required on COMMAND_CLASS_VERSION
2023-05-28 13:31:18.145 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 118: Received COMMAND_CLASS_VERSION V0 VERSION_COMMAND_CLASS_REPORT
2023-05-28 13:31:18.145 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 118: Process Version Command Class Report
2023-05-28 13:31:18.146 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 118: Requested Command Class = COMMAND_CLASS_INDICATOR, Version = 3
2023-05-28 13:31:18.146 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 118: Version = 3, version set. Enabling extra functionality.
2023-05-28 13:31:18.147 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 118: Commands processed 1.
2023-05-28 13:31:18.147 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 118: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@3dc9d0a5.
2023-05-28 13:31:18.147 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 118: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@3dc9d0a5.
2023-05-28 13:31:18.148 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 118: notifyTransactionResponse TID:4929 DONE
2023-05-28 13:31:18.149 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
2023-05-28 13:31:18.149 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 4929: Transaction event listener: DONE: DONE -> UNINTIALIZED
2023-05-28 13:31:18.149 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2023-05-28 13:31:18.150 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 118: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@36af77f4
2023-05-28 13:31:18.150 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2023-05-28 13:31:18.150 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 118: Node Init transaction completed with response COMPLETE
2023-05-28 13:31:18.151 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2023-05-28 13:31:18.151 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 118: Node advancer: VERSION - checking COMMAND_CLASS_ASSOCIATION_GRP_INFO, version is 0
2023-05-28 13:31:18.151 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 118: Node advancer: VERSION - queued   COMMAND_CLASS_ASSOCIATION_GRP_INFO
2023-05-28 13:31:18.152 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 118: Creating new message for application command VERSION_COMMAND_CLASS_GET command class COMMAND_CLASS_ASSOCIATION_GRP_INFO
2023-05-28 13:31:18.152 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 118: ZWaveCommandClassTransactionPayload - send to node
2023-05-28 13:31:18.152 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 118: SECURITY NOT required on COMMAND_CLASS_VERSION
2023-05-28 13:31:18.153 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 118: Command Class COMMAND_CLASS_VERSION is NOT required to be secured
2023-05-28 13:31:18.153 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 118: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@228894c6
2023-05-28 13:31:18.153 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 118: Adding to device queue
2023-05-28 13:31:18.154 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 118: Added 4930 to queue - size 14
2023-05-28 13:31:18.154 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2023-05-28 13:31:18.154 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 76 03 86 13 59 25 B0 CA 
2023-05-28 13:31:18.155 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 118: Sending REQUEST Message = 01 0A 00 13 76 03 86 13 59 25 B0 CA 
2023-05-28 13:31:18.157 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2023-05-28 13:31:18.158 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2023-05-28 13:31:18.158 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2023-05-28 13:31:18.159 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2023-05-28 13:31:18.159 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2023-05-28 13:31:18.160 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2023-05-28 13:31:18.161 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
2023-05-28 13:31:18.166 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2023-05-28 13:31:18.167 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 4930: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 176
2023-05-28 13:31:18.167 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2023-05-28 13:31:18.178 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2023-05-28 13:31:18.179 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2023-05-28 13:31:18.179 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 4930: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 176
2023-05-28 13:31:18.180 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2023-05-28 13:31:18.180 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 4930: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 176
2023-05-28 13:31:18.180 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2023-05-28 13:31:18.181 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 118: sentData successfully placed on stack.
2023-05-28 13:31:18.181 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 4930: Advanced to WAIT_REQUEST
2023-05-28 13:31:18.182 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 118: TID 4930: Transaction not completed
2023-05-28 13:31:18.182 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2023-05-28 13:31:18.182 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2023-05-28 13:31:18.261 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 18 00 13 B0 00 00 0A 01 BE A4 7F 7F 7F 01 01 03 18 00 00 00 02 01 00 00 32 
2023-05-28 13:31:18.274 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=176, payload=B0 00 00 0A 01 BE A4 7F 7F 7F 01 01 03 18 00 00 00 02 01 00 00 
2023-05-28 13:31:18.275 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=176, payload=B0 00 00 0A 01 BE A4 7F 7F 7F 01 01 03 18 00 00 00 02 01 00 00 
2023-05-28 13:31:18.275 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 4930: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 176
2023-05-28 13:31:18.276 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2023-05-28 13:31:18.276 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 4930: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 176
2023-05-28 13:31:18.277 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 4930: (Callback 176)
2023-05-28 13:31:18.277 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2023-05-28 13:31:18.277 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 4930: callback 176
2023-05-28 13:31:18.278 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=176, payload=B0 00 00 0A 01 BE A4 7F 7F 7F 01 01 03 18 00 00 00 02 01 00 00 
2023-05-28 13:31:18.279 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 118: SendData Request. CallBack ID = 176, Status = Transmission complete and ACK received(0)
2023-05-28 13:31:18.279 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 4930: Advanced to WAIT_DATA
2023-05-28 13:31:18.279 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 118: TID 4930: Transaction not completed
2023-05-28 13:31:18.280 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2023-05-28 13:31:18.280 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2023-05-28 13:31:18.341 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0C 00 04 00 76 04 86 14 59 03 BE 00 F3 
2023-05-28 13:31:18.354 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=118, callback=0, payload=00 76 04 86 14 59 03 BE 00 
2023-05-28 13:31:18.355 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=118, callback=0, payload=00 76 04 86 14 59 03 BE 00 
2023-05-28 13:31:18.355 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2023-05-28 13:31:18.356 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 118: Application Command Request (ALIVE:VERSION)
2023-05-28 13:31:18.356 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 118: Incoming command class COMMAND_CLASS_VERSION, endpoint 0
2023-05-28 13:31:18.356 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 118: SECURITY NOT required on COMMAND_CLASS_VERSION
2023-05-28 13:31:18.357 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 118: Received COMMAND_CLASS_VERSION V0 VERSION_COMMAND_CLASS_REPORT
2023-05-28 13:31:18.357 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 118: Process Version Command Class Report
2023-05-28 13:31:18.358 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 118: Requested Command Class = COMMAND_CLASS_ASSOCIATION_GRP_INFO, Version = 3
2023-05-28 13:31:18.358 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 118: Version = 1, version set to maximum supported by the binding. Enabling extra functionality.
2023-05-28 13:31:18.359 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 118: Commands processed 1.
2023-05-28 13:31:18.359 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 118: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@6b31299e.
2023-05-28 13:31:18.359 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 118: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@6b31299e.
2023-05-28 13:31:18.360 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 118: notifyTransactionResponse TID:4930 DONE
2023-05-28 13:31:18.361 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
2023-05-28 13:31:18.361 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 4930: Transaction event listener: DONE: DONE -> UNINTIALIZED
2023-05-28 13:31:18.362 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2023-05-28 13:31:18.362 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 118: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@27b6902f
2023-05-28 13:31:18.362 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2023-05-28 13:31:18.362 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 118: Node Init transaction completed with response COMPLETE
2023-05-28 13:31:18.363 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 118: Node advancer: VERSION - checking COMMAND_CLASS_BASIC, version is 0
2023-05-28 13:31:18.363 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2023-05-28 13:31:18.363 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 118: Node advancer: VERSION - queued   COMMAND_CLASS_BASIC
2023-05-28 13:31:18.364 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 118: Creating new message for application command VERSION_COMMAND_CLASS_GET command class COMMAND_CLASS_BASIC
2023-05-28 13:31:18.364 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 118: ZWaveCommandClassTransactionPayload - send to node
2023-05-28 13:31:18.364 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 118: SECURITY NOT required on COMMAND_CLASS_VERSION
2023-05-28 13:31:18.365 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 118: Command Class COMMAND_CLASS_VERSION is NOT required to be secured
2023-05-28 13:31:18.365 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 118: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@5bd641e9
2023-05-28 13:31:18.365 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 118: Adding to device queue
2023-05-28 13:31:18.366 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 118: Added 4931 to queue - size 14
2023-05-28 13:31:18.366 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2023-05-28 13:31:18.367 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 76 03 86 13 20 25 B1 B2 
2023-05-28 13:31:18.367 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 118: Sending REQUEST Message = 01 0A 00 13 76 03 86 13 20 25 B1 B2 
2023-05-28 13:31:18.369 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2023-05-28 13:31:18.370 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2023-05-28 13:31:18.370 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2023-05-28 13:31:18.371 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2023-05-28 13:31:18.371 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2023-05-28 13:31:18.372 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2023-05-28 13:31:18.373 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
2023-05-28 13:31:18.378 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2023-05-28 13:31:18.378 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 4931: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 177
2023-05-28 13:31:18.379 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2023-05-28 13:31:18.390 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2023-05-28 13:31:18.391 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2023-05-28 13:31:18.391 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 4931: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 177
2023-05-28 13:31:18.391 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2023-05-28 13:31:18.392 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 4931: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 177
2023-05-28 13:31:18.392 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2023-05-28 13:31:18.393 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 118: sentData successfully placed on stack.
2023-05-28 13:31:18.393 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 4931: Advanced to WAIT_REQUEST
2023-05-28 13:31:18.393 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 118: TID 4931: Transaction not completed
2023-05-28 13:31:18.394 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2023-05-28 13:31:18.394 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2023-05-28 13:31:18.473 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 18 00 13 B1 00 00 0A 01 BE A5 7F 7F 7F 01 01 03 18 00 00 00 02 01 00 00 32 
2023-05-28 13:31:18.486 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=177, payload=B1 00 00 0A 01 BE A5 7F 7F 7F 01 01 03 18 00 00 00 02 01 00 00 
2023-05-28 13:31:18.487 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=177, payload=B1 00 00 0A 01 BE A5 7F 7F 7F 01 01 03 18 00 00 00 02 01 00 00 
2023-05-28 13:31:18.487 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 4931: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 177
2023-05-28 13:31:18.488 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2023-05-28 13:31:18.488 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 4931: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 177
2023-05-28 13:31:18.488 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 4931: (Callback 177)
2023-05-28 13:31:18.489 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2023-05-28 13:31:18.489 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 4931: callback 177
2023-05-28 13:31:18.490 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=177, payload=B1 00 00 0A 01 BE A5 7F 7F 7F 01 01 03 18 00 00 00 02 01 00 00 
2023-05-28 13:31:18.490 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 118: SendData Request. CallBack ID = 177, Status = Transmission complete and ACK received(0)
2023-05-28 13:31:18.491 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 4931: Advanced to WAIT_DATA
2023-05-28 13:31:18.491 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 118: TID 4931: Transaction not completed
2023-05-28 13:31:18.492 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2023-05-28 13:31:18.492 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2023-05-28 13:31:18.551 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0C 00 04 00 76 04 86 14 20 02 BE 00 8B 
2023-05-28 13:31:18.562 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=118, callback=0, payload=00 76 04 86 14 20 02 BE 00 
2023-05-28 13:31:18.563 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=118, callback=0, payload=00 76 04 86 14 20 02 BE 00 
2023-05-28 13:31:18.563 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2023-05-28 13:31:18.564 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 118: Application Command Request (ALIVE:VERSION)
2023-05-28 13:31:18.564 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 118: Incoming command class COMMAND_CLASS_VERSION, endpoint 0
2023-05-28 13:31:18.564 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 118: SECURITY NOT required on COMMAND_CLASS_VERSION
2023-05-28 13:31:18.565 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 118: Received COMMAND_CLASS_VERSION V0 VERSION_COMMAND_CLASS_REPORT
2023-05-28 13:31:18.565 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 118: Process Version Command Class Report
2023-05-28 13:31:18.566 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 118: Requested Command Class = COMMAND_CLASS_BASIC, Version = 2
2023-05-28 13:31:18.566 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 118: Version = 1, version set to maximum supported by the binding. Enabling extra functionality.
2023-05-28 13:31:18.566 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 118: Commands processed 1.
2023-05-28 13:31:18.567 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 118: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@482cafc8.
2023-05-28 13:31:18.567 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 118: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@482cafc8.
2023-05-28 13:31:18.568 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 118: notifyTransactionResponse TID:4931 DONE
2023-05-28 13:31:18.568 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
2023-05-28 13:31:18.569 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 4931: Transaction event listener: DONE: DONE -> UNINTIALIZED
2023-05-28 13:31:18.569 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2023-05-28 13:31:18.569 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 118: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@160b8db0
2023-05-28 13:31:18.570 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 118: Node Init transaction completed with response COMPLETE
2023-05-28 13:31:18.570 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2023-05-28 13:31:18.570 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 118: Node advancer: VERSION - checking COMMAND_CLASS_MANUFACTURER_SPECIFIC, version is 0
2023-05-28 13:31:18.570 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2023-05-28 13:31:18.571 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 118: Node advancer: VERSION - queued   COMMAND_CLASS_MANUFACTURER_SPECIFIC
2023-05-28 13:31:18.571 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 118: Creating new message for application command VERSION_COMMAND_CLASS_GET command class COMMAND_CLASS_MANUFACTURER_SPECIFIC
2023-05-28 13:31:18.572 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 118: ZWaveCommandClassTransactionPayload - send to node
2023-05-28 13:31:18.572 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 118: SECURITY NOT required on COMMAND_CLASS_VERSION
2023-05-28 13:31:18.572 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 118: Command Class COMMAND_CLASS_VERSION is NOT required to be secured
2023-05-28 13:31:18.572 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 118: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@70431513
2023-05-28 13:31:18.573 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 118: Adding to device queue
2023-05-28 13:31:18.573 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 118: Added 4932 to queue - size 14
2023-05-28 13:31:18.574 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2023-05-28 13:31:18.574 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 76 03 86 13 72 25 B2 E3 
2023-05-28 13:31:18.575 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 118: Sending REQUEST Message = 01 0A 00 13 76 03 86 13 72 25 B2 E3 
2023-05-28 13:31:18.577 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2023-05-28 13:31:18.577 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2023-05-28 13:31:18.578 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2023-05-28 13:31:18.578 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2023-05-28 13:31:18.579 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2023-05-28 13:31:18.579 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2023-05-28 13:31:18.581 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
2023-05-28 13:31:18.586 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2023-05-28 13:31:18.586 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 4932: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 178
2023-05-28 13:31:18.587 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2023-05-28 13:31:18.598 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2023-05-28 13:31:18.599 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2023-05-28 13:31:18.599 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 4932: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 178
2023-05-28 13:31:18.599 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2023-05-28 13:31:18.600 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 4932: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 178
2023-05-28 13:31:18.600 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2023-05-28 13:31:18.601 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 118: sentData successfully placed on stack.
2023-05-28 13:31:18.601 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 4932: Advanced to WAIT_REQUEST
2023-05-28 13:31:18.601 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 118: TID 4932: Transaction not completed
2023-05-28 13:31:18.602 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2023-05-28 13:31:18.602 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2023-05-28 13:31:18.682 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 18 00 13 B2 00 00 0A 01 BE A5 7F 7F 7F 01 01 03 18 00 00 00 02 01 00 00 31 
2023-05-28 13:31:18.694 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=178, payload=B2 00 00 0A 01 BE A5 7F 7F 7F 01 01 03 18 00 00 00 02 01 00 00 
2023-05-28 13:31:18.695 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=178, payload=B2 00 00 0A 01 BE A5 7F 7F 7F 01 01 03 18 00 00 00 02 01 00 00 
2023-05-28 13:31:18.695 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 4932: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 178
2023-05-28 13:31:18.696 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2023-05-28 13:31:18.696 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 4932: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 178
2023-05-28 13:31:18.696 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 4932: (Callback 178)
2023-05-28 13:31:18.697 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2023-05-28 13:31:18.697 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 4932: callback 178
2023-05-28 13:31:18.698 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=178, payload=B2 00 00 0A 01 BE A5 7F 7F 7F 01 01 03 18 00 00 00 02 01 00 00 
2023-05-28 13:31:18.698 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 118: SendData Request. CallBack ID = 178, Status = Transmission complete and ACK received(0)
2023-05-28 13:31:18.699 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 4932: Advanced to WAIT_DATA
2023-05-28 13:31:18.699 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 118: TID 4932: Transaction not completed
2023-05-28 13:31:18.700 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2023-05-28 13:31:18.700 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2023-05-28 13:31:18.760 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0C 00 04 00 76 04 86 14 72 02 BE 00 D9 
2023-05-28 13:31:18.770 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=118, callback=0, payload=00 76 04 86 14 72 02 BE 00 
2023-05-28 13:31:18.771 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=118, callback=0, payload=00 76 04 86 14 72 02 BE 00 
2023-05-28 13:31:18.771 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2023-05-28 13:31:18.772 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 118: Application Command Request (ALIVE:VERSION)
2023-05-28 13:31:18.772 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 118: Incoming command class COMMAND_CLASS_VERSION, endpoint 0
2023-05-28 13:31:18.772 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 118: SECURITY NOT required on COMMAND_CLASS_VERSION
2023-05-28 13:31:18.773 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 118: Received COMMAND_CLASS_VERSION V0 VERSION_COMMAND_CLASS_REPORT
2023-05-28 13:31:18.773 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 118: Process Version Command Class Report
2023-05-28 13:31:18.774 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 118: Requested Command Class = COMMAND_CLASS_MANUFACTURER_SPECIFIC, Version = 2
2023-05-28 13:31:18.774 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 118: Version = 1, version set to maximum supported by the binding. Enabling extra functionality.
2023-05-28 13:31:18.775 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 118: Commands processed 1.
2023-05-28 13:31:18.775 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 118: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@26d446d6.
2023-05-28 13:31:18.775 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 118: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@26d446d6.

I waited a bit and used the remote control in the new log file which was created in the mean time Node 118 did not appear once.
Am I doing something wrong or is it the device?
BG

apella12 · May 28, 2023, 7:41pm

Hard to say. My thought was that once the device was included manual operation would send a message to OH even if it was not linked to a channel and that would show up in the logs. I’m cautiously optimistic that your change to blind_control in the zwave DB will show something.

Also I think you need to add at least one Association group to the DB and ensure the controller is there. (There were 4 in the XML). That might be an issue with no messages coming back. Do you see something like this on the UI page?
lifeline

Lastly, the inclusion log shown is not complete, so it is impossible to evaluate.

Edit: Does this look anything like your device/remote? (see the pdf)

Neolinger · May 29, 2023, 3:03pm

I was under the impression that you only need to add the XML file and it will be parsed, but since the Association Groups are missing, I guess that’s not the case. Unfortunately it is not clear to me what needs to be done now.

No, there are no Association Groups

I edited my previous post and added hopefully everything relevant

At first glance they look the same, but at the second you can see some differences, so they are not the same devices. This one could be a newer version because it also supports Z-Wave 700 and is from the same manufacturer(0x045a)?

apella12 · May 29, 2023, 6:19pm

I believe the blog says that the Association groups and parameters need to be added manually (Not parsed from the XML). Go to the device, sign in, go to Association group tab and hit the ADD. For group 1, set the controller item to “True”. That should help get the actions on the remote controller into OH.

Still not to the part I was looking for. The end will have the “serializing NODE118 to XML” or something like that. Also note on the same site as the DB there is a log viewer option in the upper right. Run your Debug files through that. it is easier to read.

They did have slightly different numbers. The interesting thing is it seems to be a manual on the remote. It would be great if your contacts could provide the same information for your device remote. Another option would be to use the information from that manual to add the association groups and parameters to the ZW DB and see what works once the build is completed.