09:22:52.087 [INFO ] [smarthome.event.ItemCommandEvent ] - Item 'EG_Licht_Sofa_dimmer' received command 75 09:22:52.089 [INFO ] [arthome.event.ItemStatePredictedEvent] - EG_Licht_Sofa_dimmer predicted to become 75 09:22:52.089 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 56: Command received zwave:device:512:node56:switch_dimmer --> 75 [PercentType] 09:22:52.092 [INFO ] [smarthome.event.ItemStateChangedEvent] - EG_Licht_Sofa_dimmer changed from 0 to 75 09:22:52.092 [DEBUG] [ass.ZWaveMultiLevelSwitchCommandClass] - NODE 56: Creating new message for command SWITCH_MULTILEVEL_SET 09:22:52.096 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 56: SECURITY not supported 09:22:52.097 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 56: Command Class COMMAND_CLASS_SWITCH_MULTILEVEL is NOT required to be secured 09:22:52.098 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 56: Adding to device queue 09:22:52.099 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 56: Added 10076 to queue - size 33 09:22:52.100 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 09:22:52.102 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 56: Polling intialised at 1800 seconds - start in 1500 milliseconds. 09:22:52.855 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 51 01 AA 09:22:52.857 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=AssignSucReturnRoute[81], type=Response[1], dest=255, callback=0, payload=01 09:22:52.860 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=AssignSucReturnRoute[81], type=Response[1], dest=255, callback=0, payload=01 09:22:52.861 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 18 09:22:52.861 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 10071: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 213 09:22:52.862 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=CAN[4], dest=255, callback=0, payload= 09:22:52.865 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 09:22:52.866 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 10071: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 213 09:22:52.866 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=CAN[4], dest=255, callback=0, payload= 09:22:52.867 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 10071: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 213 09:22:52.868 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Holdoff Timer started... 09:22:52.868 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 10071: Resetting transaction 09:22:52.869 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 50: notifyTransactionResponse TID:10071 WAIT_RESPONSE 09:22:52.870 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 50: Adding to device queue 09:22:52.871 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 50: Added 10071 to queue - size 34 09:22:52.871 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff true. 09:22:52.872 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 09:22:52.873 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff true. 09:22:53.119 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 09:22:53.119 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 32 03 25 01 00 25 D5 03 09:22:53.120 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 50: Sending REQUEST Message = 01 0A 00 13 32 03 25 01 00 25 D5 03 09:22:53.122 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT 09:22:53.123 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 10071: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 213 09:22:53.602 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 56: Polling... 09:22:53.603 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 56: Polling deferred until initialisation complete 09:22:55.125 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - NODE 50: TID 10071: Timeout at state WAIT_RESPONSE. 3 retries remaining. 09:22:55.126 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - Aborting Transaction! 09:22:55.127 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 10071: Transaction ABORTED 09:22:55.129 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 03 00 16 EA 09:22:55.130 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 03 00 16 EA 09:22:55.132 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT 09:22:55.133 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 09:22:56.189 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 29: Polling... 09:22:56.190 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 29: Polling deferred until initialisation complete 09:22:56.955 [WARN ] [che.commons.httpclient.HttpMethodBase] - Stream closed 09:22:56.961 [INFO ] [smarthome.event.ItemStateChangedEvent] - LOCALWEATHER_windir changed from 277 to 328 09:22:56.972 [INFO ] [smarthome.event.ItemStateChangedEvent] - LOCALWEATHER_solarrad changed from 35.85 to 37.22 09:22:56.986 [INFO ] [smarthome.event.ItemStateChangedEvent] - LOCALWEATHER_uv changed from 102 to 105 09:22:58.011 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 09:22:58.012 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 09:22:58.013 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 09:22:58.014 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 10071: [ABORTED] priority=Set, requiresResponse=true, callback: 213 09:22:58.015 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg: ACK 09:22:58.016 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 09:22:58.018 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 09:22:58.024 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 09:22:58.025 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 09:22:58.026 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 18 09:22:58.026 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 09:22:58.027 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=CAN[4], dest=255, callback=0, payload= 09:22:58.027 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 10071: [ABORTED] priority=Set, requiresResponse=true, callback: 213 09:22:58.028 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0A 00 04 00 23 04 30 03 00 0C E9 09:22:58.028 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 09:22:58.029 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 1<>127 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=35, callback=0, payload=00 23 04 30 03 00 0C 09:22:58.031 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 10071: [ABORTED] priority=Set, requiresResponse=true, callback: 213 09:22:58.033 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 09:22:58.034 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 50: sentData successfully placed on stack. 09:22:58.035 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 50: TID 10071: Transaction not completed 09:22:58.036 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (1): Message: class=null[0], type=CAN[4], dest=255, callback=0, payload= 09:22:58.038 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 10071: [ABORTED] priority=Set, requiresResponse=true, callback: 213 09:22:58.042 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Holdoff Timer started... 09:22:58.043 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 10071: Resetting transaction 09:22:58.043 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 50: notifyTransactionResponse TID:10071 ABORTED 09:22:58.044 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 50: Adding to device queue 09:22:58.045 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 50: Added 10071 to queue - size 34 09:22:58.046 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff true. 09:22:58.047 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=35, callback=0, payload=00 23 04 30 03 00 0C 09:22:58.047 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null 09:22:58.048 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 35: Application Command Request (ALIVE:SUC_ROUTE) 09:22:58.049 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 35: Incoming command class COMMAND_CLASS_SENSOR_BINARY, endpoint 0 09:22:58.050 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 35: SECURITY not supported 09:22:58.050 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 35: Received COMMAND_CLASS_SENSOR_BINARY V2 SENSOR_BINARY_REPORT 09:22:58.051 [DEBUG] [ndclass.ZWaveBinarySensorCommandClass] - Processing Sensor Type 12 09:22:58.052 [DEBUG] [ndclass.ZWaveBinarySensorCommandClass] - Sensor Type is MOTION 09:22:58.052 [DEBUG] [ndclass.ZWaveBinarySensorCommandClass] - NODE 35: Sensor Binary report, type=Motion, value=0 09:22:58.053 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 35: Got an event from Z-Wave network: ZWaveBinarySensorValueEvent 09:22:58.054 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 35: Got a value event from Z-Wave network, endpoint = 0, command class = COMMAND_CLASS_SENSOR_BINARY, value = 0 09:22:58.055 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 35: Updating channel state zwave:device:512:node35:sensor_binary to OFF [OnOffType] 09:22:58.055 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 35: Commands processed 1. 09:22:58.056 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 35: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@3b3a4bd9. 09:22:58.057 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 09:22:58.057 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 09:22:58.058 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 09:22:58.059 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff true. 09:22:58.293 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 09:22:58.294 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 32 03 25 01 00 25 D5 03 09:22:58.295 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 50: Sending REQUEST Message = 01 0A 00 13 32 03 25 01 00 25 D5 03 09:22:58.297 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT 09:22:58.298 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 10071: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 213 09:23:00.299 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - NODE 50: TID 10071: Timeout at state WAIT_RESPONSE. 3 retries remaining. 09:23:00.300 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - Aborting Transaction! 09:23:00.301 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 10071: Transaction ABORTED 09:23:00.302 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 03 00 16 EA 09:23:00.302 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 03 00 16 EA 09:23:00.303 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT 09:23:00.304 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 09:23:03.436 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 09:23:03.437 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 09:23:03.438 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 09:23:03.439 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 10071: [ABORTED] priority=Set, requiresResponse=true, callback: 213 09:23:03.441 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg: ACK 09:23:03.441 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 09:23:03.443 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 09:23:03.443 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 00 E9 09:23:03.445 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=00 09:23:03.446 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 18 09:23:03.446 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=00 09:23:03.447 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=CAN[4], dest=255, callback=0, payload= 09:23:03.447 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 10071: [ABORTED] priority=Set, requiresResponse=true, callback: 213 09:23:03.448 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 09:23:03.449 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 10071: [ABORTED] priority=Set, requiresResponse=true, callback: 213 09:23:03.450 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=00 09:23:03.451 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 50: sentData was not placed on stack. 09:23:03.453 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 10071: Transaction CANCELLED 09:23:03.453 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 50: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 09:23:03.454 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 50: CANCEL while sending message. Requeueing - 2 attempts left! 09:23:03.451 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0F 00 04 00 23 09 71 05 00 00 00 FF 07 00 00 52 09:23:03.461 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 10071: Transaction RESET with 2 retries remaining. 09:23:03.462 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 1<>127 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=35, callback=0, payload=00 23 09 71 05 00 00 00 FF 07 00 00 09:23:03.463 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 50: Adding to device queue 09:23:03.464 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 50: Added 10071 to queue - size 34 09:23:03.464 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 09:23:03.465 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 32 03 25 01 00 25 D6 00 09:23:03.466 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 50: Sending REQUEST Message = 01 0A 00 13 32 03 25 01 00 25 D6 00 09:23:03.466 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT 09:23:03.467 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 10071: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 214 09:23:03.468 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 50: TID 10071: Transaction not completed 09:23:03.469 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (1): Message: class=null[0], type=CAN[4], dest=255, callback=0, payload= 09:23:03.470 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 10071: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 214 09:23:03.471 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Holdoff Timer started... 09:23:03.471 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 10071: Resetting transaction 09:23:03.472 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 50: notifyTransactionResponse TID:10071 WAIT_RESPONSE 09:23:03.473 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 50: Adding to device queue 09:23:03.474 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 50: Added 10071 to queue - size 34 09:23:03.475 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff true. 09:23:03.475 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=35, callback=0, payload=00 23 09 71 05 00 00 00 FF 07 00 00 09:23:03.476 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null 09:23:03.477 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 35: Application Command Request (ALIVE:SUC_ROUTE) 09:23:03.478 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 35: Incoming command class COMMAND_CLASS_ALARM, endpoint 0 09:23:03.479 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 35: SECURITY not supported 09:23:03.479 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 35: Received COMMAND_CLASS_ALARM V4 NOTIFICATION_REPORT 09:23:03.480 [DEBUG] [l.commandclass.ZWaveAlarmCommandClass] - NODE 35: NOTIFICATION report - 0 = 0, event=0, status=255, plen=0 09:23:03.481 [DEBUG] [l.commandclass.ZWaveAlarmCommandClass] - NODE 35: Alarm Type = BURGLAR (0) 09:23:03.482 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 35: Got an event from Z-Wave network: ZWaveAlarmValueEvent 09:23:03.483 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 35: Got a value event from Z-Wave network, endpoint = 0, command class = COMMAND_CLASS_ALARM, value = 255 09:23:03.484 [DEBUG] [nternal.converter.ZWaveAlarmConverter] - NODE 35: Alarm converter processing NOTIFICATION 09:23:03.485 [DEBUG] [nternal.converter.ZWaveAlarmConverter] - NODE 35: Alarm converter NOTIFICATION event is 0, type OnOffType 09:23:03.486 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 35: Updating channel state zwave:device:512:node35:alarm_motion to OFF [OnOffType] 09:23:03.487 [DEBUG] [nternal.converter.ZWaveAlarmConverter] - NODE 35: Alarm converter processing NOTIFICATION 09:23:03.489 [INFO ] [smarthome.event.ItemStateChangedEvent] - PIR1_motion changed from ON to OFF 09:23:03.489 [DEBUG] [nternal.converter.ZWaveAlarmConverter] - NODE 35: Alarm converter NOTIFICATION event is 0, type OnOffType 09:23:03.491 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 35: Updating channel state zwave:device:512:node35:alarm_tamper to OFF [OnOffType] 09:23:03.492 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 35: Commands processed 1. 09:23:03.493 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 35: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@7b29dd3b. 09:23:03.494 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 09:23:03.494 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 09:23:03.495 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 09:23:03.496 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff true. 09:23:03.721 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 09:23:03.722 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 32 03 25 01 00 25 D6 00 09:23:03.723 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 50: Sending REQUEST Message = 01 0A 00 13 32 03 25 01 00 25 D6 00 09:23:03.724 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT 09:23:03.725 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 10071: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 214 09:23:05.727 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - NODE 50: TID 10071: Timeout at state WAIT_RESPONSE. 2 retries remaining. 09:23:05.728 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - Aborting Transaction! 09:23:05.729 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 10071: Transaction ABORTED 09:23:05.731 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 03 00 16 EA 09:23:05.732 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 03 00 16 EA 09:23:05.734 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT 09:23:05.736 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 09:23:08.702 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0F 00 04 10 23 09 71 05 00 00 00 FF 07 00 00 42 09:23:08.704 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=35, callback=16, payload=10 23 09 71 05 00 00 00 FF 07 00 00 09:23:08.705 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 18 09:23:08.705 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=35, callback=16, payload=10 23 09 71 05 00 00 00 FF 07 00 00 09:23:08.707 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=CAN[4], dest=255, callback=0, payload= 09:23:08.707 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 10071: [ABORTED] priority=Set, requiresResponse=true, callback: 214 09:23:08.709 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 18 09:23:08.709 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 35: Application Command Request (ALIVE:SUC_ROUTE) 09:23:08.711 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 1<>127 : Message: class=null[0], type=CAN[4], dest=255, callback=0, payload= 09:23:08.712 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 35: Incoming command class COMMAND_CLASS_ALARM, endpoint 0 09:23:08.713 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 18 09:23:08.713 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 35: SECURITY not supported 09:23:08.714 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 2<>126 : Message: class=null[0], type=CAN[4], dest=255, callback=0, payload= 09:23:08.714 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 35: Received COMMAND_CLASS_ALARM V4 NOTIFICATION_REPORT 09:23:08.715 [DEBUG] [l.commandclass.ZWaveAlarmCommandClass] - NODE 35: NOTIFICATION report - 0 = 0, event=0, status=255, plen=0 09:23:08.716 [DEBUG] [l.commandclass.ZWaveAlarmCommandClass] - NODE 35: Alarm Type = BURGLAR (0) 09:23:08.717 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 35: Got an event from Z-Wave network: ZWaveAlarmValueEvent 09:23:08.717 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 35: Got a value event from Z-Wave network, endpoint = 0, command class = COMMAND_CLASS_ALARM, value = 255 09:23:08.718 [DEBUG] [nternal.converter.ZWaveAlarmConverter] - NODE 35: Alarm converter processing NOTIFICATION 09:23:08.719 [DEBUG] [nternal.converter.ZWaveAlarmConverter] - NODE 35: Alarm converter NOTIFICATION event is 0, type OnOffType 09:23:08.720 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 35: Updating channel state zwave:device:512:node35:alarm_motion to OFF [OnOffType] 09:23:08.722 [DEBUG] [nternal.converter.ZWaveAlarmConverter] - NODE 35: Alarm converter processing NOTIFICATION 09:23:08.723 [DEBUG] [nternal.converter.ZWaveAlarmConverter] - NODE 35: Alarm converter NOTIFICATION event is 0, type OnOffType 09:23:08.724 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 35: Updating channel state zwave:device:512:node35:alarm_tamper to OFF [OnOffType] 09:23:08.725 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 35: Commands processed 1. 09:23:08.726 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 35: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@34c787ba. 09:23:08.727 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1 09:23:08.728 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1 09:23:08.728 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (2): Message: class=null[0], type=CAN[4], dest=255, callback=0, payload= 09:23:08.729 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 10071: [ABORTED] priority=Set, requiresResponse=true, callback: 214 09:23:08.730 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Holdoff Timer started... 09:23:08.731 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 10071: Resetting transaction 09:23:08.732 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 50: notifyTransactionResponse TID:10071 ABORTED 09:23:08.722 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 05 00 51 D4 00 7F 09:23:08.733 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 50: Adding to device queue 09:23:08.733 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 50: Added 10071 to queue - size 34 09:23:08.734 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff true. 09:23:08.735 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (1): Message: class=null[0], type=CAN[4], dest=255, callback=0, payload= 09:23:08.736 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null 09:23:08.736 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=CAN[4], dest=255, callback=0, payload= 09:23:08.737 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null 09:23:08.738 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 09:23:08.739 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff true. 09:23:08.745 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=AssignSucReturnRoute[81], type=Request[0], dest=0, callback=212, payload=D4 00 09:23:08.746 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=AssignSucReturnRoute[81], type=Request[0], dest=0, callback=212, payload=D4 00 09:23:08.746 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 D5 00 02 11 2D 09:23:08.747 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null 09:23:08.748 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 0 09:23:08.748 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=213, payload=D5 00 02 11 09:23:08.749 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: null 09:23:08.750 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=AssignSucReturnRoute[81], type=Request[0], dest=0, callback=212, payload=D4 00 09:23:08.751 [DEBUG] [sage.AssignSucReturnRouteMessageClass] - NODE {}: transaction not correlated for AssignSucReturnRouteMessageClass 09:23:08.752 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=213, payload=D5 00 02 11 09:23:08.753 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null 09:23:08.754 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 0 09:23:08.755 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: null 09:23:08.756 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=213, payload=D5 00 02 11 09:23:08.757 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 09:23:08.758 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff true. 09:23:08.981 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 09:23:08.982 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 32 03 25 01 00 25 D6 00 09:23:08.983 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 50: Sending REQUEST Message = 01 0A 00 13 32 03 25 01 00 25 D6 00 09:23:08.985 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT 09:23:08.985 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 09:23:08.986 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 10071: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 214 09:23:08.987 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 09:23:08.989 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 09:23:08.990 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 10071: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 214 09:23:08.991 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg: ACK 09:23:08.992 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 09:23:08.993 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 09:23:08.993 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 09:23:08.994 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 09:23:08.995 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 09:23:08.995 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 10071: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 214 09:23:08.997 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 09:23:08.998 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 10071: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 214 09:23:08.999 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 09:23:08.999 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 50: sentData successfully placed on stack. 09:23:09.000 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 10071: Advanced to WAIT_REQUEST 09:23:09.001 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 50: TID 10071: Transaction not completed 09:23:09.002 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 09:23:09.002 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 09:23:09.008 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 D6 00 00 02 3F 09:23:09.009 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=214, payload=D6 00 00 02 09:23:09.017 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=214, payload=D6 00 00 02 09:23:09.017 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 10071: [WAIT_REQUEST] priority=Set, requiresResponse=true, callback: 214 09:23:09.018 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 09:23:09.019 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 10071: [WAIT_REQUEST] priority=Set, requiresResponse=true, callback: 214 09:23:09.020 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking TID 10071: (Callback 214) 09:23:09.020 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Callback match! 09:23:09.021 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Correlated to TID 10071: callback 214 09:23:09.022 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=214, payload=D6 00 00 02 09:23:09.023 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 50: SendData Request. CallBack ID = 214, Status = Transmission complete and ACK received(0) 09:23:09.023 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 50: resetResendCount initComplete=true isDead=false 09:23:09.024 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 10071: Transaction COMPLETED 09:23:09.025 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 50: Response processed after 39ms 09:23:09.025 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 50: TID 10071: Transaction completed 09:23:09.026 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 50: notifyTransactionResponse TID:10071 DONE 09:23:09.027 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 50: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 09:23:09.027 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 09:23:09.028 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 09:23:09.029 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 38 03 26 01 4B 25 D7 43 09:23:09.029 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 56: Sending REQUEST Message = 01 0A 00 13 38 03 26 01 4B 25 D7 43 09:23:09.030 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT 09:23:09.031 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 10076: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 215 09:23:09.031 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 09:23:09.032 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 09:23:09.038 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 09:23:09.039 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 10076: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 215 09:23:09.045 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg: ACK 09:23:09.045 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 09:23:09.049 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 09:23:09.050 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 09:23:09.051 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 09:23:09.053 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 09:23:09.054 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 10076: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 215 09:23:09.055 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 09:23:09.056 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 10076: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 215 09:23:09.057 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 09:23:09.058 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 56: sentData successfully placed on stack. 09:23:09.060 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 10076: Advanced to WAIT_REQUEST 09:23:09.061 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 56: TID 10076: Transaction not completed 09:23:09.062 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 09:23:09.063 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 09:23:09.091 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 D7 00 00 05 39 09:23:09.093 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=215, payload=D7 00 00 05 09:23:09.094 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=215, payload=D7 00 00 05 09:23:09.095 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 10076: [WAIT_REQUEST] priority=Set, requiresResponse=true, callback: 215 09:23:09.095 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 09:23:09.096 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 10076: [WAIT_REQUEST] priority=Set, requiresResponse=true, callback: 215 09:23:09.097 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking TID 10076: (Callback 215) 09:23:09.098 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Callback match! 09:23:09.099 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Correlated to TID 10076: callback 215 09:23:09.099 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=215, payload=D7 00 00 05 09:23:09.100 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 56: SendData Request. CallBack ID = 215, Status = Transmission complete and ACK received(0) 09:23:09.102 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 10076: Transaction COMPLETED 09:23:09.103 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 56: Response processed after 72ms 09:23:09.104 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 56: TID 10076: Transaction completed 09:23:09.105 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 56: notifyTransactionResponse TID:10076 DONE 09:23:09.112 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 56: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 09:23:09.113 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 09:23:09.114 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 09:23:09.115 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 32 02 25 02 25 D8 0F 09:23:09.116 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 50: Sending REQUEST Message = 01 09 00 13 32 02 25 02 25 D8 0F 09:23:09.118 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 09:23:09.119 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 09:23:09.121 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT 09:23:09.122 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 10072: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 216 09:23:09.123 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 09:23:09.124 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 09:23:09.129 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 09:23:09.128 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 10072: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 216 09:23:09.130 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg: ACK 09:23:09.131 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 09:23:09.132 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 10072: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 216 09:23:09.132 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 09:23:09.133 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 10072: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 216 09:23:09.134 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 09:23:09.135 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 50: sentData successfully placed on stack. 09:23:09.136 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 10072: Advanced to WAIT_REQUEST 09:23:09.140 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 D8 00 00 02 31 09:23:09.141 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=216, payload=D8 00 00 02 09:23:09.145 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 50: TID 10072: Transaction not completed 09:23:09.146 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=216, payload=D8 00 00 02 09:23:09.147 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 10072: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 216 09:23:09.147 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 09:23:09.148 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 10072: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 216 09:23:09.149 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking TID 10072: (Callback 216) 09:23:09.150 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Callback match! 09:23:09.151 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Correlated to TID 10072: callback 216 09:23:09.162 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=216, payload=D8 00 00 02 09:23:09.163 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 50: SendData Request. CallBack ID = 216, Status = Transmission complete and ACK received(0) 09:23:09.164 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 50: resetResendCount initComplete=true isDead=false 09:23:09.164 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 10072: Advanced to WAIT_DATA 09:23:09.166 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 50: TID 10072: Transaction not completed 09:23:09.167 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 09:23:09.168 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 09:23:09.173 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 32 03 25 03 00 E5 09:23:09.175 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=50, callback=0, payload=00 32 03 25 03 00 09:23:09.176 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=50, callback=0, payload=00 32 03 25 03 00 09:23:09.177 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null 09:23:09.178 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 50: Application Command Request (ALIVE:DONE) 09:23:09.178 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 50: resetResendCount initComplete=true isDead=false 09:23:09.179 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 50: Incoming command class COMMAND_CLASS_SWITCH_BINARY, endpoint 0 09:23:09.180 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 50: SECURITY not supported 09:23:09.180 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 50: Received COMMAND_CLASS_SWITCH_BINARY V1 SWITCH_BINARY_REPORT 09:23:09.181 [DEBUG] [ndclass.ZWaveBinarySwitchCommandClass] - NODE 50: Switch Binary report, value = 0 09:23:09.182 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 50: Got an event from Z-Wave network: ZWaveCommandClassValueEvent 09:23:09.183 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 50: Got a value event from Z-Wave network, endpoint = 0, command class = COMMAND_CLASS_SWITCH_BINARY, value = 0 09:23:09.187 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 50: Updating channel state zwave:device:512:node50:switch_binary to OFF [OnOffType] 09:23:09.188 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 50: Commands processed 1. 09:23:09.189 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 50: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@6e4c9a6b. 09:23:09.190 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 50: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@6e4c9a6b. 09:23:09.191 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 50: notifyTransactionResponse TID:10072 DONE 09:23:09.194 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 50: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 09:23:09.195 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1 09:23:09.195 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 09:23:09.196 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 09:23:09.197 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 09:23:09.198 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 32 02 26 02 25 D9 0D 09:23:09.199 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 50: Sending REQUEST Message = 01 09 00 13 32 02 26 02 25 D9 0D 09:23:09.200 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT 09:23:09.200 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 09:23:09.201 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 09:23:09.200 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 10073: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 217 09:23:09.203 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 09:23:09.204 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 10073: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 217 09:23:09.204 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg: ACK 09:23:09.205 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 09:23:09.206 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 09:23:09.207 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 09:23:09.208 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 09:23:09.208 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 09:23:09.209 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 10073: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 217 09:23:09.210 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 09:23:09.211 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 10073: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 217 09:23:09.211 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 09:23:09.212 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 50: sentData successfully placed on stack. 09:23:09.217 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 10073: Advanced to WAIT_REQUEST 09:23:09.218 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 50: TID 10073: Transaction not completed 09:23:09.218 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 09:23:09.218 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 09:23:09.233 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 D9 00 00 02 30 09:23:09.234 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=217, payload=D9 00 00 02 09:23:09.235 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=217, payload=D9 00 00 02 09:23:09.236 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 10073: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 217 09:23:09.237 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 09:23:09.238 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 10073: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 217 09:23:09.239 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking TID 10073: (Callback 217) 09:23:09.241 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Callback match! 09:23:09.242 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Correlated to TID 10073: callback 217 09:23:09.243 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=217, payload=D9 00 00 02 09:23:09.244 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 50: SendData Request. CallBack ID = 217, Status = Transmission complete and ACK received(0) 09:23:09.246 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 50: resetResendCount initComplete=true isDead=false 09:23:09.247 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 10073: Advanced to WAIT_DATA 09:23:09.248 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 50: TID 10073: Transaction not completed 09:23:09.249 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 09:23:09.250 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 09:23:09.256 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 32 03 26 03 00 E6 09:23:09.258 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=50, callback=0, payload=00 32 03 26 03 00 09:23:09.259 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=50, callback=0, payload=00 32 03 26 03 00 09:23:09.261 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null 09:23:09.262 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 50: Application Command Request (ALIVE:DONE) 09:23:09.263 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 50: resetResendCount initComplete=true isDead=false 09:23:09.265 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 50: Incoming command class COMMAND_CLASS_SWITCH_MULTILEVEL, endpoint 0 09:23:09.266 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 50: SECURITY not supported 09:23:09.267 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 50: Received COMMAND_CLASS_SWITCH_MULTILEVEL V3 SWITCH_MULTILEVEL_REPORT 09:23:09.268 [DEBUG] [ass.ZWaveMultiLevelSwitchCommandClass] - NODE 50: Switch Multi Level report, value = 0 09:23:09.270 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 50: Got an event from Z-Wave network: ZWaveCommandClassValueEvent 09:23:09.271 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 50: Got a value event from Z-Wave network, endpoint = 0, command class = COMMAND_CLASS_SWITCH_MULTILEVEL, value = 0 09:23:09.273 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 50: Updating channel state zwave:device:512:node50:switch_dimmer to 0 [PercentType] 09:23:09.274 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 50: Commands processed 1. 09:23:09.275 [INFO ] [smarthome.event.ItemStateChangedEvent] - EGOG_Licht_Treppe_dimmer changed from 100 to 0 09:23:09.276 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 50: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@7c170f8. 09:23:09.279 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 50: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@7c170f8. 09:23:09.280 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 50: notifyTransactionResponse TID:10073 DONE 09:23:09.283 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 50: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 09:23:09.285 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1 09:23:09.286 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 09:23:09.288 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 09:23:09.290 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 09:23:09.291 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 32 03 32 01 00 25 DA 1B 09:23:09.292 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 50: Sending REQUEST Message = 01 0A 00 13 32 03 32 01 00 25 DA 1B 09:23:09.294 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 09:23:09.295 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 09:23:09.296 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT 09:23:09.298 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 10074: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 218 09:23:09.300 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 09:23:09.302 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 1<>127 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 09:23:09.303 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (1): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 09:23:09.305 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 10074: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 218 09:23:09.306 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg: ACK 09:23:09.307 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 09:23:09.309 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 10074: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 218 09:23:09.311 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 09:23:09.312 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 10074: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 218 09:23:09.314 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 09:23:09.315 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 50: sentData successfully placed on stack. 09:23:09.316 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 DA 00 00 02 33 09:23:09.317 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 10074: Advanced to WAIT_REQUEST 09:23:09.318 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 50: TID 10074: Transaction not completed 09:23:09.318 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=218, payload=DA 00 00 02 09:23:09.319 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 09:23:09.321 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 09:23:09.322 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=218, payload=DA 00 00 02 09:23:09.322 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 10074: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 218 09:23:09.325 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 09:23:09.326 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 10074: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 218 09:23:09.327 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking TID 10074: (Callback 218) 09:23:09.328 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Callback match! 09:23:09.330 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Correlated to TID 10074: callback 218 09:23:09.331 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=218, payload=DA 00 00 02 09:23:09.331 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 50: SendData Request. CallBack ID = 218, Status = Transmission complete and ACK received(0) 09:23:09.332 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 50: resetResendCount initComplete=true isDead=false 09:23:09.333 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 10074: Advanced to WAIT_DATA 09:23:09.334 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 50: TID 10074: Transaction not completed 09:23:09.335 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 09:23:09.336 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 09:23:09.377 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 14 00 04 00 32 0E 32 02 21 24 00 00 00 AC 00 01 00 00 00 AC E7 09:23:09.378 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=50, callback=0, payload=00 32 0E 32 02 21 24 00 00 00 AC 00 01 00 00 00 AC 09:23:09.379 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=50, callback=0, payload=00 32 0E 32 02 21 24 00 00 00 AC 00 01 00 00 00 AC 09:23:09.380 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null 09:23:09.380 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 50: Application Command Request (ALIVE:DONE) 09:23:09.381 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 50: resetResendCount initComplete=true isDead=false 09:23:09.382 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 50: Incoming command class COMMAND_CLASS_METER, endpoint 0 09:23:09.382 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 50: SECURITY not supported 09:23:09.383 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 50: Received COMMAND_CLASS_METER V3 METER_REPORT 09:23:09.383 [DEBUG] [l.commandclass.ZWaveMeterCommandClass] - NODE 50: Meter: Type=Electric(1), Scale=kWh(0), Value=17.2 09:23:09.384 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 50: Got an event from Z-Wave network: ZWaveMeterValueEvent 09:23:09.384 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 50: Got a value event from Z-Wave network, endpoint = 0, command class = COMMAND_CLASS_METER, value = 17.2 09:23:09.387 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 50: Updating channel state zwave:device:512:node50:meter_kwh to 17.2 [DecimalType] 09:23:09.388 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 50: Commands processed 1. 09:23:09.389 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 50: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@3234a53e. 09:23:09.390 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 50: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@3234a53e. 09:23:09.391 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 50: notifyTransactionResponse TID:10074 DONE 09:23:09.392 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 50: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 09:23:09.393 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1 09:23:09.394 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 09:23:09.400 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 09:23:09.400 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 09:23:09.401 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 32 03 32 01 10 25 DB 0A 09:23:09.402 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 50: Sending REQUEST Message = 01 0A 00 13 32 03 32 01 10 25 DB 0A 09:23:09.403 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT 09:23:09.404 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 10075: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 219 09:23:09.404 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 09:23:09.406 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 09:23:09.407 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 09:23:09.408 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 10075: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 219 09:23:09.409 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg: ACK 09:23:09.411 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 09:23:09.411 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 09:23:09.411 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 09:23:09.415 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 09:23:09.416 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 09:23:09.417 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 10075: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 219 09:23:09.418 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 09:23:09.419 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 10075: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 219 09:23:09.420 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 09:23:09.421 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 50: sentData successfully placed on stack. 09:23:09.422 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 10075: Advanced to WAIT_REQUEST 09:23:09.423 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 50: TID 10075: Transaction not completed 09:23:09.424 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 09:23:09.425 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 09:23:09.427 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 DB 00 00 02 32 09:23:09.428 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=219, payload=DB 00 00 02 09:23:09.433 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=219, payload=DB 00 00 02 09:23:09.435 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 10075: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 219 09:23:09.438 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 09:23:09.440 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 10075: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 219 09:23:09.444 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking TID 10075: (Callback 219) 09:23:09.444 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Callback match! 09:23:09.445 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Correlated to TID 10075: callback 219 09:23:09.446 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=219, payload=DB 00 00 02 09:23:09.446 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 50: SendData Request. CallBack ID = 219, Status = Transmission complete and ACK received(0) 09:23:09.447 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 50: resetResendCount initComplete=true isDead=false 09:23:09.448 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 10075: Advanced to WAIT_DATA 09:23:09.448 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 50: TID 10075: Transaction not completed 09:23:09.449 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 09:23:09.450 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 09:23:09.478 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 04 00 32 0A 32 02 21 34 00 00 00 3D 00 00 CB 09:23:09.482 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=50, callback=0, payload=00 32 0A 32 02 21 34 00 00 00 3D 00 00 09:23:09.483 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=50, callback=0, payload=00 32 0A 32 02 21 34 00 00 00 3D 00 00 09:23:09.484 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null 09:23:09.485 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 50: Application Command Request (ALIVE:DONE) 09:23:09.486 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 50: resetResendCount initComplete=true isDead=false 09:23:09.487 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 50: Incoming command class COMMAND_CLASS_METER, endpoint 0 09:23:09.488 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 50: SECURITY not supported 09:23:09.489 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 50: Received COMMAND_CLASS_METER V3 METER_REPORT 09:23:09.490 [DEBUG] [l.commandclass.ZWaveMeterCommandClass] - NODE 50: Meter: Type=Electric(1), Scale=W(2), Value=6.1 09:23:09.491 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 50: Got an event from Z-Wave network: ZWaveMeterValueEvent 09:23:09.492 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 50: Got a value event from Z-Wave network, endpoint = 0, command class = COMMAND_CLASS_METER, value = 6.1 09:23:09.494 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 50: Updating channel state zwave:device:512:node50:meter_watts to 6.1 [DecimalType] 09:23:09.495 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 50: Commands processed 1. 09:23:09.496 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 50: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@3ac61e95. 09:23:09.496 [INFO ] [smarthome.event.ItemStateChangedEvent] - EGOG_Licht_Treppe_watt changed from 5.9 to 6.1 09:23:09.496 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 50: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@3ac61e95. 09:23:09.497 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 50: notifyTransactionResponse TID:10075 DONE 09:23:09.499 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 50: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 09:23:09.500 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1 09:23:09.501 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 09:23:09.501 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 09:23:09.502 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 09:23:10.123 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 38 03 26 03 4B A7 09:23:10.124 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=56, callback=0, payload=00 38 03 26 03 4B 09:23:10.125 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=56, callback=0, payload=00 38 03 26 03 4B 09:23:10.127 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null 09:23:10.127 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 56: Application Command Request (ALIVE:SUC_ROUTE) 09:23:10.129 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 56: Incoming command class COMMAND_CLASS_SWITCH_MULTILEVEL, endpoint 0 09:23:10.130 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 56: SECURITY not supported 09:23:10.131 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 56: Received COMMAND_CLASS_SWITCH_MULTILEVEL V3 SWITCH_MULTILEVEL_REPORT 09:23:10.132 [DEBUG] [ass.ZWaveMultiLevelSwitchCommandClass] - NODE 56: Switch Multi Level report, value = 75 09:23:10.133 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 56: Got an event from Z-Wave network: ZWaveCommandClassValueEvent 09:23:10.134 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 56: Got a value event from Z-Wave network, endpoint = 0, command class = COMMAND_CLASS_SWITCH_MULTILEVEL, value = 75 09:23:10.135 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 56: Updating channel state zwave:device:512:node56:switch_dimmer to 75 [PercentType] 09:23:10.136 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 56: Commands processed 1. 09:23:10.137 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 56: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@30535464. 09:23:10.138 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 09:23:10.139 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 09:23:10.140 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 09:23:10.145 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 09:23:10.193 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 16 00 04 00 0F 10 8F 01 02 05 31 05 03 01 5C 06 31 05 01 22 00 B9 B9 09:23:10.195 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=15, callback=0, payload=00 0F 10 8F 01 02 05 31 05 03 01 5C 06 31 05 01 22 00 B9 09:23:10.196 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=15, callback=0, payload=00 0F 10 8F 01 02 05 31 05 03 01 5C 06 31 05 01 22 00 B9 09:23:10.197 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null 09:23:10.197 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 15: Application Command Request (ALIVE:DONE) 09:23:10.198 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 15: resetResendCount initComplete=true isDead=false 09:23:10.199 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 15: Decapsulating COMMAND_CLASS_MULTI_CMD 09:23:10.199 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 15: Incoming command class COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 0 09:23:10.200 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 15: SECURITY NOT required on COMMAND_CLASS_SENSOR_MULTILEVEL 09:23:10.200 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 15: Received COMMAND_CLASS_SENSOR_MULTILEVEL V5 SENSOR_MULTILEVEL_REPORT 09:23:10.201 [DEBUG] [ass.ZWaveMultiLevelSensorCommandClass] - NODE 15: Sensor Type = Luminance(3), Scale = 0 09:23:10.201 [DEBUG] [ass.ZWaveMultiLevelSensorCommandClass] - NODE 15: Sensor Value = 92 09:23:10.202 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 15: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent 09:23:10.203 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 15: Got a value event from Z-Wave network, endpoint = 0, command class = COMMAND_CLASS_SENSOR_MULTILEVEL, value = 92 09:23:10.204 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 15: Updating channel state zwave:device:512:node15:sensor_luminance to 92 lx [QuantityType] 09:23:10.205 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 15: Incoming command class COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 0 09:23:10.206 [INFO ] [smarthome.event.ItemStateChangedEvent] - DW3_lumi changed from 90 to 92 09:23:10.206 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 15: SECURITY NOT required on COMMAND_CLASS_SENSOR_MULTILEVEL 09:23:10.211 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 15: Received COMMAND_CLASS_SENSOR_MULTILEVEL V5 SENSOR_MULTILEVEL_REPORT 09:23:10.212 [INFO ] [smarthome.event.ItemStateChangedEvent] - ArbeitszimmerHealth changed from 19°C | Hell:90 % to 19°C | Hell:92 % 09:23:10.212 [DEBUG] [ass.ZWaveMultiLevelSensorCommandClass] - NODE 15: Sensor Type = Temperature(1), Scale = 0 09:23:10.225 [DEBUG] [ass.ZWaveMultiLevelSensorCommandClass] - NODE 15: Sensor Value = 18.5 09:23:10.226 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 15: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent 09:23:10.227 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 15: Got a value event from Z-Wave network, endpoint = 0, command class = COMMAND_CLASS_SENSOR_MULTILEVEL, value = 18.5 09:23:10.228 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 15: Updating channel state zwave:device:512:node15:sensor_temperature to 18.5 °C [QuantityType] 09:23:10.229 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 15: Commands processed 2. 09:23:10.230 [INFO ] [smarthome.event.ItemStateChangedEvent] - DW3_temp changed from 19 to 18.5 09:23:10.231 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 15: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@3c99b426. 09:23:10.231 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 09:23:10.232 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 09:23:10.233 [INFO ] [smarthome.event.ItemStateChangedEvent] - ArbeitszimmerHealth changed from 19°C | Hell:92 % to 18.5°C | Hell:92 % 09:23:10.234 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 15: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@2b93fbde. 09:23:10.236 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 09:23:10.237 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 09:23:10.237 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 09:23:10.238 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 09:23:10.288 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 04 00 32 0A 32 02 21 34 00 00 00 26 00 00 D0 09:23:10.290 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=50, callback=0, payload=00 32 0A 32 02 21 34 00 00 00 26 00 00 09:23:10.292 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=50, callback=0, payload=00 32 0A 32 02 21 34 00 00 00 26 00 00 09:23:10.293 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null 09:23:10.294 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 50: Application Command Request (ALIVE:DONE) 09:23:10.295 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 50: resetResendCount initComplete=true isDead=false 09:23:10.296 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 50: Incoming command class COMMAND_CLASS_METER, endpoint 0 09:23:10.297 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 50: SECURITY not supported 09:23:10.298 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 50: Received COMMAND_CLASS_METER V3 METER_REPORT 09:23:10.300 [DEBUG] [l.commandclass.ZWaveMeterCommandClass] - NODE 50: Meter: Type=Electric(1), Scale=W(2), Value=3.8 09:23:10.303 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 50: Got an event from Z-Wave network: ZWaveMeterValueEvent 09:23:10.305 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 50: Got a value event from Z-Wave network, endpoint = 0, command class = COMMAND_CLASS_METER, value = 3.8 09:23:10.306 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 50: Updating channel state zwave:device:512:node50:meter_watts to 3.8 [DecimalType] 09:23:10.308 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 50: Commands processed 1. 09:23:10.309 [INFO ] [smarthome.event.ItemStateChangedEvent] - EGOG_Licht_Treppe_watt changed from 6.1 to 3.8 09:23:10.312 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 50: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@212b7a8f. 09:23:10.313 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 09:23:10.314 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 09:23:10.316 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 09:23:10.319 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 09:23:11.140 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 04 00 38 0A 32 02 21 34 00 00 00 82 00 00 7E 09:23:11.142 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=56, callback=0, payload=00 38 0A 32 02 21 34 00 00 00 82 00 00 09:23:11.144 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=56, callback=0, payload=00 38 0A 32 02 21 34 00 00 00 82 00 00 09:23:11.146 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null 09:23:11.149 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 56: Application Command Request (ALIVE:SUC_ROUTE) 09:23:11.150 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 56: Incoming command class COMMAND_CLASS_METER, endpoint 0 09:23:11.151 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 56: SECURITY not supported 09:23:11.152 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 56: Received COMMAND_CLASS_METER V3 METER_REPORT 09:23:11.152 [DEBUG] [l.commandclass.ZWaveMeterCommandClass] - NODE 56: Meter: Type=Electric(1), Scale=W(2), Value=13 09:23:11.153 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 56: Got an event from Z-Wave network: ZWaveMeterValueEvent 09:23:11.154 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 56: Got a value event from Z-Wave network, endpoint = 0, command class = COMMAND_CLASS_METER, value = 13 09:23:11.155 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 56: Updating channel state zwave:device:512:node56:meter_watts to 13 [DecimalType] 09:23:11.156 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 56: Commands processed 1. 09:23:11.157 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 56: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@bf1eaba. 09:23:11.158 [INFO ] [smarthome.event.ItemStateChangedEvent] - EG_Licht_Sofa_watt changed from 0 to 13 09:23:11.158 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 09:23:11.163 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 09:23:11.163 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 09:23:11.164 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 09:23:11.291 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 04 00 32 0A 32 02 21 34 00 00 00 00 00 00 F6 09:23:11.292 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=50, callback=0, payload=00 32 0A 32 02 21 34 00 00 00 00 00 00 09:23:11.293 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=50, callback=0, payload=00 32 0A 32 02 21 34 00 00 00 00 00 00 09:23:11.295 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null 09:23:11.296 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 50: Application Command Request (ALIVE:DONE) 09:23:11.296 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 50: resetResendCount initComplete=true isDead=false 09:23:11.297 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 50: Incoming command class COMMAND_CLASS_METER, endpoint 0 09:23:11.298 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 50: SECURITY not supported 09:23:11.298 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 50: Received COMMAND_CLASS_METER V3 METER_REPORT 09:23:11.299 [DEBUG] [l.commandclass.ZWaveMeterCommandClass] - NODE 50: Meter: Type=Electric(1), Scale=W(2), Value=0E+1 09:23:11.300 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 50: Got an event from Z-Wave network: ZWaveMeterValueEvent 09:23:11.300 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 50: Got a value event from Z-Wave network, endpoint = 0, command class = COMMAND_CLASS_METER, value = 0E+1 09:23:11.301 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 50: Updating channel state zwave:device:512:node50:meter_watts to 0 [DecimalType] 09:23:11.302 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 50: Commands processed 1. 09:23:11.303 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 50: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@7a384766. 09:23:11.304 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 09:23:11.303 [INFO ] [smarthome.event.ItemStateChangedEvent] - EGOG_Licht_Treppe_watt changed from 3.8 to 0 09:23:11.304 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 09:23:11.305 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 09:23:11.306 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 09:23:12.140 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 04 00 38 0A 32 02 21 34 00 00 00 FA 00 00 06 09:23:12.141 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=56, callback=0, payload=00 38 0A 32 02 21 34 00 00 00 FA 00 00 09:23:12.143 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=56, callback=0, payload=00 38 0A 32 02 21 34 00 00 00 FA 00 00 09:23:12.145 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null 09:23:12.146 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 56: Application Command Request (ALIVE:SUC_ROUTE) 09:23:12.147 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 56: Incoming command class COMMAND_CLASS_METER, endpoint 0 09:23:12.148 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 56: SECURITY not supported 09:23:12.149 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 56: Received COMMAND_CLASS_METER V3 METER_REPORT 09:23:12.150 [DEBUG] [l.commandclass.ZWaveMeterCommandClass] - NODE 56: Meter: Type=Electric(1), Scale=W(2), Value=25 09:23:12.151 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 56: Got an event from Z-Wave network: ZWaveMeterValueEvent 09:23:12.152 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 56: Got a value event from Z-Wave network, endpoint = 0, command class = COMMAND_CLASS_METER, value = 25 09:23:12.153 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 56: Updating channel state zwave:device:512:node56:meter_watts to 25 [DecimalType] 09:23:12.154 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 56: Commands processed 1. 09:23:12.155 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 56: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@4ec10ef4. 09:23:12.156 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 09:23:12.157 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 09:23:12.158 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 09:23:12.159 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 09:23:12.161 [INFO ] [smarthome.event.ItemStateChangedEvent] - EG_Licht_Sofa_watt changed from 13 to 25 09:23:13.140 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 04 00 38 0A 32 02 21 34 00 00 01 3E 00 00 C3 09:23:13.141 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=56, callback=0, payload=00 38 0A 32 02 21 34 00 00 01 3E 00 00 09:23:13.142 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=56, callback=0, payload=00 38 0A 32 02 21 34 00 00 01 3E 00 00 09:23:13.143 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null 09:23:13.144 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 56: Application Command Request (ALIVE:SUC_ROUTE) 09:23:13.145 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 56: Incoming command class COMMAND_CLASS_METER, endpoint 0 09:23:13.146 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 56: SECURITY not supported 09:23:13.147 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 56: Received COMMAND_CLASS_METER V3 METER_REPORT 09:23:13.147 [DEBUG] [l.commandclass.ZWaveMeterCommandClass] - NODE 56: Meter: Type=Electric(1), Scale=W(2), Value=31.8 09:23:13.148 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 56: Got an event from Z-Wave network: ZWaveMeterValueEvent 09:23:13.149 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 56: Got a value event from Z-Wave network, endpoint = 0, command class = COMMAND_CLASS_METER, value = 31.8 09:23:13.150 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 56: Updating channel state zwave:device:512:node56:meter_watts to 31.8 [DecimalType] 09:23:13.152 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 56: Commands processed 1. 09:23:13.153 [INFO ] [smarthome.event.ItemStateChangedEvent] - EG_Licht_Sofa_watt changed from 25 to 31.8 09:23:13.153 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 56: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@34c4eaa1. 09:23:13.155 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 09:23:13.157 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 09:23:13.158 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 09:23:13.159 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 ou