[gelöst]fbahahttp - ERROR: cannot get SID, check hostname/fritzbox-user/password

Klaus Rubik · 09 Mai 2016, 09:21:38

Guten Morgen,

ich habe heute gleich mal das neue Modul aktiviert, jedoch bekomme if folgenden Fehler im log:

2016.05.09 09:16:50 2: ERROR: cannot get SID, check hostname/fritzbox-user/password

Hier die Devicedefinition:

Internals: 
   CFGFN 
   Clients    :FBDECT: 
   DEF        192.168.0.1 
   NAME       fbahahttp 
   NR         620 
   STATE      ERROR: cannot get SID, check hostname/fritzbox-user/password 
   TYPE       FBAHAHTTP 
   Matchlist: 
     1:FBDECT   .* 
Attributes: 
   fritzbox-user fhemapi

Um keine Probleme mit der Namensauflösung zu bekommen, habe ich die IP der FB direkt eingetragen, User und PW habe ich auch überprüft.

Positiv

: Die Definition des bisherigen FBAHA devices wurde gleich gelösche und die iodev Einstellungen in den FBAHA Devices wurden sauber umgebogen.

Viele Grüße

Klaus

rudolfkoenig · 09 Mai 2016, 09:28:48

Welche FRITZ!OS Version ist installiert? Kanst du bitte ein log mit "attr global verbose 5" erstellen, in den Daten Benutzername/Passwort unkenntlich machen, und das Ergebnis hier anhaengen?

Klaus Rubik · 09 Mai 2016, 09:48:50

Hallo Rudi,

ich verwende eine Fritzbox 7390 mit OS Version 6.51.

Logauszug:

Code Auswählen


2016.05.09 09:46:50 5: Cmd: >set fbahahttp password xxx<
2016.05.09 09:46:50 4: configDB reading file: ./FHEM/FhemUtils/uniqueID
2016.05.09 09:46:50 4: configDB writing file: ./FHEM/FhemUtils/uniqueID
2016.05.09 09:46:50 4: configDB reading file: ./FHEM/FhemUtils/uniqueID
2016.05.09 09:46:50 4: HttpUtils url=http://192.168.0.1/login_sid.lua
2016.05.09 09:46:50 4: http://192.168.0.1/login_sid.lua: HTTP response code 200
2016.05.09 09:46:50 4: HttpUtils http://192.168.0.1/login_sid.lua: Got data, length: 165
2016.05.09 09:46:50 4: HttpUtils url=http://192.168.0.1/login_sid.lua?username=fhemuser
2016.05.09 09:46:51 4: http://192.168.0.1/login_sid.lua?username=fhemuser: HTTP response code 200
2016.05.09 09:46:51 4: HttpUtils http://192.168.0.1/login_sid.lua?username=fhemuser: Got data, length: 167
2016.05.09 09:46:51 2: ERROR: cannot get SID, check hostname/fritzbox-user/password
2016.05.09 09:46:51 5: Triggering fbahahttp (1 changes)
2016.05.09 09:46:51 5: Starting notify loop for fbahahttp, first event password xxx

Viele Grüße und Danke für die schnelle Unterstützung

Klaus

rudolfkoenig · 09 Mai 2016, 10:03:34

Hmmm. Bin nicht wirklich schlauer. Kannst du bitte das Ganze mit dem angehaengten FritzBoxUtils.pm ausfuehren? Sollte 3 zusaetzliche Log-Zeilen pro Aufruf generieren.

Klaus Rubik · 09 Mai 2016, 10:20:45

Hallo Rudi,

hier der neue Logoutput:

Code Auswählen

2016.05.09 10:18:18 5: Cmd: >set fbahahttp password xxx<
2016.05.09 10:18:18 4: configDB reading file: ./FHEM/FhemUtils/uniqueID
2016.05.09 10:18:18 4: configDB writing file: ./FHEM/FhemUtils/uniqueID
2016.05.09 10:18:18 4: configDB reading file: ./FHEM/FhemUtils/uniqueID
2016.05.09 10:18:18 4: HttpUtils url=http://192.168.0.1/login_sid.lua
2016.05.09 10:18:18 4: http://192.168.0.1/login_sid.lua: HTTP response code 200
2016.05.09 10:18:18 4: HttpUtils http://192.168.0.1/login_sid.lua: Got data, length: 165
2016.05.09 10:18:18 1: FB_doCheckPW 1: <?xml version="1.0" encoding="utf-8"?><SessionInfo><SID>0000000000000000</SID><Challenge>f18eb757</Challenge><BlockTime>0</BlockTime><Rights></Rights></SessionInfo>

2016.05.09 10:18:18 1: FB_doCheckPW 2: response=f18eb757%2d4a694a8dae235f1a5bb2e84f9d1aa14a&page=%2flogin%5fsid%2elua
2016.05.09 10:18:18 4: HttpUtils url=http://192.168.0.1/login_sid.lua?username=fhemuser
2016.05.09 10:18:19 4: http://192.168.0.1/login_sid.lua?username=fhemuser: HTTP response code 200
2016.05.09 10:18:19 4: HttpUtils http://192.168.0.1/login_sid.lua?username=fhemuser: Got data, length: 167
2016.05.09 10:18:19 1: FB_doCheckPW 3: <?xml version="1.0" encoding="utf-8"?><SessionInfo><SID>0000000000000000</SID><Challenge>93e74092</Challenge><BlockTime>128</BlockTime><Rights></Rights></SessionInfo>

2016.05.09 10:18:19 2: ERROR: cannot get SID, check hostname/fritzbox-user/password
2016.05.09 10:18:19 5: Triggering fbahahttp (1 changes)
2016.05.09 10:18:19 5: Starting notify loop for fbahahttp, first event password xxx
2016.05.09 10:18:19 5: rain_notify: devname=fbahahttp rainname=Regenmenge, dev=fbahahttp, dev_regex=Regensensor rain_name=rain_total israining_name=isRaining
2016.05.09 10:18:19 4: Attribut matchs TimeCode DayChangeTime='06:00'
2016.05.09 10:18:19 5: 433_RSSI: not on any display, ignoring notify
2016.05.09 10:18:19 5: Notify from Device: fbahahttp recieved
2016.05.09 10:18:19 5: RSSI_Check: not on any display, ignoring notify
2016.05.09 10:18:19 5: battery_status: not on any display, ignoring notify
2016.05.09 10:18:19 5: Cmd: >attr global verbose 3<

rudolfkoenig · 09 Mai 2016, 11:54:41

Ich erhalte ein 0-er SID, falls Benutzername/Passwort falsch ist. BlockTime ist laut Doku "Die Zeit in Sekunden, in der kein weiterer Anmeldeversuch zugelassen wird", spricht auch dafuer. Im Erfolgsfall ist <Rights> nicht leer.

Getestet habe ich auch mit Version 6.51. Eventuell enthaelt dein Passwort "komische" Zeichen, die vom (inzwischen 3 Jahre ungeaenderten) FritzBoxUtils.pm/FB_doCheckPW nicht korrekt kodiert wird. Kannst du dich mit diesem user/password auf dem FritzBox anmelden?

Klaus Rubik · 09 Mai 2016, 12:05:12

Hallo Rudi,

ich habe jetzt mal PW = Username gesetzt, also keine Sonderzeichen oder dergleichen nur normale Buchstaben, alle "klein". Problem bleibt weiter bestehen.

Anmelden an der Fritzbox? Wie mache ich das, Telnet und ssh geht nicht.

VieleGrüße

Klaus

rudolfkoenig · 09 Mai 2016, 12:11:31

ZitatAnmelden an der Fritzbox? Wie mache ich das, Telnet und ssh geht nicht.

Es gibt eine HTTP-Benutzeroberflaeche

Bist du sicher, dass du den richtigen FritzBox ansprichst?
Verwendest du 72_FB_CALLMONITOR.pm oder 72_FRITZBOX.pm (beide nutzen die gleiche Methode).

Klaus Rubik · 09 Mai 2016, 12:24:16

Zitat von: rudolfkoenig am 09 Mai 2016, 12:11:31
Es gibt eine HTTP-Benutzeroberflaeche

Bist du sicher, dass du den richtigen FritzBox ansprichst?
Verwendest du 72_FB_CALLMONITOR.pm oder 72_FRITZBOX.pm (beide nutzen die gleiche Methode).

Die frägt aber nur nach dem Passwort zur Anmeldung als Admin

72_FB_CALLMONITOR.pm und 72_FRITZBOX.pm nutze ich, funktionieren beide...

Klaus Rubik · 09 Mai 2016, 12:29:21

UPDATE:

Hab die Einstellung in der Fritzbox gefunden und auf Username/PW umgestellt. Der verwendete User kann sich ohne Probleme anmelden. Das Problem mit fbahahttp bleibt aber bestehen.

Viele Grüße

Klaus

rudolfkoenig · 09 Mai 2016, 13:23:51

Zitat72_FB_CALLMONITOR.pm und 72_FRITZBOX.pm nutze ich, funktionieren beide...

Die verwenden doch die gleichen Funktionen, oder? Kannst du bitte deren Anmeldungsvorgang protokollieren?

Klaus Rubik · 09 Mai 2016, 14:04:31

Hallo Rudi,

hier der Output von 72_FRITZBOX.pm:

Code Auswählen

2016.05.09 14:00:44 5: Triggering global (1 changes)
2016.05.09 14:00:44 5: Starting notify loop for global, first event ATTR global verbose 5
2016.05.09 14:00:44 5: rain_notify: devname=global rainname=Regenmenge, dev=global, dev_regex=Regensensor rain_name=rain_total israining_name=isRaining
2016.05.09 14:00:44 4: Attribut matchs TimeCode DayChangeTime='06:00'
2016.05.09 14:00:44 5: 433_RSSI: not on any display, ignoring notify
2016.05.09 14:00:44 5: Notify from Device: global recieved
2016.05.09 14:00:44 5: RSSI_Check: not on any display, ignoring notify
2016.05.09 14:00:44 5: battery_status: not on any display, ignoring notify
2016.05.09 14:00:44 5: Cmd: >set Fritzbox password xxx<
2016.05.09 14:00:44 5: Cmd: >attr global verbose 3<

Folgende Kommandos habe ich dabei auf dem Telnetprompt von FHEM abgesetzt:

Code Auswählen

attr global verbose 5
set Fritzbox password xxx
attr global verbose 3

rudolfkoenig · 09 Mai 2016, 14:52:38

"set fritzbox password xxx" ist einer der wenigen Set-Funktionen im FRITZBOX Modul, was nicht mit dem FRITZ!Box redet, sondern nur das Passwort speichert.
Kannst du bitte sowas wie "guestWlan on" versuchen?

Klaus Rubik · 09 Mai 2016, 15:13:08

Hier der Output von set Fritzbox guestWlan on:

Code Auswählen

2016.05.09 15:12:13 5: Triggering global (1 changes)
2016.05.09 15:12:13 5: Starting notify loop for global, first event ATTR global verbose 5
2016.05.09 15:12:13 5: rain_notify: devname=global rainname=Regenmenge, dev=global, dev_regex=Regensensor rain_name=rain_total israining_name=isRaining
2016.05.09 15:12:13 4: Attribut matchs TimeCode DayChangeTime='06:00'
2016.05.09 15:12:13 5: 433_RSSI: not on any display, ignoring notify
2016.05.09 15:12:13 5: Notify from Device: global recieved
2016.05.09 15:12:13 5: RSSI_Check: not on any display, ignoring notify
2016.05.09 15:12:13 5: battery_status: not on any display, ignoring notify
2016.05.09 15:12:13 5: Cmd: >set Fritzbox guestWlan on<
2016.05.09 15:12:13 5: Cmd: >attr global verbose 3<

rudolfkoenig · 09 Mai 2016, 15:15:46

bin traurig, und gebe auf.

Klaus Rubik · 09 Mai 2016, 15:18:59

Schade, das wollte ich nicht.

Mir ist noch etwas aufgefallen, da Du ja ein existierendes fbaha Device ersetzt habe ich jetzt beim Restart von FHEM folgende Fheler im Log:

Code Auswählen

BZ_Strom: unknown IODev fbahahttp specified
GH_Ladestation: unknown IODev fbahahttp specified
WK_IT_Verbraucher: unknown IODev fbahahttp specified

Ich weiß nicht, ob der Fehler in der Reihenfolge auch bei Verwendung der fhem.ini auftritt, bei CONFIG-DB scheint es ein Problem zu sein.

Viele Grüße

Klaus

rudolfkoenig · 09 Mai 2016, 16:08:20

Zitatbei CONFIG-DB scheint es ein Problem zu sein.

"attr XXX IODev YYY" korrigiert die Reihenfolge ($hash->{NR}), offensichtlich haelt sich cfgDB_SaveCfg() nicht dran.

Klaus Rubik · 09 Mai 2016, 16:20:22

Danke, ich trage das mal ein und gebe dann den Status hier wieder

tupol · 09 Mai 2016, 17:41:30

Bzgl. FRITZBOX-Modul. Evtl. sieht man mehr, wenn das verbose nicht in der selben Sekunde wieder auf 3 gesetzt wird. Läuft schließlich nonblocking.

Klaus Rubik · 10 Mai 2016, 09:19:17

Zitat von: rudolfkoenig am 09 Mai 2016, 16:08:20
"attr XXX IODev YYY" korrigiert die Reihenfolge ($hash->{NR}), offensichtlich haelt sich cfgDB_SaveCfg() nicht dran.

Hallo Rudi,

der Eintrag des IODev hat das Problem behoben.

Aufgrund des Hinweises von TUPOL habe ich nochmal ein set FritzBox guestWlan on laufen lassen und den verbose Level erst nach 20 Sekunden wieder auf 3 gesetzt. Hier der Output:

Code Auswählen

2016.05.10 09:14:55 5: Triggering global (1 changes)
2016.05.10 09:14:55 5: Starting notify loop for global, first event ATTR global verbose 5
2016.05.10 09:14:55 5: rain_notify: devname=global rainname=Regenmenge, dev=global, dev_regex=Regensensor rain_name=rain_total israining_name=isRaining
2016.05.10 09:14:55 4: Attribut matchs TimeCode DayChangeTime='06:00'
2016.05.10 09:14:55 5: 433_RSSI: not on any display, ignoring notify
2016.05.10 09:14:55 5: Notify from Device: global recieved
2016.05.10 09:14:55 5: RSSI_Check: not on any display, ignoring notify
2016.05.10 09:14:55 5: battery_status: not on any display, ignoring notify
2016.05.10 09:14:55 5: Cmd: >set FritzBox guestWlan on<
2016.05.10 09:14:55 3: FRITZBOX: set FritzBox guestWlan on
2016.05.10 09:14:55 4: FRITZBOX FritzBox: Set_Cmd_Start.1961 Fork process FRITZBOX_GuestWlan_Run_Web
2016.05.10 09:14:55 4: BlockingCall (FRITZBOX_GuestWlan_Run_Web): created child (25422), uses telnetPort to connect back
2016.05.10 09:14:55 5: Triggering FritzBox (1 changes)
2016.05.10 09:14:55 5: Starting notify loop for FritzBox, first event guestWlan on
2016.05.10 09:14:55 5: rain_notify: devname=FritzBox rainname=Regenmenge, dev=FritzBox, dev_regex=Regensensor rain_name=rain_total israining_name=isRaining
2016.05.10 09:14:55 4: Attribut matchs TimeCode DayChangeTime='06:00'
2016.05.10 09:14:55 5: 433_RSSI: not on any display, ignoring notify
2016.05.10 09:14:55 5: Notify from Device: FritzBox recieved
2016.05.10 09:14:55 4: configDB reading file: ./FHEM/FhemUtils/uniqueID
2016.05.10 09:14:55 5: FRITZBOX FritzBox: readPassword.4547 Read FritzBox password from file
2016.05.10 09:14:55 4: configDB reading file: ./FHEM/FhemUtils/uniqueID
2016.05.10 09:14:55 5: RSSI_Check: not on any display, ignoring notify
2016.05.10 09:14:55 4: FRITZBOX FritzBox: TR064_Cmd.4123 Perform TR-064 call - service='WLANConfiguration:1', control='wlanconfig1', action='SetEnable', parameter1='NewEnable' => '1'
2016.05.10 09:14:55 5: battery_status: not on any display, ignoring notify
2016.05.10 09:14:55 5: CUL/RAW: /TD2AB1702F3

2016.05.10 09:14:55 4: CUL_Parse: CUL_0 TD2AB1702F3 -80.5
2016.05.10 09:14:55 5: CUL_0 dispatch TD2AB1702
2016.05.10 09:14:55 4: FHTTK Device Schuppentuer (Window: Closed)
2016.05.10 09:14:55 5: CUL/RAW: /TD2AB1782F3

2016.05.10 09:14:55 4: CUL_Parse: CUL_0 TD2AB1782F3 -80.5
2016.05.10 09:14:55 5: CUL_0 dispatch TD2AB1782
2016.05.10 09:14:55 4: FHTTK skipping state 02 as last similar telegram was received less than 5 (2) secs ago
2016.05.10 09:14:57 4: FRITZBOX FritzBox: TR064_Cmd.4123 Perform TR-064 call - service='WLANConfiguration:2', control='wlanconfig2', action='SetEnable', parameter1='NewEnable' => '1'
2016.05.10 09:14:58 4: FRITZBOX FritzBox: TR064_Cmd.4123 Perform TR-064 call - service='WLANConfiguration:3', control='wlanconfig3', action='SetEnable', parameter1='NewEnable' => '1'
2016.05.10 09:14:59 5: FRITZBOX FritzBox: Web_Query.4466 Request data via API luaQuery.
2016.05.10 09:14:59 5: FRITZBOX FritzBox: Web_Query.4474 Response: {
 "box_wlan_24GHz": "1",
 "box_wlan_5GHz": "1",
 "box_guestWlan": "1",
 "box_guestWlanRemain": "-1"
}


2016.05.10 09:14:59 5: FRITZBOX FritzBox: Readout_Add_Reading.1863 box_wlan_2.4GHz: on
2016.05.10 09:14:59 5: FRITZBOX FritzBox: Readout_Add_Reading.1863 box_wlan_5GHz: on
2016.05.10 09:14:59 5: FRITZBOX FritzBox: Readout_Add_Reading.1863 box_guestWlan: on
2016.05.10 09:14:59 5: FRITZBOX FritzBox: Readout_Add_Reading.1863 box_guestWlanRemain: -1
2016.05.10 09:14:59 5: FRITZBOX FritzBox: Readout_Add_Reading.1863 fhem->sid: 79ff63568853e30b
2016.05.10 09:14:59 5: FRITZBOX FritzBox: Readout_Add_Reading.1863 fhem->sidTime: 1462864499
2016.05.10 09:14:59 5: FRITZBOX FritzBox: Readout_Add_Reading.1863 readoutTime: 4.00
2016.05.10 09:14:59 5: FRITZBOX FritzBox: GuestWlan_Run_Web.2470 Handover to main process: box_wlan_2.4GHz|on|box_wlan_5GHz|on|box_guestWlan|on|box_guestWlanRemain|-1|fhem->sid|79ff63568853e30b|fhem->sidTime|1462864499|readoutTime|4.00
2016.05.10 09:14:59 4: Connection accepted from telnetPort_127.0.0.1_55940
2016.05.10 09:14:59 5: Cmd: >{FRITZBOX_Set_Cmd_Done('FritzBox|2|Ym94X3dsYW5fMi40R0h6fG9ufGJveF93bGFuXzVHSHp8b258Ym94X2d1ZXN0V2xhbnxvbnxib3hfZ3Vlc3RXbGFuUmVtYWlufC0xfGZoZW0tPnNpZHw3OWZmNjM1Njg4NTNlMzBifGZoZW0tPnNpZFRpbWV8MTQ2Mjg2NDQ5OXxyZWFkb3V0VGltZXw0LjAw')}<
2016.05.10 09:14:59 4: FRITZBOX FritzBox: Set_Cmd_Done.1981 Back at main process
2016.05.10 09:14:59 4: FRITZBOX FritzBox: Readout_Process.1610 Processing 7 readouts.
2016.05.10 09:14:59 5: FRITZBOX FritzBox: Readout_Process.1684 SET box_guestWlan = 'on'
2016.05.10 09:14:59 5: FRITZBOX FritzBox: Readout_Process.1684 SET box_wlan_2.4GHz = 'on'
2016.05.10 09:14:59 5: FRITZBOX FritzBox: Readout_Process.1684 SET box_guestWlanRemain = '-1'
2016.05.10 09:14:59 5: FRITZBOX FritzBox: Readout_Process.1684 SET box_wlan_5GHz = 'on'
2016.05.10 09:14:59 5: FRITZBOX FritzBox: Readout_Process.1716 SET state = 'WLAN: on gWLAN: on'
2016.05.10 09:14:59 4: FRITZBOX FritzBox: Readout_Process.1734 7 values captured in 4.00 s
2016.05.10 09:14:59 5: Triggering FritzBox (6 changes)
2016.05.10 09:14:59 5: Starting notify loop for FritzBox, first event box_guestWlan: on
2016.05.10 09:14:59 5: rain_notify: devname=FritzBox rainname=Regenmenge, dev=FritzBox, dev_regex=Regensensor rain_name=rain_total israining_name=isRaining
2016.05.10 09:14:59 4: Attribut matchs TimeCode DayChangeTime='06:00'
2016.05.10 09:14:59 5: 433_RSSI: not on any display, ignoring notify
2016.05.10 09:14:59 5: Notify from Device: FritzBox recieved
2016.05.10 09:15:00 5: RSSI_Check: not on any display, ignoring notify
2016.05.10 09:15:00 5: battery_status: not on any display, ignoring notify
2016.05.10 09:15:00 5: TRX/RAW: /V0pÅÅY
2016.05.10 09:15:00 5: TRX: TRX_Read '10560430941600700000000000c500c559'
2016.05.10 09:15:00 5: TRX_Read rmsg '10560430941600700000000000c500c559'
2016.05.10 09:15:00 5: TRX_Read TRX_data '10560430941600700000000000c500c559'
2016.05.10 09:15:00 5: TRX_Parse() '10560430941600700000000000c500c559'
2016.05.10 09:15:00 5: RFXTRXUSB dispatch 10560430941600700000000000c500c559
2016.05.10 09:15:00 5: TRX_WEATHER: decoding delay=15 hex=10560430941600700000000000c500c559
2016.05.10 09:15:00 5: TRX_WEATHER: parsing sensor_id=56 message='10560430941600700000000000c500c559'
2016.05.10 09:15:00 5: TRX_WEATHER: name=Wettersensor device=TFA_WIND_94_22 Temperatur 19.7 Grad Celsius
2016.05.10 09:15:00 5: TRX_WEATHER: name=Wettersensor device=TFA_WIND_94_22 windchill 19.7 Grad Celsius
2016.05.10 09:15:00 5: TRX_WEATHER: name=Wettersensor device=TFA_WIND_94_22 wind_speed 0 wind_avspeed 0
2016.05.10 09:15:00 5: TRX_WEATHER: name=Wettersensor device=TFA_WIND_94_22 wind_dir 112 ESE
2016.05.10 09:15:00 5: TRX_WEATHER: name=Wettersensor device=TFA_WIND_94_22 Batterie ok 100%
2016.05.10 09:15:00 5: TRX_WEATHER: name=Wettersensor device=TFA_WIND_94_22 rssi 5
2016.05.10 09:15:00 5: Triggering Wettersensor (1 changes)
2016.05.10 09:15:00 5: Starting notify loop for Wettersensor, first event rssi: 5
2016.05.10 09:15:00 5: rain_notify: devname=Wettersensor rainname=Regenmenge, dev=Wettersensor, dev_regex=Regensensor rain_name=rain_total israining_name=isRaining
2016.05.10 09:15:00 4: Attribut matchs TimeCode DayChangeTime='06:00'
2016.05.10 09:15:00 5: 433_RSSI: not on any display, ignoring notify
2016.05.10 09:15:00 5: Notify from Device: Wettersensor recieved
2016.05.10 09:15:00 5: RSSI_Check: not on any display, ignoring notify
2016.05.10 09:15:00 5: battery_status: not on any display, ignoring notify
2016.05.10 09:15:00 5: TRX_Read END
2016.05.10 09:15:00 5: HMLAN_Send:  HMLAN1 I:K
2016.05.10 09:15:00 5: HMLAN/RAW: /HHM-LAN-IF,03C4,LEQ0579733,2BAC84,250165,38997921,0005,02

2016.05.10 09:15:00 5: HMLAN_Parse: HMLAN1 V:03C4 sNo:LEQ0579733 d:2BAC84 O:250165 t:38997921 IDcnt:0005 L:2 %
2016.05.10 09:15:00 5: Triggering HMLAN1 (1 changes)
2016.05.10 09:15:00 5: Starting notify loop for HMLAN1, first event loadLvl: low
2016.05.10 09:15:00 5: rain_notify: devname=HMLAN1 rainname=Regenmenge, dev=HMLAN1, dev_regex=Regensensor rain_name=rain_total israining_name=isRaining
2016.05.10 09:15:00 4: Attribut matchs TimeCode DayChangeTime='06:00'
2016.05.10 09:15:00 5: 433_RSSI: not on any display, ignoring notify
2016.05.10 09:15:00 5: Notify from Device: HMLAN1 recieved
2016.05.10 09:15:00 5: RSSI_Check: not on any display, ignoring notify
2016.05.10 09:15:00 5: battery_status: not on any display, ignoring notify
2016.05.10 09:15:02 5: HMLAN/RAW: /E2AAA94,0000,38997FE7,FF,FFB2,D3845E2AAA940000008411F4000000000008EF01

2016.05.10 09:15:02 5: HMLAN_Parse: HMLAN1 R:E2AAA94   stat:0000 t:38997FE7 d:FF r:FFB2     m:D3 845E 2AAA94 000000 8411F4000000000008EF01
2016.05.10 09:15:02 5: HMLAN1 dispatch A14D3845E2AAA940000008411F4000000000008EF01::-78:HMLAN1
2016.05.10 09:15:02 5: Triggering HM_Ladestation_Pwr (9 changes)
2016.05.10 09:15:02 5: Starting notify loop for HM_Ladestation_Pwr, first event boot: off
2016.05.10 09:15:02 5: rain_notify: devname=HM_Ladestation_Pwr rainname=Regenmenge, dev=HM_Ladestation_Pwr, dev_regex=Regensensor rain_name=rain_total israining_name=isRaining
2016.05.10 09:15:02 4: Attribut matchs TimeCode DayChangeTime='06:00'
2016.05.10 09:15:02 5: 433_RSSI: not on any display, ignoring notify
2016.05.10 09:15:02 5: Notify from Device: HM_Ladestation_Pwr recieved
2016.05.10 09:15:02 5: HM_Components: no longer visible, ignoring notify
2016.05.10 09:15:02 5: RSSI_Check: not on any display, ignoring notify
2016.05.10 09:15:02 5: battery_status: not on any display, ignoring notify
2016.05.10 09:15:02 5: Triggering HM_Ladestation_SenF (1 changes)
2016.05.10 09:15:02 5: Starting notify loop for HM_Ladestation_SenF, first event 50.01
2016.05.10 09:15:02 5: rain_notify: devname=HM_Ladestation_SenF rainname=Regenmenge, dev=HM_Ladestation_SenF, dev_regex=Regensensor rain_name=rain_total israining_name=isRaining
2016.05.10 09:15:02 4: Attribut matchs TimeCode DayChangeTime='06:00'
2016.05.10 09:15:02 5: 433_RSSI: not on any display, ignoring notify
2016.05.10 09:15:02 5: Notify from Device: HM_Ladestation_SenF recieved
2016.05.10 09:15:02 5: RSSI_Check: not on any display, ignoring notify
2016.05.10 09:15:02 5: battery_status: not on any display, ignoring notify
2016.05.10 09:15:02 5: Triggering HM_Ladestation_SenI (1 changes)
2016.05.10 09:15:02 5: Starting notify loop for HM_Ladestation_SenI, first event 0
2016.05.10 09:15:02 5: rain_notify: devname=HM_Ladestation_SenI rainname=Regenmenge, dev=HM_Ladestation_SenI, dev_regex=Regensensor rain_name=rain_total israining_name=isRaining
2016.05.10 09:15:02 4: Attribut matchs TimeCode DayChangeTime='06:00'
2016.05.10 09:15:02 5: 433_RSSI: not on any display, ignoring notify
2016.05.10 09:15:02 5: Notify from Device: HM_Ladestation_SenI recieved
2016.05.10 09:15:02 5: RSSI_Check: not on any display, ignoring notify
2016.05.10 09:15:02 5: battery_status: not on any display, ignoring notify
2016.05.10 09:15:02 5: Triggering HM_Ladestation_SenPwr (1 changes)
2016.05.10 09:15:02 5: Starting notify loop for HM_Ladestation_SenPwr, first event 0
2016.05.10 09:15:02 5: rain_notify: devname=HM_Ladestation_SenPwr rainname=Regenmenge, dev=HM_Ladestation_SenPwr, dev_regex=Regensensor rain_name=rain_total israining_name=isRaining
2016.05.10 09:15:02 4: Attribut matchs TimeCode DayChangeTime='06:00'
2016.05.10 09:15:02 5: 433_RSSI: not on any display, ignoring notify
2016.05.10 09:15:02 5: Notify from Device: HM_Ladestation_SenPwr recieved
2016.05.10 09:15:02 5: RSSI_Check: not on any display, ignoring notify
2016.05.10 09:15:02 5: battery_status: not on any display, ignoring notify
2016.05.10 09:15:02 5: Triggering HM_Ladestation_SenU (1 changes)
2016.05.10 09:15:02 5: Starting notify loop for HM_Ladestation_SenU, first event 228.7
2016.05.10 09:15:02 5: rain_notify: devname=HM_Ladestation_SenU rainname=Regenmenge, dev=HM_Ladestation_SenU, dev_regex=Regensensor rain_name=rain_total israining_name=isRaining
2016.05.10 09:15:02 4: Attribut matchs TimeCode DayChangeTime='06:00'
2016.05.10 09:15:02 5: 433_RSSI: not on any display, ignoring notify
2016.05.10 09:15:02 5: Notify from Device: HM_Ladestation_SenU recieved
2016.05.10 09:15:02 5: RSSI_Check: not on any display, ignoring notify
2016.05.10 09:15:02 5: battery_status: not on any display, ignoring notify
2016.05.10 09:15:09 5: CUL/RAW: /TDBA85802FC

2016.05.10 09:15:09 4: CUL_Parse: CUL_0 TDBA85802FC -76
2016.05.10 09:15:09 5: CUL_0 dispatch TDBA85802
2016.05.10 09:15:09 4: FHTTK Device SZ_Fenster_links (Window: Closed)
2016.05.10 09:15:10 5: CUL/RAW: /TDBA85882FC

2016.05.10 09:15:10 4: CUL_Parse: CUL_0 TDBA85882FC -76
2016.05.10 09:15:10 5: CUL_0 dispatch TDBA85882
2016.05.10 09:15:10 4: FHTTK skipping state 02 as last similar telegram was received less than 5 (2) secs ago
2016.05.10 09:15:13 5: Cmd: >attr global verbose 3<

Evtl. kannst Du ja darin etwas erkennen

Viele Grüße

Klaus

rudolfkoenig · 10 Mai 2016, 13:52:59

Ja: vor der Zeile

Zitat2016.05.10 09:14:59 5: FRITZBOX FritzBox: Web_Query.4466 Request data via API luaQuery.

haetten die Ausgaben aus dem vorher angehaengten FritzBoxUtils.pm erscheinen muessen.
Ich vermute, dass FritzBoxUtils.pm durch ein vorheriges update ueberbuegelt wurde.
Leider komme ich ohne diese Ausgaben nicht weiter.

Klaus Rubik · 10 Mai 2016, 14:29:54

Hallo Rudi,

ich habe die "spezielle" FritzBoxUtils.pm nochmals geladen. Hier die Outputs der beiden Command-Folgen:

attr global verbose 5
set fbahahttp password xxx
attr global verbose 3

Code Auswählen

2016.05.10 14:21:32 5: Triggering global (1 changes)
2016.05.10 14:21:32 5: Starting notify loop for global, first event ATTR global verbose 5
2016.05.10 14:21:32 5: rain_notify: devname=global rainname=Regenmenge, dev=global, dev_regex=Regensensor rain_name=rain_total israining_name=isRaining
2016.05.10 14:21:32 4: Attribut matchs TimeCode DayChangeTime='06:00'
2016.05.10 14:21:32 5: 433_RSSI: not on any display, ignoring notify
2016.05.10 14:21:32 5: Notify from Device: global recieved
2016.05.10 14:21:32 5: RSSI_Check: not on any display, ignoring notify
2016.05.10 14:21:32 5: battery_status: not on any display, ignoring notify
2016.05.10 14:21:32 5: Cmd: >set fbahahttp password xxx<
2016.05.10 14:21:32 4: configDB reading file: ./FHEM/FhemUtils/uniqueID
2016.05.10 14:21:32 4: configDB writing file: ./FHEM/FhemUtils/uniqueID
2016.05.10 14:21:32 4: configDB reading file: ./FHEM/FhemUtils/uniqueID
2016.05.10 14:21:32 4: HttpUtils url=http://fritz.box/login_sid.lua
2016.05.10 14:21:32 4: http://fritz.box/login_sid.lua: HTTP response code 200
2016.05.10 14:21:32 4: HttpUtils http://fritz.box/login_sid.lua: Got data, length: 165
2016.05.10 14:21:32 1: FB_doCheckPW 1: <?xml version="1.0" encoding="utf-8"?><SessionInfo><SID>0000000000000000</SID><Challenge>9a652bec</Challenge><BlockTime>0</BlockTime><Rights></Rights></SessionInfo>

2016.05.10 14:21:32 1: FB_doCheckPW 2: response=9a652bec%2d7a3da00b99036e3f664756b72d37dd03&page=%2flogin%5fsid%2elua
2016.05.10 14:21:32 4: HttpUtils url=http://fritz.box/login_sid.lua?username=fhemapi
2016.05.10 14:21:33 4: http://fritz.box/login_sid.lua?username=fhemapi: HTTP response code 200
2016.05.10 14:21:33 4: HttpUtils http://fritz.box/login_sid.lua?username=fhemapi: Got data, length: 165
2016.05.10 14:21:33 1: FB_doCheckPW 3: <?xml version="1.0" encoding="utf-8"?><SessionInfo><SID>0000000000000000</SID><Challenge>2f69a8f1</Challenge><BlockTime>8</BlockTime><Rights></Rights></SessionInfo>

2016.05.10 14:21:33 2: fbahahttp error: cannot get SID, check connection/hostname/fritzbox-user/password
2016.05.10 14:21:33 5: Triggering fbahahttp (1 changes)
2016.05.10 14:21:33 5: Starting notify loop for fbahahttp, first event password fhemapi
2016.05.10 14:21:33 5: rain_notify: devname=fbahahttp rainname=Regenmenge, dev=fbahahttp, dev_regex=Regensensor rain_name=rain_total israining_name=isRaining
2016.05.10 14:21:33 4: Attribut matchs TimeCode DayChangeTime='06:00'
2016.05.10 14:21:33 5: 433_RSSI: not on any display, ignoring notify
2016.05.10 14:21:33 5: Notify from Device: fbahahttp recieved
2016.05.10 14:21:33 5: RSSI_Check: not on any display, ignoring notify
2016.05.10 14:21:33 5: battery_status: not on any display, ignoring notify
2016.05.10 14:21:33 5: CUL/RAW: /TCAD3538219

2016.05.10 14:21:33 4: CUL_Parse: CUL_0 TCAD3538219 -61.5
2016.05.10 14:21:33 5: CUL_0 dispatch TCAD35382
2016.05.10 14:21:33 4: FHTTK skipping state 02 as last similar telegram was received less than 5 (2) secs ago
2016.05.10 14:21:34 5: CUL/RAW: /HB1BC0059915415

2016.05.10 14:21:34 4: CUL_Parse: CUL_0 HB1BC0059915415 -63.5
2016.05.10 14:21:34 5: CUL_0 dispatch 810e04xx0510a001b1bc000000599154
2016.05.10 14:21:34 4: HMS Device b1bc (HMS100TF: T: 15.9  H: 54.9  Bat: ok)
2016.05.10 14:21:35 5: HMLAN_Send:  HMLAN1 I:K
2016.05.10 14:21:35 5: HMLAN/RAW: /HHM-LAN-IF,03C4,LEQ0579733,2BAC84,250165,39B23304,0005,01

2016.05.10 14:21:35 5: HMLAN_Parse: HMLAN1 V:03C4 sNo:LEQ0579733 d:2BAC84 O:250165 t:39B23304 IDcnt:0005 L:1 %
2016.05.10 14:21:35 5: Triggering HMLAN1 (1 changes)
2016.05.10 14:21:35 5: Starting notify loop for HMLAN1, first event loadLvl: low
2016.05.10 14:21:35 5: rain_notify: devname=HMLAN1 rainname=Regenmenge, dev=HMLAN1, dev_regex=Regensensor rain_name=rain_total israining_name=isRaining
2016.05.10 14:21:35 4: Attribut matchs TimeCode DayChangeTime='06:00'
2016.05.10 14:21:35 5: 433_RSSI: not on any display, ignoring notify
2016.05.10 14:21:35 5: Notify from Device: HMLAN1 recieved
2016.05.10 14:21:35 5: RSSI_Check: not on any display, ignoring notify
2016.05.10 14:21:35 5: battery_status: not on any display, ignoring notify
2016.05.10 14:21:35 5: Cmd: >attr global verbose 3<

attr global verbose 5
set FritzBox guestWlan on
attr global verbose 3

Code Auswählen

2016.05.10 14:22:07 5: Triggering global (1 changes)
2016.05.10 14:22:07 5: Starting notify loop for global, first event ATTR global verbose 5
2016.05.10 14:22:07 5: rain_notify: devname=global rainname=Regenmenge, dev=global, dev_regex=Regensensor rain_name=rain_total israining_name=isRaining
2016.05.10 14:22:07 4: Attribut matchs TimeCode DayChangeTime='06:00'
2016.05.10 14:22:07 5: 433_RSSI: not on any display, ignoring notify
2016.05.10 14:22:07 5: Notify from Device: global recieved
2016.05.10 14:22:07 5: RSSI_Check: not on any display, ignoring notify
2016.05.10 14:22:07 5: battery_status: not on any display, ignoring notify
2016.05.10 14:22:07 5: Cmd: >set FritzBox guestWlan on<
2016.05.10 14:22:07 3: FRITZBOX: set FritzBox guestWlan on
2016.05.10 14:22:07 4: FRITZBOX FritzBox: Set_Cmd_Start.1961 Fork process FRITZBOX_GuestWlan_Run_Web
2016.05.10 14:22:07 4: BlockingCall (FRITZBOX_GuestWlan_Run_Web): created child (3356), uses telnetPort to connect back
2016.05.10 14:22:07 5: Triggering FritzBox (1 changes)
2016.05.10 14:22:07 5: Starting notify loop for FritzBox, first event guestWlan on
2016.05.10 14:22:07 5: rain_notify: devname=FritzBox rainname=Regenmenge, dev=FritzBox, dev_regex=Regensensor rain_name=rain_total israining_name=isRaining
2016.05.10 14:22:07 4: Attribut matchs TimeCode DayChangeTime='06:00'
2016.05.10 14:22:07 5: 433_RSSI: not on any display, ignoring notify
2016.05.10 14:22:07 5: Notify from Device: FritzBox recieved
2016.05.10 14:22:07 4: configDB reading file: ./FHEM/FhemUtils/uniqueID
2016.05.10 14:22:07 5: FRITZBOX FritzBox: readPassword.4547 Read FritzBox password from file
2016.05.10 14:22:07 4: configDB reading file: ./FHEM/FhemUtils/uniqueID
2016.05.10 14:22:07 4: FRITZBOX FritzBox: TR064_Cmd.4123 Perform TR-064 call - service='WLANConfiguration:1', control='wlanconfig1', action='SetEnable', parameter1='NewEnable' => '1'
2016.05.10 14:22:07 5: RSSI_Check: not on any display, ignoring notify
2016.05.10 14:22:07 5: battery_status: not on any display, ignoring notify
2016.05.10 14:22:09 4: FRITZBOX FritzBox: TR064_Cmd.4123 Perform TR-064 call - service='WLANConfiguration:2', control='wlanconfig2', action='SetEnable', parameter1='NewEnable' => '1'
2016.05.10 14:22:10 4: FRITZBOX FritzBox: TR064_Cmd.4123 Perform TR-064 call - service='WLANConfiguration:3', control='wlanconfig3', action='SetEnable', parameter1='NewEnable' => '1'
2016.05.10 14:22:11 5: FRITZBOX FritzBox: Web_Query.4466 Request data via API luaQuery.
2016.05.10 14:22:11 5: FRITZBOX FritzBox: Web_Query.4474 Response: {
 "box_wlan_24GHz": "1",
 "box_wlan_5GHz": "1",
 "box_guestWlan": "1",
 "box_guestWlanRemain": "-1"
}


2016.05.10 14:22:11 5: FRITZBOX FritzBox: Readout_Add_Reading.1863 box_wlan_2.4GHz: on
2016.05.10 14:22:11 5: FRITZBOX FritzBox: Readout_Add_Reading.1863 box_wlan_5GHz: on
2016.05.10 14:22:11 5: FRITZBOX FritzBox: Readout_Add_Reading.1863 box_guestWlan: on
2016.05.10 14:22:11 5: FRITZBOX FritzBox: Readout_Add_Reading.1863 box_guestWlanRemain: -1
2016.05.10 14:22:11 5: FRITZBOX FritzBox: Readout_Add_Reading.1863 fhem->sid: 79ff63568853e30b
2016.05.10 14:22:11 5: FRITZBOX FritzBox: Readout_Add_Reading.1863 fhem->sidTime: 1462882931
2016.05.10 14:22:11 5: FRITZBOX FritzBox: Readout_Add_Reading.1863 readoutTime: 4.00
2016.05.10 14:22:11 5: FRITZBOX FritzBox: GuestWlan_Run_Web.2470 Handover to main process: box_wlan_2.4GHz|on|box_wlan_5GHz|on|box_guestWlan|on|box_guestWlanRemain|-1|fhem->sid|79ff63568853e30b|fhem->sidTime|1462882931|readoutTime|4.00
2016.05.10 14:22:11 4: Connection accepted from telnetPort_127.0.0.1_47104
2016.05.10 14:22:11 5: Cmd: >{FRITZBOX_Set_Cmd_Done('FritzBox|2|Ym94X3dsYW5fMi40R0h6fG9ufGJveF93bGFuXzVHSHp8b258Ym94X2d1ZXN0V2xhbnxvbnxib3hfZ3Vlc3RXbGFuUmVtYWlufC0xfGZoZW0tPnNpZHw3OWZmNjM1Njg4NTNlMzBifGZoZW0tPnNpZFRpbWV8MTQ2Mjg4MjkzMXxyZWFkb3V0VGltZXw0LjAw')}<
2016.05.10 14:22:11 4: FRITZBOX FritzBox: Set_Cmd_Done.1981 Back at main process
2016.05.10 14:22:11 4: FRITZBOX FritzBox: Readout_Process.1610 Processing 7 readouts.
2016.05.10 14:22:11 5: FRITZBOX FritzBox: Readout_Process.1684 SET box_wlan_5GHz = 'on'
2016.05.10 14:22:11 5: FRITZBOX FritzBox: Readout_Process.1684 SET box_guestWlanRemain = '-1'
2016.05.10 14:22:11 5: FRITZBOX FritzBox: Readout_Process.1684 SET box_wlan_2.4GHz = 'on'
2016.05.10 14:22:11 5: FRITZBOX FritzBox: Readout_Process.1684 SET box_guestWlan = 'on'
2016.05.10 14:22:11 5: FRITZBOX FritzBox: Readout_Process.1716 SET state = 'WLAN: on gWLAN: on'
2016.05.10 14:22:11 4: FRITZBOX FritzBox: Readout_Process.1734 7 values captured in 4.00 s
2016.05.10 14:22:11 5: Triggering FritzBox (6 changes)
2016.05.10 14:22:11 5: Starting notify loop for FritzBox, first event box_wlan_5GHz: on
2016.05.10 14:22:11 5: rain_notify: devname=FritzBox rainname=Regenmenge, dev=FritzBox, dev_regex=Regensensor rain_name=rain_total israining_name=isRaining
2016.05.10 14:22:11 4: Attribut matchs TimeCode DayChangeTime='06:00'
2016.05.10 14:22:11 5: 433_RSSI: not on any display, ignoring notify
2016.05.10 14:22:11 5: Notify from Device: FritzBox recieved
2016.05.10 14:22:12 5: RSSI_Check: not on any display, ignoring notify
2016.05.10 14:22:12 5: battery_status: not on any display, ignoring notify
2016.05.10 14:22:14 5: CUL/RAW: /T052200AA00F7

2016.05.10 14:22:14 4: CUL_Parse: CUL_0 T052200AA00F7 -78.5
2016.05.10 14:22:14 5: CUL_0 dispatch 810c04xx0909a00105220000aa00
2016.05.10 14:22:14 4: FHT FZ_Heizung actuator: 0%
2016.05.10 14:22:16 5: CUL/RAW: /T384600A677FA

2016.05.10 14:22:16 4: CUL_Parse: CUL_0 T384600A677FA -77
2016.05.10 14:22:16 5: CUL_0 dispatch 810c04xx0909a00138460000a677
2016.05.10 14:22:16 4: FHT WC_Heizung actuator: 47%
2016.05.10 14:22:16 5: Cmd: >attr global verbose 3<

Die von Dir angesprochenen Zeilen finde ich im 2. Log aber nicht

Viele Grüße

Klaus

tupol · 10 Mai 2016, 14:41:35

So beim Nachdenken über das Problem... Das FRITZBOX-Modul merkt sich derzeit die letzte SID und verwendet sie erneut.
Ich bin mir nicht so richtig sicher, was die Fritzbox tut, wenn sie von der selben IP mit unterschiedlichen SIDs bzw. Login-Versuchen angesprochen wird.

achtball · 15 Mai 2016, 14:41:07

Hallo Forum,

kann das Problem bestätigen. Bin jedoch noch totaler Anfänger in FHEM und schließe triviale Fehler meinerseits nicht aus.

Mein System wurde frisch geupdatet und hatte vorher die alter FBAHA nicht installiert.
Meine Version: FHEM Rev. 11435 auf RasPi 2 Mod.B und Raspbian Jessy mit FB 7390 mit OS 6.51.

In den FB-Optionen ist die Smarthome-Fkt. aktiviert. Ein Nutzer ist angelegt mit simplem Reintextpasswort.
Beim Zuweisen des Passworts per Webfrontend der genannte Fehler:
fritzbox error: cannot get SID, check connection/hostname/fritzbox-user/password

Gruß
(8)ball

Panik · 16 Mai 2016, 10:08:05

Hallo,

also ich habe einen Connect bekommen.
Man muss bei den drei Arten derFritz!Box-Benutzer Anmeldung im Heimnetz nicht wie üblich den 2.Punkt auswählen,
sondern den oberen.
Vorher muss man aber noch einen Benutzer definieren, der Adminrechte für alle Einstellungen hat
- Anmeldung mit FRITZ!Box-Benutzernamen und Kennwort
Um Einstellungen dieser FRITZ!Box einzusehen oder zu ändern oder um Informationen abzurufen, muss sich jeder FRITZ!Box-Benutzer mit seinem eigenen Benutzernamen und Kennwort anmelden. Damit lassen sich unterschiedliche Zugriffsmöglichkeiten einrichten.
Die Einrichtung und Rechtevergabe für die FRITZ!Box-Benutzer nehmen Sie auf dem Reiter Benutzer vor.
- Anmeldung mit dem FRITZ!Box-Kennwort
- Keine Anmeldung (nicht empfohlen)

Klaus Rubik · 16 Mai 2016, 10:37:56

Zitat von: Panik am 16 Mai 2016, 10:08:05
Hallo,

also ich habe einen Connect bekommen.
Man muss bei den drei Arten derFritz!Box-Benutzer Anmeldung im Heimnetz nicht wie üblich den 2.Punkt auswählen,
sondern den oberen.
Vorher muss man aber noch einen Benutzer definieren, der Adminrechte für alle Einstellungen hat
- Anmeldung mit FRITZ!Box-Benutzernamen und Kennwort
Um Einstellungen dieser FRITZ!Box einzusehen oder zu ändern oder um Informationen abzurufen, muss sich jeder FRITZ!Box-Benutzer mit seinem eigenen Benutzernamen und Kennwort anmelden. Damit lassen sich unterschiedliche Zugriffsmöglichkeiten einrichten.
Die Einrichtung und Rechtevergabe für die FRITZ!Box-Benutzer nehmen Sie auf dem Reiter Benutzer vor.
- Anmeldung mit dem FRITZ!Box-Kennwort
- Keine Anmeldung (nicht empfohlen)

Danke, das war der entscheidende Hinweis!!!

@Rudi: evtl. kannst Du das noch in die Doku mit aufnehmen.

Danke an alle für die Unterstützung

Viele Grüße

Klaus

rudolfkoenig · 16 Mai 2016, 12:39:15

Zitat@Rudi: evtl. kannst Du das noch in die Doku mit aufnehmen.

Habs aufgenommen. Nach etwas Experimentieren: solange die Anmeldung nur mit Kennwort konfiguriert ist, kann man in FHEM einen beliebigen Benutzernamen angeben, solange das Passwort dem aus "Anmeldung im Heimnetz" entspricht. Erst wenn hier Anmeldung mit Benutzername+Kennwort angehakt ist, sind die Benutzernamen und Kennwoerter aus dem Benutzer-Tab aktiv. Etwas irrefuehrend.

Zitatset FritzBox guestWlan on

Das laeuft wohl bei dir ueber die TR064 Schnittstelle, war also keine Hilfe.

Klaus Rubik · 16 Mai 2016, 16:49:04

Danke und ja, bei mir läuft die TR064 Schnittstelle

mgrimm86 · 10 Dezember 2017, 19:23:07

Hallo zusammen,

leider ist das Problem bei mir nicht gelöst. Es ist alles richtig eingerichtet und direkt nach der Einrichtung (Passwortspeicherung oder Attributänderung) meldet FBAHAHTTP "connected".
Danach aber kommt wieder der altbekannte Fehler:

Code Auswählen

cannot get SID, check connection/hostname/fritzbox-user/password

Ich verwende FHEM auf der Fritzbox 7490 FW 6.92.
Im Log mit Verbose 5 sieht man auch, dass es zyklisch funktioniert, aber nach ein paar Abfragen der FBDECT offenbar zu Timeouts kommt:

Code Auswählen

2017.12.10 17:43:08 2: wz_FB7490_SmartHome error: cannot get SID, check connection/hostname/fritzbox-user/password
2017.12.10 17:43:08 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:43:08 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:43:08 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:43:08 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:43:08 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:43:08 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:43:08 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:43:08 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:43:08 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:43:08 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:43:08 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:43:08 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:43:08 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:43:08 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:43:08 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:43:08 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:43:08 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:43:08 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:43:08 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:43:08 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:43:08 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:43:08 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:43:08 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:43:08 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:43:08 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:43:08 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:43:08 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:43:08 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:43:08 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:43:08 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:43:08 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:43:08 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:43:08 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:43:08 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:43:08 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:43:08 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:43:08 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:43:08 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:43:08 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:43:08 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:43:08 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:43:08 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:43:08 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:43:08 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:43:08 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:43:08 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:43:08 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:43:08 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:43:08 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:43:08 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:43:08 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:43:08 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:43:08 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:43:08 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:43:08 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:43:08 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:43:08 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:43:08 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:43:08 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:43:08 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:43:08 3: wz_FB7490_SmartHome: write to http://192.168.2.1:80 timed out
2017.12.10 17:44:10 4: FBAHAHTTP_connect wz_FB7490_SmartHome: got SID bbcf2990e3cae99f
2017.12.10 17:44:11 5: wz_FB7490_SmartHome: dispatch <device identifier="08761 0150746" id="16" functionbitmask="2944" fwversion="03.87" manufacturer="AVM" productname="FRITZ!DECT 200"><present>1</present><name>Umwälzpumpe Warmwasser</name><switch><state>0</state><mode>manuell</mode><lock>0</lock><devicelock>0</devicelock></switch><powermeter><power>0</power><energy>150708</energy></powermeter><temperature><celsius>205</celsius><offset>0</offset></temperature></device>
2017.12.10 17:44:11 5: wz_FB7490_SmartHome: dispatch <device identifier="08761 0238069" id="17" functionbitmask="2944" fwversion="03.87" manufacturer="AVM" productname="FRITZ!DECT 200"><present>1</present><name>Aquarium</name><switch><state>1</state><mode>manuell</mode><lock>0</lock><devicelock>0</devicelock></switch><powermeter><power>120730</power><energy>639069</energy></powermeter><temperature><celsius>210</celsius><offset>0</offset></temperature></device>
2017.12.10 17:44:11 5: wz_FB7490_SmartHome: dispatch <device identifier="08761 0150746" id="16" functionbitmask="2944" fwversion="03.87" manufacturer="AVM" productname="FRITZ!DECT 200"><present>1</present><name>Umwälzpumpe Warmwasser</name><switch><state>0</state><mode>manuell</mode><lock>0</lock><devicelock>0</devicelock></switch><powermeter><power>0</power><energy>150708</energy></powermeter><temperature><celsius>205</celsius><offset>0</offset></temperature></device>
2017.12.10 17:44:11 5: wz_FB7490_SmartHome: dispatch <device identifier="08761 0238069" id="17" functionbitmask="2944" fwversion="03.87" manufacturer="AVM" productname="FRITZ!DECT 200"><present>1</present><name>Aquarium</name><switch><state>1</state><mode>manuell</mode><lock>0</lock><devicelock>0</devicelock></switch><powermeter><power>120730</power><energy>639069</energy></powermeter><temperature><celsius>210</celsius><offset>0</offset></temperature></device>
2017.12.10 17:44:12 5: wz_FB7490_SmartHome: dispatch <device identifier="08761 0150746" id="16" functionbitmask="2944" fwversion="03.87" manufacturer="AVM" productname="FRITZ!DECT 200"><present>1</present><name>Umwälzpumpe Warmwasser</name><switch><state>0</state><mode>manuell</mode><lock>0</lock><devicelock>0</devicelock></switch><powermeter><power>0</power><energy>150708</energy></powermeter><temperature><celsius>205</celsius><offset>0</offset></temperature></device>
2017.12.10 17:44:12 5: wz_FB7490_SmartHome: dispatch <device identifier="08761 0238069" id="17" functionbitmask="2944" fwversion="03.87" manufacturer="AVM" productname="FRITZ!DECT 200"><present>1</present><name>Aquarium</name><switch><state>1</state><mode>manuell</mode><lock>0</lock><devicelock>0</devicelock></switch><powermeter><power>120730</power><energy>639069</energy></powermeter><temperature><celsius>210</celsius><offset>0</offset></temperature></device>
2017.12.10 17:44:13 5: wz_FB7490_SmartHome: dispatch <device identifier="08761 0150746" id="16" functionbitmask="2944" fwversion="03.87" manufacturer="AVM" productname="FRITZ!DECT 200"><present>1</present><name>Umwälzpumpe Warmwasser</name><switch><state>0</state><mode>manuell</mode><lock>0</lock><devicelock>0</devicelock></switch><powermeter><power>0</power><energy>150708</energy></powermeter><temperature><celsius>205</celsius><offset>0</offset></temperature></device>
2017.12.10 17:44:13 5: wz_FB7490_SmartHome: dispatch <device identifier="08761 0238069" id="17" functionbitmask="2944" fwversion="03.87" manufacturer="AVM" productname="FRITZ!DECT 200"><present>1</present><name>Aquarium</name><switch><state>1</state><mode>manuell</mode><lock>0</lock><devicelock>0</devicelock></switch><powermeter><power>120730</power><energy>639069</energy></powermeter><temperature><celsius>210</celsius><offset>0</offset></temperature></device>
2017.12.10 17:44:13 5: wz_FB7490_SmartHome: dispatch <device identifier="08761 0150746" id="16" functionbitmask="2944" fwversion="03.87" manufacturer="AVM" productname="FRITZ!DECT 200"><present>1</present><name>Umwälzpumpe Warmwasser</name><switch><state>0</state><mode>manuell</mode><lock>0</lock><devicelock>0</devicelock></switch><powermeter><power>0</power><energy>150708</energy></powermeter><temperature><celsius>205</celsius><offset>0</offset></temperature></device>
2017.12.10 17:44:13 5: wz_FB7490_SmartHome: dispatch <device identifier="08761 0238069" id="17" functionbitmask="2944" fwversion="03.87" manufacturer="AVM" productname="FRITZ!DECT 200"><present>1</present><name>Aquarium</name><switch><state>1</state><mode>manuell</mode><lock>0</lock><devicelock>0</devicelock></switch><powermeter><power>120730</power><energy>639069</energy></powermeter><temperature><celsius>210</celsius><offset>0</offset></temperature></device>
2017.12.10 17:44:14 5: wz_FB7490_SmartHome: dispatch <device identifier="08761 0150746" id="16" functionbitmask="2944" fwversion="03.87" manufacturer="AVM" productname="FRITZ!DECT 200"><present>1</present><name>Umwälzpumpe Warmwasser</name><switch><state>0</state><mode>manuell</mode><lock>0</lock><devicelock>0</devicelock></switch><powermeter><power>0</power><energy>150708</energy></powermeter><temperature><celsius>205</celsius><offset>0</offset></temperature></device>
2017.12.10 17:44:14 5: wz_FB7490_SmartHome: dispatch <device identifier="08761 0238069" id="17" functionbitmask="2944" fwversion="03.87" manufacturer="AVM" productname="FRITZ!DECT 200"><present>1</present><name>Aquarium</name><switch><state>1</state><mode>manuell</mode><lock>0</lock><devicelock>0</devicelock></switch><powermeter><power>120730</power><energy>639069</energy></powermeter><temperature><celsius>210</celsius><offset>0</offset></temperature></device>
2017.12.10 17:44:14 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:44:18 2: wz_FB7490_SmartHome error: cannot get SID, check connection/hostname/fritzbox-user/password
2017.12.10 17:44:18 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:44:18 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:44:18 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:44:18 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:44:18 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:44:18 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:44:18 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:44:18 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:44:18 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:44:18 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:44:18 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:44:18 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:44:18 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:44:18 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:44:18 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:44:18 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:44:18 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:44:18 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:44:18 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:44:18 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:44:18 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:44:18 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:44:18 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:44:18 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:44:18 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:44:18 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:44:18 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:44:18 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:44:18 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:44:18 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:44:18 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:44:18 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:44:18 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:44:18 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:44:18 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:44:18 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:44:18 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:44:18 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:44:18 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:44:18 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:44:18 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:44:18 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:44:18 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:44:18 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:44:18 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:44:18 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:44:18 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:44:18 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:44:18 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:44:18 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:44:18 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:44:18 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:44:18 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:44:18 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:44:18 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:44:18 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:44:18 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:44:18 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:44:18 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:44:18 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:44:18 3: wz_FB7490_SmartHome: read from http://192.168.2.1:80 timed out
2017.12.10 17:44:18 3: wz_FB7490_SmartHome: write to http://192.168.2.1:80 timed out

Das Schalten der FBDECTs scheint davon übrigens nicht betroffen zu sein. Das funktioniert ad hoc und einwandfrei.

Hat jemand eine Idee, wie ich das weg kriege?

Danke und Gruß,
Micha

rudolfkoenig · 11 Dezember 2017, 09:16:29

Auf welchem Wert steht polltime?
Gibt es auch Probleme mit der Voreinstellung (300)?

mgrimm86 · 12 Dezember 2017, 06:55:35

polltime steht auf 300s.
Allerdings sieht man in den Logs, dass er etwa im Minutentakt Anfragen schickt.

rudolfkoenig · 12 Dezember 2017, 09:49:13

ZitatAllerdings sieht man in den Logs, dass er etwa im Minutentakt Anfragen schickt.

Koenntest du mit einem hoeheren verbose rauskriegen, wer das ausloest?

mgrimm86 · 16 Dezember 2017, 08:25:04

Ich habe schonmal mit Verbose 5 loggen lassen, aber ohne weitere Erkenntnisse.
Interessanterweise ist der Fehler seit 2 Tagen weg, nachdem ich die Box für den Anschluss eines USB Hubs hart ausgeschaltet und neu gestartet habe... Ich werd das mal weiter beobachten.

noansi · 23 August 2019, 19:37:03

Hallo Zusammen,

was so einen "read from http://192.168.2.1:80 timed out" bei der 7390 auch noch auslösen kann, ist höherer ständiger empfangener WLAN Traffic.
Meine 7390 war jüngst öfter zu 30% längere Zeit ausgelastet (laut Fritz!Box WLAN Funkkanal Anzeige). Gleichzeitig ging damit die CPU Auslastung der Fritzbox auf dauerhaft fast 100% hoch, wie die Energiehistorie der Fritz!Box zeigte.

Eine Umstellung des WLAN Kanals auf einen weniger frequentierten Kanal/Bereich hat dem ein Ende gesetzt.
Offensichtlich hat die "hohe" und konstante WLAN Auslastung der Fritzbox zu wenig Zeit gelassen, um sich um Anforderungen vom FRITZBOX Modul rechtzeitig zu kümmern.

Gruß, Ansgar.

Jam2 · 27 September 2020, 12:02:45

(einer der ersten Threads in Google zu dem Thema - deshalb hier meine Lösung)
Nach einem Stromausfall hab ich ein ähnliches Szenario:
-> FB DECT schaltet ohne Probleme
-> Log quillt mit time outs und cannot get SID über (Anzahl zweistellig pro Sekunde)
-> Zusätzlich konnte fritz.box nicht mehr aufgelöst werden - allerdings nur auf dem Rechner der Fhem Installation. Die IP hat jedoch funktioniert.

Nach langer Suche und nur noch mehr Ungereimtheiten hat ein klassischer Neustart der Fritzbox (über die Weboberfläche) das problem beseitigt.