Zwave log flooded with always the same basic set and state update

Nork · September 25, 2017, 9:17am

Hi,

I am using OH 2.1 with zwave nightly 20th. sept. Short after paring the Node 9 (Steinel motion sensor, luminance and light switch), the log is flooded by always the same entries. The device functions are working ok. This happens also after restart of OH. OH is newly set up with just 3 other nodes (roller shutter) which work fine.

Logfile

2017-09-25 09:39:18.429 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Starting initialisation from DONE
2017-09-25 09:39:18.429 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@729d66f5 already registered
2017-09-25 09:39:18.429 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Incoming command class BASIC
2017-09-25 09:39:18.429 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 9: Received Basic Request
2017-09-25 09:39:18.430 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 9: Basic Set sent to the controller will be processed as Basic Report
2017-09-25 09:39:18.430 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 9: Basic report, value = 0x00
2017-09-25 09:39:18.430 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveCommandClassValueEvent
2017-09-25 09:39:18.430 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2017-09-25 09:39:18.430 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got a value event from Z-Wave network, endpoint = 0, command class = BASIC, value = 0
2017-09-25 09:39:18.430 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Updating channel state zwave:device:30fee4f9:node9:switch_binary to OFF [OnOffType]
2017-09-25 09:39:18.430 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Get, dest=9, callback=185, payload=09 06 60 0D 01 03 31 04
2017-09-25 09:39:18.430 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 09 03 20 01 00
2017-09-25 09:39:18.430 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=ApplicationCommandHandler, callback id=185, expected=ApplicationCommandHandler, cancelled=false transaction complete!
2017-09-25 09:39:18.430 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
2017-09-25 09:39:18.430 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2017-09-25 09:39:18.454 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 09 03 20 01 00 D9
2017-09-25 09:39:18.454 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2017-09-25 09:39:18.454 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 04 00 09 03 20 01 00 D9
2017-09-25 09:39:18.454 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 04 00 09 03 20 01 00 D9
2017-09-25 09:39:18.454 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 09 03 20 01 00
2017-09-25 09:39:18.454 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Application Command Request (ALIVE:DONE)
2017-09-25 09:39:18.454 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Starting initialisation from DONE
2017-09-25 09:39:18.454 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@729d66f5 already registered
2017-09-25 09:39:18.455 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Incoming command class BASIC
2017-09-25 09:39:18.455 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 9: Received Basic Request
2017-09-25 09:39:18.455 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 9: Basic Set sent to the controller will be processed as Basic Report
2017-09-25 09:39:18.455 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 9: Basic report, value = 0x00
2017-09-25 09:39:18.455 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveCommandClassValueEvent
2017-09-25 09:39:18.455 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2017-09-25 09:39:18.455 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got a value event from Z-Wave network, endpoint = 0, command class = BASIC, value = 0
2017-09-25 09:39:18.455 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Updating channel state zwave:device:30fee4f9:node9:switch_binary to OFF [OnOffType]
2017-09-25 09:39:18.455 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Get, dest=9, callback=185, payload=09 06 60 0D 01 03 31 04
2017-09-25 09:39:18.455 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 09 03 20 01 00
2017-09-25 09:39:18.455 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=ApplicationCommandHandler, callback id=185, expected=ApplicationCommandHandler, cancelled=false transaction complete!
2017-09-25 09:39:18.455 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
2017-09-25 09:39:18.455 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2017-09-25 09:39:18.479 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 09 03 20 01 00 D9
2017-09-25 09:39:18.480 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2017-09-25 09:39:18.480 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 04 00 09 03 20 01 00 D9
2017-09-25 09:39:18.480 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 04 00 09 03 20 01 00 D9
2017-09-25 09:39:18.480 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 09 03 20 01 00
2017-09-25 09:39:18.480 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Application Command Request (ALIVE:DONE)
2017-09-25 09:39:18.480 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Starting initialisation from DONE
2017-09-25 09:39:18.480 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@729d66f5 already registered
2017-09-25 09:39:18.480 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Incoming command class BASIC
2017-09-25 09:39:18.480 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 9: Received Basic Request
2017-09-25 09:39:18.480 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 9: Basic Set sent to the controller will be processed as Basic Report
2017-09-25 09:39:18.480 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 9: Basic report, value = 0x00
2017-09-25 09:39:18.480 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveCommandClassValueEvent
2017-09-25 09:39:18.481 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2017-09-25 09:39:18.481 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got a value event from Z-Wave network, endpoint = 0, command class = BASIC, value = 0
2017-09-25 09:39:18.481 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Updating channel state zwave:device:30fee4f9:node9:switch_binary to OFF [OnOffType]
2017-09-25 09:39:18.481 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Get, dest=9, callback=185, payload=09 06 60 0D 01 03 31 04
2017-09-25 09:39:18.481 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 09 03 20 01 00
2017-09-25 09:39:18.481 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=ApplicationCommandHandler, callback id=185, expected=ApplicationCommandHandler, cancelled=false transaction complete!
2017-09-25 09:39:18.481 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
2017-09-25 09:39:18.481 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2017-09-25 09:39:18.504 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 09 03 20 01 00 D9
2017-09-25 09:39:18.504 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2017-09-25 09:39:18.504 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 04 00 09 03 20 01 00 D9
2017-09-25 09:39:18.505 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 04 00 09 03 20 01 00 D9
2017-09-25 09:39:18.505 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 09 03 20 01 00
2017-09-25 09:39:18.505 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Application Command Request (ALIVE:DONE)
2017-09-25 09:39:18.505 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Starting initialisation from DONE
2017-09-25 09:39:18.505 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@729d66f5 already registered
2017-09-25 09:39:18.505 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Incoming command class BASIC
2017-09-25 09:39:18.505 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 9: Received Basic Request
2017-09-25 09:39:18.505 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 9: Basic Set sent to the controller will be processed as Basic Report
2017-09-25 09:39:18.505 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 9: Basic report, value = 0x00
2017-09-25 09:39:18.505 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveCommandClassValueEvent
2017-09-25 09:39:18.505 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2017-09-25 09:39:18.505 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got a value event from Z-Wave network, endpoint = 0, command class = BASIC, value = 0
2017-09-25 09:39:18.505 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Updating channel state zwave:device:30fee4f9:node9:switch_binary to OFF [OnOffType]
2017-09-25 09:39:18.505 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Get, dest=9, callback=185, payload=09 06 60 0D 01 03 31 04
2017-09-25 09:39:18.505 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 09 03 20 01 00
2017-09-25 09:39:18.505 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=ApplicationCommandHandler, callback id=185, expected=ApplicationCommandHandler, cancelled=false transaction complete!
2017-09-25 09:39:18.505 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
2017-09-25 09:39:18.505 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2017-09-25 09:39:18.530 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 09 03 20 01 00 D9
2017-09-25 09:39:18.530 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2017-09-25 09:39:18.530 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 04 00 09 03 20 01 00 D9
2017-09-25 09:39:18.530 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 04 00 09 03 20 01 00 D9
2017-09-25 09:39:18.530 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 09 03 20 01 00
2017-09-25 09:39:18.530 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Application Command Request (ALIVE:DONE)
2017-09-25 09:39:18.530 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Starting initialisation from DONE
2017-09-25 09:39:18.530 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@729d66f5 already registered
2017-09-25 09:39:18.530 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Incoming command class BASIC
2017-09-25 09:39:18.530 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 9: Received Basic Request
2017-09-25 09:39:18.530 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 9: Basic Set sent to the controller will be processed as Basic Report
2017-09-25 09:39:18.530 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 9: Basic report, value = 0x00
2017-09-25 09:39:18.530 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveCommandClassValueEvent
2017-09-25 09:39:18.531 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2017-09-25 09:39:18.531 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got a value event from Z-Wave network, endpoint = 0, command class = BASIC, value = 0
2017-09-25 09:39:18.531 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Updating channel state zwave:device:30fee4f9:node9:switch_binary to OFF [OnOffType]
2017-09-25 09:39:18.531 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Get, dest=9, callback=185, payload=09 06 60 0D 01 03 31 04
2017-09-25 09:39:18.531 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 09 03 20 01 00
2017-09-25 09:39:18.531 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=ApplicationCommandHandler, callback id=185, expected=ApplicationCommandHandler, cancelled=false transaction complete!
2017-09-25 09:39:18.531 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
2017-09-25 09:39:18.531 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2017-09-25 09:39:18.555 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 09 03 20 01 00 D9
2017-09-25 09:39:18.555 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2017-09-25 09:39:18.555 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 04 00 09 03 20 01 00 D9
2017-09-25 09:39:18.555 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 04 00 09 03 20 01 00 D9
2017-09-25 09:39:18.556 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 09 03 20 01 00
2017-09-25 09:39:18.556 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Application Command Request (ALIVE:DONE)
2017-09-25 09:39:18.556 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Starting initialisation from DONE
2017-09-25 09:39:18.556 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@729d66f5 already registered
2017-09-25 09:39:18.556 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Incoming command class BASIC
2017-09-25 09:39:18.556 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 9: Received Basic Request
2017-09-25 09:39:18.556 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 9: Basic Set sent to the controller will be processed as Basic Report
2017-09-25 09:39:18.556 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 9: Basic report, value = 0x00
2017-09-25 09:39:18.556 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveCommandClassValueEvent
2017-09-25 09:39:18.556 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2017-09-25 09:39:18.556 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got a value event from Z-Wave network, endpoint = 0, command class = BASIC, value = 0
2017-09-25 09:39:18.556 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Updating channel state zwave:device:30fee4f9:node9:switch_binary to OFF [OnOffType]
2017-09-25 09:39:18.557 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Get, dest=9, callback=185, payload=09 06 60 0D 01 03 31 04
2017-09-25 09:39:18.557 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 09 03 20 01 00
2017-09-25 09:39:18.557 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=ApplicationCommandHandler, callback id=185, expected=ApplicationCommandHandler, cancelled=false transaction complete!
2017-09-25 09:39:18.557 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
2017-09-25 09:39:18.557 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2017-09-25 09:39:18.584 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 09 03 20 01 00 D9
2017-09-25 09:39:18.584 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2017-09-25 09:39:18.584 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 04 00 09 03 20 01 00 D9
2017-09-25 09:39:18.584 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 04 00 09 03 20 01 00 D9
2017-09-25 09:39:18.584 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 09 03 20 01 00
2017-09-25 09:39:18.584 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Application Command Request (ALIVE:DONE)
2017-09-25 09:39:18.584 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Starting initialisation from DONE
2017-09-25 09:39:18.584 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@729d66f5 already registered
2017-09-25 09:39:18.584 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Incoming command class BASIC
2017-09-25 09:39:18.584 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 9: Received Basic Request
2017-09-25 09:39:18.585 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 9: Basic Set sent to the controller will be processed as Basic Report
2017-09-25 09:39:18.585 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 9: Basic report, value = 0x00
2017-09-25 09:39:18.585 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveCommandClassValueEvent
2017-09-25 09:39:18.585 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2017-09-25 09:39:18.585 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got a value event from Z-Wave network, endpoint = 0, command class = BASIC, value = 0
2017-09-25 09:39:18.585 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Updating channel state zwave:device:30fee4f9:node9:switch_binary to OFF [OnOffType]
2017-09-25 09:39:18.585 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Get, dest=9, callback=185, payload=09 06 60 0D 01 03 31 04
2017-09-25 09:39:18.585 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 09 03 20 01 00
2017-09-25 09:39:18.585 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=ApplicationCommandHandler, callback id=185, expected=ApplicationCommandHandler, cancelled=false transaction complete!
2017-09-25 09:39:18.585 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
2017-09-25 09:39:18.585 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent

Can you please help me, since I don’t know what to do.
Thanks in advance
Nork

chris · September 25, 2017, 9:59am

From a quick look, these messages seem to be sent unsolicited from the device. Since you have debug logging enabled, you are seeing all these messages.

Nork · September 25, 2017, 10:07am

ok- this means, the device is sending without unneeded data? Could this be a bug? I was fearing that this will overload my zwave network…

chris · September 25, 2017, 10:08am

I don’t know why the device would send this - I don’t know what the device is, or how it’s configured. Try resetting it to see if that fixes it?

Nork · September 25, 2017, 10:14am

I already resetted a few times. After some hours this unsolicited data always appeared.
Then maybe I should contact the manufacturer for a fix?

chris · September 25, 2017, 10:47am

I’ve run this through the log viewer to make it easier to read -:

I would suggest to check the configuration to make sure you’ve not set something incorrectly, otherwise, yes, speak to the manufacturer.

Nork · September 25, 2017, 10:54am

I checked config. Could it be related to the fact that I only using the association “lifeline” and not all the other like “motion” and “luminance”. Device (xledhome2) has more than one association group.
Maybe I made a mistake when entering the device data in the zwave database (xledhome2), as I am novice I am not sure.

Thank you for your help!

chris · September 25, 2017, 10:59am

Normally, you should only need the lifeline - although it depends on the device implementation. However, if you have mre than one association group set to the controller, then you will end up with multiple messages, but just a small number each time the status changes.

I make the assumption that the log above continues - ie we see 6 BASIC_SET messages over a 150ms period. Does this now stop until the device status changes, or does it continue? I assume it continues as you talk about flooding the log. If it only happens every X minutes when the device status changes, then I would likely have a different answer, so please can you confirm this.

Nork · October 7, 2017, 7:29pm

Today I tried again with a “fresh” node (I excluded the device, than included).
Then initialisation started (latest nightly zwave binding with OH 2.1 stable), after some seconds then the chatter started.
It stopped, when I did this two things: generating a motion by waving my hand in front of the motion sensor and after this moving the including button on the device to send a nif.
Having done this, initialisation suddely was finalized and the device is reporting fine.

I am fearing that after restart the chatter will start all over.
What do you thing, could I give you some clues?

Thanks

Nork · October 8, 2017, 11:28am

As expected after one night this morning the “flood” started all over again, I didn’t change anything.
I was not able to use any device since the network was overloaded because of this node51.

Just for the discussion with the vendor: is the device faulty or is openhab the cause of this problem?

Log file is attached below (I am not allowed to attach a file)


2017-10-08 09:44:30.404 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Update ASSOCIATION group_1: Adding node_1_0
2017-10-08 09:44:30.404 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Update ASSOCIATION group_2: Adding node_1_0
2017-10-08 09:44:30.404 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Update ASSOCIATION group_3: Adding node_1_0
2017-10-08 09:44:30.404 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Update ASSOCIATION group_4: Adding node_1_0
2017-10-08 09:44:30.404 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Property to update: config_10_2, 0, 0.0
2017-10-08 09:44:30.404 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Properties synchronised
2017-10-08 09:44:30.410 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 51: Application Command Request (ALIVE:EMPTYNODE)
2017-10-08 09:44:30.410 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 51: Incoming command class BASIC
2017-10-08 09:44:30.410 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 51: Received Basic Request
2017-10-08 09:44:30.410 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 51: Basic Set sent to the controller will be processed as Basic Report
2017-10-08 09:44:30.410 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 51: Basic report, value = 0x00
2017-10-08 09:44:30.425 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 51: Application Command Request (ALIVE:EMPTYNODE)
2017-10-08 09:44:30.428 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 51: Incoming command class BASIC
2017-10-08 09:44:30.436 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 51: Received Basic Request
2017-10-08 09:44:30.436 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 51: Basic Set sent to the controller will be processed as Basic Report
2017-10-08 09:44:30.437 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 51: Basic report, value = 0x00
2017-10-08 09:44:30.477 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Initialising Thing Node...
2017-10-08 09:44:30.500 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Initialising channel zwave:device:ab03b7fa:node51:switch_binary
2017-10-08 09:44:30.505 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Initialising cmd channel zwave:device:ab03b7fa:node51:switch_binary
2017-10-08 09:44:30.506 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Initialising poll channel zwave:device:ab03b7fa:node51:switch_binary
2017-10-08 09:44:30.507 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Initialising state channel zwave:device:ab03b7fa:node51:switch_binary
2017-10-08 09:44:30.507 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Initialising state channel zwave:device:ab03b7fa:node51:switch_binary
2017-10-08 09:44:30.507 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Initialising channel zwave:device:ab03b7fa:node51:scene_number
2017-10-08 09:44:30.507 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Initialising cmd channel zwave:device:ab03b7fa:node51:scene_number
2017-10-08 09:44:30.507 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Initialising poll channel zwave:device:ab03b7fa:node51:scene_number
2017-10-08 09:44:30.507 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Initialising state channel zwave:device:ab03b7fa:node51:scene_number
2017-10-08 09:44:30.507 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Initialising channel zwave:device:ab03b7fa:node51:sensor_binary
2017-10-08 09:44:30.507 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Initialising cmd channel zwave:device:ab03b7fa:node51:sensor_binary
2017-10-08 09:44:30.508 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Initialising poll channel zwave:device:ab03b7fa:node51:sensor_binary
2017-10-08 09:44:30.508 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Initialising state channel zwave:device:ab03b7fa:node51:sensor_binary
2017-10-08 09:44:30.508 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Initialising channel zwave:device:ab03b7fa:node51:sensor_luminance
2017-10-08 09:44:30.508 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Initialising cmd channel zwave:device:ab03b7fa:node51:sensor_luminance
2017-10-08 09:44:30.508 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Initialising poll channel zwave:device:ab03b7fa:node51:sensor_luminance
2017-10-08 09:44:30.508 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Initialising state channel zwave:device:ab03b7fa:node51:sensor_luminance
2017-10-08 09:44:30.519 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Initialising channel zwave:device:ab03b7fa:node51:alarm_burglar
2017-10-08 09:44:30.519 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Initialising cmd channel zwave:device:ab03b7fa:node51:alarm_burglar
2017-10-08 09:44:30.519 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Initialising poll channel zwave:device:ab03b7fa:node51:alarm_burglar
2017-10-08 09:44:30.519 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Initialising state channel zwave:device:ab03b7fa:node51:alarm_burglar
2017-10-08 09:44:30.519 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Initialising channel zwave:device:ab03b7fa:node51:alarm_system
2017-10-08 09:44:30.519 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Initialising cmd channel zwave:device:ab03b7fa:node51:alarm_system
2017-10-08 09:44:30.520 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Initialising poll channel zwave:device:ab03b7fa:node51:alarm_system
2017-10-08 09:44:30.520 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Initialising state channel zwave:device:ab03b7fa:node51:alarm_system
2017-10-08 09:44:30.520 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Initialising channel zwave:device:ab03b7fa:node51:switch_binary1
2017-10-08 09:44:30.520 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Initialising cmd channel zwave:device:ab03b7fa:node51:switch_binary1
2017-10-08 09:44:30.520 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Initialising poll channel zwave:device:ab03b7fa:node51:switch_binary1
2017-10-08 09:44:30.520 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Initialising state channel zwave:device:ab03b7fa:node51:switch_binary1
2017-10-08 09:44:30.520 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Initialising state channel zwave:device:ab03b7fa:node51:switch_binary1
2017-10-08 09:44:30.520 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Initialising channel zwave:device:ab03b7fa:node51:scene_number1
2017-10-08 09:44:30.520 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Initialising cmd channel zwave:device:ab03b7fa:node51:scene_number1
2017-10-08 09:44:30.520 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Initialising poll channel zwave:device:ab03b7fa:node51:scene_number1
2017-10-08 09:44:30.520 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Initialising state channel zwave:device:ab03b7fa:node51:scene_number1
2017-10-08 09:44:30.521 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Initialising channel zwave:device:ab03b7fa:node51:alarm_burglar2
2017-10-08 09:44:30.521 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Initialising cmd channel zwave:device:ab03b7fa:node51:alarm_burglar2
2017-10-08 09:44:30.521 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Initialising poll channel zwave:device:ab03b7fa:node51:alarm_burglar2
2017-10-08 09:44:30.521 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Initialising state channel zwave:device:ab03b7fa:node51:alarm_burglar2
2017-10-08 09:44:30.521 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Initialising state channel zwave:device:ab03b7fa:node51:alarm_burglar2
2017-10-08 09:44:30.521 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Initialising channel zwave:device:ab03b7fa:node51:sensor_luminance3
2017-10-08 09:44:30.521 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Initialising cmd channel zwave:device:ab03b7fa:node51:sensor_luminance3
2017-10-08 09:44:30.521 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Initialising poll channel zwave:device:ab03b7fa:node51:sensor_luminance3
2017-10-08 09:44:30.521 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Initialising state channel zwave:device:ab03b7fa:node51:sensor_luminance3
2017-10-08 09:44:30.522 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Initialising state channel zwave:device:ab03b7fa:node51:sensor_luminance3
2017-10-08 09:44:30.522 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Polling intialised at 7200 seconds - start in 7200000 milliseconds.
2017-10-08 09:44:30.532 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 51: Application Command Request (ALIVE:EMPTYNODE)
2017-10-08 09:44:30.533 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 51: Incoming command class BASIC
2017-10-08 09:44:30.533 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 51: Received Basic Request
2017-10-08 09:44:30.533 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 51: Basic Set sent to the controller will be processed as Basic Report
2017-10-08 09:44:30.533 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 51: Basic report, value = 0x00
2017-10-08 09:44:30.534 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2017-10-08 09:44:30.541 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Got a value event from Z-Wave network, endpoint = 0, command class = BASIC, value = 0
2017-10-08 09:44:30.542 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Updating channel state zwave:device:ab03b7fa:node51:switch_binary to OFF [OnOffType]
2017-10-08 09:44:30.557 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 51: Application Command Request (ALIVE:EMPTYNODE)
2017-10-08 09:44:30.557 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 51: Incoming command class BASIC
2017-10-08 09:44:30.557 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 51: Received Basic Request
2017-10-08 09:44:30.557 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 51: Basic Set sent to the controller will be processed as Basic Report
2017-10-08 09:44:30.557 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 51: Basic report, value = 0x00
2017-10-08 09:44:30.558 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2017-10-08 09:44:30.558 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Got a value event from Z-Wave network, endpoint = 0, command class = BASIC, value = 0
2017-10-08 09:44:30.558 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Updating channel state zwave:device:ab03b7fa:node51:switch_binary to OFF [OnOffType]
2017-10-08 09:44:30.611 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 51: Application Command Request (ALIVE:EMPTYNODE)
2017-10-08 09:44:30.611 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 51: Incoming command class BASIC
2017-10-08 09:44:30.611 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 51: Received Basic Request
2017-10-08 09:44:30.611 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 51: Basic Set sent to the controller will be processed as Basic Report
2017-10-08 09:44:30.611 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 51: Basic report, value = 0x00
2017-10-08 09:44:30.611 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2017-10-08 09:44:30.611 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Got a value event from Z-Wave network, endpoint = 0, command class = BASIC, value = 0
2017-10-08 09:44:30.612 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Updating channel state zwave:device:ab03b7fa:node51:switch_binary to OFF [OnOffType]
2017-10-08 09:44:30.654 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 51: Application Command Request (ALIVE:EMPTYNODE)
2017-10-08 09:44:30.654 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 51: Incoming command class BASIC
2017-10-08 09:44:30.654 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 51: Received Basic Request
2017-10-08 09:44:30.654 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 51: Basic Set sent to the controller will be processed as Basic Report
2017-10-08 09:44:30.654 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 51: Basic report, value = 0x00
2017-10-08 09:44:30.654 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2017-10-08 09:44:30.654 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Got a value event from Z-Wave network, endpoint = 0, command class = BASIC, value = 0
2017-10-08 09:44:30.655 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Updating channel state zwave:device:ab03b7fa:node51:switch_binary to OFF [OnOffType]
2017-10-08 09:44:30.820 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 51: Application Command Request (ALIVE:EMPTYNODE)
2017-10-08 09:44:30.821 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 51: Incoming command class BASIC
2017-10-08 09:44:30.821 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 51: Received Basic Request
2017-10-08 09:44:30.821 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 51: Basic Set sent to the controller will be processed as Basic Report
2017-10-08 09:44:30.821 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 51: Basic report, value = 0x00
2017-10-08 09:44:30.821 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2017-10-08 09:44:30.821 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Got a value event from Z-Wave network, endpoint = 0, command class = BASIC, value = 0
2017-10-08 09:44:30.822 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Updating channel state zwave:device:ab03b7fa:node51:switch_binary to OFF [OnOffType]
2017-10-08 09:44:30.841 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 51: Application Command Request (ALIVE:EMPTYNODE)
2017-10-08 09:44:30.841 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 51: Incoming command class BASIC
2017-10-08 09:44:30.841 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 51: Received Basic Request
2017-10-08 09:44:30.841 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 51: Basic Set sent to the controller will be processed as Basic Report
2017-10-08 09:44:30.841 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 51: Basic report, value = 0x00
2017-10-08 09:44:30.841 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2017-10-08 09:44:30.842 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Got a value event from Z-Wave network, endpoint = 0, command class = BASIC, value = 0
2017-10-08 09:44:30.842 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Updating channel state zwave:device:ab03b7fa:node51:switch_binary to OFF [OnOffType]
2017-10-08 09:44:30.844 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 51: Application Command Request (ALIVE:EMPTYNODE)
2017-10-08 09:44:30.844 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 51: Incoming command class BASIC
2017-10-08 09:44:30.844 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 51: Received Basic Request
2017-10-08 09:44:30.844 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 51: Basic Set sent to the controller will be processed as Basic Report
2017-10-08 09:44:30.844 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 51: Basic report, value = 0x00
2017-10-08 09:44:30.844 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2017-10-08 09:44:30.844 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Got a value event from Z-Wave network, endpoint = 0, command class = BASIC, value = 0
2017-10-08 09:44:30.844 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Updating channel state zwave:device:ab03b7fa:node51:switch_binary to OFF [OnOffType]
2017-10-08 09:44:30.846 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 51: Application Command Request (ALIVE:EMPTYNODE)
2017-10-08 09:44:30.846 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 51: Incoming command class BASIC
2017-10-08 09:44:30.846 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 51: Received Basic Request
2017-10-08 09:44:30.846 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 51: Basic Set sent to the controller will be processed as Basic Report
2017-10-08 09:44:30.846 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 51: Basic report, value = 0x00
2017-10-08 09:44:30.847 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2017-10-08 09:44:30.851 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Got a value event from Z-Wave network, endpoint = 0, command class = BASIC, value = 0
2017-10-08 09:44:30.851 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Updating channel state zwave:device:ab03b7fa:node51:switch_binary to OFF [OnOffType]
2017-10-08 09:44:30.923 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 51: Application Command Request (ALIVE:EMPTYNODE)
2017-10-08 09:44:30.924 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 51: Incoming command class BASIC
2017-10-08 09:44:30.924 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 51: Received Basic Request
2017-10-08 09:44:30.924 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 51: Basic Set sent to the controller will be processed as Basic Report
2017-10-08 09:44:30.924 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 51: Basic report, value = 0x00
2017-10-08 09:44:30.924 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2017-10-08 09:44:30.924 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Got a value event from Z-Wave network, endpoint = 0, command class = BASIC, value = 0
2017-10-08 09:44:30.924 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Updating channel state zwave:device:ab03b7fa:node51:switch_binary to OFF [OnOffType]
2017-10-08 09:44:30.932 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 51: Application Command Request (ALIVE:EMPTYNODE)

chris · October 8, 2017, 12:04pm

From what we saw earlier, OH doesn’t send anything to the device. It looks like that is also the case here, although this is a filtered log, so I can’t be 100% sure. If OH doesn’t send anything, then I don’t think we can blame OH.

Nork · October 8, 2017, 5:10pm

Ok. That’s what I expected.
I could provide an non- filtered log but don’t know how since I am not allowed to upload log files.
Could I send it via email or so?

Thank you.

chris · October 8, 2017, 5:17pm

If you like to create a ticket on my website (www.cd-jackson.com) then you can upload files up to 10MB I think. You will need to register to see the option to open a ticket, but it’s a bit easier for me than email as it keeps the thread together…

Nork · October 8, 2017, 7:01pm

I created Ticket ID: m2hVjYWTcxQ and uploaded the log file

chris · October 8, 2017, 7:07pm

So as expected, there is nothing coming from the binding - only lots of messages coming from the device. I really think that the device has a problem.

Below is just a short part of the log, but it goes on (as you know already ).

Nork · October 11, 2017, 8:03pm

I tried different configurations and could reproduce the “flooding”.
It always happens, when I add the controller node to association group 2 (control key). When removing controller from this group, flooding stops.

Furthermore OH seems to add the controller node automatically to all groups, when you restart openhab bevore it could finalize initialisation and write the node.xml.

I will observe the log files the next days (and reboots) if my theory is right.

chris · October 11, 2017, 8:21pm

Why did you add the comment in the database? There should not be a problem adding the controller to an association group - if the device is sending a message every few milliseconds, this is (IMO) a problem with the device as stated earlier.

Nork · October 11, 2017, 8:41pm

Yes, I did to protect other users having this this problem until the manufacturer fixes it. I don’t know any other way to “warn them”, sorry.

The challenge is, that the technical support (of the manufacturer Steinel) told me that their devices do work fine with their own smart home box and they are not able (sounded to me more like “don’t want”) to provide help at the moment for another smart home software like OH. They also told me that they already received other complaints for their z-wave devices not correctly working with OH.

chris · October 11, 2017, 8:46pm

Are you sure it is not just a problem with your device - ie it’s really a problem with the design? Do you know it works ok with “3rd party devices”?

I’m happy to work with them, but I don’t see that the binding is sending anything to the device so it’s really hard for me to understand how OH can cause this. In my opinion, in NO circumstances should the device send lots of messages at this rate. To me this can only be a problem with the device, but I know it’s easy for people to blame others .

Nork · October 11, 2017, 9:11pm

Well, the problem seems indeed to be related to the device itself (I have two of them).
The “flooding” starts when any software puts the the controller node in the devices association Group 2 – “On/Off control”. This then seems to be stored in the device itself. Also my open-zwave (hass) and another software which shows zwave raw data get those messages when the controller has been added to group 2.

That’s why I think you are absolutely right that the device (software) is faulty. But from my point of view any smart home software including OH will get those message flood when the add the controller into Group 2 of the device.

How can I help making contact between you and the manufacturer? As I wrote they are very eager promoting their own eco system and don’t seem to have much experience in zwave.
Attached the link to their z-wave page (german): http://www.steinel.de/de/smarthome/smart-friends-smarte-bewegungsmelder-leuchten-strahler