18:44:29.404 [WARN ] [ome.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.eclipse.smarthome.io.monitor.internal.EventLogger@1a05d4a' takes more than 5000ms.
18:46:19.784 [INFO ] [del.core.internal.ModelRepositoryImpl] - Refreshing model 'EventBased.rules'
18:46:27.527 [INFO ] [del.core.internal.ModelRepositoryImpl] - Refreshing model 'EventBased.rules'
18:48:09.190 [INFO ] [del.core.internal.ModelRepositoryImpl] - Refreshing model 'EventBased.rules'
18:48:24.053 [WARN ] [ome.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.eclipse.smarthome.io.monitor.internal.EventLogger@1a05d4a' takes more than 5000ms.
18:48:19.049 [INFO ] [smarthome.event.ThingUpdatedEvent    ] - Thing 'zwave:device:70e495c5:node6' has been updated.
18:49:24.398 [INFO ] [smarthome.event.ItemStateChangedEvent] - SunElevation changed from -24.1683879125769 to -24.874141735276755
18:49:29.399 [WARN ] [ome.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.eclipse.smarthome.io.monitor.internal.EventLogger@1a05d4a' takes more than 5000ms.
18:51:28.858 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 08 00 04 00 06 02 84 07 74
18:51:28.859 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=6, callback=0, payload=00 06 02 84 07
18:51:28.860 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=6, callback=0, payload=00 06 02 84 07
18:51:28.861 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null
18:51:28.861 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 6: Application Command Request (ALIVE:DONE)
18:51:28.862 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 6: resetResendCount initComplete=true isDead=false
18:51:28.862 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 6: Is awake with 0 messages in the queue
18:51:28.863 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 6: Start sleep timer at 1000ms
18:51:28.863 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 6: Got an event from Z-Wave network: ZWaveNodeStatusEvent
18:51:28.867 [DEBUG] [ave.internal.protocol.ZWaveController] - NODE 6: Node Status event - Node is AWAKE
18:51:28.867 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 6: Commands processed 1.
18:51:28.868 [INFO ] [smarthome.event.ThingUpdatedEvent    ] - Thing 'zwave:device:70e495c5:node6' has been updated.
18:51:28.873 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 6: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@6db6ce.
18:51:28.878 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
18:51:28.879 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
18:51:28.880 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
18:51:28.885 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
18:51:29.364 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 6: WakeupTimerTask 0 Messages waiting, state DONE
18:51:29.864 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 6: WakeupTimerTask 0 Messages waiting, state DONE
18:51:29.865 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 6: No more messages, go back to sleep
18:51:29.869 [DEBUG] [.commandclass.ZWaveWakeUpCommandClass] - NODE 6: Creating new message for application command WAKE_UP_NO_MORE_INFORMATION
18:51:29.870 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 6: SECURITY NOT required on COMMAND_CLASS_WAKE_UP
18:51:29.871 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 6: Command Class COMMAND_CLASS_WAKE_UP is NOT required to be secured
18:51:29.873 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 6: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@ffc3bd
18:51:29.875 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 6: Bump transaction 66 priority from Immediate to Immediate
18:51:29.879 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 6: Adding to device queue
18:51:29.879 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 6: Added 66 to queue - size 1
18:51:29.881 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
18:51:29.883 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 06 02 84 08 25 31 79
18:51:29.883 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 6: Sending REQUEST Message = 01 09 00 13 06 02 84 08 25 31 79
18:51:29.884 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT
18:51:29.885 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 66: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 49
18:51:29.885 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
18:51:29.891 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
18:51:29.899 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
18:51:29.900 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
18:51:29.901 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 66: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 49
18:51:29.902 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
18:51:29.903 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg: ACK
18:51:29.905 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
18:51:29.908 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 31 00 00 02 D8
18:51:29.912 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 66: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 49
18:51:29.913 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=49, payload=31 00 00 02
18:51:29.914 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
18:51:29.916 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 66: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 49
18:51:29.918 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
18:51:29.924 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 6: sentData successfully placed on stack.
18:51:29.928 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 66: Advanced to WAIT_REQUEST
18:51:29.929 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 6: TID 66: Transaction not completed
18:51:29.930 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=49, payload=31 00 00 02
18:51:29.931 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 66: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 49
18:51:29.937 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
18:51:29.939 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 66: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 49
18:51:29.940 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking TID 66: (Callback 49)
18:51:29.941 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Callback match!
18:51:29.942 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Correlated to TID 66: callback 49
18:51:29.943 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=49, payload=31 00 00 02
18:51:29.945 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 6: SendData Request. CallBack ID = 49, Status = Transmission complete and ACK received(0)
18:51:29.951 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 6: resetResendCount initComplete=true isDead=false
18:51:29.953 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 66: Transaction COMPLETED
18:51:29.953 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 6: Response processed after 68ms
18:51:29.958 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 6: TID 66: Transaction completed
18:51:29.962 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 6: notifyTransactionResponse TID:66 DONE
18:51:29.964 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 6: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
18:51:29.965 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
18:51:29.966 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
18:51:29.968 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 66: Transaction event listener: DONE: DONE ->
18:51:29.969 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 6: Went to sleep COMPLETE