OH2 Z-Wave refactoring and testing... and SECURITY

[Kim_Andersen]

In message 3944 I wrote what I did.
The new binding re-discovered everything, including the controller. I simply changed my item file to suit this new controller (new channel ID´s).

These are my items file:

Switch  ZWaveNode5ZW100MultiSensor6_BinarySensor 	"Multisensor6 PIR Binary [%s]"  							<cum_motion>  	(gMotion)		{channel="zwave:device:512:node5:sensor_binary"}
Switch  ZWaveNode5ZW100MultiSensor6_MotionAlarm 	"Multisensor6 PIR Alarm [%s]"  								<cum_motion>  	(gMotion)		{channel="zwave:device:512:node5:alarm_motion"}
Switch 	Node13_BinarySensor 				"Neo Coolcam PIR Binary Sensor [%s]" 							<cum_motion> 	(gMotion) 		{channel="zwave:device:512:node13:sensor_binary"}
Switch 	Node13_AlarmBurglar				"Neo Coolcam PIR Alarm (burglar) [%s]"							<cum_motion>	(gMotion)		{channel="zwave:device:512:node13:alarm_burglar"}
Switch 	Node10_BinarySensor 				"Vision PIR Binary Sensor [%s]" 							<cum_motion> 	(gMotion) 		{channel="zwave:device:512:node10:sensor_binary"}
Switch 	Node10_AlarmBurglar				"Vision PIR Alarm (burglar) [%s]"							<cum_motion>	(gMotion)		{channel="zwave:device:512:node10:alarm_burglar"}

Since the controller got an new ID, I found it easyer to just rename the channels ID (controller ID) rather than to rename all my items.

[rsmili (Razor)]

Continuing the discussion from OH2 Z-Wave refactoring and testing… and SECURITY:

hi @chris

I got a zwave network with a zwave.me, it was working without any issues before. but suddenly after a restart of the raspberry pi, it stoped working properly. sometimes it works after few minutes of changing the state in openhab. and other times it’s not. I am bit lost. Any help is appreciated

2018-08-24 09:02:39.773 [WARN ] [essage.ApplicationUpdateMessageClass] - TODO: Implement Application Update Request Handling of New ID Assigned (64).

2018-08-24 09:05:42.585 [INFO ] [.eclipse.smarthome.model.script.info] - PIR1_Outside_Entry_Sensor 1 ON

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

2018-08-24 09:05:42.589 [vent.ItemStateChangedEvent] - light_sensor1 changed from OFF to ON

2018-08-24 09:05:42.616 [ome.event.ItemCommandEvent] - Item 'light_sensor1' received command OFF

2018-08-24 09:05:42.622 [vent.ItemStateChangedEvent] - light_sensor1 changed from ON to OFF

2018-08-24 09:05:46.647 [vent.ItemStateChangedEvent] - light_sensor1 changed from OFF to ON

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

2018-08-24 09:05:46.655 [INFO ] [.eclipse.smarthome.model.script.info] - PIR1_Outside_Entry_Sensor 1 ON

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

2018-08-24 09:05:46.662 [ome.event.ItemCommandEvent] - Item 'light_sensor1' received command OFF

2018-08-24 09:05:46.679 [vent.ItemStateChangedEvent] - light_sensor1 changed from ON to OFF

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

2018-08-24 09:10:07.005 [ERROR] [lmessage.RequestNodeInfoMessageClass] - Request node info not placed on stack due to error.

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

2018-08-24 09:10:07.029 [vent.ItemStateChangedEvent] - ZWavePlusUSBDongle_FramesAcknowledged changed from 1205 to 1206

2018-08-24 09:10:07.035 [vent.ItemStateChangedEvent] - ZWavePlusUSBDongle_StartFrames changed from 2407 to 2408

2018-08-24 09:10:07.040 [vent.ItemStateChangedEvent] - ZWavePlusUSBDongle_FramesCancelled changed from 493 to 494

2018-08-24 09:10:07.047 [vent.ItemStateChangedEvent] - ZWavePlusUSBDongle_FramesCancelled changed from 494 to 495

2018-08-24 09:10:07.056 [vent.ItemStateChangedEvent] - ZWavePlusUSBDongle_FramesCancelled changed from 495 to 496

2018-08-24 09:10:07.079 [vent.ItemStateChangedEvent] - ZWavePlusUSBDongle_FramesCancelled changed from 496 to 497

2018-08-24 09:10:07.082 [vent.ItemStateChangedEvent] - ZWavePlusUSBDongle_FramesCancelled changed from 497 to 498

2018-08-24 09:10:07.086 [vent.ItemStateChangedEvent] - ZWavePlusUSBDongle_FramesCancelled changed from 498 to 499

2018-08-24 09:10:07.090 [vent.ItemStateChangedEvent] - ZWavePlusUSBDongle_FramesCancelled changed from 499 to 500

2018-08-24 09:10:07.095 [vent.ItemStateChangedEvent] - ZWavePlusUSBDongle_FramesCancelled changed from 500 to 501

2018-08-24 09:10:07.099 [vent.ItemStateChangedEvent] - ZWavePlusUSBDongle_FramesCancelled changed from 501 to 502

2018-08-24 09:10:07.103 [vent.ItemStateChangedEvent] - ZWavePlusUSBDongle_FramesAcknowledged changed from 1206 to 1207

2018-08-24 09:10:07.107 [vent.ItemStateChangedEvent] - ZWavePlusUSBDongle_StartFrames changed from 2408 to 2409

2018-08-24 09:10:07.110 [vent.ItemStateChangedEvent] - ZWavePlusUSBDongle_FramesCancelled changed from 502 to 503

2018-08-24 09:10:07.114 [vent.ItemStateChangedEvent] - ZWavePlusUSBDongle_FramesCancelled changed from 503 to 504

2018-08-24 09:10:07.117 [vent.ItemStateChangedEvent] - ZWavePlusUSBDongle_FramesCancelled changed from 504 to 505

2018-08-24 09:10:07.121 [arthome.event.BindingEvent] - org.openhab.binding.zwave.event.BindingEvent@21a6ef

2018-08-24 09:10:36.823 [vent.ItemStateChangedEvent] - light_sensor1 changed from OFF to ON

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

2018-08-24 09:10:36.832 [INFO ] [.eclipse.smarthome.model.script.info] - PIR1_Outside_Entry_Sensor 1 ON

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

2018-08-24 09:10:36.840 [ome.event.ItemCommandEvent] - Item 'light_sensor1' received command OFF

2018-08-24 09:10:36.853 [vent.ItemStateChangedEvent] - light_sensor1 changed from ON to OFF

2018-08-24 09:12:26.285 [vent.ItemStateChangedEvent] - ZWavePlusUSBDongle_FramesAcknowledged changed from 1207 to 1208

2018-08-24 09:12:26.300 [vent.ItemStateChangedEvent] - ZWavePlusUSBDongle_StartFrames changed from 2409 to 2410

2018-08-24 09:12:26.306 [vent.ItemStateChangedEvent] - ZWavePlusUSBDongle_FramesCancelled changed from 505 to 506

2018-08-24 09:12:26.317 [vent.ItemStateChangedEvent] - ZWavePlusUSBDongle_FramesCancelled changed from 506 to 507

2018-08-24 09:12:26.332 [vent.ItemStateChangedEvent] - ZWavePlusUSBDongle_FramesCancelled changed from 507 to 508

2018-08-24 09:12:26.335 [vent.ItemStateChangedEvent] - ZWavePlusUSBDongle_FramesCancelled changed from 508 to 509

2018-08-24 09:12:26.338 [vent.ItemStateChangedEvent] - ZWavePlusUSBDongle_FramesCancelled changed from 509 to 510

2018-08-24 09:12:26.342 [vent.ItemStateChangedEvent] - ZWavePlusUSBDongle_FramesCancelled changed from 510 to 511

2018-08-24 09:14:16.793 [vent.ItemStateChangedEvent] - light_sensor1 changed from OFF to ON

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

2018-08-24 09:14:16.800 [INFO ] [.eclipse.smarthome.model.script.info] - PIR1_Outside_Entry_Sensor 1 ON

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

2018-08-24 09:14:16.807 [ome.event.ItemCommandEvent] - Item 'light_sensor1' received command OFF

2018-08-24 09:14:16.816 [vent.ItemStateChangedEvent] - light_sensor1 changed from ON to OFF

2018-08-24 09:14:30.284 [vent.ItemStateChangedEvent] - light_sensor1 changed from OFF to ON

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

2018-08-24 09:14:30.295 [INFO ] [.eclipse.smarthome.model.script.info] - PIR1_Outside_Entry_Sensor 1 ON

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

2018-08-24 09:14:30.311 [ome.event.ItemCommandEvent] - Item 'light_sensor1' received command OFF

2018-08-24 09:14:30.356 [vent.ItemStateChangedEvent] - light_sensor1 changed from ON to OFF

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

2018-08-24 09:15:08.976 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 37: sendTransaction org.openhab.binding.zwave.internal.protocol.ZWaveSerialPayload@56f011

2018-08-24 09:15:08.979 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 37: Adding to device queue

2018-08-24 09:15:08.982 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 37: Added to queue - size 1

2018-08-24 09:15:08.983 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 37: listening == true, frequentlyListening == false, awake == false

2018-08-24 09:15:08.986 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 48 25 47 D0 

2018-08-24 09:15:08.988 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 48 25 47 D0 

2018-08-24 09:15:08.989 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT

2018-08-24 09:15:08.991 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 1516: Transaction Start type RequestNodeNeighborUpdate 

2018-08-24 09:15:13.993 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 37: TID 1516: Timeout at state WAIT_REQUEST. 3 retries remaining.

2018-08-24 09:15:13.997 [DEBUG] [sactionManager$ZWaveTransactionTimer] - TID 1516: Transaction is current transaction, so clearing!!!!!

2018-08-24 09:15:14.001 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 1516: Transaction CANCELLED

2018-08-24 09:15:14.005 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 37: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent

2018-08-24 09:15:14.010 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 37: notifyTransactionResponse TID:1516 CANCELLED

2018-08-24 09:15:14.015 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 1516: Transaction event listener: DONE: CANCELLED -> 

2018-08-24 09:15:14.019 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 37: -- To notify -- TIMEOUT_WAITING_FOR_RESPONSE

2018-08-24 09:15:14.022 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ********* Transaction Response Complete -- 1516 --

2018-08-24 09:15:14.025 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 37: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@ccc81e

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

2018-08-24 09:16:26.701 [vent.ItemStateChangedEvent] - light_sensor1 changed from OFF to ON

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

2018-08-24 09:16:26.730 [INFO ] [.eclipse.smarthome.model.script.info] - PIR1_Outside_Entry_Sensor 1 ON

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

2018-08-24 09:16:26.750 [ome.event.ItemCommandEvent] - Item 'light_sensor1' received command OFF

2018-08-24 09:16:26.762 [vent.ItemStateChangedEvent] - light_sensor1 changed from ON to OFF

2018-08-24 09:16:30.197 [vent.ItemStateChangedEvent] - light_sensor1 changed from OFF to ON

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

2018-08-24 09:16:30.207 [INFO ] [.eclipse.smarthome.model.script.info] - PIR1_Outside_Entry_Sensor 1 ON

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

2018-08-24 09:16:30.226 [ome.event.ItemCommandEvent] - Item 'light_sensor1' received command OFF

2018-08-24 09:16:30.243 [vent.ItemStateChangedEvent] - light_sensor1 changed from ON to OFF

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

2018-08-24 09:18:25.608 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 22: sendTransaction org.openhab.binding.zwave.internal.protocol.ZWaveSerialPayload@24fc07

2018-08-24 09:18:25.613 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 22: Adding to device queue

2018-08-24 09:18:25.617 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 22: Added to queue - size 1

2018-08-24 09:18:25.620 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 22: listening == true, frequentlyListening == false, awake == false

2018-08-24 09:18:25.624 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 04 00 60 16 8D 

2018-08-24 09:18:25.628 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 04 00 60 16 8D 

2018-08-24 09:18:25.631 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT

2018-08-24 09:18:25.634 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 1517: Transaction Start type RequestNodeInfo 

2018-08-24 09:18:27.636 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 22: TID 1517: Timeout at state WAIT_RESPONSE. 3 retries remaining.

2018-08-24 09:18:27.638 [DEBUG] [sactionManager$ZWaveTransactionTimer] - TID 1517: Transaction is current transaction, so clearing!!!!!

2018-08-24 09:18:27.640 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 1517: Transaction CANCELLED

2018-08-24 09:18:27.642 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 22: notifyTransactionResponse TID:1517 CANCELLED

2018-08-24 09:18:27.644 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 1517: Transaction event listener: DONE: CANCELLED -> 

2018-08-24 09:18:27.652 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 22: -- To notify -- TIMEOUT_WAITING_FOR_CONTROLLER

2018-08-24 09:18:27.655 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ********* Transaction Response Complete -- 1517 --

2018-08-24 09:18:27.657 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 22: Node Init response (8) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@10a8064

2018-08-24 09:18:30.897 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 21: sendTransaction org.openhab.binding.zwave.internal.protocol.ZWaveSerialPayload@17f69fa

2018-08-24 09:18:30.901 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 21: Adding to device queue

2018-08-24 09:18:30.904 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 21: Added to queue - size 1

2018-08-24 09:18:30.908 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 21: listening == true, frequentlyListening == false, awake == false

2018-08-24 09:18:30.912 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 04 00 60 15 8E 

2018-08-24 09:18:30.917 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 04 00 60 15 8E 

2018-08-24 09:18:30.921 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT

2018-08-24 09:18:30.925 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 1518: Transaction Start type RequestNodeInfo 

2018-08-24 09:18:32.929 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 21: TID 1518: Timeout at state WAIT_RESPONSE. 3 retries remaining.

2018-08-24 09:18:32.932 [DEBUG] [sactionManager$ZWaveTransactionTimer] - TID 1518: Transaction is current transaction, so clearing!!!!!

2018-08-24 09:18:32.935 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 1518: Transaction CANCELLED

2018-08-24 09:18:32.939 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 21: notifyTransactionResponse TID:1518 CANCELLED

2018-08-24 09:18:32.943 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 1518: Transaction event listener: DONE: CANCELLED -> 

2018-08-24 09:18:32.947 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 21: -- To notify -- TIMEOUT_WAITING_FOR_CONTROLLER

2018-08-24 09:18:32.950 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ********* Transaction Response Complete -- 1518 --

2018-08-24 09:18:32.954 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 21: Node Init response (9) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@d5ef7b

2018-08-24 09:18:44.616 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 36: sendTransaction org.openhab.binding.zwave.internal.protocol.ZWaveSerialPayload@69914f

2018-08-24 09:18:44.624 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 36: Adding to device queue

2018-08-24 09:18:44.626 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 36: Added to queue - size 1

2018-08-24 09:18:44.631 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 36: listening == true, frequentlyListening == false, awake == false

2018-08-24 09:18:44.636 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 48 24 48 DE 

2018-08-24 09:18:44.641 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 48 24 48 DE 

2018-08-24 09:18:44.644 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT

2018-08-24 09:18:44.649 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 1519: Transaction Start type RequestNodeNeighborUpdate 

2018-08-24 09:18:49.655 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 36: TID 1519: Timeout at state WAIT_REQUEST. 3 retries remaining.

2018-08-24 09:18:49.656 [DEBUG] [sactionManager$ZWaveTransactionTimer] - TID 1519: Transaction is current transaction, so clearing!!!!!

2018-08-24 09:18:49.658 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 1519: Transaction CANCELLED

2018-08-24 09:18:49.660 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 36: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent

2018-08-24 09:18:49.662 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 36: notifyTransactionResponse TID:1519 CANCELLED

2018-08-24 09:18:49.665 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 1519: Transaction event listener: DONE: CANCELLED -> 

2018-08-24 09:18:49.667 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 36: -- To notify -- TIMEOUT_WAITING_FOR_RESPONSE

2018-08-24 09:18:49.670 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ********* Transaction Response Complete -- 1519 --

2018-08-24 09:18:49.672 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 36: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@f8688a

2018-08-24 09:19:39.425 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 37: sendTransaction org.openhab.binding.zwave.internal.protocol.ZWaveSerialPayload@56f011

2018-08-24 09:19:39.429 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 37: Adding to device queue

2018-08-24 09:19:39.434 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 37: Added to queue - size 1

2018-08-24 09:19:39.438 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 37: listening == true, frequentlyListening == false, awake == false

2018-08-24 09:19:39.443 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 48 25 49 DE 

2018-08-24 09:19:39.447 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 48 25 49 DE 

2018-08-24 09:19:39.450 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT

2018-08-24 09:19:39.453 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 1520: Transaction Start type RequestNodeNeighborUpdate 

2018-08-24 09:19:42.389 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06

2018-08-24 09:19:42.394 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

2018-08-24 09:19:42.400 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage past lock Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

2018-08-24 09:19:42.403 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

2018-08-24 09:19:42.407 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1520: [WAIT_REQUEST] requiresResponse=true callback: 73

2018-08-24 09:19:42.409 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK

2018-08-24 09:19:42.410 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 05 00 48 46 23 D7 

2018-08-24 09:19:42.411 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

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

2018-08-24 09:19:42.402 [vent.ItemStateChangedEvent] - ZWavePlusUSBDongle_FramesAcknowledged changed from 1208 to 1209

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

2018-08-24 09:19:42.419 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=70, payload=46 23 

2018-08-24 09:19:42.432 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage past lock Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=70, payload=46 23 

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

2018-08-24 09:19:42.428 [vent.ItemStateChangedEvent] - ZWavePlusUSBDongle_StartFrames changed from 2410 to 2411

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

2018-08-24 09:19:42.437 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=70, payload=46 23 

2018-08-24 09:19:42.440 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1520: [WAIT_REQUEST] requiresResponse=true callback: 73

2018-08-24 09:19:42.443 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 18

2018-08-24 09:19:42.450 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=CAN[4], dest=255, callback=0, payload=

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

2018-08-24 09:19:42.449 [vent.ItemStateChangedEvent] - ZWavePlusUSBDongle_FramesCancelled changed from 511 to 512

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

2018-08-24 09:19:42.454 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1

2018-08-24 09:19:42.455 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage past lock Message: class=null[0], type=CAN[4], dest=255, callback=0, payload=

2018-08-24 09:19:42.456 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 1520: [WAIT_REQUEST] requiresResponse=true callback: 73

2018-08-24 09:19:42.459 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 1520: (Callback 73)

2018-08-24 09:19:42.461 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 18

2018-08-24 09:19:42.461 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - NO callback match! (73 <> 70)

2018-08-24 09:19:42.465 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 1<>127 : Message: class=null[0], type=CAN[4], dest=255, callback=0, payload=

2018-08-24 09:19:42.471 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage past lock Message: class=null[0], type=CAN[4], dest=255, callback=0, payload=

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

2018-08-24 09:19:42.492 [vent.ItemStateChangedEvent] - ZWavePlusUSBDongle_FramesCancelled changed from 512 to 513

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

2018-08-24 09:19:42.494 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ****************** Transaction not correlated

2018-08-24 09:19:42.501 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 18

2018-08-24 09:19:42.499 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=70, payload=46 23 

2018-08-24 09:19:42.504 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 2<>126 : Message: class=null[0], type=CAN[4], dest=255, callback=0, payload=

2018-08-24 09:19:42.507 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage past lock Message: class=null[0], type=CAN[4], dest=255, callback=0, payload=

2018-08-24 09:19:42.506 [DEBUG] [equestNodeNeighborUpdateMessageClass] - NodeNeighborUpdate request without transaction

2018-08-24 09:19:42.513 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 18

2018-08-24 09:19:42.513 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (2): Message: class=null[0], type=CAN[4], dest=255, callback=0, payload=

2018-08-24 09:19:42.517 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 2<>126 : Message: class=null[0], type=CAN[4], dest=255, callback=0, payload=

2018-08-24 09:19:42.517 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1520: [WAIT_REQUEST] requiresResponse=true callback: 73

2018-08-24 09:19:42.520 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage past lock Message: class=null[0], type=CAN[4], dest=255, callback=0, payload=

2018-08-24 09:19:42.526 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 18

2018-08-24 09:19:42.530 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 3<>125 : Message: class=null[0], type=CAN[4], dest=255, callback=0, payload=

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

2018-08-24 09:19:42.530 [vent.ItemStateChangedEvent] - ZWavePlusUSBDongle_FramesCancelled changed from 513 to 514

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

2018-08-24 09:19:42.533 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage past lock Message: class=null[0], type=CAN[4], dest=255, callback=0, payload=

2018-08-24 09:19:42.539 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 1520: Resetting transaction

2018-08-24 09:19:42.544 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 37: notifyTransactionResponse TID:1520 WAIT_REQUEST

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

2018-08-24 09:19:42.544 [vent.ItemStateChangedEvent] - ZWavePlusUSBDongle_FramesCancelled changed from 514 to 515

2018-08-24 09:19:42.548 [vent.ItemStateChangedEvent] - ZWavePlusUSBDongle_FramesCancelled changed from 515 to 516

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

2018-08-24 09:19:42.552 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 37: Adding to device queue

2018-08-24 09:19:42.556 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 1520: Transaction event listener: DONE: WAIT_REQUEST -> 

2018-08-24 09:19:42.558 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 37: Added to queue - size 1

2018-08-24 09:19:42.561 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Completing UNINTIALIZED transaction 1520!!! How?!?

2018-08-24 09:19:42.564 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (3): Message: class=null[0], type=CAN[4], dest=255, callback=0, payload=

2018-08-24 09:19:42.564 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 37: -- To notify -- CANCELLED

2018-08-24 09:19:42.566 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null

2018-08-24 09:19:42.568 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (2): Message: class=null[0], type=CAN[4], dest=255, callback=0, payload=

2018-08-24 09:19:42.570 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null

2018-08-24 09:19:42.571 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (1): Message: class=null[0], type=CAN[4], dest=255, callback=0, payload=

2018-08-24 09:19:42.571 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ********* Transaction Response Complete -- 1520 --

2018-08-24 09:19:42.573 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 37: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@1e613bc

2018-08-24 09:19:42.573 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null

2018-08-24 09:19:42.579 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=CAN[4], dest=255, callback=0, payload=

2018-08-24 09:19:42.582 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null

2018-08-24 09:19:42.584 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2018-08-24 09:19:42.587 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 37: listening == true, frequentlyListening == false, awake == false

2018-08-24 09:19:42.591 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 48 25 49 DE 

2018-08-24 09:19:42.594 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 48 25 49 DE 

2018-08-24 09:19:42.597 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT

2018-08-24 09:19:42.600 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 1520: Transaction Start type RequestNodeNeighborUpdate 

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

2018-08-24 09:19:42.695 [vent.ItemStateChangedEvent] - ZWavePlusUSBDongle_StartFrames changed from 2411 to 2412

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

2018-08-24 09:19:42.692 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 06 00 49 81 00 00 31 

2018-08-24 09:19:42.701 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationUpdate[73], type=Request[0], dest=0, callback=129, payload=81 00 00 

2018-08-24 09:19:42.703 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage past lock Message: class=ApplicationUpdate[73], type=Request[0], dest=0, callback=129, payload=81 00 00 

2018-08-24 09:19:42.705 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationUpdate[73], type=Request[0], dest=0, callback=129, payload=81 00 00 

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

2018-08-24 09:19:42.711 [vent.ItemStateChangedEvent] - ZWavePlusUSBDongle_FramesCancelled changed from 516 to 517

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

2018-08-24 09:19:42.714 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1520: [WAIT_REQUEST] requiresResponse=true callback: 73

2018-08-24 09:19:42.706 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 18

2018-08-24 09:19:42.717 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=CAN[4], dest=255, callback=0, payload=

2018-08-24 09:19:42.717 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1

2018-08-24 09:19:42.719 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage past lock Message: class=null[0], type=CAN[4], dest=255, callback=0, payload=

2018-08-24 09:19:42.720 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 1520: [WAIT_REQUEST] requiresResponse=true callback: 73

2018-08-24 09:19:42.723 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 1520: (Callback 73)

2018-08-24 09:19:42.725 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ****************** Transaction not correlated

2018-08-24 09:19:42.727 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=ApplicationUpdate[73], type=Request[0], dest=0, callback=129, payload=81 00 00 

2018-08-24 09:19:42.730 [DEBUG] [essage.ApplicationUpdateMessageClass] - Application update request. Node Info Request Failed (Unknown Node).

2018-08-24 09:19:42.732 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=CAN[4], dest=255, callback=0, payload=

2018-08-24 09:19:42.735 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1520: [WAIT_REQUEST] requiresResponse=true callback: 73

2018-08-24 09:19:42.737 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 1520: Resetting transaction

2018-08-24 09:19:42.739 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 37: notifyTransactionResponse TID:1520 WAIT_REQUEST

2018-08-24 09:19:42.741 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 37: Adding to device queue

2018-08-24 09:19:42.743 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 37: Added to queue - size 1

2018-08-24 09:19:42.746 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2018-08-24 09:19:42.748 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 37: listening == true, frequentlyListening == false, awake == false

2018-08-24 09:19:42.750 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 48 25 49 DE 

2018-08-24 09:19:42.753 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 48 25 49 DE 

2018-08-24 09:19:42.755 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT

2018-08-24 09:19:42.758 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 1520: Transaction Start type RequestNodeNeighborUpdate 

2018-08-24 09:19:42.760 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06

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

2018-08-24 09:19:42.765 [vent.ItemStateChangedEvent] - ZWavePlusUSBDongle_FramesAcknowledged changed from 1209 to 1210

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

2018-08-24 09:19:42.766 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

2018-08-24 09:19:42.769 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage past lock Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

2018-08-24 09:19:42.773 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

2018-08-24 09:19:42.776 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 05 00 48 49 23 D8 

2018-08-24 09:19:42.774 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1520: [WAIT_REQUEST] requiresResponse=true callback: 73

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

2018-08-24 09:19:42.782 [vent.ItemStateChangedEvent] - ZWavePlusUSBDongle_StartFrames changed from 2412 to 2413

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

2018-08-24 09:19:42.780 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK

2018-08-24 09:19:42.785 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2018-08-24 09:19:42.786 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=73, payload=49 23 

2018-08-24 09:19:42.791 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage past lock Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=73, payload=49 23 

[shorty707]

after upgrading to the latest build (23rd) … Zwave devices in the Inbox can not be added…
other non zwave devices can be added

:-/

nothing in the log…
habmin says nothing

paperui says error 404 not found when adding a zwave device

[chris (Chris Jackson)]

I’d suggest to look at the standard things in this situation

• is the binding really running
• is the right binding really running
• is there only one binding really running
• clear the cache
• pray to the karaf gods since they seem to control upgrades

[jwiseman (Mr. Wiseman (OH 4.2 Snapshot on Pi4))]

Hi Chris,

Thanks for ALL your work on this! I know this wasn’t an easy undertaking . . .

Your 2.4 binding as of today works on OH2.3 but it wasn’t the easiest because of the SERIAL1 binding needed for the OH2.3 version that gets uninstalled with your OLD zWave binding. You can manually re-install it but it disappears on a reboot (after cleaning cache/tmp directories before the reboot). I had to add it to the addons.cfg parameter below in order to get it to stay.

A comma-separated list of bindings to install (e.g. “binding = sonos,knx,zwave”)

binding = serial1,sonos,mail,airquality,caldav-command1,caldav-personal1,nest,network,ntp,onkyo,plex1,samsungtv,weather1,wemo

Now i get this error when I reboot because it’s not there initially but it does get installed later on the reboot. Not your issue, just want people to know.

2018-08-24 19:09:55.512 [WARN ] [org.apache.felix.fileinstall ] - Error while starting bundle: file:/volume1/homes/openhab/addons/org.openhab.binding.zwave-2.4.0-SNAPSHOT.jar
org.osgi.framework.BundleException: Could not resolve module: org.openhab.binding.zwave [193]
Unresolved requirement: Import-Package: gnu.io

at org.eclipse.osgi.container.Module.start(Module.java:444) [?:?]
at org.eclipse.osgi.internal.framework.EquinoxBundle.start(EquinoxBundle.java:383) [?:?]
at org.apache.felix.fileinstall.internal.DirectoryWatcher.startBundle(DirectoryWatcher.java:1260) [9:org.apache.felix.fileinstall:3.6.4]
at org.apache.felix.fileinstall.internal.DirectoryWatcher.startBundles(DirectoryWatcher.java:1233) [9:org.apache.felix.fileinstall:3.6.4]
at org.apache.felix.fileinstall.internal.DirectoryWatcher.startAllBundles(DirectoryWatcher.java:1221) [9:org.apache.felix.fileinstall:3.6.4]
at org.apache.felix.fileinstall.internal.DirectoryWatcher.doProcess(DirectoryWatcher.java:515) [9:org.apache.felix.fileinstall:3.6.4]
at org.apache.felix.fileinstall.internal.DirectoryWatcher.process(DirectoryWatcher.java:365) [9:org.apache.felix.fileinstall:3.6.4]
at org.apache.felix.fileinstall.internal.DirectoryWatcher.run(DirectoryWatcher.java:316) [9:org.apache.felix.fileinstall:3.6.4]

2018-08-24 19:09:55.555 [WARN ] [org.apache.felix.fileinstall ] - Error while starting bundle: file:/volume1/homes/openhab/addons/org.openhab.binding.zwave-2.4.0-SNAPSHOT.jar
org.osgi.framework.BundleException: Could not resolve module: org.openhab.binding.zwave [193]
Unresolved requirement: Import-Package: gnu.io

at org.eclipse.osgi.container.Module.start(Module.java:444) [?:?]
at org.eclipse.osgi.internal.framework.EquinoxBundle.start(EquinoxBundle.java:383) [?:?]
at org.apache.felix.fileinstall.internal.DirectoryWatcher.startBundle(DirectoryWatcher.java:1260) [9:org.apache.felix.fileinstall:3.6.4]
at org.apache.felix.fileinstall.internal.DirectoryWatcher.startBundles(DirectoryWatcher.java:1233) [9:org.apache.felix.fileinstall:3.6.4]
at org.apache.felix.fileinstall.internal.DirectoryWatcher.doProcess(DirectoryWatcher.java:520) [9:org.apache.felix.fileinstall:3.6.4]
at org.apache.felix.fileinstall.internal.DirectoryWatcher.process(DirectoryWatcher.java:365) [9:org.apache.felix.fileinstall:3.6.4]
at org.apache.felix.fileinstall.internal.DirectoryWatcher.run(DirectoryWatcher.java:316) [9:org.apache.felix.fileinstall:3.6.4]

Best, Jay

[chris (Chris Jackson)]

@jwiseman

Unresolved requirement: Import-Package: gnu.io

This is caused by not installing the serial driver. To be honest, I’m not sure what you mean about the “SERIAL1” binding. If you mean the serial binding for OH1 (??) then this isn’t needed. What is needed is the serial driver and if it’s installed as suggested at the top of this thread, then it should install ok, and should not uninstall when you restart.

So, I suggest to install the serial driver with the following karaf command -:

feature:install openhab-transport-serial

It should solve your problems.

[mstormi (Markus Storm)]

@chris

This is caused by not installing the serial driver. To be honest, I’m not sure what you mean about the “SERIAL1” binding.

That’s a binding to talk to things over the serial bus and it’s not related to zwave in the first place.
If you install it, however, it’ll automatically also install the openhab-transport-serial serial driver.
Some intentionally use that as a trick to have OH install the driver automatically after updates, cache deletions etc, some others maybe just because they discovered this is “required” to get your zwave dev binding to work.

[shorty707]

@chris

• is the binding really running
• is the right binding really running
• is there only one binding really running
• clear the cache
• pray to the karaf gods since they seem to control upgrades

ah did all that of course just not praying… will pray now until sunset and then roll back

edit: just if it is interesting I get an error on startup (despite praying)

2018-08-25 12:50:37.552 [WARN ] [ome.core.thing.internal.ThingManager] - Disposing handler for thing 'zwave:device:15348538564:node34' takes more than 5000ms.
2018-08-25 12:50:48.118 [INFO ] [ing.zwave.handler.ZWaveSerialHandler] - Stopped ZWave serial handler
2018-08-25 12:50:48.145 [ERROR] [nal.common.AbstractInvocationHandler] - An error occurred while calling method 'ThingHandler.dispose()' on 'org.openhab.binding.zwave.handler.ZWaveSerialHandler@63395e': null
java.lang.IllegalMonitorStateException: null
	at java.lang.Object.notify(Native Method) ~[?:?]
	at org.openhab.binding.zwave.internal.protocol.ZWaveTransactionManager.shutdown(ZWaveTransactionManager.java:207) [184:org.openhab.binding.zwave:2.4.0.201808231608]
	at org.openhab.binding.zwave.internal.protocol.ZWaveController.shutdown(ZWaveController.java:118) [184:org.openhab.binding.zwave:2.4.0.201808231608]
	at org.openhab.binding.zwave.handler.ZWaveControllerHandler.dispose(ZWaveControllerHandler.java:247) [184:org.openhab.binding.zwave:2.4.0.201808231608]
	at org.openhab.binding.zwave.handler.ZWaveSerialHandler.dispose(ZWaveSerialHandler.java:133) [184:org.openhab.binding.zwave:2.4.0.201808231608]
	at sun.reflect.GeneratedMethodAccessor107.invoke(Unknown Source) ~[?:?]
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:?]
	at java.lang.reflect.Method.invoke(Method.java:498) ~[?:?]
	at org.eclipse.smarthome.core.internal.common.AbstractInvocationHandler.invokeDirect(AbstractInvocationHandler.java:153) [98:org.eclipse.smarthome.core:0.10.0.201808011124]
	at org.eclipse.smarthome.core.internal.common.Invocation.call(Invocation.java:53) [98:org.eclipse.smarthome.core:0.10.0.201808011124]
	at java.util.concurrent.FutureTask.run(FutureTask.java:266) [?:?]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) [?:?]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) [?:?]
	at java.lang.Thread.run(Thread.java:745) [?:?]
2018-08-25 12:50:48.180 [ERROR] [ome.core.thing.internal.ThingManager] - Exception occurred while disposing handler of thing 'zwave:serial_zstick:15348538564': null
java.lang.IllegalMonitorStateException: null
	at java.lang.Object.notify(Native Method) ~[?:?]
	at org.openhab.binding.zwave.internal.protocol.ZWaveTransactionManager.shutdown(ZWaveTransactionManager.java:207) [184:org.openhab.binding.zwave:2.4.0.201808231608]
	at org.openhab.binding.zwave.internal.protocol.ZWaveController.shutdown(ZWaveController.java:118) [184:org.openhab.binding.zwave:2.4.0.201808231608]
	at org.openhab.binding.zwave.handler.ZWaveControllerHandler.dispose(ZWaveControllerHandler.java:247) [184:org.openhab.binding.zwave:2.4.0.201808231608]
	at org.openhab.binding.zwave.handler.ZWaveSerialHandler.dispose(ZWaveSerialHandler.java:133) [184:org.openhab.binding.zwave:2.4.0.201808231608]
	at sun.reflect.GeneratedMethodAccessor107.invoke(Unknown Source) ~[?:?]
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:?]
	at java.lang.reflect.Method.invoke(Method.java:498) ~[?:?]
	at org.eclipse.smarthome.core.internal.common.AbstractInvocationHandler.invokeDirect(AbstractInvocationHandler.java:153) [98:org.eclipse.smarthome.core:0.10.0.201808011124]
	at org.eclipse.smarthome.core.internal.common.Invocation.call(Invocation.java:53) [98:org.eclipse.smarthome.core:0.10.0.201808011124]
	at java.util.concurrent.FutureTask.run(FutureTask.java:266) [?:?]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) [?:?]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) [?:?]
	at java.lang.Thread.run(Thread.java:745) [?:?]

[mhilbush (Mark)]

@mstormi

If you install it, however, it’ll automatically also install the openhab-transport-serial serial driver.
Some intentionally use that as a trick to have OH install the driver automatically after updates, cache deletions etc

Yes, this is what I do, as I don’t want to have to remember to do a feature:install every time I update to a new OH snapshot.

I update OH snapshots pretty often on three different systems, so this saves me some time (and takes my poor memory out of the equation). The WARNS on startup are a small penalty for not having to remember to do the feature:install after every OH update. If you don’t update OH snapshots very often, then the feature:install is clearly the better way to go…

[jwiseman (Mr. Wiseman (OH 4.2 Snapshot on Pi4))]

Thanks! I wasn’t even aware that I could get into the Karaf on a Synology. This post below helped me this morning do just that.

MartinKoMartin Korsrud

Jan 2

You can log into Karaf console by

ssh -p 8101 openhab@localhost

Password is habopen. There you can set debugging level and tail the log

Best, Jay

[lsiepel]

@chris would look into this. More problems listed:

OH2 Z-Wave refactoring and testing... and SECURITY

I got this error when the binding is stopped: 2018-08-22 11:57:54.589 [INFO ] [ing.zwave.handler.ZWaveSerialHandler] - Stopped ZWave serial handler 2018-08-22 11:57:54.606 [ERROR] [nal.common.AbstractInvocationHandler] - An error occurred while calling method 'ThingHandler.dispose()' on 'org.openhab.binding.zwave.handler.ZWaveSerialHandler@1e3badc': null java.lang.IllegalMonitorStateException: null at java.lang.Object.notify(Native Method) ~[?:?] at org.openhab.binding.zwave.int…

OH2 Z-Wave refactoring and testing... and SECURITY

When i restart openhab the following IllegalMonitorStateException is shown. I’m not sure when this exception first occured and what the cause is, using 2018-08-20 binding version. 2018-08-22 22:08:15.130 [INFO ] [basic.internal.servlet.WebAppServlet] - Started Basic UI at /basicui/app 2018-08-22 22:08:15.189 [INFO ] [arthome.ui.paper.internal.PaperUIApp] - Started Paper UI at /paperui 2018-08-22 22:08:54.374 [INFO ] [ui.habmin.internal.servlet.HABminApp] - Started HABmin servlet at /habmin 2018…

@DanielMalmgren filed an issue. At what repo is that? couldn’t find the github issue.

[chris (Chris Jackson)]

@lsiepel

More problems listed:

Aren’t these just the same issue that was mentioned earlier? As I said a day or two back, I will look at this when I get a chance. This only seems to cause an exception on shutdown, so it’s not too important right now.

[chris (Chris Jackson)]

@lsiepel

At what repo is that?

It’s in the ZWave repo (always a good place for ZWave issues ).

[jwiseman (Mr. Wiseman (OH 4.2 Snapshot on Pi4))]

Need advice to get my zWave devices to ZW100 Multisensor 6 again after the upgrade?

I have the same 4 zWave sensor devices but only 1 of them is being assigned the ZW100 Multisensor 6. Any advice to force the issue on the other 3?

I did copy/paste the XML files for the other 3 and change the NODE info in them but NO luck still.

Z-Wave Node 002 - BASEMENT ONLINE
Unknown Device <—
zwave:device:ffffffd1ffffffb2ffffffdbffffffad:node2

Z-Wave Node 003 - LOFT ONLINE
ZW100 Multisensor 6 <-- Correct
zwave:device:ffffffd1ffffffb2ffffffdbffffffad:node3

Z-Wave Node 004 - GARAGE ONLINE
Unknown Device <—
zwave:device:ffffffd1ffffffb2ffffffdbffffffad:node4

Z-Wave Node 005 - ATTIC ONLINE
Unknown Device <—
zwave:device:ffffffd1ffffffb2ffffffdbffffffad:node5

Best, Jay

[lsiepel]

Yes the same issue’s and i wrote that you would look into those as you said earlier. It was a reply to Shorty707 who also posted about the same exception.

[shorty707]

@aradoorolesen

With the commit - Database update (#964), a new definition was added for the Fibaro smoke detector (FGSD002). The original defintion was changed to include:
versionMax" 3.1

My smoke detectors are version 3.3, and now when I try to add them from the inbox using PaperUI, I get an "Error: 404 - Not found, and the smoke detectors just remains in the inbox. Nothing in the log files! Even when logging zwave debug. Should I change log level on something else?
In the jsondb folder the DiscoveryResult file shows that it is using the new definition.

I have the same smoke sensors… and same problem…
I deleted them and the xml to rediscover and readd them after a db update…
they just sit in the inbox and no way to add them

[lsiepel]

I know the https://github.com/openhab/org.openhab.binding.zwave repo where it should go. Was asking where he raised the issue, because there is no issue listed at that repo with #641 or anything that looks like a IllegalMonitorStateException

[chris (Chris Jackson)]

@shorty707

and then roll back

I’d be interested to see if this works - I don’t think your issue is related to the binding. The fundamental problems you’re seeing (like nothing in logs) indicates that the binding is probably not running. Rolling back isn’t really a solution - you need to work out what’s happening if possible.

[chris (Chris Jackson)]

@shorty707

I have the same smoke sensors… and same problem…

When do you get 404? What does this mean exactly.

@shorty707

ediscover and readd them after a db update

Ok, so there’s a problem in the database that will fix this? I’ve not seen anything relating to this device so must have missed it (sorry).

[shorty707]

@chris

I’d be interested to see if this works - I don’t think your issue is related to the binding. The fundamental problems you’re seeing (like nothing in logs) indicates that the binding is probably not running. Rolling back isn’t really a solution - you need to work out what’s happening if possible.

I just found the problem from @aradoorolesen and he had the same device and same problem…
so maybe he found the root cause whats stops this device from being readded after deletion