- Platform information:
- Hardware: VM x64 8gb ram 4 core.
- ubuntu server 22.04 lts
- openHAB version: 3.4.3
- Issue of the topic: unable to add zwave device after adding zwave binding.
- Please post configurations (if applicable):
zooz s2 stick zst10 which generates /dev/ttyUSB0
- Items configuration related to the issue
UID: zwave:serial_zstick:Z-waveController
label: ZooZ S2 Xwave Controller
thingTypeUID: zwave:serial_zstick
configuration:
controller_softreset: false
security_networkkey: blah
security_inclusionmode: 0
controller_maxawakeperiod: 10
controller_sync: false
port: /dev/ttyUSB0
controller_master: true
inclusion_mode: 2
controller_wakeupperiod: 3600
heal_time: 2
controller_exclude: false
controller_inclusiontimeout: 30
controller_hardreset: false
- If logs where generated please post these here using code fences:
11:22:33.030 [DEBUG] [zwave.discovery.ZWaveDiscoveryService] - ZWave discovery: Stop zwave:serial_zstick:Z-waveController
11:22:33.055 [DEBUG] [ave.internal.protocol.ZWaveController] - ZWave inclusion process not running - nothing to do
11:22:33.059 [DEBUG] [zwave.discovery.ZWaveDiscoveryService] - ZWave discovery: Start zwave:serial_zstick:Z-waveController
11:22:33.062 [DEBUG] [ave.internal.protocol.ZWaveController] - ZWave controller start inclusion - mode 2
11:22:33.066 [DEBUG] [ave.internal.protocol.ZWaveController] - Event listener added.
11:22:33.069 [DEBUG] [nal.protocol.ZWaveInclusionController] - ZWave controller start inclusion
11:22:33.072 [DEBUG] [col.serialmessage.AddNodeMessageClass] - Setting controller into INCLUSION mode, highPower:true networkWide:true.
11:22:33.075 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 255: Added 18 to queue - size 1
11:22:33.078 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
11:22:33.081 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 4A C1 0B 7A
11:22:33.084 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 4AC1 0B 7A
11:22:33.091 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT
11:22:33.094 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
11:22:33.099 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
11:22:33.099 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 18: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 11
11:22:33.103 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 4A 0B 01 00 00 B8
11:22:33.102 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
11:22:33.107 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 18: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 11
11:22:33.111 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg: ACK
11:22:33.111 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=AddNodeToNetwork[74], type=Request[0], dest=1, callback=11, payload=0B 01 00 00
11:22:33.113 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
11:22:33.116 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
11:22:33.119 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=AddNodeToNetwork[74], type=Request[0], dest=1, callback=11, payload=0B 01 00 00
11:22:33.121 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 18: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 11
11:22:33.123 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
11:22:33.126 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 18: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 11
11:22:33.128 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking TID 18: (Callback 11)
11:22:33.130 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Callback match!
11:22:33.132 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Correlated to TID 18: callback 11
11:22:33.134 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=AddNodeToNetwork[74], type=Request[0], dest=1, callback=11, payload=0B 01 00 00
11:22:33.137 [DEBUG] [col.serialmessage.AddNodeMessageClass] - Add Node: Learn ready.
11:22:33.139 [DEBUG] [nal.protocol.ZWaveInclusionController] - Inclusion event: Current state IncludeSent, newevent IncludeStart
11:22:33.141 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 18: Transaction COMPLETED
11:22:33.144 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 255: Response processed after 45ms
11:22:33.145 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 255: TID 18: Transaction completed
11:22:33.148 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 255: notifyTransactionResponse TID:18 DONE
11:22:33.150 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
11:22:33.152 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
11:23:33.059 [DEBUG] [zwave.discovery.ZWaveDiscoveryService] - ZWave discovery: Stop zwave:serial_zstick:Z-waveController
11:23:33.064 [DEBUG] [nal.protocol.ZWaveInclusionController] - ZWave controller stopping inclusion at IncludeStart
11:23:33.066 [DEBUG] [col.serialmessage.AddNodeMessageClass] - Ending INCLUSION mode.
11:23:33.069 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 255: Added 19 to queue - size 1
11:23:33.072 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
11:23:33.075 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 4A 05 0C B9
11:23:33.077 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 4A05 0C B9
11:23:33.084 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT
11:23:33.087 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 19: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 12
11:23:33.087 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
11:23:33.092 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
11:23:33.094 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
11:23:33.096 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 19: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 12
11:23:33.097 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 4A 0C 06 00 00 B8
11:23:33.098 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg: ACK
11:23:33.101 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
11:23:33.103 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
11:23:33.104 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=AddNodeToNetwork[74], type=Request[0], dest=6, callback=12, payload=0C 06 00 00
11:23:33.106 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=AddNodeToNetwork[74], type=Request[0], dest=6, callback=12, payload=0C 06 00 00
11:23:33.108 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 19: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 12
11:23:33.110 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
11:23:33.112 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 19: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 12
11:23:33.113 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking TID 19: (Callback 12)
11:23:33.116 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Callback match!
11:23:33.117 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Correlated to TID 19: callback 12
11:23:33.120 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=AddNodeToNetwork[74], type=Request[0], dest=6, callback=12, payload=0C 06 00 00
11:23:33.125 [DEBUG] [col.serialmessage.AddNodeMessageClass] - Add Node: Done.
11:23:33.127 [DEBUG] [nal.protocol.ZWaveInclusionController] - Inclusion event: Current state IncludeDone, newevent IncludeDone
11:23:33.128 [DEBUG] [col.serialmessage.AddNodeMessageClass] - Ending INCLUSION mode.
11:23:33.130 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 255: Added 20 to queue - size 1
11:23:33.132 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
11:23:33.134 [DEBUG] [nal.protocol.ZWaveInclusionController] - ZWave controller end exclusion
11:23:33.136 [DEBUG] [ave.internal.protocol.ZWaveController] - ZWaveController include done
11:23:33.138 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 19: Advanced to DONE
11:23:33.140 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 255: Response processed after 53ms
11:23:33.141 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 255: TID 19: Transaction completed
11:23:33.143 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 255: notifyTransactionResponse TID:19 DONE
11:23:33.146 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
11:23:33.156 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
11:23:33.158 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 4A 05 00 B5
11:23:33.160 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 4A05 00 B5
11:23:33.170 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
11:23:33.171 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT
11:23:33.173 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
11:23:33.173 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 20: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 0
11:23:33.177 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
11:23:33.179 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 20: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 0
11:23:33.180 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg: ACK
11:23:33.182 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
11:23:33.184 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
11:23:38.176 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - NODE 255: TID 20: Timeout at state WAIT_REQUEST. 3 retries remaining.
11:23:38.180 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - TID 20: Transaction is current transaction, so clearing!!!!!
11:23:38.182 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 20: Transaction CANCELLED
11:23:38.184 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 255: notifyTransactionResponse TID:20 CANCELLED
11:23:38.187 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.