OH3 ZWave "Unknown Device" - devices were working with 1.8ish OH

scotthraban · January 20, 2021, 4:57am

I have been running openHAB 1.7ish for a long time, saw that OH3 had been released and decided the time was right. I did a fresh OH3 install into a docker container, and rebuilt from scratch, using the UI for everything except a few persistence configs.

I have about 25 ZWave nodes, all Linear/GoControl, some switches and outlets, various generations, a few of which I had submitted to Chris Jackson’s database and rebuilt the binding back when I was on 1.7ish. All but 2 of them came up fine in OH3, were recognized, and I was able to add them just fine. I believe that I have others of the same switch working, but not absolutely positive that I have any that are of the same generation.

Full transparency, one of the ones that did not come up might have been dead - not a switch that I use often, and seems to be acting strangely. The other one is nearly brand new, as I literally just replaced one that went south.

The switch is still paired to a secondary switch that can turn it on/off remotely, so I have not dared to completely remove it from the controller and add it back, since I might not be able to get those linked again if openHAB cannot recognize (and therefore configure) the switch.

The main WARN message is:

2021-01-17 16:54:38.419 [WARN ] [wave.discovery.ZWaveDiscoveryService] - NODE 28: Device discovery could not resolve to a thingType! Manufacturer data not known.

After putting the logs in debug, I filtered out the logs that call out the specific node, if this filters out logs that might be helpful, let me know and I can include more, but this is the part that seems to be interesting, speaking naively:

2021-01-19 20:17:32.202 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 28: Polling...
2021-01-19 20:17:32.202 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 28: Polling deferred until initialisation complete
2021-01-19 20:17:41.878 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 28: sendTransaction org.openhab.binding.zwave.internal.protocol.ZWaveSerialPayload@71f5f87a
2021-01-19 20:17:41.879 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 28: Adding to device queue
2021-01-19 20:17:41.879 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 28: Added 55379 to queue - size 1
2021-01-19 20:17:41.887 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 28: TID 55379: Transaction not completed
2021-01-19 20:18:02.202 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 28: Polling...
2021-01-19 20:18:02.202 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 28: Polling deferred until initialisation complete
2021-01-19 20:18:06.887 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 28: TID 55379: Timeout at state WAIT_DATA. 3 retries remaining.
2021-01-19 20:18:06.888 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 28: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2021-01-19 20:18:06.888 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 28: notifyTransactionResponse TID:55379 CANCELLED
2021-01-19 20:18:06.889 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 28: Node Init response (4) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@5095cb8
2021-01-19 20:18:06.889 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 28: No data from device, but it was ACK'd. Possibly not supported? (Try 4)
2021-01-19 20:18:06.889 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 28: Node Init transaction retries exceeded
2021-01-19 20:18:06.889 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 28: Node advancer - advancing to SECURITY_REPORT
2021-01-19 20:18:06.889 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 28: Got an event from Z-Wave network: ZWaveInitializationStateEvent
2021-01-19 20:18:06.890 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 28: SECURE command class not supported
2021-01-19 20:18:06.890 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 28: Node advancer - advancing to MANUFACTURER
2021-01-19 20:18:06.890 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 28: Got an event from Z-Wave network: ZWaveInitializationStateEvent
2021-01-19 20:18:06.890 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 28: Node advancer - advancing to APP_VERSION
2021-01-19 20:18:06.890 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 28: Got an event from Z-Wave network: ZWaveInitializationStateEvent
2021-01-19 20:18:06.890 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 28: Node advancer: APP_VERSION - VERSION not supported
2021-01-19 20:18:06.903 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 28: Node advancer - advancing to DISCOVERY_COMPLETE
2021-01-19 20:18:06.903 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 28: Device discovery completed
2021-01-19 20:18:06.904 [WARN ] [wave.discovery.ZWaveDiscoveryService] - NODE 28: Device discovery could not resolve to a thingType! Manufacturer data not known.
2021-01-19 20:18:06.905 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 28: Got an event from Z-Wave network: ZWaveInitializationStateEvent
2021-01-19 20:18:06.905 [DEBUG] [l.initialization.ZWaveNodeSerializer] - NODE 28: Serialise aborted as static stages not complete
2021-01-19 20:18:06.905 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 28: Node advancer - advancing to ENDPOINTS
2021-01-19 20:18:06.906 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 28: Got an event from Z-Wave network: ZWaveInitializationStateEvent
2021-01-19 20:18:06.906 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 28: Node advancer: ENDPOINTS - MultiInstance not supported.
2021-01-19 20:18:06.906 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 28: Node advancer - advancing to UPDATE_DATABASE
2021-01-19 20:18:06.906 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 28: Got an event from Z-Wave network: ZWaveInitializationStateEvent
2021-01-19 20:18:06.906 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 28: Node advancer: UPDATE_DATABASE
2021-01-19 20:18:06.908 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 28: Node advancer: UPDATE_DATABASE - thing is null!
2021-01-19 20:18:06.908 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 28: Node advancer - advancing to STATIC_VALUES
2021-01-19 20:18:06.908 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 28: Got an event from Z-Wave network: ZWaveInitializationStateEvent
2021-01-19 20:18:06.908 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 28: Node advancer: STATIC_VALUES - checking COMMAND_CLASS_NO_OPERATION
2021-01-19 20:18:06.908 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 28: Node advancer: STATIC_VALUES - checking COMMAND_CLASS_BASIC
2021-01-19 20:18:06.908 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 28: Node advancer - advancing to ASSOCIATIONS
2021-01-19 20:18:06.909 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 28: Got an event from Z-Wave network: ZWaveInitializationStateEvent
2021-01-19 20:18:06.909 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 28: Node advancer - advancing to SET_WAKEUP
2021-01-19 20:18:06.909 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 28: Got an event from Z-Wave network: ZWaveInitializationStateEvent
2021-01-19 20:18:06.909 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 28: Node advancer - advancing to SET_ASSOCIATION
2021-01-19 20:18:06.909 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 28: Got an event from Z-Wave network: ZWaveInitializationStateEvent
2021-01-19 20:18:06.909 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 28: Node advancer: SET_ASSOCIATION - ASSOCIATION class not supported
2021-01-19 20:18:06.909 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 28: Node advancer - advancing to SET_LIFELINE
2021-01-19 20:18:06.909 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 28: Got an event from Z-Wave network: ZWaveInitializationStateEvent
2021-01-19 20:18:06.909 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 28: Node advancer: SET_LIFELINE - ASSOCIATION class not supported
2021-01-19 20:18:06.910 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 28: Node advancer - advancing to GET_CONFIGURATION
2021-01-19 20:18:06.910 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 28: Got an event from Z-Wave network: ZWaveInitializationStateEvent
2021-01-19 20:18:06.910 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 28: Node advancer: GET_CONFIGURATION - CONFIGURATION class not supported
2021-01-19 20:18:06.910 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 28: Node advancer - advancing to STATIC_END
2021-01-19 20:18:06.910 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 28: Got an event from Z-Wave network: ZWaveInitializationStateEvent
2021-01-19 20:18:06.910 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 28: Updating node properties.
2021-01-19 20:18:06.910 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 28: Updating node properties. MAN=2147483647
2021-01-19 20:18:06.910 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 28: Properties synchronised
2021-01-19 20:18:06.911 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 28: MANUFACTURER not set
2021-01-19 20:18:06.911 [DEBUG] [l.initialization.ZWaveNodeSerializer] - NODE 28: Serialise aborted as static stages not complete
2021-01-19 20:18:06.911 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 28: Node advancer - advancing to DYNAMIC_VALUES
2021-01-19 20:18:06.912 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 28: Got an event from Z-Wave network: ZWaveInitializationStateEvent
2021-01-19 20:18:06.912 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 28: Node advancer: DYNAMIC_VALUES - checking COMMAND_CLASS_NO_OPERATION
2021-01-19 20:18:06.912 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 28: Node advancer: DYNAMIC_VALUES - checking COMMAND_CLASS_BASIC
2021-01-19 20:18:06.912 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 28: Node advancer: Initialisation complete!
2021-01-19 20:18:06.912 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 28: Node advancer - advancing to DYNAMIC_END
2021-01-19 20:18:06.912 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 28: Got an event from Z-Wave network: ZWaveInitializationStateEvent
2021-01-19 20:18:06.912 [DEBUG] [l.initialization.ZWaveNodeSerializer] - NODE 28: Serializing to file /openhab/userdata/zwave/network_f21a172e__node_28.xml
2021-01-19 20:18:06.913 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 28: Node advancer - advancing to DONE
2021-01-19 20:18:06.913 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 28: Got an event from Z-Wave network: ZWaveInitializationStateEvent
2021-01-19 20:18:06.913 [DEBUG] [l.initialization.ZWaveNodeSerializer] - NODE 28: Serializing to file /openhab/userdata/zwave/network_f21a172e__node_28.xml
2021-01-19 20:18:32.202 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 28: Polling...
2021-01-19 20:19:02.202 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 28: Polling...

The XML file is as follows:

<node>
  <homeId>0xf21a172e</homeId>
  <nodeId>28</nodeId>
  <version>4</version>
  <manufacturer>0x7fffffff</manufacturer>
  <deviceId>0x7fffffff</deviceId>
  <deviceType>0x7fffffff</deviceType>
  <listening>true</listening>
  <frequentlyListening>false</frequentlyListening>
  <routing>true</routing>
  <security>false</security>
  <beaming>true</beaming>
  <maxBaudRate>40000</maxBaudRate>
  <sleepDelay>1000</sleepDelay>
  <associationGroups class="concurrent-hash-map"/>
  <endpoints class="concurrent-hash-map">
    <entry>
      <int>0</int>
      <endPoint>
        <deviceClass>
          <basicDeviceClass>BASIC_TYPE_ROUTING_SLAVE</basicDeviceClass>
          <genericDeviceClass>GENERIC_TYPE_SWITCH_BINARY</genericDeviceClass>
          <specificDeviceClass>SPECIFIC_TYPE_POWER_SWITCH_BINARY</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>0</version>
              <instances>1</instances>
              <control>false</control>
              <versionSupported>0</versionSupported>
              <isGetSupported>true</isGetSupported>
            </COMMAND__CLASS__BASIC>
          </entry>
        </supportedCommandClasses>
      </endPoint>
    </entry>
  </endpoints>
  <nodeNeighbors/>
  <lastReceived>2021-01-20 04:15:44.804 UTC</lastReceived>
</node>

I have tried reinitializing the switch, healing the switch, deleting the XML file, stopping and starting the binding, unplugging and plugging back in the USB stick, all to no avail

Any help would be greatly appreciated!

scotthraban · January 20, 2021, 5:01am

In case it is helpful, a snippet of the XML from a switch that IS working that I purchased just a few months back, and I believe to be the same generation of this particular wall switch:

  <manufacturer>0x14f</manufacturer>
  <deviceId>0x3535</deviceId>
  <deviceType>0x5753</deviceType>