Neo Coolcam NAS-DS01Z does not work on OH 2.5

Device is working on my side.
Make sure item sensor_door is defined as contact and not as switch.
I made that mistake by myself, if defined wrongly the status remains at Null …

best regards Harry

also work fine for me, but with binding form ver 2.4

Thanks
Yehuda

Why not update to 2.5? Staying behind will only get more painful when OH3 is released.

I’m having the same problem. I just received this sensor today so it has never worked correctly. Running OH 2.5. Ran debug (grep’d to NODE 10 which is this device.) i see that it says Manufacturer data not known and that the Node XML isn’t created, which i confirm the file isn’t there on the file system. I’ve tried to wake up the device repeatedly using the directions on the Thing description page found here. I’m sure that i’m pasting these logs wrong… sorry in advance. Any and all help is appreciated thank you!

[23:52:11] openhabian@openHABianPi:~$ less /var/log/openhab2/openhab.log | grep "NODE 10"
2020-05-05 22:06:31.400 [WARN ] [wave.discovery.ZWaveDiscoveryService] - NODE 10: Device discovery could not resolve to a thingType! Manufacturer data not known.
2020-05-05 23:18:39.774 [WARN ] [wave.discovery.ZWaveDiscoveryService] - NODE 10: Device discovery could not resolve to a thingType! Manufacturer data not known.
2020-05-05 23:36:52.209 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 10: Handler disposed. Unregistering listener.
2020-05-05 23:36:52.243 [DEBUG] [l.initialization.ZWaveNodeSerializer] - NODE 10: Serialise aborted as static stages not complete
2020-05-05 23:36:52.690 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 10: Node Init transaction completed with response null
2020-05-05 23:36:52.692 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 10: Skipping initialization thread, process stopped by controller
2020-05-05 23:38:09.064 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 10: Channel zwave:device:19f9c0a5:node10:sensor_binary linked - polling started.
2020-05-05 23:38:09.064 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 10: Channel zwave:device:19f9c0a5:node10:alarm_tamper linked - polling started.
2020-05-05 23:38:09.068 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 10: Channel zwave:device:19f9c0a5:node10:sensor_door linked - polling started.
2020-05-05 23:38:09.068 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 10: Channel zwave:device:19f9c0a5:node10:battery-level linked - polling started.
2020-05-05 23:38:17.502 [DEBUG] [age.SerialApiGetInitDataMessageClass] - NODE 10: Node found
2020-05-05 23:38:17.562 [DEBUG] [ve.internal.protocol.ZWaveController] - NODE 10: Init node thread start
2020-05-05 23:38:17.941 [DEBUG] [l.initialization.ZWaveNodeSerializer] - NODE 10: Serializing from file /var/lib/openhab2/zwave/network_d3dc1078__node_10.xml
2020-05-05 23:38:17.956 [DEBUG] [l.initialization.ZWaveNodeSerializer] - NODE 10: Error serializing from file: file does not exist.
2020-05-05 23:38:18.002 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 10: Starting initialisation from EMPTYNODE
2020-05-05 23:38:18.034 [DEBUG] [ve.internal.protocol.ZWaveController] - NODE 10: Init node thread finished
2020-05-05 23:38:18.037 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 10: Node advancer - advancing to IDENTIFY_NODE
2020-05-05 23:38:18.054 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 10: Node advancer: Initialisation starting
2020-05-05 23:38:18.379 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 10: ProtocolInfo
2020-05-05 23:38:18.381 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 10: Listening = false
2020-05-05 23:38:18.383 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 10: Routing   = true
2020-05-05 23:38:18.386 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 10: Beaming   = true
2020-05-05 23:38:18.388 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 10: Version   = 4
2020-05-05 23:38:18.390 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 10: FLIRS     = false
2020-05-05 23:38:18.392 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 10: Security  = false
2020-05-05 23:38:18.395 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 10: Max Baud  = 40000
2020-05-05 23:38:18.397 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 10: Basic    = BASIC_TYPE_ROUTING_SLAVE
2020-05-05 23:38:18.399 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 10: Generic  = GENERIC_TYPE_SENSOR_NOTIFICATION
2020-05-05 23:38:18.401 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 10: Specific = SPECIFIC_TYPE_NOTIFICATION_SENSOR
2020-05-05 23:38:18.403 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 10: Creating new instance of command class COMMAND_CLASS_NO_OPERATION
2020-05-05 23:38:18.406 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 10: Command class COMMAND_CLASS_NO_OPERATION, endpoint 0 created
2020-05-05 23:38:18.408 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 10: Version = 1, version set. Enabling extra functionality.
2020-05-05 23:38:18.411 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 10: Adding command class COMMAND_CLASS_NO_OPERATION to the list of supported command classes.
2020-05-05 23:38:18.413 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 10: Creating new instance of command class COMMAND_CLASS_BASIC
2020-05-05 23:38:18.415 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 10: Command class COMMAND_CLASS_BASIC, endpoint 0 created
2020-05-05 23:38:18.418 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 10: Adding command class COMMAND_CLASS_BASIC to the list of supported command classes.
2020-05-05 23:38:18.433 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 10: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@16c181f
2020-05-05 23:38:18.435 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 10: Node Init transaction completed with response COMPLETE
2020-05-05 23:38:18.436 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 10: Node advancer - advancing to REQUEST_NIF
2020-05-05 23:38:18.438 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 10: sendTransaction org.openhab.binding.zwave.internal.protocol.ZWaveSerialPayload@6e8496
2020-05-05 23:38:18.440 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 10: Bump transaction 13 priority from Controller to Immediate
2020-05-05 23:38:18.447 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 10: Adding to device queue
2020-05-05 23:38:18.448 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 10: Added 13 to queue - size 3
2020-05-05 23:38:23.420 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 10: Controller status changed to ONLINE.
2020-05-05 23:38:23.422 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 10: Controller is ONLINE. Starting device initialisation.
2020-05-05 23:38:23.430 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 10: Updating node properties.
2020-05-05 23:38:23.433 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 10: Updating node properties. MAN=2147483647
2020-05-05 23:38:23.438 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 10: Properties synchronised
2020-05-05 23:38:23.442 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 10: Initialising Thing Node...
2020-05-05 23:38:23.446 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 10: Initialising cmd channel zwave:device:19f9c0a5:node10:sensor_binary for OnOffType
2020-05-05 23:38:23.448 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 10: Initialising state channel zwave:device:19f9c0a5:node10:sensor_binary for OnOffType
2020-05-05 23:38:23.461 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 10: Initialising cmd channel zwave:device:19f9c0a5:node10:alarm_tamper for OnOffType
2020-05-05 23:38:23.463 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 10: Initialising state channel zwave:device:19f9c0a5:node10:alarm_tamper for OnOffType
2020-05-05 23:38:23.470 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 10: Initialising cmd channel zwave:device:19f9c0a5:node10:sensor_door for OpenClosedType
2020-05-05 23:38:23.472 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 10: Initialising state channel zwave:device:19f9c0a5:node10:sensor_door for OpenClosedType
2020-05-05 23:38:23.479 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 10: Initialising cmd channel zwave:device:19f9c0a5:node10:battery-level for PercentType
2020-05-05 23:38:23.483 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 10: Initialising state channel zwave:device:19f9c0a5:node10:battery-level for PercentType
2020-05-05 23:38:23.487 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 10: Polling initialised at 86400 seconds - start in 33696000 milliseconds.
2020-05-05 23:38:23.489 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 10: Device initialisation complete.

try downgrade to 2.4, binding the sensor, upgrade to 2.5.
It works for me.

I am running OH 2.5.
I just downgrade to 2.4 in order to install the sensor binding, than upgrade back to 2.5

While it’s a little hard to be sure from this log, it looks like the device has not been discovered yet. There is no device information available, and until the binding receives this from the device it will not be able to do anything.

I guess this is a battery device, and you should wake it up so that the binding can discovery it fully and then configure it.

1 Like

It may also help to press the button 3x times quickly (LED will flash than instead of a one-time-blink). Actually 3x press is for in-/exclusion, but if the controller is not in in-/exclusion mode nothing will happen regarding that, but might help to initiate data transfer.

Also give it some time between experiments with trying to wake it up.
For the 1x click at least 30 seconds, for the 3x click at least a minute.

Removing the battery is also an option - give the sensor then 15 seconds (I believe) to start up.

I have 7 of these, and sometimes they included & transferred instantly, and something it was a longer process with many tries.

Also, when it was very rebellious to recognise, restarting OH can help - again, give OH then some time to fully startup - when the messaging frequency in the controller frame count reduces, try the usual one-click method to wake the sensor.

Once included and configured, the sensors are very reliable in operation, the hardware is very good, despite the low price.

Edit: Also read here: Neo Coolcam PIR motion sensor (PD03Z) - Wakeup issue
Edit: Just in case: The 1x click must not be too short. Something like a 0.1-second-click does nothing, but a 0.3-second-click works. If you don’t see the LED blink one time, it was too short pressed.

1 Like

Hi,
i have the same Problem with two new Neo Coolcam NAS-PD01Z.
Both Devices I can include in the OpenHAB Z-Wave network and Openhab will identify them correctly.
However if I Wakeup the Devices, I see nothing in the OpenHAB Z-Wave (Debug) log for a Wakeup…
Also I can’t change any settings like Assoziation Group etc., because the devices send no Wakeup Notivication to the controller? In Paper UI there is a point to Setup „Wakeup Configuration“ - „WakeupNode“. This is empty after the Inclution.
In the Userdata/zwave folder also no xml files created.
The Devices will blink red if they notivacate any motion, however send nothing to the Controller.
I already thry 3x click, without any luck.

Platform information:

  • Hardware: Raspberry Pi 4 Model B Rev 1.2 - 4GB
  • OS: Openhabian - Raspbian GNU/Linux 10 (buster)
  • Java Runtime Environment: openjdk version “1.8.0_222”
  • openHAB version: 2.5.4-1
  • Z-Wave binding: 2.5.5.202005041107

Different device so different issue with similar symptoms.
Please start a new thread to avoid dealing with multiple problems in the same thread.
The volunteers here are easily confused.

2 Likes

thanks Cris i will continue to try and give it some more time today. Have any of these, in your experience, taken more than a day to get discovered?

I found that clicking the button 3x will force awake up. So that is good. I see in the log that it shows node10 is updated but there still isn’t a xml for this node in the $OPENHAB_USERDATA/zwave folder. I’ve done this every few minutes for about an hour.

2020-05-06 09:27:47.104 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 15 00 49 84 0A 0F 04 07 01 5E 86 72 5A 73 80 71 30 85 59 84 70 4A 
2020-05-06 09:27:47.128 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationUpdate[73], type=Request[0], dest=10, callback=132, payload=84 0A 0F 04 07 01 5E 86 72 5A 73 80 71 30 85 59 84 70 
2020-05-06 09:27:47.134 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationUpdate[73], type=Request[0], dest=10, callback=132, payload=84 0A 0F 04 07 01 5E 86 72 5A 73 80 71 30 85 59 84 70 
2020-05-06 09:27:47.137 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-05-06 09:27:47.140 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 0
2020-05-06 09:27:47.142 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: null
2020-05-06 09:27:47.148 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=ApplicationUpdate[73], type=Request[0], dest=10, callback=132, payload=84 0A 0F 04 07 01 5E 86 72 5A 73 80 71 30 85 59 84 70 
2020-05-06 09:27:47.151 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 10: Application update request. Node information received. Transaction null
2020-05-06 09:27:47.154 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 10: Application update - no transaction.
2020-05-06 09:27:47.157 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-05-06 09:27:47.160 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-05-06 09:27:47.164 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 0A 03 86 13 73 25 7C 50 
2020-05-06 09:27:47.169 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 10: Sending REQUEST Message = 01 0A 00 13 0A 03 86 13 73 25 7C 50 
2020-05-06 09:27:47.172 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2020-05-06 09:27:47.174 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2020-05-06 09:27:47.175 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 144: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 124
2020-05-06 09:27:47.178 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-05-06 09:27:47.181 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-05-06 09:27:47.184 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 144: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 124
2020-05-06 09:27:47.186 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
2020-05-06 09:27:47.186 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2020-05-06 09:27:47.189 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-05-06 09:27:47.192 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-05-06 09:27:47.192 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2020-05-06 09:27:47.194 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2020-05-06 09:27:47.195 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 144: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 124
2020-05-06 09:27:47.196 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-05-06 09:27:47.198 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 144: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 124
2020-05-06 09:27:47.200 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2020-05-06 09:27:47.201 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 7C 00 00 02 95 
2020-05-06 09:27:47.201 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 10: sentData successfully placed on stack.
2020-05-06 09:27:47.203 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 144: Advanced to WAIT_REQUEST
2020-05-06 09:27:47.205 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 10: TID 144: Transaction not completed
2020-05-06 09:27:47.205 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=124, payload=7C 00 00 02 
2020-05-06 09:27:47.206 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-05-06 09:27:47.208 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-05-06 09:27:47.210 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=124, payload=7C 00 00 02 
2020-05-06 09:27:47.211 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 144: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 124
2020-05-06 09:27:47.213 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0A 00 04 00 0A 04 86 14 73 01 1F 
2020-05-06 09:27:47.213 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-05-06 09:27:47.215 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 144: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 124
2020-05-06 09:27:47.216 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 144: (Callback 124)
2020-05-06 09:27:47.218 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=10, callback=0, payload=00 0A 04 86 14 73 01 
2020-05-06 09:27:47.218 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2020-05-06 09:27:47.220 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 144: callback 124
2020-05-06 09:27:47.222 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=124, payload=7C 00 00 02 
2020-05-06 09:27:47.223 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 10: SendData Request. CallBack ID = 124, Status = Transmission complete and ACK received(0)
2020-05-06 09:27:47.225 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 144: Advanced to WAIT_DATA
2020-05-06 09:27:47.226 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 10: TID 144: Transaction not completed
2020-05-06 09:27:47.228 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=10, callback=0, payload=00 0A 04 86 14 73 01 
2020-05-06 09:27:47.230 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-05-06 09:27:47.231 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 10: Application Command Request (ALIVE:VERSION)
2020-05-06 09:27:47.233 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 10: Incoming command class COMMAND_CLASS_VERSION, endpoint 0
2020-05-06 09:27:47.234 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 10: SECURITY not supported
2020-05-06 09:27:47.236 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 10: Received COMMAND_CLASS_VERSION V0 VERSION_COMMAND_CLASS_REPORT
2020-05-06 09:27:47.237 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 10: Process Version Command Class Report
2020-05-06 09:27:47.239 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 10: Requested Command Class = COMMAND_CLASS_POWERLEVEL, Version = 1
2020-05-06 09:27:47.241 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 10: Version = 1, version set. Enabling extra functionality.
2020-05-06 09:27:47.242 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 10: Commands processed 1.
2020-05-06 09:27:47.244 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 10: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@15be65f.
2020-05-06 09:27:47.245 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 10: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@15be65f.
2020-05-06 09:27:47.247 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 10: notifyTransactionResponse TID:144 DONE
2020-05-06 09:27:47.249 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 10: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2020-05-06 09:27:47.249 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 144: Transaction event listener: DONE: DONE -> 
2020-05-06 09:27:47.250 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 10: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@23472f
2020-05-06 09:27:47.250 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
2020-05-06 09:27:47.252 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-05-06 09:27:47.252 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 10: Node Init transaction completed with response COMPLETE
2020-05-06 09:27:47.254 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 10: Node advancer: VERSION - checking COMMAND_CLASS_ASSOCIATION_GRP_INFO, version is 0
2020-05-06 09:27:47.254 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-05-06 09:27:47.255 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-05-06 09:27:47.255 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 10: Node advancer: VERSION - queued   COMMAND_CLASS_ASSOCIATION_GRP_INFO
2020-05-06 09:27:47.257 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 10: Creating new message for application command VERSION_COMMAND_CLASS_GET command class COMMAND_CLASS_ASSOCIATION_GRP_INFO
2020-05-06 09:27:47.258 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 0A 02 84 08 25 7D 39 
2020-05-06 09:27:47.259 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 10: ZWaveCommandClassTransactionPayload - send to node
2020-05-06 09:27:47.261 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 10: SECURITY not supported
2020-05-06 09:27:47.261 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 10: Sending REQUEST Message = 01 09 00 13 0A 02 84 08 25 7D 39 
2020-05-06 09:27:47.263 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2020-05-06 09:27:47.263 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 10: Command Class COMMAND_CLASS_VERSION is NOT required to be secured
2020-05-06 09:27:47.294 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2020-05-06 09:27:47.264 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 10: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@2384e7
2020-05-06 09:27:47.296 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 10: Bump transaction 146 priority from Config to Immediate
2020-05-06 09:27:47.264 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 145: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 125
2020-05-06 09:27:47.299 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 10: Adding to device queue
2020-05-06 09:27:47.301 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 10: Added 146 to queue - size 3
2020-05-06 09:27:47.299 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-05-06 09:27:47.304 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-05-06 09:27:47.305 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-05-06 09:27:47.305 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
2020-05-06 09:27:47.307 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 145: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 125
2020-05-06 09:27:47.308 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2020-05-06 09:27:47.308 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2020-05-06 09:27:47.310 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-05-06 09:27:47.312 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-05-06 09:27:47.312 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 7D 00 00 02 94 
2020-05-06 09:27:47.313 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2020-05-06 09:27:47.315 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 145: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 125
2020-05-06 09:27:47.316 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=125, payload=7D 00 00 02 
2020-05-06 09:27:47.317 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-05-06 09:27:47.318 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 145: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 125
2020-05-06 09:27:47.320 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2020-05-06 09:27:47.322 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 10: sentData successfully placed on stack.
2020-05-06 09:27:47.324 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 145: Advanced to WAIT_REQUEST
2020-05-06 09:27:47.325 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 10: TID 145: Transaction not completed
2020-05-06 09:27:47.327 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=125, payload=7D 00 00 02 
2020-05-06 09:27:47.329 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 145: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 125
2020-05-06 09:27:47.331 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-05-06 09:27:47.333 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 145: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 125
2020-05-06 09:27:47.335 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 145: (Callback 125)
2020-05-06 09:27:47.337 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2020-05-06 09:27:47.338 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 145: callback 125
2020-05-06 09:27:47.340 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=125, payload=7D 00 00 02 
2020-05-06 09:27:47.342 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 10: SendData Request. CallBack ID = 125, Status = Transmission complete and ACK received(0)
2020-05-06 09:27:47.344 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 145: Transaction COMPLETED
2020-05-06 09:27:47.346 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 10: Response processed after 82ms
2020-05-06 09:27:47.347 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 10: TID 145: Transaction completed
2020-05-06 09:27:47.349 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 10: notifyTransactionResponse TID:145 DONE
2020-05-06 09:27:47.350 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 10: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2020-05-06 09:27:47.350 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 145: Transaction event listener: DONE: DONE -> 
2020-05-06 09:27:47.352 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-05-06 09:27:47.353 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 10: Went to sleep COMPLETE
2020-05-06 09:27:47.354 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-05-06 09:27:47.407 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 10: Is awake with 1 messages in the queue
2020-05-06 09:27:47.409 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 10: Start sleep timer at 5000ms
2020-05-06 09:27:47.411 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 10: Got an event from Z-Wave network: ZWaveNodeStatusEvent

==> /var/log/openhab2/events.log <==
2020-05-06 09:27:47.428 [me.event.ThingUpdatedEvent] - Thing 'zwave:device:19f9c0a5:node10' has been updated.

==> /var/log/openhab2/openhab.log <==
2020-05-06 09:27:47.430 [DEBUG] [ve.internal.protocol.ZWaveController] - NODE 10: Node Status event - Node is AWAKE
2020-05-06 09:27:49.911 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 10: WakeupTimerTask 1 Messages waiting, state VERSION
2020-05-06 09:27:52.412 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 10: WakeupTimerTask 1 Messages waiting, state VERSION
2020-05-06 09:27:52.415 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 10: No more messages, go back to sleep
2020-05-06 09:27:52.417 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 10: Creating new message for application command WAKE_UP_NO_MORE_INFORMATION
2020-05-06 09:27:52.420 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 10: SECURITY not supported
2020-05-06 09:27:52.422 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 10: Command Class COMMAND_CLASS_WAKE_UP is NOT required to be secured
2020-05-06 09:27:52.425 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 10: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@11abfda
2020-05-06 09:27:52.428 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 10: Bump transaction 147 priority from Immediate to Immediate
2020-05-06 09:27:52.431 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 10: Adding to device queue
2020-05-06 09:27:52.434 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 10: Added 147 to queue - size 4
2020-05-06 09:27:52.436 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-05-06 09:27:52.441 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 0A 03 86 13 59 25 7E 78 
2020-05-06 09:27:52.444 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 10: Sending REQUEST Message = 01 0A 00 13 0A 03 86 13 59 25 7E 78 
2020-05-06 09:27:52.447 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2020-05-06 09:27:52.450 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 146: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 126
2020-05-06 09:27:52.451 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2020-05-06 09:27:52.456 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-05-06 09:27:52.460 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-05-06 09:27:52.463 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 146: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 126
2020-05-06 09:27:52.463 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
2020-05-06 09:27:52.466 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2020-05-06 09:27:52.468 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2020-05-06 09:27:52.469 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-05-06 09:27:52.472 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-05-06 09:27:52.476 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2020-05-06 09:27:52.479 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 146: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 126
2020-05-06 09:27:52.483 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-05-06 09:27:52.486 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 146: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 126
2020-05-06 09:27:52.490 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2020-05-06 09:27:52.493 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 10: sentData successfully placed on stack.
2020-05-06 09:27:52.496 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 146: Advanced to WAIT_REQUEST
2020-05-06 09:27:52.500 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 10: TID 146: Transaction not completed
2020-05-06 09:27:52.503 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-05-06 09:27:52.505 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-05-06 09:27:56.663 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 7E 01 01 A5 30 
2020-05-06 09:27:56.669 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=1, callback=126, payload=7E 01 01 A5 
2020-05-06 09:27:56.672 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=1, callback=126, payload=7E 01 01 A5 
2020-05-06 09:27:56.677 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 146: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 126
2020-05-06 09:27:56.680 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-05-06 09:27:56.683 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 146: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 126
2020-05-06 09:27:56.687 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 146: (Callback 126)
2020-05-06 09:27:56.690 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2020-05-06 09:27:56.693 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 146: callback 126
2020-05-06 09:27:56.697 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=1, callback=126, payload=7E 01 01 A5 
2020-05-06 09:27:56.701 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 10: SendData Request. CallBack ID = 126, Status = Transmission complete, no ACK received(1)
2020-05-06 09:27:56.704 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 146: Transaction CANCELLED
2020-05-06 09:27:56.708 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 10: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2020-05-06 09:27:56.711 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 10: Transaction failed waiting for REQUEST, assume sleeping device.
2020-05-06 09:27:56.714 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 10: CANCEL while sending message. Requeueing - 2 attempts left!
2020-05-06 09:27:56.718 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 146: Transaction RESET with 2 retries remaining.
2020-05-06 09:27:56.721 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 10: Adding to device queue
2020-05-06 09:27:56.725 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 10: Added 146 to queue - size 4
2020-05-06 09:27:56.729 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-05-06 09:27:56.732 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 10: TID 146: Transaction not completed
2020-05-06 09:27:56.735 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-05-06 09:27:56.739 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
1 Like

Alright you cool cats and kittens (feel free to flag me for using that Tiger King reference, i understand), i was able to get this to work! Here is what I did and what i learned.

  1. The .3 second press of the button that makes the LED light up 1x does nothing as far as I can tell from the debug logs.
  2. 3x quick presses wakes the device up. From the logs i could see a sleep timer starting with 5000ms (5 seconds).
  3. I would about every few seconds or so press the button 3x to try to keep it awake and eventually (after maybe 2-3min) it started working.
  4. I do have a node10.xml in the $OPENHAB_USERDATA/zwave folder so i think the device has now been fully discovered.
  5. I’m able to see the open/close contact events in the debug log and in the PaperUI.

Thank you all for your help!

2 Likes

No, some combinations of the described method always worked, although it took some (a lot :wink: ) patience sometimes.

If you see it was recognized in PaperUI, then all is good. The name is then something like “Motion Sensor NAS-DS01Z blah blah”, and you have then access to the Thing’s Settings…

The game to update the Thing’s Settings is then the same… Sometimes instantly, sometimes some persuasion is required.

At which point the XML is written, I have never cared about. If you logout out of OH, it should be written at the latest, I think. @chris could say more about how the binding it handles, but I think it’s of no practical use to know it.

Haha, I just realized that we talk about a door sensor. I was thinking it’s about the motion sensor, which is NAS-PD01Z.

I run and catch our lost Harald back to this thread… :smiley:
Obviously the methods work on both devices. Probably on all Coolcam stuff, as the core electronic is probably the same…

Which is absolutely correct. The XML is really managed by the binding and shouldn’t be something that anyone should worry about.

Not necessarily. The XML is written when there is information to write. If the binding hasn’t received information about the device, then it won’t write the file. The XML gets written at a couple of points during discovery - once initialisation is complete and it has all the information it needs from the device, then yes, it will normally always be written when the binding closes.

I hope that helps.

1 Like

Hi, really fast 3x click Wake Up the device, great!
:star_struck:

If this is not already written in the database, it would be great if someone could do an update for any devices for which this is relevant so that it goes through into the documentation and we don’t lose this information in the “fog of the forum” :slight_smile:

2 Likes

I have update the „NAS-PD01Z“ Device in the Database!

3 Likes

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