:47 AEST 2019 - 1998ms 31-Jul-2019 09:35:45.241 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Adding to device queue 31-Jul-2019 09:35:45.241 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Added 5154 to queue - size 15 31-Jul-2019 09:35:45.241 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 31-Jul-2019 09:35:45.241 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction lastTransaction outstanding... 31-Jul-2019 09:35:45.242 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - STOP transaction timer 31-Jul-2019 09:35:45.242 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Start transaction timer to Wed Jul 31 09:35:47 AEST 2019 - 1997ms 31-Jul-2019 09:35:45.242 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Adding to device queue 31-Jul-2019 09:35:45.242 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Added 5155 to queue - size 16 31-Jul-2019 09:35:45.242 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 31-Jul-2019 09:35:45.242 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction lastTransaction outstanding... 31-Jul-2019 09:35:45.242 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - STOP transaction timer 31-Jul-2019 09:35:45.242 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Start transaction timer to Wed Jul 31 09:35:47 AEST 2019 - 1997ms 31-Jul-2019 09:35:45.242 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Adding to device queue 31-Jul-2019 09:35:45.242 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Added 5156 to queue - size 17 31-Jul-2019 09:35:45.243 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 31-Jul-2019 09:35:45.243 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction lastTransaction outstanding... 31-Jul-2019 09:35:45.243 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - STOP transaction timer 31-Jul-2019 09:35:45.243 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 31-Jul-2019 09:35:45.243 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - NODE 255: Creating new SerialMessage from buffer = 06 31-Jul-2019 09:35:45.243 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 31-Jul-2019 09:35:45.243 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 31-Jul-2019 09:35:45.243 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 5151: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 62 31-Jul-2019 09:35:45.244 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK 31-Jul-2019 09:35:45.244 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 31-Jul-2019 09:35:45.243 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Start transaction timer to Wed Jul 31 09:35:47 AEST 2019 - 1996ms 31-Jul-2019 09:35:45.244 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 31-Jul-2019 09:35:45.244 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction lastTransaction outstanding... 31-Jul-2019 09:35:45.244 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - STOP transaction timer 31-Jul-2019 09:35:45.244 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Start transaction timer to Wed Jul 31 09:35:47 AEST 2019 - 1995ms 31-Jul-2019 09:35:45.249 [TRACE] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Received SOF 31-Jul-2019 09:35:45.249 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 31-Jul-2019 09:35:45.249 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - NODE 255: Creating new SerialMessage from buffer = 01 04 01 13 01 E8 31-Jul-2019 09:35:45.249 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - Calculated checksum = -24 31-Jul-2019 09:35:45.249 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - NODE 255: Checksum matched 31-Jul-2019 09:35:45.250 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - NODE 255: Message payload = 01 31-Jul-2019 09:35:45.250 [TRACE] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Message is valid, sending ACK 31-Jul-2019 09:35:45.250 [TRACE] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Response SENT 6 31-Jul-2019 09:35:45.250 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 31-Jul-2019 09:35:45.250 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 31-Jul-2019 09:35:45.250 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 5151: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 62 31-Jul-2019 09:35:45.250 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 31-Jul-2019 09:35:45.251 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 5151: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 62 31-Jul-2019 09:35:45.251 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 31-Jul-2019 09:35:45.251 [TRACE] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message type = RESPONSE 31-Jul-2019 09:35:45.251 [TRACE] [ternal.protocol.serialmessage.SendDataMessageClass] - Handle Message Send Data Response 31-Jul-2019 09:35:45.251 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 30: sentData successfully placed on stack. 31-Jul-2019 09:35:45.251 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5151: TransactionAdvance ST: WAIT_RESPONSE 31-Jul-2019 09:35:45.251 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5151: TransactionAdvance WT: ApplicationCommandHandler {} 31-Jul-2019 09:35:45.252 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5151: TransactionAdvance RX: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 31-Jul-2019 09:35:45.252 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5151: TransactionAdvance TO: WAIT_REQUEST 31-Jul-2019 09:35:45.252 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 5151: Advanced to WAIT_REQUEST 31-Jul-2019 09:35:45.252 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: TID 5151: Transaction not completed 31-Jul-2019 09:35:45.252 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 31-Jul-2019 09:35:45.252 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 31-Jul-2019 09:35:45.252 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction lastTransaction outstanding... 31-Jul-2019 09:35:45.253 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - STOP transaction timer 31-Jul-2019 09:35:45.253 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Start transaction timer to Wed Jul 31 09:35:50 AEST 2019 - 4999ms 31-Jul-2019 09:35:45.314 [TRACE] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Received SOF 31-Jul-2019 09:35:45.314 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 3E 00 00 07 D2 31-Jul-2019 09:35:45.314 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - NODE 255: Creating new SerialMessage from buffer = 01 07 00 13 3E 00 00 07 D2 31-Jul-2019 09:35:45.314 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - Calculated checksum = -46 31-Jul-2019 09:35:45.314 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - NODE 255: Checksum matched 31-Jul-2019 09:35:45.315 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - NODE 0: Message payload = 3E 00 00 07 31-Jul-2019 09:35:45.315 [TRACE] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Message is valid, sending ACK 31-Jul-2019 09:35:45.315 [TRACE] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Response SENT 6 31-Jul-2019 09:35:45.315 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=62, payload=3E 00 00 07 31-Jul-2019 09:35:45.315 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=62, payload=3E 00 00 07 31-Jul-2019 09:35:45.315 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 5151: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 62 31-Jul-2019 09:35:45.315 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 31-Jul-2019 09:35:45.315 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 5151: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 62 31-Jul-2019 09:35:45.316 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 5151: (Callback 62) 31-Jul-2019 09:35:45.316 [DEBUG] [ernal.protocol.serialmessage.ZWaveCommandProcessor] - Callback match! 31-Jul-2019 09:35:45.316 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Correlated to TID 5151: callback 62 31-Jul-2019 09:35:45.316 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=62, payload=3E 00 00 07 31-Jul-2019 09:35:45.316 [TRACE] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message type = REQUEST 31-Jul-2019 09:35:45.316 [TRACE] [ternal.protocol.serialmessage.SendDataMessageClass] - Handle Message Send Data Request 31-Jul-2019 09:35:45.316 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 30: SendData Request. CallBack ID = 62, Status = Transmission complete and ACK received(0) 31-Jul-2019 09:35:45.316 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 30: resetResendCount initComplete=true isDead=false 31-Jul-2019 09:35:45.316 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5151: TransactionAdvance ST: WAIT_REQUEST 31-Jul-2019 09:35:45.317 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5151: TransactionAdvance WT: ApplicationCommandHandler {} 31-Jul-2019 09:35:45.317 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5151: TransactionAdvance RX: Message: class=SendData[19], type=Request[0], dest=0, callback=62, payload=3E 00 00 07 31-Jul-2019 09:35:45.317 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5151: TransactionAdvance RQ: RREQ=true, RCLS=ApplicationCommandHandler 31-Jul-2019 09:35:45.317 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5151: TransactionAdvance TO: WAIT_DATA 31-Jul-2019 09:35:45.317 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 5151: Advanced to WAIT_DATA 31-Jul-2019 09:35:45.317 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: TID 5151: Transaction not completed 31-Jul-2019 09:35:45.317 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 31-Jul-2019 09:35:45.317 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 31-Jul-2019 09:35:45.317 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage nothing 31-Jul-2019 09:35:45.318 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - STOP transaction timer 31-Jul-2019 09:35:45.318 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Start transaction timer to Wed Jul 31 09:35:50 AEST 2019 - 4999ms 31-Jul-2019 09:35:45.363 [TRACE] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Received SOF 31-Jul-2019 09:35:45.363 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0D 00 04 00 1E 07 60 0D 01 01 25 03 00 A4 31-Jul-2019 09:35:45.363 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - NODE 255: Creating new SerialMessage from buffer = 01 0D 00 04 00 1E 07 60 0D 01 01 25 03 00 A4 31-Jul-2019 09:35:45.363 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - Calculated checksum = -92 31-Jul-2019 09:35:45.364 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - NODE 255: Checksum matched 31-Jul-2019 09:35:45.364 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - NODE 30: Message payload = 00 1E 07 60 0D 01 01 25 03 00 31-Jul-2019 09:35:45.364 [TRACE] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Message is valid, sending ACK 31-Jul-2019 09:35:45.364 [TRACE] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Response SENT 6 31-Jul-2019 09:35:45.364 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=30, callback=0, payload=00 1E 07 60 0D 01 01 25 03 00 31-Jul-2019 09:35:45.364 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=30, callback=0, payload=00 1E 07 60 0D 01 01 25 03 00 31-Jul-2019 09:35:45.364 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null 31-Jul-2019 09:35:45.364 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Application Command Request (ALIVE:DONE) 31-Jul-2019 09:35:45.365 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 30: resetResendCount initComplete=true isDead=false 31-Jul-2019 09:35:45.365 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 30: Decapsulating COMMAND_CLASS_MULTI_CHANNEL 31-Jul-2019 09:35:45.365 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 30: Incoming command class COMMAND_CLASS_SWITCH_BINARY, endpoint 1 31-Jul-2019 09:35:45.365 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 30: SECURITY not supported 31-Jul-2019 09:35:45.365 [DEBUG] [e.internal.protocol.commandclass.ZWaveCommandClass] - NODE 30: Received COMMAND_CLASS_SWITCH_BINARY V1 SWITCH_BINARY_REPORT 31-Jul-2019 09:35:45.365 [DEBUG] [rotocol.commandclass.ZWaveBinarySwitchCommandClass] - NODE 30: Switch Binary report, value = 0 31-Jul-2019 09:35:45.365 [TRACE] [ab.binding.zwave.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveCommandClassValueEvent 31-Jul-2019 09:35:45.365 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Got an event from Z-Wave network: ZWaveCommandClassValueEvent 31-Jul-2019 09:35:45.366 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Got a value event from Z-Wave network, endpoint=1, command class=COMMAND_CLASS_SWITCH_BINARY, value=0 31-Jul-2019 09:35:45.366 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:switch_binary, cmdClass=COMMAND_CLASS_SWITCH_BINARY, endpoint=0 31-Jul-2019 09:35:45.366 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:scene_number, cmdClass=COMMAND_CLASS_SCENE_ACTIVATION, endpoint=0 31-Jul-2019 09:35:45.366 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_voltage, cmdClass=COMMAND_CLASS_METER, endpoint=0 31-Jul-2019 09:35:45.366 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_watts, cmdClass=COMMAND_CLASS_METER, endpoint=0 31-Jul-2019 09:35:45.367 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_kwh, cmdClass=COMMAND_CLASS_METER, endpoint=0 31-Jul-2019 09:35:45.367 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_current, cmdClass=COMMAND_CLASS_METER, endpoint=0 31-Jul-2019 09:35:45.367 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_reset, cmdClass=COMMAND_CLASS_METER, endpoint=0 31-Jul-2019 09:35:45.367 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:alarm_power, cmdClass=COMMAND_CLASS_ALARM, endpoint=0 31-Jul-2019 09:35:45.367 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:alarm_heat, cmdClass=COMMAND_CLASS_ALARM, endpoint=0 31-Jul-2019 09:35:45.367 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:time_offset, cmdClass=COMMAND_CLASS_CLOCK, endpoint=0 31-Jul-2019 09:35:45.367 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:switch_binary1, cmdClass=COMMAND_CLASS_SWITCH_BINARY, endpoint=1 31-Jul-2019 09:35:45.367 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Updating channel state zwave:device:4296a94a:node30:switch_binary1 to OFF [OnOffType] 31-Jul-2019 09:35:45.368 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_voltage1, cmdClass=COMMAND_CLASS_METER, endpoint=1 31-Jul-2019 09:35:45.368 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_watts1, cmdClass=COMMAND_CLASS_METER, endpoint=1 31-Jul-2019 09:35:45.368 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_kwh1, cmdClass=COMMAND_CLASS_METER, endpoint=1 31-Jul-2019 09:35:45.368 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_current1, cmdClass=COMMAND_CLASS_METER, endpoint=1 31-Jul-2019 09:35:45.368 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:switch_binary2, cmdClass=COMMAND_CLASS_SWITCH_BINARY, endpoint=2 31-Jul-2019 09:35:45.368 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_voltage2, cmdClass=COMMAND_CLASS_METER, endpoint=2 31-Jul-2019 09:35:45.369 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_watts2, cmdClass=COMMAND_CLASS_METER, endpoint=2 31-Jul-2019 09:35:45.369 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_kwh2, cmdClass=COMMAND_CLASS_METER, endpoint=2 31-Jul-2019 09:35:45.369 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_current2, cmdClass=COMMAND_CLASS_METER, endpoint=2 31-Jul-2019 09:35:45.369 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Commands processed 1. 31-Jul-2019 09:35:45.369 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@768055f6. 31-Jul-2019 09:35:45.369 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Checking transaction 5151 ApplicationCommandHandler. 31-Jul-2019 09:35:45.369 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Checking transaction : state >> WAIT_DATA 31-Jul-2019 09:35:45.369 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Checking transaction : node >> 30 31-Jul-2019 09:35:45.369 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Checking transaction : class >> 37 == 37. 31-Jul-2019 09:35:45.370 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Checking transaction : commd >> 3 == 3. 31-Jul-2019 09:35:45.370 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@768055f6. 31-Jul-2019 09:35:45.370 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5151: TransactionAdvance ST: WAIT_DATA 31-Jul-2019 09:35:45.370 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5151: TransactionAdvance WT: ApplicationCommandHandler {} 31-Jul-2019 09:35:45.370 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5151: TransactionAdvance RX: Message: class=ApplicationCommandHandler[4], type=Request[0], dest=30, callback=0, payload=00 1E 07 60 0D 01 01 25 03 00 31-Jul-2019 09:35:45.370 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5151: TransactionAdvance TO: DONE 31-Jul-2019 09:35:45.370 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: notifyTransactionResponse TID:5151 DONE 31-Jul-2019 09:35:45.371 [TRACE] [ab.binding.zwave.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent 31-Jul-2019 09:35:45.371 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 31-Jul-2019 09:35:45.371 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1 31-Jul-2019 09:35:45.371 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 31-Jul-2019 09:35:45.371 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 31-Jul-2019 09:35:45.371 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 31-Jul-2019 09:35:45.371 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 12: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:45.371 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: Node not awake! 31-Jul-2019 09:35:45.371 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 12: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:45.372 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: Node not awake! 31-Jul-2019 09:35:45.372 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 34: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:45.372 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 34: Node not awake! 31-Jul-2019 09:35:45.372 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 24: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:45.372 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 24: Node not awake! 31-Jul-2019 09:35:45.372 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 24: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:45.372 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 24: Node not awake! 31-Jul-2019 09:35:45.372 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 24: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:45.372 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 24: Node not awake! 31-Jul-2019 09:35:45.372 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 24: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:45.373 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 24: Node not awake! 31-Jul-2019 09:35:45.373 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 24: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:45.373 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 24: Node not awake! 31-Jul-2019 09:35:45.373 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 17: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:45.373 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 17: Node not awake! 31-Jul-2019 09:35:45.373 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 17: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:45.373 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 17: Node not awake! 31-Jul-2019 09:35:45.373 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 17: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:45.373 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 17: Node not awake! 31-Jul-2019 09:35:45.373 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 17: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:45.373 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 17: Node not awake! 31-Jul-2019 09:35:45.374 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 30: listening == true, frequentlyListening == false, awake == false 31-Jul-2019 09:35:45.374 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction from sendQueue 31-Jul-2019 09:35:45.374 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - getTransactionToSend 6 31-Jul-2019 09:35:45.374 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - NODE 30: Creating empty message of class = SendData (0x13), type = Request 31-Jul-2019 09:35:45.374 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - Calculated checksum = -81 31-Jul-2019 09:35:45.374 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0E 00 13 1E 07 60 0D 01 01 32 01 10 25 3F AF 31-Jul-2019 09:35:45.374 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 30: Sending REQUEST Message = 01 0E 00 13 1E 07 60 0D 01 01 32 01 10 25 3F AF 31-Jul-2019 09:35:45.374 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT 31-Jul-2019 09:35:45.374 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5152: Transaction Start type SendData 31-Jul-2019 09:35:45.375 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 5152: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 63 31-Jul-2019 09:35:45.375 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: expected cmd class: COMMAND_CLASS_METER 31-Jul-2019 09:35:45.375 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: expected cmd: 2 31-Jul-2019 09:35:45.375 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage Transactions outstanding: 1 31-Jul-2019 09:35:45.375 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - STOP transaction timer 31-Jul-2019 09:35:45.375 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Start transaction timer to Wed Jul 31 09:35:47 AEST 2019 - 2000ms 31-Jul-2019 09:35:45.375 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage lastTransaction: TID 5152: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 63 31-Jul-2019 09:35:45.375 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - STOP transaction timer 31-Jul-2019 09:35:45.375 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Start transaction timer to Wed Jul 31 09:35:47 AEST 2019 - 2000ms 31-Jul-2019 09:35:45.378 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 31-Jul-2019 09:35:45.378 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - NODE 255: Creating new SerialMessage from buffer = 06 31-Jul-2019 09:35:45.378 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 31-Jul-2019 09:35:45.378 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 31-Jul-2019 09:35:45.378 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 5152: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 63 31-Jul-2019 09:35:45.378 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK 31-Jul-2019 09:35:45.378 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 31-Jul-2019 09:35:45.378 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 31-Jul-2019 09:35:45.379 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction lastTransaction outstanding... 31-Jul-2019 09:35:45.379 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - STOP transaction timer 31-Jul-2019 09:35:45.379 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Start transaction timer to Wed Jul 31 09:35:47 AEST 2019 - 1996ms 31-Jul-2019 09:35:45.384 [TRACE] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Received SOF 31-Jul-2019 09:35:45.384 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 31-Jul-2019 09:35:45.384 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - NODE 255: Creating new SerialMessage from buffer = 01 04 01 13 01 E8 31-Jul-2019 09:35:45.384 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - Calculated checksum = -24 31-Jul-2019 09:35:45.384 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - NODE 255: Checksum matched 31-Jul-2019 09:35:45.384 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - NODE 255: Message payload = 01 31-Jul-2019 09:35:45.384 [TRACE] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Message is valid, sending ACK 31-Jul-2019 09:35:45.384 [TRACE] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Response SENT 6 31-Jul-2019 09:35:45.384 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 31-Jul-2019 09:35:45.385 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 31-Jul-2019 09:35:45.385 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 5152: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 63 31-Jul-2019 09:35:45.385 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 31-Jul-2019 09:35:45.385 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 5152: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 63 31-Jul-2019 09:35:45.385 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 31-Jul-2019 09:35:45.385 [TRACE] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message type = RESPONSE 31-Jul-2019 09:35:45.385 [TRACE] [ternal.protocol.serialmessage.SendDataMessageClass] - Handle Message Send Data Response 31-Jul-2019 09:35:45.385 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 30: sentData successfully placed on stack. 31-Jul-2019 09:35:45.385 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5152: TransactionAdvance ST: WAIT_RESPONSE 31-Jul-2019 09:35:45.385 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5152: TransactionAdvance WT: ApplicationCommandHandler {} 31-Jul-2019 09:35:45.385 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5152: TransactionAdvance RX: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 31-Jul-2019 09:35:45.385 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5152: TransactionAdvance TO: WAIT_REQUEST 31-Jul-2019 09:35:45.386 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 5152: Advanced to WAIT_REQUEST 31-Jul-2019 09:35:45.386 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: TID 5152: Transaction not completed 31-Jul-2019 09:35:45.386 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 31-Jul-2019 09:35:45.386 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 31-Jul-2019 09:35:45.386 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction lastTransaction outstanding... 31-Jul-2019 09:35:45.386 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - STOP transaction timer 31-Jul-2019 09:35:45.386 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Start transaction timer to Wed Jul 31 09:35:50 AEST 2019 - 5000ms 31-Jul-2019 09:35:45.665 [TRACE] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Received SOF 31-Jul-2019 09:35:45.665 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 3F 00 00 1D C9 31-Jul-2019 09:35:45.665 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - NODE 255: Creating new SerialMessage from buffer = 01 07 00 13 3F 00 00 1D C9 31-Jul-2019 09:35:45.665 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - Calculated checksum = -55 31-Jul-2019 09:35:45.665 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - NODE 255: Checksum matched 31-Jul-2019 09:35:45.665 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - NODE 0: Message payload = 3F 00 00 1D 31-Jul-2019 09:35:45.666 [TRACE] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Message is valid, sending ACK 31-Jul-2019 09:35:45.666 [TRACE] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Response SENT 6 31-Jul-2019 09:35:45.666 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=63, payload=3F 00 00 1D 31-Jul-2019 09:35:45.666 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=63, payload=3F 00 00 1D 31-Jul-2019 09:35:45.666 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 5152: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 63 31-Jul-2019 09:35:45.666 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 31-Jul-2019 09:35:45.666 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 5152: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 63 31-Jul-2019 09:35:45.666 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 5152: (Callback 63) 31-Jul-2019 09:35:45.666 [DEBUG] [ernal.protocol.serialmessage.ZWaveCommandProcessor] - Callback match! 31-Jul-2019 09:35:45.667 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Correlated to TID 5152: callback 63 31-Jul-2019 09:35:45.667 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=63, payload=3F 00 00 1D 31-Jul-2019 09:35:45.667 [TRACE] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message type = REQUEST 31-Jul-2019 09:35:45.667 [TRACE] [ternal.protocol.serialmessage.SendDataMessageClass] - Handle Message Send Data Request 31-Jul-2019 09:35:45.667 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 30: SendData Request. CallBack ID = 63, Status = Transmission complete and ACK received(0) 31-Jul-2019 09:35:45.667 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 30: resetResendCount initComplete=true isDead=false 31-Jul-2019 09:35:45.667 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5152: TransactionAdvance ST: WAIT_REQUEST 31-Jul-2019 09:35:45.667 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5152: TransactionAdvance WT: ApplicationCommandHandler {} 31-Jul-2019 09:35:45.667 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5152: TransactionAdvance RX: Message: class=SendData[19], type=Request[0], dest=0, callback=63, payload=3F 00 00 1D 31-Jul-2019 09:35:45.667 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5152: TransactionAdvance RQ: RREQ=true, RCLS=ApplicationCommandHandler 31-Jul-2019 09:35:45.667 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5152: TransactionAdvance TO: WAIT_DATA 31-Jul-2019 09:35:45.667 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 5152: Advanced to WAIT_DATA 31-Jul-2019 09:35:45.667 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: TID 5152: Transaction not completed 31-Jul-2019 09:35:45.667 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 31-Jul-2019 09:35:45.667 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 31-Jul-2019 09:35:45.667 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage nothing 31-Jul-2019 09:35:45.668 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - STOP transaction timer 31-Jul-2019 09:35:45.668 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Start transaction timer to Wed Jul 31 09:35:50 AEST 2019 - 4999ms 31-Jul-2019 09:35:45.875 [TRACE] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Received SOF 31-Jul-2019 09:35:45.879 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 14 00 04 00 1E 0E 60 0D 01 01 32 02 21 74 00 00 00 00 00 00 F7 31-Jul-2019 09:35:45.879 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - NODE 255: Creating new SerialMessage from buffer = 01 14 00 04 00 1E 0E 60 0D 01 01 32 02 21 74 00 00 00 00 00 00 F7 31-Jul-2019 09:35:45.879 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - Calculated checksum = -9 31-Jul-2019 09:35:45.879 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - NODE 255: Checksum matched 31-Jul-2019 09:35:45.879 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - NODE 30: Message payload = 00 1E 0E 60 0D 01 01 32 02 21 74 00 00 00 00 00 00 31-Jul-2019 09:35:45.879 [TRACE] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Message is valid, sending ACK 31-Jul-2019 09:35:45.879 [TRACE] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Response SENT 6 31-Jul-2019 09:35:45.879 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=30, callback=0, payload=00 1E 0E 60 0D 01 01 32 02 21 74 00 00 00 00 00 00 31-Jul-2019 09:35:45.879 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=30, callback=0, payload=00 1E 0E 60 0D 01 01 32 02 21 74 00 00 00 00 00 00 31-Jul-2019 09:35:45.879 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null 31-Jul-2019 09:35:45.879 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Application Command Request (ALIVE:DONE) 31-Jul-2019 09:35:45.879 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 30: resetResendCount initComplete=true isDead=false 31-Jul-2019 09:35:45.879 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 30: Decapsulating COMMAND_CLASS_MULTI_CHANNEL 31-Jul-2019 09:35:45.879 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 30: Incoming command class COMMAND_CLASS_METER, endpoint 1 31-Jul-2019 09:35:45.879 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 30: SECURITY not supported 31-Jul-2019 09:35:45.880 [DEBUG] [e.internal.protocol.commandclass.ZWaveCommandClass] - NODE 30: Received COMMAND_CLASS_METER V3 METER_REPORT 31-Jul-2019 09:35:45.880 [DEBUG] [ernal.protocol.commandclass.ZWaveMeterCommandClass] - NODE 30: Meter: Type=Electric(1), Scale=W(2), Value=0E+1 31-Jul-2019 09:35:45.880 [TRACE] [ab.binding.zwave.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveMeterValueEvent 31-Jul-2019 09:35:45.880 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Got an event from Z-Wave network: ZWaveMeterValueEvent 31-Jul-2019 09:35:45.880 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Got a value event from Z-Wave network, endpoint=1, command class=COMMAND_CLASS_METER, value=0E+1 31-Jul-2019 09:35:45.880 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:switch_binary, cmdClass=COMMAND_CLASS_SWITCH_BINARY, endpoint=0 31-Jul-2019 09:35:45.880 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:scene_number, cmdClass=COMMAND_CLASS_SCENE_ACTIVATION, endpoint=0 31-Jul-2019 09:35:45.880 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_voltage, cmdClass=COMMAND_CLASS_METER, endpoint=0 31-Jul-2019 09:35:45.880 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_watts, cmdClass=COMMAND_CLASS_METER, endpoint=0 31-Jul-2019 09:35:45.880 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_kwh, cmdClass=COMMAND_CLASS_METER, endpoint=0 31-Jul-2019 09:35:45.880 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_current, cmdClass=COMMAND_CLASS_METER, endpoint=0 31-Jul-2019 09:35:45.880 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_reset, cmdClass=COMMAND_CLASS_METER, endpoint=0 31-Jul-2019 09:35:45.880 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:alarm_power, cmdClass=COMMAND_CLASS_ALARM, endpoint=0 31-Jul-2019 09:35:45.880 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:alarm_heat, cmdClass=COMMAND_CLASS_ALARM, endpoint=0 31-Jul-2019 09:35:45.880 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:time_offset, cmdClass=COMMAND_CLASS_CLOCK, endpoint=0 31-Jul-2019 09:35:45.880 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:switch_binary1, cmdClass=COMMAND_CLASS_SWITCH_BINARY, endpoint=1 31-Jul-2019 09:35:45.880 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_voltage1, cmdClass=COMMAND_CLASS_METER, endpoint=1 31-Jul-2019 09:35:45.880 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_watts1, cmdClass=COMMAND_CLASS_METER, endpoint=1 31-Jul-2019 09:35:45.880 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Updating channel state zwave:device:4296a94a:node30:meter_watts1 to 0 [DecimalType] 31-Jul-2019 09:35:45.881 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_kwh1, cmdClass=COMMAND_CLASS_METER, endpoint=1 31-Jul-2019 09:35:45.881 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_current1, cmdClass=COMMAND_CLASS_METER, endpoint=1 31-Jul-2019 09:35:45.881 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:switch_binary2, cmdClass=COMMAND_CLASS_SWITCH_BINARY, endpoint=2 31-Jul-2019 09:35:45.881 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_voltage2, cmdClass=COMMAND_CLASS_METER, endpoint=2 31-Jul-2019 09:35:45.881 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_watts2, cmdClass=COMMAND_CLASS_METER, endpoint=2 31-Jul-2019 09:35:45.881 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_kwh2, cmdClass=COMMAND_CLASS_METER, endpoint=2 31-Jul-2019 09:35:45.881 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_current2, cmdClass=COMMAND_CLASS_METER, endpoint=2 31-Jul-2019 09:35:45.881 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Commands processed 1. 31-Jul-2019 09:35:45.881 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@4297a7fe. 31-Jul-2019 09:35:45.881 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Checking transaction 5152 ApplicationCommandHandler. 31-Jul-2019 09:35:45.881 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Checking transaction : state >> WAIT_DATA 31-Jul-2019 09:35:45.881 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Checking transaction : node >> 30 31-Jul-2019 09:35:45.881 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Checking transaction : class >> 50 == 50. 31-Jul-2019 09:35:45.881 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Checking transaction : commd >> 2 == 2. 31-Jul-2019 09:35:45.881 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@4297a7fe. 31-Jul-2019 09:35:45.881 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5152: TransactionAdvance ST: WAIT_DATA 31-Jul-2019 09:35:45.881 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5152: TransactionAdvance WT: ApplicationCommandHandler {} 31-Jul-2019 09:35:45.881 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5152: TransactionAdvance RX: Message: class=ApplicationCommandHandler[4], type=Request[0], dest=30, callback=0, payload=00 1E 0E 60 0D 01 01 32 02 21 74 00 00 00 00 00 00 31-Jul-2019 09:35:45.881 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5152: TransactionAdvance TO: DONE 31-Jul-2019 09:35:45.882 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: notifyTransactionResponse TID:5152 DONE 31-Jul-2019 09:35:45.882 [TRACE] [ab.binding.zwave.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent 31-Jul-2019 09:35:45.882 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 31-Jul-2019 09:35:45.882 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1 31-Jul-2019 09:35:45.882 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 31-Jul-2019 09:35:45.882 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 31-Jul-2019 09:35:45.882 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 31-Jul-2019 09:35:45.882 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 12: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:45.882 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: Node not awake! 31-Jul-2019 09:35:45.882 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 12: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:45.882 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: Node not awake! 31-Jul-2019 09:35:45.882 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 34: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:45.882 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 34: Node not awake! 31-Jul-2019 09:35:45.882 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 24: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:45.882 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 24: Node not awake! 31-Jul-2019 09:35:45.882 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 24: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:45.882 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 24: Node not awake! 31-Jul-2019 09:35:45.882 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 24: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:45.882 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 24: Node not awake! 31-Jul-2019 09:35:45.882 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 24: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:45.883 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 24: Node not awake! 31-Jul-2019 09:35:45.883 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 24: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:45.883 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 24: Node not awake! 31-Jul-2019 09:35:45.883 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 17: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:45.883 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 17: Node not awake! 31-Jul-2019 09:35:45.883 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 17: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:45.883 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 17: Node not awake! 31-Jul-2019 09:35:45.883 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 17: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:45.883 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 17: Node not awake! 31-Jul-2019 09:35:45.883 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 17: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:45.883 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 17: Node not awake! 31-Jul-2019 09:35:45.883 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 30: listening == true, frequentlyListening == false, awake == false 31-Jul-2019 09:35:45.883 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction from sendQueue 31-Jul-2019 09:35:45.883 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - getTransactionToSend 6 31-Jul-2019 09:35:45.883 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - NODE 30: Creating empty message of class = SendData (0x13), type = Request 31-Jul-2019 09:35:45.883 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - Calculated checksum = -64 31-Jul-2019 09:35:45.883 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0E 00 13 1E 07 60 0D 01 01 32 01 00 25 40 C0 31-Jul-2019 09:35:45.883 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 30: Sending REQUEST Message = 01 0E 00 13 1E 07 60 0D 01 01 32 01 00 25 40 C0 31-Jul-2019 09:35:45.883 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT 31-Jul-2019 09:35:45.883 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5153: Transaction Start type SendData 31-Jul-2019 09:35:45.884 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 5153: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 64 31-Jul-2019 09:35:45.884 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: expected cmd class: COMMAND_CLASS_METER 31-Jul-2019 09:35:45.884 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: expected cmd: 2 31-Jul-2019 09:35:45.884 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage Transactions outstanding: 1 31-Jul-2019 09:35:45.884 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - STOP transaction timer 31-Jul-2019 09:35:45.884 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Start transaction timer to Wed Jul 31 09:35:47 AEST 2019 - 2000ms 31-Jul-2019 09:35:45.884 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage lastTransaction: TID 5153: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 64 31-Jul-2019 09:35:45.884 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - STOP transaction timer 31-Jul-2019 09:35:45.884 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Start transaction timer to Wed Jul 31 09:35:47 AEST 2019 - 2000ms 31-Jul-2019 09:35:45.886 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 31-Jul-2019 09:35:45.886 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - NODE 255: Creating new SerialMessage from buffer = 06 31-Jul-2019 09:35:45.886 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 31-Jul-2019 09:35:45.886 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 31-Jul-2019 09:35:45.886 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 5153: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 64 31-Jul-2019 09:35:45.886 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK 31-Jul-2019 09:35:45.887 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 31-Jul-2019 09:35:45.887 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 31-Jul-2019 09:35:45.887 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction lastTransaction outstanding... 31-Jul-2019 09:35:45.887 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - STOP transaction timer 31-Jul-2019 09:35:45.887 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Start transaction timer to Wed Jul 31 09:35:47 AEST 2019 - 1997ms 31-Jul-2019 09:35:45.893 [TRACE] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Received SOF 31-Jul-2019 09:35:45.893 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 31-Jul-2019 09:35:45.893 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - NODE 255: Creating new SerialMessage from buffer = 01 04 01 13 01 E8 31-Jul-2019 09:35:45.893 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - Calculated checksum = -24 31-Jul-2019 09:35:45.893 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - NODE 255: Checksum matched 31-Jul-2019 09:35:45.893 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - NODE 255: Message payload = 01 31-Jul-2019 09:35:45.893 [TRACE] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Message is valid, sending ACK 31-Jul-2019 09:35:45.893 [TRACE] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Response SENT 6 31-Jul-2019 09:35:45.894 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 31-Jul-2019 09:35:45.894 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 31-Jul-2019 09:35:45.894 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 5153: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 64 31-Jul-2019 09:35:45.894 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 31-Jul-2019 09:35:45.894 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 5153: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 64 31-Jul-2019 09:35:45.894 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 31-Jul-2019 09:35:45.894 [TRACE] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message type = RESPONSE 31-Jul-2019 09:35:45.894 [TRACE] [ternal.protocol.serialmessage.SendDataMessageClass] - Handle Message Send Data Response 31-Jul-2019 09:35:45.894 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 30: sentData successfully placed on stack. 31-Jul-2019 09:35:45.894 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5153: TransactionAdvance ST: WAIT_RESPONSE 31-Jul-2019 09:35:45.895 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5153: TransactionAdvance WT: ApplicationCommandHandler {} 31-Jul-2019 09:35:45.895 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5153: TransactionAdvance RX: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 31-Jul-2019 09:35:45.895 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5153: TransactionAdvance TO: WAIT_REQUEST 31-Jul-2019 09:35:45.895 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 5153: Advanced to WAIT_REQUEST 31-Jul-2019 09:35:45.895 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: TID 5153: Transaction not completed 31-Jul-2019 09:35:45.895 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 31-Jul-2019 09:35:45.895 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 31-Jul-2019 09:35:45.895 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction lastTransaction outstanding... 31-Jul-2019 09:35:45.895 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - STOP transaction timer 31-Jul-2019 09:35:45.895 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Start transaction timer to Wed Jul 31 09:35:50 AEST 2019 - 5000ms 31-Jul-2019 09:35:46.342 [TRACE] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Received SOF 31-Jul-2019 09:35:46.349 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 14 00 04 00 1E 0E 60 0D 01 01 32 02 21 74 00 00 00 00 00 00 F7 31-Jul-2019 09:35:46.349 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - NODE 255: Creating new SerialMessage from buffer = 01 14 00 04 00 1E 0E 60 0D 01 01 32 02 21 74 00 00 00 00 00 00 F7 31-Jul-2019 09:35:46.349 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - Calculated checksum = -9 31-Jul-2019 09:35:46.349 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - NODE 255: Checksum matched 31-Jul-2019 09:35:46.349 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - NODE 30: Message payload = 00 1E 0E 60 0D 01 01 32 02 21 74 00 00 00 00 00 00 31-Jul-2019 09:35:46.349 [TRACE] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Message is valid, sending ACK 31-Jul-2019 09:35:46.349 [TRACE] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Response SENT 6 31-Jul-2019 09:35:46.349 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=30, callback=0, payload=00 1E 0E 60 0D 01 01 32 02 21 74 00 00 00 00 00 00 31-Jul-2019 09:35:46.349 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=30, callback=0, payload=00 1E 0E 60 0D 01 01 32 02 21 74 00 00 00 00 00 00 31-Jul-2019 09:35:46.350 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 5153: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 64 31-Jul-2019 09:35:46.350 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Application Command Request (ALIVE:DONE) 31-Jul-2019 09:35:46.350 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 30: resetResendCount initComplete=true isDead=false 31-Jul-2019 09:35:46.350 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 30: Decapsulating COMMAND_CLASS_MULTI_CHANNEL 31-Jul-2019 09:35:46.350 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 30: Incoming command class COMMAND_CLASS_METER, endpoint 1 31-Jul-2019 09:35:46.350 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 30: SECURITY not supported 31-Jul-2019 09:35:46.350 [DEBUG] [e.internal.protocol.commandclass.ZWaveCommandClass] - NODE 30: Received COMMAND_CLASS_METER V3 METER_REPORT 31-Jul-2019 09:35:46.350 [DEBUG] [ernal.protocol.commandclass.ZWaveMeterCommandClass] - NODE 30: Meter: Type=Electric(1), Scale=W(2), Value=0E+1 31-Jul-2019 09:35:46.350 [TRACE] [ab.binding.zwave.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveMeterValueEvent 31-Jul-2019 09:35:46.350 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Got an event from Z-Wave network: ZWaveMeterValueEvent 31-Jul-2019 09:35:46.350 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Got a value event from Z-Wave network, endpoint=1, command class=COMMAND_CLASS_METER, value=0E+1 31-Jul-2019 09:35:46.350 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:switch_binary, cmdClass=COMMAND_CLASS_SWITCH_BINARY, endpoint=0 31-Jul-2019 09:35:46.351 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:scene_number, cmdClass=COMMAND_CLASS_SCENE_ACTIVATION, endpoint=0 31-Jul-2019 09:35:46.351 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_voltage, cmdClass=COMMAND_CLASS_METER, endpoint=0 31-Jul-2019 09:35:46.351 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_watts, cmdClass=COMMAND_CLASS_METER, endpoint=0 31-Jul-2019 09:35:46.351 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_kwh, cmdClass=COMMAND_CLASS_METER, endpoint=0 31-Jul-2019 09:35:46.351 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_current, cmdClass=COMMAND_CLASS_METER, endpoint=0 31-Jul-2019 09:35:46.351 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_reset, cmdClass=COMMAND_CLASS_METER, endpoint=0 31-Jul-2019 09:35:46.351 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:alarm_power, cmdClass=COMMAND_CLASS_ALARM, endpoint=0 31-Jul-2019 09:35:46.351 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:alarm_heat, cmdClass=COMMAND_CLASS_ALARM, endpoint=0 31-Jul-2019 09:35:46.351 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:time_offset, cmdClass=COMMAND_CLASS_CLOCK, endpoint=0 31-Jul-2019 09:35:46.351 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:switch_binary1, cmdClass=COMMAND_CLASS_SWITCH_BINARY, endpoint=1 31-Jul-2019 09:35:46.351 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_voltage1, cmdClass=COMMAND_CLASS_METER, endpoint=1 31-Jul-2019 09:35:46.351 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_watts1, cmdClass=COMMAND_CLASS_METER, endpoint=1 31-Jul-2019 09:35:46.352 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Updating channel state zwave:device:4296a94a:node30:meter_watts1 to 0 [DecimalType] 31-Jul-2019 09:35:46.352 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_kwh1, cmdClass=COMMAND_CLASS_METER, endpoint=1 31-Jul-2019 09:35:46.352 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_current1, cmdClass=COMMAND_CLASS_METER, endpoint=1 31-Jul-2019 09:35:46.352 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:switch_binary2, cmdClass=COMMAND_CLASS_SWITCH_BINARY, endpoint=2 31-Jul-2019 09:35:46.352 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_voltage2, cmdClass=COMMAND_CLASS_METER, endpoint=2 31-Jul-2019 09:35:46.352 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_watts2, cmdClass=COMMAND_CLASS_METER, endpoint=2 31-Jul-2019 09:35:46.352 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_kwh2, cmdClass=COMMAND_CLASS_METER, endpoint=2 31-Jul-2019 09:35:46.353 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_current2, cmdClass=COMMAND_CLASS_METER, endpoint=2 31-Jul-2019 09:35:46.353 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Commands processed 1. 31-Jul-2019 09:35:46.353 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1a5eaded. 31-Jul-2019 09:35:46.353 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Checking transaction 5153 ApplicationCommandHandler. 31-Jul-2019 09:35:46.353 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Checking transaction : state >> WAIT_REQUEST 31-Jul-2019 09:35:46.353 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Checking transaction : node >> 30 31-Jul-2019 09:35:46.353 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Checking transaction : class >> 50 == 50. 31-Jul-2019 09:35:46.353 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Checking transaction : commd >> 2 == 2. 31-Jul-2019 09:35:46.353 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Ignoring transaction since not waiting for data. 31-Jul-2019 09:35:46.354 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1 31-Jul-2019 09:35:46.354 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1 31-Jul-2019 09:35:46.354 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 31-Jul-2019 09:35:46.354 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 31-Jul-2019 09:35:46.354 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction lastTransaction outstanding... 31-Jul-2019 09:35:46.354 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - STOP transaction timer 31-Jul-2019 09:35:46.354 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Start transaction timer to Wed Jul 31 09:35:50 AEST 2019 - 4541ms 31-Jul-2019 09:35:46.409 [TRACE] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Received SOF 31-Jul-2019 09:35:46.419 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 18 00 04 00 1E 12 60 0D 01 01 32 02 21 64 00 00 00 09 00 06 00 00 00 09 F1 31-Jul-2019 09:35:46.419 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - NODE 255: Creating new SerialMessage from buffer = 01 18 00 04 00 1E 12 60 0D 01 01 32 02 21 64 00 00 00 09 00 06 00 00 00 09 F1 31-Jul-2019 09:35:46.419 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - Calculated checksum = -15 31-Jul-2019 09:35:46.419 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - NODE 255: Checksum matched 31-Jul-2019 09:35:46.419 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - NODE 30: Message payload = 00 1E 12 60 0D 01 01 32 02 21 64 00 00 00 09 00 06 00 00 00 09 31-Jul-2019 09:35:46.419 [TRACE] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Message is valid, sending ACK 31-Jul-2019 09:35:46.419 [TRACE] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Response SENT 6 31-Jul-2019 09:35:46.420 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=30, callback=0, payload=00 1E 12 60 0D 01 01 32 02 21 64 00 00 00 09 00 06 00 00 00 09 31-Jul-2019 09:35:46.420 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=30, callback=0, payload=00 1E 12 60 0D 01 01 32 02 21 64 00 00 00 09 00 06 00 00 00 09 31-Jul-2019 09:35:46.420 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 5153: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 64 31-Jul-2019 09:35:46.420 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Application Command Request (ALIVE:DONE) 31-Jul-2019 09:35:46.420 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 30: resetResendCount initComplete=true isDead=false 31-Jul-2019 09:35:46.420 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 30: Decapsulating COMMAND_CLASS_MULTI_CHANNEL 31-Jul-2019 09:35:46.420 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 30: Incoming command class COMMAND_CLASS_METER, endpoint 1 31-Jul-2019 09:35:46.420 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 30: SECURITY not supported 31-Jul-2019 09:35:46.421 [DEBUG] [e.internal.protocol.commandclass.ZWaveCommandClass] - NODE 30: Received COMMAND_CLASS_METER V3 METER_REPORT 31-Jul-2019 09:35:46.421 [DEBUG] [ernal.protocol.commandclass.ZWaveMeterCommandClass] - NODE 30: Meter: Type=Electric(1), Scale=kWh(0), Value=0.009 31-Jul-2019 09:35:46.421 [TRACE] [ab.binding.zwave.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveMeterValueEvent 31-Jul-2019 09:35:46.421 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Got an event from Z-Wave network: ZWaveMeterValueEvent 31-Jul-2019 09:35:46.421 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Got a value event from Z-Wave network, endpoint=1, command class=COMMAND_CLASS_METER, value=0.009 31-Jul-2019 09:35:46.421 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:switch_binary, cmdClass=COMMAND_CLASS_SWITCH_BINARY, endpoint=0 31-Jul-2019 09:35:46.421 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:scene_number, cmdClass=COMMAND_CLASS_SCENE_ACTIVATION, endpoint=0 31-Jul-2019 09:35:46.421 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_voltage, cmdClass=COMMAND_CLASS_METER, endpoint=0 31-Jul-2019 09:35:46.422 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_watts, cmdClass=COMMAND_CLASS_METER, endpoint=0 31-Jul-2019 09:35:46.422 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_kwh, cmdClass=COMMAND_CLASS_METER, endpoint=0 31-Jul-2019 09:35:46.422 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_current, cmdClass=COMMAND_CLASS_METER, endpoint=0 31-Jul-2019 09:35:46.422 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_reset, cmdClass=COMMAND_CLASS_METER, endpoint=0 31-Jul-2019 09:35:46.422 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:alarm_power, cmdClass=COMMAND_CLASS_ALARM, endpoint=0 31-Jul-2019 09:35:46.422 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:alarm_heat, cmdClass=COMMAND_CLASS_ALARM, endpoint=0 31-Jul-2019 09:35:46.422 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:time_offset, cmdClass=COMMAND_CLASS_CLOCK, endpoint=0 31-Jul-2019 09:35:46.422 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:switch_binary1, cmdClass=COMMAND_CLASS_SWITCH_BINARY, endpoint=1 31-Jul-2019 09:35:46.422 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_voltage1, cmdClass=COMMAND_CLASS_METER, endpoint=1 31-Jul-2019 09:35:46.423 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_watts1, cmdClass=COMMAND_CLASS_METER, endpoint=1 31-Jul-2019 09:35:46.423 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_kwh1, cmdClass=COMMAND_CLASS_METER, endpoint=1 31-Jul-2019 09:35:46.423 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Updating channel state zwave:device:4296a94a:node30:meter_kwh1 to 0.009 [DecimalType] 31-Jul-2019 09:35:46.423 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_current1, cmdClass=COMMAND_CLASS_METER, endpoint=1 31-Jul-2019 09:35:46.423 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:switch_binary2, cmdClass=COMMAND_CLASS_SWITCH_BINARY, endpoint=2 31-Jul-2019 09:35:46.423 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_voltage2, cmdClass=COMMAND_CLASS_METER, endpoint=2 31-Jul-2019 09:35:46.423 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_watts2, cmdClass=COMMAND_CLASS_METER, endpoint=2 31-Jul-2019 09:35:46.423 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_kwh2, cmdClass=COMMAND_CLASS_METER, endpoint=2 31-Jul-2019 09:35:46.424 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_current2, cmdClass=COMMAND_CLASS_METER, endpoint=2 31-Jul-2019 09:35:46.424 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Commands processed 1. 31-Jul-2019 09:35:46.424 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@2ba8c831. 31-Jul-2019 09:35:46.424 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Checking transaction 5153 ApplicationCommandHandler. 31-Jul-2019 09:35:46.424 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Checking transaction : state >> WAIT_REQUEST 31-Jul-2019 09:35:46.424 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Checking transaction : node >> 30 31-Jul-2019 09:35:46.424 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Checking transaction : class >> 50 == 50. 31-Jul-2019 09:35:46.424 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Checking transaction : commd >> 2 == 2. 31-Jul-2019 09:35:46.424 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Ignoring transaction since not waiting for data. 31-Jul-2019 09:35:46.424 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1 31-Jul-2019 09:35:46.425 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1 31-Jul-2019 09:35:46.425 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 31-Jul-2019 09:35:46.425 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 31-Jul-2019 09:35:46.425 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction lastTransaction outstanding... 31-Jul-2019 09:35:46.425 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - STOP transaction timer 31-Jul-2019 09:35:46.425 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Start transaction timer to Wed Jul 31 09:35:50 AEST 2019 - 4470ms 31-Jul-2019 09:35:46.518 [TRACE] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Received SOF 31-Jul-2019 09:35:46.518 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 40 00 00 3F 94 31-Jul-2019 09:35:46.518 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - NODE 255: Creating new SerialMessage from buffer = 01 07 00 13 40 00 00 3F 94 31-Jul-2019 09:35:46.518 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - Calculated checksum = -108 31-Jul-2019 09:35:46.519 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - NODE 255: Checksum matched 31-Jul-2019 09:35:46.519 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - NODE 0: Message payload = 40 00 00 3F 31-Jul-2019 09:35:46.519 [TRACE] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Message is valid, sending ACK 31-Jul-2019 09:35:46.519 [TRACE] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Response SENT 6 31-Jul-2019 09:35:46.519 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=64, payload=40 00 00 3F 31-Jul-2019 09:35:46.519 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=64, payload=40 00 00 3F 31-Jul-2019 09:35:46.520 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 5153: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 64 31-Jul-2019 09:35:46.520 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 31-Jul-2019 09:35:46.520 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 5153: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 64 31-Jul-2019 09:35:46.520 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 5153: (Callback 64) 31-Jul-2019 09:35:46.520 [DEBUG] [ernal.protocol.serialmessage.ZWaveCommandProcessor] - Callback match! 31-Jul-2019 09:35:46.520 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Correlated to TID 5153: callback 64 31-Jul-2019 09:35:46.520 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=64, payload=40 00 00 3F 31-Jul-2019 09:35:46.521 [TRACE] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message type = REQUEST 31-Jul-2019 09:35:46.521 [TRACE] [ternal.protocol.serialmessage.SendDataMessageClass] - Handle Message Send Data Request 31-Jul-2019 09:35:46.521 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 30: SendData Request. CallBack ID = 64, Status = Transmission complete and ACK received(0) 31-Jul-2019 09:35:46.521 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 30: resetResendCount initComplete=true isDead=false 31-Jul-2019 09:35:46.521 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5153: TransactionAdvance ST: WAIT_REQUEST 31-Jul-2019 09:35:46.521 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5153: TransactionAdvance WT: ApplicationCommandHandler {} 31-Jul-2019 09:35:46.521 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5153: TransactionAdvance RX: Message: class=SendData[19], type=Request[0], dest=0, callback=64, payload=40 00 00 3F 31-Jul-2019 09:35:46.522 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5153: TransactionAdvance RQ: RREQ=true, RCLS=ApplicationCommandHandler 31-Jul-2019 09:35:46.522 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5153: TransactionAdvance TO: WAIT_DATA 31-Jul-2019 09:35:46.522 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 5153: Advanced to WAIT_DATA 31-Jul-2019 09:35:46.522 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: TID 5153: Transaction not completed 31-Jul-2019 09:35:46.522 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 31-Jul-2019 09:35:46.522 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 31-Jul-2019 09:35:46.522 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage nothing 31-Jul-2019 09:35:46.522 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - STOP transaction timer 31-Jul-2019 09:35:46.523 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Start transaction timer to Wed Jul 31 09:35:51 AEST 2019 - 4999ms 31-Jul-2019 09:35:46.530 [TRACE] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Received SOF 31-Jul-2019 09:35:46.539 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 14 00 04 00 1E 0E 60 0D 01 01 32 02 21 64 00 00 00 09 00 00 EE 31-Jul-2019 09:35:46.539 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - NODE 255: Creating new SerialMessage from buffer = 01 14 00 04 00 1E 0E 60 0D 01 01 32 02 21 64 00 00 00 09 00 00 EE 31-Jul-2019 09:35:46.539 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - Calculated checksum = -18 31-Jul-2019 09:35:46.539 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - NODE 255: Checksum matched 31-Jul-2019 09:35:46.539 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - NODE 30: Message payload = 00 1E 0E 60 0D 01 01 32 02 21 64 00 00 00 09 00 00 31-Jul-2019 09:35:46.539 [TRACE] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Message is valid, sending ACK 31-Jul-2019 09:35:46.540 [TRACE] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Response SENT 6 31-Jul-2019 09:35:46.540 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=30, callback=0, payload=00 1E 0E 60 0D 01 01 32 02 21 64 00 00 00 09 00 00 31-Jul-2019 09:35:46.540 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=30, callback=0, payload=00 1E 0E 60 0D 01 01 32 02 21 64 00 00 00 09 00 00 31-Jul-2019 09:35:46.540 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null 31-Jul-2019 09:35:46.540 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Application Command Request (ALIVE:DONE) 31-Jul-2019 09:35:46.540 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 30: resetResendCount initComplete=true isDead=false 31-Jul-2019 09:35:46.541 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 30: Decapsulating COMMAND_CLASS_MULTI_CHANNEL 31-Jul-2019 09:35:46.541 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 30: Incoming command class COMMAND_CLASS_METER, endpoint 1 31-Jul-2019 09:35:46.541 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 30: SECURITY not supported 31-Jul-2019 09:35:46.541 [DEBUG] [e.internal.protocol.commandclass.ZWaveCommandClass] - NODE 30: Received COMMAND_CLASS_METER V3 METER_REPORT 31-Jul-2019 09:35:46.541 [DEBUG] [ernal.protocol.commandclass.ZWaveMeterCommandClass] - NODE 30: Meter: Type=Electric(1), Scale=kWh(0), Value=0.009 31-Jul-2019 09:35:46.541 [TRACE] [ab.binding.zwave.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveMeterValueEvent 31-Jul-2019 09:35:46.541 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Got an event from Z-Wave network: ZWaveMeterValueEvent 31-Jul-2019 09:35:46.542 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Got a value event from Z-Wave network, endpoint=1, command class=COMMAND_CLASS_METER, value=0.009 31-Jul-2019 09:35:46.542 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:switch_binary, cmdClass=COMMAND_CLASS_SWITCH_BINARY, endpoint=0 31-Jul-2019 09:35:46.542 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:scene_number, cmdClass=COMMAND_CLASS_SCENE_ACTIVATION, endpoint=0 31-Jul-2019 09:35:46.542 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_voltage, cmdClass=COMMAND_CLASS_METER, endpoint=0 31-Jul-2019 09:35:46.542 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_watts, cmdClass=COMMAND_CLASS_METER, endpoint=0 31-Jul-2019 09:35:46.542 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_kwh, cmdClass=COMMAND_CLASS_METER, endpoint=0 31-Jul-2019 09:35:46.542 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_current, cmdClass=COMMAND_CLASS_METER, endpoint=0 31-Jul-2019 09:35:46.543 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_reset, cmdClass=COMMAND_CLASS_METER, endpoint=0 31-Jul-2019 09:35:46.543 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:alarm_power, cmdClass=COMMAND_CLASS_ALARM, endpoint=0 31-Jul-2019 09:35:46.543 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:alarm_heat, cmdClass=COMMAND_CLASS_ALARM, endpoint=0 31-Jul-2019 09:35:46.543 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:time_offset, cmdClass=COMMAND_CLASS_CLOCK, endpoint=0 31-Jul-2019 09:35:46.543 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:switch_binary1, cmdClass=COMMAND_CLASS_SWITCH_BINARY, endpoint=1 31-Jul-2019 09:35:46.543 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_voltage1, cmdClass=COMMAND_CLASS_METER, endpoint=1 31-Jul-2019 09:35:46.543 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_watts1, cmdClass=COMMAND_CLASS_METER, endpoint=1 31-Jul-2019 09:35:46.544 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_kwh1, cmdClass=COMMAND_CLASS_METER, endpoint=1 31-Jul-2019 09:35:46.544 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Updating channel state zwave:device:4296a94a:node30:meter_kwh1 to 0.009 [DecimalType] 31-Jul-2019 09:35:46.544 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_current1, cmdClass=COMMAND_CLASS_METER, endpoint=1 31-Jul-2019 09:35:46.544 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:switch_binary2, cmdClass=COMMAND_CLASS_SWITCH_BINARY, endpoint=2 31-Jul-2019 09:35:46.544 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_voltage2, cmdClass=COMMAND_CLASS_METER, endpoint=2 31-Jul-2019 09:35:46.544 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_watts2, cmdClass=COMMAND_CLASS_METER, endpoint=2 31-Jul-2019 09:35:46.544 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_kwh2, cmdClass=COMMAND_CLASS_METER, endpoint=2 31-Jul-2019 09:35:46.545 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_current2, cmdClass=COMMAND_CLASS_METER, endpoint=2 31-Jul-2019 09:35:46.545 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Commands processed 1. 31-Jul-2019 09:35:46.545 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@5f730757. 31-Jul-2019 09:35:46.545 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Checking transaction 5153 ApplicationCommandHandler. 31-Jul-2019 09:35:46.545 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Checking transaction : state >> WAIT_DATA 31-Jul-2019 09:35:46.545 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Checking transaction : node >> 30 31-Jul-2019 09:35:46.546 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Checking transaction : class >> 50 == 50. 31-Jul-2019 09:35:46.546 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Checking transaction : commd >> 2 == 2. 31-Jul-2019 09:35:46.546 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@5f730757. 31-Jul-2019 09:35:46.546 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5153: TransactionAdvance ST: WAIT_DATA 31-Jul-2019 09:35:46.546 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5153: TransactionAdvance WT: ApplicationCommandHandler {} 31-Jul-2019 09:35:46.546 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5153: TransactionAdvance RX: Message: class=ApplicationCommandHandler[4], type=Request[0], dest=30, callback=0, payload=00 1E 0E 60 0D 01 01 32 02 21 64 00 00 00 09 00 00 31-Jul-2019 09:35:46.546 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5153: TransactionAdvance TO: DONE 31-Jul-2019 09:35:46.546 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: notifyTransactionResponse TID:5153 DONE 31-Jul-2019 09:35:46.547 [TRACE] [ab.binding.zwave.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent 31-Jul-2019 09:35:46.547 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 31-Jul-2019 09:35:46.547 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1 31-Jul-2019 09:35:46.547 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 31-Jul-2019 09:35:46.547 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 31-Jul-2019 09:35:46.548 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 31-Jul-2019 09:35:46.548 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 12: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:46.548 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: Node not awake! 31-Jul-2019 09:35:46.548 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 12: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:46.548 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: Node not awake! 31-Jul-2019 09:35:46.548 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 34: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:46.548 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 34: Node not awake! 31-Jul-2019 09:35:46.549 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 24: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:46.549 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 24: Node not awake! 31-Jul-2019 09:35:46.549 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 24: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:46.549 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 24: Node not awake! 31-Jul-2019 09:35:46.549 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 24: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:46.549 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 24: Node not awake! 31-Jul-2019 09:35:46.549 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 24: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:46.550 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 24: Node not awake! 31-Jul-2019 09:35:46.550 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 24: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:46.550 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 24: Node not awake! 31-Jul-2019 09:35:46.550 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 17: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:46.550 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 17: Node not awake! 31-Jul-2019 09:35:46.550 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 17: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:46.550 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 17: Node not awake! 31-Jul-2019 09:35:46.551 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 17: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:46.551 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 17: Node not awake! 31-Jul-2019 09:35:46.551 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 17: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:46.551 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 17: Node not awake! 31-Jul-2019 09:35:46.551 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 30: listening == true, frequentlyListening == false, awake == false 31-Jul-2019 09:35:46.551 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction from sendQueue 31-Jul-2019 09:35:46.551 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - getTransactionToSend 6 31-Jul-2019 09:35:46.552 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - NODE 30: Creating empty message of class = SendData (0x13), type = Request 31-Jul-2019 09:35:46.552 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - Calculated checksum = -44 31-Jul-2019 09:35:46.552 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0D 00 13 1E 06 60 0D 01 02 25 02 25 41 D4 31-Jul-2019 09:35:46.552 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 30: Sending REQUEST Message = 01 0D 00 13 1E 06 60 0D 01 02 25 02 25 41 D4 31-Jul-2019 09:35:46.552 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT 31-Jul-2019 09:35:46.552 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5154: Transaction Start type SendData 31-Jul-2019 09:35:46.552 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 5154: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 65 31-Jul-2019 09:35:46.553 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: expected cmd class: COMMAND_CLASS_SWITCH_BINARY 31-Jul-2019 09:35:46.553 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: expected cmd: 3 31-Jul-2019 09:35:46.553 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage Transactions outstanding: 1 31-Jul-2019 09:35:46.553 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - STOP transaction timer 31-Jul-2019 09:35:46.553 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Start transaction timer to Wed Jul 31 09:35:48 AEST 2019 - 2000ms 31-Jul-2019 09:35:46.553 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage lastTransaction: TID 5154: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 65 31-Jul-2019 09:35:46.553 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - STOP transaction timer 31-Jul-2019 09:35:46.554 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Start transaction timer to Wed Jul 31 09:35:48 AEST 2019 - 1999ms 31-Jul-2019 09:35:46.582 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 31-Jul-2019 09:35:46.582 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - NODE 255: Creating new SerialMessage from buffer = 06 31-Jul-2019 09:35:46.582 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 31-Jul-2019 09:35:46.582 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 31-Jul-2019 09:35:46.582 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 5154: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 65 31-Jul-2019 09:35:46.583 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK 31-Jul-2019 09:35:46.583 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 31-Jul-2019 09:35:46.583 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 31-Jul-2019 09:35:46.583 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction lastTransaction outstanding... 31-Jul-2019 09:35:46.583 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - STOP transaction timer 31-Jul-2019 09:35:46.583 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Start transaction timer to Wed Jul 31 09:35:48 AEST 2019 - 1970ms 31-Jul-2019 09:35:46.589 [TRACE] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Received SOF 31-Jul-2019 09:35:46.589 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 31-Jul-2019 09:35:46.589 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - NODE 255: Creating new SerialMessage from buffer = 01 04 01 13 01 E8 31-Jul-2019 09:35:46.589 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - Calculated checksum = -24 31-Jul-2019 09:35:46.589 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - NODE 255: Checksum matched 31-Jul-2019 09:35:46.589 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - NODE 255: Message payload = 01 31-Jul-2019 09:35:46.589 [TRACE] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Message is valid, sending ACK 31-Jul-2019 09:35:46.589 [TRACE] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Response SENT 6 31-Jul-2019 09:35:46.590 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 31-Jul-2019 09:35:46.590 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 31-Jul-2019 09:35:46.590 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 5154: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 65 31-Jul-2019 09:35:46.590 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 31-Jul-2019 09:35:46.590 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 5154: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 65 31-Jul-2019 09:35:46.590 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 31-Jul-2019 09:35:46.590 [TRACE] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message type = RESPONSE 31-Jul-2019 09:35:46.590 [TRACE] [ternal.protocol.serialmessage.SendDataMessageClass] - Handle Message Send Data Response 31-Jul-2019 09:35:46.590 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 30: sentData successfully placed on stack. 31-Jul-2019 09:35:46.591 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5154: TransactionAdvance ST: WAIT_RESPONSE 31-Jul-2019 09:35:46.591 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5154: TransactionAdvance WT: ApplicationCommandHandler {} 31-Jul-2019 09:35:46.591 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5154: TransactionAdvance RX: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 31-Jul-2019 09:35:46.591 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5154: TransactionAdvance TO: WAIT_REQUEST 31-Jul-2019 09:35:46.591 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 5154: Advanced to WAIT_REQUEST 31-Jul-2019 09:35:46.591 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: TID 5154: Transaction not completed 31-Jul-2019 09:35:46.591 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 31-Jul-2019 09:35:46.591 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 31-Jul-2019 09:35:46.591 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction lastTransaction outstanding... 31-Jul-2019 09:35:46.591 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - STOP transaction timer 31-Jul-2019 09:35:46.592 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Start transaction timer to Wed Jul 31 09:35:51 AEST 2019 - 4999ms 31-Jul-2019 09:35:46.604 [TRACE] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Received SOF 31-Jul-2019 09:35:46.604 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 41 00 00 02 A8 31-Jul-2019 09:35:46.604 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - NODE 255: Creating new SerialMessage from buffer = 01 07 00 13 41 00 00 02 A8 31-Jul-2019 09:35:46.604 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - Calculated checksum = -88 31-Jul-2019 09:35:46.604 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - NODE 255: Checksum matched 31-Jul-2019 09:35:46.604 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - NODE 0: Message payload = 41 00 00 02 31-Jul-2019 09:35:46.604 [TRACE] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Message is valid, sending ACK 31-Jul-2019 09:35:46.605 [TRACE] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Response SENT 6 31-Jul-2019 09:35:46.605 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=65, payload=41 00 00 02 31-Jul-2019 09:35:46.605 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=65, payload=41 00 00 02 31-Jul-2019 09:35:46.605 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 5154: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 65 31-Jul-2019 09:35:46.605 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 31-Jul-2019 09:35:46.605 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 5154: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 65 31-Jul-2019 09:35:46.605 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 5154: (Callback 65) 31-Jul-2019 09:35:46.605 [DEBUG] [ernal.protocol.serialmessage.ZWaveCommandProcessor] - Callback match! 31-Jul-2019 09:35:46.606 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Correlated to TID 5154: callback 65 31-Jul-2019 09:35:46.606 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=65, payload=41 00 00 02 31-Jul-2019 09:35:46.606 [TRACE] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message type = REQUEST 31-Jul-2019 09:35:46.606 [TRACE] [ternal.protocol.serialmessage.SendDataMessageClass] - Handle Message Send Data Request 31-Jul-2019 09:35:46.606 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 30: SendData Request. CallBack ID = 65, Status = Transmission complete and ACK received(0) 31-Jul-2019 09:35:46.606 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 30: resetResendCount initComplete=true isDead=false 31-Jul-2019 09:35:46.606 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5154: TransactionAdvance ST: WAIT_REQUEST 31-Jul-2019 09:35:46.606 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5154: TransactionAdvance WT: ApplicationCommandHandler {} 31-Jul-2019 09:35:46.606 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5154: TransactionAdvance RX: Message: class=SendData[19], type=Request[0], dest=0, callback=65, payload=41 00 00 02 31-Jul-2019 09:35:46.606 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5154: TransactionAdvance RQ: RREQ=true, RCLS=ApplicationCommandHandler 31-Jul-2019 09:35:46.606 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5154: TransactionAdvance TO: WAIT_DATA 31-Jul-2019 09:35:46.606 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 5154: Advanced to WAIT_DATA 31-Jul-2019 09:35:46.606 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: TID 5154: Transaction not completed 31-Jul-2019 09:35:46.606 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 31-Jul-2019 09:35:46.606 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 31-Jul-2019 09:35:46.607 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage nothing 31-Jul-2019 09:35:46.607 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - STOP transaction timer 31-Jul-2019 09:35:46.607 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Start transaction timer to Wed Jul 31 09:35:51 AEST 2019 - 4999ms 31-Jul-2019 09:35:46.619 [TRACE] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Received SOF 31-Jul-2019 09:35:46.619 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0D 00 04 00 1E 07 60 0D 02 01 25 03 00 A7 31-Jul-2019 09:35:46.619 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - NODE 255: Creating new SerialMessage from buffer = 01 0D 00 04 00 1E 07 60 0D 02 01 25 03 00 A7 31-Jul-2019 09:35:46.619 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - Calculated checksum = -89 31-Jul-2019 09:35:46.619 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - NODE 255: Checksum matched 31-Jul-2019 09:35:46.620 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - NODE 30: Message payload = 00 1E 07 60 0D 02 01 25 03 00 31-Jul-2019 09:35:46.620 [TRACE] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Message is valid, sending ACK 31-Jul-2019 09:35:46.620 [TRACE] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Response SENT 6 31-Jul-2019 09:35:46.620 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=30, callback=0, payload=00 1E 07 60 0D 02 01 25 03 00 31-Jul-2019 09:35:46.620 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=30, callback=0, payload=00 1E 07 60 0D 02 01 25 03 00 31-Jul-2019 09:35:46.620 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null 31-Jul-2019 09:35:46.620 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Application Command Request (ALIVE:DONE) 31-Jul-2019 09:35:46.621 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 30: resetResendCount initComplete=true isDead=false 31-Jul-2019 09:35:46.621 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 30: Decapsulating COMMAND_CLASS_MULTI_CHANNEL 31-Jul-2019 09:35:46.621 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 30: Incoming command class COMMAND_CLASS_SWITCH_BINARY, endpoint 2 31-Jul-2019 09:35:46.621 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 30: SECURITY not supported 31-Jul-2019 09:35:46.621 [DEBUG] [e.internal.protocol.commandclass.ZWaveCommandClass] - NODE 30: Received COMMAND_CLASS_SWITCH_BINARY V1 SWITCH_BINARY_REPORT 31-Jul-2019 09:35:46.621 [DEBUG] [rotocol.commandclass.ZWaveBinarySwitchCommandClass] - NODE 30: Switch Binary report, value = 0 31-Jul-2019 09:35:46.621 [TRACE] [ab.binding.zwave.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveCommandClassValueEvent 31-Jul-2019 09:35:46.621 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Got an event from Z-Wave network: ZWaveCommandClassValueEvent 31-Jul-2019 09:35:46.622 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Got a value event from Z-Wave network, endpoint=2, command class=COMMAND_CLASS_SWITCH_BINARY, value=0 31-Jul-2019 09:35:46.622 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:switch_binary, cmdClass=COMMAND_CLASS_SWITCH_BINARY, endpoint=0 31-Jul-2019 09:35:46.622 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:scene_number, cmdClass=COMMAND_CLASS_SCENE_ACTIVATION, endpoint=0 31-Jul-2019 09:35:46.622 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_voltage, cmdClass=COMMAND_CLASS_METER, endpoint=0 31-Jul-2019 09:35:46.622 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_watts, cmdClass=COMMAND_CLASS_METER, endpoint=0 31-Jul-2019 09:35:46.622 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_kwh, cmdClass=COMMAND_CLASS_METER, endpoint=0 31-Jul-2019 09:35:46.622 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_current, cmdClass=COMMAND_CLASS_METER, endpoint=0 31-Jul-2019 09:35:46.622 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_reset, cmdClass=COMMAND_CLASS_METER, endpoint=0 31-Jul-2019 09:35:46.623 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:alarm_power, cmdClass=COMMAND_CLASS_ALARM, endpoint=0 31-Jul-2019 09:35:46.623 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:alarm_heat, cmdClass=COMMAND_CLASS_ALARM, endpoint=0 31-Jul-2019 09:35:46.623 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:time_offset, cmdClass=COMMAND_CLASS_CLOCK, endpoint=0 31-Jul-2019 09:35:46.623 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:switch_binary1, cmdClass=COMMAND_CLASS_SWITCH_BINARY, endpoint=1 31-Jul-2019 09:35:46.623 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_voltage1, cmdClass=COMMAND_CLASS_METER, endpoint=1 31-Jul-2019 09:35:46.623 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_watts1, cmdClass=COMMAND_CLASS_METER, endpoint=1 31-Jul-2019 09:35:46.623 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_kwh1, cmdClass=COMMAND_CLASS_METER, endpoint=1 31-Jul-2019 09:35:46.624 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_current1, cmdClass=COMMAND_CLASS_METER, endpoint=1 31-Jul-2019 09:35:46.624 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:switch_binary2, cmdClass=COMMAND_CLASS_SWITCH_BINARY, endpoint=2 31-Jul-2019 09:35:46.624 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Updating channel state zwave:device:4296a94a:node30:switch_binary2 to OFF [OnOffType] 31-Jul-2019 09:35:46.624 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_voltage2, cmdClass=COMMAND_CLASS_METER, endpoint=2 31-Jul-2019 09:35:46.624 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_watts2, cmdClass=COMMAND_CLASS_METER, endpoint=2 31-Jul-2019 09:35:46.624 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_kwh2, cmdClass=COMMAND_CLASS_METER, endpoint=2 31-Jul-2019 09:35:46.625 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_current2, cmdClass=COMMAND_CLASS_METER, endpoint=2 31-Jul-2019 09:35:46.625 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Commands processed 1. 31-Jul-2019 09:35:46.625 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@2024e310. 31-Jul-2019 09:35:46.625 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Checking transaction 5154 ApplicationCommandHandler. 31-Jul-2019 09:35:46.625 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Checking transaction : state >> WAIT_DATA 31-Jul-2019 09:35:46.625 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Checking transaction : node >> 30 31-Jul-2019 09:35:46.625 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Checking transaction : class >> 37 == 37. 31-Jul-2019 09:35:46.626 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Checking transaction : commd >> 3 == 3. 31-Jul-2019 09:35:46.626 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@2024e310. 31-Jul-2019 09:35:46.626 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5154: TransactionAdvance ST: WAIT_DATA 31-Jul-2019 09:35:46.626 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5154: TransactionAdvance WT: ApplicationCommandHandler {} 31-Jul-2019 09:35:46.626 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5154: TransactionAdvance RX: Message: class=ApplicationCommandHandler[4], type=Request[0], dest=30, callback=0, payload=00 1E 07 60 0D 02 01 25 03 00 31-Jul-2019 09:35:46.626 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5154: TransactionAdvance TO: DONE 31-Jul-2019 09:35:46.626 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: notifyTransactionResponse TID:5154 DONE 31-Jul-2019 09:35:46.627 [TRACE] [ab.binding.zwave.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent 31-Jul-2019 09:35:46.627 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 31-Jul-2019 09:35:46.627 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1 31-Jul-2019 09:35:46.627 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 31-Jul-2019 09:35:46.627 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 31-Jul-2019 09:35:46.627 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 31-Jul-2019 09:35:46.627 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 12: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:46.628 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: Node not awake! 31-Jul-2019 09:35:46.628 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 12: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:46.628 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: Node not awake! 31-Jul-2019 09:35:46.628 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 34: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:46.628 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 34: Node not awake! 31-Jul-2019 09:35:46.628 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 24: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:46.628 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 24: Node not awake! 31-Jul-2019 09:35:46.628 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 24: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:46.629 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 24: Node not awake! 31-Jul-2019 09:35:46.629 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 24: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:46.629 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 24: Node not awake! 31-Jul-2019 09:35:46.629 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 24: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:46.629 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 24: Node not awake! 31-Jul-2019 09:35:46.629 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 24: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:46.629 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 24: Node not awake! 31-Jul-2019 09:35:46.629 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 17: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:46.629 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 17: Node not awake! 31-Jul-2019 09:35:46.629 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 17: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:46.629 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 17: Node not awake! 31-Jul-2019 09:35:46.630 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 17: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:46.630 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 17: Node not awake! 31-Jul-2019 09:35:46.630 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 17: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:46.630 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 17: Node not awake! 31-Jul-2019 09:35:46.630 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 30: listening == true, frequentlyListening == false, awake == false 31-Jul-2019 09:35:46.630 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction from sendQueue 31-Jul-2019 09:35:46.630 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - getTransactionToSend 6 31-Jul-2019 09:35:46.630 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - NODE 30: Creating empty message of class = SendData (0x13), type = Request 31-Jul-2019 09:35:46.630 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - Calculated checksum = -47 31-Jul-2019 09:35:46.630 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0E 00 13 1E 07 60 0D 01 02 32 01 10 25 42 D1 31-Jul-2019 09:35:46.630 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 30: Sending REQUEST Message = 01 0E 00 13 1E 07 60 0D 01 02 32 01 10 25 42 D1 31-Jul-2019 09:35:46.630 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT 31-Jul-2019 09:35:46.630 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5155: Transaction Start type SendData 31-Jul-2019 09:35:46.630 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 5155: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 66 31-Jul-2019 09:35:46.631 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: expected cmd class: COMMAND_CLASS_METER 31-Jul-2019 09:35:46.631 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: expected cmd: 2 31-Jul-2019 09:35:46.631 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage Transactions outstanding: 1 31-Jul-2019 09:35:46.631 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - STOP transaction timer 31-Jul-2019 09:35:46.631 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Start transaction timer to Wed Jul 31 09:35:48 AEST 2019 - 2000ms 31-Jul-2019 09:35:46.631 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage lastTransaction: TID 5155: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 66 31-Jul-2019 09:35:46.631 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - STOP transaction timer 31-Jul-2019 09:35:46.631 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Start transaction timer to Wed Jul 31 09:35:48 AEST 2019 - 2000ms 31-Jul-2019 09:35:46.662 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 31-Jul-2019 09:35:46.662 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - NODE 255: Creating new SerialMessage from buffer = 06 31-Jul-2019 09:35:46.662 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 31-Jul-2019 09:35:46.662 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 31-Jul-2019 09:35:46.662 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 5155: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 66 31-Jul-2019 09:35:46.662 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK 31-Jul-2019 09:35:46.663 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 31-Jul-2019 09:35:46.663 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 31-Jul-2019 09:35:46.663 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction lastTransaction outstanding... 31-Jul-2019 09:35:46.663 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - STOP transaction timer 31-Jul-2019 09:35:46.663 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Start transaction timer to Wed Jul 31 09:35:48 AEST 2019 - 1968ms 31-Jul-2019 09:35:46.668 [TRACE] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Received SOF 31-Jul-2019 09:35:46.668 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 31-Jul-2019 09:35:46.668 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - NODE 255: Creating new SerialMessage from buffer = 01 04 01 13 01 E8 31-Jul-2019 09:35:46.668 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - Calculated checksum = -24 31-Jul-2019 09:35:46.668 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - NODE 255: Checksum matched 31-Jul-2019 09:35:46.668 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - NODE 255: Message payload = 01 31-Jul-2019 09:35:46.668 [TRACE] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Message is valid, sending ACK 31-Jul-2019 09:35:46.668 [TRACE] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Response SENT 6 31-Jul-2019 09:35:46.668 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 31-Jul-2019 09:35:46.669 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 31-Jul-2019 09:35:46.669 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 5155: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 66 31-Jul-2019 09:35:46.669 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 31-Jul-2019 09:35:46.669 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 5155: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 66 31-Jul-2019 09:35:46.669 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 31-Jul-2019 09:35:46.669 [TRACE] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message type = RESPONSE 31-Jul-2019 09:35:46.669 [TRACE] [ternal.protocol.serialmessage.SendDataMessageClass] - Handle Message Send Data Response 31-Jul-2019 09:35:46.669 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 30: sentData successfully placed on stack. 31-Jul-2019 09:35:46.669 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5155: TransactionAdvance ST: WAIT_RESPONSE 31-Jul-2019 09:35:46.670 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5155: TransactionAdvance WT: ApplicationCommandHandler {} 31-Jul-2019 09:35:46.670 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5155: TransactionAdvance RX: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 31-Jul-2019 09:35:46.670 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5155: TransactionAdvance TO: WAIT_REQUEST 31-Jul-2019 09:35:46.670 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 5155: Advanced to WAIT_REQUEST 31-Jul-2019 09:35:46.670 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: TID 5155: Transaction not completed 31-Jul-2019 09:35:46.670 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 31-Jul-2019 09:35:46.670 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 31-Jul-2019 09:35:46.670 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction lastTransaction outstanding... 31-Jul-2019 09:35:46.670 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - STOP transaction timer 31-Jul-2019 09:35:46.670 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Start transaction timer to Wed Jul 31 09:35:51 AEST 2019 - 5000ms 31-Jul-2019 09:35:46.684 [TRACE] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Received SOF 31-Jul-2019 09:35:46.689 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 42 00 00 02 AB 31-Jul-2019 09:35:46.689 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - NODE 255: Creating new SerialMessage from buffer = 01 07 00 13 42 00 00 02 AB 31-Jul-2019 09:35:46.689 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - Calculated checksum = -85 31-Jul-2019 09:35:46.689 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - NODE 255: Checksum matched 31-Jul-2019 09:35:46.689 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - NODE 0: Message payload = 42 00 00 02 31-Jul-2019 09:35:46.689 [TRACE] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Message is valid, sending ACK 31-Jul-2019 09:35:46.689 [TRACE] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Response SENT 6 31-Jul-2019 09:35:46.690 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=66, payload=42 00 00 02 31-Jul-2019 09:35:46.690 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=66, payload=42 00 00 02 31-Jul-2019 09:35:46.690 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 5155: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 66 31-Jul-2019 09:35:46.690 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 31-Jul-2019 09:35:46.690 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 5155: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 66 31-Jul-2019 09:35:46.690 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 5155: (Callback 66) 31-Jul-2019 09:35:46.690 [DEBUG] [ernal.protocol.serialmessage.ZWaveCommandProcessor] - Callback match! 31-Jul-2019 09:35:46.691 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Correlated to TID 5155: callback 66 31-Jul-2019 09:35:46.691 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=66, payload=42 00 00 02 31-Jul-2019 09:35:46.691 [TRACE] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message type = REQUEST 31-Jul-2019 09:35:46.691 [TRACE] [ternal.protocol.serialmessage.SendDataMessageClass] - Handle Message Send Data Request 31-Jul-2019 09:35:46.691 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 30: SendData Request. CallBack ID = 66, Status = Transmission complete and ACK received(0) 31-Jul-2019 09:35:46.691 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 30: resetResendCount initComplete=true isDead=false 31-Jul-2019 09:35:46.691 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5155: TransactionAdvance ST: WAIT_REQUEST 31-Jul-2019 09:35:46.692 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5155: TransactionAdvance WT: ApplicationCommandHandler {} 31-Jul-2019 09:35:46.692 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5155: TransactionAdvance RX: Message: class=SendData[19], type=Request[0], dest=0, callback=66, payload=42 00 00 02 31-Jul-2019 09:35:46.692 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5155: TransactionAdvance RQ: RREQ=true, RCLS=ApplicationCommandHandler 31-Jul-2019 09:35:46.692 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5155: TransactionAdvance TO: WAIT_DATA 31-Jul-2019 09:35:46.692 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 5155: Advanced to WAIT_DATA 31-Jul-2019 09:35:46.692 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: TID 5155: Transaction not completed 31-Jul-2019 09:35:46.692 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 31-Jul-2019 09:35:46.692 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 31-Jul-2019 09:35:46.693 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage nothing 31-Jul-2019 09:35:46.693 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - STOP transaction timer 31-Jul-2019 09:35:46.693 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Start transaction timer to Wed Jul 31 09:35:51 AEST 2019 - 4999ms 31-Jul-2019 09:35:46.700 [TRACE] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Received SOF 31-Jul-2019 09:35:46.709 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 14 00 04 00 1E 0E 60 0D 02 01 32 02 21 74 00 00 00 00 00 00 F4 31-Jul-2019 09:35:46.709 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - NODE 255: Creating new SerialMessage from buffer = 01 14 00 04 00 1E 0E 60 0D 02 01 32 02 21 74 00 00 00 00 00 00 F4 31-Jul-2019 09:35:46.709 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - Calculated checksum = -12 31-Jul-2019 09:35:46.709 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - NODE 255: Checksum matched 31-Jul-2019 09:35:46.710 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - NODE 30: Message payload = 00 1E 0E 60 0D 02 01 32 02 21 74 00 00 00 00 00 00 31-Jul-2019 09:35:46.710 [TRACE] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Message is valid, sending ACK 31-Jul-2019 09:35:46.710 [TRACE] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Response SENT 6 31-Jul-2019 09:35:46.710 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=30, callback=0, payload=00 1E 0E 60 0D 02 01 32 02 21 74 00 00 00 00 00 00 31-Jul-2019 09:35:46.710 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=30, callback=0, payload=00 1E 0E 60 0D 02 01 32 02 21 74 00 00 00 00 00 00 31-Jul-2019 09:35:46.710 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null 31-Jul-2019 09:35:46.710 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Application Command Request (ALIVE:DONE) 31-Jul-2019 09:35:46.711 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 30: resetResendCount initComplete=true isDead=false 31-Jul-2019 09:35:46.711 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 30: Decapsulating COMMAND_CLASS_MULTI_CHANNEL 31-Jul-2019 09:35:46.711 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 30: Incoming command class COMMAND_CLASS_METER, endpoint 2 31-Jul-2019 09:35:46.711 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 30: SECURITY not supported 31-Jul-2019 09:35:46.711 [DEBUG] [e.internal.protocol.commandclass.ZWaveCommandClass] - NODE 30: Received COMMAND_CLASS_METER V3 METER_REPORT 31-Jul-2019 09:35:46.711 [DEBUG] [ernal.protocol.commandclass.ZWaveMeterCommandClass] - NODE 30: Meter: Type=Electric(1), Scale=W(2), Value=0E+1 31-Jul-2019 09:35:46.711 [TRACE] [ab.binding.zwave.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveMeterValueEvent 31-Jul-2019 09:35:46.711 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Got an event from Z-Wave network: ZWaveMeterValueEvent 31-Jul-2019 09:35:46.712 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Got a value event from Z-Wave network, endpoint=2, command class=COMMAND_CLASS_METER, value=0E+1 31-Jul-2019 09:35:46.712 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:switch_binary, cmdClass=COMMAND_CLASS_SWITCH_BINARY, endpoint=0 31-Jul-2019 09:35:46.712 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:scene_number, cmdClass=COMMAND_CLASS_SCENE_ACTIVATION, endpoint=0 31-Jul-2019 09:35:46.712 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_voltage, cmdClass=COMMAND_CLASS_METER, endpoint=0 31-Jul-2019 09:35:46.712 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_watts, cmdClass=COMMAND_CLASS_METER, endpoint=0 31-Jul-2019 09:35:46.712 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_kwh, cmdClass=COMMAND_CLASS_METER, endpoint=0 31-Jul-2019 09:35:46.712 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_current, cmdClass=COMMAND_CLASS_METER, endpoint=0 31-Jul-2019 09:35:46.712 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_reset, cmdClass=COMMAND_CLASS_METER, endpoint=0 31-Jul-2019 09:35:46.713 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:alarm_power, cmdClass=COMMAND_CLASS_ALARM, endpoint=0 31-Jul-2019 09:35:46.713 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:alarm_heat, cmdClass=COMMAND_CLASS_ALARM, endpoint=0 31-Jul-2019 09:35:46.713 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:time_offset, cmdClass=COMMAND_CLASS_CLOCK, endpoint=0 31-Jul-2019 09:35:46.713 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:switch_binary1, cmdClass=COMMAND_CLASS_SWITCH_BINARY, endpoint=1 31-Jul-2019 09:35:46.713 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_voltage1, cmdClass=COMMAND_CLASS_METER, endpoint=1 31-Jul-2019 09:35:46.713 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_watts1, cmdClass=COMMAND_CLASS_METER, endpoint=1 31-Jul-2019 09:35:46.713 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_kwh1, cmdClass=COMMAND_CLASS_METER, endpoint=1 31-Jul-2019 09:35:46.713 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_current1, cmdClass=COMMAND_CLASS_METER, endpoint=1 31-Jul-2019 09:35:46.713 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:switch_binary2, cmdClass=COMMAND_CLASS_SWITCH_BINARY, endpoint=2 31-Jul-2019 09:35:46.714 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_voltage2, cmdClass=COMMAND_CLASS_METER, endpoint=2 31-Jul-2019 09:35:46.714 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_watts2, cmdClass=COMMAND_CLASS_METER, endpoint=2 31-Jul-2019 09:35:46.714 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Updating channel state zwave:device:4296a94a:node30:meter_watts2 to 0 [DecimalType] 31-Jul-2019 09:35:46.714 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_kwh2, cmdClass=COMMAND_CLASS_METER, endpoint=2 31-Jul-2019 09:35:46.714 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_current2, cmdClass=COMMAND_CLASS_METER, endpoint=2 31-Jul-2019 09:35:46.714 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Commands processed 1. 31-Jul-2019 09:35:46.714 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@4ffa133c. 31-Jul-2019 09:35:46.714 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Checking transaction 5155 ApplicationCommandHandler. 31-Jul-2019 09:35:46.715 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Checking transaction : state >> WAIT_DATA 31-Jul-2019 09:35:46.715 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Checking transaction : node >> 30 31-Jul-2019 09:35:46.715 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Checking transaction : class >> 50 == 50. 31-Jul-2019 09:35:46.715 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Checking transaction : commd >> 2 == 2. 31-Jul-2019 09:35:46.715 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@4ffa133c. 31-Jul-2019 09:35:46.715 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5155: TransactionAdvance ST: WAIT_DATA 31-Jul-2019 09:35:46.715 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5155: TransactionAdvance WT: ApplicationCommandHandler {} 31-Jul-2019 09:35:46.715 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5155: TransactionAdvance RX: Message: class=ApplicationCommandHandler[4], type=Request[0], dest=30, callback=0, payload=00 1E 0E 60 0D 02 01 32 02 21 74 00 00 00 00 00 00 31-Jul-2019 09:35:46.716 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5155: TransactionAdvance TO: DONE 31-Jul-2019 09:35:46.716 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: notifyTransactionResponse TID:5155 DONE 31-Jul-2019 09:35:46.716 [TRACE] [ab.binding.zwave.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent 31-Jul-2019 09:35:46.716 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 31-Jul-2019 09:35:46.716 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1 31-Jul-2019 09:35:46.716 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 31-Jul-2019 09:35:46.716 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 31-Jul-2019 09:35:46.716 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 31-Jul-2019 09:35:46.716 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 12: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:46.717 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: Node not awake! 31-Jul-2019 09:35:46.717 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 12: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:46.717 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: Node not awake! 31-Jul-2019 09:35:46.717 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 34: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:46.717 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 34: Node not awake! 31-Jul-2019 09:35:46.717 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 24: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:46.717 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 24: Node not awake! 31-Jul-2019 09:35:46.717 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 24: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:46.718 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 24: Node not awake! 31-Jul-2019 09:35:46.718 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 24: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:46.718 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 24: Node not awake! 31-Jul-2019 09:35:46.718 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 24: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:46.718 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 24: Node not awake! 31-Jul-2019 09:35:46.718 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 24: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:46.719 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 24: Node not awake! 31-Jul-2019 09:35:46.719 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 17: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:46.719 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 17: Node not awake! 31-Jul-2019 09:35:46.719 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 17: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:46.719 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 17: Node not awake! 31-Jul-2019 09:35:46.719 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 17: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:46.719 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 17: Node not awake! 31-Jul-2019 09:35:46.719 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 17: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:46.719 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 17: Node not awake! 31-Jul-2019 09:35:46.719 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 30: listening == true, frequentlyListening == false, awake == false 31-Jul-2019 09:35:46.719 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction from sendQueue 31-Jul-2019 09:35:46.719 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - getTransactionToSend 6 31-Jul-2019 09:35:46.719 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - NODE 30: Creating empty message of class = SendData (0x13), type = Request 31-Jul-2019 09:35:46.719 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - Calculated checksum = -64 31-Jul-2019 09:35:46.719 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0E 00 13 1E 07 60 0D 01 02 32 01 00 25 43 C0 31-Jul-2019 09:35:46.720 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 30: Sending REQUEST Message = 01 0E 00 13 1E 07 60 0D 01 02 32 01 00 25 43 C0 31-Jul-2019 09:35:46.720 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT 31-Jul-2019 09:35:46.720 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5156: Transaction Start type SendData 31-Jul-2019 09:35:46.720 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 5156: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 67 31-Jul-2019 09:35:46.720 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: expected cmd class: COMMAND_CLASS_METER 31-Jul-2019 09:35:46.720 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: expected cmd: 2 31-Jul-2019 09:35:46.720 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage Transactions outstanding: 1 31-Jul-2019 09:35:46.720 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - STOP transaction timer 31-Jul-2019 09:35:46.720 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Start transaction timer to Wed Jul 31 09:35:48 AEST 2019 - 2000ms 31-Jul-2019 09:35:46.720 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage lastTransaction: TID 5156: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 67 31-Jul-2019 09:35:46.720 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - STOP transaction timer 31-Jul-2019 09:35:46.720 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Start transaction timer to Wed Jul 31 09:35:48 AEST 2019 - 2000ms 31-Jul-2019 09:35:46.752 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 31-Jul-2019 09:35:46.752 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - NODE 255: Creating new SerialMessage from buffer = 06 31-Jul-2019 09:35:46.752 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 31-Jul-2019 09:35:46.752 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 31-Jul-2019 09:35:46.752 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 5156: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 67 31-Jul-2019 09:35:46.752 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK 31-Jul-2019 09:35:46.753 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 31-Jul-2019 09:35:46.753 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 31-Jul-2019 09:35:46.753 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction lastTransaction outstanding... 31-Jul-2019 09:35:46.753 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - STOP transaction timer 31-Jul-2019 09:35:46.753 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Start transaction timer to Wed Jul 31 09:35:48 AEST 2019 - 1967ms 31-Jul-2019 09:35:46.759 [TRACE] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Received SOF 31-Jul-2019 09:35:46.759 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 31-Jul-2019 09:35:46.759 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - NODE 255: Creating new SerialMessage from buffer = 01 04 01 13 01 E8 31-Jul-2019 09:35:46.759 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - Calculated checksum = -24 31-Jul-2019 09:35:46.759 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - NODE 255: Checksum matched 31-Jul-2019 09:35:46.759 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - NODE 255: Message payload = 01 31-Jul-2019 09:35:46.759 [TRACE] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Message is valid, sending ACK 31-Jul-2019 09:35:46.759 [TRACE] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Response SENT 6 31-Jul-2019 09:35:46.760 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 31-Jul-2019 09:35:46.760 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 31-Jul-2019 09:35:46.760 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 5156: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 67 31-Jul-2019 09:35:46.760 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 31-Jul-2019 09:35:46.760 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 5156: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 67 31-Jul-2019 09:35:46.760 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 31-Jul-2019 09:35:46.760 [TRACE] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message type = RESPONSE 31-Jul-2019 09:35:46.760 [TRACE] [ternal.protocol.serialmessage.SendDataMessageClass] - Handle Message Send Data Response 31-Jul-2019 09:35:46.761 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 30: sentData successfully placed on stack. 31-Jul-2019 09:35:46.761 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5156: TransactionAdvance ST: WAIT_RESPONSE 31-Jul-2019 09:35:46.761 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5156: TransactionAdvance WT: ApplicationCommandHandler {} 31-Jul-2019 09:35:46.761 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5156: TransactionAdvance RX: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 31-Jul-2019 09:35:46.761 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5156: TransactionAdvance TO: WAIT_REQUEST 31-Jul-2019 09:35:46.761 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 5156: Advanced to WAIT_REQUEST 31-Jul-2019 09:35:46.761 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: TID 5156: Transaction not completed 31-Jul-2019 09:35:46.761 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 31-Jul-2019 09:35:46.761 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 31-Jul-2019 09:35:46.762 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction lastTransaction outstanding... 31-Jul-2019 09:35:46.762 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - STOP transaction timer 31-Jul-2019 09:35:46.762 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Start transaction timer to Wed Jul 31 09:35:51 AEST 2019 - 4999ms 31-Jul-2019 09:35:46.774 [TRACE] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Received SOF 31-Jul-2019 09:35:46.778 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 43 00 00 02 AA 31-Jul-2019 09:35:46.779 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - NODE 255: Creating new SerialMessage from buffer = 01 07 00 13 43 00 00 02 AA 31-Jul-2019 09:35:46.779 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - Calculated checksum = -86 31-Jul-2019 09:35:46.779 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - NODE 255: Checksum matched 31-Jul-2019 09:35:46.779 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - NODE 0: Message payload = 43 00 00 02 31-Jul-2019 09:35:46.779 [TRACE] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Message is valid, sending ACK 31-Jul-2019 09:35:46.779 [TRACE] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Response SENT 6 31-Jul-2019 09:35:46.779 [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 02 31-Jul-2019 09:35:46.779 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=67, payload=43 00 00 02 31-Jul-2019 09:35:46.780 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 5156: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 67 31-Jul-2019 09:35:46.780 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 31-Jul-2019 09:35:46.780 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 5156: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 67 31-Jul-2019 09:35:46.780 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 5156: (Callback 67) 31-Jul-2019 09:35:46.780 [DEBUG] [ernal.protocol.serialmessage.ZWaveCommandProcessor] - Callback match! 31-Jul-2019 09:35:46.780 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Correlated to TID 5156: callback 67 31-Jul-2019 09:35:46.780 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=67, payload=43 00 00 02 31-Jul-2019 09:35:46.780 [TRACE] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message type = REQUEST 31-Jul-2019 09:35:46.780 [TRACE] [ternal.protocol.serialmessage.SendDataMessageClass] - Handle Message Send Data Request 31-Jul-2019 09:35:46.780 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 30: SendData Request. CallBack ID = 67, Status = Transmission complete and ACK received(0) 31-Jul-2019 09:35:46.781 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 30: resetResendCount initComplete=true isDead=false 31-Jul-2019 09:35:46.781 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5156: TransactionAdvance ST: WAIT_REQUEST 31-Jul-2019 09:35:46.781 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5156: TransactionAdvance WT: ApplicationCommandHandler {} 31-Jul-2019 09:35:46.781 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5156: TransactionAdvance RX: Message: class=SendData[19], type=Request[0], dest=0, callback=67, payload=43 00 00 02 31-Jul-2019 09:35:46.781 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5156: TransactionAdvance RQ: RREQ=true, RCLS=ApplicationCommandHandler 31-Jul-2019 09:35:46.781 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5156: TransactionAdvance TO: WAIT_DATA 31-Jul-2019 09:35:46.781 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 5156: Advanced to WAIT_DATA 31-Jul-2019 09:35:46.781 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: TID 5156: Transaction not completed 31-Jul-2019 09:35:46.781 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 31-Jul-2019 09:35:46.781 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 31-Jul-2019 09:35:46.782 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage nothing 31-Jul-2019 09:35:46.782 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - STOP transaction timer 31-Jul-2019 09:35:46.782 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Start transaction timer to Wed Jul 31 09:35:51 AEST 2019 - 4999ms 31-Jul-2019 09:35:46.790 [TRACE] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Received SOF 31-Jul-2019 09:35:46.799 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 18 00 04 00 1E 12 60 0D 02 01 32 02 21 64 00 00 00 1D 00 05 00 00 00 1D F1 31-Jul-2019 09:35:46.799 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - NODE 255: Creating new SerialMessage from buffer = 01 18 00 04 00 1E 12 60 0D 02 01 32 02 21 64 00 00 00 1D 00 05 00 00 00 1D F1 31-Jul-2019 09:35:46.799 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - Calculated checksum = -15 31-Jul-2019 09:35:46.799 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - NODE 255: Checksum matched 31-Jul-2019 09:35:46.799 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - NODE 30: Message payload = 00 1E 12 60 0D 02 01 32 02 21 64 00 00 00 1D 00 05 00 00 00 1D 31-Jul-2019 09:35:46.799 [TRACE] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Message is valid, sending ACK 31-Jul-2019 09:35:46.799 [TRACE] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Response SENT 6 31-Jul-2019 09:35:46.799 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=30, callback=0, payload=00 1E 12 60 0D 02 01 32 02 21 64 00 00 00 1D 00 05 00 00 00 1D 31-Jul-2019 09:35:46.800 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=30, callback=0, payload=00 1E 12 60 0D 02 01 32 02 21 64 00 00 00 1D 00 05 00 00 00 1D 31-Jul-2019 09:35:46.800 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null 31-Jul-2019 09:35:46.800 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Application Command Request (ALIVE:DONE) 31-Jul-2019 09:35:46.800 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 30: resetResendCount initComplete=true isDead=false 31-Jul-2019 09:35:46.800 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 30: Decapsulating COMMAND_CLASS_MULTI_CHANNEL 31-Jul-2019 09:35:46.800 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 30: Incoming command class COMMAND_CLASS_METER, endpoint 2 31-Jul-2019 09:35:46.800 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 30: SECURITY not supported 31-Jul-2019 09:35:46.800 [DEBUG] [e.internal.protocol.commandclass.ZWaveCommandClass] - NODE 30: Received COMMAND_CLASS_METER V3 METER_REPORT 31-Jul-2019 09:35:46.800 [DEBUG] [ernal.protocol.commandclass.ZWaveMeterCommandClass] - NODE 30: Meter: Type=Electric(1), Scale=kWh(0), Value=0.029 31-Jul-2019 09:35:46.800 [TRACE] [ab.binding.zwave.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveMeterValueEvent 31-Jul-2019 09:35:46.801 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Got an event from Z-Wave network: ZWaveMeterValueEvent 31-Jul-2019 09:35:46.801 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Got a value event from Z-Wave network, endpoint=2, command class=COMMAND_CLASS_METER, value=0.029 31-Jul-2019 09:35:46.801 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:switch_binary, cmdClass=COMMAND_CLASS_SWITCH_BINARY, endpoint=0 31-Jul-2019 09:35:46.801 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:scene_number, cmdClass=COMMAND_CLASS_SCENE_ACTIVATION, endpoint=0 31-Jul-2019 09:35:46.801 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_voltage, cmdClass=COMMAND_CLASS_METER, endpoint=0 31-Jul-2019 09:35:46.801 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_watts, cmdClass=COMMAND_CLASS_METER, endpoint=0 31-Jul-2019 09:35:46.801 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_kwh, cmdClass=COMMAND_CLASS_METER, endpoint=0 31-Jul-2019 09:35:46.801 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_current, cmdClass=COMMAND_CLASS_METER, endpoint=0 31-Jul-2019 09:35:46.801 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_reset, cmdClass=COMMAND_CLASS_METER, endpoint=0 31-Jul-2019 09:35:46.802 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:alarm_power, cmdClass=COMMAND_CLASS_ALARM, endpoint=0 31-Jul-2019 09:35:46.802 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:alarm_heat, cmdClass=COMMAND_CLASS_ALARM, endpoint=0 31-Jul-2019 09:35:46.802 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:time_offset, cmdClass=COMMAND_CLASS_CLOCK, endpoint=0 31-Jul-2019 09:35:46.802 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:switch_binary1, cmdClass=COMMAND_CLASS_SWITCH_BINARY, endpoint=1 31-Jul-2019 09:35:46.802 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_voltage1, cmdClass=COMMAND_CLASS_METER, endpoint=1 31-Jul-2019 09:35:46.802 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_watts1, cmdClass=COMMAND_CLASS_METER, endpoint=1 31-Jul-2019 09:35:46.802 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_kwh1, cmdClass=COMMAND_CLASS_METER, endpoint=1 31-Jul-2019 09:35:46.802 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_current1, cmdClass=COMMAND_CLASS_METER, endpoint=1 31-Jul-2019 09:35:46.802 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:switch_binary2, cmdClass=COMMAND_CLASS_SWITCH_BINARY, endpoint=2 31-Jul-2019 09:35:46.802 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_voltage2, cmdClass=COMMAND_CLASS_METER, endpoint=2 31-Jul-2019 09:35:46.803 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_watts2, cmdClass=COMMAND_CLASS_METER, endpoint=2 31-Jul-2019 09:35:46.803 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_kwh2, cmdClass=COMMAND_CLASS_METER, endpoint=2 31-Jul-2019 09:35:46.803 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Updating channel state zwave:device:4296a94a:node30:meter_kwh2 to 0.029 [DecimalType] 31-Jul-2019 09:35:46.803 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_current2, cmdClass=COMMAND_CLASS_METER, endpoint=2 31-Jul-2019 09:35:46.803 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Commands processed 1. 31-Jul-2019 09:35:46.804 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@227ea3b2. 31-Jul-2019 09:35:46.804 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Checking transaction 5156 ApplicationCommandHandler. 31-Jul-2019 09:35:46.804 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Checking transaction : state >> WAIT_DATA 31-Jul-2019 09:35:46.804 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Checking transaction : node >> 30 31-Jul-2019 09:35:46.804 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Checking transaction : class >> 50 == 50. 31-Jul-2019 09:35:46.804 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Checking transaction : commd >> 2 == 2. 31-Jul-2019 09:35:46.804 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@227ea3b2. 31-Jul-2019 09:35:46.804 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5156: TransactionAdvance ST: WAIT_DATA 31-Jul-2019 09:35:46.804 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5156: TransactionAdvance WT: ApplicationCommandHandler {} 31-Jul-2019 09:35:46.804 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5156: TransactionAdvance RX: Message: class=ApplicationCommandHandler[4], type=Request[0], dest=30, callback=0, payload=00 1E 12 60 0D 02 01 32 02 21 64 00 00 00 1D 00 05 00 00 00 1D 31-Jul-2019 09:35:46.805 [TRACE] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 5156: TransactionAdvance TO: DONE 31-Jul-2019 09:35:46.805 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: notifyTransactionResponse TID:5156 DONE 31-Jul-2019 09:35:46.805 [TRACE] [ab.binding.zwave.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent 31-Jul-2019 09:35:46.805 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 31-Jul-2019 09:35:46.805 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1 31-Jul-2019 09:35:46.805 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 31-Jul-2019 09:35:46.806 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 31-Jul-2019 09:35:46.806 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 31-Jul-2019 09:35:46.806 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 12: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:46.806 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: Node not awake! 31-Jul-2019 09:35:46.806 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 12: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:46.806 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: Node not awake! 31-Jul-2019 09:35:46.806 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 34: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:46.806 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 34: Node not awake! 31-Jul-2019 09:35:46.807 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 24: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:46.807 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 24: Node not awake! 31-Jul-2019 09:35:46.807 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 24: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:46.807 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 24: Node not awake! 31-Jul-2019 09:35:46.807 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 24: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:46.807 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 24: Node not awake! 31-Jul-2019 09:35:46.807 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 24: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:46.808 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 24: Node not awake! 31-Jul-2019 09:35:46.808 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 24: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:46.808 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 24: Node not awake! 31-Jul-2019 09:35:46.808 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 17: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:46.808 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 17: Node not awake! 31-Jul-2019 09:35:46.808 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 17: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:46.808 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 17: Node not awake! 31-Jul-2019 09:35:46.809 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 17: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:46.809 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 17: Node not awake! 31-Jul-2019 09:35:46.809 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 17: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:46.809 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 17: Node not awake! 31-Jul-2019 09:35:46.809 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage nothing 31-Jul-2019 09:35:46.809 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - STOP transaction timer 31-Jul-2019 09:35:46.851 [TRACE] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Received SOF 31-Jul-2019 09:35:46.851 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0D 00 04 00 1E 07 60 0D 02 01 20 03 00 A2 31-Jul-2019 09:35:46.851 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - NODE 255: Creating new SerialMessage from buffer = 01 0D 00 04 00 1E 07 60 0D 02 01 20 03 00 A2 31-Jul-2019 09:35:46.851 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - Calculated checksum = -94 31-Jul-2019 09:35:46.852 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - NODE 255: Checksum matched 31-Jul-2019 09:35:46.852 [TRACE] [nhab.binding.zwave.internal.protocol.SerialMessage] - NODE 30: Message payload = 00 1E 07 60 0D 02 01 20 03 00 31-Jul-2019 09:35:46.852 [TRACE] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Message is valid, sending ACK 31-Jul-2019 09:35:46.852 [TRACE] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Response SENT 6 31-Jul-2019 09:35:46.852 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=30, callback=0, payload=00 1E 07 60 0D 02 01 20 03 00 31-Jul-2019 09:35:46.852 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=30, callback=0, payload=00 1E 07 60 0D 02 01 20 03 00 31-Jul-2019 09:35:46.853 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null 31-Jul-2019 09:35:46.853 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Application Command Request (ALIVE:DONE) 31-Jul-2019 09:35:46.853 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 30: resetResendCount initComplete=true isDead=false 31-Jul-2019 09:35:46.853 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 30: Decapsulating COMMAND_CLASS_MULTI_CHANNEL 31-Jul-2019 09:35:46.853 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 30: Incoming command class COMMAND_CLASS_BASIC, endpoint 2 31-Jul-2019 09:35:46.853 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 30: SECURITY not supported 31-Jul-2019 09:35:46.853 [DEBUG] [e.internal.protocol.commandclass.ZWaveCommandClass] - NODE 30: Received COMMAND_CLASS_BASIC V0 BASIC_REPORT 31-Jul-2019 09:35:46.854 [DEBUG] [ernal.protocol.commandclass.ZWaveBasicCommandClass] - NODE 30: Basic report, value = 0 31-Jul-2019 09:35:46.854 [TRACE] [ab.binding.zwave.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveCommandClassValueEvent 31-Jul-2019 09:35:46.854 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Got an event from Z-Wave network: ZWaveCommandClassValueEvent 31-Jul-2019 09:35:46.854 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Got a value event from Z-Wave network, endpoint=2, command class=COMMAND_CLASS_BASIC, value=0 31-Jul-2019 09:35:46.854 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:switch_binary, cmdClass=COMMAND_CLASS_SWITCH_BINARY, endpoint=0 31-Jul-2019 09:35:46.854 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:scene_number, cmdClass=COMMAND_CLASS_SCENE_ACTIVATION, endpoint=0 31-Jul-2019 09:35:46.854 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_voltage, cmdClass=COMMAND_CLASS_METER, endpoint=0 31-Jul-2019 09:35:46.855 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_watts, cmdClass=COMMAND_CLASS_METER, endpoint=0 31-Jul-2019 09:35:46.855 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_kwh, cmdClass=COMMAND_CLASS_METER, endpoint=0 31-Jul-2019 09:35:46.855 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_current, cmdClass=COMMAND_CLASS_METER, endpoint=0 31-Jul-2019 09:35:46.855 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_reset, cmdClass=COMMAND_CLASS_METER, endpoint=0 31-Jul-2019 09:35:46.855 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:alarm_power, cmdClass=COMMAND_CLASS_ALARM, endpoint=0 31-Jul-2019 09:35:46.855 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:alarm_heat, cmdClass=COMMAND_CLASS_ALARM, endpoint=0 31-Jul-2019 09:35:46.855 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:time_offset, cmdClass=COMMAND_CLASS_CLOCK, endpoint=0 31-Jul-2019 09:35:46.856 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:switch_binary1, cmdClass=COMMAND_CLASS_SWITCH_BINARY, endpoint=1 31-Jul-2019 09:35:46.856 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_voltage1, cmdClass=COMMAND_CLASS_METER, endpoint=1 31-Jul-2019 09:35:46.856 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_watts1, cmdClass=COMMAND_CLASS_METER, endpoint=1 31-Jul-2019 09:35:46.856 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_kwh1, cmdClass=COMMAND_CLASS_METER, endpoint=1 31-Jul-2019 09:35:46.856 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_current1, cmdClass=COMMAND_CLASS_METER, endpoint=1 31-Jul-2019 09:35:46.856 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:switch_binary2, cmdClass=COMMAND_CLASS_SWITCH_BINARY, endpoint=2 31-Jul-2019 09:35:46.857 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_voltage2, cmdClass=COMMAND_CLASS_METER, endpoint=2 31-Jul-2019 09:35:46.857 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_watts2, cmdClass=COMMAND_CLASS_METER, endpoint=2 31-Jul-2019 09:35:46.857 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_kwh2, cmdClass=COMMAND_CLASS_METER, endpoint=2 31-Jul-2019 09:35:46.857 [TRACE] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Checking channel=zwave:device:4296a94a:node30:meter_current2, cmdClass=COMMAND_CLASS_METER, endpoint=2 31-Jul-2019 09:35:46.857 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Commands processed 1. 31-Jul-2019 09:35:46.857 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@65704115. 31-Jul-2019 09:35:46.857 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 31-Jul-2019 09:35:46.858 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 31-Jul-2019 09:35:46.858 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 31-Jul-2019 09:35:46.858 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 31-Jul-2019 09:35:46.858 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 12: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:46.858 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: Node not awake! 31-Jul-2019 09:35:46.859 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 12: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:46.859 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: Node not awake! 31-Jul-2019 09:35:46.859 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 34: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:46.859 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 34: Node not awake! 31-Jul-2019 09:35:46.859 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 24: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:46.859 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 24: Node not awake! 31-Jul-2019 09:35:46.859 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 24: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:46.859 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 24: Node not awake! 31-Jul-2019 09:35:46.860 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 24: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:46.860 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 24: Node not awake! 31-Jul-2019 09:35:46.860 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 24: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:46.860 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 24: Node not awake! 31-Jul-2019 09:35:46.860 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 24: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:46.860 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 24: Node not awake! 31-Jul-2019 09:35:46.860 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 17: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:46.860 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 17: Node not awake! 31-Jul-2019 09:35:46.860 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 17: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:46.861 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 17: Node not awake! 31-Jul-2019 09:35:46.861 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 17: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:46.861 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 17: Node not awake! 31-Jul-2019 09:35:46.861 [TRACE] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 17: listening == false, frequentlyListening == false, awake == false 31-Jul-2019 09:35:46.861 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 17: Node not awake! 31-Jul-2019 09:35:46.861 [TRACE] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage nothing