search

KiwiHC16 / Abeille

Abeille pour Jeedom (Gateway ZiGate)
GNU Affero General Public License v3.0
60 stars 52 forks source link

[KiwiHC16] Latence de plus de 30 secondes #2682

Closed KiwiHC16 closed 5 months ago

KiwiHC16 commented 5 months ago

Observation:

La demande d allumage de l ampoule est envoyée immédiatement d apres les "event" de jeedom.

[2024-01-16 19:26:01][INFO] : Exécution du scénario [Salon][le salon][Télécommande Meuble bt 1] déclenché par : [le salon - Operation][Telecommande Meuble][bt1]
[2024-01-16 19:26:01][INFO] : Exécution de la commande [le salon][Lampe Milieu][Off] avec les paramètres {"background":"0"}
KiwiHC16 commented 5 months ago

Adresse ampoule: D2D9

[2024-01-16 19:58:43] (KiwiHC16 Debut log)
...
[2024-01-16 19:59:00] -- execute([le salon][Lampe Milieu][On], type=action, options={"background":"0"})
[2024-01-16 19:59:00] -- Msg sent: {"topic":"CmdAbeille1/D2D9/cmd-0006","payload":"ep=01&cmd=01"}
[2024-01-16 19:59:00] Msg from 'xToCmd': {"topic":"CmdAbeille1/D2D9/cmd-0006","payload":"ep=01&cmd=01"}
[2024-01-16 19:59:00]   No prepare function. Forwarding cmd to AbeilleCmdProcess.
[2024-01-16 19:59:00]   processCmd({"cmd-0006":"cmd-0006","name":"cmd-0006","priority":2,"dest":"Abeille1","ep":"01","cmd":"01","addr":"D2D9"})
[2024-01-16 19:59:00]   cmd-0006: addrMode=02, addr=D2D9, cmd=01
[2024-01-16 19:59:00]     addCmdToQueue2(Pri=2, Net=Abeille1, ZgCmd=0092, Payload=02D2D9010101, Addr=D2D9, AddrMode=02)
[2024-01-16 19:59:00]     Zg 1 queues: Pri1=0, Pri2=1, Pri3=0
[2024-01-16 19:59:00] processCmdQueues(): ZgId=1, Pri/Idx=2/0, NPDU=0, APDU=1
[2024-01-16 19:59:00]   sendCmdToZigate(Dest=Abeille1, addr=D2D9, cmd=0092, datas=02D2D9010101)
[2024-01-16 19:59:00] processAcks(): msg={"type":"8000","net":"Abeille1","status":"00","sqn":"0E","sqnAps":"20","packetType":"0092","nPDU":"00","aPDU":"01"}
[2024-01-16 19:59:00] processAcks(): msg={"type":"8012","net":"Abeille1","nPDU":"00","aPDU":"01"}
[2024-01-16 19:59:00] processAcks(): msg={"type":"8011","net":"Abeille1","status":"00","addr":"D2D9","sqnAps":"20"}
[2024-01-16 19:59:00]   cmd={"dest":"Abeille1","addr":"D2D9","cmd":"0092","datas":"02D2D9010101","zgOnly":false,"status":"SENT","try":3,"sentTime":1705431540,"timeout":8,"sqn":"0E","sqnAps":"20","ackAps":true,"waitFor":"ACK"}
[2024-01-16 19:59:00]   eq={"ieee":"000B57FFFE952A69","txStatus":"ok","jsonId":"TRADFRIbulbE27opal1000lm","jsonLocation":"Abeille","rxOnWhenIdle":false,"mainEp":"01","commands":{"etat":{"type":"info","subType":"binary","template":"light","configuration":{"repeatEventManagement":"never","visibilityCategory":"All"},"logicalId":"0006-01-0000","genericType":"LIGHT_STATE_BOOL","isVisible":1},"CurrentLevel-0008":{"subType":"numeric","template":"","configuration":{"minValue":"0","maxValue":"100","historizeRound":"0","calculValueOffset":"#value#\/255*100"},"type":"info","logicalId":"0008-01-0000","unit":"%","genericType":"LIGHT_STATE","isVisible":1},"On":{"type":"action","subType":"other","logicalId":"01-0006-CmdOn","configuration":{"topic":"cmd-0006","request":"ep=01&cmd=01","visibilityCategory":"All"},"genericType":"LIGHT_ON","isVisible":1},"Off":{"type":"action","subType":"other","logicalId":"01-0006-CmdOff","configuration":{"topic":"cmd-0006","request":"ep=01&cmd=00","visibilityCategory":"All"},"genericType":"LIGHT_OFF","isVisible":1},"Set Level":{"subType":"slider","logicalId":"01-0008-CmdMoveToLevelWithOnOff","template":"default","configuration":{"topic":"cmd-0008","request":"ep=01&cmd=04&level=#slider#","valueOffset":"#value#*255\/100","visibilityCategory":"All"},"type":"action","unit":"%","genericType":"LIGHT_SLIDER","isVisible":1},"Get-Status":{"subType":"other","configuration":{"topic":"readAttribute","request":"ep=01&clustId=0006&attrId=0000"},"type":"action","logicalId":"readAttr-01-0006-0000"},"Get-CurrentLevel":{"subType":"other","configuration":{"topic":"readAttribute","request":"ep=01&clustId=0008&attrId=0000"},"type":"action","logicalId":"readAttr-01-0008-0000"},"Identify":{"subType":"other","logicalId":"identify","template":"","configuration":{"topic":"identifySend","request":"EP=01&duration=0010","visibilityCategory":"additionalCommand"},"type":"action","isVisible":1},"Bind-0006-ToZigate":{"name":"Bind device to Zigate","subType":"other","logicalId":"bind-01-0006-ToZigate","template":"","configuration":{"topic":"bind0030","request":"addr=#IEEE#&ep=01&clustId=0006&destAddr=#ZigateIEEE#&destEp=01","execAtCreation":"Yes","execAtCreationDelay":9},"type":"action"},"Bind-0008-ToZigate":{"name":"Bind device to Zigate","subType":"other","logicalId":"bind-01-0008-ToZigate","template":"","configuration":{"topic":"bind0030","request":"addr=#IEEE#&ep=01&clustId=0008&destAddr=#ZigateIEEE#&destEp=01","execAtCreation":"Yes","execAtCreationDelay":9},"type":"action"},"SetReporting-0006-0000":{"subType":"other","logicalId":"configReport-01-0006-0000","configuration":{"topic":"configureReporting2","request":"ep=01&clustId=0006&attrType=10&attrId=0000","comment":"Full request ex: ep=01&clustId=0006&attrType=10&attrId=0000&minInterval=#MININTERVAL#&maxInterval=#MAXINTERVAL#&changeVal=#CHANGEVAL#","execAtCreation":"Yes","execAtCreationDelay":11},"type":"action"},"SetReporting-0008-0000":{"subType":"other","logicalId":"configReport-01-0008-0000","configuration":{"topic":"configureReporting2","request":"ep=01&clustId=0008&attrType=20&attrId=0000","comment":"Full request ex: ep=01&clustId=0008&attrType=20&attrId=0000&minInterval=#MININTERVAL#&maxInterval=#MAXINTERVAL#&changeVal=#CHANGEVAL#","execAtCreation":"Yes","execAtCreationDelay":11},"type":"action"},"SWBuildID":{"type":"info","subType":"string","genericType":"GENERIC_INFO","configuration":{"visibilityCategory":"All"},"logicalId":"0000-01-4000"},"Get-SWBuildID":{"subType":"other","configuration":{"topic":"readAttribute","request":"ep=01&clustId=0000&attrId=4000"},"type":"action","logicalId":"readAttr-01-0000-4000"},"Toggle":{"type":"action","subType":"other","logicalId":"01-0006-CmdToggle","configuration":{"topic":"cmd-0006","request":"ep=01&cmd=02","visibilityCategory":"All"},"genericType":"LIGHT_TOGGLE","nextLine":"after"},"Short-Addr":{"subType":"string","template":"badge","configuration":{"visibilityCategory":"Network"},"type":"info","logicalId":"Short-Addr","genericType":"GENERIC_INFO"},"IEEE-Addr":{"subType":"string","template":"badge","configuration":{"visibilityCategory":"Network"},"type":"info","logicalId":"IEEE-Addr","genericType":"GENERIC_INFO"},"Link Quality":{"subType":"string","template":"badge","configuration":{"visibilityCategory":"Network"},"type":"info","logicalId":"Link-Quality","genericType":"GENERIC_INFO"},"Online":{"subType":"binary","invertBinary":"0","template":"default","configuration":{"returnStateValue":"0","returnStateTime":"60","repeatEventManagement":"always","visibilityCategory":"Network"},"type":"info","logicalId":"online","genericType":"GENERIC_INFO"},"Time-Time":{"subType":"string","template":"","configuration":{"visibilityCategory":"Time"},"type":"info","logicalId":"Time-Time","genericType":"GENERIC_INFO"},"Time-TimeStamp":{"subType":"numeric","template":"badge","configuration":{"visibilityCategory":"Time"},"type":"info","logicalId":"Time-TimeStamp","genericType":"GENERIC_INFO"}}}
[2024-01-16 19:59:00]   Removing cmd from queue (Pri/Idx/Count=2/0/1)
[2024-01-16 19:59:00]   BEFORE: count=1, [{"dest":"Abeille1","addr":"D2D9","cmd":"0092","datas":"02D2D9010101","zgOnly":false,"status":"SENT","try":3,"sentTime":1705431540,"timeout":8,"sqn":"0E","sqnAps":"20","ackAps":true,"waitFor":"ACK"}]
[2024-01-16 19:59:00]   AFTER: count=0, []
[2024-01-16 19:59:00] processAcks(): msg={"type":"8012","net":"Abeille1","nPDU":"00","aPDU":"02"}
[2024-01-16 19:59:00] processAcks(): msg={"type":"8012","net":"Abeille1","nPDU":"00","aPDU":"02"}
[2024-01-16 19:59:00] processAcks(): msg={"type":"8011","net":"Abeille1","status":"00","addr":"D2D9","sqnAps":"21"}
[2024-01-16 19:59:00]   Corresponding cmd not found.
[2024-01-16 19:59:00] processAcks(): msg={"type":"8011","net":"Abeille1","status":"00","addr":"D2D9","sqnAps":"22"}
[2024-01-16 19:59:00]   Corresponding cmd not found.

17 secondes avant de voir la demande de commande !

KiwiHC16 commented 5 months ago

En fait je dois attendre qq s avant d avoir:

[2024-01-16 20:05:49][INFO] : Exécution du scénario [Salon][le salon][Télécommande Meuble bt 1] déclenché par : [le salon - Operation][Telecommande Meuble][bt1]
[2024-01-16 20:05:49][INFO] : Exécution de la commande [le salon][Lampe Milieu][On] avec les paramètres {"background":"0"}
KiwiHC16 commented 5 months ago 
[2024-01-16 20:07:32][INFO] : Evènement sur la commande [le salon - Operation][Telecommande Meuble][bt1] valeur : 0
...
[2024-01-16 20:08:13][INFO] : Exécution du scénario [Salon][le salon][Télécommande Meuble bt 1] déclenché par : [le salon - Operation][Telecommande Meuble][bt1]
[2024-01-16 20:08:13][INFO] : Exécution de la commande [le salon][Lampe Milieu][Off] avec les paramètres {"background":"0"}
[2024-01-16 20:08:13][INFO] : Evènement sur la commande [le salon][Lampe Milieu][etat] valeur : 0
[2024-01-16 20:08:13][INFO] : Evènement sur la commande [le salon][Lampe Milieu][Time-TimeStamp] valeur : 1705432093
[2024-01-16 20:08:13][INFO] : Evènement sur la commande [le salon][Lampe Milieu][Time-Time] valeur : 2024-01-16 20:08:13
[2024-01-16 20:08:14][INFO] : Evènement sur la commande [le salon][Lampe Milieu][Online] valeur : 1

Le temps est entre l evenment telecommande et execution scenario !!!

KiwiHC16 commented 5 months ago

Plein d erreur dans les logs scenario...

KiwiHC16 commented 5 months ago

Je clos, mon systeme est parti en vrille et ce n est pas du fait de Abeille.

KiwiHC16 commented 5 months ago

Problème de definition DNS dans /etc/resolv.conf