Controller is busy + Zwave-me non fonctionnel

Nicolasbaidez · Juin 5, 2021, 2:31

Bonjour à tous,
Suivant le forum depuis plus de 1 an, tout fonctionnait bien jusqu’à ce week-end.
Mon plugin Zwave ne fonctionne plus, malgré mes dépendances et mon demon OK.
Quand je veux faire une inclusion, exclusion, ou toute action sur le plugin Zwave, le message Controller is busy apparaît.

Autre défaut, quand je cherche à me connecter via 192.168.1.27:8083 pour accéder à ma carte Zwave-me connectée en GPIO sur mon RP4 : ce site est inaccessible.
Et ma carte Zwave-me a les leds verte et rouge allumées, fixes, constamment.

J’ai tenté une reinstall complète de mon RP4 avec injection d’une sauvegarde fonctionnelle avant le crash : idem.

Quels logs puis-je fournir pour qu’une âme charitable m’aide sur ce coup ?
Par avance merci.

Mips · Juin 5, 2021, 2:52

Bonjour,

Ceux du plugin ca parait bien, les autres seraient hors sujet

Nicolasbaidez · Juin 5, 2021, 3:07

Merci Mips
Voici les logs en Defaut.

Openzwaved :
2021-06-05 16:54:11.389 Always, OpenZwave Version 1.4.0 Starting Up
2021-06-05 16:54:12.398 Error, contrlr, ERROR: Dropping command, expected response not received after 1 attempt(s)
2021-06-05 16:54:13.399 Error, contrlr, ERROR: Dropping command, expected response not received after 1 attempt(s)
2021-06-05 16:54:14.400 Error, contrlr, ERROR: Dropping command, expected response not received after 1 attempt(s)
2021-06-05 16:54:15.401 Error, contrlr, ERROR: Dropping command, expected response not received after 1 attempt(s)
2021-06-05 16:54:16.402 Error, contrlr, ERROR: Dropping command, expected response not received after 1 attempt(s)

OpenZwave :
[2021-06-05 16:42:48][ERROR] : RequestHandler Unknow node id 4
[2021-06-05 16:42:48][ERROR] : RequestHandler Unknow node id 2
[2021-06-05 16:42:48][ERROR] : RequestHandler Unknow node id 3
[2021-06-05 16:42:48][ERROR] : RequestHandler Unknow node id 10
[2021-06-05 16:42:48][ERROR] : RequestHandler Unknow node id 14
[2021-06-05 16:42:48][ERROR] : RequestHandler Unknow node id 12
[2021-06-05 16:42:48][ERROR] : RequestHandler Unknow node id 15
[2021-06-05 16:42:48][ERROR] : RequestHandler Unknow node id 13
[2021-06-05 16:42:51][ERROR] : RequestHandler Unknow node id 4
[2021-06-05 16:42:51][ERROR] : RequestHandler Unknow node id 2
[2021-06-05 16:42:51][ERROR] : RequestHandler Unknow node id 3
[2021-06-05 16:42:51][ERROR] : RequestHandler Unknow node id 10
[2021-06-05 16:42:51][ERROR] : RequestHandler Unknow node id 14
[2021-06-05 16:42:51][ERROR] : RequestHandler Unknow node id 12
[2021-06-05 16:42:51][ERROR] : RequestHandler Unknow node id 13
[2021-06-05 16:42:51][ERROR] : RequestHandler Unknow node id 15
[2021-06-05 16:42:53][ERROR] : RequestHandler Unknow node id 4
[2021-06-05 16:42:53][ERROR] : RequestHandler Unknow node id 2
[2021-06-05 16:42:53][ERROR] : RequestHandler Unknow node id 3
[2021-06-05 16:42:53][ERROR] : RequestHandler Unknow node id 10
[2021-06-05 16:42:53][ERROR] : RequestHandler Unknow node id 14
[2021-06-05 16:42:53][ERROR] : RequestHandler Unknow node id 12
[2021-06-05 16:42:53][ERROR] : RequestHandler Unknow node id 15
[2021-06-05 16:42:53][ERROR] : RequestHandler Unknow node id 13
[2021-06-05 16:43:23][ERROR] : RequestHandler Unknow node id 1
[2021-06-05 16:43:23][ERROR] : RequestHandler Unknow node id 1
[2021-06-05 17:01:16][ERROR] : RequestHandler Unknow node id 4
[2021-06-05 17:01:16][ERROR] : RequestHandler Unknow node id 2
[2021-06-05 17:01:16][ERROR] : RequestHandler Unknow node id 3
[2021-06-05 17:01:16][ERROR] : RequestHandler Unknow node id 10
[2021-06-05 17:01:16][ERROR] : RequestHandler Unknow node id 14
[2021-06-05 17:01:16][ERROR] : RequestHandler Unknow node id 12
[2021-06-05 17:01:16][ERROR] : RequestHandler Unknow node id 15
[2021-06-05 17:01:16][ERROR] : RequestHandler Unknow node id 13

Mips · Juin 5, 2021, 4:48

Il faudrait toute de même vérifier la config zwave donc

capture de la config du plugin
capture de l’onglet résumé de l’écran « réseau zwave »

et alors passer les logs en debug, redémarrer le démon et montrer le démarrage du démon
Il faut mettre les logs dans un Texte préformaté aussi via l’outils </> et pas en

Citation

car c’est moins lisible.

Fabrice · Juin 5, 2021, 4:52

Bonsoir.

Vous pouvez tester un truc. Tout arrêter 30 minutes, Raspberry débranché, plus de câble d’alimentation sur rien.

30 minutes après, rebranchez tout et tester.

Nicolasbaidez · Juin 5, 2021, 7:12

Déjà testée.
J’ai même tout débranché y compris la carte Zwave me.

J’ai un doute sur la carte Zwave qui a ses 2 Leds allumés constamment, mais je ne trouve aucune info là dessus.

Nicolasbaidez · Juin 5, 2021, 8:55

Merci de l’explication pour intégrer les logs

Ré

Voici les logs :
Openzwave :

[2021-06-05 16:42:48][ERROR] : RequestHandler Unknow node id 4
[2021-06-05 16:42:48][ERROR] : RequestHandler Unknow node id 2
[2021-06-05 16:42:48][ERROR] : RequestHandler Unknow node id 3
[2021-06-05 16:42:48][ERROR] : RequestHandler Unknow node id 10
[2021-06-05 16:42:48][ERROR] : RequestHandler Unknow node id 14
[2021-06-05 16:42:48][ERROR] : RequestHandler Unknow node id 12
[2021-06-05 16:42:48][ERROR] : RequestHandler Unknow node id 15
[2021-06-05 16:42:48][ERROR] : RequestHandler Unknow node id 13
[2021-06-05 16:42:51][ERROR] : RequestHandler Unknow node id 4
[2021-06-05 16:42:51][ERROR] : RequestHandler Unknow node id 2
[2021-06-05 16:42:51][ERROR] : RequestHandler Unknow node id 3
[2021-06-05 16:42:51][ERROR] : RequestHandler Unknow node id 10
[2021-06-05 16:42:51][ERROR] : RequestHandler Unknow node id 14
[2021-06-05 16:42:51][ERROR] : RequestHandler Unknow node id 12
[2021-06-05 16:42:51][ERROR] : RequestHandler Unknow node id 13
[2021-06-05 16:42:51][ERROR] : RequestHandler Unknow node id 15
[2021-06-05 16:42:53][ERROR] : RequestHandler Unknow node id 4
[2021-06-05 16:42:53][ERROR] : RequestHandler Unknow node id 2
[2021-06-05 16:42:53][ERROR] : RequestHandler Unknow node id 3
[2021-06-05 16:42:53][ERROR] : RequestHandler Unknow node id 10
[2021-06-05 16:42:53][ERROR] : RequestHandler Unknow node id 14
[2021-06-05 16:42:53][ERROR] : RequestHandler Unknow node id 12
[2021-06-05 16:42:53][ERROR] : RequestHandler Unknow node id 15
[2021-06-05 16:42:53][ERROR] : RequestHandler Unknow node id 13
[2021-06-05 16:43:23][ERROR] : RequestHandler Unknow node id 1
[2021-06-05 16:43:23][ERROR] : RequestHandler Unknow node id 1
[2021-06-05 17:01:16][ERROR] : RequestHandler Unknow node id 4
[2021-06-05 17:01:16][ERROR] : RequestHandler Unknow node id 2
[2021-06-05 17:01:16][ERROR] : RequestHandler Unknow node id 3
[2021-06-05 17:01:16][ERROR] : RequestHandler Unknow node id 10
[2021-06-05 17:01:16][ERROR] : RequestHandler Unknow node id 14
[2021-06-05 17:01:16][ERROR] : RequestHandler Unknow node id 12
[2021-06-05 17:01:16][ERROR] : RequestHandler Unknow node id 15
[2021-06-05 17:01:16][ERROR] : RequestHandler Unknow node id 13
[2021-06-05 22:51:23][ERROR] : No config file found to backup
[2021-06-05 22:51:26][INFO] : Lancement démon openzwave : /usr/bin/python /var/www/html/plugins/openzwave/core/class/../../resources/openzwaved/openzwaved.py  --device /dev/ttyAMA0 --loglevel debug --port 8083 --config_folder /var/www/html/plugins/openzwave/core/class/../../resources/openzwaved/config --data_folder /var/www/html/plugins/openzwave/core/class/../../data --callback http://127.0.0.1:80/plugins/openzwave/core/php/jeeZwave.php --apikey J4jrOG2FJ1QRd0Qa2QtNhS7aLpqkBsap --suppressRefresh 0 --cycle 0.3 --pid /tmp/jeedom/openzwave/deamon.pid
[2021-06-05 22:51:27][INFO] : Start openzwaved
[2021-06-05 22:51:27][INFO] : Log level : debug
[2021-06-05 22:51:27][DEBUG] : PID file : /tmp/jeedom/openzwave/deamon.pid
[2021-06-05 22:51:27][INFO] : Device : /dev/ttyAMA0
[2021-06-05 22:51:27][DEBUG] : Apikey : J4jrOG2FJ1QRd0Qa2QtNhS7aLpqkBsap
[2021-06-05 22:51:27][INFO] : Callback : http://127.0.0.1:80/plugins/openzwave/core/php/jeeZwave.php
[2021-06-05 22:51:27][INFO] : Cycle : 0.3
[2021-06-05 22:51:27][DEBUG] : Initial disabled nodes list: []
[2021-06-05 22:51:27][DEBUG] : Init request module v2.21.0
[2021-06-05 22:51:27][DEBUG] : Starting new HTTP connection (1): 127.0.0.1:80
[2021-06-05 22:51:27][DEBUG] : http://127.0.0.1:80 "GET /plugins/openzwave/core/php/jeeZwave.php?apikey=J4jrOG2FJ1QRd0Qa2QtNhS7aLpqkBsap HTTP/1.1" 200 0
[2021-06-05 22:51:27][INFO] : Check if the port REST server available
[2021-06-05 22:51:27][INFO] : --> pass
[2021-06-05 22:51:27][INFO] : Check OpenZWave Devices Database
[2021-06-05 22:51:27][INFO] : --> pass
[2021-06-05 22:51:27][INFO] : Validate zwcfg configuration file(s)
[2021-06-05 22:51:27][INFO] : ... check: /var/www/html/plugins/openzwave/core/class/../../data/zwcfg_0xdc42ee64.xml
[2021-06-05 22:51:27][DEBUG] : Create network object.
[2021-06-05 22:51:27][DEBUG] : Use sqlite version : (u'3.27.2',)
[2021-06-05 22:51:27][DEBUG] : connect to the louie dispatcher
[2021-06-05 22:51:27][INFO] : ******** The ZWave network is being started ********
[2021-06-05 22:51:27][INFO] : Start Openzwave network.
[2021-06-05 22:51:27][INFO] : OpenZwave Library Version 1.4.0
[2021-06-05 22:51:27][INFO] : Python-OpenZwave Wrapper Version 0.3.1
[2021-06-05 22:51:27][INFO] : --> pass
[2021-06-05 22:51:27][INFO] : Waiting for network to become ready
[2021-06-05 22:51:27][DEBUG] : Writing PID 7650 to /tmp/jeedom/openzwave/deamon.pid
[2021-06-05 22:51:27][INFO] : Démon openzwave lancé
[2021-06-05 22:51:28][DEBUG] : notif_callback : new notification
[2021-06-05 22:51:28][DEBUG] : notif_callback : Notification type : 26, nodeId : 255
[2021-06-05 22:51:28][DEBUG] : notif_callback : call callback context
[2021-06-05 22:51:28][DEBUG] : zwcallback args=[{'homeId': 0L, 'notificationCode': 1, 'notificationType': 'Notification', 'nodeId': 255}]
[2021-06-05 22:51:28][DEBUG] : Z-Wave Notification : {'homeId': 0L, 'notificationCode': 1, 'notificationType': 'Notification', 'nodeId': 255}
[2021-06-05 22:51:28][DEBUG] : notif_callback : end
[2021-06-05 22:51:29][DEBUG] : notif_callback : new notification
[2021-06-05 22:51:29][DEBUG] : notif_callback : Notification type : 26, nodeId : 255
[2021-06-05 22:51:29][DEBUG] : notif_callback : call callback context
[2021-06-05 22:51:29][DEBUG] : zwcallback args=[{'homeId': 0L, 'notificationCode': 1, 'notificationType': 'Notification', 'nodeId': 255}]
[2021-06-05 22:51:29][DEBUG] : Z-Wave Notification : {'homeId': 0L, 'notificationCode': 1, 'notificationType': 'Notification', 'nodeId': 255}
[2021-06-05 22:51:29][DEBUG] : notif_callback : end
[2021-06-05 22:51:30][DEBUG] : notif_callback : new notification
[2021-06-05 22:51:30][DEBUG] : notif_callback : Notification type : 26, nodeId : 255
[2021-06-05 22:51:30][DEBUG] : notif_callback : call callback context
[2021-06-05 22:51:30][DEBUG] : zwcallback args=[{'homeId': 0L, 'notificationCode': 1, 'notificationType': 'Notification', 'nodeId': 255}]
[2021-06-05 22:51:30][DEBUG] : Z-Wave Notification : {'homeId': 0L, 'notificationCode': 1, 'notificationType': 'Notification', 'nodeId': 255}
[2021-06-05 22:51:30][DEBUG] : notif_callback : end
[2021-06-05 22:51:31][DEBUG] : notif_callback : new notification
[2021-06-05 22:51:31][DEBUG] : notif_callback : Notification type : 26, nodeId : 255
[2021-06-05 22:51:31][DEBUG] : notif_callback : call callback context
[2021-06-05 22:51:31][DEBUG] : zwcallback args=[{'homeId': 0L, 'notificationCode': 1, 'notificationType': 'Notification', 'nodeId': 255}]
[2021-06-05 22:51:31][DEBUG] : Z-Wave Notification : {'homeId': 0L, 'notificationCode': 1, 'notificationType': 'Notification', 'nodeId': 255}
[2021-06-05 22:51:31][DEBUG] : notif_callback : end
[2021-06-05 22:51:32][DEBUG] : notif_callback : new notification
[2021-06-05 22:51:32][DEBUG] : notif_callback : Notification type : 26, nodeId : 255
[2021-06-05 22:51:32][DEBUG] : notif_callback : call callback context
[2021-06-05 22:51:32][DEBUG] : zwcallback args=[{'homeId': 0L, 'notificationCode': 1, 'notificationType': 'Notification', 'nodeId': 255}]
[2021-06-05 22:51:32][DEBUG] : Z-Wave Notification : {'homeId': 0L, 'notificationCode': 1, 'notificationType': 'Notification', 'nodeId': 255}
[2021-06-05 22:51:32][DEBUG] : notif_callback : end

Openzwaved:

2021-06-05 22:51:27.381 Always, OpenZwave Version 1.4.0 Starting Up
2021-06-05 22:51:27.384 Info, Setting Up Provided Network Key for Secure Communications
2021-06-05 22:51:27.384 Info, mgr,     Added driver for controller /dev/ttyAMA0
2021-06-05 22:51:27.384 Info,   Opening controller /dev/ttyAMA0
2021-06-05 22:51:27.384 Info, Trying to open serial port /dev/ttyAMA0 (attempt 1)
2021-06-05 22:51:27.384 Info, Serial port /dev/ttyAMA0 opened (attempt 1)
2021-06-05 22:51:27.385 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_VERSION: 0x01, 0x03, 0x00, 0x15, 0xe9
2021-06-05 22:51:27.385 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_MEMORY_GET_ID: 0x01, 0x03, 0x00, 0x20, 0xdc
2021-06-05 22:51:27.385 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_CONTROLLER_CAPABILITIES: 0x01, 0x03, 0x00, 0x05, 0xf9
2021-06-05 22:51:27.385 Detail, contrlr, Queuing (Command) FUNC_ID_SERIAL_API_GET_CAPABILITIES: 0x01, 0x03, 0x00, 0x07, 0xfb
2021-06-05 22:51:27.385 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_SUC_NODE_ID: 0x01, 0x03, 0x00, 0x56, 0xaa
2021-06-05 22:51:27.385 Detail, 
2021-06-05 22:51:27.385 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x15) - FUNC_ID_ZW_GET_VERSION: 0x01, 0x03, 0x00, 0x15, 0xe9
2021-06-05 22:51:28.385 Error, contrlr, ERROR: Dropping command, expected response not received after 1 attempt(s)
2021-06-05 22:51:28.385 Detail, contrlr, Removing current message
2021-06-05 22:51:28.385 Detail, contrlr, Notification: Notification - TimeOut
2021-06-05 22:51:28.389 Detail, 
2021-06-05 22:51:28.389 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x20) - FUNC_ID_ZW_MEMORY_GET_ID: 0x01, 0x03, 0x00, 0x20, 0xdc
2021-06-05 22:51:29.389 Error, contrlr, ERROR: Dropping command, expected response not received after 1 attempt(s)
2021-06-05 22:51:29.390 Detail, contrlr, Removing current message
2021-06-05 22:51:29.390 Detail, contrlr, Notification: Notification - TimeOut
2021-06-05 22:51:29.392 Detail, 
2021-06-05 22:51:29.392 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x05) - FUNC_ID_ZW_GET_CONTROLLER_CAPABILITIES: 0x01, 0x03, 0x00, 0x05, 0xf9
2021-06-05 22:51:30.393 Error, contrlr, ERROR: Dropping command, expected response not received after 1 attempt(s)
2021-06-05 22:51:30.393 Detail, contrlr, Removing current message
2021-06-05 22:51:30.393 Detail, contrlr, Notification: Notification - TimeOut
2021-06-05 22:51:30.396 Detail, 
2021-06-05 22:51:30.396 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x07) - FUNC_ID_SERIAL_API_GET_CAPABILITIES: 0x01, 0x03, 0x00, 0x07, 0xfb
2021-06-05 22:51:31.396 Error, contrlr, ERROR: Dropping command, expected response not received after 1 attempt(s)
2021-06-05 22:51:31.396 Detail, contrlr, Removing current message
2021-06-05 22:51:31.396 Detail, contrlr, Notification: Notification - TimeOut
2021-06-05 22:51:31.399 Detail, 
2021-06-05 22:51:31.399 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x56) - FUNC_ID_ZW_GET_SUC_NODE_ID: 0x01, 0x03, 0x00, 0x56, 0xaa
2021-06-05 22:51:32.399 Error, contrlr, ERROR: Dropping command, expected response not received after 1 attempt(s)
2021-06-05 22:51:32.400 Detail, contrlr, Removing current message
2021-06-05 22:51:32.400 Detail, contrlr, Notification: Notification - TimeOut

Mips · Juin 6, 2021, 5:13

J’ai impression qu’il ne trouve pas le contrôleur.
On peut voir la page résumé de l’écran « réseau zwave », a ouvrir depuis l’écran de config des équipements.

Bison · Juin 6, 2021, 8:06

Ça sent le contrôleur en carafe mais je n’ai pas non plus trouvé d’informations sur les LED.

Nicolasbaidez · Juin 6, 2021, 8:35

Voici.
La page d’accès à la carte Zwave en 192.168.1.27:8083 reste inaccessible.

J’ai commandé une nouvelle carte Zwave-me pour faire des essais.

Mips · Juin 6, 2021, 8:56

Ce n’est pas la carte zwave là, c’est le démon python du plugin, donc ca ne sert pas à grand chose de tester ça, si ca fonctionne, le plugin fonctionne.
La carte n’expose absolument rien sur du http.

Sur la capture ce n’est toujours pas l’écran résumé du réseau zwave:

pour pouvoir vérifier ce qu’il y a ici:

(forcément ce ne sera pas le même port)

mais effectivement cela semble de plus en plus être un défaut matériel de la carte zwave (ou du controleur gpio)

Fabrice · Juin 6, 2021, 9:08

Bonjour,

Le Bluetooth du Raspberry est bien désactivé ?
Car ce type de contrôleur ne fonctionne pas quand le Bluetooth (interne uniquement) est activé.

En SSH, que donne la commande :
hciconfig

Si vous avez un doute, copiez ici le contenu du fichier :
/boot/config.txt

Il doit y avoir cela à la fin :
dtoverlay=disable-bt

Nicolasbaidez · Juin 6, 2021, 7:01

D’accord je comprends

Voici le résumé : c’est vide.

Fabrice : le Bluetooth est bien désactivé, j’avais bien galéré à la première installation il y a 1 an avec ca

Carte testée sur un Pi 3B+ remis à zéro : même constat pour les LED verte et rouge.

Nicolasbaidez · Juin 13, 2021, 7:24

Alors du nouveau. La carte Zwave était bien Hs.

Après remplacement de la carte Razberry et réinstallation complète de jeeedom (avec restauration d’une sauvegarde fonctionnelle), je bloque sur les inclusions des anciens modules.

J’ai dû tenter je ne sais combien d’inclusion, je n’ai réussi qu’à inclure 1 seul Fibaro FGR 222 sur mes 8 existants.

Bison · Juin 13, 2021, 7:43

Tu ne précise pas alors … Tu as bien fais des exclusions voir des resets des FGR-222 avant de faire les inclusions ?

Nicolasbaidez · Juin 13, 2021, 8:19

Oui, j’exclus, puis j’inclus. Rien n’y fait.
Pareil pour les reset. J’ai réussi sur un seul uniquement.
Malgré tout, le réseau Zwave fonctionne mais comme les modules sont associés à l’ancienne carte Zwave, c’est la galère…

Nicolasbaidez · Juin 14, 2021, 9:58

Problème enfin résolu

J’ai laissé mon raspberry débranché toute la nuit.
Ce matin : exclusion des anciens modules puis inclusions : OK.

Merci à tous pour votre aide

system · Juin 15, 2021, 9:58

Ce sujet a été automatiquement fermé après 24 heures suivant le dernier commentaire. Aucune réponse n’est permise dorénavant.