Aeon Labs ZW056 doorbell

djarvis8 · August 11, 2016, 4:23pm

Hi!

I am relatively new to OpenHAB but so far I’ve managed to get it set up on my Raspberry Pi. Got a handful of items, sitemaps, and some basic rules to get my feet wet.

I have the Aeon Labs ZW056 doorbell device. I’m not going to actually use the doorbell sender part of it, I just want to use the speaker part as a “chime” for different events (door open, motion triggered, etc). I found online how to issue some commands to set the volume, repetitions, etc. So I have this in my items:

Number Doorbell_Switch_Control “Doorbell Control [%d]” { zwave=“8:command=configuration,parameter=7” }

Which I can use to play a sound on the speaker. I call this from a rule:

sendCommand(Doorbell_Switch_Control, 4)

And it does seem to work, kinda. It seems to play the sound 3-4 times in rapid succession, and then for a good 10-20 seconds later I’m unable to send another command. I notice some timeouts in the zwave.log below. For some reason it seems it’s waiting for a response, not getting it, and feels it needs to retry the command?

Any help is appreciated. Can I set this up to just ignore checking for responses? I am running org.openhab.binding.zwave-1.9.0-SNAPSHOT.jar.

2016-08-11 11:57:28.066 [DEBUG] [ZWaveConfigurationCommandClass:183 ]- NODE 8: Creating new message for application command CONFIGURATIONCMD_SET
2016-08-11 11:57:28.067 [DEBUG] [o.b.z.i.protocol.SerialMessage:113 ]- NODE 8: Creating empty message of class = SendData (0x13), type = Request (0x00)
2016-08-11 11:57:28.070 [DEBUG] [ZWaveConfigurationCommandClass:160 ]- NODE 8: Creating new message for application command CONFIGURATIONCMD_GET
2016-08-11 11:57:28.072 [DEBUG] [o.b.z.i.protocol.SerialMessage:113 ]- NODE 8: Creating empty message of class = SendData (0x13), type = Request (0x00)
2016-08-11 11:57:28.074 [DEBUG] [WaveController$ZWaveSendThread:1301]- NODE 8: Sending REQUEST Message = 01 0C 00 13 08 05 70 04 06 01 04 25 3E 81
2016-08-11 11:57:28.087 [DEBUG] [b.z.i.p.s.SendDataMessageClass:38 ]- NODE 8: Sent Data successfully placed on stack.
2016-08-11 11:57:29.935 [DEBUG] [b.z.i.p.s.SendDataMessageClass:73 ]- NODE 8: SendData Request. CallBack ID = 62, Status = Transmission complete and ACK received(0)
2016-08-11 11:57:29.939 [DEBUG] [WaveController$ZWaveSendThread:1364]- NODE 8: Response processed after 1864ms/2010ms.
2016-08-11 11:57:29.941 [DEBUG] [WaveController$ZWaveSendThread:1301]- NODE 8: Sending REQUEST Message = 01 0A 00 13 08 03 70 05 06 25 3F 84
2016-08-11 11:57:29.953 [DEBUG] [b.z.i.p.s.SendDataMessageClass:38 ]- NODE 8: Sent Data successfully placed on stack.
2016-08-11 11:57:30.012 [DEBUG] [b.z.i.p.s.SendDataMessageClass:73 ]- NODE 8: SendData Request. CallBack ID = 63, Status = Transmission complete and ACK received(0)
2016-08-11 11:57:34.942 [ERROR] [WaveController$ZWaveSendThread:1347]- NODE 8: Timeout while sending message. Requeueing - 2 attempts left!
2016-08-11 11:57:34.944 [ERROR] [b.z.i.p.s.SendDataMessageClass:158 ]- NODE 8: Got an error while sending data. Resending message.
2016-08-11 11:57:34.949 [DEBUG] [WaveController$ZWaveSendThread:1301]- NODE 8: Sending REQUEST Message = 01 0A 00 13 08 03 70 05 06 25 41 FA
2016-08-11 11:57:34.964 [DEBUG] [b.z.i.p.s.SendDataMessageClass:38 ]- NODE 8: Sent Data successfully placed on stack.
2016-08-11 11:57:35.117 [DEBUG] [b.z.i.p.s.SendDataMessageClass:73 ]- NODE 8: SendData Request. CallBack ID = 65, Status = Transmission complete and ACK received(0)
2016-08-11 11:57:39.951 [ERROR] [WaveController$ZWaveSendThread:1347]- NODE 8: Timeout while sending message. Requeueing - 1 attempts left!
2016-08-11 11:57:39.960 [ERROR] [b.z.i.p.s.SendDataMessageClass:158 ]- NODE 8: Got an error while sending data. Resending message.
2016-08-11 11:57:39.965 [DEBUG] [WaveController$ZWaveSendThread:1301]- NODE 8: Sending REQUEST Message = 01 0A 00 13 08 03 70 05 06 25 42 F9
2016-08-11 11:57:39.981 [DEBUG] [b.z.i.p.s.SendDataMessageClass:38 ]- NODE 8: Sent Data successfully placed on stack.
2016-08-11 11:57:40.039 [DEBUG] [b.z.i.p.s.SendDataMessageClass:73 ]- NODE 8: SendData Request. CallBack ID = 66, Status = Transmission complete and ACK received(0)
2016-08-11 11:57:44.967 [ERROR] [WaveController$ZWaveSendThread:1347]- NODE 8: Timeout while sending message. Requeueing - 0 attempts left!
2016-08-11 11:57:44.974 [ERROR] [b.z.i.p.s.SendDataMessageClass:158 ]- NODE 8: Got an error while sending data. Resending message.
2016-08-11 11:57:44.977 [DEBUG] [WaveController$ZWaveSendThread:1301]- NODE 8: Sending REQUEST Message = 01 0A 00 13 08 03 70 05 06 25 43 F8
2016-08-11 11:57:44.989 [DEBUG] [b.z.i.p.s.SendDataMessageClass:38 ]- NODE 8: Sent Data successfully placed on stack.
2016-08-11 11:57:45.056 [DEBUG] [b.z.i.p.s.SendDataMessageClass:73 ]- NODE 8: SendData Request. CallBack ID = 67, Status = Transmission complete and ACK received(0)
2016-08-11 11:57:49.978 [WARN ] [WaveController$ZWaveSendThread:1355]- NODE 8: Too many retries. Discarding message: Message: class = SendData (0x13), type = Request (0x00), payload = 08 03 70 05 06

chris · August 11, 2016, 4:30pm

Yes - this is the way the system works. It will try 3 times as it assumes that you sent this command for a reason, and if it’s not being received by the device, it should try and resend.

You seem to be sending the command to set the tone multiple times in quick succession which is presumably why it is playing it multiple times?

Timeouts are normally caused by poor communication - maybe the device is on the edge of the distance for this device or maybe the multiple commands is causing it to lock up?

(ps please format logs by highlighting the data and using the </> button to format - it makes it much easier to read and process - thanks).

djarvis8 · August 11, 2016, 4:46pm

Ok. I’ll try with the speaker dead next to my host to see if it changes.

As far as I can tell I’m only calling sendCommand() once. Seems to be intermittent too.

Should it just be a communications issue building up my mesh network should help, right?

chris · August 11, 2016, 5:01pm

Yes - if that’s the problem, then adding extra powered nodes in an appropriate place should help.

djarvis8 · August 11, 2016, 5:26pm

It should be noted that if I just use a basic on/off switch item for the device (which uses defaults for volume, repetitions, ringtone) then I do not get any of these communications errors.

chris · August 11, 2016, 5:40pm

It looks like the device isn’t responding to a request of parameter 6 so this might be the real problem.

Can you provide a full debug log please? Please format it using the </> button as it will make it easier to read.

djarvis8 · August 11, 2016, 6:25pm

Ok, here it is. All that happened here (for NODE 8, the doorbell device) is that I triggered an event that did a sendCommand on parameter 6 with argument 5 (which indicates what ringtone i want to play).

What you said sounds correct, that perhaps these “internal” calls/parameters weren’t really meant to be called publicly and thus doesn’t send a response? I would then think there’s a way to make it NOT expect response to avoid the retries.

2016-08-11 14:15:29.220 [DEBUG] [b.z.i.protocol.ZWaveController:648 ]- Notifying event listeners: ZWaveMeterValueEvent
2016-08-11 14:15:29.220 [DEBUG] [.z.internal.ZWaveActiveBinding:449 ]- ZwaveIncomingEvent
2016-08-11 14:15:29.220 [DEBUG] [.z.internal.ZWaveActiveBinding:466 ]- NODE 6: Got a value event from Z-Wave network, endpoint = 0, command class = METER, value = 2746.569
2016-08-11 14:15:29.227 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:66  ]- Sent message Message: class = SendData (0x13), type = Request (0x00), payload = 06 03 32 01 00 
2016-08-11 14:15:29.228 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:67  ]- Recv message Message: class = ApplicationCommandHandler (0x04), type = Request (0x00), payload = 00 06 0E 32 02 21 64 00 29 E8 C9 00 0A 00 29 E8 5E 
2016-08-11 14:15:29.228 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:68  ]- Checking transaction complete: class=ApplicationCommandHandler, expected=ApplicationCommandHandler, cancelled=false
2016-08-11 14:15:29.229 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:74  ]-          transaction complete!
2016-08-11 14:15:29.229 [DEBUG] [b.z.i.protocol.ZWaveController:648 ]- Notifying event listeners: ZWaveTransactionCompletedEvent
2016-08-11 14:15:29.229 [DEBUG] [.z.internal.ZWaveActiveBinding:449 ]- ZwaveIncomingEvent
2016-08-11 14:15:29.230 [DEBUG] [WaveController$ZWaveSendThread:1364]- NODE 6: Response processed after 57ms/3707ms.
2016-08-11 14:15:33.520 [DEBUG] [.z.i.config.ZWaveConfiguration:288 ]- NODE 9: No database entry: Vision Security [ID:2201,Type:2022]
2016-08-11 14:15:33.522 [DEBUG] [.z.i.config.ZWaveConfiguration:288 ]- NODE 10: No database entry: Vision Security [ID:2201,Type:2022]
2016-08-11 14:15:33.523 [DEBUG] [.z.i.config.ZWaveConfiguration:288 ]- NODE 11: No database entry: Vision Security [ID:2201,Type:2022]
2016-08-11 14:15:33.525 [DEBUG] [.z.i.config.ZWaveConfiguration:288 ]- NODE 12: No database entry: Vision Security [ID:2201,Type:2022]
2016-08-11 14:15:33.775 [DEBUG] [ZWaveConfigurationCommandClass:183 ]- NODE 8: Creating new message for application command CONFIGURATIONCMD_SET
2016-08-11 14:15:33.776 [DEBUG] [o.b.z.i.protocol.SerialMessage:113 ]- NODE 8: Creating empty message of class = SendData (0x13), type = Request (0x00)
2016-08-11 14:15:33.777 [DEBUG] [b.z.i.protocol.ZWaveController:947 ]- Callback ID = 76
2016-08-11 14:15:33.778 [DEBUG] [b.z.i.protocol.ZWaveController:632 ]- Enqueueing message. Queue length = 1
2016-08-11 14:15:33.778 [DEBUG] [WaveController$ZWaveSendThread:1241]- Took message from queue for sending. Queue length = 0
2016-08-11 14:15:33.779 [DEBUG] [ZWaveConfigurationCommandClass:160 ]- NODE 8: Creating new message for application command CONFIGURATIONCMD_GET
2016-08-11 14:15:33.780 [DEBUG] [o.b.z.i.protocol.SerialMessage:243 ]- Assembled message buffer = 01 0C 00 13 08 05 70 04 06 01 05 25 4C F2 
2016-08-11 14:15:33.780 [DEBUG] [o.b.z.i.protocol.SerialMessage:113 ]- NODE 8: Creating empty message of class = SendData (0x13), type = Request (0x00)
2016-08-11 14:15:33.781 [DEBUG] [WaveController$ZWaveSendThread:1301]- NODE 8: Sending REQUEST Message = 01 0C 00 13 08 05 70 04 06 01 05 25 4C F2 
2016-08-11 14:15:33.782 [DEBUG] [b.z.i.protocol.ZWaveController:947 ]- Callback ID = 77
2016-08-11 14:15:33.784 [DEBUG] [b.z.i.protocol.ZWaveController:632 ]- Enqueueing message. Queue length = 1
2016-08-11 14:15:33.791 [DEBUG] [eController$ZWaveReceiveThread:1530]- Receive Message = 01 04 01 13 01 E8 
2016-08-11 14:15:33.792 [DEBUG] [eController$ZWaveReceiveThread:1446]- Receive queue ADD: Length=1
2016-08-11 14:15:33.792 [DEBUG] [b.z.i.protocol.ZWaveController:1194]- Receive queue TAKE: Length=0
2016-08-11 14:15:33.793 [DEBUG] [o.b.z.i.protocol.SerialMessage:243 ]- Assembled message buffer = 01 04 01 13 01 E8 
2016-08-11 14:15:33.794 [DEBUG] [b.z.i.protocol.ZWaveController:1195]- Process Message = 01 04 01 13 01 E8 
2016-08-11 14:15:33.794 [DEBUG] [b.z.i.protocol.ZWaveController:194 ]- Message: class = SendData (0x13), type = Response (0x01), payload = 01 
2016-08-11 14:15:33.795 [DEBUG] [b.z.i.p.s.SendDataMessageClass:38  ]- NODE 8: Sent Data successfully placed on stack.
2016-08-11 14:15:34.380 [DEBUG] [eController$ZWaveReceiveThread:1530]- Receive Message = 01 07 00 13 4C 00 00 3C 9B 
2016-08-11 14:15:34.381 [DEBUG] [eController$ZWaveReceiveThread:1446]- Receive queue ADD: Length=1
2016-08-11 14:15:34.381 [DEBUG] [b.z.i.protocol.ZWaveController:1194]- Receive queue TAKE: Length=0
2016-08-11 14:15:34.383 [DEBUG] [o.b.z.i.protocol.SerialMessage:243 ]- Assembled message buffer = 01 09 00 13 4C 00 00 3C 00 00 95 
2016-08-11 14:15:34.383 [DEBUG] [b.z.i.protocol.ZWaveController:1195]- Process Message = 01 09 00 13 4C 00 00 3C 00 00 95 
2016-08-11 14:15:34.384 [DEBUG] [b.z.i.protocol.ZWaveController:194 ]- Message: class = SendData (0x13), type = Request (0x00), payload = 4C 00 00 3C 
2016-08-11 14:15:34.384 [DEBUG] [b.z.i.p.s.SendDataMessageClass:73  ]- NODE 8: SendData Request. CallBack ID = 76, Status = Transmission complete and ACK received(0)
2016-08-11 14:15:34.385 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:66  ]- Sent message Message: class = SendData (0x13), type = Request (0x00), payload = 08 05 70 04 06 01 05 
2016-08-11 14:15:34.386 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:67  ]- Recv message Message: class = SendData (0x13), type = Request (0x00), payload = 4C 00 00 3C 
2016-08-11 14:15:34.386 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:68  ]- Checking transaction complete: class=SendData, expected=SendData, cancelled=false
2016-08-11 14:15:34.386 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:74  ]-          transaction complete!
2016-08-11 14:15:34.387 [DEBUG] [b.z.i.protocol.ZWaveController:648 ]- Notifying event listeners: ZWaveTransactionCompletedEvent
2016-08-11 14:15:34.387 [DEBUG] [.z.internal.ZWaveActiveBinding:449 ]- ZwaveIncomingEvent
2016-08-11 14:15:34.387 [DEBUG] [WaveController$ZWaveSendThread:1364]- NODE 8: Response processed after 605ms/3707ms.
2016-08-11 14:15:34.388 [DEBUG] [WaveController$ZWaveSendThread:1241]- Took message from queue for sending. Queue length = 0
2016-08-11 14:15:34.388 [DEBUG] [o.b.z.i.protocol.SerialMessage:243 ]- Assembled message buffer = 01 0A 00 13 08 03 70 05 06 25 4D F6 
2016-08-11 14:15:34.389 [DEBUG] [WaveController$ZWaveSendThread:1301]- NODE 8: Sending REQUEST Message = 01 0A 00 13 08 03 70 05 06 25 4D F6 
2016-08-11 14:15:34.397 [DEBUG] [eController$ZWaveReceiveThread:1530]- Receive Message = 01 04 01 13 01 E8 
2016-08-11 14:15:34.398 [DEBUG] [eController$ZWaveReceiveThread:1446]- Receive queue ADD: Length=1
2016-08-11 14:15:34.398 [DEBUG] [b.z.i.protocol.ZWaveController:1194]- Receive queue TAKE: Length=0
2016-08-11 14:15:34.399 [DEBUG] [o.b.z.i.protocol.SerialMessage:243 ]- Assembled message buffer = 01 04 01 13 01 E8 
2016-08-11 14:15:34.400 [DEBUG] [b.z.i.protocol.ZWaveController:1195]- Process Message = 01 04 01 13 01 E8 
2016-08-11 14:15:34.400 [DEBUG] [b.z.i.protocol.ZWaveController:194 ]- Message: class = SendData (0x13), type = Response (0x01), payload = 01 
2016-08-11 14:15:34.401 [DEBUG] [b.z.i.p.s.SendDataMessageClass:38  ]- NODE 8: Sent Data successfully placed on stack.
2016-08-11 14:15:34.484 [DEBUG] [eController$ZWaveReceiveThread:1530]- Receive Message = 01 07 00 13 4D 00 00 09 AF 
2016-08-11 14:15:34.485 [DEBUG] [eController$ZWaveReceiveThread:1446]- Receive queue ADD: Length=1
2016-08-11 14:15:34.486 [DEBUG] [b.z.i.protocol.ZWaveController:1194]- Receive queue TAKE: Length=0
2016-08-11 14:15:34.487 [DEBUG] [o.b.z.i.protocol.SerialMessage:243 ]- Assembled message buffer = 01 09 00 13 4D 00 00 09 00 00 A1 
2016-08-11 14:15:34.488 [DEBUG] [b.z.i.protocol.ZWaveController:1195]- Process Message = 01 09 00 13 4D 00 00 09 00 00 A1 
2016-08-11 14:15:34.489 [DEBUG] [b.z.i.protocol.ZWaveController:194 ]- Message: class = SendData (0x13), type = Request (0x00), payload = 4D 00 00 09 
2016-08-11 14:15:34.490 [DEBUG] [b.z.i.p.s.SendDataMessageClass:73  ]- NODE 8: SendData Request. CallBack ID = 77, Status = Transmission complete and ACK received(0)
2016-08-11 14:15:34.490 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:66  ]- Sent message Message: class = SendData (0x13), type = Request (0x00), payload = 08 03 70 05 06 
2016-08-11 14:15:34.491 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:67  ]- Recv message Message: class = SendData (0x13), type = Request (0x00), payload = 4D 00 00 09 
2016-08-11 14:15:34.492 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:68  ]- Checking transaction complete: class=SendData, expected=ApplicationCommandHandler, cancelled=false
2016-08-11 14:15:39.171 [DEBUG] [.o.b.z.i.c.ZWaveMeterConverter:70  ]- NODE 6: Generating poll message for METER, endpoint 0
2016-08-11 14:15:39.172 [DEBUG] [z.i.p.c.ZWaveMeterCommandClass:283 ]- NODE 6: Creating new message for application command METER_GET
2016-08-11 14:15:39.173 [DEBUG] [o.b.z.i.protocol.SerialMessage:113 ]- NODE 6: Creating empty message of class = SendData (0x13), type = Request (0x00)
2016-08-11 14:15:39.173 [DEBUG] [b.z.i.protocol.ZWaveController:947 ]- Callback ID = 78
2016-08-11 14:15:39.174 [DEBUG] [b.z.i.protocol.ZWaveController:632 ]- Enqueueing message. Queue length = 1
2016-08-11 14:15:39.390 [ERROR] [WaveController$ZWaveSendThread:1347]- NODE 8: Timeout while sending message. Requeueing - 2 attempts left!
2016-08-11 14:15:39.390 [ERROR] [b.z.i.p.s.SendDataMessageClass:158 ]- NODE 8: Got an error while sending data. Resending message.
2016-08-11 14:15:39.397 [DEBUG] [b.z.i.protocol.ZWaveController:947 ]- Callback ID = 79
2016-08-11 14:15:39.398 [DEBUG] [b.z.i.protocol.ZWaveController:632 ]- Enqueueing message. Queue length = 2
2016-08-11 14:15:39.399 [DEBUG] [WaveController$ZWaveSendThread:1241]- Took message from queue for sending. Queue length = 1
2016-08-11 14:15:39.400 [DEBUG] [o.b.z.i.protocol.SerialMessage:243 ]- Assembled message buffer = 01 0A 00 13 08 03 70 05 06 25 4F F4 
2016-08-11 14:15:39.401 [DEBUG] [WaveController$ZWaveSendThread:1301]- NODE 8: Sending REQUEST Message = 01 0A 00 13 08 03 70 05 06 25 4F F4 
2016-08-11 14:15:39.410 [DEBUG] [eController$ZWaveReceiveThread:1530]- Receive Message = 01 04 01 13 01 E8 
2016-08-11 14:15:39.411 [DEBUG] [eController$ZWaveReceiveThread:1446]- Receive queue ADD: Length=1
2016-08-11 14:15:39.411 [DEBUG] [b.z.i.protocol.ZWaveController:1194]- Receive queue TAKE: Length=0
2016-08-11 14:15:39.413 [DEBUG] [o.b.z.i.protocol.SerialMessage:243 ]- Assembled message buffer = 01 04 01 13 01 E8 
2016-08-11 14:15:39.414 [DEBUG] [b.z.i.protocol.ZWaveController:1195]- Process Message = 01 04 01 13 01 E8 
2016-08-11 14:15:39.415 [DEBUG] [b.z.i.protocol.ZWaveController:194 ]- Message: class = SendData (0x13), type = Response (0x01), payload = 01 
2016-08-11 14:15:39.415 [DEBUG] [b.z.i.p.s.SendDataMessageClass:38  ]- NODE 8: Sent Data successfully placed on stack.
2016-08-11 14:15:39.438 [DEBUG] [.z.i.config.ZWaveConfiguration:288 ]- NODE 9: No database entry: Vision Security [ID:2201,Type:2022]
2016-08-11 14:15:39.439 [DEBUG] [.z.i.config.ZWaveConfiguration:288 ]- NODE 10: No database entry: Vision Security [ID:2201,Type:2022]
2016-08-11 14:15:39.441 [DEBUG] [.z.i.config.ZWaveConfiguration:288 ]- NODE 11: No database entry: Vision Security [ID:2201,Type:2022]
2016-08-11 14:15:39.442 [DEBUG] [.z.i.config.ZWaveConfiguration:288 ]- NODE 12: No database entry: Vision Security [ID:2201,Type:2022]
2016-08-11 14:15:39.472 [DEBUG] [eController$ZWaveReceiveThread:1530]- Receive Message = 01 07 00 13 4F 00 00 07 A3 
2016-08-11 14:15:39.478 [DEBUG] [eController$ZWaveReceiveThread:1446]- Receive queue ADD: Length=1
2016-08-11 14:15:39.478 [DEBUG] [b.z.i.protocol.ZWaveController:1194]- Receive queue TAKE: Length=0
2016-08-11 14:15:39.479 [DEBUG] [o.b.z.i.protocol.SerialMessage:243 ]- Assembled message buffer = 01 09 00 13 4F 00 00 07 00 00 AD 
2016-08-11 14:15:39.480 [DEBUG] [b.z.i.protocol.ZWaveController:1195]- Process Message = 01 09 00 13 4F 00 00 07 00 00 AD 
2016-08-11 14:15:39.480 [DEBUG] [b.z.i.protocol.ZWaveController:194 ]- Message: class = SendData (0x13), type = Request (0x00), payload = 4F 00 00 07 
2016-08-11 14:15:39.481 [DEBUG] [b.z.i.p.s.SendDataMessageClass:73  ]- NODE 8: SendData Request. CallBack ID = 79, Status = Transmission complete and ACK received(0)
2016-08-11 14:15:39.482 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:66  ]- Sent message Message: class = SendData (0x13), type = Request (0x00), payload = 08 03 70 05 06 
2016-08-11 14:15:39.483 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:67  ]- Recv message Message: class = SendData (0x13), type = Request (0x00), payload = 4F 00 00 07 
2016-08-11 14:15:39.483 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:68  ]- Checking transaction complete: class=SendData, expected=ApplicationCommandHandler, cancelled=false
2016-08-11 14:15:44.402 [ERROR] [WaveController$ZWaveSendThread:1347]- NODE 8: Timeout while sending message. Requeueing - 1 attempts left!
2016-08-11 14:15:44.409 [ERROR] [b.z.i.p.s.SendDataMessageClass:158 ]- NODE 8: Got an error while sending data. Resending message.
2016-08-11 14:15:44.410 [DEBUG] [b.z.i.protocol.ZWaveController:947 ]- Callback ID = 80
2016-08-11 14:15:44.411 [DEBUG] [b.z.i.protocol.ZWaveController:632 ]- Enqueueing message. Queue length = 2
2016-08-11 14:15:44.412 [DEBUG] [WaveController$ZWaveSendThread:1241]- Took message from queue for sending. Queue length = 1
2016-08-11 14:15:44.413 [DEBUG] [o.b.z.i.protocol.SerialMessage:243 ]- Assembled message buffer = 01 0A 00 13 08 03 70 05 06 25 50 EB 
2016-08-11 14:15:44.414 [DEBUG] [WaveController$ZWaveSendThread:1301]- NODE 8: Sending REQUEST Message = 01 0A 00 13 08 03 70 05 06 25 50 EB 
2016-08-11 14:15:44.424 [DEBUG] [eController$ZWaveReceiveThread:1530]- Receive Message = 01 04 01 13 01 E8 
2016-08-11 14:15:44.426 [DEBUG] [eController$ZWaveReceiveThread:1446]- Receive queue ADD: Length=1
2016-08-11 14:15:44.426 [DEBUG] [b.z.i.protocol.ZWaveController:1194]- Receive queue TAKE: Length=0
2016-08-11 14:15:44.428 [DEBUG] [o.b.z.i.protocol.SerialMessage:243 ]- Assembled message buffer = 01 04 01 13 01 E8 
2016-08-11 14:15:44.429 [DEBUG] [b.z.i.protocol.ZWaveController:1195]- Process Message = 01 04 01 13 01 E8 
2016-08-11 14:15:44.430 [DEBUG] [b.z.i.protocol.ZWaveController:194 ]- Message: class = SendData (0x13), type = Response (0x01), payload = 01 
2016-08-11 14:15:44.431 [DEBUG] [b.z.i.p.s.SendDataMessageClass:38  ]- NODE 8: Sent Data successfully placed on stack.
2016-08-11 14:15:44.483 [DEBUG] [eController$ZWaveReceiveThread:1530]- Receive Message = 01 07 00 13 50 00 00 06 BD 
2016-08-11 14:15:44.485 [DEBUG] [eController$ZWaveReceiveThread:1446]- Receive queue ADD: Length=1
2016-08-11 14:15:44.485 [DEBUG] [b.z.i.protocol.ZWaveController:1194]- Receive queue TAKE: Length=0
2016-08-11 14:15:44.487 [DEBUG] [o.b.z.i.protocol.SerialMessage:243 ]- Assembled message buffer = 01 09 00 13 50 00 00 06 00 00 B3 
2016-08-11 14:15:44.488 [DEBUG] [b.z.i.protocol.ZWaveController:1195]- Process Message = 01 09 00 13 50 00 00 06 00 00 B3 
2016-08-11 14:15:44.489 [DEBUG] [b.z.i.protocol.ZWaveController:194 ]- Message: class = SendData (0x13), type = Request (0x00), payload = 50 00 00 06 
2016-08-11 14:15:44.490 [DEBUG] [b.z.i.p.s.SendDataMessageClass:73  ]- NODE 8: SendData Request. CallBack ID = 80, Status = Transmission complete and ACK received(0)
2016-08-11 14:15:44.491 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:66  ]- Sent message Message: class = SendData (0x13), type = Request (0x00), payload = 08 03 70 05 06 
2016-08-11 14:15:44.492 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:67  ]- Recv message Message: class = SendData (0x13), type = Request (0x00), payload = 50 00 00 06 
2016-08-11 14:15:44.493 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:68  ]- Checking transaction complete: class=SendData, expected=ApplicationCommandHandler, cancelled=false
2016-08-11 14:15:45.460 [DEBUG] [.z.i.config.ZWaveConfiguration:288 ]- NODE 9: No database entry: Vision Security [ID:2201,Type:2022]
2016-08-11 14:15:45.461 [DEBUG] [.z.i.config.ZWaveConfiguration:288 ]- NODE 10: No database entry: Vision Security [ID:2201,Type:2022]
2016-08-11 14:15:45.463 [DEBUG] [.z.i.config.ZWaveConfiguration:288 ]- NODE 11: No database entry: Vision Security [ID:2201,Type:2022]
2016-08-11 14:15:45.464 [DEBUG] [.z.i.config.ZWaveConfiguration:288 ]- NODE 12: No database entry: Vision Security [ID:2201,Type:2022]
2016-08-11 14:15:49.416 [ERROR] [WaveController$ZWaveSendThread:1347]- NODE 8: Timeout while sending message. Requeueing - 0 attempts left!
2016-08-11 14:15:49.423 [ERROR] [b.z.i.p.s.SendDataMessageClass:158 ]- NODE 8: Got an error while sending data. Resending message.
2016-08-11 14:15:49.424 [DEBUG] [b.z.i.protocol.ZWaveController:947 ]- Callback ID = 81
2016-08-11 14:15:49.425 [DEBUG] [b.z.i.protocol.ZWaveController:632 ]- Enqueueing message. Queue length = 2
2016-08-11 14:15:49.426 [DEBUG] [WaveController$ZWaveSendThread:1241]- Took message from queue for sending. Queue length = 1
2016-08-11 14:15:49.428 [DEBUG] [o.b.z.i.protocol.SerialMessage:243 ]- Assembled message buffer = 01 0A 00 13 08 03 70 05 06 25 51 EA 
2016-08-11 14:15:49.429 [DEBUG] [WaveController$ZWaveSendThread:1301]- NODE 8: Sending REQUEST Message = 01 0A 00 13 08 03 70 05 06 25 51 EA 
2016-08-11 14:15:49.439 [DEBUG] [eController$ZWaveReceiveThread:1530]- Receive Message = 01 04 01 13 01 E8 
2016-08-11 14:15:49.441 [DEBUG] [eController$ZWaveReceiveThread:1446]- Receive queue ADD: Length=1
2016-08-11 14:15:49.441 [DEBUG] [b.z.i.protocol.ZWaveController:1194]- Receive queue TAKE: Length=0
2016-08-11 14:15:49.442 [DEBUG] [o.b.z.i.protocol.SerialMessage:243 ]- Assembled message buffer = 01 04 01 13 01 E8 
2016-08-11 14:15:49.443 [DEBUG] [b.z.i.protocol.ZWaveController:1195]- Process Message = 01 04 01 13 01 E8 
2016-08-11 14:15:49.444 [DEBUG] [b.z.i.protocol.ZWaveController:194 ]- Message: class = SendData (0x13), type = Response (0x01), payload = 01 
2016-08-11 14:15:49.445 [DEBUG] [b.z.i.p.s.SendDataMessageClass:38  ]- NODE 8: Sent Data successfully placed on stack.
2016-08-11 14:15:49.498 [DEBUG] [eController$ZWaveReceiveThread:1530]- Receive Message = 01 07 00 13 51 00 00 07 BD 
2016-08-11 14:15:49.501 [DEBUG] [b.z.i.protocol.ZWaveController:1194]- Receive queue TAKE: Length=0
2016-08-11 14:15:49.501 [DEBUG] [eController$ZWaveReceiveThread:1446]- Receive queue ADD: Length=1
2016-08-11 14:15:49.502 [DEBUG] [o.b.z.i.protocol.SerialMessage:243 ]- Assembled message buffer = 01 09 00 13 51 00 00 07 00 00 B3 
2016-08-11 14:15:49.503 [DEBUG] [b.z.i.protocol.ZWaveController:1195]- Process Message = 01 09 00 13 51 00 00 07 00 00 B3 
2016-08-11 14:15:49.504 [DEBUG] [b.z.i.protocol.ZWaveController:194 ]- Message: class = SendData (0x13), type = Request (0x00), payload = 51 00 00 07 
2016-08-11 14:15:49.505 [DEBUG] [b.z.i.p.s.SendDataMessageClass:73  ]- NODE 8: SendData Request. CallBack ID = 81, Status = Transmission complete and ACK received(0)
2016-08-11 14:15:49.506 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:66  ]- Sent message Message: class = SendData (0x13), type = Request (0x00), payload = 08 03 70 05 06 
2016-08-11 14:15:49.508 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:67  ]- Recv message Message: class = SendData (0x13), type = Request (0x00), payload = 51 00 00 07 
2016-08-11 14:15:49.508 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:68  ]- Checking transaction complete: class=SendData, expected=ApplicationCommandHandler, cancelled=false
2016-08-11 14:15:51.483 [DEBUG] [.z.i.config.ZWaveConfiguration:288 ]- NODE 9: No database entry: Vision Security [ID:2201,Type:2022]
2016-08-11 14:15:51.485 [DEBUG] [.z.i.config.ZWaveConfiguration:288 ]- NODE 10: No database entry: Vision Security [ID:2201,Type:2022]
2016-08-11 14:15:51.486 [DEBUG] [.z.i.config.ZWaveConfiguration:288 ]- NODE 11: No database entry: Vision Security [ID:2201,Type:2022]
2016-08-11 14:15:51.487 [DEBUG] [.z.i.config.ZWaveConfiguration:288 ]- NODE 12: No database entry: Vision Security [ID:2201,Type:2022]
2016-08-11 14:15:54.431 [WARN ] [WaveController$ZWaveSendThread:1355]- NODE 8: Too many retries. Discarding message: Message: class = SendData (0x13), type = Request (0x00), payload = 08 03 70 05 06 
2016-08-11 14:15:54.440 [DEBUG] [WaveController$ZWaveSendThread:1241]- Took message from queue for sending. Queue length = 0
2016-08-11 14:15:54.441 [DEBUG] [o.b.z.i.protocol.SerialMessage:243 ]- Assembled message buffer = 01 0A 00 13 06 03 32 01 00 25 4E BB 
2016-08-11 14:15:54.442 [DEBUG] [WaveController$ZWaveSendThread:1301]- NODE 6: Sending REQUEST Message = 01 0A 00 13 06 03 32 01 00 25 4E BB 
2016-08-11 14:15:54.450 [DEBUG] [eController$ZWaveReceiveThread:1530]- Receive Message = 01 04 01 13 01 E8 
2016-08-11 14:15:54.451 [DEBUG] [eController$ZWaveReceiveThread:1446]- Receive queue ADD: Length=1
2016-08-11 14:15:54.451 [DEBUG] [b.z.i.protocol.ZWaveController:1194]- Receive queue TAKE: Length=0
2016-08-11 14:15:54.452 [DEBUG] [o.b.z.i.protocol.SerialMessage:243 ]- Assembled message buffer = 01 04 01 13 01 E8 
2016-08-11 14:15:54.453 [DEBUG] [b.z.i.protocol.ZWaveController:1195]- Process Message = 01 04 01 13 01 E8 
2016-08-11 14:15:54.453 [DEBUG] [b.z.i.protocol.ZWaveController:194 ]- Message: class = SendData (0x13), type = Response (0x01), payload = 01 
2016-08-11 14:15:54.454 [DEBUG] [b.z.i.p.s.SendDataMessageClass:38  ]- NODE 6: Sent Data successfully placed on stack.
2016-08-11 14:15:54.468 [DEBUG] [eController$ZWaveReceiveThread:1530]- Receive Message = 01 07 00 13 4E 00 00 03 A6 
2016-08-11 14:15:54.469 [DEBUG] [eController$ZWaveReceiveThread:1446]- Receive queue ADD: Length=1
2016-08-11 14:15:54.469 [DEBUG] [b.z.i.protocol.ZWaveController:1194]- Receive queue TAKE: Length=0
2016-08-11 14:15:54.470 [DEBUG] [o.b.z.i.protocol.SerialMessage:243 ]- Assembled message buffer = 01 09 00 13 4E 00 00 03 00 00 A8 
2016-08-11 14:15:54.471 [DEBUG] [b.z.i.protocol.ZWaveController:1195]- Process Message = 01 09 00 13 4E 00 00 03 00 00 A8 
2016-08-11 14:15:54.471 [DEBUG] [b.z.i.protocol.ZWaveController:194 ]- Message: class = SendData (0x13), type = Request (0x00), payload = 4E 00 00 03 
2016-08-11 14:15:54.472 [DEBUG] [b.z.i.p.s.SendDataMessageClass:73  ]- NODE 6: SendData Request. CallBack ID = 78, Status = Transmission complete and ACK received(0)
2016-08-11 14:15:54.473 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:66  ]- Sent message Message: class = SendData (0x13), type = Request (0x00), payload = 06 03 32 01 00 
2016-08-11 14:15:54.473 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:67  ]- Recv message Message: class = SendData (0x13), type = Request (0x00), payload = 4E 00 00 03 
2016-08-11 14:15:54.474 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:68  ]- Checking transaction complete: class=SendData, expected=ApplicationCommandHandler, cancelled=false
2016-08-11 14:15:54.481 [DEBUG] [eController$ZWaveReceiveThread:1530]- Receive Message = 01 14 00 04 00 06 0E 32 02 21 64 00 29 E8 C9 00 19 00 29 E8 C9 8B 
2016-08-11 14:15:54.483 [DEBUG] [eController$ZWaveReceiveThread:1446]- Receive queue ADD: Length=1
2016-08-11 14:15:54.483 [DEBUG] [b.z.i.protocol.ZWaveController:1194]- Receive queue TAKE: Length=0
2016-08-11 14:15:54.484 [DEBUG] [o.b.z.i.protocol.SerialMessage:243 ]- Assembled message buffer = 01 14 00 04 00 06 0E 32 02 21 64 00 29 E8 C9 00 19 00 29 E8 C9 8B 
2016-08-11 14:15:54.485 [DEBUG] [b.z.i.protocol.ZWaveController:1195]- Process Message = 01 14 00 04 00 06 0E 32 02 21 64 00 29 E8 C9 00 19 00 29 E8 C9 8B 
2016-08-11 14:15:54.486 [DEBUG] [b.z.i.protocol.ZWaveController:194 ]- Message: class = ApplicationCommandHandler (0x04), type = Request (0x00), payload = 00 06 0E 32 02 21 64 00 29 E8 C9 00 19 00 29 E8 C9 
2016-08-11 14:15:54.487 [DEBUG] [ApplicationCommandMessageClass:40  ]- NODE 6: Application Command Request (ALIVE:DONE)
2016-08-11 14:15:54.487 [DEBUG] [ApplicationCommandMessageClass:58  ]- NODE 6: Incoming command class METER
2016-08-11 14:15:54.488 [DEBUG] [z.i.p.c.ZWaveMeterCommandClass:109 ]- NODE 6: Received Meter Request
2016-08-11 14:15:54.489 [DEBUG] [z.i.p.c.ZWaveMeterCommandClass:157 ]- NODE 6: Meter: Type=Electric(1), Scale=kWh(0), Value=2746.569
2016-08-11 14:15:54.489 [DEBUG] [b.z.i.protocol.ZWaveController:648 ]- Notifying event listeners: ZWaveMeterValueEvent
2016-08-11 14:15:54.489 [DEBUG] [.z.internal.ZWaveActiveBinding:449 ]- ZwaveIncomingEvent
2016-08-11 14:15:54.490 [DEBUG] [.z.internal.ZWaveActiveBinding:466 ]- NODE 6: Got a value event from Z-Wave network, endpoint = 0, command class = METER, value = 2746.569
2016-08-11 14:15:54.491 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:66  ]- Sent message Message: class = SendData (0x13), type = Request (0x00), payload = 06 03 32 01 00 
2016-08-11 14:15:54.492 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:67  ]- Recv message Message: class = ApplicationCommandHandler (0x04), type = Request (0x00), payload = 00 06 0E 32 02 21 64 00 29 E8 C9 00 19 00 29 E8 C9 
2016-08-11 14:15:54.493 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:68  ]- Checking transaction complete: class=ApplicationCommandHandler, expected=ApplicationCommandHandler, cancelled=false
2016-08-11 14:15:54.493 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:74  ]-          transaction complete!
2016-08-11 14:15:54.494 [DEBUG] [b.z.i.protocol.ZWaveController:648 ]- Notifying event listeners: ZWaveTransactionCompletedEvent
2016-08-11 14:15:54.494 [DEBUG] [.z.internal.ZWaveActiveBinding:449 ]- ZwaveIncomingEvent
2016-08-11 14:15:54.495 [DEBUG] [WaveController$ZWaveSendThread:1364]- NODE 6: Response processed after 53ms/3707ms.

djarvis8 · August 11, 2016, 6:30pm

When I merely turn on the regular default switch item defined as:

Switch Doorbell_Switch “Doorbell Switch” { zwave=“8:command=switch_binary” }

I do not get any timeout messages:

2016-08-11 14:26:40.325 [DEBUG] [b.z.i.protocol.ZWaveController:648 ]- Notifying event listeners: ZWaveMeterValueEvent
2016-08-11 14:26:40.325 [DEBUG] [.z.internal.ZWaveActiveBinding:449 ]- ZwaveIncomingEvent
2016-08-11 14:26:40.325 [DEBUG] [.z.internal.ZWaveActiveBinding:466 ]- NODE 6: Got a value event from Z-Wave network, endpoint = 0, command class = METER, value = 5378.56
2016-08-11 14:26:40.326 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:66  ]- Sent message Message: class = SendData (0x13), type = Request (0x00), payload = 06 03 32 01 10 
2016-08-11 14:26:40.327 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:67  ]- Recv message Message: class = ApplicationCommandHandler (0x04), type = Request (0x00), payload = 00 06 0E 32 02 21 74 00 52 12 00 00 00 00 29 EC 87 
2016-08-11 14:26:40.328 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:68  ]- Checking transaction complete: class=ApplicationCommandHandler, expected=ApplicationCommandHandler, cancelled=false
2016-08-11 14:26:40.329 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:74  ]-          transaction complete!
2016-08-11 14:26:40.329 [DEBUG] [b.z.i.protocol.ZWaveController:648 ]- Notifying event listeners: ZWaveTransactionCompletedEvent
2016-08-11 14:26:40.329 [DEBUG] [.z.internal.ZWaveActiveBinding:449 ]- ZwaveIncomingEvent
2016-08-11 14:26:40.330 [DEBUG] [WaveController$ZWaveSendThread:1364]- NODE 6: Response processed after 51ms/3707ms.
2016-08-11 14:26:45.278 [DEBUG] [.o.b.z.i.c.ZWaveMeterConverter:70  ]- NODE 6: Generating poll message for METER, endpoint 0
2016-08-11 14:26:45.287 [DEBUG] [z.i.p.c.ZWaveMeterCommandClass:283 ]- NODE 6: Creating new message for application command METER_GET
2016-08-11 14:26:45.289 [DEBUG] [o.b.z.i.protocol.SerialMessage:113 ]- NODE 6: Creating empty message of class = SendData (0x13), type = Request (0x00)
2016-08-11 14:26:45.290 [DEBUG] [b.z.i.protocol.ZWaveController:947 ]- Callback ID = 226
2016-08-11 14:26:45.292 [DEBUG] [b.z.i.protocol.ZWaveController:632 ]- Enqueueing message. Queue length = 1
2016-08-11 14:26:45.292 [DEBUG] [WaveController$ZWaveSendThread:1241]- Took message from queue for sending. Queue length = 0
2016-08-11 14:26:45.294 [DEBUG] [o.b.z.i.protocol.SerialMessage:243 ]- Assembled message buffer = 01 0A 00 13 06 03 32 01 00 25 E2 17 
2016-08-11 14:26:45.298 [DEBUG] [WaveController$ZWaveSendThread:1301]- NODE 6: Sending REQUEST Message = 01 0A 00 13 06 03 32 01 00 25 E2 17 
2016-08-11 14:26:45.308 [DEBUG] [eController$ZWaveReceiveThread:1530]- Receive Message = 01 04 01 13 01 E8 
2016-08-11 14:26:45.310 [DEBUG] [eController$ZWaveReceiveThread:1446]- Receive queue ADD: Length=1
2016-08-11 14:26:45.310 [DEBUG] [b.z.i.protocol.ZWaveController:1194]- Receive queue TAKE: Length=0
2016-08-11 14:26:45.312 [DEBUG] [o.b.z.i.protocol.SerialMessage:243 ]- Assembled message buffer = 01 04 01 13 01 E8 
2016-08-11 14:26:45.313 [DEBUG] [b.z.i.protocol.ZWaveController:1195]- Process Message = 01 04 01 13 01 E8 
2016-08-11 14:26:45.315 [DEBUG] [b.z.i.protocol.ZWaveController:194 ]- Message: class = SendData (0x13), type = Response (0x01), payload = 01 
2016-08-11 14:26:45.316 [DEBUG] [b.z.i.p.s.SendDataMessageClass:38  ]- NODE 6: Sent Data successfully placed on stack.
2016-08-11 14:26:45.326 [DEBUG] [eController$ZWaveReceiveThread:1530]- Receive Message = 01 07 00 13 E2 00 00 03 0A 
2016-08-11 14:26:45.328 [DEBUG] [eController$ZWaveReceiveThread:1446]- Receive queue ADD: Length=1
2016-08-11 14:26:45.328 [DEBUG] [b.z.i.protocol.ZWaveController:1194]- Receive queue TAKE: Length=0
2016-08-11 14:26:45.333 [DEBUG] [o.b.z.i.protocol.SerialMessage:243 ]- Assembled message buffer = 01 09 00 13 E2 00 00 03 00 00 04 
2016-08-11 14:26:45.334 [DEBUG] [b.z.i.protocol.ZWaveController:1195]- Process Message = 01 09 00 13 E2 00 00 03 00 00 04 
2016-08-11 14:26:45.335 [DEBUG] [b.z.i.protocol.ZWaveController:194 ]- Message: class = SendData (0x13), type = Request (0x00), payload = E2 00 00 03 
2016-08-11 14:26:45.336 [DEBUG] [b.z.i.p.s.SendDataMessageClass:73  ]- NODE 6: SendData Request. CallBack ID = 226, Status = Transmission complete and ACK received(0)
2016-08-11 14:26:45.337 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:66  ]- Sent message Message: class = SendData (0x13), type = Request (0x00), payload = 06 03 32 01 00 
2016-08-11 14:26:45.338 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:67  ]- Recv message Message: class = SendData (0x13), type = Request (0x00), payload = E2 00 00 03 
2016-08-11 14:26:45.338 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:68  ]- Checking transaction complete: class=SendData, expected=ApplicationCommandHandler, cancelled=false
2016-08-11 14:26:45.340 [DEBUG] [eController$ZWaveReceiveThread:1530]- Receive Message = 01 14 00 04 00 06 0E 32 02 21 64 00 29 EC E0 00 0A 00 29 EC E0 98 
2016-08-11 14:26:45.342 [DEBUG] [eController$ZWaveReceiveThread:1446]- Receive queue ADD: Length=1
2016-08-11 14:26:45.342 [DEBUG] [b.z.i.protocol.ZWaveController:1194]- Receive queue TAKE: Length=0
2016-08-11 14:26:45.344 [DEBUG] [o.b.z.i.protocol.SerialMessage:243 ]- Assembled message buffer = 01 14 00 04 00 06 0E 32 02 21 64 00 29 EC E0 00 0A 00 29 EC E0 98 
2016-08-11 14:26:45.345 [DEBUG] [b.z.i.protocol.ZWaveController:1195]- Process Message = 01 14 00 04 00 06 0E 32 02 21 64 00 29 EC E0 00 0A 00 29 EC E0 98 
2016-08-11 14:26:45.346 [DEBUG] [b.z.i.protocol.ZWaveController:194 ]- Message: class = ApplicationCommandHandler (0x04), type = Request (0x00), payload = 00 06 0E 32 02 21 64 00 29 EC E0 00 0A 00 29 EC E0 
2016-08-11 14:26:45.347 [DEBUG] [ApplicationCommandMessageClass:40  ]- NODE 6: Application Command Request (ALIVE:DONE)
2016-08-11 14:26:45.348 [DEBUG] [ApplicationCommandMessageClass:58  ]- NODE 6: Incoming command class METER
2016-08-11 14:26:45.348 [DEBUG] [z.i.p.c.ZWaveMeterCommandClass:109 ]- NODE 6: Received Meter Request
2016-08-11 14:26:45.349 [DEBUG] [z.i.p.c.ZWaveMeterCommandClass:157 ]- NODE 6: Meter: Type=Electric(1), Scale=kWh(0), Value=2747.616
2016-08-11 14:26:45.350 [DEBUG] [b.z.i.protocol.ZWaveController:648 ]- Notifying event listeners: ZWaveMeterValueEvent
2016-08-11 14:26:45.350 [DEBUG] [.z.internal.ZWaveActiveBinding:449 ]- ZwaveIncomingEvent
2016-08-11 14:26:45.351 [DEBUG] [.z.internal.ZWaveActiveBinding:466 ]- NODE 6: Got a value event from Z-Wave network, endpoint = 0, command class = METER, value = 2747.616
2016-08-11 14:26:45.352 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:66  ]- Sent message Message: class = SendData (0x13), type = Request (0x00), payload = 06 03 32 01 00 
2016-08-11 14:26:45.353 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:67  ]- Recv message Message: class = ApplicationCommandHandler (0x04), type = Request (0x00), payload = 00 06 0E 32 02 21 64 00 29 EC E0 00 0A 00 29 EC E0 
2016-08-11 14:26:45.354 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:68  ]- Checking transaction complete: class=ApplicationCommandHandler, expected=ApplicationCommandHandler, cancelled=false
2016-08-11 14:26:45.354 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:74  ]-          transaction complete!
2016-08-11 14:26:45.355 [DEBUG] [b.z.i.protocol.ZWaveController:648 ]- Notifying event listeners: ZWaveTransactionCompletedEvent
2016-08-11 14:26:45.355 [DEBUG] [.z.internal.ZWaveActiveBinding:449 ]- ZwaveIncomingEvent
2016-08-11 14:26:45.356 [DEBUG] [WaveController$ZWaveSendThread:1364]- NODE 6: Response processed after 57ms/3707ms.
2016-08-11 14:26:45.390 [DEBUG] [.ZWaveBinarySwitchCommandClass:150 ]- NODE 8: Creating new message for application command SWITCH_BINARY_SET
2016-08-11 14:26:45.391 [DEBUG] [o.b.z.i.protocol.SerialMessage:113 ]- NODE 8: Creating empty message of class = SendData (0x13), type = Request (0x00)
2016-08-11 14:26:45.392 [DEBUG] [b.z.i.protocol.ZWaveController:947 ]- Callback ID = 227
2016-08-11 14:26:45.393 [DEBUG] [WaveController$ZWaveSendThread:1241]- Took message from queue for sending. Queue length = 0
2016-08-11 14:26:45.394 [DEBUG] [o.b.z.i.protocol.SerialMessage:243 ]- Assembled message buffer = 01 0A 00 13 08 03 25 01 FF 25 E3 F0 
2016-08-11 14:26:45.395 [DEBUG] [WaveController$ZWaveSendThread:1301]- NODE 8: Sending REQUEST Message = 01 0A 00 13 08 03 25 01 FF 25 E3 F0 
2016-08-11 14:26:45.396 [DEBUG] [b.z.i.protocol.ZWaveController:632 ]- Enqueueing message. Queue length = 0
2016-08-11 14:26:45.404 [DEBUG] [eController$ZWaveReceiveThread:1530]- Receive Message = 01 04 01 13 01 E8 
2016-08-11 14:26:45.406 [DEBUG] [eController$ZWaveReceiveThread:1446]- Receive queue ADD: Length=1
2016-08-11 14:26:45.418 [DEBUG] [b.z.i.protocol.ZWaveController:1194]- Receive queue TAKE: Length=0
2016-08-11 14:26:45.418 [DEBUG] [o.b.z.i.protocol.SerialMessage:243 ]- Assembled message buffer = 01 04 01 13 01 E8 
2016-08-11 14:26:45.419 [DEBUG] [b.z.i.protocol.ZWaveController:1195]- Process Message = 01 04 01 13 01 E8 
2016-08-11 14:26:45.420 [DEBUG] [b.z.i.protocol.ZWaveController:194 ]- Message: class = SendData (0x13), type = Response (0x01), payload = 01 
2016-08-11 14:26:45.420 [DEBUG] [b.z.i.p.s.SendDataMessageClass:38  ]- NODE 8: Sent Data successfully placed on stack.
2016-08-11 14:26:45.488 [DEBUG] [.z.i.config.ZWaveConfiguration:288 ]- NODE 9: No database entry: Vision Security [ID:2201,Type:2022]
2016-08-11 14:26:45.490 [DEBUG] [.z.i.config.ZWaveConfiguration:288 ]- NODE 10: No database entry: Vision Security [ID:2201,Type:2022]
2016-08-11 14:26:45.491 [DEBUG] [.z.i.config.ZWaveConfiguration:288 ]- NODE 11: No database entry: Vision Security [ID:2201,Type:2022]
2016-08-11 14:26:45.492 [DEBUG] [.z.i.config.ZWaveConfiguration:288 ]- NODE 12: No database entry: Vision Security [ID:2201,Type:2022]
2016-08-11 14:26:48.739 [DEBUG] [eController$ZWaveReceiveThread:1530]- Receive Message = 01 07 00 13 E3 00 01 4E 47 
2016-08-11 14:26:48.742 [DEBUG] [eController$ZWaveReceiveThread:1446]- Receive queue ADD: Length=1
2016-08-11 14:26:48.742 [DEBUG] [b.z.i.protocol.ZWaveController:1194]- Receive queue TAKE: Length=0
2016-08-11 14:26:48.744 [DEBUG] [o.b.z.i.protocol.SerialMessage:243 ]- Assembled message buffer = 01 09 00 13 E3 00 01 4E 00 00 49 
2016-08-11 14:26:48.746 [DEBUG] [b.z.i.protocol.ZWaveController:1195]- Process Message = 01 09 00 13 E3 00 01 4E 00 00 49 
2016-08-11 14:26:48.748 [DEBUG] [b.z.i.protocol.ZWaveController:194 ]- Message: class = SendData (0x13), type = Request (0x00), payload = E3 00 01 4E 
2016-08-11 14:26:48.749 [DEBUG] [b.z.i.p.s.SendDataMessageClass:73  ]- NODE 8: SendData Request. CallBack ID = 227, Status = Transmission complete and ACK received(0)
2016-08-11 14:26:48.752 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:66  ]- Sent message Message: class = SendData (0x13), type = Request (0x00), payload = 08 03 25 01 FF 
2016-08-11 14:26:48.753 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:67  ]- Recv message Message: class = SendData (0x13), type = Request (0x00), payload = E3 00 01 4E 
2016-08-11 14:26:48.754 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:68  ]- Checking transaction complete: class=SendData, expected=SendData, cancelled=false
2016-08-11 14:26:48.755 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:74  ]-          transaction complete!
2016-08-11 14:26:48.756 [DEBUG] [b.z.i.protocol.ZWaveController:648 ]- Notifying event listeners: ZWaveTransactionCompletedEvent
2016-08-11 14:26:48.756 [DEBUG] [.z.internal.ZWaveActiveBinding:449 ]- ZwaveIncomingEvent
2016-08-11 14:26:48.757 [DEBUG] [WaveController$ZWaveSendThread:1364]- NODE 8: Response processed after 3362ms/3707ms.
2016-08-11 14:26:50.294 [DEBUG] [.o.b.z.i.c.ZWaveMeterConverter:70  ]- NODE 6: Generating poll message for METER, endpoint 0
2016-08-11 14:26:50.302 [DEBUG] [z.i.p.c.ZWaveMeterCommandClass:283 ]- NODE 6: Creating new message for application command METER_GET
2016-08-11 14:26:50.303 [DEBUG] [o.b.z.i.protocol.SerialMessage:113 ]- NODE 6: Creating empty message of class = SendData (0x13), type = Request (0x00)
2016-08-11 14:26:50.304 [DEBUG] [b.z.i.protocol.ZWaveController:947 ]- Callback ID = 228
2016-08-11 14:26:50.305 [DEBUG] [b.z.i.protocol.ZWaveController:632 ]- Enqueueing message. Queue length = 1
2016-08-11 14:26:50.305 [DEBUG] [WaveController$ZWaveSendThread:1241]- Took message from queue for sending. Queue length = 0
2016-08-11 14:26:50.308 [DEBUG] [o.b.z.i.protocol.SerialMessage:243 ]- Assembled message buffer = 01 0A 00 13 06 03 32 01 10 25 E4 01 
2016-08-11 14:26:50.310 [DEBUG] [WaveController$ZWaveSendThread:1301]- NODE 6: Sending REQUEST Message = 01 0A 00 13 06 03 32 01 10 25 E4 01 
2016-08-11 14:26:50.318 [DEBUG] [eController$ZWaveReceiveThread:1530]- Receive Message = 01 04 01 13 01 E8 
2016-08-11 14:26:50.320 [DEBUG] [eController$ZWaveReceiveThread:1446]- Receive queue ADD: Length=1
2016-08-11 14:26:50.320 [DEBUG] [b.z.i.protocol.ZWaveController:1194]- Receive queue TAKE: Length=0
2016-08-11 14:26:50.322 [DEBUG] [o.b.z.i.protocol.SerialMessage:243 ]- Assembled message buffer = 01 04 01 13 01 E8 
2016-08-11 14:26:50.323 [DEBUG] [b.z.i.protocol.ZWaveController:1195]- Process Message = 01 04 01 13 01 E8 
2016-08-11 14:26:50.324 [DEBUG] [b.z.i.protocol.ZWaveController:194 ]- Message: class = SendData (0x13), type = Response (0x01), payload = 01 
2016-08-11 14:26:50.325 [DEBUG] [b.z.i.p.s.SendDataMessageClass:38  ]- NODE 6: Sent Data successfully placed on stack.
2016-08-11 14:26:50.336 [DEBUG] [eController$ZWaveReceiveThread:1530]- Receive Message = 01 07 00 13 E4 00 00 03 0C 
2016-08-11 14:26:50.339 [DEBUG] [eController$ZWaveReceiveThread:1446]- Receive queue ADD: Length=1
2016-08-11 14:26:50.339 [DEBUG] [b.z.i.protocol.ZWaveController:1194]- Receive queue TAKE: Length=0
2016-08-11 14:26:50.341 [DEBUG] [o.b.z.i.protocol.SerialMessage:243 ]- Assembled message buffer = 01 09 00 13 E4 00 00 03 00 00 02 
2016-08-11 14:26:50.342 [DEBUG] [b.z.i.protocol.ZWaveController:1195]- Process Message = 01 09 00 13 E4 00 00 03 00 00 02 
2016-08-11 14:26:50.343 [DEBUG] [b.z.i.protocol.ZWaveController:194 ]- Message: class = SendData (0x13), type = Request (0x00), payload = E4 00 00 03 
2016-08-11 14:26:50.344 [DEBUG] [b.z.i.p.s.SendDataMessageClass:73  ]- NODE 6: SendData Request. CallBack ID = 228, Status = Transmission complete and ACK received(0)
2016-08-11 14:26:50.346 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:66  ]- Sent message Message: class = SendData (0x13), type = Request (0x00), payload = 06 03 32 01 10 
2016-08-11 14:26:50.347 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:67  ]- Recv message Message: class = SendData (0x13), type = Request (0x00), payload = E4 00 00 03 
2016-08-11 14:26:50.348 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:68  ]- Checking transaction complete: class=SendData, expected=ApplicationCommandHandler, cancelled=false
2016-08-11 14:26:50.351 [DEBUG] [eController$ZWaveReceiveThread:1530]- Receive Message = 01 14 00 04 00 06 0E 32 02 21 74 00 52 43 D8 00 00 00 29 EC E0 6E 
2016-08-11 14:26:50.354 [DEBUG] [eController$ZWaveReceiveThread:1446]- Receive queue ADD: Length=1
2016-08-11 14:26:50.354 [DEBUG] [b.z.i.protocol.ZWaveController:1194]- Receive queue TAKE: Length=0
2016-08-11 14:26:50.357 [DEBUG] [o.b.z.i.protocol.SerialMessage:243 ]- Assembled message buffer = 01 14 00 04 00 06 0E 32 02 21 74 00 52 43 D8 00 00 00 29 EC E0 6E 
2016-08-11 14:26:50.359 [DEBUG] [b.z.i.protocol.ZWaveController:1195]- Process Message = 01 14 00 04 00 06 0E 32 02 21 74 00 52 43 D8 00 00 00 29 EC E0 6E 
2016-08-11 14:26:50.361 [DEBUG] [b.z.i.protocol.ZWaveController:194 ]- Message: class = ApplicationCommandHandler (0x04), type = Request (0x00), payload = 00 06 0E 32 02 21 74 00 52 43 D8 00 00 00 29 EC E0 
2016-08-11 14:26:50.362 [DEBUG] [ApplicationCommandMessageClass:40  ]- NODE 6: Application Command Request (ALIVE:DONE)
2016-08-11 14:26:50.363 [DEBUG] [ApplicationCommandMessageClass:58  ]- NODE 6: Incoming command class METER
2016-08-11 14:26:50.364 [DEBUG] [z.i.p.c.ZWaveMeterCommandClass:109 ]- NODE 6: Received Meter Request
2016-08-11 14:26:50.366 [DEBUG] [z.i.p.c.ZWaveMeterCommandClass:157 ]- NODE 6: Meter: Type=Electric(1), Scale=W(2), Value=5391.32
2016-08-11 14:26:50.367 [DEBUG] [b.z.i.protocol.ZWaveController:648 ]- Notifying event listeners: ZWaveMeterValueEvent
2016-08-11 14:26:50.368 [DEBUG] [.z.internal.ZWaveActiveBinding:449 ]- ZwaveIncomingEvent
2016-08-11 14:26:50.368 [DEBUG] [.z.internal.ZWaveActiveBinding:466 ]- NODE 6: Got a value event from Z-Wave network, endpoint = 0, command class = METER, value = 5391.32
2016-08-11 14:26:50.373 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:66  ]- Sent message Message: class = SendData (0x13), type = Request (0x00), payload = 06 03 32 01 10 
2016-08-11 14:26:50.375 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:67  ]- Recv message Message: class = ApplicationCommandHandler (0x04), type = Request (0x00), payload = 00 06 0E 32 02 21 74 00 52 43 D8 00 00 00 29 EC E0 
2016-08-11 14:26:50.376 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:68  ]- Checking transaction complete: class=ApplicationCommandHandler, expected=ApplicationCommandHandler, cancelled=false
2016-08-11 14:26:50.377 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:74  ]-          transaction complete!
2016-08-11 14:26:50.378 [DEBUG] [b.z.i.protocol.ZWaveController:648 ]- Notifying event listeners: ZWaveTransactionCompletedEvent
2016-08-11 14:26:50.379 [DEBUG] [.z.internal.ZWaveActiveBinding:449 ]- ZwaveIncomingEvent
2016-08-11 14:26:50.380 [DEBUG] [WaveController$ZWaveSendThread:1364]- NODE 6: Response processed after 70ms/3707ms.
2016-08-11 14:26:51.495 [DEBUG] [.z.i.config.ZWaveConfiguration:288 ]- NODE 9: No database entry: Vision Security [ID:2201,Type:2022]
2016-08-11 14:26:51.497 [DEBUG] [.z.i.config.ZWaveConfiguration:288 ]- NODE 10: No database entry: Vision Security [ID:2201,Type:2022]
2016-08-11 14:26:51.498 [DEBUG] [.z.i.config.ZWaveConfiguration:288 ]- NODE 11: No database entry: Vision Security [ID:2201,Type:2022]
2016-08-11 14:26:51.499 [DEBUG] [.z.i.config.ZWaveConfiguration:288 ]- NODE 12: No database entry: Vision Security [ID:2201,Type:2022]
2016-08-11 14:26:55.306 [DEBUG] [.o.b.z.i.c.ZWaveMeterConverter:70  ]- NODE 6: Generating poll message for METER, endpoint 0
2016-08-11 14:26:55.314 [DEBUG] [z.i.p.c.ZWaveMeterCommandClass:283 ]- NODE 6: Creating new message for application command METER_GET
2016-08-11 14:26:55.315 [DEBUG] [o.b.z.i.protocol.SerialMessage:113 ]- NODE 6: Creating empty message of class = SendData (0x13), type = Request (0x00)
2016-08-11 14:26:55.316 [DEBUG] [b.z.i.protocol.ZWaveController:947 ]- Callback ID = 229
2016-08-11 14:26:55.316 [DEBUG] [b.z.i.protocol.ZWaveController:632 ]- Enqueueing message. Queue length = 1
2016-08-11 14:26:55.316 [DEBUG] [WaveController$ZWaveSendThread:1241]- Took message from queue for sending. Queue length = 0
2016-08-11 14:26:55.318 [DEBUG] [o.b.z.i.protocol.SerialMessage:243 ]- Assembled message buffer = 01 0A 00 13 06 03 32 01 00 25 E5 10 
2016-08-11 14:26:55.318 [DEBUG] [WaveController$ZWaveSendThread:1301]- NODE 6: Sending REQUEST Message = 01 0A 00 13 06 03 32 01 00 25 E5 10 
2016-08-11 14:26:55.327 [DEBUG] [eController$ZWaveReceiveThread:1530]- Receive Message = 01 04 01 13 01 E8 
2016-08-11 14:26:55.329 [DEBUG] [eController$ZWaveReceiveThread:1446]- Receive queue ADD: Length=1
2016-08-11 14:26:55.329 [DEBUG] [b.z.i.protocol.ZWaveController:1194]- Receive queue TAKE: Length=0
2016-08-11 14:26:55.330 [DEBUG] [o.b.z.i.protocol.SerialMessage:243 ]- Assembled message buffer = 01 04 01 13 01 E8 
2016-08-11 14:26:55.331 [DEBUG] [b.z.i.protocol.ZWaveController:1195]- Process Message = 01 04 01 13 01 E8 
2016-08-11 14:26:55.331 [DEBUG] [b.z.i.protocol.ZWaveController:194 ]- Message: class = SendData (0x13), type = Response (0x01), payload = 01 
2016-08-11 14:26:55.332 [DEBUG] [b.z.i.p.s.SendDataMessageClass:38  ]- NODE 6: Sent Data successfully placed on stack.
2016-08-11 14:26:55.344 [DEBUG] [eController$ZWaveReceiveThread:1530]- Receive Message = 01 07 00 13 E5 00 00 02 0C 
2016-08-11 14:26:55.345 [DEBUG] [eController$ZWaveReceiveThread:1446]- Receive queue ADD: Length=1
2016-08-11 14:26:55.345 [DEBUG] [b.z.i.protocol.ZWaveController:1194]- Receive queue TAKE: Length=0
2016-08-11 14:26:55.346 [DEBUG] [o.b.z.i.protocol.SerialMessage:243 ]- Assembled message buffer = 01 09 00 13 E5 00 00 02 00 00 02 
2016-08-11 14:26:55.347 [DEBUG] [b.z.i.protocol.ZWaveController:1195]- Process Message = 01 09 00 13 E5 00 00 02 00 00 02 
2016-08-11 14:26:55.348 [DEBUG] [b.z.i.protocol.ZWaveController:194 ]- Message: class = SendData (0x13), type = Request (0x00), payload = E5 00 00 02 
2016-08-11 14:26:55.348 [DEBUG] [b.z.i.p.s.SendDataMessageClass:73  ]- NODE 6: SendData Request. CallBack ID = 229, Status = Transmission complete and ACK received(0)
2016-08-11 14:26:55.349 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:66  ]- Sent message Message: class = SendData (0x13), type = Request (0x00), payload = 06 03 32 01 00 
2016-08-11 14:26:55.349 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:67  ]- Recv message Message: class = SendData (0x13), type = Request (0x00), payload = E5 00 00 02 
2016-08-11 14:26:55.350 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:68  ]- Checking transaction complete: class=SendData, expected=ApplicationCommandHandler, cancelled=false
2016-08-11 14:26:55.357 [DEBUG] [eController$ZWaveReceiveThread:1530]- Receive Message = 01 14 00 04 00 06 0E 32 02 21 64 00 29 EC E0 00 0A 00 29 EC E0 98 
2016-08-11 14:26:55.360 [DEBUG] [eController$ZWaveReceiveThread:1446]- Receive queue ADD: Length=1
2016-08-11 14:26:55.360 [DEBUG] [b.z.i.protocol.ZWaveController:1194]- Receive queue TAKE: Length=0
2016-08-11 14:26:55.361 [DEBUG] [o.b.z.i.protocol.SerialMessage:243 ]- Assembled message buffer = 01 14 00 04 00 06 0E 32 02 21 64 00 29 EC E0 00 0A 00 29 EC E0 98 
2016-08-11 14:26:55.362 [DEBUG] [b.z.i.protocol.ZWaveController:1195]- Process Message = 01 14 00 04 00 06 0E 32 02 21 64 00 29 EC E0 00 0A 00 29 EC E0 98 
2016-08-11 14:26:55.363 [DEBUG] [b.z.i.protocol.ZWaveController:194 ]- Message: class = ApplicationCommandHandler (0x04), type = Request (0x00), payload = 00 06 0E 32 02 21 64 00 29 EC E0 00 0A 00 29 EC E0 
2016-08-11 14:26:55.364 [DEBUG] [ApplicationCommandMessageClass:40  ]- NODE 6: Application Command Request (ALIVE:DONE)
2016-08-11 14:26:55.365 [DEBUG] [ApplicationCommandMessageClass:58  ]- NODE 6: Incoming command class METER
2016-08-11 14:26:55.366 [DEBUG] [z.i.p.c.ZWaveMeterCommandClass:109 ]- NODE 6: Received Meter Request
2016-08-11 14:26:55.367 [DEBUG] [z.i.p.c.ZWaveMeterCommandClass:157 ]- NODE 6: Meter: Type=Electric(1), Scale=kWh(0), Value=2747.616
2016-08-11 14:26:55.367 [DEBUG] [b.z.i.protocol.ZWaveController:648 ]- Notifying event listeners: ZWaveMeterValueEvent
2016-08-11 14:26:55.368 [DEBUG] [.z.internal.ZWaveActiveBinding:449 ]- ZwaveIncomingEvent
2016-08-11 14:26:55.368 [DEBUG] [.z.internal.ZWaveActiveBinding:466 ]- NODE 6: Got a value event from Z-Wave network, endpoint = 0, command class = METER, value = 2747.616
2016-08-11 14:26:55.369 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:66  ]- Sent message Message: class = SendData (0x13), type = Request (0x00), payload = 06 03 32 01 00 
2016-08-11 14:26:55.370 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:67  ]- Recv message Message: class = ApplicationCommandHandler (0x04), type = Request (0x00), payload = 00 06 0E 32 02 21 64 00 29 EC E0 00 0A 00 29 EC E0 
2016-08-11 14:26:55.371 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:68  ]- Checking transaction complete: class=ApplicationCommandHandler, expected=ApplicationCommandHandler, cancelled=false
2016-08-11 14:26:55.372 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:74  ]-          transaction complete!
2016-08-11 14:26:55.372 [DEBUG] [b.z.i.protocol.ZWaveController:648 ]- Notifying event listeners: ZWaveTransactionCompletedEvent
2016-08-11 14:26:55.372 [DEBUG] [.z.internal.ZWaveActiveBinding:449 ]- ZwaveIncomingEvent
2016-08-11 14:26:55.373 [DEBUG] [WaveController$ZWaveSendThread:1364]- NODE 6: Response processed after 54ms/3707ms.
2016-08-11 14:26:57.484 [DEBUG] [.z.i.config.ZWaveConfiguration:288 ]- NODE 9: No database entry: Vision Security [ID:2201,Type:2022]
2016-08-11 14:26:57.485 [DEBUG] [.z.i.config.ZWaveConfiguration:288 ]- NODE 10: No database entry: Vision Security [ID:2201,Type:2022]
2016-08-11 14:26:57.486 [DEBUG] [.z.i.config.ZWaveConfiguration:288 ]- NODE 11: No database entry: Vision Security [ID:2201,Type:2022]
2016-08-11 14:26:57.487 [DEBUG] [.z.i.config.ZWaveConfiguration:288 ]- NODE 12: No database entry: Vision Security [ID:2201,Type:2022]

chris · August 11, 2016, 6:38pm

I doubt that it is defined not to be called publicly - it’s a configurtion command class, it should respond if the command is correctly formatted. So either the command is incorrect for some reason (maybe 5 is invalid?) or maybe there’s a bug in the device (or???).

No - this is not possible.

chris · August 11, 2016, 6:47pm

Actually, I’m wrong. I forgot there’s a write only flag, so we can use this if needed, but we should undersand the problem first.

djarvis8 · August 11, 2016, 11:03pm

Ok. Let me know if there is any more debug information that would be valuable.

djarvis8 · August 12, 2016, 1:09pm

I put the device right next to my host but the results are the same.

chris · August 12, 2016, 1:55pm

I think I will make this a write only parameter in the database. Thinking about it some more, it probably doesn’t make sense for it to be readable anyway.

djarvis8 · August 12, 2016, 2:12pm

Cool… how can I do that temporarily on my instance? I checked out node8.xml and saw some parameters (but #6 wasn’t listed, only like 2, 5, and 8 for some reason). Would I have to add 6 and make writeOnly true? Will node8.xml eventually be overwritten though?

chris · August 12, 2016, 4:01pm

You can’t easily configure this - it won’t impact your XML file, it’s done in the database which is compiled into the binding.

I’ll take a look tonight at the database just to remind myself how it works in OH1

djarvis8 · August 14, 2016, 12:27pm

Sounds good. Thanks!

chris · August 14, 2016, 1:59pm

This was implemented already, so if you update the binding now, it should (hopefully!) now not perform the readback…

djarvis8 · August 14, 2016, 2:30pm

Ok, forgive my ignorance. I’ve been painfully trudging around trying to find the latest zwave release binary jar file for OH1.

And I can’t find it.

chris · August 14, 2016, 2:31pm

The nightly build is on cloudbees -: https://openhab.ci.cloudbees.com/

djarvis8 · August 15, 2016, 4:27am

So I downloaded this: https://openhab.ci.cloudbees.com/job/openHAB-Distribution/lastSuccessfulBuild/artifact/distributions/openhab-offline/target/openhab-offline-2.0.0-SNAPSHOT.zip

unzipped it, found org.openhab.binding.zwave-2.0.0-SNAPSHOT.jar and replaced my previous version (1.9.0) in my addons folder. I now have a non-working system (never comes online in habmin). Nothing interesting shows up on openhab.log.