I still have an issue with this dual switch. Here is the openhab.log file with debug turned on. I pared it down to just the lines that I think someone with intimate knowledge of the zwave binding would be interested in. Based on it I was hoping that the zwave binding author(s) may be able to figure out how to report the information about when the manual toggle switch is operated. I assume this could be incorporate it in the binding in some way so the interface indicator of the light being on or off would reflex the actual state of the light. I did add a few comment lines in the log file that indicate the different sections.
For both switches I started with the light off. I turned it on then off via the openhab web interface. Next I turned it on then off with the physical wall switch. Whoever looks at this let me know if you need something else.
(Note: I ran into a posting length limit so the next post will be the log for the second switch.)
---------------------------------------------------------------------------------------------------------------------------------------------------------------
--------------------- Switch Front_Outdoor_Light "Front Outdoor Lights" <light> (gMain, gFrontOutdoorLights) {zwave="5:1:command=switch_binary"}
---------------------------------------------------------------------------------------------------------------------------------------------------------------
-------------- used openhab interface to turn light on
13:10:27.039 [DEBUG] [o.i.r.i.resources.ItemResource:193 ] - Received HTTP POST request at 'items/Front_Outdoor_Light' with value 'ON'.
13:10:27.040 [DEBUG] [.ZWaveBinarySwitchCommandClass:146 ] - NODE 5: Creating new message for application command SWITCH_BINARY_SET
13:10:27.040 [DEBUG] [o.b.z.i.protocol.SerialMessage:109 ] - NODE 5: Creating empty message of class = SendData (0x13), type = Request (0x00)
13:10:27.040 [DEBUG] [.z.internal.protocol.ZWaveNode:623 ] - NODE 5: Encapsulating message, instance / endpoint 1
13:10:27.040 [DEBUG] [ZWaveMultiInstanceCommandClass:560 ] - NODE 5: Creating new message for command MULTI_CHANNEL_ENCAP endpoint 1
13:10:27.040 [DEBUG] [b.z.i.protocol.ZWaveController:898 ] - Callback ID = 21
13:10:27.040 [DEBUG] [b.z.i.protocol.ZWaveController:580 ] - Enqueueing message. Queue length = 1
13:10:27.040 [DEBUG] [WaveController$ZWaveSendThread:1171 ] - Took message from queue for sending. Queue length = 0
13:10:27.041 [DEBUG] [o.b.z.i.protocol.SerialMessage:233 ] - Assembled message buffer = 01 0E 00 13 05 07 60 0D 01 01 25 01 FF 25 15 66
13:10:27.041 [DEBUG] [WaveController$ZWaveSendThread:1228 ] - NODE 5: Sending REQUEST Message = 01 0E 00 13 05 07 60 0D 01 01 25 01 FF 25 15 66
13:10:27.049 [DEBUG] [eController$ZWaveReceiveThread:1441 ] - Receive Message = 01 04 01 13 01 E8
13:10:27.049 [DEBUG] [eController$ZWaveReceiveThread:1365 ] - Receive queue ADD: Length=1
13:10:27.049 [DEBUG] [b.z.i.protocol.ZWaveController:1123 ] - Receive queue TAKE: Length=0
13:10:27.049 [DEBUG] [o.b.z.i.protocol.SerialMessage:233 ] - Assembled message buffer = 01 04 01 13 01 E8
13:10:27.049 [DEBUG] [b.z.i.protocol.ZWaveController:1124 ] - Process Message = 01 04 01 13 01 E8
13:10:27.049 [DEBUG] [b.z.i.protocol.ZWaveController:190 ] - Message: class = SendData (0x13), type = Response (0x01), payload = 01
13:10:27.049 [DEBUG] [b.z.i.p.s.SendDataMessageClass:38 ] - NODE 5: Sent Data successfully placed on stack.
13:10:27.067 [DEBUG] [eController$ZWaveReceiveThread:1441 ] - Receive Message = 01 07 00 13 15 00 00 02 FC
13:10:27.067 [DEBUG] [eController$ZWaveReceiveThread:1365 ] - Receive queue ADD: Length=1
13:10:27.067 [DEBUG] [b.z.i.protocol.ZWaveController:1123 ] - Receive queue TAKE: Length=0
13:10:27.067 [DEBUG] [o.b.z.i.protocol.SerialMessage:233 ] - Assembled message buffer = 01 09 00 13 15 00 00 02 00 00 F2
13:10:27.067 [DEBUG] [b.z.i.protocol.ZWaveController:1124 ] - Process Message = 01 09 00 13 15 00 00 02 00 00 F2
13:10:27.067 [DEBUG] [b.z.i.protocol.ZWaveController:190 ] - Message: class = SendData (0x13), type = Request (0x00), payload = 15 00 00 02
13:10:27.068 [DEBUG] [b.z.i.p.s.SendDataMessageClass:74 ] - NODE 5: SendData Request. CallBack ID = 21, Status = Transmission complete and ACK received(0)
13:10:27.068 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:63 ] - Sent message Message: class = SendData (0x13), type = Request (0x00), payload = 05 07 60 0D 01 01 25 01 FF
13:10:27.068 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:64 ] - Recv message Message: class = SendData (0x13), type = Request (0x00), payload = 15 00 00 02
13:10:27.068 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:65 ] - Checking transaction complete: class=SendData, expected=SendData, cancelled=false
13:10:27.068 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:68 ] - transaction complete!
13:10:27.068 [DEBUG] [b.z.i.protocol.ZWaveController:595 ] - Notifying event listeners: ZWaveTransactionCompletedEvent
13:10:27.068 [DEBUG] [.z.internal.ZWaveActiveBinding:433 ] - ZwaveIncomingEvent
13:10:27.068 [DEBUG] [WaveController$ZWaveSendThread:1287 ] - NODE 5: Response processed after 27ms/1259ms.
13:10:27.358 [DEBUG] [o.o.i.r.i.b.GeneralBroadcaster:46 ] - broadcaster 'org.openhab.io.rest.internal.broadcaster.GeneralBroadcaster$1@16370e86' is empty
13:10:27.428 [DEBUG] [.r.i.resources.SitemapResource:146 ] - Received HTTP GET request at 'sitemaps/copley/0000' for media type 'null'.
13:10:27.438 [DEBUG] [.r.i.resources.SitemapResource:146 ] - Received HTTP GET request at 'sitemaps/copley/0000' for media type 'null'.
13:10:27.448 [DEBUG] [.r.i.resources.SitemapResource:146 ] - Received HTTP GET request at 'sitemaps/copley/0000' for media type 'null'.
------------------------- used openhab interface to turn light off
13:10:35.140 [DEBUG] [o.i.r.i.resources.ItemResource:193 ] - Received HTTP POST request at 'items/Front_Outdoor_Light' with value 'OFF'.
13:10:35.141 [DEBUG] [.ZWaveBinarySwitchCommandClass:146 ] - NODE 5: Creating new message for application command SWITCH_BINARY_SET
13:10:35.141 [DEBUG] [o.b.z.i.protocol.SerialMessage:109 ] - NODE 5: Creating empty message of class = SendData (0x13), type = Request (0x00)
13:10:35.141 [DEBUG] [.z.internal.protocol.ZWaveNode:623 ] - NODE 5: Encapsulating message, instance / endpoint 1
13:10:35.141 [DEBUG] [ZWaveMultiInstanceCommandClass:560 ] - NODE 5: Creating new message for command MULTI_CHANNEL_ENCAP endpoint 1
13:10:35.141 [DEBUG] [b.z.i.protocol.ZWaveController:898 ] - Callback ID = 22
13:10:35.141 [DEBUG] [b.z.i.protocol.ZWaveController:580 ] - Enqueueing message. Queue length = 1
13:10:35.141 [DEBUG] [WaveController$ZWaveSendThread:1171 ] - Took message from queue for sending. Queue length = 0
13:10:35.142 [DEBUG] [o.b.z.i.protocol.SerialMessage:233 ] - Assembled message buffer = 01 0E 00 13 05 07 60 0D 01 01 25 01 00 25 16 9A
13:10:35.142 [DEBUG] [WaveController$ZWaveSendThread:1228 ] - NODE 5: Sending REQUEST Message = 01 0E 00 13 05 07 60 0D 01 01 25 01 00 25 16 9A
13:10:35.150 [DEBUG] [eController$ZWaveReceiveThread:1441 ] - Receive Message = 01 04 01 13 01 E8
13:10:35.150 [DEBUG] [eController$ZWaveReceiveThread:1365 ] - Receive queue ADD: Length=1
13:10:35.150 [DEBUG] [b.z.i.protocol.ZWaveController:1123 ] - Receive queue TAKE: Length=0
13:10:35.150 [DEBUG] [o.b.z.i.protocol.SerialMessage:233 ] - Assembled message buffer = 01 04 01 13 01 E8
13:10:35.150 [DEBUG] [b.z.i.protocol.ZWaveController:1124 ] - Process Message = 01 04 01 13 01 E8
13:10:35.150 [DEBUG] [b.z.i.protocol.ZWaveController:190 ] - Message: class = SendData (0x13), type = Response (0x01), payload = 01
13:10:35.150 [DEBUG] [b.z.i.p.s.SendDataMessageClass:38 ] - NODE 5: Sent Data successfully placed on stack.
13:10:35.167 [DEBUG] [eController$ZWaveReceiveThread:1441 ] - Receive Message = 01 07 00 13 16 00 00 02 FF
13:10:35.168 [DEBUG] [eController$ZWaveReceiveThread:1365 ] - Receive queue ADD: Length=1
13:10:35.168 [DEBUG] [b.z.i.protocol.ZWaveController:1123 ] - Receive queue TAKE: Length=0
13:10:35.168 [DEBUG] [o.b.z.i.protocol.SerialMessage:233 ] - Assembled message buffer = 01 09 00 13 16 00 00 02 00 00 F1
13:10:35.168 [DEBUG] [b.z.i.protocol.ZWaveController:1124 ] - Process Message = 01 09 00 13 16 00 00 02 00 00 F1
13:10:35.168 [DEBUG] [b.z.i.protocol.ZWaveController:190 ] - Message: class = SendData (0x13), type = Request (0x00), payload = 16 00 00 02
13:10:35.168 [DEBUG] [b.z.i.p.s.SendDataMessageClass:74 ] - NODE 5: SendData Request. CallBack ID = 22, Status = Transmission complete and ACK received(0)
13:10:35.168 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:63 ] - Sent message Message: class = SendData (0x13), type = Request (0x00), payload = 05 07 60 0D 01 01 25 01 00
13:10:35.168 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:64 ] - Recv message Message: class = SendData (0x13), type = Request (0x00), payload = 16 00 00 02
13:10:35.168 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:65 ] - Checking transaction complete: class=SendData, expected=SendData, cancelled=false
13:10:35.168 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:68 ] - transaction complete!
13:10:35.168 [DEBUG] [b.z.i.protocol.ZWaveController:595 ] - Notifying event listeners: ZWaveTransactionCompletedEvent
13:10:35.168 [DEBUG] [.z.internal.ZWaveActiveBinding:433 ] - ZwaveIncomingEvent
13:10:35.168 [DEBUG] [WaveController$ZWaveSendThread:1287 ] - NODE 5: Response processed after 26ms/1259ms.
13:10:35.455 [DEBUG] [o.o.i.r.i.b.GeneralBroadcaster:46 ] - broadcaster 'org.openhab.io.rest.internal.broadcaster.GeneralBroadcaster$1@16370e86' is empty
13:10:35.508 [DEBUG] [.r.i.resources.SitemapResource:146 ] - Received HTTP GET request at 'sitemaps/copley/0000' for media type 'null'.
13:10:35.563 [DEBUG] [.r.i.resources.SitemapResource:146 ] - Received HTTP GET request at 'sitemaps/copley/0000' for media type 'null'.
13:10:35.590 [DEBUG] [.r.i.resources.SitemapResource:146 ] - Received HTTP GET request at 'sitemaps/copley/0000' for media type 'null'.
13:10:36.995 [DEBUG] [.myopenhab.internal.MyOHClient:150 ] - Transport.EVENT_REQUEST_HEADERS
13:10:37.384 [DEBUG] [.myopenhab.internal.MyOHClient:150 ] - Transport.EVENT_REQUEST_HEADERS
---------------------------------- I believe this is the point where I flipped the physical switch on ***********************
13:10:40.297 [DEBUG] [eController$ZWaveReceiveThread:1441 ] - Receive Message = 01 0D 00 04 04 05 07 60 0A 01 10 01 20 25 8F
13:10:40.297 [DEBUG] [eController$ZWaveReceiveThread:1365 ] - Receive queue ADD: Length=1
13:10:40.297 [DEBUG] [b.z.i.protocol.ZWaveController:1123 ] - Receive queue TAKE: Length=0
13:10:40.298 [DEBUG] [o.b.z.i.protocol.SerialMessage:233 ] - Assembled message buffer = 01 0D 00 04 04 05 07 60 0A 01 10 01 20 25 8F
13:10:40.298 [DEBUG] [b.z.i.protocol.ZWaveController:1124 ] - Process Message = 01 0D 00 04 04 05 07 60 0A 01 10 01 20 25 8F
13:10:40.298 [DEBUG] [b.z.i.protocol.ZWaveController:190 ] - Message: class = ApplicationCommandHandler (0x04), type = Request (0x00), payload = 04 05 07 60 0A 01 10 01 20 25
13:10:40.298 [DEBUG] [ApplicationCommandMessageClass:38 ] - NODE 5: Application Command Request (ALIVE:DONE)
13:10:40.298 [DEBUG] [ApplicationCommandMessageClass:56 ] - NODE 5: Incoming command class MULTI_INSTANCE
13:10:40.298 [DEBUG] [ZWaveMultiInstanceCommandClass:145 ] - NODE 5: Received Multi-instance/Multi-channel Request
13:10:40.298 [DEBUG] [ZWaveMultiInstanceCommandClass:301 ] - NODE 5: Process Multi-channel capability Report
13:10:40.298 [DEBUG] [ZWaveMultiInstanceCommandClass:307 ] - NODE 5: Endpoints are the same device class = true
13:10:40.298 [DEBUG] [ZWaveMultiInstanceCommandClass:373 ] - NODE 5: Endpoint Id = 1
13:10:40.299 [DEBUG] [ZWaveMultiInstanceCommandClass:374 ] - NODE 5: Endpoints is dynamic = false
13:10:40.299 [DEBUG] [ZWaveMultiInstanceCommandClass:375 ] - NODE 5: Basic = Routing Slave 0x04
13:10:40.299 [DEBUG] [ZWaveMultiInstanceCommandClass:376 ] - NODE 5: Generic = Binary Switch 0x10
13:10:40.299 [DEBUG] [ZWaveMultiInstanceCommandClass:377 ] - NODE 5: Specific = Binary Power Switch 0x01
13:10:40.299 [DEBUG] [.o.b.z.i.p.c.ZWaveCommandClass:224 ] - NODE 5: Creating new instance of command class BASIC
13:10:40.299 [DEBUG] [ZWaveMultiInstanceCommandClass:408 ] - NODE 5: Endpoint 1: Adding command class BASIC.
13:10:40.299 [DEBUG] [.o.b.z.i.p.c.ZWaveCommandClass:224 ] - NODE 5: Creating new instance of command class SWITCH_BINARY
13:10:40.299 [DEBUG] [ZWaveMultiInstanceCommandClass:408 ] - NODE 5: Endpoint 1: Adding command class SWITCH_BINARY.
13:10:40.299 [DEBUG] [ZWaveMultiInstanceCommandClass:373 ] - NODE 5: Endpoint Id = 2
13:10:40.299 [DEBUG] [ZWaveMultiInstanceCommandClass:374 ] - NODE 5: Endpoints is dynamic = false
13:10:40.299 [DEBUG] [ZWaveMultiInstanceCommandClass:375 ] - NODE 5: Basic = Routing Slave 0x04
13:10:40.299 [DEBUG] [ZWaveMultiInstanceCommandClass:376 ] - NODE 5: Generic = Binary Switch 0x10
13:10:40.299 [DEBUG] [ZWaveMultiInstanceCommandClass:377 ] - NODE 5: Specific = Binary Power Switch 0x01
13:10:40.299 [DEBUG] [.o.b.z.i.p.c.ZWaveCommandClass:224 ] - NODE 5: Creating new instance of command class BASIC
13:10:40.299 [DEBUG] [ZWaveMultiInstanceCommandClass:408 ] - NODE 5: Endpoint 2: Adding command class BASIC.
13:10:40.300 [DEBUG] [.o.b.z.i.p.c.ZWaveCommandClass:224 ] - NODE 5: Creating new instance of command class SWITCH_BINARY
13:10:40.300 [DEBUG] [ZWaveMultiInstanceCommandClass:408 ] - NODE 5: Endpoint 2: Adding command class SWITCH_BINARY.
13:10:40.300 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:63 ] - Sent message Message: class = SendData (0x13), type = Request (0x00), payload = 05 07 60 0D 01 01 25 01 00
13:10:40.300 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:64 ] - Recv message Message: class = ApplicationCommandHandler (0x04), type = Request (0x00), payload = 04 05 07 60 0A 01 10 01 20 25
13:10:40.300 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:65 ] - Checking transaction complete: class=ApplicationCommandHandler, expected=SendData, cancelled=false
---------------------------- and this is the place where I turned the physical switch back off again *********************
13:10:45.965 [DEBUG] [eController$ZWaveReceiveThread:1441 ] - Receive Message = 01 0D 00 04 04 05 07 60 0A 01 10 01 20 25 8F
13:10:45.965 [DEBUG] [eController$ZWaveReceiveThread:1365 ] - Receive queue ADD: Length=1
13:10:45.965 [DEBUG] [b.z.i.protocol.ZWaveController:1123 ] - Receive queue TAKE: Length=0
13:10:45.965 [DEBUG] [o.b.z.i.protocol.SerialMessage:233 ] - Assembled message buffer = 01 0D 00 04 04 05 07 60 0A 01 10 01 20 25 8F
13:10:45.966 [DEBUG] [b.z.i.protocol.ZWaveController:1124 ] - Process Message = 01 0D 00 04 04 05 07 60 0A 01 10 01 20 25 8F
13:10:45.966 [DEBUG] [b.z.i.protocol.ZWaveController:190 ] - Message: class = ApplicationCommandHandler (0x04), type = Request (0x00), payload = 04 05 07 60 0A 01 10 01 20 25
13:10:45.966 [DEBUG] [ApplicationCommandMessageClass:38 ] - NODE 5: Application Command Request (ALIVE:DONE)
13:10:45.966 [DEBUG] [ApplicationCommandMessageClass:56 ] - NODE 5: Incoming command class MULTI_INSTANCE
13:10:45.966 [DEBUG] [ZWaveMultiInstanceCommandClass:145 ] - NODE 5: Received Multi-instance/Multi-channel Request
13:10:45.966 [DEBUG] [ZWaveMultiInstanceCommandClass:301 ] - NODE 5: Process Multi-channel capability Report
13:10:45.966 [DEBUG] [ZWaveMultiInstanceCommandClass:307 ] - NODE 5: Endpoints are the same device class = true
13:10:45.966 [DEBUG] [ZWaveMultiInstanceCommandClass:373 ] - NODE 5: Endpoint Id = 1
13:10:45.966 [DEBUG] [ZWaveMultiInstanceCommandClass:374 ] - NODE 5: Endpoints is dynamic = false
13:10:45.966 [DEBUG] [ZWaveMultiInstanceCommandClass:375 ] - NODE 5: Basic = Routing Slave 0x04
13:10:45.966 [DEBUG] [ZWaveMultiInstanceCommandClass:376 ] - NODE 5: Generic = Binary Switch 0x10
13:10:45.966 [DEBUG] [ZWaveMultiInstanceCommandClass:377 ] - NODE 5: Specific = Binary Power Switch 0x01
13:10:45.966 [DEBUG] [.o.b.z.i.p.c.ZWaveCommandClass:224 ] - NODE 5: Creating new instance of command class BASIC
13:10:45.966 [DEBUG] [ZWaveMultiInstanceCommandClass:408 ] - NODE 5: Endpoint 1: Adding command class BASIC.
13:10:45.966 [DEBUG] [.o.b.z.i.p.c.ZWaveCommandClass:224 ] - NODE 5: Creating new instance of command class SWITCH_BINARY
13:10:45.966 [DEBUG] [ZWaveMultiInstanceCommandClass:408 ] - NODE 5: Endpoint 1: Adding command class SWITCH_BINARY.
13:10:45.966 [DEBUG] [ZWaveMultiInstanceCommandClass:373 ] - NODE 5: Endpoint Id = 2
13:10:45.967 [DEBUG] [ZWaveMultiInstanceCommandClass:374 ] - NODE 5: Endpoints is dynamic = false
13:10:45.967 [DEBUG] [ZWaveMultiInstanceCommandClass:375 ] - NODE 5: Basic = Routing Slave 0x04
13:10:45.967 [DEBUG] [ZWaveMultiInstanceCommandClass:376 ] - NODE 5: Generic = Binary Switch 0x10
13:10:45.967 [DEBUG] [ZWaveMultiInstanceCommandClass:377 ] - NODE 5: Specific = Binary Power Switch 0x01
13:10:45.967 [DEBUG] [.o.b.z.i.p.c.ZWaveCommandClass:224 ] - NODE 5: Creating new instance of command class BASIC
13:10:45.967 [DEBUG] [ZWaveMultiInstanceCommandClass:408 ] - NODE 5: Endpoint 2: Adding command class BASIC.
13:10:45.967 [DEBUG] [.o.b.z.i.p.c.ZWaveCommandClass:224 ] - NODE 5: Creating new instance of command class SWITCH_BINARY
13:10:45.967 [DEBUG] [ZWaveMultiInstanceCommandClass:408 ] - NODE 5: Endpoint 2: Adding command class SWITCH_BINARY.
13:10:45.967 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:63 ] - Sent message Message: class = SendData (0x13), type = Request (0x00), payload = 05 07 60 0D 01 01 25 01 00
13:10:45.967 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:64 ] - Recv message Message: class = ApplicationCommandHandler (0x04), type = Request (0x00), payload = 04 05 07 60 0A 01 10 01 20 25
13:10:45.967 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:65 ] - Checking transaction complete: class=ApplicationCommandHandler, expected=SendData, cancelled=false