SteMo
(Stefan)
March 25, 2016, 2:39pm
1
Hi,
I can’t include my KFOB-C 4 Button remote control. It’s probably the same than the KFOB from zwave.me .
After the inclusion I see a new but grey node. Also the chapter “Configuration” is missing.
I tried it several times, also with “Reinitialise Node” and “Heal Node”, but it remains grey. And it’s impossible to Exclude this node.
Now I have several new probably dead Nodes und the KFOB-C do not work.
Do some of you have an idea what’s going wrong. I found here some other posts about this control but no solution.
It would be great if someone can help me.
By the way, the inclusion of an similar 4 button wall switch was possible without any problems, and the procedure is the same.
chris
(Chris Jackson)
March 25, 2016, 4:08pm
2
Have you woken the device up a few times?
Not without seeing a log file…
SteMo
(Stefan)
March 25, 2016, 4:31pm
3
Sorry, here is my log file
It’s the node 13
… and yes, I have woken it more then one times, but unfortunately without success.
2016-03-25 17:18:48.602 DEBUG o.o.b.z.i.p.s.AddNodeMessageClass[:83]- NODE 13: Adding controller.
2016-03-25 17:18:48.603 DEBUG o.o.b.z.i.p.ZWaveController[:635]- Notifying event listeners: ZWaveInclusionEvent
2016-03-25 17:18:48.603 DEBUG o.o.b.z.i.ZWaveActiveBinding[:433]- ZwaveIncomingEvent
2016-03-25 17:18:48.604 DEBUG o.o.b.z.i.p.s.ZWaveCommandProcessor[:63]- Sent message Message: class = AddNodeToNetwork (0x4A), type = Request (0x00), payload = 81 FF
2016-03-25 17:18:48.606 DEBUG o.o.b.z.i.p.s.ZWaveCommandProcessor[:64]- Recv message Message: class = AddNodeToNetwork (0x4A), type = Request (0x00), payload = FF 04 0D 19 01 01 06 5E 8F 73 98 86 72 70 85 2D 8E 80 84 5A 59 5B EF 20 5B 26 27 2B 60
2016-03-25 17:18:48.607 DEBUG o.o.b.z.i.p.s.ZWaveCommandProcessor[:65]- Checking transaction complete: class=AddNodeToNetwork, expected=AddNodeToNetwork, cancelled=false
2016-03-25 17:18:48.607 DEBUG o.o.b.z.i.p.s.ZWaveCommandProcessor[:68]- transaction complete!
2016-03-25 17:18:48.608 DEBUG o.o.b.z.i.p.ZWaveController[:635]- Notifying event listeners: ZWaveTransactionCompletedEvent
2016-03-25 17:18:48.608 DEBUG o.o.b.z.i.ZWaveActiveBinding[:433]- ZwaveIncomingEvent
2016-03-25 17:18:49.597 DEBUG o.o.b.z.i.p.ZWaveController$ZWaveReceiveThread[:1481]- Receive Message = 01 07 00 4A FF 05 0D 00 45
2016-03-25 17:18:49.615 DEBUG o.o.b.z.i.p.ZWaveController$ZWaveReceiveThread[:1405]- Receive queue ADD: Length=1
2016-03-25 17:18:49.615 DEBUG o.o.b.z.i.p.ZWaveController[:1163]- Receive queue TAKE: Length=0
2016-03-25 17:18:49.617 DEBUG o.o.b.z.i.p.SerialMessage[:233]- Assembled message buffer = 01 07 00 4A FF 05 0D 00 45
2016-03-25 17:18:49.618 DEBUG o.o.b.z.i.p.ZWaveController[:1164]- Process Message = 01 07 00 4A FF 05 0D 00 45
2016-03-25 17:18:49.619 DEBUG o.o.b.z.i.p.ZWaveController[:192]- Message: class = AddNodeToNetwork (0x4A), type = Request (0x00), payload = FF 05 0D 00
2016-03-25 17:18:49.620 DEBUG o.o.b.z.i.p.s.AddNodeMessageClass[:87]- Add Node: Protocol done.
2016-03-25 17:18:49.621 DEBUG o.o.b.z.i.p.s.ZWaveCommandProcessor[:63]- Sent message Message: class = AddNodeToNetwork (0x4A), type = Request (0x00), payload = 81 FF
2016-03-25 17:18:49.622 DEBUG o.o.b.z.i.p.s.ZWaveCommandProcessor[:64]- Recv message Message: class = AddNodeToNetwork (0x4A), type = Request (0x00), payload = FF 05 0D 00
2016-03-25 17:18:49.623 DEBUG o.o.b.z.i.p.s.ZWaveCommandProcessor[:65]- Checking transaction complete: class=AddNodeToNetwork, expected=AddNodeToNetwork, cancelled=false
2016-03-25 17:18:49.623 DEBUG o.o.b.z.i.p.s.ZWaveCommandProcessor[:68]- transaction complete!
2016-03-25 17:18:49.624 DEBUG o.o.b.z.i.p.ZWaveController[:635]- Notifying event listeners: ZWaveTransactionCompletedEvent
2016-03-25 17:18:49.625 DEBUG o.o.b.z.i.ZWaveActiveBinding[:433]- ZwaveIncomingEvent
2016-03-25 17:19:07.953 DEBUG o.o.b.z.i.c.ZWaveConfiguration[:1356]- Ending inclusion mode.
2016-03-25 17:19:07.954 DEBUG o.o.b.z.i.c.ZWaveConfiguration[:1379]- Stopping inclusion timer.
2016-03-25 17:19:07.955 DEBUG o.o.b.z.i.p.s.AddNodeMessageClass[:57]- Ending INCLUSION mode.
2016-03-25 17:19:07.956 DEBUG o.o.b.z.i.p.SerialMessage[:109]- NODE 255: Creating empty message of class = AddNodeToNetwork (0x4A), type = Request (0x00)
2016-03-25 17:19:07.957 DEBUG o.o.b.z.i.p.ZWaveController$ZWaveSendThread[:1211]- Took message from queue for sending. Queue length = 0
2016-03-25 17:19:07.958 DEBUG o.o.b.z.i.p.SerialMessage[:233]- Assembled message buffer = 01 04 00 4A 05 B4
2016-03-25 17:19:07.959 DEBUG o.o.b.z.i.p.ZWaveController$ZWaveSendThread[:1268]- NODE 255: Sending REQUEST Message = 01 04 00 4A 05 B4
2016-03-25 17:19:07.960 DEBUG o.o.b.z.i.p.ZWaveController[:620]- Enqueueing message. Queue length = 0
2016-03-25 17:19:08.047 DEBUG o.o.b.z.i.p.ZWaveController$ZWaveReceiveThread[:1481]- Receive Message = 01 07 00 4A FF 06 0D 00 46
2016-03-25 17:19:08.065 DEBUG o.o.b.z.i.p.ZWaveController$ZWaveReceiveThread[:1405]- Receive queue ADD: Length=1
2016-03-25 17:19:08.065 DEBUG o.o.b.z.i.p.ZWaveController[:1163]- Receive queue TAKE: Length=0
2016-03-25 17:19:08.067 DEBUG o.o.b.z.i.p.SerialMessage[:233]- Assembled message buffer = 01 07 00 4A FF 06 0D 00 46
2016-03-25 17:19:08.068 DEBUG o.o.b.z.i.p.ZWaveController[:1164]- Process Message = 01 07 00 4A FF 06 0D 00 46
2016-03-25 17:19:08.069 DEBUG o.o.b.z.i.p.ZWaveController[:192]- Message: class = AddNodeToNetwork (0x4A), type = Request (0x00), payload = FF 06 0D 00
2016-03-25 17:19:08.070 DEBUG o.o.b.z.i.p.s.AddNodeMessageClass[:90]- Add Node: Done.
2016-03-25 17:19:08.070 DEBUG o.o.b.z.i.p.ZWaveController[:635]- Notifying event listeners: ZWaveInclusionEvent
2016-03-25 17:19:08.071 DEBUG o.o.b.z.i.ZWaveActiveBinding[:433]- ZwaveIncomingEvent
2016-03-25 17:19:08.071 DEBUG o.o.b.z.i.c.ZWaveConfiguration[:1379]- Stopping inclusion timer.
2016-03-25 17:19:08.072 ERROR o.o.b.z.i.c.ZWaveConfiguration[:1387]- Neither inclusion nor exclusion was active!
2016-03-25 17:19:08.072 DEBUG o.o.b.z.i.p.ZWaveController[:646]- NODE 13: Including node.
2016-03-25 17:19:08.074 DEBUG o.o.b.z.i.p.s.ZWaveCommandProcessor[:63]- Sent message Message: class = AddNodeToNetwork (0x4A), type = Request (0x00), payload = 05
2016-03-25 17:19:08.074 DEBUG o.o.b.z.i.p.ZWaveController[:493]- NODE 13: Init node thread start
2016-03-25 17:19:08.075 DEBUG o.o.b.z.i.p.s.ZWaveCommandProcessor[:64]- Recv message Message: class = AddNodeToNetwork (0x4A), type = Request (0x00), payload = FF 06 0D 00
2016-03-25 17:19:08.076 DEBUG o.o.b.z.i.p.s.ZWaveCommandProcessor[:65]- Checking transaction complete: class=AddNodeToNetwork, expected=AddNodeToNetwork, cancelled=false
2016-03-25 17:19:08.077 DEBUG o.o.b.z.i.p.s.ZWaveCommandProcessor[:68]- transaction complete!
2016-03-25 17:19:08.078 DEBUG o.o.b.z.i.p.ZWaveController[:635]- Notifying event listeners: ZWaveTransactionCompletedEvent
2016-03-25 17:19:08.078 DEBUG o.o.b.z.i.ZWaveActiveBinding[:433]- ZwaveIncomingEvent
2016-03-25 17:19:08.079 DEBUG o.o.b.z.i.p.ZWaveController$ZWaveSendThread[:1327]- NODE 255: Response processed after 119ms/4248ms.
2016-03-25 17:19:08.110 DEBUG o.o.b.z.i.p.i.ZWaveNodeSerializer[:138]- NODE 13: Serializing from file /var/lib/openhab/zwave/node13.xml
2016-03-25 17:19:08.111 DEBUG o.o.b.z.i.p.i.ZWaveNodeSerializer[:141]- NODE 13: Error serializing from file: file does not exist.
2016-03-25 17:19:08.113 DEBUG o.o.b.z.i.p.i.ZWaveNodeStageAdvancer[:269]- NODE 13: Node advancer - EMPTYNODE: queue length(0), free to send(true)
2016-03-25 17:19:08.114 DEBUG o.o.b.z.i.p.i.ZWaveNodeStageAdvancer[:335]- NODE 13: Node advancer: loop - EMPTYNODE try 1: stageAdvanced(false)
2016-03-25 17:19:08.115 DEBUG o.o.b.z.i.p.i.ZWaveNodeStageAdvancer[:340]- NODE 13: Node advancer: Initialisation starting
2016-03-25 17:19:08.116 DEBUG o.o.b.z.i.p.i.ZWaveNodeStageAdvancer[:868]- NODE 13: Node advancer - advancing to PROTOINFO
2016-03-25 17:19:08.116 DEBUG o.o.b.z.i.p.i.ZWaveNodeStageAdvancer[:335]- NODE 13: Node advancer: loop - PROTOINFO try 0: stageAdvanced(true)
2016-03-25 17:19:08.117 DEBUG o.o.b.z.i.p.i.ZWaveNodeStageAdvancer[:349]- NODE 13: Node advancer: PROTOINFO - send IdentifyNode
2016-03-25 17:19:08.118 DEBUG o.o.b.z.i.p.SerialMessage[:109]- NODE 255: Creating empty message of class = IdentifyNode (0x41), type = Request (0x00)
2016-03-25 17:19:08.118 DEBUG o.o.b.z.i.p.ZWaveController[:620]- Enqueueing message. Queue length = 1
2016-03-25 17:19:08.118 DEBUG o.o.b.z.i.p.ZWaveController$ZWaveSendThread[:1211]- Took message from queue for sending. Queue length = 0
2016-03-25 17:19:08.119 DEBUG o.o.b.z.i.p.i.ZWaveNodeStageAdvancer[:242]- NODE 13: Node advancer - queued packet. Queue length is 1
2016-03-25 17:19:08.119 DEBUG o.o.b.z.i.p.SerialMessage[:233]- Assembled message buffer = 01 04 00 41 0D B7
2016-03-25 17:19:08.120 DEBUG o.o.b.z.i.p.ZWaveController[:585]- NODE 13: Init node thread finished
2016-03-25 17:19:08.120 DEBUG o.o.b.z.i.p.ZWaveController$ZWaveSendThread[:1268]- NODE 255: Sending REQUEST Message = 01 04 00 41 0D B7
2016-03-25 17:19:08.131 DEBUG o.o.b.z.i.p.ZWaveController$ZWaveReceiveThread[:1481]- Receive Message = 01 09 01 41 13 96 01 01 01 06 34
2016-03-25 17:19:08.165 DEBUG o.o.b.z.i.p.ZWaveController$ZWaveReceiveThread[:1405]- Receive queue ADD: Length=1
2016-03-25 17:19:08.165 DEBUG o.o.b.z.i.p.ZWaveController[:1163]- Receive queue TAKE: Length=0
2016-03-25 17:19:08.167 DEBUG o.o.b.z.i.p.SerialMessage[:233]- Assembled message buffer = 01 09 01 41 13 96 01 01 01 06 34
2016-03-25 17:19:08.167 DEBUG o.o.b.z.i.p.ZWaveController[:1164]- Process Message = 01 09 01 41 13 96 01 01 01 06 34
2016-03-25 17:19:08.168 DEBUG o.o.b.z.i.p.ZWaveController[:192]- Message: class = IdentifyNode (0x41), type = Response (0x01), payload = 13 96 01 01 01 06
2016-03-25 17:19:08.169 DEBUG o.o.b.z.i.p.s.IdentifyNodeMessageClass[:52]- NODE 13: ProtocolInfo
2016-03-25 17:19:08.169 DEBUG o.o.b.z.i.p.s.IdentifyNodeMessageClass[:68]- NODE 13: Listening = false
2016-03-25 17:19:08.169 DEBUG o.o.b.z.i.p.s.IdentifyNodeMessageClass[:69]- NODE 13: Routing = false
2016-03-25 17:19:08.170 DEBUG o.o.b.z.i.p.s.IdentifyNodeMessageClass[:70]- NODE 13: Beaming = true
2016-03-25 17:19:08.170 DEBUG o.o.b.z.i.p.s.IdentifyNodeMessageClass[:71]- NODE 13: Version = 4
2016-03-25 17:19:08.171 DEBUG o.o.b.z.i.p.s.IdentifyNodeMessageClass[:72]- NODE 13: FLIRS = false
2016-03-25 17:19:08.171 DEBUG o.o.b.z.i.p.s.IdentifyNodeMessageClass[:73]- NODE 13: Security = false
2016-03-25 17:19:08.171 DEBUG o.o.b.z.i.p.s.IdentifyNodeMessageClass[:74]- NODE 13: Max Baud = 40000
2016-03-25 17:19:08.172 DEBUG o.o.b.z.i.p.s.IdentifyNodeMessageClass[:89]- NODE 13: Basic = Controller
2016-03-25 17:19:08.172 DEBUG o.o.b.z.i.p.s.IdentifyNodeMessageClass[:96]- NODE 13: Generic = Remote Controller
2016-03-25 17:19:08.173 ERROR o.o.b.z.i.p.s.IdentifyNodeMessageClass[:100]- NODE 13: Specific device class 0x06 not found
2016-03-25 17:19:13.146 ERROR o.o.b.z.i.p.ZWaveController$ZWaveSendThread[:1309]- NODE 255: Timeout while sending message. Requeueing - 2 attempts left!
2016-03-25 17:19:13.147 DEBUG o.o.b.z.i.p.ZWaveController[:620]- Enqueueing message. Queue length = 1
2016-03-25 17:19:13.147 DEBUG o.o.b.z.i.p.ZWaveController$ZWaveSendThread[:1211]- Took message from queue for sending. Queue length = 0
2016-03-25 17:19:13.148 DEBUG o.o.b.z.i.p.SerialMessage[:233]- Assembled message buffer = 01 04 00 41 0D B7
2016-03-25 17:19:13.149 DEBUG o.o.b.z.i.p.ZWaveController$ZWaveSendThread[:1268]- NODE 255: Sending REQUEST Message = 01 04 00 41 0D B7
2016-03-25 17:19:13.153 DEBUG o.o.b.z.i.p.ZWaveController$ZWaveReceiveThread[:1481]- Receive Message = 01 09 01 41 13 96 01 01 01 06 34
2016-03-25 17:19:13.185 DEBUG o.o.b.z.i.p.ZWaveController$ZWaveReceiveThread[:1405]- Receive queue ADD: Length=1
2016-03-25 17:19:13.185 DEBUG o.o.b.z.i.p.ZWaveController[:1163]- Receive queue TAKE: Length=0
2016-03-25 17:19:13.187 DEBUG o.o.b.z.i.p.SerialMessage[:233]- Assembled message buffer = 01 09 01 41 13 96 01 01 01 06 34
2016-03-25 17:19:13.188 DEBUG o.o.b.z.i.p.ZWaveController[:1164]- Process Message = 01 09 01 41 13 96 01 01 01 06 34
2016-03-25 17:19:13.189 DEBUG o.o.b.z.i.p.ZWaveController[:192]- Message: class = IdentifyNode (0x41), type = Response (0x01), payload = 13 96 01 01 01 06
2016-03-25 17:19:13.190 DEBUG o.o.b.z.i.p.s.IdentifyNodeMessageClass[:52]- NODE 13: ProtocolInfo
2016-03-25 17:19:13.190 DEBUG o.o.b.z.i.p.s.IdentifyNodeMessageClass[:68]- NODE 13: Listening = false
2016-03-25 17:19:13.191 DEBUG o.o.b.z.i.p.s.IdentifyNodeMessageClass[:69]- NODE 13: Routing = false
2016-03-25 17:19:13.192 DEBUG o.o.b.z.i.p.s.IdentifyNodeMessageClass[:70]- NODE 13: Beaming = true
2016-03-25 17:19:13.192 DEBUG o.o.b.z.i.p.s.IdentifyNodeMessageClass[:71]- NODE 13: Version = 4
2016-03-25 17:19:13.193 DEBUG o.o.b.z.i.p.s.IdentifyNodeMessageClass[:72]- NODE 13: FLIRS = false
2016-03-25 17:19:13.193 DEBUG o.o.b.z.i.p.s.IdentifyNodeMessageClass[:73]- NODE 13: Security = false
2016-03-25 17:19:13.194 DEBUG o.o.b.z.i.p.s.IdentifyNodeMessageClass[:74]- NODE 13: Max Baud = 40000
2016-03-25 17:19:13.194 DEBUG o.o.b.z.i.p.s.IdentifyNodeMessageClass[:89]- NODE 13: Basic = Controller
2016-03-25 17:19:13.195 DEBUG o.o.b.z.i.p.s.IdentifyNodeMessageClass[:96]- NODE 13: Generic = Remote Controller
2016-03-25 17:19:13.196 ERROR o.o.b.z.i.p.s.IdentifyNodeMessageClass[:100]- NODE 13: Specific device class 0x06 not found
2016-03-25 17:19:18.165 ERROR o.o.b.z.i.p.ZWaveController$ZWaveSendThread[:1309]- NODE 255: Timeout while sending message. Requeueing - 1 attempts left!
2016-03-25 17:19:18.166 DEBUG o.o.b.z.i.p.ZWaveController[:620]- Enqueueing message. Queue length = 1
2016-03-25 17:19:18.167 DEBUG o.o.b.z.i.p.ZWaveController$ZWaveSendThread[:1211]- Took message from queue for sending.
SteMo
(Stefan)
March 26, 2016, 10:04pm
4
No ideas? I am not an expert for this log file but I can’t see an error. Why do this remote control not have a complete inclusion in habmin? I can try it again and again but always with the same result.
chris
(Chris Jackson)
March 26, 2016, 10:11pm
5
The node is included, but it’s not been woken up - at least not in this log. One issue that is likely to be a problem is the device class error - I need to look at this some more but haven’t had a chance yet.
SteMo
(Stefan)
March 27, 2016, 6:53pm
6
Hi chris,
thanks for the answer. Hope you finf an answer soon. If you need any further logs please contact me.
In the meantime I have excluded and included this device again but with the Erpert UI of zway. It’s fully included and I can programm it, but with zway only. In habmin I have only the grey node without the configuration and association chapter.
chris
(Chris Jackson)
March 27, 2016, 7:07pm
7
I’ve added the new device class this morning, so please try again tomorrow…
SteMo
(Stefan)
March 28, 2016, 7:09pm
8
Thank you,
but how to do it again. First exclude and then a new include procedure? Or different?
chris
(Chris Jackson)
March 28, 2016, 7:22pm
9
You just need to wake up the device and see if it gets any further with the initialisation…
SteMo
(Stefan)
March 28, 2016, 7:37pm
10
I tried it with the wake up, but no success.
Then I excluded it and included it again. Now I have a new node, but the problem still remains. Its still grey and has neither a configuration nor an association chapter.
chris
(Chris Jackson)
March 28, 2016, 8:15pm
11
Including again won’t make any difference. The issue is with the initialisation… Can you provide a new log from the new binding?
SteMo
(Stefan)
March 28, 2016, 8:47pm
12
Here the log. It’s the node 16:
2016-03-28 21:32:15.399 DEBUG o.o.b.z.i.p.s.AddNodeMessageClass[:83]- NODE 16: Adding controller.
2016-03-28 21:32:15.399 DEBUG o.o.b.z.i.p.ZWaveController[:635]- Notifying event listeners: ZWaveInclusionEvent
2016-03-28 21:32:15.400 DEBUG o.o.b.z.i.ZWaveActiveBinding[:433]- ZwaveIncomingEvent
2016-03-28 21:32:15.400 DEBUG o.o.b.z.i.p.s.ZWaveCommandProcessor[:63]- Sent message Message: class = AddNodeToNetwork (0x4A), type = Request (0x00), payload = 81 FF
2016-03-28 21:32:15.401 DEBUG o.o.b.z.i.p.s.ZWaveCommandProcessor[:64]- Recv message Message: class = AddNodeToNetwork (0x4A), type = Request (0x00), payload = FF 04 10 19 01 01 06 5E 8F 73 98 86 72 70 85 2D 8E 80 84 5A 59 5B EF 20 5B 26 27 2B 60
2016-03-28 21:32:15.402 DEBUG o.o.b.z.i.p.s.ZWaveCommandProcessor[:65]- Checking transaction complete: class=AddNodeToNetwork, expected=AddNodeToNetwork, cancelled=false
2016-03-28 21:32:15.403 DEBUG o.o.b.z.i.p.s.ZWaveCommandProcessor[:68]- transaction complete!
2016-03-28 21:32:15.403 DEBUG o.o.b.z.i.p.ZWaveController[:635]- Notifying event listeners: ZWaveTransactionCompletedEvent
2016-03-28 21:32:15.404 DEBUG o.o.b.z.i.ZWaveActiveBinding[:433]- ZwaveIncomingEvent
2016-03-28 21:32:16.358 DEBUG o.o.b.z.i.p.ZWaveController$ZWaveReceiveThread[:1481]- Receive Message = 01 07 00 4A FF 05 10 00 58
2016-03-28 21:32:16.373 DEBUG o.o.b.z.i.p.ZWaveController$ZWaveReceiveThread[:1405]- Receive queue ADD: Length=1
2016-03-28 21:32:16.374 DEBUG o.o.b.z.i.p.ZWaveController[:1163]- Receive queue TAKE: Length=0
2016-03-28 21:32:16.375 DEBUG o.o.b.z.i.p.SerialMessage[:233]- Assembled message buffer = 01 07 00 4A FF 05 10 00 58
2016-03-28 21:32:16.375 DEBUG o.o.b.z.i.p.ZWaveController[:1164]- Process Message = 01 07 00 4A FF 05 10 00 58
2016-03-28 21:32:16.376 DEBUG o.o.b.z.i.p.ZWaveController[:192]- Message: class = AddNodeToNetwork (0x4A), type = Request (0x00), payload = FF 05 10 00
2016-03-28 21:32:16.377 DEBUG o.o.b.z.i.p.s.AddNodeMessageClass[:87]- Add Node: Protocol done.
2016-03-28 21:32:16.377 DEBUG o.o.b.z.i.p.s.ZWaveCommandProcessor[:63]- Sent message Message: class = AddNodeToNetwork (0x4A), type = Request (0x00), payload = 81 FF
2016-03-28 21:32:16.378 DEBUG o.o.b.z.i.p.s.ZWaveCommandProcessor[:64]- Recv message Message: class = AddNodeToNetwork (0x4A), type = Request (0x00), payload = FF 05 10 00
2016-03-28 21:32:16.378 DEBUG o.o.b.z.i.p.s.ZWaveCommandProcessor[:65]- Checking transaction complete: class=AddNodeToNetwork, expected=AddNodeToNetwork, cancelled=false
2016-03-28 21:32:16.379 DEBUG o.o.b.z.i.p.s.ZWaveCommandProcessor[:68]- transaction complete!
2016-03-28 21:32:16.379 DEBUG o.o.b.z.i.p.ZWaveController[:635]- Notifying event listeners: ZWaveTransactionCompletedEvent
2016-03-28 21:32:16.380 DEBUG o.o.b.z.i.ZWaveActiveBinding[:433]- ZwaveIncomingEvent
2016-03-28 21:32:36.753 DEBUG o.o.b.z.i.c.ZWaveConfiguration[:1356]- Ending inclusion mode.
2016-03-28 21:32:36.754 DEBUG o.o.b.z.i.c.ZWaveConfiguration[:1379]- Stopping inclusion timer.
2016-03-28 21:32:36.755 DEBUG o.o.b.z.i.p.s.AddNodeMessageClass[:57]- Ending INCLUSION mode.
2016-03-28 21:32:36.756 DEBUG o.o.b.z.i.p.SerialMessage[:109]- NODE 255: Creating empty message of class = AddNodeToNetwork (0x4A), type = Request (0x00)
2016-03-28 21:32:36.756 DEBUG o.o.b.z.i.p.ZWaveController[:620]- Enqueueing message. Queue length = 1
2016-03-28 21:32:36.756 DEBUG o.o.b.z.i.p.ZWaveController$ZWaveSendThread[:1211]- Took message from queue for sending. Queue length = 0
2016-03-28 21:32:36.758 DEBUG o.o.b.z.i.p.SerialMessage[:233]- Assembled message buffer = 01 04 00 4A 05 B4
2016-03-28 21:32:36.759 DEBUG o.o.b.z.i.p.ZWaveController$ZWaveSendThread[:1268]- NODE 255: Sending REQUEST Message = 01 04 00 4A 05 B4
2016-03-28 21:32:37.054 DEBUG o.o.b.z.i.p.ZWaveController$ZWaveReceiveThread[:1481]- Receive Message = 01 07 00 4A FF 06 10 00 5B
2016-03-28 21:32:37.073 DEBUG o.o.b.z.i.p.ZWaveController$ZWaveReceiveThread[:1405]- Receive queue ADD: Length=1
2016-03-28 21:32:37.074 DEBUG o.o.b.z.i.p.ZWaveController[:1163]- Receive queue TAKE: Length=0
2016-03-28 21:32:37.075 DEBUG o.o.b.z.i.p.SerialMessage[:233]- Assembled message buffer = 01 07 00 4A FF 06 10 00 5B
2016-03-28 21:32:37.076 DEBUG o.o.b.z.i.p.ZWaveController[:1164]- Process Message = 01 07 00 4A FF 06 10 00 5B
2016-03-28 21:32:37.077 DEBUG o.o.b.z.i.p.ZWaveController[:192]- Message: class = AddNodeToNetwork (0x4A), type = Request (0x00), payload = FF 06 10 00
2016-03-28 21:32:37.078 DEBUG o.o.b.z.i.p.s.AddNodeMessageClass[:90]- Add Node: Done.
2016-03-28 21:32:37.079 DEBUG o.o.b.z.i.p.ZWaveController[:635]- Notifying event listeners: ZWaveInclusionEvent
2016-03-28 21:32:37.079 DEBUG o.o.b.z.i.ZWaveActiveBinding[:433]- ZwaveIncomingEvent
2016-03-28 21:32:37.080 DEBUG o.o.b.z.i.c.ZWaveConfiguration[:1379]- Stopping inclusion timer.
2016-03-28 21:32:37.081 ERROR o.o.b.z.i.c.ZWaveConfiguration[:1387]- Neither inclusion nor exclusion was active!
2016-03-28 21:32:37.081 DEBUG o.o.b.z.i.p.ZWaveController[:646]- NODE 16: Including node.
2016-03-28 21:32:37.083 DEBUG o.o.b.z.i.p.ZWaveController[:493]- NODE 16: Init node thread start
2016-03-28 21:32:37.083 DEBUG o.o.b.z.i.p.s.ZWaveCommandProcessor[:63]- Sent message Message: class = AddNodeToNetwork (0x4A), type = Request (0x00), payload = 05
2016-03-28 21:32:37.085 DEBUG o.o.b.z.i.p.s.ZWaveCommandProcessor[:64]- Recv message Message: class = AddNodeToNetwork (0x4A), type = Request (0x00), payload = FF 06 10 00
2016-03-28 21:32:37.086 DEBUG o.o.b.z.i.p.s.ZWaveCommandProcessor[:65]- Checking transaction complete: class=AddNodeToNetwork, expected=AddNodeToNetwork, cancelled=false
2016-03-28 21:32:37.087 DEBUG o.o.b.z.i.p.s.ZWaveCommandProcessor[:68]- transaction complete!
2016-03-28 21:32:37.088 DEBUG o.o.b.z.i.p.ZWaveController[:635]- Notifying event listeners: ZWaveTransactionCompletedEvent
2016-03-28 21:32:37.089 DEBUG o.o.b.z.i.ZWaveActiveBinding[:433]- ZwaveIncomingEvent
2016-03-28 21:32:37.090 DEBUG o.o.b.z.i.p.ZWaveController$ZWaveSendThread[:1327]- NODE 255: Response processed after 331ms/479ms.
2016-03-28 21:32:37.118 DEBUG o.o.b.z.i.p.i.ZWaveNodeSerializer[:138]- NODE 16: Serializing from file /var/lib/openhab/zwave/node16.xml
2016-03-28 21:32:37.119 DEBUG o.o.b.z.i.p.i.ZWaveNodeSerializer[:141]- NODE 16: Error serializing from file: file does not exist.
2016-03-28 21:32:37.121 DEBUG o.o.b.z.i.p.i.ZWaveNodeStageAdvancer[:269]- NODE 16: Node advancer - EMPTYNODE: queue length(0), free to send(true)
2016-03-28 21:32:37.122 DEBUG o.o.b.z.i.p.i.ZWaveNodeStageAdvancer[:335]- NODE 16: Node advancer: loop - EMPTYNODE try 1: stageAdvanced(false)
2016-03-28 21:32:37.122 DEBUG o.o.b.z.i.p.i.ZWaveNodeStageAdvancer[:340]- NODE 16: Node advancer: Initialisation starting
2016-03-28 21:32:37.123 DEBUG o.o.b.z.i.p.i.ZWaveNodeStageAdvancer[:868]- NODE 16: Node advancer - advancing to PROTOINFO
2016-03-28 21:32:37.124 DEBUG o.o.b.z.i.p.i.ZWaveNodeStageAdvancer[:335]- NODE 16: Node advancer: loop - PROTOINFO try 0: stageAdvanced(true)
2016-03-28 21:32:37.125 DEBUG o.o.b.z.i.p.i.ZWaveNodeStageAdvancer[:349]- NODE 16: Node advancer: PROTOINFO - send IdentifyNode
2016-03-28 21:32:37.126 DEBUG o.o.b.z.i.p.SerialMessage[:109]- NODE 255: Creating empty message of class = IdentifyNode (0x41), type = Request (0x00)
2016-03-28 21:32:37.127 DEBUG o.o.b.z.i.p.ZWaveController[:620]- Enqueueing message. Queue length = 1
2016-03-28 21:32:37.127 DEBUG o.o.b.z.i.p.ZWaveController$ZWaveSendThread[:1211]- Took message from queue for sending. Queue length = 0
2016-03-28 21:32:37.127 DEBUG o.o.b.z.i.p.i.ZWaveNodeStageAdvancer[:242]- NODE 16: Node advancer - queued packet. Queue length is 1
2016-03-28 21:32:37.128 DEBUG o.o.b.z.i.p.SerialMessage[:233]- Assembled message buffer = 01 04 00 41 10 AA
2016-03-28 21:32:37.129 DEBUG o.o.b.z.i.p.ZWaveController[:585]- NODE 16: Init node thread finished
2016-03-28 21:32:37.129 DEBUG o.o.b.z.i.p.ZWaveController$ZWaveSendThread[:1268]- NODE 255: Sending REQUEST Message = 01 04 00 41 10 AA
2016-03-28 21:32:37.137 DEBUG o.o.b.z.i.p.ZWaveController$ZWaveReceiveThread[:1481]- Receive Message = 01 09 01 41 13 96 01 01 01 06 34
2016-03-28 21:32:37.173 DEBUG o.o.b.z.i.p.ZWaveController$ZWaveReceiveThread[:1405]- Receive queue ADD: Length=1
2016-03-28 21:32:37.174 DEBUG o.o.b.z.i.p.ZWaveController[:1163]- Receive queue TAKE: Length=0
2016-03-28 21:32:37.175 DEBUG o.o.b.z.i.p.SerialMessage[:233]- Assembled message buffer = 01 09 01 41 13 96 01 01 01 06 34
2016-03-28 21:32:37.176 DEBUG o.o.b.z.i.p.ZWaveController[:1164]- Process Message = 01 09 01 41 13 96 01 01 01 06 34
2016-03-28 21:32:37.176 DEBUG o.o.b.z.i.p.ZWaveController[:192]- Message: class = IdentifyNode (0x41), type = Response (0x01), payload = 13 96 01 01 01 06
2016-03-28 21:32:37.177 DEBUG o.o.b.z.i.p.s.IdentifyNodeMessageClass[:52]- NODE 16: ProtocolInfo
2016-03-28 21:32:37.177 DEBUG o.o.b.z.i.p.s.IdentifyNodeMessageClass[:68]- NODE 16: Listening = false
2016-03-28 21:32:37.178 DEBUG o.o.b.z.i.p.s.IdentifyNodeMessageClass[:69]- NODE 16: Routing = false
2016-03-28 21:32:37.178 DEBUG o.o.b.z.i.p.s.IdentifyNodeMessageClass[:70]- NODE 16: Beaming = true
2016-03-28 21:32:37.178 DEBUG o.o.b.z.i.p.s.IdentifyNodeMessageClass[:71]- NODE 16: Version = 4
2016-03-28 21:32:37.179 DEBUG o.o.b.z.i.p.s.IdentifyNodeMessageClass[:72]- NODE 16: FLIRS = false
2016-03-28 21:32:37.179 DEBUG o.o.b.z.i.p.s.IdentifyNodeMessageClass[:73]- NODE 16: Security = false
2016-03-28 21:32:37.179 DEBUG o.o.b.z.i.p.s.IdentifyNodeMessageClass[:74]- NODE 16: Max Baud = 40000
2016-03-28 21:32:37.180 DEBUG o.o.b.z.i.p.s.IdentifyNodeMessageClass[:89]- NODE 16: Basic = Controller
2016-03-28 21:32:37.180 DEBUG o.o.b.z.i.p.s.IdentifyNodeMessageClass[:96]- NODE 16: Generic = Remote Controller
2016-03-28 21:32:37.181 ERROR o.o.b.z.i.p.s.IdentifyNodeMessageClass[:100]- NODE 16: Specific device class 0x06 not found
2016-03-28 21:32:42.153 ERROR o.o.b.z.i.p.ZWaveController$ZWaveSendThread[:1309]- NODE 255: Timeout while sending message. Requeueing - 2 attempts left!
2016-03-28 21:32:42.154 DEBUG o.o.b.z.i.p.ZWaveController[:620]- Enqueueing message. Queue length = 1
2016-03-28 21:32:42.155 DEBUG o.o.b.z.i.p.ZWaveController$ZWaveSendThread[:1211]- Took message from queue for sending. Queue length = 0
2016-03-28 21:32:42.156 DEBUG o.o.b.z.i.p.SerialMessage[:233]- Assembled message buffer = 01 04 00 41 10 AA
and I tried it with the wake up:
2016-03-28 21:32:42.196 DEBUG o.o.b.z.i.p.ZWaveController[:192]- Message: class = IdentifyNode (0x41), type = Response (0x01), payload = 13 96 01 01 01 06
2016-03-28 21:32:42.197 DEBUG o.o.b.z.i.p.s.IdentifyNodeMessageClass[:52]- NODE 16: ProtocolInfo
2016-03-28 21:32:42.197 DEBUG o.o.b.z.i.p.s.IdentifyNodeMessageClass[:68]- NODE 16: Listening = false
2016-03-28 21:32:42.197 DEBUG o.o.b.z.i.p.s.IdentifyNodeMessageClass[:69]- NODE 16: Routing = false
2016-03-28 21:32:42.198 DEBUG o.o.b.z.i.p.s.IdentifyNodeMessageClass[:70]- NODE 16: Beaming = true
2016-03-28 21:32:42.198 DEBUG o.o.b.z.i.p.s.IdentifyNodeMessageClass[:71]- NODE 16: Version = 4
2016-03-28 21:32:42.199 DEBUG o.o.b.z.i.p.s.IdentifyNodeMessageClass[:72]- NODE 16: FLIRS = false
2016-03-28 21:32:42.199 DEBUG o.o.b.z.i.p.s.IdentifyNodeMessageClass[:73]- NODE 16: Security = false
2016-03-28 21:32:42.199 DEBUG o.o.b.z.i.p.s.IdentifyNodeMessageClass[:74]- NODE 16: Max Baud = 40000
2016-03-28 21:32:42.200 DEBUG o.o.b.z.i.p.s.IdentifyNodeMessageClass[:89]- NODE 16: Basic = Controller
2016-03-28 21:32:42.200 DEBUG o.o.b.z.i.p.s.IdentifyNodeMessageClass[:96]- NODE 16: Generic = Remote Controller
2016-03-28 21:32:42.201 ERROR o.o.b.z.i.p.s.IdentifyNodeMessageClass[:100]- NODE 16: Specific device class 0x06 not found
2016-03-28 21:32:47.173 ERROR o.o.b.z.i.p.ZWaveController$ZWaveSendThread[:1309]- NODE 255: Timeout while sending message. Requeueing - 1 attempts left!
2016-03-28 21:32:47.175 DEBUG o.o.b.z.i.p.ZWaveController[:620]- Enqueueing message. Queue length = 1
2016-03-28 21:32:47.175 DEBUG o.o.b.z.i.p.ZWaveController$ZWaveSendThread[:1211]- Took message from queue for sending. Queue length = 0
2016-03-28 21:32:47.176 DEBUG o.o.b.z.i.p.SerialMessage[:233]- Assembled message buffer = 01 04 00 41 10 AA
2016-03-28 21:32:47.177 DEBUG o.o.b.z.i.p.ZWaveController$ZWaveSendThread[:1268]- NODE 255: Sending REQUEST Message = 01 04 00 41 10 AA
2016-03-28 21:32:47.181 DEBUG o.o.b.z.i.p.ZWaveController$ZWaveReceiveThread[:1481]- Receive Message = 01 09 01 41 13 96 01 01 01 06 34
2016-03-28 21:32:47.213 DEBUG o.o.b.z.i.p.ZWaveController$ZWaveReceiveThread[:1405]- Receive queue ADD: Length=1
2016-03-28 21:32:47.213 DEBUG o.o.b.z.i.p.ZWaveController[:1163]- Receive queue TAKE: Length=0
2016-03-28 21:32:47.215 DEBUG o.o.b.z.i.p.SerialMessage[:233]- Assembled message buffer = 01 09 01 41 13 96 01 01 01 06 34
2016-03-28 21:32:47.216 DEBUG o.o.b.z.i.p.ZWaveController[:1164]- Process Message = 01 09 01 41 13 96 01 01 01 06 34
2016-03-28 21:32:47.217 DEBUG o.o.b.z.i.p.ZWaveController[:192]- Message: class = IdentifyNode (0x41), type = Response (0x01), payload = 13 96 01 01 01 06
2016-03-28 21:32:47.218 DEBUG o.o.b.z.i.p.s.IdentifyNodeMessageClass[:52]- NODE 16: ProtocolInfo
2016-03-28 21:32:47.219 DEBUG o.o.b.z.i.p.s.IdentifyNodeMessageClass[:68]- NODE 16: Listening = false
2016-03-28 21:32:47.219 DEBUG o.o.b.z.i.p.s.IdentifyNodeMessageClass[:69]- NODE 16: Routing = false
2016-03-28 21:32:47.220 DEBUG o.o.b.z.i.p.s.IdentifyNodeMessageClass[:70]- NODE 16: Beaming = true
2016-03-28 21:32:47.220 DEBUG o.o.b.z.i.p.s.IdentifyNodeMessageClass[:71]- NODE 16: Version = 4
2016-03-28 21:32:47.221 DEBUG o.o.b.z.i.p.s.IdentifyNodeMessageClass[:72]- NODE 16: FLIRS = false
2016-03-28 21:32:47.221 DEBUG o.o.b.z.i.p.s.IdentifyNodeMessageClass[:73]- NODE 16: Security = false
2016-03-28 21:32:47.222 DEBUG o.o.b.z.i.p.s.IdentifyNodeMessageClass[:74]- NODE 16: Max Baud = 40000
2016-03-28 21:32:47.223 DEBUG o.o.b.z.i.p.s.IdentifyNodeMessageClass[:89]- NODE 16: Basic = Controller
2016-03-28 21:32:47.223 DEBUG o.o.b.z.i.p.s.IdentifyNodeMessageClass[:96]- NODE 16: Generic = Remote Controller
2016-03-28 21:32:47.224 ERROR o.o.b.z.i.p.s.IdentifyNodeMessageClass[:100]- NODE 16: Specific device class 0x06 not found
2016-03-28 21:32:52.194 ERROR o.o.b.z.i.p.ZWaveController$ZWaveSendThread[:1309]- NODE 255: Timeout while sending message. Requeueing - 0 attempts left!
2016-03-28 21:32:52.194 DEBUG o.o.b.z.i.p.ZWaveController[:620]- Enqueueing message. Queue length = 1
2016-03-28 21:32:52.195 DEBUG o.o.b.z.i.p.ZWaveController$ZWaveSendThread[:1211]- Took message from queue for sending. Queue length = 0
2016-03-28 21:32:52.196 DEBUG o.o.b.z.i.p.SerialMessage[:233]- Assembled message buffer = 01 04 00 41 10 AA
chris
(Chris Jackson)
March 28, 2016, 10:02pm
13
Strange - I’m still seeing the same error. Maybe I screwed up, but it looks like it’s ok. Can you confirm the version of the binding you’re running (it’s printed in the log when the binding starts) - is this the latest from Cloudbees?
SteMo
(Stefan)
March 29, 2016, 10:54am
14
My version is:
2016-03-29 12:53:16.470 DEBUG o.o.b.z.i.ZWaveActivator[:36]- Z-Wave binding started. Version 1.8.1
Is there a newer version abailable?
chris
(Chris Jackson)
March 29, 2016, 10:57am
15
Yes - you need to use a new version. If I make changes to the binding, it won’t update the version that you have on your machine unless you update the file
You should use the nightly build from cloudbees .
SteMo
(Stefan)
March 29, 2016, 11:14am
16
Ok, sorry, but I am still learning.
Now I used the version 1.9.0.201603290113 and it runs.
Many thanks for your support.
SteMo
(Stefan)
March 29, 2016, 11:21am
17
Ups, I was to fast.
The chapter Configuration and Assoziation Groups etc. are there, but without any content.
I woke up the device several times. What can I do now? Do I have to change more then the .jar file?
The “/var/lib/openhab/zwave/node16.xml” do not exist!
Here the log.
2016-03-29 13:04:56.445 INFO o.o.b.z.i.p.s.SerialApiGetInitDataMessageClass[:60]- NODE 1: Node found
2016-03-29 13:04:56.447 INFO o.o.b.z.i.p.s.SerialApiGetInitDataMessageClass[:60]- NODE 2: Node found
2016-03-29 13:04:56.448 INFO o.o.b.z.i.p.s.SerialApiGetInitDataMessageClass[:60]- NODE 4: Node found
2016-03-29 13:04:56.449 INFO o.o.b.z.i.p.s.SerialApiGetInitDataMessageClass[:60]- NODE 5: Node found
2016-03-29 13:04:56.450 INFO o.o.b.z.i.p.s.SerialApiGetInitDataMessageClass[:60]- NODE 6: Node found
2016-03-29 13:04:56.451 INFO o.o.b.z.i.p.s.SerialApiGetInitDataMessageClass[:60]- NODE 8: Node found
2016-03-29 13:04:56.452 INFO o.o.b.z.i.p.s.SerialApiGetInitDataMessageClass[:60]- NODE 10: Node found
2016-03-29 13:04:56.453 INFO o.o.b.z.i.p.s.SerialApiGetInitDataMessageClass[:60]- NODE 15: Node found
2016-03-29 13:04:56.454 INFO o.o.b.z.i.p.s.SerialApiGetInitDataMessageClass[:60]- NODE 16: Node found
2016-03-29 13:04:56.454 INFO o.o.b.z.i.p.s.SerialApiGetInitDataMessageClass[:60]- NODE 17: Node found
2016-03-29 13:04:56.455 INFO o.o.b.z.i.p.s.SerialApiGetInitDataMessageClass[:60]- NODE 18: Node found
2016-03-29 13:04:56.456 INFO o.o.b.z.i.p.s.SerialApiGetInitDataMessageClass[:60]- NODE 21: Node found
2016-03-29 13:04:56.457 INFO o.o.b.z.i.p.s.SerialApiGetInitDataMessageClass[:60]- NODE 22: Node found
2016-03-29 13:04:56.458 INFO o.o.b.z.i.p.s.SerialApiGetInitDataMessageClass[:60]- NODE 24: Node found
2016-03-29 13:04:56.460 INFO o.o.b.z.i.p.s.SerialApiGetInitDataMessageClass[:68]- ZWave Controller using Controller API
2016-03-29 13:04:56.461 INFO o.o.b.z.i.p.s.SerialApiGetInitDataMessageClass[:70]- ZWave Controller is Primary Controller
2016-03-29 13:04:56.462 INFO o.o.b.z.i.p.s.SerialApiGetInitDataMessageClass[:72]- ------------Number of Nodes Found Registered to ZWave Controller------------
2016-03-29 13:04:56.463 INFO o.o.b.z.i.p.s.SerialApiGetInitDataMessageClass[:73]- # Nodes = 14
.
2016-03-29 13:04:56.488 DEBUG o.o.b.z.i.p.ZWaveController[:506]- NODE 15: Init node thread start
2016-03-29 13:04:56.490 DEBUG o.o.b.z.i.p.ZWaveController[:506]- NODE 16: Init node thread start
2016-03-29 13:04:56.493 DEBUG o.o.b.z.i.p.ZWaveController[:506]- NODE 17: Init node thread start
.
2016-03-29 13:04:58.347 DEBUG o.o.b.z.i.p.i.ZWaveNodeSerializer[:138]- NODE 6: Serializing from file /var/lib/openhab/zwave/node6.xml
2016-03-29 13:04:58.346 DEBUG o.o.b.z.i.p.i.ZWaveNodeSerializer[:138]- NODE 16: Serializing from file /var/lib/openhab/zwave/node16.xml
2016-03-29 13:04:58.346 DEBUG o.o.b.z.i.p.i.ZWaveNodeSerializer[:138]- NODE 4: Serializing from file /var/lib/openhab/zwave/node4.xml
.
2016-03-29 13:04:58.475 DEBUG o.o.b.z.i.p.i.ZWaveNodeStageAdvancer[:267]- NODE 16: Node advancer - EMPTYNODE: queue length(0), free to send(true)
2016-03-29 13:04:58.477 DEBUG o.o.b.z.i.p.i.ZWaveNodeStageAdvancer[:332]- NODE 16: Node advancer: loop - EMPTYNODE try 1: stageAdvanced(false)
2016-03-29 13:04:58.484 DEBUG o.o.b.z.i.p.i.ZWaveNodeStageAdvancer[:337]- NODE 16: Node advancer: Initialisation starting
2016-03-29 13:04:58.486 DEBUG o.o.b.z.i.p.i.ZWaveNodeStageAdvancer[:890]- NODE 16: Node advancer - advancing to PROTOINFO
2016-03-29 13:04:58.496 DEBUG o.o.b.z.i.p.i.ZWaveNodeStageAdvancer[:332]- NODE 16: Node advancer: loop - PROTOINFO try 0: stageAdvanced(true)
2016-03-29 13:04:58.498 DEBUG o.o.b.z.i.p.i.ZWaveNodeStageAdvancer[:346]- NODE 16: Node advancer: PROTOINFO - send IdentifyNode
2016-03-29 13:04:58.502 DEBUG o.o.b.z.i.p.SerialMessage[:113]- NODE 255: Creating empty message of class = IdentifyNode (0x41), type = Request (0x00)
2016-03-29 13:04:58.504 DEBUG o.o.b.z.i.p.ZWaveController$ZWaveSendThread[:1241]- Took message from queue for sending. Queue length = 0
2016-03-29 13:04:58.506 DEBUG o.o.b.z.i.p.SerialMessage[:243]- Assembled message buffer = 01 04 00 41 10 AA
2016-03-29 13:04:58.507 DEBUG o.o.b.z.i.p.ZWaveController$ZWaveSendThread[:1301]- NODE 255: Sending REQUEST Message = 01 04 00 41 10 AA
2016-03-29 13:04:58.509 DEBUG o.o.b.z.i.p.ZWaveController[:632]- Enqueueing message. Queue length = 0
.
2016-03-29 13:04:58.574 DEBUG o.o.b.z.i.p.ZWaveController[:194]- Message: class = IdentifyNode (0x41), type = Response (0x01), payload = 13 96 01 01 01 06
2016-03-29 13:04:58.575 DEBUG o.o.b.z.i.p.s.IdentifyNodeMessageClass[:56]- NODE 16: ProtocolInfo
2016-03-29 13:04:58.581 DEBUG o.o.b.z.i.p.s.IdentifyNodeMessageClass[:72]- NODE 16: Listening = false
2016-03-29 13:04:58.582 DEBUG o.o.b.z.i.p.s.IdentifyNodeMessageClass[:73]- NODE 16: Routing = false
2016-03-29 13:04:58.583 DEBUG o.o.b.z.i.p.s.IdentifyNodeMessageClass[:74]- NODE 16: Beaming = true
2016-03-29 13:04:58.584 DEBUG o.o.b.z.i.p.s.IdentifyNodeMessageClass[:75]- NODE 16: Version = 4
2016-03-29 13:04:58.584 DEBUG o.o.b.z.i.p.s.IdentifyNodeMessageClass[:76]- NODE 16: FLIRS = false
2016-03-29 13:04:58.585 DEBUG o.o.b.z.i.p.s.IdentifyNodeMessageClass[:77]- NODE 16: Security = false
2016-03-29 13:04:58.622 DEBUG o.o.b.z.i.p.s.IdentifyNodeMessageClass[:78]- NODE 16: Max Baud = 40000
2016-03-29 13:04:58.623 DEBUG o.o.b.z.i.p.s.IdentifyNodeMessageClass[:94]- NODE 16: Basic = Controller
2016-03-29 13:04:58.625 DEBUG o.o.b.z.i.p.s.IdentifyNodeMessageClass[:102]- NODE 16: Generic = Remote Controller
2016-03-29 13:04:58.627 ERROR o.o.b.z.i.p.s.IdentifyNodeMessageClass[:106]- NODE 16: Specific device class 0x06 not found
2016-03-29 13:04:58.620 DEBUG o.o.b.z.i.p.ZWaveController[:539]- NODE 1: Restore from config: Ok.
2016-03-29 13:04:58.656 DEBUG o.o.b.z.i.p.i.ZWaveNodeStageAdvancer[:267]- NODE 1: Node advancer - EMPTYNODE: queue length(0), free to send(true)
chris
(Chris Jackson)
March 29, 2016, 11:39am
18
Sorry - my mistake this time. I’ve put the new class in the wrong place . I’ll update this later today and you can try again tomorrow…
SteMo
(Stefan)
March 30, 2016, 6:48pm
19
Hey chris,
has you already updated it? I woke up my KFOB but with the same result. No configuration or assoziation content.
The file /var/lib/openhab/zwave/node16.xml exists.
Here the log:
2016-03-30 02:00:08.881 DEBUG o.o.b.z.i.ZWaveNetworkMonitor[:295]- NODE 16: Initialisation NOT yet complete. Skipping heal.
2016-03-30 20:22:49.811 INFO o.o.b.z.i.p.s.SerialApiGetInitDataMessageClass[:60]- NODE 16: Node found
2016-03-30 20:22:49.833 DEBUG o.o.b.z.i.p.ZWaveController[:506]- NODE 16: Init node thread start
2016-03-30 20:22:50.863 DEBUG o.o.b.z.i.p.i.ZWaveNodeSerializer[:138]- NODE 16: Serializing from file /var/lib/openhab/zwave/node16.xml
2016-03-30 20:22:50.887 DEBUG o.o.b.z.i.p.i.ZWaveNodeSerializer[:141]- NODE 16: Error serializing from file: file does not exist.
2016-03-30 20:22:51.002 DEBUG o.o.b.z.i.p.i.ZWaveNodeStageAdvancer[:267]- NODE 16: Node advancer - EMPTYNODE: queue length(0), free to send(true)
2016-03-30 20:22:51.004 DEBUG o.o.b.z.i.p.i.ZWaveNodeStageAdvancer[:332]- NODE 16: Node advancer: loop - EMPTYNODE try 1: stageAdvanced(false)
2016-03-30 20:22:51.018 DEBUG o.o.b.z.i.p.ZWaveController$ZWaveSendThread[:1301]- NODE 255: Sending REQUEST Message = 01 04 00 41 11 AB
2016-03-30 20:22:51.015 DEBUG o.o.b.z.i.p.i.ZWaveNodeStageAdvancer[:337]- NODE 16: Node advancer: Initialisation starting
2016-03-30 20:22:51.028 DEBUG o.o.b.z.i.p.i.ZWaveNodeStageAdvancer[:890]- NODE 16: Node advancer - advancing to PROTOINFO
2016-03-30 20:22:51.028 DEBUG o.o.b.z.i.p.i.ZWaveNodeStageAdvancer[:332]- NODE 16: Node advancer: loop - PROTOINFO try 0: stageAdvanced(true)
2016-03-30 20:22:51.029 DEBUG o.o.b.z.i.p.i.ZWaveNodeStageAdvancer[:346]- NODE 16: Node advancer: PROTOINFO - send IdentifyNode
2016-03-30 20:22:51.034 DEBUG o.o.b.z.i.p.SerialMessage[:113]- NODE 255: Creating empty message of class = IdentifyNode (0x41), type = Request (0x00)
2016-03-30 20:22:51.035 DEBUG o.o.b.z.i.p.ZWaveController[:632]- Enqueueing message. Queue length = 1
2016-03-30 20:22:51.036 DEBUG o.o.b.z.i.p.i.ZWaveNodeStageAdvancer[:240]- NODE 16: Node advancer - queued packet. Queue length is 1
2016-03-30 20:22:51.037 DEBUG o.o.b.z.i.p.ZWaveController[:595]- NODE 16: Init node thread finished
2016-03-30 20:22:51.012 DEBUG o.o.b.z.i.p.SerialMessage[:113]- NODE 255: Creating empty message of class = IdentifyNode (0x41), type = Request (0x00)
2016-03-30 20:22:51.045 DEBUG o.o.b.z.i.p.ZWaveController[:632]- Enqueueing message. Queue length = 2
2016-03-30 20:22:51.285 DEBUG o.o.b.z.i.p.ZWaveController[:194]- Message: class = IdentifyNode (0x41), type = Response (0x01), payload = 13 96 01 01 01 06
2016-03-30 20:22:51.286 DEBUG o.o.b.z.i.p.s.IdentifyNodeMessageClass[:56]- NODE 16: ProtocolInfo
2016-03-30 20:22:51.287 DEBUG o.o.b.z.i.p.s.IdentifyNodeMessageClass[:72]- NODE 16: Listening = false
2016-03-30 20:22:51.288 DEBUG o.o.b.z.i.p.s.IdentifyNodeMessageClass[:73]- NODE 16: Routing = false
2016-03-30 20:22:51.289 DEBUG o.o.b.z.i.p.s.IdentifyNodeMessageClass[:74]- NODE 16: Beaming = true
2016-03-30 20:22:51.290 DEBUG o.o.b.z.i.p.s.IdentifyNodeMessageClass[:75]- NODE 16: Version = 4
2016-03-30 20:22:51.299 DEBUG o.o.b.z.i.p.s.IdentifyNodeMessageClass[:76]- NODE 16: FLIRS = false
2016-03-30 20:22:51.300 DEBUG o.o.b.z.i.p.s.IdentifyNodeMessageClass[:77]- NODE 16: Security = false
2016-03-30 20:22:51.310 DEBUG o.o.b.z.i.p.s.IdentifyNodeMessageClass[:78]- NODE 16: Max Baud = 40000
2016-03-30 20:22:51.311 DEBUG o.o.b.z.i.p.s.IdentifyNodeMessageClass[:94]- NODE 16: Basic = Controller
2016-03-30 20:22:51.311 DEBUG o.o.b.z.i.p.s.IdentifyNodeMessageClass[:102]- NODE 16: Generic = Remote Controller
2016-03-30 20:22:51.312 ERROR o.o.b.z.i.p.s.IdentifyNodeMessageClass[:106]- NODE 16: Specific device class 0x06 not found
2016-03-30 20:24:47.272 INFO o.o.b.z.i.p.s.SerialApiGetInitDataMessageClass[:60]- NODE 16: Node found
…
chris
(Chris Jackson)
March 30, 2016, 6:59pm
20
Yes - it should have been updated this morning. I’m not sure now why it wouldn’t pick up the new device class. Is there anything else in the log around this point? I expected to see the complete recieve packet being logged?