HELP ME PLEASE - Openzwave (beta) - frequently not responding & devices unavailable during start (long time)

Configuration Z-Wave
1

Maybe it has been asked before… But also in v0.7.0 I have the issue of “non responsive”.

I use the addon and restarting i the only option to get things going.

During restart all devices are unavailable during the start and this takes a long time: 5-10 minutes. Alle devices are then greyed out… is that normal?

Some example of what it is doing when starting:

[20201123 19:17:38.883 CET] [ozw.library] [debug]: Detail - Node: 21   ZW_SEND_DATA delivered to Z-Wave stack 
[20201123 19:17:47.025 CET] [ozw.library] [debug]: Detail - Node: 21   Received: 0x01, 0x07, 0x00, 0x13, 0x1c, 0x01, 0x03, 0x2f, 0xda 
[20201123 19:17:47.025 CET] [ozw.library] [debug]: Detail - Node: 21   ZW_SEND_DATA Request with callback ID 0x1c received (expected 0x1c) 
[20201123 19:17:47.025 CET] [ozw.logging] [debug]: Increasing Logging Capacity to  6000 
[20201123 19:17:47.025 CET] [ozw.library] [info]: Info - Node: 21 WARNING: ZW_SEND_DATA failed. No ACK received - device may be asleep. 
[20201123 19:17:47.025 CET] [ozw.library] [info]: Info - Node: 21   Node 21 has been marked as asleep 
[20201123 19:17:47.025 CET] [ozw.library] [info]: Info - Node: 21 Node not responding - moving QueryStageComplete command to Wake-Up queue 
[20201123 19:17:47.025 CET] [ozw.library] [warning]: Warning - Node: 0 CheckCompletedNodeQueries m_allNodesQueried=0 m_awakeNodesQueried=0 
[20201123 19:17:47.025 CET] [ozw.library] [warning]: Warning - Node: 0 CheckCompletedNodeQueries all=0, deadFound=0 sleepingOnly=0 
[20201123 19:17:47.025 CET] [ozw.library] [info]: Info - Node: 0 Saving Cache 
[20201123 19:17:47.026 CET] [ozw.library] [info]: Info - Node: 1 Cache Save for Node 1 as its QueryStage_CacheLoad 
[20201123 19:17:47.026 CET] [ozw.library] [info]: Info - Node: 2 Cache Save for Node 2 as its QueryStage_CacheLoad 
[20201123 19:17:47.027 CET] [ozw.library] [info]: Info - Node: 3 Cache Save for Node 3 as its QueryStage_CacheLoad 
[20201123 19:17:47.027 CET] [ozw.library] [info]: Info - Node: 4 Cache Save for Node 4 as its QueryStage_CacheLoad 
[20201123 19:17:47.028 CET] [ozw.library] [info]: Info - Node: 5 Cache Save for Node 5 as its QueryStage_CacheLoad 
[20201123 19:17:47.028 CET] [ozw.library] [info]: Info - Node: 7 Cache Save for Node 7 as its QueryStage_CacheLoad 
[20201123 19:17:47.028 CET] [ozw.library] [info]: Info - Node: 8 Cache Save for Node 8 as its QueryStage_CacheLoad 
[20201123 19:17:47.029 CET] [ozw.library] [info]: Info - Node: 9 Cache Save for Node 9 as its QueryStage_CacheLoad 
[20201123 19:17:47.030 CET] [ozw.library] [info]: Info - Node: 10 Cache Save for Node 10 as its QueryStage_CacheLoad 
[20201123 19:17:47.030 CET] [ozw.library] [info]: Info - Node: 11 Cache Save for Node 11 as its QueryStage_CacheLoad 
[20201123 19:17:47.031 CET] [ozw.library] [info]: Info - Node: 12 Cache Save for Node 12 as its QueryStage_CacheLoad 
[20201123 19:17:47.031 CET] [ozw.library] [info]: Info - Node: 13 Cache Save for Node 13 as its QueryStage_CacheLoad 
[20201123 19:17:47.032 CET] [ozw.library] [info]: Info - Node: 14 Cache Save for Node 14 as its QueryStage_CacheLoad 
[20201123 19:17:47.032 CET] [ozw.library] [info]: Info - Node: 15 Cache Save for Node 15 as its QueryStage_CacheLoad 
[20201123 19:17:47.032 CET] [ozw.library] [info]: Info - Node: 16 Cache Save for Node 16 as its QueryStage_CacheLoad 
[20201123 19:17:47.032 CET] [ozw.library] [info]: Info - Node: 17 Cache Save for Node 17 as its QueryStage_CacheLoad 
[20201123 19:17:47.033 CET] [ozw.library] [info]: Info - Node: 18 Cache Save for Node 18 as its QueryStage_CacheLoad 
[20201123 19:17:47.033 CET] [ozw.library] [info]: Info - Node: 19 Cache Save for Node 19 as its QueryStage_CacheLoad 
[20201123 19:17:47.033 CET] [ozw.library] [info]: Info - Node: 20 Cache Save for Node 20 as its QueryStage_CacheLoad 
[20201123 19:17:47.033 CET] [ozw.library] [info]: Info - Node: 21 Cache Save for Node 21 as its QueryStage_CacheLoad 
[20201123 19:17:47.034 CET] [ozw.library] [info]: Info - Node: 22 Cache Save for Node 22 as its QueryStage_CacheLoad 
[20201123 19:17:47.034 CET] [ozw.library] [info]: Info - Node: 23 Cache Save for Node 23 as its QueryStage_CacheLoad 
[20201123 19:17:47.034 CET] [ozw.library] [info]: Info - Node: 24 Cache Save for Node 24 as its QueryStage_CacheLoad 
[20201123 19:17:47.034 CET] [ozw.library] [info]: Info - Node: 26 Cache Save for Node 26 as its QueryStage_CacheLoad 
[20201123 19:17:47.035 CET] [ozw.library] [info]: Info - Node: 28 Cache Save for Node 28 as its QueryStage_CacheLoad 
[20201123 19:17:47.035 CET] [ozw.library] [info]: Info - Node: 29 Cache Save for Node 29 as its QueryStage_CacheLoad 
[20201123 19:17:47.035 CET] [ozw.library] [info]: Info - Node: 30 Cache Save for Node 30 as its QueryStage_CacheLoad 
[20201123 19:17:47.036 CET] [ozw.library] [info]: Info - Node: 31 Cache Save for Node 31 as its QueryStage_CacheLoad 
[20201123 19:17:47.036 CET] [ozw.library] [info]: Info - Node: 32 Cache Save for Node 32 as its QueryStage_CacheLoad 
[20201123 19:17:47.036 CET] [ozw.library] [info]: Info - Node: 33 Cache Save for Node 33 as its QueryStage_CacheLoad 
[20201123 19:17:47.036 CET] [ozw.library] [info]: Info - Node: 34 Cache Save for Node 34 as its QueryStage_CacheLoad 
[20201123 19:17:47.091 CET] [ozw.library] [debug]: Detail - Node: 21 Notification: Notification - NoOperation 
[20201123 19:17:47.091 CET] [ozw.library] [debug]: Detail - Node: 21 Notification: Notification - Node Asleep 
[20201123 19:17:47.091 CET] [ozw.library] [info]: Info - Node: 22 Sending (NoOp) message (Callback ID=0x1d, Expected Reply=0x13) - NoOperation_Set (Node=22): 0x01, 0x09, 0x00, 0x13, 0x16, 0x02, 0x00, 0x00, 0x25, 0x1d, 0xc9 
[20201123 19:17:47.091 CET] [ozw.library] [info]: Info - Node: 22 Encrypted Flag is 0 
[20201123 19:17:47.091 CET] [ozw.notifications] [debug]: Notification pvt_ozwNotification NotificationTypes::Notification_Code_NoOperation Thread:  0x7f6f415fad48 
[20201123 19:17:47.091 CET] [ozw.mqtt.publisher] [debug]: Publishing Event ozwNotification 
[20201123 19:17:47.091 CET] [ozw.notifications] [debug]: Notification pvt_ozwNotification NotificationTypes::Notification_Code_NodeAsleep Thread:  0x7f6f415fad48 
[20201123 19:17:47.091 CET] [ozw.mqtt.publisher] [debug]: Publishing Event ozwNotification 
[20201123 19:17:47.108 CET] [ozw.library] [debug]: Detail - Node: 22   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 
[20201123 19:17:47.108 CET] [ozw.library] [debug]: Detail - Node: 22   ZW_SEND_DATA delivered to Z-Wave stack 
[20201123 19:17:51.255 CET] [ozw.library] [debug]: Detail - Node: 22   Received: 0x01, 0x07, 0x00, 0x13, 0x1d, 0x01, 0x01, 0x9f, 0x69 
[20201123 19:17:51.255 CET] [ozw.library] [debug]: Detail - Node: 22   ZW_SEND_DATA Request with callback ID 0x1d received (expected 0x1d) 
[20201123 19:17:51.255 CET] [ozw.library] [info]: Info - Node: 22 WARNING: ZW_SEND_DATA failed. No ACK received - device may be asleep. 
[20201123 19:17:51.255 CET] [ozw.library] [info]: Info - Node: 22   Node 22 has been marked as asleep 
[20201123 19:17:51.255 CET] [ozw.library] [info]: Info - Node: 22 Node not responding - moving QueryStageComplete command to Wake-Up queue 
[20201123 19:17:51.255 CET] [ozw.library] [warning]: Warning - Node: 0 CheckCompletedNodeQueries m_allNodesQueried=0 m_awakeNodesQueried=0 
[20201123 19:17:51.255 CET] [ozw.library] [warning]: Warning - Node: 0 CheckCompletedNodeQueries all=0, deadFound=0 sleepingOnly=0 
[20201123 19:17:51.255 CET] [ozw.library] [info]: Info - Node: 0 Saving Cache 
[20201123 19:17:51.255 CET] [ozw.library] [info]: Info - Node: 1 Cache Save for Node 1 as its QueryStage_CacheLoad 
[20201123 19:17:51.256 CET] [ozw.library] [info]: Info - Node: 2 Cache Save for Node 2 as its QueryStage_CacheLoad 
[20201123 19:17:51.256 CET] [ozw.library] [info]: Info - Node: 3 Cache Save for Node 3 as its QueryStage_CacheLoad 
[20201123 19:17:51.257 CET] [ozw.library] [info]: Info - Node: 4 Cache Save for Node 4 as its QueryStage_CacheLoad 
[20201123 19:17:51.257 CET] [ozw.library] [info]: Info - Node: 5 Cache Save for Node 5 as its QueryStage_CacheLoad 
[20201123 19:17:51.257 CET] [ozw.library] [info]: Info - Node: 7 Cache Save for Node 7 as its QueryStage_CacheLoad 
[20201123 19:17:51.258 CET] [ozw.library] [info]: Info - Node: 8 Cache Save for Node 8 as its QueryStage_CacheLoad 
[20201123 19:17:51.258 CET] [ozw.library] [info]: Info - Node: 9 Cache Save for Node 9 as its QueryStage_CacheLoad 
[20201123 19:17:51.259 CET] [ozw.library] [info]: Info - Node: 10 Cache Save for Node 10 as its QueryStage_CacheLoad 
[20201123 19:17:51.259 CET] [ozw.library] [info]: Info - Node: 11 Cache Save for Node 11 as its QueryStage_CacheLoad 
[20201123 19:17:51.260 CET] [ozw.library] [info]: Info - Node: 12 Cache Save for Node 12 as its QueryStage_CacheLoad 
[20201123 19:17:51.260 CET] [ozw.library] [info]: Info - Node: 13 Cache Save for Node 13 as its QueryStage_CacheLoad 
[20201123 19:17:51.260 CET] [ozw.library] [info]: Info - Node: 14 Cache Save for Node 14 as its QueryStage_CacheLoad 
[20201123 19:17:51.261 CET] [ozw.library] [info]: Info - Node: 15 Cache Save for Node 15 as its QueryStage_CacheLoad 
[20201123 19:17:51.261 CET] [ozw.library] [info]: Info - Node: 16 Cache Save for Node 16 as its QueryStage_CacheLoad 
[20201123 19:17:51.261 CET] [ozw.library] [info]: Info - Node: 17 Cache Save for Node 17 as its QueryStage_CacheLoad 
[20201123 19:17:51.261 CET] [ozw.library] [info]: Info - Node: 18 Cache Save for Node 18 as its QueryStage_CacheLoad 
[20201123 19:17:51.261 CET] [ozw.library] [info]: Info - Node: 19 Cache Save for Node 19 as its QueryStage_CacheLoad 
[20201123 19:17:51.262 CET] [ozw.library] [info]: Info - Node: 20 Cache Save for Node 20 as its QueryStage_CacheLoad 
[20201123 19:17:51.262 CET] [ozw.library] [info]: Info - Node: 21 Cache Save for Node 21 as its QueryStage_CacheLoad 
[20201123 19:17:51.262 CET] [ozw.library] [info]: Info - Node: 22 Cache Save for Node 22 as its QueryStage_CacheLoad 
[20201123 19:17:51.262 CET] [ozw.library] [info]: Info - Node: 23 Cache Save for Node 23 as its QueryStage_CacheLoad 
[20201123 19:17:51.263 CET] [ozw.library] [info]: Info - Node: 24 Cache Save for Node 24 as its QueryStage_CacheLoad 
[20201123 19:17:51.263 CET] [ozw.library] [info]: Info - Node: 26 Cache Save for Node 26 as its QueryStage_CacheLoad 
[20201123 19:17:51.263 CET] [ozw.library] [info]: Info - Node: 28 Cache Save for Node 28 as its QueryStage_CacheLoad 
[20201123 19:17:51.263 CET] [ozw.library] [info]: Info - Node: 29 Cache Save for Node 29 as its QueryStage_CacheLoad 
[20201123 19:17:51.264 CET] [ozw.library] [info]: Info - Node: 30 Cache Save for Node 30 as its QueryStage_CacheLoad 
[20201123 19:17:51.264 CET] [ozw.library] [info]: Info - Node: 31 Cache Save for Node 31 as its QueryStage_CacheLoad 
[20201123 19:17:51.264 CET] [ozw.library] [info]: Info - Node: 32 Cache Save for Node 32 as its QueryStage_CacheLoad 
[20201123 19:17:51.265 CET] [ozw.library] [info]: Info - Node: 33 Cache Save for Node 33 as its QueryStage_CacheLoad 
[20201123 19:17:51.265 CET] [ozw.library] [info]: Info - Node: 34 Cache Save for Node 34 as its QueryStage_CacheLoad 
[20201123 19:17:51.317 CET] [ozw.library] [debug]: Detail - Node: 22 Notification: Notification - NoOperation 
[20201123 19:17:51.317 CET] [ozw.library] [debug]: Detail - Node: 22 Notification: Notification - Node Asleep 
[20201123 19:17:51.317 CET] [ozw.library] [info]: Info - Node: 23 Sending (NoOp) message (Callback ID=0x1e, Expected Reply=0x13) - NoOperation_Set (Node=23): 0x01, 0x09, 0x00, 0x13, 0x17, 0x02, 0x00, 0x00, 0x25, 0x1e, 0xcb 
[20201123 19:17:51.317 CET] [ozw.notifications] [debug]: Notification pvt_ozwNotification NotificationTypes::Notification_Code_NoOperation Thread:  0x7f6f415fad48 
[20201123 19:17:51.317 CET] [ozw.library] [info]: Info - Node: 23 Encrypted Flag is 0 
[20201123 19:17:51.317 CET] [ozw.mqtt.publisher] [debug]: Publishing Event ozwNotification 
[20201123 19:17:51.317 CET] [ozw.notifications] [debug]: Notification pvt_ozwNotification NotificationTypes::Notification_Code_NodeAsleep Thread:  0x7f6f415fad48 
[20201123 19:17:51.317 CET] [ozw.mqtt.publisher] [debug]: Publishing Event ozwNotification 
[20201123 19:17:51.330 CET] [ozw.library] [debug]: Detail - Node: 23   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 
[20201123 19:17:51.330 CET] [ozw.library] [debug]: Detail - Node: 23   ZW_SEND_DATA delivered to Z-Wave stack

And during starting also the OZW admin tool is cloed with an error…

Another piece of the log:

[20201123 19:19:48.594 CET] [ozw.library] [info]: Info - Node: 12 Received Configuration report: Parameter=8, Value=15 
[20201123 19:19:48.594 CET] [ozw.library] [debug]: Detail - Node: 12   Expected reply and command class was received 
[20201123 19:19:48.594 CET] [ozw.library] [debug]: Detail - Node: 12   Message transaction complete 
[20201123 19:19:48.594 CET] [ozw.library] [debug]: Detail - Node: 12 Removing current message 
[20201123 19:19:48.594 CET] [ozw.library] [debug]: Detail - Node: 12 Notification: ValueChanged CC: COMMAND_CLASS_CONFIGURATION Instance: 1 Index: 8 
[20201123 19:19:48.594 CET] [ozw.library] [info]: Info - Node: 12 Sending (Query) message (Callback ID=0x97, Expected Reply=0x04) - ConfigurationCmd_Get (Node=12): 0x01, 0x0a, 0x00, 0x13, 0x0c, 0x03, 0x70, 0x05, 0x09, 0x25, 0x97, 0x27 
[20201123 19:19:48.594 CET] [ozw.library] [info]: Info - Node: 12 Encrypted Flag is 0 
[20201123 19:19:48.594 CET] [ozw.notifications] [debug]: Notification pvt_valueChanged:  2251800025235473 Thread:  0x7f6f415fad48 
[20201123 19:19:48.595 CET] [ozw.mqtt.publisher] [debug]: Publishing Event valueChanged: 2251800025235473 
[20201123 19:19:48.606 CET] [ozw.library] [debug]: Detail - Node: 12   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 
[20201123 19:19:48.606 CET] [ozw.library] [debug]: Detail - Node: 12   ZW_SEND_DATA delivered to Z-Wave stack 
[20201123 19:19:49.176 CET] [ozw.library] [debug]: Detail - Node: 12   Received: 0x01, 0x07, 0x00, 0x13, 0x97, 0x00, 0x00, 0x3a, 0x46 
[20201123 19:19:49.176 CET] [ozw.library] [debug]: Detail - Node: 12   ZW_SEND_DATA Request with callback ID 0x97 received (expected 0x97) 
[20201123 19:19:49.176 CET] [ozw.library] [info]: Info - Node: 12 Request RTT 582 Average Request RTT 416 
[20201123 19:19:49.176 CET] [ozw.library] [debug]: Detail - Node: 12   Expected callbackId was received 
[20201123 19:19:49.307 CET] [ozw.library] [debug]: Detail - Node: 12   Received: 0x01, 0x0c, 0x00, 0x04, 0x00, 0x0c, 0x06, 0x70, 0x06, 0x09, 0x02, 0x00, 0x02, 0x82 
[20201123 19:19:49.307 CET] [ozw.library] [info]: Info - Node: 12 Response RTT 713 Average Response RTT 572 
[20201123 19:19:49.307 CET] [ozw.library] [warning]: Warning - Node: 0 Attempt to get a Invalid Index 2 on ValueList HomeID: 0xdb2ec661, ValueID: (Id 0x000900000c9c0014, NodeID 12, Genre config, CC 0x70, Instance 1, Index 9, Type list) 
[20201123 19:19:49.307 CET] [ozw.library] [warning]: Warning - Node: 0 Attempt to Set a Invalid Index 2 for ValueList in OnValueRefreshed HomeID: 0xdb2ec661, ValueID: (Id 0x000900000c9c0014, NodeID 12, Genre config, CC 0x70, Instance 1, Index 9, Type list) 
[20201123 19:19:49.307 CET] [ozw.library] [info]: Info - Node: 12 Received Configuration report: Parameter=9, Value=2 
[20201123 19:19:49.308 CET] [ozw.library] [debug]: Detail - Node: 12   Expected reply and command class was received 
[20201123 19:19:49.308 CET] [ozw.library] [debug]: Detail - Node: 12   Message transaction complete 
[20201123 19:19:49.308 CET] [ozw.library] [debug]: Detail - Node: 12 Removing current message 
[20201123 19:19:49.308 CET] [ozw.library] [info]: Info - Node: 12 Sending (Query) message (Callback ID=0x98, Expected Reply=0x04) - ConfigurationCmd_Get (Node=12): 0x01, 0x0a, 0x00, 0x13, 0x0c, 0x03, 0x70, 0x05, 0x27, 0x25, 0x98, 0x06 
[20201123 19:19:49.308 CET] [ozw.library] [info]: Info - Node: 12 Encrypted Flag is 0 
[20201123 19:19:49.320 CET] [ozw.library] [debug]: Detail - Node: 12   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 
[20201123 19:19:49.320 CET] [ozw.library] [debug]: Detail - Node: 12   ZW_SEND_DATA delivered to Z-Wave stack 
[20201123 19:19:49.563 CET] [ozw.library] [debug]: Detail - Node: 12   Received: 0x01, 0x07, 0x00, 0x13, 0x98, 0x00, 0x00, 0x18, 0x6b 
[20201123 19:19:49.563 CET] [ozw.library] [debug]: Detail - Node: 12   ZW_SEND_DATA Request with callback ID 0x98 received (expected 0x98) 
[20201123 19:19:49.563 CET] [ozw.library] [info]: Info - Node: 12 Request RTT 254 Average Request RTT 335 
[20201123 19:19:49.563 CET] [ozw.library] [debug]: Detail - Node: 12   Expected callbackId was received 
[20201123 19:19:49.703 CET] [ozw.library] [debug]: Detail - Node: 12   Received: 0x01, 0x0b, 0x00, 0x04, 0x00, 0x0c, 0x05, 0x70, 0x06, 0x27, 0x01, 0x14, 0xbd 
[20201123 19:19:49.703 CET] [ozw.library] [info]: Info - Node: 12 Response RTT 395 Average Response RTT 483 
[20201123 19:19:49.703 CET] [ozw.library] [debug]: Detail - Node: 12 Initial read of value 
[20201123 19:19:49.703 CET] [ozw.library] [info]: Info - Node: 12 Received Configuration report: Parameter=39, Value=20 
[20201123 19:19:49.703 CET] [ozw.library] [debug]: Detail - Node: 12   Expected reply and command class was received 
[20201123 19:19:49.703 CET] [ozw.library] [debug]: Detail - Node: 12   Message transaction complete 
[20201123 19:19:49.703 CET] [ozw.library] [debug]: Detail - Node: 12 Removing current message 
[20201123 19:19:49.703 CET] [ozw.library] [debug]: Detail - Node: 12 Notification: ValueChanged CC: COMMAND_CLASS_CONFIGURATION Instance: 1 Index: 39 
[20201123 19:19:49.703 CET] [ozw.library] [info]: Info - Node: 12 Sending (Query) message (Callback ID=0x99, Expected Reply=0x04) - ConfigurationCmd_Get (Node=12): 0x01, 0x0a, 0x00, 0x13, 0x0c, 0x03, 0x70, 0x05, 0x28, 0x25, 0x99, 0x08 
[20201123 19:19:49.703 CET] [ozw.notifications] [debug]: Notification pvt_valueChanged:  10977524303265809 Thread:  0x7f6f415fad48 
[20201123 19:19:49.703 CET] [ozw.library] [info]: Info - Node: 12 Encrypted Flag is 0 
[20201123 19:19:49.705 CET] [ozw.mqtt.publisher] [debug]: Publishing Event valueChanged: 10977524303265809 
[20201123 19:19:49.719 CET] [ozw.library] [debug]: Detail - Node: 12   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 
[20201123 19:19:49.719 CET] [ozw.library] [debug]: Detail - Node: 12   ZW_SEND_DATA delivered to Z-Wave stack 
[20201123 19:19:49.957 CET] [ozw.library] [debug]: Detail - Node: 12   Received: 0x01, 0x07, 0x00, 0x13, 0x99, 0x00, 0x00, 0x17, 0x65 
[20201123 19:19:49.957 CET] [ozw.library] [debug]: Detail - Node: 12   ZW_SEND_DATA Request with callback ID 0x99 received (expected 0x99) 
[20201123 19:19:49.957 CET] [ozw.library] [info]: Info - Node: 12 Request RTT 253 Average Request RTT 294 
[20201123 19:19:49.957 CET] [ozw.library] [debug]: Detail - Node: 12   Expected callbackId was received 
[20201123 19:19:50.091 CET] [ozw.library] [debug]: Detail - Node: 12   Received: 0x01, 0x0b, 0x00, 0x04, 0x00, 0x0c, 0x05, 0x70, 0x06, 0x28, 0x01, 0x00, 0xa6 
[20201123 19:19:50.091 CET] [ozw.library] [info]: Info - Node: 12 Response RTT 388 Average Response RTT 435 
[20201123 19:19:50.091 CET] [ozw.library] [debug]: Detail - Node: 12 Initial read of value 
[20201123 19:19:50.091 CET] [ozw.library] [info]: Info - Node: 12 Received Configuration report: Parameter=40, Value=0 
[20201123 19:19:50.091 CET] [ozw.library] [debug]: Detail - Node: 12   Expected reply and command class was received 
[20201123 19:19:50.091 CET] [ozw.library] [debug]: Detail - Node: 12   Message transaction complete 
[20201123 19:19:50.091 CET] [ozw.library] [debug]: Detail - Node: 12 Removing current message 
[20201123 19:19:50.091 CET] [ozw.library] [debug]: Detail - Node: 12 Notification: ValueChanged CC: COMMAND_CLASS_CONFIGURATION Instance: 1 Index: 40 
[20201123 19:19:50.091 CET] [ozw.library] [info]: Info - Node: 12 Sending (Query) message (Callback ID=0x9a, Expected Reply=0x04) - ConfigurationCmd_Get (Node=12): 0x01, 0x0a, 0x00, 0x13, 0x0c, 0x03, 0x70, 0x05, 0x29, 0x25, 0x9a, 0x0a 
[20201123 19:19:50.091 CET] [ozw.library] [info]: Info - Node: 12 Encrypted Flag is 0 
[20201123 19:19:50.091 CET] [ozw.notifications] [debug]: Notification pvt_valueChanged:  11258999279976468 Thread:  0x7f6f415fad48 
[20201123 19:19:50.092 CET] [ozw.mqtt.publisher.value] [debug]: List Selected Value has Not Changed:  "Disabled" 
[20201123 19:19:50.093 CET] [ozw.mqtt.publisher] [debug]: Publishing Event valueChanged: 11258999279976468 
[20201123 19:19:50.110 CET] [ozw.library] [debug]: Detail - Node: 12   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 
[20201123 19:19:50.110 CET] [ozw.library] [debug]: Detail - Node: 12   ZW_SEND_DATA delivered to Z-Wave stack 
[20201123 19:19:50.324 CET] [ozw.library] [debug]: Detail - Node: 12   Received: 0x01, 0x07, 0x00, 0x13, 0x9a, 0x00, 0x00, 0x16, 0x67 
[20201123 19:19:50.325 CET] [ozw.library] [debug]: Detail - Node: 12   ZW_SEND_DATA Request with callback ID 0x9a received (expected 0x9a) 
[20201123 19:19:50.325 CET] [ozw.library] [info]: Info - Node: 12 Request RTT 233 Average Request RTT 263 
[20201123 19:19:50.325 CET] [ozw.library] [debug]: Detail - Node: 12   Expected callbackId was received 
[20201123 19:19:50.472 CET] [ozw.library] [debug]: Detail - Node: 12   Received: 0x01, 0x0e, 0x00, 0x04, 0x00, 0x0c, 0x08, 0x70, 0x06, 0x29, 0x04, 0x00, 0x14, 0x01, 0x00, 0xbf 
[20201123 19:19:50.472 CET] [ozw.library] [info]: Info - Node: 12 Response RTT 380 Average Response RTT 407 
[20201123 19:19:50.472 CET] [ozw.library] [debug]: Detail - Node: 12 Initial read of value 
[20201123 19:19:50.472 CET] [ozw.library] [info]: Info - Node: 12 Received Configuration report: Parameter=41, Value=1310976 
[20201123 19:19:50.472 CET] [ozw.library] [debug]: Detail - Node: 12   Expected reply and command class was received 
[20201123 19:19:50.472 CET] [ozw.library] [debug]: Detail - Node: 12   Message transaction complete 
[20201123 19:19:50.472 CET] [ozw.library] [debug]: Detail - Node: 12 Removing current message 
[20201123 19:19:50.472 CET] [ozw.library] [debug]: Detail - Node: 12 Notification: ValueChanged CC: COMMAND_CLASS_CONFIGURATION Instance: 1 Index: 41 
[20201123 19:19:50.472 CET] [ozw.library] [info]: Info - Node: 12 Sending (Query) message (Callback ID=0x9b, Expected Reply=0x04) - ConfigurationCmd_Get (Node=12): 0x01, 0x0a, 0x00, 0x13, 0x0c, 0x03, 0x70, 0x05, 0x2a, 0x25, 0x9b, 0x08 
[20201123 19:19:50.472 CET] [ozw.library] [info]: Info - Node: 12 Encrypted Flag is 0 
[20201123 19:19:50.472 CET] [ozw.notifications] [debug]: Notification pvt_valueChanged:  11540474256687123 Thread:  0x7f6f415fad48 
[20201123 19:19:50.473 CET] [ozw.mqtt.publisher] [debug]: Publishing Event valueChanged: 11540474256687123 
[20201123 19:19:50.485 CET] [ozw.library] [debug]: Detail - Node: 12   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 
[20201123 19:19:50.485 CET] [ozw.library] [debug]: Detail - Node: 12   ZW_SEND_DATA delivered to Z-Wave stack 
[20201123 19:19:50.720 CET] [ozw.library] [debug]: Detail - Node: 12   Received: 0x01, 0x07, 0x00, 0x13, 0x9b, 0x00, 0x00, 0x17, 0x67 
[20201123 19:19:50.720 CET] [ozw.library] [debug]: Detail - Node: 12   ZW_SEND_DATA Request with callback ID 0x9b received (expected 0x9b) 
[20201123 19:19:50.720 CET] [ozw.library] [info]: Info - Node: 12 Request RTT 248 Average Request RTT 255 
[20201123 19:19:50.720 CET] [ozw.library] [debug]: Detail - Node: 12   Expected callbackId was received 
[20201123 19:19:51.322 CET] [ozw.library] [debug]: Detail - Node: 12   Received: 0x01, 0x0b, 0x00, 0x04, 0x00, 0x0c, 0x05, 0x70, 0x06, 0x2a, 0x01, 0x0a, 0xae 
[20201123 19:19:51.323 CET] [ozw.library] [info]: Info - Node: 12 Response RTT 851 Average Response RTT 629 
[20201123 19:19:51.323 CET] [ozw.library] [debug]: Detail - Node: 12 Initial read of value 
[20201123 19:19:51.323 CET] [ozw.library] [info]: Info - Node: 12 Received Configuration report: Parameter=42, Value=10 
[20201123 19:19:51.323 CET] [ozw.library] [debug]: Detail - Node: 12   Expected reply and command class was received 
[20201123 19:19:51.323 CET] [ozw.library] [debug]: Detail - Node: 12   Message transaction complete 
[20201123 19:19:51.323 CET] [ozw.library] [debug]: Detail - Node: 12 Removing current message 
[20201123 19:19:51.323 CET] [ozw.library] [debug]: Detail - Node: 12 Notification: ValueChanged CC: COMMAND_CLASS_CONFIGURATION Instance: 1 Index: 42 
[20201123 19:19:51.323 CET] [ozw.library] [info]: Info - Node: 12 Sending (Query) message (Callback ID=0x9c, Expected Reply=0x04) - ConfigurationCmd_Get (Node=12): 0x01, 0x0a, 0x00, 0x13, 0x0c, 0x03, 0x70, 0x05, 0x2b, 0x25, 0x9c, 0x0e 
[20201123 19:19:51.323 CET] [ozw.notifications] [debug]: Notification pvt_valueChanged:  11821949233397777 Thread:  0x7f6f415fad48 
[20201123 19:19:51.323 CET] [ozw.library] [info]: Info - Node: 12 Encrypted Flag is 0 
[20201123 19:19:51.325 CET] [ozw.mqtt.publisher] [debug]: Publishing Event valueChanged: 11821949233397777 
[20201123 19:19:51.339 CET] [ozw.library] [debug]: Detail - Node: 12   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 
[20201123 19:19:51.340 CET] [ozw.library] [debug]: Detail - Node: 12   ZW_SEND_DATA delivered to Z-Wave stack

???

2

And next morning: ALL unresponsive untill I restart the addon…

What is wrong with this!?!?!

[20201125 7:28:58.904 CET] [ozw.library] [debug]: Detail - Node: 0 Timer: waiting with timeout 1000 ms 
[20201125 7:28:58.904 CET] [ozw.logging] [debug]: popping Log Mesages 
[20201125 7:28:58.905 CET] [ozw.library] [debug]: Detail - Node: 22 Notification: ValueChanged CC: COMMAND_CLASS_CENTRAL_SCENE Instance: 1 Index: 4 
[20201125 7:28:58.905 CET] [ozw.logging] [debug]: popping Log Mesages 
[20201125 7:28:58.905 CET] [ozw.notifications] [debug]: Notification pvt_valueChanged:  1125900281626644 Thread:  0x7fd81704ed48 
[20201125 7:28:58.908 CET] [ozw.mqtt.publisher] [debug]: Publishing Event valueChanged: 1125900281626644 
[20201125 7:28:59.905 CET] [ozw.library] [info]: Info - Node: 0 Timer: delayed event 
[20201125 7:28:59.905 CET] [ozw.logging] [debug]: popping Log Mesages 
[20201125 7:28:59.906 CET] [ozw.library] [debug]: Detail - Node: 22 Value Updated: old value=1, new value=0, type=list 
[20201125 7:28:59.906 CET] [ozw.logging] [debug]: popping Log Mesages 
[20201125 7:28:59.907 CET] [ozw.library] [debug]: Detail - Node: 22 Changes to this value are not verified 
[20201125 7:28:59.907 CET] [ozw.logging] [debug]: popping Log Mesages 
[20201125 7:28:59.908 CET] [ozw.library] [debug]: Detail - Node: 0 Timer: waiting with timeout -1 ms 
[20201125 7:28:59.908 CET] [ozw.logging] [debug]: popping Log Mesages 
[20201125 7:28:59.908 CET] [ozw.library] [debug]: Detail - Node: 22 Notification: ValueChanged CC: COMMAND_CLASS_CENTRAL_SCENE Instance: 1 Index: 4 
[20201125 7:28:59.908 CET] [ozw.logging] [debug]: popping Log Mesages 
[20201125 7:28:59.909 CET] [ozw.notifications] [debug]: Notification pvt_valueChanged:  1125900281626644 Thread:  0x7fd81704ed48 
[20201125 7:28:59.911 CET] [ozw.mqtt.publisher] [debug]: Publishing Event valueChanged: 1125900281626644 
[20201125 7:29:35.186 CET] [ozw.mqtt.publisher] [debug]: "Wed Nov 25 07:29:35 2020 PingResponse\n" 
[20201125 7:30:35.181 CET] [ozw.mqtt.publisher] [debug]: "Wed Nov 25 07:30:35 2020 PingResponse\n" 
[20201125 7:31:35.186 CET] [ozw.mqtt.publisher] [debug]: "Wed Nov 25 07:31:35 2020 PingResponse\n" 
[20201125 7:32:35.186 CET] [ozw.mqtt.publisher] [debug]: "Wed Nov 25 07:32:35 2020 PingResponse\n" 
[20201125 7:33:35.167 CET] [ozw.mqtt.publisher] [debug]: "Wed Nov 25 07:33:35 2020 PingResponse\n" 
[20201125 7:34:04.964 CET] [ozw.library] [debug]: Detail - Node: 31   Received: 0x01, 0x0c, 0x00, 0x04, 0x00, 0x1f, 0x06, 0x31, 0x05, 0x01, 0x22, 0x00, 0xcc, 0x35 
[20201125 7:34:04.964 CET] [ozw.logging] [debug]: popping Log Mesages 
[20201125 7:34:04.966 CET] [ozw.library] [info]: Info - Node: 31 Received SensorMultiLevel report from node 31, instance 1, Air Temperature: value=20.4C 
[20201125 7:34:04.966 CET] [ozw.logging] [debug]: popping Log Mesages 
[20201125 7:34:04.966 CET] [ozw.library] [debug]: Detail - Node: 31 Value Updated: old value=19.4, new value=20.4, type=decimal 
[20201125 7:34:04.966 CET] [ozw.logging] [debug]: popping Log Mesages 
[20201125 7:34:04.967 CET] [ozw.library] [debug]: Detail - Node: 31 Changes to this value are not verified 
[20201125 7:34:04.967 CET] [ozw.logging] [debug]: popping Log Mesages 
[20201125 7:34:04.967 CET] [ozw.library] [debug]: Detail - Node: 31 Notification: ValueChanged CC: COMMAND_CLASS_SENSOR_MULTILEVEL Instance: 1 Index: 1 
[20201125 7:34:04.967 CET] [ozw.logging] [debug]: popping Log Mesages 
[20201125 7:34:04.968 CET] [ozw.notifications] [debug]: Notification pvt_valueChanged:  281475501801490 Thread:  0x7fd81704ed48 
[20201125 7:34:04.971 CET] [ozw.mqtt.publisher] [debug]: Publishing Event valueChanged: 281475501801490 
[20201125 7:34:35.186 CET] [ozw.mqtt.publisher] [debug]: "Wed Nov 25 07:34:35 2020 PingResponse\n" 
[20201125 7:35:35.186 CET] [ozw.mqtt.publisher] [debug]: "Wed Nov 25 07:35:35 2020 PingResponse\n" 
[20201125 7:36:35.186 CET] [ozw.mqtt.publisher] [debug]: "Wed Nov 25 07:36:35 2020 PingResponse\n" 
[20201125 7:37:35.181 CET] [ozw.mqtt.publisher] [debug]: "Wed Nov 25 07:37:35 2020 PingResponse\n" 
[20201125 7:38:35.171 CET] [ozw.mqtt.publisher] [debug]: "Wed Nov 25 07:38:35 2020 PingResponse\n" 
[20201125 7:39:35.182 CET] [ozw.mqtt.publisher] [debug]: "Wed Nov 25 07:39:35 2020 PingResponse\n" 
[20201125 7:40:35.186 CET] [ozw.mqtt.publisher] [debug]: "Wed Nov 25 07:40:35 2020 PingResponse\n" 
[20201125 7:41:35.160 CET] [ozw.mqtt.publisher] [debug]: "Wed Nov 25 07:41:35 2020 PingResponse\n" 
[20201125 7:42:35.158 CET] [ozw.mqtt.publisher] [debug]: "Wed Nov 25 07:42:35 2020 PingResponse\n" 
[20201125 7:43:35.186 CET] [ozw.mqtt.publisher] [debug]: "Wed Nov 25 07:43:35 2020 PingResponse\n" 
[20201125 7:44:35.170 CET] [ozw.mqtt.publisher] [debug]: "Wed Nov 25 07:44:35 2020 PingResponse\n" 
1606286719: Saving in-memory database to /data/mosquitto.db.
[20201125 7:45:35.170 CET] [ozw.mqtt.publisher] [debug]: "Wed Nov 25 07:45:35 2020 PingResponse\n" 
[20201125 7:46:35.160 CET] [ozw.mqtt.publisher] [debug]: "Wed Nov 25 07:46:35 2020 PingResponse\n" 
[20201125 7:47:35.185 CET] [ozw.mqtt.publisher] [debug]: "Wed Nov 25 07:47:35 2020 PingResponse\n" 
[20201125 7:48:35.185 CET] [ozw.mqtt.publisher] [debug]: "Wed Nov 25 07:48:35 2020 PingResponse\n" 
[20201125 7:49:35.179 CET] [ozw.mqtt.publisher] [debug]: "Wed Nov 25 07:49:35 2020 PingResponse\n" 
[20201125 7:50:35.159 CET] [ozw.mqtt.publisher] [debug]: "Wed Nov 25 07:50:35 2020 PingResponse\n" 
[20201125 7:51:35.186 CET] [ozw.mqtt.publisher] [debug]: "Wed Nov 25 07:51:35 2020 PingResponse\n" 
[20201125 7:52:35.159 CET] [ozw.mqtt.publisher] [debug]: "Wed Nov 25 07:52:35 2020 PingResponse\n" 
[20201125 7:53:35.186 CET] [ozw.mqtt.publisher] [debug]: "Wed Nov 25 07:53:35 2020 PingResponse\n" 
[20201125 7:54:35.186 CET] [ozw.mqtt.publisher] [debug]: "Wed Nov 25 07:54:35 2020 PingResponse\n" 
[20201125 7:55:35.186 CET] [ozw.mqtt.publisher] [debug]: "Wed Nov 25 07:55:35 2020 PingResponse\n" 
[20201125 7:56:35.185 CET] [ozw.mqtt.publisher] [debug]: "Wed Nov 25 07:56:35 2020 PingResponse\n" 
[20201125 7:57:35.158 CET] [ozw.mqtt.publisher] [debug]: "Wed Nov 25 07:57:35 2020 PingResponse\n" 
[20201125 7:58:35.186 CET] [ozw.mqtt.publisher] [debug]: "Wed Nov 25 07:58:35 2020 PingResponse\n" 
[20201125 7:59:35.174 CET] [ozw.mqtt.publisher] [debug]: "Wed Nov 25 07:59:35 2020 PingResponse\n" 
[20201125 8:00:23.289 CET] [ozw.mqtt.commands] [debug]: Got  "OpenZWave/1/command/setvalue/"  Message:  "{\"ValueIDKey\": 189349936, \"Value\": true}" 
[20201125 8:00:23.289 CET] [ozw.mqtt.commands.setValue] [info]: Setting  189349936  to Value  QVariant(bool, true) 
[20201125 8:00:23.289 CET] [ozw.values] [debug]: setData Called for Row 379  With Value QVariant(bool, true) 
[20201125 8:00:23.289 CET] [ozw.values] [debug]: valueModel Changed! QTOZW_ValueIds::Value - 379 :  QVariant(bool, true) 
[20201125 8:00:23.289 CET] [ozw.values] [debug]: valueModel Changed! vidKey: 189349936 QVariant(bool, true) 
[20201125 8:00:23.290 CET] [ozw.library] [info]: Info - Node: 11 Value::Set - COMMAND_CLASS_SWITCH_BINARY - Switch - 0 - 3 - True 
[20201125 8:00:23.290 CET] [ozw.logging] [debug]: popping Log Mesages 
[20201125 8:00:23.290 CET] [ozw.library] [info]: Info - Node: 11 SwitchBinary::Set - Setting to On 
[20201125 8:00:23.290 CET] [ozw.logging] [debug]: popping Log Mesages 
[20201125 8:00:23.291 CET] [ozw.library] [debug]: Detail - Node: 11 Queuing (Send) MultiChannel Encapsulated (instance=3): SwitchBinaryCmd_Set (Node=11): 0x01, 0x0e, 0x00, 0x13, 0x0b, 0x07, 0x60, 0x0d, 0x01, 0x02, 0x25, 0x01, 0xff, 0x25, 0x7f, 0x01 
[20201125 8:00:23.291 CET] [ozw.logging] [debug]: popping Log Mesages 
[20201125 8:00:23.291 CET] [ozw.library] [debug]: Detail - Node: 11 Queuing (Send) MultiChannel Encapsulated (instance=3): SwitchBinaryCmd_Get (Node=11): 0x01, 0x0d, 0x00, 0x13, 0x0b, 0x06, 0x60, 0x0d, 0x01, 0x02, 0x25, 0x02, 0x25, 0x80, 0x00 
[20201125 8:00:23.291 CET] [ozw.logging] [debug]: popping Log Mesages 
[20201125 8:00:23.292 CET] [ozw.library] [info]: Info - Node: 11 Sending (Send) message (Callback ID=0x7f, Expected Reply=0x13) - MultiChannel Encapsulated (instance=3): SwitchBinaryCmd_Set (Node=11): 0x01, 0x0e, 0x00, 0x13, 0x0b, 0x07, 0x60, 0x0d, 0x01, 0x02, 0x25, 0x01, 0xff, 0x25, 0x7f, 0x01 
[20201125 8:00:23.292 CET] [ozw.logging] [debug]: popping Log Mesages 
[20201125 8:00:23.292 CET] [ozw.mqtt.commands] [info]: Processed Message for  "setvalue" :  "{\"ValueIDKey\": 189349936, \"Value\": true}" 
[20201125 8:00:23.292 CET] [ozw.library] [info]: Info - Node: 11 Encrypted Flag is 0 
[20201125 8:00:23.292 CET] [ozw.logging] [debug]: popping Log Mesages 
[20201125 8:00:24.293 CET] [ozw.library] [critical]: Error - Node: 11 ERROR: Dropping command, expected response not received after 1 attempt(s). Command: "MultiChannel Encapsulated (instance=3): SwitchBinaryCmd_Set (Node=11): 0x01, 0x0e, 0x00, 0x13, 0x0b, 0x07, 0x60, 0x0d, 0x01, 0x02, 0x25, 0x01, 0xff, 0x25, 0x7f, 0x01" 
[20201125 8:00:24.293 CET] [ozw.logging] [debug]: popping Log Mesages 
[20201125 8:00:24.294 CET] [ozw.library] [debug]: Detail - Node: 11 Removing current message 
[20201125 8:00:24.294 CET] [ozw.logging] [debug]: popping Log Mesages 
[20201125 8:00:24.295 CET] [ozw.library] [debug]: Detail - Node: 11 Notification: Notification - TimeOut 
[20201125 8:00:24.295 CET] [ozw.logging] [debug]: popping Log Mesages 
[20201125 8:00:24.296 CET] [ozw.library] [info]: Info - Node: 11 Sending (Send) message (Callback ID=0x80, Expected Reply=0x04) - MultiChannel Encapsulated (instance=3): SwitchBinaryCmd_Get (Node=11): 0x01, 0x0d, 0x00, 0x13, 0x0b, 0x06, 0x60, 0x0d, 0x01, 0x02, 0x25, 0x02, 0x25, 0x80, 0x00 
[20201125 8:00:24.296 CET] [ozw.notifications] [debug]: Notification pvt_ozwNotification NotificationTypes::Notification_Code_MsgTimeout Thread:  0x7fd81704ed48 
[20201125 8:00:24.296 CET] [ozw.mqtt.publisher] [debug]: Publishing Event ozwNotification 
[20201125 8:00:24.296 CET] [ozw.logging] [debug]: popping Log Mesages 
[20201125 8:00:24.297 CET] [ozw.library] [info]: Info - Node: 11 Encrypted Flag is 0 
[20201125 8:00:24.299 CET] [ozw.logging] [debug]: popping Log Mesages 
[20201125 8:00:25.300 CET] [ozw.library] [critical]: Error - Node: 11 ERROR: Dropping command, expected response not received after 1 attempt(s). Command: "MultiChannel Encapsulated (instance=3): SwitchBinaryCmd_Get (Node=11): 0x01, 0x0d, 0x00, 0x13, 0x0b, 0x06, 0x60, 0x0d, 0x01, 0x02, 0x25, 0x02, 0x25, 0x80, 0x00" 
[20201125 8:00:25.300 CET] [ozw.logging] [debug]: popping Log Mesages 
[20201125 8:00:25.301 CET] [ozw.library] [debug]: Detail - Node: 11 Removing current message 
[20201125 8:00:25.301 CET] [ozw.logging] [debug]: popping Log Mesages 
[20201125 8:00:25.301 CET] [ozw.library] [debug]: Detail - Node: 11 Notification: Notification - TimeOut 
[20201125 8:00:25.301 CET] [ozw.logging] [debug]: popping Log Mesages 
[20201125 8:00:25.302 CET] [ozw.notifications] [debug]: Notification pvt_ozwNotification NotificationTypes::Notification_Code_MsgTimeout Thread:  0x7fd81704ed48 
[20201125 8:00:25.302 CET] [ozw.mqtt.publisher] [debug]: Publishing Event ozwNotification

This is logging like madness!

image
image1075×496 13 KB

But here only:

[20201125 8:00:25.300 CET] [ozw.logging] [debug]: popping Log Mesages 
[20201125 8:00:25.301 CET] [ozw.library] [debug]: Detail - Node: 11 Removing current message 
[20201125 8:00:25.301 CET] [ozw.logging] [debug]: popping Log Mesages 
[20201125 8:00:25.301 CET] [ozw.library] [debug]: Detail - Node: 11 Notification: Notification - TimeOut 
[20201125 8:00:25.301 CET] [ozw.logging] [debug]: popping Log Mesages 
[20201125 8:00:25.302 CET] [ozw.notifications] [debug]: Notification pvt_ozwNotification NotificationTypes::Notification_Code_MsgTimeout Thread:  0x7fd81704ed48 
[20201125 8:00:25.302 CET] [ozw.mqtt.publisher] [debug]: Publishing Event ozwNotification 
[20201125 8:00:35.162 CET] [ozw.mqtt.publisher] [debug]: "Wed Nov 25 08:00:35 2020 PingResponse\n" 
[20201125 8:01:35.183 CET] [ozw.mqtt.publisher] [debug]: "Wed Nov 25 08:01:35 2020 PingResponse\n" 
[20201125 8:02:21.356 CET] [ozw.mqtt.commands] [debug]: Got  "OpenZWave/1/command/setvalue/"  Message:  "{\"ValueIDKey\": 189349936, \"Value\": true}" 
[20201125 8:02:21.356 CET] [ozw.mqtt.commands.setValue] [info]: Setting  189349936  to Value  QVariant(bool, true) 
[20201125 8:02:21.356 CET] [ozw.mqtt.commands] [info]: Processed Message for  "setvalue" :  "{\"ValueIDKey\": 189349936, \"Value\": true}" 
[20201125 8:02:31.654 CET] [ozw.manager] [info]: New Client WebSocket Connection QHostAddress("::ffff:192.168.1.160") 
[20201125 8:02:31.659 CET] [ozw.manager] [info]: Client Connection Authenticated! 
[20201125 8:02:32.023 CET] [ozw.logging] [debug]: QTOZWLog_Internal::syncroniseLogs() called 
[20201125 8:02:32.023 CET] [ozw.logging] [debug]: QTOZWLog_Internal::sendSyncLogsBatch running 
[20201125 8:02:32.345 CET] [ozw.manager] [debug]: ProductPic Path:  "/data/ozw/config/images/aeotec/zw090.png" 
[20201125 8:02:32.549 CET] [ozw.logging] [debug]: QTOZWLog_Internal::sendSyncLogsBatch running 
[20201125 8:02:33.074 CET] [ozw.logging] [debug]: QTOZWLog_Internal::sendSyncLogsBatch running 
[20201125 8:02:33.599 CET] [ozw.logging] [debug]: QTOZWLog_Internal::sendSyncLogsBatch running 
[20201125 8:02:34.124 CET] [ozw.logging] [debug]: QTOZWLog_Internal::sendSyncLogsBatch running 
[20201125 8:02:34.649 CET] [ozw.logging] [debug]: QTOZWLog_Internal::sendSyncLogsBatch running 
[20201125 8:02:35.160 CET] [ozw.logging] [debug]: QTOZWLog_Internal::sendSyncLogsBatch running 
[20201125 8:02:35.161 CET] [ozw.mqtt.publisher] [debug]: "Wed Nov 25 08:02:35 2020 PingResponse\n" 
[20201125 8:02:35.660 CET] [ozw.logging] [debug]: QTOZWLog_Internal::sendSyncLogsBatch running 
[20201125 8:02:36.160 CET] [ozw.logging] [debug]: QTOZWLog_Internal::sendSyncLogsBatch running 
[20201125 8:02:36.660 CET] [ozw.logging] [debug]: QTOZWLog_Internal::sendSyncLogsBatch running 
[20201125 8:02:37.163 CET] [ozw.logging] [debug]: QTOZWLog_Internal::sendSyncLogsBatch running 
[20201125 8:02:37.660 CET] [ozw.logging] [debug]: QTOZWLog_Internal::sendSyncLogsBatch running 
[20201125 8:02:38.160 CET] [ozw.logging] [debug]: QTOZWLog_Internal::sendSyncLogsBatch running 
[20201125 8:02:38.660 CET] [ozw.logging] [debug]: QTOZWLog_Internal::sendSyncLogsBatch running 
[20201125 8:02:39.160 CET] [ozw.logging] [debug]: QTOZWLog_Internal::sendSyncLogsBatch running 
[20201125 8:02:39.660 CET] [ozw.logging] [debug]: QTOZWLog_Internal::sendSyncLogsBatch running 
[20201125 8:02:40.160 CET] [ozw.logging] [debug]: QTOZWLog_Internal::sendSyncLogsBatch running 
[20201125 8:02:40.660 CET] [ozw.logging] [debug]: QTOZWLog_Internal::sendSyncLogsBatch running 
[20201125 8:02:41.160 CET] [ozw.logging] [debug]: QTOZWLog_Internal::sendSyncLogsBatch running 
[20201125 8:02:41.660 CET] [ozw.logging] [debug]: QTOZWLog_Internal::sendSyncLogsBatch running 
[20201125 8:02:42.160 CET] [ozw.logging] [debug]: QTOZWLog_Internal::sendSyncLogsBatch running 
[20201125 8:02:42.660 CET] [ozw.logging] [debug]: QTOZWLog_Internal::sendSyncLogsBatch running 
[20201125 8:02:43.160 CET] [ozw.logging] [debug]: QTOZWLog_Internal::sendSyncLogsBatch running 
[20201125 8:02:43.660 CET] [ozw.logging] [debug]: QTOZWLog_Internal::sendSyncLogsBatch running 
[20201125 8:02:44.160 CET] [ozw.logging] [debug]: QTOZWLog_Internal::sendSyncLogsBatch running 
[20201125 8:02:44.660 CET] [ozw.logging] [debug]: QTOZWLog_Internal::sendSyncLogsBatch running 
[20201125 8:02:45.160 CET] [ozw.logging] [debug]: QTOZWLog_Internal::sendSyncLogsBatch running 
[20201125 8:02:45.659 CET] [ozw.logging] [debug]: QTOZWLog_Internal::sendSyncLogsBatch running 
[20201125 8:02:46.160 CET] [ozw.logging] [debug]: QTOZWLog_Internal::sendSyncLogsBatch running 
[20201125 8:02:46.660 CET] [ozw.logging] [debug]: QTOZWLog_Internal::sendSyncLogsBatch running 
[20201125 8:02:47.160 CET] [ozw.logging] [debug]: QTOZWLog_Internal::sendSyncLogsBatch running 
[20201125 8:02:47.660 CET] [ozw.logging] [debug]: QTOZWLog_Internal::sendSyncLogsBatch running 
[20201125 8:02:48.160 CET] [ozw.logging] [debug]: QTOZWLog_Internal::sendSyncLogsBatch running 
[20201125 8:02:48.660 CET] [ozw.logging] [debug]: QTOZWLog_Internal::sendSyncLogsBatch running 
[20201125 8:02:49.159 CET] [ozw.logging] [debug]: QTOZWLog_Internal::sendSyncLogsBatch running 
[20201125 8:02:49.660 CET] [ozw.logging] [debug]: QTOZWLog_Internal::sendSyncLogsBatch running 
[20201125 8:02:50.160 CET] [ozw.logging] [debug]: QTOZWLog_Internal::sendSyncLogsBatch running 
[20201125 8:02:50.660 CET] [ozw.logging] [debug]: QTOZWLog_Internal::sendSyncLogsBatch running 
[20201125 8:02:51.160 CET] [ozw.logging] [debug]: QTOZWLog_Internal::sendSyncLogsBatch running 
[20201125 8:02:51.660 CET] [ozw.logging] [debug]: QTOZWLog_Internal::sendSyncLogsBatch running 
[20201125 8:02:52.160 CET] [ozw.logging] [debug]: QTOZWLog_Internal::sendSyncLogsBatch running 
[20201125 8:02:52.660 CET] [ozw.logging] [debug]: QTOZWLog_Internal::sendSyncLogsBatch running 
[20201125 8:02:53.160 CET] [ozw.logging] [debug]: QTOZWLog_Internal::sendSyncLogsBatch running 
[20201125 8:02:53.659 CET] [ozw.logging] [debug]: QTOZWLog_Internal::sendSyncLogsBatch running 
[20201125 8:02:54.160 CET] [ozw.logging] [debug]: QTOZWLog_Internal::sendSyncLogsBatch running 
[20201125 8:02:54.660 CET] [ozw.logging] [debug]: QTOZWLog_Internal::sendSyncLogsBatch running 
[20201125 8:02:55.160 CET] [ozw.logging] [debug]: QTOZWLog_Internal::sendSyncLogsBatch running 
[20201125 8:02:55.660 CET] [ozw.logging] [debug]: QTOZWLog_Internal::sendSyncLogsBatch running 
[20201125 8:02:56.160 CET] [ozw.logging] [debug]: QTOZWLog_Internal::sendSyncLogsBatch running 
[20201125 8:02:56.660 CET] [ozw.logging] [debug]: QTOZWLog_Internal::sendSyncLogsBatch running 
[20201125 8:02:57.160 CET] [ozw.logging] [debug]: QTOZWLog_Internal::sendSyncLogsBatch running 
[20201125 8:02:57.660 CET] [ozw.logging] [debug]: QTOZWLog_Internal::sendSyncLogsBatch running 
[20201125 8:02:58.160 CET] [ozw.logging] [debug]: QTOZWLog_Internal::sendSyncLogsBatch running 
[20201125 8:02:58.660 CET] [ozw.logging] [debug]: QTOZWLog_Internal::sendSyncLogsBatch running 
[20201125 8:02:59.160 CET] [ozw.logging] [debug]: QTOZWLog_Internal::sendSyncLogsBatch running 
[20201125 8:02:59.660 CET] [ozw.logging] [debug]: QTOZWLog_Internal::sendSyncLogsBatch running 
[20201125 8:03:00.160 CET] [ozw.logging] [debug]: QTOZWLog_Internal::sendSyncLogsBatch running 
[20201125 8:03:00.660 CET] [ozw.logging] [debug]: QTOZWLog_Internal::sendSyncLogsBatch running 
[20201125 8:03:01.160 CET] [ozw.logging] [debug]: QTOZWLog_Internal::sendSyncLogsBatch running 
[20201125 8:03:01.660 CET] [ozw.logging] [debug]: QTOZWLog_Internal::sendSyncLogsBatch running 
[20201125 8:03:02.160 CET] [ozw.logging] [debug]: QTOZWLog_Internal::sendSyncLogsBatch running 
[20201125 8:03:02.660 CET] [ozw.logging] [debug]: QTOZWLog_Internal::sendSyncLogsBatch running 
[20201125 8:03:03.160 CET] [ozw.logging] [debug]: QTOZWLog_Internal::sendSyncLogsBatch running 
[20201125 8:03:03.660 CET] [ozw.logging] [debug]: QTOZWLog_Internal::sendSyncLogsBatch running 
[20201125 8:03:04.160 CET] [ozw.logging] [debug]: QTOZWLog_Internal::sendSyncLogsBatch running 
[20201125 8:03:04.660 CET] [ozw.logging] [debug]: QTOZWLog_Internal::sendSyncLogsBatch running
3

I had it again… out of the blue unresponsive.

Any chance for advise?

4

What’s your zwave adapter?

5

Aeotec gen5

6

I think I will be a frequent post for this topic… FFS all is unavailable again!

Following this topic:

and further down… How can I extract my logging of the addon!?

The first step to find out is to get the logging out properly… At this moment my only resolution is to restart the container.

Additional information:
Unavailable does not necessarily mean that the entities are not available. In home assistant they are available but not responding. If I turn them on the turn off immediately. If I restart the container the are really offline for a long while and everything comes back then.

PLEASE HELP… SOMEONE :-)?

7

Can you still operate the devices through the OZW admin? What are your nodes 11 and 12 as they seem to be flooding in the logs you have provided?

8

I have not tried that. Will do asap (hope not tomorrow :-)…

image
image1322×92 8.42 KB

image
image1373×995 33.1 KB

image
image1357×996 27.3 KB

Any tips on how to get the full text log out of the addon (container)?

9

i’m using two zwave-me ZME_UZB1 usb drive on two different installation: one on my personal Odroid N2 and one on my parents virtual machine (docker container under debian os). Both are stable here. Please check if OZW admin page let you trigger the relays when hass crash, so we can isolate if the problem is the zwave container, zwave drive or mqtt server/client on hass.

1 Like
10

I’m unsure to be honest, not running the addon on HA here.