FHEM & Danalock V3 Z-Wave

[JWSK]

Hallo die Herren,

ich bin ein quasi Neuling,
habe vor Jahren bereits mit FHEM gearbeitet.
Aufgrund der Inkopatibilität mit meiner Frau
habe ich das Projekt eingestellt.

Jetzt steige ich wieder ein, primär um den
Funktionsumfang meines Danalocks zu
erweitern.

Bisheriger Ablauf
- Raspbian Lite installiert
- Raspi-Config durchgeführt
- SSH aktiviert
- Wlan aktiviert
- Updates / Upgrades durchgeführt
- FHEM installiert
- Rijndael installiert
- Z-Stick Gen5 ft090c eingesteckt
- network_key definiert aabbccddeeffaabbccddeeffaabbccdd
- HomeID ausgelesen (mit Erfolg)

Wenn ich nun auf set ZWDongle_0 addNode onSec klicke springt
er sofort auf set ZWDongle_0 addNode off und nix passiert.

Gebe ich aber "set ZWDongle_0 addNode onSec" manuell in
das Eingabefeld oben ein, so erscheint eine Dialogbox mit
dem Inhalt "addNode failed".

Global verbose = 5 habe ich auch gesetzt.

[JWSK]

Log bei dem Drücken auf den set Button:

2018.08.04 07:09:58.918 4: ZWDongle *** set ZWDongle_0 addNode onSec
2018.08.04 07:09:58.918 5: ZWDongle_Write 004a811f ()
2018.08.04 07:09:58.918 5: SW: 0105004a811f2e
2018.08.04 07:09:58.921 5: Starting notify loop for ZWDongle_0, 1 event(s), first is addNode onSec
2018.08.04 07:09:58.921 5: createNotifyHash
2018.08.04 07:09:58.922 5: End notify loop for ZWDongle_0
2018.08.04 07:09:58.923 5: ACK received, removing 0105004a811f2e from dongle sendstack
2018.08.04 07:09:58.924 4: ZWDongle_Read ZWDongle_0: rcvd 004a1f070000 (request ZW_ADD_NODE_TO_NETWORK), sending ACK
2018.08.04 07:09:58.924 5: SW: 06
2018.08.04 07:09:58.926 5: ZWDongle_0: dispatch 004a1f070000
2018.08.04 07:09:58.927 4: CMD:ZW_ADD_NODE_TO_NETWORK ID:07 ARG:0000 CB:1f
2018.08.04 07:09:58.927 5: Cmd: >set ZWDongle_0 addNode off<
2018.08.04 07:09:58.927 4: ZWDongle *** set ZWDongle_0 addNode off
2018.08.04 07:09:58.928 5: ZWDongle_Write 004a0520 ()
2018.08.04 07:09:58.928 5: SW: 0105004a052095
2018.08.04 07:09:58.930 5: Starting notify loop for ZWDongle_0, 1 event(s), first is addNode off
2018.08.04 07:09:58.931 5: End notify loop for ZWDongle_0
2018.08.04 07:09:58.932 5: Starting notify loop for ZWDongle_0, 1 event(s), first is ZW_ADD_NODE_TO_NETWORK failed
2018.08.04 07:09:58.933 5: End notify loop for ZWDongle_0
2018.08.04 07:09:58.933 4: ZWDongle_0 ZW_ADD_NODE_TO_NETWORK failed
2018.08.04 07:09:58.934 5: ACK received, removing 0105004a052095 from dongle sendstack
2018.08.04 07:09:58.979 4: WEB_10.10.10.25_53438 GET /fhem?detail=ZWDongle_0&fw_id=; BUFLEN:0
2018.08.04 07:09:58.997 4: WEB: /fhem?detail=ZWDongle_0&fw_id= / RL:4423 / text/html; charset=UTF-8 / Content-Encoding: gzip
/
2018.08.04 07:09:59.000 4: ZWDongle_Read ZWDongle_0: rcvd 004a20060000 (request ZW_ADD_NODE_TO_NETWORK), sending ACK
2018.08.04 07:09:59.000 5: SW: 06
2018.08.04 07:09:59.002 5: ZWDongle_0: dispatch 004a20060000
2018.08.04 07:09:59.002 4: CMD:ZW_ADD_NODE_TO_NETWORK ID:06 ARG:0000 CB:20
2018.08.04 07:09:59.003 5: Starting notify loop for ZWDongle_0, 1 event(s), first is ZW_ADD_NODE_TO_NETWORK done
2018.08.04 07:09:59.004 5: End notify loop for ZWDongle_0
2018.08.04 07:09:59.004 4: ZWDongle_0 ZW_ADD_NODE_TO_NETWORK done
2018.08.04 07:09:59.415 4: WEB_10.10.10.25_53438 GET /fhem?cmd=%7BReadingsVal(%22ZWDongle_0%22%2C%22addNode%22%2C%22%22)%7D&XHR=1&fwcsrf=csrf_142467298589051; BUFLEN:0
2018.08.04 07:09:59.417 5: Cmd: >{ReadingsVal("ZWDongle_0","addNode","")}<
2018.08.04 07:09:59.418 4: WEB: /fhem?cmd=%7BReadingsVal(%22ZWDongle_0%22%2C%22addNode%22%2C%22%22)%7D&XHR=1&fwcsrf=csrf_142467298589051 / RL:21 / text/plain; charset=UTF-8 / Content-Encoding: gzip
/
2018.08.04 07:09:59.425 4: WEB_10.10.10.25_53438 GET /fhem?cmd=%7BAttrVal(%22ZWDongle_0%22%2C%22room%22%2C%22%22)%7D&XHR=1&fwcsrf=csrf_142467298589051; BUFLEN:0
2018.08.04 07:09:59.426 5: Cmd: >{AttrVal("ZWDongle_0","room","")}<
2018.08.04 07:09:59.429 4: WEB: /fhem?cmd=%7BAttrVal(%22ZWDongle_0%22%2C%22room%22%2C%22%22)%7D&XHR=1&fwcsrf=csrf_142467298589051 / RL:21 / text/plain; charset=UTF-8 / Content-Encoding: gzip
/
2018.08.04 07:09:59.430 4: Connection accepted from WEB_10.10.10.25_53440
2018.08.04 07:09:59.587 4: WEB_10.10.10.25_53438 GET /fhem?XHR=1&inform=type=status;filter=ZWDongle_0;since=1533359397;fmt=JSON&fw_id=225×tamp=1533359401090; BUFLEN:0
2018.08.04 07:10:02.444 4: Connection closed for WEB_10.10.10.25_53438: EOF
2018.08.04 07:10:02.509 4: WEB_10.10.10.25_53440 GET /fhem/FileLog_logWrapper?dev=Logfile&type=text&file=fhem-2018-08.log; BUFLEN:0



Log bei manueller Ausführung des Befehls:

2018.08.04 07:12:05.424 4: ZWDongle *** set ZWDongle_0 addNode onSec
2018.08.04 07:12:05.424 5: ZWDongle_Write 004a8121 ()
2018.08.04 07:12:05.425 5: SW: 0105004a812110
2018.08.04 07:12:05.427 5: Starting notify loop for ZWDongle_0, 1 event(s), first is addNode onSec
2018.08.04 07:12:05.427 5: createNotifyHash
2018.08.04 07:12:05.428 5: End notify loop for ZWDongle_0
2018.08.04 07:12:05.429 5: ACK received, removing 0105004a812110 from dongle sendstack
2018.08.04 07:12:05.430 4: ZWDongle_Read ZWDongle_0: rcvd 004a21010000 (request ZW_ADD_NODE_TO_NETWORK), sending ACK
2018.08.04 07:12:05.430 5: SW: 06
2018.08.04 07:12:05.432 5: ZWDongle_0: dispatch 004a21010000
2018.08.04 07:12:05.433 4: CMD:ZW_ADD_NODE_TO_NETWORK ID:01 ARG:0000 CB:21
2018.08.04 07:12:05.433 5: Starting notify loop for ZWDongle_0, 1 event(s), first is ZW_ADD_NODE_TO_NETWORK learnReady
2018.08.04 07:12:05.434 5: End notify loop for ZWDongle_0
2018.08.04 07:12:05.434 4: ZWDongle_0 ZW_ADD_NODE_TO_NETWORK learnReady
2018.08.04 07:12:05.454 4: WEB_10.10.10.25_53487 GET /fhem?room=all&fw_id=228; BUFLEN:0
2018.08.04 07:12:05.474 4: WEB: /fhem?room=all&fw_id=228 / RL:1771 / text/html; charset=UTF-8 / Content-Encoding: gzip
/
2018.08.04 07:12:05.561 4: WEB_10.10.10.25_53487 GET /fhem/pgm2/style.css?v=1533356527; BUFLEN:0
2018.08.04 07:12:05.566 4: WEB_10.10.10.25_53490 GET /fhem/pgm2/jquery-ui.min.css; BUFLEN:0
2018.08.04 07:12:05.567 4: WEB_10.10.10.25_53490 => 304 Not Modified
2018.08.04 07:12:05.568 4: Connection closed for WEB_10.10.10.25_53487: EOF
2018.08.04 07:12:05.660 4: WEB_10.10.10.25_53491 GET /fhem/pgm2/defaultCommon.css; BUFLEN:0
2018.08.04 07:12:05.681 4: WEB_10.10.10.25_53491 GET /fhem/pgm2/dashboard_style.css; BUFLEN:0
2018.08.04 07:12:05.740 4: WEB_10.10.10.25_53492 GET /fhem/images/default/fhemicon.png; BUFLEN:0
2018.08.04 07:12:05.861 4: WEB_10.10.10.25_53492 GET /fhem?XHR=1&inform=type=status;filter=room=all;since=1533359524;fmt=JSON&fw_id=228×tamp=1533359527443; BUFLEN:0
2018.08.04 07:12:08.244 4: Connection closed for WEB_10.10.10.25_53492: EOF
2018.08.04 07:12:08.306 4: WEB_10.10.10.25_53490 GET /fhem/FileLog_logWrapper?dev=Logfile&type=text&file=fhem-2018-08.log; BUFLEN:0

[rudolfkoenig]

Der Log bestaetigt nur die Ausgabe: Nach meiner Doku bedeutet CMD:ZW_ADD_NODE_TO_NETWORK mit Parameter 07 "failed". D.h. der Stick sendet innerhalb von wenigen Millisekunden nach "onSec" diese Absage. Um zu sagen warum, koennte ein ZWCUL monitor Mitschnitt helfen, weiss aber nicht, ob sich der Aufwand lohnt.

[JWSK]

Vielen Dank für die Antwort.

Ich habe den Pi mit FHEM zwischem dem Wechsel zweier Sticks
jeweils neu aufgesetzt. Der erste Stick hat gar nicht funktioniert,
der zweite liefert dieses Ergebnis.
Deute ich die Antwort richtig, dass es am Stick liegt?
Oder kann die Antwort auch von der Gegenstelle getriggert ausgelöst werden?
Macht es Sinn ein Geräte ohne Security zu testen? (müsste ich beschaffen)

[JWSK]

Ich verwende FHEM auf einem Raspberry Pi 3 Model B via Wlan.

Könnte es helfen außerdem noch BT abzuschalten?

[krikan]

Könnte es helfen außerdem noch BT abzuschalten?

Könnte sein, halte ich aber für unwahrscheinlich.

Hast Du  Danalock denn schon einmal resetet? Wenn Danalock bei all den Versuchen schon einmal inkludiert war, ist das Scheitern einer neuen Inklusion vorbestimmt.

Darum würde ich:
1. Danalock reseten
2. FHEM-Server komplett stromlos machen und dann neu starten
3. Inklusion erneut probieren

Gruß, Christian

[tux75at]

Nur ein Gedanke von mir, weil ich am Anfang auch ein Problem hatte.

Hast du ein update von FHEM gemacht?
In die Komandozeile einfach ein "undate" schreiben und danach eventuell ein "shutdown restart" oder den Raspi rebooten.

Das Raspbian updaten (apt-get update; apt-get upgrade) hast du in deinem ersten Post erwähnt, also vermute ich mal, dass du FHEM eventuell nicht aktuell hast.

Die Firmware des UZB Dongles (vermute mal, dass du den gleichen verwendest wie ich) wird auch etwas älter sein. Ich glaub die Vesion 5.7 (?) kann mich jetzt nicht ganz genau erinnern ist empfehlenswert, drüber mach es mit nicht ganz aktuellen FHEM auch Probleme.

[JWSK]

Moin die Herren,

Installation ist aktuell,
Sowohl der Stick als auch das Schloss wurden resettet.

Stick und Schloss zurück geschickt,
morgen kommt ein Razberry 2 zum testen

[JWSK]

Moin die Herren,

ich habe mich gestern vertan,
ich habe den Stick und den Fibaro Magnetschalter zurückgesandt.

Heute ist der Razberry angekommen,
das System ist frisch aufgesetzt.

FHEM installiert
Rijndal AES Plugin installiert
FHEM Updates durchgeführt

Ich habe testweise den NetworkKey auf 1234567890ABCDEF1234567890ABCDEF gesetzt
und versucht das Schloss zu verbinden. Auch hier wieder keine Reaktion.

Abstand zwischen Raspberry und Danalock 30 cm.

Folgende Schritte führe ich durch:

1. set ZWAVE1 addNode onSec
2. Pairing Button am Schloss drücken
3. abwarten
4. Checken ob neues Device gefunden wurde?!

habe im Hex Key die Buchstaben auf kleine Buchstaben
geändern und neu probiert -> geht nicht.

Anbei das passende Log:

2018.08.24 13:20:14 5: Cmd: >set ZWAVE1 addNode onSec<
2018.08.24 13:20:14 4: ZWDongle *** set ZWAVE1 addNode onSec
2018.08.24 13:20:14 5: ZWDongle_Write 004a8101 ()
2018.08.24 13:20:14 5: SW: 0105004a810130
2018.08.24 13:20:14 5: Starting notify loop for ZWAVE1, 1 event(s), first is addNode onSec
2018.08.24 13:20:14 5: createNotifyHash
2018.08.24 13:20:14 5: End notify loop for ZWAVE1
2018.08.24 13:20:14 5: ACK received, removing 0105004a810130 from dongle sendstack
2018.08.24 13:20:14 4: ZWDongle_Read ZWAVE1: rcvd 004a01010000 (request ZW_ADD_NODE_TO_NETWORK), sending ACK
2018.08.24 13:20:14 5: SW: 06
2018.08.24 13:20:14 5: ZWAVE1: dispatch 004a01010000
2018.08.24 13:20:14 5: Loading ./FHEM/10_ZWave.pm
2018.08.24 13:20:15 4: CMD:ZW_ADD_NODE_TO_NETWORK ID:01 ARG:0000 CB:01
2018.08.24 13:20:15 5: Starting notify loop for ZWAVE1, 1 event(s), first is ZW_ADD_NODE_TO_NETWORK learnReady
2018.08.24 13:20:15 5: End notify loop for ZWAVE1
2018.08.24 13:20:15 4: ZWAVE1 ZW_ADD_NODE_TO_NETWORK learnReady
2018.08.24 13:20:15 4: WEB_10.10.10.17_59988 GET /fhem?detail=ZWAVE1&fw_id=; BUFLEN:0
2018.08.24 13:20:15 4: WEB: /fhem?detail=ZWAVE1&fw_id= / RL:4597 / text/html; charset=UTF-8 / Content-Encoding: gzip
/
2018.08.24 13:20:15 4: WEB_10.10.10.17_59988 GET /fhem/pgm2/style.css?v=1535109209; BUFLEN:0
2018.08.24 13:20:15 4: Connection closed for WEB_10.10.10.17_59988: EOF
2018.08.24 13:20:15 4: Connection accepted from WEB_10.10.10.17_59990
2018.08.24 13:20:15 4: WEB_10.10.10.17_59990 GET /fhem/pgm2/defaultCommon.css; BUFLEN:0
2018.08.24 13:20:15 4: Connection accepted from WEB_10.10.10.17_59991
2018.08.24 13:20:15 4: WEB_10.10.10.17_59991 GET /fhem/pgm2/dashboard_style.css; BUFLEN:0
2018.08.24 13:20:15 4: Connection accepted from WEB_10.10.10.17_59992
2018.08.24 13:20:15 4: WEB_10.10.10.17_59992 GET /fhem?cmd=%7BReadingsVal(%22ZWAVE1%22%2C%22addNode%22%2C%22%22)%7D&XHR=1&fwcsrf=csrf_614359178314505; BUFLEN:0
2018.08.24 13:20:15 5: Cmd: >{ReadingsVal("ZWAVE1","addNode","")}<
2018.08.24 13:20:15 4: WEB: /fhem?cmd=%7BReadingsVal(%22ZWAVE1%22%2C%22addNode%22%2C%22%22)%7D&XHR=1&fwcsrf=csrf_614359178314505 / RL:21 / text/plain; charset=UTF-8 / Content-Encoding: gzip
/
2018.08.24 13:20:15 4: Connection accepted from WEB_10.10.10.17_59993
2018.08.24 13:20:15 4: WEB_10.10.10.17_59993 GET /fhem?cmd=%7BAttrVal(%22ZWAVE1%22%2C%22room%22%2C%22%22)%7D&XHR=1&fwcsrf=csrf_614359178314505; BUFLEN:0
2018.08.24 13:20:15 5: Cmd: >{AttrVal("ZWAVE1","room","")}<
2018.08.24 13:20:15 4: WEB: /fhem?cmd=%7BAttrVal(%22ZWAVE1%22%2C%22room%22%2C%22%22)%7D&XHR=1&fwcsrf=csrf_614359178314505 / RL:21 / text/plain; charset=UTF-8 / Content-Encoding: gzip
/
2018.08.24 13:20:15 4: WEB_10.10.10.17_59992 GET /fhem?XHR=1&inform=type=status;filter=ZWAVE1;since=1535109614;fmt=JSON&fw_id=55×tamp=1535109615955; BUFLEN:0
2018.08.24 13:20:24 4: Connection closed for WEB_10.10.10.17_59992: EOF
2018.08.24 13:20:24 4: WEB_10.10.10.17_59993 GET /fhem/FileLog_logWrapper?dev=Logfile&type=text&file=fhem-2018-08.log; BUFLEN:0

[JWSK]

ich habe jetzt mal das jessie von z-wave geladen
und damit ein Firmware Update durchgeführt.

nun wird auf das image von z-wave mal fhem draufgesetzt,
mal sehen was dann passiert

[JWSK]

Habe Besuch mit einem Danalock V3,
siehe da, Koppeln geht auf Anhieb!