[gelöst] FRITZ!DECT 200 lassen sich von FHEM aus nicht schalten

[wk]

Hallo,

ich habe zwei Schaltdosen FRITZ!DECT 200, die sich über die Fritzbox gut schalten lassen, aber nicht über FHEM.
FHEM ist CONNECTED und liest die Werte aus.

defmod Fritzbox FBAHAHTTP 192.168.17.1
attr Fritzbox fritzbox-user fhem
attr Fritzbox polltime 900
attr Fritzbox room Fritzbox

setstate Fritzbox connected



defmod FBDECT_Fritzbox_16 FBDECT Fritzbox:16 switch
attr FBDECT_Fritzbox_16 IODev Fritzbox
attr FBDECT_Fritzbox_16 room Fritzbox

setstate FBDECT_Fritzbox_16 on
setstate FBDECT_Fritzbox_16 2019-01-15 13:03:38 AIN 08761 0292048
setstate FBDECT_Fritzbox_16 2019-01-15 13:03:38 FBNAME Kinderflur
setstate FBDECT_Fritzbox_16 2019-01-15 13:03:38 FBPROP powerMeter,tempSensor,switch
setstate FBDECT_Fritzbox_16 2019-01-15 13:03:38 FBTYPE FRITZ!DECT 200
setstate FBDECT_Fritzbox_16 2019-01-15 13:03:38 ID 16
setstate FBDECT_Fritzbox_16 2019-01-15 13:03:38 devicelock no
setstate FBDECT_Fritzbox_16 2019-01-15 13:03:38 energy 0 Wh
setstate FBDECT_Fritzbox_16 2019-01-15 13:03:38 fwversion 04.09
setstate FBDECT_Fritzbox_16 2019-01-15 13:03:38 locked no
setstate FBDECT_Fritzbox_16 2019-01-15 13:03:38 mode auto
setstate FBDECT_Fritzbox_16 2019-01-15 13:03:38 power 0.00 W
setstate FBDECT_Fritzbox_16 2019-01-15 13:03:38 present yes
setstate FBDECT_Fritzbox_16 2019-01-15 13:05:01 state on
setstate FBDECT_Fritzbox_16 2019-01-15 13:03:38 tempadjust 0.0 C
setstate FBDECT_Fritzbox_16 2019-01-15 13:03:38 temperature 23.5 C (measured)
setstate FBDECT_Fritzbox_16 2019-01-15 13:03:38 voltage 231.712 V

Die Fritzbox hat die neueste Firmware 7.01 und die Aktoren 4.09 und der Fritzbox-Benutzer fhem ist für das Schalten von SmartHome berechtigt.

FHEM setzt zwar den Status:
2019-01-15 13:05:01 FBDECT FBDECT_Fritzbox_16 on
aber beim nächsten Pollen der Fritzbox kommt der wirkliche Status off wieder.

Wo fehlt eine Einstellung oder was ist sonst falsch?

cu
Walter

[rudolfkoenig]

Was liefert ein Schalten bei "attr FBAHAHTTP verbose 5"?

[wk]

keine zusätzlichen Zeilen

2019-01-15 20:53:10 Global global ATTR Fritzbox verbose 5
2019-01-15 20:53:35 FBDECT FBDECT_Fritzbox_16 off


Edit:
Ich hatte nur events angeschaut. In der fhem.log kam noch:

2019.01.15 20:53:35 3: Fritzbox: http://192.168.17.1/webservices/homeautoswitch.lua?sid=1ff56eef5e6063b0&ain=08761 0292048&switchcmd=setswitchoff: empty answer received

[Gerold]

vermutlich ist die IP-Adresse falsch, ist von der Fritzbox normalerweise 192.168.178.1 und nicht 192.168.17.1

[wk]

Nein, die IP-Adresse ist richtig. Wie Du oben sehen kannst, werden mit dieser Adresse alle Readings korrekt eingelesen.

[wk]

Vielleicht fällt doch noch jemand etwas ein.

Ich habe jetzt den Befehl http://192.168.17.1/webservices/homeautoswitch.lua?sid=696c6af195cb2ceb&ain=08761 0292048&switchcmd=setswitchon direkt in Firefox eingegeben und erhalte als Antwort 1 oder 0 und der Schalter schaltet richtig.

Den Befehl habe ich aus dem fhem.log kopiert, wo er keine Antwort erhält:
2019.01.17 11:27:09 3: Fritzbox: http://192.168.17.1/webservices/homeautoswitch.lua?sid=696c6af195cb2ceb&ain=08761 0292048&switchcmd=setswitchon: empty answer received

Ich habe jetzt den Befehl auch direkt von meinem Linux-Server auf dem fhem läuft eingeben mit folgendem Resultat:
curl 'http://192.168.17.1/webservices/homeautoswitch.lua?sid=696c6af195cb2ceb&ain=08761 0292048&switchcmd=setswitchon'
curl: (52) Empty reply from server

Kann sich einer von Euch einen Reim darauf machen, denn die sid ist in beiden Fällen identisch, d.h. die Fritzbox akzeptiert den Befehl von Firefox aber nicht vom Linux-Server.

[rudolfkoenig]

Wenn ich meine Geraete schalte dann bekomme ich statt "empty answer received" immer ein "HTTP response code 200".
Beim curl Aufruf kommt 1, und wenn ich sid aendere (weil ich einen Fehler provozieren will), dann nichts.

Ich habe etwas nachgedacht, ohne Ergebnis:
- es koennte ein Firewall Problem sein, aber wieso bekommst du dann die Status-Daten? Status abholen laeuft ueber den gleichen Mechanismus wie schalten, es wird nur switchcmd=getdevicelistinfos gesendet, ohne AIN, anstelle von switchcmd=setswitchon
- wenn es an einer (mir unbekannten) Berechtigungsproblem im FritzBox liegt, dann duerfte dein Browserversuch auch nicht funktionieren.

Bin ratlos.


Nachtrag: mein Fritz!Box laeuft auch mit 7.01

[Jamo]

Ich hatte das gleiche Problem gestern auch.
Über fhem auf 'on' geschaltet (bei mir innerhalb einer structure), in der FHEM Oberfläche ist der Dect200 auf 'on' gegangen. Die Steckdose ist aber nicht eingeschaltet worden, beim nächsten Pollen der Fritzbox war der wirkliche Status wieder off, die structure ging auf 'undefined'.
Hat mich Wahnsinnig gemacht. Habe dann auch das FBAHAHTTP device gelöscht und neu angelegt. Hat alles nicht geholfen.

Nach einem FHEM Neustart ist jetzt aber alles wieder gut. Vielleicht hilfts Dir auch.

[rudolfkoenig]

Nach einem FHEM Neustart ist jetzt aber alles wieder gut.

Das klingt so, dass SID nicht mehr gueltig war, normalerweise bekommt FHBAHTTP nach dem Ablauf der gueltigkeit eine Fehlermeldung, und versucht es neu.
Mann kann das Neu-Holen der SID auch mit set password oder attr fritzbox-user erzwingen.

[Jamo]

Hallo Rudolf, danke, 'set password' als auch attr fritzbox-user hatte ich glaube ich probiert, ich habe danach ja auch das FBAHAHTTP device gelöscht und neu angelegt, letztendlich war es der Neustart der geholfen hat. Vielleicht kann user wk set password und attr fritzbox-user ja nochmal ausprobieren, vor einem Neustart.
Danke nochmal!

[Jamo]

Mir fällt nochwas ein, ich hatte gestern vorher pi-hole installiert auf einem getrenntem Pi (nicht der FHEM pi), und hatte danach probleme mit DNS, und habe dann ein paarmal die DNS in der FB aendern muessen, und die FB rebooten muessen, bis alles wieder funktionierte. Kann sein das das was damit zu tun hatte!?!?!
Auf jedenfall hatte ich auch das Verhalten des user WK.

[wk]

Ich glaube, ich habe das Problem eingekreist. Es ist das Blank in der AIN. Wenn man es herausnimmt, dann geht es.

root@uhs:~# curl "http://192.168.17.1/webservices/homeautoswitch.lua?sid=f2127a49004df6b8&ain=08761 0292048&switchcmd=setswitchon"
curl: (52) Empty reply from server

root@uhs:~# curl "http://192.168.17.1/webservices/homeautoswitch.lua?sid=f2127a49004df6b8&ain=087610292048&switchcmd=setswitchon"
1
root@uhs:~#

[rudolfkoenig]

Danke fuer den Hinweis: ich habe jetzt beim Schalten im AIN das Leerzeichen weggemacht, bei mir funktioniert es immer noch.
Die Aenderung gibt es ab morgen um 8 per FHEM-update, oder ab sofort im SVN.

Wuesste aber gerne, welche Komponente bei Dir sich daran aufhaengt.

[wk]

Vielen Dank Rudolf,

ich werde es morgen testen.

Ich kann mir nur vorstellen, dass der Proxy in der Firewall dazwischen funkt, denn wenn das Blank da ist, taucht der ganze Befehl nicht im log der Firewall auf. Ich bin kein Experte für curl, aber es scheint als das durch das Blank, auch wenn es in Hochkommas steht, der Befehl geteilt wird und damit nicht erkannt. Er wird dann garnicht an den Proxy weitergeleitet. Warum das der Firefox richtig macht, verstehe ich auch nicht.

[rudolfkoenig]

Verstehe ich richtig: FHEM greift bei Dir durch ein Proxy auf dem Fritz!BOX zu?

Den Rest kann ich dir erklaeren: im URL muss man eigentlich Leerzeichen als %20 kodieren. Mein Fritz!BOX stoert sich aber nicht an der fehlenden Kodierung, und ich habe mich gewundert, wieso das bei Dir der Fall ist.

[wk]

Der Test verlief positiv. Vielen Dank dafür.

Ich habe auch probiert, das blank durch %20 im curl-Befehl zu ersetzen. Auch das funktioniert.
Bleibt nur die Frage, ob Firefox ein blank in der URL automatisch durch %20 ersetzt?

Und die Frage, warum die Fritzbox eine AIN mit blank erzeugt, wenn es dann doch ignoriert wird?

[rudolfkoenig]

Bleibt nur die Frage, ob Firefox ein blank in der URL automatisch durch %20 ersetzt?

Ja, das ist eigentlich "Vorschrift".

Und die Frage, warum die Fritzbox eine AIN mit blank erzeugt, wenn es dann doch ignoriert wird?

Falsche Frage
AIN kann gerne Leerzeichen oder sonstwas enthalten, man muss es aber per URL schuetzen, d.h. das nicht zu tun war mein Fehler.
Die richtige Frage ist: wieso ist das mir und geschaetzt 2000 anderen FHEM/FBAHAHTTP Benutzer das Problem bisher nicht aufgefallen?

[andreas_n]

Hallo. Ich habe auch das gleiche Problem und habe gerade eine FHEM-Update durchlaufen lassen. Das Password habe ich ebenfalls neu eingegeben.
Nach dem Update / Neustart reagieren die Schalter aus FHEM heraus gar nicht mehr. (Vorher Ausfall ca all 24/48)

Hier ein Beispiel nach dem Update/Neustart...

Code Auswählen Erweitern

2019.01.22 14:25:38 3: FBDECT set fb_LichtSofa on
2019.01.22 14:25:48 5: Fritzbox: dispatch <device identifier="08761 0104145" id="16" functionbitmask="2944" fwversion="04.09" manufacturer="AVM" productname="FRITZ!DECT 200"><present>1</present><name>fb_LichtSofa</name><switch><state>0</state><mode>manuell</mode><lock>0</lock><devicelock>0</devicelock></switch><powermeter><voltage>228827</voltage><power>0</power><energy>7951</energy></powermeter><temperature><celsius>190</celsius><offset>0</offset></temperature></device>
2019.01.22 14:25:48 5: fb_LichtSofa
2019.01.22 14:25:48 5:    celsius = 190
2019.01.22 14:25:48 5:    voltage = 228827
2019.01.22 14:25:48 5:    manufacturer = AVM
2019.01.22 14:25:48 5:    mode = manuell
2019.01.22 14:25:48 5:    id = 16
2019.01.22 14:25:48 5:    offset = 0
2019.01.22 14:25:48 5:    devicelock = 0
2019.01.22 14:25:48 5:    energy = 7951
2019.01.22 14:25:48 5:    state = 0
2019.01.22 14:25:48 5:    identifier = 08761 0104145
2019.01.22 14:25:48 5:    productname = FRITZ!DECT 200
2019.01.22 14:25:48 5:    present = 1
2019.01.22 14:25:48 5:    name = fb_LichtSofa
2019.01.22 14:25:48 5:    power = 0
2019.01.22 14:25:48 5:    fwversion = 04.09
2019.01.22 14:25:48 5:    lock = 0
2019.01.22 14:25:48 5:    functionbitmask = 2944
2019.01.22 14:25:49 5: Fritzbox: dispatch <device identifier="08761 0186657" id="17" functionbitmask="2944" fwversion="04.09" manufacturer="AVM" productname="FRITZ!DECT 200"><present>1</present><name>fb_SwHeizung</name><switch><state>1</state><mode>manuell</mode><lock>0</lock><devicelock>0</devicelock></switch><powermeter><voltage>228679</voltage><power>10290</power><energy>472628</energy></powermeter><temperature><celsius>190</celsius><offset>0</offset></temperature></device>

[rudolfkoenig]

Problem bitte mit "attr global verbose 5" dokumentieren, und Problemlog hier hochladen.

[andreas_n]

Hoffe ich habe das Richtige rausgefiltert.....
( FB 6490 (Cable), FW: 7.0 )

2019.01.23 01:03:12 4: WEB_106.181.80.132_50767 POST /fhem?cmd.fb_LichtSofa=set%20fb_LichtSofa%20on&room=Fritzbox,Level_1&XHR=1&fwcsrf=csrf_831481178068712&fw_id=55614; BUFLEN:0
2019.01.23 01:03:12 5: Cmd: >set fb_LichtSofa on<
2019.01.23 01:03:12 3: FBDECT set fb_LichtSofa on
2019.01.23 01:03:12 5: Starting notify loop for fb_LichtSofa, 1 event(s), first is on
2019.01.23 01:03:12 5: rain_notify: devname=fb_LichtSofa rainname=CalRegen, dev=fb_LichtSofa, dev_regex=Regen rain_name=Rain_total israining_name=israining
2019.01.23 01:03:12 5: mqttPublishList: not on any display, ignoring notify
2019.01.23 01:03:12 5: Triggering n_publish_mqtt
2019.01.23 01:03:12 4: n_publish_mqtt exec {
    my $mqttReport = AttrVal($NAME, 'mqttReport', '');;

    if ($mqttReport ne '') {
        my $reading = "";;
        my $message = "";;

        if ($EVENT =~ qr/(.*?): (.*)/p) {
            $reading = $1;;
            $message = $2;;
        } else {
            $reading = "state";;
            $message = $EVENT;;
        }
        if ($mqttReport=~m/$reading/ || $mqttReport eq 'all') {
           my $topic = "/fhem/$NAME/$reading";;
           fhem("set mqtt publish $topic $message");;
        }
    }
}

2019.01.23 01:03:12 5: Cmd: >{
    my $mqttReport = AttrVal($NAME, 'mqttReport', '');

    if ($mqttReport ne '') {
        my $reading = "";
        my $message = "";

        if ($EVENT =~ qr/(.*?): (.*)/p) {
            $reading = $1;
            $message = $2;
        } else {
            $reading = "state";
            $message = $EVENT;
        }
        if ($mqttReport=~m/$reading/ || $mqttReport eq 'all') {
           my $topic = "/fhem/$NAME/$reading";
           fhem("set mqtt publish $topic $message");
        }
    }
}
<
2019.01.23 01:03:12 5: End notify loop for fb_LichtSofa
2019.01.23 01:03:12 4: WEB: /fhem?cmd.fb_LichtSofa=set%20fb_LichtSofa%20on&room=Fritzbox,Level_1&XHR=1&fwcsrf=csrf_831481178068712&fw_id=55614 / RL:20 / text/plain; charset=UTF-8 / Content-Encoding: gzip
/ Cache-Control: no-cache, no-store, must-revalidate

..............

2019.01.23 01:03:23 5: <devicelist version="1"><device identifier="08761 0104145" id="16" functionbitmask="2944" fwversion="04.09" manufacturer="AVM" productname="FRITZ!DECT 200"><present>1</present><name>fb_LichtSofa</name><switch><state>0</state><mode>manuell</mode><lock>0</lock><devicelock>0</devicelock></switch><powermeter><voltage>235062</voltage><power>0</power><energy>7951</energy></powermeter><temperature><celsius>195</celsius><offset>0</offset></temperature></device><device identifier="08761 0186657" id="17" functionbitmask="2944" fwversion="04.09" manufacturer="AVM" productname="FRITZ!DECT 200"><present>1</present><name>fb_SwHeizung</name><switch><state>1</state><mode>manuell</mode><lock>0</lock><devicelock>0</devicelock></switch><powermeter><voltage>233890</voltage><power>10580</power><energy>473140</energy></powermeter><temperature><celsius>195</celsius><offset>0</offset></temperature></device><device identifier="08761 0100706" id="18" functionbitmask="2944" fwversion="04.09" manufacturer="AVM" productname="FRITZ!DECT 200"><present>1</present><name>fb_SwGeschirrSpuelM</name><switch><state>1</state><mode>manuell</mode><lock>0</lock><devicelock>0</devicelock></switch><powermeter><voltage>233889</voltage><power>0</power><energy>241646</energy></powermeter><temperature><celsius>160</celsius><offset>0</offset></temperature></device><device identifier="08761 0186658" id="19" functionbitmask="2944" fwversion="04.09" manufacturer="AVM" productname="FRITZ!DECT 200"><present>1</present><name>fb_SwWaschmaschine</name><switch><state>1</state><mode>manuell</mode><lock>0</lock><devicelock>0</devicelock></switch><powermeter><voltage>233574</voltage><power>0</power><energy>522487</energy></powermeter><temperature><celsius>145</celsius><offset>0</offset></temperature></device><device identifier="08761 0136986" id="20" functionbitmask="2944" fwversion="04.09" manufacturer="AVM" productname="FRITZ!DECT 200"><present>1</present><name>fb_SwPelletOfen</name><switch><state>0</state><mode>manuell</mode><lock>0</lock><devicelock>0</devicelock></switch><powermeter><voltage>233959</voltage><power>0</power><energy>115308</energy></powermeter><temperature><celsius>165</celsius><offset>0</offset></temperature></device><device identifier="08761 0148507" id="21" functionbitmask="2944" fwversion="04.09" manufacturer="AVM" productname="FRITZ!DECT 200"><present>1</present><name>fb_SwEntfGrKeller</name><switch><state>0</state><mode>manuell</mode><lock>0</lock><devicelock>0</devicelock></switch><powermeter><voltage>233611</voltage><power>0</power><energy>320930</energy></powermeter><temperature><celsius>185</celsius><offset>0</offset></temperature></device><device identifier="08761 0095162" id="22" functionbitmask="2944" fwversion="04.09" manufacturer="AVM" productname="FRITZ!DECT 200"><present>1</present><name>Sw_MWelleKeller</name><switch><state>0</state><mode>manuell</mode><lock>0</lock><devicelock>0</devicelock></switch><powermeter><voltage>234815</voltage><power>0</power><energy>22205</energy></powermeter><temperature><celsius>170</celsius><offset>0</offset></temperature></device><device identifier="08761 0472177" id="24" functionbitmask="2944" fwversion="04.09" manufacturer="AVM" productname="FRITZ!DECT 200"><present>1</present><name>fb_WasserPumpe</name><switch><state>1</state><mode>manuell</mode><lock>0</lock><devicelock>0</devicelock></switch><powermeter><voltage>233355</voltage><power>1000</power><energy>47747</energy></powermeter><temperature><celsius>150</celsius><offset>0</offset></temperature></device></devicelist>

2019.01.23 01:03:23 5: Fritzbox: dispatch <device identifier="08761 0104145" id="16" functionbitmask="2944" fwversion="04.09" manufacturer="AVM" productname="FRITZ!DECT 200"><present>1</present><name>fb_LichtSofa</name><switch><state>0</state><mode>manuell</mode><lock>0</lock><devicelock>0</devicelock></switch><powermeter><voltage>235062</voltage><power>0</power><energy>7951</energy></powermeter><temperature><celsius>195</celsius><offset>0</offset></temperature></device>
2019.01.23 01:03:23 5: fb_LichtSofa
2019.01.23 01:03:23 5:    functionbitmask = 2944
2019.01.23 01:03:23 5:    lock = 0
2019.01.23 01:03:23 5:    fwversion = 04.09
2019.01.23 01:03:23 5:    power = 0
2019.01.23 01:03:23 5:    name = fb_LichtSofa
2019.01.23 01:03:23 5:    productname = FRITZ!DECT 200
2019.01.23 01:03:23 5:    present = 1
2019.01.23 01:03:23 5:    state = 0
2019.01.23 01:03:23 5:    identifier = 08761 0104145
2019.01.23 01:03:23 5:    devicelock = 0
2019.01.23 01:03:23 5:    energy = 7951
2019.01.23 01:03:23 5:    offset = 0
2019.01.23 01:03:23 5:    id = 16
2019.01.23 01:03:23 5:    mode = manuell
2019.01.23 01:03:23 5:    manufacturer = AVM
2019.01.23 01:03:23 5:    celsius = 195
2019.01.23 01:03:23 5:    voltage = 235062

[rudolfkoenig]

Ich sehe keinen Versuch, den FritzBox zu steuern. Entweder wurde es aus dem Log entfernt, oder der FBDECT IODev ist falsch gesetzt.
Kannst du bitte "list -r fb_LichtSofa" (Detail-Ansicht, Raw Definition) hier anhaengen?

[andreas_n]

Gerne ...

Code Auswählen Erweitern

define fb_LichtSofa FBDECT Fritzbox:16 switch
attr fb_LichtSofa DbLogExclude .*
attr fb_LichtSofa IODev Fritzbox
attr fb_LichtSofa alexaName Licht Sofa
attr fb_LichtSofa alexaRoom Wohnzimmer
attr fb_LichtSofa room Fritzbox,Level_1

setstate fb_LichtSofa off
setstate fb_LichtSofa 2019-01-23 14:05:25 AIN 08761 0104145
setstate fb_LichtSofa 2019-01-23 14:05:25 FBNAME fb_LichtSofa
setstate fb_LichtSofa 2019-01-23 14:05:25 FBPROP powerMeter,tempSensor,switch
setstate fb_LichtSofa 2019-01-23 14:05:25 FBTYPE FRITZ!DECT 200
setstate fb_LichtSofa 2019-01-23 14:05:25 ID 16
setstate fb_LichtSofa 2018-09-16 04:45:29 control on fn=1 > 0.00 delay:0sec do:state off
setstate fb_LichtSofa 2018-09-30 04:50:03 current 0.0000 A
setstate fb_LichtSofa 2019-01-23 14:05:25 devicelock no
setstate fb_LichtSofa 2019-01-23 14:05:25 energy 7951 Wh
setstate fb_LichtSofa 2019-01-23 14:05:25 fwversion 04.09
setstate fb_LichtSofa 2019-01-23 14:05:25 locked no
setstate fb_LichtSofa 2019-01-23 14:05:25 mode manuell
setstate fb_LichtSofa 2018-09-16 04:45:27 options powerOnState:last,lock:none
setstate fb_LichtSofa 2019-01-23 14:05:25 power 0.00 W
setstate fb_LichtSofa 2018-09-30 04:50:03 powerFactor 0.000
setstate fb_LichtSofa 2019-01-23 14:05:25 present yes
setstate fb_LichtSofa 2019-01-23 14:05:25 state off
setstate fb_LichtSofa 2019-01-23 14:05:25 tempadjust 0.0 C
setstate fb_LichtSofa 2019-01-23 14:05:25 temperature 19.5 C (measured)
setstate fb_LichtSofa 2019-01-23 14:05:25 voltage 230.481 V

[rudolfkoenig]

Kannst du bitte die Ausgabe von "list Fritzbox" (diesmal nicht list -r) hier anhaengen?
Falls CmdStack nicht leer ist, versuch bitte FHEM neu zu starten, und dann das Befehl nochmal abzusetzen.
Am besten mit "attr global verbose 5", fuer den Fall, dass es wieder schiefgeht.

[andreas_n]

Hier das Feedback von "list Fritzbox"
Im Moment scheint die Fritzbox auch wieder zur reagieren. Muss mal abwarten bis der nächste Fehler auftritt.

Internals:
   Clients    :FBDECT:
   DEF        192.168.1.1
   FUUID      5c486557-f33f-84cd-90a5-f7b17ac0e2cce179
   NAME       Fritzbox
   NR         670
   STATE      connected
   TYPE       FBAHAHTTP
   CmdStack:
   MatchList:
     1:FBDECT   .*
Attributes:
   DbLogExclude .*
   fritzbox-user fhem_fritzbox
   polltime   15
   room       Fritzbox

[rudolfkoenig]

Ich vermute eine seltene Konstellation als Ursache: wenn mehr als ein Befehl im Queue ist, und der FritzBox eine Weile nicht erreichbar ist, dann holt zwar FHEM irgendwann eine neue SID (d.h. update funktioniert weiter), aber die Befehle aus dem Queue werden nicht mehr abgearbeitet (und CmdStack im list ist nicht leer).

Ich habe versucht das Problem zu loesen (fhem-update morgen ab acht), kann es aber nicht nachstellen, insofern bleibe ich unsicher.

[andreas_n]

Danke. Ich gebe gerne später ein Feedback. Das Update werde ich installieren. Bislang war die Fritzbox mit den Steckdosen immer sehr zuverlässig.

[Jamo]

Hallo Rudi, ja, das wird es sein, wenn die Dect nicht geschalten haben, habe ich auch gesehen das im list dann immer eine lange Schlange von nicht abgearbeiteten Befehlen zu sehen war. Danke!

[andreas_n]

Habe das Update durchgeführt. Erst dachte ich, dass das Problem behoben ist aber dann

Code Auswählen Erweitern

Internals:
   Clients    :FBDECT:
   DEF        192.168.1.1
   FUUID      5c486557-f33f-84cd-90a5-f7b17ac0e2cce179
   NAME       Fritzbox
   NR         670
   STATE      connected
   TYPE       FBAHAHTTP
   CmdStack:
     ain=087610136986&switchcmd=setswitchoff
     ain=087610136986&switchcmd=setswitchoff
     ain=087610136986&switchcmd=setswitchoff
     ain=087610136986&switchcmd=setswitchoff
     ain=087610136986&switchcmd=setswitchoff
     ain=087610136986&switchcmd=setswitchoff

[rudolfkoenig]

Ein

Code Auswählen Erweitern

{$defs{Fritzbox}{CmdStack} = ()}wird das Problem vmtl. beheben. Was mir nicht klar ist, wie es dazugekommen ist. Und das brauche ich, um das Problem zu beheben. Leider habe ich ausser "attr global verbose 5" keine gute Idee, wie man das Problem lokalisieren koennte.

[Wzut]

@Rudi , das ist doch das alte Problem von hier https://forum.fhem.de/index.php/topic,58147.msg855049.html#msg855049 ?
ich dachte die auto Löschung von CmdStack wäre jetzt drin ? Seit ich sie bei mir dazugebastelt habe ist Ruhe.

[rudolfkoenig]

ich dachte die auto Löschung von CmdStack wäre jetzt drin ?

Im Link wird der CmdStack nicht geloescht, sondern SID nicht ins Stack gepackt, damit nach einem reconnect die Neue verwendet wird.

Hier scheint es sich um was Anderes zu handeln, sowas wie FritzBOX antwortet nicht, und HttpUtils vergisst den Callback aufzurufen. Weiss aber nicht, wie das passierren kann, evtl. liege ich ganz falsch.

[frank]

vielleicht wäre ein wireshark-mitschnitt in der fritzbox (http://fritz.box/html/capture.html) erhellend.

[andreas_n]

Ich glaube was gefunden zu haben. Werden Kommandos sehr schnell  hintereinander gesendet  kann es zu diesem Problem kommen. Ich habe meine Seite etwas abgeändert und habe seit 2Tagen keine Probleme mehr.

[rainer1962]

Also bei mir ist das gleiche Problem seit ca. 2 Monaten vorhanden.nach 1-3 Tagen problemlosen betriebes streiken meine 4xDect200 . ich kann nix schalten, gelesen werden alle werte.ich kann dann fbahahttp neu definieren (modify)  dann gehts mal wieder 1Tag oder 3Tage. Danach hilft dies auch nicht und ich muss den kompletten Raspi neu starten.Ich habe nichts geändert an der Konfig nur immer fleissig Updates eingespielt von FHEM.Vorher lief das alles mit den 4 Steckdosen 3 Jahre fehlerfrei durch.Wo kann die Ursache liegen?

[Wzut]

@rainer1962, wenn es mal wieder nicht geht und bevor du dein modify von FBAHAHTTP machst oder gar den Raspi neu bootest
gib erst einmal in der Kommandozeile "list TYPE=FBAHAHTTP" ein und poste die Ausgabe hier um zu sehen ob CmdStack leer ist oder sich da
schon einiges angesammelt hat.

[rainer1962]

ok,werde ich machen ... mal sehen obs am WE schon so weit ist   

[rainer1962]

so, heute war es soweit:
Internals:
   Clients    :FBDECT:
   DEF        192.168.25.1
   FUUID      5c42f814-f33f-1719-aacc-61a3caecc87f0893
   NAME       FBDECT
   NR         829
   STATE      connected
   TYPE       FBAHAHTTP
   CmdStack:
     ain=087610375064&switchcmd=setswitchoff
     ain=087610006087&switchcmd=setswitchoff
     ain=087610375071&switchcmd=setswitchon
   MatchList:
     1:FBDECT   .*
Attributes:
   fritzbox-user 1
   polltime   30
   room       FBDECT,FritzBoxen-System
was kann das sein?

[rainer1962]

Code Auswählen Erweitern

Internals:   Clients    :FBDECT:
   DEF        192.168.25.1
   FUUID      5c42f814-f33f-1719-aacc-61a3caecc87f0893
   NAME       FBDECT
   NR         829
   STATE      connected
   TYPE       FBAHAHTTP
   CmdStack:
     ain=087610375064&switchcmd=setswitchoff
     ain=087610006087&switchcmd=setswitchoff
     ain=087610375071&switchcmd=setswitchon
     ain=087610375064&switchcmd=setswitchon
   MatchList:
     1:FBDECT   .*
Attributes:
   fritzbox-user 1
   polltime   30
   room       FBDECT,FritzBoxen-System
   
nach update und restart heute 08.03.2019 
   

Code Auswählen Erweitern

Internals:
   Clients    :FBDECT:
   CmdStack   
   DEF        192.168.25.1
   FUUID      5c42f814-f33f-1719-aacc-61a3caecc87f0893
   NAME       FBDECT
   NR         828
   STATE      connected
   TYPE       FBAHAHTTP
   MatchList:
     1:FBDECT   .*
Attributes:
   fritzbox-user 1
   polltime   30
   room       FBDECT,FritzBoxen-System

und jetzt geht das schalten vorerst wieder.

[Wzut]

das hatte ich vermutet, wenn es nicht mehr geht "stauen" sich die Schaltbefehle in CmdStack und der Stack wird niemals wieder abgearbeitet.
War bei mir damals auch so bis ich die Überwachung eingebaut habe und ihn mit Gewalt lösche im Fehlerfall, seit dem ist Ruhe.

[rainer1962]

Hallo Wzut,bei mir ist es noch schlimmer wenn ich die polltime auf 5sec nehme. dann tritt das jeden tag auf.bei polltime 30sec gehts manchmal ne woche und manchmal 3 tage ....was hast du genau gemacht?kannst du mir das erklären?
danke dir schon .

[Wzut]

das Thema des CmdStack hatten wir hier -> https://forum.fhem.de/index.php/topic,58147.msg852587.html#msg852587
und laut Rudis letztem Post sollte er seit dem gelöscht werden, was aber bei dir offensichtlich nicht passiert !
Ich muß mal schauen was ich bei mir alles verändert habe und mit Rudis letzter Version vergleichen, dann kann er es wenn er es will teilweise oder ganz übernehmen.

[rainer1962]

den post hate ich auch schon gesehen und die änderungen sind bei mir drin. habe die version "00_FBAHAHTTP.pm 18401 2019-01-24 14:07:26Z" bei mir im einsatz.Wäre froh wenn die Steckdosen wieder ornungsgemäß funktionieren würden, wie schon vorher seit jahren ...

[Wzut]

@ rainer1962, wenn du magst kannst du ja mal meine geänderte Version testen, ich habe seit 1. November 2018 damit Ruhe.
Die Version bringt ein Readings mit CS (CmdStack), ist zwar nicht unbedingt nötig, war aber für mich sehr hillfreich da ich zum einen den Wert extra logge und eine Grafik damit befülle und zum anderen sieht man sofort ohne list ob da sich da wieder ein Stau anbahnt. Zusätzlich lasse ich noch den Poll ausfallen wenn gerade ein aktiver Schaltbefehl ansteht ( erzeugt auch entsprechende Logmeldungen ) vllt. war das ja auch die entscheidende Änderung ?
Du schreibst ja das du das alles relativ schnell mit deiner kurzen Pollzeit von 5 Sekunden testen kannst.

[rainer1962]

danke dir Wzut.habe mal deine 00_FBAHAHTTP.pm eingebaut, fhem neu gestartet und die poltime auf 5 sec gesetzt.bin gespannt wie lange es geht ohne probleme ....ich melde mich wieder...
danke.

[rainer1962]

habe schon jetzt bei einigen schaltungen das CS-Reading auf 1 gebracht.wurde aber ordenungsgemäß nach kurzer zeit, ca. 1sec später, auf 0 wieder gesetzt. das schalten funktioniert vorerst.   

[Wzut]

fein lass es mal ein paar Tage laufen. U.U. kannst du ja auch ein extra Log anlegen um das CS Reading zu loggen, dann kannst du immer leicht nachschauen was den letzten Tag über so los war. Hab auch mal ein Auge auf das normale Logfile ob und wie oft normale Polls ausgelasssen werden.

[rainer1962]

mach ich Wzut.habe den loglevel beim fbahahttp auf 3 gesetzt  .... bin gespannt ..

[Wzut]

Level 3 reicht, da die mit Level 1 durchschlagen , ich habe pro Tag so 3-4 davon im Log :

Code Auswählen Erweitern

2019.03.09 09:57:48 1: fb1: Poll ist noch aktiv !
2019.03.09 09:57:51 1: fb1: Wiederholung weil Poll aktiv

[rainer1962]

bis jetzt bei mir noch nicht viel :

Code Auswählen Erweitern

2019.03.09 18:03:35.627 0: Featurelevel: 5.9
2019.03.09 18:03:35.628 0: Server started with 571 defined entities (fhem.pl:18799/2019-03-05 perl:5.024001 os:linux user:fhem pid:8463)
2019.03.09 18:54:15.615 1: FBDECT: Poll ohne SID !
2019.03.09 18:54:16.176 1: FBAHAHTTP_connect FBDECT: got SID f59739e14a7c8377
2019.03.09 18:55:38.605 1: FBDECT: Poll ist noch aktiv !
2019.03.09 18:55:39.071 1: FBDECT: Poll ist noch aktiv !
2019.03.09 18:55:41.016 1: FBDECT: Wiederholung weil Poll aktiv
2019.03.09 18:55:42.812 1: FBDECT : CmdStack -> 1

[rainer1962]

so Wzut,in der Nacht war es wieder soweit, kein schalten mehr möglich:

Code Auswählen Erweitern

Internals:
   Clients    :FBDECT:
   DEF        192.168.25.1
   FUUID      5c42f814-f33f-1719-aacc-61a3caecc87f0893
   NAME       FBDECT
   NR         828
   Pac        1
   STATE      connected
   TYPE       FBAHAHTTP
   CmdStack:
     ain=08761 0375071&switchcmd=setswitchoff
     ain=08761 0375064&switchcmd=setswitchoff
     ain=08761 0006087&switchcmd=setswitchoff
     ain=08761 0375071&switchcmd=setswitchon
     ain=08761 0381439&switchcmd=setswitchon
   MatchList:
     1:FBDECT   .*
   READINGS:
     2019-03-10 08:34:03   CS              5
Attributes:
   fritzbox-user 1
   polltime   5
   room       FBDECT,FritzBoxen-System
   verbose    3

und im log:

Code Auswählen Erweitern

2019.03.09 18:02:33.798 0: Server shutdown
2019.03.09 18:03:35.583 0: Alarm: FHEM wurde gerade neu gestartet!
2019.03.09 18:03:35.627 0: Featurelevel: 5.9
2019.03.09 18:03:35.628 0: Server started with 571 defined entities (fhem.pl:18799/2019-03-05 perl:5.024001 os:linux user:fhem pid:8463)
2019.03.09 18:54:15.615 1: FBDECT: Poll ohne SID !
2019.03.09 18:54:16.176 1: FBAHAHTTP_connect FBDECT: got SID f59739e14a7c8377
2019.03.09 18:55:38.605 1: FBDECT: Poll ist noch aktiv !
2019.03.09 18:55:39.071 1: FBDECT: Poll ist noch aktiv !
2019.03.09 18:55:41.016 1: FBDECT: Wiederholung weil Poll aktiv
2019.03.09 18:55:42.812 1: FBDECT : CmdStack -> 1
2019.03.10 00:15:07.952 1: FBDECT Poll Fehler : read from http://192.168.25.1:80 timed out
2019.03.10 00:15:09.719 1: FBDECT: Poll ohne SID !
2019.03.10 00:15:10.244 1: FBAHAHTTP_connect FBDECT: got SID 81bb9c783ad18cd8
2019.03.10 01:04:57.473 1: FBDECT Poll Fehler : read from http://192.168.25.1:80 timed out
2019.03.10 01:04:57.488 1: FBDECT: Poll ohne SID !
2019.03.10 01:04:58.005 1: FBAHAHTTP_connect FBDECT: got SID d7d10a64389ab51f
2019.03.10 02:08:02.343 1: FBDECT: Poll ist noch aktiv !
2019.03.10 02:08:03.592 1: FBDECT: Poll ist noch aktiv !
2019.03.10 03:42:50.340 1: FBDECT Poll Fehler : read from http://192.168.25.1:80 timed out
2019.03.10 03:42:50.605 1: FBDECT: Poll ohne SID !
2019.03.10 03:42:53.521 1: FBAHAHTTP_connect FBDECT: got SID bbfd0773333a7ed4
2019.03.10 03:45:40.126 1: FBDECT Poll Fehler : read from http://192.168.25.1:80 timed out
2019.03.10 03:45:46.413 1: FBDECT: Poll ohne SID !
2019.03.10 03:45:47.223 1: FBAHAHTTP_connect FBDECT: got SID b8bbc6b623ac6ae4
2019.03.10 03:47:54.736 1: FBDECT Poll Fehler : read from http://192.168.25.1:80 timed out
2019.03.10 03:47:54.737 1: FBDECT: Poll ohne SID !
2019.03.10 03:47:55.521 1: FBAHAHTTP_connect FBDECT: got SID b13fa5386e4eaa48
2019.03.10 05:06:02.048 1: FBDECT Poll Fehler : read from http://192.168.25.1:80 timed out
2019.03.10 05:06:06.788 1: FBDECT: Poll ohne SID !
2019.03.10 05:06:07.305 1: FBAHAHTTP_connect FBDECT: got SID a45874f7a934507d
2019.03.10 05:29:01.745 1: FBDECT Poll Fehler : read from http://192.168.25.1:80 timed out
2019.03.10 05:29:09.072 1: FBDECT: Poll ohne SID !
2019.03.10 05:29:09.595 1: FBAHAHTTP_connect FBDECT: got SID d41dde0e0b28dfd3
2019.03.10 06:13:27.863 1: FBDECT Poll Fehler : read from http://192.168.25.1:80 timed out
2019.03.10 06:13:34.433 1: FBDECT: Poll ohne SID !
2019.03.10 06:13:34.950 1: FBAHAHTTP_connect FBDECT: got SID 84e3e3a66a092e6a
2019.03.10 06:30:39.029 1: FBDECT Poll Fehler : read from http://192.168.25.1:80 timed out
2019.03.10 06:30:42.853 1: FBDECT: Poll ohne SID !
2019.03.10 06:30:43.367 1: FBAHAHTTP_connect FBDECT: got SID 7d420074a828ff45
Ich verzweifele langsam....gibt es denn noch eine version von mitte letztem jahr ? da ging es doch noch ganz gut.

[rainer1962]

konnte dies gleich mehrmals nachstellen.nach einem modify ging es einzeln wieder.gebe ich in einer befehlssequenz zb:"set BalkonPumpe on;set BalkonPumpe off;set BalkonPumpe on;set BalkonPumpe off;set BalkonPumpe on;set BalkonPumpe on"ein, dann gehts nicht mehr... im Log erschein dann aber auch nix mehr, außer das der cmd-stack voll ist..

[Wzut]

ich befürchte Rudi hatte mit seiner Aussage in Antwort #30 am: 28 Januar 2019 recht als er schrieb hier geht es um ein anderes Problem als damals bei mir.
Daher hilft dir meine Version auch nicht wirklich weiter, allerdings dein Problem des viele Geräte auf einen Rutsch schalten könntest du mit dem Attribut async-delay vermutlich entschärfen (und natürlich die extreme Pollzeit von 5 Sekunden wieder hoch setzen)

[rainer1962]

habe ich vorerst gemacht:pollzeit auf 30sec und async-delay auf 1sec
mal sehen wie es sich es bemerkbar macht.danke für deine hilfe.