'Secure' - Inklusion mit Fibaro FGS 223

Begonnen von PNinBB, 26 November 2018, 20:25:27

Vorheriges Thema - Nächstes Thema

PNinBB

Aus bestimmten Gründen musste ich das Gerät mit dem Fibare Doppelschalter FGS 223 neu inkludieren.
Nach der Dokumentation ist es ein ZWAVE 2 - Gerät und unterstützt die 'SECURITY'-Klasse.
Ich habe es zuerst im Z-Way-System erfolgreich erledigen können (Knoten: 41). Es wurden im Dashboard 13 Icons angezeit (Hauptgerät, die beiden Schalter, Anzeigen für 'Power' und 'Energy', jeweils Gesamt und Einzelgeräte, dazu noch diverse Alarme). Im Logfile sind Einträge wie 'devices.41.instances.x...' mit x = 0, 1 oder 2) zufinden; die gesamte Logdatei als Anhang. Danach habe ich wieder exkludiert.
Dann habe ich es in FHEM mit 'set ZWAVE addNode onNwSec' erfolgreich inkludiert. Nachfolgend ein Auszug aus der Logdatei.
2018.11.26 09:29:51.449 2: autocreate: define ZWave_SWITCH_BINARY_44 ZWave ed70b42b 44 5e8672255a5985735670328e60227571987a5bef26
2018.11.26 09:29:51.475 2: autocreate: define FileLog_ZWave_SWITCH_BINARY_44 FileLog ./log/ZWave_SWITCH_BINARY_44-%Y.log ZWave_SWITCH_BINARY_44
2018.11.26 09:29:54.207 2: ZWAVE Starting secure init for ZWave_SWITCH_BINARY_44
2018.11.26 09:29:55.070 2: ZWDongle_ProcessSendStack: no ACK, resending message 0105004a0516a3
2018.11.26 09:29:55.647 2: ZWDongle_ProcessSendStack: no ACK, resending message 0105004a0516a3
2018.11.26 09:29:56.659 2: ZWDongle_ProcessSendStack: no ACK, resending message 0105004a0516a3
2018.11.26 09:29:58.192 2: ZWDongle_ProcessSendStack: no ACK, resending message 0105004a0516a3
2018.11.26 09:29:58.754 1: ZWave_SWITCH_BINARY_44: no stored commands in Internal secMsg found
2018.11.26 09:29:58.754 1: ZWave_SWITCH_BINARY_44: Error, nonce reveived but no stored command for encryption found
2018.11.26 09:29:58.807 3: ZWave_SWITCH_BINARY_44: SECURITY enabled, networkkey was verified
2018.11.26 09:29:58.810 3: ZWave set ZWave_SWITCH_BINARY_44 secSupportedReport
2018.11.26 09:29:58.944 1: ZWAVE INIT: get ZWave_SWITCH_BINARY_44 versionClassAll: Secure operation in progress, executing in background
2018.11.26 09:29:59.029 1: ZWAVE INIT: get ZWave_SWITCH_BINARY_44 model: Secure operation in progress, executing in background
2018.11.26 09:29:59.120 3: ZWave get ZWave_SWITCH_BINARY_44 versionClass ALARM
2018.11.26 09:29:59.125 3: ZWave get ZWave_SWITCH_BINARY_44 versionClass APPLICATION_STATUS
2018.11.26 09:29:59.128 3: ZWave get ZWave_SWITCH_BINARY_44 versionClass ASSOCIATION
2018.11.26 09:29:59.130 3: ZWave get ZWave_SWITCH_BINARY_44 versionClass ASSOCIATION_GRP_INFO
2018.11.26 09:29:59.133 3: ZWave get ZWave_SWITCH_BINARY_44 versionClass CENTRAL_SCENE
2018.11.26 09:29:59.135 3: ZWave get ZWave_SWITCH_BINARY_44 versionClass CONFIGURATION
2018.11.26 09:29:59.138 3: ZWave get ZWave_SWITCH_BINARY_44 versionClass CRC_16_ENCAP
2018.11.26 09:29:59.141 3: ZWave get ZWave_SWITCH_BINARY_44 versionClass DEVICE_RESET_LOCALLY
2018.11.26 09:29:59.143 3: ZWave get ZWave_SWITCH_BINARY_44 versionClass FIRMWARE_UPDATE_MD
2018.11.26 09:29:59.146 3: ZWave get ZWave_SWITCH_BINARY_44 versionClass MANUFACTURER_SPECIFIC
2018.11.26 09:29:59.148 3: ZWave get ZWave_SWITCH_BINARY_44 versionClass METER
2018.11.26 09:29:59.151 3: ZWave get ZWave_SWITCH_BINARY_44 versionClass MULTI_CHANNEL
2018.11.26 09:29:59.153 3: ZWave get ZWave_SWITCH_BINARY_44 versionClass MULTI_CHANNEL_ASSOCIATION
2018.11.26 09:29:59.156 3: ZWave get ZWave_SWITCH_BINARY_44 versionClass POWERLEVEL
2018.11.26 09:29:59.158 3: ZWave get ZWave_SWITCH_BINARY_44 versionClass PROTECTION
2018.11.26 09:29:59.161 3: ZWave get ZWave_SWITCH_BINARY_44 versionClass SECURITY
2018.11.26 09:29:59.163 3: ZWave get ZWave_SWITCH_BINARY_44 versionClass SWITCH_BINARY
2018.11.26 09:29:59.166 3: ZWave get ZWave_SWITCH_BINARY_44 versionClass SWITCH_MULTILEVEL
2018.11.26 09:29:59.168 3: ZWave get ZWave_SWITCH_BINARY_44 versionClass VERSION
2018.11.26 09:29:59.171 3: ZWave get ZWave_SWITCH_BINARY_44 versionClass ZWAVEPLUS_INFO
2018.11.26 09:29:59.174 3: ZWave set ZWave_SWITCH_BINARY_44 associationAdd 1 1
2018.11.26 09:30:00.295 2: ZWDongle_ProcessSendStack: no ACK, resending message 010a00132c03861322258dd6
2018.11.26 09:30:01.513 2: ZWDongle_ProcessSendStack: no ACK, resending message 010a00132c03861385258e72
2018.11.26 09:30:02.906 2: ZWDongle_ProcessSendStack: no ACK, resending message 010a00132c03861359258faf
2018.11.26 09:30:04.070 2: ZWDongle_ProcessSendStack: no ACK, resending message 010a00132c0386135b2590b2
2018.11.26 09:30:06.037 2: ZWDongle_ProcessSendStack: no ACK, resending message 010a00132c03861370259198
2018.11.26 09:30:06.039 3: ZWave_SWITCH_BINARY_44: secStart older than 6 seconds detected, secUnlock will call Zwave_secEnd
2018.11.26 09:30:06.047 3: ZWave get ZWave_SWITCH_BINARY_44 model
2018.11.26 09:30:06.051 3: ZWave get ZWave_SWITCH_BINARY_44 mcEndpoints
2018.11.26 09:30:08.206 2: ZWDongle_ProcessSendStack: no ACK, resending message 010a00132c0386135a2593b0
2018.11.26 09:30:09.432 2: ZWDongle_ProcessSendStack: no ACK, resending message 010a00132c0386137a259497
2018.11.26 09:30:10.528 2: ZWDongle_ProcessSendStack: no ACK, resending message 010a00132c0386137225959e
2018.11.26 09:30:10.626 2: ZWDongle_ProcessSendStack: no ACK, resending message 010a00132c0386137225959e
2018.11.26 09:30:11.707 2: ZWDongle_ProcessSendStack: no ACK, resending message 010a00132c038613322596dd
2018.11.26 09:30:12.923 2: ZWDongle_ProcessSendStack: no ACK, resending message 010a00132c0386136025978e
2018.11.26 09:30:13.196 3: ZWave_SWITCH_BINARY_44: secStart older than 6 seconds detected, secUnlock will call Zwave_secEnd
2018.11.26 09:30:13.997 2: ZWDongle_ProcessSendStack: no ACK, resending message 010a00132c0386138e25986f
2018.11.26 09:30:15.643 2: ZWDongle_ProcessSendStack: no ACK, resending message 010a00132c03861373259993
2018.11.26 09:30:16.682 2: ZWDongle_ProcessSendStack: no ACK, resending message 010a00132c03861375259a96
2018.11.26 09:30:17.720 2: ZWDongle_ProcessSendStack: no ACK, resending message 010a00132c03861398259b7a
2018.11.26 09:30:17.724 1: Systembereinigung bezüglich 'UNPARSED' gestartet
2018.11.26 09:30:17.762 3: deletereading WZ_DS_SM UNPARSED : Deleted reading UNPARSED for device WZ_DS_SM
2018.11.26 09:30:17.770 3: deletereading ZWave_Node_33.1 UNPARSED : Deleted reading UNPARSED for device ZWave_Node_33.1
2018.11.26 09:30:17.777 1: Systembereinigung bezüglich 'UNKNOWN' gestartet
2018.11.26 09:30:19.294 2: ZWDongle_ProcessSendStack: no ACK, resending message 010a00132c03861325259cc0
2018.11.26 09:30:21.003 2: ZWDongle_ProcessSendStack: no ACK, resending message 010a00132c03861326259dc2
2018.11.26 09:30:22.111 2: ZWDongle_ProcessSendStack: no ACK, resending message 010e00131707600d000326010a25a531
2018.11.26 09:30:22.908 2: ZWDongle_ProcessSendStack: no ACK, resending message 010a00132c0386135e259fb8
2018.11.26 09:30:23.184 2: ZWDongle_ProcessSendStack: no ACK, resending message 010a00132c0386135e259fb8
2018.11.26 09:30:24.778 2: ZWDongle_ProcessSendStack: no ACK, resending message 010900132c02984025a197
2018.11.26 09:30:27.993 3: ZWave got config for fibaro/fgs223.xml from ./FHEM/lib/openzwave_deviceconfig.xml.gz
2018.11.26 09:30:27.998 3: ZWave set ZWave_SWITCH_BINARY_44 associationDel 1 1
2018.11.26 09:30:28.172 2: ZWDongle_ProcessSendStack: no ACK, resending message 010900132c02984025a492
2018.11.26 09:30:28.808 2: ZWDongle_ProcessSendStack: no ACK, resending message 010900132c02984025a492
2018.11.26 09:30:31.171 2: ZWDongle_ProcessSendStack: no ACK, resending message 011f00132c1898811b097a1f97636211c327694c10e9fa7337b77a76924225a640
2018.11.26 09:30:31.599 3: ZWave set ZWave_SWITCH_BINARY_44 mcaAdd 1 0 1 1
2018.11.26 09:30:32.720 2: ZWDongle_ProcessSendStack: no ACK, resending message 011d00132c1698816c7fcc0c14c0d26944f049ebfef3de5e03f4893d25a93a

Es werden allerdings keine Untergräte eingerichtet, vor allem nicht für die Schaltfunktionen.
Ist diese Meldung
. . .
2018.11.26 09:30:06.039 3: ZWave_SWITCH_BINARY_44: secStart older than 6 seconds detected, secUnlock will call Zwave_secEnd
. . .

eventuell dafür verantwortlich, in dem der Prozessa abgebrochen wird.
Oder ob die 'no ACK'-Meldungen damit etwas zu tun haben, ist mir unklar; ich kann die 'message'-Strings nicht entschlüsseln. Vermutlich kann man das mit Hilfe der ZWAVE_Dokumentation (!?); oder gibt es da sogar ein kleine FHEM-Hilfsprogramm.
Dann habe ich wieder exludiert und im 'unsecure'-Mode mit 'set ZWAVE addNode onNw' erfolgreich inkludiert. Nunmehr war alles wie gewohnt; anbei das Log.
2018.11.26 16:05:52.964 2: autocreate: define ZWave_SWITCH_BINARY_46 ZWave ed70b42b 46 5e8672255a5985735670328e60227571987a5bef26
2018.11.26 16:05:52.984 2: autocreate: define FileLog_ZWave_SWITCH_BINARY_46 FileLog ./log/ZWave_SWITCH_BINARY_46-%Y.log ZWave_SWITCH_BINARY_46
2018.11.26 16:05:53.279 2: ZWAVE transmit NO_ACK for CB 09, target WZ_HZ_T1
2018.11.26 16:05:54.738 3: ZWave get ZWave_SWITCH_BINARY_46 versionClass ALARM
2018.11.26 16:05:54.742 3: ZWave get ZWave_SWITCH_BINARY_46 versionClass APPLICATION_STATUS
2018.11.26 16:05:54.744 3: ZWave get ZWave_SWITCH_BINARY_46 versionClass ASSOCIATION
2018.11.26 16:05:54.747 3: ZWave get ZWave_SWITCH_BINARY_46 versionClass ASSOCIATION_GRP_INFO
2018.11.26 16:05:54.749 3: ZWave get ZWave_SWITCH_BINARY_46 versionClass CENTRAL_SCENE
2018.11.26 16:05:54.752 3: ZWave get ZWave_SWITCH_BINARY_46 versionClass CONFIGURATION
2018.11.26 16:05:54.754 3: ZWave get ZWave_SWITCH_BINARY_46 versionClass CRC_16_ENCAP
2018.11.26 16:05:54.757 3: ZWave get ZWave_SWITCH_BINARY_46 versionClass DEVICE_RESET_LOCALLY
2018.11.26 16:05:54.759 3: ZWave get ZWave_SWITCH_BINARY_46 versionClass FIRMWARE_UPDATE_MD
2018.11.26 16:05:54.761 3: ZWave get ZWave_SWITCH_BINARY_46 versionClass MANUFACTURER_SPECIFIC
2018.11.26 16:05:54.764 3: ZWave get ZWave_SWITCH_BINARY_46 versionClass METER
2018.11.26 16:05:54.767 3: ZWave get ZWave_SWITCH_BINARY_46 versionClass MULTI_CHANNEL
2018.11.26 16:05:54.769 3: ZWave get ZWave_SWITCH_BINARY_46 versionClass MULTI_CHANNEL_ASSOCIATION
2018.11.26 16:05:54.772 3: ZWave get ZWave_SWITCH_BINARY_46 versionClass POWERLEVEL
2018.11.26 16:05:54.774 3: ZWave get ZWave_SWITCH_BINARY_46 versionClass PROTECTION
2018.11.26 16:05:54.777 3: ZWave get ZWave_SWITCH_BINARY_46 versionClass SECURITY
2018.11.26 16:05:54.779 3: ZWave get ZWave_SWITCH_BINARY_46 versionClass SWITCH_BINARY
2018.11.26 16:05:54.781 3: ZWave get ZWave_SWITCH_BINARY_46 versionClass SWITCH_MULTILEVEL
2018.11.26 16:05:54.784 3: ZWave get ZWave_SWITCH_BINARY_46 versionClass VERSION
2018.11.26 16:05:54.786 3: ZWave get ZWave_SWITCH_BINARY_46 versionClass ZWAVEPLUS_INFO
2018.11.26 16:05:54.787 1: ZWAVE INIT: get ZWave_SWITCH_BINARY_46 versionClassAll: working in the background, check the vclasses attribute
2018.11.26 16:05:54.790 3: ZWave set ZWave_SWITCH_BINARY_46 associationAdd 1 1
2018.11.26 16:05:54.793 3: ZWave get ZWave_SWITCH_BINARY_46 model
2018.11.26 16:05:54.798 3: ZWave get ZWave_SWITCH_BINARY_46 mcEndpoints
2018.11.26 16:05:55.314 2: ZWDongle_ProcessSendStack: no ACK, resending message 010a00132e03861371250a00
2018.11.26 16:06:01.355 3: ZWave got config for fibaro/fgs223.xml from ./FHEM/lib/openzwave_deviceconfig.xml.gz
2018.11.26 16:06:01.361 3: ZWave set ZWave_SWITCH_BINARY_46 associationDel 1 1
2018.11.26 16:06:01.364 3: ZWave set ZWave_SWITCH_BINARY_46 mcaAdd 1 0 1 1
2018.11.26 16:06:01.840 1: PERL WARNING: Use of uninitialized value $_[1] in numeric eq (==) at ./FHEM/99_myElektroUtils.pm line 303, <FH> line 18300.
2018.11.26 16:06:01.841 3: eval: my $EVENT='1';my $TYPE='ZWave';my $SELF='no_FL_Beleuchtung';my $NAME='FL_DS_SM';my $EV-TPART0='1';{myElektroUtils_FlurBeleuchtung($EVENT)}
2018.11.26 16:06:01.841 1: PERL WARNING: substr outside of string at ./FHEM/99_myUtils.pm line 138, <FH> line 18300.
2018.11.26 16:06:01.841 3: eval: my $EVENT='1';my $TYPE='ZWave';my $SELF='no_FL_Beleuchtung';my $NAME='FL_DS_SM';my $EV-TPART0='1';{myElektroUtils_FlurBeleuchtung($EVENT)}
2018.11.26 16:06:01.841 1: PERL WARNING: Use of uninitialized value in addition (+) at ./FHEM/99_myUtils.pm line 138, <FH> line 18300.
2018.11.26 16:06:01.842 3: eval: my $EVENT='1';my $TYPE='ZWave';my $SELF='no_FL_Beleuchtung';my $NAME='FL_DS_SM';my $EV-TPART0='1';{myElektroUtils_FlurBeleuchtung($EVENT)}
2018.11.26 16:06:02.033 2: ZWDongle_ProcessSendStack: no ACK, resending message 010900132e0260072520ab
2018.11.26 16:06:03.268 2: ZWDongle_ProcessSendStack: no ACK, resending message 010900132e0260072520ab
2018.11.26 16:06:03.504 3: ZWave get ZWave_SWITCH_BINARY_46 mcCapability 1
2018.11.26 16:06:03.508 3: ZWave get ZWave_SWITCH_BINARY_46 mcCapability 2
2018.11.26 16:06:04.434 2: autocreate: define ZWave_SWITCH_BINARY_46.01 ZWave ed70b42b 11777 5e862585598e32ef26
2018.11.26 16:06:04.454 2: autocreate: define FileLog_ZWave_SWITCH_BINARY_46.01 FileLog ./log/ZWave_SWITCH_BINARY_46.01-%Y.log ZWave_SWITCH_BINARY_46.01
2018.11.26 16:06:05.480 2: autocreate: define ZWave_SWITCH_BINARY_46.02 ZWave ed70b42b 11778 5e862585598e32ef26
2018.11.26 16:06:05.500 2: autocreate: define FileLog_ZWave_SWITCH_BINARY_46.02 FileLog ./log/ZWave_SWITCH_BINARY_46.02-%Y.log ZWave_SWITCH_BINARY_46.02

Damit komme ich zwar erst einmal weiter, aber befriedigend ist dies nicht.
Ist die Ursache eventuell in nicht aktuellen xml-Dateien zu suchen ? Wäre es hilfreich, wenn ich mich dazu mal in  der Z-Way-Installation umsehe und dies mit FHEM vergleiche ?
Ein Problem habe ich noch mit diesem Gerät bei der 'Power'-Messung, aber das werde ich in ein anderes Theme schreiben, da es sicherlich nichts mit dem hier geschildetren zu tun hat.
Schönen Abend.
Peter
P.S.: Da die Z-Way-Logdatei sehr groß ist, habe ich nur die Meldungen zum Knoten 41 gelassen.
Raspi 4B + RaZberry2 (Deb 10), FritzBox 7490;
AEOTec: KeyFobGen5: 1x;
Danfoss: Living Connect 2.51: 3x;
Fibaro: FGK: 10x: 3x; FGBS: 001: 8x, 222: 1x; FGMS001: 2x; FGR: 222: 3x, 223: 2x; FGRGBWM-441: 1x; FGBS: 222: 2x, 223: 2x,224: 1x;
Philio: PAN06-1A: 3x;

rudolfkoenig

Bei der Inklusion werden (falls die passende Befehlsklasse vorhanden ist) folgende Befehle automatisch ausgefuehrt:
- set $NAME mcCreateAll
- get $NAME model
- set $NAME wakeupInterval 86400 $CTRLID
- set $NAME associationAdd 1 $CTRLID
- get $NAME versionClassAll

Weiterhin:
- set $NAME associationDel 1 $CTRLID" (nur fuer Fibaro 223)
- set $NAME mcaAdd 1 0 $CTRLID 1 (nur fuer Fibaro 223)
- get $NAME zwavePlusInfo" (nur KFOB)

Insb. versionClassAll generiert viele Befehle, und da in Deinem Netzt Uebertragungsprobleme gibt (siehe resending message), ist das nicht zu Ende gelaufen.

Bei SECURE werden deutlich mehr Meldungen (Pro Befehle statt 2 meine ich 8 ) versendet, das kann insb. bei netzwerkproblemen zu Folgefehler fuehren.
Ich empfehle die erwaehnten Befehle (soweit nicht komplett abgearbeitet) manuell auszufuehren, oder (falls moeglich) auf SECURE zu verzichten.

Btw: nicht aktuelle XML Dateien fuehren nur zu fehlende Config-Hilfe. Fuer die "normale" Operation verlaesst sich FHEM nicht auf das XML sondern auf die vom Geraet selbst uebermittelte Daten. Das ist mW bei den anderen HA-Produkten anders.