FHEM Forum

FHEM - Hausautomations-Systeme => Homematic => Thema gestartet von: Eniac am 09 November 2013, 15:13:14

Titel: Probleme beim Schalten mehrerer HM-Aktoren
Beitrag von: Eniac am 09 November 2013, 15:13:14
Hallo zusammen,

Ich habe in einer Struktur drei Rollo-Aktoren zusammengeschaltet und habe über einen Notify morgens und abends einen Timer definiert, zu dem alle Aktoren der Struktur hoch- bzw. runterfahren.
Bisher lief das auch ganz wunderbar, aber seit ein paar Tagen reagieren von den drei Aktoren immer nur zwei (zufällige) Rollos. Wenn ich den Befehl dann manuell nachhole, klappt es in der Regel dann auch beim Dritten.

ich vermute, dass hier seit dem letzten Update (5.5) ein Problem  bzw. zumindest ein anderes Verhalten auftritt, wahrscheinlich hat sich irgendetwas mit dem Timing geändert. Laut Eventlog werden in FHEM alle drei Events gesendet, aber es werden bei den Akteuren nur zwei verarbeitet. Die Signalstärke ist auch gut, vor allem sind es ja auch immer andere Rollos, die "vergessen" werden.

Habt ihr auch solche Schwierigkeiten oder eine Idee, wie ich hier das Problem löse (z.B. die Aktoren nacheinander per Skript steuern und zwischen jedem Befehl eine Wartezeit einbauen)?

Vielen Dank schonmal!

Eniac
Titel: Antw:Probleme beim Schalten mehrerer HM-Aktoren
Beitrag von: chicony am 13 November 2013, 13:31:22
Hallo Eniac,
kann das verhalten bestätigen, habe auch fhem 5.5 + CUL + 7390 (version $Id: fhem.pl 4189 2013-11-09 13:46:31Z rudolfkoenig $, os linux, user fhem, pid 2074).
Ich schalte diverse HM Unterputz-Schaltaktoren (1fach und 2fach) zu bestimmten tageszeiten mit z.B.:

at *23:32:27 set Licht_Terrasse,Pumpe,Licht_Auffahrt,Licht_Haustuer,Licht_Weg off

funktionierte eigentlich sehr zuverlässig, mittlerweile bleibt einer oder mehrere der Aktoren auf "set_off" stehen (bekommt keine Bestätigung). Manuell schalten im Floorplan oder Fhemweb ergibt das gleiche Bild: mal geht es mal nicht.

Die 10_CUL_HM.pm hatte ich mal gegen eine älter getauscht (10_CUL_HM.pm 2920 2013-03-15 19:16:02Z martinp876 $), leider keine Besserung.
Als nächstes werde ich mal fhem 5.4 testen, vielleicht liegt es an der perl-version ?

mfg
chicony 

Titel: Antw:Probleme beim Schalten mehrerer HM-Aktoren
Beitrag von: Eniac am 16 November 2013, 11:46:05
Hallo chicony,

wäre klasse, wenn du mal hier im Forum Feedback gibst, ob der Downgrade etwas gebracht hat.
Was nutzt du denn für eine HW für FHEM? Ich bin auf einer Synology, aber da gab es eigentlich kein Update, mit dem das zusammenhängen könnte...

Viele Grüße
Eniac
Titel: Antw:Probleme beim Schalten mehrerer HM-Aktoren
Beitrag von: chicony am 16 November 2013, 16:24:04
Hallo Eniac,
leider hat das downgrade auf fhem 5.4 nichts gebracht.
Momentan habe ich das "Funkfeuer" beim schalten vieler aktoren gleichzeitig im verdacht, die
zwischenlösung für mich ist eine "pause" zwischen den schaltvorgängen einzufügen :

*23:29:27 set Licht_Terrasse off;sleep 0.1;set Strom_WG off;sleep 0.1;set Licht_Haustuer off;sleep 0.1;set Strom_Pool off

läuft momentan relativ gut, werde nächste woche nochmal meine erfahrung berichten.

Eines ist mir noch aufgefallen : beim manuell schalten ist die erfolgsquote ca. 80-90%, bei einem "staus request" bei 99%.
mfg chicony
Titel: Antw:Probleme beim Schalten mehrerer HM-Aktoren
Beitrag von: martinp876 am 19 November 2013, 14:25:18
könnt ihr einmal die rohmessages aufzeichnen? Wäre gut zu sehen was abgeht.

Das sleep kann ich nicht empfehlen - das blockiert das ganze fhem. Die lösung sollte anders sein.

Gruss Martin
Titel: Antw:Probleme beim Schalten mehrerer HM-Aktoren
Beitrag von: Eniac am 19 November 2013, 15:34:22
Kann ich gerne machen. Brauchst du die Logfiles oder muss ich hier speziell noch etwas aktivieren?

Viele Grüße
Eniac
Titel: Antw:Probleme beim Schalten mehrerer HM-Aktoren
Beitrag von: martinp876 am 19 November 2013, 15:49:56
Hallo Eniac,
einfach die roh-logs wenn du die problematische Sequenz auslöst.
Hilfreich könnte eine kurze Beschreibung sein, welche Aktoren(model) du schaltest.
Gruss Martin
Titel: Antw:Probleme beim Schalten mehrerer HM-Aktoren
Beitrag von: chicony am 20 November 2013, 14:45:22
Hallo,

leider hat das mit den "Pausen" zwischen den Schaltvorgängen nichts gebracht.

diese 5 funktionieren sehr gut und zuverlässig
2 x HM-LC-Dim1PBU-FM  (Unterputz Universal Dimmer)
2 x HM-LC-Sw1-Pl           (Zwischenstecker)
1 x HM-LC-Bl1PBU-FM     (Unterputz Universal Jalousieaktor)

diese 4 sind mein problem:
2 x HM-LC-Sw1-FM          (Unterputzaktor 1fach, Terrasse, Pool)
2 x HM-LC-Sw2-FM          (Unterputzaktor 2fach, Auffahrt/Treppe, Haustuer/Weg)

Alle Aktoren werden mit einem "at" angestoßen und immer wieder hat einer der 4 Problemfälle bzw. der 6 Kanäle
nicht geschaltet. Manuelles schalten im Floorplan/Fhemweb ergibt das gleiche Bild.

Jetzt wollte ich die "roh-logs" aufnehmen, wie hier beschrieben
attr global verbose 1
attr global mseclog 1
attr <hmlan> logIDs all,sys (kann ich nicht finden)

werde jetzt einfach mal mit verbose 5 aufnehmen.


2013.11.20 14:25:00.002 5: exec at command morgens_an
2013.11.20 14:25:00.005 5: Cmd: >{ if ($wday) { fhem("set Licht_Haustuer,Licht_Auffahrt,Licht_Weg,Pumpe,Licht_Terrasse,Licht_Treppe,Strom_Pool,Strom_WG on")}}<
2013.11.20 14:25:00.010 5: Cmd: >set Licht_Haustuer,Licht_Auffahrt,Licht_Weg,Pumpe,Licht_Terrasse,Licht_Treppe,Strom_Pool,Strom_WG on<
2013.11.20 14:25:00.017 5: Triggering Licht_Haustuer (1 changes)
2013.11.20 14:25:00.023 5: Notify loop for Licht_Haustuer set_on
2013.11.20 14:25:00.084 2: CUL_HM set Licht_Haustuer on
2013.11.20 14:25:00.087 5: CUL sending As0E57A011F112341ABCB50201C80000
2013.11.20 14:25:00.088 5: SW: As0E57A011F112341ABCB50201C80000
2013.11.20 14:25:00.104 4: SND L:0E N:57 F:A0 CMD:11 SRC:F11234 DST:Licht_Sockel 0201C80000 (SET CHANNEL:0x01 VALUE:0xC8 RAMPTIME:0x0000) (,BIDI,RPTEN)
2013.11.20 14:25:00.110 5: Triggering Licht_Auffahrt (1 changes)
2013.11.20 14:25:00.115 5: Notify loop for Licht_Auffahrt set_on
2013.11.20 14:25:00.175 2: CUL_HM set Licht_Auffahrt on
2013.11.20 14:25:00.178 5: CUL sending As0E58A011F112341BC6E50201C80000
2013.11.20 14:25:00.183 4: SND L:0E N:58 F:A0 CMD:11 SRC:F11234 DST:Licht_Auffahrt_Treppe 0201C80000 (SET CHANNEL:0x01 VALUE:0xC8 RAMPTIME:0x0000) (,BIDI,RPTEN)
2013.11.20 14:25:00.187 5: Triggering Licht_Weg (1 changes)
2013.11.20 14:25:00.193 5: Notify loop for Licht_Weg set_on
2013.11.20 14:25:00.252 2: CUL_HM set Licht_Weg on
2013.11.20 14:25:00.255 5: CUL sending As0E59A011F112341ABCB50202C80000
2013.11.20 14:25:00.260 4: SND L:0E N:59 F:A0 CMD:11 SRC:F11234 DST:Licht_Sockel 0202C80000 (SET CHANNEL:0x02 VALUE:0xC8 RAMPTIME:0x0000) (,BIDI,RPTEN)
2013.11.20 14:25:00.264 5: Triggering Pumpe (1 changes)
2013.11.20 14:25:00.270 5: Notify loop for Pumpe set_on
2013.11.20 14:25:00.330 2: CUL_HM set Pumpe on
2013.11.20 14:25:00.332 5: CUL sending As0E5AA011F1123415B1BC0201C80000
2013.11.20 14:25:00.336 4: SND L:0E N:5A F:A0 CMD:11 SRC:F11234 DST:Pumpe 0201C80000 (SET CHANNEL:0x01 VALUE:0xC8 RAMPTIME:0x0000) (,BIDI,RPTEN)
2013.11.20 14:25:00.341 5: Triggering Licht_Terrasse (1 changes)
2013.11.20 14:25:00.346 5: Notify loop for Licht_Terrasse set_on
2013.11.20 14:25:00.406 2: CUL_HM set Licht_Terrasse on
2013.11.20 14:25:00.417 5: CUL sending As0E5BA011F11234192F7A0201C80000
2013.11.20 14:25:00.428 4: SND L:0E N:5B F:A0 CMD:11 SRC:F11234 DST:Licht_Terrasse 0201C80000 (SET CHANNEL:0x01 VALUE:0xC8 RAMPTIME:0x0000) (,BIDI,RPTEN)
2013.11.20 14:25:00.432 5: Triggering Licht_Treppe (1 changes)
2013.11.20 14:25:00.438 5: Notify loop for Licht_Treppe set_on
2013.11.20 14:25:00.497 2: CUL_HM set Licht_Treppe on
2013.11.20 14:25:00.499 5: CUL sending As0E5CA011F112341BC6E50202C80000
2013.11.20 14:25:00.505 4: SND L:0E N:5C F:A0 CMD:11 SRC:F11234 DST:Licht_Auffahrt_Treppe 0202C80000 (SET CHANNEL:0x02 VALUE:0xC8 RAMPTIME:0x0000) (,BIDI,RPTEN)
2013.11.20 14:25:00.509 5: Triggering Strom_Pool (1 changes)
2013.11.20 14:25:00.514 5: Notify loop for Strom_Pool set_on
2013.11.20 14:25:00.574 2: CUL_HM set Strom_Pool on
2013.11.20 14:25:00.577 5: CUL sending As0E5DA011F112341B33CB0201C80000
2013.11.20 14:25:00.581 4: SND L:0E N:5D F:A0 CMD:11 SRC:F11234 DST:Strom_Pool 0201C80000 (SET CHANNEL:0x01 VALUE:0xC8 RAMPTIME:0x0000) (,BIDI,RPTEN)
2013.11.20 14:25:00.586 5: Triggering Strom_WG (1 changes)
2013.11.20 14:25:00.592 5: Notify loop for Strom_WG set_on
2013.11.20 14:25:00.652 2: CUL_HM set Strom_WG on
2013.11.20 14:25:00.654 5: CUL sending As0E5EA011F112341ECD8E0201C80000
2013.11.20 14:25:00.658 4: SND L:0E N:5E F:A0 CMD:11 SRC:F11234 DST:Strom_WG 0201C80000 (SET CHANNEL:0x01 VALUE:0xC8 RAMPTIME:0x0000) (,BIDI,RPTEN)
2013.11.20 14:25:00.660 5: redefine at command morgens_an as *14:25:00 { if ($wday) { fhem("set Licht_Haustuer,Licht_Auffahrt,Licht_Weg,Pumpe,Licht_Terrasse,Licht_Treppe,Strom_Pool,Strom_WG on")}}
2013.11.20 14:25:00.667 5: SW: As0E58A011F112341BC6E50201C80000
2013.11.20 14:25:00.682 4: HTTP FHEMWEB:192.168.111.100:2106 GET /fhem/images/default/set_on.png
2013.11.20 14:25:00.819 5: CUL/RAW: /A0E5880021BC6E5F112340101C8002C33

2013.11.20 14:25:00.820 5: CUL: A0E5880021BC6E5F112340101C8002C -48.5
2013.11.20 14:25:00.822 5: CUL dispatch A0E5880021BC6E5F112340101C8002C::-48.5:CUL
2013.11.20 14:25:00.828 4: RCV L:0E N:58 F:80 CMD:02 SRC:Licht_Auffahrt_Treppe DST:F11234 0101C8002C (ACK_STATUS CHANNEL:0x01 STATUS:0xC8 UP:0x00 DOWN:0x00 LOWBAT:0x00 RSSI:0x2C) (,RPTEN)
2013.11.20 14:25:00.839 5: Triggering Licht_Auffahrt (5 changes)
2013.11.20 14:25:00.844 5: Notify loop for Licht_Auffahrt level: 100 %
2013.11.20 14:25:00.920 5: SW: As0E59A011F112341ABCB50202C80000
2013.11.20 14:25:00.938 4: HTTP FHEMWEB:192.168.111.100:2096 GET /fhem/images/default/Licht_Auffahrt.on.png
2013.11.20 14:25:01.071 5: CUL/RAW: /A0E5980021ABCB5F112340102C8003C1C

2013.11.20 14:25:01.073 5: CUL: A0E5980021ABCB5F112340102C8003C -60
2013.11.20 14:25:01.075 5: CUL dispatch A0E5980021ABCB5F112340102C8003C::-60:CUL
2013.11.20 14:25:01.081 4: RCV L:0E N:59 F:80 CMD:02 SRC:Licht_Sockel DST:F11234 0102C8003C (ACK_STATUS CHANNEL:0x02 STATUS:0xC8 UP:0x00 DOWN:0x00 LOWBAT:0x00 RSSI:0x3C) (,RPTEN)
2013.11.20 14:25:01.091 5: Triggering Licht_Weg (5 changes)
2013.11.20 14:25:01.096 5: Notify loop for Licht_Weg level: 100 %
2013.11.20 14:25:01.176 5: SW: As0E5AA011F1123415B1BC0201C80000
2013.11.20 14:25:01.195 4: HTTP FHEMWEB:192.168.111.100:2100 GET /fhem/images/default/Licht_Weg.on.png
2013.11.20 14:25:01.327 5: CUL/RAW: /A0E5A800215B1BCF112340101C8003817

2013.11.20 14:25:01.329 5: CUL: A0E5A800215B1BCF112340101C80038 -62.5
2013.11.20 14:25:01.331 5: CUL dispatch A0E5A800215B1BCF112340101C80038::-62.5:CUL
2013.11.20 14:25:01.337 4: RCV L:0E N:5A F:80 CMD:02 SRC:Pumpe DST:F11234 0101C80038 (ACK_STATUS CHANNEL:0x01 STATUS:0xC8 UP:0x00 DOWN:0x00 LOWBAT:0x00 RSSI:0x38) (,RPTEN)
2013.11.20 14:25:01.346 5: Triggering Pumpe (5 changes)
2013.11.20 14:25:01.352 5: Notify loop for Pumpe level: 100 %
2013.11.20 14:25:01.445 5: SW: As0E5BA011F11234192F7A0201C80000
2013.11.20 14:25:01.466 4: HTTP FHEMWEB:192.168.111.100:2104 GET /fhem/images/default/Pumpe.on.png
2013.11.20 14:25:01.597 5: CUL/RAW: /A0E5B8002192F7AF112340101C80050F1

2013.11.20 14:25:01.599 5: CUL: A0E5B8002192F7AF112340101C80050 -81.5
2013.11.20 14:25:01.601 5: CUL dispatch A0E5B8002192F7AF112340101C80050::-81.5:CUL
2013.11.20 14:25:01.607 4: RCV L:0E N:5B F:80 CMD:02 SRC:Licht_Terrasse DST:F11234 0101C80050 (ACK_STATUS CHANNEL:0x01 STATUS:0xC8 UP:0x00 DOWN:0x00 LOWBAT:0x00 RSSI:0x50) (,RPTEN)
2013.11.20 14:25:01.616 5: Triggering Licht_Terrasse (5 changes)
2013.11.20 14:25:01.622 5: Notify loop for Licht_Terrasse level: 100 %
2013.11.20 14:25:01.701 5: SW: As0E5CA011F112341BC6E50202C80000
2013.11.20 14:25:01.720 4: HTTP FHEMWEB:192.168.111.100:2095 GET /fhem/images/default/Licht_Terrasse.on.png
2013.11.20 14:25:01.851 5: CUL/RAW: /A0E5C80021BC6E5F112340102C8002F2F

2013.11.20 14:25:01.853 5: CUL: A0E5C80021BC6E5F112340102C8002F -50.5
2013.11.20 14:25:01.855 5: CUL dispatch A0E5C80021BC6E5F112340102C8002F::-50.5:CUL
2013.11.20 14:25:01.861 4: RCV L:0E N:5C F:80 CMD:02 SRC:Licht_Auffahrt_Treppe DST:F11234 0102C8002F (ACK_STATUS CHANNEL:0x02 STATUS:0xC8 UP:0x00 DOWN:0x00 LOWBAT:0x00 RSSI:0x2F) (,RPTEN)
2013.11.20 14:25:01.869 5: Triggering Licht_Treppe (5 changes)
2013.11.20 14:25:01.875 5: Notify loop for Licht_Treppe level: 100 %
2013.11.20 14:25:01.951 5: SW: As0E5DA011F112341B33CB0201C80000
2013.11.20 14:25:01.970 4: HTTP FHEMWEB:192.168.111.100:2106 GET /fhem/images/default/Licht_Treppe.on.png
2013.11.20 14:25:02.115 5: SW: As0E5EA011F112341ECD8E0201C80000
2013.11.20 14:25:02.266 5: CUL/RAW: /A0E5E80021ECD8EF112340101C800391C

2013.11.20 14:25:02.268 5: CUL: A0E5E80021ECD8EF112340101C80039 -60
2013.11.20 14:25:02.270 5: CUL dispatch A0E5E80021ECD8EF112340101C80039::-60:CUL
2013.11.20 14:25:02.276 4: RCV L:0E N:5E F:80 CMD:02 SRC:Strom_WG DST:F11234 0101C80039 (ACK_STATUS CHANNEL:0x01 STATUS:0xC8 UP:0x00 DOWN:0x00 LOWBAT:0x00 RSSI:0x39) (,RPTEN)
2013.11.20 14:25:02.286 5: Triggering Strom_WG (5 changes)
2013.11.20 14:25:02.291 5: Notify loop for Strom_WG level: 100 %
2013.11.20 14:25:02.373 4: HTTP FHEMWEB:192.168.111.100:2096 GET /fhem/images/default/Strom_WG.on.png
2013.11.20 14:25:13.148 4: Connection closed for FHEMWEB:192.168.111.100:2105
2013.11.20 14:25:13.153 4: HTTP FHEMWEB:192.168.111.100:2100 GET /fhem/FileLog_logWrapper?dev=Logfile&type=text&file=fhem-2013-11.log


Das Licht am Pool und an der Haustuer wurden nicht geschaltet (siehe Anhang), hoffe du kannst dadrin etwas brauchbares finden.

vielen dank im voraus
chicony


Titel: Antw:Probleme beim Schalten mehrerer HM-Aktoren
Beitrag von: chicony am 20 November 2013, 15:56:18
hallo,

habe noch einen versuch gespeichert :

2013.11.20 15:43:00.002 5: exec at command morgens_an
2013.11.20 15:43:00.005 5: Cmd: >{ if ($wday) { fhem("set Licht_Haustuer,Licht_Auffahrt,Licht_Weg,Pumpe,Licht_Terrasse,Licht_Treppe,Strom_Pool,Strom_WG on")}}<
2013.11.20 15:43:00.010 5: Cmd: >set Licht_Haustuer,Licht_Auffahrt,Licht_Weg,Pumpe,Licht_Terrasse,Licht_Treppe,Strom_Pool,Strom_WG on<
2013.11.20 15:43:00.017 5: Triggering Licht_Haustuer (1 changes)
2013.11.20 15:43:00.022 5: Notify loop for Licht_Haustuer set_on
2013.11.20 15:43:00.081 2: CUL_HM set Licht_Haustuer on
2013.11.20 15:43:00.083 5: CUL sending As0E82A011F112341ABCB50201C80000
2013.11.20 15:43:00.085 5: SW: As0E82A011F112341ABCB50201C80000
2013.11.20 15:43:00.101 4: SND L:0E N:82 F:A0 CMD:11 SRC:F11234 DST:Licht_Sockel 0201C80000 (SET CHANNEL:0x01 VALUE:0xC8 RAMPTIME:0x0000) (,BIDI,RPTEN)
2013.11.20 15:43:00.107 5: Triggering Licht_Auffahrt (1 changes)
2013.11.20 15:43:00.113 5: Notify loop for Licht_Auffahrt set_on
2013.11.20 15:43:00.178 2: CUL_HM set Licht_Auffahrt on
2013.11.20 15:43:00.181 5: CUL sending As0E83A011F112341BC6E50201C80000
2013.11.20 15:43:00.186 4: SND L:0E N:83 F:A0 CMD:11 SRC:F11234 DST:Licht_Auffahrt_Treppe 0201C80000 (SET CHANNEL:0x01 VALUE:0xC8 RAMPTIME:0x0000) (,BIDI,RPTEN)
2013.11.20 15:43:00.190 5: Triggering Licht_Weg (1 changes)
2013.11.20 15:43:00.196 5: Notify loop for Licht_Weg set_on
2013.11.20 15:43:00.255 2: CUL_HM set Licht_Weg on
2013.11.20 15:43:00.258 5: CUL sending As0E84A011F112341ABCB50202C80000
2013.11.20 15:43:00.263 4: SND L:0E N:84 F:A0 CMD:11 SRC:F11234 DST:Licht_Sockel 0202C80000 (SET CHANNEL:0x02 VALUE:0xC8 RAMPTIME:0x0000) (,BIDI,RPTEN)
2013.11.20 15:43:00.268 5: Triggering Pumpe (1 changes)
2013.11.20 15:43:00.273 5: Notify loop for Pumpe set_on
2013.11.20 15:43:00.335 2: CUL_HM set Pumpe on
2013.11.20 15:43:00.337 5: CUL sending As0E85A011F1123415B1BC0201C80000
2013.11.20 15:43:00.341 4: SND L:0E N:85 F:A0 CMD:11 SRC:F11234 DST:Pumpe 0201C80000 (SET CHANNEL:0x01 VALUE:0xC8 RAMPTIME:0x0000) (,BIDI,RPTEN)
2013.11.20 15:43:00.346 5: Triggering Licht_Terrasse (1 changes)
2013.11.20 15:43:00.352 5: Notify loop for Licht_Terrasse set_on
2013.11.20 15:43:00.414 2: CUL_HM set Licht_Terrasse on
2013.11.20 15:43:00.416 5: CUL sending As0E86A011F11234192F7A0201C80000
2013.11.20 15:43:00.420 4: SND L:0E N:86 F:A0 CMD:11 SRC:F11234 DST:Licht_Terrasse 0201C80000 (SET CHANNEL:0x01 VALUE:0xC8 RAMPTIME:0x0000) (,BIDI,RPTEN)
2013.11.20 15:43:00.424 5: Triggering Licht_Treppe (1 changes)
2013.11.20 15:43:00.430 5: Notify loop for Licht_Treppe set_on
2013.11.20 15:43:00.490 2: CUL_HM set Licht_Treppe on
2013.11.20 15:43:00.492 5: CUL sending As0E87A011F112341BC6E50202C80000
2013.11.20 15:43:00.497 4: SND L:0E N:87 F:A0 CMD:11 SRC:F11234 DST:Licht_Auffahrt_Treppe 0202C80000 (SET CHANNEL:0x02 VALUE:0xC8 RAMPTIME:0x0000) (,BIDI,RPTEN)
2013.11.20 15:43:00.502 5: Triggering Strom_Pool (1 changes)
2013.11.20 15:43:00.508 5: Notify loop for Strom_Pool set_on
2013.11.20 15:43:00.567 2: CUL_HM set Strom_Pool on
2013.11.20 15:43:00.569 5: CUL sending As0E88A011F112341B33CB0201C80000
2013.11.20 15:43:00.573 4: SND L:0E N:88 F:A0 CMD:11 SRC:F11234 DST:Strom_Pool 0201C80000 (SET CHANNEL:0x01 VALUE:0xC8 RAMPTIME:0x0000) (,BIDI,RPTEN)
2013.11.20 15:43:00.578 5: Triggering Strom_WG (1 changes)
2013.11.20 15:43:00.583 5: Notify loop for Strom_WG set_on
2013.11.20 15:43:00.643 2: CUL_HM set Strom_WG on
2013.11.20 15:43:00.646 5: CUL sending As0E89A011F112341ECD8E0201C80000
2013.11.20 15:43:00.650 4: SND L:0E N:89 F:A0 CMD:11 SRC:F11234 DST:Strom_WG 0201C80000 (SET CHANNEL:0x01 VALUE:0xC8 RAMPTIME:0x0000) (,BIDI,RPTEN)
2013.11.20 15:43:00.652 5: redefine at command morgens_an as *15:43:00 { if ($wday) { fhem("set Licht_Haustuer,Licht_Auffahrt,Licht_Weg,Pumpe,Licht_Terrasse,Licht_Treppe,Strom_Pool,Strom_WG on")}}
2013.11.20 15:43:00.659 5: SW: As0E83A011F112341BC6E50201C80000
2013.11.20 15:43:00.674 4: Connection accepted from FHEMWEB:192.168.111.100:1081
2013.11.20 15:43:00.810 5: CUL/RAW: /A0E8380021BC6E5F112340101C8002C32

2013.11.20 15:43:00.812 5: CUL: A0E8380021BC6E5F112340101C8002C -49
2013.11.20 15:43:00.814 5: CUL dispatch A0E8380021BC6E5F112340101C8002C::-49:CUL
2013.11.20 15:43:00.820 4: RCV L:0E N:83 F:80 CMD:02 SRC:Licht_Auffahrt_Treppe DST:F11234 0101C8002C (ACK_STATUS CHANNEL:0x01 STATUS:0xC8 UP:0x00 DOWN:0x00 LOWBAT:0x00 RSSI:0x2C) (,RPTEN)
2013.11.20 15:43:00.830 5: Triggering Licht_Auffahrt (5 changes)
2013.11.20 15:43:00.836 5: Notify loop for Licht_Auffahrt level: 100 %
2013.11.20 15:43:00.915 5: SW: As0E84A011F112341ABCB50202C80000
2013.11.20 15:43:00.934 4: HTTP FHEMWEB:192.168.111.100:1081 GET /fhem/images/default/Licht_Auffahrt.on.png
2013.11.20 15:43:01.066 5: CUL/RAW: /A0E8480021ABCB5F112340102C8003D19

2013.11.20 15:43:01.068 5: CUL: A0E8480021ABCB5F112340102C8003D -61.5
2013.11.20 15:43:01.070 5: CUL dispatch A0E8480021ABCB5F112340102C8003D::-61.5:CUL
2013.11.20 15:43:01.076 4: RCV L:0E N:84 F:80 CMD:02 SRC:Licht_Sockel DST:F11234 0102C8003D (ACK_STATUS CHANNEL:0x02 STATUS:0xC8 UP:0x00 DOWN:0x00 LOWBAT:0x00 RSSI:0x3D) (,RPTEN)
2013.11.20 15:43:01.086 5: Triggering Licht_Weg (5 changes)
2013.11.20 15:43:01.092 5: Notify loop for Licht_Weg level: 100 %
2013.11.20 15:43:01.167 5: SW: As0E85A011F1123415B1BC0201C80000
2013.11.20 15:43:01.318 5: CUL/RAW: /A0E85800215B1BCF112340101C800331E

2013.11.20 15:43:01.320 5: CUL: A0E85800215B1BCF112340101C80033 -59
2013.11.20 15:43:01.322 5: CUL dispatch A0E85800215B1BCF112340101C80033::-59:CUL
2013.11.20 15:43:01.328 4: RCV L:0E N:85 F:80 CMD:02 SRC:Pumpe DST:F11234 0101C80033 (ACK_STATUS CHANNEL:0x01 STATUS:0xC8 UP:0x00 DOWN:0x00 LOWBAT:0x00 RSSI:0x33) (,RPTEN)
2013.11.20 15:43:01.337 5: Triggering Pumpe (5 changes)
2013.11.20 15:43:01.343 5: Notify loop for Pumpe level: 100 %
2013.11.20 15:43:01.421 5: SW: As0E86A011F11234192F7A0201C80000
2013.11.20 15:43:01.584 5: SW: As0E87A011F112341BC6E50202C80000
2013.11.20 15:43:01.737 5: CUL/RAW: /A0E8780021BC6E5F112340102C8002F2F

2013.11.20 15:43:01.739 5: CUL: A0E8780021BC6E5F112340102C8002F -50.5
2013.11.20 15:43:01.741 5: CUL dispatch A0E8780021BC6E5F112340102C8002F::-50.5:CUL
2013.11.20 15:43:01.747 4: RCV L:0E N:87 F:80 CMD:02 SRC:Licht_Auffahrt_Treppe DST:F11234 0102C8002F (ACK_STATUS CHANNEL:0x02 STATUS:0xC8 UP:0x00 DOWN:0x00 LOWBAT:0x00 RSSI:0x2F) (,RPTEN)
2013.11.20 15:43:01.756 5: Triggering Licht_Treppe (5 changes)
2013.11.20 15:43:01.761 5: Notify loop for Licht_Treppe level: 100 %
2013.11.20 15:43:01.837 5: SW: As0E88A011F112341B33CB0201C80000
2013.11.20 15:43:01.988 5: CUL/RAW: /A0E8880021B33CBF112340101C8003C0F

2013.11.20 15:43:01.990 5: CUL: A0E8880021B33CBF112340101C8003C -66.5
2013.11.20 15:43:01.991 5: CUL dispatch A0E8880021B33CBF112340101C8003C::-66.5:CUL
2013.11.20 15:43:01.998 4: RCV L:0E N:88 F:80 CMD:02 SRC:Strom_Pool DST:F11234 0101C8003C (ACK_STATUS CHANNEL:0x01 STATUS:0xC8 UP:0x00 DOWN:0x00 LOWBAT:0x00 RSSI:0x3C) (,RPTEN)
2013.11.20 15:43:02.008 5: Triggering Strom_Pool (5 changes)
2013.11.20 15:43:02.014 5: Notify loop for Strom_Pool level: 100 %
2013.11.20 15:43:02.090 5: SW: As0E89A011F112341ECD8E0201C80000
2013.11.20 15:43:02.241 5: CUL/RAW: /A0E8980021ECD8EF112340101C8003B1D

2013.11.20 15:43:02.243 5: CUL: A0E8980021ECD8EF112340101C8003B -59.5
2013.11.20 15:43:02.245 5: CUL dispatch A0E8980021ECD8EF112340101C8003B::-59.5:CUL
2013.11.20 15:43:02.251 4: RCV L:0E N:89 F:80 CMD:02 SRC:Strom_WG DST:F11234 0101C8003B (ACK_STATUS CHANNEL:0x01 STATUS:0xC8 UP:0x00 DOWN:0x00 LOWBAT:0x00 RSSI:0x3B) (,RPTEN)
2013.11.20 15:43:02.261 5: Triggering Strom_WG (5 changes)
2013.11.20 15:43:02.266 5: Notify loop for Strom_WG level: 100 %
2013.11.20 15:44:02.002 4: Closing connection FHEMWEB:192.168.111.100:1081
2013.11.20 15:44:27.414 4: Connection closed for FHEMWEB:192.168.111.100:1080
2013.11.20 15:44:27.419 4: Connection accepted from FHEMWEB:192.168.111.100:1082
2013.11.20 15:44:27.422 4: HTTP FHEMWEB:192.168.111.100:1082 GET /fhem/FileLog_logWrapper?dev=Logfile&type=text&file=fhem-2013-11.log
2013.11.20 15:44:28.554 4: Connection accepted from FHEMWEB:192.168.111.100:1083
2013.11.20 15:44:28.895 4: HTTP FHEMWEB:192.168.111.100:1082 GET /fhem/FileLog_logWrapper?room=all&XHR=1&inform=1×tamp=1384958812151
2013.11.20 15:44:33.127 4: Connection closed for FHEMWEB:192.168.111.100:1083
2013.11.20 15:44:50.025 5: CUL/RAW: /A0D9484101B0AF3F112340601310015

und das Bild (//)
Haustuer und Terrasse wurden nicht geschaltet.

Beim durschschauen der protokolle ist mir aufgefallen das der jeweilige kanal, der probleme macht,
gar nicht im protokoll vorhanden ist.

Habe die "Haustuer" mal manuell geschaltet :

###############An (fehlgeschlagen)########################################
2013.11.20 16:03:41.540 5: Cmd: >set Licht_Haustuer on<
2013.11.20 16:03:41.546 5: Triggering Licht_Haustuer (1 changes)
2013.11.20 16:03:41.552 5: Notify loop for Licht_Haustuer set_on
2013.11.20 16:03:41.620 2: CUL_HM set Licht_Haustuer on
2013.11.20 16:03:41.622 5: CUL sending As0E96A011F112341ABCB50201C80000
2013.11.20 16:03:41.624 5: SW: As0E96A011F112341ABCB50201C80000
2013.11.20 16:03:41.640 4: SND L:0E N:96 F:A0 CMD:11 SRC:F11234 DST:Licht_Sockel 0201C80000 (SET CHANNEL:0x01 VALUE:0xC8 RAMPTIME:0x0000) (,BIDI,RPTEN)
2013.11.20 16:03:41.662 4: /fhem?XHR=1&cmd.Licht_Haustuer=set%20Licht_Haustuer%20on&room=SWITCH / RL:20 / text/plain; charset=UTF-8 / Content-Encoding: gzip
/


###############An (erfolgreich)########################################
2013.11.20 16:09:31.234 5: Cmd: >set Licht_Haustuer on<
2013.11.20 16:09:31.241 5: Triggering Licht_Haustuer (1 changes)
2013.11.20 16:09:31.246 5: Notify loop for Licht_Haustuer set_on
2013.11.20 16:09:31.322 2: CUL_HM set Licht_Haustuer on
2013.11.20 16:09:31.325 5: CUL sending As0E98A011F112341ABCB50201C80000
2013.11.20 16:09:31.327 5: SW: As0E98A011F112341ABCB50201C80000
2013.11.20 16:09:31.343 4: SND L:0E N:98 F:A0 CMD:11 SRC:F11234 DST:Licht_Sockel 0201C80000 (SET CHANNEL:0x01 VALUE:0xC8 RAMPTIME:0x0000) (,BIDI,RPTEN)
2013.11.20 16:09:31.365 4: /fhem?XHR=1&cmd.Licht_Haustuer=set%20Licht_Haustuer%20on&room=SWITCH / RL:20 / text/plain; charset=UTF-8 / Content-Encoding: gzip
/
2013.11.20 16:09:31.374 4: HTTP FHEMWEB:192.168.111.100:1167 GET /fhem/images/default/set_on.png
2013.11.20 16:09:31.478 5: CUL/RAW: /A0E9880021ABCB5F112340101C8003D1F

2013.11.20 16:09:31.479 5: CUL: A0E9880021ABCB5F112340101C8003D -58.5
2013.11.20 16:09:31.481 5: CUL dispatch A0E9880021ABCB5F112340101C8003D::-58.5:CUL
2013.11.20 16:09:31.487 4: RCV L:0E N:98 F:80 CMD:02 SRC:Licht_Sockel DST:F11234 0101C8003D (ACK_STATUS CHANNEL:0x01 STATUS:0xC8 UP:0x00 DOWN:0x00 LOWBAT:0x00 RSSI:0x3D) (,RPTEN)
2013.11.20 16:09:31.497 5: Triggering Licht_Haustuer (5 changes)
2013.11.20 16:09:31.503 5: Notify loop for Licht_Haustuer level: 100 %
2013.11.20 16:09:31.596 4: HTTP FHEMWEB:192.168.111.100:1167 GET /fhem/images/default/Licht_Haustuer.on.png
2013.11.20 16:09:38.444 4: Connection closed for FHEMWEB:192.168.111.100:1165
2013.11.20 16:09:38.449 4: HTTP FHEMWEB:192.168.111.100:1167 GET /fhem/FileLog_logWrapper?dev=Logfile&type=text&file=fhem-2013-11.log


##########Aus (erfolgreich)##################################################
2013.11.20 16:12:38.802 5: Cmd: >set Licht_Haustuer off<
2013.11.20 16:12:38.808 5: Triggering Licht_Haustuer (1 changes)
2013.11.20 16:12:38.814 5: Notify loop for Licht_Haustuer set_off
2013.11.20 16:12:38.883 2: CUL_HM set Licht_Haustuer off
2013.11.20 16:12:38.886 5: CUL sending As0E99A011F112341ABCB50201000000
2013.11.20 16:12:38.888 5: SW: As0E99A011F112341ABCB50201000000
2013.11.20 16:12:38.905 4: SND L:0E N:99 F:A0 CMD:11 SRC:F11234 DST:Licht_Sockel 0201000000 (SET CHANNEL:0x01 VALUE:0x00 RAMPTIME:0x0000) (,BIDI,RPTEN)
2013.11.20 16:12:38.927 4: /fhem?XHR=1&cmd.Licht_Haustuer=set%20Licht_Haustuer%20off&room=SWITCH / RL:20 / text/plain; charset=UTF-8 / Content-Encoding: gzip
/
2013.11.20 16:12:38.936 4: HTTP FHEMWEB:192.168.111.100:1175 GET /fhem/images/default/set_off.png
2013.11.20 16:12:39.039 5: CUL/RAW: /A0E9980021ABCB5F11234010100003A1A

2013.11.20 16:12:39.040 5: CUL: A0E9980021ABCB5F11234010100003A -61
2013.11.20 16:12:39.042 5: CUL dispatch A0E9980021ABCB5F11234010100003A::-61:CUL
2013.11.20 16:12:39.048 4: RCV L:0E N:99 F:80 CMD:02 SRC:Licht_Sockel DST:F11234 010100003A (ACK_STATUS CHANNEL:0x01 STATUS:0x00 UP:0x00 DOWN:0x00 LOWBAT:0x00 RSSI:0x3A) (,RPTEN)
2013.11.20 16:12:39.058 5: Triggering Licht_Haustuer (5 changes)
2013.11.20 16:12:39.064 5: Notify loop for Licht_Haustuer level: 0 %
2013.11.20 16:12:39.155 4: HTTP FHEMWEB:192.168.111.100:1175 GET /fhem/images/default/Licht_Haustuer.off.png
2013.11.20 16:12:45.380 4: Connection closed for FHEMWEB:192.168.111.100:1171
2013.11.20 16:12:45.385 4: HTTP FHEMWEB:192.168.111.100:1175 GET /fhem/FileLog_logWrapper?dev=Logfile&type=text&file=fhem-2013-11.log


###############Aus (fehlgeschlagen)###################################
2013.11.20 16:15:26.238 5: Cmd: >set Licht_Haustuer off<
2013.11.20 16:15:26.244 5: Triggering Licht_Haustuer (1 changes)
2013.11.20 16:15:26.250 5: Notify loop for Licht_Haustuer set_off
2013.11.20 16:15:26.319 2: CUL_HM set Licht_Haustuer off
2013.11.20 16:15:26.321 5: CUL sending As0E9BA011F112341ABCB50201000000
2013.11.20 16:15:26.323 5: SW: As0E9BA011F112341ABCB50201000000
2013.11.20 16:15:26.339 4: SND L:0E N:9B F:A0 CMD:11 SRC:F11234 DST:Licht_Sockel 0201000000 (SET CHANNEL:0x01 VALUE:0x00 RAMPTIME:0x0000) (,BIDI,RPTEN)
2013.11.20 16:15:26.361 4: /fhem?XHR=1&cmd.Licht_Haustuer=set%20Licht_Haustuer%20off&room=SWITCH / RL:20 / text/plain; charset=UTF-8 / Content-Encoding: gzip
/
2013.11.20 16:15:34.917 4: Connection closed for FHEMWEB:192.168.111.100:1177
2013.11.20 16:15:34.922 4: HTTP FHEMWEB:192.168.111.100:1181 GET /fhem/FileLog_logWrapper?dev=Logfile&type=text&file=fhem-2013-11.log


mfg
chicony
Titel: Antw:Probleme beim Schalten mehrerer HM-Aktoren
Beitrag von: martinp876 am 21 November 2013, 08:17:38
Hi chicony,

das erste Problem ist klar: die kommandos werden nicht beantwortet.
das lodIDs kannst du nicht finden, ist HMLAN, du hast eine CUL.

was mich wundert ist, dass keine wiederholungen gesendet werden. Hast du HMinfo und kannst ein protoEvents machen? Es sollten resends zu sehen sein.
Du bist sicher auf der neusten SW - update ist die letzten Tage erfolgt?
hast du das Attribut msgRepeat gesetzt? Auf welchen Wert?

Gruss Martin
Titel: Antw:Probleme beim Schalten mehrerer HM-Aktoren
Beitrag von: chicony am 21 November 2013, 13:21:11
Hallo Martin,
vielen dank für deine Hilfe !

update wurde am 20.11 erfolgreich durchgeführt.
msgRepeat steht bei den "Problemfällen" auf 5.
HMinfo und protoEvents erstellt und angeschaut : ein paar resends waren dabei.

Habe mit rereadcfg HMinfo wieder auf 0 gesetzt und mein Schalter angeworfen :

1.Versuch
Haustuer fehlgeschlagen -> wird als ausgeführt angezeigt:
name                :State           |CmdPend   |Snd       |Resnd     #CmdDel    |ResndFail |Nack      |IOerr     
    Auffahrt_Treppe_Device: done           | -        |2:        | -        # -        | -        | -        | -       
    Haustuer_Weg_Device : done           | -        |2:        | -        # -        | -        | -        | -       
    Licht_Terrasse      : done           | -        |1:        | -        # -        | -        | -        | -       
    Pumpe               : done           | -        |1:        | -        # -        | -        | -        | -       
    Strom_Pool          : done           | -        |1:        | -        # -        | -        | -        | -       
    Strom_WG            : done           | -        |1:        | -        # -        | -        | -        | -       


2.Versuch
Terrasse und Pool fehlgeschlagen -> nach 10min immernoch processing
name                :State           |CmdPend   |Snd       |Resnd     #CmdDel    |ResndFail |Nack      |IOerr     
    Auffahrt_Treppe_Device: done           | -        |2:        | -        # -        | -        | -        | -       
    Haustuer_Weg_Device : done           | -        |2:        | -        # -        | -        | -        | -       
    Licht_Terrasse      : processing...  | -        |1:        | -        # -        | -        | -        | -       
    Pumpe               : done           | -        |1:        | -        # -        | -        | -        | -       
    Strom_Pool          : processing...  | -        |1:        | -        # -        | -        | -        | -       
    Strom_WG            : done           | -        |1:        | -        # -        | -        | -        | -   


3.Versuch
Pool und Weg fehlgeschlagen -> Weg wird als ausgeführt angezeigt ; nach 10min immernoch processing
name                :State           |CmdPend   |Snd       |Resnd     #CmdDel    |ResndFail |Nack      |IOerr     
Auffahrt_Treppe_Device: done           | -        |2:        | -        # -        | -        | -        | -       
    Haustuer_Weg_Device : done           | -        |2:        | -        # -        | -        | -        | -       
    Licht_Terrasse      : done           | -        |1:        | -        # -        | -        | -        | -       
    Pumpe               : done           | -        |1:        | -        # -        | -        | -        | -       
    Strom_Pool          : processing...  | -        |1:        | -        # -        | -        | -        | -       
    Strom_WG            : done           | -        |1:        | -        # -        | -        | -        | -       


4.Versuch
Pool und Treppe fehlgeschlagen -> Treppe wird als ausgeführt angezeigt ;  nach 10min immernoch processing
name                :State           |CmdPend   |Snd       |Resnd     #CmdDel    |ResndFail |Nack      |IOerr     
Auffahrt_Treppe_Device: done           | -        |2:        | -        # -        | -        | -        | -       
    Haustuer_Weg_Device : done           | -        |2:        | -        # -        | -        | -        | -       
    Licht_Terrasse      : done           | -        |1:        | -        # -        | -        | -        | -       
    Pumpe               : done           | -        |1:        | -        # -        | -        | -        | -       
    Strom_Pool          : processing...  | -        |1:        | -        # -        | -        | -        | -       
    Strom_WG            : done           | -        |1:        | -        # -        | -        | -        | -       



rssi done:
    Device         :receive         from             last   avg      min<max    count
    Auffahrt_Treppe_Device:Auffahrt_Treppe_Device CUL              -47.0  -46.0  -47.0< -45.0     2
    Auffahrt_Treppe_Device:CUL             Auffahrt_Treppe_Device  -48.5  -48.5  -48.5< -48.5     2
    BM_WZ          :CUL             BM_WZ            -62.5  -62.3  -62.5< -62.0     2
    Haustuer_Weg_Device:CUL             Haustuer_Weg_Device  -62.0  -61.0  -62.0< -60.0     2
    Haustuer_Weg_Device:Haustuer_Weg_Device CUL              -60.0  -60.5  -61.0< -60.0     2
    Licht_Terrasse :CUL             Licht_Terrasse   -82.0  -82.0  -82.0< -82.0     1
    Licht_Terrasse :Licht_Terrasse  CUL              -79.0  -79.0  -79.0< -79.0     1
    Pumpe          :CUL             Pumpe            -62.5  -62.5  -62.5< -62.5     1
    Pumpe          :Pumpe           CUL              -57.0  -57.0  -57.0< -57.0     1
    Strom_Pool     :CUL             Strom_Pool       -61.5  -61.5  -61.5< -61.5     1
    Strom_Pool     :Strom_Pool      CUL              -60.0  -60.0  -60.0< -60.0     1
    Strom_WG       :CUL             Strom_WG         -61.5  -61.5  -61.5< -61.5     1
    Strom_WG       :Strom_WG        CUL              -59.0  -59.0  -59.0< -59.0     1


Die rssi - Werte sind eigentlich auch ok (Terrasse ist etwas hoch).

Auffällig ist :
1. Es gibt kein resend bei den Aktoren
2. Das "processing" bleibt bis ich über "status request" den zustand abfrage
2. Er verschluckt manchmal einen Kanal (bei den Sw2)

mfg
chicony
Titel: Antw:Probleme beim Schalten mehrerer HM-Aktoren
Beitrag von: martinp876 am 21 November 2013, 17:01:28
Hi,

ich werde einmal mit der CUL testen...

von den resends sehe ich in der Tabelle nichts - das mit dem Processing darf auch nicht sein.
wenn es noch einmal passiert, kannst du ein List der hängenden Devices machen (device, nicht channel) - damit ich die internen Variablen sehen kann.

RSSI hin oder her - processing darf nicht stehen bleiben.

Gruss Martin
Titel: Antw:Probleme beim Schalten mehrerer HM-Aktoren
Beitrag von: chicony am 21 November 2013, 17:45:05
Hallo Martin,
hier die angeforderten daten

Sw1:

Internals:
   CUL_MSGCNT 2
   CUL_RAWMSG A0E1BA4101B33CBF11234060100003C19
   CUL_RSSI   -61.5
   CUL_TIME   2013-11-21 13:16:08
   DEF        1B33CB
   EVENTS     2
   IODev      CUL
   LASTInputDev CUL
   MSGCNT     2
   NAME       Strom_Pool
   NR         105
   STATE      set_on
   TYPE       CUL_HM
   lastMsg    No:1B - t:10 s:1B33CB d:F11234 060100003C
   protLastRcv 2013-11-21 13:16:08
   protSnd    7 last_at:2013-11-21 17:30:16
   protState  CMDs_processing...
   rssi_CUL   avg:-60 min:-60 max:-60 lst:-60 cnt:2
   rssi_at_CUL avg:-61.5 min:-61.5 max:-61.5 lst:-61.5 cnt:2
   Readings:
     2013-11-21 12:08:24   CommandAccepted yes
     2013-11-13 12:54:34   PairedTo        0xF11234
     2013-11-13 12:54:34   R-intKeyVisib   invisib
     2013-11-13 12:54:34   R-pairCentral   0xF11234
     2013-11-13 12:54:34   RegL_00:        02:01 03:00 04:00 05:00 06:00 07:00 08:00 09:00 0A:F1 0B:12 0C:34 00:00
     2013-11-14 11:48:04   RegL_01:        0
     2013-11-21 13:16:08   deviceMsg       off (to CUL)
     2013-11-21 13:16:08   level           0 %
     2013-11-21 13:16:08   pct             0
     2013-11-09 12:25:55   powerOn         -
     2013-11-21 17:30:16   state           set_on
     2013-11-21 13:16:08   timedOn         off
   cmdStack:
   Helper:
     mId        0004
     rxType     1
     Prt:
       bErr       0
       sProc      1
       Rspwait:
     Q:
       qReqConf   
       qReqStat   00
     Role:
       chn        1
       dev        1
     Rssi:
       Cul:
         avg        -60
         cnt        2
         lst        -60
         max        -60
         min        -60
       At_cul:
         avg        -61.5
         cnt        2
         lst        -61.5
         max        -61.5
         min        -61.5
     Shadowreg:
Attributes:
   autoReadReg 4_reqStatus
   expert     2_full
   firmware   1.9
   fp_Grundriss_EG 161,53,1,
   model      HM-LC-SW1-FM
   msgRepeat  5
   peerIDs    00000000,
   room       SWITCH
   serialNr   JEQ0148084
   subType    switch
   webCmd     toggle:on:off:statusRequest


Sw2

Internals:
   DEF        1ABCB5
   IODev      CUL
   NAME       Haustuer_Weg_Device
   NR         215
   STATE      CMDs_processing...
   TYPE       CUL_HM
   channel_01 Licht_Haustuer
   channel_02 Licht_Weg
   lastMsg    No:26 - t:02 s:1ABCB5 d:F11234 0101C80041
   protLastRcv 2013-11-21 17:02:00
   protResnd  2 last_at:2013-11-21 17:01:39
   protSnd    25 last_at:2013-11-21 17:25:09
   protState  CMDs_processing...
   rssi_CUL   avg:-62.43 min:-65 max:-59 lst:-65 cnt:16
   rssi_at_CUL avg:-62.84 min:-66 max:-60 lst:-60.5 cnt:16
   Readings:
     2013-11-19 12:10:02   CommandAccepted yes
     2013-11-20 16:33:10   PairedTo        0xF11234
     2013-11-20 16:31:31   R-intKeyVisib   invisib
     2013-11-20 16:31:31   R-pairCentral   0xF11234
     2013-11-20 16:33:10   RegL_00:        02:01 03:00 04:00 05:00 06:00 07:00 08:00 09:00 0A:F1 0B:12 0C:34 00:00
     2013-11-21 17:25:09   state           CMDs_processing...
   cmdStack:
   Helper:
     mId        0009
     rxType     1
     Prt:
       bErr       0
       sProc      1
       Rspwait:
     Q:
       qReqConf   
       qReqStat   00
     Role:
       dev        1
     Rssi:
       Cul:
         avg        -62.4375
         cnt        16
         lst        -65
         max        -59
         min        -65
       At_cul:
         avg        -62.84375
         cnt        16
         lst        -60.5
         max        -60
         min        -66
     Shadowreg:
Attributes:
   autoReadReg 4_reqStatus
   expert     2_full
   firmware   1.9
   model      HM-LC-SW2-FM
   msgRepeat  5
   peerIDs   
   room       Unsorted
   serialNr   JEQ0183874
   subType    switch
   webCmd     getConfig


Als Anfänger hätte ich auf Endlosschleife getippt.

Viele Dank
chicony
Titel: Antw:Probleme beim Schalten mehrerer HM-Aktoren
Beitrag von: martinp876 am 21 November 2013, 19:21:11
Hi,

so das sollte es gewesen sein. Bei einigen kommandos ist der timer nicht aufgezogen worden - somit gab es kein resend.

jetzt sollte das resend kommen und das processing verschwinden. Damit müsste das schalten eigentlich immer funktionieren.
Unschön sind die resends dennoch - das ist dann der nächste schritt. Ich werden einmal massenverkehr mit der CUL testen (du hast CUL? oder CUNO?)

Teste einmal mit Version 4257 - ich hoffen, dass es stimmt, was ich gesagt haben ;-)

Gruss Martin
Titel: Antw:Probleme beim Schalten mehrerer HM-Aktoren
Beitrag von: chicony am 21 November 2013, 20:41:46
Hallo Martin,

die neue version läuft einwandfrei, super !!

hier nochmal in HMinfo :

  name                :State           |CmdPend   |Snd       |Resnd     #CmdDel    |ResndFail |Nack      |IOerr     
    Auffahrt_Treppe_Device: done           | -        |4:        | -        # -        | -        | -        | -       
    Haustuer_Weg_Device : done           | -        |6:        |1:        # -        | -        | -        | -       
    Licht_Terrasse      : done           | -        |2:        | -        # -        | -        | -        | -       
    Pumpe               : done           | -        |2:        | -        # -        | -        | -        | -       
    Strom_Pool          : done           | -        |4:        |7:        # -        | -        | -        | -       
    Strom_WG            : done           | -        |2:        | -        # -        | -        | -       


es gibt ein resend, alle Aktoren haben geschaltet !

Vielen Dank Martin
chicony
Titel: Antw:Probleme beim Schalten mehrerer HM-Aktoren
Beitrag von: oelidoc am 21 November 2013, 22:54:17
Hallo Martin,

wo finde ich denn die Vers. 4257?

Gruß

oelidoc
Titel: Antw:Probleme beim Schalten mehrerer HM-Aktoren
Beitrag von: LuckyDay am 21 November 2013, 23:08:09
http://sourceforge.net/p/fhem/code/HEAD/tree/trunk/fhem/FHEM/ (http://sourceforge.net/p/fhem/code/HEAD/tree/trunk/fhem/FHEM/)
Titel: Antw:Probleme beim Schalten mehrerer HM-Aktoren
Beitrag von: oelidoc am 21 November 2013, 23:10:27
Danke,

aber wie bekomme ich das in fhem rein?

Gruß

oelidoc
Titel: Antw:Probleme beim Schalten mehrerer HM-Aktoren
Beitrag von: martinp876 am 22 November 2013, 07:47:43
heute mit update (immer am nächsten Tag)
ansonsten das file herunterladen, in dein system kopieren (du kannst all diese files finden in ./fhem/FHEM deines Installationspfads).
und schließlich ein shutdown restart

Titel: Antw:Probleme beim Schalten mehrerer HM-Aktoren
Beitrag von: oelidoc am 22 November 2013, 08:39:02
ok danke,

hatte es dummerweise mit update thirdparty versucht.... :-X

Gruß

oelidoc
Titel: Antw:Probleme beim Schalten mehrerer HM-Aktoren
Beitrag von: smirnov am 26 November 2013, 12:53:09
hallo,

mit Freude habe ich die Änderungen bzgl. homematic-resend gelesen, und dann gleich ausprobiert, da ich schon immer Probleme mit meinen Thermostaten + COC habe. Leider hat es keine Änderung gebracht. Wenn ich ein set desired-temp an mehrerer DN's schicke, kommt es mitunter vor, dass einer oder mehrere nicht gesetzt werden, und auf "MISSING ACK" gehen, ohne dass von FHME ein resend-Versuch unternommen wird.
Hier ein log-Auszug von so einem set desired-temp:

2013.11.26 12:38:25.316 5: CUL/RAW: /A0FC986102288990000000AA0D81000582B
2013.11.26 12:38:25.318 5: COC: A0FC986102288990000000AA0D8100058 -52.5
2013.11.26 12:38:25.320 5: COC dispatch A0FC986102288990000000AA0D8100058::-52.5:COC
2013.11.26 12:38:25.326 4: RCV L:0F N:C9 F:86 CMD:10 SRC:HeizK_AZ_Erker DST:broadcast 0AA0D8100058 (INFO_TEMP SET:0xA0D8 ACT:0xA0D8 ERR:0x10 VALVE:0x10 MODE:0x10) (,WAKEMEUP,CFG,RPTEN)
2013.11.26 12:38:25.330 5: COC sending As092FA112F11234228899
2013.11.26 12:38:25.412 5: SW: As092FA112F11234228899
2013.11.26 12:38:25.428 4: SND L:09 N:2F F:A1 CMD:12 SRC:F11234 DST:HeizK_AZ_Erker  (HAVE_DATA) (,WAKEUP,BIDI,RPTEN)
2013.11.26 12:38:33.009 5: CUL/RAW: /A0CBB86702066DD00000000DC2002
2013.11.26 12:38:33.012 5: COC: A0CBB86702066DD00000000DC20 -73
2013.11.26 12:38:33.014 5: COC dispatch A0CBB86702066DD00000000DC20::-73:COC
2013.11.26 12:38:33.020 4: RCV L:0C N:BB F:86 CMD:70 SRC:temp_Bad DST:broadcast 00DC20 (WeatherEvent TEMP:0x00DC HUM:0x20) (,WAKEMEUP,CFG,RPTEN)
2013.11.26 12:38:33.900 5: CUL/RAW: /A0FE6861
2013.11.26 12:38:33.904 5: CUL/RAW: A0FE6861/0236D7E0000000AA
2013.11.26 12:38:33.908 5: CUL/RAW: A0FE68610236D7E0000000AA/0D80E00585B
2013.11.26 12:38:33.910 5: COC: A0FE68610236D7E0000000AA0D80E0058 -28.5
2013.11.26 12:38:33.913 5: COC dispatch A0FE68610236D7E0000000AA0D80E0058::-28.5:COC
2013.11.26 12:38:33.918 4: RCV L:0F N:E6 F:86 CMD:10 SRC:HeizK_AZ_Fenster DST:broadcast 0AA0D80E0058 (INFO_TEMP SET:0xA0D8 ACT:0xA0D8 ERR:0x0E VALVE:0x0E MODE:0x0E) (,WAKEMEUP,CFG,RPTEN)
2013.11.26 12:38:33.922 5: COC sending As0930A112F11234236D7E
2013.11.26 12:38:34.005 5: SW: As0930A112F11234236D7E
2013.11.26 12:38:34.020 4: SND L:09 N:30 F:A1 CMD:12 SRC:F11234 DST:HeizK_AZ_Fenster  (HAVE_DATA) (,WAKEUP,BIDI,RPTEN)
2013.11.26 12:38:34.430 5: CUL/RAW: /A0A308002236D7EF11234005A
2013.11.26 12:38:34.432 5: COC: A0A308002236D7EF1123400 -29
2013.11.26 12:38:34.435 5: COC dispatch A0A308002236D7EF1123400::-29:COC
2013.11.26 12:38:34.440 4: RCV L:0A N:30 F:80 CMD:02 SRC:HeizK_AZ_Fenster DST:F11234 00 (ACK) (,RPTEN)
2013.11.26 12:38:34.445 5: COC sending As0C31A011F11234236D7E86042B
2013.11.26 12:38:34.527 5: SW: As0C31A011F11234236D7E86042B
2013.11.26 12:38:34.543 4: SND L:0C N:31 F:A0 CMD:11 SRC:F11234 DST:HeizK_AZ_Fenster 86042B (,BIDI,RPTEN)
2013.11.26 12:38:34.694 5: CUL/RAW: /A0A31800
2013.11.26 12:38:34.698 5: CUL/RAW: A0A31800/2236D7EF11234005
2013.11.26 12:38:34.702 5: CUL/RAW: A0A318002236D7EF11234005/A
2013.11.26 12:38:34.704 5: COC: A0A318002236D7EF1123400 -29
2013.11.26 12:38:34.706 5: COC dispatch A0A318002236D7EF1123400::-29:COC
2013.11.26 12:38:34.711 4: RCV L:0A N:31 F:80 CMD:02 SRC:HeizK_AZ_Fenster DST:F11234 00 (ACK) (,RPTEN)


HeizK_AZ_Fenster wurde richtig gesetzt, HeizK_AZ_Erker ging auf MISSING ACK.
Mit scheint es so, als hätte der Empfang der Temperatur-Daten von temp_Bad das ACK des DN überdeckt. Eigentlich sollte da ja ein resend kommen, da die Übertragung nicht ordnungsgemäß stattgefunden hat.

Wie kann ich helfen, soll ich noch mehr (andere) logs produzieren?

vielen Dank für die harte Arbeit,
Tom
Titel: Antw:Probleme beim Schalten mehrerer HM-Aktoren
Beitrag von: martinp876 am 26 November 2013, 19:30:23
in deinem Log gibt es keine Überdeckung - wenn du nichts gefiltert hast. Die Entscheidenden Zeilen sind

25.316  CUL/RAW: /A0FC9 8610 228899 000000 0AA0D8100058 2B
25.412  SW: As092F A112 F11234 228899
HeizK_AZ_Erker wacht auf, 100ms danach sendet FHEM das "habeDaten" signal. Es kommt keine Antwort.


so ist es ok:
33.908  CUL/RAW: A0FE6 8610 236D7E 000000 0AA0D80E0058 5B
34.005  SW: As0930 A112 F11234 236D7E
34.430  CUL/RAW: /A0A30 8002 236D7E F11234 00
34.527  SW: As0C31 A011 F11234 236D7E 86042B
34.702  CUL/RAW: A0A31 8002 236D7E F1123 400

HeizK_AZ_Fenster sendet temp-werte, 100ms danach sendet FHEM "habeDaten". Es kommt ein ACK und dann geht es los.
Beide Aktionen sind 8sec auseinander. 

Mögliche Fehler sind:
CUL verzögert senden oder Empfangen, so dass die 100ms nicht eingehalten werden. Bei HMLAN erhalte ich einen Zeitstempel AUS DEM IODEV. Der ist erheblich genauer - und ich versuche, diesen zur Berechnung zu verwenden. CUL sendet keinen solchen stempel, ich kann keine Aussage machen, wie genau gesendet wird.

der Pegel (RSSI) stimmt nicht.


Der generelle Ablauf ist in beiden Fällen korrekt, dennoch fehlt die Antwort.

Ein wiederholen ist bei wakeup sinnlos, zumindest in diesem "aufwach-zyklus". Das device schläft mit sicherheit, bevor FHEM wiederholen kann. Das wiederholen bezieht sich also auf devices mir "normal" oder burst mode.

Du kannst überlegen, die Devices auf burst umzustellen - könnte besser gehen, das Timing ist in diesem Fall "gedreht" - FHEM gibt vor.
Gruss Martin
Titel: Antw:Probleme beim Schalten mehrerer HM-Aktoren
Beitrag von: smirnov am 26 November 2013, 19:38:27
Also "resend" ist nicht so zu verstehen, dass FHEM das ganze beim nächsten wakeup wieder versucht?
Titel: Antw:Probleme beim Schalten mehrerer HM-Aktoren
Beitrag von: martinp876 am 27 November 2013, 20:19:23
nein - jedenfalls jetzt noch nicht. Könnte man machen...

resend ist ein sofortiges noch-einmal senden (mit einer Wartezeit aus FHEM gesteuert von bis zu 4 sec).

bei einem wakeup device muss man mehr machen, wenn man ein resend will:
- auf das Device warten
- multi message kommandos komplett wiederholen (register schreiben)
- wiederhol- zähler anpassen.

es ist eine andere Steuerung - vielleicht passe ich die einmal an. Man muss dann aber entsprechend lange warten...

Aktuell wird bei wakeup nur vom HMLAN wiederholt, 3 mal schnell.

Gruss Martin
Titel: Antw:Probleme beim Schalten mehrerer HM-Aktoren
Beitrag von: Eniac am 30 November 2013, 09:12:16
Hallo zusammen,

sorry für die späte Antwort, aber bei funktioniert nun nach dem Update wieder alles!
Vielen Dank an Martin für die Korrektur!!

Für meine Jalousien bedeutet dies:
Es wird zwar nicht wie früher alle Aktoren gleichzeitig geschaltet, aber immerhin werden die Aktoren, die beim ersten Mal nicht reagiert haben, beim Resend aktiv.
Das ist für mich völlig in Ordnung, im Ergebnis gehen dann alle Aktoren (leicht zeitversetzt).

Viele Grüße und vielen Dank an das Forum und insbesondere an Martin.

Eniac
Titel: Antw:Probleme beim Schalten mehrerer HM-Aktoren
Beitrag von: martinp876 am 01 Dezember 2013, 10:26:58
@ tomballarino

das wiederholen sollte jetzt (version 4305) auch bei wakeup funktionieren.
msgRepeat wird jetzt strikt geparst, beim Eingeben:
"nur-config" devices wiederholen nicht (sehe ich keinen Sinn) man kann msgRepeat nur auf 1 setzen (einmal senden).
wakeup wiederholt - man muss aber auf das nächste aufwachen warten.

Hier noch einmal ein Hinweis/Tip, dass man hie und da den Zustand der Übertragung zu prüfen. HMInfo mit "protoEvents" und/oder die Zusammenfassung mittels "update" sollte man im Auge behalten. Besonders, wenn die Übertragung entsprechend verzögert werden kann

Bitte testen - es gibt eine Menge Kombinationen, die Übertragung anzustoßen

Gruss Martin
Titel: Antw:Probleme beim Schalten mehrerer HM-Aktoren
Beitrag von: smirnov am 01 Dezember 2013, 20:22:28
@martin: ich habe das die Version 4305 gerade mit meinem COC getestet, funktioniert einwandfrei, ich habe es nicht geschafft, die Datenübertragung zu sabotieren (z.B. durch hohe Last am Raspberry, wo das Problem absolut reproduzierbar aufgetreten ist), das resnd funktioniert super!

Vielen Dank für deine Arbeit,

lg
Tom
Titel: Antw:Probleme beim Schalten mehrerer HM-Aktoren
Beitrag von: Eniac am 09 Dezember 2013, 21:14:14
Hallo zusammen,

habe gestern ein Update gemacht und irgendwie scheint das Problem wieder zu existieren.
Ich habe mal in die Logs geschaut, von meiner Struktur mit den 3 Jalousieaktoren wird bei Schalten der Struktur auch nur einer ausgelöst (der Aktor, der als letztes in der Struktur steht).
Die anderen zwei Aktoren erhalten keinen Schaltbefehl, ist hier ggf. beim Parsen der Struktur ein Bug eingeschlichen?

Hat sonst noch jemand das Problem? Ist bei mir bei allen definierten Strukturen das gleiche Problem und auch nachvollziehbar.

Viele Grüße
Eniac77
Titel: Antw:Probleme beim Schalten mehrerer HM-Aktoren
Beitrag von: ph1959de am 10 Dezember 2013, 07:31:57
@Eniac: wenn Dein Problem so ähnlich ist wie das hier: http://forum.fhem.de/index.php/topic,17351.0.html (für mich klingt das so), dann müsstest Du noch mal ein Update machen (siehe hier: http://forum.fhem.de/index.php/topic,17351.msg113657.html#msg113657).

Gruß, Peter
Titel: Antw:Probleme beim Schalten mehrerer HM-Aktoren
Beitrag von: Eniac am 10 Dezember 2013, 15:00:01
Hallo zusammen,

nach dem heutigen Update funktioniert es wieder wie gewünscht.

Viele Grüße
Eniac