Z-Wave Binding - Steinel 035402 MotionSwitch LED

[Bany (Bernd Rydzewski)]

Hello all,
I found an actually cool device, the Steinel 035402 MotionSwitch LED. Unfortunately, I can’t get a channel in openHAB to detect motion.
From Steinel I have received the following specification. Can anyone help me with this?
Thank you very much.
Best regards
Bany
10322_Z-WAVE_module_Specification_05_2017.pdf (503.3 KB)

[chris (Chris Jackson)]

It looks like the device is not in the database, so it would need to be added. Once the device is in the network, the binding creates a file that can be used to do the “hard part” of this. Please take a look at the docs below.

Z-Wave Device Database Guide

[Bernhelm (Bernd Müller)]

Hello everyone,
meanwhile the MotionSwitch is integrated in Openhab and can be found in the database. Thank you for that.
ZWave Thing | openHAB
I now bought one of them and the thing was found and connected with Openhab.
Unfortunately, no channel is changing its status independently from what I am doing.
I am quite a beginner, so it also can be my fault. But lifeline was connected automatically to the Controller, which should be fine.
Does anyone have this MotionSwitch working properly?
I use Openhab 3.4 in the stable build and have a lot of good working z-wave devices in my network including one newly integrated one.
Any ideas what I can check?

[apella12 (Bob Eckhoff)]

Not usually a good idea to post on a year old thread with a different problem (the original problem was the device was not in the DB)

As to your issue I do not understand what this means?

@Bernhelm

Unfortunately, no channel is changing its status independently from what I am doing.

As to this;

@Bernhelm

Any ideas what I can check?

Set the Zwave binding to Debug per the documentation section “when things do not go as planned”

[anon71759204]

@chris
Root cause is a wrong channel definition in the Z-Wave database (OpenSmartHouse Z-Wave Device Database):

alarm_burglar must be replaced with alarm_motion.

Edit:

 // Motion alarms
        events = new HashMap<NotificationEvent, State>();
        events.put(NotificationEvent.HOME_SECURITY__NONE, OnOffType.OFF);
        events.put(NotificationEvent.HOME_SECURITY__MOTION, OnOffType.ON);
        events.put(NotificationEvent.**HOME_SECURITY__MOTION_UNKNOWN**, OnOffType.ON);
        notifications.put("**alarm_motion**", events);

HOME_SECURITY__MOTION_UNKNOWN corresponds to event 8.

Same problem (includes detailled debug log):

Steinel MotionSwitchLED, Alarm converter NOTIFICATION event

Hi all, I have a strange behaviour with a Steinel MotionSwitchLED (zwave version 1.132). Tracking down problems with toggling motion events (ping pong between on and off events), I started from scratch with factory defaults. Configuration (Factory defaults, Slave mode, Live line association set to controller): UID: zwave:device:2622dc76:node91 label: Heizungskeller_MotionSwitchLED2 thingTypeUID: zwave:steinel_motionswitchled_00_000 configuration: config_10_2: 10 group_5: [] group_4: []…

[Bernhelm (Bernd Müller)]

@Bob: Thank you for the remark regarding the old thread. I will do this next time.

What I did is to bring the thing into openhab (it is marked online) and then linked the following channels to items in openhab:
Dimmer (always states 0, independently of the light beeing on or of on the device, if I try to send 100 it is not switching the light on as it should)
Sensor Luminance (always states 0)
Switch 3 (I hoped this could be the motion sensor switch)
Burglar Alarm (after the remark from BK, I wanted to try that).

I checked the debug mode and there is no activity at the corresponding node while I can see all other devices working in the log. ( I mean I switch other items on/off and can see it in the log, I walk in front of the motion sensor, light is switching on but no activity in the log).

[apella12 (Bob Eckhoff)]

Ok I understand better.

Focusing on the dimmer and luminance for a moment, that indicates a communication problem. As a powered device it should be configured. Do you have 5 lines on the thing UI page?

If yes, try the “Heal the device”, if no you might have had an issue during inclusion. let me know which is the case.

As to the motion-burglar @anon71759204 is right (there are different codes), but it could be that both the burglar and motion channels are active (so not replace but add in the DB). Anyway can you post the XML for the device? (var/lib/openhab/zwave or openhab-userdata) Thanks

[Bernhelm (Bernd Müller)]

Thank you for your help, Bob.
I only have 4 lines on the UI page, Reinitialise the device is missing (but that is the case for other things, too).
I used heal but it did not change anything.

I hope I found the right xml. At least the node number is the same than the thing node number.

<node>
<homeId>0xda14487c</homeId>
<nodeId>36</nodeId>
<version>4</version>
<manufacturer>0x271</manufacturer>
<deviceId>0x19fb</deviceId>
<deviceType>0x2</deviceType>
<listening>true</listening>
<frequentlyListening>false</frequentlyListening>
<routing>true</routing>
<security>false</security>
<beaming>true</beaming>
<maxBaudRate>40000</maxBaudRate>
<sleepDelay>500</sleepDelay>
<nodeInformationFrame>
<commandClass>COMMAND_CLASS_ZWAVEPLUS_INFO</commandClass>
<commandClass>COMMAND_CLASS_TRANSPORT_SERVICE</commandClass>
<commandClass>COMMAND_CLASS_SECURITY</commandClass>
<commandClass>COMMAND_CLASS_SECURITY_2</commandClass>
<commandClass>COMMAND_CLASS_SUPERVISION</commandClass>
<commandClass>COMMAND_CLASS_APPLICATION_STATUS</commandClass>
<commandClass>COMMAND_CLASS_MANUFACTURER_SPECIFIC</commandClass>
<commandClass>COMMAND_CLASS_VERSION</commandClass>
<commandClass>COMMAND_CLASS_ASSOCIATION_GRP_INFO</commandClass>
<commandClass>COMMAND_CLASS_ASSOCIATION</commandClass>
<commandClass>COMMAND_CLASS_MULTI_CHANNEL_ASSOCIATION</commandClass>
<commandClass>COMMAND_CLASS_DEVICE_RESET_LOCALLY</commandClass>
<commandClass>COMMAND_CLASS_MULTI_CHANNEL</commandClass>
<commandClass>COMMAND_CLASS_FIRMWARE_UPDATE_MD</commandClass>
<commandClass>COMMAND_CLASS_CONFIGURATION</commandClass>
<commandClass>COMMAND_CLASS_POWERLEVEL</commandClass>
<commandClass>COMMAND_CLASS_SWITCH_BINARY</commandClass>
<commandClass>COMMAND_CLASS_ALARM</commandClass>
<commandClass>COMMAND_CLASS_SENSOR_MULTILEVEL</commandClass>
<commandClass>COMMAND_CLASS_CENTRAL_SCENE</commandClass>
<commandClass>COMMAND_CLASS_MARK</commandClass>
<commandClass>COMMAND_CLASS_SWITCH_MULTILEVEL</commandClass>
<commandClass>COMMAND_CLASS_SCENE_ACTIVATION</commandClass>
</nodeInformationFrame>
<associationGroups class="concurrent-hash-map">
<entry>
<int>1</int>
<associationGroup>
<index>1</index>
<maxNodes>0</maxNodes>
<name>Lifeline</name>
<profile1>0x0</profile1>
<profile2>0x1</profile2>
<commands>
<commandClass>COMMAND_CLASS_SWITCH_BINARY</commandClass>
<commandClass>COMMAND_CLASS_SENSOR_MULTILEVEL</commandClass>
<commandClass>COMMAND_CLASS_DEVICE_RESET_LOCALLY</commandClass>
<commandClass>COMMAND_CLASS_CENTRAL_SCENE</commandClass>
<commandClass>COMMAND_CLASS_ALARM</commandClass>
</commands>
<associations>
<associationMember>
<node>1</node>
<endpoint>1</endpoint>
</associationMember>
</associations>
</associationGroup>
</entry>
<entry>
<int>2</int>
<associationGroup>
<index>2</index>
<maxNodes>0</maxNodes>
<name>On/Off control (PIR)</name>
<profile1>0x71</profile1>
<profile2>0x7</profile2>
<commands>
<commandClass>COMMAND_CLASS_BASIC</commandClass>
</commands>
<associations/>
</associationGroup>
</entry>
<entry>
<int>3</int>
<associationGroup>
<index>3</index>
<maxNodes>0</maxNodes>
<name>Motion Begin/End (PIR)</name>
<profile1>0x71</profile1>
<profile2>0x7</profile2>
<commands>
<commandClass>COMMAND_CLASS_ALARM</commandClass>
</commands>
<associations/>
</associationGroup>
</entry>
<entry>
<int>4</int>
<associationGroup>
<index>4</index>
<maxNodes>0</maxNodes>
<name>Ambient light</name>
<profile1>0x31</profile1>
<profile2>0x3</profile2>
<commands>
<commandClass>COMMAND_CLASS_CENTRAL_SCENE</commandClass>
</commands>
<associations/>
</associationGroup>
</entry>
<entry>
<int>5</int>
<associationGroup>
<index>5</index>
<maxNodes>0</maxNodes>
<name>On/Off Button</name>
<profile1>0x20</profile1>
<profile2>0x1</profile2>
<commands>
<commandClass>COMMAND_CLASS_BASIC</commandClass>
</commands>
<associations/>
</associationGroup>
</entry>
<entry>
<int>6</int>
<associationGroup>
<index>6</index>
<maxNodes>0</maxNodes>
<name>Scene Button</name>
<profile1>0x20</profile1>
<profile2>0x1</profile2>
<commands/>
<associations/>
</associationGroup>
</entry>
<entry>
<int>7</int>
<associationGroup>
<index>7</index>
<maxNodes>0</maxNodes>
<name>Dim Button</name>
<profile1>0x20</profile1>
<profile2>0x1</profile2>
<commands/>
<associations/>
</associationGroup>
</entry>
</associationGroups>
<endpoints class="concurrent-hash-map">
<entry>
<int>0</int>
<endPoint>
<deviceClass>
<basicDeviceClass>BASIC_TYPE_ROUTING_SLAVE</basicDeviceClass>
<genericDeviceClass>GENERIC_TYPE_SENSOR_NOTIFICATION</genericDeviceClass>
<specificDeviceClass>SPECIFIC_TYPE_NOTIFICATION_SENSOR</specificDeviceClass>
</deviceClass>
<endpointId>0</endpointId>
<secureCommandClasses/>
<supportedCommandClasses class="concurrent-hash-map">
<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_BASIC</commandClass>
<COMMAND__CLASS__BASIC>
<version>1</version>
<instances>1</instances>
<control>false</control>
<versionSupported>1</versionSupported>
<isGetSupported>true</isGetSupported>
</COMMAND__CLASS__BASIC>
</entry>
<entry>
<commandClass>COMMAND_CLASS_APPLICATION_STATUS</commandClass>
<COMMAND__CLASS__APPLICATION__STATUS>
<version>1</version>
<instances>1</instances>
<control>false</control>
<versionSupported>1</versionSupported>
</COMMAND__CLASS__APPLICATION__STATUS>
</entry>
<entry>
<commandClass>COMMAND_CLASS_SWITCH_BINARY</commandClass>
<COMMAND__CLASS__SWITCH__BINARY>
<version>1</version>
<instances>1</instances>
<control>false</control>
<versionSupported>1</versionSupported>
<isGetSupported>true</isGetSupported>
</COMMAND__CLASS__SWITCH__BINARY>
</entry>
<entry>
<commandClass>COMMAND_CLASS_SENSOR_MULTILEVEL</commandClass>
<COMMAND__CLASS__SENSOR__MULTILEVEL>
<version>4</version>
<instances>1</instances>
<control>false</control>
<versionSupported>4</versionSupported>
<sensors>
<entry>
<multilevelSensorType>LUMINANCE</multilevelSensorType>
<multilevelSensor>
<sensorType>LUMINANCE</sensorType>
<initialised>true</initialised>
</multilevelSensor>
</entry>
</sensors>
<isGetSupported>true</isGetSupported>
</COMMAND__CLASS__SENSOR__MULTILEVEL>
</entry>
<entry>
<commandClass>COMMAND_CLASS_ASSOCIATION_GRP_INFO</commandClass>
<COMMAND__CLASS__ASSOCIATION__GRP__INFO>
<version>1</version>
<instances>1</instances>
<control>false</control>
<versionSupported>1</versionSupported>
<autoSubscribeGroups>
<int>1</int>
<int>3</int>
<int>4</int>
</autoSubscribeGroups>
</COMMAND__CLASS__ASSOCIATION__GRP__INFO>
</entry>
<entry>
<commandClass>COMMAND_CLASS_DEVICE_RESET_LOCALLY</commandClass>
<COMMAND__CLASS__DEVICE__RESET__LOCALLY>
<version>1</version>
<instances>1</instances>
<control>false</control>
<versionSupported>1</versionSupported>
</COMMAND__CLASS__DEVICE__RESET__LOCALLY>
</entry>
<entry>
<commandClass>COMMAND_CLASS_CENTRAL_SCENE</commandClass>
<COMMAND__CLASS__CENTRAL__SCENE>
<version>3</version>
<instances>1</instances>
<control>false</control>
<versionSupported>3</versionSupported>
<sceneCount>1</sceneCount>
</COMMAND__CLASS__CENTRAL__SCENE>
</entry>
<entry>
<commandClass>COMMAND_CLASS_ZWAVEPLUS_INFO</commandClass>
<COMMAND__CLASS__ZWAVEPLUS__INFO>
<version>1</version>
<instances>1</instances>
<control>false</control>
<versionSupported>2</versionSupported>
<zwPlusVersion>1</zwPlusVersion>
<zwPlusRole>ROLE_TYPE_SLAVE_ALWAYS_ON</zwPlusRole>
<zwPlusNodeType>NODE_TYPE_ZWAVEPLUS_NODE</zwPlusNodeType>
<isGetSupported>true</isGetSupported>
</COMMAND__CLASS__ZWAVEPLUS__INFO>
</entry>
<entry>
<commandClass>COMMAND_CLASS_MULTI_CHANNEL</commandClass>
<COMMAND__CLASS__MULTI__CHANNEL>
<version>2</version>
<instances>1</instances>
<control>false</control>
<versionSupported>4</versionSupported>
<useDestEndpointAsSource>false</useDestEndpointAsSource>
<endpointsAreTheSameDeviceClass>false</endpointsAreTheSameDeviceClass>
</COMMAND__CLASS__MULTI__CHANNEL>
</entry>
<entry>
<commandClass>COMMAND_CLASS_CONFIGURATION</commandClass>
<COMMAND__CLASS__CONFIGURATION>
<version>1</version>
<instances>1</instances>
<control>false</control>
<versionSupported>1</versionSupported>
<configParameters>
<entry>
<int>1</int>
<configurationParameter>
<index>1</index>
<size>2</size>
<value>180</value>
<readOnly>false</readOnly>
<writeOnly>false</writeOnly>
</configurationParameter>
</entry>
<entry>
<int>2</int>
<configurationParameter>
<index>2</index>
<size>2</size>
<value>2000</value>
<readOnly>false</readOnly>
<writeOnly>false</writeOnly>
</configurationParameter>
</entry>
<entry>
<int>5</int>
<configurationParameter>
<index>5</index>
<size>1</size>
<value>100</value>
<readOnly>false</readOnly>
<writeOnly>false</writeOnly>
</configurationParameter>
</entry>
<entry>
<int>6</int>
<configurationParameter>
<index>6</index>
<size>1</size>
<value>0</value>
<readOnly>false</readOnly>
<writeOnly>false</writeOnly>
</configurationParameter>
</entry>
<entry>
<int>8</int>
<configurationParameter>
<index>8</index>
<size>1</size>
<value>1</value>
<readOnly>false</readOnly>
<writeOnly>false</writeOnly>
</configurationParameter>
</entry>
<entry>
<int>9</int>
<configurationParameter>
<index>9</index>
<size>1</size>
<value>2</value>
<readOnly>false</readOnly>
<writeOnly>false</writeOnly>
</configurationParameter>
</entry>
<entry>
<int>10</int>
<configurationParameter>
<index>10</index>
<size>2</size>
<value>10</value>
<readOnly>false</readOnly>
<writeOnly>false</writeOnly>
</configurationParameter>
</entry>
<entry>
<int>11</int>
<configurationParameter>
<index>11</index>
<size>2</size>
<value>255</value>
<readOnly>false</readOnly>
<writeOnly>false</writeOnly>
</configurationParameter>
</entry>
<entry>
<int>12</int>
<configurationParameter>
<index>12</index>
<size>2</size>
<value>204</value>
<readOnly>false</readOnly>
<writeOnly>false</writeOnly>
</configurationParameter>
</entry>
<entry>
<int>13</int>
<configurationParameter>
<index>13</index>
<size>2</size>
<value>204</value>
<readOnly>false</readOnly>
<writeOnly>false</writeOnly>
</configurationParameter>
</entry>
<entry>
<int>14</int>
<configurationParameter>
<index>14</index>
<size>2</size>
<value>204</value>
<readOnly>false</readOnly>
<writeOnly>false</writeOnly>
</configurationParameter>
</entry>
<entry>
<int>15</int>
<configurationParameter>
<index>15</index>
<size>1</size>
<value>10</value>
<readOnly>false</readOnly>
<writeOnly>false</writeOnly>
</configurationParameter>
</entry>
<entry>
<int>16</int>
<configurationParameter>
<index>16</index>
<size>2</size>
<value>0</value>
<readOnly>false</readOnly>
<writeOnly>false</writeOnly>
</configurationParameter>
</entry>
<entry>
<int>17</int>
<configurationParameter>
<index>17</index>
<size>1</size>
<value>8</value>
<readOnly>false</readOnly>
<writeOnly>false</writeOnly>
</configurationParameter>
</entry>
<entry>
<int>18</int>
<configurationParameter>
<index>18</index>
<size>1</size>
<value>1</value>
<readOnly>false</readOnly>
<writeOnly>false</writeOnly>
</configurationParameter>
</entry>
<entry>
<int>19</int>
<configurationParameter>
<index>19</index>
<size>2</size>
<value>103</value>
<readOnly>false</readOnly>
<writeOnly>false</writeOnly>
</configurationParameter>
</entry>
</configParameters>
</COMMAND__CLASS__CONFIGURATION>
</entry>
<entry>
<commandClass>COMMAND_CLASS_ALARM</commandClass>
<COMMAND__CLASS__ALARM>
<version>4</version>
<instances>1</instances>
<control>false</control>
<versionSupported>4</versionSupported>
<alarms>
<entry>
<alarmType>BURGLAR</alarmType>
<alarmState>
<alarmType>BURGLAR</alarmType>
<reportedEvents/>
<outer-class reference="../../../.."/>
</alarmState>
</entry>
</alarms>
<v1Supported>true</v1Supported>
<isGetSupported>true</isGetSupported>
</COMMAND__CLASS__ALARM>
</entry>
<entry>
<commandClass>COMMAND_CLASS_MANUFACTURER_SPECIFIC</commandClass>
<COMMAND__CLASS__MANUFACTURER__SPECIFIC>
<version>1</version>
<instances>1</instances>
<control>false</control>
<versionSupported>2</versionSupported>
<initSerialNumber>false</initSerialNumber>
<deviceManufacturer>625</deviceManufacturer>
<deviceType>2</deviceType>
<deviceId>6651</deviceId>
</COMMAND__CLASS__MANUFACTURER__SPECIFIC>
</entry>
<entry>
<commandClass>COMMAND_CLASS_POWERLEVEL</commandClass>
<COMMAND__CLASS__POWERLEVEL>
<version>1</version>
<instances>1</instances>
<control>false</control>
<versionSupported>1</versionSupported>
<powerLevel>0</powerLevel>
<powerTimeout>0</powerTimeout>
</COMMAND__CLASS__POWERLEVEL>
</entry>
<entry>
<commandClass>COMMAND_CLASS_FIRMWARE_UPDATE_MD</commandClass>
<COMMAND__CLASS__FIRMWARE__UPDATE__MD>
<version>1</version>
<instances>1</instances>
<control>false</control>
<versionSupported>4</versionSupported>
</COMMAND__CLASS__FIRMWARE__UPDATE__MD>
</entry>
<entry>
<commandClass>COMMAND_CLASS_ASSOCIATION</commandClass>
<COMMAND__CLASS__ASSOCIATION>
<version>2</version>
<instances>1</instances>
<control>false</control>
<versionSupported>2</versionSupported>
<maxGroups>7</maxGroups>
</COMMAND__CLASS__ASSOCIATION>
</entry>
<entry>
<commandClass>COMMAND_CLASS_VERSION</commandClass>
<COMMAND__CLASS__VERSION>
<version>2</version>
<instances>1</instances>
<control>false</control>
<versionSupported>2</versionSupported>
<libraryType>LIB_SLAVE_ENHANCED</libraryType>
<protocolVersion>5.3</protocolVersion>
<applicationVersion>1.132</applicationVersion>
<hardwareVersion>1</hardwareVersion>
</COMMAND__CLASS__VERSION>
</entry>
<entry>
<commandClass>COMMAND_CLASS_MULTI_CHANNEL_ASSOCIATION</commandClass>
<COMMAND__CLASS__MULTI__CHANNEL__ASSOCIATION>
<version>3</version>
<instances>1</instances>
<control>false</control>
<versionSupported>3</versionSupported>
<maxGroups>7</maxGroups>
</COMMAND__CLASS__MULTI__CHANNEL__ASSOCIATION>
</entry>
<entry>
<commandClass>COMMAND_CLASS_SECURITY</commandClass>
<COMMAND__CLASS__SECURITY>
<version>1</version>
<instances>1</instances>
<control>false</control>
<versionSupported>1</versionSupported>
</COMMAND__CLASS__SECURITY>
</entry>
</supportedCommandClasses>
</endPoint>
</entry>
<entry>
<int>1</int>
<endPoint>
<deviceClass>
<basicDeviceClass>BASIC_TYPE_ROUTING_SLAVE</basicDeviceClass>
<genericDeviceClass>GENERIC_TYPE_SENSOR_NOTIFICATION</genericDeviceClass>
<specificDeviceClass>SPECIFIC_TYPE_NOTIFICATION_SENSOR</specificDeviceClass>
</deviceClass>
<endpointId>1</endpointId>
<secureCommandClasses/>
<supportedCommandClasses class="concurrent-hash-map">
<entry>
<commandClass>COMMAND_CLASS_BASIC</commandClass>
<COMMAND__CLASS__BASIC>
<endpoint reference="../../../.."/>
<version>0</version>
<instances>0</instances>
<control>false</control>
<versionSupported>0</versionSupported>
<isGetSupported>true</isGetSupported>
</COMMAND__CLASS__BASIC>
</entry>
<entry>
<commandClass>COMMAND_CLASS_ASSOCIATION_GRP_INFO</commandClass>
<COMMAND__CLASS__ASSOCIATION__GRP__INFO>
<endpoint reference="../../../.."/>
<version>1</version>
<instances>1</instances>
<control>false</control>
<versionSupported>1</versionSupported>
<autoSubscribeGroups/>
</COMMAND__CLASS__ASSOCIATION__GRP__INFO>
</entry>
<entry>
<commandClass>COMMAND_CLASS_ZWAVEPLUS_INFO</commandClass>
<COMMAND__CLASS__ZWAVEPLUS__INFO>
<endpoint reference="../../../.."/>
<version>1</version>
<instances>1</instances>
<control>false</control>
<versionSupported>1</versionSupported>
<zwPlusVersion>1</zwPlusVersion>
<zwPlusRole>ROLE_TYPE_SLAVE_ALWAYS_ON</zwPlusRole>
<zwPlusNodeType>NODE_TYPE_ZWAVEPLUS_NODE</zwPlusNodeType>
<isGetSupported>true</isGetSupported>
</COMMAND__CLASS__ZWAVEPLUS__INFO>
</entry>
<entry>
<commandClass>COMMAND_CLASS_ALARM</commandClass>
<COMMAND__CLASS__ALARM>
<endpoint reference="../../../.."/>
<version>4</version>
<instances>1</instances>
<control>false</control>
<versionSupported>4</versionSupported>
<alarms>
<entry>
<alarmType>BURGLAR</alarmType>
<alarmState>
<alarmType>BURGLAR</alarmType>
<reportedEvents/>
<outer-class reference="../../../.."/>
</alarmState>
</entry>
</alarms>
<v1Supported>true</v1Supported>
<isGetSupported>true</isGetSupported>
</COMMAND__CLASS__ALARM>
</entry>
<entry>
<commandClass>COMMAND_CLASS_ASSOCIATION</commandClass>
<COMMAND__CLASS__ASSOCIATION>
<endpoint reference="../../../.."/>
<version>2</version>
<instances>1</instances>
<control>false</control>
<versionSupported>2</versionSupported>
<maxGroups>3</maxGroups>
</COMMAND__CLASS__ASSOCIATION>
</entry>
<entry>
<commandClass>COMMAND_CLASS_MULTI_CHANNEL_ASSOCIATION</commandClass>
<COMMAND__CLASS__MULTI__CHANNEL__ASSOCIATION>
<endpoint reference="../../../.."/>
<version>3</version>
<instances>1</instances>
<control>false</control>
<versionSupported>3</versionSupported>
<maxGroups>3</maxGroups>
</COMMAND__CLASS__MULTI__CHANNEL__ASSOCIATION>
</entry>
<entry>
<commandClass>COMMAND_CLASS_SECURITY</commandClass>
<COMMAND__CLASS__SECURITY>
<endpoint reference="../../../.."/>
<version>1</version>
<instances>1</instances>
<control>false</control>
<versionSupported>1</versionSupported>
</COMMAND__CLASS__SECURITY>
</entry>
</supportedCommandClasses>
</endPoint>
</entry>
<entry>
<int>2</int>
<endPoint>
<deviceClass>
<basicDeviceClass>BASIC_TYPE_ROUTING_SLAVE</basicDeviceClass>
<genericDeviceClass>GENERIC_TYPE_SENSOR_MULTILEVEL</genericDeviceClass>
<specificDeviceClass>SPECIFIC_TYPE_ROUTING_SENSOR_MULTILEVEL</specificDeviceClass>
</deviceClass>
<endpointId>2</endpointId>
<secureCommandClasses/>
<supportedCommandClasses class="concurrent-hash-map">
<entry>
<commandClass>COMMAND_CLASS_BASIC</commandClass>
<COMMAND__CLASS__BASIC>
<endpoint reference="../../../.."/>
<version>0</version>
<instances>0</instances>
<control>false</control>
<versionSupported>0</versionSupported>
<isGetSupported>true</isGetSupported>
</COMMAND__CLASS__BASIC>
</entry>
<entry>
<commandClass>COMMAND_CLASS_SENSOR_MULTILEVEL</commandClass>
<COMMAND__CLASS__SENSOR__MULTILEVEL>
<endpoint reference="../../../.."/>
<version>4</version>
<instances>1</instances>
<control>false</control>
<versionSupported>4</versionSupported>
<sensors>
<entry>
<multilevelSensorType>LUMINANCE</multilevelSensorType>
<multilevelSensor>
<sensorType>LUMINANCE</sensorType>
<initialised>true</initialised>
</multilevelSensor>
</entry>
</sensors>
<isGetSupported>true</isGetSupported>
</COMMAND__CLASS__SENSOR__MULTILEVEL>
</entry>
<entry>
<commandClass>COMMAND_CLASS_ASSOCIATION_GRP_INFO</commandClass>
<COMMAND__CLASS__ASSOCIATION__GRP__INFO>
<endpoint reference="../../../.."/>
<version>1</version>
<instances>1</instances>
<control>false</control>
<versionSupported>1</versionSupported>
<autoSubscribeGroups/>
</COMMAND__CLASS__ASSOCIATION__GRP__INFO>
</entry>
<entry>
<commandClass>COMMAND_CLASS_ZWAVEPLUS_INFO</commandClass>
<COMMAND__CLASS__ZWAVEPLUS__INFO>
<endpoint reference="../../../.."/>
<version>1</version>
<instances>1</instances>
<control>false</control>
<versionSupported>1</versionSupported>
<zwPlusVersion>1</zwPlusVersion>
<zwPlusRole>ROLE_TYPE_SLAVE_ALWAYS_ON</zwPlusRole>
<zwPlusNodeType>NODE_TYPE_ZWAVEPLUS_NODE</zwPlusNodeType>
<isGetSupported>true</isGetSupported>
</COMMAND__CLASS__ZWAVEPLUS__INFO>
</entry>
<entry>
<commandClass>COMMAND_CLASS_ASSOCIATION</commandClass>
<COMMAND__CLASS__ASSOCIATION>
<endpoint reference="../../../.."/>
<version>2</version>
<instances>1</instances>
<control>false</control>
<versionSupported>2</versionSupported>
<maxGroups>2</maxGroups>
</COMMAND__CLASS__ASSOCIATION>
</entry>
<entry>
<commandClass>COMMAND_CLASS_MULTI_CHANNEL_ASSOCIATION</commandClass>
<COMMAND__CLASS__MULTI__CHANNEL__ASSOCIATION>
<endpoint reference="../../../.."/>
<version>3</version>
<instances>1</instances>
<control>false</control>
<versionSupported>3</versionSupported>
<maxGroups>2</maxGroups>
</COMMAND__CLASS__MULTI__CHANNEL__ASSOCIATION>
</entry>
<entry>
<commandClass>COMMAND_CLASS_SECURITY</commandClass>
<COMMAND__CLASS__SECURITY>
<endpoint reference="../../../.."/>
<version>1</version>
<instances>1</instances>
<control>false</control>
<versionSupported>1</versionSupported>
</COMMAND__CLASS__SECURITY>
</entry>
</supportedCommandClasses>
</endPoint>
</entry>
<entry>
<int>3</int>
<endPoint>
<deviceClass>
<basicDeviceClass>BASIC_TYPE_ROUTING_SLAVE</basicDeviceClass>
<genericDeviceClass>GENERIC_TYPE_SWITCH_BINARY</genericDeviceClass>
<specificDeviceClass>SPECIFIC_TYPE_POWER_SWITCH_BINARY</specificDeviceClass>
</deviceClass>
<endpointId>3</endpointId>
<secureCommandClasses/>
<supportedCommandClasses class="concurrent-hash-map">
<entry>
<commandClass>COMMAND_CLASS_BASIC</commandClass>
<COMMAND__CLASS__BASIC>
<endpoint reference="../../../.."/>
<version>0</version>
<instances>0</instances>
<control>false</control>
<versionSupported>0</versionSupported>
<isGetSupported>true</isGetSupported>
</COMMAND__CLASS__BASIC>
</entry>
<entry>
<commandClass>COMMAND_CLASS_APPLICATION_STATUS</commandClass>
<COMMAND__CLASS__APPLICATION__STATUS>
<endpoint reference="../../../.."/>
<version>1</version>
<instances>1</instances>
<control>false</control>
<versionSupported>1</versionSupported>
</COMMAND__CLASS__APPLICATION__STATUS>
</entry>
<entry>
<commandClass>COMMAND_CLASS_SWITCH_BINARY</commandClass>
<COMMAND__CLASS__SWITCH__BINARY>
<endpoint reference="../../../.."/>
<version>1</version>
<instances>1</instances>
<control>false</control>
<versionSupported>1</versionSupported>
<isGetSupported>true</isGetSupported>
</COMMAND__CLASS__SWITCH__BINARY>
</entry>
<entry>
<commandClass>COMMAND_CLASS_ASSOCIATION_GRP_INFO</commandClass>
<COMMAND__CLASS__ASSOCIATION__GRP__INFO>
<endpoint reference="../../../.."/>
<version>1</version>
<instances>1</instances>
<control>false</control>
<versionSupported>1</versionSupported>
<autoSubscribeGroups/>
</COMMAND__CLASS__ASSOCIATION__GRP__INFO>
</entry>
<entry>
<commandClass>COMMAND_CLASS_ZWAVEPLUS_INFO</commandClass>
<COMMAND__CLASS__ZWAVEPLUS__INFO>
<endpoint reference="../../../.."/>
<version>1</version>
<instances>1</instances>
<control>false</control>
<versionSupported>1</versionSupported>
<zwPlusVersion>1</zwPlusVersion>
<zwPlusRole>ROLE_TYPE_SLAVE_ALWAYS_ON</zwPlusRole>
<zwPlusNodeType>NODE_TYPE_ZWAVEPLUS_NODE</zwPlusNodeType>
<isGetSupported>true</isGetSupported>
</COMMAND__CLASS__ZWAVEPLUS__INFO>
</entry>
<entry>
<commandClass>COMMAND_CLASS_ASSOCIATION</commandClass>
<COMMAND__CLASS__ASSOCIATION>
<endpoint reference="../../../.."/>
<version>2</version>
<instances>1</instances>
<control>false</control>
<versionSupported>2</versionSupported>
<maxGroups>1</maxGroups>
</COMMAND__CLASS__ASSOCIATION>
</entry>
<entry>
<commandClass>COMMAND_CLASS_MULTI_CHANNEL_ASSOCIATION</commandClass>
<COMMAND__CLASS__MULTI__CHANNEL__ASSOCIATION>
<endpoint reference="../../../.."/>
<version>3</version>
<instances>1</instances>
<control>false</control>
<versionSupported>3</versionSupported>
<maxGroups>1</maxGroups>
</COMMAND__CLASS__MULTI__CHANNEL__ASSOCIATION>
</entry>
<entry>
<commandClass>COMMAND_CLASS_SECURITY</commandClass>
<COMMAND__CLASS__SECURITY>
<endpoint reference="../../../.."/>
<version>1</version>
<instances>1</instances>
<control>false</control>
<versionSupported>1</versionSupported>
</COMMAND__CLASS__SECURITY>
</entry>
</supportedCommandClasses>
</endPoint>
</entry>
<entry>
<int>4</int>
<endPoint>
<deviceClass>
<basicDeviceClass>BASIC_TYPE_ROUTING_SLAVE</basicDeviceClass>
<genericDeviceClass>GENERIC_TYPE_WALL_CONTROLLER</genericDeviceClass>
<specificDeviceClass>SPECIFIC_TYPE_BASIC_WALL_CONTROLLER</specificDeviceClass>
</deviceClass>
<endpointId>4</endpointId>
<secureCommandClasses/>
<supportedCommandClasses class="concurrent-hash-map">
<entry>
<commandClass>COMMAND_CLASS_BASIC</commandClass>
<COMMAND__CLASS__BASIC>
<endpoint reference="../../../.."/>
<version>0</version>
<instances>0</instances>
<control>false</control>
<versionSupported>0</versionSupported>
<isGetSupported>true</isGetSupported>
</COMMAND__CLASS__BASIC>
</entry>
<entry>
<commandClass>COMMAND_CLASS_ASSOCIATION_GRP_INFO</commandClass>
<COMMAND__CLASS__ASSOCIATION__GRP__INFO>
<endpoint reference="../../../.."/>
<version>1</version>
<instances>1</instances>
<control>false</control>
<versionSupported>1</versionSupported>
<autoSubscribeGroups/>
</COMMAND__CLASS__ASSOCIATION__GRP__INFO>
</entry>
<entry>
<commandClass>COMMAND_CLASS_CENTRAL_SCENE</commandClass>
<COMMAND__CLASS__CENTRAL__SCENE>
<endpoint reference="../../../.."/>
<version>3</version>
<instances>1</instances>
<control>false</control>
<versionSupported>3</versionSupported>
<sceneCount>1</sceneCount>
</COMMAND__CLASS__CENTRAL__SCENE>
</entry>
<entry>
<commandClass>COMMAND_CLASS_ZWAVEPLUS_INFO</commandClass>
<COMMAND__CLASS__ZWAVEPLUS__INFO>
<endpoint reference="../../../.."/>
<version>1</version>
<instances>1</instances>
<control>false</control>
<versionSupported>1</versionSupported>
<zwPlusVersion>1</zwPlusVersion>
<zwPlusRole>ROLE_TYPE_SLAVE_ALWAYS_ON</zwPlusRole>
<zwPlusNodeType>NODE_TYPE_ZWAVEPLUS_NODE</zwPlusNodeType>
<isGetSupported>true</isGetSupported>
</COMMAND__CLASS__ZWAVEPLUS__INFO>
</entry>
<entry>
<commandClass>COMMAND_CLASS_ASSOCIATION</commandClass>
<COMMAND__CLASS__ASSOCIATION>
<endpoint reference="../../../.."/>
<version>2</version>
<instances>1</instances>
<control>false</control>
<versionSupported>2</versionSupported>
<maxGroups>4</maxGroups>
</COMMAND__CLASS__ASSOCIATION>
</entry>
<entry>
<commandClass>COMMAND_CLASS_MULTI_CHANNEL_ASSOCIATION</commandClass>
<COMMAND__CLASS__MULTI__CHANNEL__ASSOCIATION>
<endpoint reference="../../../.."/>
<version>3</version>
<instances>1</instances>
<control>false</control>
<versionSupported>3</versionSupported>
<maxGroups>4</maxGroups>
</COMMAND__CLASS__MULTI__CHANNEL__ASSOCIATION>
</entry>
<entry>
<commandClass>COMMAND_CLASS_SECURITY</commandClass>
<COMMAND__CLASS__SECURITY>
<endpoint reference="../../../.."/>
<version>1</version>
<instances>1</instances>
<control>false</control>
<versionSupported>1</versionSupported>
</COMMAND__CLASS__SECURITY>
</entry>
</supportedCommandClasses>
</endPoint>
</entry>
</endpoints>
<nodeNeighbors/>
<lastReceived>2023-01-01 14:23:35.278 UTC</lastReceived>
</node>

[anon71759204]

@apella12

but it could be that both the burglar and motion channels are active (so not replace but add in the DB).

Adding both alarm channels may cause confusion to bystanders. The alarm_burglar channel doesn’t catch event 8 (HOME_SECURITY__MOTION_UNKNOWN). Whether the alarm_motion channel catches event 0 (HOME_SECURITY__NONE) remains to be seen.

[apella12 (Bob Eckhoff)]

@Bernhelm

Reinitialise the device is missing (but that is the case for other things, too).

This is a problem for a powered device. For a battery device it can be okay temporarily if the device has not woken up recently. Also the heal will not work if there are only 4 lines, so no surprise there. How close is the device to the controller (or another powered device) Do you have the controller set for Network wide inclusion? I’m assuming no security inclusion too. Controller page (advanced)

A couple of things (in increasing severity) to try to get five lines 0) set log to debug and press the device button 3 times 1) delete the thing (do not exclude) and inbox–>zwave–> scan–> add 2) restart OH 3) exclude the device (controller “exclude Devices” and press device button three times, then try inclusion again. (should be a new number)

We can solve the motion/burglar issue once the device is communicating the dimmer and luminance information (5 lines). We need to see the code when you walk by the detector while the binding is in Debug mode

        HOME_SECURITY__NONE("BURGLAR", 0),
        HOME_SECURITY__INTRUSION("BURGLAR", 1),
        HOME_SECURITY__INTRUSION_UNKNOWN("BURGLAR", 2),
        HOME_SECURITY__TAMPER("BURGLAR", 3),
        HOME_SECURITY__TAMPER_INVALID_CODE("BURGLAR", 4),
        HOME_SECURITY__GLASS_BREAK("BURGLAR", 5),
        HOME_SECURITY__GLASS_BREAK_UNKNOWN("BURGLAR", 6),
        HOME_SECURITY__MOTION("BURGLAR", 7),
        HOME_SECURITY__MOTION_UNKNOWN("BURGLAR", 8),
        HOME_SECURITY__TAMPER_MOVED("BURGLAR", 9),

[chris (Chris Jackson)]

Thanks - I’ve updated the channel definition.

[Bernhelm (Bernd Müller)]

Thank you Bob for your ideas and help.

I am still working on it, but I want to keep you informed. I am not ready with all tests yet.
My inclusion settings where right, same settings as in your screenshot.

What I got so far: If I restart openhab, I get all 5 lines. But when I press “Heal the device” it is doing something and then I only have 4 lines again until the next reboot of openhab. Problem is not solved with that.
Deleting and reconnecting the thing did not change anything.
But interesting: With a reboot of Openhab it seems that I get a new value for sensor luminance (same value in 1 and 2). At least I saw some new values on it after doing some reboot. But it does not change while I was looking in openhab, even when i change the light in the room.
I did not manage to exclude the thing. I went in exclusion mode on my controller and pressed the button in the right way and nothing happens.
Also interesting: When I look into the network map, there are a lot of connections with this new node, but all only unidirectional going from the motion switch device node to other nodes. No bidirectional connection line and no unidirectional one to the device.

My next point would be to connect the MotionSwitch to a position near the controller and try to exclude and include the thing again and test, if it is a problem in the network what is what I assume at the moment. If that is not helping, I would like to test my reboot theory and test reboots while e.g. the motion sensor is active and the light is on.

If someone has another idea, it is always welcome. If not I will continue my tries and come back with new results again.

[apella12 (Bob Eckhoff)]

@Bernhelm

If I restart openhab, I get all 5 lines. But when I press “Heal the device” it is doing something and then I only have 4 lines again until the next reboot of openhab. Problem is not solved with that.

Taking this a step at a time;
The five lines are good after a restart ! Could set binding in Debug mode before trying the Heal to see why that is failing. Are you refreshing (or reloading completely) the UI page maybe 30 seconds after the “heal”. It could be the four lines go back to five. Reason I say this is any lines on the network map from the node in question indicates some degree of success. Battery nodes will only be unidirectional at this point, but powered nodes should pop up as two way.

@Bernhelm

With a reboot of Openhab it seems that I get a new value for sensor luminance

This is also a good sign. As to changes in light it looks like the default is 2000 (parameter 2) move that lower and try the lights again. It might be working. I do not see offhand the report interval for the light, so time might be needed for the change.

I would try the motion test while in debug mode before excluding at this point. Based on the other theme in this thread you might be getting a “8”, but the channel is expecting a “3” (or something else)

[Bernhelm (Bernd Müller)]

Thank you for your response, I really appreciate your help!

I tried the first topic.
Restart again brought 5 lines. I set the debug mode and there came a lot of messages regarding node 36 (the node of the motionswitch). It got into the 4 lines again and refreshing and reloading completely did not bring back the 5 lines. Only the next reboot would.

I tried to find relevant topics in DEBUG log regarding the heal:

20:37:08.048 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 36: Configuration update received
20:37:08.066 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 36: Configuration update ignored config_10_2 to 10 (BigDecimal)
20:37:08.081 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 36: Configuration update ignored group_5 to [] (EmptyList)
20:37:08.086 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 36: Configuration update ignored group_4 to [] (EmptyList)
20:37:08.093 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 36: Configuration update ignored group_7 to [] (EmptyList)
20:37:08.110 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 36: Configuration update ignored group_6 to [] (EmptyList)
20:37:08.116 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 36: Configuration update ignored group_1 to [controller] (ArrayList)
20:37:08.121 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 36: Configuration update ignored group_3 to [] (EmptyList)
20:37:08.128 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 36: Configuration update ignored group_2 to [] (EmptyList)
20:37:08.133 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 36: Configuration update ignored config_19_2 to 103 (BigDecimal)
20:37:08.159 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 36: Configuration update ignored config_16_2 to 0 (BigDecimal)
20:37:08.165 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 36: Configuration update ignored config_17_1 to 8 (BigDecimal)
20:37:08.169 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 36: Configuration update ignored config_12_2 to 204 (BigDecimal)
20:37:08.175 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 36: Configuration update ignored config_18_1 to 1 (BigDecimal)
20:37:08.181 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 36: Configuration update ignored config_11_2 to 255 (BigDecimal)
20:37:08.187 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 36: Configuration update ignored config_13_2 to 204 (BigDecimal)
20:37:08.192 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 36: Configuration update ignored config_14_2 to 204 (BigDecimal)
20:37:08.198 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 36: Configuration update ignored config_15_1 to 10 (BigDecimal)
20:37:08.209 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 36: Configuration update ignored action_remove to false (Boolean)
20:37:08.215 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 36: Configuration update set action_heal to true (Boolean)
20:37:08.230 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 36: Starting heal on node!
20:37:08.237 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 36: Starting network mesh heal.
20:37:08.243 [DEBUG] [ialization.ZWaveNodeInitStageAdvancer] - NODE 36: Starting initialisation from HEAL_START
20:37:08.250 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 36: Configuration update ignored config_1_2 to 120 (BigDecimal)
20:37:08.251 [DEBUG] [ialization.ZWaveNodeInitStageAdvancer] - NODE 36: Node advancer - advancing to HEAL_START
20:37:08.261 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 36: Configuration update ignored config_8_1 to 1 (BigDecimal)
20:37:08.268 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 36: Got an event from Z-Wave network: ZWaveInitializationStateEvent
20:37:08.271 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 36: Configuration update ignored config_9_1 to 2 (BigDecimal)
20:37:08.274 [DEBUG] [ialization.ZWaveNodeInitStageAdvancer] - NODE 36: Node advancer - advancing to UPDATE_NEIGHBORS
20:37:08.278 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 36: Configuration update ignored config_2_2 to 2000 (BigDecimal)
20:37:08.280 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 36: Got an event from Z-Wave network: ZWaveInitializationStateEvent
20:37:08.284 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 36: Configuration update ignored config_5_1 to 100 (BigDecimal)
20:37:08.287 [DEBUG] [ialization.ZWaveNodeInitStageAdvancer] - NODE 36: Node advancer: UPDATE_NEIGHBORS - updating neighbor list
20:37:08.294 [DEBUG] [RequestNodeNeighborUpdateMessageClass] - NODE 36: Request neighbor update
20:37:08.291 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 36: Configuration update ignored config_6_1 to 0 (BigDecimal)
20:37:08.300 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 36: sendTransaction org.openhab.binding.zwave.internal.protocol.ZWaveSerialPayload@1070858
20:37:08.305 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 36: Configuration update ignored node_id to 36 (BigDecimal)
20:37:08.309 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 36: Adding to device queue
20:37:08.319 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 36: Added 358 to queue - size 1
20:37:08.327 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
20:37:08.362 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 48 24 39 AF
20:37:08.389 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 48 24 39 AF
20:37:08.398 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT
20:37:08.401 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
20:37:08.406 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 358: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 57
20:37:08.420 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
20:37:08.433 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
20:37:08.439 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 358: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 57
20:37:08.449 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg: ACK
20:37:08.456 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
20:37:08.463 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
20:37:08.626 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 05 00 48 39 21 AA
20:37:08.632 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=33, callback=57, payload=39 21
20:37:08.637 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=33, callback=57, payload=39 21
20:37:08.643 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 358: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 57
20:37:08.649 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
20:37:08.653 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 358: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 57
20:37:08.668 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking TID 358: (Callback 57)
20:37:08.676 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Callback match!
20:37:08.682 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Correlated to TID 358: callback 57
20:37:08.687 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=33, callback=57, payload=39 21
20:37:08.695 [DEBUG] [RequestNodeNeighborUpdateMessageClass] - NODE 36: Got NodeNeighborUpdate request.
20:37:08.700 [DEBUG] [RequestNodeNeighborUpdateMessageClass] - NODE 36: NodeNeighborUpdate STARTED
20:37:08.713 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 358: Advanced to WAIT_DATA
20:37:08.719 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 36: TID 358: Transaction not completed
20:37:08.723 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
20:37:08.737 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
20:37:21.526 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 05 00 48 39 22 A9
20:37:21.535 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=34, callback=57, payload=39 22
20:37:21.541 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=34, callback=57, payload=39 22
20:37:21.546 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null
20:37:21.551 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
20:37:21.556 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: null
20:37:21.564 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking TID 358: (Callback 57)
20:37:21.572 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Callback match!
20:37:21.578 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Correlated to TID 358: callback 57
20:37:21.587 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=34, callback=57, payload=39 22
20:37:21.592 [DEBUG] [RequestNodeNeighborUpdateMessageClass] - NODE 36: Got NodeNeighborUpdate request.
20:37:21.598 [DEBUG] [RequestNodeNeighborUpdateMessageClass] - NODE 36: NodeNeighborUpdate DONE
20:37:21.612 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 36: Got an event from Z-Wave network: ZWaveNetworkEvent
20:37:21.624 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 358: Transaction COMPLETED
20:37:21.638 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 36: Response processed after 13232ms
20:37:21.647 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 36: TID 358: Transaction completed
20:37:21.653 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 36: notifyTransactionResponse TID:358 DONE
20:37:21.664 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 36: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
20:37:21.667 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 358: Transaction event listener: DONE: DONE -> UNINTIALIZED
20:37:21.671 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
20:37:21.677 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
20:37:21.684 [DEBUG] [ialization.ZWaveNodeInitStageAdvancer] - NODE 36: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@e52fe2
20:37:21.690 [DEBUG] [ialization.ZWaveNodeInitStageAdvancer] - NODE 36: Node Init transaction completed with response COMPLETE
20:37:21.698 [DEBUG] [ialization.ZWaveNodeInitStageAdvancer] - NODE 36: Node advancer - advancing to GET_NEIGHBORS
20:37:21.716 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 36: Got an event from Z-Wave network: ZWaveInitializationStateEvent
20:37:21.722 [DEBUG] [ialization.ZWaveNodeInitStageAdvancer] - NODE 36: Node advancer: GET_NEIGHBORS - get RoutingInfo
20:37:21.729 [DEBUG] [ialmessage.GetRoutingInfoMessageClass] - NODE 36: Request routing info
20:37:21.735 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 255: sendTransaction org.openhab.binding.zwave.internal.protocol.ZWaveSerialPayload@5df3a3
20:37:21.741 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 255: Added 359 to queue - size 1
20:37:21.750 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
20:37:21.769 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 07 00 80 24 00 00 03 5F
20:37:21.783 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 07 00 80 24 00 00 03 5F
20:37:21.797 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT
20:37:21.799 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
20:37:21.804 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 359: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0
20:37:21.807 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
20:37:21.813 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
20:37:21.816 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 20 01 80 00 A8 D1 FF 07 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 DF
20:37:21.819 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 359: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0
20:37:21.827 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg: ACK
20:37:21.832 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
20:37:21.837 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
20:37:21.845 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=GetRoutingInfo[128], type=Response[1], dest=255, callback=0, payload=00 A8 D1 FF 07 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
20:37:21.853 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=GetRoutingInfo[128], type=Response[1], dest=255, callback=0, payload=00 A8 D1 FF 07 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
20:37:21.883 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 359: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0
20:37:21.889 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
20:37:21.897 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 359: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0
20:37:21.909 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=GetRoutingInfo[128], type=Response[1], dest=255, callback=0, payload=00 A8 D1 FF 07 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
20:37:21.915 [DEBUG] [ialmessage.GetRoutingInfoMessageClass] - NODE 36: Got NodeRoutingInfo request.
20:37:21.921 [DEBUG] [ialmessage.GetRoutingInfoMessageClass] - NODE 36: Neighbor nodes: 12 14 16 17 21 23 24 25 26 27 28 29 30 31 32 33 34 35
20:37:21.926 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 36: Got an event from Z-Wave network: ZWaveNetworkEvent
20:37:21.973 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 359: Transaction COMPLETED
20:37:21.986 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 255: Response processed after 182ms
20:37:21.992 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 255: TID 359: Transaction completed
20:37:21.997 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 255: notifyTransactionResponse TID:359 DONE
20:37:22.003 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
20:37:22.003 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 359: Transaction event listener: DONE: DONE -> UNINTIALIZED
20:37:22.021 [DEBUG] [ialization.ZWaveNodeInitStageAdvancer] - NODE 36: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@11c65d4
20:37:22.027 [DEBUG] [ialization.ZWaveNodeInitStageAdvancer] - NODE 36: Node Init transaction completed with response COMPLETE
20:37:22.008 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
20:37:22.036 [DEBUG] [ialization.ZWaveNodeInitStageAdvancer] - NODE 36: Node advancer - advancing to DELETE_SUC_ROUTES
20:37:22.040 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 36: Got an event from Z-Wave network: ZWaveInitializationStateEvent
20:37:22.051 [DEBUG] [ialization.ZWaveNodeInitStageAdvancer] - NODE 36: Node advancer is deleting SUC return route.
20:37:22.058 [DEBUG] [sage.DeleteSucReturnRouteMessageClass] - NODE 36: Deleting SUC return routes
20:37:22.064 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 36: sendTransaction org.openhab.binding.zwave.internal.protocol.ZWaveSerialPayload@1c4710b
20:37:22.071 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 36: Adding to device queue
20:37:22.080 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 36: Added 360 to queue - size 1
20:37:22.095 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
20:37:22.101 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 55 24 3A B1
20:37:22.107 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 55 24 3A B1
20:37:22.116 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
20:37:22.122 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
20:37:22.128 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT
20:37:22.135 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
20:37:22.142 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null
20:37:22.148 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg: ACK
20:37:22.154 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 360: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 58
20:37:22.163 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
20:37:22.171 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
20:37:22.487 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 55 01 AE
20:37:22.496 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=DeleteSUCReturnRoute[85], type=Response[1], dest=255, callback=0, payload=01
20:37:22.515 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=DeleteSUCReturnRoute[85], type=Response[1], dest=255, callback=0, payload=01
20:37:22.521 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 360: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 58
20:37:22.529 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
20:37:22.535 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 360: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 58
20:37:22.543 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=DeleteSUCReturnRoute[85], type=Response[1], dest=255, callback=0, payload=01
20:37:22.550 [DEBUG] [sage.DeleteSucReturnRouteMessageClass] - NODE 36: Got DeleteSUCReturnRoute response.
20:37:22.557 [DEBUG] [sage.DeleteSucReturnRouteMessageClass] - NODE 36: DeleteSUCReturnRoute command in progress.
20:37:22.563 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 360: Advanced to WAIT_REQUEST
20:37:22.570 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 36: TID 360: Transaction not completed
20:37:22.576 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
20:37:22.583 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
20:37:22.790 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 05 00 55 3A 00 95
20:37:22.798 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=DeleteSUCReturnRoute[85], type=Request[0], dest=0, callback=58, payload=3A 00
20:37:22.808 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=DeleteSUCReturnRoute[85], type=Request[0], dest=0, callback=58, payload=3A 00
20:37:22.814 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 360: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 58
20:37:22.820 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
20:37:22.831 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 360: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 58
20:37:22.837 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking TID 360: (Callback 58)
20:37:22.846 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Callback match!
20:37:22.855 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Correlated to TID 360: callback 58
20:37:22.861 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=DeleteSUCReturnRoute[85], type=Request[0], dest=0, callback=58, payload=3A 00
20:37:22.866 [DEBUG] [sage.DeleteSucReturnRouteMessageClass] - NODE 36: Got DeleteSUCReturnRoute request.
20:37:22.872 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 36: Got an event from Z-Wave network: ZWaveNetworkEvent
20:37:22.877 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 360: Transaction COMPLETED
20:37:22.884 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 36: Response processed after 730ms
20:37:22.889 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 36: TID 360: Transaction completed
20:37:22.894 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 36: notifyTransactionResponse TID:360 DONE
20:37:22.900 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 36: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
20:37:22.901 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 360: Transaction event listener: DONE: DONE -> UNINTIALIZED
20:37:22.906 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
20:37:22.912 [DEBUG] [ialization.ZWaveNodeInitStageAdvancer] - NODE 36: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@ab54d6
20:37:22.916 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
20:37:22.921 [DEBUG] [ialization.ZWaveNodeInitStageAdvancer] - NODE 36: Node Init transaction completed with response COMPLETE
20:37:22.938 [DEBUG] [ialization.ZWaveNodeInitStageAdvancer] - NODE 36: Node advancer - advancing to SUC_ROUTE
20:37:22.946 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 36: Got an event from Z-Wave network: ZWaveInitializationStateEvent
20:37:22.958 [DEBUG] [ialization.ZWaveNodeInitStageAdvancer] - NODE 36: Node advancer is setting SUC route.
20:37:22.970 [DEBUG] [sage.AssignSucReturnRouteMessageClass] - NODE 36: Assigning SUC return route
20:37:22.975 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 36: sendTransaction org.openhab.binding.zwave.internal.protocol.ZWaveSerialPayload@1829023
20:37:22.983 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 36: Adding to device queue
20:37:22.988 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 36: Added 361 to queue - size 1
20:37:23.002 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
20:37:23.008 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 51 24 3B B4
20:37:23.015 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 51 24 3B B4
20:37:23.020 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT
20:37:23.021 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
20:37:23.028 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 361: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 59
20:37:23.028 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
20:37:23.035 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
20:37:23.040 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 361: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 59
20:37:23.045 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg: ACK
20:37:23.050 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
20:37:23.055 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
20:37:25.033 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - NODE 36: TID 361: Timeout at state WAIT_RESPONSE. 3 retries remaining.
20:37:25.038 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - TID 361: Transaction is current transaction, so clearing!!!!!
20:37:25.047 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 361: Transaction CANCELLED
20:37:25.052 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 36: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
20:37:25.057 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 36: notifyTransactionResponse TID:361 CANCELLED
20:37:25.063 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 361: Transaction event listener: DONE: CANCELLED -> WAIT_RESPONSE
20:37:25.063 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
20:37:25.070 [DEBUG] [ialization.ZWaveNodeInitStageAdvancer] - NODE 36: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@8b8109
20:37:25.175 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 51 01 AA
20:37:25.183 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=AssignSucReturnRoute[81], type=Response[1], dest=255, callback=0, payload=01
20:37:25.189 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=AssignSucReturnRoute[81], type=Response[1], dest=255, callback=0, payload=01
20:37:25.194 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null
20:37:25.199 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 0
20:37:25.204 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: null
20:37:25.210 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
20:37:25.215 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
20:37:25.954 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 36: sendTransaction org.openhab.binding.zwave.internal.protocol.ZWaveSerialPayload@1829023
20:37:25.964 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 36: Adding to device queue
20:37:25.973 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 36: Added 362 to queue - size 1
20:37:25.979 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
20:37:25.987 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 51 24 3C B3
20:37:25.996 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 51 24 3C B3
20:37:26.002 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT
20:37:26.010 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 362: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 60
20:37:28.018 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - NODE 36: TID 362: Timeout at state WAIT_RESPONSE. 3 retries remaining.
20:37:28.024 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - TID 362: Transaction is current transaction, so clearing!!!!!
20:37:28.032 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 362: Transaction CANCELLED
20:37:28.042 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 36: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
20:37:28.049 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 36: notifyTransactionResponse TID:362 CANCELLED
20:37:28.058 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 362: Transaction event listener: DONE: CANCELLED -> WAIT_RESPONSE
20:37:28.058 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
20:37:28.065 [DEBUG] [ialization.ZWaveNodeInitStageAdvancer] - NODE 36: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@1a97453
20:37:28.829 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
20:37:28.834 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
20:37:28.841 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
20:37:28.842 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 51 00 AB
20:37:28.850 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null
20:37:28.856 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=AssignSucReturnRoute[81], type=Response[1], dest=255, callback=0, payload=00
20:37:28.860 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg: ACK
20:37:28.875 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=AssignSucReturnRoute[81], type=Response[1], dest=255, callback=0, payload=00
20:37:28.883 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null
20:37:28.892 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 0
20:37:28.900 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: null
20:37:28.909 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
20:37:28.917 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
20:37:30.014 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 36: sendTransaction org.openhab.binding.zwave.internal.protocol.ZWaveSerialPayload@1829023
20:37:30.020 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 36: Adding to device queue
20:37:30.024 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 36: Added 363 to queue - size 1
20:37:30.029 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
20:37:30.038 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 51 24 3D B2
20:37:30.045 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 51 24 3D B2
20:37:30.051 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT
20:37:30.062 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 363: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 61
20:37:31.056 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
20:37:31.061 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
20:37:31.068 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 51 00 AB
20:37:31.067 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
20:37:31.075 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=AssignSucReturnRoute[81], type=Response[1], dest=255, callback=0, payload=00
20:37:31.077 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 363: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 61
20:37:31.086 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg: ACK
20:37:31.091 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=AssignSucReturnRoute[81], type=Response[1], dest=255, callback=0, payload=00
20:37:31.097 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 363: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 61
20:37:31.105 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
20:37:31.110 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 363: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 61
20:37:31.115 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=AssignSucReturnRoute[81], type=Response[1], dest=255, callback=0, payload=00
20:37:31.119 [DEBUG] [sage.AssignSucReturnRouteMessageClass] - NODE 36: Got AssignSucReturnRoute response.
20:37:31.123 [DEBUG] [sage.AssignSucReturnRouteMessageClass] - NODE 36: AssignSucReturnRoute command failed.
20:37:31.131 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 36: Got an event from Z-Wave network: ZWaveNetworkEvent
20:37:31.135 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 363: Transaction CANCELLED
20:37:31.139 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Holdoff Timer started...
20:37:31.145 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 36: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
20:37:31.150 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 36: CANCEL while sending message. Requeueing - 2 attempts left!
20:37:31.154 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 363: Transaction RESET with 2 retries remaining.
20:37:31.159 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 36: Adding to device queue
20:37:31.163 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 36: Added 363 to queue - size 1
20:37:31.168 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff true.
20:37:31.173 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 36: TID 363: Transaction not completed
20:37:31.177 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
20:37:31.182 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff true.
20:37:31.395 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
20:37:31.401 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 51 24 3E B1
20:37:31.407 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 51 24 3E B1
20:37:31.418 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT
20:37:31.426 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 363: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 62
20:37:33.435 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - NODE 36: TID 363: Timeout at state WAIT_RESPONSE. 2 retries remaining.
20:37:33.440 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - TID 363: Transaction is current transaction, so clearing!!!!!
20:37:33.446 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 363: Transaction CANCELLED
20:37:33.452 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 36: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
20:37:33.458 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 36: notifyTransactionResponse TID:363 CANCELLED
20:37:33.468 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 363: Transaction event listener: DONE: CANCELLED -> WAIT_RESPONSE
20:37:33.468 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
20:37:33.475 [DEBUG] [ialization.ZWaveNodeInitStageAdvancer] - NODE 36: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@ad06e0
20:37:34.637 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
20:37:34.646 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
20:37:34.654 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
20:37:34.659 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null
20:37:34.661 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 51 00 AB
20:37:34.665 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg: ACK
20:37:34.672 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
20:37:34.678 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
20:37:34.670 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=AssignSucReturnRoute[81], type=Response[1], dest=255, callback=0, payload=00
20:37:34.688 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=AssignSucReturnRoute[81], type=Response[1], dest=255, callback=0, payload=00
20:37:34.692 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null
20:37:34.697 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 0
20:37:34.702 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: null
20:37:34.708 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 05 00 51 3E 00 95
20:37:34.714 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=AssignSucReturnRoute[81], type=Request[0], dest=0, callback=62, payload=3E 00
20:37:34.709 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
20:37:34.723 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
20:37:34.728 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=AssignSucReturnRoute[81], type=Request[0], dest=0, callback=62, payload=3E 00
20:37:34.733 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null
20:37:34.738 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 0
20:37:34.744 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: null
20:37:34.749 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=AssignSucReturnRoute[81], type=Request[0], dest=0, callback=62, payload=3E 00
20:37:34.755 [DEBUG] [sage.AssignSucReturnRouteMessageClass] - NODE ?: transaction not correlated for AssignSucReturnRouteMessageClass
20:37:34.760 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
20:37:34.765 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
20:37:37.670 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 36: sendTransaction org.openhab.binding.zwave.internal.protocol.ZWaveSerialPayload@1829023
20:37:37.676 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 36: Adding to device queue
20:37:37.683 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 36: Added 364 to queue - size 1
20:37:37.688 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
20:37:37.695 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 51 24 3F B0
20:37:37.704 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 51 24 3F B0
20:37:37.708 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT
20:37:37.712 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
20:37:37.721 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 364: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 63
20:37:37.721 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
20:37:37.732 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
20:37:37.744 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 364: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 63
20:37:37.749 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg: ACK
20:37:37.753 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
20:37:37.757 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
20:37:39.730 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - NODE 36: TID 364: Timeout at state WAIT_RESPONSE. 3 retries remaining.
20:37:39.735 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - TID 364: Transaction is current transaction, so clearing!!!!!
20:37:39.741 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 364: Transaction CANCELLED
20:37:39.749 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 36: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
20:37:39.755 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 36: notifyTransactionResponse TID:364 CANCELLED
20:37:39.765 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 364: Transaction event listener: DONE: CANCELLED -> WAIT_RESPONSE
20:37:39.765 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
20:37:39.771 [DEBUG] [ialization.ZWaveNodeInitStageAdvancer] - NODE 36: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@10aca24
20:37:39.984 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 51 01 AA
20:37:39.993 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=AssignSucReturnRoute[81], type=Response[1], dest=255, callback=0, payload=01
20:37:40.003 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=AssignSucReturnRoute[81], type=Response[1], dest=255, callback=0, payload=01
20:37:40.008 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null
20:37:40.013 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 0
20:37:40.017 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: null
20:37:40.022 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
20:37:40.026 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
20:37:48.172 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 36: sendTransaction org.openhab.binding.zwave.internal.protocol.ZWaveSerialPayload@1829023
20:37:48.178 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 36: Adding to device queue
20:37:48.186 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 36: Added 365 to queue - size 1
20:37:48.191 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
20:37:48.197 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 51 24 40 CF
20:37:48.205 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 51 24 40 CF
20:37:48.212 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT
20:37:48.218 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 365: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 64
20:37:49.359 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
20:37:49.364 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
20:37:49.374 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
20:37:49.375 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 51 00 AB
20:37:49.379 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 365: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 64
20:37:49.390 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=AssignSucReturnRoute[81], type=Response[1], dest=255, callback=0, payload=00
20:37:49.392 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg: ACK
20:37:49.402 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=AssignSucReturnRoute[81], type=Response[1], dest=255, callback=0, payload=00
20:37:49.408 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 365: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 64
20:37:49.413 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
20:37:49.419 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 365: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 64
20:37:49.425 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=AssignSucReturnRoute[81], type=Response[1], dest=255, callback=0, payload=00
20:37:49.432 [DEBUG] [sage.AssignSucReturnRouteMessageClass] - NODE 36: Got AssignSucReturnRoute response.
20:37:49.430 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 05 00 51 40 00 EB
20:37:49.437 [DEBUG] [sage.AssignSucReturnRouteMessageClass] - NODE 36: AssignSucReturnRoute command failed.
20:37:49.445 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 36: Got an event from Z-Wave network: ZWaveNetworkEvent
20:37:49.445 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=AssignSucReturnRoute[81], type=Request[0], dest=0, callback=64, payload=40 00
20:37:49.449 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 365: Transaction CANCELLED
20:37:49.458 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Holdoff Timer started...
20:37:49.469 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 36: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
20:37:49.476 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 36: CANCEL while sending message. Requeueing - 2 attempts left!
20:37:49.481 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 365: Transaction RESET with 2 retries remaining.
20:37:49.488 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 36: Adding to device queue
20:37:49.493 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 36: Added 365 to queue - size 1
20:37:49.499 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff true.
20:37:49.507 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 36: TID 365: Transaction not completed
20:37:49.513 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=AssignSucReturnRoute[81], type=Request[0], dest=0, callback=64, payload=40 00
20:37:49.519 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null
20:37:49.528 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 0
20:37:49.533 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: null
20:37:49.542 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=AssignSucReturnRoute[81], type=Request[0], dest=0, callback=64, payload=40 00
20:37:49.547 [DEBUG] [sage.AssignSucReturnRouteMessageClass] - NODE ?: transaction not correlated for AssignSucReturnRouteMessageClass
20:37:49.551 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
20:37:49.556 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff true.
20:37:49.719 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
20:37:49.725 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 51 24 41 CE
20:37:49.729 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 51 24 41 CE
20:37:49.734 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT
20:37:49.735 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
20:37:49.739 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 365: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 65
20:37:49.741 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
20:37:49.746 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
20:37:49.751 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 365: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 65
20:37:49.756 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg: ACK
20:37:49.760 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
20:37:49.764 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
20:37:51.744 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - NODE 36: TID 365: Timeout at state WAIT_RESPONSE. 2 retries remaining.
20:37:51.749 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - TID 365: Transaction is current transaction, so clearing!!!!!
20:37:51.754 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 365: Transaction CANCELLED
20:37:51.759 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 36: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
20:37:51.764 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 36: notifyTransactionResponse TID:365 CANCELLED
20:37:51.769 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 365: Transaction event listener: DONE: CANCELLED -> WAIT_RESPONSE
20:37:51.769 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
20:37:51.774 [DEBUG] [ialization.ZWaveNodeInitStageAdvancer] - NODE 36: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@12c10c5
20:37:52.010 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 51 01 AA
20:37:52.015 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=AssignSucReturnRoute[81], type=Response[1], dest=255, callback=0, payload=01
20:37:52.024 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=AssignSucReturnRoute[81], type=Response[1], dest=255, callback=0, payload=01
20:37:52.028 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null
20:37:52.033 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 0
20:37:52.038 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: null
20:37:52.045 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
20:37:52.049 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
20:38:01.443 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 05 00 51 41 00 EA
20:38:01.452 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=AssignSucReturnRoute[81], type=Request[0], dest=0, callback=65, payload=41 00
20:38:01.457 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=AssignSucReturnRoute[81], type=Request[0], dest=0, callback=65, payload=41 00
20:38:01.462 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null
20:38:01.466 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 0
20:38:01.471 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: null
20:38:01.475 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=AssignSucReturnRoute[81], type=Request[0], dest=0, callback=65, payload=41 00
20:38:01.480 [DEBUG] [sage.AssignSucReturnRouteMessageClass] - NODE ?: transaction not correlated for AssignSucReturnRouteMessageClass
20:38:01.485 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
20:38:01.489 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
20:38:09.185 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 36: sendTransaction org.openhab.binding.zwave.internal.protocol.ZWaveSerialPayload@1829023
20:38:09.194 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 36: Adding to device queue
20:38:09.200 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 36: Added 366 to queue - size 1
20:38:09.209 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
20:38:09.215 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 51 24 42 CD
20:38:09.223 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 51 24 42 CD
20:38:09.232 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
20:38:09.237 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
20:38:09.237 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT
20:38:09.242 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
20:38:09.251 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null
20:38:09.249 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 366: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 66
20:38:09.256 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg: ACK
20:38:09.262 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
20:38:09.267 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
20:38:11.262 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - NODE 36: TID 366: Timeout at state WAIT_RESPONSE. 3 retries remaining.
20:38:11.269 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - TID 366: Transaction is current transaction, so clearing!!!!!
20:38:11.276 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 366: Transaction CANCELLED
20:38:11.280 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 36: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
20:38:11.285 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 36: notifyTransactionResponse TID:366 CANCELLED
20:38:11.291 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
20:38:11.294 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 366: Transaction event listener: DONE: CANCELLED -> WAIT_RESPONSE
20:38:11.303 [DEBUG] [ialization.ZWaveNodeInitStageAdvancer] - NODE 36: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@16c5429
20:38:11.506 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 51 01 AA
20:38:11.513 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=AssignSucReturnRoute[81], type=Response[1], dest=255, callback=0, payload=01
20:38:11.518 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=AssignSucReturnRoute[81], type=Response[1], dest=255, callback=0, payload=01

Then a different node came into the log (I did not copy that part). Later on this was the last message:

20:38:46.332 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 36: sendTransaction org.openhab.binding.zwave.internal.protocol.ZWaveSerialPayload@1829023
20:38:46.338 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 36: Adding to device queue
20:38:46.342 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 36: Added 368 to queue - size 1
20:38:46.348 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
20:38:46.353 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 51 24 46 C9
20:38:46.359 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 51 24 46 C9
20:38:46.365 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT
20:38:46.368 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
20:38:46.370 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 368: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 70
20:38:46.373 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
20:38:46.379 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
20:38:46.385 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 368: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 70
20:38:46.390 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg: ACK
20:38:46.395 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
20:38:46.399 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
20:38:48.378 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - NODE 36: TID 368: Timeout at state WAIT_RESPONSE. 3 retries remaining.
20:38:48.386 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - TID 368: Transaction is current transaction, so clearing!!!!!
20:38:48.391 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 368: Transaction CANCELLED
20:38:48.399 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 36: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
20:38:48.404 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 36: notifyTransactionResponse TID:368 CANCELLED
20:38:48.410 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
20:38:48.411 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 368: Transaction event listener: DONE: CANCELLED -> WAIT_RESPONSE
20:38:48.421 [DEBUG] [ialization.ZWaveNodeInitStageAdvancer] - NODE 36: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@10f2f6d

It doesn’t tell me anything. Does it do to you?

[apella12 (Bob Eckhoff)]

There is a log viewer here that simplifies the debugging log. I can see that the Heal is not finishing.

What is the make and model of your controller? Is it a 700 chip?

[Bernhelm (Bernd Müller)]

Thank you for that hint to the log viewer. This is really helpful and my first time using the z-wave logging.

For the healing process it unfortunately don’t help me understanding what is going wrong.

But I now did a test bringing some results:
Test scenario was: Room with the device is dark, I trigger the motion sensor, light is going on, I wait 2 min for letting it go off. I turn the room light on to check and compare the behaviour and to trigger the luminance sensor. I wait a bit, and then command the dimmer of the MotionSwitch to 100%. Nothing happens at the device. Then I turn it back to 0% and then turn the room light off.

This is leading to the following log, sortet by the log viewer:

18:12:26.278	36	
COMMAND RECEIVED zwave:device:db39870438:node36:switch_dimmer 79 [PercentType]
18:12:26.299	36	
TX REQ SendData 149 SWITCH_MULTILEVEL_SET 79%  ACK AUTO_ROUTE EXPLORE
18:12:26.309	36	
RX RES SendData 149 ACCEPTED BY CONTROLLER 0 /128
18:12:26.372	36	
RX REQ SendData 149 ACK RECEIVED from device in 73ms 0 /128
18:12:26.729	36	
COMMAND RECEIVED zwave:device:db39870438:node36:switch_dimmer 100 [PercentType]
18:12:26.746	36	
TX REQ SendData 150 SWITCH_MULTILEVEL_SET 99%  ACK AUTO_ROUTE EXPLORE
18:12:26.757	36	
RX RES SendData 150 ACCEPTED BY CONTROLLER 0 /128
18:12:26.820	36	
RX REQ SendData 150 ACK RECEIVED from device in 74ms 0 /128
18:12:30.224	36	
COMMAND RECEIVED zwave:device:db39870438:node36:switch_dimmer 21 [PercentType]
18:12:30.238	36	
TX REQ SendData 151 SWITCH_MULTILEVEL_SET 21%  ACK AUTO_ROUTE EXPLORE
18:12:30.249	36	
RX RES SendData 151 ACCEPTED BY CONTROLLER 0 /128
18:12:30.312	36	
RX REQ SendData 151 ACK RECEIVED from device in 74ms 0 /128
18:12:30.435	36	
COMMAND RECEIVED zwave:device:db39870438:node36:switch_dimmer 16 [PercentType]
18:12:30.452	36	
TX REQ SendData 152 SWITCH_MULTILEVEL_SET 16%  ACK AUTO_ROUTE EXPLORE
18:12:30.463	36	
RX RES SendData 152 ACCEPTED BY CONTROLLER 0 /128
18:12:30.526	36	
RX REQ SendData 152 ACK RECEIVED from device in 74ms 0 /128
18:12:30.639	36	
COMMAND RECEIVED zwave:device:db39870438:node36:switch_dimmer 0 [PercentType]
18:12:30.665	36	
TX REQ SendData 153 SWITCH_MULTILEVEL_SET 0%  ACK AUTO_ROUTE EXPLORE
18:12:30.677	36	
RX RES SendData 153 ACCEPTED BY CONTROLLER 0 /128
18:12:30.740	36	

The dimmer signal is used in the debug, but set back to zero in some seconds, so this could be a reason why I don't see it in openhab. But the light stays on for 120s, so is somehow independent from the signal.
And no other channel is doing anything, so no other switch is changing or anything.

RX REQ SendData 153 ACK RECEIVED from device in 75ms 0 /128
18:16:50.989	30	
RX REQ ApplicationCommandHandler SWITCH_MULTILEVEL_REPORT 99% 0 /128
18:16:51.011	30	
STATE UPDATE zwave:device:db39870438:node30:switch_dimmer 100 [PercentType]
18:16:51.800	30	
RX REQ ApplicationCommandHandler SWITCH_MULTILEVEL_REPORT 99% 0 /128
18:16:51.819	30	
STATE UPDATE zwave:device:db39870438:node30:switch_dimmer 100 [PercentType]
18:16:56.005	30	
RX REQ ApplicationCommandHandler METER_REPORT ELECTRIC_W 0 /128
18:16:56.028	30	
STATE UPDATE zwave:device:db39870438:node30:meter_watts 27 [DecimalType]

Node 30 is doing what it should do (switch on the light)
No reaction in the log from the luminance sensor.

Then I commanded 100% dimmer in openhab:
18:17:30.855	36	
COMMAND RECEIVED zwave:device:db39870438:node36:switch_dimmer 61 [PercentType]
18:17:30.872	36	
TX REQ SendData 160 SWITCH_MULTILEVEL_SET 61%  ACK AUTO_ROUTE EXPLORE
18:17:30.886	36	
RX RES SendData 160 ACCEPTED BY CONTROLLER 0 /128
18:17:30.946	36	
RX REQ SendData 160 ACK RECEIVED from device in 74ms 0 /128
18:17:31.303	36	
COMMAND RECEIVED zwave:device:db39870438:node36:switch_dimmer 100 [PercentType]
18:17:31.315	36	
TX REQ SendData 161 SWITCH_MULTILEVEL_SET 99%  ACK AUTO_ROUTE EXPLORE
18:17:31.327	36	
RX RES SendData 161 ACCEPTED BY CONTROLLER 0 /128
18:17:31.388	36	
RX REQ SendData 161 ACK RECEIVED from device in 73ms 0 /128
18:17:55.243	36	
COMMAND RECEIVED zwave:device:db39870438:node36:switch_dimmer 65 [PercentType]
18:17:55.256	36	
TX REQ SendData 162 SWITCH_MULTILEVEL_SET 65%  ACK AUTO_ROUTE EXPLORE
18:17:55.268	36	
RX RES SendData 162 ACCEPTED BY CONTROLLER 0 /128
18:17:55.330	36	
RX REQ SendData 162 ACK RECEIVED from device in 74ms 0 /128
18:17:55.443	36	
COMMAND RECEIVED zwave:device:db39870438:node36:switch_dimmer 6 [PercentType]
18:17:55.458	36	
TX REQ SendData 163 SWITCH_MULTILEVEL_SET 6%  ACK AUTO_ROUTE EXPLORE
18:17:55.471	36	
RX RES SendData 163 ACCEPTED BY CONTROLLER 0 /128
18:17:55.532	36	
RX REQ SendData 163 ACK RECEIVED from device in 74ms 0 /128
18:17:55.652	36	
COMMAND RECEIVED zwave:device:db39870438:node36:switch_dimmer 0 [PercentType]
18:17:55.665	36	
TX REQ SendData 164 SWITCH_MULTILEVEL_SET 0%  ACK AUTO_ROUTE EXPLORE
18:17:55.687	36	
RX RES SendData 164 ACCEPTED BY CONTROLLER 0 /128
18:17:55.738	36	

Recieving / Transcieving side is turned correctly, but no reaction on the device.

RX REQ SendData 164 ACK RECEIVED from device in 73ms 0 /128
18:17:59.868	30	
RX REQ ApplicationCommandHandler SWITCH_MULTILEVEL_REPORT 0% 0 /128
18:17:59.892	30	
STATE UPDATE zwave:device:db39870438:node30:switch_dimmer 0 [PercentType]
18:18:05.503	30	
RX REQ ApplicationCommandHandler METER_REPORT ELECTRIC_W 0 /128
18:18:05.522	30	
STATE UPDATE zwave:device:db39870438:node30:meter_watts 0 [DecimalType]

Light in the room is turned off to end the test.

My outcomes and thoughts: I fear that the device itself is not working correctly and that it is not an issue in my configuration (and probably also not in the z-wave binding). Messages seems to be recieved and transcieved correctly, but at walking there it should do more than only turning the dimmer signal to 100% for more than some seconds and the signal should reflect the command from the controller also in turning the light on. Also no other channels are used (no luminance sensor, no burglar alarm).

To the device: It is quite newly developed, as far as I saw in 2021. But I don’t find in the documentation anything about which z-wave controller is used.

[apella12 (Bob Eckhoff)]

It is hard to read the log viewer in the manner presented and follow what you did, but a few comments

1. Can you post the raw file without any filtering from your testing. I want to check something
2. Ok to forget the “Heal”, that was a suggestion when you reported no messages in the debug log. It appears the device is in communication with the controller reasonably fast (74ms).
3. The burglar is not going to change based on the discussion between Chris and BK. Once there is a new binding the channel will show as motion and you will get messages on that channel. That could be available by the end of the week or so.
4. My controller question was about the Zstick in the USB port of your server (Rpi??). Did it come in a box with a manual?
5. Overall I think the device works, but you need to look closely at the parameters (1-19) (check the Advanced box) on the UI page for insights. Did you change param 2 from 2000 down to maybe 50. Also dimmers take time to reach the setpoint (unlike switches). What about using the LED switch binary? What is your command poll on the device UI page (if 1500 raise to 5000). From the DB it seems like there are a lot of options, you just may need to fiddle at bit

[Bernhelm (Bernd Müller)]

Hi Bob,
thanks again!
I use the Aeotec Z-Wave Stick Gen5 on a raspberry pi
AEOTEC Z-Stick GEN5 USB User Guide (manuals.plus)
So I guess it is a 500 chip. Could that lead to issues with new 700 devices?

Here is the raw file from the test plus a bit of time afterwards.
openhab_test.log (153.2 KB)

I will have a look at the parameters later that day and come back to you.

[apella12 (Bob Eckhoff)]

@Bernhelm

So I guess it is a 500 chip. Could that lead to issues with new 700 devices?

This is fine. Thanks for confirming.

See if the light works better with this channel linked to a new item “Switch 3”

@apella12

using the LED switch binary

[Bernhelm (Bernd Müller)]

Hi again,
I now checked the parameters on the UI page. And I have to admit that I am overwhelmed with it. Normally I leave all parameters I don’t understand on their default value, but this time it could be that it is an issue in the standard parameters I run in. Can I kindly ask for some help here?

This are the parameters and the explanations:

Configuration Parameters
1: TIME
120 (standard was 180)
Duration of light after motion detection [s]
Overview


2: LIGHT
50 (standard was 100)
Light threshold [lx]
Overview
Light threshold: 2 - 2000, 0 lx

0 - run Learn ambient light sequence. 2000 - is used as daylight ( always night mode ).

5: SENSITIVITY
100
Motion Radar Sensitivity [%]
6: Brightness measuring interval
0
Brightness measuring interval [min]
Overview
Interval for measuring ambient light when lamp is on ( lamp switches off briefly and measures ): 5 - 120 min. 0 = function is off.

8: GLOBAL_LIGHT
5
Use external Ambient Light Value
Overview
When GLOBAL_LIGHT mode is ON - device overrides its own light sensor values and uses Light report values from any Z-Wave light sensor instead - this has to be configured appropriately to send light automatically. If the last remote light level value is older than 30 minutes, the internal light value is used again until the next external value is received.

9: SLAVE_MODE
2
PENDING
Disable local control
Overview
Bit Field:

Bit	7	6	5	4	3	2	1	0
Function	-	-	-	-	-	"Stupid" mode	Central unit
checking in
slave mode	Slave mode

"Stupid" mode ( bit 2 = 1 ):
- has higher priority then slave mode.
- LED is permanently on ( for simple power wall switch controlling ).


Slave mode ( bit 0 = 1 ):
- only if included in Z-Wave network
- useful for controlling via third-party sensor
- LED is directly controlled via Z-Wave, internal sensors are not used for controlling it


Central unit checking ( bit 1 = 1 ): ( useful especially for controlling via gateway )
When slave bit is 0:
- device signalises fail of lifeline connection ( if this bit is zero, fail of lifeline connection
is not signalised )
When slave bit is 1:
- device checks presence of Z-Wave device in lifeline group ( gateway ). If it is not
present for 2 minutes ( testing repeatedly every 30 seconds ) device switches
to normal mode in the same way as after the end of local disabled mode
( ON_BEHAVIOUR )
- the device checks every 1 minute for recovery of Lifeline connection.
- if no lifeline specified - it works in normal mode


Be careful with this option, device stops using its own motion sensor in Slave
and Stupid mode.

10: OFF_BEHAVIOUR
10
Off behaviour ( timeout )
Overview
Behaviour after BASIC OFF ( and similar commands ).
If a transition ( even with zero change ) with a non-default duration is to be processed,
the transition cannot be interrupted by any motion event in any case.

0 LED is switched off and remains so until any new motion event ( local or remote ) is received.
1 - 209 LED is switched off and remains so until after a specified timeout once a new motion event ( local or remote ) is received.
Timeout:
1..100 - 1 second ( 1 ) to 100 seconds ( 100 ) in 1-second resolution
101..200 - 1 minute ( 101 ) to 100 minutes ( 200 ) 1-minute resolution
201..209 - 1 hour ( 201 ) to 9 hours ( 209 ) in 1-hour resolution
210 - 254 Reserved
255 LED is switched off for TIME ( cfg 1 ). It does not wait for a motion event and works normally via current motion evaluation.

11: ON_BEHAVIOUR
255
On behaviour ( timeout )
Overview
Behaviour after BASIC ON ( and similar commands ).
If a transition ( even with zero change ) with a non-default duration is to be processed, the transition cannot be interrupted by any motion event in any case.

0 LED is switched on and remains so until any new motion event ( local or remote ) is received. It then works normally via current motion evaluation.
Notice - during the day, this mode cannot be ended remotely due to motion events not being transmitted - only via local motion sensor if enabled.
1 - 209 LED is switched off and remains so until after a specified timeout
once a new motion event ( local or remote ) is received.
Timeout:
1..100 - 1 second ( 1 ) to 100 seconds ( 100 ) in 1-second resolution
101..200 - 1 minute ( 101 ) to 100 minutes ( 200 ) 1-minute resolution
201..209 - 1 hour ( 201 ) to 9 hours ( 209 ) in 1-hour resolution
Notice - during the day, this mode cannot be ended remotely due to motion
events not being transmitted - only via local motion sensor if enabled.
210 - 254 Reserved
255 LED is switched off for TIME ( cfg 1 ). It does not wait for a motion
event and works normally via current motion evaluation.

12: ON_TIME_OVER
204
On behaviour time over ( timeout )
Overview
Time limit to stop waiting for motion after timeout of ON_BEHAVIOUR or OFF_ON_BEHAVIOUR ( 0-209 ) to prevent staying ON forever when there is no motion.

0 No additional waiting for motion.
1..100 - 1 second ( 1 ) to 100 seconds ( 100 ) in 1-second resolution
101..200 - 1 minute ( 101 ) to 100 minutes ( 200 ) in 1-minute resolution
201..209 - 1 hour ( 201 ) to 9 hours ( 209 ) in 1-hour resolution
210 - 254 Reserved
255 Never stop waiting for motion.

13: ON_OFF_BEHAVIOUR
204
Sequence On-Off behaviour ( timeout )
Overview
Behaviour after a rapid sequence of BASIC ON and BASIC OFF commands.
The intention is to use a much longer timeout value than the time after a single ON command which should then be followed by a short timeout value.
The behaviour is the same as for parameter 10 ( OFF_LOCAL_DISABLE ) except: 255 - device ignores ON - OFF sequence and uses OFF behaviour

14: OFF_ON_BEHAVIOUR
204
Sequence Off-On behaviour ( timeout )
Overview
Behaviour after a rapid sequence of BASIC OFF and BASIC ON commands.
The intention is to use a much longer timeout value than the time after a single OFF command which should then be followed by a short timeout value.
The behaviour is the same as for parameter 11 ( ON_LOCAL_DISABLE ) except: 255 - device ignores OFF - ON sequence and uses ON behaviour.

15: SEQUENCE_TIME
10
Sequence Off-On behaviour ( timeout )
Overview
Time in [100 milliseconds] of maximum delay between BASIC ON and BASIC OFF ( and vice versa ) to consider this as a sequence. It is typically 1 second, but can be exceptionally longer due to retransmissions and overload - in this case, a longer interval can be allowed ( up to 5 seconds ).

16: MOTION_DISABLE
0
Motion Off behaviour ( timeout )
Overview
Motion disable timeout after BASIC SET to motion endpoint when the internal motion sensor is not used for evaluating the behaviour of the LED and groups 2 and 3. Events are, however, still transmitted to the Lifeline, and the device can be controlled via remote motion sensors.


0 BASIC SET to Motion sensor endpoint ignored, BASIC to root is mapped to LED endpoint, motion sensor still enabled
1 - 209 Internal motion sensor is disabled for specified timeout after BASIC SET 0x00 to Motion sensor endpoint. BASIC to root is mapped to Motion sensor endpoint. ( SPIR )
Timeout:
1..100 - 1 second ( 1 ) to 100 seconds ( 100 ) in 1-second resolution
101..200 - 1 minute ( 101 ) to 100 minutes ( 200 ) in 1-minute resolution
201..209 - 1 hour ( 201 ) to 9 hours ( 209 ) in 1-hour resolution
210 - 254 Reserved
255 BASIC SET to Motion sensor endpoint ignored, BASIC to root is mapped to LED endpoint, motion sensor still disabled

17: BUTTON_BEHAVIOUR
8
Toggle button behaviour
Overview
Bit Field:

Bit	7	6	5	4	3	2	1	0
Function	-	-	-	Dim
disable	Network
behaviour	Standalone
behaviour	Scene
switch	Dim
increase

Dim increase ( bit 0 ):
- long press of button does dim decreasing ( 0 = default )
- long press of button does dim increasing ( 1 )

Scene switch ( bit 1 ):
- short press of button does toggle - on/off ( 0 = default )
- short press of button does scene switch ( 1 )

Standalone behaviour ( bit 2 ):
- using "legacy" sensor deactivity after button press ( 0 = default )
- using "cfg 10-14" sensor deactivity after button press ( 1 )

Network behaviour ( bit 3 ):
- using "legacy" sensor deactivity after button press ( 0 )
- using "cfg 10-14" sensor deactivity after button press ( 1 = default )

Dim disable ( bit 4 ):
- dim events ( long press ) are used ( 0 = default )
- dim events ( long press ) are ignored ( 1 )

"legacy" sensor deactivity behaviour does this:
- Pressing push button when LED is OFF: LED will switch on as long as there is
movement and the delay time runs of
- Pressing push button when LED is ON: LED will switch off as long as there is
movement ans the delay time runs of ( invers logic ).

"cfg 10-14" sensor deactivity behaviour uses behaviour specified in parameters
10-14 ( the same behaviour as pressing of external switch using BASIC ON/OFF
functions )

18: BUTTON_SCENES
1
Toggle button Scene 1-4
Overview
Parameters 18 specifies scene numbers ( from 1 to number ) to be switched by
toggle button. 0 menas - do not useany.

19: LEGACY_INACTIVITY_TIME
103
Toggle button inactivity time in "legacy" mode ( timeout )
Overview
In "legacy" button mode function this timeout is used to cancel motion sensor inactivity mode after motion end ( both for on and off state of LED ).

Timeout:
1..100 - 1 second ( 1 ) to 100 seconds ( 100 ) in 1-second resolution
101..200 - 1 minute ( 101 ) to 100 minutes ( 200 ) in 1-minute resolution
201..209 - 1 hour ( 201 ) to 9 hours ( 209 ) in 1-hour resolution

I assume that my errors could come from the on_off behaviour and from some states preventing things from doing. But to be honest, I don’t understand them properly.

Some other points I found out: changing parameters is working. With 50 on parameter 2, the motion switch don’t turn on when the other light in the room is on. At 100, and with luminance measured between 60 and 98, it went on even with other light turned on.
Also the luminance sensor is sometimes sending values I can see in openhab. It seems to be when I change some other values in openhab, but I am not sure of this coincidence.

I link a second item to the dimmer channel which was a switch item. Changing that switch came to the log but did not turn the light on, too, and it was not possible to see the item changing when the LED turned on after a motion event, too.

I changed the command poll period to 5000, but nothing changed.

And at the moment I have all 5 lines, I don’t know what lead to that change.