Dode z-wave schakelaars bij toevoegen fibaro sensor

[MoiZie]

Ik heb al tijden een gek z-wave probleem, waar ik niet uit kom.
Mijn netwerk thuis bestaat uit enkele Qubino dubbele relais, qubino dimmers, neo coolcam bewegingsensoren, neo coolcam deursensoren en neo coolcam z-wave en wifi plugs. Dit draait op een Aeotec gen5 USB stick, onder een home-assistant instantie (core) op een VM op mijn thuis-server (debian based, modemmanager = verwijderd op zowel de host als de VM zelf).

Wat is het geval; zodra ik een fibaro binary sensor toevoeg om mijn deurbel te detecteren (werkt feilloos), valt op random momenten Qubino relais 1 (gang) of Qubino relais 2 (badkamer) uit het netwerk. Incidenteel is power-plug 'vaatwasser' het slachtoffer, maar dat gebeurt vriwjel nooit. De node is dan in alle gevallen 'dead' en moet opnieuw ge-include worden.

De afgelopen 2 weken is dit niet gebeurt; ik had de deurbel 'fibaro binary sensor' ge-exclude uit het netwerk in het kader van debugging. Ik dacht dus het probleem gevonden te hebben, en heb een fibaro smart implant gekocht. De ratio hierachter; mijn hele netwerk is z-wave plus, behalve die sensor, dus misschien lag het daar aan??

Vandaag is de fibaro smart implant binnen gekomen, ik heb hem netjes aangesloten, ge-include en ja hoor, enkele herstart van home-assistant later: qubino relais 2 (badkamer) is weer eens 'dead'. Ik geloof niet dat het toevallig 2 weken goed ging, waarbij hass ook meermaals herstart is geweest in die periode, en nu met die sensor loopt alles weer mis.

Echter; hoe debug je dit? ==> OZW log

Wat valt mij in OZW log op?

code:

2020-04-18 19:16:56.588 Always, OpenZwave Version 1.4.3469 Starting Up
2020-04-18 19:17:02.635 Info, Setting Up Provided Network Key for Secure Communications
2020-04-18 19:17:02.635 Warning, Failed - Network Key Not Set
2020-04-18 19:17:02.635 Info, mgr,     Added driver for controller /dev/ttyACM0
2020-04-18 19:17:02.635 Info,   Opening controller /dev/ttyACM0
2020-04-18 19:17:02.635 Info, Trying to open serial port /dev/ttyACM0 (attempt 1)
2020-04-18 19:17:02.638 Info, Serial port /dev/ttyACM0 opened (attempt 1)
2020-04-18 19:17:02.639 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_VERSION: 0x01, 0x03, 0x00, 0x15, 0xe9
2020-04-18 19:17:02.639 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_MEMORY_GET_ID: 0x01, 0x03, 0x00, 0x20, 0xdc
2020-04-18 19:17:02.639 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_CONTROLLER_CAPABILITIES: 0x01, 0x03, 0x00, 0x05, 0xf9
2020-04-18 19:17:02.639 Detail, contrlr, Queuing (Command) FUNC_ID_SERIAL_API_GET_CAPABILITIES: 0x01, 0x03, 0x00, 0x07, 0xfb
2020-04-18 19:17:02.639 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_SUC_NODE_ID: 0x01, 0x03, 0x00, 0x56, 0xaa
2020-04-18 19:17:02.639 Detail, 
2020-04-18 19:17:02.639 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x15) - FUNC_ID_ZW_GET_VERSION: 0x01, 0x03, 0x00, 0x15, 0xe9
2020-04-18 19:17:02.642 Detail, contrlr,   Received: 0x01, 0x10, 0x01, 0x15, 0x5a, 0x2d, 0x57, 0x61, 0x76, 0x65, 0x20, 0x33, 0x2e, 0x39, 0x35, 0x00, 0x01, 0x99
2020-04-18 19:17:02.642 Detail, 
2020-04-18 19:17:02.642 Info, contrlr, Received reply to FUNC_ID_ZW_GET_VERSION:
2020-04-18 19:17:02.642 Info, contrlr,     Static Controller library, version Z-Wave 3.95
2020-04-18 19:17:02.642 Detail, Node045,   Expected reply was received
2020-04-18 19:17:02.642 Detail, Node045,   Message transaction complete
2020-04-18 19:17:02.642 Detail, 
2020-04-18 19:17:02.642 Detail, contrlr, Removing current message
2020-04-18 19:17:02.642 Detail, 
2020-04-18 19:17:02.642 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x20) - FUNC_ID_ZW_MEMORY_GET_ID: 0x01, 0x03, 0x00, 0x20, 0xdc
2020-04-18 19:17:02.646 Detail, contrlr,   Received: 0x01, 0x08, 0x01, 0x20, 0xfe, 0x40, 0xda, 0x83, 0x01, 0x30
2020-04-18 19:17:02.646 Detail, 
2020-04-18 19:17:02.646 Info, contrlr, Received reply to FUNC_ID_ZW_MEMORY_GET_ID. Home ID = 0xfe40da83.  Our node ID = 1
2020-04-18 19:17:02.646 Detail, Node064,   Expected reply was received
2020-04-18 19:17:02.646 Detail, Node064,   Message transaction complete
2020-04-18 19:17:02.646 Detail, 
2020-04-18 19:17:02.646 Detail, contrlr, Removing current message
2020-04-18 19:17:02.646 Detail, 
2020-04-18 19:17:02.646 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x05) - FUNC_ID_ZW_GET_CONTROLLER_CAPABILITIES: 0x01, 0x03, 0x00, 0x05, 0xf9
2020-04-18 19:17:02.649 Detail, contrlr,   Received: 0x01, 0x04, 0x01, 0x05, 0x08, 0xf7
2020-04-18 19:17:02.649 Detail, 
2020-04-18 19:17:02.649 Info, contrlr, Received reply to FUNC_ID_ZW_GET_CONTROLLER_CAPABILITIES:
2020-04-18 19:17:02.649 Info, contrlr,     There is no SUC ID Server (SIS) in this network.
2020-04-18 19:17:02.649 Info, contrlr,     The PC controller is a primary controller.
2020-04-18 19:17:02.649 Detail, Node247,   Expected reply was received
2020-04-18 19:17:02.649 Detail, Node247,   Message transaction complete
2020-04-18 19:17:02.649 Detail, 
2020-04-18 19:17:02.649 Detail, contrlr, Removing current message
2020-04-18 19:17:02.649 Detail, 
2020-04-18 19:17:02.649 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x07) - FUNC_ID_SERIAL_API_GET_CAPABILITIES: 0x01, 0x03, 0x00, 0x07, 0xfb
2020-04-18 19:17:02.655 Detail, contrlr,   Received: 0x01, 0x2b, 0x01, 0x07, 0x01, 0x00, 0x00, 0x86, 0x00, 0x01, 0x00, 0x5a, 0xfe, 0x81, 0xff, 0x88, 0x4f, 0x1f, 0x00, 0x00, 0xfb, 0x9f, 0x7d, 0xa0, 0x67, 0x00, 0x00, 0x80, 0x00, 0x80, 0x86, 0x00, 0x00, 0x00, 0xe8, 0x73, 0x00, 0x00, 0x0e, 0x00, 0x00, 0x60, 0x00, 0x00, 0xfb
2020-04-18 19:17:02.655 Detail, 
2020-04-18 19:17:02.655 Info, contrlr,  Received reply to FUNC_ID_SERIAL_API_GET_CAPABILITIES
2020-04-18 19:17:02.656 Info, contrlr,     Serial API Version:   1.0
2020-04-18 19:17:02.656 Info, contrlr,     Manufacturer ID:      0x0086
2020-04-18 19:17:02.656 Info, contrlr,     Product Type:         0x0001
2020-04-18 19:17:02.656 Info, contrlr,     Product ID:           0x005a
2020-04-18 19:17:02.656 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_RANDOM: 0x01, 0x04, 0x00, 0x1c, 0x20, 0xc7
2020-04-18 19:17:02.656 Detail, contrlr, Queuing (Command) FUNC_ID_SERIAL_API_GET_INIT_DATA: 0x01, 0x03, 0x00, 0x02, 0xfe
2020-04-18 19:17:02.656 Detail, contrlr, Queuing (Command) FUNC_ID_SERIAL_API_SET_TIMEOUTS: 0x01, 0x05, 0x00, 0x06, 0x64, 0x0f, 0x97
2020-04-18 19:17:02.656 Detail, contrlr, Queuing (Command) FUNC_ID_SERIAL_API_APPL_NODE_INFORMATION: 0x01, 0x07, 0x00, 0x03, 0x01, 0x02, 0x01, 0x00, 0xf9
2020-04-18 19:17:02.656 Detail,   Expected reply was received
2020-04-18 19:17:02.656 Detail,   Message transaction complete
2020-04-18 19:17:02.656 Detail, 
2020-04-18 19:17:02.656 Detail, contrlr, Removing current message
2020-04-18 19:17:02.656 Detail, 
2020-04-18 19:17:02.656 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x56) - FUNC_ID_ZW_GET_SUC_NODE_ID: 0x01, 0x03, 0x00, 0x56, 0xaa
2020-04-18 19:17:02.658 Detail, contrlr,   Received: 0x01, 0x04, 0x01, 0x56, 0x00, 0xac
2020-04-18 19:17:02.658 Detail, 
2020-04-18 19:17:02.658 Info, contrlr, Received reply to GET_SUC_NODE_ID.  Node ID = 0
2020-04-18 19:17:02.658 Info, Controller Does not Support SUC - Cannot Setup Controller as SUC Node
2020-04-18 19:17:02.658 Detail, Node172,   Expected reply was received
2020-04-18 19:17:02.658 Detail, Node172,   Message transaction complete
2020-04-18 19:17:02.658 Detail, 
2020-04-18 19:17:02.658 Detail, contrlr, Removing current message
2020-04-18 19:17:02.658 Detail, 
2020-04-18 19:17:02.658 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x1c) - FUNC_ID_ZW_GET_RANDOM: 0x01, 0x04, 0x00, 0x1c, 0x20, 0xc7
2020-04-18 19:17:02.720 Detail, contrlr,   Received: 0x01, 0x25, 0x01, 0x1c, 0x01, 0x20, 0xe7, 0xf4, 0x21, 0x8d, 0xb5, 0xa6, 0xc7, 0xb4, 0x91, 0x09, 0xdf, 0xc6, 0x42, 0xd8, 0xaa, 0x3c, 0xd0, 0xb3, 0xee, 0xe0, 0x92, 0x83, 0xdd, 0xdb, 0x86, 0x01, 0x74, 0x80, 0x9a, 0x0c, 0x91, 0x20, 0x9a
2020-04-18 19:17:02.720 Detail, 
2020-04-18 19:17:02.720 Info, contrlr, Received reply to FUNC_ID_ZW_GET_RANDOM: true
2020-04-18 19:17:02.720 Detail, Node032,   Expected reply was received
2020-04-18 19:17:02.720 Detail, Node032,   Message transaction complete
2020-04-18 19:17:02.720 Detail, 
2020-04-18 19:17:02.720 Detail, contrlr, Removing current message
2020-04-18 19:17:02.720 Detail, 
2020-04-18 19:17:02.720 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x02) - FUNC_ID_SERIAL_API_GET_INIT_DATA: 0x01, 0x03, 0x00, 0x02, 0xfe
2020-04-18 19:17:02.818 Detail, contrlr,   Received: 0x01, 0x25, 0x01, 0x02, 0x05, 0x00, 0x1d, 0xf7, 0x5f, 0x3f, 0x49, 0x1b, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x05, 0x00, 0x01
2020-04-18 19:17:02.818 Detail, 
2020-04-18 19:17:02.818 Info, mgr,     Driver with Home ID of 0xfe40da83 is now ready.

Oa valt op dat hij een node 064, 247 en 045 ziet; zo ver reikt mijn node-aantal helemaal niet.

Even verderop komt:

code:

2020-04-18 19:18:13.716 Detail, Node036,   Received: 0x01, 0x07, 0x00, 0x13, 0x21, 0x01, 0x03, 0x8e, 0x46
2020-04-18 19:18:13.716 Detail, Node036,   ZW_SEND_DATA Request with callback ID 0x21 received (expected 0x21)
2020-04-18 19:18:13.716 Info, Node036, WARNING: ZW_SEND_DATA failed. No ACK received - device may be asleep.
2020-04-18 19:18:13.716 Warning, Node036, WARNING: Device is not a sleeping node.
2020-04-18 19:18:13.716 Info, Node036, QueryStageRetry stage CacheLoad requested stage CacheLoad max 3 retries 0 pending 1

Uiteindelijk geeft hass het op:

code:

2020-04-18 19:19:22.172 Detail, Node036,   Received: 0x01, 0x07, 0x00, 0x13, 0x3c, 0x01, 0x03, 0xb0, 0x65
2020-04-18 19:19:22.172 Detail, Node036,   ZW_SEND_DATA Request with callback ID 0x3c received (expected 0x3c)
2020-04-18 19:19:22.172 Info, Node036, WARNING: ZW_SEND_DATA failed. No ACK received - device may be asleep.
2020-04-18 19:19:22.172 Warning, Node036, WARNING: Device is not a sleeping node.
2020-04-18 19:19:22.172 Detail, Node001,   Expected callbackId was received
2020-04-18 19:19:22.172 Detail, Node001,   Expected reply was received
2020-04-18 19:19:22.172 Detail, Node001,   Message transaction complete
2020-04-18 19:19:22.172 Detail, 
2020-04-18 19:19:22.172 Detail, Node036, Removing current message
2020-04-18 19:19:22.172 Detail, 
2020-04-18 19:19:22.172 Info, Node036, Sending (Send) message (Callback ID=0x3d, Expected Reply=0x04) - MultiChannel Encapsulated (instance=2): SwitchBinaryCmd_Get (Node=36): 0x01, 0x0d, 0x00, 0x13, 0x24, 0x06, 0x60, 0x0d, 0x01, 0x01, 0x25, 0x02, 0x25, 0x3d, 0x91
2020-04-18 19:19:22.302 Detail, Node036,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-04-18 19:19:22.302 Detail, Node036,   ZW_SEND_DATA delivered to Z-Wave stack
2020-04-18 19:19:30.311 Detail, Node036,   Received: 0x01, 0x07, 0x00, 0x13, 0x3d, 0x01, 0x03, 0x2d, 0xf9
2020-04-18 19:19:30.311 Detail, Node036,   ZW_SEND_DATA Request with callback ID 0x3d received (expected 0x3d)
2020-04-18 19:19:30.311 Info, Node036, WARNING: ZW_SEND_DATA failed. No ACK received - device may be asleep.
2020-04-18 19:19:30.311 Warning, Node036, WARNING: Device is not a sleeping node.
2020-04-18 19:19:30.311 Error, Node036, ERROR: node presumed dead
2020-04-18 19:19:30.311 Warning, CheckCompletedNodeQueries m_allNodesQueried=0 m_awakeNodesQueried=0
2020-04-18 19:19:30.311 Warning, CheckCompletedNodeQueries all=0, deadFound=1 sleepingOnly=0
2020-04-18 19:19:30.311 Detail, Node001,   Expected callbackId was received
2020-04-18 19:19:30.311 Detail, Node036, Notification: Notification - Node Dead
2020-04-18 19:19:32.173 Error, Node036, ERROR: Dropping command because node is presumed dead
2020-04-18 19:19:32.173 Detail, Node036, Removing current message
2020-04-18 19:19:32.173 Detail, Node036, Notification: Notification - TimeOut

Als ik dan kies voor 'add node' en de schakelaar 3x omhaal krijg ik netjes een nieuwe node (in dit geval wordt hij dan node038).

Uiteindelijk verwijder ik de node036 maar...

code:

2020-04-18 21:56:31.785 Detail, Node036, Queuing (Controller) Remove Failed Node

Van meerdere nodes (034 als voorbeeld, ook anderen) zie ik overigens:

code:

2020-04-18 19:19:02.081 Warning, Node034, WARNING: Received reply to FUNC_ID_ZW_ASSIGN_RETURN_ROUTE - command failed
2020-04-18 19:19:02.081 Detail, Notification: ControllerCommand - Failed

Al geprobeerd:
- nieuwe sensor gekocht (zwave plus nu)
- Alle z-wave integraties gereset en opnieuw toegevoegd (......)

Overwegingen:

• nieuwe USB zwave stick (zwave.me?)
• de deurbel gewoon schrappen uit het domoticalijstje (onwenselijk...)

Waar kan ik nog kijken? Waarom vallen willekeurig (met name) schakelaar gang of schakelaar badkamer en incidenteel andere plugs eruit als ik een deurbelsensor heb en hass opnieuw opstart? Dimmers vallen er vooralsnog nooit uit. ook Sensoren blijven het altijd doen (Vooralsnog).

[Frankster]

Heb je hier al een oplossing voor gevonden? Ik heb een vergelijkbaar probleem met een Neo Coolcam deur/raam sensor...

[MoiZie]

Ik heb 2 nieuwe dimmers gekocht ipv relais. Nu werkt alles, ik denk dat de twee relais zelf kapot waren.