AVM Plugin

[psilo]

ne,
self.logger.error(response.content)
steht da nicht.

wobei.. grmmpff. habe das vermutlich grade mit gepusht... ich lass das jetzt mal drin, wer debug log anmacht muss eh mit vielen ausgaben rechnen..
update: gerade geschaut: nein ich habe es nicht gepusht..

[Sisamiwe]

bau mal in der Methode _update_fritz_device_info des Plugins das logging hier ein - error damit es auf jdn fall bei dir ausgegeben wird:

hab nun folgendes in Plugin ca. Zeile 1870:

if "dev_info_" + action not in self._response_cache:[INDENT]try:[/INDENT][INDENT=2]response = self._session.post(url, data=soap_data, timeout=self._timeout, headers=headers, auth=HTTPDigestAuth(self._fritz_device.get_user(), self._fritz_device.get_password()), verify=self._verify)[/INDENT][INDENT=2]self.logger.debug('Response: {}'.format(response.content))[/INDENT][INDENT]except Exception as e:[/INDENT][INDENT=2]if self._fritz_device.is_available():[/INDENT][INDENT=3]self.logger.error("Exception when sending POST request: %s" % str(e))[/INDENT][INDENT=3]self.set_device_availability(False)[/INDENT][INDENT=2]return[/INDENT]

Mit einer Konfig in der plugin.yaml, die ein Verbindung vermöglicht, kommt im Log:

plugins.avm         Response: b'<?xml version="1.0"?>\n<s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/" s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/">\n<s:Body>\n<u:GetInfoResponse xmlns:u="urn:dslforum-org:service:DeviceInfo:1">\n<NewManufacturerName>AVM</NewManufacturerName>\n<NewManufacturerOUI>00040E</NewManufacturerOUI>\n<NewModelName>FRITZ!Box 7490</NewModelName>\n<NewDescription>FRITZ!Box 7490 113.07.12</NewDescription>\n<NewProductClass>FRITZ!Box</NewProductClass>\n<NewSerialNumber>3810D5158767</NewSerialNumber>\n<NewSoftwareVersion>113.07.12</NewSoftwareVersion>\n<NewHardwareVersion>FRITZ!Box 7490</NewHardwareVersion>\n<NewSpecVersion>1.0</NewSpecVersion>\n<NewProvisioningCode></NewProvisioningCode>\n<NewUpTime>512874</NewUpTime>\n<NewDeviceLog>18.01.20 16:31:37 WLAN-\xc3\x9cbertragungsqualit\xc3\xa4t durch reduzierte Kanalbandbreite erh\xc3\xb6ht  (2,4 GHz). [5 Meldungen seit 16.01.20 20:41:23]\n16.01.20 19:39:45 WLAN-\xc3\x9cbertragungsqualit\xc3\xa4t durch reduzierte Kanalbandbreite erh\xc3\xb6ht  (2,4 GHz). [2 Meldungen seit 13.01.20 23:12:10]\n13.01.20 17:48:27 WLAN-\xc3\x9cbertragungsqualit\xc3\xa4t durch reduzierte Kanalbandbreite erh\xc3\xb6ht  (2,4 GHz). [3 Meldungen seit 12.01.20 21:41:34]</NewDeviceLog>\n</u:GetInfoResponse>\n</s:Body>\n</s:Envelope>'

Bei einer Konfig, die keine Verbindung ermöglicht, kommt kein Log.

[psilo]

ok logisch, weil vorher die exception fliegt.. das ist aber direkt ein fehler der Requests Lib an der stelle, weil diese keine Verbindung zu Deiner Box hinbekommt.. Die Frage ist, ob man auf Pluginebene da noch groß was untersuchen kann..

Gib vielleicht mal ALLE parameter der post Methode aus?!


self.logger.debug("%s %s %s %s %s %s" % (url, soap_data, self._timeout, headers, self._fritz_device.get_user(), self._fritz_device.get_password(), self._verify))

[Sisamiwe]

psilo
Hast Du die Möglichkeit bei Dir, mit dem Admin Interface eine Konfiguration des Plugins zu machen?

[psilo]

Sisamiwe wie gesagt, ich habe das Plugin mit dem admin if konfiguriert, er hat auch die plugin.yaml geändert. was ich nicht gemacht habe war es vorher aus der yaml datei zu löschen.. das dürfte aber EIGENTLICH keinen Unterschied machen, da er die yaml datei neu schreibt an der stelle..
gerade möchte ich auch eher nicht restarten, weil dann manchmal meine lüfter zicken und das die kinder weckt..

Aus meiner Sicht es ist unmöglich, dass der Fehler an dieser Stelle ist, v.A. auch wo Du ja die richtigen Werte ins Plugin bekommst. Wo soll denn dann hier der Fehler herkommen?. Wenn Du nochmal verifizierst, dass Requests alle Parameter richtig hat, würde ich auch das Plugin ausschliessen


Kann es evtl sein, dass Du einen längeren Timeout brauchst? Wenn Deine Box die Anfragen vielleicht nicht schnell genug handlen kann? Timeout ist derzeit 10 Sekunden.
Neues oder altes Modell der Box?

[Sisamiwe]

self.logger.debug("%s %s %s %s %s %s" % (url, soap_data, self._timeout, headers, self._fritz_device.get_user(), self._fritz_device.get_password(), self._verify))

Plugin:

if "dev_info_" + action not in self._response_cache:
try:
response = self._session.post(url, data=soap_data, timeout=self._timeout, headers=headers, auth=HTTPDigestAuth(self._fritz_device.get_user(), self._fritz_device.get_password()), verify=self._verify)
self.logger.debug('Response: {}'.format(response.content))
self.logger.debug("%s %s %s %s %s %s %s" % (url, soap_data, self._timeout, headers, self._fritz_device.get_user(), self._fritz_device.get_password(), self._verify)) if "dev_info_" + action not in self._response_cache:
try:
response = self._session.post(url, data=soap_data, timeout=self._timeout, headers=headers, auth=HTTPDigestAuth(self._fritz_device.get_user(), self._fritz_device.get_password()), verify=self._verify)
self.logger.debug('Response: {}'.format(response.content))
self.logger.debug("%s %s %s %s %s %s %s" % (url, soap_data, self._timeout, headers, self._fritz_device.get_user(), self._fritz_device.get_password(), self._verify))

Log:

2020-01-18 19:34:49 DEBUG plugins.avm scheduler_add: name = plugins.avm.update, parameters: prio=5, cycle=120, offset=2
2020-01-18 19:34:51 DEBUG plugins.avm Starting update loop for instance
2020-01-18 19:34:51 DEBUG plugins.avm Response: b'<?xml version="1.0"?>\n<s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/" s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/">\n<s:Body>\n<u:GetInfoResponse xmlns:u="urn:dslforum-org:service:DeviceInfo:1">\n<NewManufacturerName>AVM</NewManufacturerName>\n<NewManufacturerOUI>00040E</NewManufacturerOUI>\n<NewModelName>FRITZ!Box 7490</NewModelName>\n<NewDescription>FRITZ!Box 7490 113.07.12</NewDescription>\n<NewProductClass>FRITZ!Box</NewProductClass>\n<NewSerialNumber>3810D5158767</NewSerialNumber>\n<NewSoftwareVersion>113.07.12</NewSoftwareVersion>\n<NewHardwareVersion>FRITZ!Box 7490</NewHardwareVersion>\n<NewSpecVersion>1.0</NewSpecVersion>\n<NewProvisioningCode></NewProvisioningCode>\n<NewUpTime>513768</NewUpTime>\n<NewDeviceLog>18.01.20 16:31:37 WLAN-\xc3\x9cbertragungsqualit\xc3\xa4t durch reduzierte Kanalbandbreite erh\xc3\xb6ht (2,4 GHz). [5 Meldungen seit 16.01.20 20:41:23]\n16.01.20 19:39:45 WLAN-\xc3\x9cbertragungsqualit\xc3\xa4t durch reduzierte Kanalbandbreite erh\xc3\xb6ht (2,4 GHz). [2 Meldungen seit 13.01.20 23:12:10]\n13.01.20 17:48:27 WLAN-\xc3\x9cbertragungsqualit\xc3\xa4t durch reduzierte Kanalbandbreite erh\xc3\xb6ht (2,4 GHz). [3 Meldungen seit 12.01.20 21:41:34]</NewDeviceLog>\n</u:GetInfoResponse>\n</s:Body>\n</s:Envelope>'
2020-01-18 19:34:51 DEBUG plugins.avm https://192.168.2.4:49443/upnp/control/deviceinfo <?xml version="1.0" encoding="utf-8"?>
<s:Envelope s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/"
xmlns:s="http://schemas.xmlsoap.org/soap/envelope/"><s:Body>
<u:GetInfo xmlns:u="urn:dslforum-org:service:DeviceInfo:1">
</u:GetInfo>
</s:Body>
</s:Envelope> 10 {'SOAPACTION': 'urn:dslforum-org:service:DeviceInfo:1#GetInfo', 'CONTENT-TYPE': 'text/xml; charset="utf-8"'} smarthome Fortuna1 False

[psilo]

das war jetzt aber nicht im fehlerfall oder? da hätte ich erwartet, dass du die werte VOR dem post ausgeben musst, da im post schon die Exception fliegt.

zudem gibts ne response..

[Sisamiwe]

das war jetzt aber nicht im fehlerfall oder? da hätte ich erwartet, dass du die werte VOR dem post ausgeben musst, da im post schon die

Jetzt ist der Logger vor dem POST.

Log:

2020-01-18 19:43:18 DEBUG plugins.avm scheduler_add: name = plugins.avm.update, parameters: prio=5, cycle=120, offset=2
2020-01-18 19:43:20 DEBUG plugins.avm Starting update loop for instance
2020-01-18 19:43:20 DEBUG plugins.avm https://192.168.2.4:49433/upnp/control/deviceinfo <?xml version="1.0" encoding="utf-8"?>
<s:Envelope s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/"
xmlns:s="http://schemas.xmlsoap.org/soap/envelope/"><s:Body>
<u:GetInfo xmlns:u="urn:dslforum-org:service:DeviceInfo:1">
</u:GetInfo>
</s:Body>
</s:Envelope> 10 {'SOAPACTION': 'urn:dslforum-org:service:DeviceInfo:1#GetInfo', 'CONTENT-TYPE': 'text/xml; charset="utf-8"'} smarthome Fortuna1 False
2020-01-18 19:43:20 ERROR plugins.avm Exception when sending POST request: HTTPSConnectionPool(host='192.168.2.4', port=49433): Max retries exceeded with url: /upnp/control/deviceinfo (Caused by NewConnectionError('<urllib3.connection.VerifiedHT TPSConnection object at 0x668469d0>: Failed to establish a new connection: [Errno 111] Verbindungsaufbau abgelehnt'))
2020-01-18 19:43:20 DEBUG plugins.avm Availability for FritzDevice set to False
2020-01-18 19:43:20 DEBUG plugins.avm MonitoringService: disconnecting
2020-01-18 19:43:20 DEBUG plugins.avm https://192.168.2.4:49433/upnp/control/deviceinfo <?xml version="1.0" encoding="utf-8"?>
<s:Envelope s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/"
xmlns:s="http://schemas.xmlsoap.org/soap/envelope/"><s:Body>
<u:GetInfo xmlns:u="urn:dslforum-org:service:DeviceInfo:1">
</u:GetInfo>
</s:Body>
</s:Envelope> 10 {'SOAPACTION': 'urn:dslforum-org:service:DeviceInfo:1#GetInfo', 'CONTENT-TYPE': 'text/xml; charset="utf-8"'} smarthome Fortuna1 False
2020-01-18 19:43:20 DEBUG plugins.avm Data Received from CallMonitor:
2020-01-18 19:43:20 DEBUG plugins.avm https://192.168.2.4:49433/upnp/control/deviceinfo <?xml version="1.0" encoding="utf-8"?>
<s:Envelope s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/"
xmlns:s="http://schemas.xmlsoap.org/soap/envelope/"><s:Body>
<u:GetInfo xmlns:u="urn:dslforum-org:service:DeviceInfo:1">
</u:GetInfo>
</s:Body>
</s:Envelope> 10 {'SOAPACTION': 'urn:dslforum-org:service:DeviceInfo:1#GetInfo', 'CONTENT-TYPE': 'text/xml; charset="utf-8"'} smarthome Fortuna1 False
2020-01-18 19:43:20 DEBUG plugins.avm https://192.168.2.4:49433/upnp/control/deviceinfo <?xml version="1.0" encoding="utf-8"?>
<s:Envelope s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/"
xmlns:s="http://schemas.xmlsoap.org/soap/envelope/"><s:Body>
<u:GetInfo xmlns:u="urn:dslforum-org:service:DeviceInfo:1">
</u:GetInfo>
</s:Body>
</s:Envelope> 10 {'SOAPACTION': 'urn:dslforum-org:service:DeviceInfo:1#GetInfo', 'CONTENT-TYPE': 'text/xml; charset="utf-8"'} smarthome Fortuna1 False

Mir fällt auf, dass erst die Exeption kommt, und dann noch 3 Versuche.

[psilo]

Ich sehe keinen Unterschied. Requests macht an beiden Stellen exakt das Gleiche. Imho kann eigentlich nur das AVM Gerät schuld sein..

2020-01-18 19:43:20 DEBUG plugins.avm https://192.168.2.4:49433/upnp/control/deviceinfo <?xml version="1.0" encoding="utf-8"?>
<s:Envelope s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/"
xmlns:s="http://schemas.xmlsoap.org/soap/envelope/"><s:Body>
<u:GetInfo xmlns:u="urn:dslforum-org:service:DeviceInfo:1">
</u:GetInfo>
</s:Body>
</s:Envelope> 10 {'SOAPACTION': 'urn:dslforum-org:service:DeviceInfo:1#GetInfo', 'CONTENT-TYPE': 'text/xml; charset="utf-8"'} smarthome Fortuna1 False


2020-01-18 19:34:51 DEBUG plugins.avm https://192.168.2.4:49443/upnp/control/deviceinfo <?xml version="1.0" encoding="utf-8"?>
<s:Envelope s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/"
xmlns:s="http://schemas.xmlsoap.org/soap/envelope/"><s:Body>
<u:GetInfo xmlns:u="urn:dslforum-org:service:DeviceInfo:1">
</u:GetInfo>
</s:Body>
</s:Envelope> 10 {'SOAPACTION': 'urn:dslforum-org:service:DeviceInfo:1#GetInfo', 'CONTENT-TYPE': 'text/xml; charset="utf-8"'} smarthome Fortuna1 False

[psilo]

probiere mal anstatt self._session.post ein requests.post an der stelle.. vielleicht hast du mit der session probleme.. das wäre aber seltsam... vielleicht ist das ne sache älterer geräte.

[Sisamiwe]

psilo
Ich finde auch keinen Unterschied. Brechen wir hier ab. Vielen Dank nochmal.
Ich mache mir eine Notiz in meinen Unterlagen.

Wenn Du Anmerkungen zum SNMP Plugin hast, immer her damit.

[psilo]

zu SNMP: hast du den PR neu gestellt? schaus mir morgen dann an.

[Sisamiwe]

probiere mal anstatt self._session.post ein requests.post an der stelle.. vielleicht hast du mit der session probleme.. das wäre aber seltsam...

Bringt auch keine Änderung.

[Sisamiwe]

zu SNMP: hast du den PR neu gestellt? schaus mir morgen dann an.

Nein, noch nicht. Hatte zwar hier schon auf die letzte Deveop aktualisiert, aber noch keinen neuen PR.
Mach ich auch morgen.

[psilo]

Bringt auch keine Änderung.

ok dann bin ich "beruhigt". Die session aus dem Plugin ausbauen wäre a) ekelig geworden und b) für die wo es läuft, von der performanz schlechter. aber daran liegt es dann wohl nicht