[SOLVED] Z-Wave 2.5.2 - Device discovery could not resolve to a thingType! Manufacturer data not known

hschulze86 · March 7, 2020, 6:35pm

Hi there,

recently rebuilt my smart home system with a fresh Pi 4+ and 2.5.2.
I have a fibaro roller shutter which worked kind of with restarting sometimes the 2.4 snapshot binding in the karaf konsole.

In this case the devices are recognized but as unknown type? Why is that? There are obviously not unknown in the database. Am I missing something here?

Help is appreciated Thanks in advance

==> /var/log/openhab2/openhab.log <==

2020-03-07 18:54:32.321 [WARN ] [wave.discovery.ZWaveDiscoveryService] - NODE 4: Device discovery could not resolve to a thingType! Manufacturer data not known.

2020-03-07 18:54:32.332 [WARN ] [wave.discovery.ZWaveDiscoveryService] - NODE 5: Device discovery could not resolve to a thingType! Manufacturer data not known.

==> /var/log/openhab2/events.log <==

2020-03-07 18:54:34.101 [vent.ItemStateChangedEvent] - ntp_ntp_local_dateTime changed from 2020-03-07T18:53:34.094+0100 to 2020-03-07T18:54:34.096+0100

2020-03-07 18:54:34.103 [vent.ItemStateChangedEvent] - ntp_ntp_local_string changed from 2020-03-07 18:53:34 CET to 2020-03-07 18:54:34 CET

2020-03-07 18:54:35.271 [me.event.InboxRemovedEvent] - Discovery Result with UID 'zwave:device:undefined:node4' has been removed.

2020-03-07 18:54:38.488 [me.event.InboxRemovedEvent] - Discovery Result with UID 'zwave:device:undefined:node5' has been removed.

==> /var/log/openhab2/openhab.log <==

2020-03-07 18:54:44.472 [WARN ] [wave.discovery.ZWaveDiscoveryService] - NODE 4: Device discovery could not resolve to a thingType! Manufacturer data not known.

2020-03-07 18:54:44.478 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing 'zwave:device:undefined:node4' to inbox.

==> /var/log/openhab2/events.log <==

2020-03-07 18:54:44.479 [home.event.InboxAddedEvent] - Discovery Result with UID 'zwave:device:undefined:node4' has been added.

==> /var/log/openhab2/openhab.log <==

2020-03-07 18:54:44.487 [WARN ] [wave.discovery.ZWaveDiscoveryService] - NODE 5: Device discovery could not resolve to a thingType! Manufacturer data not known.

2020-03-07 18:54:44.492 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing 'zwave:device:undefined:node5' to inbox.



openhab> list -s | grep zwave
294 │ Active │  80 │ 2.5.2                   │ org.openhab.binding.zwave
openhab> bundle:list|grep -i zwave
294 │ Active │  80 │ 2.5.2                   │ openHAB Add-ons :: Bundles :: ZWave Binding
openhab> openhab> list -s | grep zwave
> 294 │ Active │  80 │ 2.5.2                   │ org.openhab.binding.zwave
> openhab> bundle:list|grep -i zwave
> 294 │ Active │  80 │ 2.5.2                   │ openHAB Add-ons :: Bundles :: ZWave Binding
> openhab>

hschulze86 · March 7, 2020, 7:23pm

2020-03-07 20:22:03.794 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - ZWave discovery: Stop zwave:serial_zstick:undefined

2020-03-07 20:22:03.796 [DEBUG] [ve.internal.protocol.ZWaveController] - ZWave inclusion process not running - nothing to do

2020-03-07 20:22:03.798 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - ZWave discovery: Start zwave:serial_zstick:undefined

2020-03-07 20:22:03.799 [DEBUG] [ve.internal.protocol.ZWaveController] - ZWave controller start inclusion - mode 2

2020-03-07 20:22:03.801 [DEBUG] [ve.internal.protocol.ZWaveController] - Event listener added.

2020-03-07 20:22:03.802 [DEBUG] [al.protocol.ZWaveInclusionController] - ZWave controller start inclusion

2020-03-07 20:22:03.804 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Setting controller into INCLUSION mode, highPower:true networkWide:true.

2020-03-07 20:22:03.806 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Added 126 to queue - size 1

2020-03-07 20:22:03.808 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

2020-03-07 20:22:03.809 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 4A C1 E3 92 

2020-03-07 20:22:03.811 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 4A C1 E3 92 

2020-03-07 20:22:03.813 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT

2020-03-07 20:22:03.814 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06

2020-03-07 20:22:03.814 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 126: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 227

2020-03-07 20:22:03.817 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

2020-03-07 20:22:03.818 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

2020-03-07 20:22:03.820 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 126: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 227

2020-03-07 20:22:03.821 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK

2020-03-07 20:22:03.822 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2020-03-07 20:22:03.825 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 4A E3 01 00 00 50 

2020-03-07 20:22:03.826 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

2020-03-07 20:22:03.828 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=AddNodeToNetwork[74], type=Request[0], dest=1, callback=227, payload=E3 01 00 00 

2020-03-07 20:22:03.830 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=AddNodeToNetwork[74], type=Request[0], dest=1, callback=227, payload=E3 01 00 00 

2020-03-07 20:22:03.831 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 126: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 227

2020-03-07 20:22:03.832 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1

2020-03-07 20:22:03.832 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 126: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 227

2020-03-07 20:22:03.833 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 126: (Callback 227)

2020-03-07 20:22:03.834 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!

2020-03-07 20:22:03.835 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 126: callback 227

2020-03-07 20:22:03.836 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=AddNodeToNetwork[74], type=Request[0], dest=1, callback=227, payload=E3 01 00 00 

2020-03-07 20:22:03.837 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Add Node: Learn ready.

2020-03-07 20:22:03.838 [DEBUG] [al.protocol.ZWaveInclusionController] - Inclusion event: Current state IncludeSent, new event IncludeStart

2020-03-07 20:22:03.839 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 126: Transaction COMPLETED

2020-03-07 20:22:03.840 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Response processed after 26ms

2020-03-07 20:22:03.841 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: TID 126: Transaction completed

2020-03-07 20:22:03.842 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: notifyTransactionResponse TID:126 DONE

2020-03-07 20:22:03.857 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2020-03-07 20:22:03.858 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

2020-03-07 20:22:05.819 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 4: Device discovery completed

2020-03-07 20:22:05.884 [WARN ] [wave.discovery.ZWaveDiscoveryService] - NODE 4: Device discovery could not resolve to a thingType! Manufacturer data not known.

2020-03-07 20:22:05.893 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing 'zwave:device:undefined:node4' to inbox.

==> /var/log/openhab2/events.log <==

2020-03-07 20:22:05.893 [home.event.InboxAddedEvent] - Discovery Result with UID 'zwave:device:undefined:node4' has been added.

==> /var/log/openhab2/openhab.log <==

2020-03-07 20:22:05.894 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 5: Device discovery completed

2020-03-07 20:22:05.908 [WARN ] [wave.discovery.ZWaveDiscoveryService] - NODE 5: Device discovery could not resolve to a thingType! Manufacturer data not known.

2020-03-07 20:22:05.913 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing 'zwave:device:undefined:node5' to inbox.

==> /var/log/openhab2/events.log <==

2020-03-07 20:22:05.913 [home.event.InboxAddedEvent] - Discovery Result with UID 'zwave:device:undefined:node5' has been added.

hschulze86 · March 7, 2020, 7:25pm

2020-03-07 20:23:03.798 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - ZWave discovery: Stop zwave:serial_zstick:undefined

2020-03-07 20:23:03.799 [DEBUG] [al.protocol.ZWaveInclusionController] - ZWave controller stopping inclusion at IncludeStart

2020-03-07 20:23:03.800 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Ending INCLUSION mode.

2020-03-07 20:23:03.801 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Added 127 to queue - size 1

2020-03-07 20:23:03.802 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

2020-03-07 20:23:03.803 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 4A 05 E4 51 

2020-03-07 20:23:03.805 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 4A 05 E4 51 

2020-03-07 20:23:03.806 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT

2020-03-07 20:23:03.808 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 127: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 228

2020-03-07 20:23:03.808 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06

2020-03-07 20:23:03.813 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

2020-03-07 20:23:03.814 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

2020-03-07 20:23:03.815 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 127: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 228

2020-03-07 20:23:03.816 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK

2020-03-07 20:23:03.817 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2020-03-07 20:23:03.818 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

2020-03-07 20:23:03.861 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 4A E4 06 00 00 50 

2020-03-07 20:23:03.866 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=AddNodeToNetwork[74], type=Request[0], dest=6, callback=228, payload=E4 06 00 00 

2020-03-07 20:23:03.869 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=AddNodeToNetwork[74], type=Request[0], dest=6, callback=228, payload=E4 06 00 00 

2020-03-07 20:23:03.871 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 127: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 228

2020-03-07 20:23:03.872 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1

2020-03-07 20:23:03.874 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 127: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 228

2020-03-07 20:23:03.875 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 127: (Callback 228)

2020-03-07 20:23:03.877 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!

2020-03-07 20:23:03.879 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 127: callback 228

2020-03-07 20:23:03.881 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=AddNodeToNetwork[74], type=Request[0], dest=6, callback=228, payload=E4 06 00 00 

2020-03-07 20:23:03.884 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Add Node: Done.

2020-03-07 20:23:03.886 [DEBUG] [al.protocol.ZWaveInclusionController] - Inclusion event: Current state IncludeDone, new event IncludeDone

2020-03-07 20:23:03.888 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Ending INCLUSION mode.

2020-03-07 20:23:03.890 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Added 128 to queue - size 1

2020-03-07 20:23:03.891 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

2020-03-07 20:23:03.894 [DEBUG] [al.protocol.ZWaveInclusionController] - ZWave controller end exclusion

2020-03-07 20:23:03.895 [DEBUG] [ve.internal.protocol.ZWaveController] - ZWaveController include done

2020-03-07 20:23:03.897 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 127: Advanced to DONE

2020-03-07 20:23:03.898 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Response processed after 91ms

2020-03-07 20:23:03.900 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: TID 127: Transaction completed

2020-03-07 20:23:03.902 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: notifyTransactionResponse TID:127 DONE

2020-03-07 20:23:03.907 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2020-03-07 20:23:03.908 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

2020-03-07 20:23:03.911 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 4A 05 00 B5 

2020-03-07 20:23:03.913 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 4A 05 00 B5 

2020-03-07 20:23:03.915 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT

2020-03-07 20:23:03.916 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06

2020-03-07 20:23:03.917 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 128: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 0

2020-03-07 20:23:03.918 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

2020-03-07 20:23:03.920 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

2020-03-07 20:23:03.922 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 128: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 0

2020-03-07 20:23:03.924 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK

2020-03-07 20:23:03.926 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2020-03-07 20:23:03.928 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

==> /var/log/openhab2/events.log <==

2020-03-07 20:23:07.302 [vent.ItemStateChangedEvent] - systeminfo_computer_homeport_memory_used changed from 635 to 636

2020-03-07 20:23:07.306 [vent.ItemStateChangedEvent] - systeminfo_computer_homeport_memory_available changed from 3271 to 3270

2020-03-07 20:23:08.368 [vent.ItemStateChangedEvent] - systeminfo_computer_homeport_memory_used changed from 636 to 635

2020-03-07 20:23:08.371 [vent.ItemStateChangedEvent] - systeminfo_computer_homeport_memory_available changed from 3270 to 3271

==> /var/log/openhab2/openhab.log <==

2020-03-07 20:23:08.921 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 255: TID 128: Timeout at state WAIT_REQUEST. 3 retries remaining.

2020-03-07 20:23:08.922 [DEBUG] [sactionManager$ZWaveTransactionTimer] - TID 128: Transaction is current transaction, so clearing!!!!!

2020-03-07 20:23:08.923 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 128: Transaction CANCELLED

2020-03-07 20:23:08.923 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: notifyTransactionResponse TID:128 CANCELLED

2020-03-07 20:23:08.924 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

Bruce_Osborne · March 7, 2020, 7:35pm

Is this a battery operated device? What does HABmin show for attributes?

Configuration -> Things > [node] - Attributes (all the way at the bottom.

For example

hschulze86 · March 7, 2020, 7:39pm

Hi,

its a Roller Shutter 2 from Fibaro
FGR-222

Bruce_Osborne · March 7, 2020, 7:43pm

The Manufacturer and Type/ID are not set . That indicates the device has not been fully discovered by the binding.
Try deleting the Thing (NOT exclude) and re-discovering. The thingid will be the same so your Items should still work.
If that does not work, please post the unfiltered debug logs from adding the Thing back.

hschulze86 · March 7, 2020, 7:48pm

Exactly done as you said:

==> /var/log/openhab2/openhab.log <==

2020-03-07 20:47:42.807 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Handler disposed. Unregistering listener.

2020-03-07 20:47:42.860 [DEBUG] [l.initialization.ZWaveNodeSerializer] - NODE 5: Serializing to file /var/lib/openhab2/zwave/network_ebb7f585__node_5.xml

==> /var/log/openhab2/events.log <==

2020-03-07 20:47:42.914 [hingStatusInfoChangedEvent] - 'zwave:device:undefined:node5' changed from UNINITIALIZED to UNINITIALIZED (HANDLER_MISSING_ERROR)

2020-03-07 20:47:43.158 [vent.ItemStateChangedEvent] - systeminfo_computer_homeport_memory_used changed from 637 to 638

2020-03-07 20:47:44.203 [vent.ItemStateChangedEvent] - systeminfo_computer_homeport_memory_used changed from 638 to 637

2020-03-07 20:47:45.924 [vent.ItemStateChangedEvent] - miio_basic_07DF6F57_motorspeed changed from 350 to 351

2020-03-07 20:47:50.582 [vent.ItemStateChangedEvent] - systeminfo_computer_homeport_memory_used changed from 637 to 638

2020-03-07 20:47:50.588 [vent.ItemStateChangedEvent] - systeminfo_computer_homeport_memory_available changed from 3269 to 3268

2020-03-07 20:47:51.687 [vent.ItemStateChangedEvent] - systeminfo_computer_homeport_memory_used changed from 638 to 637

2020-03-07 20:47:51.700 [vent.ItemStateChangedEvent] - systeminfo_computer_homeport_memory_available changed from 3268 to 3269

==> /var/log/openhab2/openhab.log <==

2020-03-07 20:47:55.111 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - ZWave discovery: Stop zwave:serial_zstick:undefined

2020-03-07 20:47:55.113 [DEBUG] [ve.internal.protocol.ZWaveController] - ZWave inclusion process not running - nothing to do

2020-03-07 20:47:55.115 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - ZWave discovery: Start zwave:serial_zstick:undefined

2020-03-07 20:47:55.117 [DEBUG] [ve.internal.protocol.ZWaveController] - ZWave controller start inclusion - mode 2

2020-03-07 20:47:55.119 [DEBUG] [ve.internal.protocol.ZWaveController] - Event listener added.

2020-03-07 20:47:55.120 [DEBUG] [al.protocol.ZWaveInclusionController] - ZWave controller start inclusion

2020-03-07 20:47:55.122 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Setting controller into INCLUSION mode, highPower:true networkWide:true.

2020-03-07 20:47:55.124 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Added 135 to queue - size 1

2020-03-07 20:47:55.126 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

2020-03-07 20:47:55.128 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 4A C1 EC 9D 

2020-03-07 20:47:55.130 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 4A C1 EC 9D 

2020-03-07 20:47:55.133 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06

2020-03-07 20:47:55.134 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

2020-03-07 20:47:55.134 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT

2020-03-07 20:47:55.136 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

2020-03-07 20:47:55.137 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null

2020-03-07 20:47:55.136 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 135: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 236

2020-03-07 20:47:55.137 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK

2020-03-07 20:47:55.138 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2020-03-07 20:47:55.139 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

2020-03-07 20:47:55.141 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 4A EC 01 00 00 5F 

2020-03-07 20:47:55.144 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=AddNodeToNetwork[74], type=Request[0], dest=1, callback=236, payload=EC 01 00 00 

2020-03-07 20:47:55.145 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=AddNodeToNetwork[74], type=Request[0], dest=1, callback=236, payload=EC 01 00 00 

2020-03-07 20:47:55.146 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 135: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 236

2020-03-07 20:47:55.147 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1

2020-03-07 20:47:55.148 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 135: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 236

2020-03-07 20:47:55.148 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 135: (Callback 236)

2020-03-07 20:47:55.149 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!

2020-03-07 20:47:55.150 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 135: callback 236

2020-03-07 20:47:55.151 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=AddNodeToNetwork[74], type=Request[0], dest=1, callback=236, payload=EC 01 00 00 

2020-03-07 20:47:55.152 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Add Node: Learn ready.

2020-03-07 20:47:55.153 [DEBUG] [al.protocol.ZWaveInclusionController] - Inclusion event: Current state IncludeSent, new event IncludeStart

2020-03-07 20:47:55.154 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 135: Transaction COMPLETED

2020-03-07 20:47:55.155 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Response processed after 19ms

2020-03-07 20:47:55.156 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: TID 135: Transaction completed

2020-03-07 20:47:55.157 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: notifyTransactionResponse TID:135 DONE

2020-03-07 20:47:55.158 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2020-03-07 20:47:55.159 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

2020-03-07 20:47:57.139 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 4: Device discovery completed

2020-03-07 20:47:57.177 [WARN ] [wave.discovery.ZWaveDiscoveryService] - NODE 4: Device discovery could not resolve to a thingType! Manufacturer data not known.

2020-03-07 20:47:57.182 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 5: Device discovery completed

2020-03-07 20:47:57.237 [WARN ] [wave.discovery.ZWaveDiscoveryService] - NODE 5: Device discovery could not resolve to a thingType! Manufacturer data not known.

2020-03-07 20:47:57.243 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing 'zwave:device:undefined:node5' to inbox.

==> /var/log/openhab2/events.log <==

2020-03-07 20:47:57.244 [home.event.InboxAddedEvent] - Discovery Result with UID 'zwave:device:undefined:node5' has been added.

2020-03-07 20:47:59.089 [vent.ItemStateChangedEvent] - systeminfo_computer_homeport_memory_used changed from 637 to 638

2020-03-07 20:48:00.083 [me.event.InboxRemovedEvent] - Discovery Result with UID 'zwave:device:undefined:node5' has been removed.

2020-03-07 20:48:00.099 [hingStatusInfoChangedEvent] - 'zwave:device:undefined:node5' changed from UNINITIALIZED to INITIALIZING

==> /var/log/openhab2/openhab.log <==

2020-03-07 20:48:00.116 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - Initializing ZWave thing handler zwave:device:undefined:node5.

2020-03-07 20:48:00.124 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: MANUFACTURER not set

2020-03-07 20:48:00.126 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Controller status changed to ONLINE.

==> /var/log/openhab2/events.log <==

2020-03-07 20:48:00.128 [hingStatusInfoChangedEvent] - 'zwave:device:undefined:node5' changed from INITIALIZING to OFFLINE (BRIDGE_OFFLINE): Controller is offline

==> /var/log/openhab2/openhab.log <==

2020-03-07 20:48:00.174 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Controller is ONLINE. Starting device initialisation.

==> /var/log/openhab2/events.log <==

2020-03-07 20:48:00.179 [vent.ItemStateChangedEvent] - systeminfo_computer_homeport_memory_used changed from 638 to 637

2020-03-07 20:48:00.182 [hingStatusInfoChangedEvent] - 'zwave:device:undefined:node5' changed from OFFLINE (BRIDGE_OFFLINE): Controller is offline to ONLINE

==> /var/log/openhab2/openhab.log <==

2020-03-07 20:48:00.232 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Updating node properties.

==> /var/log/openhab2/events.log <==

2020-03-07 20:48:00.232 [me.event.ThingUpdatedEvent] - Thing 'zwave:device:undefined:node5' has been updated.

==> /var/log/openhab2/openhab.log <==

2020-03-07 20:48:00.235 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Updating node properties. MAN=2147483647

==> /var/log/openhab2/events.log <==

2020-03-07 20:48:00.235 [me.event.ThingUpdatedEvent] - Thing 'zwave:device:undefined:node5' has been updated.

==> /var/log/openhab2/openhab.log <==

2020-03-07 20:48:00.236 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Properties synchronised

2020-03-07 20:48:00.239 [DEBUG] [ve.internal.protocol.ZWaveController] - Event listener added.

==> /var/log/openhab2/events.log <==

2020-03-07 20:48:00.239 [me.event.ThingUpdatedEvent] - Thing 'zwave:device:undefined:node5' has been updated.

==> /var/log/openhab2/openhab.log <==

2020-03-07 20:48:00.239 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Initialising Thing Node...

2020-03-07 20:48:00.241 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Polling initialised at 1800 seconds - start in 352800 milliseconds.

2020-03-07 20:48:00.242 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Device initialisation complete.

==> /var/log/openhab2/events.log <==

2020-03-07 20:48:01.213 [vent.ItemStateChangedEvent] - systeminfo_computer_homeport_memory_used changed from 637 to 638

2020-03-07 20:48:01.219 [vent.ItemStateChangedEvent] - systeminfo_computer_homeport_memory_available changed from 3269 to 3268

Bruce_Osborne · March 7, 2020, 8:28pm

Are you sure you added the Thing? Here is all the log shows.

Z-wave log viewer is here. https://www.cd-jackson.com/index.php/openhab/zwave-log-viewer

hschulze86 · March 7, 2020, 8:40pm

Got it, reinitialising did the trick on the undefined, added thing.

Actionhank · June 3, 2020, 5:35am

I am using OH 2.5.5-1 and the option “Reinitialize the device” is missing. What is the reason?

5iver · June 3, 2020, 9:05am

In Habmin, you need to select Tools> Show advanced settings. In Paper UI, you need to select More.

chris · June 3, 2020, 10:16am

There are two reasons - either (as @5iver said) you haven’t selected to show advanced options, or the device may still be initialising - the option to initialise the device is not shown when it is already initialising.