Hallo,
seit meinen Update auf Openhab 3 wird mir in Openhab Änderungen im Zwave gar nicht oder nur mit sehr viel Verzögerung angezeigt.
Hier ein Beispiel ich drücke im Wohnzimmer auf ein Lichtschalter und das Licht geht an. In openhab wird es mir aber noch als aus angezeigt.
im Zwave debug log wird aber die Änderungen angezeigt.
Gelegentlich wird nach nach länger Zeit es aktualisiert, kann aber schon mal zwischen 10 min und 2 Std dauern, aber meistens ist nach 12 Std immer noch nichts geschehen.
Ich verwende ein RASPI 4 mit 4GB und openhabian 32bit bis Gestern die OPENHAB 3.2.0 und heute zum testen auf die 3.3.0 Build #2734. aeotec gen5 mit einen USB Hub
Unter OPENHAB 1 und 2 ging es mit diesen Stick
Dann habe habe ich noch ein Problem mit den ZWAVE.me wallc Schalter, die werden mir als unbekannt angezeigt. Das hatte im in openhab 1 und 2 auch immer, aber dann konnte man mit einen Tastendruck die Infos senden lassen und gut wars.
seit meinen Update auf Openhab 3 wird mir in Openhab Änderungen im Zwave gar nicht oder nur mit sehr viel Verzögerung angezeigt.
Hier ein Beispiel ich drücke im Wohnzimmer auf ein Lichtschalter und das Licht geht an. In openhab wird es mir aber noch als aus angezeigt.
im Zwave debug log wird aber die Änderungen angezeigt.
Gelegentlich wird nach nach länger Zeit es aktualisiert, kann aber schon mal zwischen 10 min und 2 Std dauern, aber meistens ist nach 12 Std immer noch nichts geschehen.
Ich verwende ein RASPI 4 mit 4GB und openhabian 32bit bis Gestern die OPENHAB 3.2.0 und heute zum testen auf die 3.3.0 Build #2734. aeotec gen5 mit einen USB Hub
Unter OPENHAB 1 und 2 ging es mit diesen Stick
Code:
2022-02-02 11:21:04.111 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x09. 2022-02-02 11:21:04.112 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00. 2022-02-02 11:21:04.114 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x04. 2022-02-02 11:21:04.115 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00. 2022-02-02 11:21:04.117 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x05. 2022-02-02 11:21:04.118 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x03. 2022-02-02 11:21:04.120 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x25. 2022-02-02 11:21:04.121 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x03. 2022-02-02 11:21:04.123 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0xFF. 2022-02-02 11:21:04.124 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x2D. 2022-02-02 11:21:05.626 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 05 03 25 03 FF 2D 2022-02-02 11:21:05.628 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 03 25 03 FF 2022-02-02 11:21:05.630 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 03 25 03 FF 2022-02-02 11:21:05.631 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2022-02-02 11:21:05.632 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Application Command Request (ALIVE:DONE) 2022-02-02 11:21:05.633 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: resetResendCount initComplete=true isDead=false 2022-02-02 11:21:05.634 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: Incoming command class COMMAND_CLASS_SWITCH_BINARY, endpoint 0 2022-02-02 11:21:05.635 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY not supported 2022-02-02 11:21:05.636 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 5: Received COMMAND_CLASS_SWITCH_BINARY V1 SWITCH_BINARY_REPORT 2022-02-02 11:21:05.637 [DEBUG] [dclass.ZWaveBinarySwitchCommandClass] - NODE 5: Switch Binary report, value = 255 2022-02-02 11:21:05.638 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveCommandClassValueEvent 2022-02-02 11:21:05.639 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SWITCH_BINARY, value=255 2022-02-02 11:21:05.640 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Updating channel state zwave:device:Z-Wave-Gries5:node5:switch_binary to ON [OnOffType] 2022-02-02 11:21:05.641 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Commands processed 1. 2022-02-02 11:21:05.642 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveC ommandClassPayload@1e4beb2. 2022-02-02 11:21:05.643 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2022-02-02 11:21:05.644 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2022-02-02 11:21:05.645 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2022-02-02 11:21:05.646 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2022-02-02 11:21:07.105 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x09. 2022-02-02 11:21:07.107 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00. 2022-02-02 11:21:07.108 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x04. 2022-02-02 11:21:07.110 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00. 2022-02-02 11:21:07.111 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x05. 2022-02-02 11:21:07.113 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x03. 2022-02-02 11:21:07.114 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x25. 2022-02-02 11:21:07.115 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x03. 2022-02-02 11:21:07.116 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0xFF. 2022-02-02 11:21:07.117 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x2D. 2022-02-02 11:21:08.606 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 05 03 25 03 FF 2D 2022-02-02 11:21:08.925 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 03 25 03 FF 2022-02-02 11:21:08.927 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 03 25 03 FF 2022-02-02 11:21:08.928 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2022-02-02 11:21:08.929 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Application Command Request (ALIVE:DONE) 2022-02-02 11:21:08.931 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: resetResendCount initComplete=true isDead=false 2022-02-02 11:21:08.932 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: Incoming command class COMMAND_CLASS_SWITCH_BINARY, endpoint 0 2022-02-02 11:21:08.933 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY not supported 2022-02-02 11:21:08.934 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 5: Received COMMAND_CLASS_SWITCH_BINARY V1 SWITCH_BINARY_REPORT 2022-02-02 11:21:08.935 [DEBUG] [dclass.ZWaveBinarySwitchCommandClass] - NODE 5: Switch Binary report, value = 255 2022-02-02 11:21:08.937 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveCommandClassValueEvent 2022-02-02 11:21:08.938 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SWITCH_BINARY, value=255 2022-02-02 11:21:08.939 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Updating channel state zwave:device:Z-Wave-Gries5:node5:switch_binary to ON [OnOffType] 2022-02-02 11:21:08.941 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Commands processed 1. 2022-02-02 11:21:08.941 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveC ommandClassPayload@3b1a41. 2022-02-02 11:21:08.942 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2022-02-02 11:21:08.943 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2022-02-02 11:21:08.944 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2022-02-02 11:21:08.944 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. ==> /var/log/openhab/events.log <== 2022-02-02 11:39:00.119 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'EGRelaisWohnzimmer_Switch1' changed from OFF to ON
Dann habe habe ich noch ein Problem mit den ZWAVE.me wallc Schalter, die werden mir als unbekannt angezeigt. Das hatte im in openhab 1 und 2 auch immer, aber dann konnte man mit einen Tastendruck die Infos senden lassen und gut wars.
Code:
2022-02-02 11:42:41.948 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT 2022-02-02 11:42:41.949 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 1127: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 94 2022-02-02 11:42:42.363 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 1E 00 49 84 13 18 04 18 01 5E 70 85 2D 8E 80 84 8F 5A 59 5B 73 86 72 EF 20 5B 26 27 2B 60 B8 2022-02-02 11:42:42.366 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationUpdate[73], type=Request[0], dest=19, callback=132, payload=84 13 18 04 18 01 5E 70 85 2D 8E 80 84 8F 5A 59 5B 73 86 72 EF 20 5B 26 27 2B 60 2022-02-02 11:42:42.368 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationUpdate[73], type=Request[0], dest=19, callback=132, payload=84 13 18 04 18 01 5E 70 85 2D 8E 80 84 8F 5A 59 5B 73 86 72 EF 20 5B 26 27 2B 60 2022-02-02 11:42:42.368 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 08 00 04 00 13 02 84 07 61 2022-02-02 11:42:42.369 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1127: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 94 2022-02-02 11:42:42.371 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2022-02-02 11:42:42.372 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 1127: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 94 2022-02-02 11:42:42.373 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 1127: (Callback 94) 2022-02-02 11:42:42.375 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=ApplicationUpdate[73], type=Request[0], dest=19, callback=132, payload=84 13 18 04 18 01 5E 70 85 2D 8E 80 84 8F 5A 59 5B 73 86 72 EF 20 5B 26 27 2B 60 2022-02-02 11:42:42.376 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 19: Application update request. Node information received. Transaction null 2022-02-02 11:42:42.377 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 19: Creating new instance of command class COMMAND_CLASS_ZWAVEPLUS_INFO 2022-02-02 11:42:42.380 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 19: Command class COMMAND_CLASS_ZWAVEPLUS_INFO, endpoint 0 created 2022-02-02 11:42:42.381 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 19: Application update is adding command class COMMAND_CLASS_ZWAVEPLUS_INFO. 2022-02-02 11:42:42.381 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: Adding command class COMMAND_CLASS_ZWAVEPLUS_INFO to the list of supported command classes. 2022-02-02 11:42:42.382 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 19: Creating new instance of command class COMMAND_CLASS_CONFIGURATION 2022-02-02 11:42:42.383 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 19: Command class COMMAND_CLASS_CONFIGURATION, endpoint 0 created 2022-02-02 11:42:42.384 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 19: Application update is adding command class COMMAND_CLASS_CONFIGURATION. 2022-02-02 11:42:42.385 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: Adding command class COMMAND_CLASS_CONFIGURATION to the list of supported command classes. 2022-02-02 11:42:42.386 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 19: Creating new instance of command class COMMAND_CLASS_ASSOCIATION 2022-02-02 11:42:42.387 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 19: Command class COMMAND_CLASS_ASSOCIATION, endpoint 0 created 2022-02-02 11:42:42.388 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 19: Application update is adding command class COMMAND_CLASS_ASSOCIATION. 2022-02-02 11:42:42.388 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: Adding command class COMMAND_CLASS_ASSOCIATION to the list of supported command classes. 2022-02-02 11:42:42.389 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 19: Creating new instance of command class COMMAND_CLASS_SCENE_CONTROLLER_CONF 2022-02-02 11:42:42.390 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 19: Command class COMMAND_CLASS_SCENE_CONTROLLER_CONF, endpoint 0 created 2022-02-02 11:42:42.391 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 19: Application update is adding command class COMMAND_CLASS_SCENE_CONTROLLER_CONF. 2022-02-02 11:42:42.392 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: Adding command class COMMAND_CLASS_SCENE_CONTROLLER_CONF to the list of supported command classes. 2022-02-02 11:42:42.392 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 19: Creating new instance of command class COMMAND_CLASS_MULTI_CHANNEL_ASSOCIATION 2022-02-02 11:42:42.393 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 19: Command class COMMAND_CLASS_MULTI_CHANNEL_ASSOCIATION, endpoint 0 created 2022-02-02 11:42:42.394 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 19: Application update is adding command class COMMAND_CLASS_MULTI_CHANNEL_ASSOCIATION. 2022-02-02 11:42:42.395 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: Adding command class COMMAND_CLASS_MULTI_CHANNEL_ASSOCIATION to the list of supported command classes. 2022-02-02 11:42:42.396 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 19: Creating new instance of command class COMMAND_CLASS_WAKE_UP 2022-02-02 11:42:42.397 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 19: Command class COMMAND_CLASS_WAKE_UP, endpoint 0 created 2022-02-02 11:42:42.398 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 19: Application update is adding command class COMMAND_CLASS_WAKE_UP. 2022-02-02 11:42:42.399 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: Adding command class COMMAND_CLASS_WAKE_UP to the list of supported command classes. 2022-02-02 11:42:42.399 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 19: Creating new instance of command class COMMAND_CLASS_MULTI_CMD 2022-02-02 11:42:42.400 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 19: Command class COMMAND_CLASS_MULTI_CMD, endpoint 0 created 2022-02-02 11:42:42.401 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 19: Application update is adding command class COMMAND_CLASS_MULTI_CMD. 2022-02-02 11:42:42.401 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: Adding command class COMMAND_CLASS_MULTI_CMD to the list of supported command classes. 2022-02-02 11:42:42.402 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 19: Creating new instance of command class COMMAND_CLASS_DEVICE_RESET_LOCALLY 2022-02-02 11:42:42.403 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 19: Command class COMMAND_CLASS_DEVICE_RESET_LOCALLY, endpoint 0 created 2022-02-02 11:42:42.403 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 19: Application update is adding command class COMMAND_CLASS_DEVICE_RESET_LOCALLY. 2022-02-02 11:42:42.404 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: Adding command class COMMAND_CLASS_DEVICE_RESET_LOCALLY to the list of supported command classes. 2022-02-02 11:42:42.404 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 19: Creating new instance of command class COMMAND_CLASS_ASSOCIATION_GRP_INFO 2022-02-02 11:42:42.406 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 19: Command class COMMAND_CLASS_ASSOCIATION_GRP_INFO, endpoint 0 created 2022-02-02 11:42:42.406 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 19: Application update is adding command class COMMAND_CLASS_ASSOCIATION_GRP_INFO. 2022-02-02 11:42:42.407 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: Adding command class COMMAND_CLASS_ASSOCIATION_GRP_INFO to the list of supported command classes. 2022-02-02 11:42:42.408 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 19: Creating new instance of command class COMMAND_CLASS_CENTRAL_SCENE 2022-02-02 11:42:42.409 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 19: Command class COMMAND_CLASS_CENTRAL_SCENE, endpoint 0 created 2022-02-02 11:42:42.409 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 19: Application update is adding command class COMMAND_CLASS_CENTRAL_SCENE. 2022-02-02 11:42:42.410 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: Adding command class COMMAND_CLASS_CENTRAL_SCENE to the list of supported command classes. 2022-02-02 11:42:42.410 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 19: Creating new instance of command class COMMAND_CLASS_POWERLEVEL 2022-02-02 11:42:42.411 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 19: Command class COMMAND_CLASS_POWERLEVEL, endpoint 0 created 2022-02-02 11:42:42.412 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 19: Application update is adding command class COMMAND_CLASS_POWERLEVEL. 2022-02-02 11:42:42.412 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: Adding command class COMMAND_CLASS_POWERLEVEL to the list of supported command classes. 2022-02-02 11:42:42.413 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 19: Creating new instance of command class COMMAND_CLASS_VERSION 2022-02-02 11:42:42.414 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 19: Command class COMMAND_CLASS_VERSION, endpoint 0 created 2022-02-02 11:42:42.414 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 19: Application update is adding command class COMMAND_CLASS_VERSION. 2022-02-02 11:42:42.415 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: Adding command class COMMAND_CLASS_VERSION to the list of supported command classes. 2022-02-02 11:42:42.415 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 19: Creating new instance of command class COMMAND_CLASS_MANUFACTURER_SPECIFIC 2022-02-02 11:42:42.416 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 19: Command class COMMAND_CLASS_MANUFACTURER_SPECIFIC, endpoint 0 created 2022-02-02 11:42:42.417 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 19: Application update is adding command class COMMAND_CLASS_MANUFACTURER_SPECIFIC. 2022-02-02 11:42:42.417 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: Adding command class COMMAND_CLASS_MANUFACTURER_SPECIFIC to the list of supported command classes. 2022-02-02 11:42:42.418 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 19: Creating new instance of command class COMMAND_CLASS_SWITCH_MULTILEVEL 2022-02-02 11:42:42.419 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 19: Command class COMMAND_CLASS_SWITCH_MULTILEVEL, endpoint 0 created 2022-02-02 11:42:42.420 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 19: Application update is adding command class COMMAND_CLASS_SWITCH_MULTILEVEL. 2022-02-02 11:42:42.420 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: Adding command class COMMAND_CLASS_SWITCH_MULTILEVEL to the list of supported command classes. 2022-02-02 11:42:42.421 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 19: Creating new instance of command class COMMAND_CLASS_SWITCH_ALL 2022-02-02 11:42:42.422 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 19: Command class COMMAND_CLASS_SWITCH_ALL, endpoint 0 created 2022-02-02 11:42:42.422 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 19: Application update is adding command class COMMAND_CLASS_SWITCH_ALL. 2022-02-02 11:42:42.423 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: Adding command class COMMAND_CLASS_SWITCH_ALL to the list of supported command classes. 2022-02-02 11:42:42.423 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 19: Creating new instance of command class COMMAND_CLASS_SCENE_ACTIVATION 2022-02-02 11:42:42.424 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 19: Command class COMMAND_CLASS_SCENE_ACTIVATION, endpoint 0 created 2022-02-02 11:42:42.425 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 19: Application update is adding command class COMMAND_CLASS_SCENE_ACTIVATION. 2022-02-02 11:42:42.426 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: Adding command class COMMAND_CLASS_SCENE_ACTIVATION to the list of supported command classes. 2022-02-02 11:42:42.426 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 19: Creating new instance of command class COMMAND_CLASS_MULTI_CHANNEL 2022-02-02 11:42:42.427 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 19: Command class COMMAND_CLASS_MULTI_CHANNEL, endpoint 0 created 2022-02-02 11:42:42.428 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 19: Application update is adding command class COMMAND_CLASS_MULTI_CHANNEL. 2022-02-02 11:42:42.428 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: Adding command class COMMAND_CLASS_MULTI_CHANNEL to the list of supported command classes. 2022-02-02 11:42:42.429 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 19: Application update - no transaction. 2022-02-02 11:42:42.430 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2022-02-02 11:42:42.430 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2022-02-02 11:42:42.525 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=19, callback=0, payload=00 13 02 84 07 2022-02-02 11:42:42.527 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=19, callback=0, payload=00 13 02 84 07 2022-02-02 11:42:42.528 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 13 03 80 03 64 05 2022-02-02 11:42:42.528 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1127: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 94 2022-02-02 11:42:42.529 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Application Command Request (ALIVE:REQUEST_NIF) 2022-02-02 11:42:42.529 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=19, callback=0, payload=00 13 03 80 03 64 2022-02-02 11:42:42.530 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2022-02-02 11:42:42.532 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: Is awake with 1 messages in the queue 2022-02-02 11:42:42.533 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: Start sleep timer at 5000ms 2022-02-02 11:42:42.535 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 19: Got an event from Z-Wave network: ZWaveNodeStatusEvent 2022-02-02 11:42:42.539 [DEBUG] [ve.internal.protocol.ZWaveController] - NODE 19: Node Status event - Node is AWAKE 2022-02-02 11:42:42.540 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Commands processed 1. 2022-02-02 11:42:42.542 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveC ommandClassPayload@a9bb07. 2022-02-02 11:42:42.543 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1 2022-02-02 11:42:42.544 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1 2022-02-02 11:42:42.545 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=19, callback=0, payload=00 13 03 80 03 64 2022-02-02 11:42:42.546 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1127: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 94 2022-02-02 11:42:42.547 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Application Command Request (ALIVE:REQUEST_NIF) 2022-02-02 11:42:42.547 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: Incoming command class COMMAND_CLASS_BATTERY, endpoint 0 2022-02-02 11:42:42.548 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: SECURITY not supported 2022-02-02 11:42:42.549 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 19: Received COMMAND_CLASS_BATTERY V0 BATTERY_REPORT 2022-02-02 11:42:42.549 [DEBUG] [ommandclass.ZWaveBatteryCommandClass] - NODE 19: Battery report value = 100 2022-02-02 11:42:42.550 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 19: Got an event from Z-Wave network: ZWaveCommandClassValueEvent 2022-02-02 11:42:42.551 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 19: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_BATTERY, value=100 2022-02-02 11:42:42.551 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Commands processed 1. 2022-02-02 11:42:42.552 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveC ommandClassPayload@402da8. 2022-02-02 11:42:42.553 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1 2022-02-02 11:42:42.553 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1 2022-02-02 11:42:42.554 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2022-02-02 11:42:42.554 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2022-02-02 11:42:43.950 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 33: TID 1127: Timeout at state WAIT_RESPONSE. 3 retries remaining. 2022-02-02 11:42:43.951 [DEBUG] [sactionManager$ZWaveTransactionTimer] - Aborting Transaction! 2022-02-02 11:42:43.952 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 1127: Transaction ABORTED 2022-02-02 11:42:43.954 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 03 00 16 EA 2022-02-02 11:42:43.955 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 03 00 16 EA 2022-02-02 11:42:44.105 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT 2022-02-02 11:42:44.106 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2022-02-02 11:42:45.035 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: WakeupTimerTask 1 Messages waiting, state REQUEST_NIF 2022-02-02 11:42:47.536 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: WakeupTimerTask 1 Messages waiting, state REQUEST_NIF 2022-02-02 11:42:47.537 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: No more messages, go back to sleep 2022-02-02 11:42:47.538 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 19: Creating new message for application command WAKE_UP_NO_MORE_INFORMATION 2022-02-02 11:42:47.539 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: SECURITY not supported 2022-02-02 11:42:47.540 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: Command Class COMMAND_CLASS_WAKE_UP is NOT required to be secured 2022-02-02 11:42:47.542 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: sendTransaction org.openhab.binding.zwave.internal.protocol.transa ction.ZWaveCommandClassTransactionPayload@7300ab 2022-02-02 11:42:47.543 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Bump transaction 1128 priority from Immediate to Immediate 2022-02-02 11:42:47.545 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Adding to device queue 2022-02-02 11:42:47.546 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Added 1128 to queue - size 7 2022-02-02 11:42:47.547 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2022-02-02 11:42:55.954 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 33: TID 1127: Timeout at state ABORTED. 3 retries remaining. 2022-02-02 11:42:55.955 [DEBUG] [sactionManager$ZWaveTransactionTimer] - TID 1127: Transaction is current transaction, so clearing!!!!! 2022-02-02 11:42:55.956 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 1127: Transaction CANCELLED 2022-02-02 11:42:55.958 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 33: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2022-02-02 11:42:55.959 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 33: notifyTransactionResponse TID:1127 CANCELLED 2022-02-02 11:42:55.960 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2022-02-02 11:42:55.962 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 04 00 60 13 88 2022-02-02 11:42:55.963 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 04 00 60 13 88 2022-02-02 11:42:55.965 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2022-02-02 11:42:55.966 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2022-02-02 11:42:55.967 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2022-02-02 11:42:55.968 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2022-02-02 11:42:55.968 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK 2022-02-02 11:42:55.969 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2022-02-02 11:42:55.974 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 60 01 9B 2022-02-02 11:42:56.315 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT 2022-02-02 11:42:56.317 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=RequestNodeInfo[96], type=Response[1], dest=255, callback=0, payload=01 2022-02-02 11:42:56.318 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 366: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 0 2022-02-02 11:42:56.320 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2022-02-02 11:42:56.321 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=RequestNodeInfo[96], type=Response[1], dest=255, callback=0, payload=01 2022-02-02 11:42:56.322 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 366: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 0 2022-02-02 11:42:56.323 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2022-02-02 11:42:56.325 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 366: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 0 2022-02-02 11:42:56.326 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=RequestNodeInfo[96], type=Response[1], dest=255, callback=0, payload=01 2022-02-02 11:42:56.328 [DEBUG] [lmessage.RequestNodeInfoMessageClass] - Request node info successfully placed on stack. 2022-02-02 11:42:56.329 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 366: Advanced to WAIT_DATA 2022-02-02 11:42:56.330 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: TID 366: Transaction not completed 2022-02-02 11:42:56.331 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2022-02-02 11:42:56.332 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2022-02-02 11:43:04.792 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Timeout - Sending NAK 2022-02-02 11:43:06.042 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 06 00 49 81 00 00 31 2022-02-02 11:43:06.044 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationUpdate[73], type=Request[0], dest=0, callback=129, payload=81 00 00 2022-02-02 11:43:06.045 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationUpdate[73], type=Request[0], dest=0, callback=129, payload=81 00 00 2022-02-02 11:43:06.047 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 366: [WAIT_DATA] priority=Immediate, requiresResponse=true, callback: 0 2022-02-02 11:43:06.048 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2022-02-02 11:43:06.049 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 366: [WAIT_DATA] priority=Immediate, requiresResponse=true, callback: 0 2022-02-02 11:43:06.049 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 366: (Callback 0) 2022-02-02 11:43:06.050 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 366: callback 0 2022-02-02 11:43:06.052 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=ApplicationUpdate[73], type=Request[0], dest=0, callback=129, payload=81 00 00 2022-02-02 11:43:06.053 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 19: Application update request. Node Info Request Failed. 2022-02-02 11:43:06.054 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 366: Transaction CANCELLED 2022-02-02 11:43:06.055 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 19: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2022-02-02 11:43:06.056 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: CANCEL while sending message. Requeueing - 2 attempts left! 2022-02-02 11:43:06.057 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 366: Transaction RESET with 2 retries remaining. 2022-02-02 11:43:06.058 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Adding to device queue 2022-02-02 11:43:06.059 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Added 366 to queue - size 7 2022-02-02 11:43:06.060 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2022-02-02 11:43:06.061 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 04 00 60 13 88 2022-02-02 11:43:06.062 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 04 00 60 13 88 2022-02-02 11:43:06.064 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2022-02-02 11:43:06.065 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2022-02-02 11:43:06.074 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 60 01 9B 2022-02-02 11:43:06.155 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT 2022-02-02 11:43:06.156 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 366: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 0 2022-02-02 11:43:06.157 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 1<>127 : Message: class=RequestNodeInfo[96], type=Response[1], dest=255, callback=0, payload=01 2022-02-02 11:43:06.158 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: TID 366: Transaction not completed 2022-02-02 11:43:06.159 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (1): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2022-02-02 11:43:06.160 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 366: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 0 2022-02-02 11:43:06.161 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK 2022-02-02 11:43:06.162 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=RequestNodeInfo[96], type=Response[1], dest=255, callback=0, payload=01 2022-02-02 11:43:06.163 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 366: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 0 2022-02-02 11:43:06.164 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2022-02-02 11:43:06.165 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 366: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 0 2022-02-02 11:43:06.167 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=RequestNodeInfo[96], type=Response[1], dest=255, callback=0, payload=01 2022-02-02 11:43:06.168 [DEBUG] [lmessage.RequestNodeInfoMessageClass] - Request node info successfully placed on stack. 2022-02-02 11:43:06.169 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 366: Advanced to WAIT_DATA 2022-02-02 11:43:06.170 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: TID 366: Transaction not completed 2022-02-02 11:43:06.171 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2022-02-02 11:43:06.172 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2022-02-02 11:43:13.545 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2022-02-02 11:43:13.546 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2022-02-02 11:43:13.548 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2022-02-02 11:43:13.548 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00. 2022-02-02 11:43:13.549 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 366: [WAIT_DATA] priority=Immediate, requiresResponse=true, callback: 0 2022-02-02 11:43:13.550 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x49. 2022-02-02 11:43:13.550 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK 2022-02-02 11:43:13.551 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x81. 2022-02-02 11:43:13.551 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2022-02-02 11:43:13.553 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2022-02-02 11:43:13.553 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00. 2022-02-02 11:43:13.554 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00. 2022-02-02 11:43:13.556 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x31. 2022-02-02 11:43:15.041 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2022-02-02 11:43:15.043 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2022-02-02 11:43:15.044 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2022-02-02 11:43:15.045 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 366: [WAIT_DATA] priority=Immediate, requiresResponse=true, callback: 0 2022-02-02 11:43:15.045 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00. 2022-02-02 11:43:15.047 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK 2022-02-02 11:43:15.047 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x49. 2022-02-02 11:43:15.048 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2022-02-02 11:43:15.049 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x81. 2022-02-02 11:43:15.049 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2022-02-02 11:43:15.050 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00. 2022-02-02 11:43:15.052 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00. 2022-02-02 11:43:15.053 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x31. 2022-02-02 11:43:16.541 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2022-02-02 11:43:16.543 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2022-02-02 11:43:16.544 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2022-02-02 11:43:16.544 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00. 2022-02-02 11:43:16.545 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 366: [WAIT_DATA] priority=Immediate, requiresResponse=true, callback: 0 2022-02-02 11:43:16.546 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x49. 2022-02-02 11:43:16.547 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK 2022-02-02 11:43:16.547 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x81. 2022-02-02 11:43:16.548 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2022-02-02 11:43:16.548 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00. 2022-02-02 11:43:16.549 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2022-02-02 11:43:16.549 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00. 2022-02-02 11:43:16.551 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x31. 2022-02-02 11:43:18.041 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2022-02-02 11:43:18.042 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2022-02-02 11:43:18.042 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2022-02-02 11:43:18.043 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00. 2022-02-02 11:43:18.043 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 366: [WAIT_DATA] priority=Immediate, requiresResponse=true, callback: 0 2022-02-02 11:43:18.043 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x49. 2022-02-02 11:43:18.044 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK 2022-02-02 11:43:18.044 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x81. 2022-02-02 11:43:18.045 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2022-02-02 11:43:18.045 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00. 2022-02-02 11:43:18.045 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2022-02-02 11:43:18.046 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00. 2022-02-02 11:43:18.046 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x31. ==> /var/log/openhab/events.log <== 2022-02-02 11:43:19.925 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'OG1HeizungsthermostatSchlafzimmer_ActualTemperatu re' changed from 19.9 °C to 19.6 °C ==> /var/log/openhab/openhab.log <== 2022-02-02 11:43:31.170 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 19: TID 366: Timeout at state WAIT_DATA. 2 retries remaining. 2022-02-02 11:43:31.172 [DEBUG] [sactionManager$ZWaveTransactionTimer] - TID 366: Transaction is current transaction, so clearing!!!!! 2022-02-02 11:43:31.173 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 366: Transaction CANCELLED 2022-02-02 11:43:31.174 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 19: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2022-02-02 11:43:31.175 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: notifyTransactionResponse TID:366 CANCELLED 2022-02-02 11:43:31.177 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2022-02-02 11:43:31.177 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 366: Transaction event listener: DONE: CANCELLED -> 2022-02-02 11:43:31.178 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 19: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveT ransactionResponse@531274 2022-02-02 11:43:31.179 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 13 02 84 08 25 5F 02 2022-02-02 11:43:31.180 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 19: No data from device, but it was ACK'd. Possibly not supported? (Try 0) 2022-02-02 11:43:31.181 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 19: Sending REQUEST Message = 01 09 00 13 13 02 84 08 25 5F 02 2022-02-02 11:43:31.193 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2022-02-02 11:43:31.395 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT 2022-02-02 11:43:31.397 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2022-02-02 11:43:31.398 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 1128: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 95 2022-02-02 11:43:31.399 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2022-02-02 11:43:31.400 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2022-02-02 11:43:31.401 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2022-02-02 11:43:31.402 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 1128: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 95 2022-02-02 11:43:31.403 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2022-02-02 11:43:31.404 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 19: sentData successfully placed on stack. 2022-02-02 11:43:31.406 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 1128: Advanced to WAIT_REQUEST 2022-02-02 11:43:31.407 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: TID 1128: Transaction not completed 2022-02-02 11:43:31.408 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2022-02-02 11:43:31.409 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2022-02-02 11:43:32.177 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: sendTransaction org.openhab.binding.zwave.internal.protocol.ZWaveS erialPayload@6c83e6 2022-02-02 11:43:32.179 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Bump transaction 1129 priority from Controller to Immediate 2022-02-02 11:43:32.180 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Adding to device queue 2022-02-02 11:43:32.181 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Added 1129 to queue - size 6 2022-02-02 11:43:32.182 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2022-02-02 11:43:36.408 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 19: TID 1128: Timeout at state WAIT_REQUEST. 3 retries remaining. 2022-02-02 11:43:36.409 [DEBUG] [sactionManager$ZWaveTransactionTimer] - Aborting Transaction! 2022-02-02 11:43:36.411 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 1128: Transaction ABORTED 2022-02-02 11:43:36.412 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 03 00 16 EA 2022-02-02 11:43:36.414 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 03 00 16 EA 2022-02-02 11:43:36.416 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT 2022-02-02 11:43:36.416 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2022-02-02 11:43:36.418 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2022-02-02 11:43:36.419 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2022-02-02 11:43:36.420 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2022-02-02 11:43:36.420 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1128: [ABORTED] priority=Immediate, requiresResponse=true, callback: 95 2022-02-02 11:43:36.421 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK 2022-02-02 11:43:36.421 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2022-02-02 11:43:36.422 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2022-02-02 11:43:38.363 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x07. 2022-02-02 11:43:38.365 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00. 2022-02-02 11:43:38.366 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x13. 2022-02-02 11:43:38.368 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x5F. 2022-02-02 11:43:38.369 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Frame length is out of limits (2) 2022-02-02 11:43:38.370 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Frame length is out of limits (206) 2022-02-02 11:43:38.371 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Frame length is out of limits (121) 2022-02-02 11:43:38.623 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Timeout - Sending NAK 2022-02-02 11:43:39.862 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 5F 01 02 CE 79 2022-02-02 11:43:39.864 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=1, callback=95, payload=5F 01 02 CE 2022-02-02 11:43:39.866 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=1, callback=95, payload=5F 01 02 CE 2022-02-02 11:43:39.867 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1128: [ABORTED] priority=Immediate, requiresResponse=true, callback: 95 2022-02-02 11:43:39.868 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2022-02-02 11:43:39.869 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 1128: [ABORTED] priority=Immediate, requiresResponse=true, callback: 95 2022-02-02 11:43:39.870 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 1128: (Callback 95) 2022-02-02 11:43:39.871 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match! 2022-02-02 11:43:39.872 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 1128: callback 95 2022-02-02 11:43:39.873 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=1, callback=95, payload=5F 01 02 CE 2022-02-02 11:43:39.874 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 19: SendData Request. CallBack ID = 95, Status = Transmission complete, no ACK received(1) 2022-02-02 11:43:39.875 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 19: WAKE_UP_NO_MORE_INFORMATION. Treated as ACK. 2022-02-02 11:43:39.876 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: TID 1128: Transaction not completed 2022-02-02 11:43:39.877 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2022-02-02 11:43:39.878 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
Kommentar