2020-05-19 10:57:50.888 [TRACE] [ng.zwave.internal.protocol.ZWaveNode] - NODE 22: listening == false, frequentlyListening == false, awake == false

2020-05-19 10:57:50.889 [TRACE] [nal.protocol.ZWaveTransactionManager] - NODE 22: Node not awake!

2020-05-19 10:57:50.891 [TRACE] [ng.zwave.internal.protocol.ZWaveNode] - NODE 6: listening == false, frequentlyListening == false, awake == false

2020-05-19 10:57:50.892 [TRACE] [nal.protocol.ZWaveTransactionManager] - NODE 6: Node not awake!

2020-05-19 10:57:50.895 [TRACE] [ng.zwave.internal.protocol.ZWaveNode] - NODE 25: listening == false, frequentlyListening == false, awake == false

2020-05-19 10:57:50.896 [TRACE] [nal.protocol.ZWaveTransactionManager] - NODE 25: Node not awake!

2020-05-19 10:57:50.898 [TRACE] [ng.zwave.internal.protocol.ZWaveNode] - NODE 25: listening == false, frequentlyListening == false, awake == false

2020-05-19 10:57:50.900 [TRACE] [nal.protocol.ZWaveTransactionManager] - NODE 25: Node not awake!

2020-05-19 10:57:50.902 [TRACE] [ng.zwave.internal.protocol.ZWaveNode] - NODE 24: listening == false, frequentlyListening == true, awake == false

2020-05-19 10:57:50.905 [TRACE] [nal.protocol.ZWaveTransactionManager] - Transaction from sendQueue

2020-05-19 10:57:50.911 [TRACE] [nal.protocol.ZWaveTransactionManager] - NODE 24: Transaction requires security

2020-05-19 10:57:50.914 [DEBUG] [mmandclass.ZWaveSecurityCommandClass] - NODE 24: isNonceAvailable = null

2020-05-19 10:57:50.915 [DEBUG] [andclass.impl.CommandClassSecurityV1] - Creating command message SECURITY_NONCE_GET version 1

2020-05-19 10:57:50.917 [TRACE] [wave.internal.protocol.SerialMessage] - NODE 24: Creating empty message of class = SendData (0x13), type = Request

2020-05-19 10:57:50.920 [TRACE] [wave.internal.protocol.SerialMessage] - Calculated checksum = -100

2020-05-19 10:57:50.922 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 18 02 98 40 25 9E 9C 

2020-05-19 10:57:50.925 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 24: Sending REQUEST Message = 01 09 00 13 18 02 98 40 25 9E 9C 

2020-05-19 10:57:50.928 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT

2020-05-19 10:57:50.929 [TRACE] [e.internal.protocol.ZWaveTransaction] - TID 1136: Transaction Start type SendData 

2020-05-19 10:57:50.930 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06

2020-05-19 10:57:50.931 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 1136: [WAIT_RESPONSE] priority=High, requiresResponse=true, callback: 158

2020-05-19 10:57:50.931 [TRACE] [wave.internal.protocol.SerialMessage] - NODE 255: Creating new SerialMessage from buffer = 06 

2020-05-19 10:57:50.933 [TRACE] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: expected cmd class: COMMAND_CLASS_SECURITY

2020-05-19 10:57:50.933 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

2020-05-19 10:57:50.935 [TRACE] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: expected cmd: 128

2020-05-19 10:57:50.936 [TRACE] [WaveSerialHandler$ZWaveReceiveThread] - Received SOF

2020-05-19 10:57:50.937 [TRACE] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage Transactions outstanding: 1

2020-05-19 10:57:50.938 [TRACE] [nal.protocol.ZWaveTransactionManager] - STOP transaction timer

2020-05-19 10:57:50.940 [TRACE] [nal.protocol.ZWaveTransactionManager] - Start transaction timer to Tue May 19 10:57:52 CDT 2020 - 1999ms

2020-05-19 10:57:50.940 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 

2020-05-19 10:57:50.944 [TRACE] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage lastTransaction: TID 1136: [WAIT_RESPONSE] priority=High, requiresResponse=true, callback: 158

2020-05-19 10:57:50.944 [TRACE] [wave.internal.protocol.SerialMessage] - NODE 255: Creating new SerialMessage from buffer = 01 04 01 13 01 E8 

2020-05-19 10:57:50.946 [TRACE] [nal.protocol.ZWaveTransactionManager] - STOP transaction timer

2020-05-19 10:57:50.947 [TRACE] [wave.internal.protocol.SerialMessage] - Calculated checksum = -24

2020-05-19 10:57:50.949 [TRACE] [nal.protocol.ZWaveTransactionManager] - Start transaction timer to Tue May 19 10:57:52 CDT 2020 - 1989ms

2020-05-19 10:57:50.950 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

2020-05-19 10:57:50.950 [TRACE] [wave.internal.protocol.SerialMessage] - NODE 255: Checksum matched

2020-05-19 10:57:50.953 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1136: [WAIT_RESPONSE] priority=High, requiresResponse=true, callback: 158

2020-05-19 10:57:50.954 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK

2020-05-19 10:57:50.956 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2020-05-19 10:57:50.955 [TRACE] [wave.internal.protocol.SerialMessage] - NODE 255: Message payload = 01 

2020-05-19 10:57:50.959 [TRACE] [WaveSerialHandler$ZWaveReceiveThread] - Message is valid, sending ACK

2020-05-19 10:57:50.958 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

2020-05-19 10:57:50.961 [TRACE] [nal.protocol.ZWaveTransactionManager] - Transaction lastTransaction outstanding...

2020-05-19 10:57:50.963 [TRACE] [nal.protocol.ZWaveTransactionManager] - STOP transaction timer

2020-05-19 10:57:50.963 [TRACE] [WaveSerialHandler$ZWaveReceiveThread] - Response SENT 6

2020-05-19 10:57:50.965 [TRACE] [nal.protocol.ZWaveTransactionManager] - Start transaction timer to Tue May 19 10:57:52 CDT 2020 - 1974ms

2020-05-19 10:57:50.966 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 

2020-05-19 10:57:50.968 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 

2020-05-19 10:57:50.969 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1136: [WAIT_RESPONSE] priority=High, requiresResponse=true, callback: 158

2020-05-19 10:57:50.971 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1

2020-05-19 10:57:50.972 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 1136: [WAIT_RESPONSE] priority=High, requiresResponse=true, callback: 158

2020-05-19 10:57:50.974 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 

2020-05-19 10:57:50.975 [TRACE] [ve.internal.protocol.ZWaveController] - Incoming Message type = RESPONSE

2020-05-19 10:57:50.977 [TRACE] [l.serialmessage.SendDataMessageClass] - Handle Message Send Data Response

2020-05-19 10:57:50.979 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 24: sentData successfully placed on stack.

2020-05-19 10:57:50.981 [TRACE] [e.internal.protocol.ZWaveTransaction] - TID 1136: TransactionAdvance ST: WAIT_RESPONSE

2020-05-19 10:57:50.982 [TRACE] [e.internal.protocol.ZWaveTransaction] - TID 1136: TransactionAdvance WT: ApplicationCommandHandler {}

2020-05-19 10:57:50.984 [TRACE] [e.internal.protocol.ZWaveTransaction] - TID 1136: TransactionAdvance RX: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 

2020-05-19 10:57:50.986 [TRACE] [e.internal.protocol.ZWaveTransaction] - TID 1136: TransactionAdvance TO: WAIT_REQUEST

2020-05-19 10:57:50.987 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 1136: Advanced to WAIT_REQUEST

2020-05-19 10:57:50.989 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 24: TID 1136: Transaction not completed

2020-05-19 10:57:50.990 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2020-05-19 10:57:50.991 [TRACE] [WaveSerialHandler$ZWaveReceiveThread] - Received SOF

2020-05-19 10:57:50.991 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

2020-05-19 10:57:50.993 [TRACE] [nal.protocol.ZWaveTransactionManager] - Transaction lastTransaction outstanding...

2020-05-19 10:57:50.994 [TRACE] [nal.protocol.ZWaveTransactionManager] - STOP transaction timer

2020-05-19 10:57:50.994 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 9E 00 00 06 73 

2020-05-19 10:57:50.996 [TRACE] [wave.internal.protocol.SerialMessage] - NODE 255: Creating new SerialMessage from buffer = 01 07 00 13 9E 00 00 06 73 

2020-05-19 10:57:50.996 [TRACE] [nal.protocol.ZWaveTransactionManager] - Start transaction timer to Tue May 19 10:57:55 CDT 2020 - 4992ms

2020-05-19 10:57:50.999 [TRACE] [wave.internal.protocol.SerialMessage] - Calculated checksum = 115

2020-05-19 10:57:51.000 [TRACE] [wave.internal.protocol.SerialMessage] - NODE 255: Checksum matched

2020-05-19 10:57:51.002 [TRACE] [wave.internal.protocol.SerialMessage] - NODE 0: Message payload = 9E 00 00 06 

2020-05-19 10:57:51.003 [TRACE] [WaveSerialHandler$ZWaveReceiveThread] - Message is valid, sending ACK

2020-05-19 10:57:51.006 [TRACE] [WaveSerialHandler$ZWaveReceiveThread] - Response SENT 6

2020-05-19 10:57:51.007 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=158, payload=9E 00 00 06 

2020-05-19 10:57:51.010 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=158, payload=9E 00 00 06 

2020-05-19 10:57:51.011 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1136: [WAIT_REQUEST] priority=High, requiresResponse=true, callback: 158

2020-05-19 10:57:51.013 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1

2020-05-19 10:57:51.014 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 1136: [WAIT_REQUEST] priority=High, requiresResponse=true, callback: 158

2020-05-19 10:57:51.015 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 1136: (Callback 158)

2020-05-19 10:57:51.017 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!

2020-05-19 10:57:51.019 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 1136: callback 158

2020-05-19 10:57:51.021 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=158, payload=9E 00 00 06 

2020-05-19 10:57:51.022 [TRACE] [ve.internal.protocol.ZWaveController] - Incoming Message type = REQUEST

2020-05-19 10:57:51.024 [TRACE] [l.serialmessage.SendDataMessageClass] - Handle Message Send Data Request

2020-05-19 10:57:51.025 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 24: SendData Request. CallBack ID = 158, Status = Transmission complete and ACK received(0)

2020-05-19 10:57:51.027 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 24: resetResendCount initComplete=true isDead=false

2020-05-19 10:57:51.028 [TRACE] [e.internal.protocol.ZWaveTransaction] - TID 1136: TransactionAdvance ST: WAIT_REQUEST

2020-05-19 10:57:51.030 [TRACE] [e.internal.protocol.ZWaveTransaction] - TID 1136: TransactionAdvance WT: ApplicationCommandHandler {}

2020-05-19 10:57:51.031 [TRACE] [e.internal.protocol.ZWaveTransaction] - TID 1136: TransactionAdvance RX: Message: class=SendData[19], type=Request[0], dest=0, callback=158, payload=9E 00 00 06 

2020-05-19 10:57:51.033 [TRACE] [e.internal.protocol.ZWaveTransaction] - TID 1136: TransactionAdvance RQ: RREQ=true, RCLS=ApplicationCommandHandler

2020-05-19 10:57:51.034 [TRACE] [e.internal.protocol.ZWaveTransaction] - TID 1136: TransactionAdvance TO: WAIT_DATA

2020-05-19 10:57:51.036 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 1136: Advanced to WAIT_DATA

2020-05-19 10:57:51.037 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 24: TID 1136: Transaction not completed

2020-05-19 10:57:51.039 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2020-05-19 10:57:51.040 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

2020-05-19 10:57:51.042 [TRACE] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage nothing

2020-05-19 10:57:51.043 [TRACE] [nal.protocol.ZWaveTransactionManager] - STOP transaction timer

2020-05-19 10:57:51.045 [TRACE] [nal.protocol.ZWaveTransactionManager] - Start transaction timer to Tue May 19 10:57:56 CDT 2020 - 4992ms

2020-05-19 10:57:51.069 [TRACE] [WaveSerialHandler$ZWaveReceiveThread] - Received SOF

2020-05-19 10:57:51.071 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 04 00 18 0A 98 80 50 AB CA D5 D1 59 08 AC 29 

2020-05-19 10:57:51.074 [TRACE] [wave.internal.protocol.SerialMessage] - NODE 255: Creating new SerialMessage from buffer = 01 10 00 04 00 18 0A 98 80 50 AB CA D5 D1 59 08 AC 29 

2020-05-19 10:57:51.075 [TRACE] [wave.internal.protocol.SerialMessage] - Calculated checksum = 41

2020-05-19 10:57:51.077 [TRACE] [wave.internal.protocol.SerialMessage] - NODE 255: Checksum matched

2020-05-19 10:57:51.079 [TRACE] [wave.internal.protocol.SerialMessage] - NODE 24: Message payload = 00 18 0A 98 80 50 AB CA D5 D1 59 08 AC 

2020-05-19 10:57:51.081 [TRACE] [WaveSerialHandler$ZWaveReceiveThread] - Message is valid, sending ACK

2020-05-19 10:57:51.083 [TRACE] [WaveSerialHandler$ZWaveReceiveThread] - Response SENT 6

2020-05-19 10:57:51.085 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=24, callback=0, payload=00 18 0A 98 80 50 AB CA D5 D1 59 08 AC 

2020-05-19 10:57:51.087 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=24, callback=0, payload=00 18 0A 98 80 50 AB CA D5 D1 59 08 AC 

2020-05-19 10:57:51.088 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null

2020-05-19 10:57:51.090 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 24: Application Command Request (ALIVE:DONE)

2020-05-19 10:57:51.091 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 24: resetResendCount initComplete=true isDead=false

2020-05-19 10:57:51.093 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 24: Incoming command class COMMAND_CLASS_SECURITY, endpoint 0

2020-05-19 10:57:51.094 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 24: SECURITY check internal

2020-05-19 10:57:51.096 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 24: Received COMMAND_CLASS_SECURITY V1 SECURITY_NONCE_REPORT

2020-05-19 10:57:51.097 [DEBUG] [mmandclass.ZWaveSecurityCommandClass] - NODE 24: NONCE Received start...

2020-05-19 10:57:51.099 [DEBUG] [mmandclass.ZWaveSecurityCommandClass] - NODE 24: NONCE Received start null

2020-05-19 10:57:51.101 [DEBUG] [mmandclass.ZWaveSecurityCommandClass] - NODE 24: NONCE Received ZWaveNonce [nonceBytes=(50 AB CA D5 D1 59 08 AC ), timer=444525, valid=true]

2020-05-19 10:57:51.103 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 24: Commands processed 1.

2020-05-19 10:57:51.104 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 24: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1eb4854.

2020-05-19 10:57:51.106 [TRACE] [nal.protocol.ZWaveTransactionManager] - NODE 24: Checking transaction 1136  ApplicationCommandHandler.

2020-05-19 10:57:51.107 [TRACE] [nal.protocol.ZWaveTransactionManager] - NODE 24: Checking transaction : state >> WAIT_DATA

2020-05-19 10:57:51.109 [TRACE] [nal.protocol.ZWaveTransactionManager] - NODE 24: Checking transaction : node  >> 24

2020-05-19 10:57:51.110 [TRACE] [nal.protocol.ZWaveTransactionManager] - NODE 24: Checking transaction : class >> 152 == 152.

2020-05-19 10:57:51.111 [TRACE] [nal.protocol.ZWaveTransactionManager] - NODE 24: Checking transaction : commd >> 128 == 128.

2020-05-19 10:57:51.113 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 24: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1eb4854.

2020-05-19 10:57:51.114 [TRACE] [e.internal.protocol.ZWaveTransaction] - TID 1136: TransactionAdvance ST: WAIT_DATA

2020-05-19 10:57:51.115 [TRACE] [e.internal.protocol.ZWaveTransaction] - TID 1136: TransactionAdvance WT: ApplicationCommandHandler {}

2020-05-19 10:57:51.118 [TRACE] [e.internal.protocol.ZWaveTransaction] - TID 1136: TransactionAdvance RX: Message: class=ApplicationCommandHandler[4], type=Request[0], dest=24, callback=0, payload=00 18 0A 98 80 50 AB CA D5 D1 59 08 AC 

2020-05-19 10:57:51.119 [TRACE] [e.internal.protocol.ZWaveTransaction] - TID 1136: TransactionAdvance TO: DONE

2020-05-19 10:57:51.121 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 24: notifyTransactionResponse TID:1136 DONE

2020-05-19 10:57:51.123 [TRACE] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent

2020-05-19 10:57:51.125 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 24: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent

2020-05-19 10:57:51.126 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1

2020-05-19 10:57:51.128 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0

2020-05-19 10:57:51.129 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2020-05-19 10:57:51.131 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

2020-05-19 10:57:51.132 [TRACE] [nal.protocol.ZWaveTransactionManager] - Transaction from secureQueue

2020-05-19 10:57:51.134 [TRACE] [nal.protocol.ZWaveTransactionManager] - NODE 24: Transaction requires security

2020-05-19 10:57:51.135 [TRACE] [nal.protocol.ZWaveTransactionManager] - NODE 24: NONCE available so encap and send.

2020-05-19 10:57:51.137 [DEBUG] [andclass.impl.CommandClassSecurityV1] - Creating command message SECURITY_MESSAGE_ENCAPSULATION version 1

2020-05-19 10:57:51.140 [DEBUG] [mmandclass.ZWaveSecurityCommandClass] - NODE 24: SECURITY_TXD 80 02 

2020-05-19 10:57:51.142 [TRACE] [wave.internal.protocol.SerialMessage] - NODE 24: Creating empty message of class = SendData (0x13), type = Request

2020-05-19 10:57:51.143 [TRACE] [wave.internal.protocol.SerialMessage] - Calculated checksum = -100

2020-05-19 10:57:51.146 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 1D 00 13 18 16 98 81 E6 2A 22 30 03 32 D3 BE 0F F2 93 50 0C E4 48 36 4A 4F 7C 93 25 9F 9C 

2020-05-19 10:57:51.149 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 24: Sending REQUEST Message = 01 1D 00 13 18 16 98 81 E6 2A 22 30 03 32 D3 BE 0F F2 93 50 0C E4 48 36 4A 4F 7C 93 25 9F 9C 

2020-05-19 10:57:51.152 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT

2020-05-19 10:57:51.153 [TRACE] [e.internal.protocol.ZWaveTransaction] - TID 1134: Transaction Start type SendData 

2020-05-19 10:57:51.155 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06

2020-05-19 10:57:51.155 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 1134: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 159

2020-05-19 10:57:51.157 [TRACE] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: expected cmd class: COMMAND_CLASS_BATTERY

2020-05-19 10:57:51.159 [TRACE] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: expected cmd: 3

2020-05-19 10:57:51.161 [TRACE] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage Transactions outstanding: 1

2020-05-19 10:57:51.162 [TRACE] [nal.protocol.ZWaveTransactionManager] - STOP transaction timer

2020-05-19 10:57:51.161 [TRACE] [wave.internal.protocol.SerialMessage] - NODE 255: Creating new SerialMessage from buffer = 06 

2020-05-19 10:57:51.165 [TRACE] [nal.protocol.ZWaveTransactionManager] - Start transaction timer to Tue May 19 10:57:53 CDT 2020 - 1998ms

2020-05-19 10:57:51.166 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

2020-05-19 10:57:51.167 [TRACE] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage lastTransaction: TID 1134: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 159

2020-05-19 10:57:51.168 [TRACE] [WaveSerialHandler$ZWaveReceiveThread] - Received SOF

2020-05-19 10:57:51.169 [TRACE] [nal.protocol.ZWaveTransactionManager] - STOP transaction timer

2020-05-19 10:57:51.171 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 

2020-05-19 10:57:51.171 [TRACE] [nal.protocol.ZWaveTransactionManager] - Start transaction timer to Tue May 19 10:57:53 CDT 2020 - 1991ms

2020-05-19 10:57:51.173 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

2020-05-19 10:57:51.174 [TRACE] [wave.internal.protocol.SerialMessage] - NODE 255: Creating new SerialMessage from buffer = 01 04 01 13 01 E8 

2020-05-19 10:57:51.175 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1134: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 159

2020-05-19 10:57:51.175 [TRACE] [wave.internal.protocol.SerialMessage] - Calculated checksum = -24

2020-05-19 10:57:51.177 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK

2020-05-19 10:57:51.177 [TRACE] [wave.internal.protocol.SerialMessage] - NODE 255: Checksum matched

2020-05-19 10:57:51.179 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2020-05-19 10:57:51.179 [TRACE] [wave.internal.protocol.SerialMessage] - NODE 255: Message payload = 01 

2020-05-19 10:57:51.180 [TRACE] [WaveSerialHandler$ZWaveReceiveThread] - Message is valid, sending ACK

2020-05-19 10:57:51.180 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

2020-05-19 10:57:51.182 [TRACE] [nal.protocol.ZWaveTransactionManager] - Transaction lastTransaction outstanding...

2020-05-19 10:57:51.182 [TRACE] [WaveSerialHandler$ZWaveReceiveThread] - Response SENT 6

2020-05-19 10:57:51.183 [TRACE] [nal.protocol.ZWaveTransactionManager] - STOP transaction timer

2020-05-19 10:57:51.184 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 

2020-05-19 10:57:51.186 [TRACE] [nal.protocol.ZWaveTransactionManager] - Start transaction timer to Tue May 19 10:57:53 CDT 2020 - 1977ms

2020-05-19 10:57:51.188 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 

2020-05-19 10:57:51.189 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1134: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 159

2020-05-19 10:57:51.191 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1

2020-05-19 10:57:51.192 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 1134: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 159

2020-05-19 10:57:51.194 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 

2020-05-19 10:57:51.195 [TRACE] [ve.internal.protocol.ZWaveController] - Incoming Message type = RESPONSE

2020-05-19 10:57:51.197 [TRACE] [l.serialmessage.SendDataMessageClass] - Handle Message Send Data Response

2020-05-19 10:57:51.198 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 24: sentData successfully placed on stack.

2020-05-19 10:57:51.200 [TRACE] [e.internal.protocol.ZWaveTransaction] - TID 1134: TransactionAdvance ST: WAIT_RESPONSE

2020-05-19 10:57:51.201 [TRACE] [e.internal.protocol.ZWaveTransaction] - TID 1134: TransactionAdvance WT: ApplicationCommandHandler {}

2020-05-19 10:57:51.203 [TRACE] [e.internal.protocol.ZWaveTransaction] - TID 1134: TransactionAdvance RX: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 

2020-05-19 10:57:51.204 [TRACE] [e.internal.protocol.ZWaveTransaction] - TID 1134: TransactionAdvance TO: WAIT_REQUEST

2020-05-19 10:57:51.206 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 1134: Advanced to WAIT_REQUEST

2020-05-19 10:57:51.207 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 24: TID 1134: Transaction not completed

2020-05-19 10:57:51.209 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2020-05-19 10:57:51.210 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

2020-05-19 10:57:51.212 [TRACE] [nal.protocol.ZWaveTransactionManager] - Transaction lastTransaction outstanding...

2020-05-19 10:57:51.213 [TRACE] [nal.protocol.ZWaveTransactionManager] - STOP transaction timer

2020-05-19 10:57:51.215 [TRACE] [nal.protocol.ZWaveTransactionManager] - Start transaction timer to Tue May 19 10:57:56 CDT 2020 - 4993ms

2020-05-19 10:57:51.258 [TRACE] [WaveSerialHandler$ZWaveReceiveThread] - Received SOF

2020-05-19 10:57:51.260 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 9F 00 00 0A 7E 

2020-05-19 10:57:51.262 [TRACE] [wave.internal.protocol.SerialMessage] - NODE 255: Creating new SerialMessage from buffer = 01 07 00 13 9F 00 00 0A 7E 

2020-05-19 10:57:51.264 [TRACE] [wave.internal.protocol.SerialMessage] - Calculated checksum = 126

2020-05-19 10:57:51.265 [TRACE] [wave.internal.protocol.SerialMessage] - NODE 255: Checksum matched

2020-05-19 10:57:51.267 [TRACE] [wave.internal.protocol.SerialMessage] - NODE 0: Message payload = 9F 00 00 0A 

2020-05-19 10:57:51.269 [TRACE] [WaveSerialHandler$ZWaveReceiveThread] - Message is valid, sending ACK

2020-05-19 10:57:51.270 [TRACE] [WaveSerialHandler$ZWaveReceiveThread] - Response SENT 6

2020-05-19 10:57:51.272 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=159, payload=9F 00 00 0A 

2020-05-19 10:57:51.274 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=159, payload=9F 00 00 0A 

2020-05-19 10:57:51.275 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1134: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 159

2020-05-19 10:57:51.277 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1

2020-05-19 10:57:51.278 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 1134: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 159

2020-05-19 10:57:51.280 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 1134: (Callback 159)

2020-05-19 10:57:51.281 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!

2020-05-19 10:57:51.283 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 1134: callback 159

2020-05-19 10:57:51.285 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=159, payload=9F 00 00 0A 

2020-05-19 10:57:51.286 [TRACE] [ve.internal.protocol.ZWaveController] - Incoming Message type = REQUEST

2020-05-19 10:57:51.288 [TRACE] [l.serialmessage.SendDataMessageClass] - Handle Message Send Data Request

2020-05-19 10:57:51.290 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 24: SendData Request. CallBack ID = 159, Status = Transmission complete and ACK received(0)

2020-05-19 10:57:51.291 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 24: resetResendCount initComplete=true isDead=false

2020-05-19 10:57:51.293 [TRACE] [e.internal.protocol.ZWaveTransaction] - TID 1134: TransactionAdvance ST: WAIT_REQUEST

2020-05-19 10:57:51.294 [TRACE] [e.internal.protocol.ZWaveTransaction] - TID 1134: TransactionAdvance WT: ApplicationCommandHandler {}

2020-05-19 10:57:51.296 [TRACE] [e.internal.protocol.ZWaveTransaction] - TID 1134: TransactionAdvance RX: Message: class=SendData[19], type=Request[0], dest=0, callback=159, payload=9F 00 00 0A 

2020-05-19 10:57:51.297 [TRACE] [e.internal.protocol.ZWaveTransaction] - TID 1134: TransactionAdvance RQ: RREQ=true, RCLS=ApplicationCommandHandler

2020-05-19 10:57:51.299 [TRACE] [e.internal.protocol.ZWaveTransaction] - TID 1134: TransactionAdvance TO: WAIT_DATA

2020-05-19 10:57:51.300 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 1134: Advanced to WAIT_DATA

2020-05-19 10:57:51.302 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 24: TID 1134: Transaction not completed

2020-05-19 10:57:51.303 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2020-05-19 10:57:51.305 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

2020-05-19 10:57:51.306 [TRACE] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage nothing

2020-05-19 10:57:51.307 [TRACE] [nal.protocol.ZWaveTransactionManager] - STOP transaction timer

2020-05-19 10:57:51.309 [TRACE] [nal.protocol.ZWaveTransactionManager] - Start transaction timer to Tue May 19 10:57:56 CDT 2020 - 4993ms

2020-05-19 10:57:51.799 [TRACE] [WaveSerialHandler$ZWaveReceiveThread] - Received SOF

2020-05-19 10:57:51.802 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 08 00 04 00 18 02 98 40 31 

2020-05-19 10:57:51.803 [TRACE] [wave.internal.protocol.SerialMessage] - NODE 255: Creating new SerialMessage from buffer = 01 08 00 04 00 18 02 98 40 31 

2020-05-19 10:57:51.805 [TRACE] [wave.internal.protocol.SerialMessage] - Calculated checksum = 49

2020-05-19 10:57:51.806 [TRACE] [wave.internal.protocol.SerialMessage] - NODE 255: Checksum matched

2020-05-19 10:57:51.808 [TRACE] [wave.internal.protocol.SerialMessage] - NODE 24: Message payload = 00 18 02 98 40 

2020-05-19 10:57:51.809 [TRACE] [WaveSerialHandler$ZWaveReceiveThread] - Message is valid, sending ACK

2020-05-19 10:57:51.810 [TRACE] [WaveSerialHandler$ZWaveReceiveThread] - Response SENT 6

2020-05-19 10:57:51.812 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=24, callback=0, payload=00 18 02 98 40 

2020-05-19 10:57:51.813 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=24, callback=0, payload=00 18 02 98 40 

2020-05-19 10:57:51.815 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null

2020-05-19 10:57:51.816 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 24: Application Command Request (ALIVE:DONE)

2020-05-19 10:57:51.817 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 24: resetResendCount initComplete=true isDead=false

2020-05-19 10:57:51.819 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 24: Incoming command class COMMAND_CLASS_SECURITY, endpoint 0

2020-05-19 10:57:51.820 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 24: SECURITY check internal

2020-05-19 10:57:51.822 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 24: Received COMMAND_CLASS_SECURITY V1 SECURITY_NONCE_GET

2020-05-19 10:57:51.824 [DEBUG] [andclass.impl.CommandClassSecurityV1] - Creating command message SECURITY_NONCE_REPORT version 1

2020-05-19 10:57:51.825 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 24: Added to secure queue - size 1

2020-05-19 10:57:51.827 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 24: Commands processed 1.

2020-05-19 10:57:51.829 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 24: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1a0c8dc.

2020-05-19 10:57:51.830 [TRACE] [nal.protocol.ZWaveTransactionManager] - NODE 24: Checking transaction 1134  ApplicationCommandHandler.

2020-05-19 10:57:51.831 [TRACE] [nal.protocol.ZWaveTransactionManager] - NODE 24: Checking transaction : state >> WAIT_DATA

2020-05-19 10:57:51.833 [TRACE] [nal.protocol.ZWaveTransactionManager] - NODE 24: Checking transaction : node  >> 24

2020-05-19 10:57:51.834 [TRACE] [nal.protocol.ZWaveTransactionManager] - NODE 24: Checking transaction : class >> 152 == 128.

2020-05-19 10:57:51.835 [TRACE] [nal.protocol.ZWaveTransactionManager] - NODE 24: Checking transaction : commd >> 64 == 3.

2020-05-19 10:57:51.837 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 24: Command NOT verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1a0c8dc.

2020-05-19 10:57:51.838 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1

2020-05-19 10:57:51.840 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1

2020-05-19 10:57:51.841 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2020-05-19 10:57:51.842 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

2020-05-19 10:57:51.843 [TRACE] [nal.protocol.ZWaveTransactionManager] - Transaction from secureQueue

2020-05-19 10:57:51.844 [TRACE] [nal.protocol.ZWaveTransactionManager] - getTransactionToSend 6

2020-05-19 10:57:51.846 [TRACE] [wave.internal.protocol.SerialMessage] - NODE 24: Creating empty message of class = SendData (0x13), type = Request

2020-05-19 10:57:51.857 [TRACE] [wave.internal.protocol.SerialMessage] - Calculated checksum = 5

2020-05-19 10:57:51.860 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 11 00 13 18 0A 98 80 BB 98 ED A9 72 F6 20 B4 25 A0 05 

2020-05-19 10:57:51.863 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 24: Sending REQUEST Message = 01 11 00 13 18 0A 98 80 BB 98 ED A9 72 F6 20 B4 25 A0 05 

2020-05-19 10:57:51.865 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT

2020-05-19 10:57:51.868 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06

2020-05-19 10:57:51.870 [TRACE] [wave.internal.protocol.SerialMessage] - NODE 255: Creating new SerialMessage from buffer = 06 

2020-05-19 10:57:51.871 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

2020-05-19 10:57:51.874 [TRACE] [WaveSerialHandler$ZWaveReceiveThread] - Received SOF

2020-05-19 10:57:51.877 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 

2020-05-19 10:57:51.867 [TRACE] [e.internal.protocol.ZWaveTransaction] - TID 1137: Transaction Start type SendData 

2020-05-19 10:57:51.879 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 1137: [WAIT_RESPONSE] priority=NonceResponse, requiresResponse=true, callback: 160

2020-05-19 10:57:51.880 [TRACE] [wave.internal.protocol.SerialMessage] - NODE 255: Creating new SerialMessage from buffer = 01 04 01 13 01 E8 

2020-05-19 10:57:51.881 [TRACE] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: expected cmd class: null

2020-05-19 10:57:51.883 [TRACE] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: expected cmd: 0

2020-05-19 10:57:51.885 [TRACE] [wave.internal.protocol.SerialMessage] - Calculated checksum = -24

2020-05-19 10:57:51.885 [TRACE] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage Transactions outstanding: 2

2020-05-19 10:57:51.886 [TRACE] [nal.protocol.ZWaveTransactionManager] - STOP transaction timer

2020-05-19 10:57:51.886 [TRACE] [wave.internal.protocol.SerialMessage] - NODE 255: Checksum matched

2020-05-19 10:57:51.889 [TRACE] [wave.internal.protocol.SerialMessage] - NODE 255: Message payload = 01 

2020-05-19 10:57:51.889 [TRACE] [nal.protocol.ZWaveTransactionManager] - Start transaction timer to Tue May 19 10:57:53 CDT 2020 - 1998ms

2020-05-19 10:57:51.890 [TRACE] [WaveSerialHandler$ZWaveReceiveThread] - Message is valid, sending ACK

2020-05-19 10:57:51.891 [TRACE] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage lastTransaction: TID 1137: [WAIT_RESPONSE] priority=NonceResponse, requiresResponse=true, callback: 160

2020-05-19 10:57:51.893 [TRACE] [WaveSerialHandler$ZWaveReceiveThread] - Response SENT 6

2020-05-19 10:57:51.893 [TRACE] [nal.protocol.ZWaveTransactionManager] - STOP transaction timer

2020-05-19 10:57:51.895 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 1<>127 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 

2020-05-19 10:57:51.896 [TRACE] [nal.protocol.ZWaveTransactionManager] - Start transaction timer to Tue May 19 10:57:53 CDT 2020 - 1991ms

2020-05-19 10:57:51.898 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (1): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

2020-05-19 10:57:51.899 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1137: [WAIT_RESPONSE] priority=NonceResponse, requiresResponse=true, callback: 160

2020-05-19 10:57:51.902 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK

2020-05-19 10:57:51.905 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 

2020-05-19 10:57:51.908 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1137: [WAIT_RESPONSE] priority=NonceResponse, requiresResponse=true, callback: 160

2020-05-19 10:57:51.909 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 2

2020-05-19 10:57:51.912 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 1137: [WAIT_RESPONSE] priority=NonceResponse, requiresResponse=true, callback: 160

2020-05-19 10:57:51.914 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 

2020-05-19 10:57:51.916 [TRACE] [ve.internal.protocol.ZWaveController] - Incoming Message type = RESPONSE

2020-05-19 10:57:51.918 [TRACE] [l.serialmessage.SendDataMessageClass] - Handle Message Send Data Response

2020-05-19 10:57:51.921 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 24: sentData successfully placed on stack.

2020-05-19 10:57:51.922 [TRACE] [e.internal.protocol.ZWaveTransaction] - TID 1137: TransactionAdvance ST: WAIT_RESPONSE

2020-05-19 10:57:51.925 [TRACE] [e.internal.protocol.ZWaveTransaction] - TID 1137: TransactionAdvance WT: null {}

2020-05-19 10:57:51.927 [TRACE] [e.internal.protocol.ZWaveTransaction] - TID 1137: TransactionAdvance RX: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 

2020-05-19 10:57:51.929 [TRACE] [e.internal.protocol.ZWaveTransaction] - TID 1137: TransactionAdvance TO: WAIT_REQUEST

2020-05-19 10:57:51.931 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 1137: Advanced to WAIT_REQUEST

2020-05-19 10:57:51.933 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 24: TID 1137: Transaction not completed

2020-05-19 10:57:51.935 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2020-05-19 10:57:51.937 [TRACE] [WaveSerialHandler$ZWaveReceiveThread] - Received SOF

2020-05-19 10:57:51.937 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 2 out at start. Holdoff false.

2020-05-19 10:57:51.938 [TRACE] [nal.protocol.ZWaveTransactionManager] - Transaction lastTransaction outstanding...

2020-05-19 10:57:51.939 [TRACE] [nal.protocol.ZWaveTransactionManager] - STOP transaction timer

2020-05-19 10:57:51.939 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 A0 00 00 07 4C 

2020-05-19 10:57:51.941 [TRACE] [nal.protocol.ZWaveTransactionManager] - Start transaction timer to Tue May 19 10:57:56 CDT 2020 - 4360ms

2020-05-19 10:57:51.942 [TRACE] [wave.internal.protocol.SerialMessage] - NODE 255: Creating new SerialMessage from buffer = 01 07 00 13 A0 00 00 07 4C 

2020-05-19 10:57:51.944 [TRACE] [wave.internal.protocol.SerialMessage] - Calculated checksum = 76

2020-05-19 10:57:51.946 [TRACE] [wave.internal.protocol.SerialMessage] - NODE 255: Checksum matched

2020-05-19 10:57:51.948 [TRACE] [wave.internal.protocol.SerialMessage] - NODE 0: Message payload = A0 00 00 07 

2020-05-19 10:57:51.950 [TRACE] [WaveSerialHandler$ZWaveReceiveThread] - Message is valid, sending ACK

2020-05-19 10:57:51.952 [TRACE] [WaveSerialHandler$ZWaveReceiveThread] - Response SENT 6

2020-05-19 10:57:51.955 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=160, payload=A0 00 00 07 

2020-05-19 10:57:51.956 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=160, payload=A0 00 00 07 

2020-05-19 10:57:51.958 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1137: [WAIT_REQUEST] priority=NonceResponse, requiresResponse=true, callback: 160

2020-05-19 10:57:51.960 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 2

2020-05-19 10:57:51.961 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 1137: [WAIT_REQUEST] priority=NonceResponse, requiresResponse=true, callback: 160

2020-05-19 10:57:51.963 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 1134: (Callback 159)

2020-05-19 10:57:51.965 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - NO callback match! (159 <> 160)

2020-05-19 10:57:51.966 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 1137: (Callback 160)

2020-05-19 10:57:51.968 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!

2020-05-19 10:57:51.969 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 1137: callback 160

2020-05-19 10:57:51.971 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=160, payload=A0 00 00 07 

2020-05-19 10:57:51.972 [TRACE] [ve.internal.protocol.ZWaveController] - Incoming Message type = REQUEST

2020-05-19 10:57:51.973 [TRACE] [l.serialmessage.SendDataMessageClass] - Handle Message Send Data Request

2020-05-19 10:57:51.975 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 24: SendData Request. CallBack ID = 160, Status = Transmission complete and ACK received(0)

2020-05-19 10:57:51.976 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 24: resetResendCount initComplete=true isDead=false

2020-05-19 10:57:51.977 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 1137: Transaction COMPLETED

2020-05-19 10:57:51.979 [TRACE] [e.internal.protocol.ZWaveTransaction] - TID 1137: TransactionAdvance ST: DONE

2020-05-19 10:57:51.980 [TRACE] [e.internal.protocol.ZWaveTransaction] - TID 1137: TransactionAdvance WT: null {}

2020-05-19 10:57:51.982 [TRACE] [e.internal.protocol.ZWaveTransaction] - TID 1137: TransactionAdvance RX: Message: class=SendData[19], type=Request[0], dest=0, callback=160, payload=A0 00 00 07 

2020-05-19 10:57:51.984 [TRACE] [e.internal.protocol.ZWaveTransaction] - TID 1137: TransactionAdvance TO: DONE

2020-05-19 10:57:51.985 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 24: Response processed after 118ms

2020-05-19 10:57:51.987 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 24: TID 1137: Transaction completed

2020-05-19 10:57:51.988 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 24: notifyTransactionResponse TID:1137 DONE

2020-05-19 10:57:51.990 [TRACE] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent

2020-05-19 10:57:51.991 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 24: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent

2020-05-19 10:57:51.993 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2020-05-19 10:57:51.994 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

2020-05-19 10:57:51.995 [TRACE] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage nothing

2020-05-19 10:57:51.997 [TRACE] [nal.protocol.ZWaveTransactionManager] - STOP transaction timer

2020-05-19 10:57:51.999 [TRACE] [nal.protocol.ZWaveTransactionManager] - Start transaction timer to Tue May 19 10:57:56 CDT 2020 - 4303ms

2020-05-19 10:57:52.024 [TRACE] [WaveSerialHandler$ZWaveReceiveThread] - Received SOF

2020-05-19 10:57:52.027 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 1D 00 04 00 18 17 98 81 2D E0 EA D1 72 20 6C DB 76 6C C4 26 BB 24 8C 11 56 36 65 D6 46 8C 

2020-05-19 10:57:52.030 [TRACE] [wave.internal.protocol.SerialMessage] - NODE 255: Creating new SerialMessage from buffer = 01 1D 00 04 00 18 17 98 81 2D E0 EA D1 72 20 6C DB 76 6C C4 26 BB 24 8C 11 56 36 65 D6 46 8C 

2020-05-19 10:57:52.031 [TRACE] [wave.internal.protocol.SerialMessage] - Calculated checksum = -116

2020-05-19 10:57:52.033 [TRACE] [wave.internal.protocol.SerialMessage] - NODE 255: Checksum matched

2020-05-19 10:57:52.035 [TRACE] [wave.internal.protocol.SerialMessage] - NODE 24: Message payload = 00 18 17 98 81 2D E0 EA D1 72 20 6C DB 76 6C C4 26 BB 24 8C 11 56 36 65 D6 46 

2020-05-19 10:57:52.037 [TRACE] [WaveSerialHandler$ZWaveReceiveThread] - Message is valid, sending ACK

2020-05-19 10:57:52.038 [TRACE] [WaveSerialHandler$ZWaveReceiveThread] - Response SENT 6

2020-05-19 10:57:52.040 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=24, callback=0, payload=00 18 17 98 81 2D E0 EA D1 72 20 6C DB 76 6C C4 26 BB 24 8C 11 56 36 65 D6 46 

2020-05-19 10:57:52.042 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=24, callback=0, payload=00 18 17 98 81 2D E0 EA D1 72 20 6C DB 76 6C C4 26 BB 24 8C 11 56 36 65 D6 46 

2020-05-19 10:57:52.044 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null

2020-05-19 10:57:52.045 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 24: Application Command Request (ALIVE:DONE)

2020-05-19 10:57:52.046 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 24: resetResendCount initComplete=true isDead=false

2020-05-19 10:57:52.048 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 24: Decapsulating COMMAND_CLASS_SECURITY

2020-05-19 10:57:52.050 [DEBUG] [mmandclass.ZWaveSecurityCommandClass] - NODE 24: SECURITY_RXD 80 03 54 

2020-05-19 10:57:52.052 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 24: Incoming command class COMMAND_CLASS_BATTERY, endpoint 0

2020-05-19 10:57:52.053 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 24: Received COMMAND_CLASS_BATTERY V1 BATTERY_REPORT

2020-05-19 10:57:52.055 [DEBUG] [ommandclass.ZWaveBatteryCommandClass] - NODE 24: Battery report value = 84

2020-05-19 10:57:52.056 [TRACE] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveCommandClassValueEvent

2020-05-19 10:57:52.058 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 24: Got an event from Z-Wave network: ZWaveCommandClassValueEvent

2020-05-19 10:57:52.059 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 24: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_BATTERY, value=84

2020-05-19 10:57:52.062 [TRACE] [ding.zwave.handler.ZWaveThingHandler] - NODE 24: Checking channel=zwave:device:c9fd3b4d:node24:lock_door, cmdClass=COMMAND_CLASS_DOOR_LOCK, endpoint=0

2020-05-19 10:57:52.064 [TRACE] [ding.zwave.handler.ZWaveThingHandler] - NODE 24: Checking channel=zwave:device:c9fd3b4d:node24:alarm_access, cmdClass=COMMAND_CLASS_ALARM, endpoint=0

2020-05-19 10:57:52.066 [TRACE] [ding.zwave.handler.ZWaveThingHandler] - NODE 24: Checking channel=zwave:device:c9fd3b4d:node24:alarm_power, cmdClass=COMMAND_CLASS_ALARM, endpoint=0

2020-05-19 10:57:52.068 [TRACE] [ding.zwave.handler.ZWaveThingHandler] - NODE 24: Checking channel=zwave:device:c9fd3b4d:node24:alarm_raw, cmdClass=COMMAND_CLASS_ALARM, endpoint=0

2020-05-19 10:57:52.070 [TRACE] [ding.zwave.handler.ZWaveThingHandler] - NODE 24: Checking channel=zwave:device:c9fd3b4d:node24:battery-level, cmdClass=COMMAND_CLASS_BATTERY, endpoint=0

2020-05-19 10:57:52.072 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 24: Updating channel state zwave:device:c9fd3b4d:node24:battery-level to 84 [DecimalType]

2020-05-19 10:57:52.075 [TRACE] [ding.zwave.handler.ZWaveThingHandler] - NODE 24: Checking channel=zwave:device:c9fd3b4d:node24:time_offset, cmdClass=COMMAND_CLASS_TIME_PARAMETERS, endpoint=0

2020-05-19 10:57:52.077 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 24: Commands processed 1.

2020-05-19 10:57:52.078 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 24: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@19cfcc3.

2020-05-19 10:57:52.080 [TRACE] [nal.protocol.ZWaveTransactionManager] - NODE 24: Checking transaction 1134  ApplicationCommandHandler.

2020-05-19 10:57:52.081 [TRACE] [nal.protocol.ZWaveTransactionManager] - NODE 24: Checking transaction : state >> WAIT_DATA

2020-05-19 10:57:52.083 [TRACE] [nal.protocol.ZWaveTransactionManager] - NODE 24: Checking transaction : node  >> 24

2020-05-19 10:57:52.084 [TRACE] [nal.protocol.ZWaveTransactionManager] - NODE 24: Checking transaction : class >> 128 == 128.

2020-05-19 10:57:52.086 [TRACE] [nal.protocol.ZWaveTransactionManager] - NODE 24: Checking transaction : commd >> 3 == 3.

2020-05-19 10:57:52.087 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 24: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@19cfcc3.

2020-05-19 10:57:52.088 [TRACE] [e.internal.protocol.ZWaveTransaction] - TID 1134: TransactionAdvance ST: WAIT_DATA

2020-05-19 10:57:52.090 [TRACE] [e.internal.protocol.ZWaveTransaction] - TID 1134: TransactionAdvance WT: ApplicationCommandHandler {}

2020-05-19 10:57:52.092 [TRACE] [e.internal.protocol.ZWaveTransaction] - TID 1134: TransactionAdvance RX: Message: class=ApplicationCommandHandler[4], type=Request[0], dest=24, callback=0, payload=00 18 17 98 81 2D E0 EA D1 72 20 6C DB 76 6C C4 26 BB 24 8C 11 56 36 65 D6 46 

2020-05-19 10:57:52.093 [TRACE] [e.internal.protocol.ZWaveTransaction] - TID 1134: TransactionAdvance TO: DONE

2020-05-19 10:57:52.094 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 24: notifyTransactionResponse TID:1134 DONE

2020-05-19 10:57:52.095 [TRACE] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent

2020-05-19 10:57:52.097 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 24: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent

2020-05-19 10:57:52.098 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1

2020-05-19 10:57:52.099 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0

2020-05-19 10:57:52.100 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2020-05-19 10:57:52.101 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

2020-05-19 10:57:52.103 [TRACE] [ng.zwave.internal.protocol.ZWaveNode] - NODE 22: listening == false, frequentlyListening == false, awake == false

2020-05-19 10:57:52.104 [TRACE] [nal.protocol.ZWaveTransactionManager] - NODE 22: Node not awake!

2020-05-19 10:57:52.105 [TRACE] [ng.zwave.internal.protocol.ZWaveNode] - NODE 6: listening == false, frequentlyListening == false, awake == false

2020-05-19 10:57:52.106 [TRACE] [nal.protocol.ZWaveTransactionManager] - NODE 6: Node not awake!

2020-05-19 10:57:52.108 [TRACE] [ng.zwave.internal.protocol.ZWaveNode] - NODE 25: listening == false, frequentlyListening == false, awake == false

2020-05-19 10:57:52.109 [TRACE] [nal.protocol.ZWaveTransactionManager] - NODE 25: Node not awake!

2020-05-19 10:57:52.111 [TRACE] [ng.zwave.internal.protocol.ZWaveNode] - NODE 25: listening == false, frequentlyListening == false, awake == false

2020-05-19 10:57:52.112 [TRACE] [nal.protocol.ZWaveTransactionManager] - NODE 25: Node not awake!

2020-05-19 10:57:52.113 [TRACE] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage nothing

2020-05-19 10:57:52.114 [TRACE] [nal.protocol.ZWaveTransactionManager] - STOP transaction timer

==> /var/log/openhab2/events.log <==

2020-05-19 10:57:54.836 [vent.ItemStateChangedEvent] - Nw_Bedroom_Motion changed from 1 to 0

2020-05-19 10:57:56.277 [vent.ItemStateChangedEvent] - Nw_Bedroom_Temperature changed from 71.2 to 71.3

2020-05-19 10:57:56.416 [vent.ItemStateChangedEvent] - Nw_Bedroom_Humidity changed from 45.7 to 45.8

2020-05-19 10:57:56.603 [vent.ItemStateChangedEvent] - OpenhabianRasp3_Cpu_LoadAverage changed from 0.6 to 0.5

2020-05-19 10:57:56.605 [vent.ItemStateChangedEvent] - OpenhabianRasp3_Cpu_LoadAverage changed from 0.5 to 0.6

2020-05-19 10:57:56.607 [vent.ItemStateChangedEvent] - OpenhabianRasp3_Cpu_SystemUptime changed from 174.9 to 175.9

==> /var/log/openhab2/openhab.log <==

2020-05-19 10:58:05.475 [INFO ] [el.core.internal.ModelRepositoryImpl] - Refreshing model 'home.items'

2020-05-19 10:58:05.590 [INFO ] [.internal.PlexGenericBindingProvider] - Plex item PlexTVStatus_Note_9 bound to client 94cdae691747e0bb-com-plexapp-android property state

2020-05-19 10:58:05.601 [INFO ] [.internal.PlexGenericBindingProvider] - Plex item PlexTVTitle_Note_9 bound to client 94cdae691747e0bb-com-plexapp-android property title

2020-05-19 10:58:05.617 [INFO ] [.internal.PlexGenericBindingProvider] - Plex item PlexTVType_Note_9 bound to client 94cdae691747e0bb-com-plexapp-android property type

2020-05-19 10:58:05.625 [INFO ] [.internal.PlexGenericBindingProvider] - Plex item PlexTVCover_Note_9 bound to client 94cdae691747e0bb-com-plexapp-android property playback/cover

2020-05-19 10:58:05.629 [INFO ] [.internal.PlexGenericBindingProvider] - Plex item PlexTVProgress_Note_9 bound to client 94cdae691747e0bb-com-plexapp-android property playback/progress

2020-05-19 10:58:05.634 [INFO ] [.internal.PlexGenericBindingProvider] - Plex item PlexTVEndTime_Note_9 bound to client 94cdae691747e0bb-com-plexapp-androi property playback/endTime

2020-05-19 10:58:05.639 [INFO ] [.internal.PlexGenericBindingProvider] - Plex item PlexTVStatus_OLED bound to client ewkrigzy8wmz33dihzdnlnbp property state

2020-05-19 10:58:05.644 [INFO ] [.internal.PlexGenericBindingProvider] - Plex item PlexTVTitle_OLED bound to client ewkrigzy8wmz33dihzdnlnbp property title

2020-05-19 10:58:05.648 [INFO ] [.internal.PlexGenericBindingProvider] - Plex item PlexTVType_OLED bound to client ewkrigzy8wmz33dihzdnlnbp property type

2020-05-19 10:58:05.653 [INFO ] [.internal.PlexGenericBindingProvider] - Plex item PlexTVCover_OLED bound to client ewkrigzy8wmz33dihzdnlnbp property playback/cover

2020-05-19 10:58:05.657 [INFO ] [.internal.PlexGenericBindingProvider] - Plex item PlexTVProgress_OLED bound to client ewkrigzy8wmz33dihzdnlnbp property playback/progress

2020-05-19 10:58:05.662 [INFO ] [.internal.PlexGenericBindingProvider] - Plex item PlexTVEndTime_OLED bound to client ewkrigzy8wmz33dihzdnlnbp property playback/endTime

2020-05-19 10:58:05.667 [INFO ] [.internal.PlexGenericBindingProvider] - Plex item PlexTVStatus_Roku bound to client d7d6b3b42c3c53aaa6ccf659f700571d property state

2020-05-19 10:58:05.671 [INFO ] [.internal.PlexGenericBindingProvider] - Plex item PlexTVTitle_Roku bound to client d7d6b3b42c3c53aaa6ccf659f700571d property title

2020-05-19 10:58:05.676 [INFO ] [.internal.PlexGenericBindingProvider] - Plex item PlexTVType_Roku bound to client d7d6b3b42c3c53aaa6ccf659f700571d property type

2020-05-19 10:58:05.682 [INFO ] [.internal.PlexGenericBindingProvider] - Plex item PlexTVCover_Roku bound to client d7d6b3b42c3c53aaa6ccf659f700571d property playback/cover

2020-05-19 10:58:05.686 [INFO ] [.internal.PlexGenericBindingProvider] - Plex item PlexTVProgress_Roku bound to client d7d6b3b42c3c53aaa6ccf659f700571d property playback/progress

2020-05-19 10:58:05.691 [INFO ] [.internal.PlexGenericBindingProvider] - Plex item PlexTVEndTime_Roku bound to client d7d6b3b42c3c53aaa6ccf659f700571d property playback/endTime

2020-05-19 10:58:05.731 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 25: Channel zwave:device:c9fd3b4d:node25:sensor_binary linked - polling started.

==> /var/log/openhab2/events.log <==

2020-05-19 10:58:05.732 [.ItemChannelLinkAddedEvent] - Link 'Front_Door-zwave:device:c9fd3b4d:node25:sensor_binary' has been added.

2020-05-19 10:58:07.743 [vent.ItemStateChangedEvent] - Nw_Bedroom_Motion changed from 0 to 1

2020-05-19 10:58:26.653 [vent.ItemStateChangedEvent] - Nw_Bedroom_Temperature changed from 71.3 to 71.2

2020-05-19 10:58:26.743 [vent.ItemStateChangedEvent] - Nw_Bedroom_Humidity changed from 45.8 to 45.7

==> /var/log/openhab2/openhab.log <==

2020-05-19 10:58:31.442 [ERROR] [ntime.internal.engine.RuleEngineImpl] - Error during the execution of startup rule 'Changed Item': null

==> /var/log/openhab2/events.log <==

2020-05-19 10:58:45.710 [vent.ItemStateChangedEvent] - Nw_Bedroom_Motion changed from 1 to 0

2020-05-19 10:58:47.277 [vent.ItemStateChangedEvent] - LocalTime_Date changed from 2020-05-19T10:57:47.262-0500 to 2020-05-19T10:58:47.263-0500

2020-05-19 10:58:53.170 [vent.ItemStateChangedEvent] - ZoneMinderServer_CPULoad changed from 2.71 to 2.74

2020-05-19 10:58:56.630 [vent.ItemStateChangedEvent] - OpenhabianRasp3_Cpu_LoadAverage changed from 0.6 to 0.7

2020-05-19 10:58:56.634 [vent.ItemStateChangedEvent] - OpenhabianRasp3_Cpu_SystemUptime changed from 175.9 to 176.9

2020-05-19 10:58:57.054 [vent.ItemStateChangedEvent] - Nw_Bedroom_Temperature changed from 71.2 to 71.3

2020-05-19 10:58:57.215 [vent.ItemStateChangedEvent] - Nw_Bedroom_Humidity changed from 45.7 to 45.8

2020-05-19 10:58:59.658 [vent.ItemStateChangedEvent] - Nw_Bedroom_Motion changed from 0 to 1

2020-05-19 10:59:28.144 [vent.ItemStateChangedEvent] - Nw_Bedroom_Temperature changed from 71.3 to 71.2

2020-05-19 10:59:38.172 [vent.ItemStateChangedEvent] - Nw_Bedroom_Motion changed from 1 to 0

==> /var/log/openhab2/openhab.log <==

2020-05-19 10:59:39.423 [INFO ] [el.core.internal.ModelRepositoryImpl] - Refreshing model 'home.sitemap'

2020-05-19 10:59:39.544 [WARN ] [ui.internal.items.ItemUIRegistryImpl] - Cannot retrieve color item Last_Update for widget

2020-05-19 10:59:39.549 [WARN ] [ui.internal.items.ItemUIRegistryImpl] - Cannot retrieve color item Last_Update for widget

2020-05-19 10:59:39.584 [WARN ] [ui.internal.items.ItemUIRegistryImpl] - Cannot retrieve color item Last_Update for widget

2020-05-19 10:59:39.591 [WARN ] [ui.internal.items.ItemUIRegistryImpl] - Cannot retrieve color item Last_Update for widget

2020-05-19 10:59:39.626 [WARN ] [ui.internal.items.ItemUIRegistryImpl] - couldn't transform value in label because transformationService of type 'SCALE' is unavailable

2020-05-19 10:59:39.631 [WARN ] [ui.internal.items.ItemUIRegistryImpl] - couldn't transform value in label because transformationService of type 'SCALE' is unavailable

2020-05-19 10:59:39.676 [WARN ] [ui.internal.items.ItemUIRegistryImpl] - Cannot retrieve color item Last_Update for widget

2020-05-19 10:59:39.680 [WARN ] [ui.internal.items.ItemUIRegistryImpl] - Cannot retrieve color item Last_Update for widget

2020-05-19 10:59:39.688 [WARN ] [ui.internal.items.ItemUIRegistryImpl] - Cannot retrieve color item Last_Update for widget

2020-05-19 10:59:39.695 [WARN ] [ui.internal.items.ItemUIRegistryImpl] - Cannot retrieve color item Last_Update for widget

2020-05-19 10:59:39.732 [WARN ] [ui.internal.items.ItemUIRegistryImpl] - couldn't transform value in label because transformationService of type 'SCALE' is unavailable

2020-05-19 10:59:39.737 [WARN ] [ui.internal.items.ItemUIRegistryImpl] - couldn't transform value in label because transformationService of type 'SCALE' is unavailable

2020-05-19 10:59:42.046 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 25: Polling...

2020-05-19 10:59:42.047 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 25: Polling zwave:device:c9fd3b4d:node25:sensor_binary

2020-05-19 10:59:42.049 [DEBUG] [converter.ZWaveBinarySensorConverter] - NODE 25: Generating poll message for COMMAND_CLASS_SENSOR_BINARY, endpoint 0

2020-05-19 10:59:42.050 [DEBUG] [dclass.ZWaveBinarySensorCommandClass] - NODE 25: Creating new message for application command SENSOR_BINARY_GET

2020-05-19 10:59:42.051 [TRACE] [ng.zwave.internal.protocol.ZWaveNode] - NODE 25: Encapsulating message, endpoint 0

2020-05-19 10:59:42.053 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 25: SECURITY not supported

2020-05-19 10:59:42.054 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 25: Command Class COMMAND_CLASS_SENSOR_BINARY is NOT required to be secured

2020-05-19 10:59:42.055 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 25: Polling skipped for zwave:device:c9fd3b4d:node25:sensor_binary on COMMAND_CLASS_BASIC

2020-05-19 10:59:42.057 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 25: Polling zwave:device:c9fd3b4d:node25:alarm_access

2020-05-19 10:59:42.059 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 25: Polling zwave:device:c9fd3b4d:node25:battery-level

2020-05-19 10:59:42.060 [DEBUG] [rnal.converter.ZWaveBatteryConverter] - NODE 25: Generating poll message for COMMAND_CLASS_BATTERY endpoint 0

2020-05-19 10:59:42.061 [TRACE] [ng.zwave.internal.protocol.ZWaveNode] - NODE 25: Encapsulating message, endpoint 0

2020-05-19 10:59:42.063 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 25: SECURITY not supported

2020-05-19 10:59:42.064 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 25: Command Class COMMAND_CLASS_BATTERY is NOT required to be secured

2020-05-19 10:59:42.065 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 25: Bump transaction 1138 priority from Get to Immediate

2020-05-19 10:59:42.067 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 25: Adding to device queue

2020-05-19 10:59:42.068 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 25: Transaction already in queue - removed original

2020-05-19 10:59:42.070 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 25: Added 1138 to queue - size 4

2020-05-19 10:59:42.071 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

2020-05-19 10:59:42.073 [TRACE] [ng.zwave.internal.protocol.ZWaveNode] - NODE 22: listening == false, frequentlyListening == false, awake == false

2020-05-19 10:59:42.074 [TRACE] [nal.protocol.ZWaveTransactionManager] - NODE 22: Node not awake!

2020-05-19 10:59:42.075 [TRACE] [ng.zwave.internal.protocol.ZWaveNode] - NODE 6: listening == false, frequentlyListening == false, awake == false

2020-05-19 10:59:42.077 [TRACE] [nal.protocol.ZWaveTransactionManager] - NODE 6: Node not awake!

2020-05-19 10:59:42.078 [TRACE] [ng.zwave.internal.protocol.ZWaveNode] - NODE 25: listening == false, frequentlyListening == false, awake == false

2020-05-19 10:59:42.080 [TRACE] [nal.protocol.ZWaveTransactionManager] - NODE 25: Node not awake!

2020-05-19 10:59:42.081 [TRACE] [ng.zwave.internal.protocol.ZWaveNode] - NODE 25: listening == false, frequentlyListening == false, awake == false

2020-05-19 10:59:42.083 [TRACE] [nal.protocol.ZWaveTransactionManager] - NODE 25: Node not awake!

2020-05-19 10:59:42.084 [TRACE] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage nothing

2020-05-19 10:59:42.086 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 25: Bump transaction 1139 priority from Get to Immediate

2020-05-19 10:59:42.087 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 25: Adding to device queue

2020-05-19 10:59:42.089 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 25: Transaction already in queue - removed original

2020-05-19 10:59:42.090 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 25: Added 1139 to queue - size 4

2020-05-19 10:59:42.091 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

2020-05-19 10:59:42.093 [TRACE] [ng.zwave.internal.protocol.ZWaveNode] - NODE 22: listening == false, frequentlyListening == false, awake == false

2020-05-19 10:59:42.094 [TRACE] [nal.protocol.ZWaveTransactionManager] - NODE 22: Node not awake!

2020-05-19 10:59:42.096 [TRACE] [ng.zwave.internal.protocol.ZWaveNode] - NODE 6: listening == false, frequentlyListening == false, awake == false

2020-05-19 10:59:42.097 [TRACE] [nal.protocol.ZWaveTransactionManager] - NODE 6: Node not awake!

2020-05-19 10:59:42.098 [TRACE] [ng.zwave.internal.protocol.ZWaveNode] - NODE 25: listening == false, frequentlyListening == false, awake == false

2020-05-19 10:59:42.100 [TRACE] [nal.protocol.ZWaveTransactionManager] - NODE 25: Node not awake!

2020-05-19 10:59:42.101 [TRACE] [ng.zwave.internal.protocol.ZWaveNode] - NODE 25: listening == false, frequentlyListening == false, awake == false

2020-05-19 10:59:42.103 [TRACE] [nal.protocol.ZWaveTransactionManager] - NODE 25: Node not awake!

2020-05-19 10:59:42.104 [TRACE] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage nothing

==> /var/log/openhab2/events.log <==

2020-05-19 10:59:47.282 [vent.ItemStateChangedEvent] - LocalTime_Date changed from 2020-05-19T10:58:47.263-0500 to 2020-05-19T10:59:47.266-0500

==> /var/log/openhab2/openhab.log <==

2020-05-19 10:59:49.102 [TRACE] [WaveSerialHandler$ZWaveReceiveThread] - Received SOF

2020-05-19 10:59:49.107 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 19 03 20 01 FF 36 

2020-05-19 10:59:49.110 [TRACE] [wave.internal.protocol.SerialMessage] - NODE 255: Creating new SerialMessage from buffer = 01 09 00 04 00 19 03 20 01 FF 36 

2020-05-19 10:59:49.112 [TRACE] [wave.internal.protocol.SerialMessage] - Calculated checksum = 54

2020-05-19 10:59:49.114 [TRACE] [wave.internal.protocol.SerialMessage] - NODE 255: Checksum matched

2020-05-19 10:59:49.117 [TRACE] [wave.internal.protocol.SerialMessage] - NODE 25: Message payload = 00 19 03 20 01 FF 

2020-05-19 10:59:49.120 [TRACE] [WaveSerialHandler$ZWaveReceiveThread] - Message is valid, sending ACK

2020-05-19 10:59:49.123 [TRACE] [WaveSerialHandler$ZWaveReceiveThread] - Response SENT 6

2020-05-19 10:59:49.126 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=25, callback=0, payload=00 19 03 20 01 FF 

2020-05-19 10:59:49.130 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=25, callback=0, payload=00 19 03 20 01 FF 

2020-05-19 10:59:49.132 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null

2020-05-19 10:59:49.135 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 25: Application Command Request (ALIVE:DONE)

2020-05-19 10:59:49.137 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 25: resetResendCount initComplete=true isDead=false

2020-05-19 10:59:49.140 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 25: Incoming command class COMMAND_CLASS_BASIC, endpoint 0

2020-05-19 10:59:49.142 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 25: SECURITY not supported

2020-05-19 10:59:49.144 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 25: Received COMMAND_CLASS_BASIC V1 BASIC_SET

2020-05-19 10:59:49.146 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 25: Basic report, value = 255

2020-05-19 10:59:49.148 [TRACE] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveCommandClassValueEvent

2020-05-19 10:59:49.150 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 25: Got an event from Z-Wave network: ZWaveCommandClassValueEvent

2020-05-19 10:59:49.153 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 25: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_BASIC, value=255

2020-05-19 10:59:49.155 [TRACE] [ding.zwave.handler.ZWaveThingHandler] - NODE 25: Checking channel=zwave:device:c9fd3b4d:node25:sensor_binary, cmdClass=COMMAND_CLASS_SENSOR_BINARY, endpoint=0

2020-05-19 10:59:49.158 [TRACE] [ding.zwave.handler.ZWaveThingHandler] - NODE 25: Checking channel=zwave:device:c9fd3b4d:node25:sensor_binary, cmdClass=COMMAND_CLASS_BASIC, endpoint=0

2020-05-19 10:59:49.160 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 25: Updating channel state zwave:device:c9fd3b4d:node25:sensor_binary to ON [OnOffType]

2020-05-19 10:59:49.166 [TRACE] [ding.zwave.handler.ZWaveThingHandler] - NODE 25: Checking channel=zwave:device:c9fd3b4d:node25:alarm_access, cmdClass=COMMAND_CLASS_ALARM, endpoint=0

2020-05-19 10:59:49.171 [TRACE] [ding.zwave.handler.ZWaveThingHandler] - NODE 25: Checking channel=zwave:device:c9fd3b4d:node25:battery-level, cmdClass=COMMAND_CLASS_BATTERY, endpoint=0

2020-05-19 10:59:49.173 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 25: Commands processed 1.

==> /var/log/openhab2/events.log <==

2020-05-19 10:59:49.177 [vent.ItemStateChangedEvent] - Front_Door changed from NULL to ON

==> /var/log/openhab2/openhab.log <==

2020-05-19 10:59:49.175 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 25: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1fed446.

2020-05-19 10:59:49.181 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0

2020-05-19 10:59:49.183 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0

2020-05-19 10:59:49.185 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2020-05-19 10:59:49.188 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

2020-05-19 10:59:49.191 [TRACE] [ng.zwave.internal.protocol.ZWaveNode] - NODE 22: listening == false, frequentlyListening == false, awake == false

2020-05-19 10:59:49.193 [TRACE] [nal.protocol.ZWaveTransactionManager] - NODE 22: Node not awake!

2020-05-19 10:59:49.195 [TRACE] [ng.zwave.internal.protocol.ZWaveNode] - NODE 6: listening == false, frequentlyListening == false, awake == false

2020-05-19 10:59:49.198 [TRACE] [nal.protocol.ZWaveTransactionManager] - NODE 6: Node not awake!

2020-05-19 10:59:49.201 [TRACE] [ng.zwave.internal.protocol.ZWaveNode] - NODE 25: listening == false, frequentlyListening == false, awake == false

2020-05-19 10:59:49.203 [TRACE] [nal.protocol.ZWaveTransactionManager] - NODE 25: Node not awake!

2020-05-19 10:59:49.206 [TRACE] [ng.zwave.internal.protocol.ZWaveNode] - NODE 25: listening == false, frequentlyListening == false, awake == false

2020-05-19 10:59:49.216 [TRACE] [nal.protocol.ZWaveTransactionManager] - NODE 25: Node not awake!

2020-05-19 10:59:49.218 [TRACE] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage nothing

2020-05-19 10:59:49.544 [TRACE] [WaveSerialHandler$ZWaveReceiveThread] - Received SOF

2020-05-19 10:59:49.547 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 19 03 20 01 FF 36 

2020-05-19 10:59:49.559 [TRACE] [wave.internal.protocol.SerialMessage] - NODE 255: Creating new SerialMessage from buffer = 01 09 00 04 00 19 03 20 01 FF 36 

2020-05-19 10:59:49.560 [TRACE] [wave.internal.protocol.SerialMessage] - Calculated checksum = 54

2020-05-19 10:59:49.562 [TRACE] [wave.internal.protocol.SerialMessage] - NODE 255: Checksum matched

2020-05-19 10:59:49.564 [TRACE] [wave.internal.protocol.SerialMessage] - NODE 25: Message payload = 00 19 03 20 01 FF 

2020-05-19 10:59:49.565 [TRACE] [WaveSerialHandler$ZWaveReceiveThread] - Message is valid, sending ACK

2020-05-19 10:59:49.567 [TRACE] [WaveSerialHandler$ZWaveReceiveThread] - Response SENT 6

2020-05-19 10:59:49.569 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=25, callback=0, payload=00 19 03 20 01 FF 

2020-05-19 10:59:49.571 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=25, callback=0, payload=00 19 03 20 01 FF 

2020-05-19 10:59:49.572 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null

2020-05-19 10:59:49.574 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 25: Application Command Request (ALIVE:DONE)

2020-05-19 10:59:49.575 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 25: resetResendCount initComplete=true isDead=false

2020-05-19 10:59:49.576 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 25: Incoming command class COMMAND_CLASS_BASIC, endpoint 0

2020-05-19 10:59:49.577 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 25: SECURITY not supported

2020-05-19 10:59:49.579 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 25: Received COMMAND_CLASS_BASIC V1 BASIC_SET

2020-05-19 10:59:49.580 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 25: Basic report, value = 255

2020-05-19 10:59:49.581 [TRACE] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveCommandClassValueEvent

2020-05-19 10:59:49.583 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 25: Got an event from Z-Wave network: ZWaveCommandClassValueEvent

2020-05-19 10:59:49.584 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 25: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_BASIC, value=255

2020-05-19 10:59:49.586 [TRACE] [ding.zwave.handler.ZWaveThingHandler] - NODE 25: Checking channel=zwave:device:c9fd3b4d:node25:sensor_binary, cmdClass=COMMAND_CLASS_SENSOR_BINARY, endpoint=0

2020-05-19 10:59:49.587 [TRACE] [ding.zwave.handler.ZWaveThingHandler] - NODE 25: Checking channel=zwave:device:c9fd3b4d:node25:sensor_binary, cmdClass=COMMAND_CLASS_BASIC, endpoint=0

2020-05-19 10:59:49.589 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 25: Updating channel state zwave:device:c9fd3b4d:node25:sensor_binary to ON [OnOffType]

2020-05-19 10:59:49.591 [TRACE] [ding.zwave.handler.ZWaveThingHandler] - NODE 25: Checking channel=zwave:device:c9fd3b4d:node25:alarm_access, cmdClass=COMMAND_CLASS_ALARM, endpoint=0

2020-05-19 10:59:49.593 [TRACE] [ding.zwave.handler.ZWaveThingHandler] - NODE 25: Checking channel=zwave:device:c9fd3b4d:node25:battery-level, cmdClass=COMMAND_CLASS_BATTERY, endpoint=0

2020-05-19 10:59:49.595 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 25: Commands processed 1.

2020-05-19 10:59:49.597 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 25: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@4e9ac5.

2020-05-19 10:59:49.599 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0

2020-05-19 10:59:49.600 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0

2020-05-19 10:59:49.601 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2020-05-19 10:59:49.603 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

2020-05-19 10:59:49.605 [TRACE] [ng.zwave.internal.protocol.ZWaveNode] - NODE 22: listening == false, frequentlyListening == false, awake == false

2020-05-19 10:59:49.606 [TRACE] [nal.protocol.ZWaveTransactionManager] - NODE 22: Node not awake!

2020-05-19 10:59:49.608 [TRACE] [ng.zwave.internal.protocol.ZWaveNode] - NODE 6: listening == false, frequentlyListening == false, awake == false

2020-05-19 10:59:49.610 [TRACE] [nal.protocol.ZWaveTransactionManager] - NODE 6: Node not awake!

2020-05-19 10:59:49.611 [TRACE] [ng.zwave.internal.protocol.ZWaveNode] - NODE 25: listening == false, frequentlyListening == false, awake == false

2020-05-19 10:59:49.613 [TRACE] [nal.protocol.ZWaveTransactionManager] - NODE 25: Node not awake!

2020-05-19 10:59:49.614 [TRACE] [ng.zwave.internal.protocol.ZWaveNode] - NODE 25: listening == false, frequentlyListening == false, awake == false

2020-05-19 10:59:49.616 [TRACE] [nal.protocol.ZWaveTransactionManager] - NODE 25: Node not awake!

2020-05-19 10:59:49.618 [TRACE] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage nothing