Hi,
I included the device to my z-wave network. node.xml was created, but the initialization process does not finish (device shows up as unknown device in Paper UI). Any ideas?
I attached the logs below. It’s node 8. I am using z-wave binding version 2.0.0 from the openhab 2.0 stable release.
Many thanks for your support.
15:35:11.240 [WARN ] [l.serialmessage.SendDataMessageClass] - NODE 8: Already processed another send data request for this callback Id, ignoring.
15:35:16.212 [ERROR] [ocol.ZWaveController$ZWaveSendThread] - NODE 8: Timeout while sending message. Requeueing - 1 attempts left!
15:35:16.212 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 8: Is sleeping
15:35:16.212 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 8: Putting message SendData in wakeup queue.
15:35:16.212 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 0
15:35:16.212 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 8: Message already on the wake-up queue. Removing original.
15:35:16.212 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 8: Putting message SendData in wakeup queue.
15:35:19.908 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 1B 00 49 84 08 15 04 18 01 5E 85 59 8E 60 86 70 72 5A 73 84 80 5B 71 7A EF 25 26 50
15:35:19.909 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
15:35:19.909 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 1B 00 49 84 08 15 04 18 01 5E 85 59 8E 60 86 70 72 5A 73 84 80 5B 71 7A EF 25 26 50
15:35:19.909 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 1B 00 49 84 08 15 04 18 01 5E 85 59 8E 60 86 70 72 5A 73 84 80 5B 71 7A EF 25 26 50
15:35:19.909 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationUpdate[0x49], type=Request[0x00], priority=High, dest=255, callback=0, payload=84 08 15 04 18 01 5E 85 59 8E 60 86 70 72 5A 73 84 80 5B 71 7A EF 25 26
15:35:19.910 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 8: Application update request. Node information received.
15:35:19.910 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 8: Is awake with 2 messages in the wake-up queue.
15:35:19.910 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveWakeUpEvent
15:35:19.910 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 8: Wakeup during initialisation.
15:35:19.910 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 8: Node advancer - STATIC_VALUES: queue length(70), free to send(false)
15:35:19.910 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 8: Node advancer - queued packet. Queue length is 70
15:35:19.910 [DEBUG] [ve.internal.protocol.ZWaveController] - Message queued. Queue length = 1. Queue={}
15:35:19.910 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Got an event from Z-Wave network: ZWaveWakeUpEvent
15:35:19.910 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 0
15:35:19.911 [DEBUG] [ve.internal.protocol.ZWaveController] - Message queued. Queue length = 1. Queue={}
15:35:19.911 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Message has Ack Pending: Message: class=SendData[0x13], type=Request[0x00], priority=Immediate, dest=8, callback=145, payload=08 08 60 0D 01 01 59 05 00 04
15:35:19.911 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0F 00 13 08 08 60 0D 01 01 59 05 00 04 25 91 62
15:35:19.911 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 8: Sending REQUEST Message = 01 0F 00 13 08 08 60 0D 01 01 59 05 00 04 25 91 62
15:35:19.919 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
15:35:19.920 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
15:35:19.920 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 04 01 13 01 E8
15:35:19.920 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 04 01 13 01 E8
15:35:19.920 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=SendData[0x13], type=Response[0x01], priority=High, dest=255, callback=0, payload=01
15:35:19.920 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 8: Sent Data successfully placed on stack.
15:35:19.936 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 91 00 00 03 79
15:35:19.937 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
15:35:19.937 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 91 00 00 03 00 00 77
15:35:19.937 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 13 91 00 00 03 00 00 77
15:35:19.938 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=SendData[0x13], type=Request[0x00], priority=High, dest=255, callback=0, payload=91 00 00 03
15:35:19.938 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 8: SendData Request. CallBack ID = 145, Status = Transmission complete and ACK received(0)
15:35:19.939 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Immediate, dest=8, callback=145, payload=08 08 60 0D 01 01 59 05 00 04
15:35:19.939 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=SendData[0x13], type=Request[0x00], priority=High, dest=255, callback=0, payload=91 00 00 03
15:35:19.940 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=SendData, callback id=145, expected=ApplicationCommandHandler, cancelled=false MISMATCH
15:35:24.911 [ERROR] [ocol.ZWaveController$ZWaveSendThread] - NODE 8: Timeout while sending message. Requeueing - 0 attempts left!
15:35:24.912 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 8: Is sleeping
15:35:24.912 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 8: Message already on the wake-up queue. Removing original.
15:35:24.912 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 8: Putting message SendData in wakeup queue.
15:35:24.912 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 0
15:35:24.912 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 8: Putting message SendData in wakeup queue.
15:35:53.891 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 04 04 08 0A 71 05 00 00 00 FF 08 0D 00 00 63
15:35:53.892 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
15:35:53.892 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 10 00 04 04 08 0A 71 05 00 00 00 FF 08 0D 00 00 63
15:35:53.892 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 10 00 04 04 08 0A 71 05 00 00 00 FF 08 0D 00 00 63
15:35:53.892 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=04 08 0A 71 05 00 00 00 FF 08 0D 00 00
15:35:53.892 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 8: Application Command Request (ALIVE:STATIC_VALUES)
15:35:53.892 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 8: Incoming command class ALARM
15:35:53.892 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 8: Received ALARM command V4
15:35:53.892 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 8: Process NOTIFICATION_REPORT V4
15:35:53.892 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 8: NOTIFICATION report - 0 = 0, event=13, status=255
15:35:53.893 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 8: Alarm Type = POWER_MANAGEMENT (0)
15:35:53.893 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveAlarmValueEvent
15:35:53.893 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Got an event from Z-Wave network: ZWaveAlarmValueEvent
15:35:53.893 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Got a value event from Z-Wave network, endpoint = 0, command class = ALARM, value = 255
15:35:53.893 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Message has Ack Pending: Message: class=SendData[0x13], type=Request[0x00], priority=Immediate, dest=8, callback=146, payload=08 08 60 0D 01 01 59 05 00 05
15:35:53.925 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 04 08 03 80 03 64 1A
15:35:53.925 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
15:35:53.925 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 04 04 08 03 80 03 64 1A
15:35:53.925 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 04 04 08 03 80 03 64 1A
15:35:53.926 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=04 08 03 80 03 64
15:35:53.926 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 8: Application Command Request (ALIVE:STATIC_VALUES)
15:35:53.926 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 8: Incoming command class BATTERY
15:35:53.926 [DEBUG] [ommandclass.ZWaveBatteryCommandClass] - NODE 8: Received Battery Request
15:35:53.926 [DEBUG] [ommandclass.ZWaveBatteryCommandClass] - NODE 8: Battery report value = 100
15:35:53.926 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveCommandClassValueEvent
15:35:53.926 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
15:35:53.926 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Got a value event from Z-Wave network, endpoint = 0, command class = BATTERY, value = 100
15:35:53.926 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Message has Ack Pending: Message: class=SendData[0x13], type=Request[0x00], priority=Immediate, dest=8, callback=146, payload=08 08 60 0D 01 01 59 05 00 05