VRCS4 Troubles

Winston_Ewert · June 13, 2019, 1:41am

Platform information:
- Hardware: Macbook Air
- OS: Mac OS X 10.14.4
- Java Runtime Environment: 1.8.0_121
- openHAB version: 4.2.1

I’m attempting to configure a VRCS4 4-scene controller. I believe that I need to set association groups for it to include the controller. However, every time I set it, it reverts back to having no group set.

My capture debug log was too large, here is a link: https://pastebin.com/NN57mEEE

sihui · June 13, 2019, 6:03am

If you are talking about zwave, yes, you should set the association groups to your controller.
Please use HABmin to to do that.
There is a display bug in HABmin, although the associations are set correctly, they don’t show up.
You need to catch the events from the scene_number channel in a rule to use that device.
https://www.cd-jackson.com/index.php/zwave/zwave-device-database/zwave-device-list/devicesummary/197

There is nothing in it from zwave …:

log:set DEBUG org.openhab.binding.zwave is the correct Karaf command to set the zwave binding to debug.

Winston_Ewert · June 13, 2019, 6:01pm

Thanks for your help!

I had been using PaperUI to set the groups, so I tried with Habmin

Now the association groups are listed as pending and I get the following log: (thanks for providing the right log setting)

10:49:39.037 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 2: Configuration update ignored config_7_1 to 99 (BigDecimal)

10:49:39.037 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 2: Configuration update ignored config_8_2 to 5 (BigDecimal)

10:49:39.037 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 2: Configuration update ignored config_9_1 to 1 (BigDecimal)

10:49:39.038 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 2: Configuration update ignored config_12_2 to 3 (BigDecimal)

10:49:39.038 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 2: Configuration update ignored config_3_1 to 0 (BigDecimal)

10:49:39.038 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 2: Configuration update ignored config_4_1 to 0 (BigDecimal)

10:49:39.038 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 2: Configuration update set config_5_1 to 0 (BigDecimal)

10:49:39.038 [DEBUG] [dclass.ZWaveConfigurationCommandClass] - NODE 2: Creating new message for application command CONFIGURATION_SET

10:49:39.038 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 2: SECURITY not supported

10:49:39.039 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 2: Command Class COMMAND_CLASS_CONFIGURATION is NOT required to be secured

10:49:39.039 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 2: Adding to device queue

10:49:39.040 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 2: Added 922 to queue - size 2

10:49:39.040 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

10:49:39.041 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0C 00 13 02 05 70 04 05 01 00 25 8C 3E 

10:49:39.041 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 2: Sending REQUEST Message = 01 0C 00 13 02 05 70 04 05 01 00 25 8C 3E 

10:49:39.041 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT

10:49:39.041 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 922: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 140

10:49:39.042 [DEBUG] [dclass.ZWaveConfigurationCommandClass] - NODE 2: Creating new message for application command CONFIGURATIONCMD_GET

10:49:39.042 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 2: SECURITY not supported

10:49:39.042 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 2: Command Class COMMAND_CLASS_CONFIGURATION is NOT required to be secured

10:49:39.042 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 2: Adding to device queue

10:49:39.043 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 2: Added 923 to queue - size 2

10:49:39.043 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

10:49:39.043 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 2: Configuration update ignored node_id to 2 (BigDecimal)

10:49:39.044 [INFO ] [smarthome.event.ThingUpdatedEvent ] - Thing 'zwave:device:050e0827:node2' has been updated.

10:49:39.046 [INFO ] [smarthome.event.ConfigStatusInfoEvent] - ConfigStatusInfo [configStatusMessages=[ConfigStatusMessage [parameterName=config_5_1, type=PENDING, messageKey=null, arguments=null, message=null, statusCode=null]]]

10:49:41.045 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - NODE 2: TID 922: Timeout at state WAIT_RESPONSE. 3 retries remaining.

10:49:41.046 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - Aborting Transaction!

10:49:41.046 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 922: Transaction ABORTED

10:49:41.046 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 03 00 16 EA 

10:49:41.046 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 03 00 16 EA 

10:49:41.047 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT

10:49:41.047 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

10:49:53.047 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - NODE 2: TID 922: Timeout at state ABORTED. 3 retries remaining.

10:49:53.047 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - TID 922: Transaction is current transaction, so clearing!!!!!

10:49:53.047 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 922: Transaction CANCELLED

10:49:53.047 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent

10:49:53.048 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 2: notifyTransactionResponse TID:922 CANCELLED

10:49:53.048 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

10:49:53.048 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 02 03 70 05 05 25 8D 3F 

10:49:53.049 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 2: Sending REQUEST Message = 01 0A 00 13 02 03 70 05 05 25 8D 3F 

10:49:53.049 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT

10:49:53.049 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 923: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 141

10:49:55.055 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - NODE 2: TID 923: Timeout at state WAIT_RESPONSE. 3 retries remaining.

10:49:55.055 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - Aborting Transaction!

10:49:55.055 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 923: Transaction ABORTED

10:49:55.055 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 03 00 16 EA 

10:49:55.056 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 03 00 16 EA 

10:49:55.056 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT

10:49:55.056 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

10:50:07.059 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - NODE 2: TID 923: Timeout at state ABORTED. 3 retries remaining.

10:50:07.060 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - TID 923: Transaction is current transaction, so clearing!!!!!

10:50:07.061 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 923: Transaction CANCELLED

10:50:07.061 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent

10:50:07.062 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 2: notifyTransactionResponse TID:923 CANCELLED

10:50:07.063 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

10:50:31.841 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 9: Configuration update received

10:50:31.842 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 9: Configuration update set group_4 to [controller] (ArrayList)

10:50:31.843 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 9: Current Members ZWaveAssociationGroup [index=4, name=null, profile1=null, profile2=null, associations=[node_1]]

10:50:31.843 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 9: Current Members ZWaveAssociationGroup [index=4, name=null, profile1=null, profile2=null, associations=[node_1]]

10:50:31.843 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 9: New Members ZWaveAssociationGroup [index=4, name=null, profile1=null, profile2=null, associations=[node_1_1]]

10:50:31.844 [DEBUG] [andclass.ZWaveAssociationCommandClass] - NODE 9: Creating new message for application command ASSOCIATIONCMD_REMOVE group=4, node=1

10:50:31.844 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 9: SECURITY not supported

10:50:31.845 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 9: Command Class COMMAND_CLASS_ASSOCIATION is NOT required to be secured

10:50:31.845 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 9: Adding to device queue

10:50:31.845 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 9: Added 924 to queue - size 2

10:50:31.846 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

10:50:31.846 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0B 00 13 09 04 85 04 04 01 25 8E C5 

10:50:31.846 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 9: Sending REQUEST Message = 01 0B 00 13 09 04 85 04 04 01 25 8E C5 

10:50:31.846 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT

10:50:31.847 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 924: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 142

10:50:31.847 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 9: New Members ZWaveAssociationGroup [index=4, name=null, profile1=null, profile2=null, associations=[node_1_1]]

10:50:31.847 [DEBUG] [andclass.ZWaveAssociationCommandClass] - NODE 9: Creating new message for application command ASSOCIATIONCMD_SET, group=4, node=1

10:50:31.848 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 9: SECURITY not supported

10:50:31.848 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 9: Command Class COMMAND_CLASS_ASSOCIATION is NOT required to be secured

10:50:31.849 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 9: Adding to device queue

10:50:31.849 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 9: Added 925 to queue - size 2

10:50:31.850 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

10:50:31.850 [DEBUG] [andclass.ZWaveAssociationCommandClass] - NODE 9: Creating new message for application command ASSOCIATIONCMD_GET group 4

10:50:31.851 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 9: SECURITY not supported

10:50:31.852 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 9: Command Class COMMAND_CLASS_ASSOCIATION is NOT required to be secured

10:50:31.853 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 9: Adding to device queue

10:50:31.854 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 9: Added 926 to queue - size 3

10:50:31.855 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

10:50:31.855 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 9: Configuration update set group_1 to [controller] (ArrayList)

10:50:31.856 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 9: Current Members ZWaveAssociationGroup [index=1, name=null, profile1=null, profile2=null, associations=[node_1]]

10:50:31.856 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 9: Current Members ZWaveAssociationGroup [index=1, name=null, profile1=null, profile2=null, associations=[node_1]]

10:50:31.857 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 9: New Members ZWaveAssociationGroup [index=1, name=null, profile1=null, profile2=null, associations=[node_1_1]]

10:50:31.858 [DEBUG] [andclass.ZWaveAssociationCommandClass] - NODE 9: Creating new message for application command ASSOCIATIONCMD_REMOVE group=1, node=1

10:50:31.858 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 9: SECURITY not supported

10:50:31.859 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 9: Command Class COMMAND_CLASS_ASSOCIATION is NOT required to be secured

10:50:31.860 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 9: Adding to device queue

10:50:31.860 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 9: Added 927 to queue - size 4

10:50:31.861 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

10:50:31.861 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 9: New Members ZWaveAssociationGroup [index=1, name=null, profile1=null, profile2=null, associations=[node_1_1]]

10:50:31.862 [DEBUG] [andclass.ZWaveAssociationCommandClass] - NODE 9: Creating new message for application command ASSOCIATIONCMD_SET, group=1, node=1

10:50:31.862 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 9: SECURITY not supported

10:50:31.862 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 9: Command Class COMMAND_CLASS_ASSOCIATION is NOT required to be secured

10:50:31.862 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 9: Adding to device queue

10:50:31.863 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 9: Added 928 to queue - size 5

10:50:31.863 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

10:50:31.863 [DEBUG] [andclass.ZWaveAssociationCommandClass] - NODE 9: Creating new message for application command ASSOCIATIONCMD_GET group 1

10:50:31.864 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 9: SECURITY not supported

10:50:31.864 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 9: Command Class COMMAND_CLASS_ASSOCIATION is NOT required to be secured

10:50:31.864 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 9: Adding to device queue

10:50:31.865 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 9: Added 929 to queue - size 6

10:50:31.865 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

10:50:31.865 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 9: Configuration update set group_3 to [controller] (ArrayList)

10:50:31.866 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 9: Current Members ZWaveAssociationGroup [index=3, name=null, profile1=null, profile2=null, associations=[node_1]]

10:50:31.866 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 9: Current Members ZWaveAssociationGroup [index=3, name=null, profile1=null, profile2=null, associations=[node_1]]

10:50:31.867 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 9: New Members ZWaveAssociationGroup [index=3, name=null, profile1=null, profile2=null, associations=[node_1_1]]

10:50:31.867 [DEBUG] [andclass.ZWaveAssociationCommandClass] - NODE 9: Creating new message for application command ASSOCIATIONCMD_REMOVE group=3, node=1

10:50:31.868 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 9: SECURITY not supported

10:50:31.868 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 9: Command Class COMMAND_CLASS_ASSOCIATION is NOT required to be secured

10:50:31.868 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 9: Adding to device queue

10:50:31.869 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 9: Added 930 to queue - size 7

10:50:31.869 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

10:50:31.869 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 9: New Members ZWaveAssociationGroup [index=3, name=null, profile1=null, profile2=null, associations=[node_1_1]]

10:50:31.870 [DEBUG] [andclass.ZWaveAssociationCommandClass] - NODE 9: Creating new message for application command ASSOCIATIONCMD_SET, group=3, node=1

10:50:31.870 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 9: SECURITY not supported

10:50:31.870 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 9: Command Class COMMAND_CLASS_ASSOCIATION is NOT required to be secured

10:50:31.871 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 9: Adding to device queue

10:50:31.871 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 9: Added 931 to queue - size 8

10:50:31.872 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

10:50:31.872 [DEBUG] [andclass.ZWaveAssociationCommandClass] - NODE 9: Creating new message for application command ASSOCIATIONCMD_GET group 3

10:50:31.873 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 9: SECURITY not supported

10:50:31.873 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 9: Command Class COMMAND_CLASS_ASSOCIATION is NOT required to be secured

10:50:31.873 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 9: Adding to device queue

10:50:31.874 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 9: Added 932 to queue - size 9

10:50:31.874 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

10:50:31.874 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 9: Configuration update set group_2 to [controller] (ArrayList)

10:50:31.875 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 9: Current Members ZWaveAssociationGroup [index=2, name=null, profile1=null, profile2=null, associations=[node_1]]

10:50:31.875 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 9: Current Members ZWaveAssociationGroup [index=2, name=null, profile1=null, profile2=null, associations=[node_1]]

10:50:31.879 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 9: New Members ZWaveAssociationGroup [index=2, name=null, profile1=null, profile2=null, associations=[node_1_1]]

10:50:31.880 [DEBUG] [andclass.ZWaveAssociationCommandClass] - NODE 9: Creating new message for application command ASSOCIATIONCMD_REMOVE group=2, node=1

10:50:31.881 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 9: SECURITY not supported

10:50:31.881 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 9: Command Class COMMAND_CLASS_ASSOCIATION is NOT required to be secured

10:50:31.881 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 9: Adding to device queue

10:50:31.882 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 9: Added 933 to queue - size 10

10:50:31.882 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

10:50:31.882 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 9: New Members ZWaveAssociationGroup [index=2, name=null, profile1=null, profile2=null, associations=[node_1_1]]

10:50:31.883 [DEBUG] [andclass.ZWaveAssociationCommandClass] - NODE 9: Creating new message for application command ASSOCIATIONCMD_SET, group=2, node=1

10:50:31.884 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 9: SECURITY not supported

10:50:31.884 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 9: Command Class COMMAND_CLASS_ASSOCIATION is NOT required to be secured

10:50:31.885 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 9: Adding to device queue

10:50:31.885 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 9: Added 934 to queue - size 11

10:50:31.886 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

10:50:31.886 [DEBUG] [andclass.ZWaveAssociationCommandClass] - NODE 9: Creating new message for application command ASSOCIATIONCMD_GET group 2

10:50:31.887 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 9: SECURITY not supported

10:50:31.887 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 9: Command Class COMMAND_CLASS_ASSOCIATION is NOT required to be secured

10:50:31.887 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 9: Adding to device queue

10:50:31.888 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 9: Added 935 to queue - size 12

10:50:31.888 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

10:50:31.890 [INFO ] [smarthome.event.ThingUpdatedEvent ] - Thing 'zwave:device:050e0827:node9' has been updated.

10:50:31.892 [INFO ] [smarthome.event.ConfigStatusInfoEvent] - ConfigStatusInfo [configStatusMessages=[ConfigStatusMessage [parameterName=group_4, type=PENDING, messageKey=null, arguments=null, message=null, statusCode=null], ConfigStatusMessage [parameterName=group_1, type=PENDING, messageKey=null, arguments=null, message=null, statusCode=null], ConfigStatusMessage [parameterName=group_3, type=PENDING, messageKey=null, arguments=null, message=null, statusCode=null], ConfigStatusMessage [parameterName=group_2, type=PENDING, messageKey=null, arguments=null, message=null, statusCode=null]]]

10:50:33.851 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - NODE 9: TID 924: Timeout at state WAIT_RESPONSE. 3 retries remaining.

10:50:33.852 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - Aborting Transaction!

10:50:33.852 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 924: Transaction ABORTED

10:50:33.853 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 03 00 16 EA 

10:50:33.853 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 03 00 16 EA 

10:50:33.853 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT

10:50:33.854 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

10:50:45.855 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - NODE 9: TID 924: Timeout at state ABORTED. 3 retries remaining.

10:50:45.855 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - TID 924: Transaction is current transaction, so clearing!!!!!

10:50:45.856 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 924: Transaction CANCELLED

10:50:45.856 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent

10:50:45.856 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 9: notifyTransactionResponse TID:924 CANCELLED

10:50:45.857 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

10:50:45.857 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0B 00 13 09 04 85 01 04 01 25 8F C1 

10:50:45.858 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 9: Sending REQUEST Message = 01 0B 00 13 09 04 85 01 04 01 25 8F C1 

10:50:45.858 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT

10:50:45.858 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 925: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 143

10:50:47.864 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - NODE 9: TID 925: Timeout at state WAIT_RESPONSE. 3 retries remaining.

10:50:47.865 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - Aborting Transaction!

10:50:47.865 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 925: Transaction ABORTED

10:50:47.865 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 03 00 16 EA 

10:50:47.866 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 03 00 16 EA 

10:50:47.866 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT

10:50:47.866 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

10:50:59.869 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - NODE 9: TID 925: Timeout at state ABORTED. 3 retries remaining.

10:50:59.870 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - TID 925: Transaction is current transaction, so clearing!!!!!

10:50:59.871 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 925: Transaction CANCELLED

10:50:59.871 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent

10:50:59.872 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 9: notifyTransactionResponse TID:925 CANCELLED

10:50:59.874 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

10:50:59.875 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 09 03 85 02 04 25 90 DA 

10:50:59.876 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 9: Sending REQUEST Message = 01 0A 00 13 09 03 85 02 04 25 90 DA 

10:50:59.877 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT

10:50:59.877 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 926: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 144

10:51:01.881 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - NODE 9: TID 926: Timeout at state WAIT_RESPONSE. 3 retries remaining.

10:51:01.882 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - Aborting Transaction!

10:51:01.883 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 926: Transaction ABORTED

10:51:01.884 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 03 00 16 EA 

10:51:01.885 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 03 00 16 EA 

10:51:01.885 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT

10:51:01.886 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

10:51:13.890 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - NODE 9: TID 926: Timeout at state ABORTED. 3 retries remaining.

10:51:13.890 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - TID 926: Transaction is current transaction, so clearing!!!!!

10:51:13.890 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 926: Transaction CANCELLED

10:51:13.891 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent

10:51:13.891 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 9: notifyTransactionResponse TID:926 CANCELLED

10:51:13.892 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

10:51:13.892 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0B 00 13 09 04 85 04 01 01 25 91 DF 

10:51:13.893 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 9: Sending REQUEST Message = 01 0B 00 13 09 04 85 04 01 01 25 91 DF 

10:51:13.893 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT

10:51:13.893 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 927: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 145

10:51:15.898 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - NODE 9: TID 927: Timeout at state WAIT_RESPONSE. 3 retries remaining.

10:51:15.898 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - Aborting Transaction!

10:51:15.898 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 927: Transaction ABORTED

10:51:15.899 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 03 00 16 EA 

10:51:15.899 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 03 00 16 EA 

10:51:15.900 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT

10:51:15.900 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

10:51:27.905 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - NODE 9: TID 927: Timeout at state ABORTED. 3 retries remaining.

10:51:27.905 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - TID 927: Transaction is current transaction, so clearing!!!!!

10:51:27.906 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 927: Transaction CANCELLED

10:51:27.906 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent

10:51:27.907 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 9: notifyTransactionResponse TID:927 CANCELLED

10:51:27.907 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

10:51:27.908 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0B 00 13 09 04 85 01 01 01 25 92 D9 

10:51:27.908 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 9: Sending REQUEST Message = 01 0B 00 13 09 04 85 01 01 01 25 92 D9 

10:51:27.909 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT

10:51:27.909 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 928: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 146

10:51:29.911 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - NODE 9: TID 928: Timeout at state WAIT_RESPONSE. 3 retries remaining.

10:51:29.911 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - Aborting Transaction!

10:51:29.916 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 928: Transaction ABORTED

10:51:29.916 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 03 00 16 EA 

10:51:29.920 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 03 00 16 EA 

10:51:29.920 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT

10:51:29.920 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

10:51:41.916 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - NODE 9: TID 928: Timeout at state ABORTED. 3 retries remaining.

10:51:41.917 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - TID 928: Transaction is current transaction, so clearing!!!!!

10:51:41.917 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 928: Transaction CANCELLED

10:51:41.917 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent

10:51:41.918 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 9: notifyTransactionResponse TID:928 CANCELLED

10:51:41.919 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

10:51:41.919 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 09 03 85 02 01 25 93 DC 

10:51:41.920 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 9: Sending REQUEST Message = 01 0A 00 13 09 03 85 02 01 25 93 DC 

10:51:41.920 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT

10:51:41.920 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 929: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 147

10:51:43.926 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - NODE 9: TID 929: Timeout at state WAIT_RESPONSE. 3 retries remaining.

10:51:43.926 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - Aborting Transaction!

10:51:43.927 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 929: Transaction ABORTED

10:51:43.927 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 03 00 16 EA 

10:51:43.927 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 03 00 16 EA 

10:51:43.928 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT

10:51:43.928 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

10:51:55.928 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - NODE 9: TID 929: Timeout at state ABORTED. 3 retries remaining.

10:51:55.929 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - TID 929: Transaction is current transaction, so clearing!!!!!

10:51:55.929 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 929: Transaction CANCELLED

10:51:55.930 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent

10:51:55.932 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 9: notifyTransactionResponse TID:929 CANCELLED

10:51:55.932 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

10:51:55.933 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0B 00 13 09 04 85 04 03 01 25 94 D8 

10:51:55.934 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 9: Sending REQUEST Message = 01 0B 00 13 09 04 85 04 03 01 25 94 D8 

10:51:55.934 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT

10:51:55.934 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 930: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 148

10:51:57.936 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - NODE 9: TID 930: Timeout at state WAIT_RESPONSE. 3 retries remaining.

10:51:57.937 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - Aborting Transaction!

10:51:57.937 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 930: Transaction ABORTED

10:51:57.938 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 03 00 16 EA 

10:51:57.938 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 03 00 16 EA 

10:51:57.939 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT

10:51:57.939 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

10:52:09.942 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - NODE 9: TID 930: Timeout at state ABORTED. 3 retries remaining.

10:52:09.943 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - TID 930: Transaction is current transaction, so clearing!!!!!

10:52:09.943 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 930: Transaction CANCELLED

10:52:09.944 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent

10:52:09.944 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 9: notifyTransactionResponse TID:930 CANCELLED

10:52:09.944 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

10:52:09.945 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0B 00 13 09 04 85 01 03 01 25 95 DC 

10:52:09.946 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 9: Sending REQUEST Message = 01 0B 00 13 09 04 85 01 03 01 25 95 DC 

10:52:09.946 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT

10:52:09.946 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 931: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 149

10:52:11.952 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - NODE 9: TID 931: Timeout at state WAIT_RESPONSE. 3 retries remaining.

10:52:11.953 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - Aborting Transaction!

10:52:11.953 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 931: Transaction ABORTED

10:52:11.953 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 03 00 16 EA 

10:52:11.954 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 03 00 16 EA 

10:52:11.954 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT

10:52:11.954 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

10:52:23.954 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - NODE 9: TID 931: Timeout at state ABORTED. 3 retries remaining.

10:52:23.954 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - TID 931: Transaction is current transaction, so clearing!!!!!

10:52:23.955 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 931: Transaction CANCELLED

10:52:23.957 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent

10:52:23.957 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 9: notifyTransactionResponse TID:931 CANCELLED

10:52:23.958 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

10:52:23.958 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 09 03 85 02 03 25 96 DB 

10:52:23.959 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 9: Sending REQUEST Message = 01 0A 00 13 09 03 85 02 03 25 96 DB 

10:52:23.959 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT

10:52:23.960 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 932: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 150

10:52:25.960 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - NODE 9: TID 932: Timeout at state WAIT_RESPONSE. 3 retries remaining.

10:52:25.961 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - Aborting Transaction!

10:52:25.962 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 932: Transaction ABORTED

10:52:25.963 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 03 00 16 EA 

10:52:25.964 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 03 00 16 EA 

10:52:25.965 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT

10:52:25.965 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

10:52:37.966 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - NODE 9: TID 932: Timeout at state ABORTED. 3 retries remaining.

10:52:37.967 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - TID 932: Transaction is current transaction, so clearing!!!!!

10:52:37.967 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 932: Transaction CANCELLED

10:52:37.967 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent

10:52:37.967 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 9: notifyTransactionResponse TID:932 CANCELLED

10:52:37.968 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

10:52:37.968 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0B 00 13 09 04 85 04 02 01 25 97 DA 

10:52:37.968 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 9: Sending REQUEST Message = 01 0B 00 13 09 04 85 04 02 01 25 97 DA 

10:52:37.968 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT

10:52:37.968 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 933: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 151

10:52:39.969 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - NODE 9: TID 933: Timeout at state WAIT_RESPONSE. 3 retries remaining.

10:52:39.970 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - Aborting Transaction!

10:52:39.970 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 933: Transaction ABORTED

10:52:39.971 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 03 00 16 EA 

10:52:39.971 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 03 00 16 EA 

10:52:39.971 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT

10:52:39.972 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

10:52:51.973 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - NODE 9: TID 933: Timeout at state ABORTED. 3 retries remaining.

10:52:51.973 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - TID 933: Transaction is current transaction, so clearing!!!!!

10:52:51.974 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 933: Transaction CANCELLED

10:52:51.974 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent

10:52:51.974 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 9: notifyTransactionResponse TID:933 CANCELLED

10:52:51.975 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

10:52:51.976 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0B 00 13 09 04 85 01 02 01 25 98 D0 

10:52:51.977 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 9: Sending REQUEST Message = 01 0B 00 13 09 04 85 01 02 01 25 98 D0 

10:52:51.977 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT

10:52:51.978 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 934: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 152

10:52:53.984 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - NODE 9: TID 934: Timeout at state WAIT_RESPONSE. 3 retries remaining.

10:52:53.984 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - Aborting Transaction!

10:52:53.984 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 934: Transaction ABORTED

10:52:53.985 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 03 00 16 EA 

10:52:53.985 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 03 00 16 EA 

10:52:53.986 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT

10:52:53.986 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

10:53:05.985 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - NODE 9: TID 934: Timeout at state ABORTED. 3 retries remaining.

10:53:05.986 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - TID 934: Transaction is current transaction, so clearing!!!!!

10:53:05.986 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 934: Transaction CANCELLED

10:53:05.987 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent

10:53:05.987 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 9: notifyTransactionResponse TID:934 CANCELLED

10:53:05.988 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

10:53:05.988 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 09 03 85 02 02 25 99 D5 

10:53:05.989 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 9: Sending REQUEST Message = 01 0A 00 13 09 03 85 02 02 25 99 D5 

10:53:05.989 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT

10:53:05.990 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 935: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 153

10:53:07.994 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - NODE 9: TID 935: Timeout at state WAIT_RESPONSE. 3 retries remaining.

10:53:07.994 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - Aborting Transaction!

10:53:07.995 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 935: Transaction ABORTED

10:53:07.995 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 03 00 16 EA 

10:53:07.996 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 03 00 16 EA 

10:53:07.996 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT

10:53:07.996 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

10:53:19.995 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - NODE 9: TID 935: Timeout at state ABORTED. 3 retries remaining.

10:53:19.996 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - TID 935: Transaction is current transaction, so clearing!!!!!

10:53:19.996 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 935: Transaction CANCELLED

10:53:19.996 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent

10:53:19.997 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 9: notifyTransactionResponse TID:935 CANCELLED

10:53:19.998 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

sihui · June 13, 2019, 6:10pm

You need to wake the ~~lock~~ remote manually to transfer the settings to the device.

Winston_Ewert · June 13, 2019, 6:32pm

I pushed the various button on the remote (on the theory this would wake it) and the situation does not appear to have changed.

Winston_Ewert · June 13, 2019, 7:02pm

I excluded the device and re-included it.

Now I don’t get the pending message, but my scene number still isn’t coming through:

Log has references to an unsupported command type:

11:56:01.644 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationUpdate[73], type=Request[0], dest=10, callback=132, payload=84 0A 0F 02 01 00 85 2D 7C 77 82 73 86 72 91 EF 2B 2C 

11:56:01.645 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null

11:56:01.645 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 0

11:56:01.645 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: null

11:56:01.646 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=ApplicationUpdate[73], type=Request[0], dest=10, callback=132, payload=84 0A 0F 02 01 00 85 2D 7C 77 82 73 86 72 91 EF 2B 2C 

11:56:01.646 [DEBUG] [message.ApplicationUpdateMessageClass] - NODE 10: Application update request. Node information received. Transaction null

11:56:01.647 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 10: Unsupported command class COMMAND_CLASS_REMOTE_ASSOCIATION_ACTIVATE

11:56:01.647 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 10: Unknown command class 0x91

11:56:01.648 [DEBUG] [message.ApplicationUpdateMessageClass] - NODE 10: Application update - no transaction.

11:56:01.648 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

11:56:01.649 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

11:56:02.484 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 15 00 49 84 0A 0F 02 01 00 85 2D 7C 77 82 73 86 72 91 EF 2B 2C FE 

11:56:02.485 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationUpdate[73], type=Request[0], dest=10, callback=132, payload=84 0A 0F 02 01 00 85 2D 7C 77 82 73 86 72 91 EF 2B 2C 

11:56:02.486 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationUpdate[73], type=Request[0], dest=10, callback=132, payload=84 0A 0F 02 01 00 85 2D 7C 77 82 73 86 72 91 EF 2B 2C 

11:56:02.486 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null

11:56:02.487 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 0

11:56:02.487 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: null

11:56:02.487 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=ApplicationUpdate[73], type=Request[0], dest=10, callback=132, payload=84 0A 0F 02 01 00 85 2D 7C 77 82 73 86 72 91 EF 2B 2C 

11:56:02.488 [DEBUG] [message.ApplicationUpdateMessageClass] - NODE 10: Application update request. Node information received. Transaction null

11:56:02.488 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 10: Unsupported command class COMMAND_CLASS_REMOTE_ASSOCIATION_ACTIVATE

11:56:02.489 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 10: Unknown command class 0x91

11:56:02.489 [DEBUG] [message.ApplicationUpdateMessageClass] - NODE 10: Application update - no transaction.

11:56:02.490 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

11:56:02.490 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

11:56:03.618 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 15 00 49 84 0A 0F 02 01 00 85 2D 7C 77 82 73 86 72 91 EF 2B 2C FE 

11:56:03.619 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationUpdate[73], type=Request[0], dest=10, callback=132, payload=84 0A 0F 02 01 00 85 2D 7C 77 82 73 86 72 91 EF 2B 2C 

11:56:03.620 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationUpdate[73], type=Request[0], dest=10, callback=132, payload=84 0A 0F 02 01 00 85 2D 7C 77 82 73 86 72 91 EF 2B 2C 

11:56:03.620 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null

11:56:03.620 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 0

11:56:03.621 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: null

11:56:03.621 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=ApplicationUpdate[73], type=Request[0], dest=10, callback=132, payload=84 0A 0F 02 01 00 85 2D 7C 77 82 73 86 72 91 EF 2B 2C 

11:56:03.622 [DEBUG] [message.ApplicationUpdateMessageClass] - NODE 10: Application update request. Node information received. Transaction null

11:56:03.622 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 10: Unsupported command class COMMAND_CLASS_REMOTE_ASSOCIATION_ACTIVATE

11:56:03.623 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 10: Unknown command class 0x91

11:56:03.623 [DEBUG] [message.ApplicationUpdateMessageClass] - NODE 10: Application update - no transaction.

11:56:03.624 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

11:56:03.624 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

11:56:04.443 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 15 00 49 84 0A 0F 02 01 00 85 2D 7C 77 82 73 86 72 91 EF 2B 2C FE 

11:56:04.444 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationUpdate[73], type=Request[0], dest=10, callback=132, payload=84 0A 0F 02 01 00 85 2D 7C 77 82 73 86 72 91 EF 2B 2C 

11:56:04.444 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationUpdate[73], type=Request[0], dest=10, callback=132, payload=84 0A 0F 02 01 00 85 2D 7C 77 82 73 86 72 91 EF 2B 2C 

11:56:04.445 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null

11:56:04.445 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 0

11:56:04.445 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: null

11:56:04.446 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=ApplicationUpdate[73], type=Request[0], dest=10, callback=132, payload=84 0A 0F 02 01 00 85 2D 7C 77 82 73 86 72 91 EF 2B 2C 

11:56:04.446 [DEBUG] [message.ApplicationUpdateMessageClass] - NODE 10: Application update request. Node information received. Transaction null

11:56:04.447 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 10: Unsupported command class COMMAND_CLASS_REMOTE_ASSOCIATION_ACTIVATE

11:56:04.447 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 10: Unknown command class 0x91

11:56:04.447 [DEBUG] [message.ApplicationUpdateMessageClass] - NODE 10: Application update - no transaction.

11:56:04.448 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

11:56:04.448 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

11:56:05.126 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 15 00 49 84 0A 0F 02 01 00 85 2D 7C 77 82 73 86 72 91 EF 2B 2C FE 

11:56:05.127 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationUpdate[73], type=Request[0], dest=10, callback=132, payload=84 0A 0F 02 01 00 85 2D 7C 77 82 73 86 72 91 EF 2B 2C 

11:56:05.128 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationUpdate[73], type=Request[0], dest=10, callback=132, payload=84 0A 0F 02 01 00 85 2D 7C 77 82 73 86 72 91 EF 2B 2C 

11:56:05.128 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null

11:56:05.128 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 0

11:56:05.128 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: null

11:56:05.129 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=ApplicationUpdate[73], type=Request[0], dest=10, callback=132, payload=84 0A 0F 02 01 00 85 2D 7C 77 82 73 86 72 91 EF 2B 2C 

11:56:05.129 [DEBUG] [message.ApplicationUpdateMessageClass] - NODE 10: Application update request. Node information received. Transaction null

11:56:05.129 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 10: Unsupported command class COMMAND_CLASS_REMOTE_ASSOCIATION_ACTIVATE

11:56:05.130 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 10: Unknown command class 0x91

11:56:05.130 [DEBUG] [message.ApplicationUpdateMessageClass] - NODE 10: Application update - no transaction.

11:56:05.131 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

11:56:05.131 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

11:56:05.259 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 15 00 49 84 0A 0F 02 01 00 85 2D 7C 77 82 73 86 72 91 EF 2B 2C FE 

11:56:05.260 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationUpdate[73], type=Request[0], dest=10, callback=132, payload=84 0A 0F 02 01 00 85 2D 7C 77 82 73 86 72 91 EF 2B 2C 

11:56:05.261 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationUpdate[73], type=Request[0], dest=10, callback=132, payload=84 0A 0F 02 01 00 85 2D 7C 77 82 73 86 72 91 EF 2B 2C 

11:56:05.261 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null

11:56:05.262 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 0

11:56:05.262 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: null

11:56:05.263 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=ApplicationUpdate[73], type=Request[0], dest=10, callback=132, payload=84 0A 0F 02 01 00 85 2D 7C 77 82 73 86 72 91 EF 2B 2C 

11:56:05.263 [DEBUG] [message.ApplicationUpdateMessageClass] - NODE 10: Application update request. Node information received. Transaction null

11:56:05.263 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 10: Unsupported command class COMMAND_CLASS_REMOTE_ASSOCIATION_ACTIVATE

11:56:05.264 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 10: Unknown command class 0x91

11:56:05.264 [DEBUG] [message.ApplicationUpdateMessageClass] - NODE 10: Application update - no transaction.

11:56:05.265 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

11:56:05.265 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

11:56:05.510 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 15 00 49 84 0A 0F 02 01 00 85 2D 7C 77 82 73 86 72 91 EF 2B 2C FE 

11:56:05.510 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationUpdate[73], type=Request[0], dest=10, callback=132, payload=84 0A 0F 02 01 00 85 2D 7C 77 82 73 86 72 91 EF 2B 2C 

11:56:05.511 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationUpdate[73], type=Request[0], dest=10, callback=132, payload=84 0A 0F 02 01 00 85 2D 7C 77 82 73 86 72 91 EF 2B 2C 

11:56:05.511 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null

11:56:05.512 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 0

11:56:05.512 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: null

11:56:05.512 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=ApplicationUpdate[73], type=Request[0], dest=10, callback=132, payload=84 0A 0F 02 01 00 85 2D 7C 77 82 73 86 72 91 EF 2B 2C 

11:56:05.513 [DEBUG] [message.ApplicationUpdateMessageClass] - NODE 10: Application update request. Node information received. Transaction null

11:56:05.513 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 10: Unsupported command class COMMAND_CLASS_REMOTE_ASSOCIATION_ACTIVATE

11:56:05.514 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 10: Unknown command class 0x91

11:56:05.514 [DEBUG] [message.ApplicationUpdateMessageClass] - NODE 10: Application update - no transaction.

11:56:05.515 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

11:56:05.515 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

11:56:05.751 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 15 00 49 84 0A 0F 02 01 00 85 2D 7C 77 82 73 86 72 91 EF 2B 2C FE 

11:56:05.752 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationUpdate[73], type=Request[0], dest=10, callback=132, payload=84 0A 0F 02 01 00 85 2D 7C 77 82 73 86 72 91 EF 2B 2C 

11:56:05.753 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationUpdate[73], type=Request[0], dest=10, callback=132, payload=84 0A 0F 02 01 00 85 2D 7C 77 82 73 86 72 91 EF 2B 2C 

11:56:05.753 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null

11:56:05.753 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 0

11:56:05.754 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: null

11:56:05.754 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=ApplicationUpdate[73], type=Request[0], dest=10, callback=132, payload=84 0A 0F 02 01 00 85 2D 7C 77 82 73 86 72 91 EF 2B 2C 

11:56:05.755 [DEBUG] [message.ApplicationUpdateMessageClass] - NODE 10: Application update request. Node information received. Transaction null

11:56:05.755 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 10: Unsupported command class COMMAND_CLASS_REMOTE_ASSOCIATION_ACTIVATE

11:56:05.755 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 10: Unknown command class 0x91

11:56:05.756 [DEBUG] [message.ApplicationUpdateMessageClass] - NODE 10: Application update - no transaction.

11:56:05.756 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

11:56:05.757 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

11:56:06.110 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 15 00 49 84 0A 0F 02 01 00 85 2D 7C 77 82 73 86 72 91 EF 2B 2C FE 

11:56:06.111 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationUpdate[73], type=Request[0], dest=10, callback=132, payload=84 0A 0F 02 01 00 85 2D 7C 77 82 73 86 72 91 EF 2B 2C 

11:56:06.111 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationUpdate[73], type=Request[0], dest=10, callback=132, payload=84 0A 0F 02 01 00 85 2D 7C 77 82 73 86 72 91 EF 2B 2C 

11:56:06.112 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null

11:56:06.112 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 0

11:56:06.112 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: null

11:56:06.113 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=ApplicationUpdate[73], type=Request[0], dest=10, callback=132, payload=84 0A 0F 02 01 00 85 2D 7C 77 82 73 86 72 91 EF 2B 2C 

11:56:06.113 [DEBUG] [message.ApplicationUpdateMessageClass] - NODE 10: Application update request. Node information received. Transaction null

11:56:06.114 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 10: Unsupported command class COMMAND_CLASS_REMOTE_ASSOCIATION_ACTIVATE

11:56:06.114 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 10: Unknown command class 0x91

11:56:06.114 [DEBUG] [message.ApplicationUpdateMessageClass] - NODE 10: Application update - no transaction.

11:56:06.115 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

11:56:06.115 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

11:56:06.351 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 15 00 49 84 0A 0F 02 01 00 85 2D 7C 77 82 73 86 72 91 EF 2B 2C FE 

11:56:06.352 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationUpdate[73], type=Request[0], dest=10, callback=132, payload=84 0A 0F 02 01 00 85 2D 7C 77 82 73 86 72 91 EF 2B 2C 

11:56:06.352 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationUpdate[73], type=Request[0], dest=10, callback=132, payload=84 0A 0F 02 01 00 85 2D 7C 77 82 73 86 72 91 EF 2B 2C 

11:56:06.353 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null

11:56:06.353 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 0

11:56:06.353 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: null

11:56:06.353 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=ApplicationUpdate[73], type=Request[0], dest=10, callback=132, payload=84 0A 0F 02 01 00 85 2D 7C 77 82 73 86 72 91 EF 2B 2C 

11:56:06.354 [DEBUG] [message.ApplicationUpdateMessageClass] - NODE 10: Application update request. Node information received. Transaction null

11:56:06.354 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 10: Unsupported command class COMMAND_CLASS_REMOTE_ASSOCIATION_ACTIVATE

11:56:06.355 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 10: Unknown command class 0x91

11:56:06.355 [DEBUG] [message.ApplicationUpdateMessageClass] - NODE 10: Application update - no transaction.

11:56:06.355 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

11:56:06.356 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

11:56:15.504 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 3: Polling...

11:56:15.505 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 3: Polling deferred until initialisation complete

11:56:22.168 [DEBUG] [ialization.ZWaveNodeInitStageAdvancer] - NODE 10: ZWaveCommandClassTransactionPayload - send to node

11:56:22.169 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 10: SECURITY not supported

11:56:22.169 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 10: Command Class COMMAND_CLASS_VERSION is NOT required to be secured

11:56:22.170 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 10: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@512bc2f3

11:56:22.171 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 10: Adding to device queue

11:56:22.171 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 10: Added 1043 to queue - size 2

11:56:22.171 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

11:56:22.172 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 0A 03 86 13 2C 25 DC AF 

11:56:22.172 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 10: Sending REQUEST Message = 01 0A 00 13 0A 03 86 13 2C 25 DC AF 

11:56:22.173 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT

11:56:22.173 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 1043: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 220

11:56:22.174 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06

11:56:22.174 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

11:56:22.175 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

11:56:22.175 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 1043: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 220

11:56:22.176 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg: ACK

11:56:22.176 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

11:56:22.176 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

11:56:22.180 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 

11:56:22.181 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 

11:56:22.182 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 

11:56:22.182 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 1043: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 220

11:56:22.182 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1

11:56:22.183 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 1043: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 220

11:56:22.183 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 

11:56:22.184 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 10: sentData successfully placed on stack.

11:56:22.184 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 1043: Advanced to WAIT_REQUEST

11:56:22.185 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 10: TID 1043: Transaction not completed

11:56:22.185 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

11:56:22.186 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

11:56:22.198 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 DC 00 00 02 35 

11:56:22.199 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=220, payload=DC 00 00 02 

11:56:22.199 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=220, payload=DC 00 00 02 

11:56:22.200 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 1043: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 220

11:56:22.200 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1

11:56:22.200 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 1043: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 220

11:56:22.201 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking TID 1043: (Callback 220)

11:56:22.201 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Callback match!

11:56:22.201 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Correlated to TID 1043: callback 220

11:56:22.201 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=220, payload=DC 00 00 02 

11:56:22.202 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 10: SendData Request. CallBack ID = 220, Status = Transmission complete and ACK received(0)

11:56:22.203 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 1043: Advanced to WAIT_DATA

11:56:22.203 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 10: TID 1043: Transaction not completed

11:56:22.203 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

11:56:22.204 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

11:56:27.207 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - NODE 10: TID 1043: Timeout at state WAIT_DATA. 3 retries remaining.

11:56:27.208 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 1043: Transaction CANCELLED

11:56:27.208 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 10: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent

11:56:27.209 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 10: notifyTransactionResponse TID:1043 CANCELLED

11:56:27.209 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

11:56:27.209 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 1043: Transaction event listener: DONE: CANCELLED -> 

11:56:27.210 [DEBUG] [ialization.ZWaveNodeInitStageAdvancer] - NODE 10: Node Init response (7) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@59dd7470

11:56:27.210 [DEBUG] [ialization.ZWaveNodeInitStageAdvancer] - NODE 10: No data from device, but it was ACK'd. Possibly not supported? (Try 7)

11:56:28.152 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 15 00 49 84 0A 0F 02 01 00 85 2D 7C 77 82 73 86 72 91 EF 2B 2C FE 

11:56:28.153 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationUpdate[73], type=Request[0], dest=10, callback=132, payload=84 0A 0F 02 01 00 85 2D 7C 77 82 73 86 72 91 EF 2B 2C 

11:56:28.153 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationUpdate[73], type=Request[0], dest=10, callback=132, payload=84 0A 0F 02 01 00 85 2D 7C 77 82 73 86 72 91 EF 2B 2C 

11:56:28.154 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null

11:56:28.154 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 0

11:56:28.155 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: null

11:56:28.155 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=ApplicationUpdate[73], type=Request[0], dest=10, callback=132, payload=84 0A 0F 02 01 00 85 2D 7C 77 82 73 86 72 91 EF 2B 2C 

11:56:28.155 [DEBUG] [message.ApplicationUpdateMessageClass] - NODE 10: Application update request. Node information received. Transaction null

11:56:28.156 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 10: Unsupported command class COMMAND_CLASS_REMOTE_ASSOCIATION_ACTIVATE

11:56:28.156 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 10: Unknown command class 0x91

11:56:28.156 [DEBUG] [message.ApplicationUpdateMessageClass] - NODE 10: Application update - no transaction.

11:56:28.157 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

11:56:28.157 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

11:56:28.818 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 15 00 49 84 0A 0F 02 01 00 85 2D 7C 77 82 73 86 72 91 EF 2B 2C FE 

11:56:28.819 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationUpdate[73], type=Request[0], dest=10, callback=132, payload=84 0A 0F 02 01 00 85 2D 7C 77 82 73 86 72 91 EF 2B 2C 

11:56:28.820 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationUpdate[73], type=Request[0], dest=10, callback=132, payload=84 0A 0F 02 01 00 85 2D 7C 77 82 73 86 72 91 EF 2B 2C 

11:56:28.820 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null

11:56:28.821 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 0

11:56:28.821 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: null

11:56:28.822 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=ApplicationUpdate[73], type=Request[0], dest=10, callback=132, payload=84 0A 0F 02 01 00 85 2D 7C 77 82 73 86 72 91 EF 2B 2C 

11:56:28.822 [DEBUG] [message.ApplicationUpdateMessageClass] - NODE 10: Application update request. Node information received. Transaction null

11:56:28.823 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 10: Unsupported command class COMMAND_CLASS_REMOTE_ASSOCIATION_ACTIVATE

11:56:28.823 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 10: Unknown command class 0x91

11:56:28.823 [DEBUG] [message.ApplicationUpdateMessageClass] - NODE 10: Application update - no transaction.

11:56:28.824 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

11:56:28.825 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

11:56:29.318 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 15 00 49 84 0A 0F 02 01 00 85 2D 7C 77 82 73 86 72 91 EF 2B 2C FE 

11:56:29.318 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationUpdate[73], type=Request[0], dest=10, callback=132, payload=84 0A 0F 02 01 00 85 2D 7C 77 82 73 86 72 91 EF 2B 2C 

11:56:29.319 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationUpdate[73], type=Request[0], dest=10, callback=132, payload=84 0A 0F 02 01 00 85 2D 7C 77 82 73 86 72 91 EF 2B 2C 

11:56:29.319 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null

11:56:29.319 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 0

11:56:29.319 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: null

11:56:29.320 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=ApplicationUpdate[73], type=Request[0], dest=10, callback=132, payload=84 0A 0F 02 01 00 85 2D 7C 77 82 73 86 72 91 EF 2B 2C 

11:56:29.320 [DEBUG] [message.ApplicationUpdateMessageClass] - NODE 10: Application update request. Node information received. Transaction null

11:56:29.320 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 10: Unsupported command class COMMAND_CLASS_REMOTE_ASSOCIATION_ACTIVATE

11:56:29.321 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 10: Unknown command class 0x91

11:56:29.321 [DEBUG] [message.ApplicationUpdateMessageClass] - NODE 10: Application update - no transaction.

11:56:29.321 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

11:56:29.322 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

11:56:29.593 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 15 00 49 84 0A 0F 02 01 00 85 2D 7C 77 82 73 86 72 91 EF 2B 2C FE 

11:56:29.593 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationUpdate[73], type=Request[0], dest=10, callback=132, payload=84 0A 0F 02 01 00 85 2D 7C 77 82 73 86 72 91 EF 2B 2C 

11:56:29.594 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationUpdate[73], type=Request[0], dest=10, callback=132, payload=84 0A 0F 02 01 00 85 2D 7C 77 82 73 86 72 91 EF 2B 2C 

11:56:29.594 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null

11:56:29.594 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 0

11:56:29.594 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: null

11:56:29.594 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=ApplicationUpdate[73], type=Request[0], dest=10, callback=132, payload=84 0A 0F 02 01 00 85 2D 7C 77 82 73 86 72 91 EF 2B 2C 

11:56:29.595 [DEBUG] [message.ApplicationUpdateMessageClass] - NODE 10: Application update request. Node information received. Transaction null

11:56:29.595 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 10: Unsupported command class COMMAND_CLASS_REMOTE_ASSOCIATION_ACTIVATE

11:56:29.595 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 10: Unknown command class 0x91

11:56:29.595 [DEBUG] [message.ApplicationUpdateMessageClass] - NODE 10: Application update - no transaction.

11:56:29.596 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

11:56:29.596 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

11:58:07.929 [DEBUG] [ialization.ZWaveNodeInitStageAdvancer] - NODE 10: ZWaveCommandClassTransactionPayload - send to node

11:58:07.930 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 10: SECURITY not supported

11:58:07.930 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 10: Command Class COMMAND_CLASS_VERSION is NOT required to be secured

11:58:07.931 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 10: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@512bc2f3

11:58:07.931 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 10: Adding to device queue

11:58:07.932 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 10: Added 1044 to queue - size 2

11:58:07.932 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

11:58:07.933 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 0A 03 86 13 2C 25 DD AE 

11:58:07.933 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 10: Sending REQUEST Message = 01 0A 00 13 0A 03 86 13 2C 25 DD AE 

11:58:07.933 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT

11:58:07.934 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 1044: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 221

11:58:07.935 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06

11:58:07.935 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

11:58:07.935 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

11:58:07.936 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 1044: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 221

11:58:07.936 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg: ACK

11:58:07.936 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

11:58:07.937 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

11:58:07.941 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 

11:58:07.942 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 

11:58:07.942 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 

11:58:07.942 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 1044: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 221

11:58:07.943 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1

11:58:07.943 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 1044: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 221

11:58:07.944 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 

11:58:07.944 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 10: sentData successfully placed on stack.

11:58:07.944 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 1044: Advanced to WAIT_REQUEST

11:58:07.945 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 10: TID 1044: Transaction not completed

11:58:07.945 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

11:58:07.945 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

11:58:07.958 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 DD 00 00 02 34 

11:58:07.959 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=221, payload=DD 00 00 02 

11:58:07.959 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=221, payload=DD 00 00 02 

11:58:07.960 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 1044: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 221

11:58:07.960 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1

11:58:07.961 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 1044: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 221

11:58:07.961 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking TID 1044: (Callback 221)

11:58:07.961 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Callback match!

11:58:07.962 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Correlated to TID 1044: callback 221

11:58:07.962 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=221, payload=DD 00 00 02 

11:58:07.962 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 10: SendData Request. CallBack ID = 221, Status = Transmission complete and ACK received(0)

11:58:07.963 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 1044: Advanced to WAIT_DATA

11:58:07.963 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 10: TID 1044: Transaction not completed

11:58:07.964 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

11:58:07.964 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

11:58:12.968 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - NODE 10: TID 1044: Timeout at state WAIT_DATA. 3 retries remaining.

11:58:12.968 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 1044: Transaction CANCELLED

11:58:12.968 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 10: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent

11:58:12.969 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 10: notifyTransactionResponse TID:1044 CANCELLED

11:58:12.969 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

11:58:12.969 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 1044: Transaction event listener: DONE: CANCELLED -> 

11:58:12.970 [DEBUG] [ialization.ZWaveNodeInitStageAdvancer] - NODE 10: Node Init response (8) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@760079a3

11:58:12.970 [DEBUG] [ialization.ZWaveNodeInitStageAdvancer] - NODE 10: No data from device, but it was ACK'd. Possibly not supported? (Try 8)

Logs when setting association groups refer to unknown association groups

12:02:47.627 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 10: SendData Request. CallBack ID = 228, Status = Transmission complete and ACK received(0)

12:02:47.627 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 1051: Advanced to WAIT_DATA

12:02:47.627 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 10: TID 1051: Transaction not completed

12:02:47.628 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

12:02:47.628 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

12:02:52.631 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - NODE 10: TID 1051: Timeout at state WAIT_DATA. 3 retries remaining.

12:02:52.631 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 1051: Transaction CANCELLED

12:02:52.632 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 10: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent

12:02:52.632 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 10: notifyTransactionResponse TID:1051 CANCELLED

12:02:52.633 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

12:02:52.633 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 1051: Transaction event listener: DONE: CANCELLED -> 

12:02:52.634 [DEBUG] [ialization.ZWaveNodeInitStageAdvancer] - NODE 10: Node Init response (5) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@383ccce0

12:02:52.634 [DEBUG] [ialization.ZWaveNodeInitStageAdvancer] - NODE 10: No data from device, but it was ACK'd. Possibly not supported? (Try 5)

12:02:57.225 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 10: Configuration update received

12:02:57.226 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 10: Configuration update set group_4 to [controller] (ArrayList)

12:02:57.227 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 10: Unknown association group 4

12:02:57.227 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 10: Configuration update set group_1 to [controller] (ArrayList)

12:02:57.227 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 10: Unknown association group 1

12:02:57.228 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 10: Configuration update set group_3 to [controller] (ArrayList)

12:02:57.228 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 10: Unknown association group 3

12:02:57.228 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 10: Configuration update set group_2 to [controller] (ArrayList)

12:02:57.228 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 10: Unknown association group 2

12:02:57.230 [INFO ] [smarthome.event.ThingUpdatedEvent ] - Thing 'zwave:device:050e0827:node10' has been updated.

12:02:57.231 [INFO ] [smarthome.event.ConfigStatusInfoEvent] - ConfigStatusInfo [configStatusMessages=[]]

sihui · June 14, 2019, 6:14am

Sorry, I just realized this is not a battery powered device, so there is no manual wakeup
https://www.cd-jackson.com/index.php/zwave/zwave-device-database/zwave-device-list/devicesummary/197
Could you please provide the device type, device id and firmware version (application version) of your device.
You should be able to find those in the xml file of your /userdata/zwave folder. You could also post the complete xml file here.

Winston_Ewert · June 14, 2019, 5:57pm

Here is my xml file

<node>
  <homeId>0xee731e50</homeId>
  <nodeId>10</nodeId>
  <version>4</version>
  <manufacturer>0x1d</manufacturer>
  <deviceId>0x261</deviceId>
  <deviceType>0x802</deviceType>
  <listening>true</listening>
  <frequentlyListening>false</frequentlyListening>
  <routing>true</routing>
  <security>false</security>
  <beaming>false</beaming>
  <maxBaudRate>40000</maxBaudRate>
  <sleepDelay>1000</sleepDelay>
  <nodeInformationFrame>
    <commandClass>COMMAND_CLASS_ASSOCIATION</commandClass>
    <commandClass>COMMAND_CLASS_SCENE_CONTROLLER_CONF</commandClass>
    <commandClass>COMMAND_CLASS_REMOTE_ASSOCIATION_ACTIVATE</commandClass>
    <commandClass>COMMAND_CLASS_NODE_NAMING</commandClass>
    <commandClass>COMMAND_CLASS_HAIL</commandClass>
    <commandClass>COMMAND_CLASS_POWERLEVEL</commandClass>
    <commandClass>COMMAND_CLASS_VERSION</commandClass>
    <commandClass>COMMAND_CLASS_MANUFACTURER_SPECIFIC</commandClass>
    <commandClass>COMMAND_CLASS_MANUFACTURER_PROPRIETARY</commandClass>
    <commandClass>COMMAND_CLASS_MARK</commandClass>
    <commandClass>COMMAND_CLASS_SCENE_ACTIVATION</commandClass>
    <commandClass>COMMAND_CLASS_SCENE_ACTUATOR_CONF</commandClass>
  </nodeInformationFrame>
  <associationGroups class="concurrent-hash-map"/>
  <endpoints class="concurrent-hash-map">
    <entry>
      <int>0</int>
      <endPoint>
        <deviceClass>
          <basicDeviceClass>BASIC_TYPE_STATIC_CONTROLLER</basicDeviceClass>
          <genericDeviceClass>GENERIC_TYPE_GENERIC_CONTROLLER</genericDeviceClass>
          <specificDeviceClass>SPECIFIC_TYPE_NOT_USED</specificDeviceClass>
        </deviceClass>
        <endpointId>0</endpointId>
        <secureCommandClasses/>
        <supportedCommandClasses class="concurrent-hash-map">
          <entry>
            <commandClass>COMMAND_CLASS_SCENE_ACTUATOR_CONF</commandClass>
            <COMMAND__CLASS__SCENE__ACTUATOR__CONF>
              <version>0</version>
              <instances>1</instances>
              <control>true</control>
              <versionSupported>0</versionSupported>
            </COMMAND__CLASS__SCENE__ACTUATOR__CONF>
          </entry>
          <entry>
            <commandClass>COMMAND_CLASS_NODE_NAMING</commandClass>
            <COMMAND__CLASS__NODE__NAMING>
              <version>0</version>
              <instances>1</instances>
              <control>false</control>
              <versionSupported>0</versionSupported>
              <name></name>
              <location></location>
            </COMMAND__CLASS__NODE__NAMING>
          </entry>
          <entry>
            <commandClass>COMMAND_CLASS_POWERLEVEL</commandClass>
            <COMMAND__CLASS__POWERLEVEL>
              <version>0</version>
              <instances>1</instances>
              <control>false</control>
              <versionSupported>0</versionSupported>
              <powerLevel>0</powerLevel>
              <powerTimeout>0</powerTimeout>
            </COMMAND__CLASS__POWERLEVEL>
          </entry>
          <entry>
            <commandClass>COMMAND_CLASS_SCENE_CONTROLLER_CONF</commandClass>
            <COMMAND__CLASS__SCENE__CONTROLLER__CONF>
              <version>0</version>
              <instances>1</instances>
              <control>false</control>
              <versionSupported>0</versionSupported>
            </COMMAND__CLASS__SCENE__CONTROLLER__CONF>
          </entry>
          <entry>
            <commandClass>COMMAND_CLASS_VERSION</commandClass>
            <COMMAND__CLASS__VERSION>
              <version>0</version>
              <instances>1</instances>
              <control>false</control>
              <versionSupported>0</versionSupported>
              <libraryType>LIB_CONTROLLER_STATIC</libraryType>
              <protocolVersion>2.97</protocolVersion>
              <applicationVersion>0.2</applicationVersion>
            </COMMAND__CLASS__VERSION>
          </entry>
          <entry>
            <commandClass>COMMAND_CLASS_MANUFACTURER_SPECIFIC</commandClass>
            <COMMAND__CLASS__MANUFACTURER__SPECIFIC>
              <version>0</version>
              <instances>1</instances>
              <control>false</control>
              <versionSupported>0</versionSupported>
              <initSerialNumber>false</initSerialNumber>
              <deviceManufacturer>29</deviceManufacturer>
              <deviceType>2050</deviceType>
              <deviceId>609</deviceId>
            </COMMAND__CLASS__MANUFACTURER__SPECIFIC>
          </entry>
          <entry>
            <commandClass>COMMAND_CLASS_ASSOCIATION</commandClass>
            <COMMAND__CLASS__ASSOCIATION>
              <version>0</version>
              <instances>1</instances>
              <control>false</control>
              <versionSupported>0</versionSupported>
              <maxGroups>0</maxGroups>
            </COMMAND__CLASS__ASSOCIATION>
          </entry>
          <entry>
            <commandClass>COMMAND_CLASS_SCENE_ACTIVATION</commandClass>
            <COMMAND__CLASS__SCENE__ACTIVATION>
              <version>0</version>
              <instances>1</instances>
              <control>true</control>
              <versionSupported>0</versionSupported>
            </COMMAND__CLASS__SCENE__ACTIVATION>
          </entry>
          <entry>
            <commandClass>COMMAND_CLASS_NO_OPERATION</commandClass>
            <COMMAND__CLASS__NO__OPERATION>
              <version>1</version>
              <instances>1</instances>
              <control>false</control>
              <versionSupported>1</versionSupported>
            </COMMAND__CLASS__NO__OPERATION>
          </entry>
          <entry>
            <commandClass>COMMAND_CLASS_HAIL</commandClass>
            <COMMAND__CLASS__HAIL>
              <version>0</version>
              <instances>1</instances>
              <control>false</control>
              <versionSupported>0</versionSupported>
            </COMMAND__CLASS__HAIL>
          </entry>
          <entry>
            <commandClass>COMMAND_CLASS_BASIC</commandClass>
            <COMMAND__CLASS__BASIC>
              <version>0</version>
              <instances>1</instances>
              <control>false</control>
              <versionSupported>0</versionSupported>
              <isGetSupported>true</isGetSupported>
            </COMMAND__CLASS__BASIC>
          </entry>
        </supportedCommandClasses>
      </endPoint>
    </entry>
  </endpoints>
  <nodeNeighbors/>
  <lastReceived>2019-06-13 22:21:09.103 UTC</lastReceived>

sihui · June 15, 2019, 7:37am

Your device is in the database:
https://www.cd-jackson.com/index.php/zwave/zwave-device-database/zwave-device-list/devicesummary/197
and has not been updated since 9 month. So it should even work with 2.4 stable.
I can’t find anything wrong in the two seconds debug log either:

I’ve added the zwave tag to your post to attract other zwave users as I don’t know what is wrong here.
Isn’t @mhilbush using this device?

tvipond · September 3, 2019, 3:36pm

Are you still having issues with your VRCS4?

With the great help of others here I have been able to resolve a few of my startup issues, and I have 6 of these things as well which is my next challenge.

I struggled with the same “bug” in HABmin and Paper UI with the associations not showing.

I will check my log and xml and compare it to yours as well and maybe if I get mine working, I can share the results with you.

Terry

Winston_Ewert · September 3, 2019, 3:50pm

I gave up on mine. I only got one for the purpose of testing it out, and since it didn’t work, I’m planning to use other options.

Let me know if you get it working though.

tvipond · September 5, 2019, 5:55am

Okay, so I managed to get these things working…I have a bit of experience with these switches with my previous platform. However, I am going to send a message to Chris (actually tagged him below so he doesn’t have to read my long story if he doesn’t want too!) , the maintainer of the Z-Wave binding to ask him a few questions which may help us through getting these things functioning 100%.

To get these 4 button Scene Controllers working, you need to obviously include them, and then I am not joking, wait up to 2 days for them to configure themselves unless you can get your openHAB server and the Scene controllers very close together. Even with my old platform, these devices took up to 2 days to configure, especially if they are not in close range to the controller. Manual states that the scene controller needs to be within 1 foot of your Primary ZWave controller during the include and the configuration process and also specifically states that the Scene Controller needs to be “updated to allow proper network function”. With a USB Z-Stick without physically moving your host or Scene Controller next to each other, this is very difficult…so waiting longer periods of time, I found always allows this “configuration” to happen.

Personally, I believe the configuration process is learning ZWave neighbors and possible routes available to the Scene controller as well as for the primary controller to adjust any associations required for the buttons to work as expected.

These devices are Scene controllers themselves so they actually do not require a Primary ZWave controller to perform anything…The primary controller is just required to establish a valid network that the Scene controller can join. The only time a primary controller is required is when your devices that you want to control do not support a “Scene Command”, or your Scene Controller can’t find a route to the Zwave nodes without a primary controller because of not having enough repeating nodes in between the Scene controller and devices…or of course, when you want to utilize a platform like openHAB to enable this ZWave thing to control other connected non-ZWave things in your setup using a rule or scene script.

I’ve attached an image of my associations of one of my switches. You can see four button associations:

Following the instructions of the Scene controller manual, it tells you how to adjust the dimming level or ON/OFF status of all the associated nodes and store that within the scene…basically, after associations are complete…press and hold the desired scene button until it blinks Green, then adjust the ON/OFF values and/or dimming level of the devices that you have associated this button to the level you would like for this scene, and then press the Scene button again to finish programming the specific Scene. This is all done on the 4 button scene controller, not your openHAB primary zwave controller or the thing within openHAB.

The Scene controller controls itself and lights up its individual button LED indicator lights by monitoring ZWave traffic and ensuring that all devices that are associated to that button are at their present ON/OFF state and previously stored Dimming level. IF they are all at programmed values, then the LED indicator stays green, IF any one is off, then indicator goes out. This enables the Scene controller to manage 4 distinct scenes at the same time, as long as 2 scene buttons are not associated to the same device(s)…which is true in my test example…but in that example, the last scene activated is the active scene, the other ones becomes inactive.

@chris
My status right now is that everything functions…each button in isolation works fine…but if I have Scene 1 active, with the green LED lit…when I press Scene 4, then it lights, however, Scene 1 LED goes out and the zwave devices associated with Scene 1 remain on. Now it’s a pain because you need to turn on Scene 1 back on, then turn off Scene 1 again, then go back to Scene 4.

I personally believe this problem is because the zwave binding is forcing us to maintain an association to the controller node 1 in each button association group. This was added previously because of other issues from what I read/understand because end users were removing it causing other problems. Were those problems specific to Scene controller buttons or were they more related to people removing the lifeline association on battery operated devices and such?

So, I want to ask for some clarification on this mandatory association to each scene button…maybe I can get a development version to try it without a mandatory association to the master controller. I understood that the master controller node 1 must be used for a Lifeline on all Z-Wave+ devices…which is fine…that is specifically supposed to be for status updates, it may not be necessary for scene button associations. Possibly the status of the active scene is sent to the lifeline anyways even regardless if it is not in the associated items of the scene button. It’s likely that status update messages are handled separately and independently from messages used to “control” associated devices.

Or another potential, and one that may be even more powerful…The current binding only provides for a single channel for the thing…the channel indicates the active scene…essentially wiping out any history that any other scenes were active (and may still be active). What if it was possible to add 4 new channels…once for each scene button, or maybe even ones for the dim+ or dim-. This may allow us to enable scene controllers like that to control any number of things…including non-zwave things. The only concern with that is, if the Scene controller itself is controlling the LED indicator by monitoring the things in it’s association group…then what nodes would be in the association group if we moved completely to channels instead? Would we lose the LED indicator light functionality because the Scene Controller has no way of monitoring the associated devices because the devices are not associated anymore…it’s a channel instead. Not sure if that would work.

Or maybe there’s a potential to associate to Groups instead of Nodes? I am not too familiar with Association Groups…how do they get created…from where…which node? I see on my controller Z-Stick, there are Groups created when I add multiple nodes as an association, but not sure if the binding looks at that anywhere, or whether it would be of any use…possible we would still be at the same spot, unable to track multiple scenes?

Terry

Edit:

Of course, we could leave the button associates strictly as the controller node only…then program rules to respond to the channel that reports the active scene…this would enable us to send commands to shut down the first scenes devices so as to not force the end user to fiddle with the scene buttons in an effort to resync the on/off states…but it definitely eliminates the possibility to use these switches for what I do. In my house I have 6 of these things that used to be connected to Vera. One button was for a fireplace…another button was to hold my front gate open. Others were for lights. So in my example, If I had the fireplace on and I opened the gate, then the fireplace would go off. Not ideal.

chris · September 5, 2019, 12:05pm

There is no such policy - this is just how (someone?) configured the database.

From what I see in the database, this device is NOT a ZWave+ device - if so this doesn’t apply? If it is a ZWave+ device then this is not required (but then the database is also wrong as it doesn’t show it being a ZWave+ device). The database is also irrelevant in this case since the lifeline association to the controller is required by the ZWave standards, so the binding automatically detects this and configures it.

tvipond · September 5, 2019, 4:50pm

Correct, these are not ZWave+ devices. So, the database setup is what controls the mandatory assosciations then?

I agree with a lifeline association being required by the standard to promote backwards compatibility…I am just a little unsure of the needs to have the lifeline bound to specific “Association Groups” related to each Scene button in addition to the Scene controller itself. The Scene controller as a device probably needs to be associated to the controller to handle the lifeline. But, the Buttons have “Association Groups”…which, with my admittedly weak knowledge of the ZWave protocol, “Association Groups” are different than “Associations”. The Association of the Scene controller to support lifeline is different than the associations of each scene controllers button and related “Association Groups” that the scene controller requires to control the devices directly.

chris · September 5, 2019, 4:52pm

Yes.

There isn’t any, and this doesn’t really make sense. The lifeline is an association group (required to be group 1) - it can’t be bound to another association group.

As we just said - this is not a ZWave plus device - it has no lifeline group.

tvipond · September 5, 2019, 5:38pm

In other words…the Scene Controller, using it’s Lifeline association to the Primary Controller may likely use that association to tell the Primary Controller that Scene 1 is active…or Scene 1 and 3 are active, and constantly update that when it activates or de-activates it’s “own” scenes. Or to your point…because it is not a ZWave+ device, it actually probably doesn’t use the lifeline to update status to the controller, and very likely any mandatory binding to Node 1 is irrelevant on this device in any kind.

I think it is very likely that forcing an association within each of the controllers individual Scene Association Groups is very likely breaking the ability for the Scene Controller to Control it’s own scene properly…and by that I am implying that Associations Groups for each possible Scene are not the same as Associations of the Scene controller device. Scenes are not devices. Scene do not give status updates to anything. There’s only one device, but multiple Scenes. If anything, the device needs to be associated to a lifeline, not the individual Scenes. The Scene Controller monitors and listens to all status updates of all devices in “Association Group 1” and compares the actual set value of each node within group 1 to the value commanded in the Scene Controllers stored scene programming for Scene 1. For example, Node 22 dimming value at 50%. If any of the actual device reported set values do not match, then the Scene becomes deactivated automatically by the Scene Controller itself with no reliance on the Primary Controller Node 1…but not shut off. It assumes that a user manually adjusted one of the nodes or it was adjusted by some other controller other than itself, so the Scene controller essentially gives up trying to control the Scene. If on the other hand, someone set all of the nodes manually and those set values matched all the preset programmed values that existed within the Scene Controllers stored programming, then the Scene Controller lights the LED up for that Scene and starts controlling it again.

When we force Node 1 to be within the Association Group, then what is the programmed commanded value of Node 1 within the Scene Controller? And what is the reported set value of Node 1 in response? What Command is it trying to use…Node 1 is not a device…it probably doesn’t have a Set value that it could respond with? Then add on to that…if it did have a set value to respond with…then is that set value different when the Scene 2 button is pressed as opposed to when Scene 1 is pressed? If it is different, then Scene 1 is now broken the second we press Scene 2 because the set value of Node 1 changed and therefore the Scene Controller disables Scene 1.

There is one more thing I thought of trying tonight…if in fact there is a reported set value from Node 1, and if that reported set value doesn’t change between Scene 1 and Scene 2 activation, then maybe I can find a way to program the Scene within the Scene controller with whatever value so that it doesn’t disable the Scene. I think this is a pipe-dream to work, but I gonna try anyways.

I may be out to lunch…and you can feel free to tell me so! I am enjoying learning!

tvipond · September 6, 2019, 12:20am

Okay, I tried my last idea and as expected, it did not work.

The multi-scene controller cannot control multiple scenes with this setup.

So, to attempt to try my device without “forced” associations to Node 1 within each Button’s association groups…what would I need to do?

You mentioned that this comes from the database…but it’s a shared device…possibly not everyone would appreciate me changing the database settings, especially if they have managed some sort of work around and have their device working.

How can I experiment with a development version of the database…change something on my local existance of the database?

tvipond · September 6, 2019, 1:26am

connect to github, compile the binding locally…any other options? probably no?

sihui · September 6, 2019, 4:13am

sihui · September 6, 2019, 4:19am

I did not read through all of that (too much, no time), why don’t you just catch the scene number in a rule and use the full flexibility of openHAB?
That is what other people do with this sort of devices.