Coolcam Neo PIR sensor does not show chanels

CodeNinja · December 7, 2022, 3:28pm

Hi all,
I’m new to OpenHab and using MiLight/MixBoxer and Coolcam things. All my MiLight/MiBoxer works fine out of the box. Also my Coolcam z-wave powerplug works directly and out of the box.

Now i ordered a Neo Coolcam PIR sensor (NAS-PD01 according to the package) and i’m able to connect it to my aeotec z-stick gen5 controller. The device is also found by OpenHab as “unknown device”. It doesn’t show any channels and says that they may be discovered after a while probably because of the sleep mode of battery powered devices. I waited for 2 days but nothing changes.

After google a bit around i found a lot of articles of people who experience the same/similar issues with this PIR device. I found suggestions like:

Exclude and re-include the device to your controller
Reset the controller and PIR device, connect them again to eachother and try again
Wake up the PIR device by pressing the button 1x
Wake up the PIR device by activating the inclusion mode (pres 3x in 1,5 seconds)

I spend hours to get it working, tried all of the steps, made sure that all things where removed from OpenHab before following the steps and tried a clean OpenHab installation (latest current stable version, 3.x)

None of all steps worked. Somewhere i read that if none of the steps is working the device PIR may be broken. I contacted my supplier and he said when its discovered (even without showing chanels) its working fine. They say its an issue with the software i want to use the tool in (so OpenHab in my case).

So my question(s)

How can i get Neo Coolcam zwave PIR sensor working in OpenHab?
The supplier said its a zwave 700 device, the controller i use seems to be a 500 version? zwave 700 seems to be backwards compatible with the 500 version, but still, can this be the reason why its not working? (if yes, why is the PIR found by OpenHab then?)
According to some articles i found the device may be a new version and have new device ID which is not inside the database of the zwave binding. How can i find the device_id and manufacturer_id of my PIR device and where can i find if its in the database

If more info or details are required, let me know. I’m a relatively technical but are new to OpenHab so if i need to post logs or something, i need to know where to find them than i’m able to get and post them.

Thanks in advance for helping me!

anon71759204 · December 7, 2022, 5:25pm

Please post a screenshot of the ‘Thing Properties’, e.g.

The device ID is (decimal):
zwave_manufacturer:zwave_devicetype:zwave_deviceid:…

Series 700 devices and Series 500 controller should be compatible (Z-Wave HW platform of your sensor is ZM5202).

CodeNinja · December 7, 2022, 7:12pm

This is a screenshot of the thing properties of the PIR device. It looks totally different then your screenshot.

Maybe this is usefull as well

The thing settings of my neo wall plug switch do look like your screenshot

anon71759204 · December 7, 2022, 7:42pm

Well, several parameters are missing from the Thing Properties, which is a clear indication that the interview of your device wasn’t successful/completed.

Without more data from the interview (or a debug log), I can only give general advice:

Use fresh batteries.
Move your device close to your controller.
Use an USB extension cable.
Use a powered USB hub.
Debug the communication (see ZWave - Bindings | openHAB) and post your debug logs.

apella12 · December 7, 2022, 8:52pm

Of these only the wakeup options are worth trying (IMO) and really just the 1x option. I hedge on the 3x option because sometimes the manual and what works on the device are out of sync, so try that too. Following the tips above what you are looking for in the Debug is a “Wakeup notification”. Success will have the properties, but also you should see 5 lines on the UI page.
Five Lines of configured node

CodeNinja · December 8, 2022, 7:54pm

I deleted the PIR device from openhab (Delete thing), activated the debug modes and added the PIR device again.
This logs are written since adding the PIR device.


2022-12-08 20:46:53.936 [INFO ] [openhab.event.InboxAddedEvent       ] - Discovery Result with UID 'zwave:device:7144590b52:node2' has been added.
2022-12-08 20:47:03.930 [INFO ] [openhab.event.InboxRemovedEvent     ] - Discovery Result with UID 'zwave:device:7144590b52:node2' has been removed.
2022-12-08 20:47:03.940 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:device:7144590b52:node2' changed from UNINITIALIZED to INITIALIZING
2022-12-08 20:47:03.958 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:device:7144590b52:node2' changed from INITIALIZING to OFFLINE (BRIDGE_OFFLINE): Controller is offline
2022-12-08 20:47:03.974 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:device:7144590b52:node2' changed from OFFLINE (BRIDGE_OFFLINE): Controller is offline to ONLINE

After this, no new loggins appear. Not after “waking up” the device by pressing the button once, not when i removed the battery for one minute and replaced it, not when i pres the buttons 3x within one second.

I tried another power supply for the RPI, another (high quality USB) cable to power the RPI and placed my PIR device on 30 cm from my controller. Nothing helped…

Currently i don’t have a powered USB hub so currently i cant test this option. I will try to get one in the weekend.

CodeNinja · December 8, 2022, 7:56pm

I dont have those options, i only have these for the PIR device:

I do have those options for my z-wave powerplug which is working properly

apella12 · December 8, 2022, 8:21pm

The five line is a quick way to check if a device is fully discovered. Another is the presence of an XML file in the openhab-userdata/zwave folder (or var/lib/openhab/zwave).

Anyway you will need to find a way to wake the device. The log above doesn’t have any debug entries (Edit: plus that looks like the event.log folder, not the openhab.log)? Once the device is fully discovered, it will either work (All channels) or show the device numbers (TYPE:ID) necessary to add it to the DB. My suggestion is to keep at the button (one, three, press for a second, (be creative)) with 10 second rests until you see the five lines or you have a blister on your finger .

anon71759204 · December 8, 2022, 9:03pm

A combination of a Z-Stick Gen5 (without ‘+’) and a RaspberryPi 4 needs a (powered?) USB hub:

https://forums.raspberrypi.com/viewtopic.php?t=245031

I am not sure which RaspberryPi you are using though.

Your power supply must be rated for 3 A.

CodeNinja · December 9, 2022, 3:48pm

You are right, i had the wrong logs! Here the correct one (since adding the found device)

2022-12-09 16:39:03.278 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 11 00 04 00 07 09 71 05 00 00 00 FF 07 08 00 D7 00 B7 
2022-12-09 16:39:03.280 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=7, callback=0, payload=00 07 09 71 05 00 00 00 FF 07 08 00 D7 00 
2022-12-09 16:39:03.281 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=7, callback=0, payload=00 07 09 71 05 00 00 00 FF 07 08 00 D7 00 
2022-12-09 16:39:03.281 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2022-12-09 16:39:03.282 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 7: Application Command Request (ALIVE:DONE)
2022-12-09 16:39:03.282 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 7: resetResendCount initComplete=true isDead=false
2022-12-09 16:39:03.283 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 7: Incoming command class COMMAND_CLASS_ALARM, endpoint 0
2022-12-09 16:39:03.283 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 7: SECURITY not supported
2022-12-09 16:39:03.284 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 7: Received COMMAND_CLASS_ALARM V8 NOTIFICATION_REPORT
2022-12-09 16:39:03.284 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 7: NOTIFICATION report - 0 = 0, event=8, status=255, plen=0
2022-12-09 16:39:03.285 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 7: Alarm Type = BURGLAR (0)
2022-12-09 16:39:03.285 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 7: Commands processed 1.
2022-12-09 16:39:03.286 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 7: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@50d77487.
2022-12-09 16:39:03.286 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2022-12-09 16:39:03.287 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2022-12-09 16:39:03.287 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2022-12-09 16:39:03.287 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2022-12-09 16:39:03.308 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0E 00 04 00 07 06 31 05 05 22 02 7E CF 00 54 
2022-12-09 16:39:03.310 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=7, callback=0, payload=00 07 06 31 05 05 22 02 7E CF 00 
2022-12-09 16:39:03.311 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=7, callback=0, payload=00 07 06 31 05 05 22 02 7E CF 00 
2022-12-09 16:39:03.311 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2022-12-09 16:39:03.312 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 7: Application Command Request (ALIVE:DONE)
2022-12-09 16:39:03.312 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 7: resetResendCount initComplete=true isDead=false
2022-12-09 16:39:03.313 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 7: Incoming command class COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 0
2022-12-09 16:39:03.313 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 7: SECURITY not supported
2022-12-09 16:39:03.313 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 7: Received COMMAND_CLASS_SENSOR_MULTILEVEL V7 SENSOR_MULTILEVEL_REPORT
2022-12-09 16:39:03.314 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 7: Sensor Type = RelativeHumidity(5), Scale = 0
2022-12-09 16:39:03.315 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 7: Sensor Value = 63.8
2022-12-09 16:39:03.315 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 7: Commands processed 1.
2022-12-09 16:39:03.315 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 7: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@3b4cf33a.
2022-12-09 16:39:03.316 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2022-12-09 16:39:03.316 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2022-12-09 16:39:03.317 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2022-12-09 16:39:03.317 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2022-12-09 16:39:05.954 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - ZWave discovery: Stop zwave:serial_zstick:7144590b52
2022-12-09 16:39:05.954 [DEBUG] [ve.internal.protocol.ZWaveController] - ZWave inclusion process not running - nothing to do
2022-12-09 16:39:05.955 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - ZWave discovery: Start zwave:serial_zstick:7144590b52
2022-12-09 16:39:05.956 [DEBUG] [ve.internal.protocol.ZWaveController] - ZWave controller start inclusion - mode 2
2022-12-09 16:39:05.957 [DEBUG] [ve.internal.protocol.ZWaveController] - Event listener added.
2022-12-09 16:39:05.957 [DEBUG] [al.protocol.ZWaveInclusionController] - ZWave controller start inclusion
2022-12-09 16:39:05.958 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Setting controller into INCLUSION mode, highPower:true networkWide:true.
2022-12-09 16:39:05.958 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Added 80 to queue - size 1
2022-12-09 16:39:05.959 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2022-12-09 16:39:05.959 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 4A C1 53 22 
2022-12-09 16:39:05.960 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 4A C1 53 22 
2022-12-09 16:39:05.961 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2022-12-09 16:39:05.961 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 80: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 83
2022-12-09 16:39:05.961 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2022-12-09 16:39:05.962 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2022-12-09 16:39:05.963 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2022-12-09 16:39:05.963 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 80: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 83
2022-12-09 16:39:05.963 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2022-12-09 16:39:05.963 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2022-12-09 16:39:05.964 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2022-12-09 16:39:05.964 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 4A 53 01 00 00 E0 
2022-12-09 16:39:05.968 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=AddNodeToNetwork[74], type=Request[0], dest=1, callback=83, payload=53 01 00 00 
2022-12-09 16:39:05.969 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=AddNodeToNetwork[74], type=Request[0], dest=1, callback=83, payload=53 01 00 00 
2022-12-09 16:39:05.969 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 80: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 83
2022-12-09 16:39:05.969 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2022-12-09 16:39:05.970 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 80: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 83
2022-12-09 16:39:05.970 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 80: (Callback 83)
2022-12-09 16:39:05.970 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2022-12-09 16:39:05.970 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 80: callback 83
2022-12-09 16:39:05.970 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=AddNodeToNetwork[74], type=Request[0], dest=1, callback=83, payload=53 01 00 00 
2022-12-09 16:39:05.971 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Add Node: Learn ready.
2022-12-09 16:39:05.971 [DEBUG] [al.protocol.ZWaveInclusionController] - Inclusion event: Current state IncludeSent, new event IncludeStart
2022-12-09 16:39:05.971 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 80: Transaction COMPLETED
2022-12-09 16:39:05.972 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Response processed after 11ms
2022-12-09 16:39:05.972 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: TID 80: Transaction completed
2022-12-09 16:39:05.972 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: notifyTransactionResponse TID:80 DONE
2022-12-09 16:39:05.973 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2022-12-09 16:39:05.973 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2022-12-09 16:39:07.962 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 2: Device discovery completed
2022-12-09 16:39:07.965 [WARN ] [wave.discovery.ZWaveDiscoveryService] - NODE 2: Device discovery could not resolve to a thingType! Manufacturer data not known.
2022-12-09 16:39:07.973 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing 'zwave:device:7144590b52:node2' to inbox.
2022-12-09 16:39:07.975 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 5: Device discovery completed
2022-12-09 16:39:07.976 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 5: Device discovery resolved to thingType zwave:shenzhen_coolcampwrplug_00_000
2022-12-09 16:39:07.980 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 7: Device discovery completed
2022-12-09 16:39:07.984 [WARN ] [wave.discovery.ZWaveDiscoveryService] - NODE 7: Device discovery could not resolve to a thingType! 0258:0200:1036::3.6
2022-12-09 16:39:07.987 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing 'zwave:device:7144590b52:node7' to inbox.
2022-12-09 16:39:12.398 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - Initializing ZWave thing handler zwave:device:7144590b52:node7.
2022-12-09 16:39:12.405 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - Checking zwave:coqon_psmz0001_00_000
2022-12-09 16:39:12.406 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - Checking zwave:defaro_dad101_00_000

.........
.........
.........
.........
2022-12-09 16:39:12.617 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - Checking zwave:yale_ycl1_00_000
2022-12-09 16:39:12.617 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - Checking zwave:yale_ycl1_00_000
2022-12-09 16:39:12.617 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - Checking zwave:yale_yrl256_01_078
2022-12-09 16:39:12.618 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - Checking zwave:yale_yrd216_00_000
2022-12-09 16:39:12.618 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - Checking zwave:yale_yrl220_00_000
2022-12-09 16:39:12.618 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - Checking zwave:yale_yrl220_00_000
2022-12-09 16:39:12.618 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - Checking zwave:yale_yrl220_00_000
2022-12-09 16:39:12.619 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - Checking zwave:yale_yrl220_00_000
2022-12-09 16:39:12.619 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - Checking zwave:logic_zdb5100_00_000
2022-12-09 16:39:12.619 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - Checking zwave:homeseer_hsws100_00_000
2022-12-09 16:39:12.619 [DEBUG] 
[ding.zwave.handler.ZWaveThingHandler] - Checking zwave:homeseer_hswx300_00_000
2022-12-09 16:39:12.962 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - Checking zwave:ge_28167_00_000
2022-12-09 16:39:12.963 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - Checking zwave:ge_28167_00_000
2022-12-09 16:39:12.963 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - Checking zwave:ge_ultrapro39348_00_000
2022-12-09 16:39:12.963 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - Checking zwave:hank_acc01_00_000
2022-12-09 16:39:12.963 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - Checking zwave:hank_swes01_00_000
2022-12-09 16:39:12.963 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - Checking zwave:hank_hkzwms01_00_000
2022-12-09 16:39:12.964 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - Checking zwave:hank_scn04_00_000
2022-12-09 16:39:12.964 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - Checking zwave:hank_scn04_00_000
2022-12-09 16:39:12.964 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 7: Unable to find thing type (0258:0200:1036:3.6)
2022-12-09 16:39:12.964 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 7: Controller status changed to ONLINE.
2022-12-09 16:39:12.965 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 7: Controller is ONLINE. Starting device initialisation.
2022-12-09 16:39:12.967 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 7: Updating node properties.
2022-12-09 16:39:12.969 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 7: Updating node properties. MAN=600
2022-12-09 16:39:12.970 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 7: Updating node properties. MAN=600. SET. Was 600
2022-12-09 16:39:12.970 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 7: Properties synchronised
2022-12-09 16:39:12.972 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 7: Update ASSOCIATION group_ZWaveAssociationGroup [index=1, name=Lifeline, profile1=0, profile2=1, associations=[node_1]]: Adding Controller (node_1)
2022-12-09 16:39:12.973 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 7: Configuration synchronised
2022-12-09 16:39:12.976 [DEBUG] [ve.internal.protocol.ZWaveController] - Event listener added.
2022-12-09 16:39:12.977 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 7: Initialising Thing Node...
2022-12-09 16:39:12.977 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 7: Polling initialised at 86400 seconds - start in 72662400 milliseconds.
2022-12-09 16:39:12.978 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 7: Device initialisation complete.
2022-12-09 16:39:29.946 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0A 00 04 10 07 02 84 07 CA 00 AD 
2022-12-09 16:39:29.947 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=7, callback=16, payload=10 07 02 84 07 CA 00 
2022-12-09 16:39:29.948 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=7, callback=16, payload=10 07 02 84 07 CA 00 
2022-12-09 16:39:29.949 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2022-12-09 16:39:29.950 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 7: Application Command Request (ALIVE:DONE)
2022-12-09 16:39:29.950 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 7: resetResendCount initComplete=true isDead=false
2022-12-09 16:39:29.951 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 7: Is awake with 0 messages in the queue, state DONE
2022-12-09 16:39:29.952 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - awakeMax for this Timer Task 20 in 0.5 second intervals
2022-12-09 16:39:29.953 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 7: Start sleep timer with 500ms interval
2022-12-09 16:39:29.954 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 7: Got an event from Z-Wave network: ZWaveNodeStatusEvent
2022-12-09 16:39:29.958 [DEBUG] [ve.internal.protocol.ZWaveController] - NODE 7: Node Status event - Node is AWAKE
2022-12-09 16:39:29.959 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 7: Commands processed 1.
2022-12-09 16:39:29.960 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 7: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@3816d925.
2022-12-09 16:39:29.961 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2022-12-09 16:39:29.962 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2022-12-09 16:39:29.962 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2022-12-09 16:39:29.963 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2022-12-09 16:39:30.454 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 7: WakeupTimerTask 0 Messages waiting, state DONE count 1
2022-12-09 16:39:30.454 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 7: Go back to sleep, state DONE, count 1 messages 0
2022-12-09 16:39:30.455 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 7: Creating new message for application command WAKE_UP_NO_MORE_INFORMATION
2022-12-09 16:39:30.455 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 7: SECURITY not supported
2022-12-09 16:39:30.455 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 7: Command Class COMMAND_CLASS_WAKE_UP is NOT required to be secured
2022-12-09 16:39:30.455 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 7: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@37ffd531
2022-12-09 16:39:30.456 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 7: Bump transaction 81 priority from Immediate to Immediate
2022-12-09 16:39:30.457 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 7: Adding to device queue
2022-12-09 16:39:30.460 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 7: Added 81 to queue - size 2
2022-12-09 16:39:30.461 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2022-12-09 16:39:30.462 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 07 02 84 08 25 54 1D 
2022-12-09 16:39:30.463 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 7: Sending REQUEST Message = 01 09 00 13 07 02 84 08 25 54 1D 
2022-12-09 16:39:30.464 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2022-12-09 16:39:30.465 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 81: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 84
2022-12-09 16:39:30.465 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2022-12-09 16:39:30.465 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2022-12-09 16:39:30.466 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2022-12-09 16:39:30.466 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 81: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 84
2022-12-09 16:39:30.467 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2022-12-09 16:39:30.467 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2022-12-09 16:39:30.468 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2022-12-09 16:39:30.471 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
2022-12-09 16:39:30.472 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2022-12-09 16:39:30.472 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2022-12-09 16:39:30.473 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 81: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 84
2022-12-09 16:39:30.473 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2022-12-09 16:39:30.473 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 81: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 84
2022-12-09 16:39:30.474 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2022-12-09 16:39:30.474 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 7: sentData successfully placed on stack.
2022-12-09 16:39:30.475 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 81: Advanced to WAIT_REQUEST
2022-12-09 16:39:30.475 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 7: TID 81: Transaction not completed
2022-12-09 16:39:30.476 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2022-12-09 16:39:30.476 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2022-12-09 16:39:30.495 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 18 00 13 54 00 00 02 00 D0 7F 7F 7F 7F 01 01 03 00 00 00 00 02 01 00 00 72 
2022-12-09 16:39:30.496 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=84, payload=54 00 00 02 00 D0 7F 7F 7F 7F 01 01 03 00 00 00 00 02 01 00 00 
2022-12-09 16:39:30.497 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=84, payload=54 00 00 02 00 D0 7F 7F 7F 7F 01 01 03 00 00 00 00 02 01 00 00 
2022-12-09 16:39:30.497 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 81: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 84
2022-12-09 16:39:30.498 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2022-12-09 16:39:30.499 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 81: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 84
2022-12-09 16:39:30.499 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 81: (Callback 84)
2022-12-09 16:39:30.500 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2022-12-09 16:39:30.501 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 81: callback 84
2022-12-09 16:39:30.502 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=84, payload=54 00 00 02 00 D0 7F 7F 7F 7F 01 01 03 00 00 00 00 02 01 00 00 
2022-12-09 16:39:30.503 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 7: SendData Request. CallBack ID = 84, Status = Transmission complete and ACK received(0)
2022-12-09 16:39:30.503 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 7: resetResendCount initComplete=true isDead=false
2022-12-09 16:39:30.504 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 81: Transaction COMPLETED
2022-12-09 16:39:30.505 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 7: Response processed after 41ms
2022-12-09 16:39:30.506 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 7: TID 81: Transaction completed
2022-12-09 16:39:30.506 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 7: notifyTransactionResponse TID:81 DONE
2022-12-09 16:39:30.508 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 7: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2022-12-09 16:39:30.508 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 81: Transaction event listener: DONE: DONE -> UNINTIALIZED
2022-12-09 16:39:30.509 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 7: Went to sleep COMPLETE
2022-12-09 16:39:30.509 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2022-12-09 16:39:30.510 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2022-12-09 16:39:39.420 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 5: ZWaveCommandClassTransactionPayload - send to node
2022-12-09 16:39:39.421 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY doesn't encrypt PING
2022-12-09 16:39:39.422 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: Command Class COMMAND_CLASS_NO_OPERATION is NOT required to be secured
2022-12-09 16:39:39.422 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@496ac01a
2022-12-09 16:39:39.423 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Adding to device queue
2022-12-09 16:39:39.425 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Added 82 to queue - size 2
2022-12-09 16:39:39.426 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2022-12-09 16:39:39.427 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 08 00 13 05 01 00 25 55 90 
2022-12-09 16:39:39.428 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 5: Sending REQUEST Message = 01 08 00 13 05 01 00 25 55 90 
2022-12-09 16:39:39.429 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2022-12-09 16:39:39.430 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 82: [WAIT_RESPONSE] priority=Poll, requiresResponse=true, callback: 85
2022-12-09 16:39:39.431 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2022-12-09 16:39:39.431 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2022-12-09 16:39:39.432 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2022-12-09 16:39:39.433 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 82: [WAIT_RESPONSE] priority=Poll, requiresResponse=true, callback: 85
2022-12-09 16:39:39.433 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2022-12-09 16:39:39.434 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2022-12-09 16:39:39.434 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2022-12-09 16:39:39.441 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
2022-12-09 16:39:39.442 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2022-12-09 16:39:39.442 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2022-12-09 16:39:39.443 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 82: [WAIT_RESPONSE] priority=Poll, requiresResponse=true, callback: 85
2022-12-09 16:39:39.443 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2022-12-09 16:39:39.444 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 82: [WAIT_RESPONSE] priority=Poll, requiresResponse=true, callback: 85
2022-12-09 16:39:39.445 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2022-12-09 16:39:39.445 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 5: sentData successfully placed on stack.
2022-12-09 16:39:39.446 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 82: Advanced to WAIT_REQUEST
2022-12-09 16:39:39.447 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: TID 82: Transaction not completed
2022-12-09 16:39:39.447 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2022-12-09 16:39:39.448 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2022-12-09 16:39:43.684 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 18 00 13 55 01 01 A9 00 7F 7F 7F 7F 7F 01 01 07 00 00 00 00 02 07 00 00 75 
2022-12-09 16:39:43.685 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=1, callback=85, payload=55 01 01 A9 00 7F 7F 7F 7F 7F 01 01 07 00 00 00 00 02 07 00 00 
2022-12-09 16:39:43.686 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=1, callback=85, payload=55 01 01 A9 00 7F 7F 7F 7F 7F 01 01 07 00 00 00 00 02 07 00 00 
2022-12-09 16:39:43.687 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 82: [WAIT_REQUEST] priority=Poll, requiresResponse=true, callback: 85
2022-12-09 16:39:43.687 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2022-12-09 16:39:43.688 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 82: [WAIT_REQUEST] priority=Poll, requiresResponse=true, callback: 85
2022-12-09 16:39:43.689 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 82: (Callback 85)
2022-12-09 16:39:43.690 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2022-12-09 16:39:43.691 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 82: callback 85
2022-12-09 16:39:43.691 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=1, callback=85, payload=55 01 01 A9 00 7F 7F 7F 7F 7F 01 01 07 00 00 00 00 02 07 00 00 
2022-12-09 16:39:43.692 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 5: SendData Request. CallBack ID = 85, Status = Transmission complete, no ACK received(1)
2022-12-09 16:39:43.693 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 82: Transaction CANCELLED
2022-12-09 16:39:43.693 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2022-12-09 16:39:43.694 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: CANCEL while sending message. Requeueing - 2 attempts left!
2022-12-09 16:39:43.695 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 82: Transaction RESET with 2 retries remaining.
2022-12-09 16:39:43.695 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Adding to device queue
2022-12-09 16:39:43.696 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Added 82 to queue - size 2
2022-12-09 16:39:43.696 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2022-12-09 16:39:43.697 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 08 00 13 05 01 00 25 56 93 
2022-12-09 16:39:43.698 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 5: Sending REQUEST Message = 01 08 00 13 05 01 00 25 56 93 
2022-12-09 16:39:43.699 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2022-12-09 16:39:43.699 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 82: [WAIT_RESPONSE] priority=Poll, requiresResponse=true, callback: 86
2022-12-09 16:39:43.700 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2022-12-09 16:39:43.700 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: TID 82: Transaction not completed
2022-12-09 16:39:43.701 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2022-12-09 16:39:43.701 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2022-12-09 16:39:43.701 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2022-12-09 16:39:43.702 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2022-12-09 16:39:43.703 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 82: [WAIT_RESPONSE] priority=Poll, requiresResponse=true, callback: 86
2022-12-09 16:39:43.703 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2022-12-09 16:39:43.703 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2022-12-09 16:39:43.704 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2022-12-09 16:39:43.710 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
2022-12-09 16:39:43.711 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2022-12-09 16:39:43.712 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2022-12-09 16:39:43.713 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 82: [WAIT_RESPONSE] priority=Poll, requiresResponse=true, callback: 86
2022-12-09 16:39:43.713 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2022-12-09 16:39:43.713 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 82: [WAIT_RESPONSE] priority=Poll, requiresResponse=true, callback: 86
2022-12-09 16:39:43.714 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2022-12-09 16:39:43.714 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 5: sentData successfully placed on stack.
2022-12-09 16:39:43.715 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 82: Advanced to WAIT_REQUEST
2022-12-09 16:39:43.715 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: TID 82: Transaction not completed
2022-12-09 16:39:43.716 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2022-12-09 16:39:43.716 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2022-12-09 16:39:47.924 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 18 00 13 56 01 01 A6 00 7F 7F 7F 7F 7F 01 01 07 00 00 00 00 02 07 00 00 79 
2022-12-09 16:39:47.926 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=1, callback=86, payload=56 01 01 A6 00 7F 7F 7F 7F 7F 01 01 07 00 00 00 00 02 07 00 00 
2022-12-09 16:39:47.927 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=1, callback=86, payload=56 01 01 A6 00 7F 7F 7F 7F 7F 01 01 07 00 00 00 00 02 07 00 00 
2022-12-09 16:39:47.927 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 82: [WAIT_REQUEST] priority=Poll, requiresResponse=true, callback: 86
2022-12-09 16:39:47.928 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2022-12-09 16:39:47.928 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 82: [WAIT_REQUEST] priority=Poll, requiresResponse=true, callback: 86
2022-12-09 16:39:47.929 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 82: (Callback 86)
2022-12-09 16:39:47.929 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2022-12-09 16:39:47.930 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 82: callback 86
2022-12-09 16:39:47.930 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=1, callback=86, payload=56 01 01 A6 00 7F 7F 7F 7F 7F 01 01 07 00 00 00 00 02 07 00 00 
2022-12-09 16:39:47.931 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 5: SendData Request. CallBack ID = 86, Status = Transmission complete, no ACK received(1)
2022-12-09 16:39:47.931 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 82: Transaction CANCELLED
2022-12-09 16:39:47.932 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2022-12-09 16:39:47.933 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: CANCEL while sending message. Requeueing - 1 attempts left!
2022-12-09 16:39:47.933 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 82: Transaction RESET with 1 retries remaining.
2022-12-09 16:39:47.934 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Adding to device queue
2022-12-09 16:39:47.934 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Added 82 to queue - size 2
2022-12-09 16:39:47.935 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2022-12-09 16:39:47.936 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 08 00 13 05 01 00 25 57 92 
2022-12-09 16:39:47.936 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 5: Sending REQUEST Message = 01 08 00 13 05 01 00 25 57 92 
2022-12-09 16:39:47.937 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2022-12-09 16:39:47.938 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 82: [WAIT_RESPONSE] priority=Poll, requiresResponse=true, callback: 87
2022-12-09 16:39:47.938 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2022-12-09 16:39:47.939 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: TID 82: Transaction not completed
2022-12-09 16:39:47.939 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2022-12-09 16:39:47.939 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2022-12-09 16:39:47.939 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2022-12-09 16:39:47.940 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2022-12-09 16:39:47.941 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 82: [WAIT_RESPONSE] priority=Poll, requiresResponse=true, callback: 87
2022-12-09 16:39:47.941 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2022-12-09 16:39:47.942 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2022-12-09 16:39:47.943 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2022-12-09 16:39:47.949 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
2022-12-09 16:39:47.950 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2022-12-09 16:39:47.950 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2022-12-09 16:39:47.951 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 82: [WAIT_RESPONSE] priority=Poll, requiresResponse=true, callback: 87
2022-12-09 16:39:47.952 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2022-12-09 16:39:47.952 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 82: [WAIT_RESPONSE] priority=Poll, requiresResponse=true, callback: 87
2022-12-09 16:39:47.953 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2022-12-09 16:39:47.954 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 5: sentData successfully placed on stack.
2022-12-09 16:39:47.955 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 82: Advanced to WAIT_REQUEST
2022-12-09 16:39:47.955 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: TID 82: Transaction not completed
2022-12-09 16:39:47.956 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2022-12-09 16:39:47.956 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2022-12-09 16:39:52.193 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 18 00 13 57 01 01 A9 00 7F 7F 7F 7F 7F 01 01 07 00 00 00 00 02 07 00 00 77 
2022-12-09 16:39:52.194 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=1, callback=87, payload=57 01 01 A9 00 7F 7F 7F 7F 7F 01 01 07 00 00 00 00 02 07 00 00 
2022-12-09 16:39:52.194 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=1, callback=87, payload=57 01 01 A9 00 7F 7F 7F 7F 7F 01 01 07 00 00 00 00 02 07 00 00 
2022-12-09 16:39:52.194 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 82: [WAIT_REQUEST] priority=Poll, requiresResponse=true, callback: 87
2022-12-09 16:39:52.195 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2022-12-09 16:39:52.195 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 82: [WAIT_REQUEST] priority=Poll, requiresResponse=true, callback: 87
2022-12-09 16:39:52.195 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 82: (Callback 87)
2022-12-09 16:39:52.195 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2022-12-09 16:39:52.196 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 82: callback 87
2022-12-09 16:39:52.196 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=1, callback=87, payload=57 01 01 A9 00 7F 7F 7F 7F 7F 01 01 07 00 00 00 00 02 07 00 00 
2022-12-09 16:39:52.196 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 5: SendData Request. CallBack ID = 87, Status = Transmission complete, no ACK received(1)
2022-12-09 16:39:52.196 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 82: Transaction CANCELLED
2022-12-09 16:39:52.197 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2022-12-09 16:39:52.197 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Retry count exceeded. Discarding message: TID 82: [CANCELLED] priority=Poll, requiresResponse=true, callback: 87
2022-12-09 16:39:52.197 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: TID 82: Transaction completed
2022-12-09 16:39:52.197 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: notifyTransactionResponse TID:82 CANCELLED
2022-12-09 16:39:52.198 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2022-12-09 16:39:52.198 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 82: Transaction event listener: DONE: CANCELLED -> WAIT_REQUEST
2022-12-09 16:39:52.198 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2022-12-09 16:39:52.198 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 5: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@2d0be2b9
2022-12-09 16:39:52.199 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2022-12-09 16:40:02.027 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 12 00 04 10 07 0A 71 05 00 00 00 FF 07 00 01 08 D0 00 A1 
2022-12-09 16:40:02.029 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=7, callback=16, payload=10 07 0A 71 05 00 00 00 FF 07 00 01 08 D0 00 
2022-12-09 16:40:02.030 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=7, callback=16, payload=10 07 0A 71 05 00 00 00 FF 07 00 01 08 D0 00 
2022-12-09 16:40:02.031 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2022-12-09 16:40:02.031 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 7: Application Command Request (ALIVE:DONE)
2022-12-09 16:40:02.032 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 7: resetResendCount initComplete=true isDead=false
2022-12-09 16:40:02.033 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 7: Incoming command class COMMAND_CLASS_ALARM, endpoint 0
2022-12-09 16:40:02.034 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 7: SECURITY not supported
2022-12-09 16:40:02.034 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 7: Received COMMAND_CLASS_ALARM V8 NOTIFICATION_REPORT
2022-12-09 16:40:02.035 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 7: NOTIFICATION report - 0 = 0, event=0, status=255, plen=1
2022-12-09 16:40:02.036 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 7: Alarm Type = BURGLAR (0)
2022-12-09 16:40:02.037 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 7: Got an event from Z-Wave network: ZWaveAlarmValueEvent
2022-12-09 16:40:02.038 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 7: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_ALARM, value=255
2022-12-09 16:40:02.040 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 7: Commands processed 1.
2022-12-09 16:40:02.040 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 7: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@75494b8d.
2022-12-09 16:40:02.041 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2022-12-09 16:40:02.042 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2022-12-09 16:40:02.042 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2022-12-09 16:40:02.043 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

I placed a non-powered USB-hub (as i still dont have a powered one, will get one this weekend). With the hub i find the PIR device twice, once as NODE2 and once as NODE7. Node 7 looks already better, it has more device properties, including a zwave_lastwakeup which is updated when i press the button on the PIR.

I also have alle the options which i should have according to this thready here

Unfortunately i still have no chanels

I also think i have the device ID which is needed to check if the device is in the database but i’m not sure about this:

CodeNinja · December 9, 2022, 3:49pm

I use a RPI4 and a Z-stick Gen5 so i will defenitely try an powered USB hub when i bought one this weekend. I already use a 3A power supply

apella12 · December 9, 2022, 4:11pm

Ok good. The device is in the DB, but you need to update the binding since it was added after the last stable release OH3.3. Look at the end of this thread for 2 ways to update the zwave binding. Do whatever you feel comfortable with.

You don’t need a powered hub, that is optional. I like the powered one because I believe it provides a more even power to the antenna. (If the pi gets busy there could be a dip on the USB). I could be wrong as I am not an expert.

anon71759204 · December 9, 2022, 4:23pm

… and I strongly recommend using a USB extension cable between your USB hub and your Z-Wave stick to reduce EMI.

CodeNinja · December 9, 2022, 6:33pm

Thanks for your support, got it working with the new binding. Now it works out of the box like expected!.

apella12 · December 9, 2022, 7:10pm

Thanks Also a belated welcome to OH

As you continue with OH and zwave the correct way to remove a device is to use the “Exclude Devices” at the bottom of the controller UI page and then press the button on the device per the manual for exclusion. The UI page for the device should then show “Removed from Controller” in RED or something like that. Then you can use the “Delete Thing” at the bottom. When you just “Delete Thing” on the UI page and factory reset the device you will have a zombie node (like your node 2). As a battery zombie it should not be much of a problem, but if it was powered it can mess up communications

system · January 20, 2023, 11:10am

This topic was automatically closed 41 days after the last reply. New replies are no longer allowed.