nsautomate@192.168.1.3's password: Welcome to Ubuntu 18.04.2 LTS (GNU/Linux 4.15.0-72-generic x86_64) * Documentation: https://help.ubuntu.com * Management: https://landscape.canonical.com * Support: https://ubuntu.com/advantage System information as of Thu Dec 5 08:43:57 AEDT 2019 System load: 0.02 Processes: 118 Usage of /: 7.6% of 109.05GB Users logged in: 1 Memory usage: 25% IP address for enp3s0: 192.168.1.3 Swap usage: 0% * Overheard at KubeCon: "microk8s.status just blew my mind". https://microk8s.io/docs/commands#microk8s.status * Canonical Livepatch is available for installation. - Reduce system reboots and improve kernel security. Activate at: https://ubuntu.com/livepatch 106 packages can be updated. 0 updates are security updates. Last login: Thu Dec 5 08:43:53 2019 from 10.8.0.6 nsautomate@ihp:~$ cd /etc/openhab2/items nsautomate@ihp:/etc/openhab2/items$ sudo nano lights.items nsautomate@ihp:/etc/openhab2/items$ sudo nano lights.items nsautomate@ihp:/etc/openhab2/items$ tail -f /var/log/openhab2/zwave.log 2019-12-05 08:47:43.302 [DEBUG] [e.internal.protocol.commandclass.ZWaveCommandClass] - NODE 19: Received COMMAND_CLASS_HAIL V0 HAIL 2019-12-05 08:47:43.303 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 19: Got an event from Z-Wave network: ZWaveDelayedPollEvent 2019-12-05 08:47:43.303 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 19: Polling initialised at 86400 seconds - start in 75 milliseconds. 2019-12-05 08:47:43.303 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 19: Commands processed 1. 2019-12-05 08:47:43.304 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 19: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@3a16ff59. 2019-12-05 08:47:43.304 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-12-05 08:47:43.304 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-12-05 08:47:43.304 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-12-05 08:47:43.304 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-12-05 08:47:43.379 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 19: Polling... 2019-12-05 08:48:16.556 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 1D 00 49 84 13 17 04 10 01 5E 25 27 32 81 71 60 8E 2C 2B 70 86 72 73 85 59 7A 5A EF 82 2F 2019-12-05 08:48:16.559 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationUpdate[73], type=Request[0], dest=19, callback=132, payload=84 13 17 04 10 01 5E 25 27 32 81 71 60 8E 2C 2B 70 86 72 73 85 59 7A 5A EF 82 2019-12-05 08:48:16.561 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationUpdate[73], type=Request[0], dest=19, callback=132, payload=84 13 17 04 10 01 5E 25 27 32 81 71 60 8E 2C 2B 70 86 72 73 85 59 7A 5A EF 82 2019-12-05 08:48:16.562 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null 2019-12-05 08:48:16.562 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 0 2019-12-05 08:48:16.562 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: null 2019-12-05 08:48:16.562 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=ApplicationUpdate[73], type=Request[0], dest=19, callback=132, payload=84 13 17 04 10 01 5E 25 27 32 81 71 60 8E 2C 2B 70 86 72 73 85 59 7A 5A EF 82 2019-12-05 08:48:16.563 [DEBUG] [otocol.serialmessage.ApplicationUpdateMessageClass] - NODE 19: Application update request. Node information received. Transaction null 2019-12-05 08:48:16.563 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 19: resetResendCount initComplete=true isDead=false 2019-12-05 08:48:16.563 [DEBUG] [otocol.serialmessage.ApplicationUpdateMessageClass] - NODE 19: Application update - no transaction. 2019-12-05 08:48:16.564 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-12-05 08:48:16.564 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-12-05 08:48:31.403 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 25: Command received zwave:device:4711b0e7:node25:switch_dimmer --> 0 [PercentType] 2019-12-05 08:48:31.404 [DEBUG] [col.commandclass.ZWaveMultiLevelSwitchCommandClass] - NODE 25: Creating new message for command SWITCH_MULTILEVEL_SET 2019-12-05 08:48:31.404 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 25: SECURITY not supported 2019-12-05 08:48:31.404 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 25: Command Class COMMAND_CLASS_SWITCH_MULTILEVEL is NOT required to be secured 2019-12-05 08:48:31.404 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 25: Adding to device queue 2019-12-05 08:48:31.404 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 25: Added 628 to queue - size 4 2019-12-05 08:48:31.404 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-12-05 08:48:31.404 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 19 03 26 01 00 25 43 BD 2019-12-05 08:48:31.404 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 25: Sending REQUEST Message = 01 0A 00 13 19 03 26 01 00 25 43 BD 2019-12-05 08:48:31.405 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT 2019-12-05 08:48:31.405 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 628: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 67 2019-12-05 08:48:31.405 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2019-12-05 08:48:31.406 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-12-05 08:48:31.406 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-12-05 08:48:31.407 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 628: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 67 2019-12-05 08:48:31.407 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK 2019-12-05 08:48:31.407 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-12-05 08:48:31.408 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-12-05 08:48:31.412 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2019-12-05 08:48:31.412 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-12-05 08:48:31.413 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-12-05 08:48:31.413 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 628: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 67 2019-12-05 08:48:31.413 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-12-05 08:48:31.414 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 628: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 67 2019-12-05 08:48:31.415 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-12-05 08:48:31.415 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 25: sentData successfully placed on stack. 2019-12-05 08:48:31.416 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 628: Advanced to WAIT_REQUEST 2019-12-05 08:48:31.416 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 25: TID 628: Transaction not completed 2019-12-05 08:48:31.416 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-12-05 08:48:31.416 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-12-05 08:48:31.555 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 43 00 00 0F A7 2019-12-05 08:48:31.558 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=67, payload=43 00 00 0F 2019-12-05 08:48:31.560 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=67, payload=43 00 00 0F 2019-12-05 08:48:31.561 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 628: [WAIT_REQUEST] priority=Set, requiresResponse=true, callback: 67 2019-12-05 08:48:31.562 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-12-05 08:48:31.563 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 628: [WAIT_REQUEST] priority=Set, requiresResponse=true, callback: 67 2019-12-05 08:48:31.564 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 628: (Callback 67) 2019-12-05 08:48:31.565 [DEBUG] [ernal.protocol.serialmessage.ZWaveCommandProcessor] - Callback match! 2019-12-05 08:48:31.565 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Correlated to TID 628: callback 67 2019-12-05 08:48:31.565 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=67, payload=43 00 00 0F 2019-12-05 08:48:31.565 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 25: SendData Request. CallBack ID = 67, Status = Transmission complete and ACK received(0) 2019-12-05 08:48:31.566 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 25: resetResendCount initComplete=true isDead=false 2019-12-05 08:48:31.566 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 628: Transaction COMPLETED 2019-12-05 08:48:31.566 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 25: Response processed after 161ms 2019-12-05 08:48:31.567 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 25: TID 628: Transaction completed 2019-12-05 08:48:31.567 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 25: notifyTransactionResponse TID:628 DONE 2019-12-05 08:48:31.567 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 25: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2019-12-05 08:48:31.568 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-12-05 08:48:31.568 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-12-05 08:48:41.466 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 1D 00 49 84 13 17 04 10 01 5E 25 27 32 81 71 60 8E 2C 2B 70 86 72 73 85 59 7A 5A EF 82 2F 2019-12-05 08:48:41.468 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationUpdate[73], type=Request[0], dest=19, callback=132, payload=84 13 17 04 10 01 5E 25 27 32 81 71 60 8E 2C 2B 70 86 72 73 85 59 7A 5A EF 82 2019-12-05 08:48:41.469 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationUpdate[73], type=Request[0], dest=19, callback=132, payload=84 13 17 04 10 01 5E 25 27 32 81 71 60 8E 2C 2B 70 86 72 73 85 59 7A 5A EF 82 2019-12-05 08:48:41.470 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null 2019-12-05 08:48:41.470 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 0 2019-12-05 08:48:41.471 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: null 2019-12-05 08:48:41.472 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=ApplicationUpdate[73], type=Request[0], dest=19, callback=132, payload=84 13 17 04 10 01 5E 25 27 32 81 71 60 8E 2C 2B 70 86 72 73 85 59 7A 5A EF 82 2019-12-05 08:48:41.472 [DEBUG] [otocol.serialmessage.ApplicationUpdateMessageClass] - NODE 19: Application update request. Node information received. Transaction null 2019-12-05 08:48:41.473 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 19: resetResendCount initComplete=true isDead=false 2019-12-05 08:48:41.473 [DEBUG] [otocol.serialmessage.ApplicationUpdateMessageClass] - NODE 19: Application update - no transaction. 2019-12-05 08:48:41.473 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-12-05 08:48:41.474 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-12-05 08:48:51.226 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 1D 00 49 84 13 17 04 10 01 5E 25 27 32 81 71 60 8E 2C 2B 70 86 72 73 85 59 7A 5A EF 82 2F 2019-12-05 08:48:51.229 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationUpdate[73], type=Request[0], dest=19, callback=132, payload=84 13 17 04 10 01 5E 25 27 32 81 71 60 8E 2C 2B 70 86 72 73 85 59 7A 5A EF 82 2019-12-05 08:48:51.231 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationUpdate[73], type=Request[0], dest=19, callback=132, payload=84 13 17 04 10 01 5E 25 27 32 81 71 60 8E 2C 2B 70 86 72 73 85 59 7A 5A EF 82 2019-12-05 08:48:51.232 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null 2019-12-05 08:48:51.233 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 0 2019-12-05 08:48:51.234 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: null 2019-12-05 08:48:51.235 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=ApplicationUpdate[73], type=Request[0], dest=19, callback=132, payload=84 13 17 04 10 01 5E 25 27 32 81 71 60 8E 2C 2B 70 86 72 73 85 59 7A 5A EF 82 2019-12-05 08:48:51.237 [DEBUG] [otocol.serialmessage.ApplicationUpdateMessageClass] - NODE 19: Application update request. Node information received. Transaction null 2019-12-05 08:48:51.237 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 19: resetResendCount initComplete=true isDead=false 2019-12-05 08:48:51.238 [DEBUG] [otocol.serialmessage.ApplicationUpdateMessageClass] - NODE 19: Application update - no transaction. 2019-12-05 08:48:51.238 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-12-05 08:48:51.238 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-12-05 08:48:51.362 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 1D 00 49 84 13 17 04 10 01 5E 25 27 32 81 71 60 8E 2C 2B 70 86 72 73 85 59 7A 5A EF 82 2F 2019-12-05 08:48:51.363 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationUpdate[73], type=Request[0], dest=19, callback=132, payload=84 13 17 04 10 01 5E 25 27 32 81 71 60 8E 2C 2B 70 86 72 73 85 59 7A 5A EF 82 2019-12-05 08:48:51.364 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationUpdate[73], type=Request[0], dest=19, callback=132, payload=84 13 17 04 10 01 5E 25 27 32 81 71 60 8E 2C 2B 70 86 72 73 85 59 7A 5A EF 82 2019-12-05 08:48:51.365 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null 2019-12-05 08:48:51.365 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 0 2019-12-05 08:48:51.365 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: null 2019-12-05 08:48:51.365 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=ApplicationUpdate[73], type=Request[0], dest=19, callback=132, payload=84 13 17 04 10 01 5E 25 27 32 81 71 60 8E 2C 2B 70 86 72 73 85 59 7A 5A EF 82 2019-12-05 08:48:51.366 [DEBUG] [otocol.serialmessage.ApplicationUpdateMessageClass] - NODE 19: Application update request. Node information received. Transaction null 2019-12-05 08:48:51.366 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 19: resetResendCount initComplete=true isDead=false 2019-12-05 08:48:51.366 [DEBUG] [otocol.serialmessage.ApplicationUpdateMessageClass] - NODE 19: Application update - no transaction. 2019-12-05 08:48:51.367 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-12-05 08:48:51.367 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-12-05 08:49:06.410 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0F 00 04 00 04 09 71 05 00 00 00 FF 07 08 00 7D 2019-12-05 08:49:06.411 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=4, callback=0, payload=00 04 09 71 05 00 00 00 FF 07 08 00 2019-12-05 08:49:06.412 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=4, callback=0, payload=00 04 09 71 05 00 00 00 FF 07 08 00 2019-12-05 08:49:06.412 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null 2019-12-05 08:49:06.412 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 4: Application Command Request (ALIVE:REQUEST_NIF) 2019-12-05 08:49:06.413 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 4: Incoming command class COMMAND_CLASS_ALARM, endpoint 0 2019-12-05 08:49:06.413 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 4: SECURITY NOT required on COMMAND_CLASS_ALARM 2019-12-05 08:49:06.413 [DEBUG] [e.internal.protocol.commandclass.ZWaveCommandClass] - NODE 4: Received COMMAND_CLASS_ALARM V5 NOTIFICATION_REPORT 2019-12-05 08:49:06.413 [DEBUG] [ernal.protocol.commandclass.ZWaveAlarmCommandClass] - NODE 4: NOTIFICATION report - 0 = 0, event=8, status=255, plen=0 2019-12-05 08:49:06.413 [DEBUG] [ernal.protocol.commandclass.ZWaveAlarmCommandClass] - NODE 4: Alarm Type = BURGLAR (0) 2019-12-05 08:49:06.414 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 4: Got an event from Z-Wave network: ZWaveAlarmValueEvent 2019-12-05 08:49:06.414 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 4: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_ALARM, value=255 2019-12-05 08:49:06.414 [DEBUG] [nding.zwave.internal.converter.ZWaveAlarmConverter] - NODE 4: Alarm converter processing NOTIFICATION 2019-12-05 08:49:06.414 [DEBUG] [nding.zwave.internal.converter.ZWaveAlarmConverter] - NODE 4: Alarm converter NOTIFICATION event is 8, type OnOffType 2019-12-05 08:49:06.415 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 4: Updating channel state zwave:device:4711b0e7:node4:alarm_motion to ON [OnOffType] 2019-12-05 08:49:06.415 [DEBUG] [nding.zwave.internal.converter.ZWaveAlarmConverter] - NODE 4: Alarm converter processing NOTIFICATION 2019-12-05 08:49:06.415 [DEBUG] [nding.zwave.internal.converter.ZWaveAlarmConverter] - NODE 4: Alarm converter NOTIFICATION event is 8, type OnOffType 2019-12-05 08:49:06.416 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 4: Commands processed 1. 2019-12-05 08:49:06.416 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 4: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1eb4ce82. 2019-12-05 08:49:06.416 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-12-05 08:49:06.418 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-12-05 08:49:06.419 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-12-05 08:49:06.419 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-12-05 08:49:08.497 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0F 00 04 00 05 09 71 05 00 00 00 FF 07 08 00 7C 2019-12-05 08:49:08.500 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 09 71 05 00 00 00 FF 07 08 00 2019-12-05 08:49:08.503 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 09 71 05 00 00 00 FF 07 08 00 2019-12-05 08:49:08.504 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null 2019-12-05 08:49:08.504 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Application Command Request (ALIVE:REQUEST_NIF) 2019-12-05 08:49:08.505 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: Incoming command class COMMAND_CLASS_ALARM, endpoint 0 2019-12-05 08:49:08.505 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY NOT required on COMMAND_CLASS_ALARM 2019-12-05 08:49:08.505 [DEBUG] [e.internal.protocol.commandclass.ZWaveCommandClass] - NODE 5: Received COMMAND_CLASS_ALARM V5 NOTIFICATION_REPORT 2019-12-05 08:49:08.505 [DEBUG] [ernal.protocol.commandclass.ZWaveAlarmCommandClass] - NODE 5: NOTIFICATION report - 0 = 0, event=8, status=255, plen=0 2019-12-05 08:49:08.505 [DEBUG] [ernal.protocol.commandclass.ZWaveAlarmCommandClass] - NODE 5: Alarm Type = BURGLAR (0) 2019-12-05 08:49:08.505 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveAlarmValueEvent 2019-12-05 08:49:08.506 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_ALARM, value=255 2019-12-05 08:49:08.506 [DEBUG] [nding.zwave.internal.converter.ZWaveAlarmConverter] - NODE 5: Alarm converter processing NOTIFICATION 2019-12-05 08:49:08.506 [DEBUG] [nding.zwave.internal.converter.ZWaveAlarmConverter] - NODE 5: Alarm converter NOTIFICATION event is 8, type OnOffType 2019-12-05 08:49:08.506 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Updating channel state zwave:device:4711b0e7:node5:alarm_motion to ON [OnOffType] 2019-12-05 08:49:08.506 [DEBUG] [nding.zwave.internal.converter.ZWaveAlarmConverter] - NODE 5: Alarm converter processing NOTIFICATION 2019-12-05 08:49:08.507 [DEBUG] [nding.zwave.internal.converter.ZWaveAlarmConverter] - NODE 5: Alarm converter NOTIFICATION event is 8, type OnOffType 2019-12-05 08:49:08.507 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Commands processed 1. 2019-12-05 08:49:08.507 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@5b7d9c70. 2019-12-05 08:49:08.507 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-12-05 08:49:08.507 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-12-05 08:49:08.507 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-12-05 08:49:08.507 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-12-05 08:49:39.016 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 04 00 05 0A 71 05 00 00 00 FF 07 00 01 08 61 2019-12-05 08:49:39.020 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 0A 71 05 00 00 00 FF 07 00 01 08 2019-12-05 08:49:39.023 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 0A 71 05 00 00 00 FF 07 00 01 08 2019-12-05 08:49:39.024 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null 2019-12-05 08:49:39.025 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Application Command Request (ALIVE:REQUEST_NIF) 2019-12-05 08:49:39.025 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: Incoming command class COMMAND_CLASS_ALARM, endpoint 0 2019-12-05 08:49:39.026 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY NOT required on COMMAND_CLASS_ALARM 2019-12-05 08:49:39.027 [DEBUG] [e.internal.protocol.commandclass.ZWaveCommandClass] - NODE 5: Received COMMAND_CLASS_ALARM V5 NOTIFICATION_REPORT 2019-12-05 08:49:39.028 [DEBUG] [ernal.protocol.commandclass.ZWaveAlarmCommandClass] - NODE 5: NOTIFICATION report - 0 = 0, event=0, status=255, plen=1 2019-12-05 08:49:39.028 [DEBUG] [ernal.protocol.commandclass.ZWaveAlarmCommandClass] - NODE 5: Alarm Type = BURGLAR (0) 2019-12-05 08:49:39.029 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveAlarmValueEvent 2019-12-05 08:49:39.029 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_ALARM, value=255 2019-12-05 08:49:39.029 [DEBUG] [nding.zwave.internal.converter.ZWaveAlarmConverter] - NODE 5: Alarm converter processing NOTIFICATION 2019-12-05 08:49:39.029 [DEBUG] [nding.zwave.internal.converter.ZWaveAlarmConverter] - NODE 5: Alarm converter NOTIFICATION event is 0, type OnOffType 2019-12-05 08:49:39.030 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Updating channel state zwave:device:4711b0e7:node5:alarm_motion to OFF [OnOffType] 2019-12-05 08:49:39.030 [DEBUG] [nding.zwave.internal.converter.ZWaveAlarmConverter] - NODE 5: Alarm converter processing NOTIFICATION 2019-12-05 08:49:39.030 [DEBUG] [nding.zwave.internal.converter.ZWaveAlarmConverter] - NODE 5: Alarm converter NOTIFICATION event is 0, type OnOffType 2019-12-05 08:49:39.031 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Updating channel state zwave:device:4711b0e7:node5:alarm_tamper to OFF [OnOffType] 2019-12-05 08:49:39.032 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Commands processed 1. 2019-12-05 08:49:39.032 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@691fa1f3. 2019-12-05 08:49:39.033 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-12-05 08:49:39.033 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-12-05 08:49:39.033 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-12-05 08:49:39.033 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-12-05 08:49:39.443 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 25: Command received zwave:device:4711b0e7:node25:switch_dimmer --> 0 [PercentType] 2019-12-05 08:49:39.444 [DEBUG] [col.commandclass.ZWaveMultiLevelSwitchCommandClass] - NODE 25: Creating new message for command SWITCH_MULTILEVEL_SET 2019-12-05 08:49:39.444 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 25: SECURITY not supported 2019-12-05 08:49:39.445 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 25: Command Class COMMAND_CLASS_SWITCH_MULTILEVEL is NOT required to be secured 2019-12-05 08:49:39.445 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 25: Adding to device queue 2019-12-05 08:49:39.445 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 25: Added 629 to queue - size 4 2019-12-05 08:49:39.445 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-12-05 08:49:39.446 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 19 03 26 01 00 25 44 BA 2019-12-05 08:49:39.446 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 25: Sending REQUEST Message = 01 0A 00 13 19 03 26 01 00 25 44 BA 2019-12-05 08:49:39.446 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT 2019-12-05 08:49:39.447 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 629: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 68 2019-12-05 08:49:39.449 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2019-12-05 08:49:39.449 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-12-05 08:49:39.449 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-12-05 08:49:39.450 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 629: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 68 2019-12-05 08:49:39.450 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK 2019-12-05 08:49:39.450 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-12-05 08:49:39.450 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-12-05 08:49:39.454 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2019-12-05 08:49:39.455 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-12-05 08:49:39.455 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-12-05 08:49:39.456 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 629: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 68 2019-12-05 08:49:39.456 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-12-05 08:49:39.456 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 629: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 68 2019-12-05 08:49:39.457 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-12-05 08:49:39.457 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 25: sentData successfully placed on stack. 2019-12-05 08:49:39.457 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 629: Advanced to WAIT_REQUEST 2019-12-05 08:49:39.458 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 25: TID 629: Transaction not completed 2019-12-05 08:49:39.458 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-12-05 08:49:39.460 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-12-05 08:49:39.597 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 44 00 00 0E A1 2019-12-05 08:49:39.598 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=68, payload=44 00 00 0E 2019-12-05 08:49:39.599 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=68, payload=44 00 00 0E 2019-12-05 08:49:39.600 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 629: [WAIT_REQUEST] priority=Set, requiresResponse=true, callback: 68 2019-12-05 08:49:39.601 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-12-05 08:49:39.602 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 629: [WAIT_REQUEST] priority=Set, requiresResponse=true, callback: 68 2019-12-05 08:49:39.602 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 629: (Callback 68) 2019-12-05 08:49:39.603 [DEBUG] [ernal.protocol.serialmessage.ZWaveCommandProcessor] - Callback match! 2019-12-05 08:49:39.604 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Correlated to TID 629: callback 68 2019-12-05 08:49:39.605 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=68, payload=44 00 00 0E 2019-12-05 08:49:39.606 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 25: SendData Request. CallBack ID = 68, Status = Transmission complete and ACK received(0) 2019-12-05 08:49:39.606 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 25: resetResendCount initComplete=true isDead=false 2019-12-05 08:49:39.607 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 629: Transaction COMPLETED 2019-12-05 08:49:39.608 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 25: Response processed after 161ms 2019-12-05 08:49:39.608 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 25: TID 629: Transaction completed 2019-12-05 08:49:39.608 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 25: notifyTransactionResponse TID:629 DONE 2019-12-05 08:49:39.609 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 25: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2019-12-05 08:49:39.609 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-12-05 08:49:39.609 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-12-05 08:49:40.767 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 25: Command received zwave:device:4711b0e7:node25:switch_dimmer --> 70 [PercentType] 2019-12-05 08:49:40.769 [DEBUG] [col.commandclass.ZWaveMultiLevelSwitchCommandClass] - NODE 25: Creating new message for command SWITCH_MULTILEVEL_SET 2019-12-05 08:49:40.769 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 25: SECURITY not supported 2019-12-05 08:49:40.769 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 25: Command Class COMMAND_CLASS_SWITCH_MULTILEVEL is NOT required to be secured 2019-12-05 08:49:40.771 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 25: Adding to device queue 2019-12-05 08:49:40.776 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 25: Added 630 to queue - size 4 2019-12-05 08:49:40.777 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-12-05 08:49:40.777 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 19 03 26 01 46 25 45 FD 2019-12-05 08:49:40.778 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 25: Sending REQUEST Message = 01 0A 00 13 19 03 26 01 46 25 45 FD 2019-12-05 08:49:40.778 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT 2019-12-05 08:49:40.778 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 630: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 69 2019-12-05 08:49:40.779 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2019-12-05 08:49:40.780 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-12-05 08:49:40.781 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-12-05 08:49:40.781 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 630: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 69 2019-12-05 08:49:40.782 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK 2019-12-05 08:49:40.782 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-12-05 08:49:40.782 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-12-05 08:49:40.785 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2019-12-05 08:49:40.786 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-12-05 08:49:40.786 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-12-05 08:49:40.787 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 630: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 69 2019-12-05 08:49:40.787 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-12-05 08:49:40.787 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 630: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 69 2019-12-05 08:49:40.787 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-12-05 08:49:40.787 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 25: sentData successfully placed on stack. 2019-12-05 08:49:40.787 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 630: Advanced to WAIT_REQUEST 2019-12-05 08:49:40.787 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 25: TID 630: Transaction not completed 2019-12-05 08:49:40.787 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-12-05 08:49:40.787 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-12-05 08:49:40.928 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 45 00 00 0E A0 2019-12-05 08:49:40.930 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=69, payload=45 00 00 0E 2019-12-05 08:49:40.933 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=69, payload=45 00 00 0E 2019-12-05 08:49:40.933 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 630: [WAIT_REQUEST] priority=Set, requiresResponse=true, callback: 69 2019-12-05 08:49:40.934 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-12-05 08:49:40.934 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 630: [WAIT_REQUEST] priority=Set, requiresResponse=true, callback: 69 2019-12-05 08:49:40.934 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 630: (Callback 69) 2019-12-05 08:49:40.935 [DEBUG] [ernal.protocol.serialmessage.ZWaveCommandProcessor] - Callback match! 2019-12-05 08:49:40.935 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Correlated to TID 630: callback 69 2019-12-05 08:49:40.935 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=69, payload=45 00 00 0E 2019-12-05 08:49:40.936 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 25: SendData Request. CallBack ID = 69, Status = Transmission complete and ACK received(0) 2019-12-05 08:49:40.936 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 25: resetResendCount initComplete=true isDead=false 2019-12-05 08:49:40.936 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 630: Transaction COMPLETED 2019-12-05 08:49:40.937 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 25: Response processed after 159ms 2019-12-05 08:49:40.937 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 25: TID 630: Transaction completed 2019-12-05 08:49:40.937 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 25: notifyTransactionResponse TID:630 DONE 2019-12-05 08:49:40.938 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 25: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2019-12-05 08:49:40.938 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-12-05 08:49:40.939 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-12-05 08:49:51.926 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 1D 00 49 84 13 17 04 10 01 5E 25 27 32 81 71 60 8E 2C 2B 70 86 72 73 85 59 7A 5A EF 82 2F 2019-12-05 08:49:51.930 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationUpdate[73], type=Request[0], dest=19, callback=132, payload=84 13 17 04 10 01 5E 25 27 32 81 71 60 8E 2C 2B 70 86 72 73 85 59 7A 5A EF 82 2019-12-05 08:49:51.933 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationUpdate[73], type=Request[0], dest=19, callback=132, payload=84 13 17 04 10 01 5E 25 27 32 81 71 60 8E 2C 2B 70 86 72 73 85 59 7A 5A EF 82 2019-12-05 08:49:51.934 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null 2019-12-05 08:49:51.934 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 0 2019-12-05 08:49:51.935 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: null 2019-12-05 08:49:51.936 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=ApplicationUpdate[73], type=Request[0], dest=19, callback=132, payload=84 13 17 04 10 01 5E 25 27 32 81 71 60 8E 2C 2B 70 86 72 73 85 59 7A 5A EF 82 2019-12-05 08:49:51.937 [DEBUG] [otocol.serialmessage.ApplicationUpdateMessageClass] - NODE 19: Application update request. Node information received. Transaction null 2019-12-05 08:49:51.937 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 19: resetResendCount initComplete=true isDead=false 2019-12-05 08:49:51.938 [DEBUG] [otocol.serialmessage.ApplicationUpdateMessageClass] - NODE 19: Application update - no transaction. 2019-12-05 08:49:51.939 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-12-05 08:49:51.939 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-12-05 08:49:59.024 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 08 00 04 00 05 02 84 07 77 2019-12-05 08:49:59.027 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 02 84 07 2019-12-05 08:49:59.030 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 02 84 07 2019-12-05 08:49:59.031 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null 2019-12-05 08:49:59.031 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Application Command Request (ALIVE:REQUEST_NIF) 2019-12-05 08:49:59.031 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-12-05 08:49:59.033 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: Is awake with 1 messages in the queue 2019-12-05 08:49:59.033 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: Start sleep timer at 5000ms 2019-12-05 08:49:59.034 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveNodeStatusEvent 2019-12-05 08:49:59.048 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - NODE 5: Node Status event - Node is AWAKE 2019-12-05 08:49:59.049 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Commands processed 1. 2019-12-05 08:49:59.049 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@647e67be. 2019-12-05 08:49:59.049 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-12-05 08:49:59.049 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-12-05 08:49:59.049 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-12-05 08:49:59.049 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-12-05 08:49:59.049 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 04 00 60 05 9E 2019-12-05 08:49:59.050 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 04 00 60 05 9E 2019-12-05 08:49:59.050 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT 2019-12-05 08:49:59.050 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 44: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 0 2019-12-05 08:49:59.051 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2019-12-05 08:49:59.051 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-12-05 08:49:59.053 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-12-05 08:49:59.053 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 44: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 0 2019-12-05 08:49:59.053 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK 2019-12-05 08:49:59.054 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-12-05 08:49:59.055 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-12-05 08:49:59.057 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 60 01 9B 2019-12-05 08:49:59.057 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=RequestNodeInfo[96], type=Response[1], dest=255, callback=0, payload=01 2019-12-05 08:49:59.058 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=RequestNodeInfo[96], type=Response[1], dest=255, callback=0, payload=01 2019-12-05 08:49:59.058 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 44: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 0 2019-12-05 08:49:59.059 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-12-05 08:49:59.059 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 44: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 0 2019-12-05 08:49:59.059 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=RequestNodeInfo[96], type=Response[1], dest=255, callback=0, payload=01 2019-12-05 08:49:59.060 [DEBUG] [protocol.serialmessage.RequestNodeInfoMessageClass] - Request node info successfully placed on stack. 2019-12-05 08:49:59.061 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 44: Advanced to WAIT_DATA 2019-12-05 08:49:59.063 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: TID 44: Transaction not completed 2019-12-05 08:49:59.065 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-12-05 08:49:59.066 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-12-05 08:49:59.248 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 1D 00 49 84 05 17 04 07 01 5E 20 86 72 5A 59 85 73 84 80 71 56 70 31 8E 22 30 9C 98 7A C0 2019-12-05 08:49:59.250 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationUpdate[73], type=Request[0], dest=5, callback=132, payload=84 05 17 04 07 01 5E 20 86 72 5A 59 85 73 84 80 71 56 70 31 8E 22 30 9C 98 7A 2019-12-05 08:49:59.252 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationUpdate[73], type=Request[0], dest=5, callback=132, payload=84 05 17 04 07 01 5E 20 86 72 5A 59 85 73 84 80 71 56 70 31 8E 22 30 9C 98 7A 2019-12-05 08:49:59.254 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 44: [WAIT_DATA] priority=Immediate, requiresResponse=true, callback: 0 2019-12-05 08:49:59.254 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-12-05 08:49:59.255 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 44: [WAIT_DATA] priority=Immediate, requiresResponse=true, callback: 0 2019-12-05 08:49:59.255 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 44: (Callback 0) 2019-12-05 08:49:59.256 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Correlated to TID 44: callback 0 2019-12-05 08:49:59.256 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=ApplicationUpdate[73], type=Request[0], dest=5, callback=132, payload=84 05 17 04 07 01 5E 20 86 72 5A 59 85 73 84 80 71 56 70 31 8E 22 30 9C 98 7A 2019-12-05 08:49:59.257 [DEBUG] [otocol.serialmessage.ApplicationUpdateMessageClass] - NODE 5: Application update request. Node information received. Transaction TID 44: [WAIT_DATA] priority=Immediate, requiresResponse=true, callback: 0 2019-12-05 08:49:59.257 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 44: Transaction COMPLETED 2019-12-05 08:49:59.257 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Response processed after 207ms 2019-12-05 08:49:59.257 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: TID 44: Transaction completed 2019-12-05 08:49:59.258 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: notifyTransactionResponse TID:44 DONE 2019-12-05 08:49:59.258 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2019-12-05 08:49:59.259 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-12-05 08:49:59.259 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-12-05 08:49:59.259 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 44: Transaction event listener: DONE: DONE -> 2019-12-05 08:49:59.261 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 5: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@6744e447 2019-12-05 08:49:59.261 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 5: Node Init transaction completed with response COMPLETE 2019-12-05 08:49:59.261 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 5: Node advancer: Restored from file - skipping static initialisation 2019-12-05 08:49:59.261 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 5: Node advancer - advancing to STATIC_END 2019-12-05 08:49:59.262 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveInitializationStateEvent 2019-12-05 08:49:59.262 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Updating node properties. 2019-12-05 08:49:59.262 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Updating node properties. MAN=271 2019-12-05 08:49:59.262 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Updating node properties. MAN=271. SET. Was 271 2019-12-05 08:49:59.263 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Properties synchronised 2019-12-05 08:49:59.265 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Update ASSOCIATION group_ZWaveAssociationGroup [index=1, name=Lifeline, profile1=0, profile2=1, associations=[node_1]]: Adding Controller (node_1) 2019-12-05 08:49:59.267 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Initialising Thing Node... 2019-12-05 08:49:59.268 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Initialising cmd channel zwave:device:4711b0e7:node5:sensor_binary for OnOffType 2019-12-05 08:49:59.268 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Initialising state channel zwave:device:4711b0e7:node5:sensor_binary for OnOffType 2019-12-05 08:49:59.269 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Initialising state channel zwave:device:4711b0e7:node5:sensor_binary for OnOffType 2019-12-05 08:49:59.269 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Initialising cmd channel zwave:device:4711b0e7:node5:sensor_temperature for QuantityType 2019-12-05 08:49:59.270 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Initialising state channel zwave:device:4711b0e7:node5:sensor_temperature for QuantityType 2019-12-05 08:49:59.270 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Initialising cmd channel zwave:device:4711b0e7:node5:sensor_seismicintensity for DecimalType 2019-12-05 08:49:59.271 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Initialising state channel zwave:device:4711b0e7:node5:sensor_seismicintensity for DecimalType 2019-12-05 08:49:59.271 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Initialising cmd channel zwave:device:4711b0e7:node5:sensor_luminance for DecimalType 2019-12-05 08:49:59.272 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Initialising state channel zwave:device:4711b0e7:node5:sensor_luminance for DecimalType 2019-12-05 08:49:59.272 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Initialising cmd channel zwave:device:4711b0e7:node5:alarm_motion for OnOffType 2019-12-05 08:49:59.273 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Initialising state channel zwave:device:4711b0e7:node5:alarm_motion for OnOffType 2019-12-05 08:49:59.273 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Initialising cmd channel zwave:device:4711b0e7:node5:alarm_tamper for OnOffType 2019-12-05 08:49:59.273 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Initialising state channel zwave:device:4711b0e7:node5:alarm_tamper for OnOffType 2019-12-05 08:49:59.274 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Initialising cmd channel zwave:device:4711b0e7:node5:battery-level for PercentType 2019-12-05 08:49:59.274 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Initialising state channel zwave:device:4711b0e7:node5:battery-level for PercentType 2019-12-05 08:49:59.275 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Initialising cmd channel zwave:device:4711b0e7:node5:alarm_general for OnOffType 2019-12-05 08:49:59.275 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Initialising state channel zwave:device:4711b0e7:node5:alarm_general for OnOffType 2019-12-05 08:49:59.278 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Polling initialised at 86400 seconds - start in 45446400 milliseconds. 2019-12-05 08:49:59.279 [DEBUG] [ternal.protocol.initialization.ZWaveNodeSerializer] - NODE 5: Serialise aborted as static stages not complete 2019-12-05 08:49:59.279 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 5: Node advancer - advancing to DYNAMIC_VALUES 2019-12-05 08:49:59.280 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveInitializationStateEvent 2019-12-05 08:49:59.280 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 5: Node advancer: DYNAMIC_VALUES - checking COMMAND_CLASS_SENSOR_BINARY 2019-12-05 08:49:59.280 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 5: Found 1 instances of COMMAND_CLASS_SENSOR_BINARY for endpoint 0 2019-12-05 08:49:59.281 [DEBUG] [rotocol.commandclass.ZWaveBinarySensorCommandClass] - NODE 5: Creating new message for application command SENSOR_BINARY_GET 2019-12-05 08:49:59.281 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY NOT required on COMMAND_CLASS_SENSOR_BINARY 2019-12-05 08:49:59.281 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: Command Class COMMAND_CLASS_SENSOR_BINARY is NOT required to be secured 2019-12-05 08:49:59.282 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 5: ZWaveCommandClassTransactionPayload - send to node 2019-12-05 08:49:59.282 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY NOT required on COMMAND_CLASS_SENSOR_BINARY 2019-12-05 08:49:59.282 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: Command Class COMMAND_CLASS_SENSOR_BINARY is NOT required to be secured 2019-12-05 08:49:59.283 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@1fa19f15 2019-12-05 08:49:59.283 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Bump transaction 631 priority from Get to Immediate 2019-12-05 08:49:59.283 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Adding to device queue 2019-12-05 08:49:59.283 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Added 631 to queue - size 3 2019-12-05 08:49:59.283 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-12-05 08:49:59.284 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 05 02 30 02 25 46 B3 2019-12-05 08:49:59.284 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 5: Sending REQUEST Message = 01 09 00 13 05 02 30 02 25 46 B3 2019-12-05 08:49:59.284 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT 2019-12-05 08:49:59.284 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 631: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 70 2019-12-05 08:49:59.285 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2019-12-05 08:49:59.286 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-12-05 08:49:59.286 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-12-05 08:49:59.286 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 631: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 70 2019-12-05 08:49:59.286 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK 2019-12-05 08:49:59.286 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-12-05 08:49:59.286 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-12-05 08:49:59.292 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2019-12-05 08:49:59.292 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-12-05 08:49:59.293 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-12-05 08:49:59.293 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 631: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 70 2019-12-05 08:49:59.293 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-12-05 08:49:59.293 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 631: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 70 2019-12-05 08:49:59.293 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-12-05 08:49:59.293 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 5: sentData successfully placed on stack. 2019-12-05 08:49:59.293 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 631: Advanced to WAIT_REQUEST 2019-12-05 08:49:59.293 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: TID 631: Transaction not completed 2019-12-05 08:49:59.294 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-12-05 08:49:59.294 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-12-05 08:49:59.396 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 46 00 00 0B A6 2019-12-05 08:49:59.397 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=70, payload=46 00 00 0B 2019-12-05 08:49:59.400 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=70, payload=46 00 00 0B 2019-12-05 08:49:59.400 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 631: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 70 2019-12-05 08:49:59.400 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-12-05 08:49:59.400 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 631: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 70 2019-12-05 08:49:59.400 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 631: (Callback 70) 2019-12-05 08:49:59.400 [DEBUG] [ernal.protocol.serialmessage.ZWaveCommandProcessor] - Callback match! 2019-12-05 08:49:59.400 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Correlated to TID 631: callback 70 2019-12-05 08:49:59.401 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=70, payload=46 00 00 0B 2019-12-05 08:49:59.401 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 5: SendData Request. CallBack ID = 70, Status = Transmission complete and ACK received(0) 2019-12-05 08:49:59.401 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 631: Advanced to WAIT_DATA 2019-12-05 08:49:59.401 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: TID 631: Transaction not completed 2019-12-05 08:49:59.401 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-12-05 08:49:59.401 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-12-05 08:49:59.478 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 05 03 30 03 00 C7 2019-12-05 08:49:59.479 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 03 30 03 00 2019-12-05 08:49:59.481 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 03 30 03 00 2019-12-05 08:49:59.481 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null 2019-12-05 08:49:59.481 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Application Command Request (ALIVE:DYNAMIC_VALUES) 2019-12-05 08:49:59.481 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: Incoming command class COMMAND_CLASS_SENSOR_BINARY, endpoint 0 2019-12-05 08:49:59.481 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY NOT required on COMMAND_CLASS_SENSOR_BINARY 2019-12-05 08:49:59.481 [DEBUG] [e.internal.protocol.commandclass.ZWaveCommandClass] - NODE 5: Received COMMAND_CLASS_SENSOR_BINARY V1 SENSOR_BINARY_REPORT 2019-12-05 08:49:59.481 [DEBUG] [rotocol.commandclass.ZWaveBinarySensorCommandClass] - NODE 5: Sensor Binary report, type=Unknown, value=0 2019-12-05 08:49:59.481 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveBinarySensorValueEvent 2019-12-05 08:49:59.482 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SENSOR_BINARY, value=0 2019-12-05 08:49:59.482 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Updating channel state zwave:device:4711b0e7:node5:sensor_binary to OFF [OnOffType] 2019-12-05 08:49:59.482 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Commands processed 1. 2019-12-05 08:49:59.482 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@5c06756d. 2019-12-05 08:49:59.482 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@5c06756d. 2019-12-05 08:49:59.482 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: notifyTransactionResponse TID:631 DONE 2019-12-05 08:49:59.483 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2019-12-05 08:49:59.483 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1 2019-12-05 08:49:59.483 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-12-05 08:49:59.483 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-12-05 08:49:59.483 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-12-05 08:49:59.483 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 631: Transaction event listener: DONE: DONE -> 2019-12-05 08:49:59.484 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 5: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@253019c6 2019-12-05 08:49:59.484 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 5: Node Init transaction completed with response COMPLETE 2019-12-05 08:49:59.484 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 5: Node advancer: DYNAMIC_VALUES - checking COMMAND_CLASS_BASIC 2019-12-05 08:49:59.484 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 5: Node advancer: DYNAMIC_VALUES - checking COMMAND_CLASS_DEVICE_RESET_LOCALLY 2019-12-05 08:49:59.485 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 5: Node advancer: DYNAMIC_VALUES - checking COMMAND_CLASS_NO_OPERATION 2019-12-05 08:49:59.485 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 5: Node advancer: DYNAMIC_VALUES - checking COMMAND_CLASS_VERSION 2019-12-05 08:49:59.485 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 5: Node advancer: DYNAMIC_VALUES - checking COMMAND_CLASS_BATTERY 2019-12-05 08:49:59.485 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 5: Found 1 instances of COMMAND_CLASS_BATTERY for endpoint 0 2019-12-05 08:49:59.485 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY NOT required on COMMAND_CLASS_BATTERY 2019-12-05 08:49:59.485 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: Command Class COMMAND_CLASS_BATTERY is NOT required to be secured 2019-12-05 08:49:59.485 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 5: ZWaveCommandClassTransactionPayload - send to node 2019-12-05 08:49:59.485 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY NOT required on COMMAND_CLASS_BATTERY 2019-12-05 08:49:59.485 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: Command Class COMMAND_CLASS_BATTERY is NOT required to be secured 2019-12-05 08:49:59.485 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@6596b7fb 2019-12-05 08:49:59.485 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Bump transaction 632 priority from Get to Immediate 2019-12-05 08:49:59.486 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Adding to device queue 2019-12-05 08:49:59.486 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Added 632 to queue - size 3 2019-12-05 08:49:59.486 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-12-05 08:49:59.486 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 05 02 80 02 25 47 02 2019-12-05 08:49:59.486 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 5: Sending REQUEST Message = 01 09 00 13 05 02 80 02 25 47 02 2019-12-05 08:49:59.486 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT 2019-12-05 08:49:59.486 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 632: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 71 2019-12-05 08:49:59.487 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2019-12-05 08:49:59.488 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-12-05 08:49:59.488 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-12-05 08:49:59.488 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 632: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 71 2019-12-05 08:49:59.488 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK 2019-12-05 08:49:59.488 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-12-05 08:49:59.489 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-12-05 08:49:59.494 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2019-12-05 08:49:59.494 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-12-05 08:49:59.495 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-12-05 08:49:59.495 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 632: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 71 2019-12-05 08:49:59.495 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-12-05 08:49:59.495 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 632: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 71 2019-12-05 08:49:59.495 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-12-05 08:49:59.495 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 5: sentData successfully placed on stack. 2019-12-05 08:49:59.495 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 632: Advanced to WAIT_REQUEST 2019-12-05 08:49:59.495 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: TID 632: Transaction not completed 2019-12-05 08:49:59.496 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-12-05 08:49:59.496 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-12-05 08:49:59.625 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 47 00 00 0D A1 2019-12-05 08:49:59.628 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=71, payload=47 00 00 0D 2019-12-05 08:49:59.629 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=71, payload=47 00 00 0D 2019-12-05 08:49:59.630 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 632: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 71 2019-12-05 08:49:59.630 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-12-05 08:49:59.631 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 632: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 71 2019-12-05 08:49:59.631 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 632: (Callback 71) 2019-12-05 08:49:59.632 [DEBUG] [ernal.protocol.serialmessage.ZWaveCommandProcessor] - Callback match! 2019-12-05 08:49:59.632 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Correlated to TID 632: callback 71 2019-12-05 08:49:59.633 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=71, payload=47 00 00 0D 2019-12-05 08:49:59.633 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 5: SendData Request. CallBack ID = 71, Status = Transmission complete and ACK received(0) 2019-12-05 08:49:59.634 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 632: Advanced to WAIT_DATA 2019-12-05 08:49:59.634 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: TID 632: Transaction not completed 2019-12-05 08:49:59.635 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-12-05 08:49:59.635 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-12-05 08:49:59.708 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 05 03 80 03 64 13 2019-12-05 08:49:59.711 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 03 80 03 64 2019-12-05 08:49:59.713 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 03 80 03 64 2019-12-05 08:49:59.714 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null 2019-12-05 08:49:59.714 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Application Command Request (ALIVE:DYNAMIC_VALUES) 2019-12-05 08:49:59.714 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: Incoming command class COMMAND_CLASS_BATTERY, endpoint 0 2019-12-05 08:49:59.715 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY NOT required on COMMAND_CLASS_BATTERY 2019-12-05 08:49:59.715 [DEBUG] [e.internal.protocol.commandclass.ZWaveCommandClass] - NODE 5: Received COMMAND_CLASS_BATTERY V1 BATTERY_REPORT 2019-12-05 08:49:59.715 [DEBUG] [nal.protocol.commandclass.ZWaveBatteryCommandClass] - NODE 5: Battery report value = 100 2019-12-05 08:49:59.716 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveCommandClassValueEvent 2019-12-05 08:49:59.716 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_BATTERY, value=100 2019-12-05 08:49:59.717 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Updating channel state zwave:device:4711b0e7:node5:battery-level to 100 [DecimalType] 2019-12-05 08:49:59.719 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Commands processed 1. 2019-12-05 08:49:59.725 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@6bafec81. 2019-12-05 08:49:59.726 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@6bafec81. 2019-12-05 08:49:59.727 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: notifyTransactionResponse TID:632 DONE 2019-12-05 08:49:59.727 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2019-12-05 08:49:59.727 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1 2019-12-05 08:49:59.728 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-12-05 08:49:59.728 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-12-05 08:49:59.728 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-12-05 08:49:59.729 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 632: Transaction event listener: DONE: DONE -> 2019-12-05 08:49:59.729 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 5: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@17ef7be3 2019-12-05 08:49:59.730 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 5: Node Init transaction completed with response COMPLETE 2019-12-05 08:49:59.730 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 5: Node advancer: DYNAMIC_VALUES - checking COMMAND_CLASS_ZWAVEPLUS_INFO 2019-12-05 08:49:59.730 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 5: Node advancer: DYNAMIC_VALUES - checking COMMAND_CLASS_MANUFACTURER_SPECIFIC 2019-12-05 08:49:59.730 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 5: Node advancer: DYNAMIC_VALUES - checking COMMAND_CLASS_SENSOR_ALARM 2019-12-05 08:49:59.730 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 5: Found 1 instances of COMMAND_CLASS_SENSOR_ALARM for endpoint 0 2019-12-05 08:49:59.730 [DEBUG] [protocol.commandclass.ZWaveAlarmSensorCommandClass] - ====---- Resetting init flag! 2019-12-05 08:49:59.730 [DEBUG] [protocol.commandclass.ZWaveAlarmSensorCommandClass] - RESET IS true 2019-12-05 08:49:59.730 [DEBUG] [protocol.commandclass.ZWaveAlarmSensorCommandClass] - NODE 5: ====---- Checking alarm GENERAL - init false 2019-12-05 08:49:59.730 [DEBUG] [protocol.commandclass.ZWaveAlarmSensorCommandClass] - NODE 5: Creating new message for command SENSOR_ALARM_GET, type General 2019-12-05 08:49:59.730 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY NOT required on COMMAND_CLASS_SENSOR_ALARM 2019-12-05 08:49:59.731 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: Command Class COMMAND_CLASS_SENSOR_ALARM is NOT required to be secured 2019-12-05 08:49:59.731 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 5: ZWaveCommandClassTransactionPayload - send to node 2019-12-05 08:49:59.731 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY NOT required on COMMAND_CLASS_SENSOR_ALARM 2019-12-05 08:49:59.731 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: Command Class COMMAND_CLASS_SENSOR_ALARM is NOT required to be secured 2019-12-05 08:49:59.731 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@4a5173c 2019-12-05 08:49:59.732 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Bump transaction 633 priority from Get to Immediate 2019-12-05 08:49:59.732 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Adding to device queue 2019-12-05 08:49:59.732 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Added 633 to queue - size 3 2019-12-05 08:49:59.732 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-12-05 08:49:59.732 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 05 03 9C 01 00 25 48 10 2019-12-05 08:49:59.733 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 5: Sending REQUEST Message = 01 0A 00 13 05 03 9C 01 00 25 48 10 2019-12-05 08:49:59.733 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT 2019-12-05 08:49:59.733 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 633: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 72 2019-12-05 08:49:59.734 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2019-12-05 08:49:59.734 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-12-05 08:49:59.734 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-12-05 08:49:59.734 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 633: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 72 2019-12-05 08:49:59.734 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK 2019-12-05 08:49:59.734 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-12-05 08:49:59.735 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-12-05 08:49:59.740 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2019-12-05 08:49:59.741 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-12-05 08:49:59.741 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-12-05 08:49:59.741 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 633: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 72 2019-12-05 08:49:59.742 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-12-05 08:49:59.742 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 633: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 72 2019-12-05 08:49:59.742 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-12-05 08:49:59.742 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 5: sentData successfully placed on stack. 2019-12-05 08:49:59.742 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 633: Advanced to WAIT_REQUEST 2019-12-05 08:49:59.742 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: TID 633: Transaction not completed 2019-12-05 08:49:59.742 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-12-05 08:49:59.742 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-12-05 08:49:59.856 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 48 00 00 0C AF 2019-12-05 08:49:59.858 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=72, payload=48 00 00 0C 2019-12-05 08:49:59.859 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=72, payload=48 00 00 0C 2019-12-05 08:49:59.860 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 633: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 72 2019-12-05 08:49:59.860 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-12-05 08:49:59.860 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 633: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 72 2019-12-05 08:49:59.860 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 633: (Callback 72) 2019-12-05 08:49:59.860 [DEBUG] [ernal.protocol.serialmessage.ZWaveCommandProcessor] - Callback match! 2019-12-05 08:49:59.860 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Correlated to TID 633: callback 72 2019-12-05 08:49:59.860 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=72, payload=48 00 00 0C 2019-12-05 08:49:59.861 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 5: SendData Request. CallBack ID = 72, Status = Transmission complete and ACK received(0) 2019-12-05 08:49:59.861 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 633: Advanced to WAIT_DATA 2019-12-05 08:49:59.861 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: TID 633: Transaction not completed 2019-12-05 08:49:59.861 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-12-05 08:49:59.861 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-12-05 08:49:59.942 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0D 00 04 00 05 07 9C 02 05 00 00 00 00 6F 2019-12-05 08:49:59.944 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 07 9C 02 05 00 00 00 00 2019-12-05 08:49:59.947 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 07 9C 02 05 00 00 00 00 2019-12-05 08:49:59.947 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null 2019-12-05 08:49:59.948 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Application Command Request (ALIVE:DYNAMIC_VALUES) 2019-12-05 08:49:59.948 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: Incoming command class COMMAND_CLASS_SENSOR_ALARM, endpoint 0 2019-12-05 08:49:59.948 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY NOT required on COMMAND_CLASS_SENSOR_ALARM 2019-12-05 08:49:59.949 [DEBUG] [e.internal.protocol.commandclass.ZWaveCommandClass] - NODE 5: Received COMMAND_CLASS_SENSOR_ALARM V1 SENSOR_ALARM_REPORT 2019-12-05 08:49:59.949 [DEBUG] [protocol.commandclass.ZWaveAlarmSensorCommandClass] - NODE 5: Alarm Report: Source=5, Type=General(0), Value=0 2019-12-05 08:49:59.950 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveAlarmSensorValueEvent 2019-12-05 08:49:59.950 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SENSOR_ALARM, value=0 2019-12-05 08:49:59.951 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Updating channel state zwave:device:4711b0e7:node5:alarm_general to OFF [OnOffType] 2019-12-05 08:49:59.955 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Commands processed 1. 2019-12-05 08:49:59.955 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@7fabd83e. 2019-12-05 08:49:59.955 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@7fabd83e. 2019-12-05 08:49:59.956 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: notifyTransactionResponse TID:633 DONE 2019-12-05 08:49:59.963 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2019-12-05 08:49:59.963 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1 2019-12-05 08:49:59.963 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-12-05 08:49:59.963 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-12-05 08:49:59.963 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-12-05 08:49:59.963 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 633: Transaction event listener: DONE: DONE -> 2019-12-05 08:49:59.964 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 5: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@217ec047 2019-12-05 08:49:59.965 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 5: Node Init transaction completed with response COMPLETE 2019-12-05 08:49:59.965 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 5: Node advancer: DYNAMIC_VALUES - checking COMMAND_CLASS_FIRMWARE_UPDATE_MD 2019-12-05 08:49:59.966 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 5: Node advancer: DYNAMIC_VALUES - checking COMMAND_CLASS_ASSOCIATION_GRP_INFO 2019-12-05 08:49:59.966 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 5: Node advancer: DYNAMIC_VALUES - checking COMMAND_CLASS_CONFIGURATION 2019-12-05 08:49:59.966 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 5: Node advancer: DYNAMIC_VALUES - checking COMMAND_CLASS_APPLICATION_STATUS 2019-12-05 08:49:59.967 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 5: Node advancer: DYNAMIC_VALUES - checking COMMAND_CLASS_SENSOR_MULTILEVEL 2019-12-05 08:49:59.967 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 5: Found 1 instances of COMMAND_CLASS_SENSOR_MULTILEVEL for endpoint 0 2019-12-05 08:49:59.968 [DEBUG] [col.commandclass.ZWaveMultiLevelSensorCommandClass] - =========== Resetting init flag! 2019-12-05 08:49:59.968 [DEBUG] [col.commandclass.ZWaveMultiLevelSensorCommandClass] - ============ Requesting ACCELERATION_Y! 2019-12-05 08:49:59.968 [DEBUG] [col.commandclass.ZWaveMultiLevelSensorCommandClass] - NODE 5: Creating new message for command SENSOR_MULTILEVEL_GET 2019-12-05 08:49:59.968 [DEBUG] [col.commandclass.ZWaveMultiLevelSensorCommandClass] - ============ Requesting LUMINANCE! 2019-12-05 08:49:59.968 [DEBUG] [col.commandclass.ZWaveMultiLevelSensorCommandClass] - NODE 5: Creating new message for command SENSOR_MULTILEVEL_GET 2019-12-05 08:49:59.968 [DEBUG] [col.commandclass.ZWaveMultiLevelSensorCommandClass] - ============ Requesting TEMPERATURE! 2019-12-05 08:49:59.968 [DEBUG] [col.commandclass.ZWaveMultiLevelSensorCommandClass] - NODE 5: Creating new message for command SENSOR_MULTILEVEL_GET 2019-12-05 08:49:59.968 [DEBUG] [col.commandclass.ZWaveMultiLevelSensorCommandClass] - ============ Requesting ACCELERATION_X! 2019-12-05 08:49:59.968 [DEBUG] [col.commandclass.ZWaveMultiLevelSensorCommandClass] - NODE 5: Creating new message for command SENSOR_MULTILEVEL_GET 2019-12-05 08:49:59.968 [DEBUG] [col.commandclass.ZWaveMultiLevelSensorCommandClass] - ============ Requesting ACCELERATION_Z! 2019-12-05 08:49:59.969 [DEBUG] [col.commandclass.ZWaveMultiLevelSensorCommandClass] - NODE 5: Creating new message for command SENSOR_MULTILEVEL_GET 2019-12-05 08:49:59.969 [DEBUG] [col.commandclass.ZWaveMultiLevelSensorCommandClass] - ============ Requesting SEISMIC_INTENSITY! 2019-12-05 08:49:59.969 [DEBUG] [col.commandclass.ZWaveMultiLevelSensorCommandClass] - NODE 5: Creating new message for command SENSOR_MULTILEVEL_GET 2019-12-05 08:49:59.974 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY NOT required on COMMAND_CLASS_SENSOR_MULTILEVEL 2019-12-05 08:49:59.974 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: Command Class COMMAND_CLASS_SENSOR_MULTILEVEL is NOT required to be secured 2019-12-05 08:49:59.975 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 5: ZWaveCommandClassTransactionPayload - send to node 2019-12-05 08:49:59.975 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY NOT required on COMMAND_CLASS_SENSOR_MULTILEVEL 2019-12-05 08:49:59.975 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: Command Class COMMAND_CLASS_SENSOR_MULTILEVEL is NOT required to be secured 2019-12-05 08:49:59.975 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@718782fc 2019-12-05 08:49:59.975 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Bump transaction 634 priority from Get to Immediate 2019-12-05 08:49:59.975 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Adding to device queue 2019-12-05 08:49:59.975 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Added 634 to queue - size 3 2019-12-05 08:49:59.975 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-12-05 08:49:59.975 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0B 00 13 05 04 31 04 35 00 25 49 8A 2019-12-05 08:49:59.976 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 5: Sending REQUEST Message = 01 0B 00 13 05 04 31 04 35 00 25 49 8A 2019-12-05 08:49:59.976 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT 2019-12-05 08:49:59.976 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 634: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 73 2019-12-05 08:49:59.977 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2019-12-05 08:49:59.977 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-12-05 08:49:59.977 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-12-05 08:49:59.978 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 634: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 73 2019-12-05 08:49:59.978 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK 2019-12-05 08:49:59.978 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-12-05 08:49:59.978 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-12-05 08:49:59.984 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2019-12-05 08:49:59.984 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-12-05 08:49:59.984 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-12-05 08:49:59.984 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 634: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 73 2019-12-05 08:49:59.985 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-12-05 08:49:59.985 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 634: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 73 2019-12-05 08:49:59.985 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-12-05 08:49:59.985 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 5: sentData successfully placed on stack. 2019-12-05 08:49:59.985 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 634: Advanced to WAIT_REQUEST 2019-12-05 08:49:59.985 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: TID 634: Transaction not completed 2019-12-05 08:49:59.985 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-12-05 08:49:59.985 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-12-05 08:50:00.097 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 49 00 00 0B A9 2019-12-05 08:50:00.099 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=73, payload=49 00 00 0B 2019-12-05 08:50:00.101 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=73, payload=49 00 00 0B 2019-12-05 08:50:00.101 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 634: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 73 2019-12-05 08:50:00.102 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-12-05 08:50:00.102 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 634: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 73 2019-12-05 08:50:00.102 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 634: (Callback 73) 2019-12-05 08:50:00.103 [DEBUG] [ernal.protocol.serialmessage.ZWaveCommandProcessor] - Callback match! 2019-12-05 08:50:00.103 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Correlated to TID 634: callback 73 2019-12-05 08:50:00.104 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=73, payload=49 00 00 0B 2019-12-05 08:50:00.104 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 5: SendData Request. CallBack ID = 73, Status = Transmission complete and ACK received(0) 2019-12-05 08:50:00.105 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 634: Advanced to WAIT_DATA 2019-12-05 08:50:00.105 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: TID 634: Transaction not completed 2019-12-05 08:50:00.105 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-12-05 08:50:00.106 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-12-05 08:50:00.180 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0B 00 04 00 05 05 31 05 35 21 00 D0 2019-12-05 08:50:00.182 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 05 31 05 35 21 00 2019-12-05 08:50:00.184 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 05 31 05 35 21 00 2019-12-05 08:50:00.186 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null 2019-12-05 08:50:00.188 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Application Command Request (ALIVE:DYNAMIC_VALUES) 2019-12-05 08:50:00.188 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: Incoming command class COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 0 2019-12-05 08:50:00.188 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY NOT required on COMMAND_CLASS_SENSOR_MULTILEVEL 2019-12-05 08:50:00.189 [DEBUG] [e.internal.protocol.commandclass.ZWaveCommandClass] - NODE 5: Received COMMAND_CLASS_SENSOR_MULTILEVEL V8 SENSOR_MULTILEVEL_REPORT 2019-12-05 08:50:00.189 [DEBUG] [col.commandclass.ZWaveMultiLevelSensorCommandClass] - NODE 5: Sensor Type = Accelleration Y-Axis(53), Scale = 0 2019-12-05 08:50:00.189 [DEBUG] [col.commandclass.ZWaveMultiLevelSensorCommandClass] - NODE 5: Sensor Value = 0E+1 2019-12-05 08:50:00.190 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent 2019-12-05 08:50:00.190 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SENSOR_MULTILEVEL, value=0E+1 2019-12-05 08:50:00.190 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Commands processed 1. 2019-12-05 08:50:00.191 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@687f13e2. 2019-12-05 08:50:00.191 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@687f13e2. 2019-12-05 08:50:00.191 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: notifyTransactionResponse TID:634 DONE 2019-12-05 08:50:00.192 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2019-12-05 08:50:00.192 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1 2019-12-05 08:50:00.193 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-12-05 08:50:00.192 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 634: Transaction event listener: DONE: DONE -> 2019-12-05 08:50:00.193 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-12-05 08:50:00.193 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-12-05 08:50:00.194 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 5: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@1ddbcf4c 2019-12-05 08:50:00.195 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 5: Node Init transaction completed with response COMPLETE 2019-12-05 08:50:00.195 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY NOT required on COMMAND_CLASS_SENSOR_MULTILEVEL 2019-12-05 08:50:00.196 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: Command Class COMMAND_CLASS_SENSOR_MULTILEVEL is NOT required to be secured 2019-12-05 08:50:00.196 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 5: ZWaveCommandClassTransactionPayload - send to node 2019-12-05 08:50:00.197 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY NOT required on COMMAND_CLASS_SENSOR_MULTILEVEL 2019-12-05 08:50:00.197 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: Command Class COMMAND_CLASS_SENSOR_MULTILEVEL is NOT required to be secured 2019-12-05 08:50:00.197 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@31eb373c 2019-12-05 08:50:00.198 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Bump transaction 635 priority from Get to Immediate 2019-12-05 08:50:00.198 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Adding to device queue 2019-12-05 08:50:00.199 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Added 635 to queue - size 3 2019-12-05 08:50:00.199 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-12-05 08:50:00.200 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0B 00 13 05 04 31 04 03 00 25 4A BF 2019-12-05 08:50:00.200 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 5: Sending REQUEST Message = 01 0B 00 13 05 04 31 04 03 00 25 4A BF 2019-12-05 08:50:00.200 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT 2019-12-05 08:50:00.200 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 635: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 74 2019-12-05 08:50:00.201 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2019-12-05 08:50:00.202 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-12-05 08:50:00.202 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-12-05 08:50:00.203 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 635: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 74 2019-12-05 08:50:00.203 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK 2019-12-05 08:50:00.203 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-12-05 08:50:00.204 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-12-05 08:50:00.208 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2019-12-05 08:50:00.209 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-12-05 08:50:00.210 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-12-05 08:50:00.211 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 635: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 74 2019-12-05 08:50:00.211 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-12-05 08:50:00.212 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 635: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 74 2019-12-05 08:50:00.212 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-12-05 08:50:00.213 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 5: sentData successfully placed on stack. 2019-12-05 08:50:00.214 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 635: Advanced to WAIT_REQUEST 2019-12-05 08:50:00.214 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: TID 635: Transaction not completed 2019-12-05 08:50:00.215 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-12-05 08:50:00.215 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-12-05 08:50:00.327 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 4A 00 00 0C AD 2019-12-05 08:50:00.329 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=74, payload=4A 00 00 0C 2019-12-05 08:50:00.330 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=74, payload=4A 00 00 0C 2019-12-05 08:50:00.331 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 635: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 74 2019-12-05 08:50:00.333 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-12-05 08:50:00.335 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 635: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 74 2019-12-05 08:50:00.336 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 635: (Callback 74) 2019-12-05 08:50:00.336 [DEBUG] [ernal.protocol.serialmessage.ZWaveCommandProcessor] - Callback match! 2019-12-05 08:50:00.337 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Correlated to TID 635: callback 74 2019-12-05 08:50:00.337 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=74, payload=4A 00 00 0C 2019-12-05 08:50:00.337 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 5: SendData Request. CallBack ID = 74, Status = Transmission complete and ACK received(0) 2019-12-05 08:50:00.338 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 635: Advanced to WAIT_DATA 2019-12-05 08:50:00.338 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: TID 635: Transaction not completed 2019-12-05 08:50:00.338 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-12-05 08:50:00.339 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-12-05 08:50:00.410 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0C 00 04 00 05 06 31 05 03 0A 01 07 CF 2019-12-05 08:50:00.412 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 06 31 05 03 0A 01 07 2019-12-05 08:50:00.414 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 06 31 05 03 0A 01 07 2019-12-05 08:50:00.416 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null 2019-12-05 08:50:00.417 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Application Command Request (ALIVE:DYNAMIC_VALUES) 2019-12-05 08:50:00.418 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: Incoming command class COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 0 2019-12-05 08:50:00.419 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY NOT required on COMMAND_CLASS_SENSOR_MULTILEVEL 2019-12-05 08:50:00.419 [DEBUG] [e.internal.protocol.commandclass.ZWaveCommandClass] - NODE 5: Received COMMAND_CLASS_SENSOR_MULTILEVEL V8 SENSOR_MULTILEVEL_REPORT 2019-12-05 08:50:00.420 [DEBUG] [col.commandclass.ZWaveMultiLevelSensorCommandClass] - NODE 5: Sensor Type = Luminance(3), Scale = 1 2019-12-05 08:50:00.420 [DEBUG] [col.commandclass.ZWaveMultiLevelSensorCommandClass] - NODE 5: Sensor Value = 263 2019-12-05 08:50:00.420 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent 2019-12-05 08:50:00.421 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SENSOR_MULTILEVEL, value=263 2019-12-05 08:50:00.421 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Updating channel state zwave:device:4711b0e7:node5:sensor_luminance to 263 % [QuantityType] 2019-12-05 08:50:00.427 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Commands processed 1. 2019-12-05 08:50:00.427 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@73dae9d. 2019-12-05 08:50:00.428 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@73dae9d. 2019-12-05 08:50:00.428 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: notifyTransactionResponse TID:635 DONE 2019-12-05 08:50:00.434 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2019-12-05 08:50:00.435 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1 2019-12-05 08:50:00.435 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-12-05 08:50:00.435 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-12-05 08:50:00.435 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-12-05 08:50:00.435 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 635: Transaction event listener: DONE: DONE -> 2019-12-05 08:50:00.436 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 5: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@6640a5be 2019-12-05 08:50:00.436 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 5: Node Init transaction completed with response COMPLETE 2019-12-05 08:50:00.436 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY NOT required on COMMAND_CLASS_SENSOR_MULTILEVEL 2019-12-05 08:50:00.436 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: Command Class COMMAND_CLASS_SENSOR_MULTILEVEL is NOT required to be secured 2019-12-05 08:50:00.436 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 5: ZWaveCommandClassTransactionPayload - send to node 2019-12-05 08:50:00.436 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY NOT required on COMMAND_CLASS_SENSOR_MULTILEVEL 2019-12-05 08:50:00.436 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: Command Class COMMAND_CLASS_SENSOR_MULTILEVEL is NOT required to be secured 2019-12-05 08:50:00.437 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@2b1d5be8 2019-12-05 08:50:00.437 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Bump transaction 636 priority from Get to Immediate 2019-12-05 08:50:00.437 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Adding to device queue 2019-12-05 08:50:00.437 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Added 636 to queue - size 3 2019-12-05 08:50:00.437 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-12-05 08:50:00.437 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0B 00 13 05 04 31 04 01 00 25 4B BC 2019-12-05 08:50:00.437 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 5: Sending REQUEST Message = 01 0B 00 13 05 04 31 04 01 00 25 4B BC 2019-12-05 08:50:00.438 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT 2019-12-05 08:50:00.438 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 636: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 75 2019-12-05 08:50:00.439 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2019-12-05 08:50:00.439 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-12-05 08:50:00.439 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-12-05 08:50:00.439 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 636: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 75 2019-12-05 08:50:00.440 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK 2019-12-05 08:50:00.440 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-12-05 08:50:00.440 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-12-05 08:50:00.445 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2019-12-05 08:50:00.445 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-12-05 08:50:00.446 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-12-05 08:50:00.446 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 636: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 75 2019-12-05 08:50:00.446 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-12-05 08:50:00.446 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 636: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 75 2019-12-05 08:50:00.446 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-12-05 08:50:00.446 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 5: sentData successfully placed on stack. 2019-12-05 08:50:00.446 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 636: Advanced to WAIT_REQUEST 2019-12-05 08:50:00.446 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: TID 636: Transaction not completed 2019-12-05 08:50:00.447 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-12-05 08:50:00.447 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-12-05 08:50:00.558 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 4B 00 00 0B AB 2019-12-05 08:50:00.558 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=75, payload=4B 00 00 0B 2019-12-05 08:50:00.559 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=75, payload=4B 00 00 0B 2019-12-05 08:50:00.560 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 636: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 75 2019-12-05 08:50:00.560 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-12-05 08:50:00.561 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 636: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 75 2019-12-05 08:50:00.561 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 636: (Callback 75) 2019-12-05 08:50:00.562 [DEBUG] [ernal.protocol.serialmessage.ZWaveCommandProcessor] - Callback match! 2019-12-05 08:50:00.562 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Correlated to TID 636: callback 75 2019-12-05 08:50:00.562 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=75, payload=4B 00 00 0B 2019-12-05 08:50:00.563 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 5: SendData Request. CallBack ID = 75, Status = Transmission complete and ACK received(0) 2019-12-05 08:50:00.563 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 636: Advanced to WAIT_DATA 2019-12-05 08:50:00.564 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: TID 636: Transaction not completed 2019-12-05 08:50:00.564 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-12-05 08:50:00.564 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-12-05 08:50:00.641 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0C 00 04 00 05 06 31 05 01 22 00 E6 05 2019-12-05 08:50:00.642 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 06 31 05 01 22 00 E6 2019-12-05 08:50:00.643 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 06 31 05 01 22 00 E6 2019-12-05 08:50:00.645 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null 2019-12-05 08:50:00.646 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Application Command Request (ALIVE:DYNAMIC_VALUES) 2019-12-05 08:50:00.648 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: Incoming command class COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 0 2019-12-05 08:50:00.649 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY NOT required on COMMAND_CLASS_SENSOR_MULTILEVEL 2019-12-05 08:50:00.649 [DEBUG] [e.internal.protocol.commandclass.ZWaveCommandClass] - NODE 5: Received COMMAND_CLASS_SENSOR_MULTILEVEL V8 SENSOR_MULTILEVEL_REPORT 2019-12-05 08:50:00.650 [DEBUG] [col.commandclass.ZWaveMultiLevelSensorCommandClass] - NODE 5: Sensor Type = Temperature(1), Scale = 0 2019-12-05 08:50:00.650 [DEBUG] [col.commandclass.ZWaveMultiLevelSensorCommandClass] - NODE 5: Sensor Value = 23 2019-12-05 08:50:00.650 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent 2019-12-05 08:50:00.651 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SENSOR_MULTILEVEL, value=23 2019-12-05 08:50:00.651 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Updating channel state zwave:device:4711b0e7:node5:sensor_temperature to 23 °C [QuantityType] 2019-12-05 08:50:00.663 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Commands processed 1. 2019-12-05 08:50:00.663 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1362911f. 2019-12-05 08:50:00.664 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1362911f. 2019-12-05 08:50:00.664 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: notifyTransactionResponse TID:636 DONE 2019-12-05 08:50:00.672 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2019-12-05 08:50:00.673 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1 2019-12-05 08:50:00.673 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-12-05 08:50:00.673 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-12-05 08:50:00.673 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-12-05 08:50:00.673 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 636: Transaction event listener: DONE: DONE -> 2019-12-05 08:50:00.674 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 5: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@68ff4068 2019-12-05 08:50:00.674 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 5: Node Init transaction completed with response COMPLETE 2019-12-05 08:50:00.674 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY NOT required on COMMAND_CLASS_SENSOR_MULTILEVEL 2019-12-05 08:50:00.674 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: Command Class COMMAND_CLASS_SENSOR_MULTILEVEL is NOT required to be secured 2019-12-05 08:50:00.674 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 5: ZWaveCommandClassTransactionPayload - send to node 2019-12-05 08:50:00.674 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY NOT required on COMMAND_CLASS_SENSOR_MULTILEVEL 2019-12-05 08:50:00.674 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: Command Class COMMAND_CLASS_SENSOR_MULTILEVEL is NOT required to be secured 2019-12-05 08:50:00.674 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@45d89c 2019-12-05 08:50:00.674 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Bump transaction 637 priority from Get to Immediate 2019-12-05 08:50:00.675 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Adding to device queue 2019-12-05 08:50:00.675 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Added 637 to queue - size 3 2019-12-05 08:50:00.675 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-12-05 08:50:00.675 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0B 00 13 05 04 31 04 34 00 25 4C 8E 2019-12-05 08:50:00.675 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 5: Sending REQUEST Message = 01 0B 00 13 05 04 31 04 34 00 25 4C 8E 2019-12-05 08:50:00.675 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT 2019-12-05 08:50:00.676 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 637: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 76 2019-12-05 08:50:00.677 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2019-12-05 08:50:00.677 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-12-05 08:50:00.677 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-12-05 08:50:00.677 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 637: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 76 2019-12-05 08:50:00.677 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK 2019-12-05 08:50:00.677 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-12-05 08:50:00.677 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-12-05 08:50:00.683 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2019-12-05 08:50:00.684 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-12-05 08:50:00.684 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-12-05 08:50:00.684 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 637: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 76 2019-12-05 08:50:00.684 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-12-05 08:50:00.684 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 637: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 76 2019-12-05 08:50:00.684 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-12-05 08:50:00.684 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 5: sentData successfully placed on stack. 2019-12-05 08:50:00.684 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 637: Advanced to WAIT_REQUEST 2019-12-05 08:50:00.685 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: TID 637: Transaction not completed 2019-12-05 08:50:00.685 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-12-05 08:50:00.685 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-12-05 08:50:00.786 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 4C 00 00 0A AD 2019-12-05 08:50:00.787 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=76, payload=4C 00 00 0A 2019-12-05 08:50:00.788 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=76, payload=4C 00 00 0A 2019-12-05 08:50:00.788 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 637: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 76 2019-12-05 08:50:00.788 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-12-05 08:50:00.789 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 637: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 76 2019-12-05 08:50:00.789 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 637: (Callback 76) 2019-12-05 08:50:00.789 [DEBUG] [ernal.protocol.serialmessage.ZWaveCommandProcessor] - Callback match! 2019-12-05 08:50:00.790 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Correlated to TID 637: callback 76 2019-12-05 08:50:00.790 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=76, payload=4C 00 00 0A 2019-12-05 08:50:00.790 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 5: SendData Request. CallBack ID = 76, Status = Transmission complete and ACK received(0) 2019-12-05 08:50:00.791 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 637: Advanced to WAIT_DATA 2019-12-05 08:50:00.791 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: TID 637: Transaction not completed 2019-12-05 08:50:00.791 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-12-05 08:50:00.792 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-12-05 08:50:00.870 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0B 00 04 00 05 05 31 05 34 21 00 D1 2019-12-05 08:50:00.873 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 05 31 05 34 21 00 2019-12-05 08:50:00.874 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 05 31 05 34 21 00 2019-12-05 08:50:00.875 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null 2019-12-05 08:50:00.875 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Application Command Request (ALIVE:DYNAMIC_VALUES) 2019-12-05 08:50:00.876 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: Incoming command class COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 0 2019-12-05 08:50:00.876 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY NOT required on COMMAND_CLASS_SENSOR_MULTILEVEL 2019-12-05 08:50:00.877 [DEBUG] [e.internal.protocol.commandclass.ZWaveCommandClass] - NODE 5: Received COMMAND_CLASS_SENSOR_MULTILEVEL V8 SENSOR_MULTILEVEL_REPORT 2019-12-05 08:50:00.877 [DEBUG] [col.commandclass.ZWaveMultiLevelSensorCommandClass] - NODE 5: Sensor Type = Accelleration X-Axis(52), Scale = 0 2019-12-05 08:50:00.877 [DEBUG] [col.commandclass.ZWaveMultiLevelSensorCommandClass] - NODE 5: Sensor Value = 0E+1 2019-12-05 08:50:00.878 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent 2019-12-05 08:50:00.878 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SENSOR_MULTILEVEL, value=0E+1 2019-12-05 08:50:00.879 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Commands processed 1. 2019-12-05 08:50:00.879 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@32a9d165. 2019-12-05 08:50:00.880 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@32a9d165. 2019-12-05 08:50:00.880 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: notifyTransactionResponse TID:637 DONE 2019-12-05 08:50:00.880 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2019-12-05 08:50:00.881 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1 2019-12-05 08:50:00.881 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-12-05 08:50:00.881 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-12-05 08:50:00.881 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 637: Transaction event listener: DONE: DONE -> 2019-12-05 08:50:00.881 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-12-05 08:50:00.882 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 5: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@634be07f 2019-12-05 08:50:00.882 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 5: Node Init transaction completed with response COMPLETE 2019-12-05 08:50:00.882 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY NOT required on COMMAND_CLASS_SENSOR_MULTILEVEL 2019-12-05 08:50:00.882 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: Command Class COMMAND_CLASS_SENSOR_MULTILEVEL is NOT required to be secured 2019-12-05 08:50:00.882 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 5: ZWaveCommandClassTransactionPayload - send to node 2019-12-05 08:50:00.882 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY NOT required on COMMAND_CLASS_SENSOR_MULTILEVEL 2019-12-05 08:50:00.882 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: Command Class COMMAND_CLASS_SENSOR_MULTILEVEL is NOT required to be secured 2019-12-05 08:50:00.882 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@22c835e5 2019-12-05 08:50:00.882 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Bump transaction 638 priority from Get to Immediate 2019-12-05 08:50:00.883 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Adding to device queue 2019-12-05 08:50:00.883 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Added 638 to queue - size 3 2019-12-05 08:50:00.883 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-12-05 08:50:00.883 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0B 00 13 05 04 31 04 36 00 25 4D 8D 2019-12-05 08:50:00.883 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 5: Sending REQUEST Message = 01 0B 00 13 05 04 31 04 36 00 25 4D 8D 2019-12-05 08:50:00.884 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT 2019-12-05 08:50:00.884 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 638: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 77 2019-12-05 08:50:00.885 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2019-12-05 08:50:00.885 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-12-05 08:50:00.886 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-12-05 08:50:00.886 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 638: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 77 2019-12-05 08:50:00.886 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK 2019-12-05 08:50:00.886 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-12-05 08:50:00.886 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-12-05 08:50:00.892 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2019-12-05 08:50:00.893 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-12-05 08:50:00.893 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-12-05 08:50:00.894 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 638: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 77 2019-12-05 08:50:00.894 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-12-05 08:50:00.895 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 638: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 77 2019-12-05 08:50:00.895 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-12-05 08:50:00.896 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 5: sentData successfully placed on stack. 2019-12-05 08:50:00.896 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 638: Advanced to WAIT_REQUEST 2019-12-05 08:50:00.897 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: TID 638: Transaction not completed 2019-12-05 08:50:00.897 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-12-05 08:50:00.898 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-12-05 08:50:01.016 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 4D 00 00 0D AB 2019-12-05 08:50:01.017 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=77, payload=4D 00 00 0D 2019-12-05 08:50:01.019 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=77, payload=4D 00 00 0D 2019-12-05 08:50:01.021 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 638: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 77 2019-12-05 08:50:01.022 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-12-05 08:50:01.023 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 638: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 77 2019-12-05 08:50:01.024 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 638: (Callback 77) 2019-12-05 08:50:01.025 [DEBUG] [ernal.protocol.serialmessage.ZWaveCommandProcessor] - Callback match! 2019-12-05 08:50:01.026 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Correlated to TID 638: callback 77 2019-12-05 08:50:01.027 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=77, payload=4D 00 00 0D 2019-12-05 08:50:01.027 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 5: SendData Request. CallBack ID = 77, Status = Transmission complete and ACK received(0) 2019-12-05 08:50:01.027 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 638: Advanced to WAIT_DATA 2019-12-05 08:50:01.027 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: TID 638: Transaction not completed 2019-12-05 08:50:01.028 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-12-05 08:50:01.028 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-12-05 08:50:01.100 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0B 00 04 00 05 05 31 05 36 21 00 D3 2019-12-05 08:50:01.102 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 05 31 05 36 21 00 2019-12-05 08:50:01.103 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 05 31 05 36 21 00 2019-12-05 08:50:01.104 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null 2019-12-05 08:50:01.105 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Application Command Request (ALIVE:DYNAMIC_VALUES) 2019-12-05 08:50:01.105 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: Incoming command class COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 0 2019-12-05 08:50:01.106 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY NOT required on COMMAND_CLASS_SENSOR_MULTILEVEL 2019-12-05 08:50:01.107 [DEBUG] [e.internal.protocol.commandclass.ZWaveCommandClass] - NODE 5: Received COMMAND_CLASS_SENSOR_MULTILEVEL V8 SENSOR_MULTILEVEL_REPORT 2019-12-05 08:50:01.108 [DEBUG] [col.commandclass.ZWaveMultiLevelSensorCommandClass] - NODE 5: Sensor Type = Accelleration Z-Axis(54), Scale = 0 2019-12-05 08:50:01.108 [DEBUG] [col.commandclass.ZWaveMultiLevelSensorCommandClass] - NODE 5: Sensor Value = 0E+1 2019-12-05 08:50:01.110 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent 2019-12-05 08:50:01.110 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SENSOR_MULTILEVEL, value=0E+1 2019-12-05 08:50:01.111 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Commands processed 1. 2019-12-05 08:50:01.112 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@22d924d1. 2019-12-05 08:50:01.113 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@22d924d1. 2019-12-05 08:50:01.113 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: notifyTransactionResponse TID:638 DONE 2019-12-05 08:50:01.113 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2019-12-05 08:50:01.114 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1 2019-12-05 08:50:01.114 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 638: Transaction event listener: DONE: DONE -> 2019-12-05 08:50:01.114 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-12-05 08:50:01.114 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-12-05 08:50:01.114 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-12-05 08:50:01.115 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 5: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@3026cdd4 2019-12-05 08:50:01.115 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 5: Node Init transaction completed with response COMPLETE 2019-12-05 08:50:01.115 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY NOT required on COMMAND_CLASS_SENSOR_MULTILEVEL 2019-12-05 08:50:01.115 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: Command Class COMMAND_CLASS_SENSOR_MULTILEVEL is NOT required to be secured 2019-12-05 08:50:01.115 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 5: ZWaveCommandClassTransactionPayload - send to node 2019-12-05 08:50:01.115 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY NOT required on COMMAND_CLASS_SENSOR_MULTILEVEL 2019-12-05 08:50:01.115 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: Command Class COMMAND_CLASS_SENSOR_MULTILEVEL is NOT required to be secured 2019-12-05 08:50:01.115 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@1e897274 2019-12-05 08:50:01.115 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Bump transaction 639 priority from Get to Immediate 2019-12-05 08:50:01.116 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Adding to device queue 2019-12-05 08:50:01.116 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Added 639 to queue - size 3 2019-12-05 08:50:01.116 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-12-05 08:50:01.116 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0B 00 13 05 04 31 04 19 00 25 4E A1 2019-12-05 08:50:01.116 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 5: Sending REQUEST Message = 01 0B 00 13 05 04 31 04 19 00 25 4E A1 2019-12-05 08:50:01.116 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT 2019-12-05 08:50:01.116 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 639: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 78 2019-12-05 08:50:01.117 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2019-12-05 08:50:01.118 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-12-05 08:50:01.118 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-12-05 08:50:01.118 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 639: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 78 2019-12-05 08:50:01.118 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK 2019-12-05 08:50:01.118 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-12-05 08:50:01.118 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-12-05 08:50:01.124 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2019-12-05 08:50:01.125 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-12-05 08:50:01.125 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-12-05 08:50:01.125 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 639: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 78 2019-12-05 08:50:01.126 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-12-05 08:50:01.126 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 639: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 78 2019-12-05 08:50:01.126 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-12-05 08:50:01.127 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 5: sentData successfully placed on stack. 2019-12-05 08:50:01.127 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 639: Advanced to WAIT_REQUEST 2019-12-05 08:50:01.127 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: TID 639: Transaction not completed 2019-12-05 08:50:01.127 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-12-05 08:50:01.128 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-12-05 08:50:01.248 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 4E 00 00 0C A9 2019-12-05 08:50:01.249 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=78, payload=4E 00 00 0C 2019-12-05 08:50:01.251 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=78, payload=4E 00 00 0C 2019-12-05 08:50:01.253 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 639: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 78 2019-12-05 08:50:01.253 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-12-05 08:50:01.255 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 639: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 78 2019-12-05 08:50:01.255 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 639: (Callback 78) 2019-12-05 08:50:01.257 [DEBUG] [ernal.protocol.serialmessage.ZWaveCommandProcessor] - Callback match! 2019-12-05 08:50:01.258 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Correlated to TID 639: callback 78 2019-12-05 08:50:01.259 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=78, payload=4E 00 00 0C 2019-12-05 08:50:01.259 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 5: SendData Request. CallBack ID = 78, Status = Transmission complete and ACK received(0) 2019-12-05 08:50:01.260 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 639: Advanced to WAIT_DATA 2019-12-05 08:50:01.260 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: TID 639: Transaction not completed 2019-12-05 08:50:01.260 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-12-05 08:50:01.260 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-12-05 08:50:01.300 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0B 00 04 00 05 05 31 05 19 21 00 FC 2019-12-05 08:50:01.303 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 05 31 05 19 21 00 2019-12-05 08:50:01.303 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 05 31 05 19 21 00 2019-12-05 08:50:01.304 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null 2019-12-05 08:50:01.305 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Application Command Request (ALIVE:DYNAMIC_VALUES) 2019-12-05 08:50:01.305 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: Incoming command class COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 0 2019-12-05 08:50:01.306 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY NOT required on COMMAND_CLASS_SENSOR_MULTILEVEL 2019-12-05 08:50:01.306 [DEBUG] [e.internal.protocol.commandclass.ZWaveCommandClass] - NODE 5: Received COMMAND_CLASS_SENSOR_MULTILEVEL V8 SENSOR_MULTILEVEL_REPORT 2019-12-05 08:50:01.306 [DEBUG] [col.commandclass.ZWaveMultiLevelSensorCommandClass] - NODE 5: Sensor Type = SeismicIntensity(25), Scale = 0 2019-12-05 08:50:01.307 [DEBUG] [col.commandclass.ZWaveMultiLevelSensorCommandClass] - NODE 5: Sensor Value = 0E+1 2019-12-05 08:50:01.307 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent 2019-12-05 08:50:01.307 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SENSOR_MULTILEVEL, value=0E+1 2019-12-05 08:50:01.308 [DEBUG] [.internal.converter.ZWaveMultiLevelSensorConverter] - NODE 5: Sensor conversion not performed for SEISMIC_INTENSITY. 2019-12-05 08:50:01.308 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Updating channel state zwave:device:4711b0e7:node5:sensor_seismicintensity to 0 [DecimalType] 2019-12-05 08:50:01.309 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Commands processed 1. 2019-12-05 08:50:01.309 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@48fa2f51. 2019-12-05 08:50:01.310 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@48fa2f51. 2019-12-05 08:50:01.310 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: notifyTransactionResponse TID:639 DONE 2019-12-05 08:50:01.311 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2019-12-05 08:50:01.312 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1 2019-12-05 08:50:01.311 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 639: Transaction event listener: DONE: DONE -> 2019-12-05 08:50:01.312 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-12-05 08:50:01.312 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 5: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@22fd21c2 2019-12-05 08:50:01.313 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 5: Node Init transaction completed with response COMPLETE 2019-12-05 08:50:01.313 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 5: Node advancer: DYNAMIC_VALUES - checking COMMAND_CLASS_WAKE_UP 2019-12-05 08:50:01.313 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 5: Node advancer: DYNAMIC_VALUES - checking COMMAND_CLASS_SECURITY 2019-12-05 08:50:01.313 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 5: Node advancer: DYNAMIC_VALUES - checking COMMAND_CLASS_POWERLEVEL 2019-12-05 08:50:01.313 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-12-05 08:50:01.313 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 5: Found 1 instances of COMMAND_CLASS_POWERLEVEL for endpoint 0 2019-12-05 08:50:01.313 [DEBUG] [.protocol.commandclass.ZWavePowerLevelCommandClass] - NODE 5: Creating new message for application command POWERLEVEL_GET 2019-12-05 08:50:01.313 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY NOT required on COMMAND_CLASS_POWERLEVEL 2019-12-05 08:50:01.313 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: Command Class COMMAND_CLASS_POWERLEVEL is NOT required to be secured 2019-12-05 08:50:01.313 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-12-05 08:50:01.314 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 5: ZWaveCommandClassTransactionPayload - send to node 2019-12-05 08:50:01.314 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY NOT required on COMMAND_CLASS_POWERLEVEL 2019-12-05 08:50:01.314 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: Command Class COMMAND_CLASS_POWERLEVEL is NOT required to be secured 2019-12-05 08:50:01.314 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@41f95f65 2019-12-05 08:50:01.314 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Bump transaction 640 priority from Config to Immediate 2019-12-05 08:50:01.314 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Adding to device queue 2019-12-05 08:50:01.314 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Added 640 to queue - size 3 2019-12-05 08:50:01.314 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-12-05 08:50:01.314 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 05 02 73 02 25 4F F9 2019-12-05 08:50:01.315 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 5: Sending REQUEST Message = 01 09 00 13 05 02 73 02 25 4F F9 2019-12-05 08:50:01.315 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT 2019-12-05 08:50:01.315 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 640: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 79 2019-12-05 08:50:01.317 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2019-12-05 08:50:01.317 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-12-05 08:50:01.317 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-12-05 08:50:01.317 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 640: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 79 2019-12-05 08:50:01.317 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK 2019-12-05 08:50:01.317 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-12-05 08:50:01.317 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-12-05 08:50:01.323 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2019-12-05 08:50:01.324 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-12-05 08:50:01.324 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-12-05 08:50:01.324 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 640: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 79 2019-12-05 08:50:01.325 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-12-05 08:50:01.325 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 640: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 79 2019-12-05 08:50:01.325 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-12-05 08:50:01.326 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 5: sentData successfully placed on stack. 2019-12-05 08:50:01.326 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 640: Advanced to WAIT_REQUEST 2019-12-05 08:50:01.327 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: TID 640: Transaction not completed 2019-12-05 08:50:01.327 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-12-05 08:50:01.327 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-12-05 08:50:01.445 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 4F 00 00 0C A8 2019-12-05 08:50:01.446 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=79, payload=4F 00 00 0C 2019-12-05 08:50:01.446 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=79, payload=4F 00 00 0C 2019-12-05 08:50:01.448 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 640: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 79 2019-12-05 08:50:01.448 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-12-05 08:50:01.450 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 640: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 79 2019-12-05 08:50:01.450 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 640: (Callback 79) 2019-12-05 08:50:01.452 [DEBUG] [ernal.protocol.serialmessage.ZWaveCommandProcessor] - Callback match! 2019-12-05 08:50:01.452 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Correlated to TID 640: callback 79 2019-12-05 08:50:01.454 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=79, payload=4F 00 00 0C 2019-12-05 08:50:01.455 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 5: SendData Request. CallBack ID = 79, Status = Transmission complete and ACK received(0) 2019-12-05 08:50:01.456 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 640: Advanced to WAIT_DATA 2019-12-05 08:50:01.456 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: TID 640: Transaction not completed 2019-12-05 08:50:01.457 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-12-05 08:50:01.457 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-12-05 08:50:01.529 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0A 00 04 00 05 04 73 03 00 00 80 2019-12-05 08:50:01.530 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 04 73 03 00 00 2019-12-05 08:50:01.531 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 04 73 03 00 00 2019-12-05 08:50:01.532 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null 2019-12-05 08:50:01.534 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Application Command Request (ALIVE:DYNAMIC_VALUES) 2019-12-05 08:50:01.534 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: WakeupTimerTask 1 Messages waiting, state DYNAMIC_VALUES 2019-12-05 08:50:01.535 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: Incoming command class COMMAND_CLASS_POWERLEVEL, endpoint 0 2019-12-05 08:50:01.535 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY NOT required on COMMAND_CLASS_POWERLEVEL 2019-12-05 08:50:01.536 [DEBUG] [e.internal.protocol.commandclass.ZWaveCommandClass] - NODE 5: Received COMMAND_CLASS_POWERLEVEL V1 POWERLEVEL_REPORT 2019-12-05 08:50:01.536 [DEBUG] [.protocol.commandclass.ZWavePowerLevelCommandClass] - NODE 5: Received POWERLEVEL report -0dB with 0 second timeout 2019-12-05 08:50:01.537 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWavePowerLevelCommandClassChangeEvent 2019-12-05 08:50:01.537 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_POWERLEVEL, value=0 2019-12-05 08:50:01.538 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Commands processed 1. 2019-12-05 08:50:01.538 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@4f17a538. 2019-12-05 08:50:01.539 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@4f17a538. 2019-12-05 08:50:01.539 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: notifyTransactionResponse TID:640 DONE 2019-12-05 08:50:01.540 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2019-12-05 08:50:01.540 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1 2019-12-05 08:50:01.540 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-12-05 08:50:01.540 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-12-05 08:50:01.540 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-12-05 08:50:01.541 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 640: Transaction event listener: DONE: DONE -> 2019-12-05 08:50:01.542 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 5: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@47b772e3 2019-12-05 08:50:01.542 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 5: Node Init transaction completed with response COMPLETE 2019-12-05 08:50:01.542 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 5: Node advancer: DYNAMIC_VALUES - checking COMMAND_CLASS_ASSOCIATION 2019-12-05 08:50:01.542 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 5: Node advancer: DYNAMIC_VALUES - checking COMMAND_CLASS_ALARM 2019-12-05 08:50:01.542 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 5: Found 1 instances of COMMAND_CLASS_ALARM for endpoint 0 2019-12-05 08:50:01.542 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 5: Node advancer: DYNAMIC_VALUES - checking COMMAND_CLASS_CRC_16_ENCAP 2019-12-05 08:50:01.542 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 5: Node advancer: DYNAMIC_VALUES - checking COMMAND_CLASS_MULTI_CHANNEL_ASSOCIATION 2019-12-05 08:50:01.543 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 5: Node advancer: Initialisation complete! 2019-12-05 08:50:01.543 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 5: Node advancer - advancing to DYNAMIC_END 2019-12-05 08:50:01.543 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveInitializationStateEvent 2019-12-05 08:50:01.543 [DEBUG] [ternal.protocol.initialization.ZWaveNodeSerializer] - NODE 5: Serializing to file /var/lib/openhab2/zwave/network_c0fc949e__node_5.xml 2019-12-05 08:50:01.551 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 5: Node advancer - advancing to DONE 2019-12-05 08:50:01.551 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveInitializationStateEvent 2019-12-05 08:50:01.560 [DEBUG] [ternal.protocol.initialization.ZWaveNodeSerializer] - NODE 5: Serializing to file /var/lib/openhab2/zwave/network_c0fc949e__node_5.xml 2019-12-05 08:50:04.034 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: WakeupTimerTask 0 Messages waiting, state DONE 2019-12-05 08:50:04.036 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: No more messages, go back to sleep 2019-12-05 08:50:04.037 [DEBUG] [rnal.protocol.commandclass.ZWaveWakeUpCommandClass] - NODE 5: Creating new message for application command WAKE_UP_NO_MORE_INFORMATION 2019-12-05 08:50:04.038 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY NOT required on COMMAND_CLASS_WAKE_UP 2019-12-05 08:50:04.039 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: Command Class COMMAND_CLASS_WAKE_UP is NOT required to be secured 2019-12-05 08:50:04.040 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@732ba0e8 2019-12-05 08:50:04.041 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Bump transaction 641 priority from Immediate to Immediate 2019-12-05 08:50:04.041 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Adding to device queue 2019-12-05 08:50:04.042 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Added 641 to queue - size 3 2019-12-05 08:50:04.042 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-12-05 08:50:04.043 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 05 02 84 08 25 50 1B 2019-12-05 08:50:04.044 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 5: Sending REQUEST Message = 01 09 00 13 05 02 84 08 25 50 1B 2019-12-05 08:50:04.045 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT 2019-12-05 08:50:04.046 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 641: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 80 2019-12-05 08:50:04.046 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2019-12-05 08:50:04.046 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-12-05 08:50:04.047 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-12-05 08:50:04.047 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 641: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 80 2019-12-05 08:50:04.047 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK 2019-12-05 08:50:04.047 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-12-05 08:50:04.047 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-12-05 08:50:04.052 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2019-12-05 08:50:04.053 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-12-05 08:50:04.053 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-12-05 08:50:04.054 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 641: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 80 2019-12-05 08:50:04.054 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-12-05 08:50:04.054 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 641: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 80 2019-12-05 08:50:04.054 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-12-05 08:50:04.054 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 5: sentData successfully placed on stack. 2019-12-05 08:50:04.054 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 641: Advanced to WAIT_REQUEST 2019-12-05 08:50:04.054 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: TID 641: Transaction not completed 2019-12-05 08:50:04.054 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-12-05 08:50:04.055 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-12-05 08:50:04.129 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 50 00 00 09 B2 2019-12-05 08:50:04.129 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=80, payload=50 00 00 09 2019-12-05 08:50:04.131 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=80, payload=50 00 00 09 2019-12-05 08:50:04.131 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 641: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 80 2019-12-05 08:50:04.131 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-12-05 08:50:04.131 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 641: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 80 2019-12-05 08:50:04.132 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 641: (Callback 80) 2019-12-05 08:50:04.132 [DEBUG] [ernal.protocol.serialmessage.ZWaveCommandProcessor] - Callback match! 2019-12-05 08:50:04.132 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Correlated to TID 641: callback 80 2019-12-05 08:50:04.133 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=80, payload=50 00 00 09 2019-12-05 08:50:04.133 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 5: SendData Request. CallBack ID = 80, Status = Transmission complete and ACK received(0) 2019-12-05 08:50:04.133 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: resetResendCount initComplete=true isDead=false 2019-12-05 08:50:04.134 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 641: Transaction COMPLETED 2019-12-05 08:50:04.134 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Response processed after 89ms 2019-12-05 08:50:04.134 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: TID 641: Transaction completed 2019-12-05 08:50:04.134 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: notifyTransactionResponse TID:641 DONE 2019-12-05 08:50:04.135 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2019-12-05 08:50:04.135 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-12-05 08:50:04.135 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-12-05 08:50:04.136 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 641: Transaction event listener: DONE: DONE -> 2019-12-05 08:50:04.137 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: Went to sleep COMPLETE 2019-12-05 08:50:05.439 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0F 00 04 00 05 09 71 05 00 00 00 FF 07 08 00 7C 2019-12-05 08:50:05.442 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 09 71 05 00 00 00 FF 07 08 00 2019-12-05 08:50:05.445 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 09 71 05 00 00 00 FF 07 08 00 2019-12-05 08:50:05.446 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null 2019-12-05 08:50:05.446 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Application Command Request (ALIVE:DONE) 2019-12-05 08:50:05.447 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: resetResendCount initComplete=true isDead=false 2019-12-05 08:50:05.447 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: Incoming command class COMMAND_CLASS_ALARM, endpoint 0 2019-12-05 08:50:05.447 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY NOT required on COMMAND_CLASS_ALARM 2019-12-05 08:50:05.448 [DEBUG] [e.internal.protocol.commandclass.ZWaveCommandClass] - NODE 5: Received COMMAND_CLASS_ALARM V5 NOTIFICATION_REPORT 2019-12-05 08:50:05.448 [DEBUG] [ernal.protocol.commandclass.ZWaveAlarmCommandClass] - NODE 5: NOTIFICATION report - 0 = 0, event=8, status=255, plen=0 2019-12-05 08:50:05.448 [DEBUG] [ernal.protocol.commandclass.ZWaveAlarmCommandClass] - NODE 5: Alarm Type = BURGLAR (0) 2019-12-05 08:50:05.449 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveAlarmValueEvent 2019-12-05 08:50:05.449 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_ALARM, value=255 2019-12-05 08:50:05.449 [DEBUG] [nding.zwave.internal.converter.ZWaveAlarmConverter] - NODE 5: Alarm converter processing NOTIFICATION 2019-12-05 08:50:05.449 [DEBUG] [nding.zwave.internal.converter.ZWaveAlarmConverter] - NODE 5: Alarm converter NOTIFICATION event is 8, type OnOffType 2019-12-05 08:50:05.450 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Updating channel state zwave:device:4711b0e7:node5:alarm_motion to ON [OnOffType] 2019-12-05 08:50:05.450 [DEBUG] [nding.zwave.internal.converter.ZWaveAlarmConverter] - NODE 5: Alarm converter processing NOTIFICATION 2019-12-05 08:50:05.450 [DEBUG] [nding.zwave.internal.converter.ZWaveAlarmConverter] - NODE 5: Alarm converter NOTIFICATION event is 8, type OnOffType 2019-12-05 08:50:05.451 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Commands processed 1. 2019-12-05 08:50:05.451 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@33c97746. 2019-12-05 08:50:05.451 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-12-05 08:50:05.451 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-12-05 08:50:05.451 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-12-05 08:50:05.451 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-12-05 08:50:16.125 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 1D 00 49 84 13 17 04 10 01 5E 25 27 32 81 71 60 8E 2C 2B 70 86 72 73 85 59 7A 5A EF 82 2F 2019-12-05 08:50:16.128 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationUpdate[73], type=Request[0], dest=19, callback=132, payload=84 13 17 04 10 01 5E 25 27 32 81 71 60 8E 2C 2B 70 86 72 73 85 59 7A 5A EF 82 2019-12-05 08:50:16.130 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationUpdate[73], type=Request[0], dest=19, callback=132, payload=84 13 17 04 10 01 5E 25 27 32 81 71 60 8E 2C 2B 70 86 72 73 85 59 7A 5A EF 82 2019-12-05 08:50:16.131 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null 2019-12-05 08:50:16.131 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 0 2019-12-05 08:50:16.132 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: null 2019-12-05 08:50:16.132 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=ApplicationUpdate[73], type=Request[0], dest=19, callback=132, payload=84 13 17 04 10 01 5E 25 27 32 81 71 60 8E 2C 2B 70 86 72 73 85 59 7A 5A EF 82 2019-12-05 08:50:16.133 [DEBUG] [otocol.serialmessage.ApplicationUpdateMessageClass] - NODE 19: Application update request. Node information received. Transaction null 2019-12-05 08:50:16.133 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 19: resetResendCount initComplete=true isDead=false 2019-12-05 08:50:16.134 [DEBUG] [otocol.serialmessage.ApplicationUpdateMessageClass] - NODE 19: Application update - no transaction. 2019-12-05 08:50:16.134 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-12-05 08:50:16.134 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-12-05 08:50:35.541 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 04 00 05 0A 71 05 00 00 00 FF 07 00 01 08 61 2019-12-05 08:50:35.543 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 0A 71 05 00 00 00 FF 07 00 01 08 2019-12-05 08:50:35.546 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 0A 71 05 00 00 00 FF 07 00 01 08 2019-12-05 08:50:35.546 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null 2019-12-05 08:50:35.547 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Application Command Request (ALIVE:DONE) 2019-12-05 08:50:35.547 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: resetResendCount initComplete=true isDead=false 2019-12-05 08:50:35.547 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: Incoming command class COMMAND_CLASS_ALARM, endpoint 0 2019-12-05 08:50:35.548 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY NOT required on COMMAND_CLASS_ALARM 2019-12-05 08:50:35.548 [DEBUG] [e.internal.protocol.commandclass.ZWaveCommandClass] - NODE 5: Received COMMAND_CLASS_ALARM V5 NOTIFICATION_REPORT 2019-12-05 08:50:35.549 [DEBUG] [ernal.protocol.commandclass.ZWaveAlarmCommandClass] - NODE 5: NOTIFICATION report - 0 = 0, event=0, status=255, plen=1 2019-12-05 08:50:35.549 [DEBUG] [ernal.protocol.commandclass.ZWaveAlarmCommandClass] - NODE 5: Alarm Type = BURGLAR (0) 2019-12-05 08:50:35.549 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveAlarmValueEvent 2019-12-05 08:50:35.550 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_ALARM, value=255 2019-12-05 08:50:35.550 [DEBUG] [nding.zwave.internal.converter.ZWaveAlarmConverter] - NODE 5: Alarm converter processing NOTIFICATION 2019-12-05 08:50:35.551 [DEBUG] [nding.zwave.internal.converter.ZWaveAlarmConverter] - NODE 5: Alarm converter NOTIFICATION event is 0, type OnOffType 2019-12-05 08:50:35.551 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Updating channel state zwave:device:4711b0e7:node5:alarm_motion to OFF [OnOffType] 2019-12-05 08:50:35.552 [DEBUG] [nding.zwave.internal.converter.ZWaveAlarmConverter] - NODE 5: Alarm converter processing NOTIFICATION 2019-12-05 08:50:35.554 [DEBUG] [nding.zwave.internal.converter.ZWaveAlarmConverter] - NODE 5: Alarm converter NOTIFICATION event is 0, type OnOffType 2019-12-05 08:50:35.554 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Updating channel state zwave:device:4711b0e7:node5:alarm_tamper to OFF [OnOffType] 2019-12-05 08:50:35.554 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Commands processed 1. 2019-12-05 08:50:35.554 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@58dd3242. 2019-12-05 08:50:35.554 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-12-05 08:50:35.554 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-12-05 08:50:35.554 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-12-05 08:50:35.554 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-12-05 08:50:44.022 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 04 00 04 0A 71 05 00 00 00 FF 07 00 01 08 60 2019-12-05 08:50:44.024 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=4, callback=0, payload=00 04 0A 71 05 00 00 00 FF 07 00 01 08 2019-12-05 08:50:44.025 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=4, callback=0, payload=00 04 0A 71 05 00 00 00 FF 07 00 01 08 2019-12-05 08:50:44.025 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null 2019-12-05 08:50:44.025 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 4: Application Command Request (ALIVE:REQUEST_NIF) 2019-12-05 08:50:44.026 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 4: Incoming command class COMMAND_CLASS_ALARM, endpoint 0 2019-12-05 08:50:44.026 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 4: SECURITY NOT required on COMMAND_CLASS_ALARM 2019-12-05 08:50:44.027 [DEBUG] [e.internal.protocol.commandclass.ZWaveCommandClass] - NODE 4: Received COMMAND_CLASS_ALARM V5 NOTIFICATION_REPORT 2019-12-05 08:50:44.027 [DEBUG] [ernal.protocol.commandclass.ZWaveAlarmCommandClass] - NODE 4: NOTIFICATION report - 0 = 0, event=0, status=255, plen=1 2019-12-05 08:50:44.027 [DEBUG] [ernal.protocol.commandclass.ZWaveAlarmCommandClass] - NODE 4: Alarm Type = BURGLAR (0) 2019-12-05 08:50:44.028 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 4: Got an event from Z-Wave network: ZWaveAlarmValueEvent 2019-12-05 08:50:44.028 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 4: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_ALARM, value=255 2019-12-05 08:50:44.028 [DEBUG] [nding.zwave.internal.converter.ZWaveAlarmConverter] - NODE 4: Alarm converter processing NOTIFICATION 2019-12-05 08:50:44.029 [DEBUG] [nding.zwave.internal.converter.ZWaveAlarmConverter] - NODE 4: Alarm converter NOTIFICATION event is 0, type OnOffType 2019-12-05 08:50:44.029 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 4: Updating channel state zwave:device:4711b0e7:node4:alarm_motion to OFF [OnOffType] 2019-12-05 08:50:44.029 [DEBUG] [nding.zwave.internal.converter.ZWaveAlarmConverter] - NODE 4: Alarm converter processing NOTIFICATION 2019-12-05 08:50:44.029 [DEBUG] [nding.zwave.internal.converter.ZWaveAlarmConverter] - NODE 4: Alarm converter NOTIFICATION event is 0, type OnOffType 2019-12-05 08:50:44.029 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 4: Updating channel state zwave:device:4711b0e7:node4:alarm_tamper to OFF [OnOffType] 2019-12-05 08:50:44.029 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 4: Commands processed 1. 2019-12-05 08:50:44.029 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 4: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@41c41eaf. 2019-12-05 08:50:44.029 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-12-05 08:50:44.030 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-12-05 08:50:44.030 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-12-05 08:50:44.030 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-12-05 08:50:49.543 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0F 00 04 00 04 09 71 05 00 00 00 FF 07 08 00 7D 2019-12-05 08:50:49.544 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=4, callback=0, payload=00 04 09 71 05 00 00 00 FF 07 08 00 2019-12-05 08:50:49.544 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=4, callback=0, payload=00 04 09 71 05 00 00 00 FF 07 08 00 2019-12-05 08:50:49.544 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null 2019-12-05 08:50:49.544 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 4: Application Command Request (ALIVE:REQUEST_NIF) 2019-12-05 08:50:49.544 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 4: Incoming command class COMMAND_CLASS_ALARM, endpoint 0 2019-12-05 08:50:49.544 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 4: SECURITY NOT required on COMMAND_CLASS_ALARM 2019-12-05 08:50:49.545 [DEBUG] [e.internal.protocol.commandclass.ZWaveCommandClass] - NODE 4: Received COMMAND_CLASS_ALARM V5 NOTIFICATION_REPORT 2019-12-05 08:50:49.545 [DEBUG] [ernal.protocol.commandclass.ZWaveAlarmCommandClass] - NODE 4: NOTIFICATION report - 0 = 0, event=8, status=255, plen=0 2019-12-05 08:50:49.545 [DEBUG] [ernal.protocol.commandclass.ZWaveAlarmCommandClass] - NODE 4: Alarm Type = BURGLAR (0) 2019-12-05 08:50:49.545 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 4: Got an event from Z-Wave network: ZWaveAlarmValueEvent 2019-12-05 08:50:49.545 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 4: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_ALARM, value=255 2019-12-05 08:50:49.545 [DEBUG] [nding.zwave.internal.converter.ZWaveAlarmConverter] - NODE 4: Alarm converter processing NOTIFICATION 2019-12-05 08:50:49.545 [DEBUG] [nding.zwave.internal.converter.ZWaveAlarmConverter] - NODE 4: Alarm converter NOTIFICATION event is 8, type OnOffType 2019-12-05 08:50:49.545 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 4: Updating channel state zwave:device:4711b0e7:node4:alarm_motion to ON [OnOffType] 2019-12-05 08:50:49.546 [DEBUG] [nding.zwave.internal.converter.ZWaveAlarmConverter] - NODE 4: Alarm converter processing NOTIFICATION 2019-12-05 08:50:49.546 [DEBUG] [nding.zwave.internal.converter.ZWaveAlarmConverter] - NODE 4: Alarm converter NOTIFICATION event is 8, type OnOffType 2019-12-05 08:50:49.546 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 4: Commands processed 1. 2019-12-05 08:50:49.546 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 4: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@33085dca. 2019-12-05 08:50:49.546 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-12-05 08:50:49.546 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-12-05 08:50:49.546 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-12-05 08:50:49.546 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-12-05 08:50:57.606 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 1D 00 49 84 13 17 04 10 01 5E 25 27 32 81 71 60 8E 2C 2B 70 86 72 73 85 59 7A 5A EF 82 2F 2019-12-05 08:50:57.609 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationUpdate[73], type=Request[0], dest=19, callback=132, payload=84 13 17 04 10 01 5E 25 27 32 81 71 60 8E 2C 2B 70 86 72 73 85 59 7A 5A EF 82 2019-12-05 08:50:57.611 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationUpdate[73], type=Request[0], dest=19, callback=132, payload=84 13 17 04 10 01 5E 25 27 32 81 71 60 8E 2C 2B 70 86 72 73 85 59 7A 5A EF 82 2019-12-05 08:50:57.612 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null 2019-12-05 08:50:57.612 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 0 2019-12-05 08:50:57.612 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: null 2019-12-05 08:50:57.612 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=ApplicationUpdate[73], type=Request[0], dest=19, callback=132, payload=84 13 17 04 10 01 5E 25 27 32 81 71 60 8E 2C 2B 70 86 72 73 85 59 7A 5A EF 82 2019-12-05 08:50:57.613 [DEBUG] [otocol.serialmessage.ApplicationUpdateMessageClass] - NODE 19: Application update request. Node information received. Transaction null 2019-12-05 08:50:57.613 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 19: resetResendCount initComplete=true isDead=false 2019-12-05 08:50:57.613 [DEBUG] [otocol.serialmessage.ApplicationUpdateMessageClass] - NODE 19: Application update - no transaction. 2019-12-05 08:50:57.614 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-12-05 08:50:57.614 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.