FIBARO Wall Plug Gen 5 - Doppelte Logeinträge

throbin · 22 Februar 2017, 20:12:45

Hi,

ich habe Probleme mit meinem Wall Plug von FIBARO. Beim Ein/Ausschalter tauchen die on/off Einträge im Logfile vom Device oft zwei Mal auf. Das Problem tritt auf sowohl über Timer-Betrieb als auch manuell (also wenn man im Webinterface auf an/aus klickt). Das Logfile vom ZWave-Dongle zeigt folgendes an (On/Off Sequenz):

Code Auswählen


2017.02.22 20:01:24.835 3: ZWave set DG_Heizraum_Switch_CP on 
2017.02.22 20:01:24.836 5: ZWDongle_Write 00131e032501FF250d (dad62400)
2017.02.22 20:01:24.837 5: SW: 010a00131e032501FF250d08
2017.02.22 20:01:24.873 5: ACK received, WaitForAck=>2 for 010a00131e032501FF250d08
2017.02.22 20:01:24.874 4: ZWDongle_Read ZWDongle_0: rcvd 011301 (answer ZW_SEND_DATA), sending ACK
2017.02.22 20:01:24.874 5: SW: 06
2017.02.22 20:01:24.876 5: ZWDongle_0: dispatch 011301
2017.02.22 20:01:26.880 4: no response from device, removing 010a00131e032501FF250d08 from dongle sendstack
2017.02.22 20:01:28.603 4: ZWDongle_Read ZWDongle_0: rcvd 0004001e063105042200d7 (request APPLICATION_COMMAND_HANDLER), sending ACK
2017.02.22 20:01:28.603 5: SW: 06
2017.02.22 20:01:28.605 5: ZWDongle_0: dispatch 0004001e063105042200d7
2017.02.22 20:01:28.606 4: CMD:APPLICATION_COMMAND_HANDLER ID:1e ARG:063105042200d7 CB:00
2017.02.22 20:01:28.756 4: ZWDongle_Read ZWDongle_0: rcvd 00130d000187 (request ZW_SEND_DATA), sending ACK
2017.02.22 20:01:28.756 5: SW: 06
2017.02.22 20:01:28.758 5: ZWDongle_0: dispatch 00130d000187
2017.02.22 20:01:28.759 4: CMD:ZW_SEND_DATA ID:00 ARG:0187 CB:0d
2017.02.22 20:01:28.759 4: ZWDongle_0 transmit OK for CB 0d, target DG_Heizraum_Switch_CP
2017.02.22 20:01:30.436 3: ZWave set DG_Heizraum_Switch_CP off 
2017.02.22 20:01:30.437 5: ZWDongle_Write 00131e03250100250e (dad62400)
2017.02.22 20:01:30.438 5: SW: 010a00131e03250100250ef4
2017.02.22 20:01:30.467 5: ACK received, WaitForAck=>2 for 010a00131e03250100250ef4
2017.02.22 20:01:30.467 4: ZWDongle_Read ZWDongle_0: rcvd 011301 (answer ZW_SEND_DATA), sending ACK
2017.02.22 20:01:30.467 5: SW: 06
2017.02.22 20:01:30.469 5: ZWDongle_0: dispatch 011301
2017.02.22 20:01:30.588 4: ZWDongle_Read ZWDongle_0: rcvd 00130e00000f (request ZW_SEND_DATA), sending ACK
2017.02.22 20:01:30.588 5: SW: 06
2017.02.22 20:01:30.589 5: device ack reveived, removing 010a00131e03250100250ef4 from dongle sendstack
2017.02.22 20:01:30.590 5: ZWDongle_0: dispatch 00130e00000f
2017.02.22 20:01:30.590 4: CMD:ZW_SEND_DATA ID:00 ARG:000f CB:0e
2017.02.22 20:01:30.590 4: ZWDongle_0 transmit OK for CB 0e, target DG_Heizraum_Switch_CP
2017.02.22 20:01:30.718 4: ZWDongle_Read ZWDongle_0: rcvd 0004001e03250300 (request APPLICATION_COMMAND_HANDLER), sending ACK
2017.02.22 20:01:30.718 5: SW: 06
2017.02.22 20:01:30.719 5: ZWDongle_0: dispatch 0004001e03250300
2017.02.22 20:01:30.720 4: CMD:APPLICATION_COMMAND_HANDLER ID:1e ARG:03250300 CB:00
2017.02.22 20:01:31.327 4: ZWDongle_Read ZWDongle_0: rcvd 0004001e06310504220000 (request APPLICATION_COMMAND_HANDLER), sending ACK
2017.02.22 20:01:31.327 5: SW: 06
2017.02.22 20:01:31.329 5: ZWDongle_0: dispatch 0004001e06310504220000
2017.02.22 20:01:31.330 4: CMD:APPLICATION_COMMAND_HANDLER ID:1e ARG:06310504220000 CB:00

Zu Diesem Log sieht das Log vom Device so aus:

Code Auswählen


2017-02-22_20:01:24 DG_Heizraum_Switch_CP on
2017-02-22_20:01:28 DG_Heizraum_Switch_CP power: 21.5 W
2017-02-22_20:01:30 DG_Heizraum_Switch_CP off
2017-02-22_20:01:30 DG_Heizraum_Switch_CP off
2017-02-22_20:01:30 DG_Heizraum_Switch_CP reportedState: off
2017-02-22_20:01:31 DG_Heizraum_Switch_CP power: 0.0 W

Ich habe festgestellt, dass einige Timer auch nicht mehr so funktionieren wie früher, wenn man ein Update von FHEM macht und anschließend restart ausführt werden die Handler nicht ausgeführt, es wird auch im Log nichts protokolliert. Wenn ich den Pi3 reboote, dann laufen die Timer wieder... Aus den Logs kann ich aber nichts erkennen, was auf die at's schließen könnte... Hat es was mit dem neuesten Update auf die Version 5.8 zu tun?

Die Timer-Definition:

Code Auswählen


*06:20 { if(!$we) { fhem("set DG_Heizraum_Switch_CP on"); fhem("define timerCircPumpAutoOff at +00:05:00 set DG_Heizraum_Switch_CP off"); } }

Für Infos/Vorschläge wäre ich dankbar

rudolfkoenig · 23 Februar 2017, 11:22:50

Das doppelte off kann ich nicht erklaeren, evtl. sehe ich mehr, wenn ich ein Log mit "attr global verbose 5" und "attr global mseclog" sehe.

Die 5.8 Umstellung hat nur 2 alte Attribute aktiviert, indem ich die Voreinstellung des featurelevel Attributes von 5.7 auf 5.8 geaendert habe. Ob es daran liegt, kann man mit "attr global featurelevel 5.7" (und sicherheitshalber ein Neustart) testen.

Wird der erste oder der zweite at nicht ausgefuehrt? Was steht in der Detail-Ansicht des ats als TRIGGERTIME_FMT? Ist $we gesetzt?

Falls das fuer den Schalter verantwortliche Modul setExtension unterstuetzt (wie ZWave), dann wuerde ich es eher als

Code Auswählen

*06:20 { if(!$we) { fhem("set DG_Heizraum_Switch_CP on-for-timer 300"); } }
oder

Code Auswählen

*06:20 { if(!$we) { fhem("set DG_Heizraum_Switch_CP on-till 06:25"); } }
schreiben.

throbin · 23 Februar 2017, 14:28:36

Hi,

ich habe den Wallplug manuell ein/ausgeschaltet, dabei werden folgende Infos ausgegeben:

Event-Log:

Code Auswählen


2017-02-23 14:17:23.386 ZWave DG_Heizraum_Switch_CP on
2017-02-23 14:17:24.091 ZWave DG_Heizraum_Switch_CP on
2017-02-23 14:17:24.091 ZWave DG_Heizraum_Switch_CP reportedState: on
2017-02-23 14:17:24.931 ZWave DG_Heizraum_Switch_CP power: 22.9 W
2017-02-23 14:17:34.092 ZWave DG_Heizraum_Switch_CP off
2017-02-23 14:17:34.338 ZWave DG_Heizraum_Switch_CP off
2017-02-23 14:17:34.338 ZWave DG_Heizraum_Switch_CP reportedState: off
2017-02-23 14:17:36.669 ZWave DG_Heizraum_Switch_CP power: 0.0 W
2017-02-23 14:17:36.829 ZWave DG_Heizraum_Switch_CP power: 0.0 W
2017-02-23 14:17:37.235 ZWave DG_Heizraum_Switch_CP power: 0.0 W
2017-02-23 14:17:37.274 ZWave DG_Heizraum_Switch_CP power: 0.0 W
2017-02-23 14:17:37.396 ZWave DG_Heizraum_Switch_CP power: 0.0 W
2017-02-23 14:17:37.488 ZWave DG_Heizraum_Switch_CP power: 0.0 W
2017-02-23 14:17:37.549 ZWave DG_Heizraum_Switch_CP power: 0.0 W
2017-02-23 14:17:37.601 ZWave DG_Heizraum_Switch_CP power: 0.0 W
2017-02-23 14:17:37.641 ZWave DG_Heizraum_Switch_CP power: 0.0 W
2017-02-23 14:17:37.936 ZWave DG_Heizraum_Switch_CP power: 0.0 W
2017-02-23 14:17:38.010 ZWave DG_Heizraum_Switch_CP power: 0.0 W
2017-02-23 14:17:38.064 ZWave DG_Heizraum_Switch_CP power: 0.0 W
2017-02-23 14:17:38.083 ZWave DG_Heizraum_Switch_CP power: 0.0 W
2017-02-23 14:17:38.167 ZWave DG_Heizraum_Switch_CP power: 0.0 W
2017-02-23 14:17:38.225 ZWave DG_Heizraum_Switch_CP power: 0.0 W

FHEM log (mit verbose 5 und mseclog):

Code Auswählen


2017.02.23 14:17:23.369 5: Cmd: >set DG_Heizraum_Switch_CP on<
2017.02.23 14:17:23.372 3: ZWave set DG_Heizraum_Switch_CP on 
2017.02.23 14:17:23.373 5: ZWDongle_Write 00131e032501FF2502 (dad62400)
2017.02.23 14:17:23.373 5: SW: 010a00131e032501FF250207
2017.02.23 14:17:23.376 5: Starting notify loop for DG_Heizraum_Switch_CP, 1 event(s), first is on
2017.02.23 14:17:23.376 5: createNotifyHash
2017.02.23 14:17:23.386 5: rgBatteryStatus: not on any display, ignoring notify
2017.02.23 14:17:23.386 5: rgZWaveComponents: not on any display, ignoring notify
2017.02.23 14:17:23.394 5: End notify loop for DG_Heizraum_Switch_CP
2017.02.23 14:17:23.395 4: name: /fhem?cmd.DG_Heizraum_Switch_CP=set%20DG_Heizraum_Switch_CP%20on&room=Heizraum&fwcsrf=fhem_512894271270048&XHR=1&fw_id=155 / RL:20 / text/plain; charset=UTF-8 / Content-Encoding: gzip
 / 
2017.02.23 14:17:23.397 5: ACK received, WaitForAck=>2 for 010a00131e032501FF250207
2017.02.23 14:17:23.397 4: ZWDongle_Read ZWDongle_0: rcvd 011301 (answer ZW_SEND_DATA), sending ACK
2017.02.23 14:17:23.397 5: SW: 06
2017.02.23 14:17:23.399 5: ZWDongle_0: dispatch 011301
2017.02.23 14:17:23.431 4: WEB_192.168.178.137_59904 GET /fhem/images/default/on.png; BUFLEN:0
2017.02.23 14:17:23.432 4: WEB_192.168.178.137_59904 => 304 Not Modified
2017.02.23 14:17:24.046 4: ZWDongle_Read ZWDongle_0: rcvd 001302000042 (request ZW_SEND_DATA), sending ACK
2017.02.23 14:17:24.047 5: SW: 06
2017.02.23 14:17:24.048 5: device ack reveived, removing 010a00131e032501FF250207 from dongle sendstack
2017.02.23 14:17:24.049 5: ZWDongle_0: dispatch 001302000042
2017.02.23 14:17:24.049 4: CMD:ZW_SEND_DATA ID:00 ARG:0042 CB:02
2017.02.23 14:17:24.050 4: ZWDongle_0 transmit OK for CB 02, target DG_Heizraum_Switch_CP
2017.02.23 14:17:24.082 4: ZWDongle_Read ZWDongle_0: rcvd 0004001e032503ff (request APPLICATION_COMMAND_HANDLER), sending ACK
2017.02.23 14:17:24.083 5: SW: 06
2017.02.23 14:17:24.085 5: ZWDongle_0: dispatch 0004001e032503ff
2017.02.23 14:17:24.085 4: CMD:APPLICATION_COMMAND_HANDLER ID:1e ARG:032503ff CB:00
2017.02.23 14:17:24.087 5: Starting notify loop for DG_Heizraum_Switch_CP, 2 event(s), first is on
2017.02.23 14:17:24.090 5: rgBatteryStatus: not on any display, ignoring notify
2017.02.23 14:17:24.091 5: rgZWaveComponents: not on any display, ignoring notify
2017.02.23 14:17:24.099 5: End notify loop for DG_Heizraum_Switch_CP
2017.02.23 14:17:24.921 4: ZWDongle_Read ZWDongle_0: rcvd 0004001e063105042200e5 (request APPLICATION_COMMAND_HANDLER), sending ACK
2017.02.23 14:17:24.921 5: SW: 06
2017.02.23 14:17:24.923 5: ZWDongle_0: dispatch 0004001e063105042200e5
2017.02.23 14:17:24.924 4: CMD:APPLICATION_COMMAND_HANDLER ID:1e ARG:063105042200e5 CB:00
2017.02.23 14:17:24.927 5: Starting notify loop for DG_Heizraum_Switch_CP, 1 event(s), first is power: 22.9 W
2017.02.23 14:17:24.930 5: rgBatteryStatus: not on any display, ignoring notify
2017.02.23 14:17:24.930 5: rgZWaveComponents: not on any display, ignoring notify
2017.02.23 14:17:24.938 5: End notify loop for DG_Heizraum_Switch_CP
2017.02.23 14:17:34.080 4: WEB_192.168.178.137_59904 POST /fhem?cmd.DG_Heizraum_Switch_CP=set%20DG_Heizraum_Switch_CP%20off&room=Heizraum&fwcsrf=fhem_512894271270048&XHR=1&fw_id=155; BUFLEN:0
2017.02.23 14:17:34.081 5: Cmd: >set DG_Heizraum_Switch_CP off<
2017.02.23 14:17:34.085 3: ZWave set DG_Heizraum_Switch_CP off 
2017.02.23 14:17:34.085 5: ZWDongle_Write 00131e032501002503 (dad62400)
2017.02.23 14:17:34.086 5: SW: 010a00131e032501002503f9
2017.02.23 14:17:34.088 5: Starting notify loop for DG_Heizraum_Switch_CP, 1 event(s), first is off
2017.02.23 14:17:34.091 5: rgBatteryStatus: not on any display, ignoring notify
2017.02.23 14:17:34.092 5: rgZWaveComponents: not on any display, ignoring notify
2017.02.23 14:17:34.100 5: End notify loop for DG_Heizraum_Switch_CP
2017.02.23 14:17:34.101 4: name: /fhem?cmd.DG_Heizraum_Switch_CP=set%20DG_Heizraum_Switch_CP%20off&room=Heizraum&fwcsrf=fhem_512894271270048&XHR=1&fw_id=155 / RL:20 / text/plain; charset=UTF-8 / Content-Encoding: gzip
 / 
2017.02.23 14:17:34.102 5: ACK received, WaitForAck=>2 for 010a00131e032501002503f9
2017.02.23 14:17:34.103 4: ZWDongle_Read ZWDongle_0: rcvd 011301 (answer ZW_SEND_DATA), sending ACK
2017.02.23 14:17:34.103 5: SW: 06
2017.02.23 14:17:34.105 5: ZWDongle_0: dispatch 011301
2017.02.23 14:17:34.329 4: ZWDongle_Read ZWDongle_0: rcvd 0004001e03250300 (request APPLICATION_COMMAND_HANDLER), sending ACK
2017.02.23 14:17:34.330 5: SW: 06
2017.02.23 14:17:34.332 5: ZWDongle_0: dispatch 0004001e03250300
2017.02.23 14:17:34.332 4: CMD:APPLICATION_COMMAND_HANDLER ID:1e ARG:03250300 CB:00
2017.02.23 14:17:34.334 5: Starting notify loop for DG_Heizraum_Switch_CP, 2 event(s), first is off
2017.02.23 14:17:34.337 5: rgBatteryStatus: not on any display, ignoring notify
2017.02.23 14:17:34.338 5: rgZWaveComponents: not on any display, ignoring notify
2017.02.23 14:17:34.346 5: End notify loop for DG_Heizraum_Switch_CP
2017.02.23 14:17:36.349 4: no response from device, removing 010a00131e032501002503f9 from dongle sendstack
2017.02.23 14:17:36.659 4: ZWDongle_Read ZWDongle_0: rcvd 0004001e06310504220000 (request APPLICATION_COMMAND_HANDLER), sending ACK
2017.02.23 14:17:36.660 5: SW: 06
2017.02.23 14:17:36.662 5: ZWDongle_0: dispatch 0004001e06310504220000
2017.02.23 14:17:36.662 4: CMD:APPLICATION_COMMAND_HANDLER ID:1e ARG:06310504220000 CB:00
2017.02.23 14:17:36.665 5: Starting notify loop for DG_Heizraum_Switch_CP, 1 event(s), first is power: 0.0 W
2017.02.23 14:17:36.668 5: rgBatteryStatus: not on any display, ignoring notify
2017.02.23 14:17:36.669 5: rgZWaveComponents: not on any display, ignoring notify
2017.02.23 14:17:36.676 5: End notify loop for DG_Heizraum_Switch_CP
2017.02.23 14:17:36.679 4: ZWDongle_Read ZWDongle_0: rcvd 001303000103 (request ZW_SEND_DATA), sending ACK
2017.02.23 14:17:36.679 5: SW: 06
2017.02.23 14:17:36.681 5: ZWDongle_0: dispatch 001303000103
2017.02.23 14:17:36.681 4: CMD:ZW_SEND_DATA ID:00 ARG:0103 CB:03
2017.02.23 14:17:36.682 4: ZWDongle_0 transmit OK for CB 03, target DG_Heizraum_Switch_CP
2017.02.23 14:17:36.819 4: ZWDongle_Read ZWDongle_0: rcvd 0004001e06310504220000 (request APPLICATION_COMMAND_HANDLER), sending ACK
2017.02.23 14:17:36.820 5: SW: 06
2017.02.23 14:17:36.822 5: ZWDongle_0: dispatch 0004001e06310504220000
2017.02.23 14:17:36.822 4: CMD:APPLICATION_COMMAND_HANDLER ID:1e ARG:06310504220000 CB:00
2017.02.23 14:17:36.825 5: Starting notify loop for DG_Heizraum_Switch_CP, 1 event(s), first is power: 0.0 W
2017.02.23 14:17:36.828 5: rgBatteryStatus: not on any display, ignoring notify
2017.02.23 14:17:36.828 5: rgZWaveComponents: not on any display, ignoring notify
2017.02.23 14:17:36.836 5: End notify loop for DG_Heizraum_Switch_CP
2017.02.23 14:17:37.226 4: ZWDongle_Read ZWDongle_0: rcvd 0004101e06310504220000 (request APPLICATION_COMMAND_HANDLER), sending ACK
2017.02.23 14:17:37.226 5: SW: 06
2017.02.23 14:17:37.228 5: ZWDongle_0: dispatch 0004101e06310504220000
2017.02.23 14:17:37.228 4: CMD:APPLICATION_COMMAND_HANDLER ID:1e ARG:06310504220000 CB:10
2017.02.23 14:17:37.231 5: Starting notify loop for DG_Heizraum_Switch_CP, 1 event(s), first is power: 0.0 W
2017.02.23 14:17:37.234 5: rgBatteryStatus: not on any display, ignoring notify
2017.02.23 14:17:37.235 5: rgZWaveComponents: not on any display, ignoring notify
2017.02.23 14:17:37.242 5: End notify loop for DG_Heizraum_Switch_CP
2017.02.23 14:17:37.265 4: ZWDongle_Read ZWDongle_0: rcvd 0004101e06310504220000 (request APPLICATION_COMMAND_HANDLER), sending ACK
2017.02.23 14:17:37.265 5: SW: 06
2017.02.23 14:17:37.267 5: ZWDongle_0: dispatch 0004101e06310504220000
2017.02.23 14:17:37.268 4: CMD:APPLICATION_COMMAND_HANDLER ID:1e ARG:06310504220000 CB:10
2017.02.23 14:17:37.271 5: Starting notify loop for DG_Heizraum_Switch_CP, 1 event(s), first is power: 0.0 W
2017.02.23 14:17:37.274 5: rgBatteryStatus: not on any display, ignoring notify
2017.02.23 14:17:37.274 5: rgZWaveComponents: not on any display, ignoring notify
2017.02.23 14:17:37.282 5: End notify loop for DG_Heizraum_Switch_CP
2017.02.23 14:17:37.386 4: ZWDongle_Read ZWDongle_0: rcvd 0004101e06310504220000 (request APPLICATION_COMMAND_HANDLER), sending ACK
2017.02.23 14:17:37.387 5: SW: 06
2017.02.23 14:17:37.389 5: ZWDongle_0: dispatch 0004101e06310504220000
2017.02.23 14:17:37.389 4: CMD:APPLICATION_COMMAND_HANDLER ID:1e ARG:06310504220000 CB:10
2017.02.23 14:17:37.392 5: Starting notify loop for DG_Heizraum_Switch_CP, 1 event(s), first is power: 0.0 W
2017.02.23 14:17:37.395 5: rgBatteryStatus: not on any display, ignoring notify
2017.02.23 14:17:37.395 5: rgZWaveComponents: not on any display, ignoring notify
2017.02.23 14:17:37.403 5: End notify loop for DG_Heizraum_Switch_CP
2017.02.23 14:17:37.479 4: ZWDongle_Read ZWDongle_0: rcvd 0004101e06310504220000 (request APPLICATION_COMMAND_HANDLER), sending ACK
2017.02.23 14:17:37.479 5: SW: 06
2017.02.23 14:17:37.481 5: ZWDongle_0: dispatch 0004101e06310504220000
2017.02.23 14:17:37.481 4: CMD:APPLICATION_COMMAND_HANDLER ID:1e ARG:06310504220000 CB:10
2017.02.23 14:17:37.484 5: Starting notify loop for DG_Heizraum_Switch_CP, 1 event(s), first is power: 0.0 W
2017.02.23 14:17:37.487 5: rgBatteryStatus: not on any display, ignoring notify
2017.02.23 14:17:37.488 5: rgZWaveComponents: not on any display, ignoring notify
2017.02.23 14:17:37.495 5: End notify loop for DG_Heizraum_Switch_CP
2017.02.23 14:17:37.540 4: ZWDongle_Read ZWDongle_0: rcvd 0004101e06310504220000 (request APPLICATION_COMMAND_HANDLER), sending ACK
2017.02.23 14:17:37.540 5: SW: 06
2017.02.23 14:17:37.542 5: ZWDongle_0: dispatch 0004101e06310504220000
2017.02.23 14:17:37.543 4: CMD:APPLICATION_COMMAND_HANDLER ID:1e ARG:06310504220000 CB:10
2017.02.23 14:17:37.545 5: Starting notify loop for DG_Heizraum_Switch_CP, 1 event(s), first is power: 0.0 W
2017.02.23 14:17:37.548 5: rgBatteryStatus: not on any display, ignoring notify
2017.02.23 14:17:37.549 5: rgZWaveComponents: not on any display, ignoring notify
2017.02.23 14:17:37.556 5: End notify loop for DG_Heizraum_Switch_CP
2017.02.23 14:17:37.591 4: ZWDongle_Read ZWDongle_0: rcvd 0004101e06310504220000 (request APPLICATION_COMMAND_HANDLER), sending ACK
2017.02.23 14:17:37.592 5: SW: 06
2017.02.23 14:17:37.593 5: ZWDongle_0: dispatch 0004101e06310504220000
2017.02.23 14:17:37.594 4: CMD:APPLICATION_COMMAND_HANDLER ID:1e ARG:06310504220000 CB:10
2017.02.23 14:17:37.597 5: Starting notify loop for DG_Heizraum_Switch_CP, 1 event(s), first is power: 0.0 W
2017.02.23 14:17:37.600 5: rgBatteryStatus: not on any display, ignoring notify
2017.02.23 14:17:37.600 5: rgZWaveComponents: not on any display, ignoring notify
2017.02.23 14:17:37.608 5: End notify loop for DG_Heizraum_Switch_CP
2017.02.23 14:17:37.631 4: ZWDongle_Read ZWDongle_0: rcvd 0004101e06310504220000 (request APPLICATION_COMMAND_HANDLER), sending ACK
2017.02.23 14:17:37.632 5: SW: 06
2017.02.23 14:17:37.634 5: ZWDongle_0: dispatch 0004101e06310504220000
2017.02.23 14:17:37.634 4: CMD:APPLICATION_COMMAND_HANDLER ID:1e ARG:06310504220000 CB:10
2017.02.23 14:17:37.637 5: Starting notify loop for DG_Heizraum_Switch_CP, 1 event(s), first is power: 0.0 W
2017.02.23 14:17:37.640 5: rgBatteryStatus: not on any display, ignoring notify
2017.02.23 14:17:37.641 5: rgZWaveComponents: not on any display, ignoring notify
2017.02.23 14:17:37.648 5: End notify loop for DG_Heizraum_Switch_CP
2017.02.23 14:17:37.927 4: ZWDongle_Read ZWDongle_0: rcvd 0004101e06310504220000 (request APPLICATION_COMMAND_HANDLER), sending ACK
2017.02.23 14:17:37.927 5: SW: 06
2017.02.23 14:17:37.929 5: ZWDongle_0: dispatch 0004101e06310504220000
2017.02.23 14:17:37.930 4: CMD:APPLICATION_COMMAND_HANDLER ID:1e ARG:06310504220000 CB:10
2017.02.23 14:17:37.933 5: Starting notify loop for DG_Heizraum_Switch_CP, 1 event(s), first is power: 0.0 W
2017.02.23 14:17:37.935 5: rgBatteryStatus: not on any display, ignoring notify
2017.02.23 14:17:37.936 5: rgZWaveComponents: not on any display, ignoring notify
2017.02.23 14:17:37.943 5: End notify loop for DG_Heizraum_Switch_CP
2017.02.23 14:17:38.001 4: ZWDongle_Read ZWDongle_0: rcvd 0004101e06310504220000 (request APPLICATION_COMMAND_HANDLER), sending ACK
2017.02.23 14:17:38.001 5: SW: 06
2017.02.23 14:17:38.003 5: ZWDongle_0: dispatch 0004101e06310504220000
2017.02.23 14:17:38.004 4: CMD:APPLICATION_COMMAND_HANDLER ID:1e ARG:06310504220000 CB:10
2017.02.23 14:17:38.007 5: Starting notify loop for DG_Heizraum_Switch_CP, 1 event(s), first is power: 0.0 W
2017.02.23 14:17:38.009 5: rgBatteryStatus: not on any display, ignoring notify
2017.02.23 14:17:38.010 5: rgZWaveComponents: not on any display, ignoring notify
2017.02.23 14:17:38.018 5: End notify loop for DG_Heizraum_Switch_CP
2017.02.23 14:17:38.055 4: ZWDongle_Read ZWDongle_0: rcvd 0004101e06310504220000 (request APPLICATION_COMMAND_HANDLER), sending ACK
2017.02.23 14:17:38.055 5: SW: 06
2017.02.23 14:17:38.057 5: ZWDongle_0: dispatch 0004101e06310504220000
2017.02.23 14:17:38.058 4: CMD:APPLICATION_COMMAND_HANDLER ID:1e ARG:06310504220000 CB:10
2017.02.23 14:17:38.061 5: Starting notify loop for DG_Heizraum_Switch_CP, 1 event(s), first is power: 0.0 W
2017.02.23 14:17:38.063 5: rgBatteryStatus: not on any display, ignoring notify
2017.02.23 14:17:38.064 5: rgZWaveComponents: not on any display, ignoring notify
2017.02.23 14:17:38.072 5: End notify loop for DG_Heizraum_Switch_CP
2017.02.23 14:17:38.073 4: ZWDongle_Read ZWDongle_0: rcvd 0004101e06310504220000 (request APPLICATION_COMMAND_HANDLER), sending ACK
2017.02.23 14:17:38.073 5: SW: 06
2017.02.23 14:17:38.075 5: ZWDongle_0: dispatch 0004101e06310504220000
2017.02.23 14:17:38.076 4: CMD:APPLICATION_COMMAND_HANDLER ID:1e ARG:06310504220000 CB:10
2017.02.23 14:17:38.079 5: Starting notify loop for DG_Heizraum_Switch_CP, 1 event(s), first is power: 0.0 W
2017.02.23 14:17:38.082 5: rgBatteryStatus: not on any display, ignoring notify
2017.02.23 14:17:38.082 5: rgZWaveComponents: not on any display, ignoring notify
2017.02.23 14:17:38.090 5: End notify loop for DG_Heizraum_Switch_CP
2017.02.23 14:17:38.158 4: ZWDongle_Read ZWDongle_0: rcvd 0004101e06310504220000 (request APPLICATION_COMMAND_HANDLER), sending ACK
2017.02.23 14:17:38.158 5: SW: 06
2017.02.23 14:17:38.160 5: ZWDongle_0: dispatch 0004101e06310504220000
2017.02.23 14:17:38.161 4: CMD:APPLICATION_COMMAND_HANDLER ID:1e ARG:06310504220000 CB:10
2017.02.23 14:17:38.164 5: Starting notify loop for DG_Heizraum_Switch_CP, 1 event(s), first is power: 0.0 W
2017.02.23 14:17:38.166 5: rgBatteryStatus: not on any display, ignoring notify
2017.02.23 14:17:38.167 5: rgZWaveComponents: not on any display, ignoring notify
2017.02.23 14:17:38.174 5: End notify loop for DG_Heizraum_Switch_CP
2017.02.23 14:17:38.215 4: ZWDongle_Read ZWDongle_0: rcvd 0004101e06310504220000 (request APPLICATION_COMMAND_HANDLER), sending ACK
2017.02.23 14:17:38.216 5: SW: 06
2017.02.23 14:17:38.218 5: ZWDongle_0: dispatch 0004101e06310504220000
2017.02.23 14:17:38.218 4: CMD:APPLICATION_COMMAND_HANDLER ID:1e ARG:06310504220000 CB:10
2017.02.23 14:17:38.221 5: Starting notify loop for DG_Heizraum_Switch_CP, 1 event(s), first is power: 0.0 W
2017.02.23 14:17:38.224 5: rgBatteryStatus: not on any display, ignoring notify
2017.02.23 14:17:38.224 5: rgZWaveComponents: not on any display, ignoring notify
2017.02.23 14:17:38.232 5: End notify loop for DG_Heizraum_Switch_CP

Ganz schön viel Zeug.. Zwischendurch tauchen noch zwei Readingsgroups auf, diese dürften aber kein Problem darstellen.
Ich habe das mit dem Vorgängermodell (ZWave) verglichen - hier taucht der Befehl nur ein Mal auf.

Thema Timer - ich werde es mit den Extensions mal testen, danke für den Hinweis!

LG
throbin

rudolfkoenig · 23 Februar 2017, 16:14:47

ZitatGanz schön viel Zeug..

Stimmt. Der Stick meldet 15-mal power, davon 2-mal mit CallbackId 00, und 13-mal mit CB:10. Ich behaupte die 15 "rcvd" Zeilen entsprechen 15-Funknachrichten. Wie ist der Plug angebunden? Meines Wissens sollte alles, was vom Geraet "freiwillig" gemeldet wird, mit CB:00 kommen, nur bei Antworten muss das gleiche CallbackId verwendet werden, wie bei der Frage spezifiziert wurde. Auf ZWave Ebene gibt es auch ein sequence count, d.h. der Controller muesste in der Lage sein, doppelte Nachrichten zu filtern. Sowas gibts auf hoehere Ebene nicht mehr.

ZitatIch habe das mit dem Vorgängermodell (ZWave) verglichen - hier taucht der Befehl nur ein Mal auf.

Wenn man weiss, dass im letzen Jahr 120+ Aenderungen an 10_ZWave.pm vorgenommen wurden, hilft diese Info nicht viel. Was genau heisst "verglichen"? Ein vergleichbares, neu erstelltes Log mit der alten Version wuerde mich interessieren.

throbin · 23 Februar 2017, 16:48:27

Hi,

Zitat
Wie ist der Plug angebunden?

Meinst Du Listing - siehe unten, sonst ganz normal per Inklusion am ZWave-Controller (UZB1 Stick am Pi3). Sonst nichts konfiguriert, keine zus. Associations etc. "timeToAck" scheint hier generell deutlich höher zu sein als bei anderen Devices, evtl. hängt das damit zusammen?

Code Auswählen


Internals: 
   DEF        dad62400 30 
   IODev      ZWDongle_0 
   NAME       DG_Heizraum_Switch_CP 
   NR         124 
   STATE      off 
   TYPE       ZWave 
   ZWaveSubDevice no 
   homeId     dad62400 
   nodeIdHex  1e 
   Readings: 
     2017-02-22 22:33:25   assocGroup_1    Max 1 Nodes ZWDongle_0 
     2017-02-22 22:33:26   assocGroup_2    Max 10 Nodes 
     2017-02-22 22:33:26   assocGroup_3    Max 10 Nodes 
     2017-02-22 22:33:25   assocGroups     3 
     2017-02-17 14:34:03   configLEDRingIlluminationColourAtTheZ43 LEDRingFlashesRedBlueWhite 
     2017-02-23 16:01:26   energy          0.06 kWh 
     2017-02-17 13:54:56   model           FIBARO System FGWP102-ZW5 Wall Plug 
     2017-02-17 13:54:56   modelConfig     fibaro/fgwp102-zw5.xml 
     2017-02-17 13:54:56   modelId         010f-0602-1001 
     2017-02-23 16:17:34   power           0.0 W 
     2017-02-23 14:17:34   reportedState   off 
     2017-02-23 14:17:34   state           off 
     2017-02-23 14:17:36   timeToAck       2.597 
     2017-02-23 14:17:36   transmit        OK 
Attributes: 
   IODev      ZWDongle_0 
   classes    ZWAVEPLUS_INFO APPLICATION_STATUS ASSOCIATION ASSOCIATION_GRP_INFO CONFIGURATION CRC_16_ENCAP DEVICE_RESET_LOCALLY FIRMWARE_UPDATE_MD MANUFACTURER_SPECIFIC METER MULTI_CHANNEL_ASSOCIATION ALARM POWERLEVEL SECURITY SENSOR_MULTILEVEL SWITCH_BINARY VERSION 
   room       Heizraum,ZWave 
   vclasses   ALARM:5 APPLICATION_STATUS:1 ASSOCIATION:2 ASSOCIATION_GRP_INFO:1 CONFIGURATION:1 CRC_16_ENCAP:1 DEVICE_RESET_LOCALLY:1 FIRMWARE_UPDATE_MD:3 MANUFACTURER_SPECIFIC:2 METER:2 MULTI_CHANNEL_ASSOCIATION:3 POWERLEVEL:1 SECURITY:1 SENSOR_MULTILEVEL:5 SWITCH_BINARY:1 VERSION:2 ZWAVEPLUS_INFO:2

Log am alten Modell (selber Loglevel, selbe schaltsequenz), Device Name EG_Kueche_Steckdose_DAH:

Code Auswählen


2017.02.23 16:39:29.466 4: WEB_192.168.178.189_62117 POST /fhem?cmd.EG_Kueche_Steckdose_DAH=set%20EG_Kueche_Steckdose_DAH%20an&room=Kueche&XHR=1&fw_id=129; BUFLEN:0
2017.02.23 16:39:29.467 5: Cmd: >set EG_Kueche_Steckdose_DAH an<
2017.02.23 16:39:29.470 3: ZWave set EG_Kueche_Steckdose_DAH on 
2017.02.23 16:39:29.471 5: ZWDongle_Write 001317032501FF2501 (dad62400)
2017.02.23 16:39:29.472 5: SW: 010a001317032501FF25010d
2017.02.23 16:39:29.475 5: Starting notify loop for EG_Kueche_Steckdose_DAH, 1 event(s), first is an
2017.02.23 16:39:29.475 5: createNotifyHash
2017.02.23 16:39:29.494 5: rgBatteryStatus: not on any display, ignoring notify
2017.02.23 16:39:29.494 5: rgZWaveComponents: not on any display, ignoring notify
2017.02.23 16:39:29.502 5: End notify loop for EG_Kueche_Steckdose_DAH
2017.02.23 16:39:29.503 4: name: /fhem?cmd.EG_Kueche_Steckdose_DAH=set%20EG_Kueche_Steckdose_DAH%20an&room=Kueche&XHR=1&fw_id=129 / RL:20 / text/plain; charset=UTF-8 / Content-Encoding: gzip
 / 
2017.02.23 16:39:29.504 5: ACK received, WaitForAck=>2 for 010a001317032501FF25010d
2017.02.23 16:39:29.504 4: ZWDongle_Read ZWDongle_0: rcvd 011301 (answer ZW_SEND_DATA), sending ACK
2017.02.23 16:39:29.504 5: SW: 06
2017.02.23 16:39:29.506 5: ZWDongle_0: dispatch 011301
2017.02.23 16:39:29.556 4: ZWDongle_Read ZWDongle_0: rcvd 001301000004 (request ZW_SEND_DATA), sending ACK
2017.02.23 16:39:29.556 5: SW: 06
2017.02.23 16:39:29.557 5: device ack reveived, removing 010a001317032501FF25010d from dongle sendstack
2017.02.23 16:39:29.558 5: ZWDongle_0: dispatch 001301000004
2017.02.23 16:39:29.558 4: CMD:ZW_SEND_DATA ID:00 ARG:0004 CB:01
2017.02.23 16:39:29.558 4: ZWDongle_0 transmit OK for CB 01, target EG_Kueche_Steckdose_DAH
2017.02.23 16:39:29.592 4: WEB_192.168.178.189_62117 GET /fhem/images/default/on.png; BUFLEN:0
2017.02.23 16:39:29.593 4: WEB_192.168.178.189_62117 => 304 Not Modified
2017.02.23 16:39:35.066 4: WEB_192.168.178.189_62117 POST /fhem?cmd.EG_Kueche_Steckdose_DAH=set%20EG_Kueche_Steckdose_DAH%20aus&room=Kueche&XHR=1&fw_id=129; BUFLEN:0
2017.02.23 16:39:35.067 3: FHEMWEB WEB CSRF error:  ne fhem_87443305400247. For detals see the csrfToken FHEMWEB attribute
2017.02.23 16:39:35.068 4: name: /fhem?cmd.EG_Kueche_Steckdose_DAH=set%20EG_Kueche_Steckdose_DAH%20aus&room=Kueche&XHR=1&fw_id=129 / RL:20 / text/html; charset=UTF-8 / Content-Encoding: gzip
 / 
2017.02.23 16:39:37.715 4: ZWDongle_Read ZWDongle_0: rcvd 0004000906310504220000 (request APPLICATION_COMMAND_HANDLER), sending ACK
2017.02.23 16:39:37.715 5: SW: 06
2017.02.23 16:39:37.717 5: ZWDongle_0: dispatch 0004000906310504220000
2017.02.23 16:39:37.718 4: CMD:APPLICATION_COMMAND_HANDLER ID:09 ARG:06310504220000 CB:00
2017.02.23 16:39:37.722 5: Starting notify loop for EG_Kueche_RolloWS_FR, 1 event(s), first is power: 0.0 W
2017.02.23 16:39:37.722 5: createNotifyHash
2017.02.23 16:39:37.732 5: rgBatteryStatus: not on any display, ignoring notify
2017.02.23 16:39:37.732 5: rgZWaveComponents: not on any display, ignoring notify
2017.02.23 16:39:37.740 5: End notify loop for EG_Kueche_RolloWS_FR
2017.02.23 16:39:40.329 4: WEB_192.168.178.189_62117 POST /fhem?cmd.EG_Kueche_Steckdose_DAH=set%20EG_Kueche_Steckdose_DAH%20aus&room=Kueche&XHR=1&fw_id=129; BUFLEN:0
2017.02.23 16:39:40.333 3: FHEMWEB WEB CSRF error:  ne fhem_87443305400247. For detals see the csrfToken FHEMWEB attribute
2017.02.23 16:39:40.334 4: name: /fhem?cmd.EG_Kueche_Steckdose_DAH=set%20EG_Kueche_Steckdose_DAH%20aus&room=Kueche&XHR=1&fw_id=129 / RL:20 / text/html; charset=UTF-8 / Content-Encoding: gzip
 / 
2017.02.23 16:39:46.048 4: WEB_192.168.178.189_62117 POST /fhem?cmd.EG_Kueche_Steckdose_DAH=set%20EG_Kueche_Steckdose_DAH%20aus&room=Kueche&XHR=1&fw_id=129; BUFLEN:0
2017.02.23 16:39:46.049 3: FHEMWEB WEB CSRF error:  ne fhem_87443305400247. For detals see the csrfToken FHEMWEB attribute
2017.02.23 16:39:46.050 4: name: /fhem?cmd.EG_Kueche_Steckdose_DAH=set%20EG_Kueche_Steckdose_DAH%20aus&room=Kueche&XHR=1&fw_id=129 / RL:20 / text/html; charset=UTF-8 / Content-Encoding: gzip
 / 
2017.02.23 16:39:48.969 4: WEB_192.168.178.189_62117 GET /fhem?room=Kueche; BUFLEN:0
2017.02.23 16:39:49.028 4: name: /fhem?room=Kueche / RL:3212 / text/html; charset=UTF-8 / Content-Encoding: gzip
 / 
2017.02.23 16:39:49.030 4: Connection accepted from WEB_192.168.178.189_62151
2017.02.23 16:39:49.032 4: Connection accepted from WEB_192.168.178.189_62152
2017.02.23 16:39:49.033 4: Connection accepted from WEB_192.168.178.189_62153
2017.02.23 16:39:49.034 4: Connection accepted from WEB_192.168.178.189_62154
2017.02.23 16:39:49.035 4: Connection accepted from WEB_192.168.178.189_62155
2017.02.23 16:39:49.062 4: Connection closed for WEB_192.168.178.189_62145: EOF
2017.02.23 16:39:49.206 4: WEB_192.168.178.189_62117 GET /fhem/SVG_showLog?dev=SVG_FileLog_EG_Kueche_Steckdose_DAH_1&logdev=FileLog_EG_Kueche_Steckdose_DAH&gplotfile=SVG_FileLog_EG_Kueche_Steckdose_DAH_1&logfile=CURRENT&pos=; BUFLEN:0
2017.02.23 16:39:49.208 5: plotcommand: get FileLog_EG_Kueche_Steckdose_DAH CURRENT INT 2017-02-23_00:00:00 2017-02-24_00:00:01  4:EG_Kueche_Steckdose_DAH.power\x3a:: 4:EG_Kueche_Steckdose_DAH.energy\x3a::
2017.02.23 16:39:49.208 5: Cmd: >get FileLog_EG_Kueche_Steckdose_DAH CURRENT INT 2017-02-23_00:00:00 2017-02-24_00:00:01 4:EG_Kueche_Steckdose_DAH.power\x3a:: 4:EG_Kueche_Steckdose_DAH.energy\x3a::<
2017.02.23 16:39:49.209 4: FileLog_EG_Kueche_Steckdose_DAH get: Input file /opt/fhem/log/EG_Kueche_Steckdose_DAH-2017.log, from:2017-02-23_00:00:00  to:2017-02-24_00:00:01
2017.02.23 16:39:49.213 4: FileLog_EG_Kueche_Steckdose_DAH get: line 1, regexp:EG_Kueche_Steckdose_DAH.power\x3a, col:3, output lines:15
2017.02.23 16:39:49.213 4: FileLog_EG_Kueche_Steckdose_DAH get: line 2, regexp:EG_Kueche_Steckdose_DAH.energy\x3a, col:3, output lines:17
2017.02.23 16:39:49.214 5: Cmd: >{ "EG_Kueche_Steckdose_DAH-2017.log" }<
2017.02.23 16:39:49.223 4: name: /fhem/SVG_showLog?dev=SVG_FileLog_EG_Kueche_Steckdose_DAH_1&logdev=FileLog_EG_Kueche_Steckdose_DAH&gplotfile=SVG_FileLog_EG_Kueche_Steckdose_DAH_1&logfile=CURRENT&pos= / RL:2377 / image/svg+xml / Content-Encoding: gzip
 / 
2017.02.23 16:39:49.262 4: WEB_192.168.178.189_62117 GET /fhem/icons/favicon; BUFLEN:0
2017.02.23 16:39:49.299 4: Connection accepted from WEB_192.168.178.189_62157
2017.02.23 16:39:50.767 4: WEB_192.168.178.189_62117 POST /fhem?cmd.EG_Kueche_Steckdose_DAH=set%20EG_Kueche_Steckdose_DAH%20aus&room=Kueche&fwcsrf=fhem_87443305400247&XHR=1&fw_id=129; BUFLEN:0
2017.02.23 16:39:50.768 5: Cmd: >set EG_Kueche_Steckdose_DAH aus<
2017.02.23 16:39:50.769 3: ZWave set EG_Kueche_Steckdose_DAH off 
2017.02.23 16:39:50.770 5: ZWDongle_Write 001317032501002502 (dad62400)
2017.02.23 16:39:50.770 5: SW: 010a001317032501002502f1
2017.02.23 16:39:50.772 5: Starting notify loop for EG_Kueche_Steckdose_DAH, 1 event(s), first is aus
2017.02.23 16:39:50.772 5: createNotifyHash
2017.02.23 16:39:50.777 5: rgBatteryStatus: not on any display, ignoring notify
2017.02.23 16:39:50.778 5: rgZWaveComponents: not on any display, ignoring notify
2017.02.23 16:39:50.782 5: End notify loop for EG_Kueche_Steckdose_DAH
2017.02.23 16:39:50.782 4: name: /fhem?cmd.EG_Kueche_Steckdose_DAH=set%20EG_Kueche_Steckdose_DAH%20aus&room=Kueche&fwcsrf=fhem_87443305400247&XHR=1&fw_id=129 / RL:20 / text/plain; charset=UTF-8 / Content-Encoding: gzip
 / 
2017.02.23 16:39:50.783 5: ACK received, WaitForAck=>2 for 010a001317032501002502f1
2017.02.23 16:39:50.783 4: ZWDongle_Read ZWDongle_0: rcvd 011301 (answer ZW_SEND_DATA), sending ACK
2017.02.23 16:39:50.783 5: SW: 06
2017.02.23 16:39:50.785 5: ZWDongle_0: dispatch 011301
2017.02.23 16:39:50.817 4: ZWDongle_Read ZWDongle_0: rcvd 001302000004 (request ZW_SEND_DATA), sending ACK
2017.02.23 16:39:50.817 5: SW: 06
2017.02.23 16:39:50.818 5: device ack reveived, removing 010a001317032501002502f1 from dongle sendstack
2017.02.23 16:39:50.818 5: ZWDongle_0: dispatch 001302000004
2017.02.23 16:39:50.819 4: CMD:ZW_SEND_DATA ID:00 ARG:0004 CB:02
2017.02.23 16:39:50.819 4: ZWDongle_0 transmit OK for CB 02, target EG_Kueche_Steckdose_DAH
2017.02.23 16:39:54.511 4: WEB_192.168.178.189_62117 GET /fhem/FileLog_logWrapper?dev=Logfile&type=text&file=fhem-2017-02.log; BUFLEN:0

Gruß
throbin

rudolfkoenig · 23 Februar 2017, 17:18:47

ZitatMeinst Du Listing

Nein, sondern ueber welche Repeater. timeToAck von 2.6s beweist auch meine Vermutung, dass da mehrere involviert sind, als normal empfinde ich Werte zwischen 0.01s und 0.1s, 2.6s ist mir neu und zuviel. Interessant faende ich einen ZWCul Mitschnitt in diesem Fall.

Auch wenn das nicht sicher hilft: ich wuerde fuer alle benachbarten Geraete ein set neighborUpdate und danach ein get neighborList absetzen (siehe auch Show neighbor map in der ZWDongle Detail Ansicht), und mit noExplorerFrames fuer dieses Geraet experimentieren.

ZitatLog am alten Modell

Ich meinte eigentlich die alte FHEM-Version, habe deine Aussage "Vorgängermodell" falsch gelesen.
Dieses Geraet ist vmtl. ohne so viele Router angebunden, also nicht so recht interessant.

throbin · 23 Februar 2017, 17:59:07

Hi,

neighborUpdate /neighborList ausgeführt > die Liste der Teilnehmer ist um 4 gesunken (von 17 auf 13), soweit passt es. Die Devices, die am weitesten entfernt sind, sind verschwunden.

Attribut noExplorerFrames gesetzt > timeToAck ist deutlich heruntergegangen (bewegt sich zw. einigen Dutzenden ms und 1s).

Logfile sieht aber immer noch gleich aus:

Code Auswählen


2017.02.23 17:48:58.482 4: Connection accepted from WEB_192.168.178.236_61075
2017.02.23 17:49:04.776 4: WEB_192.168.178.236_61035 POST /fhem?cmd.DG_Heizraum_Switch_CP=set%20DG_Heizraum_Switch_CP%20on&fwcsrf=fhem_87443305400247&XHR=1&fw_id=315; BUFLEN:0
2017.02.23 17:49:04.777 5: Cmd: >set DG_Heizraum_Switch_CP on<
2017.02.23 17:49:04.781 3: ZWave set DG_Heizraum_Switch_CP on 
2017.02.23 17:49:04.782 5: ZWDongle_Write 00131e032501FF051c (dad62400)
2017.02.23 17:49:04.782 5: SW: 010a00131e032501FF051c39
2017.02.23 17:49:04.785 5: Starting notify loop for DG_Heizraum_Switch_CP, 1 event(s), first is on
2017.02.23 17:49:04.785 5: createNotifyHash
2017.02.23 17:49:04.794 5: rgBatteryStatus: not on any display, ignoring notify
2017.02.23 17:49:04.795 5: rgZWaveComponents: not on any display, ignoring notify
2017.02.23 17:49:04.803 5: End notify loop for DG_Heizraum_Switch_CP
2017.02.23 17:49:04.804 4: name: /fhem?cmd.DG_Heizraum_Switch_CP=set%20DG_Heizraum_Switch_CP%20on&fwcsrf=fhem_87443305400247&XHR=1&fw_id=315 / RL:20 / text/plain; charset=UTF-8 / Content-Encoding: gzip
 / 
2017.02.23 17:49:04.805 5: ACK received, WaitForAck=>2 for 010a00131e032501FF051c39
2017.02.23 17:49:04.806 4: ZWDongle_Read ZWDongle_0: rcvd 011301 (answer ZW_SEND_DATA), sending ACK
2017.02.23 17:49:04.806 5: SW: 06
2017.02.23 17:49:04.808 5: ZWDongle_0: dispatch 011301
2017.02.23 17:49:04.889 4: ZWDongle_Read ZWDongle_0: rcvd 00131c00000a (request ZW_SEND_DATA), sending ACK
2017.02.23 17:49:04.889 5: SW: 06
2017.02.23 17:49:04.890 5: device ack reveived, removing 010a00131e032501FF051c39 from dongle sendstack
2017.02.23 17:49:04.891 5: ZWDongle_0: dispatch 00131c00000a
2017.02.23 17:49:04.891 4: CMD:ZW_SEND_DATA ID:00 ARG:000a CB:1c
2017.02.23 17:49:04.892 4: ZWDongle_0 transmit OK for CB 1c, target DG_Heizraum_Switch_CP
2017.02.23 17:49:05.018 4: ZWDongle_Read ZWDongle_0: rcvd 0004001e032503ff (request APPLICATION_COMMAND_HANDLER), sending ACK
2017.02.23 17:49:05.018 5: SW: 06
2017.02.23 17:49:05.020 5: ZWDongle_0: dispatch 0004001e032503ff
2017.02.23 17:49:05.020 4: CMD:APPLICATION_COMMAND_HANDLER ID:1e ARG:032503ff CB:00
2017.02.23 17:49:05.022 5: Starting notify loop for DG_Heizraum_Switch_CP, 2 event(s), first is on
2017.02.23 17:49:05.025 5: rgBatteryStatus: not on any display, ignoring notify
2017.02.23 17:49:05.026 5: rgZWaveComponents: not on any display, ignoring notify
2017.02.23 17:49:05.034 5: End notify loop for DG_Heizraum_Switch_CP
2017.02.23 17:49:05.703 4: ZWDongle_Read ZWDongle_0: rcvd 0004001e063105042200da (request APPLICATION_COMMAND_HANDLER), sending ACK
2017.02.23 17:49:05.703 5: SW: 06
2017.02.23 17:49:05.705 5: ZWDongle_0: dispatch 0004001e063105042200da
2017.02.23 17:49:05.706 4: CMD:APPLICATION_COMMAND_HANDLER ID:1e ARG:063105042200da CB:00
2017.02.23 17:49:05.709 5: Starting notify loop for DG_Heizraum_Switch_CP, 1 event(s), first is power: 21.8 W
2017.02.23 17:49:05.712 5: rgBatteryStatus: not on any display, ignoring notify
2017.02.23 17:49:05.712 5: rgZWaveComponents: not on any display, ignoring notify
2017.02.23 17:49:05.720 5: End notify loop for DG_Heizraum_Switch_CP
2017.02.23 17:49:07.450 4: WEB_192.168.178.236_61035 POST /fhem?cmd.DG_Heizraum_Switch_CP=set%20DG_Heizraum_Switch_CP%20off&fwcsrf=fhem_87443305400247&XHR=1&fw_id=315; BUFLEN:0
2017.02.23 17:49:07.451 5: Cmd: >set DG_Heizraum_Switch_CP off<
2017.02.23 17:49:07.455 3: ZWave set DG_Heizraum_Switch_CP off 
2017.02.23 17:49:07.455 5: ZWDongle_Write 00131e03250100051d (dad62400)
2017.02.23 17:49:07.456 5: SW: 010a00131e03250100051dc7
2017.02.23 17:49:07.459 5: Starting notify loop for DG_Heizraum_Switch_CP, 1 event(s), first is off
2017.02.23 17:49:07.461 5: rgBatteryStatus: not on any display, ignoring notify
2017.02.23 17:49:07.462 5: rgZWaveComponents: not on any display, ignoring notify
2017.02.23 17:49:07.470 5: End notify loop for DG_Heizraum_Switch_CP
2017.02.23 17:49:07.471 4: name: /fhem?cmd.DG_Heizraum_Switch_CP=set%20DG_Heizraum_Switch_CP%20off&fwcsrf=fhem_87443305400247&XHR=1&fw_id=315 / RL:20 / text/plain; charset=UTF-8 / Content-Encoding: gzip
 / 
2017.02.23 17:49:07.472 5: ACK received, WaitForAck=>2 for 010a00131e03250100051dc7
2017.02.23 17:49:07.473 4: ZWDongle_Read ZWDongle_0: rcvd 011301 (answer ZW_SEND_DATA), sending ACK
2017.02.23 17:49:07.473 5: SW: 06
2017.02.23 17:49:07.475 5: ZWDongle_0: dispatch 011301
2017.02.23 17:49:07.507 4: ZWDongle_Read ZWDongle_0: rcvd 00131d000005 (request ZW_SEND_DATA), sending ACK
2017.02.23 17:49:07.508 5: SW: 06
2017.02.23 17:49:07.509 5: device ack reveived, removing 010a00131e03250100051dc7 from dongle sendstack
2017.02.23 17:49:07.510 5: ZWDongle_0: dispatch 00131d000005
2017.02.23 17:49:07.510 4: CMD:ZW_SEND_DATA ID:00 ARG:0005 CB:1d
2017.02.23 17:49:07.511 4: ZWDongle_0 transmit OK for CB 1d, target DG_Heizraum_Switch_CP
2017.02.23 17:49:07.577 4: ZWDongle_Read ZWDongle_0: rcvd 0004001e03250300 (request APPLICATION_COMMAND_HANDLER), sending ACK
2017.02.23 17:49:07.578 5: SW: 06
2017.02.23 17:49:07.580 5: ZWDongle_0: dispatch 0004001e03250300
2017.02.23 17:49:07.580 4: CMD:APPLICATION_COMMAND_HANDLER ID:1e ARG:03250300 CB:00
2017.02.23 17:49:07.582 5: Starting notify loop for DG_Heizraum_Switch_CP, 2 event(s), first is off
2017.02.23 17:49:07.585 5: rgBatteryStatus: not on any display, ignoring notify
2017.02.23 17:49:07.586 5: rgZWaveComponents: not on any display, ignoring notify
2017.02.23 17:49:07.594 5: End notify loop for DG_Heizraum_Switch_CP
2017.02.23 17:49:07.630 4: ZWDongle_Read ZWDongle_0: rcvd 0004001e03250300 (request APPLICATION_COMMAND_HANDLER), sending ACK
2017.02.23 17:49:07.630 5: SW: 06
2017.02.23 17:49:07.632 5: ZWDongle_0: dispatch 0004001e03250300
2017.02.23 17:49:07.633 4: CMD:APPLICATION_COMMAND_HANDLER ID:1e ARG:03250300 CB:00
2017.02.23 17:49:07.635 5: Starting notify loop for DG_Heizraum_Switch_CP, 2 event(s), first is off
2017.02.23 17:49:07.638 5: rgBatteryStatus: not on any display, ignoring notify
2017.02.23 17:49:07.638 5: rgZWaveComponents: not on any display, ignoring notify
2017.02.23 17:49:07.646 5: End notify loop for DG_Heizraum_Switch_CP
2017.02.23 17:49:08.303 4: ZWDongle_Read ZWDongle_0: rcvd 0004001e06310504220000 (request APPLICATION_COMMAND_HANDLER), sending ACK
2017.02.23 17:49:08.303 5: SW: 06
2017.02.23 17:49:08.305 5: ZWDongle_0: dispatch 0004001e06310504220000
2017.02.23 17:49:08.306 4: CMD:APPLICATION_COMMAND_HANDLER ID:1e ARG:06310504220000 CB:00
2017.02.23 17:49:08.309 5: Starting notify loop for DG_Heizraum_Switch_CP, 1 event(s), first is power: 0.0 W
2017.02.23 17:49:08.312 5: rgBatteryStatus: not on any display, ignoring notify
2017.02.23 17:49:08.312 5: rgZWaveComponents: not on any display, ignoring notify
2017.02.23 17:49:08.320 5: End notify loop for DG_Heizraum_Switch_CP
2017.02.23 17:49:08.364 4: ZWDongle_Read ZWDongle_0: rcvd 0004001e06310504220000 (request APPLICATION_COMMAND_HANDLER), sending ACK
2017.02.23 17:49:08.364 5: SW: 06
2017.02.23 17:49:08.366 5: ZWDongle_0: dispatch 0004001e06310504220000
2017.02.23 17:49:08.367 4: CMD:APPLICATION_COMMAND_HANDLER ID:1e ARG:06310504220000 CB:00
2017.02.23 17:49:08.370 5: Starting notify loop for DG_Heizraum_Switch_CP, 1 event(s), first is power: 0.0 W
2017.02.23 17:49:08.373 5: rgBatteryStatus: not on any display, ignoring notify
2017.02.23 17:49:08.373 5: rgZWaveComponents: not on any display, ignoring notify
2017.02.23 17:49:08.381 5: End notify loop for DG_Heizraum_Switch_CP
2017.02.23 17:49:08.653 4: WEB_192.168.178.236_61035 GET /fhem?detail=DG_Heizraum_Switch_CP&fw_id=; BUFLEN:0
2017.02.23 17:49:08.702 4: name: /fhem?detail=DG_Heizraum_Switch_CP&fw_id= / RL:6463 / text/html; charset=UTF-8 / Content-Encoding: gzip
 / 
2017.02.23 17:49:08.702 4: ZWDongle_Read ZWDongle_0: rcvd 0004001e06310504220000 (request APPLICATION_COMMAND_HANDLER), sending ACK
2017.02.23 17:49:08.703 5: SW: 06
2017.02.23 17:49:08.704 5: ZWDongle_0: dispatch 0004001e06310504220000
2017.02.23 17:49:08.704 4: CMD:APPLICATION_COMMAND_HANDLER ID:1e ARG:06310504220000 CB:00
2017.02.23 17:49:08.706 5: Starting notify loop for DG_Heizraum_Switch_CP, 1 event(s), first is power: 0.0 W
2017.02.23 17:49:08.708 5: rgBatteryStatus: not on any display, ignoring notify
2017.02.23 17:49:08.708 5: rgZWaveComponents: not on any display, ignoring notify
2017.02.23 17:49:08.712 5: End notify loop for DG_Heizraum_Switch_CP
2017.02.23 17:49:08.720 4: Connection closed for WEB_192.168.178.236_61063: EOF

ZitatInteressant faende ich einen ZWCul Mitschnitt in diesem Fall

Kann ich machen, sofern ich die nötige Ausrüstung habe, wie stelle ich das an?

Gruß
throbin

rudolfkoenig · 23 Februar 2017, 19:05:18

ZitatLogfile sieht aber immer noch gleich aus:

Von wegen: nur noch drei Wiederholungen, und kein CB:10 . Das ist 400% weniger

ZitatKann ich machen, sofern ich die nötige Ausrüstung habe, wie stelle ich das an?

Man braucht ein CUL (oder Variante), was man (temporaer) dafuer abstellt. Bei CUL_V3 mit dieser Datei flashen (bei anderen Varianten mich fragen), danach eine separate FHEM-Instanz mit folgenden fhem.cfg starten:

Code Auswählen

attr global logfile -
attr global modpath .
attr global mseclog 1
define zwc ZWCUL /dev/cu.usbmodemfd141 00000000 01
attr zwc dataRate 40k
attr zwc verbose 5

Falls man damit nichts empfaengt, die Datenrate von 40k auf 100k aendern, und nochmal versuchen.
Mit 00000000 wir das CUL in Monitor mode gesetzt, und protokolliert alle Netzwerkpakete auf stdout mit.

throbin · 23 Februar 2017, 20:27:15

Hi, Ok, sowas habe ich leider nicht in der Schublade, muss mal sehen ob ich eines auftreiben kann. Alternativ kann ich den Stecker in einem anderen Netz testen, vielleicht verhält sich der dort anders. Ich werde dann berichten.

Danke!

Gruß
throbin