Anyone who can help me remotley? SUperslow Zwave network, cant figure out

I have had a slow zwave network since summer, whatever I do i cant seem to find the problem. 40x zwavenodes all mains connected. I am at a peril, expensive system with upto 10 sec delay controlling lights. I WILL PAY the one wo can pinpoint the problem

Are you aware of any “ghost nodes”? E.g. nodes that could not be excluded from the net for any reason and are still listed as things?
Is network healing active in your setup?
Was there any change in your network around/before the time you became aware of the slow network? Placing devices somewhere else counts as change in this scenario.

No ghost nodes only one battery node.

9:48:57.413 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01

19:48:57.414 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 2: sentData successfully placed on stack.
19:48:57.414 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 591: Advanced to WAIT_REQUEST
19:48:57.414 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 2: TID 591: Transaction not completed
19:48:57.414 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
19:48:57.414 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
19:48:58.080 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 11 00 04 00 02 05 26 03 28 28 00 C6 00 03 04 1E 25 32
19:48:58.086 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=2, callback=0, payload=00 02 05 26 03 28 28 00 C6 00 03 04 1E 25
19:48:58.086 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=2, callback=0, payload=00 02 05 26 03 28 28 00 C6 00 03 04 1E 25
19:48:58.086 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 591: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 113
19:48:58.086 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 2: Application Command Request (ALIVE:DONE)
19:48:58.086 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 2: resetResendCount initComplete=true isDead=false
19:48:58.087 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 2: Incoming command class COMMAND_CLASS_SWITCH_MULTILEVEL, endpoint 0
19:48:58.087 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 2: SECURITY NOT required on COMMAND_CLASS_SWITCH_MULTILEVEL
19:48:58.087 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 2: Received COMMAND_CLASS_SWITCH_MULTILEVEL V3 SWITCH_MULTILEVEL_REPORT
19:48:58.087 [DEBUG] [ass.ZWaveMultiLevelSwitchCommandClass] - NODE 2: Switch Multi Level report, value = 40
19:48:58.087 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
19:48:58.087 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 2: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SWITCH_MULTILEVEL, value=40
19:48:58.087 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 2: Updating channel state zwave:device:703e901135:node2:switch_dimmer to 40 [PercentType]
19:48:58.088 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 2: Commands processed 1.
19:48:58.088 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 2: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@44606a9a.
19:48:58.088 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
19:48:58.088 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
19:48:58.088 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
19:48:58.088 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
19:48:58.117 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 11 00 04 00 02 05 26 03 28 28 00 C6 00 03 04 1E 25 32
19:48:58.117 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=2, callback=0, payload=00 02 05 26 03 28 28 00 C6 00 03 04 1E 25
19:48:58.117 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=2, callback=0, payload=00 02 05 26 03 28 28 00 C6 00 03 04 1E 25
19:48:58.117 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 591: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 113
19:48:58.118 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 2: Application Command Request (ALIVE:DONE)
19:48:58.118 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 2: resetResendCount initComplete=true isDead=false
19:48:58.118 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 2: Incoming command class COMMAND_CLASS_SWITCH_MULTILEVEL, endpoint 0
19:48:58.118 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 2: SECURITY NOT required on COMMAND_CLASS_SWITCH_MULTILEVEL
19:48:58.118 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 2: Received COMMAND_CLASS_SWITCH_MULTILEVEL V3 SWITCH_MULTILEVEL_REPORT
19:48:58.118 [DEBUG] [ass.ZWaveMultiLevelSwitchCommandClass] - NODE 2: Switch Multi Level report, value = 40
19:48:58.118 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
19:48:58.119 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 2: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SWITCH_MULTILEVEL, value=40
19:48:58.119 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 2: Updating channel state zwave:device:703e901135:node2:switch_dimmer to 40 [PercentType]
19:48:58.119 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 2: Commands processed 1.
19:48:58.119 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 2: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@707e5e67.
19:48:58.119 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
19:48:58.119 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
19:48:58.119 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
19:48:58.120 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
19:48:58.167 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 11 00 04 00 02 05 26 03 28 28 00 C7 00 03 04 1E 25 33
19:48:58.168 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=2, callback=0, payload=00 02 05 26 03 28 28 00 C7 00 03 04 1E 25
19:48:58.168 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=2, callback=0, payload=00 02 05 26 03 28 28 00 C7 00 03 04 1E 25
19:48:58.169 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 591: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 113
19:48:58.169 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 2: Application Command Request (ALIVE:DONE)
19:48:58.169 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 2: resetResendCount initComplete=true isDead=false
19:48:58.169 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 2: Incoming command class COMMAND_CLASS_SWITCH_MULTILEVEL, endpoint 0
19:48:58.169 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 2: SECURITY NOT required on COMMAND_CLASS_SWITCH_MULTILEVEL
19:48:58.170 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 2: Received COMMAND_CLASS_SWITCH_MULTILEVEL V3 SWITCH_MULTILEVEL_REPORT
19:48:58.170 [DEBUG] [ass.ZWaveMultiLevelSwitchCommandClass] - NODE 2: Switch Multi Level report, value = 40
19:48:58.170 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
19:48:58.170 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 2: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SWITCH_MULTILEVEL, value=40
19:48:58.170 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 2: Updating channel state zwave:device:703e901135:node2:switch_dimmer to 40 [PercentType]
19:48:58.171 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 2: Commands processed 1.
19:48:58.171 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 2: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@7664c96f.
19:48:58.171 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
19:48:58.171 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
19:48:58.171 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
19:48:58.171 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
19:48:58.306 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 16 00 04 00 10 0A 60 0D 03 01 31 05 01 22 00 00 C6 00 03 09 13 25 75
19:48:58.312 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=16, callback=0, payload=00 10 0A 60 0D 03 01 31 05 01 22 00 00 C6 00 03 09 13 25
19:48:58.313 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=16, callback=0, payload=00 10 0A 60 0D 03 01 31 05 01 22 00 00 C6 00 03 09 13 25
19:48:58.313 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 591: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 113
19:48:58.313 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 16: Application Command Request (ALIVE:DONE)
19:48:58.313 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 16: resetResendCount initComplete=true isDead=false
19:48:58.313 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 16: Decapsulating COMMAND_CLASS_MULTI_CHANNEL
19:48:58.313 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 16: Incoming command class COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 3
19:48:58.314 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 16: SECURITY NOT required on COMMAND_CLASS_SENSOR_MULTILEVEL
19:48:58.314 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 16: Received COMMAND_CLASS_SENSOR_MULTILEVEL V0 SENSOR_MULTILEVEL_REPORT
19:48:58.314 [DEBUG] [ass.ZWaveMultiLevelSensorCommandClass] - NODE 16: Sensor Type = Temperature(1), Scale = 0
19:48:58.314 [DEBUG] [ass.ZWaveMultiLevelSensorCommandClass] - NODE 16: Sensor Value = 0E+1
19:48:58.314 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 16: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent
19:48:58.314 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 16: Got a value event from Z-Wave network, endpoint=3, command class=COMMAND_CLASS_SENSOR_MULTILEVEL, value=0E+1
19:48:58.314 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 16: Updating channel state zwave:device:703e901135:node16:sensor_temperature3 to 0E+1 °C [QuantityType]
19:48:58.315 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 16: Commands processed 1.
19:48:58.315 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 16: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@3c89e095.
19:48:58.315 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
19:48:58.315 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
19:48:58.316 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
19:48:58.316 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
19:48:58.494 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 1D 00 04 00 11 12 60 0D 01 01 32 02 21 24 00 00 11 23 00 00 00 00 00 00 C7 00 02 0D 25 62
19:48:58.502 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=17, callback=0, payload=00 11 12 60 0D 01 01 32 02 21 24 00 00 11 23 00 00 00 00 00 00 C7 00 02 0D 25
19:48:58.502 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=17, callback=0, payload=00 11 12 60 0D 01 01 32 02 21 24 00 00 11 23 00 00 00 00 00 00 C7 00 02 0D 25
19:48:58.502 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 591: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 113
19:48:58.502 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 17: Application Command Request (ALIVE:DONE)
19:48:58.503 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 17: resetResendCount initComplete=true isDead=false
19:48:58.503 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 17: Decapsulating COMMAND_CLASS_MULTI_CHANNEL
19:48:58.503 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 17: Incoming command class COMMAND_CLASS_METER, endpoint 1
19:48:58.503 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 17: SECURITY NOT required on COMMAND_CLASS_METER
19:48:58.503 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 17: Received COMMAND_CLASS_METER V3 METER_REPORT
19:48:58.503 [DEBUG] [l.commandclass.ZWaveMeterCommandClass] - NODE 17: Meter: Type=Electric(1), Scale=kWh(0), Value=438.7
19:48:58.504 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 17: Got an event from Z-Wave network: ZWaveMeterValueEvent
19:48:58.504 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 17: Got a value event from Z-Wave network, endpoint=1, command class=COMMAND_CLASS_METER, value=438.7
19:48:58.504 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 17: Updating channel state zwave:device:703e901135:node17:meter_kwh1 to 438.7 [DecimalType]
19:48:58.504 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 17: Commands processed 1.
19:48:58.504 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 17: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@7b7a6924.
19:48:58.504 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
19:48:58.505 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
19:48:58.505 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
19:48:58.505 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
19:48:58.780 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 1D 00 04 00 11 12 60 0D 01 01 32 02 21 24 00 00 11 23 00 00 00 00 00 00 C7 00 02 0D 25 62
19:48:58.786 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=17, callback=0, payload=00 11 12 60 0D 01 01 32 02 21 24 00 00 11 23 00 00 00 00 00 00 C7 00 02 0D 25
19:48:58.786 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=17, callback=0, payload=00 11 12 60 0D 01 01 32 02 21 24 00 00 11 23 00 00 00 00 00 00 C7 00 02 0D 25
19:48:58.787 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 591: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 113
19:48:58.787 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 17: Application Command Request (ALIVE:DONE)
19:48:58.787 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 17: resetResendCount initComplete=true isDead=false
19:48:58.787 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 17: Decapsulating COMMAND_CLASS_MULTI_CHANNEL
19:48:58.787 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 17: Incoming command class COMMAND_CLASS_METER, endpoint 1
19:48:58.787 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 17: SECURITY NOT required on COMMAND_CLASS_METER
19:48:58.787 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 17: Received COMMAND_CLASS_METER V3 METER_REPORT
19:48:58.787 [DEBUG] [l.commandclass.ZWaveMeterCommandClass] - NODE 17: Meter: Type=Electric(1), Scale=kWh(0), Value=438.7
19:48:58.788 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 17: Got an event from Z-Wave network: ZWaveMeterValueEvent
19:48:58.788 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 17: Got a value event from Z-Wave network, endpoint=1, command class=COMMAND_CLASS_METER, value=438.7
19:48:58.788 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 17: Updating channel state zwave:device:703e901135:node17:meter_kwh1 to 438.7 [DecimalType]
19:48:58.788 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 17: Commands processed 1.
19:48:58.788 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 17: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@79f59d4a.
19:48:58.788 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
19:48:58.788 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
19:48:58.788 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
19:48:58.789 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
19:48:59.780 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0F 00 04 00 02 05 26 03 28 28 00 C6 00 01 25 34
19:48:59.786 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=2, callback=0, payload=00 02 05 26 03 28 28 00 C6 00 01 25
19:48:59.786 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=2, callback=0, payload=00 02 05 26 03 28 28 00 C6 00 01 25
19:48:59.786 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 591: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 113
19:48:59.787 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 2: Application Command Request (ALIVE:DONE)
19:48:59.787 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 2: resetResendCount initComplete=true isDead=false
19:48:59.787 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 2: Incoming command class COMMAND_CLASS_SWITCH_MULTILEVEL, endpoint 0
19:48:59.787 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 2: SECURITY NOT required on COMMAND_CLASS_SWITCH_MULTILEVEL
19:48:59.787 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 2: Received COMMAND_CLASS_SWITCH_MULTILEVEL V3 SWITCH_MULTILEVEL_REPORT
19:48:59.787 [DEBUG] [ass.ZWaveMultiLevelSwitchCommandClass] - NODE 2: Switch Multi Level report, value = 40
19:48:59.787 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
19:48:59.788 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 2: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SWITCH_MULTILEVEL, value=40
19:48:59.788 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 2: Updating channel state zwave:device:703e901135:node2:switch_dimmer to 40 [PercentType]
19:48:59.788 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 2: Commands processed 1.
19:48:59.788 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 2: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@298a1caa.
19:48:59.788 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
19:48:59.788 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
19:48:59.788 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
19:48:59.788 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
19:48:59.886 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 16 00 04 00 10 0A 60 0D 03 01 31 05 01 22 00 00 C7 00 03 20 21 25 6F
19:48:59.893 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=16, callback=0, payload=00 10 0A 60 0D 03 01 31 05 01 22 00 00 C7 00 03 20 21 25
19:48:59.894 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=16, callback=0, payload=00 10 0A 60 0D 03 01 31 05 01 22 00 00 C7 00 03 20 21 25
19:48:59.894 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 591: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 113
19:48:59.894 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 16: Application Command Request (ALIVE:DONE)
19:48:59.894 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 16: resetResendCount initComplete=true isDead=false
19:48:59.894 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 16: Decapsulating COMMAND_CLASS_MULTI_CHANNEL
19:48:59.894 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 16: Incoming command class COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 3
19:48:59.894 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 16: SECURITY NOT required on COMMAND_CLASS_SENSOR_MULTILEVEL
19:48:59.895 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 16: Received COMMAND_CLASS_SENSOR_MULTILEVEL V0 SENSOR_MULTILEVEL_REPORT
19:48:59.895 [DEBUG] [ass.ZWaveMultiLevelSensorCommandClass] - NODE 16: Sensor Type = Temperature(1), Scale = 0
19:48:59.895 [DEBUG] [ass.ZWaveMultiLevelSensorCommandClass] - NODE 16: Sensor Value = 0E+1
19:48:59.895 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 16: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent
19:48:59.895 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 16: Got a value event from Z-Wave network, endpoint=3, command class=COMMAND_CLASS_SENSOR_MULTILEVEL, value=0E+1
19:48:59.895 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 16: Updating channel state zwave:device:703e901135:node16:sensor_temperature3 to 0E+1 °C [QuantityType]
19:48:59.896 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 16: Commands processed 1.
19:48:59.896 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 16: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@5a74cc46.
19:48:59.896 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
19:48:59.896 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
19:48:59.896 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
19:48:59.896 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
19:48:59.928 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 16 00 04 00 10 0A 60 0D 03 01 31 05 01 22 00 00 C6 00 03 20 21 25 6E
19:48:59.932 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=16, callback=0, payload=00 10 0A 60 0D 03 01 31 05 01 22 00 00 C6 00 03 20 21 25
19:48:59.932 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=16, callback=0, payload=00 10 0A 60 0D 03 01 31 05 01 22 00 00 C6 00 03 20 21 25
19:48:59.932 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 591: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 113
19:48:59.932 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 16: Application Command Request (ALIVE:DONE)
19:48:59.932 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 16: resetResendCount initComplete=true isDead=false
19:48:59.932 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 16: Decapsulating COMMAND_CLASS_MULTI_CHANNEL
19:48:59.933 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 16: Incoming command class COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 3
19:48:59.933 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 16: SECURITY NOT required on COMMAND_CLASS_SENSOR_MULTILEVEL
19:48:59.933 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 16: Received COMMAND_CLASS_SENSOR_MULTILEVEL V0 SENSOR_MULTILEVEL_REPORT
19:48:59.933 [DEBUG] [ass.ZWaveMultiLevelSensorCommandClass] - NODE 16: Sensor Type = Temperature(1), Scale = 0
19:48:59.933 [DEBUG] [ass.ZWaveMultiLevelSensorCommandClass] - NODE 16: Sensor Value = 0E+1
19:48:59.933 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 16: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent
19:48:59.933 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 16: Got a value event from Z-Wave network, endpoint=3, command class=COMMAND_CLASS_SENSOR_MULTILEVEL, value=0E+1
19:48:59.934 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 16: Updating channel state zwave:device:703e901135:node16:sensor_temperature3 to 0E+1 °C [QuantityType]
19:48:59.934 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 16: Commands processed 1.
19:48:59.934 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 16: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@726fad65.
19:48:59.934 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
19:48:59.934 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
19:48:59.934 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
19:48:59.934 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
19:49:01.611 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 1C 00 04 00 13 12 60 0D 01 01 32 02 21 24 00 00 02 7B 00 00 00 00 00 00 C4 00 01 25 27
19:49:01.628 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=19, callback=0, payload=00 13 12 60 0D 01 01 32 02 21 24 00 00 02 7B 00 00 00 00 00 00 C4 00 01 25
19:49:01.629 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=19, callback=0, payload=00 13 12 60 0D 01 01 32 02 21 24 00 00 02 7B 00 00 00 00 00 00 C4 00 01 25
19:49:01.630 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 591: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 113
19:49:01.630 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 19: Application Command Request (ALIVE:DONE)
19:49:01.630 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 19: resetResendCount initComplete=true isDead=false
19:49:01.631 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 19: Decapsulating COMMAND_CLASS_MULTI_CHANNEL
19:49:01.631 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 19: Incoming command class COMMAND_CLASS_METER, endpoint 1
19:49:01.631 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 19: SECURITY NOT required on COMMAND_CLASS_METER
19:49:01.632 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 19: Received COMMAND_CLASS_METER V3 METER_REPORT
19:49:01.632 [DEBUG] [l.commandclass.ZWaveMeterCommandClass] - NODE 19: Meter: Type=Electric(1), Scale=kWh(0), Value=63.5
19:49:01.632 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 19: Got an event from Z-Wave network: ZWaveMeterValueEvent
19:49:01.633 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 19: Got a value event from Z-Wave network, endpoint=1, command class=COMMAND_CLASS_METER, value=63.5
19:49:01.633 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 19: Updating channel state zwave:device:703e901135:node19:meter_kwh1 to 63.5 [DecimalType]
19:49:01.634 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 19: Commands processed 1.
19:49:01.634 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 19: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@272561af.
19:49:01.634 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
19:49:01.635 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
19:49:01.635 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
19:49:01.635 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
19:49:02.306 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 1D 00 04 00 11 12 60 0D 01 01 32 02 21 54 00 00 00 19 00 00 00 00 00 00 C9 00 02 0D 25 37
19:49:02.322 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=17, callback=0, payload=00 11 12 60 0D 01 01 32 02 21 54 00 00 00 19 00 00 00 00 00 00 C9 00 02 0D 25
19:49:02.323 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=17, callback=0, payload=00 11 12 60 0D 01 01 32 02 21 54 00 00 00 19 00 00 00 00 00 00 C9 00 02 0D 25
19:49:02.324 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 591: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 113
19:49:02.324 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 17: Application Command Request (ALIVE:DONE)
19:49:02.324 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 17: resetResendCount initComplete=true isDead=false
19:49:02.325 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 17: Decapsulating COMMAND_CLASS_MULTI_CHANNEL
19:49:02.325 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 17: Incoming command class COMMAND_CLASS_METER, endpoint 1
19:49:02.326 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 17: SECURITY NOT required on COMMAND_CLASS_METER
19:49:02.327 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 17: Received COMMAND_CLASS_METER V3 METER_REPORT
19:49:02.327 [DEBUG] [l.commandclass.ZWaveMeterCommandClass] - NODE 17: Meter: Type=Electric(1), Scale=W(2), Value=0.25
19:49:02.328 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 17: Got an event from Z-Wave network: ZWaveMeterValueEvent
19:49:02.329 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 17: Got a value event from Z-Wave network, endpoint=1, command class=COMMAND_CLASS_METER, value=0.25
19:49:02.329 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 17: Updating channel state zwave:device:703e901135:node17:meter_watts1 to 0.25 [DecimalType]
19:49:02.330 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 17: Commands processed 1.
19:49:02.330 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 17: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@5eb356ad.
19:49:02.331 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
19:49:02.331 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
19:49:02.331 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
19:49:02.332 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
19:49:02.422 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - NODE 2: TID 591: Timeout at state WAIT_REQUEST. 3 retries remaining.
19:49:02.428 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - Aborting Transaction!
19:49:02.428 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 591: Transaction ABORTED
19:49:02.429 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 03 00 16 EA
19:49:02.429 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 03 00 16 EA
19:49:02.429 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT
19:49:02.429 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
19:49:02.430 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
19:49:02.430 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
19:49:02.430 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
19:49:02.430 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 591: [ABORTED] priority=Get, requiresResponse=true, callback: 113
19:49:02.430 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg: ACK
19:49:02.430 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
19:49:02.430 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
19:49:02.487 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 1D 00 04 00 0E 12 60 0D 01 01 32 02 21 24 00 00 11 A1 00 00 00 00 00 00 C6 00 02 0D 25 FE
19:49:02.499 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=14, callback=0, payload=00 0E 12 60 0D 01 01 32 02 21 24 00 00 11 A1 00 00 00 00 00 00 C6 00 02 0D 25
19:49:02.500 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=14, callback=0, payload=00 0E 12 60 0D 01 01 32 02 21 24 00 00 11 A1 00 00 00 00 00 00 C6 00 02 0D 25
19:49:02.500 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 591: [ABORTED] priority=Get, requiresResponse=true, callback: 113
19:49:02.501 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 14: Application Command Request (ALIVE:DYNAMIC_VALUES)
19:49:02.502 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 14: Decapsulating COMMAND_CLASS_MULTI_CHANNEL
19:49:02.502 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 14: Incoming command class COMMAND_CLASS_METER, endpoint 1
19:49:02.502 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 14: SECURITY NOT required on COMMAND_CLASS_METER
19:49:02.503 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 14: Received COMMAND_CLASS_METER V3 METER_REPORT
19:49:02.503 [DEBUG] [l.commandclass.ZWaveMeterCommandClass] - NODE 14: Meter: Type=Electric(1), Scale=kWh(0), Value=451.3
19:49:02.504 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 14: Got an event from Z-Wave network: ZWaveMeterValueEvent
19:49:02.504 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 14: Got a value event from Z-Wave network, endpoint=1, command class=COMMAND_CLASS_METER, value=451.3
19:49:02.505 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 14: Updating channel state zwave:device:703e901135:node14:meter_kwh1 to 451.3 [DecimalType]
19:49:02.505 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 14: Commands processed 1.
19:49:02.505 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 14: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@22d9b7c1.
19:49:02.506 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
19:49:02.506 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
19:49:02.506 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
19:49:02.506 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
19:49:02.810 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 19 00 04 00 1E 0E 32 02 21 44 00 00 0A E5 00 00 00 00 00 00 C6 00 02 0D 25 A4
19:49:02.819 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=30, callback=0, payload=00 1E 0E 32 02 21 44 00 00 0A E5 00 00 00 00 00 00 C6 00 02 0D 25
19:49:02.819 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=30, callback=0, payload=00 1E 0E 32 02 21 44 00 00 0A E5 00 00 00 00 00 00 C6 00 02 0D 25
19:49:02.820 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 591: [ABORTED] priority=Get, requiresResponse=true, callback: 113
19:49:02.820 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 30: Application Command Request (ALIVE:PING)
19:49:02.820 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 30: Incoming command class COMMAND_CLASS_METER, endpoint 0
19:49:02.821 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 30: SECURITY not supported
19:49:02.821 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 30: Received COMMAND_CLASS_METER V0 METER_REPORT
19:49:02.821 [DEBUG] [l.commandclass.ZWaveMeterCommandClass] - NODE 30: Meter: Type=Electric(1), Scale=kWh(0), Value=27.89
19:49:02.821 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 30: Got an event from Z-Wave network: ZWaveMeterValueEvent
19:49:02.821 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 30: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_METER, value=27.89
19:49:02.822 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 30: Commands processed 1.
19:49:02.822 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 30: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@2d52327a.
19:49:02.822 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
19:49:02.822 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
19:49:02.822 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
19:49:02.823 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

The log is a DEBUG log and it seems to be unfiltered. That is good. It is a bit of a hassle to get it into the ZWave log viewer in the way it was posted here (consider attaching a file instead) but I managed it.
I am by no means an expert in examining these logs, but what I see is this:

  • The log only covers app. 4 seconds of time
  • In these 4 seconds 5 different nodes report data.

As you have 40 nodes in your setup I assume that a LOT of traffic is happening in your ZWave net. You may be suffering from traffic congestion.
Maybe you could reduce traffic by adjusting the report periods of your devices or even stop reports being made that you do not need and see if that helps.

19:49:02.422 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - NODE 2: TID 591: Timeout at state WAIT_REQUEST. 3 retries remaining.
19:49:02.428 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - Aborting Transaction!
19:49:02.428 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 591: Transaction ABORTED

Here’s a clue. What is node 2 and why is there a timeout?

As @stefan.oh points out, a debug log that spans a considerably longer time may help provide additional insight. But given the few seconds of log that you’ve provided, you can start with diagnosing what’s going on with node 2.

@stefan.oh

Sorry did more logging
Uploaded log file here and checked the log, is it me or are my devices blabbing about w/kw in decimals ALL the time?
HEatit trm3 thermostat settings.pdf (177.5 KB) Heatit Zdim settings.pdf (135.2 KB)

LOGFILE

First of all, there is nothing you need to be sorry for. We are all learning, that’s how we can help each other in the end. And it is what the community is for.

Now taking a look at the log, this is interesting…
The log covers nearly an hour of time. Splendid!

I’m a bit surprised of the results that I see looking at the various results. E.g. under “Nodes” I see only 3 nodes (#2, #3, #5) that are listed with “Messages Sent” > 0. In consequence these are the only nodes listed with “Messages Timed Out”.

But there are clearly entries in the log for other nodes as well, having sent data. E.g. #35:

2020-11-30 11:51:12.094 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 35: Sensor Value = 105.7

Do I misread something or is the logviewer buggy?

Filtering for node #17 I see the same reports for 6 channels repeated every 60 seconds.
The 60 seconds would match the setting of parameter 9 in your “Heatit Zdim settings.pdf” if the unit is seconds for that parameter.

Node #4 seems to adhere to follow the 60 seconds interval as well, although it is only one channel being reported here.

Node #23 follows a 120 seconds interval. In this case it is a bit confusing as there are several messages for the same channel within a short period of time at the beginning but it slows down to every 2 minutes.

Node #2, listed with timeouts, reports every 60 seconds.

Everything basically normal, so far. Whether the reports are needed so frequently is a question you need to answer for yourself. The settings shown in the PDFs do not show options to have different report intervals for different channels, so I assume you set them as a compromise between the need for current data and sent amount of data.

Filtering for node #35 I see a lot of entries in “Log View”: within only 200ms three reports are listed for the channel “sensor_power” at 10:59:50. I do not see a clear time pattern but a VERY high rate of reports for the same channel. I do not think that is normal. Maybe this is the reason that other nodes cannot deliver their data to the controller?

I did not check all nodes. But I would suggest to test by taking #35 offline by shutting it down and see how that affects the network. And look for other nodes with a similar behaviour. Maybe you can isolate misbehaving nodes that way and afterwards we can see why they are misbehaving while the rest works normally again.

30+ nodes sending multiple status updates every minute or less results in a lot of traffic on your network and is no doubt causing congestion IMO. A quick look reveals a rate of more than one status update per second (on average approximately 1.4 updates per second). Why are these nodes set to report so frequently? Do you really need all this data?

No i dont need all this data, but after reading uup on a norwegian smarthouse site. Other people are struggeling with the same thing. Heatit seems needing to get their shit together FW wise. I have sent a quiery to them. I will test further with node 35 and so on. Will come back. Thank you gus for the answers so far.

I’m not familiar with the devices you’re using. Isn’t the reporting interval configurable?

FGWP102 Metered Wall Plug Switch was node 35. It vas set to redicoulus low thresholds for reporting values. Changed thet to someting more sane.
The Heatit units are reported to fall back on settings after beeing set (not holding configuration. I will do a torough check tomorrow.

I’m not an expert but had some problems with slow zwave last summer. As noted above that answer is too much traffic.
Some comments

  1. You have not ID’ed your hardware and configuration, but I found with a RPI4 with a USB3 attached SSD drive (with the root) I had inference with the Zwave traffic. I ultimately put the Zwave on a dedicated Rpi3 with the traditional SD card (and Zram) and my other major OH binding (IP camera) on a RPi4 with the SSD to capture video. I followed the tutorial for the event bus to connect the two devices with MQTT. That solved most of my traffic problems (So for you what else is going on your hardware? Is it dedicated to zwave only? Is there an attached SSD-if using RPI?)
  2. The other actions to reduce traffic can all be found in the forum, but
    a) Set polling to 10 days
    b) Eliminate all reports, just link the items you need for rules. If you must report, set the report intervals as high as possible (in hours).
    c) As you found, i beware of threshold reports. I have an energy monitor that is only used when power is out for the generator. However, I get stray readings from 1-10 watts There is a report option to report changes over x% that will fire continuously during normal utility power. I turn it off.
    d) Also beware of groups. If every item in the group is chatty, the group chattiness will multiply.
    e) Network heals will not work in heavy traffic, so your routing will not be optimal until you get the traffic under control.

My two cents

Edit: One more: Controller (Node 1) should only be in Group 1 Association Group. Remove it from the other groups (like Basic) that might be available on your device.

Very good suggestions @apella12.

Sorry for the superlate reply, i set the metering reporting to over 200 seconds differantiate between nodes like 200 sec 201 sec etc so they not ever will try to talk at the same time. It has greatly improved my system (heatit zwave units)

This topic was automatically closed 41 days after the last reply. New replies are no longer allowed.