Test Adapter KNX v2.x

[JojoS]

mit der neuen Version 2.0.15 werden Ein-Schaltbefehle mit etwa 2s Verzögerung ausgeführt, Ausschalten geht schnell. Ich bin jetzt auf 2.0.14 zurück, damit beobachte ich diesen Effekt nicht.
Bin ich Einzelschicksal oder kann jemand das bestätigen?

[Garfonso]

@jojos
ich nehme hier grundsätzlich auch eine deutliche Verzögerung wahr. Ich vermute aber nicht, dass es am konkreten Befehl liegt, sondern daran, dass es "der erste" Befehl nach einer längeren Zeit ist. (i.e. wenn die Aus schaltest, hast du vermutlich kurz vorher angeschaltet -> lässt du eine Lampe mal ein paar Stunden an, vermute ich, dass das ausschalten auch brauchen wird. Hier beobachte ich das jedenfalls mit allem möglichen, Rolladen, Farbtemperatur, dimmen, ...).

[JojoS]

@garfonso ja, wobei gefühlt trotzdem aus schneller schaltet. Aber ja, direkt nach aus einschalten geht dann auch schnell.

Edit:
Einschalten langsamer weil ich gerade ein Gerät mit elektronischem Trafo beobachtet habe, da schaltet der langsamer ein. Es ist also wie du sagst, nach einer Zeit schläft das ein und reagiert beim ersten Mal langsamer.

ioB läuft auf einem Proxmox mit i3, ioB CPU ist kontinuierlich ca. 8 %, andere Container laufen mit <10 % in Summe. Sollte da auch kein Performanceproblem sein. Und mit der älteren Version ist dieses Verhalten noch nicht zu sehen.

[chefkoch009]

@jojos Steht irgendetwas auffälliges in Deinem log?

Bei mir kann ich diese Beobachtung nicht machen. Ich habe das (dimmen, schalten )versucht bei mir erfolglos nachzustellen. Dimmen ist wegen softon-softoff sowieso sehr schwer nachzuvollziehen. Wenn ich auf den Gruppenmonitor schaue, dann geht das nahezu instant bei mir durch.

VG
chefkoch009

[JojoS]

@chefkoch009 ich habe jetzt nochmal die neue Version aktiviert und eine einfache Lampe angeschlossen. Die Ein/Ausschaltzeiten sind sehr zufällig verzögert, bis mehrere Sekunden. Ich kann da jetzt noch kein Schema erkennen.
Ein log habe ich aufgenzeichnet, wo kann ich das hinschicken?
Da war doch auch was mit Redis, wo kann ich nochmal sehen ob ich das benutze, die Installation ist schon so lange her...
edit:
also der redis service läuft nicht. Nur im log sehe ich States user redis pmessage knx.0.* Meldungen.

[lessthanmore]

@jojos said in Test Adapter KNX v2.x:

wo kann ich nochmal sehen ob ich das benutze

iob status

gibt dann sowas:

iob status
iobroker is running on this host.

At least one iobroker host is running.

Objects type: redis
States  type: redis

[JojoS]

@lessthanmore danke, ich benutze json1

@chefkoch009

knx.0	2022-06-15 13:15:24.204	info	( 4.b ) return to STATE_READY, processing : false
knx.0	2022-06-15 13:15:24.204	info	( 4 ) Sending Tunnel_Request ACK : 06 10 04 21 00 0a 04 59 10 00 ChID : 89 SeqCntIN : 16 SeqCntOUT : 142 queue length : 0
knx.0	2022-06-15 13:15:24.203	info	=====> STATE_TUNNELING_ACK
knx.0	2022-06-15 13:15:24.202	info	WRITE : mappedName : Terasse Steckdose3 li Status dest : 3/1/3 val: 0 (DPT1.011) Terasse_Steckdose3_li_Status
knx.0	2022-06-15 13:15:24.202	info	( 3.2 ) Received TUNNEL_REQUEST (WRITE - send ACK ) : 06 10 04 20 00 15 04 59 10 00 29 00 bc e0 11 03 19 03 01 00 80 ChID: 89
knx.0	2022-06-15 13:15:24.149	info	( 4.b ) return to STATE_READY, processing : false
knx.0	2022-06-15 13:15:24.149	info	( 4 ) Sending Tunnel_Request ACK : 06 10 04 21 00 0a 04 59 0f 00 ChID : 89 SeqCntIN : 15 SeqCntOUT : 142 queue length : 0
knx.0	2022-06-15 13:15:24.148	info	=====> STATE_TUNNELING_ACK
knx.0	2022-06-15 13:15:24.148	info	( 3.6a ) Received L_DATA.con 06 10 04 20 00 15 04 59 0f 00 2e 00 bc e0 11 64 18 03 01 00 80 was successfully processed by receiver.
knx.0	2022-06-15 13:15:24.131	info	==> easy-knx.js: signal runtime : 0s 1.209669ms
knx.0	2022-06-15 13:15:24.131	info	==> successful acknowledged previous package... processing next of 0
knx.0	2022-06-15 13:15:24.131	info	( 2 ) Received TUNNEL_ACK : 06 10 04 21 00 0a 04 59 8d 00 from 192.168.100.64:3671 SeqCntIN : 14 SeqCntOUT : 142 GA : 0/0/0
knx.0	2022-06-15 13:15:24.130	info	easy-knx: task.data : 06 10 04 20 00 15 04 59 8d 00 11 00 bc e0 11 64 18 03 01 00 80 byteLen : 21
knx.0	2022-06-15 13:15:24.130	info	( 1 ) Sending : GroupValueWrite : 06 10 04 20 00 15 04 59 8d 00 11 00 bc e0 11 64 18 03 01 00 80 sent to 192.168.100.64:3671 ChID: 89 SeqCntIN : 14 SeqCntOUT : 141 GA : 3/0/3 conCheck.conected : true
knx.0	2022-06-15 13:15:24.130	info	Has todo still 1 tasks, just processing : false
knx.0	2022-06-15 13:15:24.129	info	Received CONNECTIONSTATE_RESPONSE : 06 10 02 08 00 08 59 00 192.168.100.64:3671 ChID : 89 SeqCntIN : 14 SeqCntOUT : 141 msgCode : [object Object]
knx.0	2022-06-15 13:15:24.129	info	Send : conCheck Connection State Request : 06 10 02 07 00 10 59 00 08 01 00 00 00 00 9d 01 sent to 192.168.100.64:3671
knx.0	2022-06-15 13:15:24.128	info	checkConnectionState 192.168.100.64 State : true
knx.0	2022-06-15 13:15:17.897	info	BINARY CHANGE change from false to 0
knx.0	2022-06-15 13:15:17.896	info	easy-knx.js groupValueWrite value: 0 dpt : DPT1.001{"type":"Buffer","data":[6,16,4,32,0,21,4,89,141,0,17,0,188,224,17,100,24,3,1,0,128]}
knx.0	2022-06-15 13:15:17.896	info	main.js : tGA.write on Statechange : 3/0/3 P-0AC9-0_GA-52 typeof val: boolean false DPT1.001

das müsste ein Ausschnitt sein wo die Reaktion lange gedauert hat.

[chefkoch009]

@jojos Zwischen Zeile 18 und 19 liegen 7Sek?..... In Zeile 19 hat er alles soweit vorbereitet zum Senden. Dann passiert nix, deshalb fragt er nach, ob die Verbindung noch besteht und bekommt ein OK zurück und sendet unmittelbar danach.

Die Frage ist nun kann oder will er nicht senden? Tritt das erst nach längerem Betrieb auf, oder direkt beim Start?

VG
chefkoch009

[JojoS]

@chefkoch009 das passiert auch direkt beim Start. Ist das vielleicht was mit Zusammenspiel mit neuem Admin/Controller?

ich hatte es vorher nochmal mit level silly, da sieht man in der Pause aber auch nichts.

2022-06-15 12:59:58.628 - info: knx.0 (4178186) =====> STATE_TUNNELING_ACK
2022-06-15 12:59:58.629 - info: knx.0 (4178186) ( 4 ) Sending Tunnel_Request ACK : 06 10 04 21 00 0a 04 58 d1 00 ChID : 88 SeqCntIN : 209 SeqCntOUT : 106 queue length : 0
2022-06-15 12:59:58.629 - info: knx.0 (4178186) ( 4.b ) return to STATE_READY, processing : false
2022-06-15 12:59:58.630 - info: knx.0 (4178186) ( 3.2 ) Received TUNNEL_REQUEST (WRITE - send ACK ) : 06 10 04 20 00 17 04 58 d2 00 29 00 bc e0 11 04 30 03 03 00 80 0c 92 ChID: 88
2022-06-15 12:59:58.630 - info: knx.0 (4178186) WRITE : mappedName : Schlafzimmer dest : 6/0/3 val: 23.400000000000002 (DPT9.001) Schlafzimmer
2022-06-15 12:59:58.630 - info: knx.0 (4178186) =====> STATE_TUNNELING_ACK
2022-06-15 12:59:58.630 - info: knx.0 (4178186) ( 4 ) Sending Tunnel_Request ACK : 06 10 04 21 00 0a 04 58 d2 00 ChID : 88 SeqCntIN : 210 SeqCntOUT : 106 queue length : 0
2022-06-15 12:59:58.630 - info: knx.0 (4178186) ( 4.b ) return to STATE_READY, processing : false
2022-06-15 12:59:58.643 - silly: knx.0 (4178186) States user redis pmessage knx.0.*/knx.0.Sensoren.Licht.HelligkeitSchlafzimmer:{"val":511.04,"ack":true,"ts":1655290798641,"q":0,"from":"system.adapter.knx.0","user":"system.user.admin","lc":1655290797661}
2022-06-15 12:59:58.644 - silly: knx.0 (4178186) States user redis pmessage knx.0.*/knx.0.Sensoren.Temperaturen.Schlafzimmer:{"val":23.400000000000002,"ack":true,"ts":1655290798641,"q":0,"from":"system.adapter.knx.0","user":"system.user.admin","lc":1655290078077}
2022-06-15 13:00:00.785 - silly: knx.0 (4178186) States user redis pmessage knx.0.*/knx.0.Dosen_schalten.Schalten.Terasse_Steckdose3_li:{"val":true,"ack":false,"ts":1655290800784,"q":0,"from":"system.adapter.web.0","user":"system.user.admin","lc":1655290800784}
2022-06-15 13:00:00.785 - info: knx.0 (4178186) main.js : tGA.write on Statechange : 3/0/3 P-0AC9-0_GA-52 typeof val: boolean true DPT1.001
2022-06-15 13:00:00.785 - info: knx.0 (4178186) easy-knx.js groupValueWrite value: 1 dpt : DPT1.001{"type":"Buffer","data":[6,16,4,32,0,21,4,88,106,0,17,0,188,224,17,100,24,3,1,0,129]}
2022-06-15 13:00:00.786 - info: knx.0 (4178186) BINARY CHANGE change from true to 1
2022-06-15 13:00:08.631 - info: knx.0 (4178186) checkConnectionState 192.168.100.64 State : true
2022-06-15 13:00:08.632 - info: knx.0 (4178186) Send : conCheck Connection State Request : 06 10 02 07 00 10 58 00 08 01 00 00 00 00 cd e1 sent to 192.168.100.64:3671
2022-06-15 13:00:08.632 - info: knx.0 (4178186) Received CONNECTIONSTATE_RESPONSE : 06 10 02 08 00 08 58 00 192.168.100.64:3671 ChID : 88 SeqCntIN : 210 SeqCntOUT : 106 msgCode : [object Object]
2022-06-15 13:00:08.632 - info: knx.0 (4178186) Has todo still 1 tasks, just processing : false
2022-06-15 13:00:08.632 - info: knx.0 (4178186) ( 1 ) Sending : GroupValueWrite : 06 10 04 20 00 15 04 58 6a 00 11 00 bc e0 11 64 18 03 01 00 81 sent to 192.168.100.64:3671 ChID: 88 SeqCntIN : 210 SeqCntOUT : 106 GA : 3/0/3 conCheck.conected : true
2022-06-15 13:00:08.633 - info: knx.0 (4178186) easy-knx: task.data : 06 10 04 20 00 15 04 58 6a 00 11 00 bc e0 11 64 18 03 01 00 81 byteLen : 21
2022-06-15 13:00:08.633 - info: knx.0 (4178186) ( 2 ) Received TUNNEL_ACK : 06 10 04 21 00 0a 04 58 6a 00 from 192.168.100.64:3671 SeqCntIN : 210 SeqCntOUT : 107 GA : 0/0/0
2022-06-15 13:00:08.633 - info: knx.0 (4178186) ==> successful acknowledged previous package... processing next of 0
2022-06-15 13:00:08.633 - info: knx.0 (4178186) ==> easy-knx.js: signal runtime : 0s 0.917653ms

[chefkoch009]

@jojos Auf meinem Dev-System und Produktionssystem habe ich den 5er Admin laufen.

Wenn das der einzige Unterschied ist, dann möchte ich einmal in die Runde fragen, ob jemand das bestätigen kann, oder so etwas beobachtet hat.

Hast Du dieses Verhalten auch, wenn Du direkt über den Objektbaum schaltest oder nur über die Visu?

VG
chefkoch009

[JojoS]

@chefkoch009 Verzögerungen gab es auch bei Logik über Node Red. Beim Schalten über den Objektbaum gibt es die Verzögerung auch.
Den Admin habe ich bis 5.4.8 zurück installiert, die Verzögerung tritt trotzdem nur bei KNX 2.0.15 auf. Reproduzierbar, mehrmals verschiedene Versionen installiert.
Garfonso hat das doch auch beobachtet?

jojo@iobroker-prod:~$ iob info
Platform       : linux
os             : linux
Architecture   : x64
CPUs           : 1
Speed          : 2600 MHz
Model          : Intel(R) Core(TM) i3-10110U CPU @ 2.10GHz
RAM            : 4 GB
System uptime  : 17d. 23:07:09
Node.js        : v14.19.3
time           : 1655378041359
timeOffset     : -120
adapters count : 501
NPM            : v6.14.17
Disk size      : 15.6 GiB
Disk free      : 7.3 GiB
jojo@iobroker-prod:~$ iobroker v
4.0.23

[Garfonso]

@jojos
ja, hab das mit Admin 5.x und auch Admin 6 beobachtet. Schalten per Javascript, Visu (Lovelace) oder Objektbaum macht keinen Unterschied, kann alles verzögert sein.

[chefkoch009]

Vielen Dank für eure Mühen. Tritt es nur bei "Schalten" oder auch bei "dimmen Wert" auf?

VG
chefkoch009

[schmid_no1]

@chefkoch009 kann ich bestätigen mit admin 6.
Verzögerungen geschaltet über die vis kommen bis zu 7 Sekunden später an

[Garfonso]

@chefkoch009
bei dimmen bin ich mir nicht 100% sicher, ich meine aber auch das schon gesehen zu haben. Sicher gesehen habe ich es schon bei farbtemperatur und bei rolladen position (also 0-100, müsste dann ja der gleiche Datentyp sein) (Rolladenposition ließ sich gerade reproduzieren).

[leachim200]

Hallo ich verzweifle gerade an der Iobroker-KNX verbindung.
Ich habe in der ETS mein MD Ip Interface 0003 Secure mit der physicalischen Adresse 1.1.1 ausgestattet Tunnel 1-4 liegen dann auf 1.1.2-5
Die physikalische Adresse der verbindung ist 1.0.250. Wenn ich nun 1.1.4 als physikalische Adresse eintrage bekomme ich keine Geräte verbindung zu stande.

Ich hab im Interface bereits auf Set gedrück somit sollte er die Adressen ausgerollt haben. Wo liegt mein Gedanken fehler?

logauszug:
2022-07-12 19:40:52.079 - info: knx.0 (27588) Debuglevel: 2 3
2022-07-12 19:40:52.085 - info: knx.0 (27588) Event : UDP - listening
2022-07-12 19:40:52.092 - info: knx.0 (27588) Connected - local UDP Server listening on xxxxxxxxxxxxxxx:51181
2022-07-12 19:40:52.096 - info: knx.0 (27588) Send : UDP Connection Request : 06 10 02 05 00 1a 08 01 c0 a8 58 fc c7 ed 08 01 00 00 00 00 c7 ed 04 04 02 00 sent to 192.168.88.240:3671
2022-07-12 19:40:52.104 - info: knx.0 (27588) Connection persists.....closing now
2022-07-12 19:40:52.106 - info: knx.0 (27588) ... not able to close connection, because already closed
2022-07-12 19:40:52.108 - info: knx.0 (27588) STATE_NOT_CONNECTED : Try to connect / reconnect : STATE_CONNECTION_STATE_REQUEST(5) to STATE_NOT_CONNECTED(0).
2022-07-12 19:40:54.104 - info: knx.0 (27588) STATE_CONNECTION_STATE_REQUEST : Not acceptable step... disconnecting
2022-07-12 19:40:54.114 - info: knx.0 (27588) ( END ) Sending DISCONNECT_REQUEST : 06 10 02 09 00 10 00 00 08 01 c0 a8 58 fc c7 ed ChID : 0 SeqCntIN : 0 SeqCntOUT : 0 msgCode : [object Object]
2022-07-12 19:40:54.117 - info: knx.0 (27588) STATE_DISCONNECT_REQUEST : no defined handling for transition from State: STATE_CONNECTION_STATE_REQUEST(5) to STATE_DISCONNECT_REQUEST(15).
2022-07-12 19:40:54.118 - info: knx.0 (27588) Connection persists.....closing now

[leachim200]

So ich bin weiter es dürfte an der physikalischen Adresse liegen.
Wenn ich das Interface auf werkseinstellungen setzte und die physikalische Standart IP nehme funktioniert alles. Sobald ich Secure darüber spiele geht nix mehr.
Wenn ich das ganze Ohne IP-Secure aufspiele funktioniert alles fehlerfrei.
Unterstützt der Adapter IP-Secure?

[leachim200]

@schmid_no1 Kann ich bestätigen. Ich habe direkt am Objekt Befehle abgesätzt, egal ob Tag/Nach umschaltung oder Rolläden Positionen die Befehle kommen oft bis zu 7 Sekunden später an.

[leachim200]

Hallo,
ich bin auf ein weiteres Problem gestoßen und wäre echt froh über Hilfe.
Ich versuche einen Text über iobroker an den MDT Glastaster in form eines Statustextes zu senden.

GA ist angelegt und auch im iobroker hinterlegt. Schalten und prozentwerte senden funktioniert aber der Text nicht. Was mache ich falsch?

Hier noch die Screenshots

[leachim200]

Ich bin einen Schritt weiter. Wenn ich das Ganze am Gruppenmonitor in der ETS durchführe wird der Wert ohne Probleme geschrieben. Daher gehe ich davon aus, dass es am Iobroker liegt. Logfile zeigt bei einer Wertänderung keine Regung das hier etwas gesendet wurde.