Keine Event-Meldungen von ZWAVE-Sensoren

Begonnen von PNinBB, 11 Juni 2018, 11:47:03

Vorheriges Thema - Nächstes Thema

laserrichi

Hallo Peter, ich sehe du hast auf deinem UZB1 die Vers:5 Rev:4
habe in meinen Thread gestern geschrieben das ich auf Vers:5 Rev:27   gegangen bin... bis jetzt läuft es bei mir auch mit den aktuellen modulen, vieleicht machst du auch einmal einen Firmware update auf den dongle.
Ich werde das nochmal gegenchecken da ich 2 UZB1 Dongle habe.

Der Watchdog is doch eigentlich nur im SoC (also stick) der sich selbst checkt und nach 1.05sec resettet wenn ich das richtig gelesen habe.
RaspberryPi 4 Bullseye,Homematic,Z-Wave,Rademacher Duofern,Signalduino,Fritz7590,ESPEasy,Tasmota,Robonect,Kameras,1-Wire,Modbus,Solar,Maranz,VU+,ulanzi tc001 mit awtrix light

PNinBB

#16
@laserrichi: Ich muss momentan mit dem Upgrade warten, da 'ZWave.me' momentan nicht vollständig auf 'Stretch' läuft; genauer: der Mongoose - Server lässt sich nicht starten. Das Problem ist bei ZWave.me bekannt, eine neue Version ist angekündigt, aber momentan ohne genauen Zeitpunkt. Deshalb schiebe ich es noch vor mir her.

Nun zum eigentlichen Problem:

1. Parameter des seriellem Interfaces:
ZitatAuch wenn es vermutlich fuer dieses Problem nicht relevant ist: ich kenne setserial nicht, aber die angezeigten Daten sind mA nicht ausreichend. Ich empfehle stty -a < /dev/AMA0
Das habe ich während der unterschiedlichen Zustände ('tot' oder 'nicht tot') geprüft mit folgendem Ergebnis.
1. FHEM läuft:
root@PNinBBServer4 27.07.2018;08:24:23 ~ 4>stty -a < /dev/ttyAMA0
speed 115200 baud; rows 0; columns 0; line = 0;
intr = ^C; quit = ^\; erase = ^?; kill = ^U; eof = ^D; eol = <undef>; eol2 = <undef>; swtch = <undef>; start = ^Q;
stop = ^S; susp = ^Z; rprnt = ^R; werase = ^W; lnext = ^V; discard = ^O; min = 0; time = 0;
-parenb -parodd -cmspar cs8 -hupcl -cstopb cread clocal -crtscts
ignbrk -brkint -ignpar -parmrk -inpck -istrip -inlcr -igncr -icrnl -ixon -ixoff -iuclc -ixany -imaxbel -iutf8
-opost -olcuc -ocrnl -onlcr -onocr -onlret -ofill -ofdel nl0 cr0 tab0 bs0 vt0 ff0
-isig -icanon -iexten -echo -echoe -echok -echonl -noflsh -xcase -tostop -echoprt -echoctl -echoke -flusho -extproc
root@PNinBBServer4 27.07.2018;08:24:40 ~ 5>

Dabei gab es keine Unterschiede zwischen 'tot' und (noch) nicht 'tot'.
2. ZWay-Server läuft:
root@PNinBBServer4 27.07.2018;10:52:48 / 48>stty -a < /dev/ttyAMA0
speed 115200 baud; rows 0; columns 0; line = 0;
intr = ^C; quit = ^\; erase = ^?; kill = ^U; eof = ^D; eol = <undef>; eol2 = <undef>; swtch = <undef>; start = ^Q; stop = ^S; susp = ^Z; rprnt = ^R; werase = ^W; lnext = ^V;
discard = ^O; min = 1; time = 0;
-parenb -parodd -cmspar cs8 -hupcl -cstopb cread clocal -crtscts
-ignbrk -brkint ignpar -parmrk -inpck -istrip -inlcr -igncr -icrnl -ixon -ixoff -iuclc -ixany -imaxbel -iutf8
-opost -olcuc -ocrnl -onlcr -onocr -onlret -ofill -ofdel nl0 cr0 tab0 bs0 vt0 ff0
-isig -icanon -iexten -echo -echoe -echok -echonl -noflsh -xcase -tostop -echoprt -echoctl -echoke -flusho -extproc
root@PNinBBServer4 27.07.2018;10:52:51 / 49>

Vergleicht man beide Ausgaben, so zeigt sich folgendes Bild.

FHEM läuft:        min = 0; ignbrk -brkint -ignpar
ZWay-Server läuft: min = 1; -ignbrk -brkint ignpar

Ich habe daraufhin mittels der 'stty'-Funktion die Werte so gesetzt, dass sie bei FHEM-Betrieb genau so wie bei ZWay-Server-Betrieb sind, aber das Problem bestand weiter; gefühlt waren die Zeiträume vor dem 'tot'-Zustand größer, aber das ist nicht stabil, da der Tot-Zustand durch ein 'planmäßiges' FHEM-Kommando ja ohnehin aufgehoben wird.

2. Timeouts:
ZitatFHEM setzt diese Timeouts in 00_ZWDongle.pm/ZWDongle_DoInit auf 100/15.

Ich habe in 00_ZWDongle.pm/ZWDongle_DoInit folgende Änderung vorgenommen und den FHEM-Server neu gestartet.
#  ZWDongle_Set($hash, $name, ("timeouts", 100, 15));  # Sec relevant
  ZWDongle_Set($hash, $name, ("timeouts", 10, 10));  # Sec relevant

Ergebnis: keine positiven Auswirkungen.

3. API - Timeouts:
ZitatWuesste gerne, was mit old und cur gemeint ist.
Wie schon erwähnt bezieht sich dies sicherlich auf die alten (old) und momentanen (cur(rent)) Werte.
Dass diese beim Starten des ZWay - Servers ausgelesen und neu gesetzt werden, verrät das Log.
[2018-07-27 10:46:25.496] [I] [zway] Job 0x06 (Set Serial API timeouts): Old timeouts are: ACK timeout 100 ms and Byte timeout 100 ms
[2018-07-27 10:46:25.496] [D] [zway] SETDATA controller.data.oldSerialAPIAckTimeout10ms = 10 (0x0000000a)
[2018-07-27 10:46:25.496] [D] [zway] SETDATA controller.data.oldSerialAPIByteTimeout10ms = 10 (0x0000000a)
[2018-07-27 10:46:25.496] [D] [zway] SETDATA controller.data.curSerialAPIAckTimeout10ms = 10 (0x0000000a)
[2018-07-27 10:46:25.496] [D] [zway] SETDATA controller.data.curSerialAPIByteTimeout10ms = 10 (0x0000000a)
[2018-07-27 10:46:25.496] [D] [zway] Job 0x06 (Set Serial API timeouts): success
[2018-07-27 10:46:25.496] [I] [zway] Removing job: Set Serial API timeouts


Da wurden also die von mir (siehe oben) vorgenommenen Änderungen ausgelesen und wieder gesetzt. Bei den gestrigen Werten waren es die Original-FHEM-Werte.
Fazit: ich konnte einiges "aussortieren", aber das 'unerwünschte' Verhalten erweist sich als sehr 'stabil' !!
Ich werde als nächstes etwas mit den 'Timeouts' experimentieren und mich auch noch mehr um 'Watchdog' kümmern.
Peter
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;

krikan

In https://www.silabs.com/documents/login/user-guides/INS12350-Serial-API-Host-Appl.-Prg.-Guide.pdf im Abschnit 7.13 gibt es kurze Hinweise zu WATCHDOG_START und WATCHDOG_STOP.

Die Funktionen liefern keine Rückgabewerte. Der Timeout in FHEM ist also "normal".

PNinBB

#18
@krikan: Danke für den Hinweis.
Ich habe etwas mit Watchdog experimentiert, aber ohne Erfolg, mir ist noch zu viel unklar an dieser Stelle.
Ich will noch der Spur nachgehen, dass es am Raspi 3 zwei, wohl z.T. unterschiedliche UART's gibt.
Aber erst einmal steht Urlaub an und vielleicht habe ich bei einem Glas italienischen Wein eine "Eingebung"!
Peter
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;

krikan

Zitat von: PNinBB am 31 Juli 2018, 19:55:24
Glas italienischen Wein eine "Eingebung"!
Schönen Urlaub und "Zum Wohl". Mir fiel gerade beim fränkischen Bier ein:
Wir hatten das Thema WATCHDOG_START und zwave.me Controller schon einmal: https://forum.fhem.de/index.php/topic,64973.msg569091.html#msg569091 ff.
Blöderweise nicht mit positivem Ausgang.  :( Vielleicht lohnt es sich aber dennoch noch einmal zu testen.
Gruß, Christian

laserrichi

Also ich hab ja jetzt eine weile auf dem UZB1  die Firmware 5.27.... und seitdem keinen einzigen Ausfall mehr.

Die Glaskugel und gegenproben sagen mir das wohl die Firmware einen Teil zu dem Problem beiträgt.

Apropo Fränkisches Bier :-) Hier in der wahren Hauptstadt der Biere mit (leider) nur noch 9 Brauereien (11 wenn man die Mälzerei eigene Brauerei und das Biermuseum rechnet) , und im Landkreis um die 70.... (80er jahre noch 90),   ihr versteht das ich da wenig Zeit für Zwave habe :-)
Die göttliche Eingebung für den Fehler bei soviel Verkostung ist mit der Überhopfung auch nicht mehr möglich :-)

Wenn der Urlauber aus dem Urlaub zurück ist und sich der Wein in Firmware 5.27 wandelt, wissen wir sicher mehr.
Wünsche schon mal schönen kühlen Urlaub :-)

Apropo UART dazu gibt es für den Raspi eine doku, habe bei mir die UARTs auch gedreht da ich auf dem GPIO einen Homematic draufstecken habe. Das hat aber normal keine Auswirkung auf USB.
RaspberryPi 4 Bullseye,Homematic,Z-Wave,Rademacher Duofern,Signalduino,Fritz7590,ESPEasy,Tasmota,Robonect,Kameras,1-Wire,Modbus,Solar,Maranz,VU+,ulanzi tc001 mit awtrix light

PNinBB

Wenn man 365 Tage Urlaub im Jahr hat, dann kann man den Sommerurlaub schon mal etwas verlängern, vor allem bei Italien als Ziel !!
Nun aber zum Ernst des FHEM-Lebens.
Ich habe nach der Rückkehr weiter gesucht, um in der Sache voran zu kommen.
Mein Verdacht lag bei der Interruptbehandlung. Dazu habe ich die Systemeinträge unter '/proc/interrupts' ausgewertet - und siehe da: Wenn der "Totzustand" eintritt, werden vom System keine Interrupts von dieser Quelle (uart) mehr registriert.
Erst ein ausgehendes FHEM-Kommando (beispielsweise 'get ZWAVE homeId') hebt diesen Zustand wieder auf. Erst danach werden wieder Signale von Sensoren angenommen und verarbeitet.
Ich habe dann einen kleinen Monitor in Perl gebaut, der den Zeitpunkt ermittelt hat, wo es keine Interrupts mehr gab (zumindest so ungefähr!).
Ein manueller Vergleich mit dem FHEM-Logfile zeigte eine Übereinstimmung der Art, dass während dieses Zeitraums ACK für einen Befehl fehlten und der Befehl erneut gesendet wurde.
Nun wollte ich diese Routine in FHEM einbinden und die entsprechenden Ergebnisse des Monitors in den FHEM-Logfile einbauen, um eine klarere Aussage zu bekommen.
Zu diesem Zeitpunkt ist mein System abgestorben: vermutlich wegen eines Fehlers im Stromversorgungsmodul ist der Raspi 3 und auch die Speicherkarte zerstört worden. Deshalb gibt es auch die Logdateien nicht mehr !
Nun habe ich mit einem neuen Raspi 3B+ das System völlig neu aufgebaut. Dies schien mir schon vorher als ein Ausweg, da ich vor Monaten das 'Jessie' zu 'Stretch' migriert habe; und dies mit allerhand "Stolperstellen" !
Nun bin ich kurz vor der Wiederinbetriebnahme von FHEM und werde sehen (und berichten).
Eine Frage noch an die "Auch-Betroffenen": welche OS-Version, welchen Kernel, welchen ZWAVE-Modul und welche Firmware-Version nutzt ihr !
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;

laserrichi

ok die Interrupts hab ich mir bisher noch nicht weiter angesehen. Wie ich in meinen anderen Thread schon schrieb, hab ich auf dem UZB1 stick jetzt Version 5.27 aufgespielt. Seitdem ist das Problem bei mir nicht mehr aufgetreten. Stretch und RASPI3 sind bei mir auf neuesten Stand.

Wie prüfst du das mit den IRQs ?  das der Counter nicht weiter hochzählt für die Cpu ?
RaspberryPi 4 Bullseye,Homematic,Z-Wave,Rademacher Duofern,Signalduino,Fritz7590,ESPEasy,Tasmota,Robonect,Kameras,1-Wire,Modbus,Solar,Maranz,VU+,ulanzi tc001 mit awtrix light

PNinBB

Der vermutlich "Hitzetod" meines Raspi 3B hat mich doch ziemlich zurückgeworfen. Den neuen werde ich nun mit kleinen Ventilatoren ausstatten; aber das ist nicht das Problem.
Ich habe also "Stretch" und "FHEM" neu aufgesetzt und dies hat so seine Zeit benötigt.
Das hier angemerkte Verhalten ist nun auch im neuen System unverändert.
Nach "einiger" Zeit - präziser kann ich es momentan nicht angeben - reagiert FHEM nicht mehr auf Signale von den Sensoren. Erst wenn ein FHEM-Befehl "raus" geht, reagiert das System wieder auf eingehende SIgnale von den Sensoren. Momentan behelfe ich mich nach jeder Minute mit  'get ZWAVE homeId'; aber befriedigend ist dies nicht.

@laserrichi:
Ich benutze das Systemkommando:
cat /proc/interrupts | grep uart
und es liefert:
87:     477100          0          0          0  ARMCTRL-level  89 Edge      uart-pl011
Aber das ist ja nur bedingt aussagekräftig, da ja durchaus und völlig normal eine gewisse Zeit keine Interrupts von diesem Interface kommen können.

Ich habe in der Zwischenzeit auch viel über Interruptbehandlung in Debain gelesen, aber keinen Anhaltspunkt gefunden.

@rudolfkoenig:
Wo und Wie hängt sich denn der ZWave-Modul in die Interruptbehandlung von Debian ein ?
Bemerkenswert ist auch das folgende Verhalten: wenn während des "Todzustandes" ein Sensorsignal ausgelöst wird und eben bei FHEM nicht ankommt, dann kommt es entsprechend verzögert, wenn FHEM wieder reagiert; es liegt also auf der RaZberry-Seite irgendwo auf einem Stack.
Und noch eine Beobachtung: im zeitlichen Umfeld des Beginns des "Todzustandes" gibt es im FHEM-Log einige Einträge mit fehlenden ACK. Was passiert eigentlich, wenn ein Sensorsignal in solche eine Wiederholungs- bzw. Wartephase fällt ?

Auf jeden Fall ein Danke an "Ideenspender".
Peter
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;

laserrichi

Hallo Peter,

sicher das es uart ist ?  Den der UZB1  benutzt soweit ich weis eigentlich dwc_otg

habe gerade mal ausprobiert ob hier bei zwave signalen etwas hochzählt, da hat bei mir der uart nichts gezählt wenn ein sensor was gesendet hat sondern der irq für dwc_otg ging höher.

Bei mir sind die Probleme momentan nicht mehr vorhanden. Hat wohl doch eventuell etwas mit dem Update auf dem Stick zu tun.
RaspberryPi 4 Bullseye,Homematic,Z-Wave,Rademacher Duofern,Signalduino,Fritz7590,ESPEasy,Tasmota,Robonect,Kameras,1-Wire,Modbus,Solar,Maranz,VU+,ulanzi tc001 mit awtrix light

tomspatz

@PNinBB

Hast du denn auch etwas was automatisch von fhem gesteuert wird.
Nur so als Idee, wenn fhem auf ZWave immer nur lauscht (Sensoren etc.) und NIE selbst etwas ins ZWave Netz schickt. ???

LG
Tom

rudolfkoenig

ZitatWo und Wie hängt sich denn der ZWave-Modul in die Interruptbehandlung von Debian ein ?
Indirekt ueber select, diese Funktion blockiert solange, bis bei einem der registrierten Filedescriptoren was passiert, oder das spezifizierte Timeout eingetreten ist. Ueber Filedescriptoren werden nicht nur Dateien, sondern auch USB-Geraete oder Netzwerkverbindungen angesprochen. Wie vom Interrupt zum select-Benachrichtigung kommt, das is Sache des Kernels, und erstens ist das kompliziert, zweitens bin ich nicht auf dem aktuellen Stand.

Unter Windows funktioniert select nur fuer Geraete, die per TCP angebunden sind (da Microsoft den TCP-Kode vom BSD gekl^H^H^H^Huebernommen hat), USB-Geraete werden in FHEM@Windows gepollt.

PNinBB

#27
Bin erst seit einigen Tagen wiederer auf der "Baustelle".
Ich habe nun auch den ZWay-Server auf meinem neu aufgesetzten System zum Laufen gebracht. Alle meine Geräte sind sicht- und nutzbar. Ich habe - auf die Schnelle - ein paar Aktionen mit "Wenn --> dann" eingerichtet, vor allem auch mit meinen Sensoren.
Alles funktioniert wie es sein soll, insbesondere gibt es keine "Verklemmungen" bzw. "Totzustände" !! So lief es etwa 3 Tage.
Wenn ich wieder auf FHEM gehe, tritt der Effekt wieder auf.
Meine Verdacht ist unverändert: es scheint aufzutreten, wenn eine Meldung von einem der Binärsensoren während eines laufenden Sendevorganges von ZWAVE (evenuell mit Wiederholungen!) übermittelt wird. Da natürlich eine solche Meldung ein asynchrones Ereignis ist, kann es solche Verklemmungen hervorrufen.
Ich bin erst einmal wieder 14 Tage unterwegs. Dann will ich mir mal die serielle Schnittstelle vorknüpfen und vor allem versuchen zu verstehen, wie die Details der Sende- und Empfangsvorgänge sind.

@Rudi:
Gibt es eventuell einige Hilfsprogramme, die man dafür nutzen könnte.
ZitatIndirekt ueber select, diese Funktion blockiert solange, bis bei einem der registrierten Filedescriptoren was passiert, oder das spezifizierte Timeout eingetreten ist.
Und was passiert nach diesem Timeout ??

@laserrichi:
Ich benutze nicht den UZB1, sondern die ZWAVE 2 Steckkarte, welche uart nutzt. Aber auf dem Raspi3B sind zwei eingebaut. Ich habe vieles dazu gelesen und keinen Anhaltspunkt gefunden.Die neueste Firmware 2.3.7 ist installiert; alles andere ist auch aktuell. Es ist zwar in den Foren die Rede, dass es wohl noch Probleme mit ZWay unter Stretch gibt, aber eine aktualisierte Software gibt es bisher nicht.

@tomspatz:
Natürlich habe ich mehrere derartiger Befehle; die sind es ja, die den Blockadezustand wieder aufheben.

Es geht zwar momentan einigermassen zufriedenstellend mit den minütliche HomeID-Abfragen, aber eine akzeptable Lösung ist das nicht.
Für gute Ideen bin ich natürlich immer offen !!
Schönen Sonntag !
Peter


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

ZitatGibt es eventuell einige Hilfsprogramme, die man dafür nutzen könnte.
- mit "strace -f -o /tmp/fhem.strace.out -p <FHEM-PID>" kann man pruefen, ob das Betriebsystem die Daten an FHEM weiterreicht. Damit ich die Ausgabe interpretieren kann, brauche ich den aktuellen FileDescriptor, das sieht man mit "list TYPE=ZWDongle FD"
- mit einem CUL + ZWCUL + monitor mode kann man pruefen, ob die Daten per Funk geliefert werden

ZitatUnd was passiert nach diesem Timeout ??
Die per InternalTimer spezifizierten Funktionen werden abgearbeitet (at/DOIF/cleanups/etc).


ZitatEs geht zwar momentan einigermassen zufriedenstellend mit den minütliche HomeID-Abfragen, aber eine akzeptable Lösung ist das nicht.
Vielen Dank an deinem Ausdauer, ich wuerde gerne das Problem auch lieber sauber loesen.

PNinBB

#29
Ich bin zurück auf der "Baustelle" !
Was ist inzwischen geschehen bzw. habe ich unternommen.

1. Es gibt seit 10.10.2018 ein neues Firmware-Update für den RaZberry: Version 2.3.8. Die Installation verlief ohne Probleme.
Im ChangeLog steht u.a.:
Zitat
10.10.2018 v2.3.8

== Z-Way ==

Z-Wave (libzway) changes:
- Fix interview problem on A/MCA with 0 groups (for multichannel devices like Danfoss HC5/10)
- Showing secure payload on send in the log for easier debugging
- Firmware upgrade uses by default reported fragment size and only then from Defaults.xml (solves OTA problem)
- Added libraries for Raspbian Stretch
- Fixed deadlock in HomeKit app
- Fixed socket not being closed on peer disconnect
- Fixed lastSend not updated
- Added manualUrl to ZDDX files
- Added AssignPriorityReturnRoute and AssignPrioritySUCReturnRoute
- Added SwitchBinary unknown state support
- Fixed possible segfault
- Addeding more cases for DT.3.2
- Fixed RT.18.1 from Portable Controller to Portable Slave
- Few potential crash problems fixed

== SmartHomeUI v1.9.0 ==
. . .

Nach Stoppen von FHEM und Starten von Z-Way lief alles wie erwartet; auch der "Totzustand" trat wieder auf.

Um der Sache auf die Spur zu kommen, habe ich mir ein Szenario überlegt und erst einmal im fehlerfreien System mit dem ZWay-Server probiert.
Beteiligt sind drei Geräte:

  • zwei Binärsensoren (Fibaro FGBS-001, Nr.: 35D (Name: KU_GZ_FS), Nr.: 40D (Name: AZ_GZ_FV)) mit einem angeschlossenen Magnetkontakt,
  • ein Multimotionsensor (Fibaro FGMS-001, Nr,. 37D (Name: FL_DS_SM))
  • zwei Schalter (Philio PAN06-1A, Nr.: 11D (Name: FL_SK_D1)), Nr.: 12D (Name: AZ_SK_D1))
Ablauf: wird der Zustand des Kontaktes geändert. d. h. Entfernen des Magnetkontaktes, dann wird eine am Schalter angeschlossenen Lampe eingeschaltet und umgekehrt;
bzw. mit dem Motionsensor: bei erkannter Bewegung wird das Licht eingeschaltet und nach der fixierten Zeitspanne wieder ausgeschaltet.
Das ist mit einer einfachen "Wenn --> Dann" - Anwendung programmiert.
Dieses und andere ähnliche Szenarien (mit anderen Geräten) funktionieren fehlerfrei und regelmäßig.
Nachfolgend ein Auszug für die Fälle aus dem sehr ausführlichen "z-way-server.log".

Fall 1: mit dem ersten Sensor (Gerät 40D):

[2018-10-24 17:05:00.558] [D] [zway] RECEIVED: ( 01 11 00 04 00 28 0B 60 0D 01 01 9C 02 28 00 FF 00 00 ED )
[2018-10-24 17:05:00.563] [D] [zway] SENT ACK
[2018-10-24 17:05:00.564] [D] [zway] SETDATA devices.40.data.lastReceived = 0 (0x00000000)
[2018-10-24 17:05:00.564] [D] [zway] SETDATA devices.40.instances.1.commandClasses.156.data.0.srcId = 40 (0x00000028)
[2018-10-24 17:05:00.564] [D] [zway] SETDATA devices.40.instances.1.commandClasses.156.data.0.sensorState = 255 (0x000000ff)
[2018-10-24 17:05:00.564] [D] [zway] SETDATA devices.40.instances.1.commandClasses.156.data.0.sensorTime = 0 (0x00000000)
[2018-10-24 17:05:00.564] [D] [zway] SETDATA devices.40.instances.1.commandClasses.156.data.0 = Empty
[2018-10-24 17:05:00.608] [D] [zway] RECEIVED: ( 01 0D 00 04 00 28 07 60 0D 01 01 20 01 FF 6A )
[2018-10-24 17:05:00.608] [D] [zway] SENT ACK
[2018-10-24 17:05:00.609] [D] [zway] SETDATA devices.40.data.lastReceived = 0 (0x00000000)
[2018-10-24 17:05:00.609] [D] [zway] SETDATA devices.1.instances.1.commandClasses.32.data.srcNodeId = 40 (0x00000028)
[2018-10-24 17:05:00.609] [D] [zway] SETDATA devices.1.instances.1.commandClasses.32.data.srcInstanceId = 1 (0x00000001)
[2018-10-24 17:05:00.609] [D] [zway] SETDATA devices.1.instances.1.commandClasses.32.data.level = 255 (0x000000ff)
[2018-10-24 17:05:00.609] [D] [zway] SETDATA devices.40.instances.1.commandClasses.48.data.1.level = True
[2018-10-24 17:05:00.609] [D] [zway] SETDATA devices.40.instances.1.commandClasses.48.data.1 = Empty
[2018-10-24 17:05:00.621] [I] [core] Notification: device-info (device-OnOff): {"dev":"AZ_GZ_FV","l":"on","location":2}
[2018-10-24 17:05:00.626] [I] [core] ---  ZWayVDev_zway_12-1-37 performCommand processing: {"0":"on"}
[2018-10-24 17:05:00.626] [I] [zway] Adding job: SwitchBinary Set
[2018-10-24 17:05:00.626] [I] [zway] Adding job: SwitchBinary Get
[2018-10-24 17:05:00.629] [D] [zway] SENDING (cb 0x66): ( 01 0E 00 13 0C 07 60 0D 00 01 25 01 FF 25 66 1D )
[2018-10-24 17:05:00.631] [D] [zway] RECEIVED ACK
[2018-10-24 17:05:00.637] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 )
[2018-10-24 17:05:00.637] [D] [zway] SENT ACK
[2018-10-24 17:05:00.637] [D] [zway] Delivered to Z-Wave stack
[2018-10-24 17:05:00.678] [I] [core] [BaseModule-13] Set lastLevel to on for ZWayVDev_zway_40-1-48-1 (was off)
[2018-10-24 17:05:00.724] [D] [zway] RECEIVED: ( 01 07 00 13 66 00 00 09 84 )
[2018-10-24 17:05:00.724] [D] [zway] SENT ACK
[2018-10-24 17:05:00.724] [I] [zway] Job 0x13 (SwitchBinary Set): Delivered
[2018-10-24 17:05:00.725] [D] [zway] SendData Response with callback 0x66 received: received by recipient
[2018-10-24 17:05:00.725] [D] [zway] SETDATA devices.12.data.lastSendInternal = **********
[2018-10-24 17:05:00.725] [D] [zway] SETDATA devices.12.data.lastSend = 353401 (0x00056479)
[2018-10-24 17:05:00.725] [D] [zway] Job 0x13 (SwitchBinary Set): success
[2018-10-24 17:05:00.725] [I] [zway] Removing job: SwitchBinary Set
[2018-10-24 17:05:00.764] [D] [zway] SENDING (cb 0x67): ( 01 0D 00 13 0C 06 60 0D 00 01 25 02 25 67 E2 )
[2018-10-24 17:05:00.767] [D] [zway] RECEIVED ACK
[2018-10-24 17:05:00.771] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 )
[2018-10-24 17:05:00.771] [D] [zway] SENT ACK
[2018-10-24 17:05:00.771] [D] [zway] Delivered to Z-Wave stack
[2018-10-24 17:05:00.795] [D] [zway] RECEIVED: ( 01 07 00 13 67 00 00 02 8E )
[2018-10-24 17:05:00.795] [D] [zway] SENT ACK
[2018-10-24 17:05:00.795] [I] [zway] Job 0x13 (SwitchBinary Get): Delivered
[2018-10-24 17:05:00.795] [D] [zway] SendData Response with callback 0x67 received: received by recipient
[2018-10-24 17:05:00.795] [D] [zway] SETDATA devices.12.data.lastSendInternal = **********
[2018-10-24 17:05:00.795] [D] [zway] SETDATA devices.12.data.lastSend = 353408 (0x00056480)
[2018-10-24 17:05:00.795] [D] [zway] Job 0x13 (SwitchBinary Get): success
[2018-10-24 17:05:00.796] [I] [zway] Waiting for job reply: SwitchBinary Get
[2018-10-24 17:05:00.805] [D] [zway] RECEIVED: ( 01 0D 00 04 00 0C 07 60 0D 01 00 25 03 FF 48 )
[2018-10-24 17:05:00.805] [D] [zway] SENT ACK
[2018-10-24 17:05:00.805] [D] [zway] SETDATA devices.12.data.lastReceived = 0 (0x00000000)
[2018-10-24 17:05:00.806] [D] [zway] Received reply on job (SwitchBinary Get)
[2018-10-24 17:05:00.806] [D] [zway] SETDATA devices.12.instances.1.commandClasses.37.data.level = True
[2018-10-24 17:05:00.823] [I] [core] Notification: device-info (device-OnOff): {"dev":"AZ_SK_D1","l":"on","location":2}
[2018-10-24 17:05:00.868] [I] [core] [BaseModule-13] Set lastLevel to on for ZWayVDev_zway_12-1-37 (was off)
[2018-10-24 17:05:02.677] [D] [zway] RECEIVED: ( 01 11 00 04 00 28 0B 60 0D 01 01 9C 02 28 00 00 00 00 12 )
[2018-10-24 17:05:02.677] [D] [zway] SENT ACK
[2018-10-24 17:05:02.677] [D] [zway] SETDATA devices.40.data.lastReceived = 0 (0x00000000)
[2018-10-24 17:05:02.677] [D] [zway] SETDATA devices.40.instances.1.commandClasses.156.data.0.srcId = 40 (0x00000028)
[2018-10-24 17:05:02.677] [D] [zway] SETDATA devices.40.instances.1.commandClasses.156.data.0.sensorState = 0 (0x00000000)
[2018-10-24 17:05:02.677] [D] [zway] SETDATA devices.40.instances.1.commandClasses.156.data.0.sensorTime = 0 (0x00000000)
[2018-10-24 17:05:02.677] [D] [zway] SETDATA devices.40.instances.1.commandClasses.156.data.0 = Empty
[2018-10-24 17:05:02.728] [D] [zway] RECEIVED: ( 01 0D 00 04 00 28 07 60 0D 01 01 20 01 00 95 )
[2018-10-24 17:05:02.728] [D] [zway] SENT ACK
[2018-10-24 17:05:02.728] [D] [zway] SETDATA devices.40.data.lastReceived = 0 (0x00000000)
[2018-10-24 17:05:02.729] [D] [zway] SETDATA devices.1.instances.1.commandClasses.32.data.srcNodeId = 40 (0x00000028)
[2018-10-24 17:05:02.729] [D] [zway] SETDATA devices.1.instances.1.commandClasses.32.data.srcInstanceId = 1 (0x00000001)
[2018-10-24 17:05:02.729] [D] [zway] SETDATA devices.1.instances.1.commandClasses.32.data.level = 0 (0x00000000)
[2018-10-24 17:05:02.729] [D] [zway] SETDATA devices.40.instances.1.commandClasses.48.data.1.level = False
[2018-10-24 17:05:02.729] [D] [zway] SETDATA devices.40.instances.1.commandClasses.48.data.1 = Empty
[2018-10-24 17:05:02.737] [I] [core] Notification: device-info (device-OnOff): {"dev":"AZ_GZ_FV","l":"off","location":2}
[2018-10-24 17:05:02.739] [D] [zway] RECEIVED: ( 01 09 00 04 00 0C 03 25 03 FF 24 )
[2018-10-24 17:05:02.739] [D] [zway] SENT ACK
[2018-10-24 17:05:02.739] [D] [zway] SETDATA devices.12.data.lastReceived = 0 (0x00000000)
[2018-10-24 17:05:02.739] [D] [zway] SETDATA devices.12.instances.0.commandClasses.37.data.level = True
[2018-10-24 17:05:02.739] [I] [core] ---  ZWayVDev_zway_12-1-37 performCommand processing: {"0":"off"}
[2018-10-24 17:05:02.740] [I] [zway] Adding job: SwitchBinary Set
[2018-10-24 17:05:02.740] [I] [zway] Adding job: SwitchBinary Get
[2018-10-24 17:05:02.758] [D] [zway] SENDING (cb 0x68): ( 01 0E 00 13 0C 07 60 0D 00 01 25 01 00 25 68 EC )
[2018-10-24 17:05:02.761] [D] [zway] RECEIVED ACK
[2018-10-24 17:05:02.766] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 )
[2018-10-24 17:05:02.767] [D] [zway] SENT ACK
[2018-10-24 17:05:02.767] [D] [zway] Delivered to Z-Wave stack
[2018-10-24 17:05:02.786] [I] [core] [BaseModule-13] Set lastLevel to off for ZWayVDev_zway_40-1-48-1 (was on)
[2018-10-24 17:05:02.790] [D] [zway] RECEIVED: ( 01 09 00 04 00 0C 03 25 03 FF 24 )
[2018-10-24 17:05:02.790] [D] [zway] SENT ACK
[2018-10-24 17:05:02.791] [D] [zway] SETDATA devices.12.data.lastReceived = 0 (0x00000000)
[2018-10-24 17:05:02.791] [D] [zway] SETDATA devices.12.instances.0.commandClasses.37.data.level = True
[2018-10-24 17:05:02.822] [D] [zway] RECEIVED: ( 01 09 00 04 00 0C 03 25 03 FF 24 )
[2018-10-24 17:05:02.822] [D] [zway] SENT ACK
[2018-10-24 17:05:02.822] [D] [zway] SETDATA devices.12.data.lastReceived = 0 (0x00000000)
[2018-10-24 17:05:02.822] [D] [zway] SETDATA devices.12.instances.0.commandClasses.37.data.level = True
[2018-10-24 17:05:02.902] [D] [zway] RECEIVED: ( 01 09 00 04 00 0C 03 25 03 FF 24 )
[2018-10-24 17:05:02.902] [D] [zway] SENT ACK
[2018-10-24 17:05:02.903] [D] [zway] SETDATA devices.12.data.lastReceived = 0 (0x00000000)
[2018-10-24 17:05:02.903] [D] [zway] SETDATA devices.12.instances.0.commandClasses.37.data.level = True
[2018-10-24 17:05:02.943] [D] [zway] RECEIVED: ( 01 07 00 13 68 00 00 11 92 )
[2018-10-24 17:05:02.943] [D] [zway] SENT ACK
[2018-10-24 17:05:02.943] [I] [zway] Job 0x13 (SwitchBinary Set): Delivered
[2018-10-24 17:05:02.944] [D] [zway] SendData Response with callback 0x68 received: received by recipient
[2018-10-24 17:05:02.944] [D] [zway] SETDATA devices.12.data.lastSendInternal = **********
[2018-10-24 17:05:02.944] [D] [zway] SETDATA devices.12.data.lastSend = 353618 (0x00056552)
[2018-10-24 17:05:02.944] [D] [zway] Job 0x13 (SwitchBinary Set): success
[2018-10-24 17:05:02.944] [I] [zway] Removing job: SwitchBinary Set
[2018-10-24 17:05:02.984] [D] [zway] SENDING (cb 0x69): ( 01 0D 00 13 0C 06 60 0D 00 01 25 02 25 69 EC )
[2018-10-24 17:05:02.986] [D] [zway] RECEIVED ACK
[2018-10-24 17:05:02.990] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 )
[2018-10-24 17:05:02.991] [D] [zway] SENT ACK
[2018-10-24 17:05:02.991] [D] [zway] Delivered to Z-Wave stack
[2018-10-24 17:05:03.015] [D] [zway] RECEIVED: ( 01 07 00 13 69 00 00 02 80 )
[2018-10-24 17:05:03.015] [D] [zway] SENT ACK
[2018-10-24 17:05:03.015] [I] [zway] Job 0x13 (SwitchBinary Get): Delivered
[2018-10-24 17:05:03.015] [D] [zway] SendData Response with callback 0x69 received: received by recipient
[2018-10-24 17:05:03.015] [D] [zway] SETDATA devices.12.data.lastSendInternal = **********
[2018-10-24 17:05:03.015] [D] [zway] SETDATA devices.12.data.lastSend = 353625 (0x00056559)
[2018-10-24 17:05:03.015] [D] [zway] Job 0x13 (SwitchBinary Get): success
[2018-10-24 17:05:03.015] [I] [zway] Waiting for job reply: SwitchBinary Get
[2018-10-24 17:05:03.025] [D] [zway] RECEIVED: ( 01 0D 00 04 00 0C 07 60 0D 01 00 25 03 00 B7 )
[2018-10-24 17:05:03.025] [D] [zway] SENT ACK
[2018-10-24 17:05:03.026] [D] [zway] SETDATA devices.12.data.lastReceived = 0 (0x00000000)
[2018-10-24 17:05:03.026] [D] [zway] Received reply on job (SwitchBinary Get)
[2018-10-24 17:05:03.026] [D] [zway] SETDATA devices.12.instances.1.commandClasses.37.data.level = False
[2018-10-24 17:05:03.048] [I] [core] Notification: device-info (device-OnOff): {"dev":"AZ_SK_D1","l":"off","location":2}
[2018-10-24 17:05:03.077] [D] [zway] Job 0x13: deleted from queue
[2018-10-24 17:05:03.092] [I] [core] [BaseModule-13] Set lastLevel to off for ZWayVDev_zway_12-1-37 (was on)
[2018-10-24 17:05:04.949] [D] [zway] RECEIVED: ( 01 09 00 04 00 0C 03 25 03 00 DB )
[2018-10-24 17:05:04.950] [D] [zway] SENT ACK
[2018-10-24 17:05:04.950] [D] [zway] SETDATA devices.12.data.lastReceived = 0 (0x00000000)
[2018-10-24 17:05:04.950] [D] [zway] SETDATA devices.12.instances.0.commandClasses.37.data.level = False
[2018-10-24 17:05:04.980] [D] [zway] RECEIVED: ( 01 09 00 04 00 0C 03 25 03 00 DB )
[2018-10-24 17:05:04.980] [D] [zway] SENT ACK
[2018-10-24 17:05:04.981] [D] [zway] SETDATA devices.12.data.lastReceived = 0 (0x00000000)
[2018-10-24 17:05:04.981] [D] [zway] SETDATA devices.12.instances.0.commandClasses.37.data.level = False
[2018-10-24 17:05:05.184] [D] [zway] Job 0x13: deleted from queue


Fall 2: mit dem zweiten Sensor (Gerät 35D):

[2018-10-24 17:05:16.669] [D] [zway] RECEIVED: ( 01 22 00 04 00 16 1C 8F 01 06 03 80 03 3A 06 43 03 01 42 01 90 04 46 08 00 7F 02 81 05 02 46 04 02 84 07 07 )
[2018-10-24 17:05:16.669] [D] [zway] SENT ACK
[2018-10-24 17:05:16.670] [D] [zway] SETDATA devices.22.data.lastReceived = 0 (0x00000000)
[2018-10-24 17:05:16.670] [D] [zway] SETDATA devices.22.instances.0.commandClasses.128.data.history.58 = 1540393516 (0x5bd08a2c)
[2018-10-24 17:05:16.670] [D] [zway] SETDATA devices.22.instances.0.commandClasses.128.data.last = 58 (0x0000003a)
[2018-10-24 17:05:16.670] [D] [zway] SETDATA devices.22.instances.0.commandClasses.67.data.1.size = **********
[2018-10-24 17:05:16.670] [D] [zway] SETDATA devices.22.instances.0.commandClasses.67.data.1.precision = **********
[2018-10-24 17:05:16.670] [D] [zway] SETDATA devices.22.instances.0.commandClasses.67.data.1.deviceScale = 0 (0x00000000)
[2018-10-24 17:05:16.670] [D] [zway] SETDATA devices.22.instances.0.commandClasses.67.data.1.deviceScaleString = "°C"
[2018-10-24 17:05:16.670] [D] [zway] SETDATA devices.22.instances.0.commandClasses.67.data.1.val = 4.000000
[2018-10-24 17:05:16.670] [D] [zway] SETDATA devices.22.instances.0.commandClasses.67.data.1.setVal = 4.000000
[2018-10-24 17:05:16.670] [D] [zway] SETDATA devices.22.instances.0.commandClasses.67.data.1 = Empty
[2018-10-24 17:05:16.671] [D] [zway] SETDATA devices.22.instances.0.commandClasses.70.data.overrideType = 0 (0x00000000)
[2018-10-24 17:05:16.671] [D] [zway] SETDATA devices.22.instances.0.commandClasses.70.data.overrideState = 127 (0x0000007f)
[2018-10-24 17:05:16.671] [D] [zway] SETDATA devices.22.data.isAwake = True
[2018-10-24 17:05:16.671] [I] [zway] Adding job: Clock Report
[2018-10-24 17:05:16.671] [I] [zway] Adding job: Schedule Change Report (schedule change mechanism disabled)
[2018-10-24 17:05:16.671] [I] [zway] Node 22:0 CC Wakeup: Wakeup notification
[2018-10-24 17:05:16.671] [D] [zway] SETDATA devices.22.instances.0.commandClasses.132.data.lastWakeup = 1540393516 (0x5bd08a2c)
[2018-10-24 17:05:16.671] [D] [zway] SETDATA devices.22.data.lastSendInternal = **********
[2018-10-24 17:05:16.671] [D] [zway] SETDATA devices.22.data.lastNonceGet = 353956 (0x000566a4)
[2018-10-24 17:05:16.671] [D] [zway] Running wakeup handler for node 22
[2018-10-24 17:05:16.671] [D] [zway] SETDATA devices.22.instances.0.commandClasses.132.data.lastSleep = 1540393516 (0x5bd08a2c)
[2018-10-24 17:05:16.671] [I] [zway] Node 22:0 CC Wakeup: Send node to sleep
[2018-10-24 17:05:16.671] [I] [zway] Adding job: Wakeup Sleep
[2018-10-24 17:05:16.672] [I] [zway] Job 0x13 (Wakeup Sleep): Cancelling job: Wakeup Sleep
[2018-10-24 17:05:16.672] [I] [zway] Removing job: Wakeup Sleep
[2018-10-24 17:05:16.672] [I] [zway] Adding job: MultiCmd, Clock Report, Schedule Change Report (schedule change mechanism disabled)
[2018-10-24 17:05:16.672] [D] [zway] SETDATA devices.22.instances.0.commandClasses.132.data.lastSleep = 1540393516 (0x5bd08a2c)
[2018-10-24 17:05:16.672] [I] [zway] Node 22:0 CC Wakeup: Send node to sleep
[2018-10-24 17:05:16.672] [I] [zway] Adding job: Wakeup Sleep
[2018-10-24 17:05:16.672] [D] [zway] SENDING (cb 0x6f): ( 01 13 00 13 16 0C 8F 01 02 04 81 06 71 05 03 46 05 00 05 6F B4 )
[2018-10-24 17:05:16.680] [D] [zway] RECEIVED ACK
[2018-10-24 17:05:16.680] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 )
[2018-10-24 17:05:16.680] [D] [zway] SENT ACK
[2018-10-24 17:05:16.680] [I] [zway] Job 0x13 (MultiCmd, Clock Report, Schedule Change Report (schedule change mechanism disabled)): Response received - transfered to encapsulated jobs
[2018-10-24 17:05:16.680] [D] [zway] Delivered to Z-Wave stack
[2018-10-24 17:05:16.680] [D] [zway] Delivered to Z-Wave stack
[2018-10-24 17:05:16.680] [D] [zway] Delivered to Z-Wave stack
[2018-10-24 17:05:16.699] [D] [zway] RECEIVED: ( 01 07 00 13 6F 00 00 02 86 )
[2018-10-24 17:05:16.699] [D] [zway] SENT ACK
[2018-10-24 17:05:16.699] [I] [zway] Job 0x13 (MultiCmd, Clock Report, Schedule Change Report (schedule change mechanism disabled)): Callback received - transfered to encapsulated jobs
[2018-10-24 17:05:16.699] [I] [zway] Job 0x13 (Clock Report): Delivered
[2018-10-24 17:05:16.699] [D] [zway] SendData Response with callback 0x6f received: received by recipient
[2018-10-24 17:05:16.699] [D] [zway] Job 0x13 (Clock Report): success
[2018-10-24 17:05:16.699] [I] [zway] Removing job: Clock Report
[2018-10-24 17:05:16.699] [I] [zway] Job 0x13 (Schedule Change Report (schedule change mechanism disabled)): Delivered
[2018-10-24 17:05:16.699] [D] [zway] SendData Response with callback 0x6f received: received by recipient
[2018-10-24 17:05:16.699] [D] [zway] Job 0x13 (Schedule Change Report (schedule change mechanism disabled)): success
[2018-10-24 17:05:16.700] [I] [zway] Removing job: Schedule Change Report (schedule change mechanism disabled)
[2018-10-24 17:05:16.700] [I] [zway] Job 0x13 (MultiCmd, Clock Report, Schedule Change Report (schedule change mechanism disabled)): Delivered
[2018-10-24 17:05:16.700] [D] [zway] SendData Response with callback 0x6f received: received by recipient
[2018-10-24 17:05:16.700] [D] [zway] SETDATA devices.22.data.lastSendInternal = **********
[2018-10-24 17:05:16.700] [D] [zway] SETDATA devices.22.data.lastSend = 354959 (0x00056a8f)
[2018-10-24 17:05:16.700] [D] [zway] Job 0x13 (MultiCmd, Clock Report, Schedule Change Report (schedule change mechanism disabled)): success
[2018-10-24 17:05:16.700] [I] [zway] Removing job: MultiCmd, Clock Report, Schedule Change Report (schedule change mechanism disabled)
[2018-10-24 17:05:16.741] [D] [zway] SENDING (cb 0x70): ( 01 09 00 13 16 02 84 08 05 70 08 )
[2018-10-24 17:05:16.742] [D] [zway] RECEIVED ACK
[2018-10-24 17:05:16.746] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 )
[2018-10-24 17:05:16.746] [D] [zway] SENT ACK
[2018-10-24 17:05:16.746] [D] [zway] Delivered to Z-Wave stack
[2018-10-24 17:05:16.762] [D] [zway] RECEIVED: ( 01 07 00 13 70 00 00 02 99 )
[2018-10-24 17:05:16.763] [D] [zway] SENT ACK
[2018-10-24 17:05:16.763] [I] [zway] Job 0x13 (Wakeup Sleep): Delivered
[2018-10-24 17:05:16.763] [D] [zway] SendData Response with callback 0x70 received: received by recipient
[2018-10-24 17:05:16.763] [D] [zway] SETDATA devices.22.data.lastSendInternal = **********
[2018-10-24 17:05:16.764] [D] [zway] SETDATA devices.22.data.lastSend = 354965 (0x00056a95)
[2018-10-24 17:05:16.764] [D] [zway] Job 0x13 (Wakeup Sleep): success
[2018-10-24 17:05:16.764] [D] [zway] SETDATA devices.22.data.isAwake = False
[2018-10-24 17:05:16.764] [D] [zway] Sending the queue for node 22 into sleep
[2018-10-24 17:05:16.764] [I] [zway] Removing job: Wakeup Sleep
[2018-10-24 17:05:17.100] [I] [zway] Node 73:0 CC Security: sending Nonce Get
[2018-10-24 17:05:17.100] [D] [zway] SETDATA devices.73.instances.0.commandClasses.152.data.rNonceAckWait = **********
[2018-10-24 17:05:17.100] [I] [zway] Adding job: Nonce Get
[2018-10-24 17:05:17.100] [D] [zway] SETDATA devices.73.data.lastNonceGet = 354997 (0x00056ab5)
[2018-10-24 17:05:17.110] [D] [zway] SENDING (cb 0x71): ( 01 09 00 13 49 02 98 40 25 71 22 )
[2018-10-24 17:05:17.112] [D] [zway] RECEIVED ACK
[2018-10-24 17:05:17.116] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 )
[2018-10-24 17:05:17.116] [D] [zway] SENT ACK
[2018-10-24 17:05:17.116] [D] [zway] Delivered to Z-Wave stack
[2018-10-24 17:05:17.314] [D] [zway] RECEIVED: ( 01 07 00 13 71 00 00 13 89 )
[2018-10-24 17:05:17.314] [D] [zway] SENT ACK
[2018-10-24 17:05:17.315] [I] [zway] Job 0x13 (Nonce Get): Delivered
[2018-10-24 17:05:17.315] [D] [zway] SendData Response with callback 0x71 received: received by recipient
[2018-10-24 17:05:17.315] [D] [zway] SETDATA devices.73.data.lastSendInternal = **********
[2018-10-24 17:05:17.315] [D] [zway] SETDATA devices.73.data.lastSend = 355019 (0x00056acb)
[2018-10-24 17:05:17.315] [D] [zway] Job 0x13 (Nonce Get): success
[2018-10-24 17:05:17.315] [D] [zway] SETDATA devices.73.instances.0.commandClasses.152.data.rNonceAckWait = **********
[2018-10-24 17:05:17.315] [D] [zway] SETDATA devices.73.data.lastNonceGet = 355019 (0x00056acb)
[2018-10-24 17:05:17.315] [I] [zway] Waiting for job reply: Nonce Get
[2018-10-24 17:05:19.366] [D] [zway] Job 0x13: deleted from queue


Fall 3: mit dem Motionsensor Nr.: 37D (Name: FL_DS_SM) und dem anderen Schalter Nr.: 12D (Name: AZ_SK_D1)

[2018-10-27 10:07:20.200] [D] [zway] RECEIVED: ( 01 09 00 04 00 25 03 30 03 FF 18 )
[2018-10-27 10:07:20.200] [D] [zway] SENT ACK
[2018-10-27 10:07:20.200] [D] [zway] SETDATA devices.37.data.lastReceived = 0 (0x00000000)
[2018-10-27 10:07:20.200] [D] [zway] SETDATA devices.37.instances.0.commandClasses.48.data.1.level = True
[2018-10-27 10:07:20.200] [D] [zway] SETDATA devices.37.instances.0.commandClasses.48.data.1 = Empty
[2018-10-27 10:07:20.206] [I] [core] Notification: device-info (device-OnOff): {"dev":"FL_DS_SM","l":"on","location":1}
[2018-10-27 10:07:20.212] [I] [core] ---  ZWayVDev_zway_11-1-37 performCommand processing: {"0":"on"}
[2018-10-27 10:07:20.212] [I] [zway] Adding job: SwitchBinary Set
[2018-10-27 10:07:20.212] [I] [zway] Adding job: SwitchBinary Get
[2018-10-27 10:07:20.221] [D] [zway] RECEIVED: ( 01 09 00 04 00 25 03 20 01 FF 0A )
[2018-10-27 10:07:20.221] [D] [zway] SENT ACK
[2018-10-27 10:07:20.221] [D] [zway] SETDATA devices.37.data.lastReceived = 0 (0x00000000)
[2018-10-27 10:07:20.221] [D] [zway] SETDATA devices.1.instances.0.commandClasses.32.data.srcNodeId = 37 (0x00000025)
[2018-10-27 10:07:20.221] [D] [zway] SETDATA devices.1.instances.0.commandClasses.32.data.srcInstanceId = 0 (0x00000000)
[2018-10-27 10:07:20.221] [D] [zway] SETDATA devices.1.instances.0.commandClasses.32.data.level = 255 (0x000000ff)
[2018-10-27 10:07:20.221] [D] [zway] SETDATA devices.37.instances.0.commandClasses.48.data.1.level = True
[2018-10-27 10:07:20.222] [D] [zway] SETDATA devices.37.instances.0.commandClasses.48.data.1 = Empty
[2018-10-27 10:07:20.222] [D] [zway] SENDING (cb 0x09): ( 01 0E 00 13 0B 07 60 0D 00 01 25 01 FF 25 09 75 )
[2018-10-27 10:07:20.231] [D] [zway] RECEIVED ACK
[2018-10-27 10:07:20.232] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 )
[2018-10-27 10:07:20.232] [D] [zway] SENT ACK
[2018-10-27 10:07:20.232] [D] [zway] Delivered to Z-Wave stack
[2018-10-27 10:07:20.241] [D] [zway] RECEIVED: ( 01 0D 00 04 00 25 07 60 0D 01 01 20 01 FF 67 )
[2018-10-27 10:07:20.242] [D] [zway] SENT ACK
[2018-10-27 10:07:20.242] [D] [zway] SETDATA devices.37.data.lastReceived = 0 (0x00000000)
[2018-10-27 10:07:20.242] [D] [zway] SETDATA devices.1.instances.1.commandClasses.32.data.srcNodeId = 37 (0x00000025)
[2018-10-27 10:07:20.242] [D] [zway] SETDATA devices.1.instances.1.commandClasses.32.data.srcInstanceId = 1 (0x00000001)
[2018-10-27 10:07:20.242] [D] [zway] SETDATA devices.1.instances.1.commandClasses.32.data.level = 255 (0x000000ff)
[2018-10-27 10:07:20.261] [I] [core] [BaseModule-13] Set lastLevel to on for ZWayVDev_zway_37-0-48-1 (was off)
[2018-10-27 10:07:20.261] [D] [zway] RECEIVED: ( 01 07 00 13 09 00 00 03 E1 )
[2018-10-27 10:07:20.261] [D] [zway] SENT ACK
[2018-10-27 10:07:20.262] [I] [zway] Job 0x13 (SwitchBinary Set): Delivered
[2018-10-27 10:07:20.262] [D] [zway] SendData Response with callback 0x09 received: received by recipient
[2018-10-27 10:07:20.262] [D] [zway] SETDATA devices.11.data.lastSendInternal = **********
[2018-10-27 10:07:20.262] [D] [zway] SETDATA devices.11.data.lastSend = 411231 (0x0006465f)
[2018-10-27 10:07:20.262] [D] [zway] Job 0x13 (SwitchBinary Set): success
[2018-10-27 10:07:20.262] [I] [zway] Removing job: SwitchBinary Set
[2018-10-27 10:07:20.303] [D] [zway] SENDING (cb 0x0a): ( 01 0D 00 13 0B 06 60 0D 00 01 25 02 25 0A 88 )
[2018-10-27 10:07:20.305] [D] [zway] RECEIVED ACK
[2018-10-27 10:07:20.309] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 )
[2018-10-27 10:07:20.309] [D] [zway] SENT ACK
[2018-10-27 10:07:20.310] [D] [zway] Delivered to Z-Wave stack
[2018-10-27 10:07:20.310] [I] [core] ---  ZWayVDev_zway_11-1-37 performCommand processing: {"0":"on"}
[2018-10-27 10:07:20.310] [I] [zway] Adding job: SwitchBinary Set
[2018-10-27 10:07:20.310] [I] [zway] Adding job: SwitchBinary Get
[2018-10-27 10:07:20.334] [D] [zway] RECEIVED: ( 01 07 00 13 0A 00 00 02 E3 )
[2018-10-27 10:07:20.334] [D] [zway] SENT ACK
[2018-10-27 10:07:20.334] [I] [zway] Job 0x13 (SwitchBinary Get): Delivered
[2018-10-27 10:07:20.334] [D] [zway] SendData Response with callback 0x0a received: received by recipient
[2018-10-27 10:07:20.334] [D] [zway] SETDATA devices.11.data.lastSendInternal = **********
[2018-10-27 10:07:20.334] [D] [zway] SETDATA devices.11.data.lastSend = 411238 (0x00064666)
[2018-10-27 10:07:20.335] [D] [zway] Job 0x13 (SwitchBinary Get): success
[2018-10-27 10:07:20.335] [I] [zway] Waiting for job reply: SwitchBinary Get
[2018-10-27 10:07:20.344] [D] [zway] RECEIVED: ( 01 0D 00 04 00 0B 07 60 0D 01 00 25 03 FF 4F )
[2018-10-27 10:07:20.345] [D] [zway] SENT ACK
[2018-10-27 10:07:20.345] [D] [zway] SETDATA devices.11.data.lastReceived = 0 (0x00000000)
[2018-10-27 10:07:20.345] [D] [zway] Received reply on job (SwitchBinary Get)
[2018-10-27 10:07:20.345] [D] [zway] SETDATA devices.11.instances.1.commandClasses.37.data.level = True
[2018-10-27 10:07:20.375] [D] [zway] SENDING (cb 0x0b): ( 01 0E 00 13 0B 07 60 0D 00 01 25 01 FF 25 0B 77 )
[2018-10-27 10:07:20.377] [I] [core] Notification: device-info (device-OnOff): {"dev":"FL_SK_D1","l":"on","location":1}
[2018-10-27 10:07:20.378] [D] [zway] RECEIVED ACK
[2018-10-27 10:07:20.382] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 )
[2018-10-27 10:07:20.382] [D] [zway] SENT ACK
[2018-10-27 10:07:20.382] [D] [zway] Delivered to Z-Wave stack
[2018-10-27 10:07:20.421] [I] [core] [BaseModule-13] Set lastLevel to on for ZWayVDev_zway_11-1-37 (was off)
[2018-10-27 10:07:20.551] [D] [zway] RECEIVED: ( 01 07 00 13 0B 00 00 10 F0 )
[2018-10-27 10:07:20.552] [D] [zway] SENT ACK
[2018-10-27 10:07:20.552] [I] [zway] Job 0x13 (SwitchBinary Set): Delivered
[2018-10-27 10:07:20.552] [D] [zway] SendData Response with callback 0x0b received: received by recipient
[2018-10-27 10:07:20.552] [D] [zway] SETDATA devices.11.data.lastSendInternal = **********
[2018-10-27 10:07:20.552] [D] [zway] SETDATA devices.11.data.lastSend = 411259 (0x0006467b)
[2018-10-27 10:07:20.552] [D] [zway] Job 0x13 (SwitchBinary Set): success
[2018-10-27 10:07:20.552] [I] [zway] Removing job: SwitchBinary Set
[2018-10-27 10:07:20.592] [D] [zway] SENDING (cb 0x0c): ( 01 0D 00 13 0B 06 60 0D 00 01 25 02 25 0C 8E )
[2018-10-27 10:07:20.594] [D] [zway] RECEIVED ACK
[2018-10-27 10:07:20.599] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 )
[2018-10-27 10:07:20.599] [D] [zway] SENT ACK
[2018-10-27 10:07:20.599] [D] [zway] Delivered to Z-Wave stack
[2018-10-27 10:07:20.623] [D] [zway] RECEIVED: ( 01 07 00 13 0C 00 00 02 E5 )
[2018-10-27 10:07:20.623] [D] [zway] SENT ACK
[2018-10-27 10:07:20.624] [I] [zway] Job 0x13 (SwitchBinary Get): Delivered
[2018-10-27 10:07:20.624] [D] [zway] SendData Response with callback 0x0c received: received by recipient
[2018-10-27 10:07:20.624] [D] [zway] SETDATA devices.11.data.lastSendInternal = **********
[2018-10-27 10:07:20.624] [D] [zway] SETDATA devices.11.data.lastSend = 411266 (0x00064682)
[2018-10-27 10:07:20.624] [D] [zway] Job 0x13 (SwitchBinary Get): success
[2018-10-27 10:07:20.624] [I] [zway] Waiting for job reply: SwitchBinary Get
[2018-10-27 10:07:20.633] [D] [zway] RECEIVED: ( 01 0D 00 04 00 0B 07 60 0D 01 00 25 03 FF 4F )
[2018-10-27 10:07:20.634] [D] [zway] SENT ACK
[2018-10-27 10:07:20.634] [D] [zway] SETDATA devices.11.data.lastReceived = 0 (0x00000000)
[2018-10-27 10:07:20.634] [D] [zway] Received reply on job (SwitchBinary Get)
[2018-10-27 10:07:20.634] [D] [zway] SETDATA devices.11.instances.1.commandClasses.37.data.level = True
[2018-10-27 10:07:22.563] [D] [zway] Job 0x13: deleted from queue
[2018-10-27 10:07:22.564] [D] [zway] RECEIVED: ( 01 09 00 04 00 0B 03 25 03 FF 23 )
[2018-10-27 10:07:22.564] [D] [zway] SENT ACK
[2018-10-27 10:07:22.564] [D] [zway] SETDATA devices.11.data.lastReceived = 0 (0x00000000)
[2018-10-27 10:07:22.564] [D] [zway] SETDATA devices.11.instances.0.commandClasses.37.data.level = True
[2018-10-27 10:07:22.594] [D] [zway] RECEIVED: ( 01 09 00 04 00 0B 03 25 03 FF 23 )
[2018-10-27 10:07:22.595] [D] [zway] SENT ACK
[2018-10-27 10:07:22.595] [D] [zway] SETDATA devices.11.data.lastReceived = 0 (0x00000000)
[2018-10-27 10:07:22.595] [D] [zway] SETDATA devices.11.instances.0.commandClasses.37.data.level = True
[2018-10-27 10:07:22.809] [D] [zway] Job 0x13: deleted from queue

Obwohl ich nicht alle Details richtig überprüfen kann, so scheint alles in Ordnung zu sein.

Dann wurde der ZWay-Server gestoppt und FHEM gestartet.
Pauschale Aussage: wenn die Blockierung auftritt, dann gibt es keinerlei Eintrag in der FHEM-Logdateie (auch bei Level 5!).
Zum Test wurde die minütliche Abfrage der 'homeID' abgeschaltet.
Im folgenden fhem.log tritt irgendwann nach 18:35:00 (? ? ? ? ?) die Blockierung auf. Es wurde sofort auf verbose=5 gestellt und 'strace' gestartet.
Nachfolgend fhem.log vor und nach diesem Zeitpunkt.

2018.10.27 18:12:06.606 2: Calendar: keyword BYSETPOS in RRULE FREQ=MONTHLY;INTERVAL=1;BYDAY=TH;BYSETPOS=2 is not supported
2018.10.27 18:12:13.419 2: Calendar: keyword BYSETPOS in RRULE FREQ=MONTHLY;INTERVAL=1;BYDAY=TH;BYSETPOS=-1 is not supported
2018.10.27 18:12:44.433 3: UWZ Unwetter: Run.1043 Done fetching data
2018.10.27 18:41:47.186 1: Systembereinigung bezüglich 'UNPARSED' gestartet
2018.10.27 18:41:47.238 1: Systembereinigung bezüglich 'UNKNOWN' gestartet
2018.10.27 18:47:49.732 1: 127.0.0.1:1883 disconnected, waiting to reappear (Mosquitto)
2018.10.27 18:47:49.963 1: 127.0.0.1:1883 reappeared (Mosquitto)
2018.10.27 18:49:13.567 5: ZWDongle_Write 001302032501FF2568 (ed70b42b)
2018.10.27 18:49:13.570 5: SW: 010a001302032501FF256871
2018.10.27 18:49:13.869 5: ZWDongle_Write 001320032501FF2569 (ed70b42b)
2018.10.27 18:49:14.252 5: ZWDongle_Write 001304032501FF256a (ed70b42b)
2018.10.27 18:49:15.191 5: ACK received, WaitForAck=>2 for 010a001302032501FF256871
2018.10.27 18:49:15.193 4: ZWDongle_Read ZWAVE: rcvd 011301 (answer ZW_SEND_DATA), sending ACK
2018.10.27 18:49:15.194 5: SW: 06
2018.10.27 18:49:15.197 5: ZWAVE: dispatch 011301
2018.10.27 18:49:15.199 4: ZWDongle_Read ZWAVE: rcvd 011301 (answer ZW_SEND_DATA), sending ACK
2018.10.27 18:49:15.200 5: SW: 06
2018.10.27 18:49:15.203 5: ZWAVE: dispatch 011301
2018.10.27 18:49:15.212 4: ZWDongle_Read ZWAVE: rcvd 0004002103600d01 (request APPLICATION_COMMAND_HANDLER), sending ACK
2018.10.27 18:49:15.213 5: SW: 06
2018.10.27 18:49:15.217 5: ZWAVE: dispatch 0004002103600d01
2018.10.27 18:49:15.219 4: CMD:APPLICATION_COMMAND_HANDLER ID:21 ARG:03600d01 CB:00
2018.10.27 18:49:15.223 4: ZWDongle_Read ZWAVE: rcvd 00040021063105030a0000 (request APPLICATION_COMMAND_HANDLER), sending ACK
2018.10.27 18:49:15.224 5: SW: 06
2018.10.27 18:49:15.228 5: ZWAVE: dispatch 00040021063105030a0000
2018.10.27 18:49:15.229 4: CMD:APPLICATION_COMMAND_HANDLER ID:21 ARG:063105030a0000 CB:00
2018.10.27 18:49:15.348 4: ZWDongle_Read ZWAVE: rcvd 0004002103300300 (request APPLICATION_COMMAND_HANDLER), sending ACK
2018.10.27 18:49:15.349 5: SW: 06
2018.10.27 18:49:15.352 5: ZWAVE: dispatch 0004002103300300
2018.10.27 18:49:15.354 4: CMD:APPLICATION_COMMAND_HANDLER ID:21 ARG:03300300 CB:00
2018.10.27 18:49:16.374 4: ZWDongle_Read ZWAVE: rcvd 00040025033003ff (request APPLICATION_COMMAND_HANDLER), sending ACK
2018.10.27 18:49:16.376 5: SW: 06
2018.10.27 18:49:16.379 5: ZWAVE: dispatch 00040025033003ff
2018.10.27 18:49:16.380 4: CMD:APPLICATION_COMMAND_HANDLER ID:25 ARG:033003ff CB:00
2018.10.27 18:49:16.543 3: ZWave set FL_SK_D1 on
2018.10.27 18:49:16.545 5: ZWDongle_Write 00130b07600d00012501FF256b (ed70b42b)
2018.10.27 18:49:16.547 4: no response from device, removing 010a001302032501FF256871 from dongle sendstack
2018.10.27 18:49:16.548 5: SW: 010a001320032501FF256952
2018.10.27 18:49:16.821 3: ZWave set FL_SK_D2 on
2018.10.27 18:49:17.128 4: ZWDongle_Read ZWAVE: rcvd 00040025032001ff (request APPLICATION_COMMAND_HANDLER), sending ACK
2018.10.27 18:49:17.129 5: SW: 06
2018.10.27 18:49:17.132 5: ZWAVE: dispatch 00040025032001ff
2018.10.27 18:49:17.134 4: CMD:APPLICATION_COMMAND_HANDLER ID:25 ARG:032001ff CB:00
2018.10.27 18:49:17.138 4: ZWDongle_Read ZWAVE: CAN received
2018.10.27 18:49:17.144 4: ZWDongle_Read ZWAVE: rcvd 0004002507600d01012001ff (request APPLICATION_COMMAND_HANDLER), sending ACK
2018.10.27 18:49:17.145 5: SW: 06
2018.10.27 18:49:17.148 5: ZWAVE: dispatch 0004002507600d01012001ff
2018.10.27 18:49:17.150 4: CMD:APPLICATION_COMMAND_HANDLER ID:25 ARG:07600d01012001ff CB:00
2018.10.27 18:49:17.243 2: ZWDongle_ProcessSendStack: no ACK, resending message 010a001320032501FF256952
2018.10.27 18:49:17.244 5: SW: 010a001320032501FF256952
2018.10.27 18:49:17.248 5: ACK received, WaitForAck=>2 for 010a001320032501FF256952
2018.10.27 18:49:17.253 4: ZWDongle_Read ZWAVE: rcvd 011301 (answer ZW_SEND_DATA), sending ACK
2018.10.27 18:49:17.254 5: SW: 06
2018.10.27 18:49:17.257 5: ZWAVE: dispatch 011301
2018.10.27 18:49:17.296 4: ZWDongle_Read ZWAVE: rcvd 001369000005 (request ZW_SEND_DATA), sending ACK
2018.10.27 18:49:17.297 5: SW: 06
2018.10.27 18:49:17.300 5: device ack reveived, removing 010a001320032501FF256952 from dongle sendstack
2018.10.27 18:49:17.302 5: ZWAVE: dispatch 001369000005
2018.10.27 18:49:17.303 4: CMD:ZW_SEND_DATA ID:00 ARG:0005 CB:69
2018.10.27 18:49:17.304 4: ZWAVE transmit OK for CB 69, target WZ_RL_FT
2018.10.27 18:49:17.309 5: SW: 010a001304032501FF256a75
2018.10.27 18:49:17.314 5: ACK received, WaitForAck=>2 for 010a001304032501FF256a75
2018.10.27 18:49:17.318 4: ZWDongle_Read ZWAVE: rcvd 011301 (answer ZW_SEND_DATA), sending ACK
2018.10.27 18:49:17.319 5: SW: 06
2018.10.27 18:49:17.322 5: ZWAVE: dispatch 011301
2018.10.27 18:49:17.369 4: ZWDongle_Read ZWAVE: rcvd 00136a000005 (request ZW_SEND_DATA), sending ACK
2018.10.27 18:49:17.370 5: SW: 06
2018.10.27 18:49:17.373 5: device ack reveived, removing 010a001304032501FF256a75 from dongle sendstack
2018.10.27 18:49:17.375 5: ZWAVE: dispatch 00136a000005
2018.10.27 18:49:17.376 4: CMD:ZW_SEND_DATA ID:00 ARG:0005 CB:6a
2018.10.27 18:49:17.377 4: ZWAVE transmit OK for CB 6a, target WZ_RL_TT
2018.10.27 18:49:17.382 5: SW: 010e00130b07600d00012501FF256b17
2018.10.27 18:49:17.386 5: ACK received, WaitForAck=>2 for 010e00130b07600d00012501FF256b17
2018.10.27 18:49:17.390 4: ZWDongle_Read ZWAVE: rcvd 011301 (answer ZW_SEND_DATA), sending ACK
2018.10.27 18:49:17.391 5: SW: 06
2018.10.27 18:49:17.394 5: ZWAVE: dispatch 011301
2018.10.27 18:49:17.405 4: ZWDongle_Read ZWAVE: rcvd 00136b000002 (request ZW_SEND_DATA), sending ACK
2018.10.27 18:49:17.406 5: SW: 06
2018.10.27 18:49:17.409 5: device ack reveived, removing 010e00130b07600d00012501FF256b17 from dongle sendstack
2018.10.27 18:49:17.411 5: ZWAVE: dispatch 00136b000002
2018.10.27 18:49:17.412 4: CMD:ZW_SEND_DATA ID:00 ARG:0002 CB:6b
2018.10.27 18:49:17.413 4: ZWAVE transmit OK for CB 6b, target FL_AB_RM
2018.10.27 18:49:17.417 5: ZWDongle_Write 00130b07600d00022501FF256c (ed70b42b)
2018.10.27 18:49:17.419 5: SW: 010e00130b07600d00022501FF256c13
2018.10.27 18:49:17.425 5: ACK received, WaitForAck=>2 for 010e00130b07600d00022501FF256c13
2018.10.27 18:49:17.429 4: ZWDongle_Read ZWAVE: rcvd 011301 (answer ZW_SEND_DATA), sending ACK
2018.10.27 18:49:17.430 5: SW: 06
2018.10.27 18:49:17.433 5: ZWAVE: dispatch 011301
2018.10.27 18:49:17.448 4: ZWDongle_Read ZWAVE: rcvd 00136c000002 (request ZW_SEND_DATA), sending ACK
2018.10.27 18:49:17.449 5: SW: 06
2018.10.27 18:49:17.452 5: device ack reveived, removing 010e00130b07600d00022501FF256c13 from dongle sendstack
2018.10.27 18:49:17.454 5: ZWAVE: dispatch 00136c000002
2018.10.27 18:49:17.455 4: CMD:ZW_SEND_DATA ID:00 ARG:0002 CB:6c
2018.10.27 18:49:17.456 4: ZWAVE transmit OK for CB 6c, target FL_AB_RM
2018.10.27 18:49:18.454 4: ZWDongle_Read ZWAVE: rcvd 000400200631050422094f (request APPLICATION_COMMAND_HANDLER), sending ACK
2018.10.27 18:49:18.455 5: SW: 06
2018.10.27 18:49:18.458 5: ZWAVE: dispatch 000400200631050422094f
2018.10.27 18:49:18.460 4: CMD:APPLICATION_COMMAND_HANDLER ID:20 ARG:0631050422094f CB:00
2018.10.27 18:49:18.929 2: ZWave: No ACK from WZ_RL_FS after 5s for sentset:1302032501FF2568
2018.10.27 18:49:18.932 4: ZWDongle_Read ZWAVE: rcvd 00040004063105042203b7 (request APPLICATION_COMMAND_HANDLER), sending ACK
2018.10.27 18:49:18.933 5: SW: 06
2018.10.27 18:49:18.937 5: ZWAVE: dispatch 00040004063105042203b7
2018.10.27 18:49:18.938 4: CMD:APPLICATION_COMMAND_HANDLER ID:04 ARG:063105042203b7 CB:00
2018.10.27 18:49:19.455 4: ZWDongle_Read ZWAVE: rcvd 0004000b032503ff (request APPLICATION_COMMAND_HANDLER), sending ACK
2018.10.27 18:49:19.456 5: SW: 06
2018.10.27 18:49:19.459 5: ZWAVE: dispatch 0004000b032503ff
2018.10.27 18:49:19.460 4: CMD:APPLICATION_COMMAND_HANDLER ID:0b ARG:032503ff CB:00
2018.10.27 18:49:19.626 4: ZWDongle_Read ZWAVE: rcvd 0004000b032503ff (request APPLICATION_COMMAND_HANDLER), sending ACK
2018.10.27 18:49:19.627 5: SW: 06
2018.10.27 18:49:19.631 5: ZWAVE: dispatch 0004000b032503ff
2018.10.27 18:49:19.632 4: CMD:APPLICATION_COMMAND_HANDLER ID:0b ARG:032503ff CB:00
2018.10.27 18:49:19.832 4: ZWDongle_Read ZWAVE: rcvd 0004000b032503ff (request APPLICATION_COMMAND_HANDLER), sending ACK
2018.10.27 18:49:19.834 5: SW: 06
2018.10.27 18:49:19.840 5: ZWAVE: dispatch 0004000b032503ff
2018.10.27 18:49:19.843 4: CMD:APPLICATION_COMMAND_HANDLER ID:0b ARG:032503ff CB:00
2018.10.27 18:49:24.154 4: ZWDongle_Read ZWAVE: rcvd 00040021033003ff (request APPLICATION_COMMAND_HANDLER), sending ACK
2018.10.27 18:49:24.155 5: SW: 06
2018.10.27 18:49:24.158 5: ZWAVE: dispatch 00040021033003ff
2018.10.27 18:49:24.160 4: CMD:APPLICATION_COMMAND_HANDLER ID:21 ARG:033003ff CB:00
2018.10.27 18:49:24.356 4: ZWDongle_Read ZWAVE: rcvd 00040021032001ff (request APPLICATION_COMMAND_HANDLER), sending ACK
2018.10.27 18:49:24.357 5: SW: 06
2018.10.27 18:49:24.360 5: ZWAVE: dispatch 00040021032001ff
2018.10.27 18:49:24.362 4: CMD:APPLICATION_COMMAND_HANDLER ID:21 ARG:032001ff CB:00
2018.10.27 18:49:24.369 4: ZWDongle_Read ZWAVE: rcvd 0004002103600d01 (request APPLICATION_COMMAND_HANDLER), sending ACK
2018.10.27 18:49:24.370 5: SW: 06
2018.10.27 18:49:24.373 5: ZWAVE: dispatch 0004002103600d01
2018.10.27 18:49:24.374 4: CMD:APPLICATION_COMMAND_HANDLER ID:21 ARG:03600d01 CB:00
2018.10.27 18:49:28.309 4: ZWDongle_Read ZWAVE: rcvd 00040025063105030a0021 (request APPLICATION_COMMAND_HANDLER), sending ACK
2018.10.27 18:49:28.310 5: SW: 06
2018.10.27 18:49:28.313 5: ZWAVE: dispatch 00040025063105030a0021
2018.10.27 18:49:28.315 4: CMD:APPLICATION_COMMAND_HANDLER ID:25 ARG:063105030a0021 CB:00
2018.10.27 18:49:28.790 4: ZWDongle_Read ZWAVE: rcvd 00040021063105030a0000 (request APPLICATION_COMMAND_HANDLER), sending ACK
2018.10.27 18:49:28.791 5: SW: 06
2018.10.27 18:49:28.794 5: ZWAVE: dispatch 00040021063105030a0000
2018.10.27 18:49:28.796 4: CMD:APPLICATION_COMMAND_HANDLER ID:21 ARG:063105030a0000 CB:00
2018.10.27 18:49:29.262 4: ZWDongle_Read ZWAVE: rcvd 0004002503300300 (request APPLICATION_COMMAND_HANDLER), sending ACK
2018.10.27 18:49:29.263 5: SW: 06
2018.10.27 18:49:29.266 5: ZWAVE: dispatch 0004002503300300
2018.10.27 18:49:29.267 4: CMD:APPLICATION_COMMAND_HANDLER ID:25 ARG:03300300 CB:00
2018.10.27 18:49:29.465 4: ZWDongle_Read ZWAVE: rcvd 0004002503200100 (request APPLICATION_COMMAND_HANDLER), sending ACK
2018.10.27 18:49:29.467 5: SW: 06
2018.10.27 18:49:29.470 5: ZWAVE: dispatch 0004002503200100
2018.10.27 18:49:29.471 4: CMD:APPLICATION_COMMAND_HANDLER ID:25 ARG:03200100 CB:00
2018.10.27 18:49:29.478 4: ZWDongle_Read ZWAVE: rcvd 0004002507600d0101200100 (request APPLICATION_COMMAND_HANDLER), sending ACK
2018.10.27 18:49:29.479 5: SW: 06
2018.10.27 18:49:29.482 5: ZWAVE: dispatch 0004002507600d0101200100
2018.10.27 18:49:29.484 4: CMD:APPLICATION_COMMAND_HANDLER ID:25 ARG:07600d0101200100 CB:00
2018.10.27 18:49:29.579 4: ZWDongle_Read ZWAVE: rcvd 00040025033003ff (request APPLICATION_COMMAND_HANDLER), sending ACK
2018.10.27 18:49:29.580 5: SW: 06
2018.10.27 18:49:29.583 5: ZWAVE: dispatch 00040025033003ff
2018.10.27 18:49:29.584 4: CMD:APPLICATION_COMMAND_HANDLER ID:25 ARG:033003ff CB:00
2018.10.27 18:49:30.390 4: ZWDongle_Read ZWAVE: rcvd 0004000206310504220007 (request APPLICATION_COMMAND_HANDLER), sending ACK
2018.10.27 18:49:30.391 5: SW: 06
2018.10.27 18:49:30.394 5: ZWAVE: dispatch 0004000206310504220007
2018.10.27 18:49:30.395 4: CMD:APPLICATION_COMMAND_HANDLER ID:02 ARG:06310504220007 CB:00
2018.10.27 18:49:31.763 4: ZWDongle_Read ZWAVE: rcvd 000400020891010f2603026300 (request APPLICATION_COMMAND_HANDLER), sending ACK
2018.10.27 18:49:31.764 5: SW: 06
2018.10.27 18:49:31.767 5: ZWAVE: dispatch 000400020891010f2603026300
2018.10.27 18:49:31.768 4: CMD:APPLICATION_COMMAND_HANDLER ID:02 ARG:0891010f2603026300 CB:00
2018.10.27 18:49:32.129 4: ZWDongle_Read ZWAVE: rcvd 0004000206310504220007 (request APPLICATION_COMMAND_HANDLER), sending ACK
2018.10.27 18:49:32.130 5: SW: 06
2018.10.27 18:49:32.134 5: ZWAVE: dispatch 0004000206310504220007
2018.10.27 18:49:32.135 4: CMD:APPLICATION_COMMAND_HANDLER ID:02 ARG:06310504220007 CB:00
2018.10.27 18:49:36.716 4: ZWDongle_Read ZWAVE: rcvd 0004002103300300 (request APPLICATION_COMMAND_HANDLER), sending ACK
2018.10.27 18:49:36.717 5: SW: 06
2018.10.27 18:49:36.721 5: ZWAVE: dispatch 0004002103300300
2018.10.27 18:49:36.722 4: CMD:APPLICATION_COMMAND_HANDLER ID:21 ARG:03300300 CB:00
2018.10.27 18:49:36.917 4: ZWDongle_Read ZWAVE: rcvd 0004002103200100 (request APPLICATION_COMMAND_HANDLER), sending ACK
2018.10.27 18:49:36.919 5: SW: 06
2018.10.27 18:49:36.922 5: ZWAVE: dispatch 0004002103200100
2018.10.27 18:49:36.923 4: CMD:APPLICATION_COMMAND_HANDLER ID:21 ARG:03200100 CB:00
2018.10.27 18:49:36.931 4: ZWDongle_Read ZWAVE: rcvd 0004002103600d01 (request APPLICATION_COMMAND_HANDLER), sending ACK
2018.10.27 18:49:36.932 5: SW: 06
2018.10.27 18:49:36.935 5: ZWAVE: dispatch 0004002103600d01
2018.10.27 18:49:36.936 4: CMD:APPLICATION_COMMAND_HANDLER ID:21 ARG:03600d01 CB:00
2018.10.27 18:49:36.943 4: ZWDongle_Read ZWAVE: rcvd 0004002006310504220561 (request APPLICATION_COMMAND_HANDLER), sending ACK
2018.10.27 18:49:36.944 5: SW: 06
2018.10.27 18:49:36.948 5: ZWAVE: dispatch 0004002006310504220561
2018.10.27 18:49:36.949 4: CMD:APPLICATION_COMMAND_HANDLER ID:20 ARG:06310504220561 CB:00
2018.10.27 18:49:38.700 4: ZWDongle_Read ZWAVE: rcvd 000400200891010f2603026300 (request APPLICATION_COMMAND_HANDLER), sending ACK
2018.10.27 18:49:38.701 5: SW: 06
2018.10.27 18:49:38.705 5: ZWAVE: dispatch 000400200891010f2603026300
2018.10.27 18:49:38.706 4: CMD:APPLICATION_COMMAND_HANDLER ID:20 ARG:0891010f2603026300 CB:00
2018.10.27 18:49:39.201 4: ZWDongle_Read ZWAVE: rcvd 0004002006310504220000 (request APPLICATION_COMMAND_HANDLER), sending ACK
2018.10.27 18:49:39.202 5: SW: 06
2018.10.27 18:49:39.205 5: ZWAVE: dispatch 0004002006310504220000
2018.10.27 18:49:39.206 4: CMD:APPLICATION_COMMAND_HANDLER ID:20 ARG:06310504220000 CB:00
2018.10.27 18:49:44.087 4: ZWDongle_Read ZWAVE: rcvd 0004002503300300 (request APPLICATION_COMMAND_HANDLER), sending ACK
2018.10.27 18:49:44.088 5: SW: 06
2018.10.27 18:49:44.091 5: ZWAVE: dispatch 0004002503300300
2018.10.27 18:49:44.092 4: CMD:APPLICATION_COMMAND_HANDLER ID:25 ARG:03300300 CB:00
2018.10.27 18:49:44.292 4: ZWDongle_Read ZWAVE: rcvd 0004002503200100 (request APPLICATION_COMMAND_HANDLER), sending ACK
2018.10.27 18:49:44.293 5: SW: 06
2018.10.27 18:49:44.297 5: ZWAVE: dispatch 0004002503200100
2018.10.27 18:49:44.298 4: CMD:APPLICATION_COMMAND_HANDLER ID:25 ARG:03200100 CB:00
2018.10.27 18:49:44.306 4: ZWDongle_Read ZWAVE: rcvd 0004002507600d0101200100 (request APPLICATION_COMMAND_HANDLER), sending ACK
2018.10.27 18:49:44.307 5: SW: 06
2018.10.27 18:49:44.310 5: ZWAVE: dispatch 0004002507600d0101200100
2018.10.27 18:49:44.312 4: CMD:APPLICATION_COMMAND_HANDLER ID:25 ARG:07600d0101200100 CB:00
2018.10.27 18:49:45.166 4: ZWDongle_Read ZWAVE: rcvd 0004000406310504220007 (request APPLICATION_COMMAND_HANDLER), sending ACK
2018.10.27 18:49:45.168 5: SW: 06
2018.10.27 18:49:45.171 5: ZWAVE: dispatch 0004000406310504220007
2018.10.27 18:49:45.172 4: CMD:APPLICATION_COMMAND_HANDLER ID:04 ARG:06310504220007 CB:00
2018.10.27 18:49:46.685 4: ZWDongle_Read ZWAVE: rcvd 000400040891010f2603026200 (request APPLICATION_COMMAND_HANDLER), sending ACK
2018.10.27 18:49:46.686 5: SW: 06
2018.10.27 18:49:46.689 5: ZWAVE: dispatch 000400040891010f2603026200
2018.10.27 18:49:46.691 4: CMD:APPLICATION_COMMAND_HANDLER ID:04 ARG:0891010f2603026200 CB:00
2018.10.27 18:49:47.078 4: ZWDongle_Read ZWAVE: rcvd 0004000406310504220007 (request APPLICATION_COMMAND_HANDLER), sending ACK
2018.10.27 18:49:47.079 5: SW: 06
2018.10.27 18:49:47.083 5: ZWAVE: dispatch 0004000406310504220007
2018.10.27 18:49:47.084 4: CMD:APPLICATION_COMMAND_HANDLER ID:04 ARG:06310504220007 CB:00
2018.10.27 18:49:49.248 4: ZWDongle_Read ZWAVE: rcvd 0004000f1c8f01060380034b06430301420190044608007f028105024604028407 (request APPLICATION_COMMAND_HANDLER), sending ACK
2018.10.27 18:49:49.249 5: SW: 06
2018.10.27 18:49:49.252 5: ZWAVE: dispatch 0004000f1c8f01060380034b06430301420190044608007f028105024604028407
2018.10.27 18:49:49.254 4: CMD:APPLICATION_COMMAND_HANDLER ID:0f ARG:1c8f01060380034b06430301420190044608007f028105024604028407 CB:00
2018.10.27 18:49:51.461 5: ZWDongle_Write 00130f028408256d (ed70b42b)
2018.10.27 18:49:51.463 5: SW: 010900130f028408256d2c
2018.10.27 18:49:51.468 5: ACK received, WaitForAck=>2 for 010900130f028408256d2c
2018.10.27 18:49:51.473 4: ZWDongle_Read ZWAVE: rcvd 011301 (answer ZW_SEND_DATA), sending ACK
2018.10.27 18:49:51.474 5: SW: 06
2018.10.27 18:49:51.477 5: ZWAVE: dispatch 011301
2018.10.27 18:49:51.486 4: ZWDongle_Read ZWAVE: rcvd 00136d000002 (request ZW_SEND_DATA), sending ACK
2018.10.27 18:49:51.487 5: SW: 06
2018.10.27 18:49:51.490 5: device ack reveived, removing 010900130f028408256d2c from dongle sendstack
2018.10.27 18:49:51.491 5: ZWAVE: dispatch 00136d000002
2018.10.27 18:49:51.492 4: CMD:ZW_SEND_DATA ID:00 ARG:0002 CB:6d
2018.10.27 18:49:51.493 4: ZWAVE transmit OK for CB 6d, target AZ_HZ_T1
2018.10.27 18:49:51.835 4: ZWDongle_Read ZWAVE: rcvd 000400150c600d03033105014400000772 (request APPLICATION_COMMAND_HANDLER), sending ACK
2018.10.27 18:49:51.836 5: SW: 06
2018.10.27 18:49:51.839 5: ZWAVE: dispatch 000400150c600d03033105014400000772
2018.10.27 18:49:51.841 4: CMD:APPLICATION_COMMAND_HANDLER ID:15 ARG:0c600d03033105014400000772 CB:00
2018.10.27 18:50:18.372 3: list WZ_RL_Alle DEF : WZ_RL_Alle               Wohnzimmer WZ_RL_FS WZ_RL_FT WZ_RL_TT

2018.10.27 18:51:16.845 3: ZWave set FL_SK_D1 off
2018.10.27 18:51:17.105 3: ZWave set FL_SK_D2 off
2018.10.27 19:11:47.181 1: Systembereinigung bezüglich 'UNPARSED' gestartet
2018.10.27 19:11:47.222 3: deletereading WZ_DS_SM UNPARSED : Deleted reading UNPARSED for device WZ_DS_SM
2018.10.27 19:11:47.233 1: Systembereinigung bezüglich 'UNKNOWN' gestartet
2018.10.27 19:12:20.879 2: Calendar: keyword BYSETPOS in RRULE FREQ=MONTHLY;INTERVAL=1;BYDAY=TH;BYSETPOS=-1 is not supported
2018.10.27 19:12:33.046 2: Calendar: keyword BYSETPOS in RRULE FREQ=MONTHLY;INTERVAL=1;BYDAY=TH;BYSETPOS=2 is not supported
2018.10.27 19:12:46.259 3: UWZ Unwetter: Run.1043 Done fetching data
2018.10.27 19:12:49.457 2: ZWAVE transmit NO_ACK for CB 7b, target BD_HZ_T1
2018.10.27 19:14:48.827 3: ZWave set FL_SK_D1 on
2018.10.27 19:14:49.086 3: ZWave set FL_SK_D2 on
2018.10.27 19:14:51.677 1: KU_GZ_FS meldet 'offen'
2018.10.27 19:14:51.679 3: ZWave set AZ_SK_D1 on
2018.10.27 19:14:52.765 2: ZWDongle_ProcessSendStack: no ACK, resending message 010e00130c07600d00012501FF257e05
2018.10.27 19:14:54.163 2: ZWDongle_ProcessSendStack: no ACK, resending message 010e00130c07600d00012501FF257e05
2018.10.27 19:14:54.177 1: KU_GZ_FS meldet 'zu'
2018.10.27 19:14:54.179 3: ZWave set AZ_SK_D1 off
2018.10.27 19:14:55.924 2: ZWDongle_ProcessSendStack: no ACK, resending message 010e00130c07600d00012501FF257e05
2018.10.27 19:14:58.096 1: KU_GZ_FS meldet 'offen'
2018.10.27 19:14:58.103 3: ZWave set AZ_SK_D1 on
2018.10.27 19:14:59.150 2: ZWDongle_ProcessSendStack: no ACK, resending message 010e00130c07600d00012501FF2580fb
2018.10.27 19:15:01.364 1: KU_GZ_FS meldet 'zu'
2018.10.27 19:15:01.366 3: ZWave set AZ_SK_D1 off
2018.10.27 19:15:02.440 2: ZWDongle_ProcessSendStack: no ACK, resending message 010e00130c07600d0001250100258105
2018.10.27 19:15:03.451 2: ZWDongle_ProcessSendStack: no ACK, resending message 010e00130c07600d0001250100258105
2018.10.27 19:16:49.926 3: ZWave set FL_SK_D1 off
2018.10.27 19:16:50.206 3: ZWave set FL_SK_D2 off

Um 18:49:13 Uhr ist das Herunterfahren der Rollladen programmiert; diese FHEM-Befehle beendete auch die Blockierung !
Dazu auch das 'strace'-Log als Datei für etwa diesen Zeitraum, wobei ich sagen muss, dass mir die Interpretation momentan noch schwer fällt !
Nachfolgend noch die Geräte-Lists, die eventuell hilfreich bzw. notwendig sind.
ZWAVE-Controller:
Internals:
   CFGFN     
   CallbackNr 0
   Clients    :ZWave:
   DEF        /dev/ttyAMA0@115200
   DeviceName /dev/ttyAMA0@115200
   FD         10
   MaxSendRetries 3
   NAME       ZWAVE
   NR         14
   PARTIAL   
   RAWMSG     00040021063105030a0000
   ReadTime   1540662644.99133
   STATE      Initialized
   SendRetries 0
   SendTime   1540662587.18003
   TYPE       ZWDongle
   WaitForAck 0
   ZWAVE_MSGCNT 4469
   ZWAVE_TIME 2018-10-27 19:50:45
   homeId     ed70b42b
   nodeIdHex  01
   nrNAck     0
   .attraggr:
   .attrminint:
   .clientArray:
     ZWave
   MatchList:
     1:ZWave    .*
   READINGS:
     2018-10-27 10:11:45   caps            Vers:5 Rev:4 ManufID:0147 ProductType:0400 ProductID:0002 SERIAL_API_GET_INIT_DATA SERIAL_API_APPL_NODE_INFORMATION APPLICATION_COMMAND_HANDLER ZW_GET_CONTROLLER_CAPABILITIES SERIAL_API_SET_TIMEOUTS SERIAL_API_GET_CAPABILITIES SERIAL_API_SOFT_RESET UNKNOWN_09 UNKNOWN_0a ZW_SET_R_F_RECEIVE_MODE ZW_SET_SLEEP_MODE ZW_SEND_NODE_INFORMATION ZW_SEND_DATA ZW_SEND_DATA_MULTI ZW_GET_VERSION ZW_SEND_DATA_ABORT ZW_R_F_POWER_LEVEL_SET ZW_SEND_DATA_META ZW_GET_RANDOM MEMORY_GET_ID MEMORY_GET_BYTE MEMORY_PUT_BYTE MEMORY_GET_BUFFER MEMORY_PUT_BUFFER FLASH_AUTO_PROG_SET ZW_NVR_GET_VALUE NVM_GET_ID NVM_EXT_READ_LONG_BUFFER NVM_EXT_WRITE_LONG_BUFFER NVM_EXT_READ_LONG_BYTE NVM_EXT_WRITE_LONG_BYTE ZW_GET_NODE_PROTOCOL_INFO ZW_SET_DEFAULT ZW_REPLICATION_COMMAND_COMPLETE ZW_REPLICATION_SEND_DATA ZW_ASSIGN_RETURN_ROUTE ZW_DELETE_RETURN_ROUTE ZW_REQUEST_NODE_NEIGHBOR_UPDATE ZW_APPLICATION_UPDATE ZW_ADD_NODE_TO_NETWORK ZW_REMOVE_NODE_FROM_NETWORK ZW_CREATE_NEW_PRIMARY ZW_CONTROLLER_CHANGE ZW_SET_LEARN_MODE ZW_ASSIGN_SUC_RETURN_ROUTE ZW_REQUEST_NETWORK_UPDATE ZW_SET_SUC_NODE_ID ZW_DELETE_SUC_RETURN_ROUTE ZW_GET_SUC_NODE_ID ZW_SEND_SUC_ID ZW_EXPLORE_REQUEST_INCLUSION ZW_REQUEST_NODE_INFO ZW_REMOVE_FAILED_NODE_ID ZW_IS_FAILED_NODE ZW_REPLACE_FAILED_NODE UNKNOWN_66 UNKNOWN_67 ZW_FIRMWARE_UPDATE_NVM GET_ROUTING_TABLE_LINE LOCK_ROUTE_RESPONSE ZW_GET_PRIORITY_ROUTE ZW_SET_PRIORITY_ROUTE UNKNOWN_98 ZW_SET_WUT_TIMEOUT ZW_WATCHDOG_ENABLE ZW_WATCHDOG_DISABLE ZW_WATCHDOG_CHECK ZW_SET_EXT_INT_LEVEL ZW_RF_POWERLEVEL_GET ZW_TYPE_LIBRARY ZW_SEND_TEST_FRAME ZW_GET_PROTOCOL_STATUS WATCHDOG_START WATCHDOG_STOP ZW_SET_ROUTING_MAX UNKNOWN_ef ZME_FREQ_CHANGE ZME_BOOTLOADER_FLASH ZME_CAPABILITIES
     2018-10-27 10:11:45   ctrlCaps        OTHER MEMBER PRIMARY SUC
     2018-10-27 19:49:47   homeId          HomeId:ed70b42b CtrlNodeIdHex:01
     2018-09-18 08:56:35   neighborList_12 ZWAVE AZ_HZ_T1 BD_HZ_T1 FL_DS_SM
     2018-09-18 09:00:44   neighborList_20 ZWAVE
     2018-09-18 08:56:54   neighborList_29 FL_AB_RM AZ_AB_WV AZ_HZ_T1 BD_HZ_T1 SZ_DB_FS KU_DB_FS FL_DB_TV AZ_DB_FV GA_DB_TO
     2018-09-18 08:56:04   neighborList_7  ZWAVE AZ_HZ_T1 BD_HZ_T1 FL_DS_SM
     2018-09-18 08:57:29   neighborList_73 WZ_RL_FS WZ_RL_TT TR_AB_WA WZ_HZ_T1 WZ_GZ_FS WZ_AB_LS SZ_RL_FL WZ_RL_FT KL_GZ_TK
     2018-09-18 08:53:10   neighborList_8  ZWAVE WZ_HZ_T1 WZ_GZ_FS WZ_DS_SM KL_GZ_TK UNKNOWN_75
     2018-10-27 10:11:45   random          f3b33714ebc8a6cb3e0e2f8c73a1aae39cc939287479323a78cadb1e40d4e300
     2018-10-27 10:11:45   state           Initialized
     2018-10-27 10:11:45   sucNodeId       1
     2018-08-28 14:35:19   version         Z-Wave 4.05 STATIC_CONTROLLER
   SendStack:
Attributes:
   group      MasterController
   helpSites  pepper,alliance
   homeId     ed70b42b
   icon       scc_868
   model      ZWDongle
   neighborListPos 486,626
   room       Controller
   verbose    3

Dazu der FHEM-Status:
root@PNinBBServer4 27.10.2018;19:52:05 / 37>service fhem status
* fhem.service - LSB: FHEM server
   Loaded: loaded (/etc/init.d/fhem; generated; vendor preset: enabled)
   Active: active (running) since Sat 2018-10-27 10:11:42 CEST; 9h ago
     Docs: man:systemd-sysv-generator(8)
  Process: 11477 ExecStop=/etc/init.d/fhem stop (code=exited, status=0/SUCCESS)
  Process: 12886 ExecStart=/etc/init.d/fhem start (code=exited, status=0/SUCCESS)
   CGroup: /system.slice/fhem.service
           `-12891 perl fhem.pl configDB

Okt 27 10:11:39 PNinBBServer4 systemd[1]: Starting LSB: FHEM server...
Okt 27 10:11:40 PNinBBServer4 fhem[12886]: Starting fhem...
Okt 27 10:11:42 PNinBBServer4 systemd[1]: Started LSB: FHEM server.
root@PNinBBServer4 27.10.2018;19:52:08 / 38>



Fazit: ich habe momentan mehr Fragen als Antworten, aber vielleicht hilft die kollektive Weisheit weiter ??!!
Schönes Wochenende !
Peter.
Nachtrag: ich habe den Verdacht, dass dies garnichts mit FHEM zu tun hat, sondern sich ein anderer Prozess mit an diese serielle Schnittstelle hängt.
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;