[RÉSOLU] Message en boucle dans les logs "Zigate Pi Cmd: 0100 Data: xxx already in queue"

[Metalx]

Bonjour, depuis peu, j'ai un bug dans les logs de Domotics, je vois ce message tourné en boucle, 3 fois toutes les 10 secondes :

(Zigate Pi) Cmd: 0100 Data: 07b51d0102000600000000010000 already in queue.

Version screenshot :


Je ne comprends pas d'où vient le problème...
J'ai essayé de redémarrer à tous les niveaux (Raspberry Pi, Domotics, Plugin), ça ne change rien.
Si quelqu'un a une idée de la provenance ou d'une astuce pour virer ça, ce serait super.
Car en plus, j'ai l'impression que ça créer, de temps en temps, des ralentissements dans mes appels Zigbee.
Merci.

Code : Tout sélectionner

Domotics: 2020.2
Plugin Zigate: 4.11.010
Firmware Zigate: 031a

[Keros]

Une raison particulière pour être rester avec une version du firmware à 031 ?
On est à la 031d depuis 2-3 mois.

Ce bug est apparu suite à une mise à jour ? l'ajout d'un capteur ?

[pipiche]

Il serait intéressant d'avoir le contexte, les logs dans leur ensemble depuis le lancement du plugin. L'erreur seule 'est pas très utile.

[Metalx]

Dans un premier temps, merci pour vos réponses.

Une raison particulière pour être rester avec une version du firmware à 031 ?
On est à la 031d depuis 2-3 mois.

Non, pas de raison particulière, mais je n'arrive pas à le mettre à jour, je vais créer un poste dédié pour ce souci.
(en gros, lorsqu je lance l'envoie du ".bin" avec "JennicModuleProgrammer", il charge à l'infinie et j'ai un thread du processeur qui reste à 100%)
(Ok, j'ai résolut ce problème de firmware grâce à ce sujet : https://zigate.fr/forum/topic/pizigate- ... ed/?part=2)

Ce bug est apparu suite à une mise à jour ? l'ajout d'un capteur ?

Je dois avouer que je n'ai pas trop fait attention du moment où c'est apparu... Mais peut-être lors j'ai rétrogradé (pas vraiment car au final c'est la même version je crois) de "beta" à "stable" avec le plugin Zigate de Zomoticz.

Il serait intéressant d'avoir le contexte, les logs dans leur ensemble depuis le lancement du plugin. L'erreur seule 'est pas très utile.

Alors déjà, dans un premier temps, le re démarrage via "Admin => Plugin => Redémarrer le plugin" ne fonctionne pas :

Et via "Admin => ZiGate => ZiGate réinitialisation logicielle" :


Voilà.

[Metalx]

Ok, donc après avoir réussi l'installation du dernier firmware (031d), j'ai redémarré complétement le Raspberry Pi et voilà les log de Domoticz :

Code : Tout sélectionner

2020-11-26 15:33:13.091 Sunrise: 08:16:00 SunSet: 17:01:00
2020-11-26 15:33:13.091 Day length: 08:45:00 Sun at south: 12:38:00
2020-11-26 15:33:13.091 Civil twilight start: 07:40:00 Civil twilight end: 17:36:00
2020-11-26 15:33:13.091 Nautical twilight start: 07:01:00 Nautical twilight end: 18:15:00
2020-11-26 15:33:13.091 Astronomical twilight start: 06:24:00 Astronomical twilight end: 18:53:00
2020-11-26 15:33:13.256 Active notification Subsystems: (0/13)
2020-11-26 15:33:13.280 Starting shared server on: :::6144
2020-11-26 15:33:13.005 Status: Domoticz V2020.2 (c)2012-2020 GizMoCuz
2020-11-26 15:33:13.005 Status: Build Hash: b63341bc0, Date: 2020-04-26 13:47:55
2020-11-26 15:33:13.005 Status: Startup Path: /home/pi/domoticz/
2020-11-26 15:33:13.251 Status: PluginSystem: Started, Python version '3.7.3'.
2020-11-26 15:33:13.267 Status: WebServer(HTTP) started on address: :: with port 8080
2020-11-26 15:33:13.278 Status: WebServer(SSL) started on address: :: with port 443
2020-11-26 15:33:13.281 Status: TCPServer: shared server started...
2020-11-26 15:33:13.281 Status: RxQueue: queue worker started...
2020-11-26 15:33:15.281 Status: (Zigate Pi) Started.
2020-11-26 15:33:15.282 Status: EventSystem: reset all events...
2020-11-26 15:33:15.282 Status: NotificationSystem: thread started...
2020-11-26 15:33:15.287 Status: EventSystem: reset all device statuses...
2020-11-26 15:33:15.377 Status: Python EventSystem: Initalizing event module.
2020-11-26 15:33:15.377 Status: EventSystem: Started
2020-11-26 15:33:15.377 Status: EventSystem: Queue thread started...
2020-11-26 15:33:15.610 Status: PluginSystem: Entering work loop.
2020-11-26 15:33:16.040 (Zigate Pi) DomoticzVersion: 2020.2
2020-11-26 15:33:16.416 (Zigate Pi) runmode_with_gpiocommand
2020-11-26 15:33:16.416 (Zigate Pi) + Checkint GPIO PINs
2020-11-26 15:33:16.499 (Zigate Pi) + Checkint GPIO PINs
2020-11-26 15:33:16.632 (Zigate Pi) Decode0302 - PDMloadConfirmed: 00
2020-11-26 15:33:16.633 (Zigate Pi) Decode8024: Status: 01
2020-11-26 15:33:16.633 (Zigate Pi) setZigateIEEE - Set Zigate IEEE: 00158d0002bcfe7d
2020-11-26 15:33:16.633 (Zigate Pi) Decode8006 - MsgData: 01
2020-11-26 15:33:16.033 Status: (Zigate Pi) Entering work loop.
2020-11-26 15:33:16.034 Status: (Zigate Pi) Initialized version 4.11, author 'zaraki673 pipiche38'
2020-11-26 15:33:16.040 Status: (Zigate Pi) Zigate plugin stable-4.11.010 started
2020-11-26 15:33:16.040 Status: (Zigate Pi) Debug: 0
2020-11-26 15:33:16.041 Status: (Zigate Pi) load PluginConf
2020-11-26 15:33:16.042 Status: (Zigate Pi) enablegroupmanagement set to 1
2020-11-26 15:33:16.043 Status: (Zigate Pi) enableReadAttributes set to 1
2020-11-26 15:33:16.043 Status: (Zigate Pi) forcePassiveWidget set to 1
2020-11-26 15:33:16.043 Status: (Zigate Pi) zigatePartOfGroup0000 set to 1
2020-11-26 15:33:16.043 Status: (Zigate Pi) channel set to 11
2020-11-26 15:33:16.043 Status: (Zigate Pi) filename set to /home/pi/domoticz/plugins/Domoticz-Zigate/Conf/PluginConf-02.json
2020-11-26 15:33:16.043 Status: (Zigate Pi) pluginHome set to /home/pi/domoticz/plugins/Domoticz-Zigate/
2020-11-26 15:33:16.043 Status: (Zigate Pi) homedirectory set to /home/pi/domoticz/plugins/Domoticz-Zigate/
2020-11-26 15:33:16.043 Status: (Zigate Pi) pluginData set to /home/pi/domoticz/plugins/Domoticz-Zigate/Data/
2020-11-26 15:33:16.043 Status: (Zigate Pi) pluginConfig set to /home/pi/domoticz/plugins/Domoticz-Zigate/Conf/
2020-11-26 15:33:16.043 Status: (Zigate Pi) pluginOTAFirmware set to /home/pi/domoticz/plugins/Domoticz-Zigate/OTAFirmware/
2020-11-26 15:33:16.043 Status: (Zigate Pi) pluginReports set to /home/pi/domoticz/plugins/Domoticz-Zigate/Reports/
2020-11-26 15:33:16.043 Status: (Zigate Pi) pluginWWW set to /home/pi/domoticz/plugins/Domoticz-Zigate/www/
2020-11-26 15:33:16.043 Status: (Zigate Pi) pluginLogs set to /home/pi/domoticz/plugins/Domoticz-Zigate/Logs/
2020-11-26 15:33:16.043 Status: (Zigate Pi) Switching Heartbeat to 5 s interval
2020-11-26 15:33:16.043 Status: (Zigate Pi) Python Version - 3.7.3 (default, Jul 25 2020, 13:03:44)
2020-11-26 15:33:16.043 [GCC 8.3.0]
2020-11-26 15:33:16.043 Status: (Zigate Pi) DomoticzVersion: 2020.2
2020-11-26 15:33:16.043 Status: (Zigate Pi) DomoticzHash: b63341bc0
2020-11-26 15:33:16.043 Status: (Zigate Pi) DomoticzBuildTime: 2020-04-26 13:47:55
2020-11-26 15:33:16.043 Status: (Zigate Pi) Opening DomoticzDB in raw
2020-11-26 15:33:16.101 Status: (Zigate Pi) Plugin Database: DeviceList-2.txt
2020-11-26 15:33:16.109 Status: (Zigate Pi) DeviceConf loaded
2020-11-26 15:33:16.255 Status: (Zigate Pi) load ListOfDevice
2020-11-26 15:33:16.382 Status: (Zigate Pi) Entries loaded from /home/pi/domoticz/plugins/Domoticz-Zigate/Data/DeviceList-2.txt
2020-11-26 15:33:16.396 Status: (Zigate Pi) Transport mode: PI
2020-11-26 15:33:16.414 Status: (Zigate Pi) Python version: ['3.7.3 (default, Jul 25 2020, 13:03:44) ', '[GCC 8.3.0]'] dist: ('debian', '10.6', '') linux_distribution: ('debian', '10.6', '') system: Linux machine: armv7l platform: Linux-5.4.72-v7l+-armv7l-with-debian-10.6 uname: uname_result(system='Linux', node='raspberrypi', release='5.4.72-v7l+', version='#1356 SMP Thu Oct 22 13:57:51 BST 2020', machine='armv7l', processor='') version: #1356 SMP Thu Oct 22 13:57:51 BST 2020 mac_ver: ('', ('', '', ''), '')
2020-11-26 15:33:16.524 Status: (Zigate Pi) ==> Transport Mode: auto
2020-11-26 15:33:16.526 Status: (Zigate Pi) Connection Name: Zigate, Transport: Serial, Address: /dev/serial0
2020-11-26 15:33:16.530 Status: (Zigate Pi) Connection open: Name: 'ZiGate', Transport: 'Serial', Protocol: 'None', Address: '/dev/serial0', Port: '', Baud: 115200, Connected: False, Parent: 'None'
2020-11-26 15:33:16.633 Status: (Zigate Pi) Start Network - Formed new network
2020-11-26 15:33:16.633 Status: (Zigate Pi) Zigate details IEEE: 00158d0002bcfe7d, NetworkID: 0000, Channel: 11, Status: 01: Success
2020-11-26 15:33:16.633 Status: (Zigate Pi) Non 'Factory new' Restart status: RUNNING
2020-11-26 15:33:48.215 Status: (Zigate Pi) Firmware with APS SQN switching to Mode: ZiGateAck
2020-11-26 15:33:48.216 Status: (Zigate Pi) Major Version Num: 0003
2020-11-26 15:33:48.216 Status: (Zigate Pi) Installer Version Number: 031d
2020-11-26 15:33:53.127 Status: (Zigate Pi) Request Disabling Accepting new Hardware
2020-11-26 15:33:53.539 Status: (Zigate Pi) Accepting new Hardware: Disable
2020-11-26 15:33:53.641 Status: (Zigate Pi) Number of devices recently active in Zigate = 13
2020-11-26 15:33:53.641 Status: (Zigate Pi) [00] DevID = 00 Network addr = 4dfc IEEE = 00158d00053f0de1 LQI = 182 Power = 00 Model = lumi.remote.b286acn02
2020-11-26 15:33:53.641 Status: (Zigate Pi) [01] DevID = 01 Network addr = 17e1 IEEE = 00158d00042c0269 LQI = 121 Power = 00 Model = lumi.remote.b186acn02
2020-11-26 15:33:53.641 Status: (Zigate Pi) [02] DevID = 04 Network addr = be28 IEEE = 680ae2fffebc9e98 LQI = 195 Power = 01 Model = TRADFRI Signal Repeater
2020-11-26 15:33:53.641 Status: (Zigate Pi) [03] DevID = 28 Network addr = 3421 IEEE = 90fd9ffffe92bd56 LQI = 118 Power = 01 Model = TRADFRI bulb E14 WS opal 400lm
2020-11-26 15:33:53.641 Status: (Zigate Pi) [04] DevID = 29 Network addr = 53dc IEEE = 086bd7fffe51cb3d LQI = 120 Power = 01 Model = TRADFRI bulb GU10 WS 400lm
2020-11-26 15:33:53.642 Status: (Zigate Pi) [05] DevID = 2a Network addr = 4a65 IEEE = 90fd9ffffe7ff6df LQI = 098 Power = 01 Model = TRADFRI bulb E27 CWS opal 600lm
2020-11-26 15:33:53.642 Status: (Zigate Pi) [06] DevID = 2b Network addr = 1db3 IEEE = 000d6ffffe4b5833 LQI = 084 Power = 01 Model = TRADFRI bulb GU10 WS 400lm
2020-11-26 15:33:53.642 Status: (Zigate Pi) [07] DevID = 2c Network addr = c06f IEEE = 000d6ffffe4b0114 LQI = 098 Power = 01 Model = TRADFRI bulb GU10 WS 400lm
2020-11-26 15:33:53.642 Status: (Zigate Pi) [08] DevID = 2d Network addr = 9d25 IEEE = 000d6ffffea03de4 LQI = 132 Power = 01 Model = TRADFRI bulb GU10 WS 400lm
2020-11-26 15:33:53.642 Status: (Zigate Pi) [09] DevID = 2e Network addr = 5577 IEEE = 000d6ffffe4c3080 LQI = 115 Power = 01 Model = TRADFRI bulb GU10 WS 400lm
2020-11-26 15:33:53.642 Status: (Zigate Pi) [10] DevID = 2f Network addr = 0a0c IEEE = 000d6ffffe4c7c2e LQI = 130 Power = 01 Model = TRADFRI bulb GU10 WS 400lm
2020-11-26 15:33:53.642 Status: (Zigate Pi) [11] DevID = 30 Network addr = 6b54 IEEE = 000d6ffffe2ccc1f LQI = 132 Power = 01 Model = TRADFRI bulb GU10 WS 400lm
2020-11-26 15:33:53.643 Status: (Zigate Pi) [12] DevID = 31 Network addr = ed61 IEEE = 000d6ffffe4b28a1 LQI = 111 Power = 01 Model = TRADFRI bulb GU10 WS 400lm
2020-11-26 15:33:58.103 (Zigate Pi) Switch Blue Led On
2020-11-26 15:33:58.103 Status: (Zigate Pi) Zigate set to Certification : CE
2020-11-26 15:33:58.103 Status: (Zigate Pi) Start Group Management
2020-11-26 15:33:58.108 Status: (Zigate Pi) Start Web Server connection
2020-11-26 15:33:58.118 Status: (Zigate Pi) Web backend for Web User Interface started on port: 9440
2020-11-26 15:33:58.118 Status: (Zigate Pi) Plugin with Zigate firmware 031d correctly initialized
2020-11-26 15:33:58.322 Status: (Zigate Pi) TxPower Attenuation : 0 dBm
2020-11-26 15:34:03.264 (Zigate Pi) Cmd: 0100 Data: 07b51d0102000600000000010000 already in queue.
2020-11-26 15:34:03.265 (Zigate Pi) Cmd: 0100 Data: 07b51d0102000600000000010000 already in queue.
2020-11-26 15:34:03.265 (Zigate Pi) Cmd: 0100 Data: 07b51d0102000600000000010000 already in queue.
2020-11-26 15:34:03.265 (Zigate Pi) Cmd: 0100 Data: 07b51d0102000600000000010000 already in queue.
2020-11-26 15:34:08.112 (Zigate Pi) Cmd: 0100 Data: 073cc30101000600000000010000 already in queue.
2020-11-26 15:34:08.113 (Zigate Pi) Cmd: 0100 Data: 073cc30102000600000000010000 already in queue.
2020-11-26 15:34:08.114 (Zigate Pi) Cmd: 0100 Data: 073cc30103000600000000010000 already in queue.
2020-11-26 15:34:18.121 (Zigate Pi) Cmd: 0100 Data: 07b51d0102000600000000010000 already in queue.
2020-11-26 15:34:18.122 (Zigate Pi) Cmd: 0100 Data: 07b51d0102000600000000010000 already in queue.
2020-11-26 15:34:18.123 (Zigate Pi) Cmd: 0100 Data: 07b51d0102000600000000010000 already in queue.
2020-11-26 15:34:28.112 (Zigate Pi) Cmd: 0100 Data: 07b51d0102000600000000010000 already in queue.
2020-11-26 15:34:28.113 (Zigate Pi) Cmd: 0100 Data: 07b51d0102000600000000010000 already in queue.
2020-11-26 15:34:28.114 (Zigate Pi) Cmd: 0100 Data: 07b51d0102000600000000010000 already in queue.
2020-11-26 15:34:28.116 (Zigate Pi) Cmd: 0100 Data: 07b51d0102000600000000010000 already in queue.
2020-11-26 15:34:29.687 (Zigate Pi) Receive NACK from Cuisine planché 1 (d038) clusterId: 0000 for Command: 0100 Status: a7
2020-11-26 15:34:33.108 (Zigate Pi) Cmd: 0100 Data: 073cc30101000600000000010000 already in queue.
2020-11-26 15:34:33.110 (Zigate Pi) Cmd: 0100 Data: 073cc30102000600000000010000 already in queue.
2020-11-26 15:34:33.111 (Zigate Pi) Cmd: 0100 Data: 073cc30103000600000000010000 already in queue.
2020-11-26 15:34:43.108 (Zigate Pi) Cmd: 0100 Data: 07b51d0102000600000000010000 already in queue.
2020-11-26 15:34:43.109 (Zigate Pi) Cmd: 0100 Data: 07b51d0102000600000000010000 already in queue.
2020-11-26 15:34:43.110 (Zigate Pi) Cmd: 0100 Data: 07b51d0102000600000000010000 already in queue.
2020-11-26 15:34:53.133 (Zigate Pi) Cmd: 0100 Data: 07b51d0102000600000000010000 already in queue.
2020-11-26 15:34:53.134 (Zigate Pi) Cmd: 0100 Data: 07b51d0102000600000000010000 already in queue.
2020-11-26 15:34:53.135 (Zigate Pi) Cmd: 0100 Data: 07b51d0102000600000000010000 already in queue.
2020-11-26 15:35:03.118 (Zigate Pi) Cmd: 0100 Data: 07b51d0102000600000000010000 already in queue.
2020-11-26 15:35:03.119 (Zigate Pi) Cmd: 0100 Data: 07b51d0102000600000000010000 already in queue.
2020-11-26 15:35:03.120 (Zigate Pi) Cmd: 0100 Data: 07b51d0102000600000000010000 already in queue.

Voilà.

[pipiche]

Y-at-il une raison particulière pour que le Polling soit activé ? 'Activer interrogation des dispositifs ' ?

est-ce que en le desactivement le problème disparait ?

[Metalx]

C'était complètement ça !
Je ne me rappelle pas avoir activé cette option, peut-être une erreur...

Merci beaucoup !

[pipiche]

je regarderais pourquoi. Il n'y aucune raison qu'il y est ce type de problème.

D'un coté à la vue des devices que tu as Ikea tu as pas besoin d'activiter cette feature